-- Logs begin at Sun 2025-05-11 07:44:15 CEST, end at Mon 2025-05-12 09:05:17 CEST. -- May 12 09:04:54 sleepy systemd[1]: Time has been changed May 12 09:04:54 sleepy volumio[989]: info: MYVOLUMIO Environment detected May 12 09:04:54 sleepy volumio[989]: info: Plugin folders cleanup May 12 09:04:54 sleepy volumio[989]: info: Scanning into folder /volumio/app/plugins/ May 12 09:04:54 sleepy volumio[989]: info: Scanning category audio_interface May 12 09:04:54 sleepy volumio[989]: info: Scanning category miscellanea May 12 09:04:54 sleepy volumio[989]: info: Scanning category music_service May 12 09:04:54 sleepy volumio[989]: info: Scanning category plugins.json May 12 09:04:54 sleepy volumio[989]: info: Scanning category system_controller May 12 09:04:54 sleepy volumio[989]: info: Scanning category user_interface May 12 09:04:54 sleepy volumio[989]: info: Scanning into folder /data/plugins/ May 12 09:04:54 sleepy volumio[989]: info: Scanning category music_service May 12 09:04:54 sleepy volumio[989]: info: Scanning category system_controller May 12 09:04:54 sleepy volumio[989]: info: Plugin folders cleanup completed May 12 09:04:54 sleepy volumio[989]: info: ------------------------------------------- May 12 09:04:54 sleepy volumio[989]: info: ----- Core plugins startup ---- May 12 09:04:54 sleepy volumio[989]: info: ------------------------------------------- May 12 09:04:54 sleepy volumio[989]: info: Loading plugins from folder /volumio/app/plugins/ May 12 09:04:54 sleepy volumio[989]: info: Adding plugin upnp to MyMusic Plugins May 12 09:04:54 sleepy volumio[989]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 09:04:54 sleepy volumio[989]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 09:04:54 sleepy volumio[989]: info: Loading plugins from folder /data/plugins/ May 12 09:04:54 sleepy volumio[989]: info: Loading plugin "system"... May 12 09:04:54 sleepy volumio[989]: info: Loading plugin "appearance"... May 12 09:04:59 sleepy volumio[989]: info: Loading plugin "network"... May 12 09:05:00 sleepy volumio[989]: info: Refreshing Cached IP Addresses May 12 09:05:00 sleepy sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 09:05:00 sleepy volumio[989]: info: Loading plugin "services"... May 12 09:05:00 sleepy volumio[989]: info: Loading plugin "alsa_controller"... May 12 09:05:00 sleepy sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 09:05:00 sleepy sudo[1016]: pam_unix(sudo:session): session closed for user root May 12 09:05:00 sleepy sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 09:05:00 sleepy sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 09:05:00 sleepy sudo[1019]: pam_unix(sudo:session): session closed for user root May 12 09:05:01 sleepy volumio[989]: info: Loading plugin "wizard"... May 12 09:05:01 sleepy volumio[989]: info: Loading plugin "volumio_command_line_client"... May 12 09:05:01 sleepy volumio[989]: info: Loading plugin "upnp"... May 12 09:05:01 sleepy volumio[989]: info: [1747033501941] Starting Upmpd Daemon May 12 09:05:01 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 09:05:01 sleepy volumio[989]: info: Loading plugin "my_music"... May 12 09:05:01 sleepy volumio[989]: info: Loading plugin "mpd"... May 12 09:05:02 sleepy volumio[989]: info: Loading plugin "upnp_browser"... May 12 09:05:04 sleepy volumio[989]: info: Loading plugin "networkfs"... May 12 09:05:04 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 09:05:04 sleepy volumio[989]: info: Loading plugin "alarm-clock"... May 12 09:05:04 sleepy sudo[1043]: 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 May 12 09:05:04 sleepy sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 09:05:04 sleepy kernel: FS-Cache: Netfs 'cifs' registered for caching May 12 09:05:04 sleepy kernel: Key type cifs.spnego registered May 12 09:05:04 sleepy kernel: Key type cifs.idmap registered May 12 09:05:04 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. May 12 09:05:04 sleepy sudo[1043]: pam_unix(sudo:session): session closed for user root May 12 09:05:05 sleepy volumio[989]: info: Loading plugin "airplay_emulation"... May 12 09:05:05 sleepy volumio[989]: info: Starting Shairport Sync May 12 09:05:05 sleepy volumio[989]: info: Loading plugin "last_100"... May 12 09:05:05 sleepy volumio[989]: info: Loading plugin "webradio"... May 12 09:05:05 sleepy volumio[989]: info: Loading plugin "i2s_dacs"... May 12 09:05:05 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 09:05:05 sleepy volumio[989]: info: Loading plugin "volumiodiscovery"... May 12 09:05:05 sleepy volumio[989]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 09:05:05 sleepy node[989]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 09:05:05 sleepy node[989]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 09:05:05 sleepy node[989]: *** WARNING *** For more information see May 12 09:05:05 sleepy node[989]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 09:05:05 sleepy node[989]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 09:05:05 sleepy node[989]: *** WARNING *** For more information see May 12 09:05:05 sleepy volumio[989]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 09:05:05 sleepy volumio[989]: *** WARNING *** For more information see May 12 09:05:05 sleepy volumio[989]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 09:05:05 sleepy volumio[989]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 09:05:05 sleepy volumio[989]: *** WARNING *** For more information see May 12 09:05:05 sleepy volumio[989]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 09:05:05 sleepy volumio[989]: Discovery: StartAdv! undefined May 12 09:05:05 sleepy volumio[989]: Discovery: Started advertising... sleepy - undefined May 12 09:05:05 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 09:05:05 sleepy volumio[989]: info: Loading plugin "outputs"... May 12 09:05:05 sleepy volumio[989]: info: Loading plugin "albumart"... May 12 09:05:05 sleepy volumio[989]: info: Plugin example_plugin is not enabled May 12 09:05:05 sleepy volumio[989]: info: Loading plugin "inputs"... May 12 09:05:05 sleepy volumio[989]: info: Loading plugin "updater_comm"... May 12 09:05:06 sleepy volumio[989]: info: Plugin mpdemulation is not enabled May 12 09:05:06 sleepy volumio[989]: info: Loading plugin "rest_api"... May 12 09:05:06 sleepy volumio[989]: info: Loading plugin "websocket"... May 12 09:05:06 sleepy volumio[989]: info: Loading plugin "squeezelite"... May 12 09:05:06 sleepy volumio[989]: Forking 3 albumart workers May 12 09:05:07 sleepy volumio[989]: info: Squeezelite initiated May 12 09:05:07 sleepy volumio[989]: info: Loading plugin "backup_restore"... May 12 09:05:08 sleepy volumio[989]: info: ___________ START PLUGINS ___________ May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 09:05:08 sleepy volumio[989]: info: [1747033508018] CoreMusicLibrary::Adding element Media Servers May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 09:05:08 sleepy volumio[989]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 09:05:08 sleepy volumio[989]: Starting albumart workers May 12 09:05:08 sleepy volumio[989]: Starting albumart workers May 12 09:05:08 sleepy volumio[989]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 09:05:08 sleepy volumio[989]: info: [1747033508328] CoreMusicLibrary::Adding element Last_100 May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 09:05:08 sleepy volumio[989]: info: [1747033508337] CoreMusicLibrary::Adding element Webradio May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 09:05:08 sleepy volumio[989]: info: ------------------------------------------- May 12 09:05:08 sleepy volumio[989]: info: ----- MyVolumio plugins startup ---- May 12 09:05:08 sleepy volumio[989]: info: ------------------------------------------- May 12 09:05:08 sleepy volumio[989]: info: [MyVolumio PluginManager] Fetching plans data.... May 12 09:05:08 sleepy volumio[989]: Starting albumart workers May 12 09:05:08 sleepy volumio[989]: info: Loading i18n strings for locale nl May 12 09:05:08 sleepy volumio[989]: Updating browse sources language May 12 09:05:08 sleepy sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 12 09:05:08 sleepy volumio[989]: Cannot find translation for sourceMedia Servers May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 09:05:08 sleepy sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 09:05:08 sleepy systemd[1]: Reloading. May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::initPlayerControls May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 12 09:05:08 sleepy systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' May 12 09:05:08 sleepy systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' May 12 09:05:08 sleepy systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' May 12 09:05:08 sleepy systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 09:05:08 sleepy volumio[989]: info: BOOT COMPLETED May 12 09:05:08 sleepy volumio[989]: [Metrics] CommandRouter: 16s 714.05ms May 12 09:05:08 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 09:05:09 sleepy sudo[1117]: pam_unix(sudo:session): session closed for user root May 12 09:05:09 sleepy volumio-remote-updater[454]: [2025-05-12 09:05:09] [connect] Successful connection May 12 09:05:09 sleepy kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 12 09:05:11 sleepy sshd[1164]: Accepted publickey for volumio from 192.168.1.129 port 38164 ssh2: RSA 0c:d0:64:da:c7:0b:a8:88:55:18:33:ea:6f:1d:f0:cb May 12 09:05:11 sleepy sshd[1164]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 12 09:05:11 sleepy systemd[1]: Starting user-1000.slice. May 12 09:05:11 sleepy systemd[1]: Created slice user-1000.slice. May 12 09:05:11 sleepy systemd[1]: Starting User Manager for UID 1000... May 12 09:05:11 sleepy systemd[1]: Starting Session c1 of user volumio. May 12 09:05:11 sleepy systemd-logind[470]: New session c1 of user volumio. May 12 09:05:11 sleepy systemd[1]: Started Session c1 of user volumio. May 12 09:05:11 sleepy systemd[1168]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) May 12 09:05:11 sleepy systemd[1168]: Starting Paths. May 12 09:05:11 sleepy systemd[1168]: Reached target Paths. May 12 09:05:11 sleepy systemd[1168]: Starting Timers. May 12 09:05:11 sleepy systemd[1168]: Reached target Timers. May 12 09:05:11 sleepy systemd[1168]: Starting Sockets. May 12 09:05:11 sleepy systemd[1168]: Reached target Sockets. May 12 09:05:11 sleepy systemd[1168]: Starting Basic System. May 12 09:05:11 sleepy systemd[1168]: Reached target Basic System. May 12 09:05:11 sleepy systemd[1168]: Starting Default. May 12 09:05:11 sleepy systemd[1168]: Reached target Default. May 12 09:05:11 sleepy systemd[1168]: Startup finished in 62ms. May 12 09:05:11 sleepy systemd[1]: Started User Manager for UID 1000. May 12 09:05:11 sleepy sshd[1174]: Received disconnect from 192.168.1.129: 11: disconnected by user May 12 09:05:11 sleepy sshd[1164]: pam_unix(sshd:session): session closed for user volumio May 12 09:05:11 sleepy sudo[1177]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash /mnt/NAS/motaro/scripts/current/rpi/volume.sh May 12 09:05:11 sleepy sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 09:05:14 sleepy volumio[989]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 12 09:05:14 sleepy volumio[989]: info: CoreCommandRouter::Close All Modals sent May 12 09:05:14 sleepy volumio[989]: info: CoreCommandRouter::Close All Modals sent May 12 09:05:14 sleepy volumio[989]: Express server listening on port 3000 May 12 09:05:14 sleepy volumio[989]: [Metrics] WebUI: 23s 520.31ms May 12 09:05:14 sleepy volumio[989]: Volumio Calling Home May 12 09:05:14 sleepy volumio[989]: info: CoreStateMachine::resetVolumioState May 12 09:05:14 sleepy volumio[989]: info: CoreStateMachine::getcurrentVolume May 12 09:05:14 sleepy volumio[989]: info: CoreCommandRouter::volumioRetrievevolume May 12 09:05:14 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 09:05:14 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 09:05:14 sleepy sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 09:05:14 sleepy sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 09:05:14 sleepy sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 09:05:14 sleepy sudo[1205]: pam_unix(sudo:session): session closed for user root May 12 09:05:14 sleepy sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 09:05:14 sleepy sudo[1207]: pam_unix(sudo:session): session closed for user root May 12 09:05:15 sleepy volumio[989]: info: Volumio called home May 12 09:05:15 sleepy volumio[989]: info: Setting Device type: Raspberry PI May 12 09:05:15 sleepy volumio[989]: info: squeezelite reloaded May 12 09:05:15 sleepy volumio[989]: error: Cannot initialize MPD Connection: MPD is not running May 12 09:05:15 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 12 09:05:15 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 12 09:05:15 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 12 09:05:15 sleepy sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart squeezelite May 12 09:05:15 sleepy sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 09:05:15 sleepy systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... May 12 09:05:15 sleepy systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. May 12 09:05:15 sleepy kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 12 09:05:15 sleepy volumio[989]: info: VolumeController:: Volume=8 Mute =false May 12 09:05:15 sleepy volumio[989]: info: CoreStateMachine::pushState May 12 09:05:15 sleepy volumio[989]: info: CorePlayQueue::getTrack 0 May 12 09:05:15 sleepy volumio[989]: info: CoreCommandRouter::volumioPushState May 12 09:05:15 sleepy volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 09:05:15 sleepy volumio[989]: info: CoreStateMachine::updateTrackBlock May 12 09:05:15 sleepy volumio[989]: info: CorePlayQueue::getTrackBlock May 12 09:05:15 sleepy volumio[989]: info: CoreCommandRouter::volumioRetrievevolume May 12 09:05:15 sleepy sudo[1224]: pam_unix(sudo:session): session closed for user root May 12 09:05:15 sleepy volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 09:05:15 sleepy volumio[989]: TypeError: Cannot read property 'error' of undefined May 12 09:05:15 sleepy volumio[989]: at /volumio/app/index.js:183:22 May 12 09:05:15 sleepy volumio[989]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 May 12 09:05:15 sleepy volumio[989]: at fs.js:1279:7 May 12 09:05:15 sleepy volumio[989]: at FSReqWrap.oncomplete (fs.js:135:15) May 12 09:05:15 sleepy volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 09:05:17 sleepy sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 09:04 May 12 09:05:17 sleepy sudo[1245]: 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"