Apr 23 12:33:00 volumio-alpha volumio[1460]: info: Initializing connection to go-librespot Websocket Apr 23 12:33:00 volumio-alpha go-librespot[1700]: time="2025-04-23T12:33:00+01:00" level=debug msg="new websocket client" Apr 23 12:33:00 volumio-alpha volumio[1460]: info: Connection to go-librespot Websocket established Apr 23 12:33:00 volumio-alpha volumio[1460]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 23 12:33:00 volumio-alpha volumio-remote-updater[914]: [2025-04-23 12:33:00] [connect] Successful connection Apr 23 12:33:00 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:01 volumio-alpha volumio[1460]: 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: 71 Apr 23 12:33:01 volumio-alpha volumio-remote-updater[914]: [2025-04-23 12:33:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745407980 101 Apr 23 12:33:03 volumio-alpha sudo[1754]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 12:33:03 volumio-alpha sudo[1754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 23 12:33:03 volumio-alpha sudo[1754]: pam_unix(sudo:session): session closed for user root Apr 23 12:33:03 volumio-alpha sudo[1756]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 12:33:03 volumio-alpha sudo[1756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 23 12:33:03 volumio-alpha sudo[1756]: pam_unix(sudo:session): session closed for user root Apr 23 12:33:03 volumio-alpha volumio[1460]: info: Getting Spotify volume Apr 23 12:33:03 volumio-alpha volumio[1460]: info: Spotify volume: 100 Apr 23 12:33:04 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:33:04 volumio-alpha volumio[1460]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 72 Apr 23 12:33:05 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:05 volumio-alpha sudo[1761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 23 12:33:05 volumio-alpha sudo[1761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 23 12:33:05 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioGetState Apr 23 12:33:05 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:05 volumio-alpha systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 23 12:33:05 volumio-alpha sudo[1761]: pam_unix(sudo:session): session closed for user root Apr 23 12:33:05 volumio-alpha volumio[1460]: info: Upmpdcli Daemon Started Apr 23 12:33:06 volumio-alpha systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 23 12:33:06 volumio-alpha volumio[1460]: info: Adding plugin bluetooth to MyMusic Plugins Apr 23 12:33:06 volumio-alpha volumio[1460]: info: Adding plugin multiroom to MyMusic Plugins Apr 23 12:33:06 volumio-alpha volumio[1460]: info: Adding plugin metavolumio to MyMusic Plugins Apr 23 12:33:06 volumio-alpha volumio[1460]: info: Adding plugin cd_controller to MyMusic Plugins Apr 23 12:33:06 volumio-alpha volumio[1460]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 23 12:33:06 volumio-alpha volumio[1460]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 23 12:33:06 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:06 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:06 volumio-alpha volumio[1460]: info: Starting MyVolumio Remote Streaming Endpoints Apr 23 12:33:06 volumio-alpha volumio[1460]: info: MyVolumio login type: Token Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 23 12:33:06 volumio-alpha volumio[1460]: info: Streaming services startup Apr 23 12:33:06 volumio-alpha volumio[1460]: info: Starting Streaming Daemon Apr 23 12:33:06 volumio-alpha sudo[1781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 23 12:33:06 volumio-alpha sudo[1781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 23 12:33:06 volumio-alpha volumio[1460]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 23 12:33:06 volumio-alpha sudo[1781]: pam_unix(sudo:session): session closed for user root Apr 23 12:33:06 volumio-alpha volumio[1460]: error: Cannot start Volumio Streaming Daemon Apr 23 12:33:06 volumio-alpha volumio[1460]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 23 12:33:06 volumio-alpha volumio[1460]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 23 12:33:07 volumio-alpha volumio[1460]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 23 12:33:07 volumio-alpha volumio[1460]: info: MyVolumio token set successfully Apr 23 12:33:07 volumio-alpha volumio[1460]: info: MYVOLUMIO: Adding device Apr 23 12:33:07 volumio-alpha volumio[1460]: info: MYVOLUMIO: Evaluating Server Apr 23 12:33:07 volumio-alpha volumio[1460]: info: MyVolumio status changed Apr 23 12:33:07 volumio-alpha volumio[1460]: info: Streaming services startup Apr 23 12:33:07 volumio-alpha volumio[1460]: info: Starting Streaming Daemon Apr 23 12:33:07 volumio-alpha systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 23 12:33:07 volumio-alpha volumio[1460]: info: Removing browser output: myVolumio user plan is not superstar Apr 23 12:33:07 volumio-alpha volumio[1460]: info: Removing audio output: Apr 23 12:33:07 volumio-alpha volumio[1460]: info: Stoppping Tunnel 1 Apr 23 12:33:07 volumio-alpha sudo[1807]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 23 12:33:07 volumio-alpha sudo[1807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 23 12:33:07 volumio-alpha sudo[1809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 23 12:33:07 volumio-alpha sudo[1809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 23 12:33:07 volumio-alpha sudo[1807]: pam_unix(sudo:session): session closed for user root Apr 23 12:33:07 volumio-alpha volumio[1460]: error: Cannot start Volumio Streaming Daemon Apr 23 12:33:07 volumio-alpha volumio[1460]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 23 12:33:07 volumio-alpha volumio[1460]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 23 12:33:07 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 23 12:33:07 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 23 12:33:07 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 23 12:33:07 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 23 12:33:07 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 23 12:33:07 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 23 12:33:07 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 23 12:33:07 volumio-alpha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 23 12:33:07 volumio-alpha sudo[1809]: pam_unix(sudo:session): session closed for user root Apr 23 12:33:08 volumio-alpha volumio[1460]: info: Remote SSH Stopped Apr 23 12:33:08 volumio-alpha volumio[1460]: info: Setting Geolocation for MyVolumio to eu3 Apr 23 12:33:08 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:08 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:08 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:08 volumio-alpha volumio[1460]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 23 12:33:08 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:09 volumio-alpha volumio[1460]: info: Updating MyVolumio device info Apr 23 12:33:09 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:09 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:09 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:09 volumio-alpha volumio[1460]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 23 12:33:10 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:33:12 volumio-alpha volumio[1460]: info: MYVOLUMIO: Adding device Apr 23 12:33:12 volumio-alpha volumio[1460]: info: MYVOLUMIO: Evaluating Server Apr 23 12:33:12 volumio-alpha upmpdcli[1828]: writing RSA key Apr 23 12:33:12 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:13 volumio-alpha volumio[1460]: info: Setting Geolocation for MyVolumio to eu3 Apr 23 12:33:13 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:13 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:13 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:13 volumio-alpha volumio[1460]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 23 12:33:14 volumio-alpha volumio[1460]: info: Updating MyVolumio device info Apr 23 12:33:14 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:14 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:14 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:14 volumio-alpha volumio[1460]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 23 12:33:16 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:33:16 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:20 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:21 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 23 12:33:21 volumio-alpha volumio[1460]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 73 Apr 23 12:33:21 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioGetState Apr 23 12:33:21 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:22 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:33:24 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 12:33:24 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:24 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 23 12:33:24 volumio-alpha volumio-remote-updater[914]: No test mode Apr 23 12:33:24 volumio-alpha volumio-remote-updater[914]: No alpha test mode Apr 23 12:33:24 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 23 12:33:24 volumio-alpha volumio[1460]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES


NEW ADDITIONS

 

 

","title":"Update v3.799","updateavailable":true} Apr 23 12:33:24 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 23 12:33:24 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:24 volumio-alpha go-librespot[1700]: time="2025-04-23T12:33:24+01:00" level=trace msg="sent dealer ping" Apr 23 12:33:24 volumio-alpha go-librespot[1700]: time="2025-04-23T12:33:24+01:00" level=trace msg="received dealer pong" Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioGetBrowseSources Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 12:33:26 volumio-alpha volumio[1460]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 23 12:33:26 volumio-alpha volumio[1460]: info: Completed starting MyVolumio Plugin Apr 23 12:33:26 volumio-alpha volumio[1460]: [Metrics] CommandRouter: 35s 3.15ms Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumiosetStartupVolume Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::Close All Modals sent Apr 23 12:33:26 volumio-alpha volumio[1460]: info: CoreCommandRouter::Close All Modals sent Apr 23 12:33:27 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 23 12:33:27 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 23 12:33:27 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 23 12:33:28 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:33:28 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:32 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:33 volumio-alpha volumio[1460]: info: BOOT COMPLETED Apr 23 12:33:34 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:33:36 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand rescan Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:33:37 volumio-alpha volumio[1460]: info: Apr 23 12:33:37 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: update Apr 23 12:33:37 volumio-alpha volumio[1460]: info: sendMpdCommand rescan took 3 milliseconds Apr 23 12:33:37 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:33:37 volumio-alpha volumio[1460]: info: sendMpdCommand status took 2 milliseconds Apr 23 12:33:37 volumio-alpha volumio[1460]: info: sendMpdCommand status took 1 milliseconds Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: Command Router : Notfying DB Updatetrue Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: 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} Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService stop Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus stop Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: No code Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: ------------------------------ 35ms Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:33:37 volumio-alpha volumio[1460]: info: Apr 23 12:33:37 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: update Apr 23 12:33:37 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:33:37 volumio-alpha volumio[1460]: info: Apr 23 12:33:37 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: update Apr 23 12:33:37 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:33:37 volumio-alpha volumio[1460]: info: sendMpdCommand status took 2 milliseconds Apr 23 12:33:37 volumio-alpha volumio[1460]: info: sendMpdCommand status took 1 milliseconds Apr 23 12:33:37 volumio-alpha volumio[1460]: info: sendMpdCommand status took 1 milliseconds Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: Command Router : Notfying DB Updatetrue Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::Close All Modals sent Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: Command Router : Notfying DB Updatetrue Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::Close All Modals sent Apr 23 12:33:37 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: 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} Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService stop Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus stop Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: No code Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: ------------------------------ 31ms Apr 23 12:33:37 volumio-alpha volumio[1460]: info: sendMpdCommand status took 48 milliseconds Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: 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} Apr 23 12:33:37 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService stop Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus stop Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: No code Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:33:37 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:33:37 volumio-alpha volumio[1460]: info: ------------------------------ 57ms Apr 23 12:33:40 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:40 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:33:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:47 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:33:48 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:52 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:53 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:33:54 volumio-alpha volumio[1460]: info: UPDATER: Scheduling automatic update Apr 23 12:33:54 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Apr 23 12:33:54 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Apr 23 12:33:54 volumio-alpha volumio[1460]: info: UPDATER: Auto update will take place at: Thu Apr 24 2025 04:33:36 GMT+0100 (British Summer Time) Apr 23 12:33:54 volumio-alpha go-librespot[1700]: time="2025-04-23T12:33:54+01:00" level=trace msg="sent dealer ping" Apr 23 12:33:54 volumio-alpha go-librespot[1700]: time="2025-04-23T12:33:54+01:00" level=trace msg="received dealer pong" Apr 23 12:33:56 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:33:59 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:34:00 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:34:04 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 12:34:05 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:34:07 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 23 12:34:07 volumio-alpha volumio[1460]: info: Preload queue cleared Apr 23 12:34:09 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 23 12:34:09 volumio-alpha volumio[1460]: info: Preload queue cleared Apr 23 12:34:12 volumio-alpha volumio[1460]: info: Preload queue cleared Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::ClearQueue Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::stop Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CorePlayQueue::clearPlayQueue Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CorePlayQueue::saveQueue Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushQueue Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::addQueueItems Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CorePlayQueue::addQueueItems Apr 23 12:34:12 volumio-alpha volumio[1460]: info: Preload queue cleared Apr 23 12:34:12 volumio-alpha volumio[1460]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s333036&filter=s:~M:country Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushQueue Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CorePlayQueue::saveQueue Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::updateTrackBlock Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrackBlock Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPlay Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::play index 0 Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::stop Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::play index undefined Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CoreStateMachine::startPlaybackTimer Apr 23 12:34:12 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:12 volumio-alpha volumio[1460]: info: [1745408052431] ControllerWebradio::clearAddPlayTrack Apr 23 12:34:12 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand stop Apr 23 12:34:12 volumio-alpha volumio[1460]: info: sendMpdCommand stop took 1 milliseconds Apr 23 12:34:12 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand clear Apr 23 12:34:12 volumio-alpha volumio[1460]: info: sendMpdCommand clear took 1 milliseconds Apr 23 12:34:12 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand load "https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs" Apr 23 12:34:12 volumio-alpha volumio[1460]: info: Apr 23 12:34:12 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:12 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:12 volumio-alpha volumio[1460]: info: Apr 23 12:34:12 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:12 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:12 volumio-alpha volumio[1460]: info: Apr 23 12:34:12 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:12 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:14 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand add "https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs" Apr 23 12:34:14 volumio-alpha volumio[1460]: error: updateQueue error: null Apr 23 12:34:14 volumio-alpha volumio[1460]: error: updateQueue error: null Apr 23 12:34:14 volumio-alpha volumio[1460]: error: updateQueue error: null Apr 23 12:34:14 volumio-alpha volumio[1460]: info: ------------------------------ 1707ms Apr 23 12:34:14 volumio-alpha volumio[1460]: info: ------------------------------ 1706ms Apr 23 12:34:14 volumio-alpha volumio[1460]: info: ------------------------------ 1706ms Apr 23 12:34:14 volumio-alpha volumio[1460]: info: Apr 23 12:34:14 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:14 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:14 volumio-alpha volumio[1460]: info: sendMpdCommand add "https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs" took 2 milliseconds Apr 23 12:34:14 volumio-alpha volumio[1460]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 23 12:34:14 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand play Apr 23 12:34:14 volumio-alpha volumio[1460]: info: Apr 23 12:34:14 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:14 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:14 volumio-alpha volumio[1460]: info: Apr 23 12:34:14 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:14 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:14 volumio-alpha volumio[1460]: info: ------------------------------ 2ms Apr 23 12:34:14 volumio-alpha volumio[1460]: info: sendMpdCommand play took 2 milliseconds Apr 23 12:34:14 volumio-alpha volumio[1460]: info: ------------------------------ 2ms Apr 23 12:34:14 volumio-alpha volumio[1460]: info: ------------------------------ 1ms Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Apr 23 12:34:15 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: player Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Apr 23 12:34:15 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: player Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Apr 23 12:34:15 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: player Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Apr 23 12:34:15 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Apr 23 12:34:15 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: player Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand status took 25 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand status took 24 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand status took 23 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Apr 23 12:34:15 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Apr 23 12:34:15 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: player Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Apr 23 12:34:15 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Apr 23 12:34:15 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: player Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ------------------------------ 4ms Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand status took 3 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ------------------------------ 3ms Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand status took 2 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ------------------------------ 2ms Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand status took 2 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseTrackInfo Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseTrackInfo Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseTrackInfo Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","artist":null,"album":null,"uri":"https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","trackType":""} Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus stop Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":305,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","artist":null,"album":null,"uri":"https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","trackType":""} Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Received an update from plugin. extracting info from payload Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":368,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","artist":null,"album":null,"uri":"https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","trackType":""} Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Received an update from plugin. extracting info from payload Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ------------------------------ 38ms Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ------------------------------ 46ms Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ------------------------------ 46ms Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseTrackInfo Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseTrackInfo Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseTrackInfo Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","artist":null,"album":null,"uri":"https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","trackType":""} Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Received an update from plugin. extracting info from payload Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","artist":null,"album":null,"uri":"https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","trackType":""} Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Received an update from plugin. extracting info from payload Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","artist":null,"album":null,"uri":"https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","trackType":""} Apr 23 12:34:15 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus play Apr 23 12:34:15 volumio-alpha volumio[1460]: info: Received an update from plugin. extracting info from payload Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ------------------------------ 45ms Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ------------------------------ 42ms Apr 23 12:34:15 volumio-alpha volumio[1460]: info: ------------------------------ 43ms Apr 23 12:34:16 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:34:21 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:34:24 volumio-alpha go-librespot[1700]: time="2025-04-23T12:34:24+01:00" level=trace msg="sent dealer ping" Apr 23 12:34:24 volumio-alpha go-librespot[1700]: time="2025-04-23T12:34:24+01:00" level=trace msg="received dealer pong" Apr 23 12:34:27 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:34:29 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 23 12:34:29 volumio-alpha volumio[1460]: info: TuneIn handleBrowseUri: tunein/location Apr 23 12:34:29 volumio-alpha volumio[1460]: info: Preload queue cleared Apr 23 12:34:31 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 23 12:34:31 volumio-alpha volumio[1460]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101218 Apr 23 12:34:31 volumio-alpha volumio[1460]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101218 Apr 23 12:34:32 volumio-alpha volumio[1460]: info: Preload queue cleared Apr 23 12:34:33 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 23 12:34:33 volumio-alpha volumio[1460]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100436 Apr 23 12:34:33 volumio-alpha volumio[1460]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100436 Apr 23 12:34:34 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:34:34 volumio-alpha volumio[1460]: info: Preload queue cleared Apr 23 12:34:40 volumio-alpha wpa_supplicant[1279]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Apr 23 12:34:44 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Apr 23 12:34:44 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: player Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Apr 23 12:34:44 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Apr 23 12:34:44 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: player Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Apr 23 12:34:44 volumio-alpha volumio[1460]: ---------------------------- MPD announces system playlist update Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Ignoring MPD Status Update Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Apr 23 12:34:44 volumio-alpha volumio[1460]: ---------------------------- MPD announces state update: player Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ControllerMpd::getState Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ------------------------------ 4ms Apr 23 12:34:44 volumio-alpha volumio[1460]: info: sendMpdCommand status took 3 milliseconds Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ------------------------------ 4ms Apr 23 12:34:44 volumio-alpha volumio[1460]: info: sendMpdCommand status took 4 milliseconds Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ------------------------------ 5ms Apr 23 12:34:44 volumio-alpha volumio[1460]: info: sendMpdCommand status took 4 milliseconds Apr 23 12:34:44 volumio-alpha volumio[1460]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 23 12:34:44 volumio-alpha volumio[1460]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseState Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseTrackInfo Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseTrackInfo Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29605,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"John Williams - Battle of The Heroes - London Symphony Orchestra, London Voices, John Williams","artist":null,"album":null,"uri":"https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","trackType":""} Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService play Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus play Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Received an update from plugin. extracting info from payload Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29605,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"John Williams - Battle of The Heroes - London Symphony Orchestra, London Voices, John Williams","artist":null,"album":null,"uri":"https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","trackType":""} Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService play Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus play Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Received an update from plugin. extracting info from payload Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ------------------------------ 24ms Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ------------------------------ 23ms Apr 23 12:34:44 volumio-alpha volumio[1460]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: ControllerMpd::parseTrackInfo Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ControllerMpd::pushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::servicePushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CorePlayQueue::getTrack 0 Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29605,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"John Williams - Battle of The Heroes - London Symphony Orchestra, London Voices, John Williams","artist":null,"album":null,"uri":"https://stream-mz.hellorayo.co.uk/scala.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=mrs","trackType":""} Apr 23 12:34:44 volumio-alpha volumio[1460]: verbose: CURRENT POSITION 0 Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState stateService play Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::syncState currentStatus play Apr 23 12:34:44 volumio-alpha volumio[1460]: info: Received an update from plugin. extracting info from payload Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreStateMachine::pushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: CoreCommandRouter::volumioPushState Apr 23 12:34:44 volumio-alpha volumio[1460]: info: ------------------------------ 32ms Apr 23 12:34:45 volumio-alpha volumio[1460]: Searching plugin music_service/webradio Apr 23 12:34:45 volumio-alpha volumio[1460]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 23 12:34:45 volumio-alpha volumio[1460]: Use XMLElement.setAttribute instead Apr 23 12:34:45 volumio-alpha volumio[1460]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 12:34:45 volumio-alpha volumio[1460]: TypeError: children[i].attr(...).value is not a function Apr 23 12:34:45 volumio-alpha volumio[1460]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Apr 23 12:34:45 volumio-alpha volumio[1460]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 23 12:34:45 volumio-alpha volumio[1460]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 12:34:45 volumio-alpha sudo[2086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-23 12:33' Apr 23 12:34:45 volumio-alpha sudo[2086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="77459d3da1093d939f1a501571c373c95e4a2652" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="0da3792b433b4daf69b90315de2841f333f7f716" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Apr 22 11:07:15 UTC 2025" VOLUMIO_VERSION="0.058" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cdc65c6dbdd6f51850072894d2c25614"