-- Logs begin at Sat 2025-07-19 13:37:27 +04, end at Sat 2025-07-19 13:53:27 +04. -- Jul 19 13:52:05 sunset CRON[5823]: pam_unix(cron:session): session opened for user root by (uid=0) Jul 19 13:52:05 sunset CRON[5839]: (root) CMD (/root/sycfm/bin/mp-sw.sh 1) Jul 19 13:52:06 sunset volumio[5375]: info: CoreCommandRouter::volumioGetState Jul 19 13:52:06 sunset volumio[5375]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:06 sunset sudo[5852]: root : TTY=unknown ; PWD=/root ; USER=root ; COMMAND=/usr/bin/killall -9 mpd Jul 19 13:52:06 sunset sudo[5852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:06 sunset sudo[5852]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:06 sunset systemd[1]: mpd.service: Main process exited, code=killed, status=9/KILL Jul 19 13:52:06 sunset systemd[1]: mpd.service: Failed with result 'signal'. Jul 19 13:52:06 sunset sudo[5854]: root : TTY=unknown ; PWD=/root ; USER=root ; COMMAND=/bin/systemctl restart mpd Jul 19 13:52:06 sunset sudo[5854]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:06 sunset systemd[1]: Starting Music Player Daemon... Jul 19 13:52:06 sunset sudo[5856]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 13:52:06 sunset sudo[5856]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:06 sunset sudo[5856]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:06 sunset volumio[5375]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 19 13:52:06 sunset volumio[5375]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 19 13:52:06 sunset volumio[5375]: info: CoreCommandRouter::volumioGetState Jul 19 13:52:06 sunset volumio[5375]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:06 sunset volumio[5375]: info: CoreCommandRouter::volumioGetVisibleSources Jul 19 13:52:06 sunset volumio[5375]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:52:07 sunset volumio[5375]: error: MyVolumio Plugin failed to start in a timely fashion Jul 19 13:52:07 sunset volumio[5375]: [Metrics] CommandRouter: 59s 113.17ms Jul 19 13:52:07 sunset volumio[5375]: info: CoreCommandRouter::volumiosetStartupVolume Jul 19 13:52:07 sunset volumio[5375]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:07 sunset volumio[5375]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:52:07 sunset volumio[5375]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:52:07 sunset volumio[5375]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:52:08 sunset volumio[5375]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 19 13:52:08 sunset volumio[5375]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 19 13:52:08 sunset volumio[5375]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 19 13:52:08 sunset mpd[5858]: Jul 19 13:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 13:52:08 sunset systemd[1]: Started Music Player Daemon. Jul 19 13:52:08 sunset sudo[5854]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:08 sunset sudo[5867]: root : TTY=unknown ; PWD=/root ; USER=root ; COMMAND=/bin/systemctl restart volumio Jul 19 13:52:08 sunset sudo[5867]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:09 sunset systemd[1]: Stopping Volumio Backend Module... Jul 19 13:52:09 sunset volumio-remote-updater[577]: [2025-07-19 13:52:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 19 13:52:09 sunset volumio-remote-updater[577]: [2025-07-19 13:52:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 19 13:52:09 sunset systemd[1]: Started dynamicswap service. Jul 19 13:52:09 sunset systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM Jul 19 13:52:09 sunset systemd[1]: volumio.service: Succeeded. Jul 19 13:52:09 sunset systemd[1]: Stopped Volumio Backend Module. Jul 19 13:52:09 sunset systemd[1]: Started Volumio Backend Module. Jul 19 13:52:09 sunset sudo[5867]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:09 sunset systemd[1]: dynamicswap.service: Succeeded. Jul 19 13:52:12 sunset volumio[5874]: info: ------------------------------------------- Jul 19 13:52:12 sunset volumio[5874]: info: ----- Volumio3 ---- Jul 19 13:52:12 sunset volumio[5874]: info: ------------------------------------------- Jul 19 13:52:12 sunset volumio[5874]: info: ----- System startup ---- Jul 19 13:52:12 sunset volumio[5874]: info: ------------------------------------------- Jul 19 13:52:13 sunset volumio[5874]: info: MYVOLUMIO Environment detected Jul 19 13:52:13 sunset volumio[5874]: info: Plugin folders cleanup Jul 19 13:52:13 sunset volumio[5874]: info: Scanning into folder /volumio/app/plugins/ Jul 19 13:52:13 sunset volumio[5874]: info: Scanning category audio_interface Jul 19 13:52:13 sunset volumio[5874]: info: Scanning category miscellanea Jul 19 13:52:13 sunset volumio[5874]: info: Scanning category music_service Jul 19 13:52:13 sunset volumio[5874]: info: Scanning category plugins.json Jul 19 13:52:13 sunset volumio[5874]: info: Scanning category system_controller Jul 19 13:52:13 sunset volumio[5874]: info: Scanning category user_interface Jul 19 13:52:13 sunset volumio[5874]: info: Scanning into folder /data/plugins/ Jul 19 13:52:13 sunset volumio[5874]: info: Plugin folders cleanup completed Jul 19 13:52:13 sunset volumio[5874]: info: ------------------------------------------- Jul 19 13:52:13 sunset volumio[5874]: info: ----- Core plugins startup ---- Jul 19 13:52:13 sunset volumio[5874]: info: ------------------------------------------- Jul 19 13:52:13 sunset volumio[5874]: info: Loading plugins from folder /volumio/app/plugins/ Jul 19 13:52:13 sunset volumio[5874]: info: Adding plugin upnp to MyMusic Plugins Jul 19 13:52:13 sunset volumio[5874]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 19 13:52:13 sunset volumio[5874]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 19 13:52:13 sunset volumio[5874]: info: Loading plugins from folder /data/plugins/ Jul 19 13:52:13 sunset volumio[5874]: info: Loading plugin "system"... Jul 19 13:52:13 sunset volumio[5874]: info: Loading plugin "appearance"... Jul 19 13:52:14 sunset volumio-remote-updater[577]: [2025-07-19 13:52:14] [connect] Successful connection Jul 19 13:52:15 sunset volumio[5874]: info: Loading plugin "network"... Jul 19 13:52:15 sunset volumio[5874]: info: Refreshing Cached IP Addresses Jul 19 13:52:15 sunset sudo[5913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 13:52:15 sunset sudo[5913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:15 sunset sudo[5913]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:15 sunset sudo[5915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 13:52:15 sunset sudo[5915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:15 sunset sudo[5915]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:15 sunset volumio[5874]: info: Loading plugin "services"... Jul 19 13:52:15 sunset volumio[5874]: info: Loading plugin "alsa_controller"... Jul 19 13:52:15 sunset sudo[5924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 19 13:52:15 sunset sudo[5924]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:15 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 13:52:15 sunset volumio[5874]: info: Loading plugin "wizard"... Jul 19 13:52:15 sunset volumio[5874]: info: Loading plugin "networkfs"... Jul 19 13:52:15 sunset volumio[5874]: info: Starting Udev Watcher for removable devices Jul 19 13:52:15 sunset volumio[5874]: info: Ignoring mount for partition: boot Jul 19 13:52:15 sunset volumio[5874]: info: Ignoring mount for partition: volumio Jul 19 13:52:15 sunset volumio[5874]: info: Ignoring mount for partition: volumio_data Jul 19 13:52:15 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 13:52:15 sunset volumio[5874]: info: Loading plugin "volumio_command_line_client"... Jul 19 13:52:15 sunset volumio[5874]: info: Loading plugin "upnp"... Jul 19 13:52:15 sunset volumio[5874]: info: [1752918735931] Starting Upmpd Daemon Jul 19 13:52:15 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 13:52:15 sunset volumio[5874]: info: Loading plugin "my_music"... Jul 19 13:52:15 sunset volumio[5874]: info: Loading plugin "mpd"... Jul 19 13:52:16 sunset volumio[5874]: info: Loading plugin "upnp_browser"... Jul 19 13:52:16 sunset sudo[5924]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:16 sunset volumio[5874]: info: Loading plugin "alarm-clock"... Jul 19 13:52:17 sunset volumio[5874]: info: Loading plugin "airplay_emulation"... Jul 19 13:52:17 sunset volumio[5874]: info: Starting Shairport Sync Jul 19 13:52:17 sunset volumio[5874]: info: Loading plugin "last_100"... Jul 19 13:52:17 sunset volumio[5874]: info: Loading plugin "webradio"... Jul 19 13:52:17 sunset volumio[5874]: info: Loading plugin "i2s_dacs"... Jul 19 13:52:17 sunset volumio[5874]: info: I2S DAC not set, start Auto-detection Jul 19 13:52:17 sunset volumio[5874]: info: Loading plugin "volumiodiscovery"... Jul 19 13:52:17 sunset volumio[5874]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 19 13:52:17 sunset volumio[5874]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 13:52:17 sunset volumio[5874]: *** WARNING *** For more information see Jul 19 13:52:17 sunset volumio[5874]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 19 13:52:17 sunset node[5874]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 19 13:52:17 sunset volumio[5874]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 13:52:17 sunset volumio[5874]: *** WARNING *** For more information see Jul 19 13:52:17 sunset node[5874]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 13:52:17 sunset node[5874]: *** WARNING *** For more information see Jul 19 13:52:17 sunset node[5874]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 19 13:52:17 sunset node[5874]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 13:52:17 sunset node[5874]: *** WARNING *** For more information see Jul 19 13:52:21 sunset kernel: hwmon hwmon1: Voltage normalised Jul 19 13:52:22 sunset volumio[5874]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 19 13:52:22 sunset volumio[5874]: info: Discovery: Started advertising with name: Sunset Jul 19 13:52:22 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 13:52:22 sunset volumio[5874]: info: Loading plugin "outputs"... Jul 19 13:52:22 sunset volumio[5874]: info: Loading plugin "albumart"... Jul 19 13:52:22 sunset volumio[5874]: info: Plugin example_plugin is not enabled Jul 19 13:52:22 sunset volumio[5874]: info: Loading plugin "inputs"... Jul 19 13:52:22 sunset volumio[5874]: info: Loading plugin "updater_comm"... Jul 19 13:52:22 sunset volumio[5874]: info: Plugin mpdemulation is not enabled Jul 19 13:52:22 sunset volumio[5874]: info: Loading plugin "rest_api"... Jul 19 13:52:22 sunset volumio[5874]: info: Loading plugin "websocket"... Jul 19 13:52:22 sunset volumio[5874]: info: Starting Socket.io Server version 2.3.0 Jul 19 13:52:22 sunset volumio[5874]: info: Loading i18n strings for locale en Jul 19 13:52:22 sunset volumio[5874]: Updating browse sources language Jul 19 13:52:22 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:52:23 sunset volumio[5874]: Forking 3 albumart workers Jul 19 13:52:23 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 13:52:23 sunset volumio[5874]: info: CoreCommandRouter::initPlayerControls Jul 19 13:52:23 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:23 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:23 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:23 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 13:52:23 sunset volumio[5874]: Express server listening on port 3000 Jul 19 13:52:23 sunset volumio[5874]: [Metrics] WebUI: 12s 102.10ms Jul 19 13:52:23 sunset volumio[5874]: info: CoreStateMachine::resetVolumioState Jul 19 13:52:23 sunset volumio[5874]: info: CoreStateMachine::getcurrentVolume Jul 19 13:52:23 sunset volumio[5874]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 13:52:23 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:52:24 sunset kernel: hwmon hwmon1: Undervoltage detected! Jul 19 13:52:24 sunset volumio[5874]: Starting albumart workers Jul 19 13:52:24 sunset volumio[5874]: Starting albumart workers Jul 19 13:52:24 sunset volumio[5874]: Starting albumart workers Jul 19 13:52:26 sunset volumio[5874]: info: Volumio Network Manager: Network status updated: 1 Jul 19 13:52:26 sunset sudo[5992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 13:52:26 sunset volumio-remote-updater[577]: [2025-07-19 13:52:26] [connect] Successful connection Jul 19 13:52:26 sunset sudo[5992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:26 sunset sudo[5992]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:26 sunset sudo[5994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 13:52:26 sunset sudo[5994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:26 sunset sudo[5994]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:26 sunset volumio[5874]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 19 13:52:26 sunset volumio[5874]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jul 19 13:52:26 sunset volumio[5874]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 19 13:52:26 sunset volumio[5874]: info: VolumeController:: Volume=0 Mute =false Jul 19 13:52:26 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:26 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:26 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 13:52:26 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:27 sunset volumio[5874]: info: CoreStateMachine::updateTrackBlock Jul 19 13:52:27 sunset volumio[5874]: info: CorePlayQueue::getTrackBlock Jul 19 13:52:27 sunset volumio[5874]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 13:52:27 sunset volumio[5874]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 19 13:52:27 sunset volumio[5874]: info: Reloading queue from file Jul 19 13:52:27 sunset volumio-remote-updater[577]: [2025-07-19 13:52:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752918746 101 Jul 19 13:52:27 sunset volumio[5874]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Jul 19 13:52:27 sunset volumio[5874]: info: CoreStateMachine::setRepeat null single undefined Jul 19 13:52:27 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:27 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:27 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:27 sunset volumio[5874]: info: CoreStateMachine::setRandom null Jul 19 13:52:27 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:27 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:27 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:27 sunset volumio[5874]: info: Setting Device type: Raspberry PI Jul 19 13:52:27 sunset volumio[5874]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 19 13:52:27 sunset volumio[5874]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jul 19 13:52:27 sunset volumio[5874]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 19 13:52:27 sunset sudo[6022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 19 13:52:27 sunset sudo[6022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:27 sunset volumio[5874]: info: VolumeController:: Volume=0 Mute =false Jul 19 13:52:27 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:27 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:27 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:27 sunset volumio[5874]: info: Completed loading Core Plugins Jul 19 13:52:28 sunset volumio[5874]: info: Preparing to generate the ALSA configuration file Jul 19 13:52:28 sunset sudo[6022]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:28 sunset volumio[5874]: info: Upmpdcli Daemon Started Jul 19 13:52:28 sunset volumio[5874]: info: Asound.conf file unchanged, so no further update is needed Jul 19 13:52:28 sunset volumio[5874]: info: Output device has changed, restarting MPD Jul 19 13:52:28 sunset volumio[5874]: info: Output device has changed, restarting Shairport Sync Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:52:28 sunset sudo[6025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 13:52:28 sunset sudo[6027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 13:52:28 sunset sudo[6025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:28 sunset sudo[6027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:28 sunset sudo[6025]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:28 sunset systemd[1]: Stopping Music Player Daemon... Jul 19 13:52:28 sunset volumio[5874]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 13:52:28 sunset volumio[5874]: info: ___________ START PLUGINS ___________ Jul 19 13:52:28 sunset systemd[1]: mpd.service: Succeeded. Jul 19 13:52:28 sunset systemd[1]: Stopped Music Player Daemon. Jul 19 13:52:28 sunset volumio[5874]: info: ControllerMpd::onStart: Initializing MPD Jul 19 13:52:28 sunset volumio[5874]: info: Creating MPD Configuration file Jul 19 13:52:28 sunset systemd[1]: Starting Music Player Daemon... Jul 19 13:52:28 sunset sudo[6035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 13:52:28 sunset sudo[6035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 13:52:28 sunset sudo[6035]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 13:52:28 sunset volumio[5874]: info: [1752918748573] CoreMusicLibrary::Adding element Media Servers Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:52:28 sunset sudo[6038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 13:52:28 sunset sudo[6038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:28 sunset systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 19 13:52:28 sunset systemd[1]: mpd.service: Succeeded. Jul 19 13:52:28 sunset systemd[1]: Stopped Music Player Daemon. Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:52:28 sunset systemd[1]: Starting Music Player Daemon... Jul 19 13:52:28 sunset volumio[5874]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:52:28 sunset volumio[5874]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 13:52:28 sunset volumio[5874]: info: [1752918748955] CoreMusicLibrary::Adding element Last_100 Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 13:52:28 sunset volumio[5874]: info: [1752918748961] CoreMusicLibrary::Adding element Webradio Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:52:28 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 13:52:28 sunset volumio[5874]: info: Initializing BBC Radios Jul 19 13:52:29 sunset sudo[6040]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 13:52:29 sunset sudo[6040]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:29 sunset sudo[6040]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: Volumio Calling Home Jul 19 13:52:29 sunset volumio[5874]: info: MPD Permissions set Jul 19 13:52:29 sunset volumio[5874]: info: MPD Permissions set Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::volumioGetQueue Jul 19 13:52:29 sunset volumio[5874]: info: CoreStateMachine::getQueue Jul 19 13:52:29 sunset volumio[5874]: info: CorePlayQueue::getQueue Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:52:29 sunset volumio[5874]: info: Starting Shairport Sync Jul 19 13:52:29 sunset volumio[5874]: info: Starting Shairport Sync Jul 19 13:52:29 sunset sudo[6110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 13:52:29 sunset volumio[5874]: info: Starting Shairport Sync Jul 19 13:52:29 sunset sudo[6110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:29 sunset sudo[6112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 13:52:29 sunset sudo[6112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:29 sunset volumio[5874]: info: CoreCommandRouter::volumioGetQueue Jul 19 13:52:29 sunset volumio[5874]: info: CoreStateMachine::getQueue Jul 19 13:52:29 sunset volumio[5874]: info: CorePlayQueue::getQueue Jul 19 13:52:29 sunset systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 13:52:30 sunset systemd[1]: shairport-sync.service: Succeeded. Jul 19 13:52:30 sunset systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 13:52:30 sunset sudo[6115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 13:52:30 sunset sudo[6115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:52:30 sunset systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 13:52:30 sunset sudo[6110]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:30 sunset volumio[5874]: info: Shairport-Sync Started Jul 19 13:52:30 sunset volumio[5874]: Error adding Membership: Error: addMembership EINVAL Jul 19 13:52:30 sunset sudo[6112]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:30 sunset volumio[5874]: info: Shairport-Sync Started Jul 19 13:52:30 sunset systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 13:52:30 sunset systemd[1]: shairport-sync.service: Succeeded. Jul 19 13:52:30 sunset systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 13:52:30 sunset systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 13:52:30 sunset sudo[6115]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:30 sunset volumio[5874]: info: Shairport-Sync Started Jul 19 13:52:30 sunset volumio[5874]: info: Volumio called home Jul 19 13:52:33 sunset volumio[5874]: info: Discovery: adding ecc65555-93c5-4407-8ada-442f42def875 Jul 19 13:52:33 sunset volumio[5874]: info: Discovery: Found device Sunset Jul 19 13:52:33 sunset volumio[5874]: info: CoreCommandRouter::volumioGetState Jul 19 13:52:33 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:33 sunset volumio[5874]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 19 13:52:33 sunset volumio[5874]: info: CoreCommandRouter::volumioGetState Jul 19 13:52:33 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:33 sunset volumio[5874]: info: Discovery: this is already registered, ecc65555-93c5-4407-8ada-442f42def875 Jul 19 13:52:33 sunset volumio[5874]: info: Discovery: Found device Sunset Jul 19 13:52:33 sunset volumio[5874]: info: CoreCommandRouter::volumioGetState Jul 19 13:52:33 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:34 sunset kernel: hwmon hwmon1: Voltage normalised Jul 19 13:52:34 sunset mpd[6064]: Jul 19 13:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 13:52:34 sunset systemd[1]: Started Music Player Daemon. Jul 19 13:52:34 sunset sudo[6027]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:34 sunset sudo[6038]: pam_unix(sudo:session): session closed for user root Jul 19 13:52:35 sunset volumio[5874]: info: Completed starting Core Plugins Jul 19 13:52:35 sunset volumio[5874]: info: ------------------------------------------- Jul 19 13:52:35 sunset volumio[5874]: info: ----- MyVolumio plugins startup ---- Jul 19 13:52:35 sunset volumio[5874]: info: ------------------------------------------- Jul 19 13:52:35 sunset volumio[5874]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 19 13:52:35 sunset systemd[1]: Starting Cleanup of Temporary Directories... Jul 19 13:52:35 sunset volumio[5874]: error: MPD error: The expression evaluated to a falsy value: Jul 19 13:52:35 sunset volumio[5874]: assert.ok(self.idling) Jul 19 13:52:35 sunset volumio[5874]: error: The expression evaluated to a falsy value: Jul 19 13:52:35 sunset volumio[5874]: assert.ok(self.idling) Jul 19 13:52:35 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:35 sunset volumio[5874]: info: Jul 19 13:52:35 sunset volumio[5874]: ---------------------------- MPD announces state update: update Jul 19 13:52:35 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:35 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:35 sunset volumio[5874]: info: MPD running with PID6064 Jul 19 13:52:35 sunset volumio[5874]: ,establishing connection Jul 19 13:52:35 sunset volumio[5874]: error: updateQueue error: null Jul 19 13:52:35 sunset volumio[5874]: info: sendMpdCommand status took 36 milliseconds Jul 19 13:52:35 sunset volumio[5874]: info: sendMpdCommand status took 31 milliseconds Jul 19 13:52:35 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:36 sunset kernel: hwmon hwmon1: Undervoltage detected! Jul 19 13:52:37 sunset cron[631]: 2025-07-19 13:52:37 1ud4En-0001XI-I1 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=118 egid=124 Jul 19 13:52:37 sunset cron[631]: 2025-07-19 13:52:37 1ud4En-0001XI-I1 <= root@volumio U=root P=local S=1132 Jul 19 13:52:37 sunset cron[631]: 2025-07-19 13:52:37 1ud4En-0001XI-I1 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=118 egid=124 Jul 19 13:52:37 sunset cron[631]: exim: could not open panic log - aborting: see message(s) above Jul 19 13:52:37 sunset exim[5908]: 2025-07-19 13:52:37 1ud4En-0001XI-I1 <= root@volumio U=root P=local S=1132 Jul 19 13:52:37 sunset exim[5908]: 2025-07-19 13:52:37 1ud4En-0001XI-I1 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=118 egid=124 Jul 19 13:52:37 sunset exim[5908]: exim: could not open panic log - aborting: see message(s) above Jul 19 13:52:37 sunset CRON[5255]: (root) MAIL (mailed 583 bytes of output but got status 0x0001 from MTA ) Jul 19 13:52:37 sunset CRON[5255]: pam_unix(cron:session): session closed for user root Jul 19 13:52:38 sunset systemd-tmpfiles[6130]: [/usr/lib/tmpfiles.d/zabbix-agent.conf:1] Line references path below legacy directory /var/run/, updating /var/run/zabbix → /run/zabbix; please update the tmpfiles.d/ drop-in file accordingly. Jul 19 13:52:38 sunset volumio[5874]: info: Command Router : Notfying DB Updatetrue Jul 19 13:52:38 sunset systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jul 19 13:52:38 sunset systemd[1]: Started Cleanup of Temporary Directories. Jul 19 13:52:38 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:38 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:52:38 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:52:38 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:38 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:38 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:38 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:38 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:52:38 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:52:38 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:52:38 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:52:38 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:38 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:38 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 13:52:38 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:38 sunset volumio[5874]: info: No code Jul 19 13:52:38 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:38 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:38 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:38 sunset volumio[5874]: info: ------------------------------ 3208ms Jul 19 13:52:38 sunset volumio[5874]: error: updateQueue error: null Jul 19 13:52:38 sunset volumio[5874]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 19 13:52:38 sunset volumio[5874]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 19 13:52:38 sunset volumio[5874]: info: CoreCommandRouter::volumioGetState Jul 19 13:52:38 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:38 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 19 13:52:38 sunset volumio[5874]: info: CoreCommandRouter::volumioGetVisibleSources Jul 19 13:52:38 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:52:38 sunset volumio[5874]: info: Listing playlists Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioGetState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioGetState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: Jul 19 13:52:39 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:52:39 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:39 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:39 sunset volumio[5874]: info: Jul 19 13:52:39 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:52:39 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:39 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:39 sunset volumio[5874]: info: Jul 19 13:52:39 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:52:39 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:39 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:39 sunset volumio[5874]: info: Jul 19 13:52:39 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:52:39 sunset volumio[5874]: info: Jul 19 13:52:39 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:52:39 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:39 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:39 sunset volumio[5874]: info: sendMpdCommand status took 84 milliseconds Jul 19 13:52:39 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:39 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:39 sunset volumio[5874]: info: sendMpdCommand status took 67 milliseconds Jul 19 13:52:39 sunset volumio[5874]: info: sendMpdCommand status took 50 milliseconds Jul 19 13:52:39 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:39 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:39 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:39 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:52:39 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:39 sunset volumio[5874]: info: No code Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:39 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:52:39 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:39 sunset volumio[5874]: info: No code Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:39 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:52:39 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:39 sunset volumio[5874]: info: No code Jul 19 13:52:39 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:39 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:39 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: ------------------------------ 658ms Jul 19 13:52:40 sunset volumio[5874]: info: ------------------------------ 655ms Jul 19 13:52:40 sunset volumio[5874]: info: ------------------------------ 642ms Jul 19 13:52:40 sunset volumio[5874]: info: Jul 19 13:52:40 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:52:40 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:40 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:40 sunset volumio[5874]: info: Jul 19 13:52:40 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:52:40 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:40 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:40 sunset volumio[5874]: info: MPD Database updated - AlbumList cache refreshed Jul 19 13:52:40 sunset volumio[5874]: info: Jul 19 13:52:40 sunset volumio[5874]: ---------------------------- MPD announces state update: database Jul 19 13:52:40 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:40 sunset volumio[5874]: info: Jul 19 13:52:40 sunset volumio[5874]: ---------------------------- MPD announces state update: update Jul 19 13:52:40 sunset volumio[5874]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 19 13:52:40 sunset volumio[5874]: info: sendMpdCommand status took 727 milliseconds Jul 19 13:52:40 sunset volumio[5874]: info: sendMpdCommand status took 719 milliseconds Jul 19 13:52:40 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:40 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:40 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:40 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:40 sunset volumio[5874]: info: sendMpdCommand status took 130 milliseconds Jul 19 13:52:40 sunset volumio[5874]: info: sendMpdCommand status took 120 milliseconds Jul 19 13:52:40 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:40 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:40 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:40 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:40 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:52:40 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: No code Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:52:40 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: No code Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:52:40 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: No code Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:52:40 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:40 sunset volumio[5874]: info: No code Jul 19 13:52:40 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:40 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:40 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:41 sunset volumio[5874]: info: ------------------------------ 1451ms Jul 19 13:52:41 sunset volumio[5874]: info: ------------------------------ 1457ms Jul 19 13:52:41 sunset volumio[5874]: info: ------------------------------ 859ms Jul 19 13:52:41 sunset volumio[5874]: info: ------------------------------ 856ms Jul 19 13:52:41 sunset volumio[5874]: info: MPD Database updated - AlbumList cache refreshed Jul 19 13:52:41 sunset volumio[5874]: info: Jul 19 13:52:41 sunset volumio[5874]: ---------------------------- MPD announces state update: database Jul 19 13:52:41 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:41 sunset volumio[5874]: info: Jul 19 13:52:41 sunset volumio[5874]: ---------------------------- MPD announces state update: update Jul 19 13:52:41 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:41 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:41 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:41 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:41 sunset volumio[5874]: info: MPD Database updated - AlbumList cache refreshed Jul 19 13:52:41 sunset volumio[5874]: info: Jul 19 13:52:41 sunset volumio[5874]: ---------------------------- MPD announces state update: database Jul 19 13:52:41 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:41 sunset volumio[5874]: info: Jul 19 13:52:41 sunset volumio[5874]: ---------------------------- MPD announces state update: update Jul 19 13:52:41 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:41 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:41 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:52:41 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:52:41 sunset volumio[5874]: info: sendMpdCommand status took 1274 milliseconds Jul 19 13:52:41 sunset volumio[5874]: info: sendMpdCommand status took 1229 milliseconds Jul 19 13:52:41 sunset volumio[5874]: info: sendMpdCommand status took 1223 milliseconds Jul 19 13:52:41 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:51 sunset kernel: hwmon hwmon1: Voltage normalised Jul 19 13:52:52 sunset sshd[5136]: Received disconnect from 192.168.2.1 port 64928:11: disconnected by user Jul 19 13:52:52 sunset sshd[5136]: Disconnected from user root 192.168.2.1 port 64928 Jul 19 13:52:52 sunset sshd[5136]: pam_unix(sshd:session): session closed for user root Jul 19 13:52:52 sunset systemd[1]: session-7.scope: Succeeded. Jul 19 13:52:52 sunset systemd-logind[596]: Session 7 logged out. Waiting for processes to exit. Jul 19 13:52:52 sunset systemd-logind[596]: Removed session 7. Jul 19 13:52:53 sunset kernel: hwmon hwmon1: Undervoltage detected! Jul 19 13:52:55 sunset volumio[5874]: info: Command Router : Notfying DB Updatefalse Jul 19 13:52:55 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:52:55 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:55 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:55 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:52:55 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:52:55 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:55 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:55 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:55 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:55 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:52:55 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:52:55 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:52:55 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:52:55 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:55 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:55 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:55 sunset volumio[5874]: info: No code Jul 19 13:52:55 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:55 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:55 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:55 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:52:55 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:52:55 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:55 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:55 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:55 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:55 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:52:55 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:52:55 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:52:55 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:52:55 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:55 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:55 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:55 sunset volumio[5874]: info: No code Jul 19 13:52:55 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:52:55 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:52:55 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:52:55 sunset volumio[5874]: info: ------------------------------ 15144ms Jul 19 13:52:55 sunset volumio[5874]: info: ------------------------------ 15140ms Jul 19 13:52:55 sunset volumio[5874]: info: sendMpdCommand status took 14384 milliseconds Jul 19 13:52:55 sunset volumio[5874]: info: sendMpdCommand status took 14375 milliseconds Jul 19 13:52:55 sunset volumio[5874]: info: sendMpdCommand status took 14369 milliseconds Jul 19 13:52:55 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:52:55 sunset sshd[6227]: Accepted publickey for root from 192.168.2.1 port 64992 ssh2: RSA SHA256:+ZqEzlJXWcAGSfDzmm+baP9rZyx3J1wnRVkNditwAjI Jul 19 13:52:55 sunset sshd[6227]: pam_unix(sshd:session): session opened for user root by (uid=0) Jul 19 13:52:55 sunset systemd-logind[596]: New session 11 of user root. Jul 19 13:52:55 sunset systemd[1]: Started Session 11 of user root. Jul 19 13:53:00 sunset volumio-remote-updater[577]: [2025-07-19 13:53:00] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jul 19 13:53:01 sunset kernel: hwmon hwmon1: Voltage normalised Jul 19 13:53:02 sunset volumio[5874]: info: Command Router : Notfying DB Updatefalse Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:53:02 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:02 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:02 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:02 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:02 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:02 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:02 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:02 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:02 sunset volumio[5874]: info: No code Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:02 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:02 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:02 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:02 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:02 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:02 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:02 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:02 sunset volumio[5874]: info: No code Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:02 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:02 sunset volumio[5874]: info: ------------------------------ 21477ms Jul 19 13:53:02 sunset volumio[5874]: info: ------------------------------ 21476ms Jul 19 13:53:02 sunset volumio[5874]: info: sendMpdCommand status took 21552 milliseconds Jul 19 13:53:02 sunset volumio[5874]: info: sendMpdCommand status took 21545 milliseconds Jul 19 13:53:02 sunset volumio[5874]: info: sendMpdCommand status took 21540 milliseconds Jul 19 13:53:02 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:02 sunset volumio[5874]: info: Command Router : Notfying DB Updatefalse Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:53:02 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:02 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:02 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:02 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:02 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:02 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:03 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:03 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:03 sunset volumio[5874]: info: No code Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:03 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:03 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:03 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:03 sunset volumio[5874]: info: No code Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:03 sunset volumio[5874]: info: ------------------------------ 21953ms Jul 19 13:53:03 sunset volumio[5874]: info: ------------------------------ 21952ms Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 19 13:53:03 sunset volumio[5874]: error: MyVolumio Plugin failed to start in a timely fashion Jul 19 13:53:03 sunset volumio[5874]: [Metrics] CommandRouter: 50s 915.62ms Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumiosetStartupVolume Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:53:03 sunset volumio[5874]: info: Jul 19 13:53:03 sunset volumio[5874]: ---------------------------- Client requests Volumio stop Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioStop Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::stop Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 13:53:03 sunset volumio[5874]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 19 13:53:03 sunset kernel: hwmon hwmon1: Undervoltage detected! Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioGetState Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioGetVisibleSources Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:53:03 sunset volumio[5874]: info: Jul 19 13:53:03 sunset volumio[5874]: ---------------------------- Client requests Volumio Clear Queue Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioClearQueue Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::ClearQueue Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::stop Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::clearPlayQueue Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::saveQueue Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioPushQueue Jul 19 13:53:03 sunset volumio[5874]: error: API:pushError: TypeError: callback.apply is not a function Jul 19 13:53:03 sunset volumio[5874]: info: Jul 19 13:53:03 sunset volumio[5874]: ---------------------------- Client requests Volumio Play Playlist 1 Jul 19 13:53:03 sunset volumio[5874]: info: Play playlist 1 Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioClearQueue Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::ClearQueue Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::stop Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::clearPlayQueue Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::saveQueue Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioPushQueue Jul 19 13:53:03 sunset volumio[5874]: info: CoreCommandRouter::volumioAddQueueItems Jul 19 13:53:03 sunset volumio[5874]: info: CoreStateMachine::addQueueItems Jul 19 13:53:03 sunset volumio[5874]: info: CorePlayQueue::addQueueItems Jul 19 13:53:03 sunset volumio[5874]: info: Preload queue cleared Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Come With Me on the Roof.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Come With Me on the Roof.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Blue Amethyst.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Blue Amethyst.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Buoyant Loufish.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Buoyant Loufish.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Birds & Bees Govi.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Birds & Bees Govi.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Aquatic Screen Jazzmaster.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Aquatic Screen Jazzmaster.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Espresso knoodle,Creason,snaate.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Espresso knoodle,Creason,snaate.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Chilled White Wine Mystic Diversions.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Chilled White Wine Mystic Diversions.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Midnight Blue Blank & Jones.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Midnight Blue Blank & Jones.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Back & Forth The Other Guys.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Back & Forth The Other Guys.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Autumn Sunrise Blank & Jones,Julian & Roman Wasserfuhr.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Autumn Sunrise Blank & Jones,Julian & Roman Wasserfuhr.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Cihuacoatl Smoke Trees.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Cihuacoatl Smoke Trees.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Clouds In The Sky Tierra Negra.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Clouds In The Sky Tierra Negra.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Breathe.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Breathe.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Cologne Nights.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Cologne Nights.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Beach Date Guzimi & Heinrich Schlupf.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Beach Date Guzimi & Heinrich Schlupf.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Cloudy Haze Rein Dayz.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Cloudy Haze Rein Dayz.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Gamla Stan Jacob Gurevitsch.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Gamla Stan Jacob Gurevitsch.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Beyond the Pines Guzimi.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Beyond the Pines Guzimi.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/calm motion.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/calm motion.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Cold Brew knoodle,Creason,snaate.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Cold Brew knoodle,Creason,snaate.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Dreams.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Dreams.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Comme Ça. UKDD & Linearwave.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Comme Ça. UKDD & Linearwave.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Peaceful Thomas Lemmer.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Peaceful Thomas Lemmer.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Busking Tag.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Busking Tag.mp3 in service mpd Jul 19 13:53:03 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Bundle Up.mp3 Jul 19 13:53:03 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Bundle Up.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Bike Ride in Osaka tecashi.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Bike Ride in Osaka tecashi.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/caviar.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/caviar.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Chazzed.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Chazzed.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Anybody.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Anybody.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Blue Mood sleepsleepsleep.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Blue Mood sleepsleepsleep.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Coffee for Two HawkOne & Alejo.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Coffee for Two HawkOne & Alejo.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Beige.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Beige.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Aquarell damaa.beats & Sátyr.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Aquarell damaa.beats & Sátyr.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Mongostein Lou Lizza.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Mongostein Lou Lizza.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Deep Down Chillski,finton,cloudkut.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Deep Down Chillski,finton,cloudkut.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/dreamy sights from the heights.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/dreamy sights from the heights.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Coffee Break knoodle,Creason,snaate.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Coffee Break knoodle,Creason,snaate.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Coral Pink Seafoam.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Coral Pink Seafoam.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Ebony Don Philippe & Rzuma.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Ebony Don Philippe & Rzuma.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Downtown.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Downtown.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/C'est Beau La Vie - Jazzed Blank & Jones,Julian & Roman Wasserfuhr.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/C'est Beau La Vie - Jazzed Blank & Jones,Julian & Roman Wasserfuhr.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Coral.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Coral.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/About Midnight.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/About Midnight.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Babbling Brook.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Babbling Brook.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Eternal Love - Weathertunes.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Eternal Love - Weathertunes.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Everest Kim Kozy.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Everest Kim Kozy.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/altitude - helistofax.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/altitude - helistofax.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Downstream.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Downstream.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: info: Adding Item to queue: mnt/INTERNAL/1 Morning/Bubbly Chillski,finton,cloudkut.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: Exploding uri mnt/INTERNAL/1 Morning/Bubbly Chillski,finton,cloudkut.mp3 in service mpd Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:04 sunset volumio[5874]: info: Jul 19 13:53:04 sunset volumio[5874]: ---------------------------- MPD announces state update: update Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=My%20Companion/Come%20With%20Me%20On%20The%20Roof/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FCome%20With%20Me%20on%20the%20Roof.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Come With Me on the Roof.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Electrotastica/Blue%20Amethyst/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBlue%20Amethyst.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Blue Amethyst.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Loufish/Buoyant%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBuoyant%20%20Loufish.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Buoyant Loufish.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Govi/Guitarra%20Mistica/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBirds%20%26%20Bees%20Govi.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Birds & Bees Govi.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Screen%20Jazzmaster/Aquatic/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FAquatic%20Screen%20Jazzmaster.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Aquatic Screen Jazzmaster.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=knoodle%2CCreason%2Csnaate/Home/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FEspresso%20knoodle%2CCreason%2Csnaate.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Espresso knoodle,Creason,snaate.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Mystic%20Diversions/Beneath%20Another%20Sky/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FChilled%20White%20Wine%20Mystic%20Diversions.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Chilled White Wine Mystic Diversions.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Blank%20%26%20Jones/Relax%20Edition%209/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FMidnight%20Blue%20Blank%20%26%20Jones.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Midnight Blue Blank & Jones.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=The%20Other%20Guys/Back%20%26%20Forth%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBack%20%26%20Forth%20%20The%20Other%20Guys.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Back & Forth The Other Guys.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Blank%20%26%20Jones%2CJulian%20%26%20Roman%20Wasserfuhr/Jazzed%20EP/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FAutumn%20Sunrise%20Blank%20%26%20Jones%2CJulian%20%26%20Roman%20Wasserfuhr.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Autumn Sunrise Blank & Jones,Julian & Roman Wasserfuhr.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Smoke%20Trees/Cihuacoatl%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FCihuacoatl%20%20Smoke%20Trees.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Cihuacoatl Smoke Trees.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Tierra%20Negra/Clouds%20In%20The%20Sky/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FClouds%20In%20The%20Sky%20Tierra%20Negra.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Clouds In The Sky Tierra Negra.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Suede%20Wallet/Breathe/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBreathe.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Breathe.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Jost%20Esser/Cologne%20Nights/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FCologne%20Nights.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Cologne Nights.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Guzimi%20%26%20Heinrich%20Schlupf/Beach%20Date%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBeach%20Date%20%20Guzimi%20%26%20Heinrich%20Schlupf.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Beach Date Guzimi & Heinrich Schlupf.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Rein%20Dayz/Cloudy%20Haze/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FCloudy%20Haze%20Rein%20Dayz.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Cloudy Haze Rein Dayz.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Jacob%20Gurevitsch/Milchbar%20-%20Seaside%20Season%2013/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FGamla%20Stan%20Jacob%20Gurevitsch.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Gamla Stan Jacob Gurevitsch.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Guzimi/Beyond%20the%20Pines%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBeyond%20the%20Pines%20%20Guzimi.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Beyond the Pines Guzimi.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Maci/calm%20motion/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2Fcalm%20motion.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/calm motion.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=knoodle%2CCreason%2Csnaate/Bonanza/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FCold%20Brew%20knoodle%2CCreason%2Csnaate.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Cold Brew knoodle,Creason,snaate.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Chalk%20Duo/Atmosphere%20Mood/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FDreams.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Dreams.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=UKDD%20%26%20Linearwave/Comme%20%C3%87a.%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FComme%20C%CC%A7a.%20%20UKDD%20%26%20Linearwave.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Comme Ça. UKDD & Linearwave.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Thomas%20Lemmer/Pure/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FPeaceful%20Thomas%20Lemmer.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Peaceful Thomas Lemmer.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Tag/Lofi%20Work/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBusking%20%20Tag.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Busking Tag.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=d'Addario/Bundle%20Up/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBundle%20Up.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Bundle Up.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=tecashi/Bike%20Ride%20in%20Osaka%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBike%20Ride%20in%20Osaka%20%20tecashi.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Bike Ride in Osaka tecashi.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=meadowzz/laugh%20at%20the%20balance/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2Fcaviar.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/caviar.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Justnormal/Longings/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FChazzed.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Chazzed.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Deep%20Dial/Anybody/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FAnybody.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Anybody.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=sleepsleepsleep/Blue%20Mood%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBlue%20Mood%20%20sleepsleepsleep.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Blue Mood sleepsleepsleep.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=HawkOne%20%26%20Alejo/Coffee%20for%20Two%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FCoffee%20for%20Two%20%20HawkOne%20%26%20Alejo.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Coffee for Two HawkOne & Alejo.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=kust/Beige/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBeige.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Beige.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=damaa.beats%20%26%20S%C3%A1tyr/Aquarell%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FAquarell%20%20damaa.beats%20%26%20Sa%CC%81tyr.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Aquarell damaa.beats & Sátyr.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Lou%20Lizza/Mongostein/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FMongostein%20Lou%20Lizza.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Mongostein Lou Lizza.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Chillski%2Cfinton%2Ccloudkut/Americano/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FDeep%20Down%20Chillski%2Cfinton%2Ccloudkut.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Deep Down Chillski,finton,cloudkut.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=dLira/dreamy%20sights%20from%20the%20heights/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2Fdreamy%20sights%20from%20the%20heights.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/dreamy sights from the heights.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=knoodle%2CCreason%2Csnaate/Bonanza/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FCoffee%20Break%20knoodle%2CCreason%2Csnaate.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Coffee Break knoodle,Creason,snaate.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Miilano/Coral%20Pink%20Seafoam/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FCoral%20Pink%20Seafoam.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Coral Pink Seafoam.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Don%20Philippe%20%26%20Rzuma/All%20In%20Blues/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FEbony%20%20Don%20Philippe%20%26%20Rzuma.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Ebony Don Philippe & Rzuma.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Dreamfield/Downtown/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FDowntown.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Downtown.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Blank%20%26%20Jones%2CJulian%20%26%20Roman%20Wasserfuhr/Relax%20-%20Jazzed%203/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FC'est%20Beau%20La%20Vie%20-%20Jazzed%20Blank%20%26%20Jones%2CJulian%20%26%20Roman%20Wasserfuhr.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/C'est Beau La Vie - Jazzed Blank & Jones,Julian & Roman Wasserfuhr.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Moderator/Coral/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FCoral.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Coral.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Jack%20Reefa/Future%20Sound%20Of%20Be-Bop/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FAbout%20Midnight.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/About Midnight.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=rsbd/Babbling%20Brook/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBabbling%20Brook.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Babbling Brook.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Weathertunes/Passions/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FEternal%20Love%20-%20Weathertunes.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Eternal Love - Weathertunes.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Kim%20Kozy/Everest%20-%20Single/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FEverest%20%20Kim%20Kozy.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Everest Kim Kozy.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=helistofax/altitude/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2Faltitude%20-%20helistofax.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/altitude - helistofax.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Yasumu/Downstream/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FDownstream.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Downstream.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ALBUMART /albumart?cacheid=98&web=Chillski%2Cfinton%2Ccloudkut/Americano/extralarge&path=%2Fmnt%2FINTERNAL%2F1%20Morning%2FBubbly%20Chillski%2Cfinton%2Ccloudkut.mp3&metadata=false Jul 19 13:53:04 sunset volumio[5874]: info: URI /mnt/INTERNAL/1 Morning/Bubbly Chillski,finton,cloudkut.mp3 Jul 19 13:53:04 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:04 sunset volumio[5874]: info: CoreCommandRouter::volumioPushQueue Jul 19 13:53:04 sunset volumio[5874]: info: CorePlayQueue::saveQueue Jul 19 13:53:04 sunset volumio[5874]: info: CoreStateMachine::updateTrackBlock Jul 19 13:53:04 sunset volumio[5874]: info: CorePlayQueue::getTrackBlock Jul 19 13:53:04 sunset volumio[5874]: info: CoreCommandRouter::volumioPlay Jul 19 13:53:04 sunset volumio[5874]: info: CoreStateMachine::play index 0 Jul 19 13:53:04 sunset volumio[5874]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 13:53:04 sunset volumio[5874]: info: CoreStateMachine::stop Jul 19 13:53:04 sunset volumio[5874]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 13:53:04 sunset volumio[5874]: info: CoreStateMachine::play index undefined Jul 19 13:53:04 sunset volumio[5874]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 13:53:04 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:04 sunset volumio[5874]: info: CoreStateMachine::startPlaybackTimer Jul 19 13:53:04 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/1 Morning/Come With Me on the Roof.mp3 Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand stop Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:04 sunset volumio[5874]: info: Jul 19 13:53:04 sunset volumio[5874]: ---------------------------- MPD announces state update: update Jul 19 13:53:04 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:04 sunset volumio[5874]: info: Jul 19 13:53:04 sunset volumio[5874]: ---------------------------- MPD announces state update: update Jul 19 13:53:04 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:04 sunset volumio[5874]: info: MPD Database updated - AlbumList cache refreshed Jul 19 13:53:04 sunset volumio[5874]: info: Jul 19 13:53:04 sunset volumio[5874]: ---------------------------- MPD announces state update: database Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:04 sunset volumio[5874]: info: Jul 19 13:53:04 sunset volumio[5874]: ---------------------------- MPD announces state update: update Jul 19 13:53:04 sunset volumio[5874]: info: Jul 19 13:53:04 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:53:04 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:04 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:04 sunset volumio[5874]: info: sendMpdCommand status took 220 milliseconds Jul 19 13:53:04 sunset volumio[5874]: info: sendMpdCommand status took 132 milliseconds Jul 19 13:53:04 sunset volumio[5874]: info: sendMpdCommand stop took 45 milliseconds Jul 19 13:53:04 sunset volumio[5874]: info: sendMpdCommand status took 43 milliseconds Jul 19 13:53:04 sunset volumio[5874]: info: sendMpdCommand status took 40 milliseconds Jul 19 13:53:04 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:04 sunset volumio[5874]: info: sendMpdCommand status took 39 milliseconds Jul 19 13:53:04 sunset volumio[5874]: info: sendMpdCommand status took 38 milliseconds Jul 19 13:53:04 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:05 sunset volumio[5874]: info: Command Router : Notfying DB Updatefalse Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand clear Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:05 sunset volumio-remote-updater[577]: [2025-07-19 13:53:05] [connect] Successful connection Jul 19 13:53:05 sunset volumio[5874]: info: Command Router : Notfying DB Updatefalse Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:05 sunset volumio[5874]: info: Command Router : Notfying DB Updatefalse Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:05 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:05 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:05 sunset volumio[5874]: info: No code Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:05 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:05 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:05 sunset volumio[5874]: info: No code Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:05 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:05 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:05 sunset volumio[5874]: info: No code Jul 19 13:53:05 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:05 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:05 sunset volumio[5874]: info: ------------------------------ 1448ms Jul 19 13:53:05 sunset volumio[5874]: info: ------------------------------ 1270ms Jul 19 13:53:05 sunset volumio[5874]: info: ------------------------------ 1265ms Jul 19 13:53:05 sunset volumio[5874]: info: MPD Database updated - AlbumList cache refreshed Jul 19 13:53:05 sunset volumio[5874]: info: Jul 19 13:53:05 sunset volumio[5874]: ---------------------------- MPD announces state update: database Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:05 sunset volumio[5874]: info: Jul 19 13:53:05 sunset volumio[5874]: ---------------------------- MPD announces state update: update Jul 19 13:53:05 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:05 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:05 sunset volumio[5874]: info: MPD Database updated - AlbumList cache refreshed Jul 19 13:53:05 sunset volumio[5874]: info: Jul 19 13:53:05 sunset volumio[5874]: ---------------------------- MPD announces state update: database Jul 19 13:53:05 sunset volumio[5874]: info: Jul 19 13:53:05 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:05 sunset volumio[5874]: info: Jul 19 13:53:05 sunset volumio[5874]: ---------------------------- MPD announces state update: update Jul 19 13:53:05 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:05 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:05 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 19 13:53:05 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 19 13:53:05 sunset volumio[5874]: info: sendMpdCommand status took 1360 milliseconds Jul 19 13:53:05 sunset volumio[5874]: info: sendMpdCommand status took 1356 milliseconds Jul 19 13:53:05 sunset volumio[5874]: info: sendMpdCommand status took 1354 milliseconds Jul 19 13:53:05 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:06 sunset volumio[5874]: info: Command Router : Notfying DB Updatefalse Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:53:06 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:06 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:06 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:06 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: No code Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:06 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: No code Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: ------------------------------ 2386ms Jul 19 13:53:06 sunset volumio[5874]: info: ------------------------------ 2386ms Jul 19 13:53:06 sunset volumio[5874]: info: Jul 19 13:53:06 sunset volumio[5874]: ---------------------------- MPD announces system playlist update Jul 19 13:53:06 sunset volumio[5874]: info: Ignoring MPD Status Update Jul 19 13:53:06 sunset volumio[5874]: info: sendMpdCommand status took 2444 milliseconds Jul 19 13:53:06 sunset volumio[5874]: info: sendMpdCommand clear took 1351 milliseconds Jul 19 13:53:06 sunset volumio[5874]: info: sendMpdCommand status took 1217 milliseconds Jul 19 13:53:06 sunset volumio[5874]: info: sendMpdCommand status took 1216 milliseconds Jul 19 13:53:06 sunset volumio[5874]: info: sendMpdCommand status took 1217 milliseconds Jul 19 13:53:06 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:06 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/1 Morning/Come With Me on the Roof.mp3" Jul 19 13:53:06 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:06 sunset volumio[5874]: info: Command Router : Notfying DB Updatefalse Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:53:06 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:06 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:06 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:06 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: No code Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:06 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: No code Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:06 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: No code Jul 19 13:53:06 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:06 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:06 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:06 sunset volumio[5874]: info: ------------------------------ 2589ms Jul 19 13:53:06 sunset volumio[5874]: info: ------------------------------ 1396ms Jul 19 13:53:06 sunset volumio[5874]: info: ------------------------------ 1397ms Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand status took 1466 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand status took 1466 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand status took 1467 milliseconds Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:07 sunset volumio[5874]: info: Command Router : Notfying DB Updatefalse Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::Close All Modals sent Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:07 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: No code Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:07 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: No code Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 1622ms Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 1624ms Jul 19 13:53:07 sunset volumio[5874]: error: updateQueue error: null Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces system playlist update Jul 19 13:53:07 sunset volumio[5874]: info: Ignoring MPD Status Update Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand status took 1632 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 487ms Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand add "INTERNAL/1 Morning/Come With Me on the Roof.mp3" took 420 milliseconds Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand play Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 19 13:53:07 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState stateService stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: No code Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 1685ms Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces system playlist update Jul 19 13:53:07 sunset volumio[5874]: info: Ignoring MPD Status Update Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces system playlist update Jul 19 13:53:07 sunset volumio[5874]: info: Ignoring MPD Status Update Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces state update: player Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces state update: player Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 231ms Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand play took 226 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 179ms Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand status took 188 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 187ms Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces system playlist update Jul 19 13:53:07 sunset volumio[5874]: info: Ignoring MPD Status Update Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces state update: player Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces system playlist update Jul 19 13:53:07 sunset volumio[5874]: info: Ignoring MPD Status Update Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces state update: player Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:07 sunset volumio-remote-updater[577]: [2025-07-19 13:53:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752918785 101 Jul 19 13:53:07 sunset volumio[5874]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- Client requests Volumio play at index 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPlay Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::play index 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::play index undefined Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::startPlaybackTimer Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/1 Morning/Come With Me on the Roof.mp3 Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioGetState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces state update: player Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand status took 104 milliseconds Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand status took 107 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand clearerror took 96 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand playlistinfo took 93 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 93ms Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand status took 91 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 90ms Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand status took 87 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand stop took 50 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:07 sunset sudo[6597]: root : TTY=unknown ; PWD=/root ; USER=root ; COMMAND=/usr/bin/killall -9 mpd Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 13:53:07 sunset sudo[6597]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseTrackInfo Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand clear Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":128,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Come With Me on the Roof","artist":"My Companion","album":"Come With Me On The Roof","uri":"INTERNAL/1 Morning/Come With Me on the Roof.mp3","trackType":"mp3"} Jul 19 13:53:07 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState stateService pause Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset sudo[6597]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 357ms Jul 19 13:53:07 sunset systemd[1]: mpd.service: Main process exited, code=killed, status=9/KILL Jul 19 13:53:07 sunset systemd[1]: mpd.service: Failed with result 'signal'. Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces state update: options Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand clearerror took 92 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand clearerror took 90 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand status took 83 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand playlistinfo took 74 milliseconds Jul 19 13:53:07 sunset volumio[5874]: info: sendMpdCommand playlistinfo took 74 milliseconds Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseTrackInfo Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::parseTrackInfo Jul 19 13:53:07 sunset volumio[5874]: error: MPD error: Error: write EPIPE Jul 19 13:53:07 sunset volumio[5874]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 19 13:53:07 sunset sudo[6608]: root : TTY=unknown ; PWD=/root ; USER=root ; COMMAND=/bin/systemctl restart mpd Jul 19 13:53:07 sunset sudo[6608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":128,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Come With Me on the Roof","artist":"My Companion","album":"Come With Me On The Roof","uri":"INTERNAL/1 Morning/Come With Me on the Roof.mp3","trackType":"mp3"} Jul 19 13:53:07 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState stateService pause Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::servicePushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":128,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":true,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Come With Me on the Roof","artist":"My Companion","album":"Come With Me On The Roof","uri":"INTERNAL/1 Morning/Come With Me on the Roof.mp3","trackType":"mp3"} Jul 19 13:53:07 sunset volumio[5874]: verbose: CURRENT POSITION 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState stateService pause Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::syncState currentStatus stop Jul 19 13:53:07 sunset volumio[5874]: info: CoreStateMachine::pushState Jul 19 13:53:07 sunset volumio[5874]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:07 sunset volumio[5874]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 307ms Jul 19 13:53:07 sunset volumio[5874]: info: ------------------------------ 307ms Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces state update: player Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:07 sunset volumio[5874]: error: MPD error: Error: write EPIPE Jul 19 13:53:07 sunset volumio[5874]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 19 13:53:07 sunset volumio[5874]: info: Jul 19 13:53:07 sunset volumio[5874]: ---------------------------- MPD announces state update: player Jul 19 13:53:07 sunset volumio[5874]: info: ControllerMpd::getState Jul 19 13:53:07 sunset volumio[5874]: verbose: ControllerMpd::sendMpdCommand status Jul 19 13:53:07 sunset volumio[5874]: error: MPD error: Error: write EPIPE Jul 19 13:53:07 sunset volumio[5874]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 19 13:53:07 sunset systemd[1]: Starting Music Player Daemon... Jul 19 13:53:07 sunset sudo[6610]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 13:53:07 sunset sudo[6610]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:07 sunset sudo[6610]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:09 sunset mpd[6612]: Jul 19 13:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 13:53:09 sunset systemd[1]: Started Music Player Daemon. Jul 19 13:53:09 sunset sudo[6608]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:09 sunset sudo[6616]: root : TTY=unknown ; PWD=/root ; USER=root ; COMMAND=/bin/systemctl restart volumio Jul 19 13:53:09 sunset sudo[6616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:09 sunset systemd[1]: Started dynamicswap service. Jul 19 13:53:09 sunset systemd[1]: Stopping Volumio Backend Module... Jul 19 13:53:09 sunset volumio-remote-updater[577]: [2025-07-19 13:53:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 19 13:53:09 sunset volumio-remote-updater[577]: [2025-07-19 13:53:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 19 13:53:09 sunset systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM Jul 19 13:53:09 sunset systemd[1]: volumio.service: Succeeded. Jul 19 13:53:09 sunset systemd[1]: Stopped Volumio Backend Module. Jul 19 13:53:10 sunset systemd[1]: dynamicswap.service: Succeeded. Jul 19 13:53:10 sunset systemd[1]: Started Volumio Backend Module. Jul 19 13:53:10 sunset sudo[6616]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:12 sunset cron[631]: 2025-07-19 13:53:12 1ud4Fk-0001iz-3l Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=118 egid=124 Jul 19 13:53:12 sunset cron[631]: 2025-07-19 13:53:12 1ud4Fk-0001iz-3l <= root@volumio U=root P=local S=1112 Jul 19 13:53:12 sunset cron[631]: 2025-07-19 13:53:12 1ud4Fk-0001iz-3l Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=118 egid=124 Jul 19 13:53:12 sunset cron[631]: exim: could not open panic log - aborting: see message(s) above Jul 19 13:53:12 sunset exim[6633]: 2025-07-19 13:53:12 1ud4Fk-0001iz-3l <= root@volumio U=root P=local S=1112 Jul 19 13:53:12 sunset exim[6633]: 2025-07-19 13:53:12 1ud4Fk-0001iz-3l Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=118 egid=124 Jul 19 13:53:12 sunset exim[6633]: exim: could not open panic log - aborting: see message(s) above Jul 19 13:53:12 sunset CRON[5823]: (root) MAIL (mailed 563 bytes of output but got status 0x0001 from MTA ) Jul 19 13:53:12 sunset CRON[5823]: pam_unix(cron:session): session closed for user root Jul 19 13:53:13 sunset volumio[6625]: info: ------------------------------------------- Jul 19 13:53:13 sunset volumio[6625]: info: ----- Volumio3 ---- Jul 19 13:53:13 sunset volumio[6625]: info: ------------------------------------------- Jul 19 13:53:13 sunset volumio[6625]: info: ----- System startup ---- Jul 19 13:53:13 sunset volumio[6625]: info: ------------------------------------------- Jul 19 13:53:14 sunset volumio[6625]: info: MYVOLUMIO Environment detected Jul 19 13:53:14 sunset volumio[6625]: info: Plugin folders cleanup Jul 19 13:53:14 sunset volumio[6625]: info: Scanning into folder /volumio/app/plugins/ Jul 19 13:53:14 sunset volumio[6625]: info: Scanning category audio_interface Jul 19 13:53:14 sunset volumio[6625]: info: Scanning category miscellanea Jul 19 13:53:14 sunset volumio[6625]: info: Scanning category music_service Jul 19 13:53:14 sunset volumio[6625]: info: Scanning category plugins.json Jul 19 13:53:14 sunset volumio[6625]: info: Scanning category system_controller Jul 19 13:53:14 sunset volumio[6625]: info: Scanning category user_interface Jul 19 13:53:14 sunset volumio[6625]: info: Scanning into folder /data/plugins/ Jul 19 13:53:14 sunset volumio[6625]: info: Plugin folders cleanup completed Jul 19 13:53:14 sunset volumio[6625]: info: ------------------------------------------- Jul 19 13:53:14 sunset volumio[6625]: info: ----- Core plugins startup ---- Jul 19 13:53:14 sunset volumio[6625]: info: ------------------------------------------- Jul 19 13:53:14 sunset volumio[6625]: info: Loading plugins from folder /volumio/app/plugins/ Jul 19 13:53:14 sunset volumio[6625]: info: Adding plugin upnp to MyMusic Plugins Jul 19 13:53:14 sunset volumio[6625]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 19 13:53:14 sunset volumio[6625]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 19 13:53:14 sunset volumio[6625]: info: Loading plugins from folder /data/plugins/ Jul 19 13:53:14 sunset volumio[6625]: info: Loading plugin "system"... Jul 19 13:53:14 sunset volumio[6625]: info: Loading plugin "appearance"... Jul 19 13:53:14 sunset volumio-remote-updater[577]: [2025-07-19 13:53:14] [connect] Successful connection Jul 19 13:53:16 sunset volumio[6625]: info: Loading plugin "network"... Jul 19 13:53:16 sunset volumio[6625]: info: Refreshing Cached IP Addresses Jul 19 13:53:16 sunset sudo[6652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 13:53:16 sunset sudo[6652]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:16 sunset sudo[6652]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:16 sunset sudo[6654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 13:53:16 sunset sudo[6654]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:16 sunset sudo[6654]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:16 sunset volumio[6625]: info: Loading plugin "services"... Jul 19 13:53:16 sunset volumio[6625]: info: Loading plugin "alsa_controller"... Jul 19 13:53:16 sunset sudo[6665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 19 13:53:16 sunset sudo[6665]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:16 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 13:53:16 sunset volumio[6625]: info: Loading plugin "wizard"... Jul 19 13:53:16 sunset volumio[6625]: info: Loading plugin "networkfs"... Jul 19 13:53:16 sunset volumio[6625]: info: Starting Udev Watcher for removable devices Jul 19 13:53:16 sunset volumio[6625]: info: Ignoring mount for partition: boot Jul 19 13:53:16 sunset volumio[6625]: info: Ignoring mount for partition: volumio Jul 19 13:53:16 sunset volumio[6625]: info: Ignoring mount for partition: volumio_data Jul 19 13:53:16 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 13:53:16 sunset volumio[6625]: info: Loading plugin "volumio_command_line_client"... Jul 19 13:53:16 sunset volumio[6625]: info: Loading plugin "upnp"... Jul 19 13:53:16 sunset volumio[6625]: info: [1752918796664] Starting Upmpd Daemon Jul 19 13:53:16 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 13:53:16 sunset volumio[6625]: info: Loading plugin "my_music"... Jul 19 13:53:16 sunset volumio[6625]: info: Loading plugin "mpd"... Jul 19 13:53:17 sunset sudo[6665]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "upnp_browser"... Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "alarm-clock"... Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "airplay_emulation"... Jul 19 13:53:17 sunset volumio[6625]: info: Starting Shairport Sync Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "last_100"... Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "webradio"... Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "i2s_dacs"... Jul 19 13:53:17 sunset volumio[6625]: info: I2S DAC not set, start Auto-detection Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "volumiodiscovery"... Jul 19 13:53:17 sunset volumio[6625]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 19 13:53:17 sunset volumio[6625]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 13:53:17 sunset volumio[6625]: *** WARNING *** For more information see Jul 19 13:53:17 sunset node[6625]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 19 13:53:17 sunset volumio[6625]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 19 13:53:17 sunset volumio[6625]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 13:53:17 sunset volumio[6625]: *** WARNING *** For more information see Jul 19 13:53:17 sunset node[6625]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 13:53:17 sunset node[6625]: *** WARNING *** For more information see Jul 19 13:53:17 sunset node[6625]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 19 13:53:17 sunset node[6625]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 13:53:17 sunset node[6625]: *** WARNING *** For more information see Jul 19 13:53:17 sunset volumio[6625]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 19 13:53:17 sunset volumio[6625]: info: Discovery: Started advertising with name: Sunset Jul 19 13:53:17 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "outputs"... Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "albumart"... Jul 19 13:53:17 sunset volumio[6625]: info: Plugin example_plugin is not enabled Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "inputs"... Jul 19 13:53:17 sunset volumio[6625]: info: Loading plugin "updater_comm"... Jul 19 13:53:18 sunset volumio[6625]: info: Plugin mpdemulation is not enabled Jul 19 13:53:18 sunset volumio[6625]: info: Loading plugin "rest_api"... Jul 19 13:53:18 sunset volumio[6625]: info: Loading plugin "websocket"... Jul 19 13:53:18 sunset volumio[6625]: info: Starting Socket.io Server version 2.3.0 Jul 19 13:53:18 sunset volumio[6625]: info: Loading i18n strings for locale en Jul 19 13:53:18 sunset volumio[6625]: Updating browse sources language Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:53:18 sunset volumio[6625]: Forking 3 albumart workers Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::initPlayerControls Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 13:53:18 sunset volumio[6625]: Express server listening on port 3000 Jul 19 13:53:18 sunset volumio[6625]: [Metrics] WebUI: 6s 658.46ms Jul 19 13:53:18 sunset volumio[6625]: info: CoreStateMachine::resetVolumioState Jul 19 13:53:18 sunset volumio[6625]: info: CoreStateMachine::getcurrentVolume Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 13:53:18 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:53:19 sunset volumio[6625]: info: Volumio Network Manager: Network status updated: 1 Jul 19 13:53:19 sunset volumio[6625]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 19 13:53:19 sunset volumio[6625]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jul 19 13:53:19 sunset volumio-remote-updater[577]: [2025-07-19 13:53:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752918794 101 Jul 19 13:53:19 sunset volumio[6625]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Jul 19 13:53:19 sunset volumio[6625]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 19 13:53:19 sunset volumio[6625]: verbose: New Socket.io Connection to 192.168.2.125 from 192.168.2.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/103.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 19 13:53:19 sunset volumio[6625]: info: Reloading queue from file Jul 19 13:53:19 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 19 13:53:19 sunset volumio[6625]: info: CoreCommandRouter::volumioGetState Jul 19 13:53:19 sunset volumio[6625]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:19 sunset volumio[6625]: info: CoreCommandRouter::volumioGetVisibleSources Jul 19 13:53:19 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:53:19 sunset volumio[6625]: info: VolumeController:: Volume=0 Mute =false Jul 19 13:53:19 sunset volumio[6625]: info: CoreStateMachine::pushState Jul 19 13:53:19 sunset volumio[6625]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:19 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 13:53:19 sunset volumio[6625]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:19 sunset volumio[6625]: info: CoreStateMachine::updateTrackBlock Jul 19 13:53:19 sunset volumio[6625]: info: CorePlayQueue::getTrackBlock Jul 19 13:53:19 sunset volumio[6625]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 13:53:19 sunset volumio[6625]: info: CoreStateMachine::setRepeat null single undefined Jul 19 13:53:19 sunset volumio[6625]: info: CoreStateMachine::pushState Jul 19 13:53:19 sunset volumio[6625]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:19 sunset volumio[6625]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:19 sunset volumio[6625]: info: CoreStateMachine::setRandom null Jul 19 13:53:19 sunset volumio[6625]: info: CoreStateMachine::pushState Jul 19 13:53:19 sunset volumio[6625]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:19 sunset volumio[6625]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:19 sunset volumio[6625]: info: Setting Device type: Raspberry PI Jul 19 13:53:20 sunset volumio[6625]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 19 13:53:20 sunset volumio[6625]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jul 19 13:53:20 sunset volumio[6625]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 19 13:53:20 sunset volumio[6625]: info: VolumeController:: Volume=0 Mute =false Jul 19 13:53:20 sunset volumio[6625]: info: CoreStateMachine::pushState Jul 19 13:53:20 sunset volumio[6625]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:20 sunset volumio[6625]: info: CoreCommandRouter::volumioPushState Jul 19 13:53:20 sunset volumio[6625]: info: Completed loading Core Plugins Jul 19 13:53:20 sunset volumio[6625]: info: Preparing to generate the ALSA configuration file Jul 19 13:53:20 sunset volumio[6625]: Starting albumart workers Jul 19 13:53:20 sunset volumio[6625]: info: Asound.conf file unchanged, so no further update is needed Jul 19 13:53:20 sunset volumio[6625]: info: Output device has changed, restarting MPD Jul 19 13:53:20 sunset volumio[6625]: Starting albumart workers Jul 19 13:53:20 sunset volumio[6625]: info: Output device has changed, restarting Shairport Sync Jul 19 13:53:20 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:20 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:53:20 sunset sudo[6723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 13:53:20 sunset sudo[6723]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:20 sunset sudo[6723]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:20 sunset sudo[6725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 13:53:20 sunset sudo[6725]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:20 sunset volumio[6625]: Starting albumart workers Jul 19 13:53:20 sunset systemd[1]: Stopping Music Player Daemon... Jul 19 13:53:20 sunset systemd[1]: mpd.service: Succeeded. Jul 19 13:53:20 sunset systemd[1]: Stopped Music Player Daemon. Jul 19 13:53:20 sunset volumio[6625]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 13:53:20 sunset volumio[6625]: info: ___________ START PLUGINS ___________ Jul 19 13:53:20 sunset volumio[6625]: info: ControllerMpd::onStart: Initializing MPD Jul 19 13:53:20 sunset volumio[6625]: info: Creating MPD Configuration file Jul 19 13:53:20 sunset systemd[1]: Starting Music Player Daemon... Jul 19 13:53:20 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 13:53:20 sunset sudo[6733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 13:53:20 sunset sudo[6733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:20 sunset volumio[6625]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 13:53:20 sunset volumio[6625]: info: [1752918800886] CoreMusicLibrary::Adding element Media Servers Jul 19 13:53:20 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:53:20 sunset sudo[6733]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:20 sunset sudo[6736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 13:53:20 sunset sudo[6736]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:53:21 sunset systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 19 13:53:21 sunset systemd[1]: mpd.service: Succeeded. Jul 19 13:53:21 sunset systemd[1]: Stopped Music Player Daemon. Jul 19 13:53:21 sunset systemd[1]: Starting Music Player Daemon... Jul 19 13:53:21 sunset volumio[6625]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:53:21 sunset volumio[6625]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 13:53:21 sunset volumio[6625]: info: [1752918801288] CoreMusicLibrary::Adding element Last_100 Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 13:53:21 sunset volumio[6625]: info: [1752918801295] CoreMusicLibrary::Adding element Webradio Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 13:53:21 sunset volumio[6625]: info: Initializing BBC Radios Jul 19 13:53:21 sunset sudo[6740]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 13:53:21 sunset sudo[6740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:21 sunset sudo[6740]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 13:53:21 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:53:21 sunset volumio[6625]: info: Volumio Calling Home Jul 19 13:53:22 sunset volumio[6625]: info: MPD Permissions set Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 13:53:22 sunset volumio[6625]: info: Starting Shairport Sync Jul 19 13:53:22 sunset volumio[6625]: info: Starting Shairport Sync Jul 19 13:53:22 sunset volumio[6625]: info: Starting Shairport Sync Jul 19 13:53:22 sunset sudo[6795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 13:53:22 sunset sudo[6795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:22 sunset sudo[6797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 13:53:22 sunset sudo[6797]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:22 sunset sudo[6800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 13:53:22 sunset sudo[6800]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 13:53:22 sunset systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 13:53:22 sunset systemd[1]: shairport-sync.service: Succeeded. Jul 19 13:53:22 sunset systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 13:53:22 sunset systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 13:53:22 sunset sudo[6795]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:22 sunset sudo[6797]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:22 sunset sudo[6800]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:22 sunset volumio[6625]: info: Shairport-Sync Started Jul 19 13:53:22 sunset volumio[6625]: Error adding Membership: Error: addMembership EINVAL Jul 19 13:53:22 sunset volumio[6625]: info: Shairport-Sync Started Jul 19 13:53:23 sunset volumio[6625]: info: Volumio called home Jul 19 13:53:23 sunset volumio[6625]: info: Discovery: adding ecc65555-93c5-4407-8ada-442f42def875 Jul 19 13:53:23 sunset volumio[6625]: info: Discovery: Found device Sunset Jul 19 13:53:23 sunset volumio[6625]: info: CoreCommandRouter::volumioGetState Jul 19 13:53:23 sunset volumio[6625]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:23 sunset volumio[6625]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 19 13:53:23 sunset volumio[6625]: info: CoreCommandRouter::volumioGetState Jul 19 13:53:23 sunset volumio[6625]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:24 sunset volumio[6625]: info: Discovery: this is already registered, ecc65555-93c5-4407-8ada-442f42def875 Jul 19 13:53:24 sunset volumio[6625]: info: Discovery: Found device Sunset Jul 19 13:53:24 sunset volumio[6625]: info: CoreCommandRouter::volumioGetState Jul 19 13:53:24 sunset volumio[6625]: info: CorePlayQueue::getTrack 0 Jul 19 13:53:24 sunset mpd[6750]: Jul 19 13:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 13:53:24 sunset systemd[1]: Started Music Player Daemon. Jul 19 13:53:24 sunset sudo[6725]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:24 sunset sudo[6736]: pam_unix(sudo:session): session closed for user root Jul 19 13:53:24 sunset volumio[6625]: info: Completed starting Core Plugins Jul 19 13:53:24 sunset volumio[6625]: info: ------------------------------------------- Jul 19 13:53:24 sunset volumio[6625]: info: ----- MyVolumio plugins startup ---- Jul 19 13:53:24 sunset volumio[6625]: info: ------------------------------------------- Jul 19 13:53:24 sunset volumio[6625]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 19 13:53:24 sunset volumio[6625]: error: MPD error: The expression evaluated to a falsy value: Jul 19 13:53:24 sunset volumio[6625]: assert.ok(self.idling) Jul 19 13:53:24 sunset volumio[6625]: error: The expression evaluated to a falsy value: Jul 19 13:53:24 sunset volumio[6625]: assert.ok(self.idling) Jul 19 13:53:24 sunset volumio[6625]: error: updateQueue error: null Jul 19 13:53:24 sunset volumio[6625]: info: MPD running with PID6750 Jul 19 13:53:24 sunset volumio[6625]: ,establishing connection Jul 19 13:53:24 sunset volumio[6625]: error: updateQueue error: null Jul 19 13:53:26 sunset volumio[6625]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 13:53:26 sunset volumio[6625]: Error [ERR_TLS_CERT_ALTNAME_INVALID]: Hostname/IP does not match certificate's altnames: Host: lastfm.freetls.fastly.net. is not in the cert's altnames: DNS:retail.soundyoucanfeel.me Jul 19 13:53:26 sunset volumio[6625]: at Object.checkServerIdentity (tls.js:297:12) Jul 19 13:53:26 sunset volumio[6625]: at TLSSocket.onConnectSecure (_tls_wrap.js:1507:27) Jul 19 13:53:26 sunset volumio[6625]: at TLSSocket.emit (events.js:315:20) Jul 19 13:53:26 sunset volumio[6625]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jul 19 13:53:26 sunset volumio[6625]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jul 19 13:53:26 sunset volumio[6625]: reason: "Host: lastfm.freetls.fastly.net. is not in the cert's altnames: DNS:retail.soundyoucanfeel.me", Jul 19 13:53:26 sunset volumio[6625]: host: 'lastfm.freetls.fastly.net', Jul 19 13:53:26 sunset volumio[6625]: cert: { Jul 19 13:53:26 sunset volumio[6625]: subject: [Object: null prototype] { CN: 'retail.soundyoucanfeel.me' }, Jul 19 13:53:26 sunset volumio[6625]: issuer: [Object: null prototype] { C: 'US', O: "Let's Encrypt", CN: 'E6' }, Jul 19 13:53:26 sunset volumio[6625]: subjectaltname: 'DNS:retail.soundyoucanfeel.me', Jul 19 13:53:26 sunset volumio[6625]: infoAccess: [Object: null prototype] { Jul 19 13:53:26 sunset volumio[6625]: 'OCSP - URI': [Array], Jul 19 13:53:26 sunset volumio[6625]: 'CA Issuers - URI': [Array] Jul 19 13:53:26 sunset volumio[6625]: }, Jul 19 13:53:26 sunset volumio[6625]: bits: 384, Jul 19 13:53:26 sunset volumio[6625]: pubkey: , Jul 19 13:53:26 sunset volumio[6625]: asn1Curve: 'secp384r1', Jul 19 13:53:26 sunset volumio[6625]: nistCurve: 'P-384', Jul 19 13:53:26 sunset volumio[6625]: valid_from: 'Apr 28 03:36:45 2025 GMT', Jul 19 13:53:26 sunset volumio[6625]: valid_to: 'Jul 27 03:36:44 2025 GMT', Jul 19 13:53:26 sunset volumio[6625]: fingerprint: '2C:20:BE:9A:92:8E:B4:98:EB:F5:6A:8C:E4:D8:8C:F4:2F:F5:12:2C', Jul 19 13:53:26 sunset volumio[6625]: fingerprint256: '3B:B8:8D:75:E4:6D:1F:40:E8:92:34:C3:C6:B6:AC:BB:25:4B:C7:29:AD:F6:D0:D0:F4:F5:1E:1D:EE:11:9F:C0', Jul 19 13:53:26 sunset volumio[6625]: ext_key_usage: [ '1.3.6.1.5.5.7.3.1', '1.3.6.1.5.5.7.3.2' ], Jul 19 13:53:26 sunset volumio[6625]: serialNumber: '057C433BC5FCC264600F5E0C05A8F648F712', Jul 19 13:53:26 sunset volumio[6625]: raw: , Jul 19 13:53:26 sunset volumio[6625]: issuerCertificate: { Jul 19 13:53:26 sunset volumio[6625]: subject: [Object: null prototype], Jul 19 13:53:26 sunset volumio[6625]: issuer: [Object: null prototype], Jul 19 13:53:26 sunset volumio[6625]: infoAccess: [Object: null prototype], Jul 19 13:53:26 sunset volumio[6625]: bits: 384, Jul 19 13:53:26 sunset volumio[6625]: pubkey: , Jul 19 13:53:26 sunset volumio[6625]: asn1Curve: 'secp384r1', Jul 19 13:53:26 sunset volumio[6625]: nistCurve: 'P-384', Jul 19 13:53:26 sunset volumio[6625]: valid_from: 'Mar 13 00:00:00 2024 GMT', Jul 19 13:53:26 sunset volumio[6625]: valid_to: 'Mar 12 23:59:59 2027 GMT', Jul 19 13:53:26 sunset volumio[6625]: fingerprint: 'C9:4D:C4:83:1A:90:1A:9F:EC:0F:B4:9B:71:BD:49:B5:AA:D4:FA:D0', Jul 19 13:53:26 sunset volumio[6625]: fingerprint256: '76:E9:E2:88:AA:FC:0E:37:F4:39:0C:BF:94:6A:AD:99:7D:5C:1C:90:1B:3C:E5:13:D3:D8:FA:DB:AB:E2:AB:85', Jul 19 13:53:26 sunset volumio[6625]: ext_key_usage: [Array], Jul 19 13:53:26 sunset volumio[6625]: serialNumber: 'B0573E9173972770DBB487CB3A452B38', Jul 19 13:53:26 sunset volumio[6625]: raw: , Jul 19 13:53:26 sunset volumio[6625]: issuerCertificate: [Object] Jul 19 13:53:26 sunset volumio[6625]: } Jul 19 13:53:26 sunset volumio[6625]: }, Jul 19 13:53:26 sunset volumio[6625]: code: 'ERR_TLS_CERT_ALTNAME_INVALID' Jul 19 13:53:26 sunset volumio[6625]: } Jul 19 13:53:26 sunset volumio[6625]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 13:53:27 sunset sudo[6833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-19 13:52 Jul 19 13:53:27 sunset sudo[6833]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"