-- Logs begin at Thu 2024-05-09 18:18:45 CST, end at Thu 2024-05-09 18:25:47 CST. -- May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:06 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:06 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:06 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:06 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:06 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:06 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:06 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:07 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:07 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:07 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:07 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:07 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:07 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:07 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:10 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:10 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:10 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:10 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:10 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:10 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:10 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:10 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:10 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:10 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:15 volumio volumio[1861]: verbose: New Socket.io Connection to 192.168.5.11 from 192.168.5.9 UA: Mozilla/5.0 (iPad; CPU OS 16_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.4 Mobile/15E148 Safari/604.1 Total Clients: 5 May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::volumioGetVisibleSources May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::volumioGetState May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 18:24:16 volumio volumio[1861]: info: Received Get System Info May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:24:16 volumio volumio[1861]: info: Discovery: Getting this device information May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::volumioGetState May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::volumioGetState May 09 18:24:16 volumio volumio[1861]: info: Listing playlists May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::volumioGetQueue May 09 18:24:16 volumio volumio[1861]: info: CoreStateMachine::getQueue May 09 18:24:16 volumio volumio[1861]: info: CorePlayQueue::getQueue May 09 18:24:16 volumio volumio[1861]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Diana%20Krall/Turn%20Up%20the%20Quiet/f3b09977-55c7-4902-b08f-dab59e376535.jpg' May 09 18:24:16 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:18 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:18 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:18 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:18 volumio shairport-sync[1984]: {"time":1715250207799,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 09 18:24:18 volumio shairport-sync[1984]: Dload Upload Total Spent Left Speed May 09 18:24:18 volumio volumio[1861]: info: May 09 18:24:18 volumio volumio[1861]: ---------------------------- Client requests Stop Airplay Playback May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback May 09 18:24:18 volumio volumio[1861]: info: Airplay playback stop May 09 18:24:18 volumio volumio[1861]: info: Airplay Playback Stopped, clearing state May 09 18:24:18 volumio volumio[1861]: info: CoreStateMachine::resetVolumioState May 09 18:24:18 volumio volumio[1861]: info: CoreStateMachine::getcurrentVolume May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::volumioRetrievevolume May 09 18:24:18 volumio shairport-sync[1984]: [158B blob data] May 09 18:24:18 volumio volumio[1861]: info: VolumeController:: Volume=45 Mute =false May 09 18:24:18 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:18 volumio volumio[1861]: info: CoreStateMachine::updateTrackBlock May 09 18:24:18 volumio volumio[1861]: info: CorePlayQueue::getTrackBlock May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::volumioRetrievevolume May 09 18:24:18 volumio volumio[1861]: info: VolumeController:: Volume=45 Mute =false May 09 18:24:18 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:18 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:21 volumio nmbd[745]: [2024/05/09 18:24:21.456191, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 09 18:24:21 volumio nmbd[745]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.11 for name WORKGROUP<1d>. May 09 18:24:21 volumio nmbd[745]: This response was from IP 192.168.5.105, reporting an IP address of 192.168.5.105. May 09 18:24:21 volumio volumio[1861]: info: Preload queue cleared May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioReplaceandPlayItems May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::ClearQueue May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::stop May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::serviceStop May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::serviceStop May 09 18:24:21 volumio volumio[1861]: info: Airplay Stop May 09 18:24:21 volumio volumio[1861]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::clearPlayQueue May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::saveQueue May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPushQueue May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::addQueueItems May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::addQueueItems May 09 18:24:21 volumio volumio[1861]: info: Preload queue cleared May 09 18:24:21 volumio volumio[1861]: info: Adding Item to queue: albums://Diana%20Krall/Turn%20Up%20The%20Quiet May 09 18:24:21 volumio volumio[1861]: info: Exploding uri albums://Diana%20Krall/Turn%20Up%20The%20Quiet in service mpd May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPushQueue May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::saveQueue May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::updateTrackBlock May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrackBlock May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPlay May 09 18:24:21 volumio volumio[1861]: verbose: UNSET VOLATILE: Service: airplay_emulation May 09 18:24:21 volumio volumio[1861]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 09 18:24:21 volumio sudo[2187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1984 May 09 18:24:21 volumio sudo[2187]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:24:21 volumio sudo[2187]: pam_unix(sudo:session): session closed for user root May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::play index 0 May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::stop May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::play index undefined May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::startPlaybackTimer May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::clearAddPlayTracks NAS/dietpi/Diana Krall - Turn Up the Quiet (2017) Hi-Res/01. Diana Krall - Like Someone In Love.flac May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand stop May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand stop took 1 milliseconds May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand clear May 09 18:24:21 volumio volumio[1861]: info: Shairport-Sync paused with USR2 May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces system playlist update May 09 18:24:21 volumio volumio[1861]: info: Ignoring MPD Status Update May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand clear took 6 milliseconds May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand add "NAS/dietpi/Diana Krall - Turn Up the Quiet (2017) Hi-Res/01. Diana Krall - Like Someone In Love.flac" May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces system playlist update May 09 18:24:21 volumio volumio[1861]: info: Ignoring MPD Status Update May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces system playlist update May 09 18:24:21 volumio volumio[1861]: info: Ignoring MPD Status Update May 09 18:24:21 volumio volumio[1861]: error: updateQueue error: null May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces system playlist update May 09 18:24:21 volumio volumio[1861]: info: Ignoring MPD Status Update May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 18ms May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand add "NAS/dietpi/Diana Krall - Turn Up the Quiet (2017) Hi-Res/01. Diana Krall - Like Someone In Love.flac" took 15 milliseconds May 09 18:24:21 volumio sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1984 May 09 18:24:21 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 13ms May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 12ms May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand play May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces system playlist update May 09 18:24:21 volumio volumio[1861]: info: Ignoring MPD Status Update May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces system playlist update May 09 18:24:21 volumio volumio[1861]: info: Ignoring MPD Status Update May 09 18:24:21 volumio sudo[2191]: pam_unix(sudo:session): session closed for user root May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 18ms May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand play took 9 milliseconds May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 8ms May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 7ms May 09 18:24:21 volumio volumio[1861]: info: Shairport-Sync paused with USR2 May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces state update: player May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::getState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand status May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces state update: player May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::getState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand status May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces state update: player May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::getState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand status May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces state update: player May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::getState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand status May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand status took 8 milliseconds May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand status took 3 milliseconds May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::parseState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::parseState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces state update: player May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::getState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand status May 09 18:24:21 volumio volumio[1861]: info: May 09 18:24:21 volumio volumio[1861]: ---------------------------- MPD announces state update: player May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::getState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand status May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand status took 6 milliseconds May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand status took 5 milliseconds May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand playlistinfo took 3 milliseconds May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand status took 3 milliseconds May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::parseState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::parseState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::parseTrackInfo May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::parseTrackInfo May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::parseState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::pushState May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Like Someone In Love","artist":"Diana Krall","album":"Turn Up The Quiet","uri":"NAS/dietpi/Diana Krall - Turn Up the Quiet (2017) Hi-Res/01. Diana Krall - Like Someone In Love.flac","trackType":"flac"} May 09 18:24:21 volumio volumio[1861]: verbose: CURRENT POSITION 0 May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::syncState stateService play May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::syncState currentStatus stop May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::pushState May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Like Someone In Love","artist":"Diana Krall","album":"Turn Up The Quiet","uri":"NAS/dietpi/Diana Krall - Turn Up the Quiet (2017) Hi-Res/01. Diana Krall - Like Someone In Love.flac","trackType":"flac"} May 09 18:24:21 volumio volumio[1861]: verbose: CURRENT POSITION 0 May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::syncState stateService play May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::syncState currentStatus play May 09 18:24:21 volumio volumio[1861]: info: Received an update from plugin. extracting info from payload May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 33ms May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 42ms May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand status took 33 milliseconds May 09 18:24:21 volumio volumio[1861]: info: sendMpdCommand playlistinfo took 31 milliseconds May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::parseState May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:24:21 volumio volumio[1861]: verbose: ControllerMpd::parseTrackInfo May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::pushState May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Like Someone In Love","artist":"Diana Krall","album":"Turn Up The Quiet","uri":"NAS/dietpi/Diana Krall - Turn Up the Quiet (2017) Hi-Res/01. Diana Krall - Like Someone In Love.flac","trackType":"flac"} May 09 18:24:21 volumio volumio[1861]: verbose: CURRENT POSITION 0 May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::syncState stateService play May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::syncState currentStatus play May 09 18:24:21 volumio volumio[1861]: info: Received an update from plugin. extracting info from payload May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::pushState May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Like Someone In Love","artist":"Diana Krall","album":"Turn Up The Quiet","uri":"NAS/dietpi/Diana Krall - Turn Up the Quiet (2017) Hi-Res/01. Diana Krall - Like Someone In Love.flac","trackType":"flac"} May 09 18:24:21 volumio volumio[1861]: verbose: CURRENT POSITION 0 May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::syncState stateService play May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::syncState currentStatus play May 09 18:24:21 volumio volumio[1861]: info: Received an update from plugin. extracting info from payload May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:21 volumio volumio[1861]: info: ControllerMpd::pushState May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Like Someone In Love","artist":"Diana Krall","album":"Turn Up The Quiet","uri":"NAS/dietpi/Diana Krall - Turn Up the Quiet (2017) Hi-Res/01. Diana Krall - Like Someone In Love.flac","trackType":"flac"} May 09 18:24:21 volumio volumio[1861]: verbose: CURRENT POSITION 0 May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::syncState stateService play May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::syncState currentStatus play May 09 18:24:21 volumio volumio[1861]: info: Received an update from plugin. extracting info from payload May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:21 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:21 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:21 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 117ms May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 116ms May 09 18:24:21 volumio volumio[1861]: info: ------------------------------ 116ms May 09 18:24:22 volumio volumio[1861]: info: sendMpdCommand playlistinfo took 107 milliseconds May 09 18:24:22 volumio volumio[1861]: verbose: ControllerMpd::parseTrackInfo May 09 18:24:22 volumio volumio[1861]: info: ControllerMpd::pushState May 09 18:24:22 volumio volumio[1861]: info: CoreCommandRouter::servicePushState May 09 18:24:22 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:22 volumio volumio[1861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Like Someone In Love","artist":"Diana Krall","album":"Turn Up The Quiet","uri":"NAS/dietpi/Diana Krall - Turn Up the Quiet (2017) Hi-Res/01. Diana Krall - Like Someone In Love.flac","trackType":"flac"} May 09 18:24:22 volumio volumio[1861]: verbose: CURRENT POSITION 0 May 09 18:24:22 volumio volumio[1861]: info: CoreStateMachine::syncState stateService play May 09 18:24:22 volumio volumio[1861]: info: CoreStateMachine::syncState currentStatus play May 09 18:24:22 volumio volumio[1861]: info: Received an update from plugin. extracting info from payload May 09 18:24:22 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:22 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:22 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:22 volumio volumio[1861]: info: CoreStateMachine::pushState May 09 18:24:22 volumio volumio[1861]: info: CorePlayQueue::getTrack 0 May 09 18:24:22 volumio volumio[1861]: info: CoreCommandRouter::volumioPushState May 09 18:24:22 volumio volumio[1861]: info: ------------------------------ 198ms May 09 18:24:22 volumio volumio[1861]: info: Restarting Shairport-Sync after stop May 09 18:24:22 volumio sudo[2194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 18:24:22 volumio sudo[2194]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:24:22 volumio volumio[1861]: info: Restarting Shairport-Sync after stop May 09 18:24:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 18:24:22 volumio shairport-sync[1984]: {"time":1715250258425,"response":"stopAirplayPlayback Success"} May 09 18:24:22 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 18:24:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 18:24:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 18:24:22 volumio sudo[2197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 18:24:22 volumio sudo[2197]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:24:22 volumio sudo[2194]: pam_unix(sudo:session): session closed for user root May 09 18:24:22 volumio volumio[1861]: info: Shairport-Sync retarted May 09 18:24:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 18:24:22 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 09 18:24:22 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 18:24:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 18:24:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 18:24:22 volumio sudo[2197]: pam_unix(sudo:session): session closed for user root May 09 18:24:22 volumio volumio[1861]: info: Shairport-Sync retarted May 09 18:24:49 volumio ntpd[964]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 09 18:25:47 volumio volumio[1861]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:25:47 volumio volumio[1861]: Error: connect ETIMEDOUT 157.240.1.9:443 May 09 18:25:47 volumio volumio[1861]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 09 18:25:47 volumio volumio[1861]: errno: -110, May 09 18:25:47 volumio volumio[1861]: code: 'ETIMEDOUT', May 09 18:25:47 volumio volumio[1861]: syscall: 'connect', May 09 18:25:47 volumio volumio[1861]: address: '157.240.1.9', May 09 18:25:47 volumio volumio[1861]: port: 443 May 09 18:25:47 volumio volumio[1861]: } May 09 18:25:47 volumio volumio[1861]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:25:47 volumio sudo[2262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 18:24 May 09 18:25:47 volumio sudo[2262]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"