-- Logs begin at Wed 2025-07-16 07:09:03 CEST, end at Wed 2025-07-16 21:05:14 CEST. -- Jul 16 21:04:52 sleepy systemd[1]: Time has been changed Jul 16 21:04:55 sleepy volumio[999]: info: Loading plugin "network"... Jul 16 21:04:55 sleepy volumio[999]: info: Refreshing Cached IP Addresses Jul 16 21:04:55 sleepy sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 16 21:04:55 sleepy volumio[999]: info: Loading plugin "services"... Jul 16 21:04:55 sleepy volumio[999]: info: Loading plugin "alsa_controller"... Jul 16 21:04:55 sleepy sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 21:04:55 sleepy sudo[1026]: pam_unix(sudo:session): session closed for user root Jul 16 21:04:55 sleepy sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 16 21:04:55 sleepy sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 21:04:55 sleepy sudo[1028]: pam_unix(sudo:session): session closed for user root Jul 16 21:04:55 sleepy volumio[999]: info: Loading plugin "wizard"... Jul 16 21:04:55 sleepy volumio[999]: info: Loading plugin "volumio_command_line_client"... Jul 16 21:04:55 sleepy volumio[999]: info: Loading plugin "upnp"... Jul 16 21:04:55 sleepy volumio[999]: info: [1752692695698] Starting Upmpd Daemon Jul 16 21:04:55 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 16 21:04:55 sleepy volumio[999]: info: Loading plugin "my_music"... Jul 16 21:04:55 sleepy volumio[999]: info: Loading plugin "mpd"... Jul 16 21:04:56 sleepy volumio[999]: info: Loading plugin "upnp_browser"... Jul 16 21:04:57 sleepy volumio[999]: info: Loading plugin "networkfs"... Jul 16 21:04:58 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 16 21:04:58 sleepy volumio[999]: info: Loading plugin "alarm-clock"... Jul 16 21:04:58 sleepy sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.143/raid /mnt/NAS/motaro Jul 16 21:04:58 sleepy sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 21:04:58 sleepy kernel: FS-Cache: Netfs 'cifs' registered for caching Jul 16 21:04:58 sleepy kernel: Key type cifs.spnego registered Jul 16 21:04:58 sleepy kernel: Key type cifs.idmap registered Jul 16 21:04:58 sleepy kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Jul 16 21:04:58 sleepy sudo[1052]: pam_unix(sudo:session): session closed for user root Jul 16 21:04:58 sleepy volumio[999]: info: Loading plugin "airplay_emulation"... Jul 16 21:04:58 sleepy volumio[999]: info: Starting Shairport Sync Jul 16 21:04:58 sleepy volumio[999]: info: Loading plugin "last_100"... Jul 16 21:04:58 sleepy volumio[999]: info: Loading plugin "webradio"... Jul 16 21:04:59 sleepy volumio[999]: info: Loading plugin "i2s_dacs"... Jul 16 21:04:59 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 21:04:59 sleepy volumio[999]: info: Loading plugin "volumiodiscovery"... Jul 16 21:04:59 sleepy volumio[999]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 16 21:04:59 sleepy node[999]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 16 21:04:59 sleepy node[999]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 16 21:04:59 sleepy node[999]: *** WARNING *** For more information see Jul 16 21:04:59 sleepy node[999]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 16 21:04:59 sleepy node[999]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 16 21:04:59 sleepy node[999]: *** WARNING *** For more information see Jul 16 21:04:59 sleepy volumio[999]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 16 21:04:59 sleepy volumio[999]: *** WARNING *** For more information see Jul 16 21:04:59 sleepy volumio[999]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 16 21:04:59 sleepy volumio[999]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 16 21:04:59 sleepy volumio[999]: *** WARNING *** For more information see Jul 16 21:04:59 sleepy volumio[999]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 16 21:04:59 sleepy volumio[999]: Discovery: StartAdv! undefined Jul 16 21:04:59 sleepy volumio[999]: Discovery: Started advertising... sleepy - undefined Jul 16 21:04:59 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 16 21:04:59 sleepy volumio[999]: info: Loading plugin "outputs"... Jul 16 21:04:59 sleepy volumio[999]: info: Loading plugin "albumart"... Jul 16 21:04:59 sleepy volumio[999]: info: Plugin example_plugin is not enabled Jul 16 21:04:59 sleepy volumio[999]: info: Loading plugin "inputs"... Jul 16 21:04:59 sleepy volumio[999]: info: Loading plugin "updater_comm"... Jul 16 21:04:59 sleepy volumio[999]: info: Plugin mpdemulation is not enabled Jul 16 21:04:59 sleepy volumio[999]: info: Loading plugin "rest_api"... Jul 16 21:04:59 sleepy volumio[999]: info: Loading plugin "websocket"... Jul 16 21:05:00 sleepy volumio[999]: info: Loading plugin "squeezelite"... Jul 16 21:05:00 sleepy volumio[999]: Forking 3 albumart workers Jul 16 21:05:00 sleepy volumio[999]: info: Squeezelite initiated Jul 16 21:05:00 sleepy volumio[999]: info: Loading plugin "backup_restore"... Jul 16 21:05:01 sleepy volumio[999]: Starting albumart workers Jul 16 21:05:01 sleepy volumio[999]: Starting albumart workers Jul 16 21:05:02 sleepy volumio[999]: info: ___________ START PLUGINS ___________ Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 16 21:05:02 sleepy volumio[999]: info: [1752692702083] CoreMusicLibrary::Adding element Media Servers Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 21:05:02 sleepy volumio[999]: Starting albumart workers Jul 16 21:05:02 sleepy volumio[999]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 16 21:05:02 sleepy volumio[999]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 16 21:05:02 sleepy volumio[999]: info: [1752692702433] CoreMusicLibrary::Adding element Last_100 Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 16 21:05:02 sleepy volumio[999]: info: [1752692702444] CoreMusicLibrary::Adding element Webradio Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 16 21:05:02 sleepy volumio[999]: info: ------------------------------------------- Jul 16 21:05:02 sleepy volumio[999]: info: ----- MyVolumio plugins startup ---- Jul 16 21:05:02 sleepy volumio[999]: info: ------------------------------------------- Jul 16 21:05:02 sleepy volumio[999]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 16 21:05:02 sleepy sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 16 21:05:02 sleepy sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 21:05:02 sleepy volumio[999]: info: Loading i18n strings for locale nl Jul 16 21:05:02 sleepy volumio[999]: Updating browse sources language Jul 16 21:05:02 sleepy volumio[999]: Cannot find translation for sourceMedia Servers Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 21:05:02 sleepy systemd[1]: Reloading. Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::initPlayerControls Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 21:05:02 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jul 16 21:05:02 sleepy systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Jul 16 21:05:02 sleepy systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Jul 16 21:05:02 sleepy systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Jul 16 21:05:02 sleepy systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Jul 16 21:05:03 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 21:05:03 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 21:05:03 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 21:05:03 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 21:05:03 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 21:05:03 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 21:05:03 sleepy volumio[999]: info: BOOT COMPLETED Jul 16 21:05:03 sleepy volumio[999]: [Metrics] CommandRouter: 15s 189.61ms Jul 16 21:05:03 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 21:05:03 sleepy sudo[1127]: pam_unix(sudo:session): session closed for user root Jul 16 21:05:03 sleepy kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 16 21:05:07 sleepy volumio-remote-updater[467]: [2025-07-16 21:05:07] [connect] Successful connection Jul 16 21:05:10 sleepy sshd[1174]: Accepted publickey for volumio from 192.168.1.129 port 43776 ssh2: RSA 0c:d0:64:da:c7:0b:a8:88:55:18:33:ea:6f:1d:f0:cb Jul 16 21:05:10 sleepy sshd[1174]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jul 16 21:05:10 sleepy systemd[1]: Starting user-1000.slice. Jul 16 21:05:10 sleepy systemd[1]: Created slice user-1000.slice. Jul 16 21:05:10 sleepy systemd[1]: Starting User Manager for UID 1000... Jul 16 21:05:10 sleepy systemd[1]: Starting Session c1 of user volumio. Jul 16 21:05:10 sleepy systemd-logind[479]: New session c1 of user volumio. Jul 16 21:05:10 sleepy systemd[1]: Started Session c1 of user volumio. Jul 16 21:05:10 sleepy systemd[1178]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jul 16 21:05:10 sleepy systemd[1178]: Starting Paths. Jul 16 21:05:10 sleepy systemd[1178]: Reached target Paths. Jul 16 21:05:10 sleepy systemd[1178]: Starting Timers. Jul 16 21:05:10 sleepy systemd[1178]: Reached target Timers. Jul 16 21:05:10 sleepy systemd[1178]: Starting Sockets. Jul 16 21:05:10 sleepy systemd[1178]: Reached target Sockets. Jul 16 21:05:10 sleepy systemd[1178]: Starting Basic System. Jul 16 21:05:10 sleepy systemd[1178]: Reached target Basic System. Jul 16 21:05:10 sleepy systemd[1178]: Starting Default. Jul 16 21:05:10 sleepy systemd[1178]: Reached target Default. Jul 16 21:05:10 sleepy systemd[1178]: Startup finished in 52ms. Jul 16 21:05:10 sleepy systemd[1]: Started User Manager for UID 1000. Jul 16 21:05:10 sleepy sshd[1184]: Received disconnect from 192.168.1.129: 11: disconnected by user Jul 16 21:05:10 sleepy sshd[1174]: pam_unix(sshd:session): session closed for user volumio Jul 16 21:05:11 sleepy sudo[1187]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash /mnt/NAS/motaro/scripts/current/rpi/volume.sh Jul 16 21:05:11 sleepy volumio[999]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 16 21:05:11 sleepy volumio[999]: underrun!!! (at least 2467.484 ms long) Jul 16 21:05:11 sleepy volumio[999]: info: CoreCommandRouter::Close All Modals sent Jul 16 21:05:11 sleepy volumio[999]: info: CoreCommandRouter::Close All Modals sent Jul 16 21:05:11 sleepy sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 21:05:11 sleepy volumio[999]: Express server listening on port 3000 Jul 16 21:05:11 sleepy volumio[999]: [Metrics] WebUI: 24s 415.74ms Jul 16 21:05:11 sleepy volumio[999]: Volumio Calling Home Jul 16 21:05:11 sleepy volumio[999]: info: CoreStateMachine::resetVolumioState Jul 16 21:05:11 sleepy volumio[999]: info: CoreStateMachine::getcurrentVolume Jul 16 21:05:11 sleepy volumio[999]: info: CoreCommandRouter::volumioRetrievevolume Jul 16 21:05:11 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 21:05:11 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 21:05:11 sleepy sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 16 21:05:11 sleepy sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 21:05:11 sleepy sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 16 21:05:11 sleepy sudo[1211]: pam_unix(sudo:session): session closed for user root Jul 16 21:05:11 sleepy sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 21:05:11 sleepy sudo[1213]: pam_unix(sudo:session): session closed for user root Jul 16 21:05:12 sleepy volumio[999]: info: Setting Device type: Raspberry PI Jul 16 21:05:12 sleepy volumio[999]: info: squeezelite reloaded Jul 16 21:05:12 sleepy volumio[999]: error: Cannot initialize MPD Connection: MPD is not running Jul 16 21:05:12 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 16 21:05:12 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 16 21:05:12 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 16 21:05:12 sleepy sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart squeezelite Jul 16 21:05:12 sleepy sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 21:05:12 sleepy systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jul 16 21:05:12 sleepy systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jul 16 21:05:12 sleepy kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 16 21:05:12 sleepy volumio[999]: info: VolumeController:: Volume=8 Mute =false Jul 16 21:05:12 sleepy volumio[999]: info: CoreStateMachine::pushState Jul 16 21:05:12 sleepy volumio[999]: info: CorePlayQueue::getTrack 0 Jul 16 21:05:12 sleepy volumio[999]: info: CoreCommandRouter::volumioPushState Jul 16 21:05:12 sleepy volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 21:05:12 sleepy volumio[999]: info: CoreStateMachine::updateTrackBlock Jul 16 21:05:12 sleepy volumio[999]: info: CorePlayQueue::getTrackBlock Jul 16 21:05:12 sleepy volumio[999]: info: CoreCommandRouter::volumioRetrievevolume Jul 16 21:05:12 sleepy sudo[1230]: pam_unix(sudo:session): session closed for user root Jul 16 21:05:12 sleepy volumio[999]: info: Volumio called home Jul 16 21:05:12 sleepy volumio[999]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 21:05:12 sleepy volumio[999]: TypeError: Cannot read property 'error' of undefined Jul 16 21:05:12 sleepy volumio[999]: at /volumio/app/index.js:183:22 Jul 16 21:05:12 sleepy volumio[999]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Jul 16 21:05:12 sleepy volumio[999]: at fs.js:1279:7 Jul 16 21:05:12 sleepy volumio[999]: at FSReqWrap.oncomplete (fs.js:135:15) Jul 16 21:05:12 sleepy volumio[999]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 21:05:14 sleepy sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-16 21:04 Jul 16 21:05:14 sleepy sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"