-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Thu 2025-04-24 13:10:02 CST. -- Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 24 13:09:01 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 24 13:09:04 primo ntpd[14936]: ntpd exiting on signal 15 (Terminated) Apr 24 13:09:04 primo ntpd[14936]: 193.182.111.142 local addr 192.168.88.221 -> Apr 24 13:09:04 primo ntpd[14936]: 162.159.200.123 local addr 192.168.88.221 -> Apr 24 13:09:04 primo systemd[1]: Stopping Network Time Service... Apr 24 13:09:04 primo ntpd[14936]: 193.182.111.143 local addr 192.168.88.221 -> Apr 24 13:09:04 primo ntpd[14936]: 202.118.1.81 local addr 192.168.88.221 -> Apr 24 13:09:04 primo ntpd[14936]: 193.182.111.12 local addr 192.168.88.221 -> Apr 24 13:09:04 primo ntpd[14936]: 202.118.1.130 local addr 192.168.88.221 -> Apr 24 13:09:04 primo ntpd[14936]: 162.159.200.1 local addr 192.168.88.221 -> Apr 24 13:09:04 primo ntpd[14936]: 116.203.151.74 local addr 192.168.88.221 -> Apr 24 13:09:04 primo ntpd[14936]: 108.59.2.24 local addr 192.168.88.221 -> Apr 24 13:09:04 primo systemd[1]: ntp.service: Succeeded. Apr 24 13:09:04 primo systemd[1]: Stopped Network Time Service. Apr 24 13:09:04 primo systemd[1]: Starting Network Time Service... Apr 24 13:09:04 primo ntpd[18105]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 24 13:09:04 primo ntpd[18105]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 24 13:09:04 primo systemd[1]: Started Network Time Service. Apr 24 13:09:04 primo ntpd[18111]: proto: precision = 0.583 usec (-21) Apr 24 13:09:04 primo ntpd[18111]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 24 13:09:04 primo ntpd[18111]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 24 13:09:04 primo ntpd[18111]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 849 days ago Apr 24 13:09:04 primo ntpd[18111]: Listen and drop on 0 v6wildcard [::]:123 Apr 24 13:09:04 primo ntpd[18111]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 24 13:09:04 primo ntpd[18111]: Listen normally on 2 lo 127.0.0.1:123 Apr 24 13:09:04 primo ntpd[18111]: Listen normally on 3 eth0 192.168.88.221:123 Apr 24 13:09:04 primo ntpd[18111]: Listen normally on 4 wlan0 192.168.211.1:123 Apr 24 13:09:04 primo ntpd[18111]: Listen normally on 5 wlan0 169.254.245.244:123 Apr 24 13:09:04 primo ntpd[18111]: Listening on routing socket on fd #22 for interface updates Apr 24 13:09:04 primo ntpd[18111]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 24 13:09:04 primo ntpd[18111]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 24 13:09:05 primo volumio[17514]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 24 13:09:05 primo volumio[17514]: info: Preparing to save Alsa Options, stopping services first Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::volumioGetState Apr 24 13:09:05 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::volumioPause Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::pause Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::stPlaybackTimer Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::servicePause Apr 24 13:09:05 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::servicePause Apr 24 13:09:05 primo volumio[17514]: info: ControllerMpd::pause Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand pause Apr 24 13:09:05 primo volumio[17514]: info: Saving Audio Output to: {"output_device":{"value":"1,2","label":"Audio Jack Out"}} Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:05 primo volumio[17514]: info: Found match in Cards Database: setting mixer Headphone,1 for card Audio Jack Out Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 24 13:09:05 primo volumio[17514]: info: Updating Volume Controller Parameters: Device: 1,2 Name: Audio Jack Out Mixer: Headphone,1 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 24 13:09:05 primo volumio[17514]: info: Disabling external Volume Control Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 13:09:05 primo volumio[17514]: info: Preparing to generate the ALSA configuration file Apr 24 13:09:05 primo volumio[17514]: info: Apr 24 13:09:05 primo volumio[17514]: ---------------------------- MPD announces state update: player Apr 24 13:09:05 primo volumio[17514]: info: sendMpdCommand pause took 139 milliseconds Apr 24 13:09:05 primo volumio[17514]: info: ControllerMpd::getState Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand status Apr 24 13:09:05 primo volumio[17514]: info: Apr 24 13:09:05 primo volumio[17514]: ---------------------------- MPD announces state update: player Apr 24 13:09:05 primo volumio[17514]: info: ControllerMpd::getState Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand status Apr 24 13:09:05 primo volumio[17514]: info: Apr 24 13:09:05 primo volumio[17514]: ---------------------------- MPD announces state update: player Apr 24 13:09:05 primo volumio[17514]: info: ControllerMpd::getState Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand status Apr 24 13:09:05 primo volumio[17514]: info: sendMpdCommand status took 17 milliseconds Apr 24 13:09:05 primo volumio[17514]: info: sendMpdCommand status took 16 milliseconds Apr 24 13:09:05 primo volumio[17514]: info: sendMpdCommand status took 14 milliseconds Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::parseState Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::parseState Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::parseState Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 13:09:05 primo volumio[17514]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 24 13:09:05 primo volumio[17514]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 24 13:09:05 primo volumio[17514]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::parseTrackInfo Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::parseTrackInfo Apr 24 13:09:05 primo volumio[17514]: verbose: ControllerMpd::parseTrackInfo Apr 24 13:09:05 primo volumio[17514]: info: ControllerMpd::pushState Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::servicePushState Apr 24 13:09:05 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:05 primo volumio[17514]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22114,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"MEMORY","artist":"BENI","album":"MTV UNplugged","uri":"NAS/music/BENI - MTV UNplugged/02. MEMORY.mp3","trackType":"mp3"} Apr 24 13:09:05 primo volumio[17514]: verbose: CURRENT POSITION 1 Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::syncState stateService pause Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::syncState currentStatus pause Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:05 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::stPlaybackTimer Apr 24 13:09:05 primo volumio[17514]: info: ControllerMpd::pushState Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::servicePushState Apr 24 13:09:05 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:05 primo volumio[17514]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22114,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"MEMORY","artist":"BENI","album":"MTV UNplugged","uri":"NAS/music/BENI - MTV UNplugged/02. MEMORY.mp3","trackType":"mp3"} Apr 24 13:09:05 primo volumio[17514]: verbose: CURRENT POSITION 1 Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::syncState stateService pause Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::syncState currentStatus pause Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:05 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::stPlaybackTimer Apr 24 13:09:05 primo volumio[17514]: info: ControllerMpd::pushState Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::servicePushState Apr 24 13:09:05 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:05 primo volumio[17514]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22114,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"MEMORY","artist":"BENI","album":"MTV UNplugged","uri":"NAS/music/BENI - MTV UNplugged/02. MEMORY.mp3","trackType":"mp3"} Apr 24 13:09:05 primo volumio[17514]: verbose: CURRENT POSITION 1 Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::syncState stateService pause Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::syncState currentStatus pause Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:05 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::stPlaybackTimer Apr 24 13:09:05 primo volumio[17514]: info: ------------------------------ 62ms Apr 24 13:09:05 primo volumio[17514]: info: ------------------------------ 61ms Apr 24 13:09:05 primo volumio[17514]: info: ------------------------------ 61ms Apr 24 13:09:05 primo ntpd[18111]: Soliciting pool server 193.182.111.143 Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 24 13:09:05 primo volumio[17514]: info: VolumeController:: Volume=41 Mute =false Apr 24 13:09:05 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:05 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:05 primo volumio[17514]: info: Asound.conf file written Apr 24 13:09:05 primo sudo[18126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 24 13:09:05 primo sudo[18126]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:05 primo sudo[18126]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:05 primo volumio[17514]: No state is present for card IF Apr 24 13:09:05 primo volumio[17514]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8630" "" "" Apr 24 13:09:05 primo volumio[17514]: Hardware is initialized using a generic method Apr 24 13:09:05 primo volumio[17514]: No state is present for card IF Apr 24 13:09:05 primo volumio[17514]: info: Output device has changed, restarting MPD Apr 24 13:09:05 primo volumio[17514]: info: Output device has changed, restarting Shairport Sync Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:05 primo sudo[18138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 13:09:05 primo sudo[18138]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:05 primo sudo[18141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 13:09:05 primo sudo[18138]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:05 primo sudo[18141]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:05 primo volumio[17514]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 24 13:09:05 primo volumio[17514]: info: Output device has changed, restarting MPD Apr 24 13:09:05 primo systemd[1]: Stopping Music Player Daemon... Apr 24 13:09:05 primo volumio[17514]: info: Output device has changed, restarting Shairport Sync Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:05 primo sudo[18158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 13:09:05 primo sudo[18158]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:05 primo sudo[18161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 13:09:05 primo sudo[18158]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:05 primo sudo[18161]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:05 primo volumio[17514]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 24 13:09:05 primo volumio[17514]: info: MPD Permissions set Apr 24 13:09:05 primo systemd[1]: mpd.service: Succeeded. Apr 24 13:09:05 primo systemd[1]: Stopped Music Player Daemon. Apr 24 13:09:05 primo volumio[17514]: info: MPD Permissions set Apr 24 13:09:05 primo systemd[1]: Starting Music Player Daemon... Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo systemd[1]: mpd.service: Succeeded. Apr 24 13:09:05 primo systemd[1]: Stopped Music Player Daemon. Apr 24 13:09:05 primo systemd[1]: Starting Music Player Daemon... Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 13:09:05 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:05 primo volumio[17514]: info: Starting Shairport Sync Apr 24 13:09:05 primo sudo[18178]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 24 13:09:05 primo volumio[17514]: info: Starting Shairport Sync Apr 24 13:09:05 primo sudo[18178]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:05 primo sudo[18194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 13:09:05 primo sudo[18178]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:05 primo sudo[18194]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:05 primo sudo[18198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 13:09:05 primo sudo[18198]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:05 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 13:09:05 primo systemd[1]: shairport-sync.service: Succeeded. Apr 24 13:09:05 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 13:09:05 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 13:09:05 primo sudo[18194]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:05 primo volumio[17514]: info: Shairport-Sync Started Apr 24 13:09:05 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 13:09:05 primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 24 13:09:05 primo systemd[1]: shairport-sync.service: Succeeded. Apr 24 13:09:05 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 13:09:05 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 13:09:05 primo sudo[18198]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:06 primo volumio[17514]: info: Shairport-Sync Started Apr 24 13:09:06 primo ntpd[18111]: ntpd exiting on signal 15 (Terminated) Apr 24 13:09:06 primo ntpd[18111]: 193.182.111.143 local addr 192.168.88.221 -> Apr 24 13:09:06 primo systemd[1]: Stopping Network Time Service... Apr 24 13:09:06 primo systemd[1]: ntp.service: Succeeded. Apr 24 13:09:06 primo systemd[1]: Stopped Network Time Service. Apr 24 13:09:06 primo systemd[1]: Starting Network Time Service... Apr 24 13:09:06 primo ntpd[18256]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 24 13:09:06 primo ntpd[18256]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 24 13:09:06 primo systemd[1]: Started Network Time Service. Apr 24 13:09:06 primo ntpd[18262]: proto: precision = 0.875 usec (-20) Apr 24 13:09:06 primo ntpd[18262]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 24 13:09:06 primo ntpd[18262]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 24 13:09:06 primo ntpd[18262]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 849 days ago Apr 24 13:09:06 primo ntpd[18262]: Listen and drop on 0 v6wildcard [::]:123 Apr 24 13:09:06 primo ntpd[18262]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 24 13:09:06 primo ntpd[18262]: Listen normally on 2 lo 127.0.0.1:123 Apr 24 13:09:06 primo ntpd[18262]: Listen normally on 3 eth0 192.168.88.221:123 Apr 24 13:09:06 primo ntpd[18262]: Listen normally on 4 wlan0 192.168.211.1:123 Apr 24 13:09:06 primo ntpd[18262]: Listen normally on 5 wlan0 169.254.245.244:123 Apr 24 13:09:06 primo ntpd[18262]: Listening on routing socket on fd #22 for interface updates Apr 24 13:09:06 primo ntpd[18262]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 24 13:09:06 primo ntpd[18262]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 24 13:09:06 primo mpd[18236]: Apr 24 13:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 24 13:09:06 primo systemd[1]: Started Music Player Daemon. Apr 24 13:09:06 primo sudo[18141]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:06 primo sudo[18161]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:06 primo volumio[17514]: error: MPD error: The expression evaluated to a falsy value: Apr 24 13:09:06 primo volumio[17514]: assert.ok(self.idling) Apr 24 13:09:06 primo volumio[17514]: error: The expression evaluated to a falsy value: Apr 24 13:09:06 primo volumio[17514]: assert.ok(self.idling) Apr 24 13:09:06 primo volumio[17514]: error: updateQueue error: null Apr 24 13:09:07 primo ntpd[18262]: Soliciting pool server 193.182.111.143 Apr 24 13:09:08 primo ntpd[18262]: Soliciting pool server 202.118.1.81 Apr 24 13:09:08 primo ntpd[18262]: Soliciting pool server 193.182.111.14 Apr 24 13:09:08 primo volumio[17514]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 24 13:09:08 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 24 13:09:08 primo volumio[17514]: info: Preparing to save Alsa Options, stopping services first Apr 24 13:09:08 primo volumio[17514]: info: CoreCommandRouter::volumioGetState Apr 24 13:09:08 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:08 primo volumio[17514]: info: CoreCommandRouter::volumioPause Apr 24 13:09:08 primo volumio[17514]: info: CoreStateMachine::pause Apr 24 13:09:08 primo volumio[17514]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"VIO USB 2.0 IF"}} Apr 24 13:09:08 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:09 primo volumio[17514]: info: Setting mixer VIO USB 2.0 IF for card VIO USB 2.0 IF Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 24 13:09:09 primo volumio[17514]: info: Updating Volume Controller Parameters: Device: 5 Name: VIO USB 2.0 IF Mixer: VIO USB 2.0 IF Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 24 13:09:09 primo volumio[17514]: info: Disabling external Volume Control Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 13:09:09 primo volumio[17514]: info: Preparing to generate the ALSA configuration file Apr 24 13:09:09 primo ntpd[18262]: Soliciting pool server 193.182.111.12 Apr 24 13:09:09 primo ntpd[18262]: Soliciting pool server 202.118.1.130 Apr 24 13:09:09 primo ntpd[18262]: Soliciting pool server 193.182.111.141 Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 24 13:09:09 primo volumio[17514]: info: Asound.conf file written Apr 24 13:09:09 primo sudo[18288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 24 13:09:09 primo sudo[18288]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:09 primo sudo[18288]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:09 primo volumio[17514]: No state is present for card IF Apr 24 13:09:09 primo volumio[17514]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8630" "" "" Apr 24 13:09:09 primo volumio[17514]: Hardware is initialized using a generic method Apr 24 13:09:09 primo volumio[17514]: No state is present for card IF Apr 24 13:09:09 primo volumio[17514]: info: Output device has changed, restarting MPD Apr 24 13:09:09 primo volumio[17514]: info: Output device has changed, restarting Shairport Sync Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:09 primo sudo[18300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 13:09:09 primo sudo[18300]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:09 primo sudo[18302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 13:09:09 primo sudo[18300]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:09 primo sudo[18302]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:09 primo volumio[17514]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 24 13:09:09 primo volumio[17514]: info: Output device has changed, restarting MPD Apr 24 13:09:09 primo systemd[1]: Stopping Music Player Daemon... Apr 24 13:09:09 primo volumio[17514]: info: Output device has changed, restarting Shairport Sync Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:09 primo sudo[18320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 13:09:09 primo sudo[18322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 13:09:09 primo sudo[18320]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:09 primo sudo[18322]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:09 primo sudo[18320]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:09 primo volumio[17514]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 24 13:09:09 primo volumio[17514]: info: CoreStateMachine::getcurrentVolume Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 13:09:09 primo volumio[17514]: info: MPD Permissions set Apr 24 13:09:09 primo volumio[17514]: info: VolumeController:: Volume=0 Mute =false Apr 24 13:09:09 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:09 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:09 primo volumio[17514]: info: MPD Permissions set Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo systemd[1]: mpd.service: Succeeded. Apr 24 13:09:09 primo systemd[1]: Stopped Music Player Daemon. Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo systemd[1]: Starting Music Player Daemon... Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:09 primo sudo[18340]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 24 13:09:09 primo volumio[17514]: info: Starting Shairport Sync Apr 24 13:09:09 primo sudo[18340]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:09 primo volumio[17514]: info: Starting Shairport Sync Apr 24 13:09:09 primo sudo[18340]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:09 primo volumio[17514]: info: VolumeController:: Volume=0 Mute =false Apr 24 13:09:09 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:09 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:09 primo sudo[18361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 13:09:09 primo volumio[17514]: info: CoreStateMachine::updateTrackBlock Apr 24 13:09:09 primo volumio[17514]: info: CorePlayQueue::getTrackBlock Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 13:09:09 primo sudo[18366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 13:09:09 primo sudo[18361]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:09 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 13:09:09 primo systemd[1]: shairport-sync.service: Succeeded. Apr 24 13:09:09 primo sudo[18366]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:09 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 13:09:09 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 13:09:09 primo sudo[18361]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:09 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 13:09:09 primo volumio[17514]: info: VolumeController:: Volume=0 Mute =false Apr 24 13:09:09 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:09 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:09 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:09 primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 24 13:09:09 primo systemd[1]: shairport-sync.service: Succeeded. Apr 24 13:09:09 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 13:09:09 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 13:09:09 primo sudo[18366]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:09 primo volumio[17514]: info: Shairport-Sync Started Apr 24 13:09:09 primo volumio[17514]: info: Shairport-Sync Started Apr 24 13:09:10 primo ntpd[18262]: Soliciting pool server 162.159.200.123 Apr 24 13:09:10 primo ntpd[18262]: Soliciting pool server 101.43.66.2 Apr 24 13:09:10 primo ntpd[18262]: Soliciting pool server 193.182.111.142 Apr 24 13:09:10 primo mpd[18380]: Apr 24 13:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 24 13:09:10 primo systemd[1]: Started Music Player Daemon. Apr 24 13:09:10 primo sudo[18322]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:10 primo sudo[18302]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:10 primo volumio[17514]: error: MPD error: The expression evaluated to a falsy value: Apr 24 13:09:10 primo volumio[17514]: assert.ok(self.idling) Apr 24 13:09:10 primo volumio[17514]: error: The expression evaluated to a falsy value: Apr 24 13:09:10 primo volumio[17514]: assert.ok(self.idling) Apr 24 13:09:10 primo volumio[17514]: error: updateQueue error: null Apr 24 13:09:11 primo ntpd[18262]: Soliciting pool server 84.16.67.12 Apr 24 13:09:11 primo ntpd[18262]: Soliciting pool server 45.76.221.157 Apr 24 13:09:12 primo ntpd[18262]: Soliciting pool server 108.59.2.24 Apr 24 13:09:12 primo ntpd[18262]: Soliciting pool server 2606:4700:f1::1 Apr 24 13:09:13 primo volumio[17514]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 24 13:09:13 primo volumio[17514]: info: CoreStateMachine::getcurrentVolume Apr 24 13:09:13 primo volumio[17514]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 13:09:13 primo volumio[17514]: info: VolumeController:: Volume=0 Mute =false Apr 24 13:09:13 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:13 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:13 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:13 primo volumio[17514]: info: CoreStateMachine::updateTrackBlock Apr 24 13:09:13 primo volumio[17514]: info: CorePlayQueue::getTrackBlock Apr 24 13:09:13 primo volumio[17514]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 13:09:13 primo volumio[17514]: info: VolumeController:: Volume=0 Mute =false Apr 24 13:09:13 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:13 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:13 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:14 primo volumio[17514]: info: CoreCommandRouter::volumioPlay Apr 24 13:09:14 primo volumio[17514]: info: CoreStateMachine::play index undefined Apr 24 13:09:14 primo volumio[17514]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 13:09:14 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:14 primo volumio[17514]: info: CoreStateMachine::startPlaybackTimer Apr 24 13:09:14 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:14 primo volumio[17514]: info: ControllerMpd::resume Apr 24 13:09:14 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand play Apr 24 13:09:14 primo volumio[17514]: info: sendMpdCommand play took 1 milliseconds Apr 24 13:09:23 primo volumio[17514]: info: CoreCommandRouter::volumioGetState Apr 24 13:09:23 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:27 primo kernel: usb 1-1.4: USB disconnect, device number 4 Apr 24 13:09:27 primo volumio[17514]: info: Apr 24 13:09:27 primo volumio[17514]: ---------------------------- USB Audio Device Detached Apr 24 13:09:27 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 24 13:09:27 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:27 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 24 13:09:30 primo kernel: usb 1-1: reset high-speed USB device number 3 using dwc2 Apr 24 13:09:30 primo kernel: usb 1-1: USB disconnect, device number 3 Apr 24 13:09:30 primo kernel: usb 1-1: new high-speed USB device number 5 using dwc2 Apr 24 13:09:31 primo kernel: usb 1-1: New USB device found, idVendor=05e3, idProduct=0610 Apr 24 13:09:31 primo kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Apr 24 13:09:31 primo kernel: usb 1-1: Product: USB2.0 Hub Apr 24 13:09:31 primo kernel: hub 1-1:1.0: USB hub found Apr 24 13:09:31 primo kernel: hub 1-1:1.0: 4 ports detected Apr 24 13:09:31 primo kernel: usb 1-1.2: new high-speed USB device number 6 using dwc2 Apr 24 13:09:31 primo kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8630 Apr 24 13:09:31 primo kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 24 13:09:31 primo kernel: usb 1-1.2: Product: VIO USB 2.0 IF Apr 24 13:09:31 primo kernel: usb 1-1.2: Manufacturer: VIO Apr 24 13:09:31 primo kernel: input: VIO VIO USB 2.0 IF as /devices/platform/ff540000.usb/usb1/1-1/1-1.2/1-1.2:1.3/0003:152A:8630.0003/input/input5 Apr 24 13:09:31 primo kernel: hid-generic 0003:152A:8630.0003: input,hidraw1: USB HID v1.10 Device [VIO VIO USB 2.0 IF] on usb-ff540000.usb-1.2/input3 Apr 24 13:09:31 primo systemd-udevd[18435]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Apr 24 13:09:31 primo volumio[17514]: info: Apr 24 13:09:31 primo volumio[17514]: ---------------------------- USB Audio Device Attached Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::Close All Modals sent Apr 24 13:09:31 primo volumio[17514]: info: Preparing to save Alsa Options, stopping services first Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::volumioGetState Apr 24 13:09:31 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::volumioPause Apr 24 13:09:31 primo volumio[17514]: info: CoreStateMachine::pause Apr 24 13:09:31 primo volumio[17514]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"VIO USB 2.0 IF","alsacard":"IF"},"i2s":false} Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:31 primo volumio[17514]: info: Setting mixer VIO USB 2.0 IF for card VIO USB 2.0 IF Apr 24 13:09:31 primo systemd-udevd[18436]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 24 13:09:31 primo volumio[17514]: info: Updating Volume Controller Parameters: Device: 5 Name: VIO USB 2.0 IF Mixer: VIO USB 2.0 IF Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 24 13:09:31 primo volumio[17514]: info: Disabling external Volume Control Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 13:09:31 primo volumio[17514]: info: Preparing to generate the ALSA configuration file Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 24 13:09:31 primo volumio[17514]: info: Asound.conf file unchanged, so no further update is needed Apr 24 13:09:31 primo volumio[17514]: info: Output device has changed, restarting MPD Apr 24 13:09:31 primo volumio[17514]: info: Output device has changed, restarting Shairport Sync Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:31 primo sudo[18466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 13:09:31 primo sudo[18468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 13:09:31 primo sudo[18466]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:31 primo sudo[18466]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:31 primo sudo[18468]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:31 primo volumio[17514]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 24 13:09:31 primo systemd[1]: Stopping Music Player Daemon... Apr 24 13:09:31 primo volumio[17514]: info: MPD Permissions set Apr 24 13:09:31 primo volumio[17514]: info: VolumeController:: Volume=100 Mute =false Apr 24 13:09:31 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:31 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 13:09:31 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:09:31 primo volumio[17514]: info: Starting Shairport Sync Apr 24 13:09:31 primo systemd[1]: mpd.service: Succeeded. Apr 24 13:09:31 primo systemd[1]: Stopped Music Player Daemon. Apr 24 13:09:31 primo systemd[1]: Starting Music Player Daemon... Apr 24 13:09:32 primo sudo[18493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 13:09:32 primo sudo[18493]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:32 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 13:09:32 primo systemd[1]: shairport-sync.service: Succeeded. Apr 24 13:09:32 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 13:09:32 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 13:09:32 primo sudo[18493]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:32 primo sudo[18491]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 24 13:09:32 primo volumio[17514]: info: Shairport-Sync Started Apr 24 13:09:32 primo sudo[18491]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 13:09:32 primo sudo[18491]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:32 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 24 13:09:32 primo mpd[18513]: Apr 24 13:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 24 13:09:32 primo systemd[1]: Started Music Player Daemon. Apr 24 13:09:32 primo sudo[18468]: pam_unix(sudo:session): session closed for user root Apr 24 13:09:33 primo volumio[17514]: error: updateQueue error: null Apr 24 13:09:35 primo volumio[17514]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 24 13:09:35 primo volumio[17514]: info: CoreStateMachine::getcurrentVolume Apr 24 13:09:35 primo volumio[17514]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 13:09:35 primo volumio[17514]: info: VolumeController:: Volume=100 Mute =false Apr 24 13:09:35 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:35 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:35 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 13:09:35 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:35 primo volumio[17514]: info: CoreStateMachine::updateTrackBlock Apr 24 13:09:35 primo volumio[17514]: info: CorePlayQueue::getTrackBlock Apr 24 13:09:35 primo volumio[17514]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 13:09:35 primo volumio[17514]: info: VolumeController:: Volume=100 Mute =false Apr 24 13:09:35 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:35 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:35 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:37 primo volumio[17514]: info: CoreCommandRouter::volumioPlay Apr 24 13:09:37 primo volumio[17514]: info: CoreStateMachine::play index undefined Apr 24 13:09:37 primo volumio[17514]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 13:09:37 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:37 primo volumio[17514]: info: CoreStateMachine::startPlaybackTimer Apr 24 13:09:37 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:37 primo volumio[17514]: info: ControllerMpd::resume Apr 24 13:09:37 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand play Apr 24 13:09:37 primo volumio[17514]: info: sendMpdCommand play took 1 milliseconds Apr 24 13:09:40 primo volumio[17514]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BENI/MTV%20UNplugged/83922082-da3c-48b9-921e-d9f8402f7ded.jpg' Apr 24 13:09:41 primo volumio[17514]: info: CoreCommandRouter::volumioPlay Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::play index 9 Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::stop Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::updateTrackBlock Apr 24 13:09:41 primo volumio[17514]: info: CorePlayQueue::getTrackBlock Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::stPlaybackTimer Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:41 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:41 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 13:09:41 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::serviceStop Apr 24 13:09:41 primo volumio[17514]: info: CorePlayQueue::getTrack 1 Apr 24 13:09:41 primo volumio[17514]: info: CoreCommandRouter::serviceStop Apr 24 13:09:41 primo volumio[17514]: info: ControllerMpd::stop Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 13:09:41 primo volumio[17514]: info: sendMpdCommand stop took 5 milliseconds Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::play index undefined Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 13:09:41 primo volumio[17514]: info: CorePlayQueue::getTrack 9 Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::startPlaybackTimer Apr 24 13:09:41 primo volumio[17514]: info: CorePlayQueue::getTrack 9 Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/BENI - MTV UNplugged/10. KIRA KIRA.mp3 Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 13:09:41 primo volumio[17514]: info: sendMpdCommand stop took 1 milliseconds Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 13:09:41 primo volumio[17514]: info: Apr 24 13:09:41 primo volumio[17514]: ---------------------------- MPD announces system playlist update Apr 24 13:09:41 primo volumio[17514]: info: Ignoring MPD Status Update Apr 24 13:09:41 primo volumio[17514]: info: sendMpdCommand clear took 3 milliseconds Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/BENI - MTV UNplugged/10. KIRA KIRA.mp3" Apr 24 13:09:41 primo volumio[17514]: error: updateQueue error: null Apr 24 13:09:41 primo volumio[17514]: info: Apr 24 13:09:41 primo volumio[17514]: ---------------------------- MPD announces system playlist update Apr 24 13:09:41 primo volumio[17514]: info: Ignoring MPD Status Update Apr 24 13:09:41 primo volumio[17514]: info: ------------------------------ 2ms Apr 24 13:09:41 primo volumio[17514]: info: sendMpdCommand add "NAS/music/BENI - MTV UNplugged/10. KIRA KIRA.mp3" took 2 milliseconds Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand play Apr 24 13:09:41 primo volumio[17514]: info: ------------------------------ 2ms Apr 24 13:09:41 primo volumio[17514]: info: sendMpdCommand play took 1 milliseconds Apr 24 13:09:41 primo volumio[17514]: info: Apr 24 13:09:41 primo volumio[17514]: ---------------------------- MPD announces state update: player Apr 24 13:09:41 primo volumio[17514]: info: ControllerMpd::getState Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand status Apr 24 13:09:41 primo volumio[17514]: info: Apr 24 13:09:41 primo volumio[17514]: ---------------------------- MPD announces state update: player Apr 24 13:09:41 primo volumio[17514]: info: sendMpdCommand status took 32 milliseconds Apr 24 13:09:41 primo volumio[17514]: info: ControllerMpd::getState Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand status Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::parseState Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 13:09:41 primo volumio[17514]: info: sendMpdCommand status took 1 milliseconds Apr 24 13:09:41 primo volumio[17514]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::parseState Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::parseTrackInfo Apr 24 13:09:41 primo volumio[17514]: info: ControllerMpd::pushState Apr 24 13:09:41 primo volumio[17514]: info: CoreCommandRouter::servicePushState Apr 24 13:09:41 primo volumio[17514]: info: CorePlayQueue::getTrack 9 Apr 24 13:09:41 primo volumio[17514]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"KIRA KIRA","artist":"BENI","album":"MTV UNplugged","uri":"NAS/music/BENI - MTV UNplugged/10. KIRA KIRA.mp3","trackType":"mp3"} Apr 24 13:09:41 primo volumio[17514]: verbose: CURRENT POSITION 9 Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::syncState stateService play Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::syncState currentStatus stop Apr 24 13:09:41 primo volumio[17514]: info: ------------------------------ 39ms Apr 24 13:09:41 primo volumio[17514]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 24 13:09:41 primo volumio[17514]: verbose: ControllerMpd::parseTrackInfo Apr 24 13:09:41 primo volumio[17514]: info: ControllerMpd::pushState Apr 24 13:09:41 primo volumio[17514]: info: CoreCommandRouter::servicePushState Apr 24 13:09:41 primo volumio[17514]: info: CorePlayQueue::getTrack 9 Apr 24 13:09:41 primo volumio[17514]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"KIRA KIRA","artist":"BENI","album":"MTV UNplugged","uri":"NAS/music/BENI - MTV UNplugged/10. KIRA KIRA.mp3","trackType":"mp3"} Apr 24 13:09:41 primo volumio[17514]: verbose: CURRENT POSITION 9 Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::syncState stateService play Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::syncState currentStatus play Apr 24 13:09:41 primo volumio[17514]: info: Received an update from plugin. extracting info from payload Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:41 primo volumio[17514]: info: CorePlayQueue::getTrack 9 Apr 24 13:09:41 primo volumio[17514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 13:09:41 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:41 primo volumio[17514]: info: CoreStateMachine::pushState Apr 24 13:09:41 primo volumio[17514]: info: CorePlayQueue::getTrack 9 Apr 24 13:09:41 primo volumio[17514]: info: CoreCommandRouter::volumioPushState Apr 24 13:09:41 primo volumio[17514]: info: ------------------------------ 36ms Apr 24 13:10:02 primo volumio[17514]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 13:10:02 primo volumio[17514]: Error: connect ETIMEDOUT 69.171.229.73:80 Apr 24 13:10:02 primo volumio[17514]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 24 13:10:02 primo volumio[17514]: errno: -110, Apr 24 13:10:02 primo volumio[17514]: code: 'ETIMEDOUT', Apr 24 13:10:02 primo volumio[17514]: syscall: 'connect', Apr 24 13:10:02 primo volumio[17514]: address: '69.171.229.73', Apr 24 13:10:02 primo volumio[17514]: port: 80 Apr 24 13:10:02 primo volumio[17514]: } Apr 24 13:10:02 primo volumio[17514]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 13:10:02 primo sudo[18626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 13:09 Apr 24 13:10:02 primo sudo[18626]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:14:40 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="a9bbde8819c9c7e405795a261e2a2f93"