-- Logs begin at Sun 2025-02-23 22:48:26 CST, end at Sun 2025-02-23 23:17:23 CST. -- Feb 23 23:16:16 volumio volumio[891]: info: CorePlayQueue::getQueue Feb 23 23:16:16 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:16 volumio volumio[891]: info: [1740374176392] ControllerPandora::ExpireOldTracks::reaper Feb 23 23:16:16 volumio volumio[891]: info: [1740374176392] [Pandora] ExpireOldTracks::reaper: No victims found: Expiring zero tracks. Don't worry -- Jason will return. Feb 23 23:16:20 volumio volumio[891]: info: Airplay started streaming, receiving metadatas Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::volumioStop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::stop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::updateTrackBlock Feb 23 23:16:20 volumio volumio[891]: info: CorePlayQueue::getTrackBlock Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::stPlaybackTimer Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:20 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::serviceStop Feb 23 23:16:20 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::serviceStop Feb 23 23:16:20 volumio volumio[891]: info: [1740374180352] ControllerPandora::stop Feb 23 23:16:20 volumio volumio[891]: info: ControllerMpd::stop Feb 23 23:16:20 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 23:16:20 volumio volumio[891]: info: Airplay started streaming, receiving metadatas Feb 23 23:16:20 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::volumioStop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::stop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 23:16:20 volumio volumio[891]: info: Airplay started streaming, receiving metadatas Feb 23 23:16:20 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::volumioStop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::stop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 23:16:20 volumio volumio[891]: info: Airplay started streaming, receiving metadatas Feb 23 23:16:20 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::volumioStop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::stop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 23:16:20 volumio volumio[891]: info: Feb 23 23:16:20 volumio volumio[891]: ---------------------------- MPD announces state update: player Feb 23 23:16:20 volumio volumio[891]: info: sendMpdCommand stop took 9 milliseconds Feb 23 23:16:20 volumio volumio[891]: info: ControllerMpd::getState Feb 23 23:16:20 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand status Feb 23 23:16:20 volumio volumio[891]: info: ControllerMpd::clear Feb 23 23:16:20 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 23:16:20 volumio volumio[891]: info: Feb 23 23:16:20 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:20 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:20 volumio volumio[891]: info: sendMpdCommand status took 1 milliseconds Feb 23 23:16:20 volumio volumio[891]: info: sendMpdCommand clear took 1 milliseconds Feb 23 23:16:20 volumio volumio[891]: verbose: ControllerMpd::parseState Feb 23 23:16:20 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 23:16:20 volumio volumio[891]: info: [1740374180363] ControllerPandora::pushState Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:20 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:20 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:20 volumio volumio[891]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"2 Wicky","name":"2 Wicky","artist":"Hooverphonic","album":"A New Stereophonic Sound Spectacular","albumart":"http://cont-1.p-cdn.us/images/f7/e6/8e/d9/8b904e438eea2f3f730be2b0/1080W_1080H.jpg","realUri":"http://t1-4.p-cdn.us/access/4645038150491562039.mp3?version=5&lid=45805085&token=DMGpYpAMOKXT%2FP0AdlYrDFQMbfoPZE4esEYNfCc%2F%2Bw0ONavB1O%2FIlV%2BwfQGqAgKRTiPDXYJSUUl1texz7%2FL%2FXiozjX3T%2B1FXT6%2B8vkJsJCB6thkHsW4uJgiowuXpmrZqA64VkkcFq%2BgmFqkN15sfQj6UV2YI2oaBBKasyTa2kE37GIcqGgQMokvztIRlRt5Yweh5cZXdiXRsibHwoUROJHeevxxbO3Ov3tiyo1Qc1El%2BVpO6ExeSvdeW%2BzJB%2BUniRNRF2QiT6tz%2FDAWsAX9RTm5EVgH2w3hQSbXzn2kHPLkG%2FgKqsnx9GJqDuarYw5LIYC4Pl9L6yfE%3D","isStreaming":true,"duration":284,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-4.p-cdn.us/access/4645038150491562039.mp3?version=5&lid=45805085&token=DMGpYpAMOKXT%2FP0AdlYrDFQMbfoPZE4esEYNfCc%2F%2Bw0ONavB1O%2FIlV%2BwfQGqAgKRTiPDXYJSUUl1texz7%2FL%2FXiozjX3T%2B1FXT6%2B8vkJsJCB6thkHsW4uJgiowuXpmrZqA64VkkcFq%2BgmFqkN15sfQj6UV2YI2oaBBKasyTa2kE37GIcqGgQMokvztIRlRt5Yweh5cZXdiXRsibHwoUROJHeevxxbO3Ov3tiyo1Qc1El%2BVpO6ExeSvdeW%2BzJB%2BUniRNRF2QiT6tz%2FDAWsAX9RTm5EVgH2w3hQSbXzn2kHPLkG%2FgKqsnx9GJqDuarYw5LIYC4Pl9L6yfE%3D","seek":4758,"status":"stop"} Feb 23 23:16:20 volumio volumio[891]: verbose: CURRENT POSITION 36 Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::syncState stateService stop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus stop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:20 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:20 volumio volumio[891]: info: No code Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:20 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService pandora Feb 23 23:16:20 volumio volumio[891]: error: updateQueue error: null Feb 23 23:16:20 volumio volumio[891]: info: ------------------------------ 8ms Feb 23 23:16:20 volumio volumio[891]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 23 23:16:20 volumio volumio[891]: verbose: ControllerMpd::parseTrackInfo Feb 23 23:16:20 volumio volumio[891]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 23 23:16:20 volumio volumio[891]: info: ------------------------------ 10ms Feb 23 23:16:20 volumio shairport-sync[1749]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 23 23:16:20 volumio shairport-sync[1749]: Dload Upload Total Spent Left Speed Feb 23 23:16:20 volumio volumio[891]: info: Feb 23 23:16:20 volumio volumio[891]: ---------------------------- Client requests Start Airplay PlaybackRoutine Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Feb 23 23:16:20 volumio volumio[891]: info: Airplay playback start Feb 23 23:16:20 volumio volumio[891]: info: CoreCommandRouter::volumioStop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::stop Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 23:16:20 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 23:16:20 volumio shairport-sync[1749]: [158B blob data] Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:21 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:21 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:21 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:21 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:21 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:21 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:24 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:24 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:24 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:24 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:24 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:24 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:24 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:24 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:24 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:29 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:29 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:29 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 23:16:29 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:29 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:29 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:29 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:29 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:29 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:29 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:29 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:29 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:29 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:33 volumio volumio[891]: info: Clearing queue after UPNP request Feb 23 23:16:33 volumio volumio[891]: verbose: UNSET VOLATILE: Service: airplay_emulation Feb 23 23:16:33 volumio volumio[891]: info: Stopping Airplay Playback and sending pause command to client via USR2 Feb 23 23:16:33 volumio sudo[6257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1749 Feb 23 23:16:33 volumio sudo[6257]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 23:16:33 volumio sudo[6257]: pam_unix(sudo:session): session closed for user root Feb 23 23:16:33 volumio volumio[891]: info: Shairport-Sync paused with USR2 Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::ClearQueue Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::stop Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::stPlaybackTimer Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::updateTrackBlock Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrackBlock Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::serviceStop Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::serviceStop Feb 23 23:16:34 volumio volumio[891]: info: [1740374194233] ControllerPandora::stop Feb 23 23:16:34 volumio volumio[891]: info: ControllerMpd::stop Feb 23 23:16:34 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::clearPlayQueue Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::saveQueue Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::volumioPushQueue Feb 23 23:16:34 volumio volumio[891]: error: Upnp client error: Error: This socket has been ended by the other party Feb 23 23:16:34 volumio volumio[891]: info: sendMpdCommand stop took 6 milliseconds Feb 23 23:16:34 volumio volumio[891]: info: ControllerMpd::clear Feb 23 23:16:34 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 23:16:34 volumio volumio[891]: info: Feb 23 23:16:34 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:34 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:34 volumio volumio[891]: info: sendMpdCommand clear took 0 milliseconds Feb 23 23:16:34 volumio volumio[891]: info: [1740374194240] ControllerPandora::pushState Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"2 Wicky","name":"2 Wicky","artist":"Hooverphonic","album":"A New Stereophonic Sound Spectacular","albumart":"http://cont-1.p-cdn.us/images/f7/e6/8e/d9/8b904e438eea2f3f730be2b0/1080W_1080H.jpg","realUri":"http://t1-4.p-cdn.us/access/4645038150491562039.mp3?version=5&lid=45805085&token=DMGpYpAMOKXT%2FP0AdlYrDFQMbfoPZE4esEYNfCc%2F%2Bw0ONavB1O%2FIlV%2BwfQGqAgKRTiPDXYJSUUl1texz7%2FL%2FXiozjX3T%2B1FXT6%2B8vkJsJCB6thkHsW4uJgiowuXpmrZqA64VkkcFq%2BgmFqkN15sfQj6UV2YI2oaBBKasyTa2kE37GIcqGgQMokvztIRlRt5Yweh5cZXdiXRsibHwoUROJHeevxxbO3Ov3tiyo1Qc1El%2BVpO6ExeSvdeW%2BzJB%2BUniRNRF2QiT6tz%2FDAWsAX9RTm5EVgH2w3hQSbXzn2kHPLkG%2FgKqsnx9GJqDuarYw5LIYC4Pl9L6yfE%3D","isStreaming":true,"duration":284,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-4.p-cdn.us/access/4645038150491562039.mp3?version=5&lid=45805085&token=DMGpYpAMOKXT%2FP0AdlYrDFQMbfoPZE4esEYNfCc%2F%2Bw0ONavB1O%2FIlV%2BwfQGqAgKRTiPDXYJSUUl1texz7%2FL%2FXiozjX3T%2B1FXT6%2B8vkJsJCB6thkHsW4uJgiowuXpmrZqA64VkkcFq%2BgmFqkN15sfQj6UV2YI2oaBBKasyTa2kE37GIcqGgQMokvztIRlRt5Yweh5cZXdiXRsibHwoUROJHeevxxbO3Ov3tiyo1Qc1El%2BVpO6ExeSvdeW%2BzJB%2BUniRNRF2QiT6tz%2FDAWsAX9RTm5EVgH2w3hQSbXzn2kHPLkG%2FgKqsnx9GJqDuarYw5LIYC4Pl9L6yfE%3D","seek":4758,"status":"stop"} Feb 23 23:16:34 volumio volumio[891]: verbose: CURRENT POSITION 36 Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::syncState stateService stop Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus stop Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:34 volumio volumio[891]: info: No code Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:34 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService pandora Feb 23 23:16:34 volumio volumio[891]: error: updateQueue error: null Feb 23 23:16:34 volumio volumio[891]: info: ------------------------------ 4ms Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received airplay_emulation Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received airplay_emulation Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received airplay_emulation Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received airplay_emulation Feb 23 23:16:34 volumio volumio[891]: info: Restarting Shairport-Sync after stop Feb 23 23:16:34 volumio sudo[6260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 23:16:34 volumio sudo[6260]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 23:16:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received airplay_emulation Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received airplay_emulation Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received airplay_emulation Feb 23 23:16:34 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:34 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:34 volumio volumio[891]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received airplay_emulation Feb 23 23:16:34 volumio shairport-sync[1749]: {"time":1740374180378,"response":"startAirplayPlayback Success"}Execution of on-stop command failed to start Feb 23 23:16:34 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 23 23:16:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 23 23:16:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 23 23:16:34 volumio sudo[6260]: pam_unix(sudo:session): session closed for user root Feb 23 23:16:34 volumio volumio[891]: info: Shairport-Sync retarted Feb 23 23:16:35 volumio volumio[891]: info: Starting UPNP Playback Feb 23 23:16:35 volumio volumio[891]: info: Preparing playback through UPNP Feb 23 23:16:35 volumio volumio[891]: info: Consume mode Feb 23 23:16:35 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Feb 23 23:16:35 volumio volumio[891]: info: CoreCommandRouter::volumioStop Feb 23 23:16:35 volumio volumio[891]: info: CoreStateMachine::stop Feb 23 23:16:35 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 23:16:35 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 23:16:36 volumio volumio[891]: info: Feb 23 23:16:36 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:36 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:36 volumio volumio[891]: info: ------------------------------ 1ms Feb 23 23:16:36 volumio volumio[891]: info: Feb 23 23:16:36 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:36 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:36 volumio volumio[891]: info: ------------------------------ 1ms Feb 23 23:16:36 volumio volumio[891]: info: Feb 23 23:16:36 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:36 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:36 volumio volumio[891]: info: ------------------------------ 0ms Feb 23 23:16:36 volumio volumio[891]: info: Feb 23 23:16:36 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:36 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:36 volumio volumio[891]: info: ------------------------------ 1ms Feb 23 23:16:36 volumio volumio[891]: info: Feb 23 23:16:36 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:36 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:36 volumio volumio[891]: info: ------------------------------ 0ms Feb 23 23:16:36 volumio volumio[891]: info: Feb 23 23:16:36 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:36 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:36 volumio volumio[891]: info: ------------------------------ 1ms Feb 23 23:16:36 volumio volumio[891]: info: Feb 23 23:16:36 volumio volumio[891]: ---------------------------- MPD announces state update: player Feb 23 23:16:36 volumio volumio[891]: info: ControllerMpd::getState Feb 23 23:16:36 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand status Feb 23 23:16:36 volumio volumio[891]: info: sendMpdCommand status took 72 milliseconds Feb 23 23:16:36 volumio volumio[891]: verbose: ControllerMpd::parseState Feb 23 23:16:36 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 23:16:36 volumio volumio[891]: info: Feb 23 23:16:36 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:36 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:36 volumio volumio[891]: info: Feb 23 23:16:36 volumio volumio[891]: ---------------------------- MPD announces state update: player Feb 23 23:16:36 volumio volumio[891]: info: ControllerMpd::getState Feb 23 23:16:36 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand status Feb 23 23:16:36 volumio volumio[891]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 23:16:36 volumio volumio[891]: verbose: ControllerMpd::parseTrackInfo Feb 23 23:16:36 volumio volumio[891]: info: ControllerMpd::pushState Feb 23 23:16:36 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:36 volumio volumio[891]: verbose: In UPNP mode Feb 23 23:16:36 volumio volumio[891]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2385 Kbps","isStreaming":false,"title":"54255c635ca14f4ea0f4bfc0d02dcd45.flac?ts=1740374193","artist":"Music Assistant","album":null,"uri":"http://192.168.1.87:8097/flow/uuid:548a2cb5-2925-37ea-1818-bc2411ef2ca9/54255c635ca14f4ea0f4bfc0d02dcd45.flac?ts=1740374193","trackType":""} Feb 23 23:16:36 volumio volumio[891]: verbose: CURRENT POSITION 36 Feb 23 23:16:36 volumio volumio[891]: info: CoreStateMachine::syncState stateService play Feb 23 23:16:36 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus stop Feb 23 23:16:36 volumio volumio[891]: info: ------------------------------ 74ms Feb 23 23:16:36 volumio volumio[891]: info: ------------------------------ 1ms Feb 23 23:16:36 volumio volumio[891]: info: sendMpdCommand status took 1 milliseconds Feb 23 23:16:36 volumio volumio[891]: verbose: ControllerMpd::parseState Feb 23 23:16:36 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 23:16:36 volumio volumio[891]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 23:16:36 volumio volumio[891]: verbose: ControllerMpd::parseTrackInfo Feb 23 23:16:36 volumio volumio[891]: info: ControllerMpd::pushState Feb 23 23:16:36 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:36 volumio volumio[891]: verbose: In UPNP mode Feb 23 23:16:36 volumio volumio[891]: verbose: STATE SERVICE {"status":"play","position":0,"seek":993,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2451 Kbps","isStreaming":false,"title":"54255c635ca14f4ea0f4bfc0d02dcd45.flac?ts=1740374193","artist":"Music Assistant","album":null,"uri":"http://192.168.1.87:8097/flow/uuid:548a2cb5-2925-37ea-1818-bc2411ef2ca9/54255c635ca14f4ea0f4bfc0d02dcd45.flac?ts=1740374193","trackType":""} Feb 23 23:16:36 volumio volumio[891]: verbose: CURRENT POSITION 36 Feb 23 23:16:36 volumio volumio[891]: info: CoreStateMachine::syncState stateService play Feb 23 23:16:36 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus play Feb 23 23:16:36 volumio volumio[891]: info: Received an update from plugin. extracting info from payload Feb 23 23:16:36 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 23:16:36 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:36 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:36 volumio volumio[891]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 23 23:16:36 volumio volumio[891]: info: ------------------------------ 3ms Feb 23 23:16:56 volumio volumio[891]: info: Clearing queue after UPNP request Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::ClearQueue Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::stop Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::stPlaybackTimer Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::updateTrackBlock Feb 23 23:16:56 volumio volumio[891]: info: CorePlayQueue::getTrackBlock Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:56 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:56 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 23:16:56 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::serviceStop Feb 23 23:16:56 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:56 volumio volumio[891]: info: ControllerMpd::stop Feb 23 23:16:56 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 23:16:56 volumio volumio[891]: info: CorePlayQueue::clearPlayQueue Feb 23 23:16:56 volumio volumio[891]: info: CorePlayQueue::saveQueue Feb 23 23:16:56 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:56 volumio volumio[891]: info: CoreCommandRouter::volumioPushQueue Feb 23 23:16:56 volumio volumio[891]: info: Feb 23 23:16:56 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:56 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:56 volumio volumio[891]: info: Feb 23 23:16:56 volumio volumio[891]: ---------------------------- MPD announces state update: player Feb 23 23:16:56 volumio volumio[891]: info: sendMpdCommand stop took 5 milliseconds Feb 23 23:16:56 volumio volumio[891]: info: ControllerMpd::getState Feb 23 23:16:56 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand status Feb 23 23:16:56 volumio volumio[891]: error: updateQueue error: null Feb 23 23:16:56 volumio volumio[891]: info: ------------------------------ 1ms Feb 23 23:16:56 volumio volumio[891]: info: sendMpdCommand status took 2 milliseconds Feb 23 23:16:56 volumio volumio[891]: verbose: ControllerMpd::parseState Feb 23 23:16:56 volumio volumio[891]: info: ControllerMpd::pushState Feb 23 23:16:56 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:56 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:56 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:56 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:56 volumio volumio[891]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 23 23:16:56 volumio volumio[891]: verbose: CURRENT POSITION 36 Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::syncState stateService stop Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus stop Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:56 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:56 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:56 volumio volumio[891]: info: No code Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:56 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:56 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:56 volumio volumio[891]: info: ------------------------------ 9ms Feb 23 23:16:56 volumio volumio[891]: info: Starting UPNP Playback Feb 23 23:16:56 volumio volumio[891]: info: Preparing playback through UPNP Feb 23 23:16:56 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Feb 23 23:16:56 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:56 volumio volumio[891]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 23:16:57 volumio volumio[891]: info: Feb 23 23:16:57 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:57 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:57 volumio volumio[891]: info: ------------------------------ 0ms Feb 23 23:16:57 volumio volumio[891]: info: Feb 23 23:16:57 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:57 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:57 volumio volumio[891]: info: ------------------------------ 1ms Feb 23 23:16:57 volumio volumio[891]: info: Feb 23 23:16:57 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:57 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:57 volumio volumio[891]: info: ------------------------------ 0ms Feb 23 23:16:57 volumio volumio[891]: info: Feb 23 23:16:57 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:57 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:57 volumio volumio[891]: info: ------------------------------ 0ms Feb 23 23:16:57 volumio volumio[891]: info: Feb 23 23:16:57 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:57 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:57 volumio volumio[891]: info: Feb 23 23:16:57 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:57 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:57 volumio volumio[891]: info: ------------------------------ 0ms Feb 23 23:16:57 volumio volumio[891]: info: ------------------------------ 0ms Feb 23 23:16:57 volumio volumio[891]: info: Feb 23 23:16:57 volumio volumio[891]: ---------------------------- MPD announces state update: player Feb 23 23:16:57 volumio volumio[891]: info: ControllerMpd::getState Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand status Feb 23 23:16:57 volumio volumio[891]: info: Feb 23 23:16:57 volumio volumio[891]: ---------------------------- MPD announces state update: player Feb 23 23:16:57 volumio volumio[891]: info: sendMpdCommand status took 1 milliseconds Feb 23 23:16:57 volumio volumio[891]: info: ControllerMpd::getState Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand status Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::parseState Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 23:16:57 volumio volumio[891]: info: sendMpdCommand status took 1 milliseconds Feb 23 23:16:57 volumio volumio[891]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::parseState Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::parseTrackInfo Feb 23 23:16:57 volumio volumio[891]: info: ControllerMpd::pushState Feb 23 23:16:57 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:57 volumio volumio[891]: verbose: In UPNP mode Feb 23 23:16:57 volumio volumio[891]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.87:8097/flow/uuid:548a2cb5-2925-37ea-1818-bc2411ef2ca9/8d09b7207f6f41279c46bf6e76f59b16.flac?ts=1740374216","trackType":""} Feb 23 23:16:57 volumio volumio[891]: verbose: CURRENT POSITION 36 Feb 23 23:16:57 volumio volumio[891]: info: CoreStateMachine::syncState stateService play Feb 23 23:16:57 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus stop Feb 23 23:16:57 volumio volumio[891]: info: ------------------------------ 4ms Feb 23 23:16:57 volumio volumio[891]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::parseTrackInfo Feb 23 23:16:57 volumio volumio[891]: info: ControllerMpd::pushState Feb 23 23:16:57 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:57 volumio volumio[891]: verbose: In UPNP mode Feb 23 23:16:57 volumio volumio[891]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.1.87:8097/flow/uuid:548a2cb5-2925-37ea-1818-bc2411ef2ca9/8d09b7207f6f41279c46bf6e76f59b16.flac?ts=1740374216","trackType":""} Feb 23 23:16:57 volumio volumio[891]: verbose: CURRENT POSITION 36 Feb 23 23:16:57 volumio volumio[891]: info: CoreStateMachine::syncState stateService play Feb 23 23:16:57 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus play Feb 23 23:16:57 volumio volumio[891]: info: Received an update from plugin. extracting info from payload Feb 23 23:16:57 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:57 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 23:16:57 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:57 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:57 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Feb 23 23:16:57 volumio volumio[891]: info: ------------------------------ 9ms Feb 23 23:16:57 volumio volumio[891]: info: Feb 23 23:16:57 volumio volumio[891]: ---------------------------- MPD announces system playlist update Feb 23 23:16:57 volumio volumio[891]: info: Ignoring MPD Status Update Feb 23 23:16:57 volumio volumio[891]: info: Feb 23 23:16:57 volumio volumio[891]: ---------------------------- MPD announces state update: player Feb 23 23:16:57 volumio volumio[891]: info: ControllerMpd::getState Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand status Feb 23 23:16:57 volumio volumio[891]: info: ------------------------------ 1ms Feb 23 23:16:57 volumio volumio[891]: info: sendMpdCommand status took 0 milliseconds Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::parseState Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 23:16:57 volumio volumio[891]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 23:16:57 volumio volumio[891]: verbose: ControllerMpd::parseTrackInfo Feb 23 23:16:57 volumio volumio[891]: info: ControllerMpd::pushState Feb 23 23:16:57 volumio volumio[891]: info: CoreCommandRouter::servicePushState Feb 23 23:16:57 volumio volumio[891]: verbose: In UPNP mode Feb 23 23:16:57 volumio volumio[891]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198,"duration":0,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2212 Kbps","isStreaming":false,"title":"8d09b7207f6f41279c46bf6e76f59b16.flac?ts=1740374216","artist":"Music Assistant","album":null,"uri":"http://192.168.1.87:8097/flow/uuid:548a2cb5-2925-37ea-1818-bc2411ef2ca9/8d09b7207f6f41279c46bf6e76f59b16.flac?ts=1740374216","trackType":""} Feb 23 23:16:57 volumio volumio[891]: verbose: CURRENT POSITION 36 Feb 23 23:16:57 volumio volumio[891]: info: CoreStateMachine::syncState stateService play Feb 23 23:16:57 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus play Feb 23 23:16:57 volumio volumio[891]: info: Received an update from plugin. extracting info from payload Feb 23 23:16:57 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 23:16:57 volumio volumio[891]: info: CoreStateMachine::pushState Feb 23 23:16:57 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:16:57 volumio volumio[891]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 23 23:16:57 volumio volumio[891]: info: ------------------------------ 7ms Feb 23 23:17:23 volumio volumio[891]: info: Airplay started streaming, receiving metadatas Feb 23 23:17:23 volumio volumio[891]: info: CorePlayQueue::getTrack 36 Feb 23 23:17:23 volumio volumio[891]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 23:17:23 volumio volumio[891]: TypeError: Cannot read property 'name' of undefined Feb 23 23:17:23 volumio volumio[891]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 23 23:17:23 volumio volumio[891]: at AirPlayInterface.prepareAirplayPlayback (/volumio/app/plugins/music_service/airplay_emulation/index.js:538:47) Feb 23 23:17:23 volumio volumio[891]: at ShairportSyncReaderUDP. (/volumio/app/plugins/music_service/airplay_emulation/index.js:257:12) Feb 23 23:17:23 volumio volumio[891]: at ShairportSyncReaderUDP.emit (events.js:412:35) Feb 23 23:17:23 volumio volumio[891]: at ShairportSyncReaderUDP.useData (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js:86:16) Feb 23 23:17:23 volumio volumio[891]: at Socket. (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js:21:12) Feb 23 23:17:23 volumio volumio[891]: at Socket.emit (events.js:400:28) Feb 23 23:17:23 volumio volumio[891]: at UDP.onMessage [as onmessage] (dgram.js:931:8) Feb 23 23:17:23 volumio volumio[891]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 23:17:23 volumio shairport-sync[6263]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 23 23:17:23 volumio shairport-sync[6263]: Dload Upload Total Spent Left Speed Feb 23 23:17:23 volumio sudo[6387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 23:16 Feb 23 23:17:23 volumio sudo[6387]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"