-- Logs begin at Sat 2020-01-18 20:48:52 UTC, end at Wed 2026-04-15 09:13:24 UTC. -- Apr 15 09:13:10 volumio systemd[1]: Time has been changed Apr 15 09:13:10 volumio volumio[1020]: info: Loading plugin "mpd"... Apr 15 09:13:11 volumio sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 09:13:11 volumio volumio[1020]: info: Plugin upnp_browser is not enabled Apr 15 09:13:11 volumio volumio[1020]: info: Loading plugin "networkfs"... Apr 15 09:13:11 volumio sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 09:13:11 volumio sudo[1051]: pam_unix(sudo:session): session closed for user root Apr 15 09:13:11 volumio sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 09:13:11 volumio sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 09:13:11 volumio systemd[1]: Stopping Music Player Daemon... Apr 15 09:13:11 volumio systemd[1]: Starting Music Player Daemon... Apr 15 09:13:11 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 15 09:13:11 volumio volumio[1020]: info: Loading plugin "alarm-clock"... Apr 15 09:13:12 volumio volumio[1020]: info: Loading plugin "airplay_emulation"... Apr 15 09:13:12 volumio volumio[1020]: info: Starting Shairport Sync Apr 15 09:13:12 volumio volumio[1020]: info: Loading plugin "last_100"... Apr 15 09:13:12 volumio volumio[1020]: info: Loading plugin "webradio"... Apr 15 09:13:12 volumio systemd[1]: Started Music Player Daemon. Apr 15 09:13:12 volumio sudo[1054]: pam_unix(sudo:session): session closed for user root Apr 15 09:13:12 volumio volumio[1020]: info: Loading plugin "i2s_dacs"... Apr 15 09:13:12 volumio volumio[1020]: info: I2S DAC not set, start Auto-detection Apr 15 09:13:12 volumio volumio[1020]: info: Loading plugin "volumiodiscovery"... Apr 15 09:13:12 volumio dhcpcd[499]: timed out Apr 15 09:13:12 volumio dhcpcd[499]: allowing 8 seconds for IPv4LL timeout Apr 15 09:13:12 volumio ifup[477]: dhcpcd[499]: timed out Apr 15 09:13:12 volumio ifup[477]: dhcpcd[499]: allowing 8 seconds for IPv4LL timeout Apr 15 09:13:13 volumio volumio[1020]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 15 09:13:13 volumio node[1020]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 15 09:13:13 volumio node[1020]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 09:13:13 volumio node[1020]: *** WARNING *** For more information see Apr 15 09:13:13 volumio node[1020]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 15 09:13:13 volumio node[1020]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 09:13:13 volumio node[1020]: *** WARNING *** For more information see Apr 15 09:13:13 volumio volumio[1020]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 09:13:13 volumio volumio[1020]: *** WARNING *** For more information see Apr 15 09:13:13 volumio volumio[1020]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 15 09:13:13 volumio volumio[1020]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 09:13:13 volumio volumio[1020]: *** WARNING *** For more information see Apr 15 09:13:13 volumio volumio[1020]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 15 09:13:13 volumio volumio[1020]: Discovery: StartAdv! undefined Apr 15 09:13:13 volumio volumio[1020]: Discovery: Started advertising... Volumio - undefined Apr 15 09:13:13 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 15 09:13:13 volumio volumio[1020]: info: Loading plugin "youtube"... Apr 15 09:13:16 volumio volumio[1020]: info: Loading plugin "outputs"... Apr 15 09:13:16 volumio volumio[1020]: info: Loading plugin "albumart"... Apr 15 09:13:16 volumio volumio[1020]: info: Plugin example_plugin is not enabled Apr 15 09:13:16 volumio volumio[1020]: info: Loading plugin "inputs"... Apr 15 09:13:16 volumio volumio[1020]: info: Loading plugin "updater_comm"... Apr 15 09:13:16 volumio volumio[1020]: info: Plugin mpdemulation is not enabled Apr 15 09:13:16 volumio volumio[1020]: info: Loading plugin "rest_api"... Apr 15 09:13:17 volumio volumio[1020]: info: Loading plugin "websocket"... Apr 15 09:13:17 volumio volumio[1020]: info: Loading plugin "spop"... Apr 15 09:13:17 volumio volumio[1020]: Forking 3 albumart workers Apr 15 09:13:18 volumio volumio[1020]: Starting albumart workers Apr 15 09:13:18 volumio volumio[1020]: Starting albumart workers Apr 15 09:13:18 volumio volumio[1020]: Starting albumart workers Apr 15 09:13:19 volumio volumio[1020]: info: Loading plugin "volspotconnect2"... Apr 15 09:13:20 volumio volumio[1020]: info: ___________ START PLUGINS ___________ Apr 15 09:13:20 volumio volumio[1020]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so Apr 15 09:13:20 volumio volumio[1020]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 Apr 15 09:13:20 volumio volumio[1020]: aplay: device_list:277: control open (0): No such file or directory Apr 15 09:13:20 volumio volumio[1020]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 09:13:20 volumio volumio[1020]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so Apr 15 09:13:20 volumio volumio[1020]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 Apr 15 09:13:20 volumio volumio[1020]: aplay: device_list:277: control open (0): No such file or directory Apr 15 09:13:20 volumio volumio[1020]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so Apr 15 09:13:20 volumio volumio[1020]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 Apr 15 09:13:20 volumio volumio[1020]: aplay: device_list:277: control open (0): No such file or directory Apr 15 09:13:20 volumio volumio[1020]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 09:13:20 volumio volumio[1020]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so Apr 15 09:13:20 volumio volumio[1020]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 Apr 15 09:13:20 volumio volumio[1020]: aplay: device_list:277: control open (0): No such file or directory Apr 15 09:13:20 volumio volumio[1020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 09:13:20 volumio volumio[1020]: info: [1776244400955] CoreMusicLibrary::Adding element Last_100 Apr 15 09:13:20 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 09:13:20 volumio volumio[1020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 09:13:20 volumio volumio[1020]: info: [1776244400973] CoreMusicLibrary::Adding element Webradio Apr 15 09:13:20 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 09:13:20 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 09:13:20 volumio volumio[1020]: info: Youtube::onStart Adding to browse sources Apr 15 09:13:20 volumio dhcpcd[499]: timed out Apr 15 09:13:20 volumio dhcpcd[499]: exited Apr 15 09:13:20 volumio ifup[477]: dhcpcd[499]: timed out Apr 15 09:13:20 volumio ifup[477]: dhcpcd[499]: exited Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 09:13:21 volumio volumio[1020]: info: [1776244401000] CoreMusicLibrary::Adding element Youtube Apr 15 09:13:21 volumio ifup[477]: Failed to bring up eth0. Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 09:13:21 volumio sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Apr 15 09:13:21 volumio volumio[1020]: info: ------------------------------------------- Apr 15 09:13:21 volumio volumio[1020]: info: ----- MyVolumio plugins startup ---- Apr 15 09:13:21 volumio volumio[1020]: info: ------------------------------------------- Apr 15 09:13:21 volumio sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 09:13:21 volumio volumio[1020]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 15 09:13:21 volumio sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Apr 15 09:13:21 volumio volumio[1020]: info: Loading i18n strings for locale de Apr 15 09:13:21 volumio sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 09:13:21 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Apr 15 09:13:21 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Apr 15 09:13:21 volumio systemd[1]: Starting Spotify Daemon... Apr 15 09:13:21 volumio volumio[1020]: Updating browse sources language Apr 15 09:13:21 volumio systemd[1]: Started Spotify Daemon. Apr 15 09:13:21 volumio volumio[1020]: Cannot find translation for sourceYoutube Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::initPlayerControls Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 15 09:13:21 volumio volumio[1020]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so Apr 15 09:13:21 volumio volumio[1020]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 Apr 15 09:13:21 volumio volumio[1020]: aplay: device_list:277: control open (0): No such file or directory Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 09:13:21 volumio volumio[1020]: info: BOOT COMPLETED Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 09:13:21 volumio sudo[1130]: pam_unix(sudo:session): session closed for user root Apr 15 09:13:21 volumio volumio[1020]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so Apr 15 09:13:21 volumio volumio[1020]: ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM plughw:0,0 Apr 15 09:13:21 volumio volumio[1020]: aplay: main:722: audio open error: No such file or directory Apr 15 09:13:21 volumio volumio[1020]: Cannot play startup sound Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::Close All Modals sent Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::Close All Modals sent Apr 15 09:13:21 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 15 09:13:21 volumio volumio[1141]: 2026-04-15 09:13:21 [SPTF] 09:13:21.360 I [offline_authorizer.cpp:297] Unable to login offline: no such user Apr 15 09:13:21 volumio volumio[1141]: 2026-04-15 09:13:21 [SPTF] 09:13:21.361 I [ap:1752] Connecting to AP ap.spotify.com:4070 Apr 15 09:13:21 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 15 09:13:21 volumio sudo[1133]: pam_unix(sudo:session): session closed for user root Apr 15 09:13:21 volumio volumio[1020]: Express server listening on port 3000 Apr 15 09:13:21 volumio volumio[1020]: Volumio Calling Home Apr 15 09:13:21 volumio volumio[1141]: 2026-04-15 09:13:21 [SPTF] 09:13:21.409 I [ap:1226] Connected to AP: 104.199.65.9:4070 Apr 15 09:13:21 volumio volumio[1020]: info: CoreStateMachine::resetVolumioState Apr 15 09:13:21 volumio volumio[1020]: info: CoreStateMachine::getcurrentVolume Apr 15 09:13:21 volumio volumio[1020]: info: CoreCommandRouter::volumioRetrievevolume Apr 15 09:13:21 volumio volumio[1151]: vollibrespot v0.1.9 c98fd5d 2019-11-08 (librespot 4674946 2019-11-08) -- Built On 2019-11-08 Apr 15 09:13:21 volumio volumio[1141]: 2026-04-15 09:13:21 [SPTF] 09:13:21.619 E [ap:3953] Connection error: 410 Apr 15 09:13:21 volumio volumio[1141]: 2026-04-15 09:13:21 [WARN] Login failed: Bad username and/or password Apr 15 09:13:21 volumio volumio[1141]: 2026-04-15 09:13:21 [ERR ] Could not get the playlist container. Apr 15 09:13:21 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Apr 15 09:13:21 volumio systemd[1]: Unit spop.service entered failed state. Apr 15 09:13:21 volumio sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 09:13:21 volumio sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 09:13:21 volumio sudo[1170]: pam_unix(sudo:session): session closed for user root Apr 15 09:13:21 volumio volumio[1151]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Apr 15 09:13:22 volumio volumio[1020]: info: Setting Device type: Raspberry PI Apr 15 09:13:22 volumio volumio[1020]: [SpotifyConnect] Vollibrespot Daemon Started Apr 15 09:13:22 volumio volumio[1020]: [SpotifyConnect] Starting metadata listener Apr 15 09:13:22 volumio volumio[1020]: [SpotifyConnect] FE => [ 1, [length]: 1 ] Apr 15 09:13:22 volumio volumio[1020]: info: SpopD Daemon Started Apr 15 09:13:22 volumio sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Apr 15 09:13:22 volumio volumio[1020]: info: MPD Permissions set Apr 15 09:13:22 volumio sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 09:13:22 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 15 09:13:22 volumio sudo[1180]: pam_unix(sudo:session): session closed for user root Apr 15 09:13:22 volumio volumio[1020]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so Apr 15 09:13:22 volumio volumio[1020]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 Apr 15 09:13:22 volumio volumio[1020]: aplay: device_list:277: control open (0): No such file or directory Apr 15 09:13:22 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 15 09:13:22 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 15 09:13:22 volumio volumio[1020]: info: MPD running with PID1066 ,establishing connection Apr 15 09:13:22 volumio volumio[1020]: info: VolumeController:: Volume=undefined Mute =false Apr 15 09:13:22 volumio volumio[1020]: info: CoreStateMachine::pushState Apr 15 09:13:22 volumio volumio[1020]: info: CoreStateMachine::getState Apr 15 09:13:22 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Apr 15 09:13:22 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Apr 15 09:13:22 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 09:13:22 volumio volumio[1020]: info: CoreStateMachine::updateTrackBlock Apr 15 09:13:22 volumio volumio[1020]: info: CorePlayQueue::getTrackBlock Apr 15 09:13:22 volumio volumio[1020]: info: CoreCommandRouter::volumioRetrievevolume Apr 15 09:13:22 volumio volumio[1020]: info: Apr 15 09:13:22 volumio volumio[1020]: ---------------------------- Client requests Volume 50 Apr 15 09:13:22 volumio volumio[1020]: info: VolumeController::SetAlsaVolume50 Apr 15 09:13:22 volumio volumio[1020]: info: CoreStateMachine::pushState Apr 15 09:13:22 volumio volumio[1020]: info: CoreStateMachine::getState Apr 15 09:13:22 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Apr 15 09:13:22 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Apr 15 09:13:22 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 09:13:22 volumio volumio[1020]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 09:13:22 volumio volumio[1020]: { AssertionError [ERR_ASSERTION]: false == true Apr 15 09:13:22 volumio volumio[1020]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Apr 15 09:13:22 volumio volumio[1020]: at /volumio/app/plugins/music_service/mpd/index.js:3118:28 Apr 15 09:13:22 volumio volumio[1020]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Apr 15 09:13:22 volumio volumio[1020]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 15 09:13:22 volumio volumio[1020]: generatedMessage: true, Apr 15 09:13:22 volumio volumio[1020]: name: 'AssertionError [ERR_ASSERTION]', Apr 15 09:13:23 volumio volumio[1020]: code: 'ERR_ASSERTION', Apr 15 09:13:23 volumio volumio[1020]: actual: false, Apr 15 09:13:23 volumio volumio[1020]: expected: true, Apr 15 09:13:23 volumio volumio[1020]: operator: '==' } Apr 15 09:13:23 volumio volumio[1020]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 09:13:24 volumio volumio-remote-updater[567]: [2026-04-15 09:13:24] [connect] Successful connection Apr 15 09:13:24 volumio sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-15 09:12 Apr 15 09:13:24 volumio sudo[1202]: 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="80e3a7415791a01dac7cd7cc2cc0a3d3fc65ba9d" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="a5d5fb6aa4e4c71be85580a005adf6b2009366a5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jan 16 20:23:20 CET 2020" VOLUMIO_VERSION="2.698" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="3dcbb012a2043e1c4181cb54ce618cae"