-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-09-01 01:51:58 CEST. -- Sep 01 01:50:00 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:00 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:00 volumio volumio[1062]: info: Starting Uninstall of plugin system_hardware - audiophonics_evo_sabre Sep 01 01:50:00 volumio volumio[1062]: info: Uninstalling plugin audiophonics_evo_sabre Sep 01 01:50:00 volumio volumio[1062]: info: Disabling plugin audiophonics_evo_sabre Sep 01 01:50:00 volumio volumio[1062]: info: Checking if uninstall.sh is present Sep 01 01:50:00 volumio volumio[1062]: info: Executing uninstall.sh Sep 01 01:50:00 volumio sudo[7575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/audiophonics_evo_sabre/uninstall.sh Sep 01 01:50:00 volumio sudo[7575]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 01:50:00 volumio lircd[4380]: lircd-0.10.1[4380]: Notice: caught signal Sep 01 01:50:00 volumio lircd-0.10.1[4380]: Notice: caught signal Sep 01 01:50:00 volumio systemd[1]: Stopping evo_remote.service... Sep 01 01:50:00 volumio systemd[1]: evo_remote.service: Main process exited, code=killed, status=15/TERM Sep 01 01:50:00 volumio systemd[1]: evo_remote.service: Succeeded. Sep 01 01:50:00 volumio systemd[1]: Stopped evo_remote.service. Sep 01 01:50:00 volumio systemd[1]: evo_irexec.service: Succeeded. Sep 01 01:50:00 volumio sudo[7575]: pam_unix(sudo:session): session closed for user root Sep 01 01:50:00 volumio volumio[1062]: info: Uninstall script completed Sep 01 01:50:00 volumio volumio[1062]: info: Removing plugin audiophonics_evo_sabre from configuration Sep 01 01:50:00 volumio volumio[1062]: info: Successfully removed audiophonics_evo_sabre configuration files Sep 01 01:50:00 volumio volumio[1062]: info: Plugin folders cleanup Sep 01 01:50:00 volumio volumio[1062]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:50:00 volumio volumio[1062]: info: Scanning category audio_interface Sep 01 01:50:00 volumio volumio[1062]: info: Scanning category miscellanea Sep 01 01:50:00 volumio volumio[1062]: info: Scanning category music_service Sep 01 01:50:00 volumio volumio[1062]: info: Scanning category plugins.json Sep 01 01:50:00 volumio volumio[1062]: info: Scanning category system_controller Sep 01 01:50:00 volumio volumio[1062]: info: Scanning category user_interface Sep 01 01:50:00 volumio volumio[1062]: info: Scanning into folder /data/plugins/ Sep 01 01:50:00 volumio volumio[1062]: info: Scanning category music_service Sep 01 01:50:00 volumio volumio[1062]: info: Scanning category system_controller Sep 01 01:50:00 volumio volumio[1062]: info: Scanning category system_hardware Sep 01 01:50:00 volumio volumio[1062]: info: Cleaning folder for audiophonics_evo_sabre Sep 01 01:50:00 volumio volumio[1062]: info: Scanning category user_interface Sep 01 01:50:00 volumio volumio[1062]: info: Plugin folders cleanup completed Sep 01 01:50:00 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 01:50:00 volumio volumio[1062]: info: [{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.2.2","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"GPIO Control","name":"gpio_control","category":"system_hardware","version":"1.0.10","icon":"fa-microchip","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"IR Remote Controller","name":"ir_controller","category":"system_hardware","version":"3.4.13","icon":"fa-magic","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":false,"active":false}] Sep 01 01:50:00 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 01 01:50:03 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:03 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:06 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:06 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:09 volumio volumio[1062]: info: Starting Uninstall of plugin system_hardware - gpio_control Sep 01 01:50:09 volumio volumio[1062]: info: Uninstalling plugin gpio_control Sep 01 01:50:09 volumio volumio[1062]: info: Disabling plugin gpio_control Sep 01 01:50:09 volumio volumio[1062]: info: Checking if uninstall.sh is present Sep 01 01:50:09 volumio volumio[1062]: info: Executing uninstall.sh Sep 01 01:50:09 volumio sudo[7603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/gpio_control/uninstall.sh Sep 01 01:50:09 volumio sudo[7603]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 01:50:09 volumio sudo[7603]: pam_unix(sudo:session): session closed for user root Sep 01 01:50:09 volumio volumio[1062]: info: Uninstall script completed Sep 01 01:50:09 volumio volumio[1062]: info: Removing plugin gpio_control from configuration Sep 01 01:50:09 volumio volumio[1062]: info: Successfully removed gpio_control configuration files Sep 01 01:50:09 volumio volumio[1062]: info: Plugin folders cleanup Sep 01 01:50:09 volumio volumio[1062]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:50:09 volumio volumio[1062]: info: Scanning category audio_interface Sep 01 01:50:09 volumio volumio[1062]: info: Scanning category miscellanea Sep 01 01:50:09 volumio volumio[1062]: info: Scanning category music_service Sep 01 01:50:09 volumio volumio[1062]: info: Scanning category plugins.json Sep 01 01:50:09 volumio volumio[1062]: info: Scanning category system_controller Sep 01 01:50:09 volumio volumio[1062]: info: Scanning category user_interface Sep 01 01:50:09 volumio volumio[1062]: info: Scanning into folder /data/plugins/ Sep 01 01:50:09 volumio volumio[1062]: info: Scanning category music_service Sep 01 01:50:09 volumio volumio[1062]: info: Scanning category system_hardware Sep 01 01:50:09 volumio volumio[1062]: info: Cleaning folder for gpio_control Sep 01 01:50:09 volumio volumio[1062]: info: Scanning category user_interface Sep 01 01:50:09 volumio volumio[1062]: info: Plugin folders cleanup completed Sep 01 01:50:09 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 01:50:09 volumio volumio[1062]: info: [{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.2.2","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"IR Remote Controller","name":"ir_controller","category":"system_hardware","version":"3.4.13","icon":"fa-magic","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":false,"active":false}] Sep 01 01:50:09 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 01 01:50:09 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:09 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:12 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:12 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:15 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:15 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:18 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:18 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:18 volumio volumio[1062]: info: Starting Uninstall of plugin user_interface - now_playing Sep 01 01:50:18 volumio volumio[1062]: info: Uninstalling plugin now_playing Sep 01 01:50:18 volumio volumio[1062]: info: Disabling plugin now_playing Sep 01 01:50:18 volumio volumio[1062]: info: Checking if uninstall.sh is present Sep 01 01:50:18 volumio volumio[1062]: info: Executing uninstall.sh Sep 01 01:50:18 volumio sudo[7626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/uninstall.sh Sep 01 01:50:18 volumio sudo[7626]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 01:50:18 volumio sudo[7626]: pam_unix(sudo:session): session closed for user root Sep 01 01:50:18 volumio volumio[1062]: info: Uninstall script completed Sep 01 01:50:18 volumio volumio[1062]: info: Removing plugin now_playing from configuration Sep 01 01:50:18 volumio volumio[1062]: info: Successfully removed now_playing configuration files Sep 01 01:50:18 volumio volumio[1062]: info: Plugin folders cleanup Sep 01 01:50:18 volumio volumio[1062]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:50:18 volumio volumio[1062]: info: Scanning category audio_interface Sep 01 01:50:18 volumio volumio[1062]: info: Scanning category miscellanea Sep 01 01:50:18 volumio volumio[1062]: info: Scanning category music_service Sep 01 01:50:18 volumio volumio[1062]: info: Scanning category plugins.json Sep 01 01:50:18 volumio volumio[1062]: info: Scanning category system_controller Sep 01 01:50:18 volumio volumio[1062]: info: Scanning category user_interface Sep 01 01:50:18 volumio volumio[1062]: info: Scanning into folder /data/plugins/ Sep 01 01:50:18 volumio volumio[1062]: info: Scanning category music_service Sep 01 01:50:18 volumio volumio[1062]: info: Scanning category system_hardware Sep 01 01:50:18 volumio volumio[1062]: info: Scanning category user_interface Sep 01 01:50:18 volumio volumio[1062]: info: Cleaning folder for now_playing Sep 01 01:50:19 volumio volumio[1062]: info: Plugin folders cleanup completed Sep 01 01:50:19 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 01:50:19 volumio volumio[1062]: info: [{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.2.2","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"IR Remote Controller","name":"ir_controller","category":"system_hardware","version":"3.4.13","icon":"fa-magic","isManuallyInstalled":false,"enabled":false,"active":false}] Sep 01 01:50:19 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 01 01:50:20 volumio volumio[1062]: error: Cannot download Available plugins list: Error: ETIMEDOUT Sep 01 01:50:21 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:21 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:24 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:24 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:26 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Sep 01 01:50:27 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:27 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:27 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Sep 01 01:50:27 volumio volumio[1062]: info: CoreStateMachine::play index undefined Sep 01 01:50:27 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:27 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:27 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Sep 01 01:50:27 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:27 volumio volumio[1062]: info: [1756684227610] ControllerWebradio::clearAddPlayTrack Sep 01 01:50:27 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 01:50:27 volumio volumio[1062]: info: Sep 01 01:50:27 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:27 volumio volumio[1062]: info: sendMpdCommand stop took 14 milliseconds Sep 01 01:50:27 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:27 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:27 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 01:50:27 volumio volumio[1062]: info: Sep 01 01:50:27 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:27 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:27 volumio volumio[1062]: info: sendMpdCommand status took 1 milliseconds Sep 01 01:50:27 volumio volumio[1062]: info: sendMpdCommand clear took 1 milliseconds Sep 01 01:50:27 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:27 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:27 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand load "https://ibizasonica.streaming-pro.com:8011/sonicaclub" Sep 01 01:50:27 volumio volumio[1062]: info: Sep 01 01:50:27 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:27 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:27 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:27 volumio volumio[1062]: error: updateQueue error: null Sep 01 01:50:27 volumio volumio[1062]: info: ------------------------------ 4ms Sep 01 01:50:27 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 01:50:27 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:27 volumio volumio[1062]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 01 01:50:27 volumio volumio[1062]: info: ------------------------------ 6ms Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "https://ibizasonica.streaming-pro.com:8011/sonicaclub" Sep 01 01:50:28 volumio volumio[1062]: info: Sep 01 01:50:28 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:28 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand status took 976 milliseconds Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand add "https://ibizasonica.streaming-pro.com:8011/sonicaclub" took 1 milliseconds Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Sep 01 01:50:28 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:28 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:28 volumio volumio[1062]: 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} Sep 01 01:50:28 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:28 volumio volumio[1062]: info: No code Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:28 volumio volumio[1062]: info: ------------------------------ 999ms Sep 01 01:50:28 volumio volumio[1062]: info: Sep 01 01:50:28 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:28 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:28 volumio volumio[1062]: info: ------------------------------ 26ms Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand play took 25 milliseconds Sep 01 01:50:28 volumio volumio[1062]: info: ------------------------------ 4ms Sep 01 01:50:28 volumio volumio[1062]: info: Sep 01 01:50:28 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:28 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:28 volumio volumio[1062]: info: ------------------------------ 5ms Sep 01 01:50:28 volumio volumio[1062]: info: Sep 01 01:50:28 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:28 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:28 volumio volumio[1062]: info: Sep 01 01:50:28 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:28 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:28 volumio volumio[1062]: info: Sep 01 01:50:28 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:28 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:28 volumio volumio[1062]: info: Sep 01 01:50:28 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:28 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand status took 13 milliseconds Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand status took 13 milliseconds Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand clearerror took 8 milliseconds Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:28 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:28 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:28 volumio volumio[1062]: verbose: STATE SERVICE {"status":"pause","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":"sonicaclub","artist":null,"album":null,"uri":"https://ibizasonica.streaming-pro.com:8011/sonicaclub","trackType":""} Sep 01 01:50:28 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::syncState stateService pause Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:28 volumio volumio[1062]: info: ------------------------------ 30ms Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand status took 24 milliseconds Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand clearerror took 19 milliseconds Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand clearerror took 18 milliseconds Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:28 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:28 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:28 volumio volumio[1062]: verbose: STATE SERVICE {"status":"pause","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":"sonicaclub","artist":null,"album":null,"uri":"https://ibizasonica.streaming-pro.com:8011/sonicaclub","trackType":""} Sep 01 01:50:28 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::syncState stateService pause Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:28 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:28 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:28 volumio volumio[1062]: verbose: STATE SERVICE {"status":"pause","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":"sonicaclub","artist":null,"album":null,"uri":"https://ibizasonica.streaming-pro.com:8011/sonicaclub","trackType":""} Sep 01 01:50:28 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::syncState stateService pause Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:28 volumio volumio[1062]: info: ------------------------------ 53ms Sep 01 01:50:28 volumio volumio[1062]: info: ------------------------------ 51ms Sep 01 01:50:28 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 01 01:50:28 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:28 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:28 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:28 volumio volumio[1062]: verbose: STATE SERVICE {"status":"pause","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":"sonicaclub","artist":null,"album":null,"uri":"https://ibizasonica.streaming-pro.com:8011/sonicaclub","trackType":""} Sep 01 01:50:28 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::syncState stateService pause Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:28 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:28 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:29 volumio volumio[1062]: info: ------------------------------ 53ms Sep 01 01:50:30 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:30 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:33 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:33 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:33 volumio volumio[1062]: info: CoreCommandRouter::volumioGetQueue Sep 01 01:50:33 volumio volumio[1062]: info: CoreStateMachine::getQueue Sep 01 01:50:33 volumio volumio[1062]: info: CorePlayQueue::getQueue Sep 01 01:50:36 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:36 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:36 volumio volumio[1062]: error: error Sep 01 01:50:36 volumio volumio[1062]: error: error Sep 01 01:50:36 volumio volumio[1062]: error: error Sep 01 01:50:38 volumio volumio[1062]: error: error Sep 01 01:50:38 volumio volumio[1062]: error: error Sep 01 01:50:39 volumio volumio[1062]: error: error Sep 01 01:50:39 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Sep 01 01:50:39 volumio volumio[1062]: info: [1756684239274] [80s80s] handleBrowseUri curUri: 80s80s Sep 01 01:50:39 volumio volumio[1062]: info: Preload queue cleared Sep 01 01:50:39 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:39 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:41 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Sep 01 01:50:41 volumio volumio[1062]: info: [1756684241568] [80s80s] handleBrowseUri curUri: 80s80s/nineties Sep 01 01:50:41 volumio volumio[1062]: info: [1756684241569] [80s80s] getRadioContent url: nineties Sep 01 01:50:41 volumio volumio[1062]: info: Preload queue cleared Sep 01 01:50:41 volumio volumio[1062]: info: Preload queue cleared Sep 01 01:50:41 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::stop Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:41 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Sep 01 01:50:41 volumio volumio[1062]: info: CorePlayQueue::saveQueue Sep 01 01:50:41 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Sep 01 01:50:41 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Sep 01 01:50:41 volumio volumio[1062]: info: Preload queue cleared Sep 01 01:50:41 volumio volumio[1062]: info: Adding Item to queue: webnineties/1 Sep 01 01:50:41 volumio volumio[1062]: info: Exploding uri webnineties/1 in service 80s80s Sep 01 01:50:41 volumio volumio[1062]: info: [1756684241791] [80s80s] explodeUri: webnineties/1 Sep 01 01:50:41 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Sep 01 01:50:41 volumio volumio[1062]: info: CorePlayQueue::saveQueue Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Sep 01 01:50:41 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Sep 01 01:50:41 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::play index 0 Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::stop Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::play index undefined Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:41 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:41 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Sep 01 01:50:41 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:41 volumio volumio[1062]: info: [1756684241801] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/grunge/mp3-192/volumio Sep 01 01:50:41 volumio volumio[1062]: info: [1756684241801] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=253&count=2 Sep 01 01:50:41 volumio volumio[1062]: error: error Sep 01 01:50:42 volumio volumio[1062]: info: [1756684242293] [80s80s] received new event containing 2 songs. Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 01:50:42 volumio volumio[1062]: info: sendMpdCommand stop took 5 milliseconds Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 01:50:42 volumio volumio[1062]: info: Sep 01 01:50:42 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:42 volumio volumio[1062]: info: Sep 01 01:50:42 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:42 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:42 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:42 volumio volumio[1062]: info: sendMpdCommand clear took 1 milliseconds Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 01 01:50:42 volumio volumio[1062]: info: Sep 01 01:50:42 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:42 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:42 volumio volumio[1062]: error: updateQueue error: null Sep 01 01:50:42 volumio volumio[1062]: info: Sep 01 01:50:42 volumio volumio[1062]: ---------------------------- MPD announces state update: options Sep 01 01:50:42 volumio volumio[1062]: info: sendMpdCommand status took 3 milliseconds Sep 01 01:50:42 volumio volumio[1062]: info: ------------------------------ 3ms Sep 01 01:50:42 volumio volumio[1062]: info: sendMpdCommand consume 1 took 2 milliseconds Sep 01 01:50:42 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:42 volumio volumio[1062]: info: sendMpdCommand status took 1 milliseconds Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:42 volumio volumio[1062]: info: [1756684242304] [80s80s] adding url: http://streams.90s90s.de/grunge/mp3-192/volumio Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/grunge/mp3-192/volumio" Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:42 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:42 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:42 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:50:42 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:42 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:42 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:42 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:42 volumio volumio[1062]: info: ------------------------------ 22ms Sep 01 01:50:42 volumio volumio[1062]: info: ------------------------------ 22ms Sep 01 01:50:42 volumio volumio[1062]: info: Sep 01 01:50:42 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:42 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:42 volumio volumio[1062]: info: Sep 01 01:50:42 volumio volumio[1062]: ---------------------------- MPD announces state update: options Sep 01 01:50:42 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:42 volumio volumio[1062]: info: Sep 01 01:50:42 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:42 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:42 volumio volumio[1062]: info: sendMpdCommand status took 22 milliseconds Sep 01 01:50:42 volumio volumio[1062]: info: sendMpdCommand add "http://streams.90s90s.de/grunge/mp3-192/volumio" took 21 milliseconds Sep 01 01:50:42 volumio volumio[1062]: info: ------------------------------ 2ms Sep 01 01:50:42 volumio volumio[1062]: info: sendMpdCommand status took 1 milliseconds Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Sep 01 01:50:42 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:42 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:42 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:42 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:42 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:42 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:42 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:42 volumio volumio[1062]: info: ------------------------------ 37ms Sep 01 01:50:42 volumio volumio[1062]: info: ------------------------------ 22ms Sep 01 01:50:42 volumio volumio[1062]: info: Sep 01 01:50:42 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:42 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:42 volumio volumio[1062]: info: ------------------------------ 28ms Sep 01 01:50:42 volumio volumio[1062]: info: sendMpdCommand play took 24 milliseconds Sep 01 01:50:42 volumio volumio[1062]: info: ------------------------------ 7ms Sep 01 01:50:42 volumio volumio[1062]: info: [1756684242353] [80s80s] Pushing the next song state: P.O.T.U.S.O.A. - Lump Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"/albumart?sourceicon=music_service/80s80s/images/90s90s-grunge-cover.png","name":"P.O.T.U.S.O.A. - Lump","title":"Lump","artist":"90s90s Grunge","album":"","streaming":true,"disableUiControls":true,"duration":"129","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 01 01:50:42 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:42 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Sep 01 01:50:42 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:42 volumio volumio[1062]: info: [1756684242355] [80s80s] PlayNextTrack API delay: 30 Sep 01 01:50:42 volumio volumio[1062]: info: [1756684242366] [80s80s] Setting timer to: 129000 milliseconds. Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:42 volumio volumio[1062]: info: CorePlayQueue::getTrack 1 Sep 01 01:50:42 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:42 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:44 volumio volumio[1062]: info: Preload queue cleared Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::stop Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::stPlaybackTimer Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::serviceStop Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::serviceStop Sep 01 01:50:44 volumio volumio[1062]: info: ControllerMpd::stop Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::saveQueue Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Sep 01 01:50:44 volumio volumio[1062]: info: Preload queue cleared Sep 01 01:50:44 volumio volumio[1062]: info: Adding Item to queue: webnineties/2 Sep 01 01:50:44 volumio volumio[1062]: info: Exploding uri webnineties/2 in service 80s80s Sep 01 01:50:44 volumio volumio[1062]: info: [1756684244396] [80s80s] explodeUri: webnineties/2 Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::saveQueue Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::play index 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::stop Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::play index undefined Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: [1756684244417] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/eurodance/mp3-192/volumio Sep 01 01:50:44 volumio volumio[1062]: info: [1756684244418] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=188&count=2 Sep 01 01:50:44 volumio volumio[1062]: info: Sep 01 01:50:44 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:44 volumio volumio[1062]: info: sendMpdCommand stop took 28 milliseconds Sep 01 01:50:44 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"/albumart?sourceicon=music_service/80s80s/images/90s90s-grunge-cover.png","name":"P.O.T.U.S.O.A. - Lump","title":"Lump","artist":"90s90s Grunge","album":"","streaming":true,"disableUiControls":true,"duration":"129","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 01 01:50:44 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:44 volumio volumio[1062]: info: No code Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:44 volumio volumio[1062]: info: Sep 01 01:50:44 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:44 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:44 volumio volumio[1062]: info: sendMpdCommand status took 30 milliseconds Sep 01 01:50:44 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:44 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 01:50:44 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:44 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:44 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:44 volumio volumio[1062]: info: ------------------------------ 50ms Sep 01 01:50:44 volumio volumio[1062]: info: ------------------------------ 26ms Sep 01 01:50:44 volumio volumio[1062]: info: [1756684244732] [80s80s] received new event containing 2 songs. Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 01:50:44 volumio volumio[1062]: info: sendMpdCommand stop took 1 milliseconds Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 01:50:44 volumio volumio[1062]: info: Sep 01 01:50:44 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:44 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:44 volumio volumio[1062]: info: sendMpdCommand clear took 1 milliseconds Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 01 01:50:44 volumio volumio[1062]: info: Sep 01 01:50:44 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:44 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:44 volumio volumio[1062]: error: updateQueue error: null Sep 01 01:50:44 volumio volumio[1062]: error: updateQueue error: null Sep 01 01:50:44 volumio volumio[1062]: info: ------------------------------ 2ms Sep 01 01:50:44 volumio volumio[1062]: info: sendMpdCommand consume 1 took 2 milliseconds Sep 01 01:50:44 volumio volumio[1062]: info: ------------------------------ 1ms Sep 01 01:50:44 volumio volumio[1062]: info: [1756684244736] [80s80s] adding url: http://streams.90s90s.de/eurodance/mp3-192/volumio Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" Sep 01 01:50:44 volumio volumio[1062]: info: Sep 01 01:50:44 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:44 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:44 volumio volumio[1062]: info: sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" took 1 milliseconds Sep 01 01:50:44 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Sep 01 01:50:44 volumio volumio[1062]: info: Sep 01 01:50:44 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:44 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:44 volumio volumio[1062]: info: ------------------------------ 6ms Sep 01 01:50:44 volumio volumio[1062]: info: sendMpdCommand play took 2 milliseconds Sep 01 01:50:44 volumio volumio[1062]: info: ------------------------------ 1ms Sep 01 01:50:44 volumio volumio[1062]: info: [1756684244743] [80s80s] Pushing the next song state: Captain Jack - Captain Jack Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music114/v4/38/c4/df/38c4dfe7-6205-d9fd-3705-e772f4d8eed7/source/600x600bb.jpg","name":"Captain Jack - Captain Jack","title":"Captain Jack","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"242","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 01 01:50:44 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Sep 01 01:50:44 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:44 volumio volumio[1062]: info: [1756684244744] [80s80s] PlayNextTrack API delay: 30 Sep 01 01:50:44 volumio volumio[1062]: info: [1756684244746] [80s80s] Setting timer to: 22256 milliseconds. Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:44 volumio volumio[1062]: info: CorePlayQueue::getTrack 1 Sep 01 01:50:45 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:45 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:45 volumio volumio[1062]: info: Sep 01 01:50:45 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:45 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:45 volumio volumio[1062]: info: Sep 01 01:50:45 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:45 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:45 volumio volumio[1062]: info: Sep 01 01:50:45 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 01:50:45 volumio volumio[1062]: info: sendMpdCommand status took 6 milliseconds Sep 01 01:50:45 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:45 volumio volumio[1062]: info: Sep 01 01:50:45 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:45 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 01:50:45 volumio volumio[1062]: info: sendMpdCommand status took 13 milliseconds Sep 01 01:50:45 volumio volumio[1062]: info: sendMpdCommand clearerror took 9 milliseconds Sep 01 01:50:45 volumio volumio[1062]: info: sendMpdCommand status took 9 milliseconds Sep 01 01:50:45 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 01 01:50:45 volumio volumio[1062]: info: sendMpdCommand status took 7 milliseconds Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:45 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:45 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:45 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:45 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:45 volumio volumio[1062]: info: ------------------------------ 18ms Sep 01 01:50:45 volumio volumio[1062]: info: sendMpdCommand clearerror took 5 milliseconds Sep 01 01:50:45 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 01:50:45 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 01:50:45 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:45 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:45 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:45 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:45 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:45 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:45 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:45 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:45 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:45 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:45 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:45 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:45 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:45 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:45 volumio volumio[1062]: info: ------------------------------ 21ms Sep 01 01:50:45 volumio volumio[1062]: info: ------------------------------ 20ms Sep 01 01:50:45 volumio volumio[1062]: info: ------------------------------ 14ms Sep 01 01:50:47 volumio volumio[1062]: info: Preload queue cleared Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::stop Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::stPlaybackTimer Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::serviceStop Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::serviceStop Sep 01 01:50:47 volumio volumio[1062]: info: ControllerMpd::stop Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::saveQueue Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Sep 01 01:50:47 volumio volumio[1062]: info: Preload queue cleared Sep 01 01:50:47 volumio volumio[1062]: info: Adding Item to queue: webnineties/2 Sep 01 01:50:47 volumio volumio[1062]: info: Using cached record of: webnineties/2 Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::saveQueue Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::play index 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::stop Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::play index undefined Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: [1756684247710] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/eurodance/mp3-192/volumio Sep 01 01:50:47 volumio volumio[1062]: info: [1756684247710] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=188&count=2 Sep 01 01:50:47 volumio volumio[1062]: info: Sep 01 01:50:47 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:47 volumio volumio[1062]: info: sendMpdCommand stop took 21 milliseconds Sep 01 01:50:47 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music114/v4/38/c4/df/38c4dfe7-6205-d9fd-3705-e772f4d8eed7/source/600x600bb.jpg","name":"Captain Jack - Captain Jack","title":"Captain Jack","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"242","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 01 01:50:47 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:47 volumio volumio[1062]: info: No code Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:47 volumio volumio[1062]: info: Sep 01 01:50:47 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:47 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:47 volumio volumio[1062]: info: sendMpdCommand status took 32 milliseconds Sep 01 01:50:47 volumio volumio[1062]: info: sendMpdCommand status took 5 milliseconds Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:47 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 01:50:47 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:47 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:47 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:47 volumio volumio[1062]: info: ------------------------------ 50ms Sep 01 01:50:47 volumio volumio[1062]: info: ------------------------------ 22ms Sep 01 01:50:47 volumio volumio[1062]: info: [1756684247849] [80s80s] received new event containing 2 songs. Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 01:50:47 volumio volumio[1062]: info: sendMpdCommand stop took 0 milliseconds Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 01:50:47 volumio volumio[1062]: info: Sep 01 01:50:47 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:47 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:47 volumio volumio[1062]: info: sendMpdCommand clear took 0 milliseconds Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 01 01:50:47 volumio volumio[1062]: info: Sep 01 01:50:47 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:47 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:47 volumio volumio[1062]: error: updateQueue error: null Sep 01 01:50:47 volumio volumio[1062]: error: updateQueue error: null Sep 01 01:50:47 volumio volumio[1062]: info: ------------------------------ 2ms Sep 01 01:50:47 volumio volumio[1062]: info: sendMpdCommand consume 1 took 1 milliseconds Sep 01 01:50:47 volumio volumio[1062]: info: ------------------------------ 1ms Sep 01 01:50:47 volumio volumio[1062]: info: [1756684247853] [80s80s] adding url: http://streams.90s90s.de/eurodance/mp3-192/volumio Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" Sep 01 01:50:47 volumio volumio[1062]: info: Sep 01 01:50:47 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:47 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:47 volumio volumio[1062]: info: sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" took 0 milliseconds Sep 01 01:50:47 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Sep 01 01:50:47 volumio volumio[1062]: info: Sep 01 01:50:47 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:47 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:47 volumio volumio[1062]: info: ------------------------------ 6ms Sep 01 01:50:47 volumio volumio[1062]: info: sendMpdCommand play took 2 milliseconds Sep 01 01:50:47 volumio volumio[1062]: info: ------------------------------ 1ms Sep 01 01:50:47 volumio volumio[1062]: info: [1756684247860] [80s80s] Pushing the next song state: Captain Jack - Captain Jack Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music114/v4/38/c4/df/38c4dfe7-6205-d9fd-3705-e772f4d8eed7/source/600x600bb.jpg","name":"Captain Jack - Captain Jack","title":"Captain Jack","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"242","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 01 01:50:47 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Sep 01 01:50:47 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:47 volumio volumio[1062]: info: [1756684247861] [80s80s] PlayNextTrack API delay: 30 Sep 01 01:50:47 volumio volumio[1062]: info: [1756684247862] [80s80s] Setting timer to: 19139 milliseconds. Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:47 volumio volumio[1062]: info: CorePlayQueue::getTrack 1 Sep 01 01:50:48 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:48 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:49 volumio volumio[1062]: info: Sep 01 01:50:49 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:49 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:49 volumio volumio[1062]: info: Sep 01 01:50:49 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:49 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:49 volumio volumio[1062]: info: Sep 01 01:50:49 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:49 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:49 volumio volumio[1062]: info: Sep 01 01:50:49 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Sep 01 01:50:49 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand status took 15 milliseconds Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand clearerror took 10 milliseconds Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand clearerror took 9 milliseconds Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand status took 9 milliseconds Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:49 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:49 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:49 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:49 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:49 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:49 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:49 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:49 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:49 volumio volumio[1062]: info: ------------------------------ 21ms Sep 01 01:50:49 volumio volumio[1062]: info: ------------------------------ 20ms Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand clearerror took 5 milliseconds Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 01 01:50:49 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:49 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:49 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:49 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:49 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:49 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:49 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:49 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:49 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:49 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:49 volumio volumio[1062]: info: ------------------------------ 23ms Sep 01 01:50:49 volumio volumio[1062]: info: ------------------------------ 22ms Sep 01 01:50:51 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:51 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:54 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:54 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:56 volumio volumio[1062]: info: Preload queue cleared Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::stop Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::stPlaybackTimer Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::serviceStop Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::serviceStop Sep 01 01:50:56 volumio volumio[1062]: info: ControllerMpd::stop Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::saveQueue Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Sep 01 01:50:56 volumio volumio[1062]: info: Preload queue cleared Sep 01 01:50:56 volumio volumio[1062]: info: Adding Item to queue: webnineties/2 Sep 01 01:50:56 volumio volumio[1062]: info: Using cached record of: webnineties/2 Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::saveQueue Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::play index 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::stop Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::play index undefined Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: [1756684256659] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/eurodance/mp3-192/volumio Sep 01 01:50:56 volumio volumio[1062]: info: [1756684256659] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=188&count=2 Sep 01 01:50:56 volumio volumio[1062]: info: Sep 01 01:50:56 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:56 volumio volumio[1062]: info: sendMpdCommand stop took 22 milliseconds Sep 01 01:50:56 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music114/v4/38/c4/df/38c4dfe7-6205-d9fd-3705-e772f4d8eed7/source/600x600bb.jpg","name":"Captain Jack - Captain Jack","title":"Captain Jack","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"242","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 01 01:50:56 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:56 volumio volumio[1062]: info: No code Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:56 volumio volumio[1062]: info: Sep 01 01:50:56 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:56 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:56 volumio volumio[1062]: info: sendMpdCommand status took 35 milliseconds Sep 01 01:50:56 volumio volumio[1062]: info: sendMpdCommand status took 6 milliseconds Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:56 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 01:50:56 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:56 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:56 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:56 volumio volumio[1062]: info: ------------------------------ 57ms Sep 01 01:50:56 volumio volumio[1062]: info: ------------------------------ 29ms Sep 01 01:50:56 volumio volumio[1062]: info: [1756684256942] [80s80s] received new event containing 2 songs. Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 01:50:56 volumio volumio[1062]: info: sendMpdCommand stop took 1 milliseconds Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 01:50:56 volumio volumio[1062]: info: Sep 01 01:50:56 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:56 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:56 volumio volumio[1062]: info: sendMpdCommand clear took 1 milliseconds Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 01 01:50:56 volumio volumio[1062]: info: Sep 01 01:50:56 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:56 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:56 volumio volumio[1062]: error: updateQueue error: null Sep 01 01:50:56 volumio volumio[1062]: error: updateQueue error: null Sep 01 01:50:56 volumio volumio[1062]: info: ------------------------------ 2ms Sep 01 01:50:56 volumio volumio[1062]: info: sendMpdCommand consume 1 took 1 milliseconds Sep 01 01:50:56 volumio volumio[1062]: info: ------------------------------ 2ms Sep 01 01:50:56 volumio volumio[1062]: info: [1756684256947] [80s80s] adding url: http://streams.90s90s.de/eurodance/mp3-192/volumio Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" Sep 01 01:50:56 volumio volumio[1062]: info: Sep 01 01:50:56 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:56 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:56 volumio volumio[1062]: info: sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" took 5 milliseconds Sep 01 01:50:56 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Sep 01 01:50:56 volumio volumio[1062]: info: Sep 01 01:50:56 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Sep 01 01:50:56 volumio volumio[1062]: info: Ignoring MPD Status Update Sep 01 01:50:56 volumio volumio[1062]: info: ------------------------------ 12ms Sep 01 01:50:56 volumio volumio[1062]: info: sendMpdCommand play took 2 milliseconds Sep 01 01:50:56 volumio volumio[1062]: info: ------------------------------ 1ms Sep 01 01:50:56 volumio volumio[1062]: info: [1756684256959] [80s80s] Pushing the next song state: Captain Jack - Captain Jack Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music114/v4/38/c4/df/38c4dfe7-6205-d9fd-3705-e772f4d8eed7/source/600x600bb.jpg","name":"Captain Jack - Captain Jack","title":"Captain Jack","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"242","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 01 01:50:56 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Sep 01 01:50:56 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:50:56 volumio volumio[1062]: info: [1756684256960] [80s80s] PlayNextTrack API delay: 30 Sep 01 01:50:56 volumio volumio[1062]: info: [1756684256961] [80s80s] Setting timer to: 10040 milliseconds. Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:56 volumio volumio[1062]: info: CorePlayQueue::getTrack 1 Sep 01 01:50:57 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:50:57 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:50:57 volumio volumio[1062]: info: Sep 01 01:50:57 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:57 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:57 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:57 volumio volumio[1062]: info: Sep 01 01:50:57 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:57 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:57 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:57 volumio volumio[1062]: info: Sep 01 01:50:57 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:57 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 01:50:57 volumio volumio[1062]: info: sendMpdCommand status took 6 milliseconds Sep 01 01:50:57 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:57 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:57 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:57 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:57 volumio volumio[1062]: info: Sep 01 01:50:57 volumio volumio[1062]: ---------------------------- MPD announces state update: player Sep 01 01:50:57 volumio volumio[1062]: info: ControllerMpd::getState Sep 01 01:50:57 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 01:50:58 volumio volumio[1062]: info: sendMpdCommand status took 12 milliseconds Sep 01 01:50:58 volumio volumio[1062]: info: sendMpdCommand clearerror took 6 milliseconds Sep 01 01:50:58 volumio volumio[1062]: info: sendMpdCommand status took 7 milliseconds Sep 01 01:50:58 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 01 01:50:58 volumio volumio[1062]: info: sendMpdCommand status took 6 milliseconds Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::parseState Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:50:58 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:58 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:58 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:58 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:58 volumio volumio[1062]: info: ------------------------------ 19ms Sep 01 01:50:58 volumio volumio[1062]: info: sendMpdCommand clearerror took 9 milliseconds Sep 01 01:50:58 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 01 01:50:58 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 01 01:50:58 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:58 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:50:58 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:58 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:58 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:58 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:58 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:58 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:58 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:58 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:58 volumio volumio[1062]: info: ControllerMpd::pushState Sep 01 01:50:58 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:50:58 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:50:58 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 01 01:50:58 volumio volumio[1062]: info: ------------------------------ 23ms Sep 01 01:50:58 volumio volumio[1062]: info: ------------------------------ 22ms Sep 01 01:50:58 volumio volumio[1062]: info: ------------------------------ 16ms Sep 01 01:51:00 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:00 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:03 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:03 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:06 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:06 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:07 volumio volumio[1062]: info: [1756684267001] [80s80s] Pushing the next song state Captain Jack - Captain Jack and getting next track. Sep 01 01:51:07 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:07 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Sep 01 01:51:07 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:07 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music114/v4/38/c4/df/38c4dfe7-6205-d9fd-3705-e772f4d8eed7/source/600x600bb.jpg","name":"Captain Jack - Captain Jack","title":"Captain Jack","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"242","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 01 01:51:07 volumio volumio[1062]: verbose: CURRENT POSITION 0 Sep 01 01:51:07 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Sep 01 01:51:07 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus play Sep 01 01:51:07 volumio volumio[1062]: info: Received an update from plugin. extracting info from payload Sep 01 01:51:07 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:51:07 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:07 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:51:07 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:07 volumio volumio[1062]: info: CoreStateMachine::pushState Sep 01 01:51:07 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:07 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:07 volumio volumio[1062]: info: [1756684267018] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=188&count=1 Sep 01 01:51:07 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:07 volumio volumio[1062]: info: CorePlayQueue::getTrack 1 Sep 01 01:51:07 volumio volumio[1062]: info: [1756684267271] [80s80s] received new event containing 1 songs. Sep 01 01:51:07 volumio volumio[1062]: info: [1756684267271] [80s80s] PlayNextTrack API delay: 30 Sep 01 01:51:07 volumio volumio[1062]: info: [1756684267272] [80s80s] Setting timer to: 242000 milliseconds. Sep 01 01:51:09 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:09 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:10 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Sep 01 01:51:10 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:12 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:12 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:15 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:15 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:16 volumio volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:16 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 01 01:51:16 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 01 01:51:16 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 01 01:51:16 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 01 01:51:16 volumio volumio[1062]: info: Received Get System Version Sep 01 01:51:16 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:51:18 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:18 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:21 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:21 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:24 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:24 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:27 volumio volumio[1062]: error: error Sep 01 01:51:27 volumio volumio[1062]: error: error Sep 01 01:51:27 volumio volumio[1062]: error: error Sep 01 01:51:27 volumio volumio[1062]: error: error Sep 01 01:51:27 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:27 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:30 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:30 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:30 volumio volumio[1062]: error: error Sep 01 01:51:30 volumio volumio[1062]: error: error Sep 01 01:51:30 volumio volumio[1062]: error: error Sep 01 01:51:33 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:33 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:36 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:36 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:39 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:39 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:42 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:42 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:45 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:45 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:48 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:48 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:51 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:51 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:54 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:54 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:56 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Sep 01 01:51:56 volumio volumio[1062]: info: CoreCommandRouter::Reload Ui Sep 01 01:51:56 volumio sudo[7920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:51:56 volumio sudo[7920]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 01:51:56 volumio sudo[7920]: pam_unix(sudo:session): session closed for user root Sep 01 01:51:56 volumio sudo[7923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:51:56 volumio sudo[7923]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 01:51:56 volumio sudo[7923]: pam_unix(sudo:session): session closed for user root Sep 01 01:51:56 volumio volumio[1062]: verbose: New Socket.io Connection to 192.168.0.35 from 192.168.0.69 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Sep 01 01:51:57 volumio volumio[1062]: info: Received Get System Info Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 01:51:57 volumio volumio[1062]: info: Discovery: Getting this device information Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Sep 01 01:51:57 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 01:51:57 volumio volumio-remote-updater[700]: No test mode Sep 01 01:51:57 volumio volumio-remote-updater[700]: No alpha test mode Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:57 volumio volumio[1062]: info: Retrieving Cloud Streaming UI Sep 01 01:51:57 volumio volumio[1062]: info: Getting Tidal Cloud Configuration Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:57 volumio volumio[1062]: info: Getting Qobuz Cloud Configuration Sep 01 01:51:57 volumio volumio[1062]: info: Asking plugin for UI Config Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:57 volumio volumio[1062]: info: Getting Spotify Cloud Configuration Sep 01 01:51:57 volumio volumio[1062]: info: Asking plugin for UI Config Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:57 volumio volumio[1062]: info: Saving Spotify Acccount Sep 01 01:51:57 volumio volumio[1062]: info: Got Tidal Cloud Configuration Sep 01 01:51:57 volumio volumio[1062]: info: Got it Sep 01 01:51:57 volumio volumio[1062]: info: Got it Sep 01 01:51:57 volumio volumio[1062]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 01:51:57 volumio volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:57 volumio volumio[1062]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 01 01:51:57 volumio volumio[1062]: info: Initializing connection to go-librespot Websocket Sep 01 01:51:57 volumio volumio[1062]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:51:58 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 01 01:51:58 volumio volumio[1062]: info: Loading i18n strings for locale hu Sep 01 01:51:58 volumio volumio[1062]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:51:58 volumio volumio[1062]: TypeError: Cannot read property 'logger' of null Sep 01 01:51:58 volumio volumio[1062]: at NowPlayingContext.getLogger (/data/plugins/user_interface/now_playing/dist/lib/NowPlayingContext.js:71:83) Sep 01 01:51:58 volumio volumio[1062]: at ControllerNowPlaying._ControllerNowPlaying_onVolumioLanguageChanged (/data/plugins/user_interface/now_playing/dist/index.js:2032:33) Sep 01 01:51:58 volumio volumio[1062]: at /volumio/node_modules/v-conf/index.js:165:13 Sep 01 01:51:58 volumio volumio[1062]: at /volumio/node_modules/multimap/index.js:150:9 Sep 01 01:51:58 volumio volumio[1062]: at Array.forEach () Sep 01 01:51:58 volumio volumio[1062]: at /volumio/node_modules/multimap/index.js:149:13 Sep 01 01:51:58 volumio volumio[1062]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Sep 01 01:51:58 volumio volumio[1062]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5) Sep 01 01:51:58 volumio volumio[1062]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10) Sep 01 01:51:58 volumio volumio[1062]: at Config.set (/volumio/node_modules/v-conf/index.js:162:20) Sep 01 01:51:58 volumio volumio[1062]: at volumioAppearance.setLanguage (/volumio/app/plugins/miscellanea/appearance/index.js:326:35) Sep 01 01:51:58 volumio volumio[1062]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Sep 01 01:51:58 volumio volumio[1062]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1674:37) Sep 01 01:51:58 volumio volumio[1062]: at Socket.emit (events.js:315:20) Sep 01 01:51:58 volumio volumio[1062]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 01 01:51:58 volumio volumio[1062]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 01 01:51:58 volumio volumio[1062]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:51:58 volumio sudo[7938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 01:50 Sep 01 01:51:58 volumio sudo[7938]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"