-- Logs begin at Tue 2024-09-17 17:06:54 CEST, end at Mon 2024-10-28 07:44:29 CET. -- Oct 28 07:43:10 volumio ntpd[767]: receive: Unexpected origin timestamp 0xea9419e1.44615458 does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xeac9b0fe.b2f3f510 Oct 28 07:43:10 volumio ntpd[767]: receive: Unexpected origin timestamp 0xea9419e1.445dac79 does not match aorg 0000000000.00000000 from server@185.224.145.68 xmt 0xeac9b0fe.b37901b3 Oct 28 07:43:10 volumio systemd[1]: Starting Daily apt download activities... Oct 28 07:43:10 volumio volumio[1016]: info: Discovery: A device disappeared from network Oct 28 07:43:10 volumio volumio[1016]: info: Discovery: Device volumio disappeared from network Oct 28 07:43:11 volumio volumio[1016]: info: Discovery: adding 2e497612-73f6-4249-9761-834e7133541a Oct 28 07:43:11 volumio volumio[1016]: info: Discovery: Found device Volumio Oct 28 07:43:11 volumio volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 28 07:43:11 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 Oct 28 07:43:12 volumio systemd[1]: apt-daily.service: Succeeded. Oct 28 07:43:12 volumio systemd[1]: Started Daily apt download activities. Oct 28 07:43:12 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Oct 28 07:43:12 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 28 07:43:14 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 28 07:43:14 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 28 07:43:16 volumio kernel: hwmon hwmon1: Voltage normalised Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 28 07:43:17 volumio volumio[1016]: info: Adding plugin bluetooth to MyMusic Plugins Oct 28 07:43:17 volumio volumio[1016]: info: Adding plugin multiroom to MyMusic Plugins Oct 28 07:43:17 volumio volumio[1016]: info: Adding plugin metavolumio to MyMusic Plugins Oct 28 07:43:17 volumio volumio[1016]: info: Adding plugin cd_controller to MyMusic Plugins Oct 28 07:43:17 volumio volumio[1016]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 28 07:43:17 volumio volumio[1016]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 28 07:43:17 volumio volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 28 07:43:19 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 28 07:43:19 volumio volumio[1016]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 28 07:43:19 volumio volumio[1016]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 28 07:43:19 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 07:43:19 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 07:43:19 volumio volumio[1016]: info: Starting MyVolumio Remote Streaming Endpoints Oct 28 07:43:19 volumio volumio[1016]: info: MyVolumio not started Oct 28 07:43:19 volumio volumio[1016]: info: Initializing device activation check Oct 28 07:43:20 volumio volumio[1016]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 28 07:43:20 volumio volumio[1016]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 28 07:43:22 volumio volumio[1016]: info: Starting Streaming Service Transparent Proxy Oct 28 07:43:22 volumio volumio[1016]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 28 07:43:22 volumio volumio[1016]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 28 07:43:22 volumio volumio[1016]: info: Streaming services startup Oct 28 07:43:22 volumio volumio[1016]: info: Starting Streaming Daemon Oct 28 07:43:22 volumio sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 28 07:43:22 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 07:43:22 volumio volumio[1016]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 28 07:43:22 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root Oct 28 07:43:22 volumio volumio[1016]: error: Cannot start Volumio Streaming Daemon Oct 28 07:43:22 volumio volumio[1016]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 28 07:43:22 volumio volumio[1016]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 28 07:43:24 volumio volumio[1016]: STREAMING PROXY: Starting server on port 3245 Oct 28 07:43:24 volumio volumio[1016]: Node JS runtime: 14 Oct 28 07:43:29 volumio kernel: hwmon hwmon1: Voltage normalised Oct 28 07:43:34 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 07:43:34 volumio volumio[1016]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 28 07:43:34 volumio volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 28 07:43:34 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 Oct 28 07:43:39 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 07:43:39 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 07:43:39 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 28 07:43:39 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 28 07:43:39 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 28 07:43:39 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 28 07:43:39 volumio volumio[1016]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 07:43:39 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 07:43:41 volumio volumio[1016]: info: Clearing queue after UPNP request Oct 28 07:43:41 volumio volumio[1016]: info: CoreStateMachine::ClearQueue Oct 28 07:43:41 volumio volumio[1016]: info: CoreStateMachine::stop Oct 28 07:43:41 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 07:43:41 volumio volumio[1016]: info: CorePlayQueue::clearPlayQueue Oct 28 07:43:41 volumio volumio[1016]: info: CorePlayQueue::saveQueue Oct 28 07:43:41 volumio volumio[1016]: info: CoreCommandRouter::volumioPushState Oct 28 07:43:41 volumio volumio[1016]: info: CoreCommandRouter::volumioPushQueue Oct 28 07:43:41 volumio volumio[1016]: error: Upnp client error: Error: This socket has been ended by the other party Oct 28 07:43:41 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 28 07:43:43 volumio volumio[1016]: info: Starting UPNP Playback Oct 28 07:43:43 volumio volumio[1016]: info: Preparing playback through UPNP Oct 28 07:43:43 volumio volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 28 07:43:43 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 Oct 28 07:43:43 volumio volumio[1016]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 18ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 13ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 10ms Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 13ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 10ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 7ms Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 30ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 9ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 7ms Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 12ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 9ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 6ms Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 11ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 8ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 6ms Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces system playlist update Oct 28 07:43:43 volumio volumio[1016]: info: Ignoring MPD Status Update Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 33ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 30ms Oct 28 07:43:43 volumio volumio[1016]: info: ------------------------------ 7ms Oct 28 07:43:43 volumio volumio[1218]: 56 Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces state update: player Oct 28 07:43:43 volumio volumio[1016]: info: ControllerMpd::getState Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces state update: player Oct 28 07:43:43 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 28 07:43:43 volumio volumio[1016]: info: ControllerMpd::getState Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces state update: player Oct 28 07:43:43 volumio volumio[1016]: info: ControllerMpd::getState Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 28 07:43:43 volumio volumio[1016]: info: sendMpdCommand status took 25 milliseconds Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::parseState Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 07:43:43 volumio volumio[1016]: info: Oct 28 07:43:43 volumio volumio[1016]: ---------------------------- MPD announces state update: player Oct 28 07:43:43 volumio volumio[1016]: info: ControllerMpd::getState Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 28 07:43:43 volumio volumio[1016]: info: sendMpdCommand status took 31 milliseconds Oct 28 07:43:43 volumio volumio[1016]: info: sendMpdCommand status took 28 milliseconds Oct 28 07:43:43 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::parseState Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::parseState Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 07:43:43 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 28 07:43:43 volumio volumio[1016]: info: ControllerMpd::pushState Oct 28 07:43:43 volumio volumio[1016]: info: CoreCommandRouter::servicePushState Oct 28 07:43:43 volumio volumio[1016]: verbose: In UPNP mode Oct 28 07:43:44 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41041/HiFy.LPCM","trackType":"LPCM"} Oct 28 07:43:44 volumio volumio[1016]: verbose: CURRENT POSITION 0 Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus stop Oct 28 07:43:44 volumio volumio[1218]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 28 07:43:44 volumio volumio[1218]: Dload Upload Total Spent Left Speed Oct 28 07:43:44 volumio volumio[1016]: info: ------------------------------ 71ms Oct 28 07:43:44 volumio volumio[1016]: info: Oct 28 07:43:44 volumio volumio[1016]: ---------------------------- MPD announces state update: player Oct 28 07:43:44 volumio volumio[1016]: info: ControllerMpd::getState Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 28 07:43:44 volumio volumio[1016]: info: Oct 28 07:43:44 volumio volumio[1016]: ---------------------------- MPD announces state update: player Oct 28 07:43:44 volumio volumio[1016]: info: ControllerMpd::getState Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand status Oct 28 07:43:44 volumio volumio[1016]: info: sendMpdCommand status took 41 milliseconds Oct 28 07:43:44 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 28 07:43:44 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 28 07:43:44 volumio volumio[1016]: info: sendMpdCommand status took 11 milliseconds Oct 28 07:43:44 volumio volumio[1016]: info: sendMpdCommand status took 8 milliseconds Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::parseState Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::parseState Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::parseState Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 07:43:44 volumio volumio[1016]: info: ControllerMpd::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CoreCommandRouter::servicePushState Oct 28 07:43:44 volumio volumio[1016]: verbose: In UPNP mode Oct 28 07:43:44 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41041/HiFy.LPCM","trackType":"LPCM"} Oct 28 07:43:44 volumio volumio[1016]: verbose: CURRENT POSITION 0 Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 28 07:43:44 volumio volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 28 07:43:44 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 Oct 28 07:43:44 volumio volumio[1016]: info: ControllerMpd::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CoreCommandRouter::servicePushState Oct 28 07:43:44 volumio volumio[1016]: verbose: In UPNP mode Oct 28 07:43:44 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41041/HiFy.LPCM","trackType":"LPCM"} Oct 28 07:43:44 volumio volumio[1016]: verbose: CURRENT POSITION 0 Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 28 07:43:44 volumio volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 28 07:43:44 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 Oct 28 07:43:44 volumio volumio[1016]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 28 07:43:44 volumio volumio[1016]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 28 07:43:44 volumio volumio[1016]: info: ------------------------------ 106ms Oct 28 07:43:44 volumio volumio[1016]: info: ------------------------------ 100ms Oct 28 07:43:44 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 28 07:43:44 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 28 07:43:44 volumio volumio[1016]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 28 07:43:44 volumio volumio[1016]: verbose: ControllerMpd::parseTrackInfo Oct 28 07:43:44 volumio volumio[1016]: info: ControllerMpd::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CoreCommandRouter::servicePushState Oct 28 07:43:44 volumio volumio[1016]: verbose: In UPNP mode Oct 28 07:43:44 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41041/HiFy.LPCM","trackType":"LPCM"} Oct 28 07:43:44 volumio volumio[1016]: verbose: CURRENT POSITION 0 Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 28 07:43:44 volumio volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 28 07:43:44 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 Oct 28 07:43:44 volumio volumio[1016]: info: ControllerMpd::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CoreCommandRouter::servicePushState Oct 28 07:43:44 volumio volumio[1016]: verbose: In UPNP mode Oct 28 07:43:44 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41041/HiFy.LPCM","trackType":"LPCM"} Oct 28 07:43:44 volumio volumio[1016]: verbose: CURRENT POSITION 0 Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 28 07:43:44 volumio volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 28 07:43:44 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 Oct 28 07:43:44 volumio volumio[1016]: info: ControllerMpd::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CoreCommandRouter::servicePushState Oct 28 07:43:44 volumio volumio[1016]: verbose: In UPNP mode Oct 28 07:43:44 volumio volumio[1016]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.2.14:41041/HiFy.LPCM","trackType":"LPCM"} Oct 28 07:43:44 volumio volumio[1016]: verbose: CURRENT POSITION 0 Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState stateService play Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::syncState currentStatus play Oct 28 07:43:44 volumio volumio[1016]: info: Received an update from plugin. extracting info from payload Oct 28 07:43:44 volumio volumio[1016]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 Oct 28 07:43:44 volumio volumio[1016]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 28 07:43:44 volumio volumio[1016]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 28 07:43:44 volumio volumio[1016]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 28 07:43:44 volumio volumio[1016]: info: ------------------------------ 111ms Oct 28 07:43:44 volumio volumio[1016]: info: ------------------------------ 77ms Oct 28 07:43:44 volumio volumio[1016]: info: ------------------------------ 73ms Oct 28 07:43:44 volumio volumio[1016]: info: Oct 28 07:43:44 volumio volumio[1016]: ---------------------------- Client requests Volume 56 Oct 28 07:43:44 volumio volumio[1016]: info: VolumeController::SetAlsaVolume56 Oct 28 07:43:44 volumio volumio[1016]: info: CoreStateMachine::pushState Oct 28 07:43:44 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 Oct 28 07:43:44 volumio volumio[1218]: [158B blob data] Oct 28 07:43:44 volumio volumio[1218]: {"time":1730097824115,"response":"volume Success"}56 Oct 28 07:43:48 volumio kernel: hwmon hwmon1: Voltage normalised Oct 28 07:43:56 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 28 07:44:02 volumio kernel: hwmon hwmon1: Voltage normalised Oct 28 07:44:17 volumio dhcpcd[929]: wlan1: carrier acquired Oct 28 07:44:17 volumio dhcpcd[929]: wlan1: connected to Access Point `KPN713016' Oct 28 07:44:17 volumio dhcpcd[929]: wlan1: IAID eb:fe:21:9a Oct 28 07:44:17 volumio dhcpcd[929]: wlan1: adding address fe80::ba27:ebff:fefe:219a Oct 28 07:44:17 volumio dhcpcd[929]: ipv6_addaddr1: Permission denied Oct 28 07:44:18 volumio dhcpcd[929]: wlan1: rebinding lease of 192.168.2.30 Oct 28 07:44:18 volumio dhcpcd[929]: wlan1: soliciting an IPv6 router Oct 28 07:44:19 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 28 07:44:22 volumio dhcpcd[929]: wlan1: probing address 192.168.2.30/24 Oct 28 07:44:23 volumio kernel: hwmon hwmon1: Voltage normalised Oct 28 07:44:27 volumio dhcpcd[929]: wlan1: leased 192.168.2.30 for 86400 seconds Oct 28 07:44:27 volumio avahi-daemon[673]: Joining mDNS multicast group on interface wlan1.IPv4 with address 192.168.2.30. Oct 28 07:44:27 volumio dhcpcd[929]: wlan1: adding route to 192.168.2.0/24 Oct 28 07:44:27 volumio avahi-daemon[673]: New relevant interface wlan1.IPv4 for mDNS. Oct 28 07:44:27 volumio dhcpcd[929]: wlan1: adding default route via 192.168.2.254 Oct 28 07:44:27 volumio avahi-daemon[673]: Registering new address record for 192.168.2.30 on wlan1.IPv4. Oct 28 07:44:27 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 28 07:44:28 volumio volumio[1016]: info: Discovery: this is already registered, 2e497612-73f6-4249-9761-834e7133541a Oct 28 07:44:28 volumio volumio[1016]: info: Discovery: Found device Volumio Oct 28 07:44:28 volumio volumio[1016]: info: CoreCommandRouter::volumioGetState Oct 28 07:44:28 volumio volumio[1016]: info: CorePlayQueue::getTrack 0 Oct 28 07:44:28 volumio volumio[1016]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 07:44:28 volumio volumio[1016]: TypeError: Cannot read property 'name' of undefined Oct 28 07:44:28 volumio volumio[1016]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 28 07:44:28 volumio volumio[1016]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 28 07:44:28 volumio volumio[1016]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:272:40) Oct 28 07:44:28 volumio volumio[1016]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Oct 28 07:44:28 volumio volumio[1016]: at Browser.emit (events.js:315:20) Oct 28 07:44:28 volumio volumio[1016]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 28 07:44:28 volumio volumio[1016]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Oct 28 07:44:28 volumio volumio[1016]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Oct 28 07:44:28 volumio volumio[1016]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 28 07:44:28 volumio volumio[1016]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 28 07:44:28 volumio volumio[1016]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 28 07:44:28 volumio volumio[1016]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 28 07:44:28 volumio volumio[1016]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 07:44:28 volumio ntpd[767]: Listen normally on 4 wlan1 192.168.2.30:123 Oct 28 07:44:28 volumio ntpd[767]: new interface(s) found: waking up resolver Oct 28 07:44:29 volumio sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 07:43 Oct 28 07:44:29 volumio sudo[1611]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"