-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Sun 2025-05-04 19:47:51 EDT. -- May 04 19:46:00 volumio volumio[785]: info: Setting Geolocation for MyVolumio to us1 May 04 19:46:00 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 19:46:00 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 19:46:00 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 19:46:00 volumio volumio[785]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 04 19:46:01 volumio volumio[785]: info: Updating MyVolumio device info May 04 19:46:01 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 19:46:01 volumio volumio[785]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 04 19:46:03 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 19:46:03 volumio volumio[785]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 04 19:46:03 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 04 19:46:03 volumio volumio[785]: info: Received Get System Version May 04 19:46:03 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 19:46:03 volumio volumio[785]: info: Received Get System Info May 04 19:46:03 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 19:46:03 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 19:46:03 volumio volumio[785]: info: Discovery: Getting this device information May 04 19:46:03 volumio volumio[785]: info: CoreCommandRouter::volumioGetState May 04 19:46:03 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:03 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 19:46:04 volumio volumio[785]: info: MYVOLUMIO: Adding device May 04 19:46:04 volumio volumio[785]: info: MYVOLUMIO: Evaluating Server May 04 19:46:05 volumio systemd[1]: systemd-timedated.service: Succeeded. May 04 19:46:05 volumio volumio[785]: info: Setting Geolocation for MyVolumio to us1 May 04 19:46:05 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 19:46:05 volumio volumio[1081]: ..........................................................................................................++++ May 04 19:46:05 volumio volumio[1081]: e is 65537 (0x010001) May 04 19:46:05 volumio volumio[1081]: writing RSA key May 04 19:46:05 volumio volumio[785]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 04 19:46:06 volumio volumio[785]: info: Updating MyVolumio device info May 04 19:46:06 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 19:46:06 volumio volumio[785]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 04 19:46:11 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 19:46:11 volumio volumio[785]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 04 19:46:11 volumio volumio[785]: info: CoreCommandRouter::volumioGetState May 04 19:46:11 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:14 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 19:46:14 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 19:46:14 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 04 19:46:14 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 04 19:46:14 volumio volumio-remote-updater[533]: No test mode May 04 19:46:14 volumio volumio-remote-updater[533]: No alpha test mode May 04 19:46:14 volumio volumio[785]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 04 19:46:14 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 04 19:46:16 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 19:46:16 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 19:46:16 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 04 19:46:16 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 04 19:46:16 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 04 19:46:16 volumio volumio[785]: info: CoreCommandRouter::volumioGetBrowseSources May 04 19:46:16 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 19:46:18 volumio volumio[785]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 04 19:46:18 volumio volumio[785]: info: Completed starting MyVolumio Plugin May 04 19:46:18 volumio volumio[785]: [Metrics] CommandRouter: 45s 69.33ms May 04 19:46:18 volumio volumio[785]: info: CoreCommandRouter::volumiosetStartupVolume May 04 19:46:18 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 19:46:18 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 19:46:18 volumio volumio[785]: info: CoreCommandRouter::Close All Modals sent May 04 19:46:18 volumio volumio[785]: info: CoreCommandRouter::Close All Modals sent May 04 19:46:19 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 04 19:46:19 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 04 19:46:19 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 04 19:46:25 volumio volumio[785]: info: BOOT COMPLETED May 04 19:46:30 volumio ntpd[749]: Soliciting pool server 204.2.134.172 May 04 19:46:31 volumio ntpd[749]: Soliciting pool server 173.11.101.155 May 04 19:46:32 volumio ntpd[749]: Soliciting pool server 72.14.183.39 May 04 19:46:33 volumio ntpd[749]: Soliciting pool server 99.28.14.242 May 04 19:46:33 volumio ntpd[749]: Soliciting pool server 66.118.231.14 May 04 19:46:33 volumio ntpd[749]: Soliciting pool server 155.248.196.28 May 04 19:46:34 volumio ntpd[749]: Soliciting pool server 23.168.136.132 May 04 19:46:34 volumio ntpd[749]: Soliciting pool server 148.113.194.34 May 04 19:46:34 volumio ntpd[749]: Soliciting pool server 208.67.72.43 May 04 19:46:34 volumio ntpd[749]: Soliciting pool server 149.28.61.105 May 04 19:46:35 volumio ntpd[749]: Soliciting pool server 5.78.62.36 May 04 19:46:35 volumio ntpd[749]: Soliciting pool server 73.65.80.137 May 04 19:46:35 volumio ntpd[749]: Soliciting pool server 168.235.89.132 May 04 19:46:35 volumio ntpd[749]: Soliciting pool server 23.186.168.126 May 04 19:46:36 volumio ntpd[749]: Soliciting pool server 45.41.204.203 May 04 19:46:36 volumio ntpd[749]: Soliciting pool server 23.155.40.38 May 04 19:46:36 volumio ntpd[749]: Soliciting pool server 23.186.168.123 May 04 19:46:36 volumio ntpd[749]: Soliciting pool server 67.198.37.16 May 04 19:46:39 volumio ntpd[749]: receive: Unexpected origin timestamp 0xebc27adf.187077f7 does not match aorg 0000000000.00000000 from server@23.168.136.132 xmt 0xebc27adf.ed413ebe May 04 19:46:39 volumio ntpd[749]: receive: Unexpected origin timestamp 0xebc27adf.18740107 does not match aorg 0000000000.00000000 from server@204.2.134.172 xmt 0xebc27adf.f34f0760 May 04 19:46:40 volumio ntpd[749]: receive: Unexpected origin timestamp 0xebc27adf.18634bc9 does not match aorg 0000000000.00000000 from server@23.186.168.123 xmt 0xebc27adf.f143e530 May 04 19:46:40 volumio ntpd[749]: receive: Unexpected origin timestamp 0xebc27adf.1873286e does not match aorg 0000000000.00000000 from server@173.11.101.155 xmt 0xebc27adf.f5922e05 May 04 19:46:40 volumio volumio[785]: info: Clearing queue after UPNP request May 04 19:46:40 volumio volumio[785]: info: CoreStateMachine::ClearQueue May 04 19:46:40 volumio volumio[785]: info: CoreStateMachine::stop May 04 19:46:40 volumio volumio[785]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 19:46:40 volumio volumio[785]: info: CorePlayQueue::clearPlayQueue May 04 19:46:40 volumio volumio[785]: info: CorePlayQueue::saveQueue May 04 19:46:40 volumio volumio[785]: info: CoreCommandRouter::volumioPushState May 04 19:46:40 volumio volumio[785]: info: CoreCommandRouter::volumioPushQueue May 04 19:46:40 volumio volumio[785]: info: May 04 19:46:40 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:40 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:40 volumio volumio[785]: info: May 04 19:46:40 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:40 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:40 volumio volumio[785]: info: May 04 19:46:40 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:40 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:40 volumio volumio[785]: error: updateQueue error: null May 04 19:46:40 volumio volumio[785]: error: updateQueue error: null May 04 19:46:40 volumio volumio[785]: error: updateQueue error: null May 04 19:46:40 volumio volumio[785]: info: ------------------------------ 10ms May 04 19:46:40 volumio volumio[785]: info: ------------------------------ 8ms May 04 19:46:40 volumio volumio[785]: info: ------------------------------ 6ms May 04 19:46:40 volumio volumio[785]: info: Starting UPNP Playback May 04 19:46:40 volumio volumio[785]: info: Preparing playback through UPNP May 04 19:46:40 volumio volumio[785]: info: CoreCommandRouter::volumioGetState May 04 19:46:40 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:40 volumio volumio[785]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 7ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 5ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 5ms May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 6ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 5ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 3ms May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 6ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 4ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 3ms May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 6ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 4ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 3ms May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 5ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 5ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 3ms May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 6ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 4ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 2ms May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand status took 5 milliseconds May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand status took 12 milliseconds May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand playlistinfo took 10 milliseconds May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand status took 8 milliseconds May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand status took 7 milliseconds May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:41 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:41 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"efc1fae8e1ad4b468f4f4560cc7668d7.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/DMw5v2DW/uuid:548a2cb5-2925-37ea-1818-b827eb692424/efc1fae8e1ad4b468f4f4560cc7668d7.flac","trackType":"flac"} May 04 19:46:41 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus stop May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 43ms May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:41 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:41 volumio volumio[785]: info: May 04 19:46:41 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 35ms May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand status took 33 milliseconds May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand playlistinfo took 27 milliseconds May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand playlistinfo took 23 milliseconds May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand playlistinfo took 23 milliseconds May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 12ms May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand status took 8 milliseconds May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 7ms May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand status took 5 milliseconds May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:41 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:41 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"741 Kbps","isStreaming":false,"title":"efc1fae8e1ad4b468f4f4560cc7668d7.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/DMw5v2DW/uuid:548a2cb5-2925-37ea-1818-b827eb692424/efc1fae8e1ad4b468f4f4560cc7668d7.flac","trackType":"flac"} May 04 19:46:41 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:41 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:41 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:41 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:41 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"741 Kbps","isStreaming":false,"title":"efc1fae8e1ad4b468f4f4560cc7668d7.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/DMw5v2DW/uuid:548a2cb5-2925-37ea-1818-b827eb692424/efc1fae8e1ad4b468f4f4560cc7668d7.flac","trackType":"flac"} May 04 19:46:41 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:41 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:41 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:41 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:41 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"741 Kbps","isStreaming":false,"title":"efc1fae8e1ad4b468f4f4560cc7668d7.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/DMw5v2DW/uuid:548a2cb5-2925-37ea-1818-b827eb692424/efc1fae8e1ad4b468f4f4560cc7668d7.flac","trackType":"flac"} May 04 19:46:41 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:41 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:41 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:41 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:41 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:41 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 75ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 66ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 64ms May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand playlistinfo took 25 milliseconds May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand playlistinfo took 22 milliseconds May 04 19:46:41 volumio volumio[785]: info: sendMpdCommand playlistinfo took 22 milliseconds May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:41 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:41 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:41 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"732 Kbps","isStreaming":false,"title":"efc1fae8e1ad4b468f4f4560cc7668d7.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/DMw5v2DW/uuid:548a2cb5-2925-37ea-1818-b827eb692424/efc1fae8e1ad4b468f4f4560cc7668d7.flac","trackType":"flac"} May 04 19:46:41 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:41 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:41 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:41 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:41 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"732 Kbps","isStreaming":false,"title":"efc1fae8e1ad4b468f4f4560cc7668d7.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/DMw5v2DW/uuid:548a2cb5-2925-37ea-1818-b827eb692424/efc1fae8e1ad4b468f4f4560cc7668d7.flac","trackType":"flac"} May 04 19:46:41 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:41 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:41 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:41 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:41 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:41 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"732 Kbps","isStreaming":false,"title":"efc1fae8e1ad4b468f4f4560cc7668d7.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/DMw5v2DW/uuid:548a2cb5-2925-37ea-1818-b827eb692424/efc1fae8e1ad4b468f4f4560cc7668d7.flac","trackType":"flac"} May 04 19:46:41 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:41 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:41 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:41 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:41 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:41 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:41 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:41 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 79ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 56ms May 04 19:46:41 volumio volumio[785]: info: ------------------------------ 49ms May 04 19:46:44 volumio ntpd[749]: receive: Unexpected origin timestamp 0xebc27ae4.e874523d does not match aorg 0000000000.00000000 from server@23.168.136.132 xmt 0xebc27ae4.ed3de01e May 04 19:46:46 volumio ntpd[749]: receive: Unexpected origin timestamp 0xebc27ae6.e871f00a does not match aorg 0000000000.00000000 from server@23.168.136.132 xmt 0xebc27ae6.ec2e7bee May 04 19:46:48 volumio ntpd[749]: receive: Unexpected origin timestamp 0xebc27ae8.e872f599 does not match aorg 0000000000.00000000 from server@23.168.136.132 xmt 0xebc27ae8.ec5b8858 May 04 19:46:50 volumio ntpd[749]: receive: Unexpected origin timestamp 0xebc27aea.e876ad51 does not match aorg 0000000000.00000000 from server@23.168.136.132 xmt 0xebc27aea.ecf491de May 04 19:46:52 volumio ntpd[749]: receive: Unexpected origin timestamp 0xebc27aec.e871f913 does not match aorg 0000000000.00000000 from server@23.168.136.132 xmt 0xebc27aec.f1ecaa14 May 04 19:46:54 volumio ntpd[749]: receive: Unexpected origin timestamp 0xebc27aee.e86d3261 does not match aorg 0000000000.00000000 from server@23.168.136.132 xmt 0xebc27aee.ed1f3d1c May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:57 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:57 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:57 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:57 volumio volumio[785]: info: sendMpdCommand status took 7 milliseconds May 04 19:46:57 volumio volumio[785]: info: sendMpdCommand status took 4 milliseconds May 04 19:46:57 volumio volumio[785]: info: sendMpdCommand status took 2 milliseconds May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:57 volumio volumio[785]: info: sendMpdCommand playlistinfo took 3 milliseconds May 04 19:46:57 volumio volumio[785]: info: sendMpdCommand playlistinfo took 3 milliseconds May 04 19:46:57 volumio volumio[785]: info: sendMpdCommand playlistinfo took 3 milliseconds May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:57 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:57 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:57 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:57 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:57 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:57 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:57 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:57 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:57 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:57 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:57 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:57 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 28ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 24ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 21ms May 04 19:46:57 volumio volumio[785]: info: Clearing queue after UPNP request May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::ClearQueue May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::stop May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::stPlaybackTimer May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::updateTrackBlock May 04 19:46:57 volumio volumio[785]: info: CorePlayQueue::getTrackBlock May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:57 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:57 volumio volumio[785]: info: CoreCommandRouter::volumioPushState May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::serviceStop May 04 19:46:57 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:57 volumio volumio[785]: info: ControllerMpd::stop May 04 19:46:57 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand stop May 04 19:46:57 volumio volumio[785]: info: CorePlayQueue::clearPlayQueue May 04 19:46:57 volumio volumio[785]: info: CorePlayQueue::saveQueue May 04 19:46:57 volumio volumio[785]: info: CoreCommandRouter::volumioPushState May 04 19:46:57 volumio volumio[785]: info: CoreCommandRouter::volumioPushQueue May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: sendMpdCommand stop took 18 milliseconds May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: error: updateQueue error: null May 04 19:46:57 volumio volumio[785]: error: updateQueue error: null May 04 19:46:57 volumio volumio[785]: error: updateQueue error: null May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 7ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 5ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 3ms May 04 19:46:57 volumio volumio[785]: info: Starting UPNP Playback May 04 19:46:57 volumio volumio[785]: info: Preparing playback through UPNP May 04 19:46:57 volumio volumio[785]: info: CoreCommandRouter::volumioGetState May 04 19:46:57 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:57 volumio volumio[785]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 6ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 5ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 4ms May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 6ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 4ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 3ms May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 6ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 4ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 2ms May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 5ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 4ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 3ms May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 5ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 3ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 3ms May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: May 04 19:46:57 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:57 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 5ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 4ms May 04 19:46:57 volumio volumio[785]: info: ------------------------------ 2ms May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:58 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand status took 10 milliseconds May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand status took 8 milliseconds May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand status took 6 milliseconds May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:58 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand status took 11 milliseconds May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 14ms May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand status took 11 milliseconds May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand playlistinfo took 10 milliseconds May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand playlistinfo took 9 milliseconds May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand playlistinfo took 9 milliseconds May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand status took 6 milliseconds May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 6ms May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand status took 5 milliseconds May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:58 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:58 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"699dca1b26e546edac6ccef823436934.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/V9oWt53c/uuid:548a2cb5-2925-37ea-1818-b827eb692424/699dca1b26e546edac6ccef823436934.flac","trackType":"flac"} May 04 19:46:58 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus stop May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:58 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:58 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"699dca1b26e546edac6ccef823436934.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/V9oWt53c/uuid:548a2cb5-2925-37ea-1818-b827eb692424/699dca1b26e546edac6ccef823436934.flac","trackType":"flac"} May 04 19:46:58 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:58 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:58 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:58 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:58 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"699dca1b26e546edac6ccef823436934.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/V9oWt53c/uuid:548a2cb5-2925-37ea-1818-b827eb692424/699dca1b26e546edac6ccef823436934.flac","trackType":"flac"} May 04 19:46:58 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:58 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:58 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 43ms May 04 19:46:58 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:58 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 42ms May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 38ms May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces system playlist update May 04 19:46:58 volumio volumio[785]: info: Ignoring MPD Status Update May 04 19:46:58 volumio volumio[785]: info: May 04 19:46:58 volumio volumio[785]: ---------------------------- MPD announces state update: player May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::getState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand status May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand playlistinfo took 22 milliseconds May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand playlistinfo took 22 milliseconds May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand playlistinfo took 21 milliseconds May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand playlistinfo took 22 milliseconds May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 6ms May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand status took 6 milliseconds May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseState May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:58 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:58 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"498 Kbps","isStreaming":false,"title":"699dca1b26e546edac6ccef823436934.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/V9oWt53c/uuid:548a2cb5-2925-37ea-1818-b827eb692424/699dca1b26e546edac6ccef823436934.flac","trackType":"flac"} May 04 19:46:58 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:58 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:58 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:58 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:58 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"498 Kbps","isStreaming":false,"title":"699dca1b26e546edac6ccef823436934.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/V9oWt53c/uuid:548a2cb5-2925-37ea-1818-b827eb692424/699dca1b26e546edac6ccef823436934.flac","trackType":"flac"} May 04 19:46:58 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:58 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:58 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:58 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:58 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"498 Kbps","isStreaming":false,"title":"699dca1b26e546edac6ccef823436934.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/V9oWt53c/uuid:548a2cb5-2925-37ea-1818-b827eb692424/699dca1b26e546edac6ccef823436934.flac","trackType":"flac"} May 04 19:46:58 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:58 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:58 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:58 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:58 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"498 Kbps","isStreaming":false,"title":"699dca1b26e546edac6ccef823436934.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/V9oWt53c/uuid:548a2cb5-2925-37ea-1818-b827eb692424/699dca1b26e546edac6ccef823436934.flac","trackType":"flac"} May 04 19:46:58 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:58 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:58 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:58 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:58 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:58 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:58 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 68ms May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 68ms May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 60ms May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 59ms May 04 19:46:58 volumio volumio[785]: info: sendMpdCommand playlistinfo took 35 milliseconds May 04 19:46:58 volumio volumio[785]: verbose: ControllerMpd::parseTrackInfo May 04 19:46:58 volumio volumio[785]: info: ControllerMpd::pushState May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::servicePushState May 04 19:46:58 volumio volumio[785]: verbose: In UPNP mode May 04 19:46:58 volumio volumio[785]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"526 Kbps","isStreaming":false,"title":"699dca1b26e546edac6ccef823436934.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.23:8097/flow/V9oWt53c/uuid:548a2cb5-2925-37ea-1818-b827eb692424/699dca1b26e546edac6ccef823436934.flac","trackType":"flac"} May 04 19:46:58 volumio volumio[785]: verbose: CURRENT POSITION 0 May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState stateService play May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::syncState currentStatus play May 04 19:46:58 volumio volumio[785]: info: Received an update from plugin. extracting info from payload May 04 19:46:58 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 04 19:46:58 volumio volumio[785]: info: CoreStateMachine::pushState May 04 19:46:58 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:46:58 volumio volumio[785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 04 19:46:58 volumio volumio[785]: info: ------------------------------ 56ms May 04 19:47:51 volumio volumio[785]: verbose: New Socket.io Connection to 192.168.1.211 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/BP22.250325.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 04 19:47:51 volumio volumio[785]: verbose: New Socket.io Connection to 192.168.1.211 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/BP22.250325.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 04 19:47:51 volumio volumio[785]: verbose: New Socket.io Connection to 192.168.1.211 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/BP22.250325.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 04 19:47:51 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 19:47:51 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 19:47:51 volumio volumio[785]: info: Discovery: Getting this device information May 04 19:47:51 volumio volumio[785]: info: CoreCommandRouter::volumioGetState May 04 19:47:51 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:47:51 volumio volumio[785]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined May 04 19:47:51 volumio volumio[785]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) May 04 19:47:51 volumio volumio[785]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) May 04 19:47:51 volumio volumio[785]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:464:38) May 04 19:47:51 volumio volumio[785]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 04 19:47:51 volumio volumio[785]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:617:45) May 04 19:47:51 volumio volumio[785]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 04 19:47:51 volumio volumio[785]: at RESTApiSystem.getSystemInfo (/volumio/app/plugins/user_interface/rest_api/system.js:38:41) May 04 19:47:51 volumio volumio[785]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 04 19:47:51 volumio volumio[785]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) May 04 19:47:51 volumio volumio[785]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) May 04 19:47:51 volumio volumio[785]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 04 19:47:51 volumio volumio[785]: at /volumio/node_modules/express/lib/router/index.js:281:22 May 04 19:47:51 volumio volumio[785]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) May 04 19:47:51 volumio volumio[785]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) May 04 19:47:51 volumio volumio[785]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) May 04 19:47:51 volumio volumio[785]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 04 19:47:51 volumio volumio[785]: verbose: New Socket.io Connection to 192.168.1.211 from 192.168.1.146 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/BP22.250325.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 04 19:47:51 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 19:47:51 volumio volumio[785]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 04 19:47:51 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 04 19:47:51 volumio volumio[785]: info: Received Get System Version May 04 19:47:51 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 19:47:51 volumio volumio[785]: info: Received Get System Info May 04 19:47:51 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 19:47:51 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 19:47:51 volumio volumio[785]: info: Discovery: Getting this device information May 04 19:47:51 volumio volumio[785]: info: CoreCommandRouter::volumioGetState May 04 19:47:51 volumio volumio[785]: info: CorePlayQueue::getTrack 0 May 04 19:47:51 volumio volumio[785]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 19:47:51 volumio volumio[785]: TypeError: Cannot read property 'name' of undefined May 04 19:47:51 volumio volumio[785]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) May 04 19:47:51 volumio volumio[785]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) May 04 19:47:51 volumio volumio[785]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:464:38) May 04 19:47:51 volumio volumio[785]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 04 19:47:51 volumio volumio[785]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:617:45) May 04 19:47:51 volumio volumio[785]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 04 19:47:51 volumio volumio[785]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1057:49) May 04 19:47:51 volumio volumio[785]: at Socket.emit (events.js:315:20) May 04 19:47:51 volumio volumio[785]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 04 19:47:51 volumio volumio[785]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 04 19:47:51 volumio volumio[785]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 19:47:51 volumio sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 19:46 May 04 19:47:51 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"