-- Logs begin at Thu 2025-05-29 06:18:20 BST, end at Fri 2025-05-30 17:24:21 BST. -- May 30 17:23:04 parasound volumio[8651]: info: CoreCommandRouter::volumioGetState May 30 17:23:04 parasound volumio[8651]: info: CorePlayQueue::getTrack 0 May 30 17:23:06 parasound sshd[17546]: Accepted password for volumio from 10.10.3.124 port 53929 ssh2 May 30 17:23:06 parasound sshd[17546]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 30 17:23:06 parasound systemd-logind[656]: New session 23 of user volumio. May 30 17:23:06 parasound systemd[1]: Started Session 23 of user volumio. May 30 17:23:09 parasound volumio[8651]: info: Listing playlists May 30 17:23:09 parasound volumio[8651]: info: Listing playlists May 30 17:23:14 parasound sshd[17544]: Accepted password for volumio from 10.10.3.124 port 53928 ssh2 May 30 17:23:14 parasound sshd[17544]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 30 17:23:14 parasound systemd-logind[656]: New session 24 of user volumio. May 30 17:23:14 parasound systemd[1]: Started Session 24 of user volumio. May 30 17:23:24 parasound volumio[8651]: info: CoreCommandRouter::volumioGetState May 30 17:23:24 parasound volumio[8651]: info: CorePlayQueue::getTrack 0 May 30 17:23:24 parasound sudo[17606]: volumio : TTY=pts/3 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl stop volumio.service May 30 17:23:24 parasound sudo[17606]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) May 30 17:23:24 parasound systemd[1]: Stopping Volumio Backend Module... May 30 17:23:25 parasound systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM May 30 17:23:25 parasound systemd[1]: volumio.service: Succeeded. May 30 17:23:25 parasound go-librespot[8861]: time="2025-05-30T17:23:25+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" May 30 17:23:25 parasound systemd[1]: Stopped Volumio Backend Module. May 30 17:23:25 parasound sudo[17606]: pam_unix(sudo:session): session closed for user root May 30 17:23:25 parasound volumio-remote-updater[672]: [2025-05-30 17:23:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 30 17:23:25 parasound volumio-remote-updater[672]: [2025-05-30 17:23:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 30 17:23:25 parasound sudo[17609]: volumio : TTY=pts/3 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl start volumio.service May 30 17:23:25 parasound sudo[17609]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) May 30 17:23:25 parasound systemd[1]: Started dynamicswap service. May 30 17:23:25 parasound systemd[1]: Started Volumio Backend Module. May 30 17:23:25 parasound sudo[17609]: pam_unix(sudo:session): session closed for user root May 30 17:23:25 parasound systemd[1]: dynamicswap.service: Succeeded. May 30 17:23:25 parasound kernel: hwmon hwmon1: Undervoltage detected! May 30 17:23:29 parasound volumio[17616]: info: ------------------------------------------- May 30 17:23:29 parasound volumio[17616]: info: ----- Volumio3 ---- May 30 17:23:29 parasound volumio[17616]: info: ------------------------------------------- May 30 17:23:29 parasound volumio[17616]: info: ----- System startup ---- May 30 17:23:29 parasound volumio[17616]: info: ------------------------------------------- May 30 17:23:30 parasound volumio-remote-updater[672]: [2025-05-30 17:23:30] [connect] Successful connection May 30 17:23:30 parasound volumio[17616]: info: MYVOLUMIO Environment detected May 30 17:23:30 parasound volumio[17616]: info: Plugin folders cleanup May 30 17:23:30 parasound volumio[17616]: info: Scanning into folder /volumio/app/plugins/ May 30 17:23:30 parasound volumio[17616]: info: Scanning category audio_interface May 30 17:23:30 parasound volumio[17616]: info: Scanning category miscellanea May 30 17:23:30 parasound volumio[17616]: info: Scanning category music_service May 30 17:23:30 parasound volumio[17616]: info: Scanning category plugins.json May 30 17:23:30 parasound volumio[17616]: info: Scanning category system_controller May 30 17:23:30 parasound volumio[17616]: info: Scanning category user_interface May 30 17:23:30 parasound volumio[17616]: info: Scanning into folder /data/plugins/ May 30 17:23:30 parasound volumio[17616]: info: Scanning category miscellanea May 30 17:23:30 parasound volumio[17616]: info: Scanning category music_service May 30 17:23:30 parasound volumio[17616]: info: Scanning category user_interface May 30 17:23:30 parasound volumio[17616]: info: Plugin folders cleanup completed May 30 17:23:30 parasound volumio[17616]: info: ------------------------------------------- May 30 17:23:30 parasound volumio[17616]: info: ----- Core plugins startup ---- May 30 17:23:30 parasound volumio[17616]: info: ------------------------------------------- May 30 17:23:30 parasound volumio[17616]: info: Loading plugins from folder /volumio/app/plugins/ May 30 17:23:30 parasound volumio[17616]: info: Adding plugin upnp to MyMusic Plugins May 30 17:23:30 parasound volumio[17616]: info: Adding plugin airplay_emulation to MyMusic Plugins May 30 17:23:30 parasound volumio[17616]: info: Adding plugin upnp_browser to MyMusic Plugins May 30 17:23:30 parasound volumio[17616]: info: Loading plugins from folder /data/plugins/ May 30 17:23:30 parasound volumio[17616]: info: Loading plugin "system"... May 30 17:23:30 parasound volumio[17616]: info: Loading plugin "ampstatusled"... May 30 17:23:31 parasound volumio[17616]: info: Applying required configuration parameters for plugin ampstatusled May 30 17:23:31 parasound volumio[17616]: info: Loading plugin "planetrock_radio"... May 30 17:23:31 parasound kernel: hwmon hwmon1: Voltage normalised May 30 17:23:32 parasound volumio[17616]: info: ControllerPlanetRockRadio::constructor May 30 17:23:32 parasound volumio[17616]: info: Loading plugin "appearance"... May 30 17:23:33 parasound volumio[17616]: info: Loading plugin "network"... May 30 17:23:33 parasound volumio[17616]: info: Refreshing Cached IP Addresses May 30 17:23:33 parasound sudo[17704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 17:23:33 parasound sudo[17704]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:34 parasound sudo[17706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 17:23:34 parasound sudo[17704]: pam_unix(sudo:session): session closed for user root May 30 17:23:34 parasound sudo[17706]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:34 parasound sudo[17706]: pam_unix(sudo:session): session closed for user root May 30 17:23:34 parasound volumio[17616]: info: Loading plugin "services"... May 30 17:23:34 parasound volumio[17616]: info: Loading plugin "alsa_controller"... May 30 17:23:34 parasound sudo[17715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 30 17:23:34 parasound sudo[17715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:34 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 17:23:34 parasound volumio[17616]: info: Loading plugin "wizard"... May 30 17:23:34 parasound volumio[17616]: info: Loading plugin "networkfs"... May 30 17:23:34 parasound volumio[17616]: info: Starting Udev Watcher for removable devices May 30 17:23:34 parasound volumio[17616]: info: Ignoring mount for partition: boot May 30 17:23:34 parasound volumio[17616]: info: Ignoring mount for partition: volumio May 30 17:23:34 parasound volumio[17616]: info: Ignoring mount for partition: volumio_data May 30 17:23:34 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 17:23:34 parasound volumio[17616]: info: Loading plugin "volumio_command_line_client"... May 30 17:23:34 parasound volumio[17616]: info: Loading plugin "upnp"... May 30 17:23:34 parasound volumio[17616]: info: [1748622214462] Starting Upmpd Daemon May 30 17:23:34 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 17:23:34 parasound volumio[17616]: info: Loading plugin "my_music"... May 30 17:23:34 parasound volumio[17616]: info: Loading plugin "mpd"... May 30 17:23:35 parasound volumio[17616]: info: Loading plugin "upnp_browser"... May 30 17:23:35 parasound kernel: hwmon hwmon1: Undervoltage detected! May 30 17:23:36 parasound volumio[17616]: info: Loading plugin "alarm-clock"... May 30 17:23:36 parasound volumio[17616]: info: Loading plugin "airplay_emulation"... May 30 17:23:36 parasound volumio[17616]: info: Starting Shairport Sync May 30 17:23:36 parasound volumio[17616]: info: Loading plugin "last_100"... May 30 17:23:36 parasound volumio[17616]: info: Loading plugin "webradio"... May 30 17:23:37 parasound volumio[17616]: info: Loading plugin "i2s_dacs"... May 30 17:23:37 parasound volumio[17616]: info: Loading plugin "volumiodiscovery"... May 30 17:23:37 parasound volumio[17616]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 30 17:23:37 parasound volumio[17616]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 17:23:37 parasound node[17616]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 30 17:23:37 parasound volumio[17616]: *** WARNING *** For more information see May 30 17:23:37 parasound volumio[17616]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 30 17:23:37 parasound volumio[17616]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 17:23:37 parasound volumio[17616]: *** WARNING *** For more information see May 30 17:23:37 parasound node[17616]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 17:23:37 parasound node[17616]: *** WARNING *** For more information see May 30 17:23:37 parasound node[17616]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 30 17:23:37 parasound node[17616]: *** WARNING *** Please fix your application to use the native API of Avahi! May 30 17:23:37 parasound node[17616]: *** WARNING *** For more information see May 30 17:23:37 parasound volumio[17616]: info: Applying required configuration parameters for plugin volumiodiscovery May 30 17:23:37 parasound volumio[17616]: info: Discovery: Started advertising with name: parasound May 30 17:23:37 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 17:23:37 parasound volumio[17616]: info: Loading plugin "spop"... May 30 17:23:37 parasound sudo[17715]: pam_unix(sudo:session): session closed for user root May 30 17:23:40 parasound volumio[17616]: info: Loading plugin "now_playing"... May 30 17:23:41 parasound volumio[17616]: info: Loading plugin "outputs"... May 30 17:23:41 parasound volumio[17616]: info: Loading plugin "albumart"... May 30 17:23:42 parasound volumio[17616]: info: Plugin example_plugin is not enabled May 30 17:23:42 parasound volumio[17616]: info: Loading plugin "inputs"... May 30 17:23:42 parasound volumio[17616]: info: Loading plugin "updater_comm"... May 30 17:23:42 parasound volumio[17616]: info: Plugin mpdemulation is not enabled May 30 17:23:42 parasound volumio[17616]: info: Loading plugin "rest_api"... May 30 17:23:42 parasound volumio[17616]: info: Loading plugin "websocket"... May 30 17:23:42 parasound volumio[17616]: info: Starting Socket.io Server version 2.3.0 May 30 17:23:42 parasound volumio[17616]: info: Loading plugin "ampswitch_serial"... May 30 17:23:42 parasound volumio-remote-updater[672]: [2025-05-30 17:23:42] [connect] Successful connection May 30 17:23:42 parasound volumio[17616]: Forking 3 albumart workers May 30 17:23:43 parasound volumio[17616]: info: Applying required configuration parameters for plugin ampswitch_serial May 30 17:23:44 parasound volumio[17616]: info: Loading plugin "screen_switch"... May 30 17:23:45 parasound volumio[17616]: info: Applying required configuration parameters for plugin screen_switch May 30 17:23:45 parasound volumio[17616]: Starting albumart workers May 30 17:23:45 parasound volumio[17616]: Starting albumart workers May 30 17:23:45 parasound volumio[17616]: info: Loading plugin "touch_display"... May 30 17:23:46 parasound volumio[17616]: Starting albumart workers May 30 17:23:47 parasound volumio[17616]: info: Applying required configuration parameters for plugin touch_display May 30 17:23:48 parasound volumio[17616]: info: Loading i18n strings for locale en May 30 17:23:48 parasound volumio[17616]: Updating browse sources language May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::initPlayerControls May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 17:23:48 parasound volumio[17616]: Express server listening on port 3000 May 30 17:23:48 parasound volumio[17616]: [Metrics] WebUI: 20s 277.68ms May 30 17:23:48 parasound volumio[17616]: info: CoreStateMachine::resetVolumioState May 30 17:23:48 parasound volumio[17616]: info: CoreStateMachine::getcurrentVolume May 30 17:23:48 parasound volumio[17616]: info: CoreCommandRouter::volumioRetrievevolume May 30 17:23:48 parasound sudo[17806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 17:23:48 parasound sudo[17806]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:49 parasound sudo[17806]: pam_unix(sudo:session): session closed for user root May 30 17:23:49 parasound sudo[17808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 17:23:49 parasound sudo[17808]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:49 parasound sudo[17808]: pam_unix(sudo:session): session closed for user root May 30 17:23:49 parasound volumio[17616]: info: Volumio Network Manager: Network status updated: 2 May 30 17:23:49 parasound volumio[17616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 30 17:23:49 parasound volumio[17616]: verbose: New Socket.io Connection to 10.10.14.230 from 10.10.3.124 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 May 30 17:23:49 parasound volumio[17616]: verbose: New Socket.io Connection to 10.10.14.230 from 10.10.3.124 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 May 30 17:23:49 parasound volumio[17616]: verbose: New Socket.io Connection to 10.10.14.230 from 10.10.3.124 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:49 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:49 parasound volumio[17616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 30 17:23:49 parasound volumio[17616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 30 17:23:49 parasound volumio[17616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 30 17:23:49 parasound volumio[17616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 30 17:23:49 parasound volumio[17616]: info: VolumeController:: Volume=100 Mute =false May 30 17:23:49 parasound volumio[17616]: info: CoreStateMachine::pushState May 30 17:23:49 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioPushState May 30 17:23:49 parasound volumio[17616]: info: CoreStateMachine::updateTrackBlock May 30 17:23:49 parasound volumio[17616]: info: CorePlayQueue::getTrackBlock May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioRetrievevolume May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:23:49 parasound volumio[17616]: info: Reloading queue from file May 30 17:23:49 parasound volumio[17616]: info: CoreStateMachine::setRepeat false single undefined May 30 17:23:49 parasound volumio[17616]: info: CoreStateMachine::pushState May 30 17:23:49 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioPushState May 30 17:23:49 parasound volumio[17616]: info: CoreStateMachine::setRandom undefined May 30 17:23:49 parasound volumio[17616]: info: CoreStateMachine::pushState May 30 17:23:49 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioPushState May 30 17:23:49 parasound volumio[17616]: info: Setting Device type: Raspberry PI May 30 17:23:49 parasound volumio[17616]: verbose: New Socket.io Connection to 10.10.14.230 from 10.10.3.124 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 May 30 17:23:49 parasound volumio[17616]: info: Completed loading Core Plugins May 30 17:23:49 parasound volumio[17616]: info: Preparing to generate the ALSA configuration file May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 17:23:49 parasound sudo[17821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 30 17:23:49 parasound sudo[17821]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:49 parasound volumio[17616]: info: VolumeController:: Volume=100 Mute =false May 30 17:23:49 parasound volumio[17616]: info: CoreStateMachine::pushState May 30 17:23:49 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioPushState May 30 17:23:49 parasound sudo[17821]: pam_unix(sudo:session): session closed for user root May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioGetVisibleSources May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:49 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioGetQueue May 30 17:23:49 parasound volumio[17616]: info: CoreStateMachine::getQueue May 30 17:23:49 parasound volumio[17616]: info: CorePlayQueue::getQueue May 30 17:23:49 parasound volumio[17616]: info: Listing playlists May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 17:23:49 parasound volumio[17616]: info: Received Get System Info May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 17:23:49 parasound volumio[17616]: info: Discovery: Getting this device information May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:49 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 17:23:49 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:49 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:50 parasound volumio[17616]: info: Asound.conf file unchanged, so no further update is needed May 30 17:23:50 parasound volumio[17616]: info: Output device has changed, restarting MPD May 30 17:23:50 parasound sudo[17824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 17:23:50 parasound volumio[17616]: info: Output device has changed, restarting Shairport Sync May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:23:50 parasound sudo[17824]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:50 parasound sudo[17824]: pam_unix(sudo:session): session closed for user root May 30 17:23:50 parasound sudo[17826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 17:23:50 parasound sudo[17826]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:50 parasound systemd[1]: Stopping Music Player Daemon... May 30 17:23:50 parasound volumio[17616]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 17:23:50 parasound volumio[17616]: info: ___________ START PLUGINS ___________ May 30 17:23:50 parasound volumio[17616]: info: [AmpStatusLED] Togle GPIO LED: Ready May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 17:23:50 parasound volumio[17616]: info: [1748622230343] CoreMusicLibrary::Adding element Planet Rock May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 17:23:50 parasound volumio[17616]: Cannot find translation for source Planet Rock May 30 17:23:50 parasound volumio[17616]: info: ControllerMpd::onStart: Initializing MPD May 30 17:23:50 parasound volumio[17616]: info: Creating MPD Configuration file May 30 17:23:50 parasound sudo[17834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 17:23:50 parasound sudo[17834]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:50 parasound sudo[17834]: pam_unix(sudo:session): session closed for user root May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 17:23:50 parasound volumio[17616]: info: [1748622230471] CoreMusicLibrary::Adding element Media Servers May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 17:23:50 parasound volumio[17616]: Cannot find translation for source Planet Rock May 30 17:23:50 parasound systemd[1]: mpd.service: Succeeded. May 30 17:23:50 parasound systemd[1]: Stopped Music Player Daemon. May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:50 parasound sudo[17837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 17:23:50 parasound sudo[17837]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:23:50 parasound systemd[1]: Starting Music Player Daemon... May 30 17:23:50 parasound systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 30 17:23:50 parasound systemd[1]: mpd.service: Succeeded. May 30 17:23:50 parasound systemd[1]: Stopped Music Player Daemon. May 30 17:23:50 parasound volumio[17616]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:23:50 parasound systemd[1]: Starting Music Player Daemon... May 30 17:23:50 parasound volumio[17616]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 17:23:50 parasound volumio[17616]: info: [1748622230835] CoreMusicLibrary::Adding element Last_100 May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 17:23:50 parasound volumio[17616]: Cannot find translation for source Planet Rock May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 30 17:23:50 parasound volumio[17616]: info: [1748622230843] CoreMusicLibrary::Adding element Webradio May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 17:23:50 parasound volumio[17616]: Cannot find translation for source Planet Rock May 30 17:23:50 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 17:23:50 parasound volumio[17616]: info: Initializing BBC Radios May 30 17:23:50 parasound sudo[17843]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 17:23:50 parasound sudo[17843]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:51 parasound sudo[17843]: pam_unix(sudo:session): session closed for user root May 30 17:23:51 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 17:23:51 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:23:51 parasound volumio[17616]: info: Creating Spotify config file May 30 17:23:51 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:51 parasound volumio[17616]: info: [now-playing-config] Config is up to date. May 30 17:23:51 parasound volumio[17616]: info: [now-playing-weather] Fetching API key... May 30 17:23:51 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 17:23:51 parasound volumio[17616]: info: Discovery: Getting this device information May 30 17:23:51 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:51 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:51 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 17:23:51 parasound volumio[17616]: info: Loading i18n strings for locale en May 30 17:23:51 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 17:23:51 parasound volumio[17616]: info: Volumio Calling Home May 30 17:23:51 parasound volumio[17616]: info: [now-playing-app] App is listening on port 4004. May 30 17:23:51 parasound sudo[17863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 30 17:23:51 parasound sudo[17863]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:51 parasound volumio[17616]: info: Discovery: adding 25997584-b6ae-4035-8a02-28b4d26b9025 May 30 17:23:51 parasound volumio[17616]: info: Discovery: Found device parasound May 30 17:23:51 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:51 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:52 parasound systemd[1]: Reloading. May 30 17:23:52 parasound volumio[17616]: info: Upmpdcli Daemon Started May 30 17:23:52 parasound volumio[17616]: info: [ScreenSwitchDebug] Initialized serial May 30 17:23:52 parasound volumio[17616]: info: MPD Permissions set May 30 17:23:52 parasound volumio[17616]: info: Spotify config file written May 30 17:23:52 parasound sudo[17880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 30 17:23:52 parasound sudo[17880]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:52 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:52 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:52 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:52 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:52 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:52 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:52 parasound volumio[17616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 30 17:23:53 parasound volumio[17616]: info: [AmpStatusLED] CurState: stop PrevState: na May 30 17:23:53 parasound volumio[17616]: info: [AmpStatusLED] Togle GPIO LED: Ready May 30 17:23:53 parasound volumio[17616]: info: [AmpStatusLED] CurState: stop PrevState: stop May 30 17:23:53 parasound volumio[17616]: info: [ScreenSwitchDebug] CurState: stop PrevState: na May 30 17:23:53 parasound volumio[17616]: info: [ScreenSwitchDebug] InitTimeout - Serial message-off in: 300000 ms May 30 17:23:53 parasound volumio[17616]: info: [ScreenSwitchDebug] CurState: stop PrevState: stop May 30 17:23:53 parasound volumio[17616]: info: [ScreenSwitchDebug] CurState: stop PrevState: na May 30 17:23:53 parasound volumio[17616]: info: [ScreenSwitchDebug] InitTimeout - Screen message-off in: 300000 ms May 30 17:23:53 parasound volumio[17616]: info: [ScreenSwitchDebug] CurState: stop PrevState: stop May 30 17:23:53 parasound systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 30 17:23:53 parasound volumio[17616]: info: Volumio called home May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 17:23:53 parasound volumio[17616]: info: No need to fix Spotify hosts May 30 17:23:53 parasound volumio[17616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 30 17:23:53 parasound volumio[17616]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 30 17:23:54 parasound volumio[17616]: info: [AmpStatusLED] CurState: stop PrevState: stop May 30 17:23:54 parasound volumio[17616]: info: [ScreenSwitchDebug] CurState: stop PrevState: stop May 30 17:23:54 parasound volumio[17616]: info: [ScreenSwitchDebug] CurState: stop PrevState: stop May 30 17:23:54 parasound volumio[17616]: info: Starting Shairport Sync May 30 17:23:54 parasound volumio[17616]: info: Starting Shairport Sync May 30 17:23:54 parasound sudo[17894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 17:23:54 parasound sudo[17894]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:54 parasound volumio[17616]: info: Starting Shairport Sync May 30 17:23:54 parasound sudo[17897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 17:23:54 parasound sudo[17897]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:54 parasound volumio[17616]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:54 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:54 parasound sudo[17900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 17:23:54 parasound sudo[17900]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:54 parasound volumio[17616]: (node:17616) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '1' of null May 30 17:23:54 parasound volumio[17616]: at doGet (/data/plugins/user_interface/now_playing/lib/api/openweathermap/index.js:74:60) May 30 17:23:54 parasound volumio[17616]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 30 17:23:54 parasound volumio[17616]: (Use `node --trace-warnings ...` to show where the warning was created) May 30 17:23:54 parasound volumio[17616]: (node:17616) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) May 30 17:23:54 parasound volumio[17616]: (node:17616) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. May 30 17:23:54 parasound volumio[17616]: info: Listing playlists May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::volumioGetQueue May 30 17:23:54 parasound volumio[17616]: info: CoreStateMachine::getQueue May 30 17:23:54 parasound volumio[17616]: info: CorePlayQueue::getQueue May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::volumioGetVisibleSources May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:54 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:54 parasound volumio[17616]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo May 30 17:23:54 parasound volumio[17616]: info: Listing playlists May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::volumioGetQueue May 30 17:23:54 parasound volumio[17616]: info: CoreStateMachine::getQueue May 30 17:23:54 parasound volumio[17616]: info: CorePlayQueue::getQueue May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::volumioGetVisibleSources May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 17:23:54 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:54 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:54 parasound sudo[17863]: pam_unix(sudo:session): session closed for user root May 30 17:23:54 parasound volumio[17616]: info: Listing playlists May 30 17:23:54 parasound volumio[17616]: info: Listing playlists May 30 17:23:54 parasound volumio[17616]: info: touch_display: systemctl daemon-reload succeeded. May 30 17:23:54 parasound systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 30 17:23:54 parasound systemd[1]: Stopping go-librespot Daemon... May 30 17:23:54 parasound sudo[17917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 30 17:23:54 parasound sudo[17917]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:23:54 parasound systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 30 17:23:54 parasound systemd[1]: go-librespot-daemon.service: Succeeded. May 30 17:23:54 parasound systemd[1]: Stopped go-librespot Daemon. May 30 17:23:55 parasound systemd[1]: shairport-sync.service: Succeeded. May 30 17:23:55 parasound systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 30 17:23:55 parasound systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 30 17:23:55 parasound sudo[17897]: pam_unix(sudo:session): session closed for user root May 30 17:23:55 parasound sudo[17894]: pam_unix(sudo:session): session closed for user root May 30 17:23:55 parasound sudo[17900]: pam_unix(sudo:session): session closed for user root May 30 17:23:55 parasound volumio[17616]: info: Shairport-Sync Started May 30 17:23:55 parasound volumio[17616]: Error adding Membership: Error: addMembership EINVAL May 30 17:23:55 parasound volumio[17616]: info: Shairport-Sync Started May 30 17:23:55 parasound systemd[1]: Started go-librespot Daemon. May 30 17:23:55 parasound go-librespot[17922]: go-librespot daemon starting... May 30 17:23:55 parasound sudo[17880]: pam_unix(sudo:session): session closed for user root May 30 17:23:55 parasound go-librespot[17922]: time="2025-05-30T17:23:55+01:00" level=info msg="running go-librespot 0.2.0" May 30 17:23:55 parasound go-librespot[17922]: time="2025-05-30T17:23:55+01:00" level=debug msg="app state loaded" May 30 17:23:55 parasound go-librespot[17922]: time="2025-05-30T17:23:55+01:00" level=debug msg="stored credentials not found" May 30 17:23:55 parasound go-librespot[17922]: time="2025-05-30T17:23:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" May 30 17:23:55 parasound volumio[17616]: info: [now-playing-weather] Fetching API key... May 30 17:23:55 parasound sudo[17917]: pam_unix(sudo:session): session closed for user root May 30 17:23:55 parasound volumio[17616]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 30 17:23:55 parasound volumio[17616]: info: touch_display: Volumio Kiosk started May 30 17:23:55 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:55 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:55 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:55 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:55 parasound volumio[17616]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 30 17:23:55 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:23:55 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:23:55 parasound volumio[17616]: (node:17616) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '1' of null May 30 17:23:55 parasound volumio[17616]: at doGet (/data/plugins/user_interface/now_playing/lib/api/openweathermap/index.js:74:60) May 30 17:23:55 parasound volumio[17616]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 30 17:23:55 parasound volumio[17616]: (node:17616) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 7) May 30 17:23:55 parasound mpd[17854]: May 30 17:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 30 17:23:55 parasound systemd[1]: Started Music Player Daemon. May 30 17:23:55 parasound volumio[17616]: info: touch_display: Setting screensaver timeout to 60 seconds. May 30 17:23:55 parasound volumio[17616]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 30 17:23:55 parasound sudo[17826]: pam_unix(sudo:session): session closed for user root May 30 17:23:55 parasound sudo[17837]: pam_unix(sudo:session): session closed for user root May 30 17:23:55 parasound go-librespot[17922]: time="2025-05-30T17:23:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 30 17:23:55 parasound go-librespot[17922]: time="2025-05-30T17:23:55+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 30 17:23:55 parasound go-librespot[17922]: time="2025-05-30T17:23:55+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 30 17:23:56 parasound volumio[17616]: info: Completed starting Core Plugins May 30 17:23:56 parasound volumio[17616]: info: ------------------------------------------- May 30 17:23:56 parasound volumio[17616]: info: ----- MyVolumio plugins startup ---- May 30 17:23:56 parasound volumio[17616]: info: ------------------------------------------- May 30 17:23:56 parasound volumio[17616]: info: [MyVolumio PluginManager] Fetching plans data.... May 30 17:23:56 parasound go-librespot[17922]: time="2025-05-30T17:23:56+01:00" level=info msg="zeroconf server listening on port 39479" May 30 17:23:56 parasound volumio[17616]: error: MPD error: The expression evaluated to a falsy value: May 30 17:23:56 parasound volumio[17616]: assert.ok(self.idling) May 30 17:23:56 parasound volumio[17616]: error: The expression evaluated to a falsy value: May 30 17:23:56 parasound volumio[17616]: assert.ok(self.idling) May 30 17:23:56 parasound volumio[17616]: info: MPD running with PID17854 May 30 17:23:56 parasound volumio[17616]: ,establishing connection May 30 17:23:56 parasound volumio[17616]: error: updateQueue error: null May 30 17:23:56 parasound volumio[17616]: error: updateQueue error: null May 30 17:23:57 parasound volumio-remote-updater[672]: [2025-05-30 17:23:57] [connect] Successful connection May 30 17:23:57 parasound volumio-remote-updater[672]: [2025-05-30 17:23:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748622237 101 May 30 17:23:57 parasound volumio[17616]: 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: 12 May 30 17:23:58 parasound volumio[17616]: info: go-librespot daemon successfully initialized May 30 17:24:00 parasound kernel: hwmon hwmon1: Voltage normalised May 30 17:24:01 parasound volumio[17616]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 30 17:24:01 parasound volumio[17616]: info: Initializing connection to go-librespot Websocket May 30 17:24:01 parasound go-librespot[17922]: time="2025-05-30T17:24:01+01:00" level=debug msg="new websocket client" May 30 17:24:01 parasound volumio[17616]: info: Connection to go-librespot Websocket established May 30 17:24:04 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:24:04 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:04 parasound volumio[17616]: info: Getting Spotify volume May 30 17:24:04 parasound volumio[17616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 30 17:24:04 parasound volumio[17616]: info: Adding plugin bluetooth to MyMusic Plugins May 30 17:24:04 parasound volumio[17616]: info: Adding plugin multiroom to MyMusic Plugins May 30 17:24:04 parasound volumio[17616]: info: Adding plugin metavolumio to MyMusic Plugins May 30 17:24:04 parasound volumio[17616]: info: Adding plugin cd_controller to MyMusic Plugins May 30 17:24:04 parasound volumio[17616]: info: Adding plugin smart_inputs to MyMusic Plugins May 30 17:24:04 parasound volumio[17616]: info: Adding plugin tidalconnect to MyMusic Plugins May 30 17:24:04 parasound volumio[17616]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 30 17:24:05 parasound volumio[17616]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 30 17:24:05 parasound volumio[17616]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 30 17:24:05 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:24:05 parasound volumio[17616]: info: Starting MyVolumio Remote Streaming Endpoints May 30 17:24:05 parasound volumio[17616]: info: MyVolumio login type: Token May 30 17:24:05 parasound volumio[17616]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 30 17:24:05 parasound volumio[17616]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 30 17:24:06 parasound volumio[17616]: info: Starting Streaming Service Transparent Proxy May 30 17:24:06 parasound volumio[17616]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 30 17:24:06 parasound volumio[17616]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 30 17:24:06 parasound volumio[17616]: info: Streaming services startup May 30 17:24:06 parasound volumio[17616]: info: Starting Streaming Daemon May 30 17:24:06 parasound sudo[17967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 30 17:24:06 parasound sudo[17967]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:24:06 parasound sudo[17967]: pam_unix(sudo:session): session closed for user root May 30 17:24:06 parasound volumio[17616]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 30 17:24:06 parasound volumio[17616]: error: Cannot start Volumio Streaming Daemon May 30 17:24:06 parasound volumio[17616]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 30 17:24:06 parasound volumio[17616]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 30 17:24:06 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:24:06 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:06 parasound volumio[17616]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 30 17:24:06 parasound volumio[17616]: SPOTIFY: SPOTIFY VOLUME undefined May 30 17:24:06 parasound volumio[17616]: SPOTIFY: VOLUMIO VOLUME 100 May 30 17:24:06 parasound volumio[17616]: info: Aligning Spotify Volume to Volumio Volume May 30 17:24:06 parasound volumio[17616]: info: CoreCommandRouter::volumioGetState May 30 17:24:06 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:06 parasound volumio[17616]: info: Setting Spotify Volume from Volumio: 100 May 30 17:24:06 parasound volumio[17616]: error: MyVolumio Custom Token format not valid, refreshing it May 30 17:24:07 parasound kernel: hwmon hwmon1: Undervoltage detected! May 30 17:24:07 parasound volumio[17616]: info: MyVolumio login type: Token May 30 17:24:08 parasound volumio[17616]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 30 17:24:08 parasound volumio[17616]: SPOTIFY: SETTING SPOTIFY VOLUME 100 May 30 17:24:08 parasound volumio[17616]: info: Sending Spotify command with payload to local API: /player/volume May 30 17:24:08 parasound volumio[17616]: STREAMING PROXY: Starting server on port 3245 May 30 17:24:08 parasound volumio[17616]: Node JS runtime: 14 May 30 17:24:08 parasound volumio[17616]: info: MyVolumio token set successfully May 30 17:24:08 parasound volumio[17616]: info: MYVOLUMIO: Adding device May 30 17:24:08 parasound volumio[17616]: info: MYVOLUMIO: Evaluating Server May 30 17:24:09 parasound volumio[17616]: info: MyVolumio status changed May 30 17:24:09 parasound volumio[17616]: info: Streaming services startup May 30 17:24:09 parasound volumio[17616]: info: Starting Streaming Daemon May 30 17:24:09 parasound volumio[17616]: info: Removing browser output: myVolumio user plan is not superstar May 30 17:24:09 parasound volumio[17616]: info: Removing audio output: May 30 17:24:09 parasound volumio[17616]: info: Stoppping Tunnel 1 May 30 17:24:09 parasound sudo[17994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 30 17:24:09 parasound sudo[17994]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:24:09 parasound sudo[17997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 30 17:24:09 parasound sudo[17997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 17:24:09 parasound sudo[17994]: pam_unix(sudo:session): session closed for user root May 30 17:24:09 parasound volumio[17616]: info: Listing playlists May 30 17:24:09 parasound volumio[17616]: info: Listing playlists May 30 17:24:09 parasound volumio[17616]: error: Cannot start Volumio Streaming Daemon May 30 17:24:09 parasound volumio[17616]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 30 17:24:09 parasound volumio[17616]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 30 17:24:09 parasound sudo[17997]: pam_unix(sudo:session): session closed for user root May 30 17:24:09 parasound volumio[17616]: info: Remote SSH Stopped May 30 17:24:13 parasound kernel: hwmon hwmon1: Voltage normalised May 30 17:24:14 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: planetrock_radio , handleBrowseUri May 30 17:24:14 parasound volumio[17616]: info: Making CSRF token request to: https://account.planetradio.co.uk/ajax/process-account/ May 30 17:24:14 parasound volumio[17616]: info: CSRF Response Status: 200 May 30 17:24:14 parasound volumio[17616]: info: CSRF Response Headers: { May 30 17:24:14 parasound volumio[17616]: "date": "Fri, 30 May 2025 16:24:14 GMT", May 30 17:24:14 parasound volumio[17616]: "content-type": "application/json", May 30 17:24:14 parasound volumio[17616]: "transfer-encoding": "chunked", May 30 17:24:14 parasound volumio[17616]: "connection": "close", May 30 17:24:14 parasound volumio[17616]: "server": "Apache", May 30 17:24:14 parasound volumio[17616]: "x-powered-by": "PHP/8.3.19", May 30 17:24:14 parasound volumio[17616]: "expires": "Thu, 19 Nov 1981 08:52:00 GMT", May 30 17:24:14 parasound volumio[17616]: "cache-control": "no-store, no-cache, must-revalidate", May 30 17:24:14 parasound volumio[17616]: "pragma": "no-cache", May 30 17:24:14 parasound volumio[17616]: "vary": "Origin,Accept-Encoding", May 30 17:24:14 parasound volumio[17616]: "x-csrf-token": "{\"csrf_name\":\"csrf6839dbaea6bd0\",\"csrf_value\":\"64a167c1f11c9aa7d927606ec6a386c0\"}", May 30 17:24:14 parasound volumio[17616]: "set-cookie": [ May 30 17:24:14 parasound volumio[17616]: "PHPSESSID=qls23vfa1u5769qb3fig3fofda; path=/" May 30 17:24:14 parasound volumio[17616]: ] May 30 17:24:14 parasound volumio[17616]: } May 30 17:24:14 parasound volumio[17616]: info: CSRF Response Body: { May 30 17:24:14 parasound volumio[17616]: "status": 724 May 30 17:24:14 parasound volumio[17616]: } May 30 17:24:14 parasound volumio[17616]: info: Making login request with form data: processmode=login&emailfield=shaaquiel%40gmail.com&passwordfield=yhvF7%21t%2A2%40u%2ADc6V&authMethod=native&csrf_name=csrf6839dbaea6bd0&csrf_value=64a167c1f11c9aa7d927606ec6a386c0 May 30 17:24:14 parasound volumio[17616]: info: Login request headers: { May 30 17:24:14 parasound volumio[17616]: "Content-Type": "application/x-www-form-urlencoded", May 30 17:24:14 parasound volumio[17616]: "X-CSRF-Token": "{\"csrf_name\":\"csrf6839dbaea6bd0\",\"csrf_value\":\"64a167c1f11c9aa7d927606ec6a386c0\"}", May 30 17:24:14 parasound volumio[17616]: "Cookie": [ May 30 17:24:14 parasound volumio[17616]: "PHPSESSID=qls23vfa1u5769qb3fig3fofda; path=/" May 30 17:24:14 parasound volumio[17616]: ] May 30 17:24:14 parasound volumio[17616]: } May 30 17:24:14 parasound volumio[17616]: info: Setting Geolocation for MyVolumio to eu3 May 30 17:24:14 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:24:14 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:24:14 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:24:14 parasound volumio[17616]: info: Login Response Status: 200 May 30 17:24:14 parasound volumio[17616]: info: Login Response Error: false May 30 17:24:14 parasound volumio[17616]: info: Login Response Headers: { May 30 17:24:14 parasound volumio[17616]: "date": "Fri, 30 May 2025 16:24:14 GMT", May 30 17:24:14 parasound volumio[17616]: "content-type": "application/json", May 30 17:24:14 parasound volumio[17616]: "transfer-encoding": "chunked", May 30 17:24:14 parasound volumio[17616]: "connection": "close", May 30 17:24:14 parasound volumio[17616]: "server": "Apache", May 30 17:24:14 parasound volumio[17616]: "x-powered-by": "PHP/8.3.19", May 30 17:24:14 parasound volumio[17616]: "expires": "Thu, 19 Nov 1981 08:52:00 GMT", May 30 17:24:14 parasound volumio[17616]: "cache-control": "no-store, no-cache, must-revalidate", May 30 17:24:14 parasound volumio[17616]: "pragma": "no-cache", May 30 17:24:14 parasound volumio[17616]: "vary": "Origin,Accept-Encoding", May 30 17:24:14 parasound volumio[17616]: "x-csrf-token": "{\"csrf_name\":\"csrf6839dbaebbeb3\",\"csrf_value\":\"d7cdd1f62290be363612445c344d219d\"}", May 30 17:24:14 parasound volumio[17616]: "set-cookie": [ May 30 17:24:14 parasound volumio[17616]: "jwt-radio-uk-sso-uk_radio=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJpc3MiOiJhY2NvdW50LnBsYW5ldHJhZGlvLmNvLnVrIiwiaWF0IjoxNzQ4NjIyMjU0LCJleHAiOjE3NjQxNzQyNTQsImlkIjoiMjAyMTM1MjM5NiIsImNpZCI6IjZmNjFlMDk3M2JjMDU1NGQxNTFlNGVhY2Y1Zjg1ZTQyIiwic2FsdCI6IlZlcnNGTU9LSERTdCIsImZpcnN0bmFtZSI6IkZhYmlvIiwidGFyZ2V0ZWRBZHMiOiJlbmFibGVkIiwidmVyaWZpZWQiOjEsImdyb3VwaWQiOiJVS19SQURJTyIsInNlc3Npb25pZCI6ImM0ZTVkZjNjLWJjNGYtNDAyMy1hYjAwLWYwYTZiMGNjZTRlNSIsInJlZnJlc2hhYmxlIjoxfQ.Fw0c1REb46PftFbkXiisjh6ToINqqtsedrXB6-cZtfA; expires=Wed, 26 Nov 2025 16:24:14 GMT; Max-Age=15552000; path=/; domain=.planetradio.co.uk; secure; HttpOnly", May 30 17:24:14 parasound volumio[17616]: "jwt-radio-uk-sso-uk_radio=deleted; expires=Thu, 01 Jan 1970 00:00:01 GMT; Max-Age=0; path=/; domain=.account.planetradio.co.uk; secure; HttpOnly", May 30 17:24:14 parasound volumio[17616]: "auth-radio-uk=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJpc3MiOiJhY2NvdW50LnBsYW5ldHJhZGlvLmNvLnVrIiwiaWF0IjoxNzQ4NjIyMjU0LCJleHAiOjE3NDg2MjQwNTQsImF1dGhIYXNoIjoiMmMwNjViNTliYTIzYWE3NzBhMjc5MjMyZjgyOTExNDE2ODFjZTU2MDUxOWM4YjQ1MGIwZGFkOTQ1ZTk2YjNmMCJ9.AeUD4MVWJTyxRPwGelmwFuuWiOZLZHKlC13BIF23CHc; expires=Fri, 30 May 2025 16:54:14 GMT; Max-Age=1800; path=/; domain=.planetradio.co.uk; secure; HttpOnly", May 30 17:24:14 parasound volumio[17616]: "auth-radio-uk=deleted; expires=Thu, 01 Jan 1970 00:00:01 GMT; Max-Age=0; path=/; domain=.account.planetradio.co.uk; secure; HttpOnly" May 30 17:24:14 parasound volumio[17616]: ] May 30 17:24:14 parasound volumio[17616]: } May 30 17:24:14 parasound volumio[17616]: info: Login Response Body: { May 30 17:24:14 parasound volumio[17616]: "status": 601, May 30 17:24:14 parasound volumio[17616]: "redirectUrl": null May 30 17:24:14 parasound volumio[17616]: } May 30 17:24:14 parasound volumio[17616]: info: Successfully extracted user ID: 2021352396 May 30 17:24:15 parasound volumio[17616]: info: Preload queue cleared May 30 17:24:15 parasound volumio[17616]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 30 17:24:15 parasound volumio[17616]: info: Updating MyVolumio device info May 30 17:24:15 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 17:24:16 parasound volumio[17616]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 30 17:24:19 parasound volumio[17616]: info: Preload queue cleared May 30 17:24:19 parasound volumio[17616]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::ClearQueue May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::stop May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::clearPlayQueue May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::saveQueue May 30 17:24:19 parasound volumio[17616]: info: CoreCommandRouter::volumioPushQueue May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::addQueueItems May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::addQueueItems May 30 17:24:19 parasound volumio[17616]: info: Preload queue cleared May 30 17:24:19 parasound volumio[17616]: info: Adding Item to queue: https://stream-mz.hellorayo.co.uk/planetrock_premhigh.aac?direct=false&listenerid=2021352396&aw_0_1st.bauer_listenerid=2021352396&aw_0_1st.playerid=BMUK_inpage_html5&aw_0_1st.skey=1748622254&aw_0_1st.bauer_loggedin=true&user_id=2021352396&aw_0_1st.bauer_user_id=2021352396®ion=GB May 30 17:24:19 parasound volumio[17616]: info: Exploding uri https://stream-mz.hellorayo.co.uk/planetrock_premhigh.aac?direct=false&listenerid=2021352396&aw_0_1st.bauer_listenerid=2021352396&aw_0_1st.playerid=BMUK_inpage_html5&aw_0_1st.skey=1748622254&aw_0_1st.bauer_loggedin=true&user_id=2021352396&aw_0_1st.bauer_user_id=2021352396®ion=GB in service planetrock_radio May 30 17:24:19 parasound volumio[17616]: info: CoreCommandRouter::volumioPushQueue May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::saveQueue May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::updateTrackBlock May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::getTrackBlock May 30 17:24:19 parasound volumio[17616]: info: CoreCommandRouter::volumioPlay May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::play index 0 May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::stop May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::play index undefined May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::startPlaybackTimer May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:19 parasound volumio[17616]: info: ControllerPlanetRockRadio::clearAddPlayTrack May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::sendMpdCommand stop May 30 17:24:19 parasound volumio[17616]: info: sendMpdCommand stop took 15 milliseconds May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::sendMpdCommand clear May 30 17:24:19 parasound volumio[17616]: info: May 30 17:24:19 parasound volumio[17616]: ---------------------------- MPD announces system playlist update May 30 17:24:19 parasound volumio[17616]: info: Ignoring MPD Status Update May 30 17:24:19 parasound volumio[17616]: info: sendMpdCommand clear took 4 milliseconds May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::sendMpdCommand add "https://stream-mz.hellorayo.co.uk/planetrock_premhigh.aac?direct=false&listenerid=2021352396&aw_0_1st.bauer_listenerid=2021352396&aw_0_1st.playerid=BMUK_inpage_html5&aw_0_1st.skey=1748622254&aw_0_1st.bauer_loggedin=true&user_id=2021352396&aw_0_1st.bauer_user_id=2021352396®ion=GB" May 30 17:24:19 parasound volumio[17616]: info: May 30 17:24:19 parasound volumio[17616]: ---------------------------- MPD announces system playlist update May 30 17:24:19 parasound volumio[17616]: info: Ignoring MPD Status Update May 30 17:24:19 parasound volumio[17616]: info: May 30 17:24:19 parasound volumio[17616]: ---------------------------- MPD announces system playlist update May 30 17:24:19 parasound volumio[17616]: info: Ignoring MPD Status Update May 30 17:24:19 parasound volumio[17616]: error: updateQueue error: null May 30 17:24:19 parasound volumio[17616]: info: May 30 17:24:19 parasound volumio[17616]: ---------------------------- MPD announces system playlist update May 30 17:24:19 parasound volumio[17616]: info: Ignoring MPD Status Update May 30 17:24:19 parasound volumio[17616]: info: ------------------------------ 12ms May 30 17:24:19 parasound volumio[17616]: info: sendMpdCommand add "https://stream-mz.hellorayo.co.uk/planetrock_premhigh.aac?direct=false&listenerid=2021352396&aw_0_1st.bauer_listenerid=2021352396&aw_0_1st.playerid=BMUK_inpage_html5&aw_0_1st.skey=1748622254&aw_0_1st.bauer_loggedin=true&user_id=2021352396&aw_0_1st.bauer_user_id=2021352396®ion=GB" took 9 milliseconds May 30 17:24:19 parasound volumio[17616]: info: ------------------------------ 9ms May 30 17:24:19 parasound volumio[17616]: info: ------------------------------ 7ms May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::sendMpdCommand consume 1 May 30 17:24:19 parasound volumio[17616]: info: May 30 17:24:19 parasound volumio[17616]: ---------------------------- MPD announces system playlist update May 30 17:24:19 parasound volumio[17616]: info: Ignoring MPD Status Update May 30 17:24:19 parasound volumio[17616]: info: May 30 17:24:19 parasound volumio[17616]: ---------------------------- MPD announces system playlist update May 30 17:24:19 parasound volumio[17616]: info: Ignoring MPD Status Update May 30 17:24:19 parasound volumio[17616]: info: May 30 17:24:19 parasound volumio[17616]: ---------------------------- MPD announces state update: options May 30 17:24:19 parasound volumio[17616]: info: ------------------------------ 12ms May 30 17:24:19 parasound volumio[17616]: info: sendMpdCommand consume 1 took 6 milliseconds May 30 17:24:19 parasound volumio[17616]: info: ControllerMpd::getState May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::sendMpdCommand status May 30 17:24:19 parasound volumio[17616]: info: ------------------------------ 7ms May 30 17:24:19 parasound volumio[17616]: info: ------------------------------ 6ms May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::sendMpdCommand play May 30 17:24:19 parasound volumio[17616]: info: May 30 17:24:19 parasound volumio[17616]: ---------------------------- MPD announces state update: options May 30 17:24:19 parasound volumio[17616]: info: ControllerMpd::getState May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::sendMpdCommand status May 30 17:24:19 parasound volumio[17616]: info: May 30 17:24:19 parasound volumio[17616]: ---------------------------- MPD announces state update: options May 30 17:24:19 parasound volumio[17616]: info: ControllerMpd::getState May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::sendMpdCommand status May 30 17:24:19 parasound volumio[17616]: info: MYVOLUMIO: Adding device May 30 17:24:19 parasound volumio[17616]: info: MYVOLUMIO: Evaluating Server May 30 17:24:19 parasound volumio[17616]: info: sendMpdCommand status took 29 milliseconds May 30 17:24:19 parasound volumio[17616]: info: sendMpdCommand play took 18 milliseconds May 30 17:24:19 parasound volumio[17616]: info: sendMpdCommand status took 14 milliseconds May 30 17:24:19 parasound volumio[17616]: info: sendMpdCommand status took 11 milliseconds May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::parseState May 30 17:24:19 parasound volumio[17616]: info: CoreCommandRouter::servicePushState May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:19 parasound volumio[17616]: verbose: STATE SERVICE {"status":"play"} May 30 17:24:19 parasound volumio[17616]: verbose: CURRENT POSITION 0 May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::syncState stateService play May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::syncState currentStatus stop May 30 17:24:19 parasound volumio[17616]: info: Fetching now playing info from Planet Rock API... May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::parseState May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::parseState May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 17:24:19 parasound volumio[17616]: info: ControllerMpd::pushState May 30 17:24:19 parasound volumio[17616]: info: CoreCommandRouter::servicePushState May 30 17:24:19 parasound volumio[17616]: info: CoreStateMachine::pushState May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:19 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 17:24:19 parasound volumio[17616]: info: CoreCommandRouter::volumioPushState May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:19 parasound volumio[17616]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current planetrock_radio Received mpd May 30 17:24:19 parasound volumio[17616]: info: ------------------------------ 84ms May 30 17:24:19 parasound volumio[17616]: info: [AmpStatusLED] CurState: play PrevState: stop May 30 17:24:19 parasound volumio[17616]: info: [AmpStatusLED] Togle GPIO LED: Playing May 30 17:24:19 parasound volumio[17616]: info: [ScreenSwitchDebug] CurState: play PrevState: stop May 30 17:24:19 parasound volumio[17616]: info: [ScreenSwitchDebug] Sending ON message to serial May 30 17:24:19 parasound volumio[17616]: info: [ScreenSwitchDebug] CurState: play PrevState: stop May 30 17:24:19 parasound volumio[17616]: info: [ScreenSwitchDebug] Turning on screen May 30 17:24:19 parasound volumio[17616]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 30 17:24:19 parasound volumio[17616]: info: sendMpdCommand playlistinfo took 188 milliseconds May 30 17:24:19 parasound volumio[17616]: info: sendMpdCommand playlistinfo took 189 milliseconds May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::parseTrackInfo May 30 17:24:19 parasound volumio[17616]: verbose: ControllerMpd::parseTrackInfo May 30 17:24:19 parasound volumio[17616]: info: ControllerMpd::pushState May 30 17:24:19 parasound volumio[17616]: info: CoreCommandRouter::servicePushState May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:19 parasound volumio[17616]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current planetrock_radio Received mpd May 30 17:24:19 parasound volumio[17616]: info: ControllerMpd::pushState May 30 17:24:19 parasound volumio[17616]: info: CoreCommandRouter::servicePushState May 30 17:24:19 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:19 parasound volumio[17616]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current planetrock_radio Received mpd May 30 17:24:19 parasound volumio[17616]: info: ------------------------------ 231ms May 30 17:24:19 parasound volumio[17616]: info: ------------------------------ 228ms May 30 17:24:20 parasound volumio[17616]: info: touch_display: Setting screensaver timeout to 0 seconds. May 30 17:24:20 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 17:24:20 parasound volumio[17616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 30 17:24:20 parasound volumio[17616]: info: API Response Status: 200 May 30 17:24:20 parasound volumio[17616]: info: Current track ID: 0 May 30 17:24:20 parasound volumio[17616]: info: Last track ID: null May 30 17:24:20 parasound volumio[17616]: info: Track has changed, updating info... May 30 17:24:20 parasound volumio[17616]: info: New state to sync: { May 30 17:24:20 parasound volumio[17616]: "status": "play", May 30 17:24:20 parasound volumio[17616]: "service": "planetrock_radio", May 30 17:24:20 parasound volumio[17616]: "title": "", May 30 17:24:20 parasound volumio[17616]: "artist": "", May 30 17:24:20 parasound volumio[17616]: "albumart": "", May 30 17:24:20 parasound volumio[17616]: "trackType": "webradio", May 30 17:24:20 parasound volumio[17616]: "streaming": true May 30 17:24:20 parasound volumio[17616]: } May 30 17:24:20 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:20 parasound volumio[17616]: verbose: STATE SERVICE {"status":"play","service":"planetrock_radio","title":"","artist":"","albumart":"","trackType":"webradio","streaming":true} May 30 17:24:20 parasound volumio[17616]: verbose: CURRENT POSITION 0 May 30 17:24:20 parasound volumio[17616]: info: CoreStateMachine::syncState stateService play May 30 17:24:20 parasound volumio[17616]: info: CoreStateMachine::syncState currentStatus play May 30 17:24:20 parasound volumio[17616]: info: Received an update from plugin. extracting info from payload May 30 17:24:20 parasound volumio[17616]: info: CoreStateMachine::pushState May 30 17:24:20 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:20 parasound volumio[17616]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 17:24:20 parasound volumio[17616]: info: CoreCommandRouter::volumioPushState May 30 17:24:20 parasound volumio[17616]: info: CoreStateMachine::pushState May 30 17:24:20 parasound volumio[17616]: info: CorePlayQueue::getTrack 0 May 30 17:24:20 parasound volumio[17616]: info: CoreCommandRouter::volumioPushState May 30 17:24:20 parasound volumio[17616]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 17:24:20 parasound volumio[17616]: TypeError: Cannot read property 'fail' of undefined May 30 17:24:20 parasound volumio[17616]: at /data/plugins/music_service/planetrock_radio/index.js:440:13 May 30 17:24:20 parasound volumio[17616]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/planetrock_radio/node_modules/unirest/index.js:552:70) May 30 17:24:20 parasound volumio[17616]: at Request.self.callback (/data/plugins/music_service/planetrock_radio/node_modules/request/request.js:185:22) May 30 17:24:20 parasound volumio[17616]: at Request.emit (events.js:315:20) May 30 17:24:20 parasound volumio[17616]: at Request. (/data/plugins/music_service/planetrock_radio/node_modules/request/request.js:1154:10) May 30 17:24:20 parasound volumio[17616]: at Request.emit (events.js:315:20) May 30 17:24:20 parasound volumio[17616]: at IncomingMessage. (/data/plugins/music_service/planetrock_radio/node_modules/request/request.js:1076:12) May 30 17:24:20 parasound volumio[17616]: at Object.onceWrapper (events.js:421:28) May 30 17:24:20 parasound volumio[17616]: at IncomingMessage.emit (events.js:327:22) May 30 17:24:20 parasound volumio[17616]: at endReadableNT (internal/streams/readable.js:1327:12) May 30 17:24:20 parasound volumio[17616]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 30 17:24:20 parasound volumio[17616]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 17:24:21 parasound kernel: hwmon hwmon1: Undervoltage detected! May 30 17:24:21 parasound sudo[18050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-30 17:23 May 30 17:24:21 parasound sudo[18050]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"