-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-03-16 20:24:34 CET. -- Mar 16 20:23:00 volumio go-librespot[1234]: time="2025-03-16T20:23:00+01:00" level=debug msg="fetched chunk 18/21, size: 524288" uri="spotify:track:1jdotZBW7ybF2wf6896occ" Mar 16 20:23:05 volumio go-librespot[1234]: time="2025-03-16T20:23:05+01:00" level=trace msg="sent dealer ping" Mar 16 20:23:05 volumio go-librespot[1234]: time="2025-03-16T20:23:05+01:00" level=trace msg="received dealer pong" Mar 16 20:23:08 volumio volumio[907]: 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: 7 Mar 16 20:23:09 volumio volumio[907]: 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 Mar 16 20:23:09 volumio volumio[907]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip Mar 16 20:23:09 volumio volumio[907]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip Mar 16 20:23:09 volumio volumio[907]: info: Folder /tmp/plugins removed Mar 16 20:23:09 volumio volumio[907]: info: An error occurred installing the plugin. Rolling back config Mar 16 20:23:09 volumio volumio[907]: info: Plugin folders cleanup Mar 16 20:23:09 volumio volumio[907]: info: Scanning into folder /volumio/app/plugins/ Mar 16 20:23:09 volumio volumio[907]: info: Scanning category audio_interface Mar 16 20:23:09 volumio volumio[907]: info: Scanning category miscellanea Mar 16 20:23:09 volumio volumio[907]: info: Scanning category music_service Mar 16 20:23:09 volumio volumio[907]: info: Scanning category plugins.json Mar 16 20:23:09 volumio volumio[907]: info: Scanning category system_controller Mar 16 20:23:09 volumio volumio[907]: info: Scanning category user_interface Mar 16 20:23:09 volumio volumio[907]: info: Scanning into folder /data/plugins/ Mar 16 20:23:09 volumio volumio[907]: info: Scanning category music_service Mar 16 20:23:09 volumio volumio[907]: info: Scanning category user_interface Mar 16 20:23:09 volumio volumio[907]: info: Plugin folders cleanup completed Mar 16 20:23:09 volumio volumio[907]: info: Error: Error Mar 16 20:23:09 volumio volumio[907]: info: Folder /tmp/plugins removed Mar 16 20:23:09 volumio volumio[907]: info: Folder /tmp/downloaded_plugin.zip removed Mar 16 20:23:09 volumio volumio[907]: info: Folder /data/temp removed Mar 16 20:23:09 volumio volumio[907]: info: Folder /tmp/downloaded_plugin.zip removed Mar 16 20:23:11 volumio go-librespot[1234]: time="2025-03-16T20:23:11+01:00" level=debug msg="fetched chunk 19/21, size: 524288" uri="spotify:track:1jdotZBW7ybF2wf6896occ" Mar 16 20:23:24 volumio go-librespot[1234]: time="2025-03-16T20:23:24+01:00" level=debug msg="fetched chunk 20/21, size: 524288" uri="spotify:track:1jdotZBW7ybF2wf6896occ" Mar 16 20:23:34 volumio sudo[9632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 20:23:34 volumio sudo[9634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 20:23:34 volumio sudo[9632]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:23:34 volumio sudo[9634]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:23:34 volumio sudo[9634]: pam_unix(sudo:session): session closed for user root Mar 16 20:23:34 volumio sudo[9632]: pam_unix(sudo:session): session closed for user root Mar 16 20:23:34 volumio volumio[907]: verbose: New Socket.io Connection to 192.168.1.37 from 192.168.1.168 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 8 Mar 16 20:23:35 volumio sudo[9638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 20:23:35 volumio sudo[9638]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:23:35 volumio sudo[9638]: pam_unix(sudo:session): session closed for user root Mar 16 20:23:35 volumio sudo[9640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 20:23:35 volumio sudo[9640]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:23:35 volumio sudo[9640]: pam_unix(sudo:session): session closed for user root Mar 16 20:23:35 volumio volumio[907]: verbose: New Socket.io Connection to 192.168.1.37 from 192.168.1.168 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 9 Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::volumioGetVisibleSources Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 16 20:23:35 volumio volumio[907]: info: Received Get System Info Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 20:23:35 volumio volumio[907]: info: Discovery: Getting this device information Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Mar 16 20:23:35 volumio volumio[907]: info: Listing playlists Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 16 20:23:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 16 20:23:35 volumio go-librespot[1234]: time="2025-03-16T20:23:35+01:00" level=trace msg="sent dealer ping" Mar 16 20:23:35 volumio go-librespot[1234]: time="2025-03-16T20:23:35+01:00" level=trace msg="received dealer pong" Mar 16 20:23:36 volumio go-librespot[1234]: time="2025-03-16T20:23:36+01:00" level=debug msg="fetched chunk 21/21, size: 42216" uri="spotify:track:1jdotZBW7ybF2wf6896occ" Mar 16 20:23:36 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Mar 16 20:23:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 16 20:23:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 16 20:23:36 volumio volumio[907]: info: Received Get System Info Mar 16 20:23:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 20:23:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 20:23:36 volumio volumio[907]: info: Discovery: Getting this device information Mar 16 20:23:36 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Mar 16 20:23:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 20:23:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 16 20:23:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 16 20:23:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 16 20:23:38 volumio volumio[907]: info: Received Get System Info Mar 16 20:23:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 20:23:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 20:23:38 volumio volumio[907]: info: Discovery: Getting this device information Mar 16 20:23:38 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Mar 16 20:23:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 20:23:44 volumio go-librespot[1234]: time="2025-03-16T20:23:44+01:00" level=debug msg="prefetching next track" uri="spotify:track:1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:23:45 volumio go-librespot[1234]: time="2025-03-16T20:23:45+01:00" level=debug msg="selected format OGG_VORBIS_320 (14695706e36571ebe58a1915361024ac2d120ff6)" uri="spotify:track:1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:23:45 volumio go-librespot[1234]: time="2025-03-16T20:23:45+01:00" level=debug msg="requested aes key for file 14695706e36571ebe58a1915361024ac2d120ff6, gid: 1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:23:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 16 20:23:45 volumio go-librespot[1234]: time="2025-03-16T20:23:45+01:00" level=debug msg="fetched first chunk of 23, total size is 11944928 bytes" uri="spotify:track:1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:23:45 volumio go-librespot[1234]: time="2025-03-16T20:23:45+01:00" level=info msg="prefetched track \"Sei La Piu Bella Del Mondo\" (duration: 284866ms)" uri="spotify:track:1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:23:45 volumio go-librespot[1234]: time="2025-03-16T20:23:45+01:00" level=debug msg="fetched chunk 1/22, size: 524288" uri="spotify:track:1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:23:45 volumio go-librespot[1234]: time="2025-03-16T20:23:45+01:00" level=debug msg="fetched chunk 3/22, size: 524288" uri="spotify:track:1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:23:45 volumio go-librespot[1234]: time="2025-03-16T20:23:45+01:00" level=debug msg="fetched chunk 2/22, size: 524288" uri="spotify:track:1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:23:51 volumio volumio[907]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppymeterbasic/volumio/buster/armhf Mar 16 20:23:53 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 16 20:23:53 volumio volumio[907]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 16 20:23:53 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 16 20:23:53 volumio volumio[907]: info: Received Get System Version Mar 16 20:23:53 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 20:23:53 volumio volumio[907]: info: Received Get System Info Mar 16 20:23:53 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 20:23:53 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 20:23:53 volumio volumio[907]: info: Discovery: Getting this device information Mar 16 20:23:53 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Mar 16 20:23:53 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 20:23:53 volumio volumio[907]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppymeterbasic/volumio/buster/armhf Mar 16 20:23:53 volumio volumio[907]: info: Folder /tmp/plugins removed Mar 16 20:23:54 volumio volumio[907]: info: Check plugin dependencies Mar 16 20:23:54 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 20:23:54 volumio volumio[907]: info: Checking if plugin already exists Mar 16 20:23:54 volumio volumio[907]: info: Rename folder Mar 16 20:23:54 volumio volumio[907]: info: Folder /tmp/downloaded_plugin.zip removed Mar 16 20:23:54 volumio volumio[907]: info: Move to category Mar 16 20:23:59 volumio volumio[907]: info: Checking if install.sh is present Mar 16 20:23:59 volumio volumio[907]: info: Executing install.sh Mar 16 20:23:59 volumio sudo[9689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/peppymeterbasic/install.sh Mar 16 20:23:59 volumio sudo[9689]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:23:59 volumio volumio[907]: info: Installing peppyalsa plugin dependencies Mar 16 20:23:59 volumio sudo[9693]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update Mar 16 20:23:59 volumio sudo[9693]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:23:59 volumio volumio[907]: info: Hit:1 https://deb.nodesource.com/node_14.x buster InRelease Mar 16 20:24:00 volumio volumio[907]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease Mar 16 20:24:04 volumio volumio[907]: info: Hit:3 http://raspbian.raspberrypi.org/raspbian buster InRelease Mar 16 20:24:05 volumio go-librespot[1234]: time="2025-03-16T20:24:05+01:00" level=trace msg="received accesspoint ping" Mar 16 20:24:05 volumio go-librespot[1234]: time="2025-03-16T20:24:05+01:00" level=trace msg="received accesspoint pong ack" Mar 16 20:24:05 volumio go-librespot[1234]: time="2025-03-16T20:24:05+01:00" level=trace msg="sent dealer ping" Mar 16 20:24:05 volumio go-librespot[1234]: time="2025-03-16T20:24:05+01:00" level=trace msg="received dealer pong" Mar 16 20:24:13 volumio volumio[907]: info: Reading package lists... Mar 16 20:24:13 volumio sudo[9693]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:13 volumio volumio[907]: info: cloning peppymeter repo Mar 16 20:24:14 volumio go-librespot[1234]: time="2025-03-16T20:24:14+01:00" level=trace msg="emitting websocket event: not_playing" Mar 16 20:24:14 volumio go-librespot[1234]: time="2025-03-16T20:24:14+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:24:14 volumio volumio[907]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:1jdotZBW7ybF2wf6896occ","play_origin":"playlist"}} Mar 16 20:24:14 volumio volumio[907]: error: Failed to decode event: not_playing Mar 16 20:24:14 volumio go-librespot[1234]: time="2025-03-16T20:24:14+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 16 20:24:14 volumio go-librespot[1234]: time="2025-03-16T20:24:14+01:00" level=trace msg="emitting websocket event: will_play" Mar 16 20:24:14 volumio go-librespot[1234]: time="2025-03-16T20:24:14+01:00" level=info msg="loaded track \"Sei La Piu Bella Del Mondo\" (paused: false, position: 0ms, duration: 284866ms, prefetched: true)" uri="spotify:track:1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:24:14 volumio volumio[907]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1LyqfRdWZnKHtpg4jM2xrL","play_origin":"playlist"}} Mar 16 20:24:14 volumio go-librespot[1234]: time="2025-03-16T20:24:14+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 16 20:24:14 volumio go-librespot[1234]: time="2025-03-16T20:24:14+01:00" level=trace msg="scheduling prefetch in 254s" Mar 16 20:24:14 volumio go-librespot[1234]: time="2025-03-16T20:24:14+01:00" level=trace msg="emitting websocket event: metadata" Mar 16 20:24:14 volumio volumio[907]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1LyqfRdWZnKHtpg4jM2xrL","name":"Sei La Piu Bella Del Mondo","artist_names":["Raf"],"album_name":"Manifesto","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d8ec6dda8c89178d7836e2ac","position":0,"duration":284866,"release_date":"year:1995 month:6 day:8","track_number":1,"disc_number":1}} Mar 16 20:24:14 volumio go-librespot[1234]: time="2025-03-16T20:24:14+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 16 20:24:14 volumio go-librespot[1234]: time="2025-03-16T20:24:14+01:00" level=trace msg="emitting websocket event: playing" Mar 16 20:24:14 volumio volumio[907]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1LyqfRdWZnKHtpg4jM2xrL","play_origin":"playlist"}} Mar 16 20:24:14 volumio volumio[907]: SPOTIFY: PUSH STATE SPOTIFY Mar 16 20:24:14 volumio volumio[907]: SPOTIFY: {"status":"play","service":"spop","title":"Sei La Piu Bella Del Mondo","artist":"Raf","album":"Manifesto","albumart":"https://i.scdn.co/image/ab67616d00001e02d8ec6dda8c89178d7836e2ac","uri":"spotify:track:1LyqfRdWZnKHtpg4jM2xrL","trackType":"spotify","seek":0,"duration":284,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Mar 16 20:24:14 volumio volumio[907]: info: CoreCommandRouter::servicePushState Mar 16 20:24:14 volumio volumio[907]: info: CoreStateMachine::pushState Mar 16 20:24:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 20:24:14 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Mar 16 20:24:14 volumio volumio[907]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 20:24:15 volumio volumio[907]: SPOTIFY: PUSH STATE SPOTIFY Mar 16 20:24:15 volumio volumio[907]: SPOTIFY: {"status":"play","service":"spop","title":"Sei La Piu Bella Del Mondo","artist":"Raf","album":"Manifesto","albumart":"https://i.scdn.co/image/ab67616d00001e02d8ec6dda8c89178d7836e2ac","uri":"spotify:track:1LyqfRdWZnKHtpg4jM2xrL","trackType":"spotify","seek":0,"duration":284,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Mar 16 20:24:15 volumio volumio[907]: info: CoreCommandRouter::servicePushState Mar 16 20:24:15 volumio volumio[907]: info: CoreStateMachine::pushState Mar 16 20:24:15 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Mar 16 20:24:15 volumio volumio[907]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 16 20:24:20 volumio sudo[9984]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown -R volumio /data/plugins/user_interface/peppymeterbasic /data/INTERNAL/PeppyMeterBasic/Templates Mar 16 20:24:20 volumio sudo[9984]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:24:20 volumio sudo[9984]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:20 volumio sudo[9987]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chgrp -R volumio /data/plugins/user_interface/peppymeterbasic /data/INTERNAL/PeppyMeterBasic/Templates Mar 16 20:24:20 volumio sudo[9987]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:24:20 volumio sudo[9987]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:20 volumio volumio[907]: info: installing apt packages Mar 16 20:24:20 volumio sudo[9989]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install python3-pygame python3-pip python3-dev libjpeg-dev zlib1g-dev Mar 16 20:24:20 volumio sudo[9989]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:24:21 volumio volumio[907]: info: Reading package lists... Mar 16 20:24:21 volumio volumio[907]: info: Building dependency tree... Mar 16 20:24:21 volumio volumio[907]: info: Reading state information... Mar 16 20:24:23 volumio volumio[907]: info: zlib1g-dev is already the newest version (1:1.2.11.dfsg-1+deb10u2). Mar 16 20:24:23 volumio volumio[907]: info: zlib1g-dev set to manually installed. Mar 16 20:24:23 volumio volumio[907]: info: The following additional packages will be installed: Mar 16 20:24:23 volumio volumio[907]: info: build-essential bzip2 dh-python dpkg-dev fakeroot fonts-freefont-ttf g++ Mar 16 20:24:23 volumio volumio[907]: info: g++-8 gcc gcc-8 libalgorithm-diff-perl libalgorithm-diff-xs-perl Mar 16 20:24:23 volumio volumio[907]: info: libalgorithm-merge-perl libasan5 libblas3 libcc1-0 libexpat1-dev libfakeroot Mar 16 20:24:23 volumio volumio[907]: info: libgcc-8-dev libgfortran5 libjpeg62-turbo-dev liblapack3 libportmidi0 Mar 16 20:24:23 volumio volumio[907]: info: libpython3-dev libpython3.7 libpython3.7-dev libsdl-image1.2 libsdl-mixer1.2 Mar 16 20:24:23 volumio volumio[907]: info: libsdl-ttf2.0-0 libsdl1.2debian libstdc++-8-dev libubsan1 make patch Mar 16 20:24:23 volumio volumio[907]: info: python-pip-whl python3-asn1crypto python3-cffi-backend python3-crypto Mar 16 20:24:23 volumio volumio[907]: info: python3-cryptography python3-dbus python3-entrypoints python3-gi Mar 16 20:24:23 volumio volumio[907]: info: python3-keyring python3-keyrings.alt python3-numpy python3-secretstorage Mar 16 20:24:23 volumio volumio[907]: info: python3-setuptools python3-wheel python3-xdg python3.7-dev Mar 16 20:24:23 volumio volumio[907]: info: timgm6mb-soundfont Mar 16 20:24:23 volumio volumio[907]: info: Suggested packages: Mar 16 20:24:23 volumio volumio[907]: info: bzip2-doc debian-keyring gcc-8-doc libstdc++6-8-dbg gcc-multilib autoconf Mar 16 20:24:23 volumio volumio[907]: info: automake libtool flex bison gdb gcc-doc gcc-8-locales libgcc1-dbg Mar 16 20:24:23 volumio volumio[907]: info: libgomp1-dbg libitm1-dbg libatomic1-dbg libasan5-dbg liblsan0-dbg Mar 16 20:24:23 volumio volumio[907]: info: libtsan0-dbg libubsan1-dbg libmpx2-dbg libquadmath0-dbg libstdc++-8-doc Mar 16 20:24:23 volumio volumio[907]: info: make-doc ed diffutils-doc python-crypto-doc python-cryptography-doc Mar 16 20:24:23 volumio volumio[907]: info: python3-cryptography-vectors python-dbus-doc python3-dbus-dbg gnome-keyring Mar 16 20:24:23 volumio volumio[907]: info: libkf5wallet-bin gir1.2-gnomekeyring-1.0 gfortran python-numpy-doc Mar 16 20:24:23 volumio volumio[907]: info: python3-pytest python3-numpy-dbg python-pygame-doc timidity Mar 16 20:24:23 volumio volumio[907]: info: python-secretstorage-doc python-setuptools-doc fluid-soundfont-gm fluidsynth Mar 16 20:24:23 volumio volumio[907]: info: musescore Mar 16 20:24:23 volumio volumio[907]: info: The following NEW packages will be installed: Mar 16 20:24:23 volumio volumio[907]: info: build-essential bzip2 dh-python dpkg-dev fakeroot fonts-freefont-ttf g++ Mar 16 20:24:23 volumio volumio[907]: info: g++-8 gcc gcc-8 libalgorithm-diff-perl libalgorithm-diff-xs-perl Mar 16 20:24:23 volumio volumio[907]: info: libalgorithm-merge-perl libasan5 libblas3 libcc1-0 libexpat1-dev libfakeroot Mar 16 20:24:23 volumio volumio[907]: info: libgcc-8-dev libgfortran5 libjpeg-dev libjpeg62-turbo-dev liblapack3 Mar 16 20:24:23 volumio volumio[907]: info: libportmidi0 libpython3-dev libpython3.7 libpython3.7-dev libsdl-image1.2 Mar 16 20:24:23 volumio volumio[907]: info: libsdl-mixer1.2 libsdl-ttf2.0-0 libsdl1.2debian libstdc++-8-dev libubsan1 Mar 16 20:24:23 volumio volumio[907]: info: make patch python-pip-whl python3-asn1crypto python3-cffi-backend Mar 16 20:24:23 volumio volumio[907]: info: python3-crypto python3-cryptography python3-dbus python3-dev Mar 16 20:24:23 volumio volumio[907]: info: python3-entrypoints python3-gi python3-keyring python3-keyrings.alt Mar 16 20:24:23 volumio volumio[907]: info: python3-numpy python3-pip python3-pygame python3-secretstorage Mar 16 20:24:23 volumio volumio[907]: info: python3-setuptools python3-wheel python3-xdg python3.7-dev Mar 16 20:24:23 volumio volumio[907]: info: timgm6mb-soundfont Mar 16 20:24:23 volumio sudo[9989]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:23 volumio sudo[10010]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 16 20:24:23 volumio sudo[10010]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:24:23 volumio systemd[1]: Reloading. Mar 16 20:24:23 volumio volumio[907]: info: 0 upgraded, 55 newly installed, 0 to remove and 2 not upgraded. Mar 16 20:24:23 volumio volumio[907]: info: Need to get 86.5 MB of archives. Mar 16 20:24:23 volumio volumio[907]: info: After this operation, 196 MB of additional disk space will be used. Mar 16 20:24:24 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 16 20:24:24 volumio sudo[10010]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:24 volumio volumio[907]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so Mar 16 20:24:24 volumio volumio[907]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so.0 Mar 16 20:24:24 volumio sudo[10028]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/user_interface/peppymeterbasic/startpeppymeterbasic.sh Mar 16 20:24:24 volumio sudo[10028]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:24:24 volumio sudo[10028]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:24 volumio sudo[9689]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:24 volumio volumio[907]: info: Install script completed Mar 16 20:24:24 volumio volumio[907]: info: Adding reference to registry Mar 16 20:24:24 volumio volumio[907]: info: Done installing plugin. Mar 16 20:24:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 16 20:24:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 16 20:24:24 volumio volumio[907]: Plugin install end detected on script Mar 16 20:24:24 volumio volumio[907]: info: Folder /tmp/plugins removed Mar 16 20:24:24 volumio volumio[907]: info: Folder /tmp/downloaded_plugin.zip removed Mar 16 20:24:24 volumio volumio[907]: info: Folder /data/temp removed Mar 16 20:24:28 volumio go-librespot[1234]: time="2025-03-16T20:24:28+01:00" level=debug msg="fetched chunk 4/22, size: 524288" uri="spotify:track:1LyqfRdWZnKHtpg4jM2xrL" Mar 16 20:24:29 volumio volumio[907]: info: Enabling plugin peppymeterbasic Mar 16 20:24:29 volumio volumio[907]: info: Loading plugin "peppymeterbasic"... Mar 16 20:24:29 volumio volumio[907]: error: !!!! WARNING !!!! Mar 16 20:24:29 volumio volumio[907]: error: The plugin user_interface/peppymeterbasic failed to load, setting it to stopped. Error: Error: Cannot find module 'universalify' Mar 16 20:24:29 volumio volumio[907]: Require stack: Mar 16 20:24:29 volumio volumio[907]: - /data/plugins/user_interface/peppymeterbasic/node_modules/fs-extra/lib/fs/index.js Mar 16 20:24:29 volumio volumio[907]: - /data/plugins/user_interface/peppymeterbasic/node_modules/fs-extra/lib/index.js Mar 16 20:24:29 volumio volumio[907]: - /data/plugins/user_interface/peppymeterbasic/index.js Mar 16 20:24:29 volumio volumio[907]: - /volumio/app/pluginmanager.js Mar 16 20:24:29 volumio volumio[907]: - /volumio/app/index.js Mar 16 20:24:29 volumio volumio[907]: - /volumio/index.js Mar 16 20:24:29 volumio volumio[907]: error: Stack trace: Error: Cannot find module 'universalify' Mar 16 20:24:29 volumio volumio[907]: Require stack: Mar 16 20:24:29 volumio volumio[907]: - /data/plugins/user_interface/peppymeterbasic/node_modules/fs-extra/lib/fs/index.js Mar 16 20:24:29 volumio volumio[907]: - /data/plugins/user_interface/peppymeterbasic/node_modules/fs-extra/lib/index.js Mar 16 20:24:29 volumio volumio[907]: - /data/plugins/user_interface/peppymeterbasic/index.js Mar 16 20:24:29 volumio volumio[907]: - /volumio/app/pluginmanager.js Mar 16 20:24:29 volumio volumio[907]: - /volumio/app/index.js Mar 16 20:24:29 volumio volumio[907]: - /volumio/index.js Mar 16 20:24:29 volumio volumio[907]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Mar 16 20:24:29 volumio volumio[907]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Mar 16 20:24:29 volumio volumio[907]: at Module.require (internal/modules/cjs/loader.js:952:19) Mar 16 20:24:29 volumio volumio[907]: at require (internal/modules/cjs/helpers.js:88:18) Mar 16 20:24:29 volumio volumio[907]: at Object. (/data/plugins/user_interface/peppymeterbasic/node_modules/fs-extra/lib/fs/index.js:3:11) Mar 16 20:24:29 volumio volumio[907]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Mar 16 20:24:29 volumio volumio[907]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Mar 16 20:24:29 volumio volumio[907]: at Module.load (internal/modules/cjs/loader.js:928:32) Mar 16 20:24:29 volumio volumio[907]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Mar 16 20:24:29 volumio volumio[907]: at Module.require (internal/modules/cjs/loader.js:952:19) Mar 16 20:24:29 volumio volumio[907]: at require (internal/modules/cjs/helpers.js:88:18) Mar 16 20:24:29 volumio volumio[907]: at Object. (/data/plugins/user_interface/peppymeterbasic/node_modules/fs-extra/lib/index.js:8:12) Mar 16 20:24:29 volumio volumio[907]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Mar 16 20:24:29 volumio volumio[907]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Mar 16 20:24:29 volumio volumio[907]: at Module.load (internal/modules/cjs/loader.js:928:32) Mar 16 20:24:29 volumio volumio[907]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Mar 16 20:24:29 volumio volumio[907]: error: !!!! WARNING !!!! Mar 16 20:24:29 volumio volumio[907]: info: Preparing to generate the ALSA configuration file Mar 16 20:24:29 volumio volumio[907]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 16 20:24:29 volumio volumio[907]: info: Reading ALSA contributions from plugins. Mar 16 20:24:29 volumio volumio[907]: info: Asound.conf file written Mar 16 20:24:29 volumio sudo[10031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 16 20:24:29 volumio sudo[10031]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:24:29 volumio sudo[10031]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:29 volumio volumio[907]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Mar 16 20:24:29 volumio volumio[907]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Mar 16 20:24:29 volumio volumio[907]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Mar 16 20:24:29 volumio volumio[907]: info: Output device has changed, restarting MPD Mar 16 20:24:29 volumio sudo[10037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 16 20:24:29 volumio volumio[907]: info: Output device has changed, restarting Shairport Sync Mar 16 20:24:29 volumio sudo[10037]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 20:24:29 volumio sudo[10037]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:29 volumio sudo[10039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 16 20:24:29 volumio sudo[10039]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:24:29 volumio systemd[1]: Stopping Music Player Daemon... Mar 16 20:24:29 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 20:24:29 volumio volumio[907]: info: Done. Mar 16 20:24:29 volumio volumio[907]: info: MPD Permissions set Mar 16 20:24:29 volumio volumio[907]: info: Starting Shairport Sync Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:24:29 volumio sudo[10046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 20:24:29 volumio sudo[10046]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 20:24:29 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 20:24:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 20:24:30 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 16 20:24:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 20:24:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 20:24:30 volumio sudo[10046]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:30 volumio volumio[907]: info: Shairport-Sync Started Mar 16 20:24:31 volumio systemd[1]: mpd.service: Succeeded. Mar 16 20:24:31 volumio systemd[1]: Stopped Music Player Daemon. Mar 16 20:24:31 volumio systemd[1]: Starting Music Player Daemon... Mar 16 20:24:31 volumio sudo[10058]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 16 20:24:31 volumio sudo[10058]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 20:24:31 volumio sudo[10058]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:33 volumio mpd[10061]: Mar 16 20:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 16 20:24:33 volumio systemd[1]: Started Music Player Daemon. Mar 16 20:24:33 volumio sudo[10039]: pam_unix(sudo:session): session closed for user root Mar 16 20:24:33 volumio volumio[907]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 20:24:33 volumio volumio[907]: TypeError: Cannot read property 'printToastMessage' of null Mar 16 20:24:33 volumio volumio[907]: at /volumio/app/index.js:1214:32 Mar 16 20:24:33 volumio volumio[907]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Mar 16 20:24:33 volumio volumio[907]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Mar 16 20:24:33 volumio volumio[907]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1212:13) Mar 16 20:24:33 volumio volumio[907]: at /volumio/app/plugins/music_service/mpd/index.js:798:30 Mar 16 20:24:33 volumio volumio[907]: at /volumio/app/plugins/music_service/mpd/index.js:942:9 Mar 16 20:24:33 volumio volumio[907]: at ChildProcess.exithandler (child_process.js:299:7) Mar 16 20:24:33 volumio volumio[907]: at ChildProcess.emit (events.js:315:20) Mar 16 20:24:33 volumio volumio[907]: at maybeClose (internal/child_process.js:1048:16) Mar 16 20:24:33 volumio volumio[907]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Mar 16 20:24:33 volumio volumio[907]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 20:24:34 volumio sudo[10089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 20:23 Mar 16 20:24:34 volumio sudo[10089]: 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="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"