-- Logs begin at Fri 2022-04-08 15:29:57 UTC, end at Tue 2025-12-09 18:35:44 UTC. -- Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:05 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:05 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:05 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:05 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:05 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:05 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:05 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:05 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:05 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:05 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:05 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:05 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:05 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:06 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:06 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:06 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:06 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:06 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:06 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:06 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:06 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:06 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:07 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:07 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:07 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:07 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:07 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:07 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:07 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:07 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:07 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:07 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:07 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:07 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:07 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:17 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:17 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:17 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:17 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:17 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:17 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:17 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:17 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:17 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:17 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:17 opera shairport-sync[1016]: {"time":1765305214530,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Dec 09 18:34:17 opera shairport-sync[1016]: Dload Upload Total Spent Left Speed Dec 09 18:34:17 opera volumio[755]: info: Dec 09 18:34:17 opera volumio[755]: ---------------------------- Client requests Stop Airplay Playback Dec 09 18:34:17 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Dec 09 18:34:17 opera volumio[755]: info: Airplay playback stop Dec 09 18:34:17 opera volumio[755]: info: Airplay Playback Stopped, clearing state Dec 09 18:34:17 opera volumio[755]: info: CoreStateMachine::resetVolumioState Dec 09 18:34:17 opera volumio[755]: info: CoreStateMachine::getcurrentVolume Dec 09 18:34:17 opera volumio[755]: info: CoreCommandRouter::volumioRetrievevolume Dec 09 18:34:17 opera shairport-sync[1016]: [155B blob data] Dec 09 18:34:17 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:17 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:17 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:17 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:27 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:27 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:27 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:27 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:27 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:34:27 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:34:27 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:34:27 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:34:27 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:27 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:34:27 opera shairport-sync[1016]: {"time":1765305257157,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Dec 09 18:34:27 opera shairport-sync[1016]: Dload Upload Total Spent Left Speed Dec 09 18:34:27 opera volumio[755]: info: Dec 09 18:34:27 opera volumio[755]: ---------------------------- Client requests AirplayInactive Dec 09 18:34:27 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayInctive Dec 09 18:34:27 opera shairport-sync[1016]: [155B blob data] Dec 09 18:34:33 opera volumio[755]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 18:34:33 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 18:34:33 opera volumio[755]: info: CoreCommandRouter::volumioGetState Dec 09 18:34:33 opera volumio[755]: info: CoreCommandRouter::volumioGetQueue Dec 09 18:34:33 opera volumio[755]: info: CoreStateMachine::getQueue Dec 09 18:34:33 opera volumio[755]: info: CorePlayQueue::getQueue Dec 09 18:34:33 opera volumio[755]: info: Listing playlists Dec 09 18:34:36 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 18:34:39 opera volumio[755]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 18:34:39 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 18:34:39 opera volumio[755]: info: CoreCommandRouter::volumioGetState Dec 09 18:34:39 opera volumio[755]: info: CoreCommandRouter::volumioGetQueue Dec 09 18:34:39 opera volumio[755]: info: CoreStateMachine::getQueue Dec 09 18:34:39 opera volumio[755]: info: CorePlayQueue::getQueue Dec 09 18:34:39 opera volumio[755]: info: Listing playlists Dec 09 18:34:40 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 18:34:57 opera volumio[755]: info: Received OAUTH Data Dec 09 18:34:57 opera volumio[755]: info: Refreshing QOBUZ token Dec 09 18:34:57 opera sudo[2091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 18:34:57 opera sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 18:34:57 opera sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 18:34:57 opera sudo[2091]: pam_unix(sudo:session): session closed for user root Dec 09 18:34:57 opera sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 18:34:57 opera sudo[2094]: pam_unix(sudo:session): session closed for user root Dec 09 18:34:58 opera volumio[1726]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.3:5353): query type 65 is invalid Dec 09 18:34:58 opera volumio[1726]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.3:5353): query type 65 is invalid Dec 09 18:34:58 opera volumio[755]: info: Access Token successfully retrieved Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 09 18:34:58 opera volumio[755]: info: [1765305298048] CoreMusicLibrary::Adding element QOBUZ Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 18:34:58 opera volumio[755]: info: Retrieving Cloud Streaming UI Dec 09 18:34:58 opera volumio[755]: info: Getting Tidal Cloud Configuration Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 18:34:58 opera volumio[755]: info: Getting Qobuz Cloud Configuration Dec 09 18:34:58 opera volumio[755]: info: Asking plugin for UI Config Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 18:34:58 opera volumio[755]: info: Getting Spotify Cloud Configuration Dec 09 18:34:58 opera volumio[755]: info: Asking plugin for UI Config Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 18:34:58 opera volumio[755]: error: Could not retrieve plugin music_service spotify Dec 09 18:34:58 opera volumio[755]: info: Saving Spotify Acccount Dec 09 18:34:58 opera volumio[755]: info: Got it Dec 09 18:34:58 opera volumio[755]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 09 18:34:58 opera volumio[755]: info: Got Tidal Cloud Configuration Dec 09 18:34:58 opera volumio[755]: info: Got it Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::volumioGetState Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::volumioGetBrowseSources Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 18:34:58 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 09 18:34:59 opera volumio[1726]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.3:5353): query type 65 is invalid Dec 09 18:34:59 opera volumio[1726]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.3:5353): query type 65 is invalid Dec 09 18:35:11 opera volumio[755]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 18:35:11 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 18:35:11 opera volumio[755]: info: CoreCommandRouter::volumioGetQueue Dec 09 18:35:11 opera volumio[755]: info: CoreStateMachine::getQueue Dec 09 18:35:11 opera volumio[755]: info: CorePlayQueue::getQueue Dec 09 18:35:11 opera volumio[755]: info: Listing playlists Dec 09 18:35:11 opera volumio[1726]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.3:5353): query type 65 is invalid Dec 09 18:35:12 opera volumio[1726]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.3:5353): query type 65 is invalid Dec 09 18:35:12 opera volumio[1726]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.3:5353): query type 65 is invalid Dec 09 18:35:14 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 09 18:35:14 opera volumio[755]: info: handleBrowseUri took 356 milliseconds Dec 09 18:35:18 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 18:35:18 opera volumio[755]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 09 18:35:18 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 09 18:35:18 opera volumio[755]: error: Could not retrieve plugin system_controller my_volumio Dec 09 18:35:18 opera volumio[755]: info: Received Get System Version Dec 09 18:35:18 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 18:35:20 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 09 18:35:21 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 09 18:35:23 opera volumio[755]: info: handleBrowseUri took 2905 milliseconds Dec 09 18:35:24 opera volumio[755]: info: handleBrowseUri took 2982 milliseconds Dec 09 18:35:26 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 09 18:35:27 opera volumio[755]: info: handleBrowseUri took 1300 milliseconds Dec 09 18:35:29 opera volumio[755]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 18:35:29 opera volumio[755]: info: CoreStateMachine::ClearQueue Dec 09 18:35:29 opera volumio[755]: info: CoreStateMachine::stop Dec 09 18:35:29 opera volumio[755]: info: CoreStateMachine::serviceStop Dec 09 18:35:29 opera volumio[755]: info: CoreCommandRouter::serviceStop Dec 09 18:35:29 opera volumio[755]: info: Airplay Stop Dec 09 18:35:29 opera volumio[755]: info: Stopping Airplay Playback and sending pause command to client via USR2 Dec 09 18:35:29 opera volumio[755]: info: CorePlayQueue::clearPlayQueue Dec 09 18:35:29 opera volumio[755]: info: CorePlayQueue::saveQueue Dec 09 18:35:29 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:35:29 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:35:29 opera volumio[755]: info: CoreCommandRouter::volumioPushQueue Dec 09 18:35:29 opera volumio[755]: info: CoreStateMachine::addQueueItems Dec 09 18:35:29 opera volumio[755]: info: CorePlayQueue::addQueueItems Dec 09 18:35:29 opera volumio[755]: info: Adding Item to queue: qobuz://playlist/46991970 Dec 09 18:35:29 opera volumio[755]: info: Exploding uri qobuz://playlist/46991970 in service qobuz Dec 09 18:35:29 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:35:29 opera sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1016 Dec 09 18:35:29 opera sudo[2183]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 18:35:29 opera sudo[2183]: pam_unix(sudo:session): session closed for user root Dec 09 18:35:29 opera systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Dec 09 18:35:29 opera shairport-sync[1016]: {"time":1765305267157,"response":"airplayInactive Success"} Dec 09 18:35:29 opera systemd[1]: Unit shairport-sync.service entered failed state. Dec 09 18:35:29 opera volumio[755]: info: Shairport-Sync paused with USR2 Dec 09 18:35:30 opera volumio[755]: info: Restarting Shairport-Sync after stop Dec 09 18:35:30 opera sudo[2196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 09 18:35:30 opera sudo[2196]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 18:35:30 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 09 18:35:30 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 09 18:35:30 opera sudo[2196]: pam_unix(sudo:session): session closed for user root Dec 09 18:35:30 opera volumio[755]: info: Shairport-Sync retarted Dec 09 18:35:31 opera volumio[755]: info: CoreCommandRouter::volumioPlay Dec 09 18:35:31 opera volumio[755]: verbose: UNSET VOLATILE Dec 09 18:35:31 opera volumio[755]: info: Stopping Airplay Playback and sending pause command to client via USR2 Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::play index undefined Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 18:35:31 opera volumio[755]: info: CorePlayQueue::getTrack 0 Dec 09 18:35:31 opera sudo[2219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 2201 Dec 09 18:35:31 opera sudo[2219]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 18:35:31 opera systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Dec 09 18:35:31 opera sudo[2219]: pam_unix(sudo:session): session closed for user root Dec 09 18:35:31 opera systemd[1]: Unit shairport-sync.service entered failed state. Dec 09 18:35:31 opera volumio[755]: info: Shairport-Sync paused with USR2 Dec 09 18:35:31 opera volumio[755]: info: explodeUri took 2061 milliseconds Dec 09 18:35:31 opera volumio[755]: info: CorePlayQueue::saveQueue Dec 09 18:35:31 opera volumio[755]: info: CoreCommandRouter::volumioPushQueue Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::updateTrackBlock Dec 09 18:35:31 opera volumio[755]: info: CorePlayQueue::getTrackBlock Dec 09 18:35:31 opera volumio[755]: info: CoreCommandRouter::volumioPlay Dec 09 18:35:31 opera volumio[755]: verbose: UNSET VOLATILE Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::play index 0 Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::stop Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 18:35:31 opera volumio[755]: verbose: UNSET VOLATILE Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::stPlaybackTimer Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::updateTrackBlock Dec 09 18:35:31 opera volumio[755]: info: CorePlayQueue::getTrackBlock Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:35:31 opera volumio[755]: info: CorePlayQueue::getTrack 0 Dec 09 18:35:31 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:35:31 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::serviceStop Dec 09 18:35:31 opera volumio[755]: info: CorePlayQueue::getTrack 0 Dec 09 18:35:31 opera volumio[755]: info: CoreCommandRouter::serviceStop Dec 09 18:35:31 opera volumio[755]: info: [1765305331887] ControllerQobuz::stop Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 09 18:35:31 opera volumio[755]: info: ControllerMpd::stop Dec 09 18:35:31 opera volumio[755]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 18:35:31 opera volumio[755]: info: sendMpdCommand stop took 4 milliseconds Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::play index undefined Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 18:35:31 opera volumio[755]: info: CorePlayQueue::getTrack 0 Dec 09 18:35:31 opera volumio[755]: info: CoreStateMachine::startPlaybackTimer Dec 09 18:35:31 opera volumio[755]: info: CorePlayQueue::getTrack 0 Dec 09 18:35:31 opera volumio[755]: info: [1765305331894] ControllerQobuz::clearAddPlayTrack Dec 09 18:35:31 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:35:32 opera volumio[755]: info: getStreamUrl took 683 milliseconds Dec 09 18:35:32 opera volumio[755]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 18:35:32 opera volumio[755]: info: sendMpdCommand stop took 1 milliseconds Dec 09 18:35:32 opera volumio[755]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 18:35:32 opera volumio[755]: info: Dec 09 18:35:32 opera volumio[755]: ---------------------------- MPD announces system playlist update Dec 09 18:35:32 opera volumio[755]: info: Ignoring MPD Status Update Dec 09 18:35:32 opera volumio[755]: info: sendMpdCommand clear took 2 milliseconds Dec 09 18:35:32 opera volumio[755]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=9143235&eid=339800783&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1765391732&hmac=0cMEhpKXm4XwathdzYv7GrDjeak" Dec 09 18:35:32 opera volumio[755]: error: updateQueue error: null Dec 09 18:35:32 opera volumio[755]: info: ------------------------------ 4ms Dec 09 18:35:32 opera volumio[755]: info: Restarting Shairport-Sync after stop Dec 09 18:35:32 opera sudo[2232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 09 18:35:32 opera sudo[2232]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 18:35:32 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 09 18:35:32 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 09 18:35:32 opera sudo[2232]: pam_unix(sudo:session): session closed for user root Dec 09 18:35:32 opera volumio[755]: info: Shairport-Sync retarted Dec 09 18:35:33 opera volumio[755]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9143235&eid=339800783&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1765391732&hmac=0cMEhpKXm4XwathdzYv7GrDjeak" Dec 09 18:35:33 opera volumio[755]: info: Dec 09 18:35:33 opera volumio[755]: ---------------------------- MPD announces system playlist update Dec 09 18:35:33 opera volumio[755]: info: Ignoring MPD Status Update Dec 09 18:35:33 opera volumio[755]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=9143235&eid=339800783&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1765391732&hmac=0cMEhpKXm4XwathdzYv7GrDjeak" took 2 milliseconds Dec 09 18:35:33 opera volumio[755]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 09 18:35:33 opera volumio[755]: verbose: ControllerMpd::sendMpdCommand play Dec 09 18:35:33 opera volumio[755]: info: ------------------------------ 4ms Dec 09 18:35:33 opera volumio[755]: info: sendMpdCommand play took 3 milliseconds Dec 09 18:35:35 opera volumio[755]: info: Dec 09 18:35:35 opera volumio[755]: ---------------------------- MPD announces state update: player Dec 09 18:35:35 opera volumio[755]: info: ControllerMpd::getState Dec 09 18:35:35 opera volumio[755]: verbose: ControllerMpd::sendMpdCommand status Dec 09 18:35:35 opera volumio[755]: info: sendMpdCommand status took 222 milliseconds Dec 09 18:35:35 opera volumio[755]: verbose: ControllerMpd::parseState Dec 09 18:35:35 opera volumio[755]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 18:35:35 opera volumio[755]: info: Dec 09 18:35:35 opera volumio[755]: ---------------------------- MPD announces state update: player Dec 09 18:35:35 opera volumio[755]: info: ControllerMpd::getState Dec 09 18:35:35 opera volumio[755]: verbose: ControllerMpd::sendMpdCommand status Dec 09 18:35:35 opera volumio[755]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 09 18:35:35 opera volumio[755]: verbose: ControllerMpd::parseTrackInfo Dec 09 18:35:35 opera volumio[755]: info: ControllerMpd::pushState Dec 09 18:35:35 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:35:35 opera volumio[755]: info: CorePlayQueue::getTrack 0 Dec 09 18:35:35 opera volumio[755]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2537 Kbps","isStreaming":false,"title":"file?uid=9143235&eid=339800783&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1765391732&hmac=0cMEhpKXm4XwathdzYv7GrDjeak","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9143235&eid=339800783&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1765391732&hmac=0cMEhpKXm4XwathdzYv7GrDjeak","trackType":"qobuz"} Dec 09 18:35:35 opera volumio[755]: verbose: CURRENT POSITION 0 Dec 09 18:35:35 opera volumio[755]: info: CoreStateMachine::syncState stateService play Dec 09 18:35:35 opera volumio[755]: info: CoreStateMachine::syncState currentStatus stop Dec 09 18:35:35 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:35:35 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:35:35 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:35:35 opera volumio[755]: info: ------------------------------ 247ms Dec 09 18:35:35 opera volumio[755]: info: sendMpdCommand status took 21 milliseconds Dec 09 18:35:35 opera volumio[755]: verbose: ControllerMpd::parseState Dec 09 18:35:35 opera volumio[755]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 18:35:35 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:35:35 opera volumio[755]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 09 18:35:35 opera volumio[755]: verbose: ControllerMpd::parseTrackInfo Dec 09 18:35:35 opera volumio[755]: info: ControllerMpd::pushState Dec 09 18:35:35 opera volumio[755]: info: CoreCommandRouter::servicePushState Dec 09 18:35:35 opera volumio[755]: info: CorePlayQueue::getTrack 0 Dec 09 18:35:35 opera volumio[755]: verbose: STATE SERVICE {"status":"play","position":0,"seek":490,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2537 Kbps","isStreaming":false,"title":"file?uid=9143235&eid=339800783&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1765391732&hmac=0cMEhpKXm4XwathdzYv7GrDjeak","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=9143235&eid=339800783&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1765391732&hmac=0cMEhpKXm4XwathdzYv7GrDjeak","trackType":"qobuz"} Dec 09 18:35:35 opera volumio[755]: verbose: CURRENT POSITION 0 Dec 09 18:35:35 opera volumio[755]: info: CoreStateMachine::syncState stateService play Dec 09 18:35:35 opera volumio[755]: info: CoreStateMachine::syncState currentStatus play Dec 09 18:35:35 opera volumio[755]: info: Received an update from plugin. extracting info from payload Dec 09 18:35:35 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:35:35 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:35:35 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:35:35 opera volumio[755]: info: CoreStateMachine::pushState Dec 09 18:35:35 opera volumio[755]: info: CoreCommandRouter::volumioPushState Dec 09 18:35:35 opera volumio[755]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 18:35:35 opera volumio[755]: info: ------------------------------ 46ms Dec 09 18:35:35 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:35:35 opera volumio[755]: info: Updating RAAT Signal Path Dec 09 18:35:44 opera volumio[755]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 18:35:44 opera volumio[755]: { Error: connect ETIMEDOUT 199.59.149.234:443 Dec 09 18:35:44 opera volumio[755]: at Object._errnoException (util.js:1022:11) Dec 09 18:35:44 opera volumio[755]: at _exceptionWithHostPort (util.js:1044:20) Dec 09 18:35:44 opera volumio[755]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Dec 09 18:35:44 opera volumio[755]: code: 'ETIMEDOUT', Dec 09 18:35:44 opera volumio[755]: errno: 'ETIMEDOUT', Dec 09 18:35:44 opera volumio[755]: syscall: 'connect', Dec 09 18:35:44 opera volumio[755]: address: '199.59.149.234', Dec 09 18:35:44 opera volumio[755]: port: 443 } Dec 09 18:35:44 opera volumio[755]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 18:35:44 opera sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-09 18:34 Dec 09 18:35:44 opera sudo[2280]: 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"