-- Logs begin at Wed 2023-11-29 18:22:54 UTC, end at Wed 2023-11-29 19:51:55 UTC. -- Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 19:50:12 musical-fidelity volumio[1325]: info: Discovery: Getting this device information Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetState Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 19:50:12 musical-fidelity volumio[1325]: info: Discovery: Getting this device information Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetState Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:50:12 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:50:13 musical-fidelity volumio[1325]: info: Received OAUTH Data Nov 29 19:50:13 musical-fidelity volumio[1325]: info: Refreshing TIDAL token Nov 29 19:50:13 musical-fidelity volumio[1325]: error: No access token received for TIDAL Nov 29 19:50:13 musical-fidelity sudo[3201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 19:50:13 musical-fidelity sudo[3201]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 19:50:13 musical-fidelity sudo[3201]: pam_unix(sudo:session): session closed for user root Nov 29 19:50:14 musical-fidelity sudo[3204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 19:50:14 musical-fidelity sudo[3204]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 19:50:14 musical-fidelity sudo[3204]: pam_unix(sudo:session): session closed for user root Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Retrieving Cloud Streaming UI Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 29 19:50:14 musical-fidelity volumio[1325]: error: Could not retrieve plugin audio_interface outputs Nov 29 19:50:14 musical-fidelity volumio[1325]: error: WARNING: No Audio Output plugin found Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetState Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Getting Tidal Cloud Configuration Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Getting Qobuz Cloud Configuration Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Asking plugin for UI Config Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Getting Spotify Cloud Configuration Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Asking plugin for UI Config Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:14 musical-fidelity volumio[1325]: error: Could not retrieve plugin music_service spotify Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Saving Spotify Acccount Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Got it Nov 29 19:50:14 musical-fidelity volumio[1325]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Got Tidal Cloud Configuration Nov 29 19:50:14 musical-fidelity volumio[1325]: info: Got it Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetBrowseSources Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 29 19:50:14 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 29 19:50:18 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 29 19:50:22 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 29 19:50:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 19:50:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:29 musical-fidelity volumio[1325]: info: Retrieving Cloud Streaming UI Nov 29 19:50:29 musical-fidelity volumio[1325]: info: Getting Tidal Cloud Configuration Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:29 musical-fidelity volumio[1325]: info: Getting Qobuz Cloud Configuration Nov 29 19:50:29 musical-fidelity volumio[1325]: info: Asking plugin for UI Config Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:29 musical-fidelity volumio[1325]: info: Getting Spotify Cloud Configuration Nov 29 19:50:29 musical-fidelity volumio[1325]: info: Asking plugin for UI Config Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:29 musical-fidelity volumio[1325]: error: Could not retrieve plugin music_service spotify Nov 29 19:50:29 musical-fidelity volumio[1325]: info: Saving Spotify Acccount Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:29 musical-fidelity volumio[1325]: info: Got it Nov 29 19:50:29 musical-fidelity volumio[1325]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 29 19:50:29 musical-fidelity volumio[1325]: info: Got Tidal Cloud Configuration Nov 29 19:50:29 musical-fidelity volumio[1325]: info: Got it Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetBrowseSources Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 29 19:50:29 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 29 19:50:32 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 19:50:32 musical-fidelity volumio[1325]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 29 19:50:32 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 29 19:50:32 musical-fidelity volumio[1325]: error: Could not retrieve plugin system_controller my_volumio Nov 29 19:50:32 musical-fidelity volumio[1325]: info: Received Get System Version Nov 29 19:50:32 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioStop Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreStateMachine::stop Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 19:50:35 musical-fidelity volumio[1325]: verbose: UNSET VOLATILE Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreStateMachine::stPlaybackTimer Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreStateMachine::updateTrackBlock Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CorePlayQueue::getTrackBlock Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CorePlayQueue::getTrack 0 Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreStateMachine::serviceStop Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CorePlayQueue::getTrack 0 Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreCommandRouter::serviceStop Nov 29 19:50:35 musical-fidelity volumio[1325]: info: [1701287435093] ControllerWebradio::stop Nov 29 19:50:35 musical-fidelity volumio[1325]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 19:50:35 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:50:35 musical-fidelity volumio[1325]: info: Nov 29 19:50:35 musical-fidelity volumio[1325]: ---------------------------- MPD announces state update: player Nov 29 19:50:35 musical-fidelity volumio[1325]: info: sendMpdCommand stop took 122 milliseconds Nov 29 19:50:35 musical-fidelity volumio[1325]: info: ControllerMpd::getState Nov 29 19:50:35 musical-fidelity volumio[1325]: verbose: ControllerMpd::sendMpdCommand status Nov 29 19:50:35 musical-fidelity volumio[1325]: info: sendMpdCommand status took 2 milliseconds Nov 29 19:50:35 musical-fidelity volumio[1325]: verbose: ControllerMpd::parseState Nov 29 19:50:35 musical-fidelity volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 19:50:35 musical-fidelity volumio[1325]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 19:50:35 musical-fidelity volumio[1325]: verbose: ControllerMpd::parseTrackInfo Nov 29 19:50:35 musical-fidelity volumio[1325]: info: ControllerMpd::pushState Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CorePlayQueue::getTrack 0 Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:50:35 musical-fidelity volumio[1325]: info: CorePlayQueue::getTrack 0 Nov 29 19:50:35 musical-fidelity volumio[1325]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 29 19:50:35 musical-fidelity volumio[1325]: info: ------------------------------ 22ms Nov 29 19:50:35 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:50:36 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetQueue Nov 29 19:50:36 musical-fidelity volumio[1325]: info: CoreStateMachine::getQueue Nov 29 19:50:36 musical-fidelity volumio[1325]: info: CorePlayQueue::getQueue Nov 29 19:50:36 musical-fidelity volumio[1325]: info: Listing playlists Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , getAirplayStatus Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSyncOnDemand Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 19:50:38 musical-fidelity volumio[1325]: verbose: UNSET VOLATILE Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioStop Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreStateMachine::stop Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 19:50:38 musical-fidelity volumio[1325]: verbose: UNSET VOLATILE Nov 29 19:50:38 musical-fidelity volumio[1325]: No protocol specified Nov 29 19:50:38 musical-fidelity volumio[1325]: xcb_connection_has_error() returned true Nov 29 19:50:38 musical-fidelity volumio[1325]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 19:50:38 musical-fidelity volumio[1325]: No protocol specified Nov 29 19:50:38 musical-fidelity volumio[1325]: xcb_connection_has_error() returned true Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioStop Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreStateMachine::stop Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 19:50:38 musical-fidelity volumio[1325]: verbose: UNSET VOLATILE Nov 29 19:50:38 musical-fidelity volumio[1325]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 19:50:38 musical-fidelity sudo[3398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 19:50:38 musical-fidelity sudo[3398]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 19:50:38 musical-fidelity systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 29 19:50:38 musical-fidelity volumio[1325]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Nov 29 19:50:38 musical-fidelity systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 19:50:38 musical-fidelity volumio[1325]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Nov 29 19:50:38 musical-fidelity sudo[3398]: pam_unix(sudo:session): session closed for user root Nov 29 19:50:38 musical-fidelity volumio[1325]: info: Shairport-Sync Started Nov 29 19:50:38 musical-fidelity volumio[1325]: Error adding Membership: Error: addMembership EINVAL Nov 29 19:50:38 musical-fidelity volumio[1325]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Nov 29 19:50:38 musical-fidelity volumio[1325]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Nov 29 19:50:38 musical-fidelity volumio[1325]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumiosetSourceActiveairplay_emulation Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 19:50:39 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:50:39 musical-fidelity volumio[1325]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:50:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:50:39 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:50:48 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 19:50:48 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 19:50:48 musical-fidelity volumio[1325]: info: Discovery: Getting this device information Nov 29 19:50:48 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetState Nov 29 19:50:48 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 29 19:50:48 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:50:48 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 19:50:49 musical-fidelity volumio[1325]: info: Discovery: Getting this device information Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetState Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: bluetooth , getBTStatus Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: bluetooth , startBluetoothOnDemand Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 19:50:49 musical-fidelity volumio[1325]: verbose: UNSET VOLATILE Nov 29 19:50:49 musical-fidelity volumio[1325]: STOPPING SHAIRPORT WITH UNSET VOLATILE Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioStop Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreStateMachine::stop Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 19:50:49 musical-fidelity volumio[1325]: verbose: UNSET VOLATILE Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreStateMachine::stPlaybackTimer Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreStateMachine::updateTrackBlock Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CorePlayQueue::getTrackBlock Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CorePlayQueue::getTrack 0 Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreStateMachine::serviceStop Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CorePlayQueue::getTrack 0 Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::serviceStop Nov 29 19:50:49 musical-fidelity volumio[1325]: info: [1701287449745] ControllerWebradio::stop Nov 29 19:50:49 musical-fidelity volumio[1325]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 19:50:49 musical-fidelity volumio[1325]: info: Enabling Bluetooth Audio Output Nov 29 19:50:49 musical-fidelity sudo[3484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Nov 29 19:50:49 musical-fidelity sudo[3484]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 19:50:49 musical-fidelity systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 19:50:49 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 19:50:49 musical-fidelity sudo[3484]: pam_unix(sudo:session): session closed for user root Nov 29 19:50:49 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:50:49 musical-fidelity volumio[1325]: info: sendMpdCommand stop took 72 milliseconds Nov 29 19:50:49 musical-fidelity volumio[1325]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Nov 29 19:50:49 musical-fidelity volumio[1325]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Nov 29 19:50:49 musical-fidelity volumio[1325]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Nov 29 19:50:49 musical-fidelity volumio[1325]: info: Shairport-Sync Stopped Nov 29 19:50:49 musical-fidelity volumio[1325]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Nov 29 19:50:49 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Nov 29 19:50:50 musical-fidelity volumio[1325]: info: Bluetooth Audio successfully Enabled Nov 29 19:50:50 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:50:50 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:50:50 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:50:50 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:50:50 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:50:50 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:50:50 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:50:50 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:50:50 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:50:50 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumiosetSourceActivebluetooth Nov 29 19:50:50 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 19:50:50 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 19:51:28 musical-fidelity volumio[1325]: info: Discovery: Getting this device information Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetState Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 19:51:28 musical-fidelity volumio[1325]: info: Discovery: Getting this device information Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioGetState Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:51:28 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 29 19:51:38 musical-fidelity volumio[1325]: Duration is 87000 Nov 29 19:51:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:51:38 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:51:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:51:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:51:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:51:38 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:51:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:51:38 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:51:38 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:51:38 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:51:39 musical-fidelity volumio[1325]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Nov 29 19:51:39 musical-fidelity volumio[1325]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Nov 29 19:51:39 musical-fidelity volumio[1325]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Nov 29 19:51:39 musical-fidelity volumio[1325]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Nov 29 19:51:39 musical-fidelity volumio[1325]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Nov 29 19:51:39 musical-fidelity volumio[1325]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:445:13) Nov 29 19:51:39 musical-fidelity volumio[1325]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 29 19:51:39 musical-fidelity volumio[1325]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Nov 29 19:51:39 musical-fidelity volumio[1325]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 29 19:51:39 musical-fidelity volumio[1325]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Nov 29 19:51:39 musical-fidelity volumio[1325]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Nov 29 19:51:39 musical-fidelity volumio[1325]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Nov 29 19:51:39 musical-fidelity volumio[1325]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Nov 29 19:51:39 musical-fidelity volumio[1325]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Nov 29 19:51:39 musical-fidelity volumio[1325]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:445:13) Nov 29 19:51:39 musical-fidelity volumio[1325]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 29 19:51:39 musical-fidelity volumio[1325]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Nov 29 19:51:39 musical-fidelity volumio[1325]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 29 19:51:39 musical-fidelity volumio[1325]: Duration is 87458 Nov 29 19:51:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:51:39 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:51:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:51:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:51:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:51:39 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:51:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:51:39 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:51:39 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:51:39 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:51:39 musical-fidelity volumio[1325]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Nov 29 19:51:39 musical-fidelity volumio[1325]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Nov 29 19:51:39 musical-fidelity volumio[1325]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Nov 29 19:51:39 musical-fidelity volumio[1325]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Nov 29 19:51:39 musical-fidelity volumio[1325]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Nov 29 19:51:39 musical-fidelity volumio[1325]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:445:13) Nov 29 19:51:39 musical-fidelity volumio[1325]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 29 19:51:39 musical-fidelity volumio[1325]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Nov 29 19:51:39 musical-fidelity volumio[1325]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 29 19:51:39 musical-fidelity volumio[1325]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Nov 29 19:51:39 musical-fidelity volumio[1325]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Nov 29 19:51:39 musical-fidelity volumio[1325]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Nov 29 19:51:39 musical-fidelity volumio[1325]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Nov 29 19:51:39 musical-fidelity volumio[1325]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Nov 29 19:51:39 musical-fidelity volumio[1325]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:445:13) Nov 29 19:51:39 musical-fidelity volumio[1325]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 29 19:51:39 musical-fidelity volumio[1325]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Nov 29 19:51:39 musical-fidelity volumio[1325]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 29 19:51:39 musical-fidelity volumio[1325]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Nov 29 19:51:39 musical-fidelity volumio[1325]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Nov 29 19:51:39 musical-fidelity volumio[1325]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Nov 29 19:51:39 musical-fidelity volumio[1325]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Nov 29 19:51:39 musical-fidelity volumio[1325]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Nov 29 19:51:39 musical-fidelity volumio[1325]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:445:13) Nov 29 19:51:39 musical-fidelity volumio[1325]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 29 19:51:39 musical-fidelity volumio[1325]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Nov 29 19:51:39 musical-fidelity volumio[1325]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 29 19:51:53 musical-fidelity volumio[1325]: Duration is 172000 Nov 29 19:51:53 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:51:53 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:51:53 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:51:53 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:51:53 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:51:53 musical-fidelity volumio[1325]: info: CoreCommandRouter::servicePushState Nov 29 19:51:53 musical-fidelity volumio[1325]: info: CoreStateMachine::pushState Nov 29 19:51:53 musical-fidelity volumio[1325]: info: CoreCommandRouter::volumioPushState Nov 29 19:51:53 musical-fidelity volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 19:51:53 musical-fidelity volumio[1325]: info: Updating RAAT Signal Path Nov 29 19:51:54 musical-fidelity volumio[1325]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 19:51:54 musical-fidelity volumio[1325]: { Error: certificate is not yet valid Nov 29 19:51:54 musical-fidelity volumio[1325]: at TLSSocket. (_tls_wrap.js:1105:38) Nov 29 19:51:54 musical-fidelity volumio[1325]: at emitNone (events.js:106:13) Nov 29 19:51:54 musical-fidelity volumio[1325]: at TLSSocket.emit (events.js:208:7) Nov 29 19:51:54 musical-fidelity volumio[1325]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Nov 29 19:51:54 musical-fidelity volumio[1325]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Nov 29 19:51:54 musical-fidelity volumio[1325]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 19:51:55 musical-fidelity sudo[3924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-29 19:50 Nov 29 19:51:55 musical-fidelity sudo[3924]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"