-- Logs begin at Mon 2024-12-02 16:22:41 HKT, end at Mon 2024-12-02 16:36:32 HKT. -- Dec 02 16:35:04 volumio sudo[3080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 16:35:04 volumio sudo[3080]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 16:35:04 volumio sudo[3080]: pam_unix(sudo:session): session closed for user root Dec 02 16:35:04 volumio sudo[3083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 16:35:04 volumio sudo[3083]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 16:35:04 volumio sudo[3083]: pam_unix(sudo:session): session closed for user root Dec 02 16:35:04 volumio volumio[1221]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.6 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 02 16:35:04 volumio volumio[1221]: info: Received Get System Info Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 16:35:04 volumio volumio[1221]: info: Discovery: Getting this device information Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState Dec 02 16:35:04 volumio volumio[1221]: info: Listing playlists Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 16:35:04 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 02 16:35:06 volumio volumio[1221]: info: Received Get System Info Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 16:35:06 volumio volumio[1221]: info: Discovery: Getting this device information Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::volumioStop Dec 02 16:35:06 volumio volumio[1221]: info: CoreStateMachine::stop Dec 02 16:35:06 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 16:35:06 volumio volumio[1221]: info: CoreStateMachine::stPlaybackTimer Dec 02 16:35:06 volumio volumio[1221]: info: CoreStateMachine::updateTrackBlock Dec 02 16:35:06 volumio volumio[1221]: info: CorePlayQueue::getTrackBlock Dec 02 16:35:06 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:35:06 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:35:06 volumio volumio[1221]: info: CoreStateMachine::serviceStop Dec 02 16:35:06 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::serviceStop Dec 02 16:35:06 volumio volumio[1221]: info: [1733128506946] ControllerWebradio::stop Dec 02 16:35:06 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 16:35:06 volumio volumio[1221]: info: Dec 02 16:35:06 volumio volumio[1221]: ---------------------------- MPD announces state update: player Dec 02 16:35:06 volumio volumio[1221]: info: sendMpdCommand stop took 9 milliseconds Dec 02 16:35:06 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:35:06 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:35:06 volumio volumio[1221]: info: sendMpdCommand status took 1 milliseconds Dec 02 16:35:06 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:35:06 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:35:06 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 02 16:35:06 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:35:06 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:35:06 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:35:06 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:06 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:35:06 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:06 volumio volumio[1221]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 02 16:35:06 volumio volumio[1221]: info: ------------------------------ 12ms Dec 02 16:35:08 volumio volumio[1221]: info: CoreCommandRouter::volumioNext Dec 02 16:35:08 volumio volumio[1221]: info: CoreStateMachine::next Dec 02 16:35:08 volumio volumio[1221]: info: CoreStateMachine::stop Dec 02 16:35:08 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 16:35:08 volumio volumio[1221]: info: CoreStateMachine::play index undefined Dec 02 16:35:08 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 16:35:08 volumio volumio[1221]: info: CorePlayQueue::getTrack 1 Dec 02 16:35:10 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Dec 02 16:35:10 volumio volumio[1221]: info: CoreStateMachine::getQueue Dec 02 16:35:10 volumio volumio[1221]: info: CorePlayQueue::getQueue Dec 02 16:35:14 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 16:35:21 volumio volumio[1221]: info: Retrieving Cloud Streaming UI Dec 02 16:35:21 volumio volumio[1221]: info: Getting Tidal Cloud Configuration Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 16:35:21 volumio volumio[1221]: info: Getting Qobuz Cloud Configuration Dec 02 16:35:21 volumio volumio[1221]: info: Asking plugin for UI Config Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 16:35:21 volumio volumio[1221]: info: Getting Spotify Cloud Configuration Dec 02 16:35:21 volumio volumio[1221]: info: Asking plugin for UI Config Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 16:35:21 volumio volumio[1221]: info: Saving Spotify Acccount Dec 02 16:35:21 volumio volumio[1221]: info: Got Tidal Cloud Configuration Dec 02 16:35:21 volumio volumio[1221]: info: Got it Dec 02 16:35:21 volumio volumio[1221]: info: Got it Dec 02 16:35:21 volumio volumio[1221]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 16:35:21 volumio volumio[1221]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 16:35:21 volumio volumio[1221]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 16:35:22 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:35:22 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 02 16:35:24 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 02 16:35:24 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand rescan Dec 02 16:35:24 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:35:24 volumio volumio[1221]: info: Dec 02 16:35:24 volumio volumio[1221]: ---------------------------- MPD announces state update: update Dec 02 16:35:24 volumio volumio[1221]: info: sendMpdCommand rescan took 2 milliseconds Dec 02 16:35:24 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:35:24 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:35:24 volumio volumio[1221]: info: sendMpdCommand status took 2 milliseconds Dec 02 16:35:24 volumio volumio[1221]: info: sendMpdCommand status took 1 milliseconds Dec 02 16:35:24 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:35:28 volumio volumio[1221]: info: Command Router : Notfying DB Updatetrue Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:35:28 volumio volumio[1221]: info: Dec 02 16:35:28 volumio volumio[1221]: ---------------------------- MPD announces state update: update Dec 02 16:35:28 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:35:28 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:35:28 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:35:28 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:35:28 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:28 volumio volumio[1221]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 02 16:35:28 volumio volumio[1221]: info: ------------------------------ 3847ms Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand update Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:35:28 volumio volumio[1221]: info: Dec 02 16:35:28 volumio volumio[1221]: ---------------------------- MPD announces state update: update Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand status took 27 milliseconds Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand status took 27 milliseconds Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand update took 6 milliseconds Dec 02 16:35:28 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:35:28 volumio volumio[1221]: info: Command Router : Notfying DB Updatefalse Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::Close All Modals sent Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:35:28 volumio volumio[1221]: info: Dec 02 16:35:28 volumio volumio[1221]: ---------------------------- MPD announces state update: update Dec 02 16:35:28 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand status took 48 milliseconds Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand status took 47 milliseconds Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:35:28 volumio volumio[1221]: info: Command Router : Notfying DB Updatefalse Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::Close All Modals sent Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:35:28 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:35:28 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:35:28 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:35:28 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:28 volumio volumio[1221]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 02 16:35:28 volumio volumio[1221]: info: ------------------------------ 108ms Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand status took 38 milliseconds Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand status took 38 milliseconds Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:35:28 volumio volumio[1221]: info: Command Router : Notfying DB Updatefalse Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::Close All Modals sent Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:35:28 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:35:28 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:35:28 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:35:28 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:28 volumio volumio[1221]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 02 16:35:28 volumio volumio[1221]: info: ------------------------------ 125ms Dec 02 16:35:28 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 02 16:35:28 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:35:28 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:35:28 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:35:28 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:28 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:35:28 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:35:28 volumio volumio[1221]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 02 16:35:28 volumio volumio[1221]: info: ------------------------------ 88ms Dec 02 16:35:30 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:35:34 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:35:35 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 02 16:35:35 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 02 16:35:38 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:35:42 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:35:46 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:35:50 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:35:54 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:35:58 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:35:59 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Dec 02 16:35:59 volumio volumio[1221]: info: Adding a new share Dec 02 16:35:59 volumio volumio[1221]: info: Share alias is blank Dec 02 16:36:02 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:36:06 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:36:10 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:36:14 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:36:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:36:22 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 16:36:25 volumio volumio[1221]: info: CoreCommandRouter::volumioPlay Dec 02 16:36:25 volumio volumio[1221]: info: CoreStateMachine::play index undefined Dec 02 16:36:25 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 16:36:25 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:25 volumio volumio[1221]: info: CoreStateMachine::startPlaybackTimer Dec 02 16:36:25 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:25 volumio volumio[1221]: info: [1733128585654] ControllerWebradio::clearAddPlayTrack Dec 02 16:36:25 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 16:36:25 volumio volumio[1221]: info: sendMpdCommand stop took 0 milliseconds Dec 02 16:36:25 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand clear Dec 02 16:36:25 volumio volumio[1221]: info: Dec 02 16:36:25 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Dec 02 16:36:25 volumio volumio[1221]: info: Ignoring MPD Status Update Dec 02 16:36:25 volumio volumio[1221]: info: sendMpdCommand clear took 0 milliseconds Dec 02 16:36:25 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1240065" Dec 02 16:36:25 volumio volumio[1221]: error: updateQueue error: null Dec 02 16:36:25 volumio volumio[1221]: info: ------------------------------ 1ms Dec 02 16:36:26 volumio volumio[1221]: info: Dec 02 16:36:26 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Dec 02 16:36:26 volumio volumio[1221]: info: Ignoring MPD Status Update Dec 02 16:36:26 volumio volumio[1221]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1240065" took 1079 milliseconds Dec 02 16:36:26 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 16:36:26 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand play Dec 02 16:36:26 volumio volumio[1221]: info: ------------------------------ 2ms Dec 02 16:36:26 volumio volumio[1221]: info: sendMpdCommand play took 1 milliseconds Dec 02 16:36:27 volumio volumio[1221]: info: Dec 02 16:36:27 volumio volumio[1221]: ---------------------------- MPD announces state update: player Dec 02 16:36:27 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:36:27 volumio volumio[1221]: info: Dec 02 16:36:27 volumio volumio[1221]: ---------------------------- MPD announces state update: player Dec 02 16:36:27 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:36:27 volumio volumio[1221]: info: sendMpdCommand status took 1 milliseconds Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:36:27 volumio volumio[1221]: info: sendMpdCommand status took 2 milliseconds Dec 02 16:36:27 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:36:27 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:36:27 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:27 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 187/9999) RSO 91,7 THESSALONIKI GREECE","album":null,"uri":"http://116.202.246.47:8594/stream","trackType":""} Dec 02 16:36:27 volumio volumio[1221]: verbose: CURRENT POSITION 0 Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Dec 02 16:36:27 volumio volumio[1221]: info: ------------------------------ 6ms Dec 02 16:36:27 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:36:27 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:36:27 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:27 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 187/9999) RSO 91,7 THESSALONIKI GREECE","album":null,"uri":"http://116.202.246.47:8594/stream","trackType":""} Dec 02 16:36:27 volumio volumio[1221]: verbose: CURRENT POSITION 0 Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Dec 02 16:36:27 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:36:27 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:36:27 volumio volumio[1221]: info: ------------------------------ 22ms Dec 02 16:36:27 volumio volumio[1221]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20187%2F9999)%20RSO%2091%2C7%20THESSALONIKI%20GREECE/04c7f962-1484-4f67-bcf8-c66e8119fe40.jpg' Dec 02 16:36:27 volumio volumio[1221]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20187%2F9999)%20RSO%2091%2C7%20THESSALONIKI%20GREECE/04c7f962-1484-4f67-bcf8-c66e8119fe40.jpg' Dec 02 16:36:27 volumio volumio[1221]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20187%2F9999)%20RSO%2091%2C7%20THESSALONIKI%20GREECE/04c7f962-1484-4f67-bcf8-c66e8119fe40.jpg' Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::volumioStop Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::stop Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::stPlaybackTimer Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::updateTrackBlock Dec 02 16:36:27 volumio volumio[1221]: info: CorePlayQueue::getTrackBlock Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:36:27 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::serviceStop Dec 02 16:36:27 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::serviceStop Dec 02 16:36:27 volumio volumio[1221]: info: [1733128587982] ControllerWebradio::stop Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 16:36:27 volumio volumio[1221]: info: Dec 02 16:36:27 volumio volumio[1221]: ---------------------------- MPD announces state update: player Dec 02 16:36:27 volumio volumio[1221]: info: sendMpdCommand stop took 6 milliseconds Dec 02 16:36:27 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:36:27 volumio volumio[1221]: info: sendMpdCommand status took 1 milliseconds Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:36:27 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 16:36:27 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:36:27 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:36:27 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:36:27 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:27 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:36:27 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:27 volumio volumio[1221]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 02 16:36:28 volumio volumio[1221]: info: ------------------------------ 12ms Dec 02 16:36:28 volumio volumio[1221]: info: CoreCommandRouter::volumioPlay Dec 02 16:36:28 volumio volumio[1221]: info: CoreStateMachine::play index undefined Dec 02 16:36:28 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 16:36:28 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:28 volumio volumio[1221]: info: CoreStateMachine::startPlaybackTimer Dec 02 16:36:28 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:28 volumio volumio[1221]: info: [1733128588742] ControllerWebradio::clearAddPlayTrack Dec 02 16:36:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 16:36:28 volumio volumio[1221]: info: sendMpdCommand stop took 1 milliseconds Dec 02 16:36:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand clear Dec 02 16:36:28 volumio volumio[1221]: info: Dec 02 16:36:28 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Dec 02 16:36:28 volumio volumio[1221]: info: Ignoring MPD Status Update Dec 02 16:36:28 volumio volumio[1221]: info: sendMpdCommand clear took 1 milliseconds Dec 02 16:36:28 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1240065" Dec 02 16:36:28 volumio volumio[1221]: error: updateQueue error: null Dec 02 16:36:28 volumio volumio[1221]: info: ------------------------------ 2ms Dec 02 16:36:29 volumio volumio[1221]: info: Dec 02 16:36:29 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Dec 02 16:36:29 volumio volumio[1221]: info: Ignoring MPD Status Update Dec 02 16:36:29 volumio volumio[1221]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1240065" took 287 milliseconds Dec 02 16:36:29 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 16:36:29 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand play Dec 02 16:36:29 volumio volumio[1221]: info: ------------------------------ 2ms Dec 02 16:36:29 volumio volumio[1221]: info: sendMpdCommand play took 1 milliseconds Dec 02 16:36:29 volumio volumio[1221]: info: Dec 02 16:36:29 volumio volumio[1221]: ---------------------------- MPD announces state update: player Dec 02 16:36:29 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:36:29 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:36:29 volumio volumio[1221]: info: Dec 02 16:36:29 volumio volumio[1221]: ---------------------------- MPD announces state update: player Dec 02 16:36:29 volumio volumio[1221]: info: sendMpdCommand status took 2 milliseconds Dec 02 16:36:29 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:36:29 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:36:29 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:36:29 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:36:29 volumio volumio[1221]: info: sendMpdCommand status took 1 milliseconds Dec 02 16:36:29 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 02 16:36:29 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:36:29 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:36:29 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:36:29 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:36:29 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:36:29 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:29 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 187/9999) RSO 91,7 THESSALONIKI GREECE","album":null,"uri":"http://116.202.246.47:8594/stream","trackType":""} Dec 02 16:36:29 volumio volumio[1221]: verbose: CURRENT POSITION 0 Dec 02 16:36:29 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Dec 02 16:36:29 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Dec 02 16:36:29 volumio volumio[1221]: info: ------------------------------ 6ms Dec 02 16:36:29 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 02 16:36:29 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:36:29 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:36:29 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:36:29 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:29 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 187/9999) RSO 91,7 THESSALONIKI GREECE","album":null,"uri":"http://116.202.246.47:8594/stream","trackType":""} Dec 02 16:36:29 volumio volumio[1221]: verbose: CURRENT POSITION 0 Dec 02 16:36:29 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Dec 02 16:36:29 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Dec 02 16:36:29 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Dec 02 16:36:29 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 16:36:29 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:36:29 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:29 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 16:36:29 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:36:29 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:36:29 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:36:29 volumio volumio[1221]: info: ------------------------------ 20ms Dec 02 16:36:31 volumio volumio[1221]: info: Dec 02 16:36:31 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Dec 02 16:36:31 volumio volumio[1221]: info: Ignoring MPD Status Update Dec 02 16:36:31 volumio volumio[1221]: info: Dec 02 16:36:31 volumio volumio[1221]: ---------------------------- MPD announces state update: player Dec 02 16:36:31 volumio volumio[1221]: info: ControllerMpd::getState Dec 02 16:36:31 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Dec 02 16:36:31 volumio volumio[1221]: info: ------------------------------ 1ms Dec 02 16:36:31 volumio volumio[1221]: info: sendMpdCommand status took 1 milliseconds Dec 02 16:36:31 volumio volumio[1221]: verbose: ControllerMpd::parseState Dec 02 16:36:31 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 16:36:31 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 02 16:36:31 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Dec 02 16:36:31 volumio volumio[1221]: info: ControllerMpd::pushState Dec 02 16:36:31 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Dec 02 16:36:31 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:31 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1240,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://116.202.246.47:8594/stream","trackType":""} Dec 02 16:36:31 volumio volumio[1221]: verbose: CURRENT POSITION 0 Dec 02 16:36:31 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Dec 02 16:36:31 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Dec 02 16:36:31 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Dec 02 16:36:31 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:36:31 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Dec 02 16:36:31 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 16:36:31 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:36:31 volumio volumio[1221]: info: CoreStateMachine::pushState Dec 02 16:36:31 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Dec 02 16:36:31 volumio volumio[1221]: info: ------------------------------ 17ms Dec 02 16:36:32 volumio volumio[1221]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 16:36:32 volumio volumio[1221]: Error: connect ETIMEDOUT 199.59.149.235:80 Dec 02 16:36:32 volumio volumio[1221]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 02 16:36:32 volumio volumio[1221]: errno: -110, Dec 02 16:36:32 volumio volumio[1221]: code: 'ETIMEDOUT', Dec 02 16:36:32 volumio volumio[1221]: syscall: 'connect', Dec 02 16:36:32 volumio volumio[1221]: address: '199.59.149.235', Dec 02 16:36:32 volumio volumio[1221]: port: 80 Dec 02 16:36:32 volumio volumio[1221]: } Dec 02 16:36:32 volumio volumio[1221]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 16:36:32 volumio sudo[3155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 16:35 Dec 02 16:36:32 volumio sudo[3155]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"