-- Logs begin at Fri 2025-02-14 09:15:34 UTC, end at Sat 2025-02-15 07:17:50 UTC. -- Feb 15 07:16:04 opera volumio[1698]: 87 Feb 15 07:16:04 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:04 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:04 opera volumio[14933]: info: Feb 15 07:16:04 opera volumio[14933]: ---------------------------- Client requests Volume 87 Feb 15 07:16:04 opera volumio[14933]: info: VolumeController::SetAlsaVolume87 Feb 15 07:16:04 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:04 opera volumio[1698]: [155B blob data] Feb 15 07:16:04 opera volumio[1698]: {"time":1739603764463,"response":"volume Success"}87 Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:04 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:04 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:04 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:04 opera volumio[1698]: 82 Feb 15 07:16:04 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:04 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:04 opera volumio[14933]: info: Feb 15 07:16:04 opera volumio[14933]: ---------------------------- Client requests Volume 82 Feb 15 07:16:04 opera volumio[14933]: info: VolumeController::SetAlsaVolume82 Feb 15 07:16:04 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:04 opera volumio[1698]: [155B blob data] Feb 15 07:16:04 opera volumio[1698]: {"time":1739603764515,"response":"volume Success"}82 Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:04 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:04 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:04 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:04 opera volumio[1698]: 75 Feb 15 07:16:04 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:04 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:04 opera volumio[14933]: info: Feb 15 07:16:04 opera volumio[14933]: ---------------------------- Client requests Volume 75 Feb 15 07:16:04 opera volumio[14933]: info: VolumeController::SetAlsaVolume75 Feb 15 07:16:04 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:04 opera volumio[1698]: [155B blob data] Feb 15 07:16:04 opera volumio[1698]: {"time":1739603764588,"response":"volume Success"}75 Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:04 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:04 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:04 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:04 opera volumio[1698]: 69 Feb 15 07:16:04 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:04 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:04 opera volumio[14933]: info: Feb 15 07:16:04 opera volumio[14933]: ---------------------------- Client requests Volume 69 Feb 15 07:16:04 opera volumio[14933]: info: VolumeController::SetAlsaVolume69 Feb 15 07:16:04 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:04 opera volumio[1698]: [155B blob data] Feb 15 07:16:04 opera volumio[1698]: {"time":1739603764658,"response":"volume Success"}69 Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:04 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:04 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:04 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:04 opera volumio[1698]: 63 Feb 15 07:16:04 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:04 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:04 opera volumio[14933]: info: Feb 15 07:16:04 opera volumio[14933]: ---------------------------- Client requests Volume 63 Feb 15 07:16:04 opera volumio[14933]: info: VolumeController::SetAlsaVolume63 Feb 15 07:16:04 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:04 opera volumio[1698]: [155B blob data] Feb 15 07:16:04 opera volumio[1698]: {"time":1739603764718,"response":"volume Success"}63 Feb 15 07:16:04 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:04 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:04 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:04 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:04 opera volumio[1698]: 55 Feb 15 07:16:04 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:04 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:05 opera volumio[14933]: info: Feb 15 07:16:05 opera volumio[14933]: ---------------------------- Client requests Volume 55 Feb 15 07:16:05 opera volumio[14933]: info: VolumeController::SetAlsaVolume55 Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:05 opera volumio[1698]: [155B blob data] Feb 15 07:16:05 opera volumio[1698]: {"time":1739603764999,"response":"volume Success"}55 Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:05 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:05 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:05 opera volumio[1698]: 48 Feb 15 07:16:05 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:05 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:05 opera volumio[14933]: info: Feb 15 07:16:05 opera volumio[14933]: ---------------------------- Client requests Volume 48 Feb 15 07:16:05 opera volumio[14933]: info: VolumeController::SetAlsaVolume48 Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:05 opera volumio[1698]: [155B blob data] Feb 15 07:16:05 opera volumio[1698]: {"time":1739603765086,"response":"volume Success"}48 Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:05 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:05 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:05 opera volumio[1698]: 40 Feb 15 07:16:05 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:05 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:05 opera volumio[14933]: info: Feb 15 07:16:05 opera volumio[14933]: ---------------------------- Client requests Volume 40 Feb 15 07:16:05 opera volumio[14933]: info: VolumeController::SetAlsaVolume40 Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:05 opera volumio[1698]: [155B blob data] Feb 15 07:16:05 opera volumio[1698]: {"time":1739603765147,"response":"volume Success"}40 Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:05 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:05 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:05 opera volumio[1698]: 32 Feb 15 07:16:05 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:05 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:05 opera volumio[14933]: info: Feb 15 07:16:05 opera volumio[14933]: ---------------------------- Client requests Volume 32 Feb 15 07:16:05 opera volumio[14933]: info: VolumeController::SetAlsaVolume32 Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:05 opera volumio[1698]: [155B blob data] Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:05 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:05 opera volumio[1698]: {"time":1739603765214,"response":"volume Success"}32 Feb 15 07:16:05 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:05 opera volumio[1698]: 24 Feb 15 07:16:05 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:05 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:05 opera volumio[14933]: info: Feb 15 07:16:05 opera volumio[14933]: ---------------------------- Client requests Volume 24 Feb 15 07:16:05 opera volumio[14933]: info: VolumeController::SetAlsaVolume24 Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:05 opera volumio[1698]: [155B blob data] Feb 15 07:16:05 opera volumio[1698]: {"time":1739603765297,"response":"volume Success"}24 Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:05 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:05 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:05 opera volumio[1698]: 19 Feb 15 07:16:05 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:05 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:05 opera volumio[14933]: info: Feb 15 07:16:05 opera volumio[14933]: ---------------------------- Client requests Volume 19 Feb 15 07:16:05 opera volumio[14933]: info: VolumeController::SetAlsaVolume19 Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:05 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:05 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:05 opera volumio[1698]: [155B blob data] Feb 15 07:16:05 opera volumio[1698]: {"time":1739603765515,"response":"volume Success"}19 Feb 15 07:16:05 opera volumio[1698]: 15 Feb 15 07:16:05 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:05 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:05 opera volumio[14933]: info: Feb 15 07:16:05 opera volumio[14933]: ---------------------------- Client requests Volume 15 Feb 15 07:16:05 opera volumio[14933]: info: VolumeController::SetAlsaVolume15 Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:05 opera volumio[1698]: [155B blob data] Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:05 opera volumio[1698]: {"time":1739603765582,"response":"volume Success"}15 Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:05 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:05 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:05 opera volumio[1698]: 10 Feb 15 07:16:05 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:05 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:05 opera volumio[14933]: info: Feb 15 07:16:05 opera volumio[14933]: ---------------------------- Client requests Volume 10 Feb 15 07:16:05 opera volumio[14933]: info: VolumeController::SetAlsaVolume10 Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:05 opera volumio[1698]: [155B blob data] Feb 15 07:16:05 opera volumio[1698]: {"time":1739603765633,"response":"volume Success"}10 Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:05 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:05 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:05 opera volumio[1698]: 2 Feb 15 07:16:05 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:05 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:05 opera volumio[14933]: info: Feb 15 07:16:05 opera volumio[14933]: ---------------------------- Client requests Volume 2 Feb 15 07:16:05 opera volumio[14933]: info: VolumeController::SetAlsaVolume2 Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:05 opera volumio[1698]: [155B blob data] Feb 15 07:16:05 opera volumio[1698]: {"time":1739603765701,"response":"volume Success"}2 Feb 15 07:16:05 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:05 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:05 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:05 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:06 opera volumio[14933]: verbose: In UPNP mode Feb 15 07:16:06 opera volumio[14933]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received airplay_emulation Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:06 opera volumio[14933]: verbose: In UPNP mode Feb 15 07:16:06 opera volumio[14933]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received airplay_emulation Feb 15 07:16:06 opera shairport-sync[16704]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Feb 15 07:16:06 opera shairport-sync[16704]: alsa: error -16 ("Device or resource busy") opening alsa device "plughw:5,0". Feb 15 07:16:06 opera volumio[14933]: info: Airplay started streaming, receiving metadatas Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioStop Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::stop Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:16:06 opera volumio[14933]: verbose: UNSET VOLATILE Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::stPlaybackTimer Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::updateTrackBlock Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrackBlock Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera shairport-sync[16704]: % Total % R e c%e ivTeodt a%l X f e%r dR e cAevievreadg e% SXpfeeerdd ATviemrea g e Speed TiTmiem e T i m eT i m e TCiumrer e nCtu Feb 15 07:16:06 opera shairport-sync[16704]: r r e n t Feb 15 07:16:06 opera shairport-sync[16704]: Dload U p l o ad D l oTado t aUlp l o aSdp e n tT o t a lL e f tS p eSnpte e d Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::serviceStop Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: ControllerMpd::stop Feb 15 07:16:06 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:16:06 opera volumio[14933]: info: Airplay started streaming, receiving metadatas Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioStop Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::stop Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:16:06 opera volumio[14933]: verbose: UNSET VOLATILE Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera shairport-sync[16704]: [23B blob data] Feb 15 07:16:06 opera volumio[14933]: info: Feb 15 07:16:06 opera volumio[14933]: ---------------------------- Client requests Start Airplay PlaybackRoutine Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Feb 15 07:16:06 opera volumio[14933]: info: Airplay playback start Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioStop Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::stop Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:16:06 opera volumio[14933]: verbose: UNSET VOLATILE Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:16:06 opera shairport-sync[16704]: [223B blob data] Feb 15 07:16:06 opera volumio[14933]: info: Feb 15 07:16:06 opera volumio[14933]: ---------------------------- Client requests AirplayActive Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Feb 15 07:16:06 opera shairport-sync[16704]: [143B blob data] Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:16:06 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus stop Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:16:06 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:16:06 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera shairport-sync[16704]: alsa: error -16 ("Device or resource busy") opening alsa device "plughw:5,0". Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Niagara Falls (Explicit)","artist":"The Weeknd","album":"Hurry Up Tomorrow (Explicit)","albumart":"/albumart?cacheid=367&web=The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:16:06 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:16:06 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Niagara Falls (Explicit)","artist":"The Weeknd","album":"Hurry Up Tomorrow (Explicit)","albumart":"/albumart?cacheid=367&web=The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:16:06 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:16:06 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera volumio[14933]: info: Feb 15 07:16:06 opera volumio[14933]: ---------------------------- MPD announces state update: player Feb 15 07:16:06 opera volumio[14933]: info: sendMpdCommand stop took 128 milliseconds Feb 15 07:16:06 opera volumio[14933]: info: ControllerMpd::getState Feb 15 07:16:06 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand status Feb 15 07:16:06 opera volumio[14933]: info: sendMpdCommand status took 1 milliseconds Feb 15 07:16:06 opera volumio[14933]: verbose: ControllerMpd::parseState Feb 15 07:16:06 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 07:16:06 opera volumio[14933]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 15 07:16:06 opera volumio[14933]: verbose: ControllerMpd::parseTrackInfo Feb 15 07:16:06 opera volumio[14933]: info: ControllerMpd::pushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: 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":"Niagara Falls","artist":"The Weeknd","album":"Hurry Up Tomorrow (Explicit)","uri":"http://116.31.99.205/amobile.music.tc.qq.com/M500003NcjBN3Cl0ZG.mp3?guid=7141208BD8964BFE905C7D2794E1C916&vkey=CC8B821CFF046ED3738B79733D8D963F2FA6BAF6CAFE01F513F9C3143B0B750DDB6E186B23658817341885F2FA9D1A0ABA9AA09B1EFA5E7A__v2b94c7a3&uin=&src=M500000rAHww37Zc0h.mp3&fromtag=118042","trackType":""} Feb 15 07:16:06 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::syncState stateService stop Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::stPlaybackTimer Feb 15 07:16:06 opera volumio[14933]: info: ------------------------------ 18ms Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:06 opera volumio[1698]: 0 Feb 15 07:16:06 opera volumio[1698]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:06 opera volumio[1698]: Dload Upload Total Spent Left Speed Feb 15 07:16:06 opera volumio[14933]: info: Feb 15 07:16:06 opera volumio[14933]: ---------------------------- Client requests Volume 1 Feb 15 07:16:06 opera volumio[14933]: info: VolumeController::SetAlsaVolume1 Feb 15 07:16:06 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:06 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:06 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:06 opera volumio[1698]: [155B blob data] Feb 15 07:16:06 opera volumio[1698]: {"time":1739603766766,"response":"volume Success"}1 Feb 15 07:16:06 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:07 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:07 opera volumio[14933]: verbose: STATE SERVICE {"status":"pause","service":"airplay_emulation","title":"Niagara Falls (Explicit)","artist":"The Weeknd","album":"Hurry Up Tomorrow (Explicit)","albumart":"/albumart?cacheid=367&web=The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:16:07 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:16:07 opera volumio[14933]: info: CoreStateMachine::syncState stateService pause Feb 15 07:16:07 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus stop Feb 15 07:16:07 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:07 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:07 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:07 opera volumio[14933]: verbose: STATE SERVICE {"status":"pause","service":"airplay_emulation","title":"Niagara Falls (Explicit)","artist":"The Weeknd","album":"Hurry Up Tomorrow (Explicit)","albumart":"/albumart?cacheid=367&web=The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:16:07 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:16:07 opera volumio[14933]: info: CoreStateMachine::syncState stateService pause Feb 15 07:16:07 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus stop Feb 15 07:16:07 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:07 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:07 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:07 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:07 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:07 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:07 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:07 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:07 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:07 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:07 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:07 opera volumio[14933]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/013aede5-fb75-4058-a84c-cf8d60861478.jpg' Feb 15 07:16:07 opera volumio[14933]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/013aede5-fb75-4058-a84c-cf8d60861478.jpg' Feb 15 07:16:14 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:14 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:14 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:14 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:14 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:14 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:14 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:14 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:14 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:14 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:14 opera shairport-sync[16704]: {"time":1739603766116,"response":"airplayActive Success"} % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:14 opera shairport-sync[16704]: Dload Upload Total Spent Left Speed Feb 15 07:16:14 opera volumio[14933]: info: Feb 15 07:16:14 opera volumio[14933]: ---------------------------- Client requests Stop Airplay Playback Feb 15 07:16:14 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Feb 15 07:16:14 opera volumio[14933]: info: Airplay playback stop Feb 15 07:16:14 opera volumio[14933]: info: Airplay Playback Stopped, clearing state Feb 15 07:16:14 opera volumio[14933]: info: CoreStateMachine::resetVolumioState Feb 15 07:16:14 opera volumio[14933]: info: CoreStateMachine::getcurrentVolume Feb 15 07:16:14 opera volumio[14933]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 07:16:14 opera shairport-sync[16704]: [155B blob data] Feb 15 07:16:14 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:14 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:14 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:14 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:20 opera volumio[14933]: info: Airplay started streaming, receiving metadatas Feb 15 07:16:20 opera volumio[14933]: info: Airplay started streaming, receiving metadatas Feb 15 07:16:20 opera shairport-sync[16704]: {"time":1739603774082,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:16:20 opera shairport-sync[16704]: Dload Upload Total Spent Left Speed Feb 15 07:16:20 opera volumio[14933]: info: Feb 15 07:16:20 opera volumio[14933]: ---------------------------- Client requests Start Airplay PlaybackRoutine Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Feb 15 07:16:20 opera volumio[14933]: info: Airplay playback start Feb 15 07:16:20 opera shairport-sync[16704]: [155B blob data] Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:20 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:20 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:20 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:20 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:20 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:20 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:20 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:20 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:20 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:20 opera volumio[14933]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/013aede5-fb75-4058-a84c-cf8d60861478.jpg' Feb 15 07:16:21 opera volumio[14933]: info: Clearing queue after UPNP request Feb 15 07:16:21 opera volumio[14933]: verbose: UNSET VOLATILE Feb 15 07:16:21 opera volumio[14933]: info: Stopping Airplay Playback and sending pause command to client via USR2 Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:21 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:21 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:21 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:21 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:21 opera sudo[16866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 16704 Feb 15 07:16:21 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:21 opera sudo[16866]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 07:16:21 opera sudo[16866]: pam_unix(sudo:session): session closed for user root Feb 15 07:16:21 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:21 opera volumio[14933]: info: Shairport-Sync paused with USR2 Feb 15 07:16:21 opera systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Feb 15 07:16:21 opera shairport-sync[16704]: {"time":1739603780431,"response":"startAirplayPlayback Success"} Feb 15 07:16:21 opera systemd[1]: Unit shairport-sync.service entered failed state. Feb 15 07:16:21 opera volumio[14933]: info: CoreStateMachine::ClearQueue Feb 15 07:16:21 opera volumio[14933]: info: CoreStateMachine::stop Feb 15 07:16:21 opera volumio[14933]: info: CoreStateMachine::serviceStop Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::serviceStop Feb 15 07:16:21 opera volumio[14933]: info: Airplay Stop Feb 15 07:16:21 opera volumio[14933]: info: Stopping Airplay Playback and sending pause command to client via USR2 Feb 15 07:16:21 opera volumio[14933]: info: CorePlayQueue::clearPlayQueue Feb 15 07:16:21 opera volumio[14933]: info: CorePlayQueue::saveQueue Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::volumioPushQueue Feb 15 07:16:21 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:21 opera volumio[14933]: info: Feb 15 07:16:21 opera volumio[14933]: ---------------------------- MPD announces system playlist update Feb 15 07:16:21 opera volumio[14933]: info: Ignoring MPD Status Update Feb 15 07:16:21 opera volumio[14933]: error: updateQueue error: null Feb 15 07:16:21 opera volumio[14933]: info: ------------------------------ 1ms Feb 15 07:16:21 opera volumio[14933]: info: Starting UPNP Playback Feb 15 07:16:21 opera volumio[14933]: info: Preparing playback through UPNP Feb 15 07:16:21 opera volumio[14933]: verbose: UNSET VOLATILE Feb 15 07:16:21 opera volumio[14933]: info: Stopping Airplay Playback and sending pause command to client via USR2 Feb 15 07:16:21 opera volumio[14933]: info: CoreCommandRouter::volumioGetState Feb 15 07:16:21 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:16:21 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 07:16:21 opera sudo[16879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 Feb 15 07:16:21 opera sudo[16879]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 07:16:21 opera sudo[16879]: pam_unix(sudo:session): session closed for user root Feb 15 07:16:21 opera volumio[14933]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync) Feb 15 07:16:21 opera volumio[14933]: kill: invalid argument U Feb 15 07:16:21 opera volumio[14933]: Usage: Feb 15 07:16:21 opera volumio[14933]: kill [options] [...] Feb 15 07:16:21 opera volumio[14933]: Options: Feb 15 07:16:21 opera volumio[14933]: [...] send signal to every listed Feb 15 07:16:21 opera volumio[14933]: -, -s, --signal Feb 15 07:16:21 opera volumio[14933]: specify the to be sent Feb 15 07:16:21 opera volumio[14933]: -l, --list=[] list all signal names, or convert one to a name Feb 15 07:16:21 opera volumio[14933]: -L, --table list all signal names in a nice table Feb 15 07:16:21 opera volumio[14933]: -h, --help display this help and exit Feb 15 07:16:21 opera volumio[14933]: -V, --version output version information and exit Feb 15 07:16:21 opera volumio[14933]: For more details see kill(1). Feb 15 07:16:21 opera sudo[16888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 Feb 15 07:16:21 opera sudo[16888]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 07:16:21 opera sudo[16888]: pam_unix(sudo:session): session closed for user root Feb 15 07:16:21 opera volumio[14933]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync) Feb 15 07:16:21 opera volumio[14933]: kill: invalid argument U Feb 15 07:16:21 opera volumio[14933]: Usage: Feb 15 07:16:21 opera volumio[14933]: kill [options] [...] Feb 15 07:16:21 opera volumio[14933]: Options: Feb 15 07:16:21 opera volumio[14933]: [...] send signal to every listed Feb 15 07:16:21 opera volumio[14933]: -, -s, --signal Feb 15 07:16:21 opera volumio[14933]: specify the to be sent Feb 15 07:16:21 opera volumio[14933]: -l, --list=[] list all signal names, or convert one to a name Feb 15 07:16:21 opera volumio[14933]: -L, --table list all signal names in a nice table Feb 15 07:16:21 opera volumio[14933]: -h, --help display this help and exit Feb 15 07:16:21 opera volumio[14933]: -V, --version output version information and exit Feb 15 07:16:21 opera volumio[14933]: For more details see kill(1). Feb 15 07:16:22 opera volumio[14933]: info: Feb 15 07:16:22 opera volumio[14933]: ---------------------------- MPD announces system playlist update Feb 15 07:16:22 opera volumio[14933]: info: Ignoring MPD Status Update Feb 15 07:16:22 opera volumio[14933]: info: ------------------------------ 0ms Feb 15 07:16:22 opera volumio[14933]: info: Feb 15 07:16:22 opera volumio[14933]: ---------------------------- MPD announces system playlist update Feb 15 07:16:22 opera volumio[14933]: info: Ignoring MPD Status Update Feb 15 07:16:22 opera volumio[14933]: info: ------------------------------ 1ms Feb 15 07:16:22 opera volumio[14933]: info: Feb 15 07:16:22 opera volumio[14933]: ---------------------------- MPD announces system playlist update Feb 15 07:16:22 opera volumio[14933]: info: Ignoring MPD Status Update Feb 15 07:16:22 opera volumio[14933]: info: ------------------------------ 1ms Feb 15 07:16:22 opera volumio[14933]: info: Feb 15 07:16:22 opera volumio[14933]: ---------------------------- MPD announces system playlist update Feb 15 07:16:22 opera volumio[14933]: info: Ignoring MPD Status Update Feb 15 07:16:22 opera volumio[14933]: info: ------------------------------ 1ms Feb 15 07:16:22 opera volumio[14933]: info: Feb 15 07:16:22 opera volumio[14933]: ---------------------------- MPD announces system playlist update Feb 15 07:16:22 opera volumio[14933]: info: Ignoring MPD Status Update Feb 15 07:16:22 opera volumio[14933]: info: Feb 15 07:16:22 opera volumio[14933]: ---------------------------- MPD announces system playlist update Feb 15 07:16:22 opera volumio[14933]: info: Ignoring MPD Status Update Feb 15 07:16:22 opera volumio[14933]: info: ------------------------------ 1ms Feb 15 07:16:22 opera volumio[14933]: info: ------------------------------ 0ms Feb 15 07:16:22 opera volumio[14933]: info: Feb 15 07:16:22 opera volumio[14933]: ---------------------------- MPD announces state update: player Feb 15 07:16:22 opera volumio[14933]: info: ControllerMpd::getState Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand status Feb 15 07:16:22 opera volumio[14933]: info: Feb 15 07:16:22 opera volumio[14933]: ---------------------------- MPD announces state update: player Feb 15 07:16:22 opera volumio[14933]: info: sendMpdCommand status took 2 milliseconds Feb 15 07:16:22 opera volumio[14933]: info: ControllerMpd::getState Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand status Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::parseState Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 07:16:22 opera volumio[14933]: info: Feb 15 07:16:22 opera volumio[14933]: ---------------------------- MPD announces system playlist update Feb 15 07:16:22 opera volumio[14933]: info: Ignoring MPD Status Update Feb 15 07:16:22 opera volumio[14933]: info: sendMpdCommand status took 2 milliseconds Feb 15 07:16:22 opera volumio[14933]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::parseState Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::parseTrackInfo Feb 15 07:16:22 opera volumio[14933]: info: ControllerMpd::pushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:22 opera volumio[14933]: verbose: In UPNP mode Feb 15 07:16:22 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Niagara Falls","artist":"The Weeknd","album":"Hurry Up Tomorrow (Explicit)","uri":"http://116.31.99.205/amobile.music.tc.qq.com/M500003NcjBN3Cl0ZG.mp3?guid=7141208BD8964BFE905C7D2794E1C916&vkey=CC8B821CFF046ED3738B79733D8D963F2FA6BAF6CAFE01F513F9C3143B0B750DDB6E186B23658817341885F2FA9D1A0ABA9AA09B1EFA5E7A__v2b94c7a3&uin=&src=M500000rAHww37Zc0h.mp3&fromtag=118042","trackType":""} Feb 15 07:16:22 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus pause Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:22 opera volumio[14933]: info: ------------------------------ 37ms Feb 15 07:16:22 opera volumio[14933]: info: ------------------------------ 35ms Feb 15 07:16:22 opera volumio[14933]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::parseTrackInfo Feb 15 07:16:22 opera volumio[14933]: info: ControllerMpd::pushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:22 opera volumio[14933]: verbose: In UPNP mode Feb 15 07:16:22 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":277,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Niagara Falls","artist":"The Weeknd","album":"Hurry Up Tomorrow (Explicit)","uri":"http://116.31.99.205/amobile.music.tc.qq.com/M500003NcjBN3Cl0ZG.mp3?guid=7141208BD8964BFE905C7D2794E1C916&vkey=CC8B821CFF046ED3738B79733D8D963F2FA6BAF6CAFE01F513F9C3143B0B750DDB6E186B23658817341885F2FA9D1A0ABA9AA09B1EFA5E7A__v2b94c7a3&uin=&src=M500000rAHww37Zc0h.mp3&fromtag=118042","trackType":""} Feb 15 07:16:22 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:16:22 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:22 opera volumio[14933]: info: ------------------------------ 54ms Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:22 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:22 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:22 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:22 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:22 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:22 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:22 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:22 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:22 opera volumio[14933]: info: Restarting Shairport-Sync after stop Feb 15 07:16:22 opera volumio[14933]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/013aede5-fb75-4058-a84c-cf8d60861478.jpg' Feb 15 07:16:22 opera sudo[16911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 07:16:22 opera sudo[16911]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 07:16:22 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 15 07:16:22 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 07:16:22 opera sudo[16911]: pam_unix(sudo:session): session closed for user root Feb 15 07:16:22 opera volumio[14933]: info: Shairport-Sync retarted Feb 15 07:16:22 opera volumio[14933]: info: Feb 15 07:16:22 opera volumio[14933]: ---------------------------- MPD announces state update: player Feb 15 07:16:22 opera volumio[14933]: info: ControllerMpd::getState Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand status Feb 15 07:16:22 opera volumio[14933]: info: sendMpdCommand status took 3 milliseconds Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::parseState Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 07:16:22 opera volumio[14933]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 15 07:16:22 opera volumio[14933]: verbose: ControllerMpd::parseTrackInfo Feb 15 07:16:22 opera volumio[14933]: info: ControllerMpd::pushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:16:22 opera volumio[14933]: verbose: In UPNP mode Feb 15 07:16:22 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1000,"duration":277,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Niagara Falls","artist":"The Weeknd","album":"Hurry Up Tomorrow (Explicit)","uri":"http://116.31.99.205/amobile.music.tc.qq.com/M500003NcjBN3Cl0ZG.mp3?guid=7141208BD8964BFE905C7D2794E1C916&vkey=CC8B821CFF046ED3738B79733D8D963F2FA6BAF6CAFE01F513F9C3143B0B750DDB6E186B23658817341885F2FA9D1A0ABA9AA09B1EFA5E7A__v2b94c7a3&uin=&src=M500000rAHww37Zc0h.mp3&fromtag=118042","trackType":""} Feb 15 07:16:22 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:16:22 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:16:22 opera volumio[14933]: info: ------------------------------ 25ms Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:22 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:22 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:16:22 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:16:22 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:16:22 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:16:22 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:18 opera volumio[14933]: info: Feb 15 07:17:18 opera volumio[14933]: ---------------------------- MPD announces state update: player Feb 15 07:17:18 opera volumio[14933]: info: ControllerMpd::getState Feb 15 07:17:18 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand status Feb 15 07:17:18 opera volumio[14933]: info: sendMpdCommand status took 2 milliseconds Feb 15 07:17:18 opera volumio[14933]: verbose: ControllerMpd::parseState Feb 15 07:17:18 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 07:17:18 opera volumio[14933]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 15 07:17:18 opera volumio[14933]: verbose: ControllerMpd::parseTrackInfo Feb 15 07:17:18 opera volumio[14933]: info: ControllerMpd::pushState Feb 15 07:17:18 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:18 opera volumio[14933]: verbose: In UPNP mode Feb 15 07:17:18 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37000,"duration":277,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Niagara Falls","artist":"The Weeknd","album":"Hurry Up Tomorrow (Explicit)","uri":"http://116.31.99.205/amobile.music.tc.qq.com/M500003NcjBN3Cl0ZG.mp3?guid=7141208BD8964BFE905C7D2794E1C916&vkey=CC8B821CFF046ED3738B79733D8D963F2FA6BAF6CAFE01F513F9C3143B0B750DDB6E186B23658817341885F2FA9D1A0ABA9AA09B1EFA5E7A__v2b94c7a3&uin=&src=M500000rAHww37Zc0h.mp3&fromtag=118042","trackType":""} Feb 15 07:17:18 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:17:18 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:17:18 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:17:18 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:17:18 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 15 07:17:18 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:18 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:18 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:18 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:18 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:18 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:18 opera volumio[14933]: info: ------------------------------ 16ms Feb 15 07:17:18 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:17:18 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:17:18 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:17:18 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:17:18 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:17:18 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:17:18 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:18 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:24 opera smbd[4119]: [2025/02/15 07:17:24.786643, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 07:17:24 opera smbd[4119]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 15 07:17:26 opera volumio[14933]: info: Feb 15 07:17:26 opera volumio[14933]: ---------------------------- MPD announces state update: player Feb 15 07:17:26 opera volumio[14933]: info: ControllerMpd::getState Feb 15 07:17:26 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand status Feb 15 07:17:26 opera volumio[14933]: info: sendMpdCommand status took 2 milliseconds Feb 15 07:17:26 opera volumio[14933]: verbose: ControllerMpd::parseState Feb 15 07:17:26 opera volumio[14933]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 07:17:26 opera volumio[14933]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 15 07:17:26 opera volumio[14933]: verbose: ControllerMpd::parseTrackInfo Feb 15 07:17:26 opera volumio[14933]: info: ControllerMpd::pushState Feb 15 07:17:26 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:26 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:26 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:26 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:26 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:26 opera volumio[14933]: 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":"Niagara Falls","artist":"The Weeknd","album":"Hurry Up Tomorrow (Explicit)","uri":"http://116.31.99.205/amobile.music.tc.qq.com/M500003NcjBN3Cl0ZG.mp3?guid=7141208BD8964BFE905C7D2794E1C916&vkey=CC8B821CFF046ED3738B79733D8D963F2FA6BAF6CAFE01F513F9C3143B0B750DDB6E186B23658817341885F2FA9D1A0ABA9AA09B1EFA5E7A__v2b94c7a3&uin=&src=M500000rAHww37Zc0h.mp3&fromtag=118042","trackType":""} Feb 15 07:17:26 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:17:26 opera volumio[14933]: info: CoreStateMachine::syncState stateService stop Feb 15 07:17:26 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:17:26 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:26 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:26 opera volumio[14933]: info: CoreStateMachine::stPlaybackTimer Feb 15 07:17:26 opera volumio[14933]: info: ------------------------------ 23ms Feb 15 07:17:26 opera volumio[14933]: info: CoreCommandRouter::volumioGetQueue Feb 15 07:17:26 opera volumio[14933]: info: CoreStateMachine::getQueue Feb 15 07:17:26 opera volumio[14933]: info: CorePlayQueue::getQueue Feb 15 07:17:26 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:26 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:38 opera volumio[14933]: verbose: In UPNP mode Feb 15 07:17:38 opera volumio[14933]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received airplay_emulation Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:38 opera volumio[14933]: verbose: In UPNP mode Feb 15 07:17:38 opera volumio[14933]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received airplay_emulation Feb 15 07:17:38 opera shairport-sync[16918]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Feb 15 07:17:38 opera volumio[14933]: info: Airplay started streaming, receiving metadatas Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioStop Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::stop Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:17:38 opera volumio[14933]: verbose: UNSET VOLATILE Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:17:38 opera volumio[14933]: info: Airplay started streaming, receiving metadatas Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioStop Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::stop Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:17:38 opera volumio[14933]: verbose: UNSET VOLATILE Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:17:38 opera shairport-sync[16918]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:17:38 opera shairport-sync[16918]: Dload Upload Total Spent Left Speed Feb 15 07:17:38 opera volumio[14933]: info: Feb 15 07:17:38 opera volumio[14933]: ---------------------------- Client requests Start Airplay PlaybackRoutine Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Feb 15 07:17:38 opera volumio[14933]: info: Airplay playback start Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioStop Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::stop Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:17:38 opera volumio[14933]: verbose: UNSET VOLATILE Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 07:17:38 opera shairport-sync[16918]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 07:17:38 opera shairport-sync[16918]: Dload Upload Total Spent Left Speed Feb 15 07:17:38 opera volumio[14933]: info: Feb 15 07:17:38 opera volumio[14933]: ---------------------------- Client requests AirplayActive Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Feb 15 07:17:38 opera shairport-sync[16918]: [155B blob data] Feb 15 07:17:38 opera shairport-sync[16918]: [143B blob data] Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:17:38 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus stop Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:17:38 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:17:38 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Niagara Falls (Explicit)","artist":"威肯)","album":"","albumart":"/albumart?cacheid=367&web=%E5%A8%81%E8%82%AF)//extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:17:38 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:17:38 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Niagara Falls (Explicit)","artist":"威肯)","album":"","albumart":"/albumart?cacheid=367&web=%E5%A8%81%E8%82%AF)//extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:17:38 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:17:38 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Niagara Falls (Explicit) - The Weeknd (","artist":"Niagara Falls (Explicit) — The Weeknd","album":"Hurry Up Tomorrow (Explicit)","albumart":"/albumart?cacheid=367&web=Niagara%20Falls%20(Explicit)%20%E2%80%94%20The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:17:38 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:17:38 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Niagara Falls (Explicit) - The Weeknd (","artist":"Niagara Falls (Explicit) — The Weeknd","album":"Hurry Up Tomorrow (Explicit)","albumart":"/albumart?cacheid=367&web=Niagara%20Falls%20(Explicit)%20%E2%80%94%20The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:17:38 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:17:38 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Niagara Falls (Explicit) - The Weeknd (","artist":"Niagara Falls (Explicit) — The Weeknd","album":"Hurry Up Tomorrow (Explicit)","albumart":"/albumart?cacheid=367&web=Niagara%20Falls%20(Explicit)%20%E2%80%94%20The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":10000,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:17:38 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:17:38 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Niagara Falls (Explicit) - The Weeknd (","artist":"Niagara Falls (Explicit) — The Weeknd","album":"Hurry Up Tomorrow (Explicit)","albumart":"/albumart?cacheid=367&web=Niagara%20Falls%20(Explicit)%20%E2%80%94%20The%20Weeknd/Hurry%20Up%20Tomorrow%20(Explicit)/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":10000,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Feb 15 07:17:38 opera volumio[14933]: verbose: CURRENT POSITION 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState stateService play Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::syncState currentStatus play Feb 15 07:17:38 opera volumio[14933]: info: Received an update from plugin. extracting info from payload Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:38 opera volumio[14933]: info: CorePlayQueue::getTrack 0 Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:38 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:38 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:39 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:39 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:39 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:39 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:39 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:39 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:39 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:39 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:39 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:39 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:39 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:39 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:39 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:39 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:39 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:41 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:41 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:41 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:41 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:41 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::servicePushState Feb 15 07:17:41 opera volumio[14933]: info: CoreStateMachine::pushState Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::volumioPushState Feb 15 07:17:41 opera volumio[14933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 07:17:41 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:41 opera volumio[14933]: info: Updating RAAT Signal Path Feb 15 07:17:50 opera volumio[14933]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 07:17:50 opera volumio[14933]: { Error: connect ETIMEDOUT 199.59.149.239:80 Feb 15 07:17:50 opera volumio[14933]: at Object._errnoException (util.js:1022:11) Feb 15 07:17:50 opera volumio[14933]: at _exceptionWithHostPort (util.js:1044:20) Feb 15 07:17:50 opera volumio[14933]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 15 07:17:50 opera volumio[14933]: code: 'ETIMEDOUT', Feb 15 07:17:50 opera volumio[14933]: errno: 'ETIMEDOUT', Feb 15 07:17:50 opera volumio[14933]: syscall: 'connect', Feb 15 07:17:50 opera volumio[14933]: address: '199.59.149.239', Feb 15 07:17:50 opera volumio[14933]: port: 80 } Feb 15 07:17:50 opera volumio[14933]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 07:17:50 opera sudo[17205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 07:16 Feb 15 07:17:50 opera sudo[17205]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"