-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-05-01 12:41:46 CEST. -- May 01 12:40:28 volumio sshd[1090]: pam_unix(sshd:session): session closed for user volumio May 01 12:40:28 volumio systemd[1]: session-3.scope: Succeeded. May 01 12:40:28 volumio systemd-logind[606]: Session 3 logged out. Waiting for processes to exit. May 01 12:40:28 volumio systemd-logind[606]: Removed session 3. May 01 12:40:28 volumio sshd[1093]: pam_unix(sshd:session): session closed for user volumio May 01 12:40:28 volumio systemd-logind[606]: Session 4 logged out. Waiting for processes to exit. May 01 12:40:28 volumio systemd[1]: session-4.scope: Succeeded. May 01 12:40:28 volumio systemd-logind[606]: Removed session 4. May 01 12:40:53 volumio volumio[1062]: info: [1746096053955] [80s80s] Pushing the next song state Erasure - A Little Respect and getting next track. May 01 12:40:53 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 May 01 12:40:53 volumio volumio[1062]: info: CoreCommandRouter::servicePushState May 01 12:40:53 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 May 01 12:40:53 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music1/v4/25/ac/96/25ac96a4-e9d7-cfe1-8819-3435029cf2e6/source/600x600bb.jpg","name":"Erasure - A Little Respect","title":"A Little Respect","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"196","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 01 12:40:53 volumio volumio[1062]: verbose: CURRENT POSITION 0 May 01 12:40:53 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play May 01 12:40:53 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus play May 01 12:40:53 volumio volumio[1062]: info: Received an update from plugin. extracting info from payload May 01 12:40:53 volumio volumio[1062]: info: CoreStateMachine::pushState May 01 12:40:53 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 May 01 12:40:53 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 12:40:53 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState May 01 12:40:53 volumio volumio[1062]: info: CoreStateMachine::pushState May 01 12:40:53 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 May 01 12:40:53 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState May 01 12:40:53 volumio volumio[1062]: info: [1746096053985] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=252&count=1 May 01 12:40:54 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 May 01 12:40:54 volumio volumio[1062]: info: CorePlayQueue::getTrack 1 May 01 12:40:54 volumio volumio[1062]: info: [1746096054242] [80s80s] received new event containing 1 songs. May 01 12:40:54 volumio volumio[1062]: info: [1746096054242] [80s80s] PlayNextTrack API delay: 30 May 01 12:40:54 volumio volumio[1062]: info: [1746096054253] [80s80s] Setting timer to: 196000 milliseconds. May 01 12:41:01 volumio volumio[1062]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 01 12:41:01 volumio volumio[1062]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip May 01 12:41:01 volumio volumio[1062]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip May 01 12:41:01 volumio volumio[1062]: info: Folder /tmp/plugins removed May 01 12:41:02 volumio volumio[1062]: info: Check plugin dependencies May 01 12:41:02 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 12:41:02 volumio volumio[1062]: info: Checking if plugin already exists May 01 12:41:02 volumio volumio[1062]: info: Rename folder May 01 12:41:02 volumio volumio[1062]: info: Move to category May 01 12:41:02 volumio volumio[1062]: info: Folder /tmp/downloaded_plugin.zip removed May 01 12:41:06 volumio volumio[1062]: info: Checking if install.sh is present May 01 12:41:06 volumio volumio[1062]: info: Executing install.sh May 01 12:41:06 volumio sudo[2981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/peppy_screensaver/install.sh May 01 12:41:06 volumio sudo[2981]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:06 volumio volumio[1062]: info: Installing peppy-screensaver Dependencies May 01 12:41:06 volumio volumio[1062]: info: ___Install peppyalsa ... May 01 12:41:06 volumio sudo[3006]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/peppyalsa.tar.gz -C /usr/local/lib May 01 12:41:06 volumio sudo[3006]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:06 volumio sudo[3006]: pam_unix(sudo:session): session closed for user root May 01 12:41:06 volumio volumio[1062]: info: ___Install peppyalsa client ... May 01 12:41:06 volumio volumio[1062]: info: ___commandline tool already compiled May 01 12:41:06 volumio volumio[1062]: info: ___Install PeppyMeter... May 01 12:41:16 volumio volumio[1062]: info: ___Install PeppySpectrum... May 01 12:41:17 volumio volumio[1062]: info: ___Install python pygame... May 01 12:41:17 volumio sudo[3157]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/pygame2.tar.gz -C /usr May 01 12:41:17 volumio sudo[3157]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:18 volumio sudo[3157]: pam_unix(sudo:session): session closed for user root May 01 12:41:18 volumio volumio[1062]: info: ___Install python socket-IO... May 01 12:41:18 volumio sudo[3167]: 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 12:41:18 volumio sudo[3167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:18 volumio sudo[3167]: pam_unix(sudo:session): session closed for user root May 01 12:41:18 volumio volumio[1062]: info: ___Install python pillow... May 01 12:41:18 volumio sudo[3174]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/PIL.tar.gz -C /usr/local/lib/python3.7/dist-packages May 01 12:41:18 volumio sudo[3174]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:18 volumio sudo[3174]: pam_unix(sudo:session): session closed for user root May 01 12:41:18 volumio volumio[1062]: info: ___Install python screenshot... May 01 12:41:18 volumio sudo[3183]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/pyscreenshot.tar.gz -C /usr/local/lib/python3.7/dist-packages May 01 12:41:18 volumio sudo[3183]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:18 volumio sudo[3183]: pam_unix(sudo:session): session closed for user root May 01 12:41:18 volumio sudo[3186]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rd /data/plugins/user_interface/peppy_screensaver/dependencies May 01 12:41:18 volumio sudo[3186]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:18 volumio sudo[3186]: pam_unix(sudo:session): session closed for user root May 01 12:41:18 volumio volumio[1062]: info: ___Modify PeppyMeter config for Volumio... May 01 12:41:19 volumio volumio[1062]: info: ___Modify PeppySpectrum config for Volumio... May 01 12:41:19 volumio sudo[2981]: pam_unix(sudo:session): session closed for user root May 01 12:41:19 volumio volumio[1062]: info: ___Finished May 01 12:41:19 volumio volumio[1062]: Plugin install end detected on script May 01 12:41:19 volumio volumio[1062]: info: Install script completed May 01 12:41:19 volumio volumio[1062]: info: Adding reference to registry May 01 12:41:19 volumio volumio[1062]: info: Done installing plugin. May 01 12:41:19 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 12:41:19 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 01 12:41:19 volumio volumio[1062]: info: Folder /tmp/plugins removed May 01 12:41:19 volumio volumio[1062]: info: Folder /tmp/downloaded_plugin.zip removed May 01 12:41:19 volumio volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 12:41:19 volumio volumio[1062]: info: Folder /data/temp removed May 01 12:41:19 volumio volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 12:41:19 volumio volumio[1062]: info: FusionDsp - May 01 12:41:20 volumio volumio[1062]: info: FusionDsp - undefined May 01 12:41:20 volumio volumio[1062]: info: FusionDsp - May 01 12:41:20 volumio volumio[1062]: info: FusionDsp - undefined May 01 12:41:40 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 12:41:40 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 01 12:41:40 volumio volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 12:41:40 volumio volumio[1062]: info: FusionDsp - May 01 12:41:40 volumio volumio[1062]: info: FusionDsp - undefined May 01 12:41:45 volumio volumio[1062]: info: Enabling plugin peppy_screensaver May 01 12:41:45 volumio volumio[1062]: info: Loading plugin "peppy_screensaver"... May 01 12:41:45 volumio volumio[1062]: error: !!!! WARNING !!!! May 01 12:41:45 volumio volumio[1062]: 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/index.js' May 01 12:41:45 volumio volumio[1062]: Require stack: May 01 12:41:45 volumio volumio[1062]: - /volumio/app/pluginmanager.js May 01 12:41:45 volumio volumio[1062]: - /volumio/app/index.js May 01 12:41:45 volumio volumio[1062]: - /volumio/index.js May 01 12:41:45 volumio volumio[1062]: error: Stack trace: Error: Cannot find module '/data/plugins//user_interface/peppy_screensaver/index.js' May 01 12:41:45 volumio volumio[1062]: Require stack: May 01 12:41:45 volumio volumio[1062]: - /volumio/app/pluginmanager.js May 01 12:41:45 volumio volumio[1062]: - /volumio/app/index.js May 01 12:41:45 volumio volumio[1062]: - /volumio/index.js May 01 12:41:45 volumio volumio[1062]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) May 01 12:41:45 volumio volumio[1062]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) May 01 12:41:45 volumio volumio[1062]: at Module.require (internal/modules/cjs/loader.js:952:19) May 01 12:41:45 volumio volumio[1062]: at require (internal/modules/cjs/helpers.js:88:18) May 01 12:41:45 volumio volumio[1062]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) May 01 12:41:45 volumio volumio[1062]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 01 12:41:45 volumio volumio[1062]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 01 12:41:45 volumio volumio[1062]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 01 12:41:45 volumio volumio[1062]: error: !!!! WARNING !!!! May 01 12:41:45 volumio volumio[1062]: info: Preparing to generate the ALSA configuration file May 01 12:41:45 volumio volumio[1062]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 01 12:41:45 volumio volumio[1062]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 01 12:41:45 volumio volumio[1062]: info: Reading ALSA contributions from plugins. May 01 12:41:45 volumio volumio[1062]: info: Asound.conf file written May 01 12:41:45 volumio sudo[3309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 01 12:41:45 volumio sudo[3309]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:45 volumio sudo[3309]: pam_unix(sudo:session): session closed for user root May 01 12:41:45 volumio volumio[1062]: info: Output device has changed, restarting MPD May 01 12:41:45 volumio sudo[3314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 12:41:45 volumio sudo[3314]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:45 volumio sudo[3314]: pam_unix(sudo:session): session closed for user root May 01 12:41:45 volumio volumio[1062]: info: Output device has changed, restarting Shairport Sync May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 12:41:45 volumio sudo[3317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 12:41:45 volumio sudo[3317]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:45 volumio systemd[1]: Stopping Music Player Daemon... May 01 12:41:45 volumio volumio[1062]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 12:41:45 volumio volumio[1062]: info: Done. May 01 12:41:45 volumio systemd[1]: mpd.service: Succeeded. May 01 12:41:45 volumio systemd[1]: Stopped Music Player Daemon. May 01 12:41:45 volumio volumio[1062]: info: MPD Permissions set May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 12:41:45 volumio volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 12:41:45 volumio volumio[1062]: info: Starting Shairport Sync May 01 12:41:45 volumio volumio[1062]: error: Upnp client error: Error: This socket has been ended by the other party May 01 12:41:45 volumio volumio[1062]: info: FusionDsp - May 01 12:41:45 volumio sudo[3328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 12:41:45 volumio sudo[3328]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:45 volumio systemd[1]: Starting Music Player Daemon... May 01 12:41:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 12:41:45 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 12:41:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 12:41:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 12:41:45 volumio sudo[3328]: pam_unix(sudo:session): session closed for user root May 01 12:41:45 volumio sudo[3322]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 01 12:41:45 volumio sudo[3322]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 12:41:45 volumio sudo[3322]: pam_unix(sudo:session): session closed for user root May 01 12:41:45 volumio volumio[1062]: info: FusionDsp - undefined May 01 12:41:45 volumio volumio[1062]: info: Shairport-Sync Started May 01 12:41:46 volumio mpd[3334]: May 01 12:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 12:41:46 volumio systemd[1]: Started Music Player Daemon. May 01 12:41:46 volumio sudo[3317]: pam_unix(sudo:session): session closed for user root May 01 12:41:46 volumio volumio[1062]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 12:41:46 volumio volumio[1062]: TypeError: Cannot read property 'printToastMessage' of null May 01 12:41:46 volumio volumio[1062]: at /volumio/app/index.js:1214:32 May 01 12:41:46 volumio volumio[1062]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) May 01 12:41:46 volumio volumio[1062]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) May 01 12:41:46 volumio volumio[1062]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1212:13) May 01 12:41:46 volumio volumio[1062]: at /volumio/app/plugins/music_service/mpd/index.js:798:30 May 01 12:41:46 volumio volumio[1062]: at /volumio/app/plugins/music_service/mpd/index.js:942:9 May 01 12:41:46 volumio volumio[1062]: at ChildProcess.exithandler (child_process.js:299:7) May 01 12:41:46 volumio volumio[1062]: at ChildProcess.emit (events.js:315:20) May 01 12:41:46 volumio volumio[1062]: at maybeClose (internal/child_process.js:1048:16) May 01 12:41:46 volumio volumio[1062]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) May 01 12:41:46 volumio volumio[1062]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 12:41:46 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) May 01 12:41:46 volumio sudo[3346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 12:40 May 01 12:41:46 volumio sudo[3346]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"