-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Fri 2025-01-17 17:52:14 CST. -- Jan 17 17:51:02 volumio volumio[882]: verbose: New Socket.io Connection to 192.168.99.111 from 192.168.99.146 UA: okhttp/3.12.12 Total Clients: 5 Jan 17 17:51:02 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 17 17:51:02 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 17 17:51:02 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 17 17:51:02 volumio volumio[882]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ANTENNE%20BAYERN/f720ecd2-c52e-4829-b57f-a06a44699fd9.jpg' Jan 17 17:51:04 volumio volumio[882]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ANTENNE%20BAYERN/f720ecd2-c52e-4829-b57f-a06a44699fd9.jpg' Jan 17 17:51:04 volumio kernel: Under-voltage detected! (0x00050005) Jan 17 17:51:04 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:51:04 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:51:04 volumio volumio[882]: info: Discovery: Getting this device information Jan 17 17:51:04 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 17 17:51:04 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:51:04 volumio sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 17:51:04 volumio sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:51:05 volumio sudo[1290]: pam_unix(sudo:session): session closed for user root Jan 17 17:51:05 volumio sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 17:51:05 volumio sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:51:05 volumio sudo[1292]: pam_unix(sudo:session): session closed for user root Jan 17 17:51:05 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:51:05 volumio volumio[882]: verbose: New Socket.io Connection to 192.168.99.111 from 192.168.99.146 UA: okhttp/3.12.12 Total Clients: 6 Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 17:51:05 volumio volumio[882]: info: Received Get System Info Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:51:05 volumio volumio[882]: info: Discovery: Getting this device information Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 17 17:51:05 volumio volumio[882]: info: Listing playlists Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 17 17:51:05 volumio volumio[882]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ANTENNE%20BAYERN/f720ecd2-c52e-4829-b57f-a06a44699fd9.jpg' Jan 17 17:51:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 17 17:51:06 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 17:51:06 volumio volumio[882]: info: Received Get System Info Jan 17 17:51:06 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:51:06 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:51:06 volumio volumio[882]: info: Discovery: Getting this device information Jan 17 17:51:06 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 17 17:51:06 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:51:08 volumio volumio[882]: info: CoreCommandRouter::volumioGetQueue Jan 17 17:51:08 volumio volumio[882]: info: CoreStateMachine::getQueue Jan 17 17:51:08 volumio volumio[882]: info: CorePlayQueue::getQueue Jan 17 17:51:11 volumio kernel: Voltage normalised (0x00000000) Jan 17 17:51:11 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:51:13 volumio volumio[882]: info: CoreCommandRouter::volumioToggle Jan 17 17:51:13 volumio volumio[882]: info: CoreStateMachine::stop Jan 17 17:51:13 volumio volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 17:51:13 volumio volumio[882]: info: CoreStateMachine::stPlaybackTimer Jan 17 17:51:13 volumio volumio[882]: info: CoreStateMachine::updateTrackBlock Jan 17 17:51:13 volumio volumio[882]: info: CorePlayQueue::getTrackBlock Jan 17 17:51:13 volumio volumio[882]: info: CoreStateMachine::pushState Jan 17 17:51:13 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:13 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 17:51:13 volumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 17 17:51:13 volumio volumio[882]: info: CoreStateMachine::serviceStop Jan 17 17:51:13 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:13 volumio volumio[882]: info: CoreCommandRouter::serviceStop Jan 17 17:51:13 volumio volumio[882]: info: [1737107473754] ControllerWebradio::stop Jan 17 17:51:13 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 17:51:13 volumio volumio[882]: info: sendMpdCommand stop took 101 milliseconds Jan 17 17:51:13 volumio volumio[882]: info: Jan 17 17:51:13 volumio volumio[882]: ---------------------------- MPD announces state update: player Jan 17 17:51:13 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:13 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:13 volumio volumio[882]: info: sendMpdCommand status took 0 milliseconds Jan 17 17:51:13 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:13 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:13 volumio volumio[882]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 17 17:51:13 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:13 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:13 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:13 volumio volumio[882]: info: CoreStateMachine::pushState Jan 17 17:51:13 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:13 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 17:51:13 volumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 17 17:51:13 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:13 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:13 volumio volumio[882]: info: ------------------------------ 11ms Jan 17 17:51:15 volumio volumio[882]: info: CoreCommandRouter::volumioToggle Jan 17 17:51:15 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:15 volumio volumio[882]: info: CoreStateMachine::play index undefined Jan 17 17:51:15 volumio volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 17:51:15 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:15 volumio volumio[882]: info: CoreStateMachine::startPlaybackTimer Jan 17 17:51:15 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:15 volumio volumio[882]: info: [1737107475875] ControllerWebradio::clearAddPlayTrack Jan 17 17:51:15 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 17:51:15 volumio volumio[882]: info: sendMpdCommand stop took 0 milliseconds Jan 17 17:51:15 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 17:51:15 volumio volumio[882]: info: sendMpdCommand clear took 0 milliseconds Jan 17 17:51:15 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Jan 17 17:51:15 volumio volumio[882]: info: Jan 17 17:51:15 volumio volumio[882]: ---------------------------- MPD announces system playlist update Jan 17 17:51:15 volumio volumio[882]: info: Ignoring MPD Status Update Jan 17 17:51:16 volumio volumio[882]: info: Jan 17 17:51:16 volumio volumio[882]: ---------------------------- MPD announces system playlist update Jan 17 17:51:16 volumio volumio[882]: info: Ignoring MPD Status Update Jan 17 17:51:16 volumio volumio[882]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 840 milliseconds Jan 17 17:51:16 volumio volumio[882]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 17:51:16 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand play Jan 17 17:51:16 volumio volumio[882]: info: ------------------------------ 840ms Jan 17 17:51:16 volumio volumio[882]: info: ------------------------------ 2ms Jan 17 17:51:16 volumio volumio[882]: info: sendMpdCommand play took 1 milliseconds Jan 17 17:51:17 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:51:17 volumio volumio[882]: info: CoreCommandRouter::volumioToggle Jan 17 17:51:17 volumio volumio[882]: info: CoreStateMachine::stop Jan 17 17:51:17 volumio volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 17:51:18 volumio volumio[882]: info: Jan 17 17:51:18 volumio volumio[882]: ---------------------------- MPD announces state update: player Jan 17 17:51:18 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:18 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jan 17 17:51:18 volumio volumio[882]: info: sendMpdCommand status took 14 milliseconds Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:18 volumio volumio[882]: info: Jan 17 17:51:18 volumio volumio[882]: ---------------------------- MPD announces state update: player Jan 17 17:51:18 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:18 volumio volumio[882]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 17 17:51:18 volumio volumio[882]: info: sendMpdCommand status took 2 milliseconds Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:18 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:18 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:18 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:18 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:18 volumio volumio[882]: info: ------------------------------ 21ms Jan 17 17:51:18 volumio volumio[882]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:18 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:18 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:18 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:18 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:18 volumio volumio[882]: info: ------------------------------ 6ms Jan 17 17:51:18 volumio volumio[882]: info: Jan 17 17:51:18 volumio volumio[882]: ---------------------------- MPD announces system playlist update Jan 17 17:51:18 volumio volumio[882]: info: Ignoring MPD Status Update Jan 17 17:51:18 volumio volumio[882]: info: Jan 17 17:51:18 volumio volumio[882]: ---------------------------- MPD announces state update: player Jan 17 17:51:18 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:18 volumio volumio[882]: info: Jan 17 17:51:18 volumio volumio[882]: ---------------------------- MPD announces system playlist update Jan 17 17:51:18 volumio volumio[882]: info: Ignoring MPD Status Update Jan 17 17:51:18 volumio volumio[882]: info: Jan 17 17:51:18 volumio volumio[882]: ---------------------------- MPD announces state update: player Jan 17 17:51:18 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:18 volumio volumio[882]: info: ------------------------------ 3ms Jan 17 17:51:18 volumio volumio[882]: info: sendMpdCommand status took 2 milliseconds Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:18 volumio volumio[882]: info: ------------------------------ 3ms Jan 17 17:51:18 volumio volumio[882]: info: sendMpdCommand status took 4 milliseconds Jan 17 17:51:18 volumio volumio[882]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:18 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:18 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:18 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:18 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:18 volumio volumio[882]: info: ------------------------------ 8ms Jan 17 17:51:18 volumio volumio[882]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 17:51:18 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:18 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:18 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:18 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:18 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:18 volumio volumio[882]: info: ------------------------------ 9ms Jan 17 17:51:20 volumio volumio[882]: info: Jan 17 17:51:20 volumio volumio[882]: ---------------------------- MPD announces system playlist update Jan 17 17:51:20 volumio volumio[882]: info: Ignoring MPD Status Update Jan 17 17:51:20 volumio volumio[882]: info: Jan 17 17:51:20 volumio volumio[882]: ---------------------------- MPD announces state update: player Jan 17 17:51:20 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:20 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:20 volumio volumio[882]: info: ------------------------------ 2ms Jan 17 17:51:20 volumio volumio[882]: info: sendMpdCommand status took 1 milliseconds Jan 17 17:51:20 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:20 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:20 volumio volumio[882]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 17 17:51:20 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:20 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:20 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:20 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:20 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:20 volumio volumio[882]: info: ------------------------------ 3ms Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:51:22 volumio volumio[882]: info: Retrieving Cloud Streaming UI Jan 17 17:51:22 volumio volumio[882]: info: Getting Tidal Cloud Configuration Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:51:22 volumio volumio[882]: info: Getting Qobuz Cloud Configuration Jan 17 17:51:22 volumio volumio[882]: info: Asking plugin for UI Config Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:51:22 volumio volumio[882]: info: Getting Spotify Cloud Configuration Jan 17 17:51:22 volumio volumio[882]: info: Asking plugin for UI Config Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:51:22 volumio volumio[882]: info: Saving Spotify Acccount Jan 17 17:51:22 volumio volumio[882]: info: Got Tidal Cloud Configuration Jan 17 17:51:22 volumio volumio[882]: info: Got it Jan 17 17:51:22 volumio volumio[882]: info: Got it Jan 17 17:51:22 volumio volumio[882]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:51:22 volumio volumio[882]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:51:22 volumio volumio[882]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 17:51:22 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:51:23 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:51:23 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 17 17:51:27 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:51:28 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:51:29 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand update Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:29 volumio volumio[882]: info: Jan 17 17:51:29 volumio volumio[882]: ---------------------------- MPD announces state update: update Jan 17 17:51:29 volumio volumio[882]: info: sendMpdCommand update took 4 milliseconds Jan 17 17:51:29 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:29 volumio volumio[882]: info: sendMpdCommand status took 3 milliseconds Jan 17 17:51:29 volumio volumio[882]: info: sendMpdCommand status took 1 milliseconds Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:29 volumio volumio[882]: info: Command Router : Notfying DB Updatetrue Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:29 volumio volumio[882]: info: MPD Database updated - AlbumList cache refreshed Jan 17 17:51:29 volumio volumio[882]: info: Jan 17 17:51:29 volumio volumio[882]: ---------------------------- MPD announces state update: database Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:29 volumio volumio[882]: info: Jan 17 17:51:29 volumio volumio[882]: ---------------------------- MPD announces state update: update Jan 17 17:51:29 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:29 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:29 volumio volumio[882]: info: sendMpdCommand playlistinfo took 22 milliseconds Jan 17 17:51:29 volumio volumio[882]: info: sendMpdCommand status took 5 milliseconds Jan 17 17:51:29 volumio volumio[882]: info: sendMpdCommand status took 5 milliseconds Jan 17 17:51:29 volumio volumio[882]: info: sendMpdCommand status took 4 milliseconds Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:29 volumio volumio[882]: info: Command Router : Notfying DB Updatefalse Jan 17 17:51:29 volumio volumio[882]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:29 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:29 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:29 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:29 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:29 volumio volumio[882]: info: ------------------------------ 137ms Jan 17 17:51:29 volumio volumio[882]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 17 17:51:29 volumio volumio[882]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:29 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:29 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:29 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:29 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:29 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:29 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:29 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:29 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:29 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:29 volumio volumio[882]: info: ------------------------------ 68ms Jan 17 17:51:29 volumio volumio[882]: info: ------------------------------ 68ms Jan 17 17:51:31 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:51:32 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 17 17:51:32 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:34 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:51:35 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:51:39 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:51:40 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:51:42 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand rescan Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:42 volumio volumio[882]: info: Jan 17 17:51:42 volumio volumio[882]: ---------------------------- MPD announces state update: update Jan 17 17:51:42 volumio volumio[882]: info: sendMpdCommand rescan took 3 milliseconds Jan 17 17:51:42 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:42 volumio volumio[882]: info: Jan 17 17:51:42 volumio volumio[882]: ---------------------------- MPD announces state update: update Jan 17 17:51:42 volumio volumio[882]: info: ControllerMpd::getState Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:51:42 volumio volumio[882]: info: sendMpdCommand status took 3 milliseconds Jan 17 17:51:42 volumio volumio[882]: info: sendMpdCommand status took 2 milliseconds Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:42 volumio volumio[882]: info: Command Router : Notfying DB Updatefalse Jan 17 17:51:42 volumio volumio[882]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:42 volumio volumio[882]: info: sendMpdCommand status took 31 milliseconds Jan 17 17:51:42 volumio volumio[882]: info: sendMpdCommand status took 30 milliseconds Jan 17 17:51:42 volumio volumio[882]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:42 volumio volumio[882]: info: Command Router : Notfying DB Updatefalse Jan 17 17:51:42 volumio volumio[882]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::parseState Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:42 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:42 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:42 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:42 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:42 volumio volumio[882]: info: ------------------------------ 60ms Jan 17 17:51:42 volumio volumio[882]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 17 17:51:42 volumio volumio[882]: verbose: ControllerMpd::parseTrackInfo Jan 17 17:51:42 volumio volumio[882]: info: ControllerMpd::pushState Jan 17 17:51:42 volumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 17 17:51:42 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:51:42 volumio volumio[882]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 17 17:51:42 volumio volumio[882]: info: ------------------------------ 63ms Jan 17 17:51:43 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:51:46 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:51:47 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:51:51 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:51:51 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 17 17:51:51 volumio volumio[882]: info: CURURI: music-library Jan 17 17:51:52 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:51:55 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:51:56 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 17 17:51:56 volumio volumio[882]: info: CURURI: music-library/USB Jan 17 17:51:58 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:51:59 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 17 17:51:59 volumio volumio[882]: info: CURURI: music-library/USB/000BBEB2000C5EAF Jan 17 17:51:59 volumio volumio[882]: error: Failed LSINFO: null Jan 17 17:51:59 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:52:00 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 17 17:52:00 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 17 17:52:02 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 17 17:52:02 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 17 17:52:03 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:52:04 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:52:05 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 17 17:52:05 volumio volumio[882]: info: CURURI: music-library/USB/000BBEB2000C5EAF Jan 17 17:52:05 volumio volumio[882]: error: Failed LSINFO: null Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 17 17:52:08 volumio volumio[882]: info: Getting Alsa Cards List without I2S DAC Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 17 17:52:08 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 17 17:52:10 volumio wpa_supplicant[700]: wlan0: Failed to initiate sched scan Jan 17 17:52:12 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 17 17:52:12 volumio volumio[882]: info: CURURI: music-library/USB/000BBEB2000C5EAF Jan 17 17:52:12 volumio volumio[882]: error: Failed LSINFO: null Jan 17 17:52:14 volumio volumio[882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 17:52:14 volumio volumio[882]: Error: connect ETIMEDOUT 31.13.68.169:80 Jan 17 17:52:14 volumio volumio[882]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 17 17:52:14 volumio volumio[882]: errno: -110, Jan 17 17:52:14 volumio volumio[882]: code: 'ETIMEDOUT', Jan 17 17:52:14 volumio volumio[882]: syscall: 'connect', Jan 17 17:52:14 volumio volumio[882]: address: '31.13.68.169', Jan 17 17:52:14 volumio volumio[882]: port: 80 Jan 17 17:52:14 volumio volumio[882]: } Jan 17 17:52:14 volumio volumio[882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 17:52:14 volumio sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-17 17:51 Jan 17 17:52:14 volumio sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"