-- Logs begin at Mon 2024-05-27 12:25:26 UTC, end at Mon 2024-08-26 10:16:54 UTC. -- Aug 26 10:15:07 opera volumio[1176]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 10:15:07 opera volumio[1176]: info: CoreStateMachine::ClearQueue Aug 26 10:15:07 opera volumio[1176]: info: CoreStateMachine::stop Aug 26 10:15:07 opera volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:15:07 opera volumio[1176]: verbose: UNSET VOLATILE Aug 26 10:15:07 opera volumio[1176]: info: CorePlayQueue::clearPlayQueue Aug 26 10:15:07 opera volumio[1176]: info: CorePlayQueue::saveQueue Aug 26 10:15:07 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:07 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:07 opera volumio[1176]: info: CoreCommandRouter::volumioPushQueue Aug 26 10:15:07 opera volumio[1176]: info: CoreStateMachine::addQueueItems Aug 26 10:15:07 opera volumio[1176]: info: CorePlayQueue::addQueueItems Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track0 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track0 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track1 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track1 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track2 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track2 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track3 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track3 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track4 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track4 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track5 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track5 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track6 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track6 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track7 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track7 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track8 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track8 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track9 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track9 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track10 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track10 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track11 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track11 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track12 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track12 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track13 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track13 in service cd_controller Aug 26 10:15:07 opera volumio[1176]: info: Adding Item to queue: cdaudio/track14 Aug 26 10:15:07 opera volumio[1176]: info: Exploding uri cdaudio/track14 in service cd_controller Aug 26 10:15:14 opera volumio[1176]: info: CorePlayQueue::saveQueue Aug 26 10:15:14 opera volumio[1176]: info: CoreCommandRouter::volumioPushQueue Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::updateTrackBlock Aug 26 10:15:14 opera volumio[1176]: info: CorePlayQueue::getTrackBlock Aug 26 10:15:14 opera volumio[1176]: info: CoreCommandRouter::volumioPlay Aug 26 10:15:14 opera volumio[1176]: verbose: UNSET VOLATILE Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::play index 0 Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::stop Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:15:14 opera volumio[1176]: verbose: UNSET VOLATILE Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::play index undefined Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:15:14 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::startPlaybackTimer Aug 26 10:15:14 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::clearAddPlayTracks cdda:///1 Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 10:15:14 opera volumio[1176]: info: Aug 26 10:15:14 opera volumio[1176]: ---------------------------- MPD announces state update: player Aug 26 10:15:14 opera volumio[1176]: info: sendMpdCommand stop took 41 milliseconds Aug 26 10:15:14 opera volumio[1176]: info: ControllerMpd::getState Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 10:15:14 opera volumio[1176]: info: Aug 26 10:15:14 opera volumio[1176]: ---------------------------- MPD announces system playlist update Aug 26 10:15:14 opera volumio[1176]: info: Ignoring MPD Status Update Aug 26 10:15:14 opera volumio[1176]: info: sendMpdCommand status took 2 milliseconds Aug 26 10:15:14 opera volumio[1176]: info: sendMpdCommand clear took 2 milliseconds Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::parseState Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand add "cdda:///1" Aug 26 10:15:14 opera volumio[1176]: error: updateQueue error: null Aug 26 10:15:14 opera volumio[1176]: info: Aug 26 10:15:14 opera volumio[1176]: ---------------------------- MPD announces system playlist update Aug 26 10:15:14 opera volumio[1176]: info: Ignoring MPD Status Update Aug 26 10:15:14 opera volumio[1176]: info: ------------------------------ 4ms Aug 26 10:15:14 opera volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 10:15:14 opera volumio[1176]: info: sendMpdCommand add "cdda:///1" took 3 milliseconds Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand play Aug 26 10:15:14 opera volumio[1176]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 26 10:15:14 opera volumio[1176]: info: ------------------------------ 9ms Aug 26 10:15:14 opera volumio[1176]: info: ------------------------------ 3ms Aug 26 10:15:14 opera volumio[1176]: info: sendMpdCommand play took 2 milliseconds Aug 26 10:15:14 opera volumio[1176]: info: CoreCommandRouter::volumioPlay Aug 26 10:15:14 opera volumio[1176]: verbose: UNSET VOLATILE Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::play index undefined Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:15:14 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:14 opera volumio[1176]: info: CoreStateMachine::startPlaybackTimer Aug 26 10:15:14 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::clearAddPlayTracks cdda:///1 Aug 26 10:15:14 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 10:15:16 opera volumio[1176]: info: sendMpdCommand stop took 1998 milliseconds Aug 26 10:15:16 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 10:15:16 opera volumio[1176]: info: Aug 26 10:15:16 opera volumio[1176]: ---------------------------- MPD announces state update: player Aug 26 10:15:16 opera volumio[1176]: info: Aug 26 10:15:16 opera volumio[1176]: ---------------------------- MPD announces system playlist update Aug 26 10:15:16 opera volumio[1176]: info: Ignoring MPD Status Update Aug 26 10:15:16 opera volumio[1176]: info: ControllerMpd::getState Aug 26 10:15:16 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:15:16 opera volumio[1176]: info: sendMpdCommand clear took 2 milliseconds Aug 26 10:15:16 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand add "cdda:///1" Aug 26 10:15:16 opera volumio[1176]: error: updateQueue error: null Aug 26 10:15:16 opera volumio[1176]: info: Aug 26 10:15:16 opera volumio[1176]: ---------------------------- MPD announces system playlist update Aug 26 10:15:16 opera volumio[1176]: info: Ignoring MPD Status Update Aug 26 10:15:16 opera volumio[1176]: info: sendMpdCommand status took 6 milliseconds Aug 26 10:15:16 opera volumio[1176]: info: ------------------------------ 7ms Aug 26 10:15:16 opera volumio[1176]: info: sendMpdCommand add "cdda:///1" took 6 milliseconds Aug 26 10:15:16 opera volumio[1176]: verbose: ControllerMpd::parseState Aug 26 10:15:16 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand play Aug 26 10:15:16 opera volumio[1176]: info: ControllerMpd::pushState Aug 26 10:15:16 opera volumio[1176]: info: CoreCommandRouter::servicePushState Aug 26 10:15:16 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:15:16 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:16 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:16 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:16 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:16 opera volumio[1176]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 10:15:16 opera volumio[1176]: verbose: CURRENT POSITION 0 Aug 26 10:15:16 opera volumio[1176]: info: CoreStateMachine::syncState stateService stop Aug 26 10:15:16 opera volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:15:16 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:15:16 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:16 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:16 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:16 opera volumio[1176]: info: No code Aug 26 10:15:16 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:15:16 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:16 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:16 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:16 opera volumio[1176]: info: ------------------------------ 32ms Aug 26 10:15:16 opera volumio[1176]: info: ------------------------------ 26ms Aug 26 10:15:16 opera volumio[1176]: info: sendMpdCommand play took 25 milliseconds Aug 26 10:15:16 opera volumio[1176]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E5%9C%8B%E7%A5%A5/%E5%BE%AA%E7%92%B0%E5%86%8D%E5%94%B1%20(K2HD)/ab8a9cfc-8a3d-415f-b499-26fb8412759e.jpg' Aug 26 10:15:21 opera volumio[1176]: info: Aug 26 10:15:21 opera volumio[1176]: ---------------------------- MPD announces state update: player Aug 26 10:15:21 opera volumio[1176]: info: ControllerMpd::getState Aug 26 10:15:21 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:15:21 opera volumio[1176]: info: Aug 26 10:15:21 opera volumio[1176]: ---------------------------- MPD announces state update: player Aug 26 10:15:21 opera volumio[1176]: info: sendMpdCommand status took 125 milliseconds Aug 26 10:15:21 opera volumio[1176]: info: ControllerMpd::getState Aug 26 10:15:21 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:15:21 opera volumio[1176]: verbose: ControllerMpd::parseState Aug 26 10:15:21 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:15:21 opera volumio[1176]: info: sendMpdCommand status took 2 milliseconds Aug 26 10:15:21 opera volumio[1176]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 10:15:21 opera volumio[1176]: verbose: ControllerMpd::parseState Aug 26 10:15:21 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:15:21 opera volumio[1176]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:15:21 opera volumio[1176]: info: ControllerMpd::pushState Aug 26 10:15:21 opera volumio[1176]: info: CoreCommandRouter::servicePushState Aug 26 10:15:21 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:21 opera volumio[1176]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} Aug 26 10:15:21 opera volumio[1176]: verbose: CURRENT POSITION 0 Aug 26 10:15:21 opera volumio[1176]: info: CoreStateMachine::syncState stateService play Aug 26 10:15:21 opera volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:15:21 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:15:21 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:21 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:21 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:21 opera volumio[1176]: info: ------------------------------ 135ms Aug 26 10:15:21 opera volumio[1176]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 10:15:21 opera volumio[1176]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:15:21 opera volumio[1176]: info: ControllerMpd::pushState Aug 26 10:15:21 opera volumio[1176]: info: CoreCommandRouter::servicePushState Aug 26 10:15:21 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:21 opera volumio[1176]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} Aug 26 10:15:21 opera volumio[1176]: verbose: CURRENT POSITION 0 Aug 26 10:15:21 opera volumio[1176]: info: CoreStateMachine::syncState stateService play Aug 26 10:15:21 opera volumio[1176]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:15:21 opera volumio[1176]: info: Received an update from plugin. extracting info from payload Aug 26 10:15:21 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:15:21 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:21 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:21 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:21 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:15:21 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:21 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:21 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:21 opera volumio[1176]: info: ------------------------------ 24ms Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 10:15:30 opera volumio[1176]: No protocol specified Aug 26 10:15:30 opera volumio[1176]: xcb_connection_has_error() returned true Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 10:15:30 opera volumio[1176]: No protocol specified Aug 26 10:15:30 opera volumio[1176]: xcb_connection_has_error() returned true Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 26 10:15:30 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 10:15:42 opera volumio[1176]: info: Executing endpoint metavolumio Aug 26 10:15:42 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 10:15:42 opera volumio[1176]: info: Executing endpoint metavolumio Aug 26 10:15:42 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 10:15:54 opera volumio[1176]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 10:15:54 opera volumio[1176]: info: CoreStateMachine::ClearQueue Aug 26 10:15:54 opera volumio[1176]: info: CoreStateMachine::stop Aug 26 10:15:54 opera volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:15:54 opera volumio[1176]: verbose: UNSET VOLATILE Aug 26 10:15:54 opera volumio[1176]: info: CoreStateMachine::stPlaybackTimer Aug 26 10:15:54 opera volumio[1176]: info: CoreStateMachine::updateTrackBlock Aug 26 10:15:54 opera volumio[1176]: info: CorePlayQueue::getTrackBlock Aug 26 10:15:54 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:15:54 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:54 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:54 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:54 opera volumio[1176]: info: CoreStateMachine::serviceStop Aug 26 10:15:54 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:54 opera volumio[1176]: info: CoreCommandRouter::serviceStop Aug 26 10:15:54 opera volumio[1176]: info: ControllerMpd::stop Aug 26 10:15:54 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 10:15:54 opera volumio[1176]: info: CorePlayQueue::clearPlayQueue Aug 26 10:15:54 opera volumio[1176]: info: CorePlayQueue::saveQueue Aug 26 10:15:54 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:54 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:54 opera volumio[1176]: info: CoreCommandRouter::volumioPushQueue Aug 26 10:15:54 opera volumio[1176]: info: CoreStateMachine::addQueueItems Aug 26 10:15:54 opera volumio[1176]: info: CorePlayQueue::addQueueItems Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track0 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track0 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track1 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track1 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track2 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track2 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track3 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track3 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track4 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track4 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track5 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track5 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track6 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track6 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track7 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track7 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track8 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track8 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track9 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track9 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track10 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track10 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track11 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track11 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track12 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track12 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track13 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track13 in service cd_controller Aug 26 10:15:54 opera volumio[1176]: info: Adding Item to queue: cdaudio/track14 Aug 26 10:15:54 opera volumio[1176]: info: Exploding uri cdaudio/track14 in service cd_controller Aug 26 10:15:56 opera volumio[1176]: info: Aug 26 10:15:56 opera volumio[1176]: ---------------------------- MPD announces state update: player Aug 26 10:15:56 opera volumio[1176]: info: sendMpdCommand stop took 2105 milliseconds Aug 26 10:15:56 opera volumio[1176]: info: ControllerMpd::getState Aug 26 10:15:56 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:15:56 opera volumio[1176]: info: sendMpdCommand status took 1 milliseconds Aug 26 10:15:56 opera volumio[1176]: verbose: ControllerMpd::parseState Aug 26 10:15:56 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:15:56 opera volumio[1176]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 10:15:56 opera volumio[1176]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:15:56 opera volumio[1176]: info: ControllerMpd::pushState Aug 26 10:15:56 opera volumio[1176]: info: CoreCommandRouter::servicePushState Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:15:56 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:56 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:56 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:56 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:56 opera volumio[1176]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} Aug 26 10:15:56 opera volumio[1176]: verbose: CURRENT POSITION 0 Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::syncState stateService stop Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:15:56 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:56 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:56 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:56 opera volumio[1176]: info: No code Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:15:56 opera volumio[1176]: info: CorePlayQueue::getTrack 0 Aug 26 10:15:56 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:15:56 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:15:56 opera volumio[1176]: info: ------------------------------ 39ms Aug 26 10:15:56 opera volumio[1176]: info: CorePlayQueue::saveQueue Aug 26 10:15:56 opera volumio[1176]: info: CoreCommandRouter::volumioPushQueue Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::updateTrackBlock Aug 26 10:15:56 opera volumio[1176]: info: CorePlayQueue::getTrackBlock Aug 26 10:15:56 opera volumio[1176]: info: CoreCommandRouter::volumioPlay Aug 26 10:15:56 opera volumio[1176]: verbose: UNSET VOLATILE Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::play index 12 Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::stop Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:15:56 opera volumio[1176]: verbose: UNSET VOLATILE Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::play index undefined Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:15:56 opera volumio[1176]: info: CorePlayQueue::getTrack 12 Aug 26 10:15:56 opera volumio[1176]: info: CoreStateMachine::startPlaybackTimer Aug 26 10:15:56 opera volumio[1176]: info: CorePlayQueue::getTrack 12 Aug 26 10:15:56 opera volumio[1176]: verbose: ControllerMpd::clearAddPlayTracks cdda:///13 Aug 26 10:15:56 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 10:15:56 opera volumio[1176]: info: sendMpdCommand stop took 2 milliseconds Aug 26 10:15:56 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 10:15:56 opera volumio[1176]: info: Aug 26 10:15:56 opera volumio[1176]: ---------------------------- MPD announces system playlist update Aug 26 10:15:56 opera volumio[1176]: info: Ignoring MPD Status Update Aug 26 10:15:56 opera volumio[1176]: info: sendMpdCommand clear took 7 milliseconds Aug 26 10:15:56 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand add "cdda:///13" Aug 26 10:15:56 opera volumio[1176]: error: updateQueue error: null Aug 26 10:15:56 opera volumio[1176]: info: Aug 26 10:15:56 opera volumio[1176]: ---------------------------- MPD announces system playlist update Aug 26 10:15:56 opera volumio[1176]: info: Ignoring MPD Status Update Aug 26 10:15:56 opera volumio[1176]: info: ------------------------------ 6ms Aug 26 10:15:56 opera volumio[1176]: info: sendMpdCommand add "cdda:///13" took 2 milliseconds Aug 26 10:15:56 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand play Aug 26 10:15:56 opera volumio[1176]: info: ------------------------------ 3ms Aug 26 10:15:56 opera volumio[1176]: info: sendMpdCommand play took 3 milliseconds Aug 26 10:16:02 opera volumio[1176]: info: Aug 26 10:16:02 opera volumio[1176]: ---------------------------- MPD announces state update: player Aug 26 10:16:02 opera volumio[1176]: info: ControllerMpd::getState Aug 26 10:16:02 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:16:02 opera volumio[1176]: info: Aug 26 10:16:02 opera volumio[1176]: ---------------------------- MPD announces state update: player Aug 26 10:16:02 opera volumio[1176]: info: sendMpdCommand status took 2 milliseconds Aug 26 10:16:02 opera volumio[1176]: info: ControllerMpd::getState Aug 26 10:16:02 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:16:02 opera volumio[1176]: verbose: ControllerMpd::parseState Aug 26 10:16:02 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:16:02 opera volumio[1176]: info: sendMpdCommand status took 1 milliseconds Aug 26 10:16:02 opera volumio[1176]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 10:16:02 opera volumio[1176]: verbose: ControllerMpd::parseState Aug 26 10:16:02 opera volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:16:02 opera volumio[1176]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:16:02 opera volumio[1176]: info: ControllerMpd::pushState Aug 26 10:16:02 opera volumio[1176]: info: CoreCommandRouter::servicePushState Aug 26 10:16:02 opera volumio[1176]: info: CorePlayQueue::getTrack 12 Aug 26 10:16:02 opera volumio[1176]: verbose: STATE SERVICE {"status":"play","position":0,"seek":232,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13","artist":null,"album":null,"uri":"cdda:///13","trackType":"CD Audio"} Aug 26 10:16:02 opera volumio[1176]: verbose: CURRENT POSITION 12 Aug 26 10:16:02 opera volumio[1176]: info: CoreStateMachine::syncState stateService play Aug 26 10:16:02 opera volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:16:02 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:16:02 opera volumio[1176]: info: CorePlayQueue::getTrack 12 Aug 26 10:16:02 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:16:02 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:16:02 opera volumio[1176]: info: ------------------------------ 12ms Aug 26 10:16:02 opera volumio[1176]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 26 10:16:02 opera volumio[1176]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:16:02 opera volumio[1176]: info: ControllerMpd::pushState Aug 26 10:16:02 opera volumio[1176]: info: CoreCommandRouter::servicePushState Aug 26 10:16:02 opera volumio[1176]: info: CorePlayQueue::getTrack 12 Aug 26 10:16:02 opera volumio[1176]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13","artist":null,"album":null,"uri":"cdda:///13","trackType":"CD Audio"} Aug 26 10:16:02 opera volumio[1176]: verbose: CURRENT POSITION 12 Aug 26 10:16:02 opera volumio[1176]: info: CoreStateMachine::syncState stateService play Aug 26 10:16:02 opera volumio[1176]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:16:02 opera volumio[1176]: info: Received an update from plugin. extracting info from payload Aug 26 10:16:02 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:16:02 opera volumio[1176]: info: CorePlayQueue::getTrack 12 Aug 26 10:16:02 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:16:02 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:16:02 opera volumio[1176]: info: CoreStateMachine::pushState Aug 26 10:16:02 opera volumio[1176]: info: CorePlayQueue::getTrack 12 Aug 26 10:16:02 opera volumio[1176]: info: CoreCommandRouter::volumioPushState Aug 26 10:16:02 opera volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:16:02 opera volumio[1176]: info: ------------------------------ 24ms Aug 26 10:16:46 opera volumio[1176]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E5%9C%8B%E7%A5%A5/%E5%BE%AA%E7%92%B0%E5%86%8D%E5%94%B1%20(K2HD)/ab8a9cfc-8a3d-415f-b499-26fb8412759e.jpg' Aug 26 10:16:54 opera volumio[1176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 10:16:54 opera volumio[1176]: { Error: connect ETIMEDOUT 104.244.43.231:443 Aug 26 10:16:54 opera volumio[1176]: at Object._errnoException (util.js:1022:11) Aug 26 10:16:54 opera volumio[1176]: at _exceptionWithHostPort (util.js:1044:20) Aug 26 10:16:54 opera volumio[1176]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 26 10:16:54 opera volumio[1176]: code: 'ETIMEDOUT', Aug 26 10:16:54 opera volumio[1176]: errno: 'ETIMEDOUT', Aug 26 10:16:54 opera volumio[1176]: syscall: 'connect', Aug 26 10:16:54 opera volumio[1176]: address: '104.244.43.231', Aug 26 10:16:54 opera volumio[1176]: port: 443 } Aug 26 10:16:54 opera volumio[1176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 10:16:54 opera sudo[12130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-26 10:15 Aug 26 10:16:54 opera sudo[12130]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"