-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2025-06-17 19:05:14 UTC. -- Jun 17 19:04:42 volumio hostapd[1036]: wlan0: STA 02:92:40:5d:7e:da IEEE 802.11: associated Jun 17 19:04:42 volumio hostapd[1036]: wlan0: STA 02:92:40:5d:7e:da RADIUS: starting accounting session 6452D320EEF73C48 Jun 17 19:04:46 volumio dnsmasq-dhcp[1039]: DHCPDISCOVER(wlan0) 02:92:40:5d:7e:da Jun 17 19:04:46 volumio dnsmasq-dhcp[1039]: DHCPOFFER(wlan0) 192.168.211.172 02:92:40:5d:7e:da Jun 17 19:04:46 volumio dnsmasq-dhcp[1039]: DHCPDISCOVER(wlan0) 02:92:40:5d:7e:da Jun 17 19:04:46 volumio dnsmasq-dhcp[1039]: DHCPOFFER(wlan0) 192.168.211.172 02:92:40:5d:7e:da Jun 17 19:04:47 volumio dnsmasq-dhcp[1039]: DHCPREQUEST(wlan0) 192.168.211.172 02:92:40:5d:7e:da Jun 17 19:04:47 volumio dnsmasq-dhcp[1039]: DHCPACK(wlan0) 192.168.211.172 02:92:40:5d:7e:da Jun 17 19:04:51 volumio sudo[4365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 19:04:51 volumio sudo[4365]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:04:51 volumio sudo[4365]: pam_unix(sudo:session): session closed for user root Jun 17 19:04:51 volumio sudo[4367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 19:04:51 volumio sudo[4367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:04:51 volumio sudo[4367]: pam_unix(sudo:session): session closed for user root Jun 17 19:04:51 volumio volumio[1044]: verbose: New Socket.io Connection to 10.80.2.30 from 192.168.211.172 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 3 Jun 17 19:04:52 volumio sudo[4371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 19:04:52 volumio sudo[4371]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:04:52 volumio sudo[4371]: pam_unix(sudo:session): session closed for user root Jun 17 19:04:52 volumio sudo[4373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 19:04:52 volumio sudo[4373]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 19:04:52 volumio sudo[4373]: pam_unix(sudo:session): session closed for user root Jun 17 19:04:52 volumio volumio[1044]: verbose: New Socket.io Connection to 10.80.2.30 from 192.168.211.172 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 3 Jun 17 19:04:52 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 17 19:04:52 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:52 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 17 19:04:52 volumio volumio[1044]: info: Listing playlists Jun 17 19:04:52 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 17 19:04:52 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 17 19:04:52 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 17 19:04:52 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::volumioPlay Jun 17 19:04:54 volumio volumio[1044]: verbose: UNSET VOLATILE Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::play index undefined Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/CALL3.mp3 Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand stop took 5 milliseconds Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand clear took 1 milliseconds Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:04:54 volumio volumio[1044]: info: Jun 17 19:04:54 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:04:54 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:04:54 volumio volumio[1044]: info: Jun 17 19:04:54 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:04:54 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 6 milliseconds Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jun 17 19:04:54 volumio volumio[1044]: error: Upnp client error: Error: This socket has been ended by the other party Jun 17 19:04:54 volumio volumio[1044]: info: ------------------------------ 20ms Jun 17 19:04:54 volumio volumio[1044]: info: ------------------------------ 19ms Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand play took 8 milliseconds Jun 17 19:04:54 volumio volumio[1044]: info: Jun 17 19:04:54 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:04:54 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:04:54 volumio volumio[1044]: info: Jun 17 19:04:54 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Jun 17 19:04:54 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:04:54 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:04:54 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:04:54 volumio volumio[1044]: info: ------------------------------ 34ms Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 23 milliseconds Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:04:54 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:04:54 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jun 17 19:04:54 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:04:54 volumio volumio[1044]: info: ------------------------------ 46ms Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: info: Prefetching next song Jun 17 19:04:54 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 1 milliseconds Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:04:54 volumio volumio[1044]: info: Jun 17 19:04:54 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:04:54 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:04:54 volumio volumio[1044]: info: Jun 17 19:04:54 volumio volumio[1044]: ---------------------------- MPD announces state update: options Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand consume 1 took 4 milliseconds Jun 17 19:04:54 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:04:54 volumio volumio[1044]: info: ------------------------------ 7ms Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:04:54 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 17 19:04:54 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:04:54 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1051,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:04:54 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jun 17 19:04:54 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:04:54 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:04:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:54 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:04:54 volumio volumio[1044]: info: ------------------------------ 35ms Jun 17 19:04:55 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:55 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:55 volumio volumio[1044]: info: Prefetching next song Jun 17 19:04:55 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:04:55 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:04:55 volumio volumio[1044]: info: Jun 17 19:04:55 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:04:55 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:04:55 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 2 milliseconds Jun 17 19:04:55 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:04:55 volumio volumio[1044]: info: ------------------------------ 4ms Jun 17 19:04:55 volumio volumio[1044]: info: sendMpdCommand consume 1 took 2 milliseconds Jun 17 19:04:56 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:04:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:56 volumio volumio[1044]: info: Prefetching next song Jun 17 19:04:56 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:04:56 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:04:56 volumio volumio[1044]: info: Jun 17 19:04:56 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:04:56 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:04:56 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 2 milliseconds Jun 17 19:04:56 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:04:56 volumio volumio[1044]: info: ------------------------------ 4ms Jun 17 19:04:56 volumio volumio[1044]: info: sendMpdCommand consume 1 took 2 milliseconds Jun 17 19:04:56 volumio volumio[1044]: info: Jun 17 19:04:56 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:04:56 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:04:56 volumio volumio[1044]: info: Jun 17 19:04:56 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:04:56 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:04:56 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:04:56 volumio volumio[1044]: info: ------------------------------ 9ms Jun 17 19:04:56 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Jun 17 19:04:56 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:04:56 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:04:56 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 17 19:04:56 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:04:56 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:04:56 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:04:56 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:04:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:56 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:04:56 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:04:56 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:04:56 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jun 17 19:04:56 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Jun 17 19:04:56 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:04:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:56 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:04:56 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:04:56 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:04:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:56 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:04:56 volumio volumio[1044]: info: ------------------------------ 32ms Jun 17 19:04:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:56 volumio volumio[1044]: info: Prefetching next song Jun 17 19:04:56 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:04:56 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:04:56 volumio volumio[1044]: info: Jun 17 19:04:56 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:04:56 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:04:56 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 3 milliseconds Jun 17 19:04:56 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:04:56 volumio volumio[1044]: info: ------------------------------ 5ms Jun 17 19:04:56 volumio volumio[1044]: info: sendMpdCommand consume 1 took 3 milliseconds Jun 17 19:04:59 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:04:59 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:59 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:59 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:59 volumio volumio[1044]: info: Prefetching next song Jun 17 19:04:59 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:04:59 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:04:59 volumio volumio[1044]: info: Jun 17 19:04:59 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:04:59 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:04:59 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 4 milliseconds Jun 17 19:04:59 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:04:59 volumio volumio[1044]: info: ------------------------------ 6ms Jun 17 19:04:59 volumio volumio[1044]: info: sendMpdCommand consume 1 took 3 milliseconds Jun 17 19:04:59 volumio volumio[1044]: info: Jun 17 19:04:59 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:04:59 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:04:59 volumio volumio[1044]: info: Jun 17 19:04:59 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:04:59 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:04:59 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:04:59 volumio volumio[1044]: info: ------------------------------ 6ms Jun 17 19:04:59 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Jun 17 19:04:59 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:04:59 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:04:59 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 17 19:04:59 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:04:59 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:04:59 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:04:59 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:04:59 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:59 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:59 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:04:59 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:04:59 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:04:59 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jun 17 19:04:59 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Jun 17 19:04:59 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:04:59 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:59 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:04:59 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:04:59 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:59 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:04:59 volumio volumio[1044]: info: ------------------------------ 28ms Jun 17 19:04:59 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:59 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:04:59 volumio volumio[1044]: info: Prefetching next song Jun 17 19:04:59 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:04:59 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:04:59 volumio volumio[1044]: info: Jun 17 19:04:59 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:04:59 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:04:59 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 3 milliseconds Jun 17 19:04:59 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:04:59 volumio volumio[1044]: info: ------------------------------ 8ms Jun 17 19:04:59 volumio volumio[1044]: info: sendMpdCommand consume 1 took 5 milliseconds Jun 17 19:05:02 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:05:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:02 volumio volumio[1044]: info: Prefetching next song Jun 17 19:05:02 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:05:02 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:02 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 1 milliseconds Jun 17 19:05:02 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:05:02 volumio volumio[1044]: info: Jun 17 19:05:02 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:02 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:02 volumio volumio[1044]: info: sendMpdCommand consume 1 took 3 milliseconds Jun 17 19:05:02 volumio volumio[1044]: info: ------------------------------ 3ms Jun 17 19:05:02 volumio volumio[1044]: info: Jun 17 19:05:02 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:02 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:02 volumio volumio[1044]: info: Jun 17 19:05:02 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:05:02 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:05:02 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:05:02 volumio volumio[1044]: info: ------------------------------ 5ms Jun 17 19:05:02 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Jun 17 19:05:02 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:05:02 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:05:02 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 17 19:05:02 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:05:02 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:05:02 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:05:02 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:05:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:02 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:05:02 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:05:02 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:05:02 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jun 17 19:05:02 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Jun 17 19:05:02 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:02 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:02 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:02 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:02 volumio volumio[1044]: info: ------------------------------ 26ms Jun 17 19:05:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:02 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:02 volumio volumio[1044]: info: Prefetching next song Jun 17 19:05:02 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:05:02 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:02 volumio volumio[1044]: info: Jun 17 19:05:02 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:02 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:02 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 3 milliseconds Jun 17 19:05:02 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:05:02 volumio volumio[1044]: info: ------------------------------ 7ms Jun 17 19:05:02 volumio volumio[1044]: info: sendMpdCommand consume 1 took 5 milliseconds Jun 17 19:05:05 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:05:05 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:05 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:05 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:05 volumio volumio[1044]: info: Prefetching next song Jun 17 19:05:05 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:05:05 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:05 volumio volumio[1044]: info: Jun 17 19:05:05 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:05 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:05 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 3 milliseconds Jun 17 19:05:05 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:05:05 volumio volumio[1044]: info: ------------------------------ 7ms Jun 17 19:05:05 volumio volumio[1044]: info: sendMpdCommand consume 1 took 4 milliseconds Jun 17 19:05:05 volumio volumio[1044]: info: Jun 17 19:05:05 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:05 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:05 volumio volumio[1044]: info: Jun 17 19:05:05 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:05:05 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:05:05 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:05:05 volumio volumio[1044]: info: ------------------------------ 5ms Jun 17 19:05:05 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Jun 17 19:05:05 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:05:05 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:05:05 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 17 19:05:05 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:05:05 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:05:05 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:05:05 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:05:05 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:05 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:05 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:05:05 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:05:05 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:05:05 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jun 17 19:05:05 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Jun 17 19:05:05 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:05 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:05 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:05 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:05 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:05 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:05 volumio volumio[1044]: info: ------------------------------ 24ms Jun 17 19:05:05 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:05 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:05 volumio volumio[1044]: info: Prefetching next song Jun 17 19:05:05 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:05:05 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:05 volumio volumio[1044]: info: Jun 17 19:05:05 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:05 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:05 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 6 milliseconds Jun 17 19:05:05 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:05:05 volumio volumio[1044]: info: ------------------------------ 6ms Jun 17 19:05:05 volumio volumio[1044]: info: sendMpdCommand consume 1 took 5 milliseconds Jun 17 19:05:08 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:05:08 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:08 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:08 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:08 volumio volumio[1044]: info: Prefetching next song Jun 17 19:05:08 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:05:08 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:08 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 1 milliseconds Jun 17 19:05:08 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:05:08 volumio volumio[1044]: info: Jun 17 19:05:08 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:08 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:08 volumio volumio[1044]: info: sendMpdCommand consume 1 took 2 milliseconds Jun 17 19:05:08 volumio volumio[1044]: info: ------------------------------ 4ms Jun 17 19:05:08 volumio volumio[1044]: info: Jun 17 19:05:08 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:08 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:08 volumio volumio[1044]: info: Jun 17 19:05:08 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:05:08 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:05:08 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:05:08 volumio volumio[1044]: info: ------------------------------ 7ms Jun 17 19:05:08 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Jun 17 19:05:08 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:05:08 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:05:08 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 17 19:05:08 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:05:08 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:05:08 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:05:08 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:05:08 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:08 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:08 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:05:08 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:05:08 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:05:08 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jun 17 19:05:08 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Jun 17 19:05:08 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:08 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:08 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:08 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:08 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:08 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:08 volumio volumio[1044]: info: ------------------------------ 25ms Jun 17 19:05:08 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:08 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:08 volumio volumio[1044]: info: Prefetching next song Jun 17 19:05:08 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:05:08 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:08 volumio volumio[1044]: info: Jun 17 19:05:08 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:08 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:08 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 3 milliseconds Jun 17 19:05:08 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:05:08 volumio volumio[1044]: info: ------------------------------ 7ms Jun 17 19:05:08 volumio volumio[1044]: info: sendMpdCommand consume 1 took 6 milliseconds Jun 17 19:05:11 volumio volumio[1044]: info: CoreCommandRouter::volumioNext Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::next Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::stop Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:05:11 volumio volumio[1044]: verbose: UNSET VOLATILE Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::stPlaybackTimer Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::updateTrackBlock Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrackBlock Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:05:11 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::serviceStop Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: info: CoreCommandRouter::serviceStop Jun 17 19:05:11 volumio volumio[1044]: info: ControllerMpd::stop Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand stop took 109 milliseconds Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::play index undefined Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/CALL3.mp3 Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::updateTrackBlock Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrackBlock Jun 17 19:05:11 volumio volumio[1044]: info: Jun 17 19:05:11 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:05:11 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand stop took 3 milliseconds Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 19:05:11 volumio volumio[1044]: info: Jun 17 19:05:11 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:11 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand clear took 4 milliseconds Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:11 volumio volumio[1044]: error: updateQueue error: null Jun 17 19:05:11 volumio volumio[1044]: info: Jun 17 19:05:11 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:11 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:11 volumio volumio[1044]: info: ------------------------------ 7ms Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 4 milliseconds Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jun 17 19:05:11 volumio volumio[1044]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 17 19:05:11 volumio volumio[1044]: info: ------------------------------ 16ms Jun 17 19:05:11 volumio volumio[1044]: info: Jun 17 19:05:11 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:05:11 volumio volumio[1044]: info: ------------------------------ 12ms Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand play took 9 milliseconds Jun 17 19:05:11 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:05:11 volumio volumio[1044]: info: Jun 17 19:05:11 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:05:11 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand status took 5 milliseconds Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:05:11 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:05:11 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:05:11 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:05:11 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:11 volumio volumio[1044]: info: ------------------------------ 28ms Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:05:11 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:05:11 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:05:11 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jun 17 19:05:11 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:11 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:11 volumio volumio[1044]: info: ------------------------------ 53ms Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:11 volumio volumio[1044]: info: Prefetching next song Jun 17 19:05:11 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:11 volumio volumio[1044]: info: Jun 17 19:05:11 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:11 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 4 milliseconds Jun 17 19:05:11 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:05:11 volumio volumio[1044]: info: ------------------------------ 8ms Jun 17 19:05:11 volumio volumio[1044]: info: sendMpdCommand consume 1 took 5 milliseconds Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::volumioNext Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::next Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::stop Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:05:12 volumio volumio[1044]: verbose: UNSET VOLATILE Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::stPlaybackTimer Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::updateTrackBlock Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrackBlock Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::serviceStop Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::serviceStop Jun 17 19:05:12 volumio volumio[1044]: info: ControllerMpd::stop Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:05:12 volumio volumio[1044]: info: Jun 17 19:05:12 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand stop took 79 milliseconds Jun 17 19:05:12 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::play index undefined Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/CALL3.mp3 Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::updateTrackBlock Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrackBlock Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand status took 10 milliseconds Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand stop took 4 milliseconds Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 19:05:12 volumio volumio[1044]: info: Jun 17 19:05:12 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:12 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand clear took 4 milliseconds Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:12 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:05:12 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:12 volumio volumio[1044]: info: No code Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:12 volumio volumio[1044]: info: ------------------------------ 55ms Jun 17 19:05:12 volumio volumio[1044]: error: updateQueue error: null Jun 17 19:05:12 volumio volumio[1044]: info: Jun 17 19:05:12 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:12 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:12 volumio volumio[1044]: info: ------------------------------ 41ms Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 37 milliseconds Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jun 17 19:05:12 volumio volumio[1044]: info: Jun 17 19:05:12 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:05:12 volumio volumio[1044]: info: ------------------------------ 18ms Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand play took 13 milliseconds Jun 17 19:05:12 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:05:12 volumio volumio[1044]: info: Jun 17 19:05:12 volumio volumio[1044]: ---------------------------- MPD announces state update: player Jun 17 19:05:12 volumio volumio[1044]: info: ControllerMpd::getState Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: Prefetching next song Jun 17 19:05:12 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:12 volumio volumio[1044]: info: Jun 17 19:05:12 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:12 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand status took 11 milliseconds Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 5 milliseconds Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::parseState Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:05:12 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:05:12 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:12 volumio volumio[1044]: info: ------------------------------ 41ms Jun 17 19:05:12 volumio volumio[1044]: info: ------------------------------ 25ms Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand consume 1 took 18 milliseconds Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 17 19:05:12 volumio volumio[1044]: info: ControllerMpd::pushState Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":3,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"59746617.mp3","artist":"TextAloud: IVONA Conchita22 (Spanish [Modern])","album":"Created: 12/11/2020 1:43:56 PM","uri":"INTERNAL/CALL3.mp3","trackType":"mp3"} Jun 17 19:05:12 volumio volumio[1044]: verbose: CURRENT POSITION 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jun 17 19:05:12 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:12 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:12 volumio volumio[1044]: info: ------------------------------ 56ms Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:12 volumio volumio[1044]: info: Prefetching next song Jun 17 19:05:12 volumio volumio[1044]: info: DOING PREFETCH IN MPD Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CALL3.mp3" Jun 17 19:05:12 volumio volumio[1044]: info: Jun 17 19:05:12 volumio volumio[1044]: ---------------------------- MPD announces system playlist update Jun 17 19:05:12 volumio volumio[1044]: info: Ignoring MPD Status Update Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand add "INTERNAL/CALL3.mp3" took 3 milliseconds Jun 17 19:05:12 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 19:05:12 volumio volumio[1044]: info: ------------------------------ 5ms Jun 17 19:05:12 volumio volumio[1044]: info: sendMpdCommand consume 1 took 3 milliseconds Jun 17 19:05:13 volumio volumio[1044]: info: CoreCommandRouter::volumioRandom Jun 17 19:05:13 volumio volumio[1044]: info: CoreCommandRouter::writePlayerControls Jun 17 19:05:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:13 volumio volumio[1044]: info: CoreStateMachine::setRandom true Jun 17 19:05:13 volumio volumio[1044]: info: CoreStateMachine::pushState Jun 17 19:05:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:13 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 19:05:13 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 17 19:05:14 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 17 19:05:14 volumio volumio[1044]: info: CorePlayQueue::getTrack undefined Jun 17 19:05:14 volumio volumio[1044]: info: CorePlayQueue::getTrack undefined Jun 17 19:05:14 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 17 19:05:14 volumio volumio[1044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 19:05:14 volumio volumio[1044]: TypeError: Cannot read property 'service' of undefined Jun 17 19:05:14 volumio volumio[1044]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:431:107) Jun 17 19:05:14 volumio volumio[1044]: at listOnTimeout (internal/timers.js:554:17) Jun 17 19:05:14 volumio volumio[1044]: at processTimers (internal/timers.js:497:7) Jun 17 19:05:14 volumio volumio[1044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 19:05:14 volumio sudo[4456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 19:04 Jun 17 19:05:14 volumio sudo[4456]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"