expectingtofly wrote: > I've added a Buffer threshold setting to BBC sounds and amended the > protocol handler. To be honest, it doesn't seem to have much impact. > In this trace I've set it to the bufferThreadhold to 4 seconds of audio, > which on the 320 streams is 160 kb (163840). > Here is the log trace from that, as you can see, the threshold setting > that is being applied, appears in the log as expected, but I don't > really understand what that means in this context as it doesn't seem to > be filling up the output buffer with 4 seconds of audio before the > underrun occurs. Sorry, if I misunderstood what was meant to occur. > > As you can see following the underrun, both the input and output > buffers fill up and it is rock solid from there onwards. > > > Code: -------------------- > > > Feb 28 14:31:46 squeezeplay: DEBUG audio.decode - decode_start:705 decode_start > Feb 28 14:31:46 squeezeplay: INFO audio.decode - decode_start_handler:281 init decoder aac > Feb 28 14:31:46 squeezeplay: DEBUG audio.decode - decode_output_set_track_gain:520 Track gain 65536 > Feb 28 14:31:46 squeezeplay: DEBUG audio.decode - decode_output_set_track_gain:524 Track clip range 7fffffff 80000000 > Feb 28 14:31:46 squeezeplay: DEBUG audio.decode - decode_set_track_polarity_inversion:529 Polarity inversion 0 > Feb 28 14:31:46 squeezeplay: DEBUG audio.decode - decode_set_output_channels:537 Output channels left 0, right 0 > Feb 28 14:31:46 squeezeplay: INFO audio.decode - Playback.lua:477 connect 172.16.0.8:9000 GET /stream.mp3?player=00:04:20:2c:fa:10 HTTP/1.0^M > Feb 28 14:31:46 squeezeplay: DEBUG audio.decode - Playback.lua:194 source=stream > Feb 28 14:31:46 squeezeplay: DEBUG audio.decode - stream_connectL:506 streambuf connect 172.16.0.8:9000 > Feb 28 14:31:46 squeezeplay: DEBUG audio.decode - Playback.lua:1262 stopping local pause timer > Feb 28 14:31:46 squeezeplay: DEBUG audio.decode - Playback.lua:1192 gainL, gainR: 555 555 > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - Playback.lua:397 resume decoder, 32768 bytes buffered, decode threshold 2048 > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - decode_resume_decoder:580 decode_resume_decoder > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - decode_resume_decoder_handler:122 resume_decoder decode state: 1 audio state 0 > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - debug_fullness:112 fullness: 32768 / 0 | 1.04% / 0.00% > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - decode_output_samples:280 first buffer sample_rate=48000 > Feb 28 14:31:48 squeezeplay: INFO audio.decode - Playback.lua:448 2.7%/0.5% > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - Playback.lua:424 resume audio bytesReceivedL=223552 outputTime=320 threshold=163840 > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - decode_resume_audio:601 decode_resume_audio start_jiffies=0 > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - decode_resume_audio_handler:133 decode_resume_audio_handler start_jiffies=0 > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - debug_fullness:112 fullness: 178496 / 131072 | 5.67% / 3.72% > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - decode_resume_audio_handler:146 resume_audio decode state: 1 audio state 40 > Feb 28 14:31:48 squeezeplay: DEBUG audio.decode - Playback.lua:381 status TRACK STARTED (elapsed: 35) > Feb 28 14:31:49 squeezeplay: INFO audio.decode - Playback.lua:448 14.8%/2.5% > Feb 28 14:31:50 squeezeplay: playback_callback:346 Audio underrun: used 448 frames, requested 480 frames. elapsed samples 83520 > Feb 28 14:31:50 squeezeplay: INFO audio.decode - Playback.lua:364 OUTPUT UNDERRUN > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - decode_pause_audio:624 decode_pause_audio interval_ms=0 > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - decode_pause_audio_handler:156 decode_pause_handler interval=0 > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - decode_pause_audio_handler:171 pause_audio decode state: 1 audio state 0 > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - Playback.lua:855 strm p > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - Playback.lua:1262 stopping local pause timer > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - decode_pause_audio:624 decode_pause_audio interval_ms=0 > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - decode_pause_audio_handler:156 decode_pause_handler interval=0 > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - decode_pause_audio_handler:171 pause_audio decode state: 1 audio state 0 > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - Playback.lua:1262 stopping local pause timer > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - Playback.lua:1192 gainL, gainR: 0 0 > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - Playback.lua:855 strm u > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - Playback.lua:991 resume unpause > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - decode_resume_audio:601 decode_resume_audio start_jiffies=0 > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - decode_resume_audio_handler:133 decode_resume_audio_handler start_jiffies=0 > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - debug_fullness:112 fullness: 910447 / 82944 | 28.94% / 2.35% > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - decode_resume_audio_handler:146 resume_audio decode state: 1 audio state 40 > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - Playback.lua:1262 stopping local pause timer > Feb 28 14:31:50 squeezeplay: DEBUG audio.decode - Playback.lua:1192 gainL, gainR: 555 555 > Feb 28 14:31:51 squeezeplay: INFO audio.decode - Playback.lua:448 33.6%/5.4% > Feb 28 14:31:52 squeezeplay: INFO audio.decode - Playback.lua:448 42.3%/7.9% > Feb 28 14:31:53 squeezeplay: INFO audio.decode - Playback.lua:448 50.6%/11.3% > Feb 28 14:31:54 squeezeplay: INFO audio.decode - Playback.lua:448 63.9%/12.7% > Feb 28 14:31:55 squeezeplay: INFO audio.decode - Playback.lua:448 78.2%/9.8% > Feb 28 14:31:56 squeezeplay: INFO audio.decode - Playback.lua:448 85.7%/15.4% > Feb 28 14:31:57 squeezeplay: INFO audio.decode - Playback.lua:448 99.7%/17.3% > Feb 28 14:31:58 squeezeplay: INFO audio.decode - Playback.lua:448 99.7%/29.0% > Feb 28 14:31:59 squeezeplay: INFO audio.decode - Playback.lua:448 99.6%/41.0% > Feb 28 14:32:00 squeezeplay: INFO audio.decode - Playback.lua:448 99.7%/52.0% > Feb 28 14:32:01 squeezeplay: INFO audio.decode - Playback.lua:448 99.7%/63.9% > Feb 28 14:32:03 squeezeplay: INFO audio.decode - Playback.lua:448 99.6%/76.8% > Feb 28 14:32:04 squeezeplay: INFO audio.decode - Playback.lua:448 99.7%/88.1% > Feb 28 14:32:05 squeezeplay: INFO audio.decode - Playback.lua:448 99.6%/99.0% > Feb 28 14:32:06 squeezeplay: INFO audio.decode - Playback.lua:448 100.0%/97.9% > Feb 28 14:32:07 squeezeplay: INFO audio.decode - Playback.lua:448 100.0%/97.6% > Feb 28 14:32:08 squeezeplay: INFO audio.decode - Playback.lua:448 99.9%/98.0% > Feb 28 14:32:09 squeezeplay: INFO audio.decode - Playback.lua:448 100.0%/97.4% > Feb 28 14:32:10 squeezeplay: DEBUG audio.decode - Playback.lua:1262 stopping local pause timer > Feb 28 14:32:10 squeezeplay: DEBUG audio.decode - Playback.lua:1192 gainL, gainR: 0 0 > Feb 28 14:32:10 squeezeplay: DEBUG audio.decode - Playback.lua:855 strm p > Feb 28 14:32:10 squeezeplay: DEBUG audio.decode - Playback.lua:1262 stopping local pause timer > Feb 28 14:32:10 squeezeplay: DEBUG audio.decode - decode_pause_audio:624 decode_pause_audio interval_ms=0 > Feb 28 14:32:10 squeezeplay: DEBUG audio.decode - decode_pause_audio_handler:156 decode_pause_handler interval=0 > Feb 28 14:32:10 squeezeplay: DEBUG audio.decode - decode_pause_audio_handler:171 pause_audio decode state: 1 audio state 0 > Feb 28 14:32:10 squeezeplay: DEBUG audio.decode - Playback.lua:855 strm q > Feb 28 14:32:10 squeezeplay: DEBUG audio.decode - Playback.lua:1262 stopping local pause timer > Feb 28 14:32:10 squeezeplay: DEBUG audio.decode - decode_stop:667 decode_stop > -------------------- > > > > As far as I'm concerned the ramp up audio underruns really aren't > that much of a problem, they don't always happen, and now that the > output buffer is filling up correctly they don't occur again once the > stream is underway. Thank you for trying this. I'll have a closer look at your log later on today.
I agree that just with the higher buffer fill rate after the initial pause, it is much better than the r16835 release. Do you see the same issue with either the hide audio sample rate from LMS or Force http streaming options enabled? Ralphy *1*-Touch, *5*-Classics, *3*-Booms, *2*-UE Radio 'Squeezebox client builds' (https://sourceforge.net/projects/lmsclients/files/) 'donations' (https://www.paypal.com/cgi-bin/webscr?cmd=_donations&business=LL5P6365KQEXN&lc=CA&item_name=Squeezebox%20client%20builds¤cy_code=USD&bn=PP%2dDonationsBF%3abtn_donate_SM%2egif%3aNonHosted) always appreciated. ------------------------------------------------------------------------ ralphy's Profile: http://forums.slimdevices.com/member.php?userid=3484 View this thread: http://forums.slimdevices.com/showthread.php?t=113479 _______________________________________________ plugins mailing list plugins@lists.slimdevices.com http://lists.slimdevices.com/mailman/listinfo/plugins
