Sep 13 18:37:05 volumio volumio[931]: error: error Sep 13 18:37:18 volumio volumio[931]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/bookworm/armhf Sep 13 18:37:22 volumio volumio[931]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/bookworm/armhf Sep 13 18:37:22 volumio volumio[931]: info: Folder /tmp/plugins removed Sep 13 18:37:26 volumio volumio[931]: info: Check plugin dependencies Sep 13 18:37:26 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 13 18:37:26 volumio volumio[931]: info: Checking if plugin already exists Sep 13 18:37:26 volumio volumio[931]: info: Rename folder Sep 13 18:37:26 volumio volumio[931]: info: Folder /tmp/downloaded_plugin.zip removed Sep 13 18:37:26 volumio volumio[931]: info: Move to category Sep 13 18:37:28 volumio volumio[931]: info: Checking if install.sh is present Sep 13 18:37:28 volumio volumio[931]: info: Executing install.sh Sep 13 18:37:28 volumio sudo[3246]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh Sep 13 18:37:28 volumio sudo[3246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 13 18:37:28 volumio volumio[931]: info: Installing geo-tz node dependency Sep 13 18:37:28 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:37:48 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:37:48 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:37:48 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:37:48 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:37:48 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:37:48 volumio volumio[931]: info: ------------------------------ 20ms Sep 13 18:37:48 volumio volumio[931]: info: sendMpdCommand status took 11 milliseconds Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:37:48 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:37:48 volumio volumio[931]: info: ------------------------------ 27ms Sep 13 18:37:48 volumio volumio[931]: info: sendMpdCommand status took 20 milliseconds Sep 13 18:37:48 volumio volumio[931]: info: ------------------------------ 22ms Sep 13 18:37:48 volumio volumio[931]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 13 18:37:48 volumio volumio[931]: info: ------------------------------ 13ms Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:37:48 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:37:48 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:37:48 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:48 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.%20I.%20Allegro%20di%20molto.flac?album=Mendelssohn:%20The%20Complete%20Symphonies&artist=New%20Philharmonia%20Orchestra&title=I.%20Allegro%20di%20molto&genre=Classical&year=2025&time=463&track=1&id=-1&cantata=song","artist":null,"album":null,"uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/New%20Philharmonia%20Orchestra%20&%20Wolfgang%20Sawallisch%20-%20Mendelssohn_%20The%20Complete%20Symphonies%20(2025)%20FLAC/01.%20I.%20Allegro%20di%20molto.flac?album=Mendelssohn:%20The%20Complete%20Symphonies&artist=New%20Philharmonia%20Orchestra&title=I.%20Allegro%20di%20molto&genre=Classical&year=2025&time=463&track=1&id=-1&cantata=song","trackType":""} Sep 13 18:37:48 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:37:48 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:37:48 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:37:48 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:37:48 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:48 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:48 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:48 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:48 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:48 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:48 volumio volumio[931]: info: ------------------------------ 112ms Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:37:48 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:37:48 volumio volumio[931]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 13 18:37:48 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:37:48 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:37:48 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:37:48 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:48 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.%20I.%20Allegro%20di%20molto.flac?album=Mendelssohn:%20The%20Complete%20Symphonies&artist=New%20Philharmonia%20Orchestra&title=I.%20Allegro%20di%20molto&genre=Classical&year=2025&time=463&track=1&id=-1&cantata=song","artist":null,"album":null,"uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/New%20Philharmonia%20Orchestra%20&%20Wolfgang%20Sawallisch%20-%20Mendelssohn_%20The%20Complete%20Symphonies%20(2025)%20FLAC/01.%20I.%20Allegro%20di%20molto.flac?album=Mendelssohn:%20The%20Complete%20Symphonies&artist=New%20Philharmonia%20Orchestra&title=I.%20Allegro%20di%20molto&genre=Classical&year=2025&time=463&track=1&id=-1&cantata=song","trackType":""} Sep 13 18:37:48 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:37:48 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:37:48 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:37:48 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:37:48 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:48 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:48 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:48 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:48 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:48 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:48 volumio volumio[931]: info: ------------------------------ 217ms Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:37:48 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:37:48 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:37:48 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:37:48 volumio volumio[931]: info: Sep 13 18:37:48 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:37:48 volumio volumio[931]: info: sendMpdCommand status took 196 milliseconds Sep 13 18:37:48 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:37:48 volumio volumio[931]: info: sendMpdCommand status took 192 milliseconds Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:37:48 volumio volumio[931]: info: ------------------------------ 91ms Sep 13 18:37:48 volumio volumio[931]: info: sendMpdCommand status took 85 milliseconds Sep 13 18:37:48 volumio volumio[931]: info: ------------------------------ 84ms Sep 13 18:37:48 volumio volumio[931]: info: sendMpdCommand status took 79 milliseconds Sep 13 18:37:48 volumio volumio[931]: info: sendMpdCommand playlistinfo took 77 milliseconds Sep 13 18:37:48 volumio volumio[931]: info: sendMpdCommand playlistinfo took 75 milliseconds Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:37:48 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:37:49 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:37:49 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:37:49 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":464,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"603 Kbps","isStreaming":false,"title":"I. Allegro di molto","artist":"New Philharmonia Orchestra","album":"Mendelssohn: The Complete Symphonies","uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/New%20Philharmonia%20Orchestra%20&%20Wolfgang%20Sawallisch%20-%20Mendelssohn_%20The%20Complete%20Symphonies%20(2025)%20FLAC/01.%20I.%20Allegro%20di%20molto.flac?album=Mendelssohn:%20The%20Complete%20Symphonies&artist=New%20Philharmonia%20Orchestra&title=I.%20Allegro%20di%20molto&genre=Classical&year=2025&time=463&track=1&id=-1&cantata=song","trackType":""} Sep 13 18:37:49 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:37:49 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:49 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":464,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"841 Kbps","isStreaming":false,"title":"I. Allegro di molto","artist":"New Philharmonia Orchestra","album":"Mendelssohn: The Complete Symphonies","uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/New%20Philharmonia%20Orchestra%20&%20Wolfgang%20Sawallisch%20-%20Mendelssohn_%20The%20Complete%20Symphonies%20(2025)%20FLAC/01.%20I.%20Allegro%20di%20molto.flac?album=Mendelssohn:%20The%20Complete%20Symphonies&artist=New%20Philharmonia%20Orchestra&title=I.%20Allegro%20di%20molto&genre=Classical&year=2025&time=463&track=1&id=-1&cantata=song","trackType":""} Sep 13 18:37:49 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:37:49 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:49 volumio volumio[931]: info: ------------------------------ 557ms Sep 13 18:37:49 volumio volumio[931]: info: ------------------------------ 552ms Sep 13 18:37:49 volumio volumio[931]: info: sendMpdCommand playlistinfo took 287 milliseconds Sep 13 18:37:49 volumio volumio[931]: info: sendMpdCommand playlistinfo took 280 milliseconds Sep 13 18:37:49 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:37:49 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:37:49 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":464,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"843 Kbps","isStreaming":false,"title":"I. Allegro di molto","artist":"New Philharmonia Orchestra","album":"Mendelssohn: The Complete Symphonies","uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/New%20Philharmonia%20Orchestra%20&%20Wolfgang%20Sawallisch%20-%20Mendelssohn_%20The%20Complete%20Symphonies%20(2025)%20FLAC/01.%20I.%20Allegro%20di%20molto.flac?album=Mendelssohn:%20The%20Complete%20Symphonies&artist=New%20Philharmonia%20Orchestra&title=I.%20Allegro%20di%20molto&genre=Classical&year=2025&time=463&track=1&id=-1&cantata=song","trackType":""} Sep 13 18:37:49 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:37:49 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:49 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":464,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"843 Kbps","isStreaming":false,"title":"I. Allegro di molto","artist":"New Philharmonia Orchestra","album":"Mendelssohn: The Complete Symphonies","uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/New%20Philharmonia%20Orchestra%20&%20Wolfgang%20Sawallisch%20-%20Mendelssohn_%20The%20Complete%20Symphonies%20(2025)%20FLAC/01.%20I.%20Allegro%20di%20molto.flac?album=Mendelssohn:%20The%20Complete%20Symphonies&artist=New%20Philharmonia%20Orchestra&title=I.%20Allegro%20di%20molto&genre=Classical&year=2025&time=463&track=1&id=-1&cantata=song","trackType":""} Sep 13 18:37:49 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:37:49 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:49 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:37:49 volumio volumio[931]: info: ------------------------------ 544ms Sep 13 18:37:49 volumio volumio[931]: info: ------------------------------ 538ms Sep 13 18:37:49 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Sep 13 18:37:49 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:37:55 volumio volumio[931]: error: error Sep 13 18:38:00 volumio volumio[931]: info: Sep 13 18:38:00 volumio volumio[931]: info: added 26 packages, changed 4 packages, and audited 166 packages in 28s Sep 13 18:38:00 volumio volumio[931]: info: Sep 13 18:38:00 volumio volumio[931]: info: 23 packages are looking for funding Sep 13 18:38:00 volumio volumio[931]: info: run `npm fund` for details Sep 13 18:38:00 volumio volumio[931]: info: Sep 13 18:38:00 volumio volumio[931]: info: 3 vulnerabilities (1 low, 2 high) Sep 13 18:38:00 volumio volumio[931]: info: Sep 13 18:38:00 volumio volumio[931]: info: To address all issues, run: Sep 13 18:38:00 volumio volumio[931]: info: npm audit fix Sep 13 18:38:00 volumio volumio[931]: info: Sep 13 18:38:00 volumio volumio[931]: info: Run `npm audit` for details. Sep 13 18:38:10 volumio ntpd[863]: PROTO: 45.13.105.44 unlink local addr 192.168.1.14 -> Sep 13 18:38:17 volumio volumio[931]: info: Sep 13 18:38:17 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:38:17 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:38:17 volumio volumio[931]: info: Sep 13 18:38:17 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:38:17 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:38:17 volumio volumio[931]: info: ------------------------------ 12ms Sep 13 18:38:17 volumio volumio[931]: info: ------------------------------ 12ms Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:38:19 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:38:19 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:38:19 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:38:19 volumio volumio[931]: error: updateQueue error: null Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:38:19 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:38:19 volumio volumio[931]: info: ------------------------------ 53ms Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand status took 53 milliseconds Sep 13 18:38:19 volumio volumio[931]: info: ------------------------------ 54ms Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand status took 44 milliseconds Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:38:19 volumio volumio[931]: info: ------------------------------ 40ms Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:38:19 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:19 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:19 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:19 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:19 volumio volumio[931]: 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 13 18:38:19 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::syncState stateService stop Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:38:19 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::stPlaybackTimer Sep 13 18:38:19 volumio volumio[931]: info: ------------------------------ 168ms Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:38:19 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:38:19 volumio volumio[931]: info: ------------------------------ 164ms Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand status took 143 milliseconds Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand status took 141 milliseconds Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand playlistinfo took 128 milliseconds Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand status took 29 milliseconds Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:38:19 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:38:19 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:19 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"T’Aimer Encore","artist":"Florent Pagny","album":"Grandeur Nature","uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/pagny/08%20-%20T%E2%80%99Aimer%20Encore.flac?album=Grandeur%20Nature&artist=Florent%20Pagny&title=T%E2%80%99Aimer%20Encore&genre=French%20Music&year=2025&time=191&track=8&id=-1&cantata=song","trackType":""} Sep 13 18:38:19 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus stop Sep 13 18:38:19 volumio volumio[931]: info: ------------------------------ 241ms Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces system playlist update Sep 13 18:38:19 volumio volumio[931]: info: Ignoring MPD Status Update Sep 13 18:38:19 volumio volumio[931]: info: Sep 13 18:38:19 volumio volumio[931]: ---------------------------- MPD announces state update: player Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::getState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Sep 13 18:38:19 volumio volumio[931]: info: ------------------------------ 111ms Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand status took 101 milliseconds Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand playlistinfo took 81 milliseconds Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand playlistinfo took 81 milliseconds Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand playlistinfo took 73 milliseconds Sep 13 18:38:19 volumio volumio[931]: info: ------------------------------ 27ms Sep 13 18:38:19 volumio volumio[931]: info: sendMpdCommand status took 18 milliseconds Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::parseState Sep 13 18:38:19 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:38:19 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:38:19 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:19 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1343 Kbps","isStreaming":false,"title":"T’Aimer Encore","artist":"Florent Pagny","album":"Grandeur Nature","uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/pagny/08%20-%20T%E2%80%99Aimer%20Encore.flac?album=Grandeur%20Nature&artist=Florent%20Pagny&title=T%E2%80%99Aimer%20Encore&genre=French%20Music&year=2025&time=191&track=8&id=-1&cantata=song","trackType":""} Sep 13 18:38:19 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:38:19 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:19 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:19 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:19 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:19 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:19 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:38:19 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:38:19 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:19 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1343 Kbps","isStreaming":false,"title":"T’Aimer Encore","artist":"Florent Pagny","album":"Grandeur Nature","uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/pagny/08%20-%20T%E2%80%99Aimer%20Encore.flac?album=Grandeur%20Nature&artist=Florent%20Pagny&title=T%E2%80%99Aimer%20Encore&genre=French%20Music&year=2025&time=191&track=8&id=-1&cantata=song","trackType":""} Sep 13 18:38:19 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:38:19 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:38:20 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:20 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1110,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1339 Kbps","isStreaming":false,"title":"T’Aimer Encore","artist":"Florent Pagny","album":"Grandeur Nature","uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/pagny/08%20-%20T%E2%80%99Aimer%20Encore.flac?album=Grandeur%20Nature&artist=Florent%20Pagny&title=T%E2%80%99Aimer%20Encore&genre=French%20Music&year=2025&time=191&track=8&id=-1&cantata=song","trackType":""} Sep 13 18:38:20 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:38:20 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:20 volumio volumio[931]: info: ------------------------------ 483ms Sep 13 18:38:20 volumio volumio[931]: info: ------------------------------ 482ms Sep 13 18:38:20 volumio volumio[931]: info: ------------------------------ 356ms Sep 13 18:38:20 volumio volumio[931]: info: sendMpdCommand playlistinfo took 225 milliseconds Sep 13 18:38:20 volumio volumio[931]: info: sendMpdCommand playlistinfo took 212 milliseconds Sep 13 18:38:20 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:38:20 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Sep 13 18:38:20 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1110,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1339 Kbps","isStreaming":false,"title":"T’Aimer Encore","artist":"Florent Pagny","album":"Grandeur Nature","uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/pagny/08%20-%20T%E2%80%99Aimer%20Encore.flac?album=Grandeur%20Nature&artist=Florent%20Pagny&title=T%E2%80%99Aimer%20Encore&genre=French%20Music&year=2025&time=191&track=8&id=-1&cantata=song","trackType":""} Sep 13 18:38:20 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:38:20 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:20 volumio volumio[931]: info: ControllerMpd::pushState Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::servicePushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1332 Kbps","isStreaming":false,"title":"T’Aimer Encore","artist":"Florent Pagny","album":"Grandeur Nature","uri":"http://192.168.1.127:44325/home/laurent/T%C3%A9l%C3%A9chargements/pagny/08%20-%20T%E2%80%99Aimer%20Encore.flac?album=Grandeur%20Nature&artist=Florent%20Pagny&title=T%E2%80%99Aimer%20Encore&genre=French%20Music&year=2025&time=191&track=8&id=-1&cantata=song","trackType":""} Sep 13 18:38:20 volumio volumio[931]: verbose: CURRENT POSITION 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Sep 13 18:38:20 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:20 volumio volumio[931]: info: CoreStateMachine::pushState Sep 13 18:38:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:20 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Sep 13 18:38:20 volumio volumio[931]: info: ------------------------------ 522ms Sep 13 18:38:20 volumio volumio[931]: info: ------------------------------ 432ms Sep 13 18:38:24 volumio volumio[931]: info: Sep 13 18:38:24 volumio volumio[931]: info: up to date, audited 166 packages in 13s Sep 13 18:38:24 volumio volumio[931]: info: Sep 13 18:38:24 volumio volumio[931]: info: 23 packages are looking for funding Sep 13 18:38:24 volumio volumio[931]: info: run `npm fund` for details Sep 13 18:38:24 volumio volumio[931]: info: Sep 13 18:38:24 volumio volumio[931]: info: 3 vulnerabilities (1 low, 2 high) Sep 13 18:38:24 volumio volumio[931]: info: Sep 13 18:38:24 volumio volumio[931]: info: To address all issues, run: Sep 13 18:38:24 volumio volumio[931]: info: npm audit fix Sep 13 18:38:24 volumio volumio[931]: info: Sep 13 18:38:24 volumio volumio[931]: info: Run `npm audit` for details. Sep 13 18:38:24 volumio volumio[931]: info: Creating user directories (if not exist) Sep 13 18:38:24 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 13 18:38:25 volumio sudo[3246]: pam_unix(sudo:session): session closed for user root Sep 13 18:38:25 volumio volumio[931]: info: Now Playing plugin installed Sep 13 18:38:25 volumio dbus-daemon[585]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.42' (uid=0 pid=3425 comm="timedatectl show --property=NTPSynchronized --valu") Sep 13 18:38:25 volumio volumio[931]: Plugin install end detected on script Sep 13 18:38:25 volumio volumio[931]: info: Install script completed Sep 13 18:38:25 volumio volumio[931]: info: Adding reference to registry Sep 13 18:38:25 volumio volumio[931]: info: Done installing plugin. Sep 13 18:38:25 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 13 18:38:25 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 13 18:38:25 volumio volumio[931]: info: Folder /tmp/plugins removed Sep 13 18:38:25 volumio volumio[931]: info: Folder /tmp/downloaded_plugin.zip removed Sep 13 18:38:25 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 13 18:38:25 volumio volumio[931]: info: Folder /data/temp removed Sep 13 18:38:25 volumio dbus-daemon[585]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 13 18:38:25 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 13 18:38:25 volumio setdatetime-helper.sh[3419]: Time is not synchronized. Attempting to sync... Sep 13 18:38:25 volumio sudo[3438]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 13 Sep 2025 16:38:26 GMT#015' Sep 13 18:38:25 volumio sudo[3438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 13 18:38:26 volumio setdatetime-helper.sh[3439]: Sat Sep 13 18:38:26 CEST 2025 Sep 13 18:38:26 volumio sudo[3438]: pam_unix(sudo:session): session closed for user root Sep 13 18:38:26 volumio setdatetime-helper.sh[3419]: Time synchronized successfully. Sep 13 18:38:26 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 13 18:38:26 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 13 18:38:26 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Sep 13 18:38:26 volumio volumio[931]: info: Enabling plugin now_playing Sep 13 18:38:26 volumio volumio[931]: info: Loading plugin "now_playing"... Sep 13 18:38:27 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Sep 13 18:38:27 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Sep 13 18:38:27 volumio systemd[1]: apt-daily-upgrade.service: Consumed 1.260s CPU time. Sep 13 18:38:29 volumio volumio[931]: info: PLUGIN START: now_playing Sep 13 18:38:29 volumio volumio[931]: info: [now-playing] ConfigUpdater: config version unavailable. Either this is the first time the plugin is installed, or the previous version is < 0.3.0). Config will not be updated. Sep 13 18:38:29 volumio volumio[931]: info: [now-playing] ConfigUpdater: updated config version to 1.0.2 Sep 13 18:38:29 volumio volumio[931]: info: [now-playing] App is listening on port 4004. Sep 13 18:38:29 volumio volumio[931]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Sep 13 18:38:29 volumio volumio[931]: info: Done. Sep 13 18:38:34 volumio volumio[931]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 13 18:38:34 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 18:38:34 volumio volumio[931]: info: Discovery: Getting this device information Sep 13 18:38:34 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Sep 13 18:38:34 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:34 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 18:38:43 volumio volumio[931]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.127 UA: Mozilla/5.0 (X11; Linux x86_64; rv:142.0) Gecko/20100101 Firefox/142.0 Engine version: 3 Transport: polling Total Clients: 5 Sep 13 18:38:43 volumio volumio[931]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 13 18:38:43 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 13 18:38:44 volumio volumio[931]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Sep 13 18:38:44 volumio volumio[931]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:95:19) Sep 13 18:38:44 volumio volumio[931]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:116:37) Sep 13 18:38:44 volumio volumio[931]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:51:13 Sep 13 18:38:44 volumio volumio[931]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Sep 13 18:38:44 volumio volumio[931]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:149:13) Sep 13 18:38:44 volumio volumio[931]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:119:3) Sep 13 18:38:44 volumio volumio[931]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Sep 13 18:38:44 volumio volumio[931]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Sep 13 18:38:44 volumio volumio[931]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Sep 13 18:38:44 volumio volumio[931]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Sep 13 18:38:44 volumio volumio[931]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Sep 13 18:38:44 volumio volumio[931]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Sep 13 18:38:44 volumio volumio[931]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Sep 13 18:38:44 volumio volumio[931]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Sep 13 18:38:44 volumio volumio[931]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Sep 13 18:38:44 volumio volumio[931]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Sep 13 18:38:44 volumio volumio[931]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.127 UA: Mozilla/5.0 (X11; Linux x86_64; rv:142.0) Gecko/20100101 Firefox/142.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 13 18:38:45 volumio volumio[931]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 13 18:38:45 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 13 18:38:45 volumio volumio[931]: info: Listing playlists Sep 13 18:38:45 volumio volumio[931]: info: CoreCommandRouter::volumioGetQueue Sep 13 18:38:45 volumio volumio[931]: info: CoreStateMachine::getQueue Sep 13 18:38:45 volumio volumio[931]: info: CorePlayQueue::getQueue Sep 13 18:38:45 volumio volumio[931]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 18:38:45 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:38:45 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Sep 13 18:38:45 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:45 volumio volumio[931]: error: error Sep 13 18:38:53 volumio volumio[931]: info: CoreCommandRouter::volumioToggle Sep 13 18:38:53 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:53 volumio volumio[931]: info: CoreStateMachine::play index undefined Sep 13 18:38:53 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 18:38:53 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:55 volumio volumio[931]: info: CoreCommandRouter::volumioPrevious Sep 13 18:38:55 volumio volumio[931]: info: CoreStateMachine::previous Sep 13 18:38:55 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Sep 13 18:38:55 volumio volumio[931]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 18:38:55 volumio volumio[931]: TypeError: Cannot read properties of undefined (reading 'service') Sep 13 18:38:55 volumio volumio[931]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1327:97) Sep 13 18:38:55 volumio volumio[931]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:97:28) Sep 13 18:38:55 volumio volumio[931]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:270:33) Sep 13 18:38:55 volumio volumio[931]: at Socket.emit (node:events:514:28) Sep 13 18:38:55 volumio volumio[931]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Sep 13 18:38:55 volumio volumio[931]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Sep 13 18:38:55 volumio volumio[931]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 18:38:55 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 13 18:38:56 volumio sudo[3567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-13 18:37' Sep 13 18:38:56 volumio sudo[3567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025" VOLUMIO_VERSION="4.022" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"