Thank you for your donation!


Solved: Sound form NAS is breaking up via WiFi connection
#19
(10-09-2018, 05:14 PM)Tim Curtis Wrote: This error in mpd log suggests a possible codec issue.
Oct 09 18:48 : player: Decoder is too slow; playing silence to avoid xrun

MPD in moOde 4.2 used only ffmpeg. moOde 4.3 added the libaudiofile codec library.

Lets try turning on verbose logging in MPD and see what codec its using for the aif and wav files.

1. sudo nano /etc/mpd.conf
2. change
log_level "default"
to
log_level "verbose"
3. save the file
4. sudo systemctl restart mpd
5. play a song
6. post the verbose log output for that particular song play. It should list the codec, either [audiofile] or [ffmpeg].

-Tim

Hi Tim,

Here is the verbose log;
Code:
Oct 10 02:28 : client: [40] opened from 127.0.0.1:46376
Oct 10 02:28 : client: [40] process command "next"
Oct 10 02:28 : playlist: play 11:"NAS/kitamura-music/Bill Evans/Some Other Time; The Lost Session From the Black Forest-DISC1/01 Bill Evans - You Go to My Head.aif"
Oct 10 02:28 : decoder_thread: probing plugin audiofile
Oct 10 02:28 : decoder: audio_format=96000:24:2, seekable=true
Oct 10 02:28 : playlist: queue song 11:"NAS/kitamura-music/Idles/Joy as an Act of Resistance/01 Idles - Colossus .aif"
Oct 10 02:28 : client: [40] command returned 0
Oct 10 02:28 : client: [40] process command "close"
Oct 10 02:28 : client: [40] command returned 3
Oct 10 02:28 : client: [40] closed
Oct 10 02:28 : client: [37] process command "status"
Oct 10 02:28 : client: [37] command returned 0
Oct 10 02:28 : client: [37] process command "currentsong"
Oct 10 02:28 : client: [37] command returned 0
Oct 10 02:28 : client: [37] process command "close"
Oct 10 02:28 : client: [37] command returned 3
Oct 10 02:28 : client: [37] closed
Oct 10 02:28 : client: [41] opened from 127.0.0.1:46380
Oct 10 02:28 : client: [41] process command "close"
Oct 10 02:28 : client: [41] command returned 3
Oct 10 02:28 : client: [41] closed
Oct 10 02:28 : client: [42] opened from 127.0.0.1:46384
Oct 10 02:28 : client: [42] process command "playlistinfo"
Oct 10 02:28 : client: [42] command returned 0
Oct 10 02:28 : client: [42] process command "close"
Oct 10 02:28 : client: [42] command returned 3
Oct 10 02:28 : client: [42] closed
Oct 10 02:28 : client: [43] opened from 127.0.0.1:46388
Oct 10 02:28 : client: [43] process command "status"
Oct 10 02:28 : client: [43] command returned 0
Oct 10 02:28 : client: [43] process command "idle"
Oct 10 02:28 : client: [43] command returned 1
Oct 10 02:28 : client: [44] opened from 127.0.0.1:46392
Oct 10 02:28 : client: [44] process command "close"
Oct 10 02:28 : client: [44] command returned 3
Oct 10 02:28 : client: [44] closed
Oct 10 02:28 : state_file: Saving state file /var/lib/mpd/state
Oct 10 02:28 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:28 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:28 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:29 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:29 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:29 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:29 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:29 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:29 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:29 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:29 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:29 : client: [45] opened from 127.0.0.1:46398
Oct 10 02:29 : client: [45] process command "next"
Oct 10 02:29 : playlist: play 11:"NAS/kitamura-music/Idles/Joy as an Act of Resistance/01 Idles - Colossus .aif"
Oct 10 02:29 : decoder_thread: probing plugin audiofile
Oct 10 02:29 : decoder: audio_format=96000:24:2, seekable=true
Oct 10 02:29 : playlist: queue song 11:"NAS/kitamura-music/Fabrizio Sotti Trio/Forty/01 Fabrizio Sotti - Redemption.aif"
Oct 10 02:29 : client: [45] command returned 0
Oct 10 02:29 : client: [45] process command "close"
Oct 10 02:29 : client: [45] command returned 3
Oct 10 02:29 : client: [45] closed
Oct 10 02:29 : client: [43] process command "status"
Oct 10 02:29 : client: [43] command returned 0
Oct 10 02:29 : client: [43] process command "currentsong"
Oct 10 02:29 : client: [43] command returned 0
Oct 10 02:29 : client: [43] process command "close"
Oct 10 02:29 : client: [43] command returned 3
Oct 10 02:29 : client: [43] closed
Oct 10 02:29 : client: [46] opened from 127.0.0.1:46402
Oct 10 02:29 : client: [46] process command "close"
Oct 10 02:29 : client: [46] command returned 3
Oct 10 02:29 : client: [46] closed
Oct 10 02:29 : client: [47] opened from 127.0.0.1:46406
Oct 10 02:29 : client: [47] process command "playlistinfo"
Oct 10 02:29 : client: [47] command returned 0
Oct 10 02:29 : client: [47] process command "close"
Oct 10 02:29 : client: [47] command returned 3
Oct 10 02:29 : client: [47] closed
Oct 10 02:29 : client: [48] opened from 127.0.0.1:46410
Oct 10 02:29 : client: [48] process command "status"
Oct 10 02:29 : client: [48] command returned 0
Oct 10 02:29 : client: [48] process command "idle"
Oct 10 02:29 : client: [48] command returned 1
Oct 10 02:29 : client: [49] opened from 127.0.0.1:46414
Oct 10 02:29 : client: [49] process command "close"
Oct 10 02:29 : client: [49] command returned 3
Oct 10 02:29 : client: [49] closed
Oct 10 02:29 : state_file: Saving state file /var/lib/mpd/state
Oct 10 02:30 : client: [50] opened from 127.0.0.1:46420
Oct 10 02:30 : client: [50] process command "currentsong"
Oct 10 02:30 : client: [50] command returned 0
Oct 10 02:30 : client: [50] process command "status"
Oct 10 02:30 : client: [50] command returned 0
Oct 10 02:30 : client: [50] closed
Oct 10 02:30 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:30 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:30 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:30 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:30 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:30 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:30 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:31 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:31 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:31 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:31 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:31 : client: [51] opened from 127.0.0.1:46424
Oct 10 02:31 : client: [51] process command "next"
Oct 10 02:31 : playlist: play 11:"NAS/kitamura-music/Fabrizio Sotti Trio/Forty/01 Fabrizio Sotti - Redemption.aif"
Oct 10 02:31 : decoder_thread: probing plugin audiofile
Oct 10 02:31 : decoder: audio_format=96000:24:2, seekable=true
Oct 10 02:31 : client: [51] command returned 0
Oct 10 02:31 : client: [51] process command "close"
Oct 10 02:31 : client: [51] command returned 3
Oct 10 02:31 : client: [51] closed
Oct 10 02:31 : client: [48] process command "status"
Oct 10 02:31 : client: [48] command returned 0
Oct 10 02:31 : client: [48] process command "currentsong"
Oct 10 02:31 : client: [48] command returned 0
Oct 10 02:31 : client: [48] process command "close"
Oct 10 02:31 : client: [48] command returned 3
Oct 10 02:31 : client: [48] closed
Oct 10 02:31 : client: [52] opened from 127.0.0.1:46428
Oct 10 02:31 : client: [52] process command "close"
Oct 10 02:31 : client: [52] command returned 3
Oct 10 02:31 : client: [52] closed
Oct 10 02:31 : client: [53] opened from 127.0.0.1:46432
Oct 10 02:31 : client: [53] process command "playlistinfo"
Oct 10 02:31 : client: [53] command returned 0
Oct 10 02:31 : client: [53] process command "close"
Oct 10 02:31 : client: [53] command returned 3
Oct 10 02:31 : client: [53] closed
Oct 10 02:31 : client: [54] opened from 127.0.0.1:46436
Oct 10 02:31 : client: [54] process command "status"
Oct 10 02:31 : client: [54] command returned 0
Oct 10 02:31 : client: [54] process command "idle"
Oct 10 02:31 : client: [54] command returned 1
Oct 10 02:31 : client: [55] opened from 127.0.0.1:46440
Oct 10 02:31 : client: [55] process command "close"
Oct 10 02:31 : client: [55] command returned 3
Oct 10 02:31 : client: [55] closed
Oct 10 02:31 : state_file: Saving state file /var/lib/mpd/state
Oct 10 02:31 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:31 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:31 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:31 : player: Decoder is too slow; playing silence to avoid xrun
Oct 10 02:32 : client: [56] opened from 127.0.0.1:46446
Oct 10 02:32 : client: [56] process command "pause"
Oct 10 02:32 : output: closed plugin=alsa name="ALSA default"
Oct 10 02:32 : client: [56] command returned 0
Oct 10 02:32 : client: [56] process command "close"
Oct 10 02:32 : client: [56] command returned 3
Oct 10 02:32 : client: [56] closed
Oct 10 02:32 : client: [54] process command "status"
Oct 10 02:32 : client: [54] command returned 0
Oct 10 02:32 : client: [54] process command "currentsong"
Oct 10 02:32 : client: [54] command returned 0
Oct 10 02:32 : client: [54] process command "close"
Oct 10 02:32 : client: [54] command returned 3
Oct 10 02:32 : client: [54] closed
Oct 10 02:32 : client: [57] opened from 127.0.0.1:46450
Oct 10 02:32 : client: [57] process command "close"
Oct 10 02:32 : client: [57] command returned 3
Oct 10 02:32 : client: [57] closed
Oct 10 02:32 : client: [58] opened from 127.0.0.1:46454
Oct 10 02:32 : client: [58] process command "status"
Oct 10 02:32 : client: [58] command returned 0
Oct 10 02:32 : client: [58] process command "idle"
Oct 10 02:32 : client: [58] command returned 1
Oct 10 02:32 : client: [59] opened from 127.0.0.1:46458
Oct 10 02:32 : client: [59] process command "close"
Oct 10 02:32 : client: [59] command returned 3
Oct 10 02:32 : client: [59] closed
Oct 10 02:32 : state_file: Saving state file /var/lib/mpd/state

Thanks,
kitamura_design
Reply


Messages In This Thread
RE: Sound form NAS is breaking up via WiFi connection - by kitamura_design - 10-09-2018, 05:42 PM

Forum Jump: