-- Logs begin at Thu 2025-04-24 09:58:17 UTC, end at Thu 2025-04-24 22:02:49 UTC. -- Apr 24 22:02:44 volumio systemd[1]: Time has been changed Apr 24 22:02:45 volumio volumio-remote-updater[580]: [2025-04-24 22:02:45] [connect] Successful connection Apr 24 22:02:46 volumio sudo[1068]: pam_unix(sudo:session): session closed for user root Apr 24 22:02:46 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Apr 24 22:02:46 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Apr 24 22:02:47 volumio volumio[1015]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::Close All Modals sent Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::Close All Modals sent Apr 24 22:02:47 volumio volumio[1015]: Express server listening on port 3000 Apr 24 22:02:47 volumio volumio[1015]: [Metrics] WebUI: 11s 86.38ms Apr 24 22:02:47 volumio volumio[1015]: Volumio Calling Home Apr 24 22:02:47 volumio volumio[1015]: info: Volsimpleequal Started Apr 24 22:02:47 volumio volumio[1015]: info: CoreStateMachine::resetVolumioState Apr 24 22:02:47 volumio volumio[1015]: info: CoreStateMachine::getcurrentVolume Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 22:02:47 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 24 22:02:47 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 22:02:47 volumio volumio[1015]: info: Output device has changed, restarting MPD Apr 24 22:02:47 volumio volumio[1015]: info: Output device has changed, restarting Shairport Sync Apr 24 22:02:47 volumio sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 22:02:47 volumio sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 22:02:47 volumio sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 22:02:47 volumio sudo[1214]: pam_unix(sudo:session): session closed for user root Apr 24 22:02:47 volumio sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 22:02:47 volumio systemd[1]: Stopping Music Player Daemon... Apr 24 22:02:47 volumio volumio[1015]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 24 22:02:47 volumio volumio[1015]: info: Output device has changed, continuing config Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 22:02:47 volumio volumio[1015]: info: Output is Loopback, do not update Mixer Apr 24 22:02:47 volumio volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 22:02:47 volumio volumio[1015]: info: MPD Permissions set Apr 24 22:02:47 volumio volumio[1015]: info: Setting Device type: Raspberry PI Apr 24 22:02:47 volumio volumio[1015]: info: snd_aloop loaded Apr 24 22:02:47 volumio volumio[1015]: info: Cannot mount NAS sakitama at system boot, trial number 1 ,retrying in 5 seconds Apr 24 22:02:47 volumio volumio[1015]: info: Cannot mount NAS sakitama at system boot, trial number 1 ,retrying in 5 seconds Apr 24 22:02:47 volumio volumio[1015]: info: MPD running with PID651 Apr 24 22:02:47 volumio volumio[1015]: ,establishing connection Apr 24 22:02:47 volumio volumio-remote-updater[580]: [2025-04-24 22:02:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1745532165 101 Apr 24 22:02:48 volumio sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Apr 24 22:02:48 volumio sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 22:02:48 volumio sudo[1234]: pam_unix(sudo:session): session closed for user root Apr 24 22:02:48 volumio systemd[1]: Starting Music Player Daemon... Apr 24 22:02:48 volumio volumio[1015]: info: No Overlays Loaded Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 24 22:02:48 volumio volumio[1015]: info: Volumio called home Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 24 22:02:48 volumio mpd[1241]: output: No 'AudioOutput' defined in config file Apr 24 22:02:48 volumio mpd[1241]: output: Attempt to detect audio output device Apr 24 22:02:48 volumio mpd[1241]: output: Attempting to detect a alsa audio device Apr 24 22:02:48 volumio volumio[1015]: info: CoreStateMachine::setRepeat true single undefined Apr 24 22:02:48 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 24 22:02:48 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 22:02:48 volumio volumio[1015]: info: CoreStateMachine::setRandom false Apr 24 22:02:48 volumio volumio[1015]: info: CoreStateMachine::pushState Apr 24 22:02:48 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 22:02:48 volumio volumio[1015]: info: Reloading queue from file Apr 24 22:02:48 volumio mpd[1241]: output: Successfully detected a alsa audio device Apr 24 22:02:48 volumio systemd[1]: Started Music Player Daemon. Apr 24 22:02:48 volumio sudo[1216]: pam_unix(sudo:session): session closed for user root Apr 24 22:02:48 volumio volumio[1015]: info: mDNS: A device disapperared from network Apr 24 22:02:48 volumio volumio[1015]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Apr 24 22:02:48 volumio volumio[1015]: info: Starting Shairport Sync Apr 24 22:02:48 volumio volumio[1015]: info: Starting Shairport Sync Apr 24 22:02:48 volumio volumio[1015]: info: Starting Shairport Sync Apr 24 22:02:48 volumio sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 22:02:48 volumio sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 22:02:48 volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 22:02:48 volumio volumio[1015]: Discovery: adding d8f18a33-c6fc-4029-af34-fe7119551522 Apr 24 22:02:48 volumio volumio[1015]: info: mDNS: Found device Volumio Apr 24 22:02:48 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState Apr 24 22:02:48 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 Apr 24 22:02:48 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 24 22:02:48 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 22:02:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 22:02:48 volumio sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 22:02:48 volumio sudo[1263]: pam_unix(sudo:session): session closed for user root Apr 24 22:02:48 volumio volumio[1015]: error: updateQueue error: null Apr 24 22:02:48 volumio volumio[1015]: error: Failed LSINFO: Error: [50@0] {} No database Apr 24 22:02:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 22:02:48 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 24 22:02:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 22:02:48 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Apr 24 22:02:48 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 22:02:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 22:02:48 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 24 22:02:48 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Apr 24 22:02:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 22:02:48 volumio volumio[1015]: info: asound.conf file written Apr 24 22:02:48 volumio sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/asoundrc /etc/asound.conf Apr 24 22:02:48 volumio sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 22:02:48 volumio sudo[1305]: pam_unix(sudo:session): session closed for user root Apr 24 22:02:48 volumio volumio[1015]: info: Shairport-Sync Started Apr 24 22:02:48 volumio volumio[1015]: Error adding Membership: Error: addMembership EINVAL Apr 24 22:02:48 volumio volumio[1015]: info: Shairport-Sync Started Apr 24 22:02:48 volumio volumio[1015]: info: Shairport-Sync Started Apr 24 22:02:48 volumio volumio[1015]: error: updateQueue error: null Apr 24 22:02:48 volumio volumio[1015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 22:02:48 volumio volumio[1015]: Error: Non okay return status: "ACK [50@0] {} No database" Apr 24 22:02:48 volumio volumio[1015]: at MPD._checkReturn (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:353:10) Apr 24 22:02:48 volumio volumio[1015]: at MPD. (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:188:18) Apr 24 22:02:48 volumio volumio[1015]: at MPD._handleResponse (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:450:3) Apr 24 22:02:48 volumio volumio[1015]: at MPD._onData (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:344:10) Apr 24 22:02:48 volumio volumio[1015]: at emitOne (events.js:116:13) Apr 24 22:02:48 volumio volumio[1015]: at Socket.emit (events.js:211:7) Apr 24 22:02:48 volumio volumio[1015]: at addChunk (_stream_readable.js:263:12) Apr 24 22:02:48 volumio volumio[1015]: at readableAddChunk (_stream_readable.js:246:13) Apr 24 22:02:48 volumio volumio[1015]: at Socket.Readable.push (_stream_readable.js:208:10) Apr 24 22:02:48 volumio volumio[1015]: at TCP.onread (net.js:607:20) Apr 24 22:02:48 volumio volumio[1015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 22:02:49 volumio sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 22:01 Apr 24 22:02:49 volumio sudo[1323]: 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"