-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2026-10-15 14:11:49 CEST. -- Oct 15 14:10:08 volumio wpa_supplicant[1077]: wlan0: Failed to initiate sched scan Oct 15 14:10:19 volumio wpa_supplicant[1077]: wlan0: Failed to initiate sched scan Oct 15 14:10:29 volumio wpa_supplicant[1077]: wlan0: Failed to initiate sched scan Oct 15 14:10:40 volumio wpa_supplicant[1077]: wlan0: Failed to initiate sched scan Oct 15 14:10:51 volumio wpa_supplicant[1077]: wlan0: Failed to initiate sched scan Oct 15 14:11:02 volumio wpa_supplicant[1077]: wlan0: Failed to initiate sched scan Oct 15 14:11:02 volumio volumio[1364]: info: Preload queue cleared Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::ClearQueue Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::stop Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::clearPlayQueue Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::saveQueue Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::volumioPushQueue Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::addQueueItems Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::addQueueItems Oct 15 14:11:02 volumio volumio[1364]: info: Preload queue cleared Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/01-Enter_Sandman.mp3 Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/01-Enter_Sandman.mp3 Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/12 - I) Rise 'n Shine, II) Am I Ever Gonna Change, III) Who Cares.mp3 Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/12 - I) Rise 'n Shine, II) Am I Ever Gonna Change, III) Who Cares.mp3 Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/Bon Iver - Towers.mp3 Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/Bon Iver - Towers.mp3 Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/guitarra.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/guitarra.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/guitarra2.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/guitarra2.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/guitarra_presentacion.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/guitarra_presentacion.wav Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::volumioPushQueue Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::saveQueue Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::updateTrackBlock Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrackBlock Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::volumioPlay Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::play index 5 Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::addQueueItems Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::addQueueItems Oct 15 14:11:02 volumio volumio[1364]: info: Preload queue cleared Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/limpio.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/limpio.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/mxr_guitarra.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/mxr_guitarra.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/mxr_guitarra2.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/mxr_guitarra2.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/mxr_mono.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/mxr_mono.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/mxr_output.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/mxr_output.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/output.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/output.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono220.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono220.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono220_dis0.00.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono220_dis0.00.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono220_dis0.25.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono220_dis0.25.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono220_dis0.50.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono220_dis0.50.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono220_dis0.75.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono220_dis0.75.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono220_dis1.00.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono220_dis1.00.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono_0.0.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono_0.0.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono_0.25.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono_0.25.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono_0.5.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono_0.5.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono_0.75.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono_0.75.wav Oct 15 14:11:02 volumio volumio[1364]: info: Adding Item to queue: audiorecorder2/tono_1.0.wav Oct 15 14:11:02 volumio volumio[1364]: info: Using cached record of: audiorecorder2/tono_1.0.wav Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::stop Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::volumioPushQueue Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::saveQueue Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::play index undefined Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::updateTrackBlock Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrackBlock Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::startPlaybackTimer Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:02 volumio volumio[1364]: info: clearAddPlayTrack ha sido llamado con: {"service":"audiorecorder2","type":"track","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","uri":"file:///home/volumio/recordings/guitarra_presentacion.wav","trackType":"wav"} Oct 15 14:11:02 volumio volumio[1364]: info: Obteniendo duración de la pista antes de reproducir. Oct 15 14:11:02 volumio volumio[1364]: info: Duración de la pista obtenida: 11.75 segundos. Oct 15 14:11:02 volumio volumio[1364]: info: Añadiendo pista a la cola y reproduciendo: {"service":"audiorecorder2","type":"track","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","uri":"file:///home/volumio/recordings/guitarra_presentacion.wav"} Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: mpd , clearAddPlayTrack Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::clearAddPlayTracks file:///home/volumio/recordings/guitarra_presentacion.wav Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand stop took 1 milliseconds Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand clear Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Oct 15 14:11:02 volumio volumio[1364]: info: Ignoring MPD Status Update Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand clear took 1 milliseconds Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand add "file:///home/volumio/recordings/guitarra_presentacion.wav" Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Oct 15 14:11:02 volumio volumio[1364]: info: Ignoring MPD Status Update Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Oct 15 14:11:02 volumio volumio[1364]: info: Ignoring MPD Status Update Oct 15 14:11:02 volumio volumio[1364]: error: Upnp client error: Error: This socket has been ended by the other party Oct 15 14:11:02 volumio volumio[1364]: error: updateQueue error: null Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Oct 15 14:11:02 volumio volumio[1364]: info: Ignoring MPD Status Update Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 3ms Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand add "file:///home/volumio/recordings/guitarra_presentacion.wav" took 3 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 2ms Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 2ms Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand play Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Oct 15 14:11:02 volumio volumio[1364]: info: Ignoring MPD Status Update Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces system playlist update Oct 15 14:11:02 volumio volumio[1364]: info: Ignoring MPD Status Update Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces state update: player Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 2ms Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand play took 2 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 2ms Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::getState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 1ms Oct 15 14:11:02 volumio volumio[1364]: info: Reproducción iniciada. Oct 15 14:11:02 volumio volumio[1364]: info: Pushstate ha sido llamado Oct 15 14:11:02 volumio volumio[1364]: info: Enviando estado actualizado a Volumio: {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":0} Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:02 volumio volumio[1364]: verbose: STATE SERVICE {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":0} Oct 15 14:11:02 volumio volumio[1364]: verbose: CURRENT POSITION 5 Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::syncState stateService recording Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Oct 15 14:11:02 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces state update: player Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::getState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces state update: player Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::getState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces state update: player Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::getState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand status took 6 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand status took 1 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand status took 1 milliseconds Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces state update: player Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::getState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Oct 15 14:11:02 volumio volumio[1364]: info: Oct 15 14:11:02 volumio volumio[1364]: ---------------------------- MPD announces state update: player Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::getState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand status took 3 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand status took 1 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand status took 1 milliseconds Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseState Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::pushState Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:02 volumio volumio[1364]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current audiorecorder2 Received mpd Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::pushState Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:02 volumio volumio[1364]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current audiorecorder2 Received mpd Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::pushState Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:02 volumio volumio[1364]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current audiorecorder2 Received mpd Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 12ms Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 6ms Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 6ms Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 15 14:11:02 volumio volumio[1364]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Oct 15 14:11:02 volumio volumio[1364]: verbose: ControllerMpd::parseTrackInfo Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::pushState Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:02 volumio volumio[1364]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current audiorecorder2 Received mpd Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::pushState Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:02 volumio volumio[1364]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current audiorecorder2 Received mpd Oct 15 14:11:02 volumio volumio[1364]: info: ControllerMpd::pushState Oct 15 14:11:02 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:02 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:02 volumio volumio[1364]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current audiorecorder2 Received mpd Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 9ms Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 7ms Oct 15 14:11:02 volumio volumio[1364]: info: ------------------------------ 6ms Oct 15 14:11:04 volumio volumio[1364]: info: Pushstate ha sido llamado Oct 15 14:11:04 volumio volumio[1364]: info: Enviando estado actualizado a Volumio: {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":2000} Oct 15 14:11:04 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:04 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:04 volumio volumio[1364]: verbose: STATE SERVICE {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":2000} Oct 15 14:11:04 volumio volumio[1364]: verbose: CURRENT POSITION 5 Oct 15 14:11:04 volumio volumio[1364]: info: CoreStateMachine::syncState stateService recording Oct 15 14:11:04 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Oct 15 14:11:04 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:04 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:04 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 14:11:04 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:06 volumio volumio[1364]: info: Pushstate ha sido llamado Oct 15 14:11:06 volumio volumio[1364]: info: Enviando estado actualizado a Volumio: {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":4000} Oct 15 14:11:06 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:06 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:06 volumio volumio[1364]: verbose: STATE SERVICE {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":4000} Oct 15 14:11:06 volumio volumio[1364]: verbose: CURRENT POSITION 5 Oct 15 14:11:06 volumio volumio[1364]: info: CoreStateMachine::syncState stateService recording Oct 15 14:11:06 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Oct 15 14:11:06 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:06 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:06 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 14:11:06 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:08 volumio volumio[1364]: info: Pushstate ha sido llamado Oct 15 14:11:08 volumio volumio[1364]: info: Enviando estado actualizado a Volumio: {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":6000} Oct 15 14:11:08 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:08 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:08 volumio volumio[1364]: verbose: STATE SERVICE {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":6000} Oct 15 14:11:08 volumio volumio[1364]: verbose: CURRENT POSITION 5 Oct 15 14:11:08 volumio volumio[1364]: info: CoreStateMachine::syncState stateService recording Oct 15 14:11:08 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Oct 15 14:11:08 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:08 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:08 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 14:11:08 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:10 volumio volumio[1364]: info: Pushstate ha sido llamado Oct 15 14:11:10 volumio volumio[1364]: info: Enviando estado actualizado a Volumio: {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":8000} Oct 15 14:11:10 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:10 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:10 volumio volumio[1364]: verbose: STATE SERVICE {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":8000} Oct 15 14:11:10 volumio volumio[1364]: verbose: CURRENT POSITION 5 Oct 15 14:11:10 volumio volumio[1364]: info: CoreStateMachine::syncState stateService recording Oct 15 14:11:10 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Oct 15 14:11:10 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:10 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:10 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 14:11:10 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:12 volumio volumio[1364]: info: Pushstate ha sido llamado Oct 15 14:11:12 volumio volumio[1364]: info: Enviando estado actualizado a Volumio: {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":10000} Oct 15 14:11:12 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:12 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:12 volumio volumio[1364]: verbose: STATE SERVICE {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":10000} Oct 15 14:11:12 volumio volumio[1364]: verbose: CURRENT POSITION 5 Oct 15 14:11:12 volumio volumio[1364]: info: CoreStateMachine::syncState stateService recording Oct 15 14:11:12 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Oct 15 14:11:12 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:12 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:12 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 14:11:12 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:12 volumio wpa_supplicant[1077]: wlan0: Failed to initiate sched scan Oct 15 14:11:14 volumio volumio[1364]: info: Oct 15 14:11:14 volumio volumio[1364]: ---------------------------- MPD announces state update: player Oct 15 14:11:14 volumio volumio[1364]: info: ControllerMpd::getState Oct 15 14:11:14 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Oct 15 14:11:14 volumio volumio[1364]: info: Oct 15 14:11:14 volumio volumio[1364]: ---------------------------- MPD announces state update: player Oct 15 14:11:14 volumio volumio[1364]: info: ControllerMpd::getState Oct 15 14:11:14 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Oct 15 14:11:14 volumio volumio[1364]: info: Oct 15 14:11:14 volumio volumio[1364]: ---------------------------- MPD announces state update: player Oct 15 14:11:14 volumio volumio[1364]: info: ControllerMpd::getState Oct 15 14:11:14 volumio volumio[1364]: verbose: ControllerMpd::sendMpdCommand status Oct 15 14:11:14 volumio volumio[1364]: info: sendMpdCommand status took 1 milliseconds Oct 15 14:11:14 volumio volumio[1364]: info: sendMpdCommand status took 0 milliseconds Oct 15 14:11:14 volumio volumio[1364]: info: sendMpdCommand status took 0 milliseconds Oct 15 14:11:14 volumio volumio[1364]: verbose: ControllerMpd::parseState Oct 15 14:11:14 volumio volumio[1364]: verbose: ControllerMpd::parseState Oct 15 14:11:14 volumio volumio[1364]: verbose: ControllerMpd::parseState Oct 15 14:11:14 volumio volumio[1364]: info: ControllerMpd::pushState Oct 15 14:11:14 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:14 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:14 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:14 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 14:11:14 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:14 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:14 volumio volumio[1364]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current audiorecorder2 Received mpd Oct 15 14:11:14 volumio volumio[1364]: info: ControllerMpd::pushState Oct 15 14:11:14 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:14 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:14 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:14 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:14 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:14 volumio volumio[1364]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current audiorecorder2 Received mpd Oct 15 14:11:14 volumio volumio[1364]: info: ControllerMpd::pushState Oct 15 14:11:14 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:14 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:14 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:14 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:14 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:14 volumio volumio[1364]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current audiorecorder2 Received mpd Oct 15 14:11:14 volumio volumio[1364]: info: ------------------------------ 9ms Oct 15 14:11:14 volumio volumio[1364]: info: ------------------------------ 9ms Oct 15 14:11:14 volumio volumio[1364]: info: ------------------------------ 9ms Oct 15 14:11:14 volumio volumio[1364]: info: Pushstate ha sido llamado Oct 15 14:11:14 volumio volumio[1364]: info: Enviando estado actualizado a Volumio: {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":12000} Oct 15 14:11:14 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:14 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:14 volumio volumio[1364]: verbose: STATE SERVICE {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":12000} Oct 15 14:11:14 volumio volumio[1364]: verbose: CURRENT POSITION 5 Oct 15 14:11:14 volumio volumio[1364]: info: CoreStateMachine::syncState stateService recording Oct 15 14:11:14 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Oct 15 14:11:14 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:14 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:14 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 14:11:14 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:16 volumio volumio[1364]: info: La pista ha finalizado. Cambiando estado a stop. Oct 15 14:11:16 volumio volumio[1364]: info: Pushstate ha sido llamado Oct 15 14:11:16 volumio volumio[1364]: info: Enviando estado actualizado a Volumio: {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":12000} Oct 15 14:11:16 volumio volumio[1364]: info: CoreCommandRouter::servicePushState Oct 15 14:11:16 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:16 volumio volumio[1364]: verbose: STATE SERVICE {"status":"recording","service":"audiorecorder2","title":"guitarra_presentacion.wav","artist":"Grabación Local","album":"Mis Grabaciones","trackType":"wav","uri":"","duration":11.75,"seek":12000} Oct 15 14:11:16 volumio volumio[1364]: verbose: CURRENT POSITION 5 Oct 15 14:11:16 volumio volumio[1364]: info: CoreStateMachine::syncState stateService recording Oct 15 14:11:16 volumio volumio[1364]: info: CoreStateMachine::syncState currentStatus stop Oct 15 14:11:16 volumio volumio[1364]: info: CoreStateMachine::pushState Oct 15 14:11:16 volumio volumio[1364]: info: CorePlayQueue::getTrack 5 Oct 15 14:11:16 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 14:11:16 volumio volumio[1364]: info: CoreCommandRouter::volumioPushState Oct 15 14:11:19 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 14:11:19 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 15 14:11:22 volumio volumio[1364]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 14:11:23 volumio wpa_supplicant[1077]: wlan0: Failed to initiate sched scan Oct 15 14:11:34 volumio wpa_supplicant[1077]: wlan0: Failed to initiate sched scan Oct 15 14:11:42 volumio volumio[1364]: info: CALLMETHOD: music_service audiorecorder2 saveMxrConfig [object Object] Oct 15 14:11:42 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: audiorecorder2 , saveMxrConfig Oct 15 14:11:42 volumio volumio[1364]: info: MXR: parametros guardados dis=0.5 out=1 gain=0.2 Oct 15 14:11:45 volumio wpa_supplicant[1077]: wlan0: Failed to initiate sched scan Oct 15 14:11:46 volumio volumio[1364]: info: CALLMETHOD: music_service audiorecorder2 applyMxrEffect undefined Oct 15 14:11:46 volumio volumio[1364]: info: CoreCommandRouter::executeOnPlugin: audiorecorder2 , applyMxrEffect Oct 15 14:11:46 volumio volumio[1364]: info: MXR: ejecutando -> /usr/bin/python3 -u "/data/plugins/music_service/audiorecorder2/file_proc.py" "/home/volumio/recordings/guitarra_presentacion.wav" "/home/volumio/recordings/mxr_guitarra_presentacion.wav" 0.5 1 --in-gain 0.2 Oct 15 14:11:49 volumio volumio[1364]: info: MXR: OK Oct 15 14:11:49 volumio volumio[1364]: Entrada : guitarra_presentacion.wav (564000 muestras, 48000 Hz, 2 canal/es) Oct 15 14:11:49 volumio volumio[1364]: Parametros: dis=0.500 out=1.000 in_gain=0.200 Oct 15 14:11:49 volumio volumio[1364]: Progreso: 0% Oct 15 14:11:49 volumio volumio[1364]: Progreso: 10% Oct 15 14:11:49 volumio volumio[1364]: Progreso: 20% Oct 15 14:11:49 volumio volumio[1364]: Progreso: 30% Oct 15 14:11:49 volumio volumio[1364]: Progreso: 40% Oct 15 14:11:49 volumio volumio[1364]: Progreso: 50% Oct 15 14:11:49 volumio volumio[1364]: Progreso: 60% Oct 15 14:11:49 volumio volumio[1364]: Progreso: 70% Oct 15 14:11:49 volumio volumio[1364]: Progreso: 80% Oct 15 14:11:49 volumio volumio[1364]: Progreso: 90% Oct 15 14:11:49 volumio volumio[1364]: Progreso: 100% Oct 15 14:11:49 volumio volumio[1364]: OK: /home/volumio/recordings/mxr_guitarra_presentacion.wav (estereo) Oct 15 14:11:49 volumio volumio[1364]: Duracion audio: 11.75 s | Tiempo CPU: 1.99 s | x0.17 tiempo real Oct 15 14:11:49 volumio volumio[1364]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 14:11:49 volumio volumio[1364]: TypeError: self.commandRouter.refreshBrowseSources is not a function Oct 15 14:11:49 volumio volumio[1364]: at /data/plugins/music_service/audiorecorder2/index.js:1147:32 Oct 15 14:11:49 volumio volumio[1364]: at ChildProcess.exithandler (child_process.js:299:7) Oct 15 14:11:49 volumio volumio[1364]: at ChildProcess.emit (events.js:315:20) Oct 15 14:11:49 volumio volumio[1364]: at maybeClose (internal/child_process.js:1048:16) Oct 15 14:11:49 volumio volumio[1364]: at Socket. (internal/child_process.js:439:11) Oct 15 14:11:49 volumio volumio[1364]: at Socket.emit (events.js:315:20) Oct 15 14:11:49 volumio volumio[1364]: at Pipe. (net.js:673:12) Oct 15 14:11:49 volumio volumio[1364]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 14:11:49 volumio sudo[4884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-15 14:10 Oct 15 14:11:49 volumio sudo[4884]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"