-- Logs begin at Thu 2025-07-17 08:38:56 UTC, end at Thu 2025-07-17 09:15:11 UTC. -- Jul 17 09:14:01 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:02 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:02 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 09:14:08 opera volumio[1273]: info: Retrieving Cloud Streaming UI Jul 17 09:14:08 opera volumio[1273]: info: Getting Tidal Cloud Configuration Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 09:14:08 opera volumio[1273]: info: Getting Qobuz Cloud Configuration Jul 17 09:14:08 opera volumio[1273]: info: Asking plugin for UI Config Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 09:14:08 opera volumio[1273]: info: Getting Spotify Cloud Configuration Jul 17 09:14:08 opera volumio[1273]: info: Asking plugin for UI Config Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 09:14:08 opera volumio[1273]: error: Could not retrieve plugin music_service spotify Jul 17 09:14:08 opera volumio[1273]: info: Saving Spotify Acccount Jul 17 09:14:08 opera volumio[1273]: info: Got it Jul 17 09:14:08 opera volumio[1273]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 17 09:14:08 opera volumio[1273]: info: Got Tidal Cloud Configuration Jul 17 09:14:08 opera volumio[1273]: info: Got it Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::volumioGetBrowseSources Jul 17 09:14:08 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:08 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 09:14:08 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 17 09:14:09 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::sendMpdCommand update Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::sendMpdCommand status Jul 17 09:14:11 opera volumio[1273]: info: Jul 17 09:14:11 opera volumio[1273]: ---------------------------- MPD announces state update: update Jul 17 09:14:11 opera volumio[1273]: info: sendMpdCommand update took 6 milliseconds Jul 17 09:14:11 opera volumio[1273]: info: ControllerMpd::getState Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::sendMpdCommand status Jul 17 09:14:11 opera volumio[1273]: info: sendMpdCommand status took 7 milliseconds Jul 17 09:14:11 opera volumio[1273]: info: sendMpdCommand status took 2 milliseconds Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::parseState Jul 17 09:14:11 opera volumio[1273]: info: Command Router : Notfying DB Updatetrue Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::parseState Jul 17 09:14:11 opera volumio[1273]: info: ControllerMpd::pushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 09:14:11 opera volumio[1273]: verbose: CURRENT POSITION 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::syncState stateService stop Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::syncState currentStatus stop Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:14:11 opera volumio[1273]: info: No code Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:14:11 opera volumio[1273]: info: ------------------------------ 65ms Jul 17 09:14:11 opera volumio[1273]: info: MPD Database updated - AlbumList cache refreshed Jul 17 09:14:11 opera volumio[1273]: info: Jul 17 09:14:11 opera volumio[1273]: ---------------------------- MPD announces state update: database Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::sendMpdCommand status Jul 17 09:14:11 opera volumio[1273]: info: Jul 17 09:14:11 opera volumio[1273]: ---------------------------- MPD announces state update: update Jul 17 09:14:11 opera volumio[1273]: info: ControllerMpd::getState Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::sendMpdCommand status Jul 17 09:14:11 opera volumio[1273]: info: ControllerMpd::getState Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::sendMpdCommand status Jul 17 09:14:11 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:14:11 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:14:11 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:14:11 opera volumio[1273]: info: sendMpdCommand status took 31 milliseconds Jul 17 09:14:11 opera volumio[1273]: info: sendMpdCommand status took 30 milliseconds Jul 17 09:14:11 opera volumio[1273]: info: sendMpdCommand status took 32 milliseconds Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::parseState Jul 17 09:14:11 opera volumio[1273]: info: Command Router : Notfying DB Updatefalse Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::Close All Modals sent Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::parseState Jul 17 09:14:11 opera volumio[1273]: verbose: ControllerMpd::parseState Jul 17 09:14:11 opera volumio[1273]: info: ControllerMpd::pushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: 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} Jul 17 09:14:11 opera volumio[1273]: verbose: CURRENT POSITION 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::syncState stateService stop Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::syncState currentStatus stop Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:14:11 opera volumio[1273]: info: No code Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:14:11 opera volumio[1273]: info: ControllerMpd::pushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: 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} Jul 17 09:14:11 opera volumio[1273]: verbose: CURRENT POSITION 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::syncState stateService stop Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::syncState currentStatus stop Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:14:11 opera volumio[1273]: info: No code Jul 17 09:14:11 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:14:11 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:14:11 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:14:11 opera volumio[1273]: info: ------------------------------ 109ms Jul 17 09:14:11 opera volumio[1273]: info: ------------------------------ 109ms Jul 17 09:14:11 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:14:11 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:14:11 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:14:11 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:14:11 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:14:11 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:14:12 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 09:14:15 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:16 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:16 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:28 opera systemd[1]: Started volumio-remote-updater-push.service. Jul 17 09:14:28 opera systemd[1]: Starting Multi-User System. Jul 17 09:14:28 opera systemd[1]: Reached target Multi-User System. Jul 17 09:14:28 opera systemd[1]: Starting Graphical Interface. Jul 17 09:14:28 opera systemd[1]: Reached target Graphical Interface. Jul 17 09:14:28 opera systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 17 09:14:28 opera volumio[1273]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 17 09:14:28 opera systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 17 09:14:28 opera systemd[1]: Startup finished in 11.476s (kernel) + 2min 6.367s (userspace) = 2min 17.844s. Jul 17 09:14:40 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 17 09:14:40 opera volumio[1273]: info: CURURI: albums://%E5%85%B8%E8%8C%83/Hi-Fi%E5%B9%B4%E4%BB%A3%20Vol.6%20-%20%E7%BA%A2%E6%B0%91%E6%AD%8C Jul 17 09:14:40 opera volumio[1273]: error: Listalbum songs error: null Jul 17 09:14:42 opera volumio[1273]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::ClearQueue Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::stop Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 09:14:42 opera volumio[1273]: verbose: UNSET VOLATILE Jul 17 09:14:42 opera volumio[1273]: info: CorePlayQueue::clearPlayQueue Jul 17 09:14:42 opera volumio[1273]: info: CorePlayQueue::saveQueue Jul 17 09:14:42 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:14:42 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:14:42 opera volumio[1273]: info: CoreCommandRouter::volumioPushQueue Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::addQueueItems Jul 17 09:14:42 opera volumio[1273]: info: CorePlayQueue::addQueueItems Jul 17 09:14:42 opera volumio[1273]: info: Adding Item to queue: music-library/ Jul 17 09:14:42 opera volumio[1273]: info: Exploding uri music-library/ in service mpd Jul 17 09:14:42 opera volumio[1273]: Failed to stat '/mnt//USB/EFI' Jul 17 09:14:42 opera volumio[1273]: Failed to stat '/mnt//USB/未命名' Jul 17 09:14:42 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:14:42 opera volumio[1273]: info: CorePlayQueue::saveQueue Jul 17 09:14:42 opera volumio[1273]: info: CoreCommandRouter::volumioPushQueue Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::updateTrackBlock Jul 17 09:14:42 opera volumio[1273]: info: CorePlayQueue::getTrackBlock Jul 17 09:14:42 opera volumio[1273]: info: CoreCommandRouter::volumioPlay Jul 17 09:14:42 opera volumio[1273]: verbose: UNSET VOLATILE Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::play index 0 Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::stop Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 09:14:42 opera volumio[1273]: verbose: UNSET VOLATILE Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::play index undefined Jul 17 09:14:42 opera volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 09:14:42 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:14:52 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:52 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:53 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:14:53 opera volumio[1657]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Jul 17 09:15:05 opera volumio[1273]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Elliott%20Smith/Good%20Will%20Hunting/7688d6f9-8087-4be3-9b6c-84a998ba2f24.png' Jul 17 09:15:05 opera volumio[1273]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Luscious%20Jackson/Good%20Will%20Hunting/288b11fb-983c-4d1c-bb75-8768c602d231.jpg' Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:15:06 opera volumio[1273]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} Jul 17 09:15:06 opera volumio[1273]: verbose: CURRENT POSITION 0 Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::syncState stateService play Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::syncState currentStatus stop Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:15:06 opera volumio[1273]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} Jul 17 09:15:06 opera volumio[1273]: verbose: CURRENT POSITION 0 Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::syncState stateService play Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::syncState currentStatus play Jul 17 09:15:06 opera volumio[1273]: info: Received an update from plugin. extracting info from payload Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:06 opera shairport-sync[1678]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 17 09:15:06 opera shairport-sync[1678]: Dload Upload Total Spent Left Speed Jul 17 09:15:06 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:06 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:06 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:06 opera volumio[1273]: info: Jul 17 09:15:06 opera volumio[1273]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jul 17 09:15:06 opera volumio[1273]: info: Airplay playback start Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::volumioStop Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::stop Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 09:15:06 opera volumio[1273]: verbose: UNSET VOLATILE Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::stPlaybackTimer Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::updateTrackBlock Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrackBlock Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:06 opera shairport-sync[1678]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current Jul 17 09:15:06 opera shairport-sync[1678]: Dload Upload Total Spent Left Speed Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::serviceStop Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:15:06 opera volumio[1273]: info: ControllerMpd::stop Jul 17 09:15:06 opera volumio[1273]: verbose: ControllerMpd::sendMpdCommand stop Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 09:15:06 opera shairport-sync[1678]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Jul 17 09:15:06 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:06 opera volumio[1273]: info: sendMpdCommand stop took 12 milliseconds Jul 17 09:15:06 opera shairport-sync[1678]: 100 64 100 64 0 0 2356 0 --:--:-- --:--:-- --:--:-- 2461 Jul 17 09:15:06 opera volumio[1273]: info: Jul 17 09:15:06 opera volumio[1273]: ---------------------------- Client requests AirplayActive Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Jul 17 09:15:06 opera shairport-sync[1678]: [143B blob data] Jul 17 09:15:06 opera volumio[1273]: info: Airplay started streaming, receiving metadatas Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::volumioStop Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::stop Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 09:15:06 opera volumio[1273]: verbose: UNSET VOLATILE Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 09:15:06 opera volumio[1273]: info: Airplay started streaming, receiving metadatas Jul 17 09:15:06 opera volumio[1273]: info: CorePlayQueue::getTrack 0 Jul 17 09:15:06 opera volumio[1273]: info: CoreCommandRouter::volumioStop Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::stop Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 09:15:06 opera volumio[1273]: verbose: UNSET VOLATILE Jul 17 09:15:06 opera volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:15:07 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:07 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:15:07 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:07 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:15:07 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:07 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:15:07 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:15:07 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:15:07 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::servicePushState Jul 17 09:15:07 opera volumio[1273]: info: CoreStateMachine::pushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::volumioPushState Jul 17 09:15:07 opera volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 09:15:07 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:07 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:07 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:07 opera volumio[1273]: info: Updating RAAT Signal Path Jul 17 09:15:10 opera volumio[1273]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 09:15:10 opera volumio[1273]: { Error: connect ETIMEDOUT 162.125.32.2:443 Jul 17 09:15:10 opera volumio[1273]: at Object._errnoException (util.js:1022:11) Jul 17 09:15:10 opera volumio[1273]: at _exceptionWithHostPort (util.js:1044:20) Jul 17 09:15:10 opera volumio[1273]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jul 17 09:15:10 opera volumio[1273]: code: 'ETIMEDOUT', Jul 17 09:15:10 opera volumio[1273]: errno: 'ETIMEDOUT', Jul 17 09:15:10 opera volumio[1273]: syscall: 'connect', Jul 17 09:15:10 opera volumio[1273]: address: '162.125.32.2', Jul 17 09:15:10 opera volumio[1273]: port: 443 } Jul 17 09:15:10 opera volumio[1273]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 09:15:11 opera sudo[2130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 09:14 Jul 17 09:15:11 opera sudo[2130]: 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"