xpra icon
Bug tracker and wiki

This bug tracker and wiki are being discontinued
please use https://github.com/Xpra-org/xpra instead.


Ticket #1178: ticket1178-pulsesink-connect.txt

File ticket1178-pulsesink-connect.txt, 157.8 KB (added by alas, 5 years ago)

logs connecting with: XPRA_SOUND_UNDERRUN_MIN_LEVEL=30 XPRA_SOUND_SINK=pulsesink XPRA_SOUND_SOURCE_BUFFER_TIME=64 XPRA_SOUND_SOURCE_LATENCY_TIME=32 xpra attach tcp: -d sound

Line 
12016-04-28 16:52:41,931 Error: printing disabled:
22016-04-28 16:52:41,932  No module named cups
32016-04-28 16:52:41,939 Warning: outdated/buggy version of Python: 2.6.6.final.0
42016-04-28 16:52:41,939  switching to process polling every 2 seconds to support 'exit-with-children'
52016-04-28 16:52:41,940 Xpra gtk2 client version 0.16.4-r12444
62016-04-28 16:52:41,945  running on Linux CentOS 6.4 Final
72016-04-28 16:52:41,947 query_sound() command=['xpra', '_sound_query', '-d', ',sound'], env={'SSH_ASKPASS': '/usr/libexec/openssh/gnome-ssh-askpass', 'USERNAME': 'maint', 'GNOME_DESKTOP_SESSION_ID': 'this-is-deprecated', 'LESSOPEN': '|/usr/bin/lesspipe.sh %s', 'CVS_RSH': 'ssh', 'LOGNAME': 'maint', 'USER': 'maint', 'PATH': '/usr/lib64/qt-3.3/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/maint/bin', 'QTDIR': '/usr/lib64/qt-3.3', 'XPRA_FORCE_COLOR_LOG': '1', 'WINDOWPATH': '1', 'XPRA_SOUND_UNDERRUN_MIN_LEVEL': '30', 'QTLIB': '/usr/lib64/qt-3.3/lib', 'TERM': 'xterm', 'XPRA_SOUND_SOURCE_BUFFER_TIME': '64', 'XDG_SESSION_COOKIE': '87a3bbc63ef6ac99e420387500000017-1461274672.201546-614647152', 'SESSION_MANAGER': 'local/unix:@/tmp/.ICE-unix/2377,unix/unix:/tmp/.ICE-unix/2377', 'QTINC': '/usr/lib64/qt-3.3/include', 'G_BROKEN_FILENAMES': '1', 'HISTSIZE': '1000', 'XPRA_SOUND_SOURCE_LATENCY_TIME': '32', 'WINDOWID': '56635963', 'ORBIT_SOCKETDIR': '/tmp/orbit-maint', 'XPRA_SOUND_SINK': '_SINK=pulsesink', 'GIO_LAUNCHED_DESKTOP_FILE_PID': '2735', 'HOME': '/home/maint', 'GDM_KEYBOARD_LAYOUT': 'us', 'LANG': 'en_US.UTF-8', 'GIO_LAUNCHED_DESKTOP_FILE': '/usr/share/applications/gnome-terminal.desktop', 'GTK_RC_FILES': '/etc/gtk/gtkrc:/home/maint/.gtkrc-1.2-gnome2', 'SSH_AUTH_SOCK': '/tmp/keyring-rgQwbe/socket.ssh', 'GNOME_KEYRING_SOCKET': '/tmp/keyring-rgQwbe/socket', 'GDMSESSION': 'gnome', 'SHELL': '/bin/bash', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-zUA9LqlzeF,guid=4e7e0bcd7187cc4c6154df5e0000001f', '_': '/usr/bin/xpra', 'XAUTHORITY': '/var/run/gdm/auth-for-maint-7nX1Ee/database', 'DESKTOP_SESSION': 'gnome', 'DISPLAY': ':0.0', 'GNOME_KEYRING_PID': '2367', 'HOSTNAME': 'TestPCLow2.spikes.eng', 'GDM_LANG': 'en_US.UTF-8', 'HISTCONTROL': 'ignoredups', 'SHLVL': '2', 'PWD': '/home/maint', 'COLORTERM': 'gnome-terminal', 'MAIL': '/var/spool/mail/maint', 'LS_COLORS': 'rs=0:di=01;34:ln=01;36:mh=00:pi=40;33:so=01;35:do=01;35:bd=40;33;01:cd=40;33;01:or=40;31;01:mi=01;05;37;41:su=37;41:sg=30;43:ca=30;41:tw=30;42:ow=34;42:st=37;44:ex=01;32:*.tar=01;31:*.tgz=01;31:*.arj=01;31:*.taz=01;31:*.lzh=01;31:*.lzma=01;31:*.tlz=01;31:*.txz=01;31:*.zip=01;31:*.z=01;31:*.Z=01;31:*.dz=01;31:*.gz=01;31:*.lz=01;31:*.xz=01;31:*.bz2=01;31:*.tbz=01;31:*.tbz2=01;31:*.bz=01;31:*.tz=01;31:*.deb=01;31:*.rpm=01;31:*.jar=01;31:*.rar=01;31:*.ace=01;31:*.zoo=01;31:*.cpio=01;31:*.7z=01;31:*.rz=01;31:*.jpg=01;35:*.jpeg=01;35:*.gif=01;35:*.bmp=01;35:*.pbm=01;35:*.pgm=01;35:*.ppm=01;35:*.tga=01;35:*.xbm=01;35:*.xpm=01;35:*.tif=01;35:*.tiff=01;35:*.png=01;35:*.svg=01;35:*.svgz=01;35:*.mng=01;35:*.pcx=01;35:*.mov=01;35:*.mpg=01;35:*.mpeg=01;35:*.m2v=01;35:*.mkv=01;35:*.ogm=01;35:*.mp4=01;35:*.m4v=01;35:*.mp4v=01;35:*.vob=01;35:*.qt=01;35:*.nuv=01;35:*.wmv=01;35:*.asf=01;35:*.rm=01;35:*.rmvb=01;35:*.flc=01;35:*.avi=01;35:*.fli=01;35:*.flv=01;35:*.gl=01;35:*.dl=01;35:*.xcf=01;35:*.xwd=01;35:*.yuv=01;35:*.cgm=01;35:*.emf=01;35:*.axv=01;35:*.anx=01;35:*.ogv=01;35:*.ogx=01;35:*.aac=01;36:*.au=01;36:*.flac=01;36:*.mid=01;36:*.midi=01;36:*.mka=01;36:*.mp3=01;36:*.mpc=01;36:*.ogg=01;36:*.ra=01;36:*.wav=01;36:*.axa=01;36:*.oga=01;36:*.spx=01;36:*.xspf=01;36:', 'XPRA_SKIP_UI': '1'}, kwargs={'close_fds': True}
8GStreamer 1.0 environment: {'PATH': '/usr/lib64/qt-3.3/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/maint/bin', 'GIO_LAUNCHED_DESKTOP_FILE': '/usr/share/applications/gnome-terminal.desktop', 'GIO_LAUNCHED_DESKTOP_FILE_PID': '2735'}
9GStreamer 1.0 sys.path=/usr/bin, /usr/lib64/python26.zip, /usr/lib64/python2.6, /usr/lib64/python2.6/plat-linux2, /usr/lib64/python2.6/lib-tk, /usr/lib64/python2.6/lib-old, /usr/lib64/python2.6/lib-dynload, /usr/lib64/python2.6/site-packages, /usr/lib64/python2.6/site-packages/gst-0.10, /usr/lib64/python2.6/site-packages/gtk-2.0, /usr/lib/python2.6/site-packages, /usr/lib/python2.6/site-packages/setuptools-0.6c11-py2.6.egg-info
10trying to import GStreamer 1.0 using <function import_gst1 at 0xc3d398>
11import_gst1()
12Warning failed to import GStreamer 1.0
13Traceback (most recent call last):
14  File "/usr/lib64/python2.6/site-packages/xpra/sound/gstreamer_util.py", line 297, in import_gst
15    _gst = import_function()
16  File "/usr/lib64/python2.6/site-packages/xpra/sound/gstreamer_util.py", line 172, in import_gst1
17    import gi
18ImportError: No module named gi
19GStreamer 0.10 environment: {'PATH': '/usr/lib64/qt-3.3/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/maint/bin', 'GIO_LAUNCHED_DESKTOP_FILE': '/usr/share/applications/gnome-terminal.desktop', 'GIO_LAUNCHED_DESKTOP_FILE_PID': '2735'}
20GStreamer 0.10 sys.path=/usr/bin, /usr/lib64/python26.zip, /usr/lib64/python2.6, /usr/lib64/python2.6/plat-linux2, /usr/lib64/python2.6/lib-tk, /usr/lib64/python2.6/lib-old, /usr/lib64/python2.6/lib-dynload, /usr/lib64/python2.6/site-packages, /usr/lib64/python2.6/site-packages/gst-0.10, /usr/lib64/python2.6/site-packages/gtk-2.0, /usr/lib/python2.6/site-packages, /usr/lib/python2.6/site-packages/setuptools-0.6c11-py2.6.egg-info
21trying to import GStreamer 0.10 using <function import_gst0_10 at 0xc3d410>
22import_gst0_10()
23import_gst0_10() pygst=<module 'pygst' from '/usr/lib64/python2.6/site-packages/pygst.pyc'>
24Python GStreamer version 0.10.29 for Python 2.6
25run_sound(_sound_query, <function err at 0x7f1a79a258c0>, {'pulseaudio_command': 'pulseaudio --start --daemonize=false --system=false --exit-idle-time=-1 -n --load=module-suspend-on-idle --load=module-null-sink --load=module-native-protocol-unix --log-level=2 --log-target=stderr', 'socket_dir': '', 'tcp_encryption_keyfile': '', 'xsettings': True, 'input_method': 'none', 'password_file': '', 'min_quality': 30, 'lpadmin': '/usr/sbin/lpadmin', 'speed': 0, 'video_encoders': ['x264', 'vpx', 'nvenc'], 'sync_xvfb': 0, 'title': '@title@ on @client-machine@', 'mmap': True, 'encoding': '', 'clipboard': 'yes', 'socket_permissions': '600', 'keyboard_sync': True, 'file_transfer': True, 'min_speed': 0, 'local_clipboard': 'CLIPBOARD', 'csc_modules': ['swscale', 'cython'], 'auth': '', 'use_display': False, 'window_icon': '', 'open_files': False, 'daemon': True, 'remote_xpra': '~/.xpra/run-xpra', 'delay_tray': False, 'lpinfo': '/usr/sbin/lpinfo', 'debug': ',sound', 'video_decoders': ['avcodec2', 'vpx'], 'tray': True, 'bind_tcp': [], 'dpi': 0, 'remote_logging': False, 'pings': False, 'wm_name': 'Xpra', 'encodings': ['h264', 'vp9', 'vp8', 'png', 'png/P', 'png/L', 'webp', 'rgb', 'rgb24', 'rgb32', 'jpeg', 'h265'], 'desktop_scaling': 'auto', 'compressors': ['lz4', 'lzo', 'zlib'], 'av_sync': True, 'socket_dirs': ['~/.xpra'], 'exit_with_client': False, 'log_dir': '~/.xpra', 'encryption_keyfile': '', 'tcp_encryption': '', 'speaker_codec': [], 'start_child': [], 'pdf_printer': '', 'open_command': 'xdg-open', 'tcp_proxy': '', 'bell': True, 'postscript_printer': '', 'tray_icon': '', 'video_scaling': 1, 'exit_ssh': True, 'quality': 0, 'mdns': False, 'opengl': None, 'encryption': '', 'start': [], 'start_new_commands': False, 'username': 'maint', 'sharing': False, 'notifications': True, 'xvfb': 'xpra_Xdummy -noreset -nolisten tcp +extension GLX +extension RANDR +extension RENDER -auth $XAUTHORITY -logfile ${HOME}/.xpra/Xorg.${DISPLAY}.log -configdir ${HOME}/.xpra/xorg.conf.d -config /etc/xpra/xorg.conf', 'microphone_codec': [], 'tcp_auth': '', 'exit_with_children': False, 'file_size_limit': 10, 'printing': True, 'session_name': '', 'dbus_control': True, 'display': '', 'remote_clipboard': 'CLIPBOARD', 'server_idle_timeout': 0, 'key_shortcut': ['Meta+Shift+F1:show_menu', 'Meta+Shift+F2:show_start_new_command', 'Meta+Shift+F3:show_bug_report', 'Meta+Shift+F4:quit', 'Meta+Shift+F5:increase_quality', 'Meta+Shift+F6:decrease_quality', 'Meta+Shift+F7:increase_speed', 'Meta+Shift+F8:decrease_speed', 'Meta+Shift+F10:magic_key', 'Meta+Shift+F11:show_session_info', 'Meta+Shift+plus:scaleup', 'Meta+Shift+plusminus:scaleup', 'Meta+Shift+minus:scaledown', 'Meta+Shift+underscore:scaledown', 'Meta+Shift+emdash:scaledown', 'Meta+Shift+KP_Add:scaleup', 'Meta+Shift+KP_Subtract:scaledown', 'Meta+Shift+KP_Multiply:scalereset', 'Meta+Shift+degree:scalereset'], 'auto_refresh_delay': 0.14999999999999999, 'cursors': True, 'border': 'auto,0', 'microphone': 'off', 'system_tray': True, 'global_menus': True, 'readonly': False, 'html': '', 'speaker': 'on', 'shadow_fullscreen': False, 'env': ["#avoid Ubuntu's global menu, which is a mess and cannot be forwarded:", 'UBUNTU_MENUPROXY=', 'QT_X11_NO_NATIVE_MENUBAR=1', "#fix for MainSoft's MainWin buggy window management:", 'MWNOCAPTURE=true', 'MWNO_RIT=true', 'MWWM=allwm'], 'sound_source': '', 'mmap_group': False, 'idle_timeout': 0, 'fake_xinerama': True, 'max_size': '', 'clipboard_filter_file': '', 'windows': True, 'pulseaudio': True, 'packet_encoders': ['rencode', 'bencode', 'yaml'], 'compression_level': 1, 'displayfd': True, 'dbus_proxy': True, 'log_file': '$DISPLAY.log', 'ssh': 'ssh -x'}, []) gst=<module 'gst' from '/usr/lib64/python2.6/site-packages/gst-0.10/gst/__init__.pyc'>
26found the following plugins: ['ac3iec958', 'adder', 'agingtv', 'alawdec', 'alawenc', 'alpha', 'alphacolor', 'alsamixer', 'alsasink', 'alsasrc', 'apedemux', 'apev2mux', 'appsink', 'appsrc', 'asfdemux', 'aspectratiocrop', 'asteriskh263', 'audioamplify', 'audiochebband', 'audiocheblimit', 'audioconvert', 'audiodynamic', 'audioecho', 'audiofirfilter', 'audioiirfilter', 'audioinvert', 'audiokaraoke', 'audiopanorama', 'audiorate', 'audioresample', 'audiotestsrc', 'audiowsincband', 'audiowsinclimit', 'auparse', 'autoaudiosink', 'autoaudiosrc', 'autovideosink', 'autovideosrc', 'avidemux', 'avimux', 'avisubtitle', 'bin', 'breakmydata', 'cairorender', 'cairotextoverlay', 'cairotimeoverlay', 'capsfilter', 'capssetter', 'cdparanoiasrc', 'clockoverlay', 'cmmldec', 'cmmlenc', 'cutter', 'decodebin', 'decodebin2', 'deinterlace', 'deinterleave', 'dicetv', 'dv1394src', 'dvddemux', 'dvdec', 'dvdemux', 'dvdlpcmdec', 'dvdsubdec', 'dvdsubparse', 'dynudpsink', 'edgetv', 'efence', 'equalizer-10bands', 'equalizer-3bands', 'equalizer-nbands', 'fakesink', 'fakesrc', 'fdsink', 'fdsrc', 'ffmpegcolorspace', 'filesink', 'filesrc', 'flacdec', 'flacenc', 'flactag', 'flvdemux', 'flvmux', 'flxdec', 'gamma', 'gconfaudiosink', 'gconfaudiosrc', 'gconfvideosink', 'gconfvideosrc', 'gdkpixbufdec', 'gdkpixbufscale', 'gdkpixbufsink', 'gdpdepay', 'gdppay', 'giosink', 'giosrc', 'giostreamsink', 'giostreamsrc', 'goom', 'goom2k1', 'gstrtpbin', 'gstrtpjitterbuffer', 'gstrtpptdemux', 'gstrtpsession', 'gstrtpssrcdemux', 'halaudiosink', 'halaudiosrc', 'hdv1394src', 'icydemux', 'id3demux', 'id3v2mux', 'identity', 'imagefreeze', 'interleave', 'jpegdec', 'jpegenc', 'lame', 'lamemp3enc', 'level', 'mad', 'matroskademux', 'matroskamux', 'mp3parse', 'mpegdemux', 'mpegparse', 'mulawdec', 'mulawenc', 'multifdsink', 'multifilesink', 'multifilesrc', 'multipartdemux', 'multipartmux', 'multiqueue', 'multiudpsink', 'navigationtest', 'navseek', 'oggaviparse', 'oggdemux', 'oggmux', 'oggparse', 'ogmaudioparse', 'ogmtextparse', 'ogmvideoparse', 'optv', 'oss4mixer', 'oss4sink', 'oss4src', 'ossmixer', 'osssink', 'osssrc', 'pipeline', 'playbin', 'playbin2', 'playsink', 'pngdec', 'pngenc', 'pnmsrc', 'progressreport', 'pulsemixer', 'pulsesink', 'pulsesrc', 'pushfilesrc', 'qtdemux', 'quarktv', 'queue', 'queue2', 'rademux', 'radioactv', 'rdtdepay', 'rdtmanager', 'revtv', 'rganalysis', 'rglimiter', 'rgvolume', 'rippletv', 'rmdemux', 'rndbuffersize', 'rtpL16depay', 'rtpL16pay', 'rtpac3depay', 'rtpamrdepay', 'rtpamrpay', 'rtpasfdepay', 'rtpbvdepay', 'rtpbvpay', 'rtpceltdepay', 'rtpceltpay', 'rtpdec', 'rtpdepay', 'rtpdvdepay', 'rtpdvpay', 'rtpg723depay', 'rtpg723pay', 'rtpg726depay', 'rtpg726pay', 'rtpg729depay', 'rtpg729pay', 'rtpgsmdepay', 'rtpgsmpay', 'rtph263depay', 'rtph263pay', 'rtph263pdepay', 'rtph263ppay', 'rtph264depay', 'rtph264pay', 'rtpilbcdepay', 'rtpilbcpay', 'rtpj2kdepay', 'rtpj2kpay', 'rtpjpegdepay', 'rtpjpegpay', 'rtpmp1sdepay', 'rtpmp2tdepay', 'rtpmp2tpay', 'rtpmp4adepay', 'rtpmp4apay', 'rtpmp4gdepay', 'rtpmp4gpay', 'rtpmp4vdepay', 'rtpmp4vpay', 'rtpmpadepay', 'rtpmpapay', 'rtpmpvdepay', 'rtpmpvpay', 'rtppcmadepay', 'rtppcmapay', 'rtppcmudepay', 'rtppcmupay', 'rtpqcelpdepay', 'rtpqdm2depay', 'rtpsirendepay', 'rtpsirenpay', 'rtpspeexdepay', 'rtpspeexpay', 'rtpsv3vdepay', 'rtptheoradepay', 'rtptheorapay', 'rtpvorbisdepay', 'rtpvorbispay', 'rtpvrawdepay', 'rtpvrawpay', 'rtpxqtdepay', 'rtspreal', 'rtspsrc', 'rtspwms', 'shagadelictv', 'shapewipe', 'shout2send', 'smokedec', 'smokeenc', 'smpte', 'smptealpha', 'souphttpsrc', 'spectrum', 'speexdec', 'speexenc', 'ssaparse', 'streaktv', 'subparse', 'subtitleoverlay', 'taginject', 'tcpclientsink', 'tcpclientsrc', 'tcpserversink', 'tcpserversrc', 'tee', 'testsink', 'textoverlay', 'textrender', 'theoradec', 'theoraenc', 'theoraparse', 'timeoverlay', 'typefind', 'udpsink', 'udpsrc', 'uridecodebin', 'v4l2sink', 'v4l2src', 'v4lsrc', 'vertigotv', 'videobalance', 'videobox', 'videocrop', 'videoflip', 'videomixer', 'videorate', 'videoscale', 'videotestsrc', 'volume', 'vorbisdec', 'vorbisenc', 'vorbisparse', 'vorbistag', 'warptv', 'wavenc', 'wavpackdec', 'wavpackenc', 'wavpackparse', 'wavparse', 'webmmux', 'ximagesink', 'ximagesrc', 'xingmux', 'xvimagesink', 'y4menc']
27get_x11_property(PULSE_SERVER)={87a3bbc63ef6ac99e420387500000017}unix:/home/maint/.pulse/87a3bbc63ef6ac99e420387500000017-runtime/native
28avoiding vorbis with gdp muxer - gstreamer version (0, 10, 29) is too old
29skipping opus with GStreamer 0.10
30initialized sound codecs:
31* flac       : flacenc     , oggmux      , flacdec     , oggdemux   
32* mp3        : lamemp3enc  , None        , mad         , mp3parse   
33* wav        : wavenc      , None        , None        , wavparse   
34* wavpack    : wavpackenc  , None        , wavpackdec  , wavpackparse
35* speex      : speexenc    , oggmux      , speexdec    , oggdemux   
362016-04-28 16:52:42,074 query_sound() process returned 0
372016-04-28 16:52:42,074 query_sound() out=sources=pulsesrc,autoaudiosrc,alsasrc,osssrc,oss4src,audiotestsrc
38gst.version=0,10,29
39encoders=flac,mp3,wav,wavpack,speex
40python.version=2,6,6
41demuxers=gdp,ogg
42muxers=gdp,ogg
43decoders=flac,mp3,wav,wavpack,speex
44plugins=ac3iec958,adder,agingtv,alawdec,alawenc,alpha,alphacolor,alsamixer,alsasink,alsasrc,apedemux,apev2mux,appsink,appsrc,asfdemux,aspectratiocrop,asteriskh263,audioamplify,audiochebband,audiocheblimit,audioconvert,audiodynamic,audioecho,audiofirfilter,audioiirfilter,audioinvert,audiokaraoke,audiopanorama,audiorate,audioresample,audiotestsrc,audiowsincband,audiowsinclimit,auparse,autoaudiosink,autoaudiosrc,autovideosink,autovideosrc,avidemux,avimux,avisubtitle,bin,breakmydata,cairorender,cairotextoverlay,cairotimeoverlay,capsfilter,capssetter,cdparanoiasrc,clockoverlay,cmmldec,cmmlenc,cutter,decodebin,decodebin2,deinterlace,deinterleave,dicetv,dv1394src,dvddemux,dvdec,dvdemux,dvdlpcmdec,dvdsubdec,dvdsubparse,dynudpsink,edgetv,efence,equalizer-10bands,equalizer-3bands,equalizer-nbands,fakesink,fakesrc,fdsink,fdsrc,ffmpegcolorspace,filesink,filesrc,flacdec,flacenc,flactag,flvdemux,flvmux,flxdec,gamma,gconfaudiosink,gconfaudiosrc,gconfvideosink,gconfvideosrc,gdkpixbufdec,gdkpixbufscale,gdkpixbufsink,gdpdepay,gdppay,giosink,giosrc,giostreamsink,giostreamsrc,goom,goom2k1,gstrtpbin,gstrtpjitterbuffer,gstrtpptdemux,gstrtpsession,gstrtpssrcdemux,halaudiosink,halaudiosrc,hdv1394src,icydemux,id3demux,id3v2mux,identity,imagefreeze,interleave,jpegdec,jpegenc,lame,lamemp3enc,level,mad,matroskademux,matroskamux,mp3parse,mpegdemux,mpegparse,mulawdec,mulawenc,multifdsink,multifilesink,multifilesrc,multipartdemux,multipartmux,multiqueue,multiudpsink,navigationtest,navseek,oggaviparse,oggdemux,oggmux,oggparse,ogmaudioparse,ogmtextparse,ogmvideoparse,optv,oss4mixer,oss4sink,oss4src,ossmixer,osssink,osssrc,pipeline,playbin,playbin2,playsink,pngdec,pngenc,pnmsrc,progressreport,pulsemixer,pulsesink,pulsesrc,pushfilesrc,qtdemux,quarktv,queue,queue2,rademux,radioactv,rdtdepay,rdtmanager,revtv,rganalysis,rglimiter,rgvolume,rippletv,rmdemux,rndbuffersize,rtpL16depay,rtpL16pay,rtpac3depay,rtpamrdepay,rtpamrpay,rtpasfdepay,rtpbvdepay,rtpbvpay,rtpceltdepay,rtpceltpay,rtpdec,rtpdepay,rtpdvdepay,rtpdvpay,rtpg723depay,rtpg723pay,rtpg726depay,rtpg726pay,rtpg729depay,rtpg729pay,rtpgsmdepay,rtpgsmpay,rtph263depay,rtph263pay,rtph263pdepay,rtph263ppay,rtph264depay,rtph264pay,rtpilbcdepay,rtpilbcpay,rtpj2kdepay,rtpj2kpay,rtpjpegdepay,rtpjpegpay,rtpmp1sdepay,rtpmp2tdepay,rtpmp2tpay,rtpmp4adepay,rtpmp4apay,rtpmp4gdepay,rtpmp4gpay,rtpmp4vdepay,rtpmp4vpay,rtpmpadepay,rtpmpapay,rtpmpvdepay,rtpmpvpay,rtppcmadepay,rtppcmapay,rtppcmudepay,rtppcmupay,rtpqcelpdepay,rtpqdm2depay,rtpsirendepay,rtpsirenpay,rtpspeexdepay,rtpspeexpay,rtpsv3vdepay,rtptheoradepay,rtptheorapay,rtpvorbisdepay,rtpvorbispay,rtpvrawdepay,rtpvrawpay,rtpxqtdepay,rtspreal,rtspsrc,rtspwms,shagadelictv,shapewipe,shout2send,smokedec,smokeenc,smpte,smptealpha,souphttpsrc,spectrum,speexdec,speexenc,ssaparse,streaktv,subparse,subtitleoverlay,taginject,tcpclientsink,tcpclientsrc,tcpserversink,tcpserversrc,tee,testsink,textoverlay,textrender,theoradec,theoraenc,theoraparse,timeoverlay,typefind,udpsink,udpsrc,uridecodebin,v4l2sink,v4l2src,v4lsrc,vertigotv,videobalance,videobox,videocrop,videoflip,videomixer,videorate,videoscale,videotestsrc,volume,vorbisdec,vorbisenc,vorbisparse,vorbistag,warptv,wavenc,wavpackdec,wavpackenc,wavpackparse,wavparse,webmmux,ximagesink,ximagesrc,xingmux,xvimagesink,y4menc
45pygst.version=0,10,16
46, err=None
472016-04-28 16:52:42,075 query_sound()={'gst.version': ['0', '10', '29'], 'muxers': ['gdp', 'ogg'], 'decoders': ['flac', 'mp3', 'wav', 'wavpack', 'speex'], 'pygst.version': ['0', '10', '16'], 'sources': ['pulsesrc', 'autoaudiosrc', 'alsasrc', 'osssrc', 'oss4src', 'audiotestsrc'], 'encoders': ['flac', 'mp3', 'wav', 'wavpack', 'speex'], 'demuxers': ['gdp', 'ogg'], 'plugins': ['ac3iec958', 'adder', 'agingtv', 'alawdec', 'alawenc', 'alpha', 'alphacolor', 'alsamixer', 'alsasink', 'alsasrc', 'apedemux', 'apev2mux', 'appsink', 'appsrc', 'asfdemux', 'aspectratiocrop', 'asteriskh263', 'audioamplify', 'audiochebband', 'audiocheblimit', 'audioconvert', 'audiodynamic', 'audioecho', 'audiofirfilter', 'audioiirfilter', 'audioinvert', 'audiokaraoke', 'audiopanorama', 'audiorate', 'audioresample', 'audiotestsrc', 'audiowsincband', 'audiowsinclimit', 'auparse', 'autoaudiosink', 'autoaudiosrc', 'autovideosink', 'autovideosrc', 'avidemux', 'avimux', 'avisubtitle', 'bin', 'breakmydata', 'cairorender', 'cairotextoverlay', 'cairotimeoverlay', 'capsfilter', 'capssetter', 'cdparanoiasrc', 'clockoverlay', 'cmmldec', 'cmmlenc', 'cutter', 'decodebin', 'decodebin2', 'deinterlace', 'deinterleave', 'dicetv', 'dv1394src', 'dvddemux', 'dvdec', 'dvdemux', 'dvdlpcmdec', 'dvdsubdec', 'dvdsubparse', 'dynudpsink', 'edgetv', 'efence', 'equalizer-10bands', 'equalizer-3bands', 'equalizer-nbands', 'fakesink', 'fakesrc', 'fdsink', 'fdsrc', 'ffmpegcolorspace', 'filesink', 'filesrc', 'flacdec', 'flacenc', 'flactag', 'flvdemux', 'flvmux', 'flxdec', 'gamma', 'gconfaudiosink', 'gconfaudiosrc', 'gconfvideosink', 'gconfvideosrc', 'gdkpixbufdec', 'gdkpixbufscale', 'gdkpixbufsink', 'gdpdepay', 'gdppay', 'giosink', 'giosrc', 'giostreamsink', 'giostreamsrc', 'goom', 'goom2k1', 'gstrtpbin', 'gstrtpjitterbuffer', 'gstrtpptdemux', 'gstrtpsession', 'gstrtpssrcdemux', 'halaudiosink', 'halaudiosrc', 'hdv1394src', 'icydemux', 'id3demux', 'id3v2mux', 'identity', 'imagefreeze', 'interleave', 'jpegdec', 'jpegenc', 'lame', 'lamemp3enc', 'level', 'mad', 'matroskademux', 'matroskamux', 'mp3parse', 'mpegdemux', 'mpegparse', 'mulawdec', 'mulawenc', 'multifdsink', 'multifilesink', 'multifilesrc', 'multipartdemux', 'multipartmux', 'multiqueue', 'multiudpsink', 'navigationtest', 'navseek', 'oggaviparse', 'oggdemux', 'oggmux', 'oggparse', 'ogmaudioparse', 'ogmtextparse', 'ogmvideoparse', 'optv', 'oss4mixer', 'oss4sink', 'oss4src', 'ossmixer', 'osssink', 'osssrc', 'pipeline', 'playbin', 'playbin2', 'playsink', 'pngdec', 'pngenc', 'pnmsrc', 'progressreport', 'pulsemixer', 'pulsesink', 'pulsesrc', 'pushfilesrc', 'qtdemux', 'quarktv', 'queue', 'queue2', 'rademux', 'radioactv', 'rdtdepay', 'rdtmanager', 'revtv', 'rganalysis', 'rglimiter', 'rgvolume', 'rippletv', 'rmdemux', 'rndbuffersize', 'rtpL16depay', 'rtpL16pay', 'rtpac3depay', 'rtpamrdepay', 'rtpamrpay', 'rtpasfdepay', 'rtpbvdepay', 'rtpbvpay', 'rtpceltdepay', 'rtpceltpay', 'rtpdec', 'rtpdepay', 'rtpdvdepay', 'rtpdvpay', 'rtpg723depay', 'rtpg723pay', 'rtpg726depay', 'rtpg726pay', 'rtpg729depay', 'rtpg729pay', 'rtpgsmdepay', 'rtpgsmpay', 'rtph263depay', 'rtph263pay', 'rtph263pdepay', 'rtph263ppay', 'rtph264depay', 'rtph264pay', 'rtpilbcdepay', 'rtpilbcpay', 'rtpj2kdepay', 'rtpj2kpay', 'rtpjpegdepay', 'rtpjpegpay', 'rtpmp1sdepay', 'rtpmp2tdepay', 'rtpmp2tpay', 'rtpmp4adepay', 'rtpmp4apay', 'rtpmp4gdepay', 'rtpmp4gpay', 'rtpmp4vdepay', 'rtpmp4vpay', 'rtpmpadepay', 'rtpmpapay', 'rtpmpvdepay', 'rtpmpvpay', 'rtppcmadepay', 'rtppcmapay', 'rtppcmudepay', 'rtppcmupay', 'rtpqcelpdepay', 'rtpqdm2depay', 'rtpsirendepay', 'rtpsirenpay', 'rtpspeexdepay', 'rtpspeexpay', 'rtpsv3vdepay', 'rtptheoradepay', 'rtptheorapay', 'rtpvorbisdepay', 'rtpvorbispay', 'rtpvrawdepay', 'rtpvrawpay', 'rtpxqtdepay', 'rtspreal', 'rtspsrc', 'rtspwms', 'shagadelictv', 'shapewipe', 'shout2send', 'smokedec', 'smokeenc', 'smpte', 'smptealpha', 'souphttpsrc', 'spectrum', 'speexdec', 'speexenc', 'ssaparse', 'streaktv', 'subparse', 'subtitleoverlay', 'taginject', 'tcpclientsink', 'tcpclientsrc', 'tcpserversink', 'tcpserversrc', 'tee', 'testsink', 'textoverlay', 'textrender', 'theoradec', 'theoraenc', 'theoraparse', 'timeoverlay', 'typefind', 'udpsink', 'udpsrc', 'uridecodebin', 'v4l2sink', 'v4l2src', 'v4lsrc', 'vertigotv', 'videobalance', 'videobox', 'videocrop', 'videoflip', 'videomixer', 'videorate', 'videoscale', 'videotestsrc', 'volume', 'vorbisdec', 'vorbisenc', 'vorbisparse', 'vorbistag', 'warptv', 'wavenc', 'wavpackdec', 'wavpackenc', 'wavpackparse', 'wavparse', 'webmmux', 'ximagesink', 'ximagesrc', 'xingmux', 'xvimagesink', 'y4menc'], 'python.version': ['2', '6', '6']}
482016-04-28 16:52:42,075 GStreamer version 0.10 for Python 2.6
492016-04-28 16:52:42,075 speaker-codec=flac, mp3, wav, wavpack, speex
502016-04-28 16:52:42,075 microphone-codec=flac, mp3, wav, wavpack, speex
512016-04-28 16:52:42,075 speaker: codecs=['flac', 'mp3', 'wav', 'wavpack', 'speex'], allowed=True, enabled=flac, mp3, wav, wavpack, speex
522016-04-28 16:52:42,075 microphone: codecs=['flac', 'mp3', 'wav', 'wavpack', 'speex'], allowed=True, enabled=flac, mp3, wav, wavpack, speex
532016-04-28 16:52:42,075 av-sync=True
54libGL error: unable to load driver: swrast_dri.so
55libGL error: failed to load driver: swrast
562016-04-28 16:52:42,267 OpenGL_accelerate module loaded
572016-04-28 16:52:42,268 OpenGL support is missing:
582016-04-28 16:52:42,268  renderer 'Software Rasterizer' is blacklisted!
592016-04-28 16:52:42,369  detected keyboard: rules=evdev, model=pc105inet, layout=us
602016-04-28 16:52:42,370  desktop size is 1600x900 with 1 screen:
612016-04-28 16:52:42,370   :0.0 (432x241 mm - DPI: 94x94) workarea: 1600x850 at 0x25
622016-04-28 16:52:42,370     monitor 1
632016-04-28 16:52:42,371 get_x11_property(PULSE_SERVER)={87a3bbc63ef6ac99e420387500000017}unix:/home/maint/.pulse/87a3bbc63ef6ac99e420387500000017-runtime/native
642016-04-28 16:52:42,371 get_x11_property(PULSE_ID)=500@87a3bbc63ef6ac99e420387500000017/2431
652016-04-28 16:52:42,371 get_x11_property(PULSE_SERVER)={87a3bbc63ef6ac99e420387500000017}unix:/home/maint/.pulse/87a3bbc63ef6ac99e420387500000017-runtime/native
662016-04-28 16:52:42,374 safe_exec(['which', 'pactl'])=(0, '/usr/bin/pactl\n')
672016-04-28 16:52:42,380 pactl_output('list',) returned 0
682016-04-28 16:52:42,387 pactl_output('list',) returned 0
692016-04-28 16:52:42,394 pactl_output('list',) returned 0
702016-04-28 16:52:42,401 pactl_output('list',) returned 0
712016-04-28 16:52:42,402 sound capabilities: {'pulseaudio.id': '500@87a3bbc63ef6ac99e420387500000017/2431\x00', 'gst.version': ['0', '10', '29'], 'muxers': ['gdp', 'ogg'], 'decoders': ['flac', 'mp3', 'wav', 'wavpack', 'speex'], 'pygst.version': ['0', '10', '16'], 'sources': ['pulsesrc', 'autoaudiosrc', 'alsasrc', 'osssrc', 'oss4src', 'audiotestsrc'], 'devices': 7, 'device.alsa_output.pci-0000_00_1b.0.analog-stereo': 'Internal Audio Analog Stereo', 'plugins': ['ac3iec958', 'adder', 'agingtv', 'alawdec', 'alawenc', 'alpha', 'alphacolor', 'alsamixer', 'alsasink', 'alsasrc', 'apedemux', 'apev2mux', 'appsink', 'appsrc', 'asfdemux', 'aspectratiocrop', 'asteriskh263', 'audioamplify', 'audiochebband', 'audiocheblimit', 'audioconvert', 'audiodynamic', 'audioecho', 'audiofirfilter', 'audioiirfilter', 'audioinvert', 'audiokaraoke', 'audiopanorama', 'audiorate', 'audioresample', 'audiotestsrc', 'audiowsincband', 'audiowsinclimit', 'auparse', 'autoaudiosink', 'autoaudiosrc', 'autovideosink', 'autovideosrc', 'avidemux', 'avimux', 'avisubtitle', 'bin', 'breakmydata', 'cairorender', 'cairotextoverlay', 'cairotimeoverlay', 'capsfilter', 'capssetter', 'cdparanoiasrc', 'clockoverlay', 'cmmldec', 'cmmlenc', 'cutter', 'decodebin', 'decodebin2', 'deinterlace', 'deinterleave', 'dicetv', 'dv1394src', 'dvddemux', 'dvdec', 'dvdemux', 'dvdlpcmdec', 'dvdsubdec', 'dvdsubparse', 'dynudpsink', 'edgetv', 'efence', 'equalizer-10bands', 'equalizer-3bands', 'equalizer-nbands', 'fakesink', 'fakesrc', 'fdsink', 'fdsrc', 'ffmpegcolorspace', 'filesink', 'filesrc', 'flacdec', 'flacenc', 'flactag', 'flvdemux', 'flvmux', 'flxdec', 'gamma', 'gconfaudiosink', 'gconfaudiosrc', 'gconfvideosink', 'gconfvideosrc', 'gdkpixbufdec', 'gdkpixbufscale', 'gdkpixbufsink', 'gdpdepay', 'gdppay', 'giosink', 'giosrc', 'giostreamsink', 'giostreamsrc', 'goom', 'goom2k1', 'gstrtpbin', 'gstrtpjitterbuffer', 'gstrtpptdemux', 'gstrtpsession', 'gstrtpssrcdemux', 'halaudiosink', 'halaudiosrc', 'hdv1394src', 'icydemux', 'id3demux', 'id3v2mux', 'identity', 'imagefreeze', 'interleave', 'jpegdec', 'jpegenc', 'lame', 'lamemp3enc', 'level', 'mad', 'matroskademux', 'matroskamux', 'mp3parse', 'mpegdemux', 'mpegparse', 'mulawdec', 'mulawenc', 'multifdsink', 'multifilesink', 'multifilesrc', 'multipartdemux', 'multipartmux', 'multiqueue', 'multiudpsink', 'navigationtest', 'navseek', 'oggaviparse', 'oggdemux', 'oggmux', 'oggparse', 'ogmaudioparse', 'ogmtextparse', 'ogmvideoparse', 'optv', 'oss4mixer', 'oss4sink', 'oss4src', 'ossmixer', 'osssink', 'osssrc', 'pipeline', 'playbin', 'playbin2', 'playsink', 'pngdec', 'pngenc', 'pnmsrc', 'progressreport', 'pulsemixer', 'pulsesink', 'pulsesrc', 'pushfilesrc', 'qtdemux', 'quarktv', 'queue', 'queue2', 'rademux', 'radioactv', 'rdtdepay', 'rdtmanager', 'revtv', 'rganalysis', 'rglimiter', 'rgvolume', 'rippletv', 'rmdemux', 'rndbuffersize', 'rtpL16depay', 'rtpL16pay', 'rtpac3depay', 'rtpamrdepay', 'rtpamrpay', 'rtpasfdepay', 'rtpbvdepay', 'rtpbvpay', 'rtpceltdepay', 'rtpceltpay', 'rtpdec', 'rtpdepay', 'rtpdvdepay', 'rtpdvpay', 'rtpg723depay', 'rtpg723pay', 'rtpg726depay', 'rtpg726pay', 'rtpg729depay', 'rtpg729pay', 'rtpgsmdepay', 'rtpgsmpay', 'rtph263depay', 'rtph263pay', 'rtph263pdepay', 'rtph263ppay', 'rtph264depay', 'rtph264pay', 'rtpilbcdepay', 'rtpilbcpay', 'rtpj2kdepay', 'rtpj2kpay', 'rtpjpegdepay', 'rtpjpegpay', 'rtpmp1sdepay', 'rtpmp2tdepay', 'rtpmp2tpay', 'rtpmp4adepay', 'rtpmp4apay', 'rtpmp4gdepay', 'rtpmp4gpay', 'rtpmp4vdepay', 'rtpmp4vpay', 'rtpmpadepay', 'rtpmpapay', 'rtpmpvdepay', 'rtpmpvpay', 'rtppcmadepay', 'rtppcmapay', 'rtppcmudepay', 'rtppcmupay', 'rtpqcelpdepay', 'rtpqdm2depay', 'rtpsirendepay', 'rtpsirenpay', 'rtpspeexdepay', 'rtpspeexpay', 'rtpsv3vdepay', 'rtptheoradepay', 'rtptheorapay', 'rtpvorbisdepay', 'rtpvorbispay', 'rtpvrawdepay', 'rtpvrawpay', 'rtpxqtdepay', 'rtspreal', 'rtspsrc', 'rtspwms', 'shagadelictv', 'shapewipe', 'shout2send', 'smokedec', 'smokeenc', 'smpte', 'smptealpha', 'souphttpsrc', 'spectrum', 'speexdec', 'speexenc', 'ssaparse', 'streaktv', 'subparse', 'subtitleoverlay', 'taginject', 'tcpclientsink', 'tcpclientsrc', 'tcpserversink', 'tcpserversrc', 'tee', 'testsink', 'textoverlay', 'textrender', 'theoradec', 'theoraenc', 'theoraparse', 'timeoverlay', 'typefind', 'udpsink', 'udpsrc', 'uridecodebin', 'v4l2sink', 'v4l2src', 'v4lsrc', 'vertigotv', 'videobalance', 'videobox', 'videocrop', 'videoflip', 'videomixer', 'videorate', 'videoscale', 'videotestsrc', 'volume', 'vorbisdec', 'vorbisenc', 'vorbisparse', 'vorbistag', 'warptv', 'wavenc', 'wavpackdec', 'wavpackenc', 'wavpackparse', 'wavparse', 'webmmux', 'ximagesink', 'ximagesrc', 'xingmux', 'xvimagesink', 'y4menc'], 'device.alsa_card.pci-0000_00_1b.0': 'Internal Audio', 'pulseaudio.wrapper': 'pactl', 'device.alsa_output.pci-0000_00_1b.0.analog-stereo.monitor': 'Monitor of Internal Audio Analog Stereo', 'encoders': ['flac', 'mp3', 'wav', 'wavpack', 'speex'], 'device.alsa_input.usb-C-Media_Electronics_Inc._Microsoft_LifeChat_LX-3000-00-LX3000.analog-mono': 'Microsoft LifeChat LX-3000 Analog Mono', 'receive': True, 'send': True, 'device.alsa_output.usb-C-Media_Electronics_Inc._Microsoft_LifeChat_LX-3000-00-LX3000.analog-stereo.monitor': 'Monitor of Microsoft LifeChat LX-3000 Analog Stereo', 'pulseaudio.server': '{87a3bbc63ef6ac99e420387500000017}unix:/home/maint/.pulse/87a3bbc63ef6ac99e420387500000017-runtime/native\x00', 'device.alsa_output.usb-C-Media_Electronics_Inc._Microsoft_LifeChat_LX-3000-00-LX3000.analog-stereo': 'Microsoft LifeChat LX-3000 Analog Stereo', 'demuxers': ['gdp', 'ogg'], 'pulseaudio.found': '{87a3bbc63ef6ac99e420387500000017}unix:/home/maint/.pulse/87a3bbc63ef6ac99e420387500000017-runtime/native\x00', 'device.alsa_input.pci-0000_00_1b.0.analog-stereo': 'Internal Audio Analog Stereo', 'python.version': ['2', '6', '6']}
72/usr/lib64/python2.6/site-packages/xpra/client/gtk_base/statusicon_tray.py:97: GtkWarning: IA__gdk_window_get_origin: assertion `GDK_IS_WINDOW (window)' failed
73  ag = self.tray_widget.get_geometry()
742016-04-28 16:52:42,474 Xpra X11 server version 0.17.1-r12453
752016-04-28 16:52:42,475  running on Linux Fedora 23 Twenty Three
762016-04-28 16:52:42,476 pulseaudio id=None, server=None, sound decoders=opus+gdp, opus, vorbis, vorbis+mka, flac+gdp, mp3, wav, wavpack, speex+gdp, speex, sound encoders=opus+gdp, opus, vorbis, vorbis+mka, flac+gdp, mp3, wav, wavpack, speex+gdp, speex, receive=True, send=True
772016-04-28 16:52:42,476 start_receiving_sound() sound sink=None
782016-04-28 16:52:42,477 start_receiving_sound() matching codecs: mp3, wav, wavpack, speex
792016-04-28 16:52:42,477 start_sound_sink(mp3)
802016-04-28 16:52:42,477 starting mp3 sound sink
812016-04-28 16:52:42,477 start_receiving_sound(mp3)
822016-04-28 16:52:42,480 start() sound output subprocess(['xpra', '_sound_play', '-', '-', '', '', 'mp3', '', '1.0', '-d', ',sound'])=13406
832016-04-28 16:52:42,480 mp3 sound sink started
842016-04-28 16:52:42,481 Attached to tcp:10.0.32.134:1203 (press Control-C to detach)
85
86[36m2016-04-28 16:52:42,560 sound output GStreamer 1.0 environment: {'PATH': '/usr/lib64/qt-3.3/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/maint/bin', 'GIO_LAUNCHED_DESKTOP_FILE': '/usr/share/applications/gnome-terminal.desktop', 'GIO_LAUNCHED_DESKTOP_FILE_PID': '2735'}[0m
87[36m2016-04-28 16:52:42,560 sound output GStreamer 1.0 sys.path=/usr/bin, /usr/lib64/python26.zip, /usr/lib64/python2.6, /usr/lib64/python2.6/plat-linux2, /usr/lib64/python2.6/lib-tk, /usr/lib64/python2.6/lib-old, /usr/lib64/python2.6/lib-dynload, /usr/lib64/python2.6/site-packages, /usr/lib64/python2.6/site-packages/gst-0.10, /usr/lib64/python2.6/site-packages/gtk-2.0, /usr/lib/python2.6/site-packages, /usr/lib/python2.6/site-packages/setuptools-0.6c11-py2.6.egg-info[0m
88[36m2016-04-28 16:52:42,560 sound output trying to import GStreamer 1.0 using <function import_gst1 at 0x7f1c35236a28>[0m
89[36m2016-04-28 16:52:42,560 sound output import_gst1()[0m
90[36m2016-04-28 16:52:42,561 sound output Warning failed to import GStreamer 1.0
91Traceback (most recent call last):
92  File "/usr/lib64/python2.6/site-packages/xpra/sound/gstreamer_util.py", line 297, in import_gst
93    _gst = import_function()
94  File "/usr/lib64/python2.6/site-packages/xpra/sound/gstreamer_util.py", line 172, in import_gst1
95    import gi
96ImportError: No module named gi[0m
97[36m2016-04-28 16:52:42,561 sound output GStreamer 0.10 environment: {'PATH': '/usr/lib64/qt-3.3/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/maint/bin', 'GIO_LAUNCHED_DESKTOP_FILE': '/usr/share/applications/gnome-terminal.desktop', 'GIO_LAUNCHED_DESKTOP_FILE_PID': '2735'}[0m
98[36m2016-04-28 16:52:42,561 sound output GStreamer 0.10 sys.path=/usr/bin, /usr/lib64/python26.zip, /usr/lib64/python2.6, /usr/lib64/python2.6/plat-linux2, /usr/lib64/python2.6/lib-tk, /usr/lib64/python2.6/lib-old, /usr/lib64/python2.6/lib-dynload, /usr/lib64/python2.6/site-packages, /usr/lib64/python2.6/site-packages/gst-0.10, /usr/lib64/python2.6/site-packages/gtk-2.0, /usr/lib/python2.6/site-packages, /usr/lib/python2.6/site-packages/setuptools-0.6c11-py2.6.egg-info[0m
99[36m2016-04-28 16:52:42,561 sound output trying to import GStreamer 0.10 using <function import_gst0_10 at 0x7f1c35236aa0>[0m
100[36m2016-04-28 16:52:42,561 sound output import_gst0_10()[0m
101[36m2016-04-28 16:52:42,561 sound output import_gst0_10() pygst=<module 'pygst' from '/usr/lib64/python2.6/site-packages/pygst.pyc'>[0m
102[36m2016-04-28 16:52:42,571 sound output Python GStreamer version 0.10.29 for Python 2.6[0m
103[36m2016-04-28 16:52:42,571 sound output run_sound(_sound_play, <function err at 0x7f1c367c88c0>, {'pulseaudio_command': 'pulseaudio --start --daemonize=false --system=false --exit-idle-time=-1 -n --load=module-suspend-on-idle --load=module-null-sink --load=module-native-protocol-unix --log-level=2 --log-target=stderr', 'socket_dir': '', 'tcp_encryption_keyfile': '', 'xsettings': True, 'input_method': 'none', 'password_file': '', 'min_quality': 30, 'lpadmin': '/usr/sbin/lpadmin', 'speed': 0, 'video_encoders': ['x264', 'vpx', 'nvenc'], 'sync_xvfb': 0, 'title': '@title@ on @client-machine@', 'mmap': True, 'encoding': '', 'clipboard': 'yes', 'socket_permissions': '600', 'keyboard_sync': True, 'file_transfer': True, 'min_speed': 0, 'local_clipboard': 'CLIPBOARD', 'csc_modules': ['swscale', 'cython'], 'auth': '', 'use_display': False, 'window_icon': '', 'open_files': False, 'daemon': True, 'remote_xpra': '~/.xpra/run-xpra', 'delay_tray': False, 'lpinfo': '/usr/sbin/lpinfo', 'debug': ',sound', 'video_decoders': ['avcodec2', 'vpx'], 'tray': True, 'bind_tcp': [], 'dpi': 0, 'remote_logging': False, 'pings': False, 'wm_name': 'Xpra', 'encodings': ['h264', 'vp9', 'vp8', 'png', 'png/P', 'png/L', 'webp', 'rgb', 'rgb24', 'rgb32', 'jpeg', 'h265'], 'desktop_scaling': 'auto', 'compressors': ['lz4', 'lzo', 'zlib'], 'av_sync': True, 'socket_dirs': ['~/.xpra'], 'exit_with_client': False, 'log_dir': '~/.xpra', 'encryption_keyfile': '', 'tcp_encryption': '', 'speaker_codec': [], 'start_child': [], 'pdf_printer': '', 'open_command': 'xdg-open', 'tcp_proxy': '', 'bell': True, 'postscript_printer': '', 'tray_icon': '', 'video_scaling': 1, 'exit_ssh': True, 'quality': 0, 'mdns': False, 'opengl': None, 'encryption': '', 'start': [], 'start_new_commands': False, 'username': 'maint', 'sharing': False, 'notifications': True, 'xvfb': 'xpra_Xdummy -noreset -nolisten tcp +extension GLX +extension RANDR +extension RENDER -auth $XAUTHORITY -logfile ${HOME}/.xpra/Xorg.${DISPLAY}.log -configdir ${HOME}/.xpra/xorg.conf.d -config /etc/xpra/xorg.conf', 'microphone_codec': [], 'tcp_auth': '', 'exit_with_children': False, 'file_size_limit': 10, 'printing': True, 'session_name': '', 'dbus_control': True, 'display': '', 'remote_clipboard': 'CLIPBOARD', 'server_idle_timeout': 0, 'key_shortcut': ['Meta+Shift+F1:show_menu', 'Meta+Shift+F2:show_start_new_command', 'Meta+Shift+F3:show_bug_report', 'Meta+Shift+F4:quit', 'Meta+Shift+F5:increase_quality', 'Meta+Shift+F6:decrease_quality', 'Meta+Shift+F7:increase_speed', 'Meta+Shift+F8:decrease_speed', 'Meta+Shift+F10:magic_key', 'Meta+Shift+F11:show_session_info', 'Meta+Shift+plus:scaleup', 'Meta+Shift+plusminus:scaleup', 'Meta+Shift+minus:scaledown', 'Meta+Shift+underscore:scaledown', 'Meta+Shift+emdash:scaledown', 'Meta+Shift+KP_Add:scaleup', 'Meta+Shift+KP_Subtract:scaledown', 'Meta+Shift+KP_Multiply:scalereset', 'Meta+Shift+degree:scalereset'], 'auto_refresh_delay': 0.14999999999999999, 'cursors': True, 'border': 'auto,0', 'microphone': 'off', 'system_tray': True, 'global_menus': True, 'readonly': False, 'html': '', 'speaker': 'on', 'shadow_fullscreen': False, 'env': ["#avoid Ubuntu's global menu, which is a mess and cannot be forwarded:", 'UBUNTU_MENUPROXY=', 'QT_X11_NO_NATIVE_MENUBAR=1', "#fix for MainSoft's MainWin buggy window management:", 'MWNOCAPTURE=true', 'MWNO_RIT=true', 'MWWM=allwm'], 'sound_source': '', 'mmap_group': False, 'idle_timeout': 0, 'fake_xinerama': True, 'max_size': '', 'clipboard_filter_file': '', 'windows': True, 'pulseaudio': True, 'packet_encoders': ['rencode', 'bencode', 'yaml'], 'compression_level': 1, 'displayfd': True, 'dbus_proxy': True, 'log_file': '$DISPLAY.log', 'ssh': 'ssh -x'}, ['-', '-', '', '', 'mp3', '', '1.0']) gst=<module 'gst' from '/usr/lib64/python2.6/site-packages/gst-0.10/gst/__init__.pyc'>[0m
104[36m2016-04-28 16:52:42,600 sound output get_x11_property(PULSE_SERVER)={87a3bbc63ef6ac99e420387500000017}unix:/home/maint/.pulse/87a3bbc63ef6ac99e420387500000017-runtime/native[0m
105[31m2016-04-28 16:52:42,601 sound output invalid default sound sink: '_SINK=pulsesink' is not in ['autoaudiosink', 'pulsesink', 'alsasink', 'osssink', 'oss4sink', 'jackaudiosink'], using autoaudiosink instead[0m
106[36m2016-04-28 16:52:42,601 sound output avoiding vorbis with gdp muxer - gstreamer version (0, 10, 29) is too old[0m
107[36m2016-04-28 16:52:42,601 sound output found the following plugins: ['ac3iec958', 'adder', 'agingtv', 'alawdec', 'alawenc', 'alpha', 'alphacolor', 'alsamixer', 'alsasink', 'alsasrc', 'apedemux', 'apev2mux', 'appsink', 'appsrc', 'asfdemux', 'aspectratiocrop', 'asteriskh263', 'audioamplify', 'audiochebband', 'audiocheblimit', 'audioconvert', 'audiodynamic', 'audioecho', 'audiofirfilter', 'audioiirfilter', 'audioinvert', 'audiokaraoke', 'audiopanorama', 'audiorate', 'audioresample', 'audiotestsrc', 'audiowsincband', 'audiowsinclimit', 'auparse', 'autoaudiosink', 'autoaudiosrc', 'autovideosink', 'autovideosrc', 'avidemux', 'avimux', 'avisubtitle', 'bin', 'breakmydata', 'cairorender', 'cairotextoverlay', 'cairotimeoverlay', 'capsfilter', 'capssetter', 'cdparanoiasrc', 'clockoverlay', 'cmmldec', 'cmmlenc', 'cutter', 'decodebin', 'decodebin2', 'deinterlace', 'deinterleave', 'dicetv', 'dv1394src', 'dvddemux', 'dvdec', 'dvdemux', 'dvdlpcmdec', 'dvdsubdec', 'dvdsubparse', 'dynudpsink', 'edgetv', 'efence', 'equalizer-10bands', 'equalizer-3bands', 'equalizer-nbands', 'fakesink', 'fakesrc', 'fdsink', 'fdsrc', 'ffmpegcolorspace', 'filesink', 'filesrc', 'flacdec', 'flacenc', 'flactag', 'flvdemux', 'flvmux', 'flxdec', 'gamma', 'gconfaudiosink', 'gconfaudiosrc', 'gconfvideosink', 'gconfvideosrc', 'gdkpixbufdec', 'gdkpixbufscale', 'gdkpixbufsink', 'gdpdepay', 'gdppay', 'giosink', 'giosrc', 'giostreamsink', 'giostreamsrc', 'goom', 'goom2k1', 'gstrtpbin', 'gstrtpjitterbuffer', 'gstrtpptdemux', 'gstrtpsession', 'gstrtpssrcdemux', 'halaudiosink', 'halaudiosrc', 'hdv1394src', 'icydemux', 'id3demux', 'id3v2mux', 'identity', 'imagefreeze', 'interleave', 'jpegdec', 'jpegenc', 'lame', 'lamemp3enc', 'level', 'mad', 'matroskademux', 'matroskamux', 'mp3parse', 'mpegdemux', 'mpegparse', 'mulawdec', 'mulawenc', 'multifdsink', 'multifilesink', 'multifilesrc', 'multipartdemux', 'multipartmux', 'multiqueue', 'multiudpsink', 'navigationtest', 'navseek', 'oggaviparse', 'oggdemux', 'oggmux', 'oggparse', 'ogmaudioparse', 'ogmtextparse', 'ogmvideoparse', 'optv', 'oss4mixer', 'oss4sink', 'oss4src', 'ossmixer', 'osssink', 'osssrc', 'pipeline', 'playbin', 'playbin2', 'playsink', 'pngdec', 'pngenc', 'pnmsrc', 'progressreport', 'pulsemixer', 'pulsesink', 'pulsesrc', 'pushfilesrc', 'qtdemux', 'quarktv', 'queue', 'queue2', 'rademux', 'radioactv', 'rdtdepay', 'rdtmanager', 'revtv', 'rganalysis', 'rglimiter', 'rgvolume', 'rippletv', 'rmdemux', 'rndbuffersize', 'rtpL16depay', 'rtpL16pay', 'rtpac3depay', 'rtpamrdepay', 'rtpamrpay', 'rtpasfdepay', 'rtpbvdepay', 'rtpbvpay', 'rtpceltdepay', 'rtpceltpay', 'rtpdec', 'rtpdepay', 'rtpdvdepay', 'rtpdvpay', 'rtpg723depay', 'rtpg723pay', 'rtpg726depay', 'rtpg726pay', 'rtpg729depay', 'rtpg729pay', 'rtpgsmdepay', 'rtpgsmpay', 'rtph263depay', 'rtph263pay', 'rtph263pdepay', 'rtph263ppay', 'rtph264depay', 'rtph264pay', 'rtpilbcdepay', 'rtpilbcpay', 'rtpj2kdepay', 'rtpj2kpay', 'rtpjpegdepay', 'rtpjpegpay', 'rtpmp1sdepay', 'rtpmp2tdepay', 'rtpmp2tpay', 'rtpmp4adepay', 'rtpmp4apay', 'rtpmp4gdepay', 'rtpmp4gpay', 'rtpmp4vdepay', 'rtpmp4vpay', 'rtpmpadepay', 'rtpmpapay', 'rtpmpvdepay', 'rtpmpvpay', 'rtppcmadepay', 'rtppcmapay', 'rtppcmudepay', 'rtppcmupay', 'rtpqcelpdepay', 'rtpqdm2depay', 'rtpsirendepay', 'rtpsirenpay', 'rtpspeexdepay', 'rtpspeexpay', 'rtpsv3vdepay', 'rtptheoradepay', 'rtptheorapay', 'rtpvorbisdepay', 'rtpvorbispay', 'rtpvrawdepay', 'rtpvrawpay', 'rtpxqtdepay', 'rtspreal', 'rtspsrc', 'rtspwms', 'shagadelictv', 'shapewipe', 'shout2send', 'smokedec', 'smokeenc', 'smpte', 'smptealpha', 'souphttpsrc', 'spectrum', 'speexdec', 'speexenc', 'ssaparse', 'streaktv', 'subparse', 'subtitleoverlay', 'taginject', 'tcpclientsink', 'tcpclientsrc', 'tcpserversink', 'tcpserversrc', 'tee', 'testsink', 'textoverlay', 'textrender', 'theoradec', 'theoraenc', 'theoraparse', 'timeoverlay', 'typefind', 'udpsink', 'udpsrc', 'uridecodebin', 'v4l2sink', 'v4l2src', 'v4lsrc', 'vertigotv', 'videobalance', 'videobox', 'videocrop', 'videoflip', 'videomixer', 'videorate', 'videoscale', 'videotestsrc', 'volume', 'vorbisdec', 'vorbisenc', 'vorbisparse', 'vorbistag', 'warptv', 'wavenc', 'wavpackdec', 'wavpackenc', 'wavpackparse', 'wavparse', 'webmmux', 'ximagesink', 'ximagesrc', 'xingmux', 'xvimagesink', 'y4menc'][0m
108[36m2016-04-28 16:52:42,601 sound output skipping opus with GStreamer 0.10[0m
109[36m2016-04-28 16:52:42,602 sound output initialized sound codecs:[0m
110[36m2016-04-28 16:52:42,602 sound output * flac       : flacenc     , oggmux      , flacdec     , oggdemux    [0m
111[36m2016-04-28 16:52:42,602 sound output * mp3        : lamemp3enc  , None        , mad         , mp3parse    [0m
112[36m2016-04-28 16:52:42,602 sound output * wav        : wavenc      , None        , None        , wavparse    [0m
113[36m2016-04-28 16:52:42,602 sound output * wavpack    : wavpackenc  , None        , wavpackdec  , wavpackparse[0m
114[36m2016-04-28 16:52:42,602 sound output * speex      : speexenc    , oggmux      , speexdec    , oggdemux    [0m
115[36m2016-04-28 16:52:42,602 sound output SoundSink(..) found matching codecs ['mp3'][0m
116[36m2016-04-28 16:52:42,602 sound output pipeline elements=['appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4', 'mp3parse', 'mad', 'audioconvert', 'audioresample', 'volume name=volume volume=1.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'][0m
117[36m2016-04-28 16:52:42,602 sound output pipeline=appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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[0m
118[36m2016-04-28 16:52:42,622 sound output SoundPipeline.start() codec=mp3[0m
119[36m2016-04-28 16:52:42,627 sound output ignoring underrun during startup[0m
120[36m2016-04-28 16:52:42,627 sound output SoundPipeline.start() done[0m
121[36m2016-04-28 16:52:42,628 sound output state-changed on /GstPipeline:pipeline0/GstAutoAudioSink:autoaudiosink0 (__main__.GstAutoAudioSink): READY[0m
122[36m2016-04-28 16:52:42,628 sound output state-changed on /GstPipeline:pipeline0/GstQueue:queue (__main__.GstQueue): READY[0m
123[36m2016-04-28 16:52:42,628 sound output state-changed on /GstPipeline:pipeline0/GstVolume:volume (__main__.GstVolume): READY[0m
124[36m2016-04-28 16:52:42,628 sound output state-changed on /GstPipeline:pipeline0/GstAudioResample:audioresample0 (__main__.GstAudioResample): READY[0m
125[36m2016-04-28 16:52:42,628 sound output state-changed on /GstPipeline:pipeline0/GstAudioConvert:audioconvert0 (__main__.GstAudioConvert): READY[0m
126[36m2016-04-28 16:52:42,628 sound output state-changed on /GstPipeline:pipeline0/GstMad:mad0 (__main__.GstMad): READY[0m
127[36m2016-04-28 16:52:42,628 sound output state-changed on /GstPipeline:pipeline0/GstMPEGAudioParse:mpegaudioparse0 (__main__.GstMPEGAudioParse): READY[0m
128[36m2016-04-28 16:52:42,628 sound output state-changed on /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc): READY[0m
129[36m2016-04-28 16:52:42,628 sound output state-changed on /GstPipeline:pipeline0 (gst.Pipeline): READY[0m
130[36m2016-04-28 16:52:42,629 sound output stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstQueue\)\ queue", object=(GstTask)"\(GstTask\)\ queue:src"; from src at 0x16fb0f0>[0m
131[36m2016-04-28 16:52:42,629 sound output state-changed on /GstPipeline:pipeline0/GstQueue:queue (__main__.GstQueue): PAUSED[0m
132[36m2016-04-28 16:52:42,629 sound output state-changed on /GstPipeline:pipeline0/GstVolume:volume (__main__.GstVolume): PAUSED[0m
133[36m2016-04-28 16:52:42,629 sound output state-changed on /GstPipeline:pipeline0/GstAudioResample:audioresample0 (__main__.GstAudioResample): PAUSED[0m
134[36m2016-04-28 16:52:42,629 sound output state-changed on /GstPipeline:pipeline0/GstAudioConvert:audioconvert0 (__main__.GstAudioConvert): PAUSED[0m
135[36m2016-04-28 16:52:42,629 sound output stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstQueue\)\ queue", object=(GstTask)"\(GstTask\)\ queue:src"; from src at 0x7f1c18001c20>[0m
136[36m2016-04-28 16:52:42,629 sound output state-changed on /GstPipeline:pipeline0/GstMad:mad0 (__main__.GstMad): PAUSED[0m
137[36m2016-04-28 16:52:42,629 sound output state-changed on /GstPipeline:pipeline0/GstMPEGAudioParse:mpegaudioparse0 (__main__.GstMPEGAudioParse): PAUSED[0m
138[36m2016-04-28 16:52:42,629 sound output stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstAppSrc\)\ src", object=(GstTask)"\(GstTask\)\ src:src"; from src at 0x1717490>[0m
139[36m2016-04-28 16:52:42,629 sound output state-changed on /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc): PAUSED[0m
140[36m2016-04-28 16:52:42,629 sound output stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstAppSrc\)\ src", object=(GstTask)"\(GstTask\)\ src:src"; from src at 0x7f1c180022d0>[0m
1412016-04-28 16:52:42,630 sound_sink_state_changed(sink_subprocess_wrapper(13406), ready) on_sink_ready=<function sink_ready at 0x33c85f0>
1422016-04-28 16:52:42,630 sink_ready(()) codec=mp3
1432016-04-28 16:52:42,678 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'starting', 'codec_description': '', 'bytes': 0, 'queue.underruns': 0, 'volume': 100, 'state': 'ready', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 0, 'codec': 'mp3', 'queue.min': 0}
144[36m2016-04-28 16:52:43,169 sound output add_data(731 bytes, {'duration': 26122448, 'timestamp': 0, 'sequence': 0, 'time': 1461887547334}) queue_state=starting[0m
145[36m2016-04-28 16:52:43,169 sound output pushed   731 bytes, new buffer level:   0ms, queue state=starting[0m
146[36m2016-04-28 16:52:43,169 sound output add_data(731 bytes, {'duration': 26122448, 'timestamp': 0, 'sequence': 0, 'time': 1461887547335}) queue_state=starting[0m
147[36m2016-04-28 16:52:43,169 sound output pushed   731 bytes, new buffer level:   0ms, queue state=starting[0m
148[36m2016-04-28 16:52:43,180 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 26122448, 'sequence': 0, 'time': 1461887547347}) queue_state=starting[0m
149[36m2016-04-28 16:52:43,180 sound output pushed   261 bytes, new buffer level:   0ms, queue state=starting[0m
1502016-04-28 16:52:43,180 sound output using audio codec: MPEG 1 Audio, Layer 3 (MP3)[0m
151[36m2016-04-28 16:52:43,180 sound output set_max_level lrange=  0, last_max_update=0s[0m
152[36m2016-04-28 16:52:43,180 sound output new bitrate: 224000[0m
153[36m2016-04-28 16:52:43,180 sound output ignoring underrun during startup[0m
154[36m2016-04-28 16:52:43,180 sound output mode: mono[0m
155[36m2016-04-28 16:52:43,182 sound output set_max_level lrange=  0, last_max_update=0s[0m
156[36m2016-04-28 16:52:43,182 sound output new bitrate: 176000[0m
157[36m2016-04-28 16:52:43,185 sound output state-changed on /GstPipeline:pipeline0/GstAutoAudioSink:autoaudiosink0/GstPulseSink:autoaudiosink0-actual-sink-pulse (__main__.GstPulseSink): PAUSED[0m
158[36m2016-04-28 16:52:43,185 sound output ignoring underrun during startup[0m
159[36m2016-04-28 16:52:43,185 sound output state-changed on /GstPipeline:pipeline0/GstAutoAudioSink:autoaudiosink0 (__main__.GstAutoAudioSink): PAUSED[0m
160[36m2016-04-28 16:52:43,185 sound output state-changed on /GstPipeline:pipeline0 (gst.Pipeline): PAUSED[0m
161[36m2016-04-28 16:52:43,185 sound output <gst.Message (none) from pipeline0 at 0x7f1c18001e50>[0m
162[36m2016-04-28 16:52:43,186 sound output <gst.Message GstMessageNewClock, clock=(GstClock)"\(GstAudioClock\)\ GstPulseSinkClock"; from pipeline0 at 0x16fb2b0>[0m
163[36m2016-04-28 16:52:43,186 sound output state-changed on /GstPipeline:pipeline0/GstAutoAudioSink:autoaudiosink0/GstPulseSink:autoaudiosink0-actual-sink-pulse (__main__.GstPulseSink): PLAYING[0m
164[36m2016-04-28 16:52:43,186 sound output state-changed on /GstPipeline:pipeline0/GstAutoAudioSink:autoaudiosink0 (__main__.GstAutoAudioSink): PLAYING[0m
165[36m2016-04-28 16:52:43,186 sound output state-changed on /GstPipeline:pipeline0/GstQueue:queue (__main__.GstQueue): PLAYING[0m
166[36m2016-04-28 16:52:43,186 sound output state-changed on /GstPipeline:pipeline0/GstVolume:volume (__main__.GstVolume): PLAYING[0m
167[36m2016-04-28 16:52:43,186 sound output state-changed on /GstPipeline:pipeline0/GstAudioResample:audioresample0 (__main__.GstAudioResample): PLAYING[0m
168[36m2016-04-28 16:52:43,186 sound output state-changed on /GstPipeline:pipeline0/GstAudioConvert:audioconvert0 (__main__.GstAudioConvert): PLAYING[0m
169[36m2016-04-28 16:52:43,186 sound output state-changed on /GstPipeline:pipeline0/GstMad:mad0 (__main__.GstMad): PLAYING[0m
170[36m2016-04-28 16:52:43,186 sound output state-changed on /GstPipeline:pipeline0/GstMPEGAudioParse:mpegaudioparse0 (__main__.GstMPEGAudioParse): PLAYING[0m
171[36m2016-04-28 16:52:43,186 sound output state-changed on /GstPipeline:pipeline0/GstAppSrc:src (__main__.GstAppSrc): PLAYING[0m
172[36m2016-04-28 16:52:43,186 sound output state-changed on /GstPipeline:pipeline0 (gst.Pipeline): PLAYING[0m
173[36m2016-04-28 16:52:43,186 sound output stream status: <gst.Message GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstPulseSink\)\ autoaudiosink0-actual-sink-pulse", object=(gpointer)NULL; from autoaudiosink0-actual-sink-pulse at 0x7f1c10002200>[0m
1742016-04-28 16:52:43,187 sound_sink_state_changed(sink_subprocess_wrapper(13406), paused) on_sink_ready=None
1752016-04-28 16:52:43,187 sound_sink_state_changed(sink_subprocess_wrapper(13406), active) on_sink_ready=None
176[36m2016-04-28 16:52:43,214 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 52244897, 'sequence': 0, 'time': 1461887547381}) queue_state=pushing[0m
177[36m2016-04-28 16:52:43,214 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
178[36m2016-04-28 16:52:43,214 sound output set_max_level lrange=  0, last_max_update=0s[0m
179[36m2016-04-28 16:52:43,214 sound output new bitrate: 152000[0m
180[36m2016-04-28 16:52:43,215 sound output ignoring underrun during startup[0m
1812016-04-28 16:52:43,220 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 1984, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 4, 'codec_mode': 'mono', 'bitrate': 152000, 'codec': 'mp3', 'queue.min': 0}
182[36m2016-04-28 16:52:43,266 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 78367346, 'sequence': 0, 'time': 1461887547427}) queue_state=pushing[0m
183[36m2016-04-28 16:52:43,266 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
184[36m2016-04-28 16:52:43,266 sound output set_max_level lrange=  0, last_max_update=0s[0m
185[36m2016-04-28 16:52:43,266 sound output set_max_level lrange=  0, last_max_update=0s[0m
186[36m2016-04-28 16:52:43,267 sound output ignoring underrun during startup[0m
187[36m2016-04-28 16:52:43,267 sound output new bitrate: 138000[0m
188[36m2016-04-28 16:52:43,296 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 104489795, 'sequence': 0, 'time': 1461887547463}) queue_state=pushing[0m
189[36m2016-04-28 16:52:43,296 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
190[36m2016-04-28 16:52:43,296 sound output set_max_level lrange=  0, last_max_update=0s[0m
191[36m2016-04-28 16:52:43,296 sound output new bitrate: 128000[0m
192[36m2016-04-28 16:52:43,297 sound output ignoring underrun during startup[0m
1932016-04-28 16:52:43,317 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 2506, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 6, 'codec_mode': 'mono', 'bitrate': 128000, 'codec': 'mp3', 'queue.min': 0}
194[36m2016-04-28 16:52:43,319 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 130612244, 'sequence': 0, 'time': 1461887547486}) queue_state=pushing[0m
195[36m2016-04-28 16:52:43,319 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
196[36m2016-04-28 16:52:43,319 sound output set_max_level lrange=  0, last_max_update=0s[0m
197[36m2016-04-28 16:52:43,319 sound output set_max_level lrange=  0, last_max_update=0s[0m
198[36m2016-04-28 16:52:43,319 sound output ignoring underrun during startup[0m
199[36m2016-04-28 16:52:43,345 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 156734693, 'sequence': 0, 'time': 1461887547510}) queue_state=pushing[0m
200[36m2016-04-28 16:52:43,346 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
201[36m2016-04-28 16:52:43,346 sound output set_max_level lrange=  0, last_max_update=0s[0m
202[36m2016-04-28 16:52:43,346 sound output new bitrate: 116000[0m
203[36m2016-04-28 16:52:43,346 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 182857142, 'sequence': 0, 'time': 1461887547510}) queue_state=pushing[0m
204[36m2016-04-28 16:52:43,346 sound output ignoring underrun during startup[0m
205[36m2016-04-28 16:52:43,346 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
206[36m2016-04-28 16:52:43,347 sound output set_max_level lrange=  0, last_max_update=0s[0m
207[36m2016-04-28 16:52:43,347 sound output ignoring underrun during startup[0m
2082016-04-28 16:52:43,370 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 3236, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 9, 'codec_mode': 'mono', 'bitrate': 116000, 'codec': 'mp3', 'queue.min': 0}
209[36m2016-04-28 16:52:43,389 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 208979591, 'sequence': 0, 'time': 1461887547556}) queue_state=pushing[0m
210[36m2016-04-28 16:52:43,389 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
211[36m2016-04-28 16:52:43,389 sound output set_max_level lrange=  0, last_max_update=0s[0m
212[36m2016-04-28 16:52:43,389 sound output new bitrate: 107000[0m
213[36m2016-04-28 16:52:43,389 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 235102040, 'sequence': 0, 'time': 1461887547557}) queue_state=pushing[0m
214[36m2016-04-28 16:52:43,390 sound output ignoring underrun during startup[0m
215[36m2016-04-28 16:52:43,390 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
216[36m2016-04-28 16:52:43,390 sound output set_max_level lrange=  0, last_max_update=0s[0m
217[36m2016-04-28 16:52:43,390 sound output ignoring underrun during startup[0m
218[36m2016-04-28 16:52:43,422 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 261224489, 'sequence': 0, 'time': 1461887547589}) queue_state=pushing[0m
219[36m2016-04-28 16:52:43,422 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
220[36m2016-04-28 16:52:43,423 sound output set_max_level lrange=  0, last_max_update=0s[0m
221[36m2016-04-28 16:52:43,423 sound output ignoring underrun during startup[0m
2222016-04-28 16:52:43,440 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 4019, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 12, 'codec_mode': 'mono', 'bitrate': 107000, 'codec': 'mp3', 'queue.min': 0}
223[36m2016-04-28 16:52:43,458 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 287346938, 'sequence': 0, 'time': 1461887547626}) queue_state=pushing[0m
224[36m2016-04-28 16:52:43,459 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
225[36m2016-04-28 16:52:43,459 sound output set_max_level lrange=  0, last_max_update=0s[0m
226[36m2016-04-28 16:52:43,459 sound output ignoring underrun during startup[0m
227[36m2016-04-28 16:52:43,468 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 313469387, 'sequence': 0, 'time': 1461887547636}) queue_state=pushing[0m
228[36m2016-04-28 16:52:43,468 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
229[36m2016-04-28 16:52:43,468 sound output set_max_level lrange=  0, last_max_update=0s[0m
230[36m2016-04-28 16:52:43,469 sound output new bitrate: 99000[0m
231[36m2016-04-28 16:52:43,469 sound output ignoring underrun during startup[0m
2322016-04-28 16:52:43,510 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 4541, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 14, 'codec_mode': 'mono', 'bitrate': 99000, 'codec': 'mp3', 'queue.min': 0}
233[36m2016-04-28 16:52:43,528 sound output add_data(365 bytes, {'duration': 26122449, 'timestamp': 339591836, 'sequence': 0, 'time': 1461887547695}) queue_state=pushing[0m
234[36m2016-04-28 16:52:43,528 sound output pushed   365 bytes, new buffer level:   0ms, queue state=pushing[0m
235[36m2016-04-28 16:52:43,528 sound output set_max_level lrange=  0, last_max_update=0s[0m
236[36m2016-04-28 16:52:43,528 sound output new bitrate: 100000[0m
237[36m2016-04-28 16:52:43,528 sound output ignoring underrun during startup[0m
238[36m2016-04-28 16:52:43,552 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 365714285, 'sequence': 0, 'time': 1461887547719}) queue_state=pushing[0m
239[36m2016-04-28 16:52:43,552 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
240[36m2016-04-28 16:52:43,552 sound output set_max_level lrange=  0, last_max_update=0s[0m
241[36m2016-04-28 16:52:43,552 sound output ignoring underrun during startup[0m
242[36m2016-04-28 16:52:43,552 sound output new bitrate: 98000[0m
243[36m2016-04-28 16:52:43,552 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 391836734, 'sequence': 0, 'time': 1461887547719}) queue_state=pushing[0m
244[36m2016-04-28 16:52:43,552 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
245[36m2016-04-28 16:52:43,552 sound output set_max_level lrange=  0, last_max_update=0s[0m
246[36m2016-04-28 16:52:43,553 sound output ignoring underrun during startup[0m
2472016-04-28 16:52:43,579 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 5322, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 17, 'codec_mode': 'mono', 'bitrate': 98000, 'codec': 'mp3', 'queue.min': 0}
248[36m2016-04-28 16:52:43,598 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 417959183, 'sequence': 0, 'time': 1461887547765}) queue_state=pushing[0m
249[36m2016-04-28 16:52:43,598 sound output pushed   313 bytes, new buffer level:   0ms, queue state=pushing[0m
250[36m2016-04-28 16:52:43,598 sound output set_max_level lrange=  0, last_max_update=0s[0m
251[36m2016-04-28 16:52:43,598 sound output ignoring underrun during startup[0m
252[36m2016-04-28 16:52:43,631 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 444081632, 'sequence': 0, 'time': 1461887547798}) queue_state=pushing[0m
253[36m2016-04-28 16:52:43,631 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
254[36m2016-04-28 16:52:43,631 sound output set_max_level lrange=  0, last_max_update=1s[0m
255[36m2016-04-28 16:52:43,631 sound output set_max_level lrange=  0, last_max_update=1s[0m
256[36m2016-04-28 16:52:43,631 sound output ignoring underrun during startup[0m
2572016-04-28 16:52:43,648 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 5896, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 19, 'codec_mode': 'mono', 'bitrate': 98000, 'codec': 'mp3', 'queue.min': 0}
258[36m2016-04-28 16:52:43,655 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 470204081, 'sequence': 0, 'time': 1461887547821}) queue_state=pushing[0m
259[36m2016-04-28 16:52:43,655 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
260[36m2016-04-28 16:52:43,655 sound output set_max_level lrange=  0, last_max_update=1s[0m
261[36m2016-04-28 16:52:43,655 sound output ignoring underrun during startup[0m
262[36m2016-04-28 16:52:43,655 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 496326530, 'sequence': 0, 'time': 1461887547822}) queue_state=pushing[0m
263[36m2016-04-28 16:52:43,656 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
264[36m2016-04-28 16:52:43,656 sound output set_max_level lrange=  0, last_max_update=1s[0m
265[36m2016-04-28 16:52:43,656 sound output ignoring underrun during startup[0m
2662016-04-28 16:52:43,706 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 6312, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 21, 'codec_mode': 'mono', 'bitrate': 98000, 'codec': 'mp3', 'queue.min': 0}
267[36m2016-04-28 16:52:43,714 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 522448979, 'sequence': 0, 'time': 1461887547881}) queue_state=pushing[0m
268[36m2016-04-28 16:52:43,714 sound output pushed   313 bytes, new buffer level:   0ms, queue state=pushing[0m
269[36m2016-04-28 16:52:43,714 sound output set_max_level lrange=  0, last_max_update=1s[0m
270[36m2016-04-28 16:52:43,714 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 548571428, 'sequence': 0, 'time': 1461887547881}) queue_state=running[0m
271[36m2016-04-28 16:52:43,714 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
272[36m2016-04-28 16:52:43,715 sound output ignoring underrun during startup[0m
273[36m2016-04-28 16:52:43,715 sound output set_max_level lrange=  0, last_max_update=1s[0m
274[36m2016-04-28 16:52:43,715 sound output ignoring underrun during startup[0m
275[36m2016-04-28 16:52:43,739 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 574693877, 'sequence': 0, 'time': 1461887547905}) queue_state=pushing[0m
276[36m2016-04-28 16:52:43,739 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
277[36m2016-04-28 16:52:43,739 sound output set_max_level lrange=  0, last_max_update=1s[0m
278[36m2016-04-28 16:52:43,739 sound output ignoring underrun during startup[0m
279[36m2016-04-28 16:52:43,760 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 600816326, 'sequence': 0, 'time': 1461887547927}) queue_state=pushing[0m
280[36m2016-04-28 16:52:43,760 sound output pushed   313 bytes, new buffer level:   0ms, queue state=pushing[0m
281[36m2016-04-28 16:52:43,760 sound output set_max_level lrange=  0, last_max_update=1s[0m
282[36m2016-04-28 16:52:43,760 sound output ignoring underrun during startup[0m
2832016-04-28 16:52:43,765 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 7460, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 25, 'codec_mode': 'mono', 'bitrate': 98000, 'codec': 'mp3', 'queue.min': 0}
284[36m2016-04-28 16:52:43,793 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 626938775, 'sequence': 0, 'time': 1461887547961}) queue_state=pushing[0m
285[36m2016-04-28 16:52:43,794 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
286[36m2016-04-28 16:52:43,794 sound output set_max_level lrange=  0, last_max_update=1s[0m
287[36m2016-04-28 16:52:43,794 sound output set_max_level lrange=  0, last_max_update=1s[0m
288[36m2016-04-28 16:52:43,794 sound output ignoring underrun during startup[0m
289[36m2016-04-28 16:52:43,840 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 653061224, 'sequence': 0, 'time': 1461887548007}) queue_state=pushing[0m
290[36m2016-04-28 16:52:43,841 sound output pushed   313 bytes, new buffer level:   0ms, queue state=pushing[0m
291[36m2016-04-28 16:52:43,841 sound output set_max_level lrange=  0, last_max_update=1s[0m
292[36m2016-04-28 16:52:43,841 sound output set_max_level lrange=  0, last_max_update=1s[0m
293[36m2016-04-28 16:52:43,841 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 679183673, 'sequence': 0, 'time': 1461887548007}) queue_state=running[0m
294[36m2016-04-28 16:52:43,841 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
295[36m2016-04-28 16:52:43,841 sound output set_max_level lrange=  0, last_max_update=1s[0m
296[36m2016-04-28 16:52:43,841 sound output ignoring underrun during startup[0m
297[36m2016-04-28 16:52:43,841 sound output set_max_level lrange=  0, last_max_update=1s[0m
298[36m2016-04-28 16:52:43,841 sound output ignoring underrun during startup[0m
2992016-04-28 16:52:43,845 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 8242, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 28, 'codec_mode': 'mono', 'bitrate': 98000, 'codec': 'mp3', 'queue.min': 0}
300[36m2016-04-28 16:52:43,863 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 705306122, 'sequence': 0, 'time': 1461887548031}) queue_state=pushing[0m
301[36m2016-04-28 16:52:43,863 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
302[36m2016-04-28 16:52:43,863 sound output set_max_level lrange=  0, last_max_update=1s[0m
303[36m2016-04-28 16:52:43,863 sound output ignoring underrun during startup[0m
3042016-04-28 16:52:43,914 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 8503, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 29, 'codec_mode': 'mono', 'bitrate': 98000, 'codec': 'mp3', 'queue.min': 0}
305[36m2016-04-28 16:52:43,922 sound output add_data(365 bytes, {'duration': 26122449, 'timestamp': 731428571, 'sequence': 0, 'time': 1461887548090}) queue_state=pushing[0m
306[36m2016-04-28 16:52:43,922 sound output pushed   365 bytes, new buffer level:   0ms, queue state=pushing[0m
307[36m2016-04-28 16:52:43,923 sound output set_max_level lrange=  0, last_max_update=1s[0m
308[36m2016-04-28 16:52:43,923 sound output ignoring underrun during startup[0m
309[36m2016-04-28 16:52:43,946 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 757551020, 'sequence': 0, 'time': 1461887548113}) queue_state=pushing[0m
310[36m2016-04-28 16:52:43,946 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
311[36m2016-04-28 16:52:43,946 sound output set_max_level lrange=  0, last_max_update=1s[0m
312[36m2016-04-28 16:52:43,946 sound output ignoring underrun during startup[0m
313[36m2016-04-28 16:52:43,969 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 783673469, 'sequence': 0, 'time': 1461887548136}) queue_state=pushing[0m
314[36m2016-04-28 16:52:43,969 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
315[36m2016-04-28 16:52:43,969 sound output set_max_level lrange=  0, last_max_update=1s[0m
316[36m2016-04-28 16:52:43,969 sound output set_max_level lrange=  0, last_max_update=1s[0m
317[36m2016-04-28 16:52:43,969 sound output ignoring underrun during startup[0m
3182016-04-28 16:52:43,974 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 9390, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 32, 'codec_mode': 'mono', 'bitrate': 98000, 'codec': 'mp3', 'queue.min': 0}
319[36m2016-04-28 16:52:43,993 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 809795918, 'sequence': 0, 'time': 1461887548160}) queue_state=pushing[0m
320[36m2016-04-28 16:52:43,993 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
321[36m2016-04-28 16:52:43,993 sound output set_max_level lrange=  0, last_max_update=1s[0m
322[36m2016-04-28 16:52:43,993 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 835918367, 'sequence': 0, 'time': 1461887548160}) queue_state=pushing[0m
323[36m2016-04-28 16:52:43,993 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
324[36m2016-04-28 16:52:43,993 sound output set_max_level lrange=  0, last_max_update=1s[0m
325[36m2016-04-28 16:52:43,993 sound output new bitrate: 89000[0m
326[36m2016-04-28 16:52:43,993 sound output ignoring underrun during startup[0m
327[36m2016-04-28 16:52:43,993 sound output set_max_level lrange=  0, last_max_update=1s[0m
328[36m2016-04-28 16:52:43,994 sound output ignoring underrun during startup[0m
3292016-04-28 16:52:44,044 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 9859, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 34, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
330[36m2016-04-28 16:52:44,049 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 862040816, 'sequence': 0, 'time': 1461887548216}) queue_state=pushing[0m
331[36m2016-04-28 16:52:44,049 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
332[36m2016-04-28 16:52:44,049 sound output set_max_level lrange=  0, last_max_update=1s[0m
333[36m2016-04-28 16:52:44,049 sound output ignoring underrun during startup[0m
334[36m2016-04-28 16:52:44,072 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 888163265, 'sequence': 0, 'time': 1461887548239}) queue_state=pushing[0m
335[36m2016-04-28 16:52:44,072 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
336[36m2016-04-28 16:52:44,072 sound output set_max_level lrange=  0, last_max_update=1s[0m
337[36m2016-04-28 16:52:44,073 sound output ignoring underrun during startup[0m
3382016-04-28 16:52:44,100 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 10381, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 36, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
339[36m2016-04-28 16:52:44,109 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 914285714, 'sequence': 0, 'time': 1461887548276}) queue_state=pushing[0m
340[36m2016-04-28 16:52:44,110 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
341[36m2016-04-28 16:52:44,110 sound output set_max_level lrange=  0, last_max_update=1s[0m
342[36m2016-04-28 16:52:44,110 sound output ignoring underrun during startup[0m
343[36m2016-04-28 16:52:44,119 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 940408163, 'sequence': 0, 'time': 1461887548286}) queue_state=pushing[0m
344[36m2016-04-28 16:52:44,119 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
345[36m2016-04-28 16:52:44,119 sound output set_max_level lrange=  0, last_max_update=1s[0m
346[36m2016-04-28 16:52:44,119 sound output ignoring underrun during startup[0m
347[36m2016-04-28 16:52:44,155 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 966530612, 'sequence': 0, 'time': 1461887548322}) queue_state=pushing[0m
348[36m2016-04-28 16:52:44,155 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
349[36m2016-04-28 16:52:44,155 sound output set_max_level lrange=  0, last_max_update=1s[0m
350[36m2016-04-28 16:52:44,155 sound output ignoring underrun during startup[0m
3512016-04-28 16:52:44,161 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 11164, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 39, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
352[36m2016-04-28 16:52:44,178 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 992653061, 'sequence': 0, 'time': 1461887548346}) queue_state=pushing[0m
353[36m2016-04-28 16:52:44,178 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
354[36m2016-04-28 16:52:44,179 sound output set_max_level lrange=  0, last_max_update=1s[0m
355[36m2016-04-28 16:52:44,179 sound output ignoring underrun during startup[0m
356[36m2016-04-28 16:52:44,204 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1018775510, 'sequence': 0, 'time': 1461887548369}) queue_state=pushing[0m
357[36m2016-04-28 16:52:44,204 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
358[36m2016-04-28 16:52:44,204 sound output set_max_level lrange=  0, last_max_update=1s[0m
359[36m2016-04-28 16:52:44,204 sound output set_max_level lrange=  0, last_max_update=1s[0m
360[36m2016-04-28 16:52:44,204 sound output ignoring underrun during startup[0m
3612016-04-28 16:52:44,230 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 11633, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 41, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
362[36m2016-04-28 16:52:44,236 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1044897959, 'sequence': 0, 'time': 1461887548402}) queue_state=pushing[0m
363[36m2016-04-28 16:52:44,236 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
364[36m2016-04-28 16:52:44,236 sound output set_max_level lrange=  0, last_max_update=1s[0m
365[36m2016-04-28 16:52:44,236 sound output ignoring underrun during startup[0m
366[36m2016-04-28 16:52:44,258 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1071020408, 'sequence': 0, 'time': 1461887548425}) queue_state=pushing[0m
367[36m2016-04-28 16:52:44,258 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
368[36m2016-04-28 16:52:44,258 sound output set_max_level lrange=  0, last_max_update=1s[0m
369[36m2016-04-28 16:52:44,258 sound output ignoring underrun during startup[0m
370[36m2016-04-28 16:52:44,281 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1097142857, 'sequence': 0, 'time': 1461887548448}) queue_state=pushing[0m
371[36m2016-04-28 16:52:44,281 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
372[36m2016-04-28 16:52:44,281 sound output set_max_level lrange=  0, last_max_update=1s[0m
373[36m2016-04-28 16:52:44,281 sound output set_max_level lrange=  0, last_max_update=1s[0m
374[36m2016-04-28 16:52:44,281 sound output ignoring underrun during startup[0m
3752016-04-28 16:52:44,287 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 12363, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 44, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
376[36m2016-04-28 16:52:44,319 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1123265306, 'sequence': 0, 'time': 1461887548485}) queue_state=pushing[0m
377[36m2016-04-28 16:52:44,319 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
378[36m2016-04-28 16:52:44,319 sound output set_max_level lrange=  0, last_max_update=1s[0m
379[36m2016-04-28 16:52:44,319 sound output set_max_level lrange=  0, last_max_update=1s[0m
380[36m2016-04-28 16:52:44,319 sound output ignoring underrun during startup[0m
381[36m2016-04-28 16:52:44,327 sound output add_data(156 bytes, {'duration': 26122449, 'timestamp': 1149387755, 'sequence': 0, 'time': 1461887548495}) queue_state=pushing[0m
382[36m2016-04-28 16:52:44,327 sound output pushed   156 bytes, new buffer level:   0ms, queue state=pushing[0m
383[36m2016-04-28 16:52:44,327 sound output set_max_level lrange=  0, last_max_update=1s[0m
384[36m2016-04-28 16:52:44,328 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1175510204, 'sequence': 0, 'time': 1461887548495}) queue_state=pushing[0m
385[36m2016-04-28 16:52:44,328 sound output ignoring underrun during startup[0m
386[36m2016-04-28 16:52:44,328 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
387[36m2016-04-28 16:52:44,328 sound output set_max_level lrange=  0, last_max_update=1s[0m
388[36m2016-04-28 16:52:44,328 sound output ignoring underrun during startup[0m
389[36m2016-04-28 16:52:44,364 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 1201632653, 'sequence': 0, 'time': 1461887548531}) queue_state=pushing[0m
390[36m2016-04-28 16:52:44,364 sound output pushed   313 bytes, new buffer level:   0ms, queue state=pushing[0m
391[36m2016-04-28 16:52:44,364 sound output set_max_level lrange=  0, last_max_update=1s[0m
392[36m2016-04-28 16:52:44,364 sound output ignoring underrun during startup[0m
3932016-04-28 16:52:44,370 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 13248, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 48, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
394[36m2016-04-28 16:52:44,410 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 1227755102, 'sequence': 0, 'time': 1461887548578}) queue_state=pushing[0m
395[36m2016-04-28 16:52:44,411 sound output pushed   313 bytes, new buffer level:   0ms, queue state=pushing[0m
396[36m2016-04-28 16:52:44,411 sound output set_max_level lrange=  0, last_max_update=1s[0m
397[36m2016-04-28 16:52:44,411 sound output set_max_level lrange=  0, last_max_update=1s[0m
398[36m2016-04-28 16:52:44,411 sound output ignoring underrun during startup[0m
399[36m2016-04-28 16:52:44,444 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1253877551, 'sequence': 0, 'time': 1461887548611}) queue_state=pushing[0m
400[36m2016-04-28 16:52:44,444 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
401[36m2016-04-28 16:52:44,444 sound output set_max_level lrange=  0, last_max_update=1s[0m
402[36m2016-04-28 16:52:44,444 sound output ignoring underrun during startup[0m
4032016-04-28 16:52:44,462 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 13822, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 50, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
404[36m2016-04-28 16:52:44,467 sound output add_data(261 bytes, {'duration': 26122448, 'timestamp': 1280000000, 'sequence': 0, 'time': 1461887548634}) queue_state=pushing[0m
405[36m2016-04-28 16:52:44,467 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
406[36m2016-04-28 16:52:44,467 sound output set_max_level lrange=  0, last_max_update=1s[0m
407[36m2016-04-28 16:52:44,467 sound output ignoring underrun during startup[0m
408[36m2016-04-28 16:52:44,491 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1306122448, 'sequence': 0, 'time': 1461887548657}) queue_state=pushing[0m
409[36m2016-04-28 16:52:44,491 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
410[36m2016-04-28 16:52:44,491 sound output set_max_level lrange=  0, last_max_update=1s[0m
411[36m2016-04-28 16:52:44,491 sound output ignoring underrun during startup[0m
4122016-04-28 16:52:44,518 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 14344, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 52, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
413[36m2016-04-28 16:52:44,527 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1332244897, 'sequence': 0, 'time': 1461887548694}) queue_state=pushing[0m
414[36m2016-04-28 16:52:44,527 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
415[36m2016-04-28 16:52:44,527 sound output set_max_level lrange=  0, last_max_update=1s[0m
416[36m2016-04-28 16:52:44,527 sound output ignoring underrun during startup[0m
417[36m2016-04-28 16:52:44,551 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1358367346, 'sequence': 0, 'time': 1461887548717}) queue_state=pushing[0m
418[36m2016-04-28 16:52:44,551 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
419[36m2016-04-28 16:52:44,551 sound output set_max_level lrange=  0, last_max_update=1s[0m
420[36m2016-04-28 16:52:44,551 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1384489795, 'sequence': 0, 'time': 1461887548717}) queue_state=pushing[0m
421[36m2016-04-28 16:52:44,551 sound output ignoring underrun during startup[0m
422[36m2016-04-28 16:52:44,551 sound output pushed   208 bytes, new buffer level:   0ms, queue state=pushing[0m
423[36m2016-04-28 16:52:44,551 sound output set_max_level lrange=  0, last_max_update=1s[0m
424[36m2016-04-28 16:52:44,551 sound output ignoring underrun during startup[0m
425[36m2016-04-28 16:52:44,573 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1410612244, 'sequence': 0, 'time': 1461887548740}) queue_state=pushing[0m
426[36m2016-04-28 16:52:44,573 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
427[36m2016-04-28 16:52:44,573 sound output set_max_level lrange=  0, last_max_update=1s[0m
428[36m2016-04-28 16:52:44,573 sound output ignoring underrun during startup[0m
4292016-04-28 16:52:44,578 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'pushing', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 15229, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 56, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
430[36m2016-04-28 16:52:44,619 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 1436734693, 'sequence': 0, 'time': 1461887548787}) queue_state=pushing[0m
431[36m2016-04-28 16:52:44,619 sound output pushed   313 bytes, new buffer level:   0ms, queue state=pushing[0m
432[36m2016-04-28 16:52:44,619 sound output set_max_level lrange=  0, last_max_update=1s[0m
433[36m2016-04-28 16:52:44,620 sound output set_max_level lrange=  0, last_max_update=1s[0m
434[36m2016-04-28 16:52:44,653 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1462857142, 'sequence': 0, 'time': 1461887548820}) queue_state=underrun[0m
435[36m2016-04-28 16:52:44,653 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
436[36m2016-04-28 16:52:44,653 sound output set_max_level lrange=  0, last_max_update=2s[0m
4372016-04-28 16:52:44,671 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 15750, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 58, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
438[36m2016-04-28 16:52:44,675 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1488979591, 'sequence': 0, 'time': 1461887548843}) queue_state=underrun[0m
439[36m2016-04-28 16:52:44,675 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
440[36m2016-04-28 16:52:44,675 sound output set_max_level lrange=  0, last_max_update=2s[0m
441[36m2016-04-28 16:52:44,699 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1515102040, 'sequence': 0, 'time': 1461887548866}) queue_state=underrun[0m
442[36m2016-04-28 16:52:44,699 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
443[36m2016-04-28 16:52:44,699 sound output set_max_level lrange=  0, last_max_update=2s[0m
4442016-04-28 16:52:44,726 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 16219, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 60, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
445[36m2016-04-28 16:52:44,736 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1541224489, 'sequence': 0, 'time': 1461887548904}) queue_state=underrun[0m
446[36m2016-04-28 16:52:44,736 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
447[36m2016-04-28 16:52:44,736 sound output set_max_level lrange=  0, last_max_update=2s[0m
448[36m2016-04-28 16:52:44,760 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1567346938, 'sequence': 0, 'time': 1461887548926}) queue_state=underrun[0m
449[36m2016-04-28 16:52:44,760 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
450[36m2016-04-28 16:52:44,760 sound output set_max_level lrange=  0, last_max_update=2s[0m
451[36m2016-04-28 16:52:44,760 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1593469387, 'sequence': 0, 'time': 1461887548926}) queue_state=running[0m
452[36m2016-04-28 16:52:44,761 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
453[36m2016-04-28 16:52:44,761 sound output set_max_level lrange=  0, last_max_update=2s[0m
454[36m2016-04-28 16:52:44,782 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1619591836, 'sequence': 0, 'time': 1461887548949}) queue_state=underrun[0m
455[36m2016-04-28 16:52:44,782 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
456[36m2016-04-28 16:52:44,782 sound output set_max_level lrange=  0, last_max_update=2s[0m
4572016-04-28 16:52:44,787 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 17104, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 64, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
458[36m2016-04-28 16:52:44,806 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 1645714285, 'sequence': 0, 'time': 1461887548972}) queue_state=underrun[0m
459[36m2016-04-28 16:52:44,806 sound output pushed   313 bytes, new buffer level:   0ms, queue state=underrun[0m
460[36m2016-04-28 16:52:44,806 sound output set_max_level lrange=  0, last_max_update=2s[0m
4612016-04-28 16:52:44,857 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 17417, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 65, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
462[36m2016-04-28 16:52:44,861 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 1671836734, 'sequence': 0, 'time': 1461887549029}) queue_state=underrun[0m
463[36m2016-04-28 16:52:44,861 sound output pushed   313 bytes, new buffer level:   0ms, queue state=underrun[0m
464[36m2016-04-28 16:52:44,861 sound output set_max_level lrange=  0, last_max_update=2s[0m
465[36m2016-04-28 16:52:44,884 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1697959183, 'sequence': 0, 'time': 1461887549052}) queue_state=underrun[0m
466[36m2016-04-28 16:52:44,885 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
467[36m2016-04-28 16:52:44,885 sound output set_max_level lrange=  0, last_max_update=2s[0m
468[36m2016-04-28 16:52:44,908 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1724081632, 'sequence': 0, 'time': 1461887549075}) queue_state=underrun[0m
469[36m2016-04-28 16:52:44,909 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
470[36m2016-04-28 16:52:44,909 sound output set_max_level lrange=  0, last_max_update=2s[0m
4712016-04-28 16:52:44,912 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 18199, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 68, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
472[36m2016-04-28 16:52:44,931 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1750204081, 'sequence': 0, 'time': 1461887549098}) queue_state=underrun[0m
473[36m2016-04-28 16:52:44,931 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
474[36m2016-04-28 16:52:44,931 sound output set_max_level lrange=  0, last_max_update=2s[0m
475[36m2016-04-28 16:52:44,967 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1776326530, 'sequence': 0, 'time': 1461887549135}) queue_state=underrun[0m
476[36m2016-04-28 16:52:44,968 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
477[36m2016-04-28 16:52:44,968 sound output set_max_level lrange=  0, last_max_update=2s[0m
4782016-04-28 16:52:44,981 verify_started() process=<subprocess.Popen object at 0x33d2650>, last_info={'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 18199, 'pid': 13406, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 68, 'time': 1461887564, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}, codec=mp3
4792016-04-28 16:52:44,982 info_update: {'queue.max': 450, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 18668, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 70, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
480[36m2016-04-28 16:52:44,990 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1802448979, 'sequence': 0, 'time': 1461887549158}) queue_state=underrun[0m
481[36m2016-04-28 16:52:44,991 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
482[36m2016-04-28 16:52:44,991 sound output set_max_level lrange=  0, last_max_update=2s[0m
483[36m2016-04-28 16:52:45,015 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1828571428, 'sequence': 0, 'time': 1461887549181}) queue_state=underrun[0m
484[36m2016-04-28 16:52:45,015 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
485[36m2016-04-28 16:52:45,015 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1854693877, 'sequence': 0, 'time': 1461887549182}) queue_state=underrun[0m
486[36m2016-04-28 16:52:45,015 sound output pushed   208 bytes, new buffer level:  26ms, queue state=underrun[0m
487[36m2016-04-28 16:52:45,015 sound output set_max_level lrange= 26, last_max_update=2s[0m
488[36m2016-04-28 16:52:45,015 sound output set_max_level overrun count=0 , margin= 50, pct= 0, cmst=450, mst=250[0m
4892016-04-28 16:52:45,041 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 19345, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 73, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
490[36m2016-04-28 16:52:45,070 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1880816326, 'sequence': 0, 'time': 1461887549238}) queue_state=underrun[0m
491[36m2016-04-28 16:52:45,071 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
492[36m2016-04-28 16:52:45,071 sound output set_max_level lrange= 26, last_max_update=0s[0m
493[36m2016-04-28 16:52:45,094 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1906938775, 'sequence': 0, 'time': 1461887549261}) queue_state=underrun[0m
494[36m2016-04-28 16:52:45,094 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
495[36m2016-04-28 16:52:45,094 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 1933061224, 'sequence': 0, 'time': 1461887549261}) queue_state=underrun[0m
496[36m2016-04-28 16:52:45,094 sound output pushed   261 bytes, new buffer level:  54ms, queue state=underrun[0m
497[36m2016-04-28 16:52:45,095 sound output set_max_level lrange= 26, last_max_update=0s[0m
498[36m2016-04-28 16:52:45,118 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 1959183673, 'sequence': 0, 'time': 1461887549285}) queue_state=underrun[0m
499[36m2016-04-28 16:52:45,118 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
500[36m2016-04-28 16:52:45,118 sound output set_max_level lrange= 54, last_max_update=0s[0m
5012016-04-28 16:52:45,121 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 20336, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 77, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
502[36m2016-04-28 16:52:45,141 sound output add_data(417 bytes, {'duration': 26122449, 'timestamp': 1985306122, 'sequence': 0, 'time': 1461887549307}) queue_state=underrun[0m
503[36m2016-04-28 16:52:45,141 sound output pushed   417 bytes, new buffer level:   0ms, queue state=underrun[0m
504[36m2016-04-28 16:52:45,141 sound output set_max_level lrange= 54, last_max_update=0s[0m
5052016-04-28 16:52:45,191 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 20753, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 78, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
506[36m2016-04-28 16:52:45,200 sound output add_data(365 bytes, {'duration': 26122449, 'timestamp': 2011428571, 'sequence': 0, 'time': 1461887549367}) queue_state=underrun[0m
507[36m2016-04-28 16:52:45,200 sound output pushed   365 bytes, new buffer level:   0ms, queue state=underrun[0m
508[36m2016-04-28 16:52:45,200 sound output set_max_level lrange= 54, last_max_update=0s[0m
509[36m2016-04-28 16:52:45,223 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2037551020, 'sequence': 0, 'time': 1461887549390}) queue_state=underrun[0m
510[36m2016-04-28 16:52:45,223 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
511[36m2016-04-28 16:52:45,223 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2063673469, 'sequence': 0, 'time': 1461887549391}) queue_state=underrun[0m
512[36m2016-04-28 16:52:45,223 sound output pushed   208 bytes, new buffer level:  50ms, queue state=underrun[0m
513[36m2016-04-28 16:52:45,224 sound output set_max_level lrange= 54, last_max_update=0s[0m
5142016-04-28 16:52:45,251 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 21534, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 81, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
515[36m2016-04-28 16:52:45,256 sound output add_data(182 bytes, {'duration': 26122449, 'timestamp': 2089795918, 'sequence': 0, 'time': 1461887549424}) queue_state=underrun[0m
516[36m2016-04-28 16:52:45,256 sound output pushed   182 bytes, new buffer level:   0ms, queue state=underrun[0m
517[36m2016-04-28 16:52:45,257 sound output set_max_level lrange= 54, last_max_update=0s[0m
518[36m2016-04-28 16:52:45,303 sound output add_data(417 bytes, {'duration': 26122449, 'timestamp': 2115918367, 'sequence': 0, 'time': 1461887549470}) queue_state=underrun[0m
519[36m2016-04-28 16:52:45,303 sound output pushed   417 bytes, new buffer level:   0ms, queue state=underrun[0m
520[36m2016-04-28 16:52:45,303 sound output set_max_level lrange= 54, last_max_update=0s[0m
5212016-04-28 16:52:45,307 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 22133, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 83, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
522[36m2016-04-28 16:52:45,340 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2142040816, 'sequence': 0, 'time': 1461887549507}) queue_state=underrun[0m
523[36m2016-04-28 16:52:45,340 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
524[36m2016-04-28 16:52:45,340 sound output set_max_level lrange= 54, last_max_update=0s[0m
525[36m2016-04-28 16:52:45,349 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2168163265, 'sequence': 0, 'time': 1461887549516}) queue_state=underrun[0m
526[36m2016-04-28 16:52:45,349 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
527[36m2016-04-28 16:52:45,349 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2194285714, 'sequence': 0, 'time': 1461887549517}) queue_state=underrun[0m
528[36m2016-04-28 16:52:45,350 sound output set_max_level lrange= 54, last_max_update=0s[0m
529[36m2016-04-28 16:52:45,350 sound output pushed   208 bytes, new buffer level:  26ms, queue state=running[0m
530[36m2016-04-28 16:52:45,350 sound output set_max_level lrange= 54, last_max_update=0s[0m
5312016-04-28 16:52:45,391 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 22810, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 86, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
532[36m2016-04-28 16:52:45,409 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 2220408163, 'sequence': 0, 'time': 1461887549576}) queue_state=underrun[0m
533[36m2016-04-28 16:52:45,409 sound output pushed   313 bytes, new buffer level:   0ms, queue state=underrun[0m
534[36m2016-04-28 16:52:45,409 sound output set_max_level lrange= 54, last_max_update=0s[0m
535[36m2016-04-28 16:52:45,432 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2246530612, 'sequence': 0, 'time': 1461887549599}) queue_state=underrun[0m
536[36m2016-04-28 16:52:45,432 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
537[36m2016-04-28 16:52:45,432 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2272653061, 'sequence': 0, 'time': 1461887549600}) queue_state=underrun[0m
538[36m2016-04-28 16:52:45,433 sound output set_max_level lrange= 54, last_max_update=0s[0m
539[36m2016-04-28 16:52:45,433 sound output pushed   208 bytes, new buffer level:  58ms, queue state=running[0m
540[36m2016-04-28 16:52:45,433 sound output set_max_level lrange= 58, last_max_update=0s[0m
5412016-04-28 16:52:45,460 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 23592, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 89, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
542[36m2016-04-28 16:52:45,465 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2298775510, 'sequence': 0, 'time': 1461887549633}) queue_state=underrun[0m
543[36m2016-04-28 16:52:45,465 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
544[36m2016-04-28 16:52:45,465 sound output set_max_level lrange= 58, last_max_update=0s[0m
545[36m2016-04-28 16:52:45,479 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2324897959, 'sequence': 0, 'time': 1461887549646}) queue_state=underrun[0m
546[36m2016-04-28 16:52:45,479 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
547[36m2016-04-28 16:52:45,479 sound output set_max_level lrange= 58, last_max_update=0s[0m
548[36m2016-04-28 16:52:45,512 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 2351020408, 'sequence': 0, 'time': 1461887549679}) queue_state=underrun[0m
549[36m2016-04-28 16:52:45,512 sound output pushed   313 bytes, new buffer level:   0ms, queue state=underrun[0m
550[36m2016-04-28 16:52:45,512 sound output set_max_level lrange= 58, last_max_update=0s[0m
5512016-04-28 16:52:45,516 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 24427, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 92, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
552[36m2016-04-28 16:52:45,548 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2377142857, 'sequence': 0, 'time': 1461887549715}) queue_state=underrun[0m
553[36m2016-04-28 16:52:45,548 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
554[36m2016-04-28 16:52:45,548 sound output set_max_level lrange= 58, last_max_update=0s[0m
555[36m2016-04-28 16:52:45,558 sound output add_data(365 bytes, {'duration': 26122449, 'timestamp': 2403265306, 'sequence': 0, 'time': 1461887549725}) queue_state=underrun[0m
556[36m2016-04-28 16:52:45,558 sound output pushed   365 bytes, new buffer level:   0ms, queue state=underrun[0m
557[36m2016-04-28 16:52:45,558 sound output set_max_level lrange= 58, last_max_update=0s[0m
5582016-04-28 16:52:45,598 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 25053, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 94, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
559[36m2016-04-28 16:52:45,618 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 2429387755, 'sequence': 0, 'time': 1461887549785}) queue_state=underrun[0m
560[36m2016-04-28 16:52:45,618 sound output pushed   313 bytes, new buffer level:   0ms, queue state=underrun[0m
561[36m2016-04-28 16:52:45,618 sound output set_max_level lrange= 58, last_max_update=0s[0m
562[36m2016-04-28 16:52:45,642 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2455510204, 'sequence': 0, 'time': 1461887549809}) queue_state=underrun[0m
563[36m2016-04-28 16:52:45,642 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
564[36m2016-04-28 16:52:45,642 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2481632653, 'sequence': 0, 'time': 1461887549809}) queue_state=underrun[0m
565[36m2016-04-28 16:52:45,642 sound output set_max_level lrange= 58, last_max_update=0s[0m
566[36m2016-04-28 16:52:45,642 sound output pushed   208 bytes, new buffer level:  50ms, queue state=underrun[0m
5672016-04-28 16:52:45,672 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 25782, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 97, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
568[36m2016-04-28 16:52:45,674 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2507755102, 'sequence': 0, 'time': 1461887549842}) queue_state=underrun[0m
569[36m2016-04-28 16:52:45,674 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
570[36m2016-04-28 16:52:45,674 sound output set_max_level lrange= 58, last_max_update=0s[0m
571[36m2016-04-28 16:52:45,689 sound output add_data(417 bytes, {'duration': 26122449, 'timestamp': 2533877551, 'sequence': 0, 'time': 1461887549855}) queue_state=underrun[0m
572[36m2016-04-28 16:52:45,689 sound output pushed   417 bytes, new buffer level:   0ms, queue state=underrun[0m
573[36m2016-04-28 16:52:45,689 sound output set_max_level lrange= 58, last_max_update=0s[0m
574[36m2016-04-28 16:52:45,720 sound output add_data(313 bytes, {'duration': 26122448, 'timestamp': 2560000000, 'sequence': 0, 'time': 1461887549888}) queue_state=underrun[0m
575[36m2016-04-28 16:52:45,721 sound output pushed   313 bytes, new buffer level:   0ms, queue state=underrun[0m
576[36m2016-04-28 16:52:45,721 sound output set_max_level lrange= 58, last_max_update=0s[0m
5772016-04-28 16:52:45,725 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 26720, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 100, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
578[36m2016-04-28 16:52:45,767 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 2586122448, 'sequence': 0, 'time': 1461887549934}) queue_state=underrun[0m
579[36m2016-04-28 16:52:45,768 sound output pushed   313 bytes, new buffer level:   0ms, queue state=underrun[0m
580[36m2016-04-28 16:52:45,768 sound output set_max_level lrange= 58, last_max_update=0s[0m
581[36m2016-04-28 16:52:45,804 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2612244897, 'sequence': 0, 'time': 1461887549971}) queue_state=underrun[0m
582[36m2016-04-28 16:52:45,804 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
583[36m2016-04-28 16:52:45,804 sound output set_max_level lrange= 58, last_max_update=0s[0m
5842016-04-28 16:52:45,818 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 27241, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 102, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
585[36m2016-04-28 16:52:45,826 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2638367346, 'sequence': 0, 'time': 1461887549994}) queue_state=underrun[0m
586[36m2016-04-28 16:52:45,826 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
587[36m2016-04-28 16:52:45,826 sound output set_max_level lrange= 58, last_max_update=0s[0m
588[36m2016-04-28 16:52:45,850 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2664489795, 'sequence': 0, 'time': 1461887550017}) queue_state=underrun[0m
589[36m2016-04-28 16:52:45,851 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
590[36m2016-04-28 16:52:45,851 sound output set_max_level lrange= 58, last_max_update=0s[0m
5912016-04-28 16:52:45,877 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 27710, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 104, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
592[36m2016-04-28 16:52:45,883 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2690612244, 'sequence': 0, 'time': 1461887550050}) queue_state=underrun[0m
593[36m2016-04-28 16:52:45,883 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
594[36m2016-04-28 16:52:45,883 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2716734693, 'sequence': 0, 'time': 1461887550051}) queue_state=underrun[0m
595[36m2016-04-28 16:52:45,884 sound output set_max_level lrange= 58, last_max_update=0s[0m
596[36m2016-04-28 16:52:45,884 sound output pushed   208 bytes, new buffer level:  26ms, queue state=running[0m
597[36m2016-04-28 16:52:45,884 sound output set_max_level lrange= 58, last_max_update=0s[0m
598[36m2016-04-28 16:52:45,907 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2742857142, 'sequence': 0, 'time': 1461887550074}) queue_state=underrun[0m
599[36m2016-04-28 16:52:45,907 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
600[36m2016-04-28 16:52:45,907 sound output set_max_level lrange= 58, last_max_update=0s[0m
6012016-04-28 16:52:45,934 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 28440, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 107, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
602[36m2016-04-28 16:52:45,956 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 2768979591, 'sequence': 0, 'time': 1461887550121}) queue_state=underrun[0m
603[36m2016-04-28 16:52:45,956 sound output pushed   313 bytes, new buffer level:   0ms, queue state=underrun[0m
604[36m2016-04-28 16:52:45,956 sound output set_max_level lrange= 58, last_max_update=0s[0m
605[36m2016-04-28 16:52:45,989 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2795102040, 'sequence': 0, 'time': 1461887550156}) queue_state=underrun[0m
606[36m2016-04-28 16:52:45,989 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
607[36m2016-04-28 16:52:45,989 sound output set_max_level lrange= 58, last_max_update=0s[0m
6082016-04-28 16:52:46,007 info_update: {'queue.max': 250, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 29014, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 109, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
609[36m2016-04-28 16:52:46,012 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2821224489, 'sequence': 0, 'time': 1461887550180}) queue_state=underrun[0m
610[36m2016-04-28 16:52:46,012 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
611[36m2016-04-28 16:52:46,013 sound output set_max_level lrange= 58, last_max_update=0s[0m
612[36m2016-04-28 16:52:46,035 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2847346938, 'sequence': 0, 'time': 1461887550203}) queue_state=underrun[0m
613[36m2016-04-28 16:52:46,036 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
614[36m2016-04-28 16:52:46,036 sound output set_max_level lrange= 58, last_max_update=1s[0m
615[36m2016-04-28 16:52:46,036 sound output set_max_level overrun count=0 , margin= 50, pct= 0, cmst=250, mst=168[0m
616[36m2016-04-28 16:52:46,059 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2873469387, 'sequence': 0, 'time': 1461887550226}) queue_state=underrun[0m
617[36m2016-04-28 16:52:46,060 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
618[36m2016-04-28 16:52:46,060 sound output set_max_level lrange= 58, last_max_update=0s[0m
6192016-04-28 16:52:46,063 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 29691, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 112, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
620[36m2016-04-28 16:52:46,082 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2899591836, 'sequence': 0, 'time': 1461887550250}) queue_state=underrun[0m
621[36m2016-04-28 16:52:46,082 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
622[36m2016-04-28 16:52:46,082 sound output set_max_level lrange= 58, last_max_update=0s[0m
623[36m2016-04-28 16:52:46,115 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2925714285, 'sequence': 0, 'time': 1461887550283}) queue_state=underrun[0m
624[36m2016-04-28 16:52:46,116 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
625[36m2016-04-28 16:52:46,116 sound output set_max_level lrange= 58, last_max_update=0s[0m
6262016-04-28 16:52:46,133 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 30107, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 114, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
627[36m2016-04-28 16:52:46,139 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 2951836734, 'sequence': 0, 'time': 1461887550306}) queue_state=underrun[0m
628[36m2016-04-28 16:52:46,139 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
629[36m2016-04-28 16:52:46,139 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 2977959183, 'sequence': 0, 'time': 1461887550306}) queue_state=underrun[0m
630[36m2016-04-28 16:52:46,140 sound output set_max_level lrange= 58, last_max_update=0s[0m
631[36m2016-04-28 16:52:46,140 sound output pushed   208 bytes, new buffer level:  26ms, queue state=running[0m
632[36m2016-04-28 16:52:46,140 sound output set_max_level lrange= 58, last_max_update=0s[0m
633[36m2016-04-28 16:52:46,161 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3004081632, 'sequence': 0, 'time': 1461887550329}) queue_state=underrun[0m
634[36m2016-04-28 16:52:46,162 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
635[36m2016-04-28 16:52:46,162 sound output set_max_level lrange= 58, last_max_update=0s[0m
6362016-04-28 16:52:46,190 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 30837, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 117, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
637[36m2016-04-28 16:52:46,208 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 3030204081, 'sequence': 0, 'time': 1461887550376}) queue_state=underrun[0m
638[36m2016-04-28 16:52:46,208 sound output pushed   313 bytes, new buffer level:   0ms, queue state=underrun[0m
639[36m2016-04-28 16:52:46,208 sound output set_max_level lrange= 58, last_max_update=0s[0m
640[36m2016-04-28 16:52:46,244 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3056326530, 'sequence': 0, 'time': 1461887550412}) queue_state=underrun[0m
641[36m2016-04-28 16:52:46,245 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
642[36m2016-04-28 16:52:46,245 sound output set_max_level lrange= 58, last_max_update=0s[0m
6432016-04-28 16:52:46,259 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 31358, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 119, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
644[36m2016-04-28 16:52:46,268 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3082448979, 'sequence': 0, 'time': 1461887550436}) queue_state=underrun[0m
645[36m2016-04-28 16:52:46,269 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
646[36m2016-04-28 16:52:46,269 sound output set_max_level lrange= 58, last_max_update=0s[0m
647[36m2016-04-28 16:52:46,291 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3108571428, 'sequence': 0, 'time': 1461887550458}) queue_state=underrun[0m
648[36m2016-04-28 16:52:46,291 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
649[36m2016-04-28 16:52:46,292 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3134693877, 'sequence': 0, 'time': 1461887550459}) queue_state=underrun[0m
650[36m2016-04-28 16:52:46,292 sound output set_max_level lrange= 58, last_max_update=0s[0m
651[36m2016-04-28 16:52:46,292 sound output pushed   208 bytes, new buffer level:  26ms, queue state=running[0m
652[36m2016-04-28 16:52:46,292 sound output set_max_level lrange= 58, last_max_update=0s[0m
6532016-04-28 16:52:46,320 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 32088, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 122, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
654[36m2016-04-28 16:52:46,348 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 3160816326, 'sequence': 0, 'time': 1461887550515}) queue_state=underrun[0m
655[36m2016-04-28 16:52:46,348 sound output pushed   313 bytes, new buffer level:   0ms, queue state=underrun[0m
656[36m2016-04-28 16:52:46,349 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3186938775, 'sequence': 0, 'time': 1461887550515}) queue_state=underrun[0m
657[36m2016-04-28 16:52:46,349 sound output set_max_level lrange= 58, last_max_update=0s[0m
658[36m2016-04-28 16:52:46,349 sound output pushed   208 bytes, new buffer level:   4ms, queue state=running[0m
659[36m2016-04-28 16:52:46,349 sound output set_max_level lrange= 58, last_max_update=0s[0m
660[36m2016-04-28 16:52:46,373 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3213061224, 'sequence': 0, 'time': 1461887550539}) queue_state=underrun[0m
661[36m2016-04-28 16:52:46,373 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
662[36m2016-04-28 16:52:46,373 sound output set_max_level lrange= 58, last_max_update=0s[0m
6632016-04-28 16:52:46,399 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 32817, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 125, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
664[36m2016-04-28 16:52:46,417 sound output add_data(365 bytes, {'duration': 26122449, 'timestamp': 3239183673, 'sequence': 0, 'time': 1461887550585}) queue_state=underrun[0m
665[36m2016-04-28 16:52:46,417 sound output pushed   365 bytes, new buffer level:   0ms, queue state=underrun[0m
666[36m2016-04-28 16:52:46,417 sound output set_max_level lrange= 58, last_max_update=0s[0m
667[36m2016-04-28 16:52:46,455 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3265306122, 'sequence': 0, 'time': 1461887550621}) queue_state=underrun[0m
668[36m2016-04-28 16:52:46,455 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
669[36m2016-04-28 16:52:46,455 sound output set_max_level lrange= 58, last_max_update=0s[0m
6702016-04-28 16:52:46,468 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 33390, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 127, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
671[36m2016-04-28 16:52:46,476 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3291428571, 'sequence': 0, 'time': 1461887550644}) queue_state=underrun[0m
672[36m2016-04-28 16:52:46,476 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
673[36m2016-04-28 16:52:46,477 sound output set_max_level lrange= 58, last_max_update=0s[0m
674[36m2016-04-28 16:52:46,500 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3317551020, 'sequence': 0, 'time': 1461887550667}) queue_state=underrun[0m
675[36m2016-04-28 16:52:46,500 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
676[36m2016-04-28 16:52:46,500 sound output set_max_level lrange= 58, last_max_update=0s[0m
6772016-04-28 16:52:46,528 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 33806, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 129, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
678[36m2016-04-28 16:52:46,533 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3343673469, 'sequence': 0, 'time': 1461887550701}) queue_state=underrun[0m
679[36m2016-04-28 16:52:46,533 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
680[36m2016-04-28 16:52:46,533 sound output set_max_level lrange= 58, last_max_update=0s[0m
681[36m2016-04-28 16:52:46,559 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3369795918, 'sequence': 0, 'time': 1461887550725}) queue_state=underrun[0m
682[36m2016-04-28 16:52:46,559 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
683[36m2016-04-28 16:52:46,559 sound output set_max_level lrange= 58, last_max_update=0s[0m
684[36m2016-04-28 16:52:46,559 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3395918367, 'sequence': 0, 'time': 1461887550725}) queue_state=running[0m
685[36m2016-04-28 16:52:46,559 sound output pushed   261 bytes, new buffer level:   0ms, queue state=pushing[0m
686[36m2016-04-28 16:52:46,559 sound output set_max_level lrange= 58, last_max_update=0s[0m
687[36m2016-04-28 16:52:46,579 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3422040816, 'sequence': 0, 'time': 1461887550747}) queue_state=underrun[0m
688[36m2016-04-28 16:52:46,579 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
689[36m2016-04-28 16:52:46,580 sound output set_max_level lrange= 58, last_max_update=0s[0m
6902016-04-28 16:52:46,584 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 34691, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 133, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
691[36m2016-04-28 16:52:46,639 sound output add_data(417 bytes, {'duration': 26122449, 'timestamp': 3448163265, 'sequence': 0, 'time': 1461887550807}) queue_state=underrun[0m
692[36m2016-04-28 16:52:46,639 sound output pushed   417 bytes, new buffer level:   0ms, queue state=underrun[0m
693[36m2016-04-28 16:52:46,640 sound output set_max_level lrange= 58, last_max_update=0s[0m
694[36m2016-04-28 16:52:46,663 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3474285714, 'sequence': 0, 'time': 1461887550830}) queue_state=underrun[0m
695[36m2016-04-28 16:52:46,663 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
696[36m2016-04-28 16:52:46,663 sound output set_max_level lrange= 58, last_max_update=0s[0m
697[36m2016-04-28 16:52:46,686 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3500408163, 'sequence': 0, 'time': 1461887550853}) queue_state=underrun[0m
698[36m2016-04-28 16:52:46,686 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
699[36m2016-04-28 16:52:46,686 sound output set_max_level lrange= 58, last_max_update=0s[0m
7002016-04-28 16:52:46,690 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 35630, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 136, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
701[36m2016-04-28 16:52:46,709 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3526530612, 'sequence': 0, 'time': 1461887550876}) queue_state=underrun[0m
702[36m2016-04-28 16:52:46,709 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
703[36m2016-04-28 16:52:46,709 sound output set_max_level lrange= 58, last_max_update=0s[0m
704[36m2016-04-28 16:52:46,742 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3552653061, 'sequence': 0, 'time': 1461887550910}) queue_state=underrun[0m
705[36m2016-04-28 16:52:46,742 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
706[36m2016-04-28 16:52:46,743 sound output set_max_level lrange= 58, last_max_update=0s[0m
7072016-04-28 16:52:46,760 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 36099, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 138, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
708[36m2016-04-28 16:52:46,765 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3578775510, 'sequence': 0, 'time': 1461887550933}) queue_state=underrun[0m
709[36m2016-04-28 16:52:46,765 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
710[36m2016-04-28 16:52:46,765 sound output set_max_level lrange= 58, last_max_update=0s[0m
711[36m2016-04-28 16:52:46,789 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3604897959, 'sequence': 0, 'time': 1461887550956}) queue_state=underrun[0m
712[36m2016-04-28 16:52:46,789 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
713[36m2016-04-28 16:52:46,789 sound output set_max_level lrange= 58, last_max_update=0s[0m
7142016-04-28 16:52:46,817 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 36568, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 140, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
715[36m2016-04-28 16:52:46,825 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3631020408, 'sequence': 0, 'time': 1461887550992}) queue_state=underrun[0m
716[36m2016-04-28 16:52:46,825 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
717[36m2016-04-28 16:52:46,825 sound output set_max_level lrange= 58, last_max_update=0s[0m
718[36m2016-04-28 16:52:46,848 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3657142857, 'sequence': 0, 'time': 1461887551016}) queue_state=underrun[0m
719[36m2016-04-28 16:52:46,848 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
720[36m2016-04-28 16:52:46,848 sound output set_max_level lrange= 58, last_max_update=0s[0m
721[36m2016-04-28 16:52:46,873 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3683265306, 'sequence': 0, 'time': 1461887551039}) queue_state=underrun[0m
722[36m2016-04-28 16:52:46,873 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
723[36m2016-04-28 16:52:46,873 sound output set_max_level lrange= 58, last_max_update=0s[0m
7242016-04-28 16:52:46,876 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 37245, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 143, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
725[36m2016-04-28 16:52:46,894 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3709387755, 'sequence': 0, 'time': 1461887551062}) queue_state=underrun[0m
726[36m2016-04-28 16:52:46,895 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
727[36m2016-04-28 16:52:46,895 sound output set_max_level lrange= 58, last_max_update=0s[0m
728[36m2016-04-28 16:52:46,918 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3735510204, 'sequence': 0, 'time': 1461887551085}) queue_state=underrun[0m
729[36m2016-04-28 16:52:46,918 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
730[36m2016-04-28 16:52:46,918 sound output set_max_level lrange= 58, last_max_update=0s[0m
7312016-04-28 16:52:46,945 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 37714, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 145, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
732[36m2016-04-28 16:52:46,951 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3761632653, 'sequence': 0, 'time': 1461887551119}) queue_state=underrun[0m
733[36m2016-04-28 16:52:46,951 sound output pushed   261 bytes, new buffer level:   0ms, queue state=underrun[0m
734[36m2016-04-28 16:52:46,951 sound output set_max_level lrange= 58, last_max_update=0s[0m
735[36m2016-04-28 16:52:46,974 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3787755102, 'sequence': 0, 'time': 1461887551142}) queue_state=underrun[0m
736[36m2016-04-28 16:52:46,974 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
737[36m2016-04-28 16:52:46,974 sound output set_max_level lrange= 58, last_max_update=0s[0m
738[36m2016-04-28 16:52:46,998 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3813877551, 'sequence': 0, 'time': 1461887551165}) queue_state=underrun[0m
739[36m2016-04-28 16:52:46,998 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
740[36m2016-04-28 16:52:46,998 sound output set_max_level lrange= 58, last_max_update=0s[0m
7412016-04-28 16:52:47,003 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 0, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 38391, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 0, 'buffers': 148, 'codec_mode': 'mono', 'bitrate': 89000, 'codec': 'mp3', 'queue.min': 0}
742[36m2016-04-28 16:52:47,023 sound output add_data(208 bytes, {'duration': 26122448, 'timestamp': 3840000000, 'sequence': 0, 'time': 1461887551188}) queue_state=underrun[0m
743[36m2016-04-28 16:52:47,023 sound output pushed   208 bytes, new buffer level:   0ms, queue state=underrun[0m
744[36m2016-04-28 16:52:47,023 sound output add_data(182 bytes, {'duration': 26122449, 'timestamp': 3866122448, 'sequence': 0, 'time': 1461887551189}) queue_state=underrun[0m
745[36m2016-04-28 16:52:47,024 sound output set_min_level pct=80, cmtt=  0, mtt=100, lrange=26 (UNDERRUN_MIN_LEVEL=30)[0m
746[36m2016-04-28 16:52:47,024 sound output pushed   182 bytes, new buffer level:  26ms, queue state=running[0m
747[36m2016-04-28 16:52:47,024 sound output set_min_level min-threshold-time=100[0m
748[36m2016-04-28 16:52:47,024 sound output set_max_level lrange= 58, last_max_update=0s[0m
749[36m2016-04-28 16:52:47,024 sound output new bitrate: 79000[0m
750[36m2016-04-28 16:52:47,060 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 3892244897, 'sequence': 0, 'time': 1461887551225}) queue_state=underrun[0m
751[36m2016-04-28 16:52:47,060 sound output pushed   313 bytes, new buffer level:  52ms, queue state=underrun[0m
7522016-04-28 16:52:47,075 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 31, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 39094, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 52, 'buffers': 151, 'codec_mode': 'mono', 'bitrate': 79000, 'codec': 'mp3', 'queue.min': 100}
753[36m2016-04-28 16:52:47,105 sound output add_data(417 bytes, {'duration': 26122449, 'timestamp': 3918367346, 'sequence': 0, 'time': 1461887551271}) queue_state=underrun[0m
754[36m2016-04-28 16:52:47,105 sound output pushed   417 bytes, new buffer level:  52ms, queue state=underrun[0m
755[36m2016-04-28 16:52:47,105 sound output new bitrate: 80000[0m
756[36m2016-04-28 16:52:47,106 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 3944489795, 'sequence': 0, 'time': 1461887551271}) queue_state=underrun[0m
757[36m2016-04-28 16:52:47,106 sound output pushed   261 bytes, new buffer level:  78ms, queue state=underrun[0m
758[36m2016-04-28 16:52:47,106 sound output set_min_level pct=76, cmtt=100, mtt= 95, lrange=54 (UNDERRUN_MIN_LEVEL=30)[0m
759[36m2016-04-28 16:52:47,106 sound output set_min_level min-threshold-time=95[0m
760[36m2016-04-28 16:52:47,106 sound output set_max_level lrange= 78, last_max_update=1s[0m
761[36m2016-04-28 16:52:47,106 sound output set_max_level overrun count=0 , margin= 50, pct= 0, cmst=168, mst=142[0m
762[36m2016-04-28 16:52:47,127 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 3970612244, 'sequence': 0, 'time': 1461887551294}) queue_state=underrun[0m
763[36m2016-04-28 16:52:47,127 sound output pushed   208 bytes, new buffer level:  82ms, queue state=underrun[0m
7642016-04-28 16:52:47,156 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 49, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 39980, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 83, 'buffers': 154, 'codec_mode': 'mono', 'bitrate': 80000, 'codec': 'mp3', 'queue.min': 95}
765[36m2016-04-28 16:52:47,184 sound output add_data(313 bytes, {'duration': 26122449, 'timestamp': 3996734693, 'sequence': 0, 'time': 1461887551351}) queue_state=underrun[0m
766[36m2016-04-28 16:52:47,184 sound output pushed   313 bytes, new buffer level:  83ms, queue state=underrun[0m
767[36m2016-04-28 16:52:47,184 sound output set_min_level pct=72, cmtt= 95, mtt= 90, lrange=54 (UNDERRUN_MIN_LEVEL=30)[0m
768[36m2016-04-28 16:52:47,184 sound output set_min_level min-threshold-time=90[0m
769[36m2016-04-28 16:52:47,184 sound output set_max_level lrange= 83, last_max_update=1s[0m
770[36m2016-04-28 16:52:47,184 sound output set_max_level overrun count=0 , margin= 50, pct= 0, cmst=168, mst=146[0m
771[36m2016-04-28 16:52:47,207 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 4022857142, 'sequence': 0, 'time': 1461887551374}) queue_state=underrun[0m
772[36m2016-04-28 16:52:47,208 sound output pushed   261 bytes, new buffer level:  82ms, queue state=underrun[0m
773[36m2016-04-28 16:52:47,208 sound output set_min_level pct=71, cmtt= 90, mtt= 88, lrange=54 (UNDERRUN_MIN_LEVEL=30)[0m
774[36m2016-04-28 16:52:47,208 sound output set_min_level min-threshold-time=88[0m
775[36m2016-04-28 16:52:47,208 sound output set_max_level lrange= 83, last_max_update=1s[0m
776[36m2016-04-28 16:52:47,208 sound output set_max_level overrun count=0 , margin= 50, pct= 0, cmst=168, mst=146[0m
777[36m2016-04-28 16:52:47,231 sound output add_data(261 bytes, {'duration': 26122449, 'timestamp': 4048979591, 'sequence': 0, 'time': 1461887551397}) queue_state=underrun[0m
778[36m2016-04-28 16:52:47,231 sound output pushed   261 bytes, new buffer level:  79ms, queue state=underrun[0m
779[36m2016-04-28 16:52:47,231 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 4075102040, 'sequence': 0, 'time': 1461887551397}) queue_state=underrun[0m
780[36m2016-04-28 16:52:47,231 sound output set_min_level pct=70, cmtt= 88, mtt= 87, lrange=54 (UNDERRUN_MIN_LEVEL=30)[0m
781[36m2016-04-28 16:52:47,231 sound output pushed   208 bytes, new buffer level: 106ms, queue state=underrun[0m
782[36m2016-04-28 16:52:47,232 sound output set_min_level min-threshold-time=87[0m
783[36m2016-04-28 16:52:47,232 sound output set_max_level lrange=106, last_max_update=1s[0m
784[36m2016-04-28 16:52:47,232 sound output set_max_level overrun count=0 , margin= 50, pct= 0, cmst=168, mst=163[0m
7852016-04-28 16:52:47,235 info_update: {'queue.max': 168, 'pipeline': 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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', 'queue.pct': 31, 'queue.state': 'underrun', 'codec_description': 'mpeg 1 audio, layer 3 (mp3)', 'bytes': 41023, 'queue.underruns': 0, 'volume': 100, 'state': 'active', 'queue.overruns': 0, 'queue.cur': 52, 'buffers': 158, 'codec_mode': 'mono', 'bitrate': 80000, 'codec': 'mp3', 'queue.min': 87}
786[36m2016-04-28 16:52:47,266 sound output add_data(208 bytes, {'duration': 26122449, 'timestamp': 4101224489, 'sequence': 0, 'time': 1461887551434}) queue_state=underrun[0m
787[36m2016-04-28 16:52:47,267 sound output pushed   208 bytes, new buffer level:  52ms, queue state=underrun[0m
788
789got signal SIGINT, exiting
790[36m2016-04-28 16:52:47,291 sound output stop() wrapped object=SoundSink('appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=stream format=4 ! mp3parse ! mad ! audioconvert ! audioresample ! volume name=volume volume=1.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' - active)[0m
7912016-04-28 16:52:47,292 stop_receiving_sound(True) calling <bound method sink_subprocess_wrapper.cleanup of sink_subprocess_wrapper(13406)>
792[36m2016-04-28 16:52:47,292 sound output SoundPipeline.cleanup()[0m
793[36m2016-04-28 16:52:47,292 sound output SoundPipeline.stop() state=active[0m
7942016-04-28 16:52:47,292 sound output stopping[0m
7952016-04-28 16:52:47,292 cleanup() sending cleanup request to sound output
7962016-04-28 16:52:47,292 stop_receiving_sound(True) done
7972016-04-28 16:52:47,292 subprocess_signal: SIGINT
798[36m2016-04-28 16:52:47,296 sound output SoundPipeline.stop() done[0m
799[36m2016-04-28 16:52:47,297 sound output SoundPipeline.cleanup() bus=/GstBus:bus2 (gst.Bus)[0m
800[36m2016-04-28 16:52:47,297 sound output SoundPipeline.cleanup() bus_message_handler_id=13[0m
801[36m2016-04-28 16:52:47,297 sound output SoundPipeline.cleanup() done[0m
802[36m2016-04-28 16:52:47,297 sound output stop() wrapped object=None[0m
803[36m2016-04-28 16:52:47,297 sound output stop() wrapped object=None[0m
804[36m2016-04-28 16:52:47,297 sound output stop() wrapped object=None[0m