-- Logs begin at Wed 2024-05-01 17:29:56 SAST, end at Wed 2024-05-01 17:39:45 SAST. -- May 01 17:38:08 volumio volumio[892]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 01 17:38:08 volumio volumio[892]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip May 01 17:38:08 volumio volumio[892]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip May 01 17:38:08 volumio volumio[892]: info: Folder /tmp/plugins removed May 01 17:38:08 volumio volumio[892]: info: Check plugin dependencies May 01 17:38:08 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 17:38:08 volumio volumio[892]: info: Checking if plugin already exists May 01 17:38:08 volumio volumio[892]: info: Rename folder May 01 17:38:08 volumio volumio[892]: info: Folder /tmp/downloaded_plugin.zip removed May 01 17:38:08 volumio volumio[892]: info: Move to category May 01 17:38:10 volumio volumio[892]: info: Checking if install.sh is present May 01 17:38:10 volumio volumio[892]: info: Executing install.sh May 01 17:38:10 volumio sudo[2927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/peppy_screensaver/install.sh May 01 17:38:10 volumio sudo[2927]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:38:10 volumio volumio[892]: info: Installing peppy-screensaver Dependencies May 01 17:38:10 volumio volumio[892]: info: ___peppyalsa already installed May 01 17:38:10 volumio volumio[892]: info: ___commandline tool already compiled May 01 17:38:10 volumio volumio[892]: info: ___Install PeppyMeter... May 01 17:38:15 volumio sudo[2963]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown volumio /data/plugins/user_interface/peppy_screensaver/peppymeter May 01 17:38:15 volumio sudo[2963]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:38:15 volumio sudo[2963]: pam_unix(sudo:session): session closed for user root May 01 17:38:15 volumio sudo[2965]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chgrp volumio /data/plugins/user_interface/peppy_screensaver/peppymeter May 01 17:38:15 volumio sudo[2965]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:38:15 volumio sudo[2965]: pam_unix(sudo:session): session closed for user root May 01 17:38:15 volumio sudo[2969]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/user_interface/peppy_screensaver/peppymeter/run_peppymeter.sh May 01 17:38:15 volumio sudo[2969]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:38:15 volumio sudo[2969]: pam_unix(sudo:session): session closed for user root May 01 17:38:15 volumio sudo[2982]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /data/INTERNAL/peppy_screensaver/templates May 01 17:38:15 volumio sudo[2982]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:38:15 volumio sudo[2982]: pam_unix(sudo:session): session closed for user root May 01 17:38:16 volumio volumio[892]: info: ___Python pygame already installed May 01 17:38:16 volumio volumio[892]: info: ___Install python socket-IO... May 01 17:38:16 volumio sudo[2992]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/socketIO.tar.gz -C /usr/local/lib/python3.7/dist-packages May 01 17:38:16 volumio sudo[2992]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:38:16 volumio sudo[2992]: pam_unix(sudo:session): session closed for user root May 01 17:38:16 volumio volumio[892]: info: ___Install python cairoSVG... May 01 17:38:16 volumio sudo[3001]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/cairosvg.tar.gz -C /usr/local/lib/python3.7/dist-packages May 01 17:38:16 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:38:16 volumio sudo[3001]: pam_unix(sudo:session): session closed for user root May 01 17:38:16 volumio sudo[3004]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rd /data/plugins/user_interface/peppy_screensaver/dependencies May 01 17:38:16 volumio sudo[3004]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:38:16 volumio sudo[3004]: pam_unix(sudo:session): session closed for user root May 01 17:38:16 volumio volumio[892]: info: ___Modify PeppyMeter config for Volumio... May 01 17:38:16 volumio sudo[2927]: pam_unix(sudo:session): session closed for user root May 01 17:38:16 volumio volumio[892]: info: ___Finished May 01 17:38:16 volumio volumio[892]: Plugin install end detected on script May 01 17:38:16 volumio volumio[892]: info: Install script completed May 01 17:38:16 volumio volumio[892]: info: Adding reference to registry May 01 17:38:16 volumio volumio[892]: info: Done installing plugin. May 01 17:38:16 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 17:38:16 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 01 17:38:16 volumio volumio[892]: info: Folder /tmp/plugins removed May 01 17:38:16 volumio volumio[892]: info: Folder /tmp/downloaded_plugin.zip removed May 01 17:38:16 volumio volumio[892]: info: Folder /data/temp removed May 01 17:38:16 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:38:16 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:38:17 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:38:17 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:38:17 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:38:30 volumio sshd[2793]: pam_unix(sshd:session): session closed for user volumio May 01 17:38:30 volumio sshd[2648]: pam_unix(sshd:session): session closed for user volumio May 01 17:38:30 volumio sshd[2792]: pam_unix(sshd:session): session closed for user volumio May 01 17:38:30 volumio systemd[1]: session-3.scope: Succeeded. May 01 17:38:30 volumio systemd[1]: session-4.scope: Succeeded. May 01 17:38:30 volumio systemd[1]: session-5.scope: Succeeded. May 01 17:38:30 volumio systemd-logind[563]: Session 4 logged out. Waiting for processes to exit. May 01 17:38:30 volumio systemd-logind[563]: Session 3 logged out. Waiting for processes to exit. May 01 17:38:30 volumio systemd-logind[563]: Session 5 logged out. Waiting for processes to exit. May 01 17:38:30 volumio systemd-logind[563]: Removed session 3. May 01 17:38:30 volumio systemd-logind[563]: Removed session 4. May 01 17:38:30 volumio systemd-logind[563]: Removed session 5. May 01 17:38:49 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 01 17:38:49 volumio volumio[892]: info: Preload queue cleared May 01 17:38:51 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 01 17:38:51 volumio volumio[892]: info: Preload queue cleared May 01 17:38:52 volumio volumio[892]: info: Preload queue cleared May 01 17:38:52 volumio volumio[892]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::ClearQueue May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::stop May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::stPlaybackTimer May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock May 01 17:38:52 volumio volumio[892]: info: CorePlayQueue::getTrackBlock May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::pushState May 01 17:38:52 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:38:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:38:52 volumio volumio[892]: info: CoreCommandRouter::volumioPushState May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::serviceStop May 01 17:38:52 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:38:52 volumio volumio[892]: info: CoreCommandRouter::serviceStop May 01 17:38:52 volumio volumio[892]: info: [1714577932426] ControllerWebradio::stop May 01 17:38:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop May 01 17:38:52 volumio volumio[892]: info: CorePlayQueue::clearPlayQueue May 01 17:38:52 volumio volumio[892]: info: CorePlayQueue::saveQueue May 01 17:38:52 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::addQueueItems May 01 17:38:52 volumio volumio[892]: info: CorePlayQueue::addQueueItems May 01 17:38:52 volumio volumio[892]: info: Preload queue cleared May 01 17:38:52 volumio volumio[892]: info: Adding Item to queue: http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084 May 01 17:38:52 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 01 17:38:52 volumio volumio[892]: info: CoreCommandRouter::volumioPushQueue May 01 17:38:52 volumio volumio[892]: info: CorePlayQueue::saveQueue May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::updateTrackBlock May 01 17:38:52 volumio volumio[892]: info: CorePlayQueue::getTrackBlock May 01 17:38:52 volumio volumio[892]: info: CoreCommandRouter::volumioPlay May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::play index 0 May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::stop May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::play index undefined May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 17:38:52 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:38:52 volumio volumio[892]: info: CoreStateMachine::startPlaybackTimer May 01 17:38:52 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:38:52 volumio volumio[892]: info: [1714577932462] ControllerWebradio::clearAddPlayTrack May 01 17:38:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand stop May 01 17:38:52 volumio volumio[892]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 01 17:38:52 volumio volumio[892]: info: May 01 17:38:52 volumio volumio[892]: ---------------------------- MPD announces state update: player May 01 17:38:52 volumio volumio[892]: info: sendMpdCommand stop took 50 milliseconds May 01 17:38:52 volumio volumio[892]: info: ControllerMpd::getState May 01 17:38:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status May 01 17:38:52 volumio volumio[892]: info: sendMpdCommand stop took 16 milliseconds May 01 17:38:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand clear May 01 17:38:52 volumio volumio[892]: info: May 01 17:38:52 volumio volumio[892]: ---------------------------- MPD announces system playlist update May 01 17:38:52 volumio volumio[892]: info: Ignoring MPD Status Update May 01 17:38:52 volumio volumio[892]: info: sendMpdCommand status took 3 milliseconds May 01 17:38:52 volumio volumio[892]: info: sendMpdCommand clear took 2 milliseconds May 01 17:38:52 volumio volumio[892]: verbose: ControllerMpd::parseState May 01 17:38:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 17:38:52 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand load "http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084" May 01 17:38:52 volumio volumio[892]: error: updateQueue error: null May 01 17:38:52 volumio volumio[892]: info: ------------------------------ 41ms May 01 17:38:52 volumio volumio[892]: info: sendMpdCommand playlistinfo took 40 milliseconds May 01 17:38:52 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo May 01 17:38:52 volumio volumio[892]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 01 17:38:52 volumio volumio[892]: info: ------------------------------ 49ms May 01 17:38:52 volumio volumio[892]: info: touch_display: Setting screensaver timeout to 180 seconds. May 01 17:38:53 volumio volumio[892]: info: camilladsp spawned new process with pid 3140, instance 1, run: true May 01 17:38:53 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand add "http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084" May 01 17:38:53 volumio volumio[892]: info: May 01 17:38:53 volumio volumio[892]: ---------------------------- MPD announces system playlist update May 01 17:38:53 volumio volumio[892]: info: Ignoring MPD Status Update May 01 17:38:53 volumio volumio[892]: info: sendMpdCommand add "http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084" took 1 milliseconds May 01 17:38:53 volumio volumio[892]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 17:38:53 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand play May 01 17:38:53 volumio volumio[892]: info: ------------------------------ 4ms May 01 17:38:53 volumio volumio[892]: info: sendMpdCommand play took 3 milliseconds May 01 17:38:53 volumio volumio[892]: info: May 01 17:38:53 volumio volumio[892]: ---------------------------- MPD announces state update: player May 01 17:38:53 volumio volumio[892]: info: ControllerMpd::getState May 01 17:38:53 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status May 01 17:38:54 volumio volumio[892]: info: FusionDsp - ---- read samplerate, raw: 24000,S32_LE,2,32 May 01 17:38:54 volumio volumio[892]: info: FusionDsp - ---- read samplerate from file: 24000 May 01 17:38:54 volumio volumio[892]: info: camilladsp stopping service pid 3140... May 01 17:38:54 volumio volumio[892]: info: camilladsp service terminated, instance 1 May 01 17:38:54 volumio volumio[892]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 01 17:38:54 volumio volumio[892]: info: camilladsp service started and running in background, instance 1 May 01 17:38:54 volumio volumio[892]: info: FusionDsp - ---- read samplerate, raw: 24000,S32_LE,2,32 May 01 17:38:54 volumio volumio[892]: info: FusionDsp - ---- read samplerate from file: 24000 May 01 17:38:54 volumio volumio[892]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 01 17:38:54 volumio volumio[892]: info: May 01 17:38:54 volumio volumio[892]: ---------------------------- MPD announces state update: player May 01 17:38:54 volumio volumio[892]: info: sendMpdCommand status took 62 milliseconds May 01 17:38:54 volumio volumio[892]: info: ControllerMpd::getState May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::parseState May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 17:38:54 volumio volumio[892]: info: sendMpdCommand status took 2 milliseconds May 01 17:38:54 volumio volumio[892]: info: sendMpdCommand playlistinfo took 2 milliseconds May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::parseState May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo May 01 17:38:54 volumio volumio[892]: info: ControllerMpd::pushState May 01 17:38:54 volumio volumio[892]: info: CoreCommandRouter::servicePushState May 01 17:38:54 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:38:54 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";?rp_source=1&=&&___cb=948129876368084","artist":null,"album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} May 01 17:38:54 volumio volumio[892]: verbose: CURRENT POSITION 0 May 01 17:38:54 volumio volumio[892]: info: CoreStateMachine::syncState stateService play May 01 17:38:54 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus stop May 01 17:38:54 volumio volumio[892]: info: ------------------------------ 67ms May 01 17:38:54 volumio volumio[892]: error: FusionDsp - WebSocket error: [object Object] May 01 17:38:54 volumio volumio[892]: info: camilladsp spawned new process with pid 3155, instance 1, run: true May 01 17:38:54 volumio volumio[892]: info: sendMpdCommand playlistinfo took 22 milliseconds May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo May 01 17:38:54 volumio volumio[892]: info: ControllerMpd::pushState May 01 17:38:54 volumio volumio[892]: info: CoreCommandRouter::servicePushState May 01 17:38:54 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:38:54 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";?rp_source=1&=&&___cb=948129876368084","artist":null,"album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} May 01 17:38:54 volumio volumio[892]: verbose: CURRENT POSITION 0 May 01 17:38:54 volumio volumio[892]: info: CoreStateMachine::syncState stateService play May 01 17:38:54 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play May 01 17:38:54 volumio volumio[892]: info: Received an update from plugin. extracting info from payload May 01 17:38:54 volumio volumio[892]: info: CoreStateMachine::pushState May 01 17:38:54 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:38:54 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:38:54 volumio volumio[892]: info: CoreCommandRouter::volumioPushState May 01 17:38:54 volumio volumio[892]: info: CoreStateMachine::pushState May 01 17:38:54 volumio volumio[892]: info: CoreCommandRouter::volumioPushState May 01 17:38:54 volumio volumio[892]: info: ------------------------------ 56ms May 01 17:38:54 volumio volumio[892]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 01 17:38:54 volumio volumio[892]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 01 17:38:54 volumio volumio[892]: info: May 01 17:38:54 volumio volumio[892]: ---------------------------- MPD announces system playlist update May 01 17:38:54 volumio volumio[892]: info: Ignoring MPD Status Update May 01 17:38:54 volumio volumio[892]: info: May 01 17:38:54 volumio volumio[892]: ---------------------------- MPD announces state update: player May 01 17:38:54 volumio volumio[892]: info: ControllerMpd::getState May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status May 01 17:38:54 volumio volumio[892]: info: ------------------------------ 4ms May 01 17:38:54 volumio volumio[892]: info: sendMpdCommand status took 1 milliseconds May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::parseState May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 17:38:54 volumio volumio[892]: info: touch_display: Setting screensaver timeout to 0 seconds. May 01 17:38:54 volumio volumio[892]: info: sendMpdCommand playlistinfo took 2 milliseconds May 01 17:38:54 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo May 01 17:38:54 volumio volumio[892]: info: ControllerMpd::pushState May 01 17:38:54 volumio volumio[892]: info: CoreCommandRouter::servicePushState May 01 17:38:54 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:38:54 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":";?rp_source=1&=&&___cb=948129876368084","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} May 01 17:38:54 volumio volumio[892]: verbose: CURRENT POSITION 0 May 01 17:38:54 volumio volumio[892]: info: CoreStateMachine::syncState stateService play May 01 17:38:54 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play May 01 17:38:54 volumio volumio[892]: info: Received an update from plugin. extracting info from payload May 01 17:38:54 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 01 17:38:54 volumio volumio[892]: info: CoreStateMachine::pushState May 01 17:38:54 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:38:54 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:38:54 volumio volumio[892]: info: CoreCommandRouter::volumioPushState May 01 17:38:54 volumio volumio[892]: info: CoreStateMachine::pushState May 01 17:38:54 volumio volumio[892]: info: CoreCommandRouter::volumioPushState May 01 17:38:54 volumio volumio[892]: info: ------------------------------ 51ms May 01 17:38:54 volumio volumio[892]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 01 17:38:54 volumio volumio[892]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 01 17:38:55 volumio volumio[892]: info: May 01 17:38:55 volumio volumio[892]: ---------------------------- MPD announces system playlist update May 01 17:38:55 volumio volumio[892]: info: Ignoring MPD Status Update May 01 17:38:55 volumio volumio[892]: info: May 01 17:38:55 volumio volumio[892]: ---------------------------- MPD announces state update: player May 01 17:38:55 volumio volumio[892]: info: ControllerMpd::getState May 01 17:38:55 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status May 01 17:38:55 volumio volumio[892]: info: ------------------------------ 2ms May 01 17:38:55 volumio volumio[892]: info: sendMpdCommand status took 2 milliseconds May 01 17:38:55 volumio volumio[892]: verbose: ControllerMpd::parseState May 01 17:38:55 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 17:38:55 volumio volumio[892]: info: sendMpdCommand playlistinfo took 1 milliseconds May 01 17:38:55 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo May 01 17:38:55 volumio volumio[892]: info: ControllerMpd::pushState May 01 17:38:55 volumio volumio[892]: info: CoreCommandRouter::servicePushState May 01 17:38:55 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:38:55 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1553,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"The Troggs - Maybe the Madman","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} May 01 17:38:55 volumio volumio[892]: verbose: CURRENT POSITION 0 May 01 17:38:55 volumio volumio[892]: info: CoreStateMachine::syncState stateService play May 01 17:38:55 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play May 01 17:38:55 volumio volumio[892]: info: Received an update from plugin. extracting info from payload May 01 17:38:55 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 01 17:38:55 volumio volumio[892]: info: CoreStateMachine::pushState May 01 17:38:55 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:38:55 volumio volumio[892]: info: CoreCommandRouter::volumioPushState May 01 17:38:55 volumio volumio[892]: info: CoreStateMachine::pushState May 01 17:38:55 volumio volumio[892]: info: CoreCommandRouter::volumioPushState May 01 17:38:55 volumio volumio[892]: info: ------------------------------ 31ms May 01 17:38:55 volumio volumio[892]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 01 17:38:55 volumio volumio[892]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 01 17:38:57 volumio volumio[892]: info: VolumeController::SetAlsaVolume48 May 01 17:38:57 volumio volumio[892]: info: CoreStateMachine::pushState May 01 17:38:57 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:38:57 volumio volumio[892]: info: CoreCommandRouter::volumioPushState May 01 17:38:57 volumio volumio[892]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 May 01 17:38:57 volumio volumio[892]: SPOTIFY: SPOTIFY VOLUME 33 May 01 17:38:57 volumio volumio[892]: SPOTIFY: VOLUMIO VOLUME 48 May 01 17:38:57 volumio volumio[892]: SPOTIFY: DELTA VOLUME ENOUGH: true May 01 17:38:57 volumio volumio[892]: info: Setting Spotify Volume from Volumio: 48 May 01 17:38:58 volumio volumio[892]: SPOTIFY: SETTING SPOTIFY VOLUME 48 May 01 17:38:58 volumio volumio[892]: info: Sending Spotify command with payload to local API: /player/volume May 01 17:38:58 volumio go-librespot[1191]: time="2024-05-01T17:38:58+02:00" level=debug msg="update volume to 31456/65535" May 01 17:38:59 volumio go-librespot[1191]: time="2024-05-01T17:38:59+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 01 17:38:59 volumio go-librespot[1191]: time="2024-05-01T17:38:59+02:00" level=trace msg="emitting websocket event: volume" May 01 17:38:59 volumio volumio[892]: SPOTIFY: received: {"type":"volume","data":{"value":48,"max":100}} May 01 17:38:59 volumio volumio[892]: SPOTIFY: RECEIVED SPOTIFY VOLUME 48 May 01 17:39:03 volumio volumio[892]: info: CoreCommandRouter::volumioGetState May 01 17:39:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 17:39:05 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 01 17:39:05 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:05 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:05 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:05 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:08 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:26 volumio volumio[892]: info: May 01 17:39:26 volumio volumio[892]: ---------------------------- MPD announces system playlist update May 01 17:39:26 volumio volumio[892]: info: Ignoring MPD Status Update May 01 17:39:26 volumio volumio[892]: info: May 01 17:39:26 volumio volumio[892]: ---------------------------- MPD announces state update: player May 01 17:39:26 volumio volumio[892]: info: ControllerMpd::getState May 01 17:39:26 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand status May 01 17:39:26 volumio volumio[892]: info: ------------------------------ 2ms May 01 17:39:26 volumio volumio[892]: info: sendMpdCommand status took 1 milliseconds May 01 17:39:26 volumio volumio[892]: verbose: ControllerMpd::parseState May 01 17:39:26 volumio volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 17:39:26 volumio volumio[892]: info: sendMpdCommand playlistinfo took 1 milliseconds May 01 17:39:26 volumio volumio[892]: verbose: ControllerMpd::parseTrackInfo May 01 17:39:26 volumio volumio[892]: info: ControllerMpd::pushState May 01 17:39:26 volumio volumio[892]: info: CoreCommandRouter::servicePushState May 01 17:39:26 volumio volumio[892]: info: CorePlayQueue::getTrack 0 May 01 17:39:26 volumio volumio[892]: verbose: STATE SERVICE {"status":"play","position":0,"seek":33591,"duration":0,"samplerate":"24 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Radio Caroline - www.radiocaroline.co.uk (16:39)","artist":"Radio Caroline","album":null,"uri":"http://sc2.radiocaroline.net:8000/;?rp_source=1&=&&___cb=948129876368084","trackType":""} May 01 17:39:26 volumio volumio[892]: verbose: CURRENT POSITION 0 May 01 17:39:26 volumio volumio[892]: info: CoreStateMachine::syncState stateService play May 01 17:39:26 volumio volumio[892]: info: CoreStateMachine::syncState currentStatus play May 01 17:39:26 volumio volumio[892]: info: Received an update from plugin. extracting info from payload May 01 17:39:26 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 01 17:39:26 volumio volumio[892]: info: CoreStateMachine::pushState May 01 17:39:26 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:39:26 volumio volumio[892]: info: CoreCommandRouter::volumioPushState May 01 17:39:26 volumio volumio[892]: info: CoreStateMachine::pushState May 01 17:39:26 volumio volumio[892]: info: CoreCommandRouter::volumioPushState May 01 17:39:26 volumio volumio[892]: info: ------------------------------ 27ms May 01 17:39:26 volumio volumio[892]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 May 01 17:39:26 volumio volumio[892]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 May 01 17:39:28 volumio volumio[892]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] May 01 17:39:28 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq May 01 17:39:28 volumio volumio[892]: info: FusionDsp - value distance L------- 270 R 300 May 01 17:39:28 volumio volumio[892]: info: FusionDsp - lsamplerate/2 then disable it May 01 17:39:29 volumio volumio[892]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 01 17:39:29 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:29 volumio volumio[892]: info: CoreCommandRouter::Close All Modals sent May 01 17:39:32 volumio volumio[892]: info: CoreCommandRouter::volumioGetState May 01 17:39:34 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 17:39:34 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 01 17:39:34 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:34 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:34 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:34 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:43 volumio volumio[892]: info: Enabling plugin peppy_screensaver May 01 17:39:43 volumio volumio[892]: info: Loading plugin "peppy_screensaver"... May 01 17:39:43 volumio volumio[892]: error: !!!! WARNING !!!! May 01 17:39:43 volumio volumio[892]: error: The plugin user_interface/peppy_screensaver failed to load, setting it to stopped. Error: Error: Cannot find module '/data/plugins/user_interface/peppy_screensaver/node_modules/v-conf/node_modules/fs-extra/lib/index'. Please verify that the package.json has a valid "main" entry May 01 17:39:43 volumio volumio[892]: error: Stack trace: Error: Cannot find module '/data/plugins/user_interface/peppy_screensaver/node_modules/v-conf/node_modules/fs-extra/lib/index'. Please verify that the package.json has a valid "main" entry May 01 17:39:43 volumio volumio[892]: at tryPackage (internal/modules/cjs/loader.js:303:19) May 01 17:39:43 volumio volumio[892]: at Function.Module._findPath (internal/modules/cjs/loader.js:516:18) May 01 17:39:43 volumio volumio[892]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:867:27) May 01 17:39:43 volumio volumio[892]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) May 01 17:39:43 volumio volumio[892]: at Module.require (internal/modules/cjs/loader.js:952:19) May 01 17:39:43 volumio volumio[892]: at require (internal/modules/cjs/helpers.js:88:18) May 01 17:39:43 volumio volumio[892]: at Object. (/data/plugins/user_interface/peppy_screensaver/node_modules/v-conf/index.js:6:8) May 01 17:39:43 volumio volumio[892]: at Module._compile (internal/modules/cjs/loader.js:1063:30) May 01 17:39:43 volumio volumio[892]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) May 01 17:39:43 volumio volumio[892]: at Module.load (internal/modules/cjs/loader.js:928:32) May 01 17:39:43 volumio volumio[892]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) May 01 17:39:43 volumio volumio[892]: at Module.require (internal/modules/cjs/loader.js:952:19) May 01 17:39:43 volumio volumio[892]: at require (internal/modules/cjs/helpers.js:88:18) May 01 17:39:43 volumio volumio[892]: at Object. (/data/plugins/user_interface/peppy_screensaver/index.js:5:19) May 01 17:39:43 volumio volumio[892]: at Module._compile (internal/modules/cjs/loader.js:1063:30) May 01 17:39:43 volumio volumio[892]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) May 01 17:39:43 volumio volumio[892]: error: !!!! WARNING !!!! May 01 17:39:43 volumio volumio[892]: info: Preparing to generate the ALSA configuration file May 01 17:39:43 volumio volumio[892]: info: The plugin alsa_controller has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 01 17:39:43 volumio volumio[892]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 01 17:39:43 volumio volumio[892]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 01 17:39:43 volumio volumio[892]: info: Reading ALSA contributions from plugins. May 01 17:39:43 volumio volumio[892]: info: Asound.conf file written May 01 17:39:43 volumio sudo[3314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 01 17:39:43 volumio sudo[3314]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:39:43 volumio sudo[3314]: pam_unix(sudo:session): session closed for user root May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: /usr/sbin/alsactl: set_controls:1482: snd_ctl_open error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: /usr/sbin/alsactl: set_controls:1482: snd_ctl_open error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: /usr/sbin/alsactl: set_controls:1482: snd_ctl_open error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: /usr/sbin/alsactl: set_controls:1482: snd_ctl_open error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: /usr/sbin/alsactl: set_controls:1482: snd_ctl_open error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: /usr/sbin/alsactl: set_controls:1482: snd_ctl_open error: Invalid argument May 01 17:39:43 volumio volumio[892]: info: Output device has changed, restarting MPD May 01 17:39:43 volumio sudo[3320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 17:39:43 volumio sudo[3320]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:39:43 volumio sudo[3320]: pam_unix(sudo:session): session closed for user root May 01 17:39:43 volumio volumio[892]: info: Output device has changed, restarting Shairport Sync May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 17:39:43 volumio sudo[3323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 17:39:43 volumio sudo[3323]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:39:43 volumio systemd[1]: Stopping Music Player Daemon... May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: aplay: device_list:281: control open (0): Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: aplay: device_list:281: control open (1): Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: aplay: device_list:281: control open (7): Invalid argument May 01 17:39:43 volumio volumio[892]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: aplay: device_list:281: control open (0): Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: aplay: device_list:281: control open (1): Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1410:(parse_def) pcm is not a compound May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:127:5:Unexpected char May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument May 01 17:39:43 volumio volumio[892]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration May 01 17:39:43 volumio volumio[892]: aplay: device_list:281: control open (7): Invalid argument May 01 17:39:43 volumio volumio[892]: info: Done. May 01 17:39:43 volumio volumio[892]: info: MPD Permissions set May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 17:39:43 volumio volumio[892]: error: Upnp client error: Error: read ECONNRESET May 01 17:39:43 volumio volumio[892]: info: Starting Shairport Sync May 01 17:39:43 volumio systemd[1]: mpd.service: Succeeded. May 01 17:39:43 volumio systemd[1]: Stopped Music Player Daemon. May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:43 volumio sudo[3334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 17:39:43 volumio sudo[3334]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:39:43 volumio systemd[1]: Starting Music Player Daemon... May 01 17:39:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 17:39:43 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 17:39:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 17:39:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 17:39:43 volumio sudo[3334]: pam_unix(sudo:session): session closed for user root May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:43 volumio volumio[892]: info: Shairport-Sync Started May 01 17:39:43 volumio volumio[892]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 17:39:44 volumio mpd[3332]: May 01 17:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 17:39:44 volumio volumio[892]: info: camilladsp spawned new process with pid 3340, instance 1, run: true May 01 17:39:44 volumio volumio[892]: info: camilladsp spawned new process with pid 3347, instance 1, run: true May 01 17:39:44 volumio systemd[1]: Started Music Player Daemon. May 01 17:39:44 volumio sudo[3323]: pam_unix(sudo:session): session closed for user root May 01 17:39:44 volumio volumio[892]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 17:39:44 volumio volumio[892]: TypeError: Cannot read property 'printToastMessage' of null May 01 17:39:44 volumio volumio[892]: at /volumio/app/index.js:1195:32 May 01 17:39:44 volumio volumio[892]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) May 01 17:39:44 volumio volumio[892]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) May 01 17:39:44 volumio volumio[892]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1193:13) May 01 17:39:44 volumio volumio[892]: at /volumio/app/plugins/music_service/mpd/index.js:797:30 May 01 17:39:44 volumio volumio[892]: at /volumio/app/plugins/music_service/mpd/index.js:941:9 May 01 17:39:44 volumio volumio[892]: at ChildProcess.exithandler (child_process.js:299:7) May 01 17:39:44 volumio volumio[892]: at ChildProcess.emit (events.js:315:20) May 01 17:39:44 volumio volumio[892]: at maybeClose (internal/child_process.js:1048:16) May 01 17:39:44 volumio volumio[892]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) May 01 17:39:44 volumio volumio[892]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 17:39:45 volumio sudo[3366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-01 17:38 May 01 17:39:45 volumio sudo[3366]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"