05-24-2018, 05:14 PM
Hi Tim,
Here's what I did:
1) Put a single FLAC 24/88.2 file on the USB stick
2) Plug the stick to Pi, rescan
3) Reboot
4) After reboot, click play on the web UI
5) Once the glitch occur around 3 or 4 times (1 minute 30 seconds in), click pause
6) Obtain the logs
I did a few reboots between step 3 to step 4 to get the process down right. The log files are as such:
1) Menu, Configure, System | Menu, System info
2) tail -f /var/log/mpd/log
Thanks for your help
Here's what I did:
1) Put a single FLAC 24/88.2 file on the USB stick
2) Plug the stick to Pi, rescan
3) Reboot
4) After reboot, click play on the web UI
5) Once the glitch occur around 3 or 4 times (1 minute 30 seconds in), click pause
6) Obtain the logs
I did a few reboots between step 3 to step 4 to get the process down right. The log files are as such:
1) Menu, Configure, System | Menu, System info
Code:
System information
S Y S T E M P A R A M E T E R S
Date and time = 2018-05-24 13:02:26
System uptime = up 2 minutes
Timezone = America/Detroit
moOde = Release 4.1 2018-04-02
Host name = moode
ETH0 IP = 192.168.1.160
ETH0 MAC = b8:27:eb:61:49:c8
WLAN0 IP = unassigned
WLAN0 MAC = no adapter
WiFi country = SG
HDWR REV = Pi-3B 1GB v1.2
SoC = BCM2835
CORES = 4
ARCH = armv7l
RASPBIAN = 9.4
KERNEL = 4.14.32-v7+
KTIMER FREQ = 100 Hz
USB BOOT = not enabled
Warranty = OK
ROOT size = 7.2G
ROOT used = 30%
ROOT avail = 4.9G
FS expand = expanded
MEM free = 656 MB
MEM used = 56 MB
Temperature = 55.8°C
CPU GOV = performance
MPD SCHDPOL = TS
P3-WIFI = Off
P3-BT = Off
HDMI = Off
ETH0 CHECK = On
MAX USB CUR = Off
UAC2 FIX = On
SSH server = Off
LED0 = on
LED1 = on
C O R E S E R V E R S
PHP-FPM = 7.0.27
NGINX = 1.10.3
SQLite = 3.16.2
Bluetooth = 5.49
C P U L O A D
CPU %usr %sys %idle
all 0.88 0.63 98.49
0 0.00 0.00 100.00
1 1.00 1.00 98.00
2 0.00 1.49 98.51
3 2.50 0.00 97.50
P R O C E S S L O A D
PRI RTPRIO COMMAND %MEM PSR %CPU
19 - sysinfo.sh 0.2 1 3.5
29 - mpd 2.5 3 2.9
19 - systemd 0.6 0 2.0
19 - kworker/1:1 0.0 1 0.8
19 - systemd-journal 0.4 2 0.6
19 - sudo 0.3 3 0.5
19 - systemd-udevd 0.3 0 0.4
19 - php-fpm7.0 1.1 2 0.4
19 - mmcqd/0 0.0 0 0.3
C L O C K F R E Q U E N C I E S
arm = 600 MHz pwm = 0 MHz
core = 250 MHz emmc = 200 MHz
h264 = 250 MHz pixel = 0 MHz
isp = 250 MHz vec = 0 MHz
v3d = 250 MHz hdmi = 0 MHz
uart = 47 MHz dpi = 0 MHz
SD card = 50 MHz
S Y S T E M V O L T A G E S
core = 1.2000V
sdram controller = 1.2000V
sdram I/O = 1.2000V
sdram chip = 1.2250V
U I C U S T O M I Z A T I O N S
Theme = Standard
Accent color = Emerald
Alpha blend = 1.0
Adaptive background = No
Background image = No
Playback history = No
Extra metadata = Yes
Library = Use Artist
A U D I O P A R A M E T E R S
Audio device = USB audio device
Interface = USB
Hdwr volume =
Mixer name = PCM
Output stream = Closed
ALSA version = 1.1.3-5+rpi3
SoX version = 0.1.2-2
Volume knob = 0
Volume mute = Unmuted
Bluetooth controller = Off
Airplay receiver = Off
Squeezelite = Off
UPnP renderer = On
DLNA server = Off
Rotary encoder = Off
Encoder params = 100 2 3 4 5
Crossfeed = Off
Parametric EQ = Off
Graphic EQ = Off
Auto-shuffle = Off
Autoplay = Off
MPD crossfade = Off
M P D S E T T I N G S
Version = 0.20.18
Volume control = disabled
ALSA device = hw:1
SoX resampling = disabled
SoX quality = very high
SoX multithreading = off
Audio buffer (kb) = 4096
Buffer before play = 30%
Output buffer size (kb) = 81920
Volume normalization = no
DSD over PCM (DoP) = no
Replay gain = off
A I R P L A Y S E T T I N G S
Version = 3.1.7
Friendly name = Moode Airplay
ALSA device = hw:1
Volume mixer = auto
Resume MPD after = No
Output bit depth = S16
Output sample rate = 44100
Session interruption = no
Session timeout (ms) = 120
Audio buffer (secs) = 0.0
S Q U E E Z E L I T E S E T T I N G S
Version = 1.8.7-1052 "DSD/SRC enabled"
Friendly name = Moode
ALSA device = hw:0
ALSA params = 80:4::1
Output buffers = 40000:100000
Task priority = 45
Codec list = flac,pcm,mp3,ogg,aac,alac,dsd
Other options = -W -D 500 -R E
M O O D E L O G
20180524 130026 worker: - Start
20180524 130027 worker: Successfully daemonized
20180524 130027 worker: - Init
20180524 130027 worker: Session loaded
20180524 130027 worker: Debug logging (on)
20180524 130028 worker: Host (moode)
20180524 130028 worker: Hdwr (Pi-3B 1GB v1.2)
20180524 130028 worker: Arch (armv7l)
20180524 130028 worker: Rasp (9.4)
20180524 130028 worker: Kver (4.14.32-v7+)
20180524 130028 worker: Ktyp (Standard)
20180524 130028 worker: Gov (performance)
20180524 130028 worker: Rel (Moode 4.1 2018-04-02)
20180524 130028 worker: Upd (None)
20180524 130028 worker: MPD (0.20.18)
20180524 130028 worker: USB boot not enabled yet
20180524 130028 worker: File system expanded
20180524 130028 worker: HDMI port off
20180524 130029 worker: File check ok
20180524 130029 worker: - Network
20180524 130029 worker: eth0 exists
20180524 130029 worker: eth0 (192.168.1.160)
20180524 130029 worker: wlan0 does not exist
20180524 130029 worker: - Audio
20180524 130029 worker: ALSA outputs unmuted
20180524 130029 worker: Audio output (USB audio device)
20180524 130029 worker: ALSA mixer name (PCM)
20180524 130029 worker: MPD volume control (disabled)
20180524 130030 worker: Hdwr volume controller exists
20180524 130030 worker: - Services
20180524 130032 worker: MPD started
20180524 130032 worker: MPD scheduler policy (time-share)
20180524 130032 worker: Configure MPD outputs
20180524 130032 readMpdResponse(): reading response
20180524 130032 readMpdResponse(): success $str=(OK MPD 0.20.0)
20180524 130032 readMpdResponse(): success $resp[0]=(OK MPD 0.20.0)
20180524 130032 readMpdResponse(): reading response
20180524 130032 readMpdResponse(): success $str=(OK)
20180524 130032 readMpdResponse(): success $resp[0]=(outputid: 0)
20180524 130032 worker: MPD output 1 ALSA default (on)
20180524 130032 worker: MPD output 2 ALSA crossfeed (off)
20180524 130032 worker: MPD output 3 ALSA parametric eq (off)
20180524 130032 worker: MPD output 4 ALSA graphic eq (off)
20180524 130032 worker: MPD output 5 ALSA bluetooth (off)
20180524 130032 worker: MPD crossfade (off)
20180524 130032 worker: UPnP renderer started
20180524 130032 worker: - Last
20180524 130032 worker: USB source (NEW VOLUME)
20180524 130032 worker: NAS sources (none configured)
20180524 130032 worker: Volume level (0) restored
20180524 130032 readMpdResponse(): reading response
20180524 130032 readMpdResponse(): success $str=(OK)
20180524 130032 readMpdResponse(): success $resp[0]=(OK)
20180524 130032 worker: Maintenance interval (21600)
20180524 130032 worker: Watchdog started
20180524 130032 worker: Ready
20180524 130032 engine-mpd: Connect
20180524 130032 engine-mpd: Session loaded
20180524 130032 engine-mpd: Open socket
20180524 130032 readMpdResponse(): reading response
20180524 130032 readMpdResponse(): success $str=(OK MPD 0.20.0)
20180524 130032 readMpdResponse(): success $resp[0]=(OK MPD 0.20.0)
20180524 130032 engine-mpd: Get initial status
20180524 130032 readMpdResponse(): reading response
20180524 130032 readMpdResponse(): success $str=(OK)
20180524 130032 readMpdResponse(): success $resp[0]=(volume: -1)
20180524 130032 engine-mpd: UI state=(stop), MPD state=(stop)
20180524 130032 engine-mpd: Idle
20180524 130032 engine-mpd: Wait for idle timeout
20180524 130032 readMpdResponse(): reading response
20180524 130033 engine-mpd: Connect
20180524 130033 engine-mpd: Session loaded
20180524 130033 engine-mpd: Open socket
20180524 130033 readMpdResponse(): reading response
20180524 130033 readMpdResponse(): success $str=(OK MPD 0.20.0)
20180524 130033 readMpdResponse(): success $resp[0]=(OK MPD 0.20.0)
20180524 130033 engine-mpd: Get initial status
20180524 130033 readMpdResponse(): reading response
20180524 130033 readMpdResponse(): success $str=(OK)
20180524 130033 readMpdResponse(): success $resp[0]=(volume: -1)
20180524 130033 engine-mpd: UI state=(stop), MPD state=(stop)
20180524 130033 engine-mpd: Idle
20180524 130033 engine-mpd: Wait for idle timeout
20180524 130033 readMpdResponse(): reading response
20180524 130034 readMpdResponse(): reading response
20180524 130034 readMpdResponse(): success $str=(OK MPD 0.20.0)
20180524 130034 readMpdResponse(): success $resp[0]=(OK MPD 0.20.0)
20180524 130034 readMpdResponse(): reading response
20180524 130034 readMpdResponse(): success $str=(OK)
20180524 130034 readMpdResponse(): success $resp[0]=(OK)
20180524 130034 readMpdResponse(): success $str=(OK)
20180524 130034 readMpdResponse(): success $resp[0]=(changed: player)
20180524 130034 engine-mpd: resp[0]=(changed: player)
20180524 130034 engine-mpd: Get new status
20180524 130034 readMpdResponse(): reading response
20180524 130034 readMpdResponse(): success $str=(OK)
20180524 130034 readMpdResponse(): success $resp[0]=(volume: -1)
20180524 130034 engine-mpd: Idle timeout event=(changed: player)
20180524 130034 engine-mpd: Generating enhanced metadata
20180524 130034 readMpdResponse(): reading response
20180524 130034 readMpdResponse(): success $str=(OK)
20180524 130034 readMpdResponse(): success $str=(OK)
20180524 130034 readMpdResponse(): success $resp[0]=(file: USB/NEW VOLUME/test.flac)
20180524 130034 readMpdResponse(): success $resp[0]=(changed: player)
20180524 130034 engine-mpd: resp[0]=(changed: player)
20180524 130034 avprobe -show_streams "/var/lib/mpd/music/USB/NEW VOLUME/test.flac" 2>&1 | egrep "codec_name|sample_fmt|sample_rate|channels|bits_per_raw_sample"
20180524 130034 engine-mpd: Get new status
20180524 130034 readMpdResponse(): reading response
20180524 130034 readMpdResponse(): success $str=(OK)
20180524 130034 readMpdResponse(): success $resp[0]=(volume: -1)
20180524 130034 engine-mpd: Idle timeout event=(changed: player)
20180524 130034 engine-mpd: Generating enhanced metadata
20180524 130034 readMpdResponse(): reading response
20180524 130034 readMpdResponse(): success $str=(OK)
20180524 130034 readMpdResponse(): success $resp[0]=(file: USB/NEW VOLUME/test.flac)
20180524 130034 avprobe -show_streams "/var/lib/mpd/music/USB/NEW VOLUME/test.flac" 2>&1 | egrep "codec_name|sample_fmt|sample_rate|channels|bits_per_raw_sample"
20180524 130035 enhanceMetadata(): coverurl: (/coverart.php/USB%2FNEW%20VOLUME%2Ftest.flac)
20180524 130035 enhanceMetadata(): Song file
20180524 130035 engine-mpd: Metadata returned to client: Size=(30)
20180524 130035 enhanceMetadata(): coverurl: (/coverart.php/USB%2FNEW%20VOLUME%2Ftest.flac)
20180524 130035 enhanceMetadata(): Song file
20180524 130035 engine-mpd: Metadata returned to client: Size=(30)
20180524 130036 engine-mpd: Connect
20180524 130036 engine-mpd: Session loaded
20180524 130036 engine-mpd: Open socket
20180524 130036 readMpdResponse(): reading response
20180524 130036 readMpdResponse(): success $str=(OK MPD 0.20.0)
20180524 130036 readMpdResponse(): success $resp[0]=(OK MPD 0.20.0)
20180524 130036 engine-mpd: Get initial status
20180524 130036 readMpdResponse(): reading response
20180524 130036 readMpdResponse(): success $str=(OK)
20180524 130036 readMpdResponse(): success $resp[0]=(volume: -1)
20180524 130036 engine-mpd: UI state=(play), MPD state=(play)
20180524 130036 engine-mpd: Idle
20180524 130036 engine-mpd: Wait for idle timeout
20180524 130036 readMpdResponse(): reading response
20180524 130036 engine-mpd: Connect
20180524 130036 engine-mpd: Session loaded
20180524 130036 engine-mpd: Open socket
20180524 130036 readMpdResponse(): reading response
20180524 130036 readMpdResponse(): success $str=(OK MPD 0.20.0)
20180524 130036 readMpdResponse(): success $resp[0]=(OK MPD 0.20.0)
20180524 130036 engine-mpd: Get initial status
20180524 130036 readMpdResponse(): reading response
20180524 130036 readMpdResponse(): success $str=(OK)
20180524 130036 readMpdResponse(): success $resp[0]=(volume: -1)
20180524 130036 engine-mpd: UI state=(play), MPD state=(play)
20180524 130036 engine-mpd: Idle
20180524 130036 engine-mpd: Wait for idle timeout
20180524 130036 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): success $str=(OK MPD 0.20.0)
20180524 130218 readMpdResponse(): success $resp[0]=(OK MPD 0.20.0)
20180524 130218 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): success $str=(OK)
20180524 130218 readMpdResponse(): success $resp[0]=(changed: player)
20180524 130218 readMpdResponse(): success $str=(OK)
20180524 130218 engine-mpd: resp[0]=(changed: player)
20180524 130218 readMpdResponse(): success $resp[0]=(changed: player)
20180524 130218 engine-mpd: Get new status
20180524 130218 engine-mpd: resp[0]=(changed: player)
20180524 130218 engine-mpd: Get new status
20180524 130218 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): success $str=(OK)
20180524 130218 readMpdResponse(): success $resp[0]=(volume: -1)
20180524 130218 readMpdResponse(): success $str=(OK)
20180524 130218 readMpdResponse(): success $resp[0]=(volume: -1)
20180524 130218 engine-mpd: Idle timeout event=(changed: player)
20180524 130218 engine-mpd: Idle timeout event=(changed: player)
20180524 130218 engine-mpd: Generating enhanced metadata
20180524 130218 engine-mpd: Generating enhanced metadata
20180524 130218 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): success $str=(OK)
20180524 130218 readMpdResponse(): success $str=(OK)
20180524 130218 readMpdResponse(): success $resp[0]=(file: USB/NEW VOLUME/test.flac)
20180524 130218 readMpdResponse(): success $resp[0]=(file: USB/NEW VOLUME/test.flac)
20180524 130218 enhanceMetadata(): coverurl: (/coverart.php/USB%2FNEW%20VOLUME%2Ftest.flac)
20180524 130218 enhanceMetadata(): coverurl: (/coverart.php/USB%2FNEW%20VOLUME%2Ftest.flac)
20180524 130218 enhanceMetadata(): Song file
20180524 130218 enhanceMetadata(): Song file
20180524 130218 engine-mpd: Metadata returned to client: Size=(30)
20180524 130218 engine-mpd: Metadata returned to client: Size=(30)
20180524 130218 readMpdResponse(): success $str=(OK)
20180524 130218 readMpdResponse(): success $resp[0]=(OK)
20180524 130218 engine-mpd: Connect
20180524 130218 engine-mpd: Session loaded
20180524 130218 engine-mpd: Open socket
20180524 130218 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): success $str=(OK MPD 0.20.0)
20180524 130218 readMpdResponse(): success $resp[0]=(OK MPD 0.20.0)
20180524 130218 engine-mpd: Get initial status
20180524 130218 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): success $str=(OK)
20180524 130218 readMpdResponse(): success $resp[0]=(volume: -1)
20180524 130218 engine-mpd: UI state=(pause), MPD state=(pause)
20180524 130218 engine-mpd: Idle
20180524 130218 engine-mpd: Wait for idle timeout
20180524 130218 readMpdResponse(): reading response
20180524 130218 engine-mpd: Connect
20180524 130218 engine-mpd: Session loaded
20180524 130218 engine-mpd: Open socket
20180524 130218 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): success $str=(OK MPD 0.20.0)
20180524 130218 readMpdResponse(): success $resp[0]=(OK MPD 0.20.0)
20180524 130218 engine-mpd: Get initial status
20180524 130218 readMpdResponse(): reading response
20180524 130218 readMpdResponse(): success $str=(OK)
20180524 130218 readMpdResponse(): success $resp[0]=(volume: -1)
20180524 130218 engine-mpd: UI state=(pause), MPD state=(pause)
20180524 130218 engine-mpd: Idle
20180524 130218 engine-mpd: Wait for idle timeout
20180524 130218 readMpdResponse(): reading response
2) tail -f /var/log/mpd/log
Code:
May 24 12:32 : update: added USB/NEW VOLUME/test.flac
May 24 12:35 : player: played "USB/NEW VOLUME/test.flac"
May 24 12:41 : player: played "USB/NEW VOLUME/test.flac"
May 24 12:51 : player: played "USB/NEW VOLUME/test.flac"
May 24 13:00 : player: played "USB/NEW VOLUME/test.flac"
Thanks for your help