-- Logs begin at Tue 2024-11-05 17:45:55 CST, end at Thu 2024-11-21 13:02:51 CST. -- Nov 21 13:01:14 volumio volumio[6909]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%2014%2F300)%20Radio%20MCA%20-%20La%20Classica%20dei%20giovani/44b8fb3e-296f-4150-ba57-2ef7d7dec33c.jpg' Nov 21 13:01:15 volumio volumio[6909]: verbose: New Socket.io Connection to 192.168.50.173 from 192.168.50.81 UA: Mozilla/5.0 (Linux; Android 10; VOG-AL00 Build/HUAWEIVOG-AL00; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/83.0.4103.106 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 21 13:01:15 volumio volumio[6909]: verbose: New Socket.io Connection to 192.168.50.173 from 192.168.50.81 UA: Mozilla/5.0 (Linux; Android 10; VOG-AL00 Build/HUAWEIVOG-AL00; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/83.0.4103.106 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::volumioGetVisibleSources Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::volumioGetState Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::volumioGetQueue Nov 21 13:01:15 volumio volumio[6909]: info: CoreStateMachine::getQueue Nov 21 13:01:15 volumio volumio[6909]: info: CorePlayQueue::getQueue Nov 21 13:01:15 volumio volumio[6909]: info: Listing playlists Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 21 13:01:15 volumio volumio[6909]: info: Received Get System Info Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 13:01:15 volumio volumio[6909]: info: Discovery: Getting this device information Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::volumioGetState Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 13:01:15 volumio volumio[6909]: info: CoreCommandRouter::volumioGetState Nov 21 13:01:18 volumio volumio[6909]: info: CoreCommandRouter::volumioGetState Nov 21 13:01:57 volumio volumio[6909]: verbose: New Socket.io Connection to 192.168.50.173 from 192.168.50.81 UA: Mozilla/5.0 (Linux; Android 10; VOG-AL00 Build/HUAWEIVOG-AL00; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/83.0.4103.106 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::volumioGetVisibleSources Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::volumioGetState Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::volumioGetQueue Nov 21 13:01:57 volumio volumio[6909]: info: CoreStateMachine::getQueue Nov 21 13:01:57 volumio volumio[6909]: info: CorePlayQueue::getQueue Nov 21 13:01:57 volumio volumio[6909]: info: Listing playlists Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 21 13:01:57 volumio volumio[6909]: info: Received Get System Info Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 13:01:57 volumio volumio[6909]: info: Discovery: Getting this device information Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::volumioGetState Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 13:01:57 volumio volumio[6909]: info: CoreCommandRouter::volumioGetState Nov 21 13:02:01 volumio volumio[6909]: info: CoreCommandRouter::volumioGetState Nov 21 13:02:05 volumio volumio[6909]: info: Preload queue cleared Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::ClearQueue Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::stop Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::stPlaybackTimer Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::updateTrackBlock Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::getTrackBlock Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::serviceStop Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::serviceStop Nov 21 13:02:05 volumio volumio[6909]: info: [1732165325490] ControllerWebradio::stop Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::clearPlayQueue Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::saveQueue Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::volumioPushQueue Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::addQueueItems Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::addQueueItems Nov 21 13:02:05 volumio volumio[6909]: info: Preload queue cleared Nov 21 13:02:05 volumio volumio[6909]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99510328 Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::volumioPushQueue Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::saveQueue Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::updateTrackBlock Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::getTrackBlock Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::volumioPlay Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::play index 0 Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::stop Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::play index undefined Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::startPlaybackTimer Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:05 volumio volumio[6909]: info: [1732165325503] ControllerWebradio::clearAddPlayTrack Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 13:02:05 volumio volumio[6909]: info: Nov 21 13:02:05 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:05 volumio volumio[6909]: info: sendMpdCommand stop took 15 milliseconds Nov 21 13:02:05 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:05 volumio volumio[6909]: info: sendMpdCommand stop took 3 milliseconds Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 13:02:05 volumio volumio[6909]: info: Nov 21 13:02:05 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:05 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:05 volumio volumio[6909]: info: Nov 21 13:02:05 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:05 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:05 volumio volumio[6909]: info: Nov 21 13:02:05 volumio volumio[6909]: ---------------------------- MPD announces system playlist update Nov 21 13:02:05 volumio volumio[6909]: info: Ignoring MPD Status Update Nov 21 13:02:05 volumio volumio[6909]: info: sendMpdCommand status took 5 milliseconds Nov 21 13:02:05 volumio volumio[6909]: info: sendMpdCommand clear took 4 milliseconds Nov 21 13:02:05 volumio volumio[6909]: info: sendMpdCommand status took 3 milliseconds Nov 21 13:02:05 volumio volumio[6909]: info: sendMpdCommand status took 1 milliseconds Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99510328" Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:05 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:05 volumio volumio[6909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 21 13:02:05 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:05 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:05 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:05 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:05 volumio volumio[6909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 21 13:02:05 volumio volumio[6909]: info: ------------------------------ 17ms Nov 21 13:02:05 volumio volumio[6909]: info: ------------------------------ 15ms Nov 21 13:02:05 volumio volumio[6909]: info: Nov 21 13:02:05 volumio volumio[6909]: ---------------------------- MPD announces system playlist update Nov 21 13:02:05 volumio volumio[6909]: info: Ignoring MPD Status Update Nov 21 13:02:05 volumio volumio[6909]: info: Nov 21 13:02:05 volumio volumio[6909]: ---------------------------- MPD announces system playlist update Nov 21 13:02:05 volumio volumio[6909]: info: Ignoring MPD Status Update Nov 21 13:02:05 volumio volumio[6909]: error: updateQueue error: null Nov 21 13:02:05 volumio volumio[6909]: info: ------------------------------ 17ms Nov 21 13:02:05 volumio volumio[6909]: info: sendMpdCommand playlistinfo took 16 milliseconds Nov 21 13:02:05 volumio volumio[6909]: verbose: ControllerMpd::parseTrackInfo Nov 21 13:02:05 volumio volumio[6909]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 21 13:02:05 volumio volumio[6909]: info: ------------------------------ 24ms Nov 21 13:02:07 volumio volumio[6909]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99510328" took 2057 milliseconds Nov 21 13:02:07 volumio volumio[6909]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 13:02:07 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand play Nov 21 13:02:07 volumio volumio[6909]: info: Nov 21 13:02:07 volumio volumio[6909]: ---------------------------- MPD announces system playlist update Nov 21 13:02:07 volumio volumio[6909]: info: Ignoring MPD Status Update Nov 21 13:02:07 volumio volumio[6909]: info: ------------------------------ 2047ms Nov 21 13:02:07 volumio volumio[6909]: info: ------------------------------ 2047ms Nov 21 13:02:07 volumio volumio[6909]: info: sendMpdCommand play took 2 milliseconds Nov 21 13:02:07 volumio volumio[6909]: info: Nov 21 13:02:07 volumio volumio[6909]: ---------------------------- MPD announces system playlist update Nov 21 13:02:07 volumio volumio[6909]: info: Ignoring MPD Status Update Nov 21 13:02:07 volumio volumio[6909]: info: Nov 21 13:02:07 volumio volumio[6909]: ---------------------------- MPD announces system playlist update Nov 21 13:02:07 volumio volumio[6909]: info: Ignoring MPD Status Update Nov 21 13:02:07 volumio volumio[6909]: info: ------------------------------ 5ms Nov 21 13:02:07 volumio volumio[6909]: info: ------------------------------ 2ms Nov 21 13:02:07 volumio volumio[6909]: info: ------------------------------ 2ms Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand status took 2 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand status took 1 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand status took 2 milliseconds Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand status took 3 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand status took 2 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand status took 1 milliseconds Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseTrackInfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseTrackInfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseTrackInfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: 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":"adagioradio.mp3","artist":"(#1 - 24/300) Adagio Radio HQ","album":null,"uri":"https://server5.mediasector.es:8010/adagioradio.mp3","trackType":"mp3"} Nov 21 13:02:09 volumio volumio[6909]: verbose: CURRENT POSITION 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState stateService play Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState currentStatus stop Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: 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":"adagioradio.mp3","artist":"(#1 - 24/300) Adagio Radio HQ","album":null,"uri":"https://server5.mediasector.es:8010/adagioradio.mp3","trackType":"mp3"} Nov 21 13:02:09 volumio volumio[6909]: verbose: CURRENT POSITION 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState stateService play Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState currentStatus play Nov 21 13:02:09 volumio volumio[6909]: info: Received an update from plugin. extracting info from payload Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: 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":"adagioradio.mp3","artist":"(#1 - 24/300) Adagio Radio HQ","album":null,"uri":"https://server5.mediasector.es:8010/adagioradio.mp3","trackType":"mp3"} Nov 21 13:02:09 volumio volumio[6909]: verbose: CURRENT POSITION 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState stateService play Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState currentStatus play Nov 21 13:02:09 volumio volumio[6909]: info: Received an update from plugin. extracting info from payload Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 15ms Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 22ms Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 22ms Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand playlistinfo took 18 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseTrackInfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseTrackInfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseTrackInfo Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: 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":"adagioradio.mp3","artist":"(#1 - 24/300) Adagio Radio HQ","album":null,"uri":"https://server5.mediasector.es:8010/adagioradio.mp3","trackType":"mp3"} Nov 21 13:02:09 volumio volumio[6909]: verbose: CURRENT POSITION 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState stateService play Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState currentStatus play Nov 21 13:02:09 volumio volumio[6909]: info: Received an update from plugin. extracting info from payload Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: 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":"adagioradio.mp3","artist":"(#1 - 24/300) Adagio Radio HQ","album":null,"uri":"https://server5.mediasector.es:8010/adagioradio.mp3","trackType":"mp3"} Nov 21 13:02:09 volumio volumio[6909]: verbose: CURRENT POSITION 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState stateService play Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState currentStatus play Nov 21 13:02:09 volumio volumio[6909]: info: Received an update from plugin. extracting info from payload Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: 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":"adagioradio.mp3","artist":"(#1 - 24/300) Adagio Radio HQ","album":null,"uri":"https://server5.mediasector.es:8010/adagioradio.mp3","trackType":"mp3"} Nov 21 13:02:09 volumio volumio[6909]: verbose: CURRENT POSITION 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState stateService play Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState currentStatus play Nov 21 13:02:09 volumio volumio[6909]: info: Received an update from plugin. extracting info from payload Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 43ms Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 42ms Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 42ms Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces system playlist update Nov 21 13:02:09 volumio volumio[6909]: info: Ignoring MPD Status Update Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces system playlist update Nov 21 13:02:09 volumio volumio[6909]: info: Ignoring MPD Status Update Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces system playlist update Nov 21 13:02:09 volumio volumio[6909]: info: Ignoring MPD Status Update Nov 21 13:02:09 volumio volumio[6909]: info: Nov 21 13:02:09 volumio volumio[6909]: ---------------------------- MPD announces state update: player Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::getState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand status Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 4ms Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand status took 3 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 4ms Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand status took 3 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 2ms Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand status took 2 milliseconds Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseState Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 21 13:02:09 volumio volumio[6909]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseTrackInfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseTrackInfo Nov 21 13:02:09 volumio volumio[6909]: verbose: ControllerMpd::parseTrackInfo Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":205,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Peter Ilyich Tchaikovsky - Tchaikovsky: The Seasons, Op. 37b: VI. Juin (Barcarolle)","artist":"Adagio Radio HQ","album":null,"uri":"https://server5.mediasector.es:8010/adagioradio.mp3","trackType":"mp3"} Nov 21 13:02:09 volumio volumio[6909]: verbose: CURRENT POSITION 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState stateService play Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState currentStatus play Nov 21 13:02:09 volumio volumio[6909]: info: Received an update from plugin. extracting info from payload Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":217,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Peter Ilyich Tchaikovsky - Tchaikovsky: The Seasons, Op. 37b: VI. Juin (Barcarolle)","artist":"Adagio Radio HQ","album":null,"uri":"https://server5.mediasector.es:8010/adagioradio.mp3","trackType":"mp3"} Nov 21 13:02:09 volumio volumio[6909]: verbose: CURRENT POSITION 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState stateService play Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState currentStatus play Nov 21 13:02:09 volumio volumio[6909]: info: Received an update from plugin. extracting info from payload Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: ControllerMpd::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::servicePushState Nov 21 13:02:09 volumio volumio[6909]: info: CorePlayQueue::getTrack 0 Nov 21 13:02:09 volumio volumio[6909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Peter Ilyich Tchaikovsky - Tchaikovsky: The Seasons, Op. 37b: VI. Juin (Barcarolle)","artist":"Adagio Radio HQ","album":null,"uri":"https://server5.mediasector.es:8010/adagioradio.mp3","trackType":"mp3"} Nov 21 13:02:09 volumio volumio[6909]: verbose: CURRENT POSITION 0 Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState stateService play Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::syncState currentStatus play Nov 21 13:02:09 volumio volumio[6909]: info: Received an update from plugin. extracting info from payload Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreStateMachine::pushState Nov 21 13:02:09 volumio volumio[6909]: info: CoreCommandRouter::volumioPushState Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 26ms Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 25ms Nov 21 13:02:09 volumio volumio[6909]: info: ------------------------------ 25ms Nov 21 13:02:09 volumio volumio[6909]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%2024%2F300)%20Adagio%20Radio%20HQ/88951d15-ebbb-4569-84e7-e9aa2526b7cc.jpg' Nov 21 13:02:51 volumio volumio[6909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 13:02:51 volumio volumio[6909]: Error: connect ETIMEDOUT 104.244.46.17:80 Nov 21 13:02:51 volumio volumio[6909]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Nov 21 13:02:51 volumio volumio[6909]: errno: -110, Nov 21 13:02:51 volumio volumio[6909]: code: 'ETIMEDOUT', Nov 21 13:02:51 volumio volumio[6909]: syscall: 'connect', Nov 21 13:02:51 volumio volumio[6909]: address: '104.244.46.17', Nov 21 13:02:51 volumio volumio[6909]: port: 80 Nov 21 13:02:51 volumio volumio[6909]: } Nov 21 13:02:51 volumio volumio[6909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 13:02:51 volumio sudo[7531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 13:01 Nov 21 13:02:51 volumio sudo[7531]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"