-- Logs begin at Sat 2020-01-18 20:48:52 UTC, end at Tue 2025-05-06 15:09:06 UTC. -- May 06 15:08:50 volumio systemd[1]: Time has been changed May 06 15:08:51 volumio volumio[1182]: info: Loading plugin "services"... May 06 15:08:51 volumio volumio[1182]: info: Loading plugin "alsa_controller"... May 06 15:08:51 volumio volumio[1182]: info: Loading plugin "wizard"... May 06 15:08:51 volumio volumio[1182]: info: Loading plugin "volumio_command_line_client"... May 06 15:08:51 volumio volumio[1182]: info: Loading plugin "upnp"... May 06 15:08:51 volumio volumio[1182]: info: [1746544131274] Starting Upmpd Daemon May 06 15:08:51 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 15:08:51 volumio volumio[1182]: info: Loading plugin "my_music"... May 06 15:08:51 volumio volumio[1182]: info: Loading plugin "mpd"... May 06 15:08:52 volumio sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 06 15:08:52 volumio volumio[1182]: info: Plugin upnp_browser is not enabled May 06 15:08:52 volumio volumio[1182]: info: Loading plugin "networkfs"... May 06 15:08:52 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 15:08:52 volumio sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 06 15:08:52 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root May 06 15:08:52 volumio sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 15:08:52 volumio systemd[1]: Stopping Music Player Daemon... May 06 15:08:52 volumio systemd[1]: Starting Music Player Daemon... May 06 15:08:52 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 15:08:52 volumio volumio[1182]: info: Loading plugin "alarm-clock"... May 06 15:08:52 volumio volumio[1182]: info: Loading plugin "airplay_emulation"... May 06 15:08:53 volumio volumio[1182]: info: Starting Shairport Sync May 06 15:08:53 volumio volumio[1182]: info: Loading plugin "last_100"... May 06 15:08:53 volumio volumio[1182]: info: Loading plugin "webradio"... May 06 15:08:53 volumio systemd[1]: Started Music Player Daemon. May 06 15:08:53 volumio sudo[1215]: pam_unix(sudo:session): session closed for user root May 06 15:08:53 volumio volumio[1182]: info: Loading plugin "i2s_dacs"... May 06 15:08:53 volumio volumio[1182]: info: I2S DAC not set, start Auto-detection May 06 15:08:53 volumio volumio[1182]: info: Loading plugin "volumiodiscovery"... May 06 15:08:53 volumio volumio[1182]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 06 15:08:53 volumio node[1182]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 06 15:08:53 volumio node[1182]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 15:08:53 volumio node[1182]: *** WARNING *** For more information see May 06 15:08:53 volumio node[1182]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 06 15:08:53 volumio node[1182]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 15:08:53 volumio node[1182]: *** WARNING *** For more information see May 06 15:08:53 volumio volumio[1182]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 15:08:53 volumio volumio[1182]: *** WARNING *** For more information see May 06 15:08:53 volumio volumio[1182]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 06 15:08:53 volumio volumio[1182]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 15:08:53 volumio volumio[1182]: *** WARNING *** For more information see May 06 15:08:53 volumio volumio[1182]: info: Applying required configuration parameters for plugin volumiodiscovery May 06 15:08:53 volumio volumio[1182]: Discovery: StartAdv! undefined May 06 15:08:53 volumio volumio[1182]: Discovery: Started advertising... Volumio - undefined May 06 15:08:53 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 15:08:53 volumio volumio[1182]: info: Loading plugin "youtube"... May 06 15:08:57 volumio volumio[1182]: info: Loading plugin "outputs"... May 06 15:08:57 volumio volumio[1182]: info: Loading plugin "albumart"... May 06 15:08:57 volumio volumio[1182]: info: Plugin example_plugin is not enabled May 06 15:08:57 volumio volumio[1182]: info: Loading plugin "inputs"... May 06 15:08:57 volumio volumio[1182]: info: Loading plugin "updater_comm"... May 06 15:08:57 volumio volumio[1182]: info: Plugin mpdemulation is not enabled May 06 15:08:57 volumio volumio[1182]: info: Loading plugin "rest_api"... May 06 15:08:57 volumio volumio[1182]: info: Loading plugin "websocket"... May 06 15:08:57 volumio volumio[1182]: info: Loading plugin "spop"... May 06 15:08:57 volumio volumio[1182]: Forking 3 albumart workers May 06 15:08:59 volumio volumio[1182]: Starting albumart workers May 06 15:08:59 volumio volumio[1182]: Starting albumart workers May 06 15:08:59 volumio volumio[1182]: Starting albumart workers May 06 15:09:00 volumio volumio[1182]: info: Loading plugin "volspotconnect2"... May 06 15:09:02 volumio volumio[1182]: info: ___________ START PLUGINS ___________ May 06 15:09:03 volumio volumio[1182]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so May 06 15:09:03 volumio volumio[1182]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 May 06 15:09:03 volumio volumio[1182]: aplay: device_list:277: control open (0): No such file or directory May 06 15:09:03 volumio volumio[1182]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 15:09:03 volumio volumio[1182]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so May 06 15:09:03 volumio volumio[1182]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 May 06 15:09:03 volumio volumio[1182]: aplay: device_list:277: control open (0): No such file or directory May 06 15:09:03 volumio volumio[1182]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so May 06 15:09:03 volumio volumio[1182]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 May 06 15:09:03 volumio volumio[1182]: aplay: device_list:277: control open (0): No such file or directory May 06 15:09:03 volumio volumio[1182]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 15:09:03 volumio volumio[1182]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so May 06 15:09:03 volumio volumio[1182]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 May 06 15:09:03 volumio volumio[1182]: aplay: device_list:277: control open (0): No such file or directory May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 15:09:03 volumio volumio[1182]: info: [1746544143121] CoreMusicLibrary::Adding element Last_100 May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 15:09:03 volumio volumio[1182]: info: [1746544143144] CoreMusicLibrary::Adding element Webradio May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 15:09:03 volumio volumio[1182]: info: Youtube::onStart Adding to browse sources May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 15:09:03 volumio volumio[1182]: info: [1746544143174] CoreMusicLibrary::Adding element Youtube May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 15:09:03 volumio sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 06 15:09:03 volumio volumio[1182]: info: ------------------------------------------- May 06 15:09:03 volumio volumio[1182]: info: ----- MyVolumio plugins startup ---- May 06 15:09:03 volumio volumio[1182]: info: ------------------------------------------- May 06 15:09:03 volumio volumio[1182]: info: [MyVolumio PluginManager] Fetching plans data.... May 06 15:09:03 volumio sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 15:09:03 volumio sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 06 15:09:03 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. May 06 15:09:03 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. May 06 15:09:03 volumio sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 15:09:03 volumio volumio[1182]: info: Loading i18n strings for locale de May 06 15:09:03 volumio systemd[1]: Starting Spotify Daemon... May 06 15:09:03 volumio systemd[1]: Started Spotify Daemon. May 06 15:09:03 volumio volumio[1182]: Updating browse sources language May 06 15:09:03 volumio volumio[1182]: Cannot find translation for sourceYoutube May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::initPlayerControls May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 06 15:09:03 volumio volumio[1182]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so May 06 15:09:03 volumio volumio[1182]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 May 06 15:09:03 volumio volumio[1182]: aplay: device_list:277: control open (0): No such file or directory May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 15:09:03 volumio volumio[1182]: info: BOOT COMPLETED May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 15:09:03 volumio volumio[1300]: 2025-05-06 15:09:03 [SPTF] 15:09:03.507 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 06 15:09:03 volumio volumio[1300]: 2025-05-06 15:09:03 [SPTF] 15:09:03.509 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 06 15:09:03 volumio sudo[1289]: pam_unix(sudo:session): session closed for user root May 06 15:09:03 volumio systemd[1]: Starting Volspotconnect2 Daemon... May 06 15:09:03 volumio systemd[1]: Started Volspotconnect2 Daemon. May 06 15:09:03 volumio sudo[1292]: pam_unix(sudo:session): session closed for user root May 06 15:09:03 volumio volumio[1182]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so May 06 15:09:03 volumio volumio[1182]: ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM plughw:0,0 May 06 15:09:03 volumio volumio[1182]: aplay: main:722: audio open error: No such file or directory May 06 15:09:03 volumio volumio[1182]: Cannot play startup sound May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::Close All Modals sent May 06 15:09:03 volumio volumio[1300]: 2025-05-06 15:09:03 [SPTF] 15:09:03.566 I [ap:1226] Connected to AP: 34.158.1.133:4070 May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::Close All Modals sent May 06 15:09:03 volumio volumio[1182]: Express server listening on port 3000 May 06 15:09:03 volumio volumio[1182]: Volumio Calling Home May 06 15:09:03 volumio volumio[1182]: info: CoreStateMachine::resetVolumioState May 06 15:09:03 volumio volumio[1182]: info: CoreStateMachine::getcurrentVolume May 06 15:09:03 volumio volumio[1182]: info: CoreCommandRouter::volumioRetrievevolume May 06 15:09:03 volumio volumio[1309]: vollibrespot v0.1.9 c98fd5d 2019-11-08 (librespot 4674946 2019-11-08) -- Built On 2019-11-08 May 06 15:09:03 volumio sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 15:09:03 volumio sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 15:09:03 volumio sudo[1328]: pam_unix(sudo:session): session closed for user root May 06 15:09:03 volumio volumio[1300]: 2025-05-06 15:09:03 [SPTF] 15:09:03.985 E [ap:3953] Connection error: 410 May 06 15:09:03 volumio volumio[1300]: 2025-05-06 15:09:03 [WARN] Login failed: Bad username and/or password May 06 15:09:03 volumio volumio[1300]: 2025-05-06 15:09:03 [ERR ] Could not get the playlist container. May 06 15:09:03 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP May 06 15:09:03 volumio systemd[1]: Unit spop.service entered failed state. May 06 15:09:04 volumio volumio[1309]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 06 15:09:04 volumio volumio[1182]: info: Setting Device type: Raspberry PI May 06 15:09:04 volumio volumio[1182]: [SpotifyConnect] Vollibrespot Daemon Started May 06 15:09:04 volumio volumio[1182]: [SpotifyConnect] Starting metadata listener May 06 15:09:04 volumio volumio[1182]: [SpotifyConnect] FE => [ 1, [length]: 1 ] May 06 15:09:04 volumio volumio[1182]: info: SpopD Daemon Started May 06 15:09:04 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 May 06 15:09:04 volumio volumio[1182]: info: MPD Permissions set May 06 15:09:04 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 15:09:04 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 06 15:09:04 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root May 06 15:09:04 volumio volumio[1182]: ALSA lib conf.c:3523:(snd_config_hooks_call) Cannot open shared library libasound_module_conf_pulse.so May 06 15:09:04 volumio volumio[1182]: ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL hw:0 May 06 15:09:04 volumio volumio[1182]: aplay: device_list:277: control open (0): No such file or directory May 06 15:09:04 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 06 15:09:04 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 06 15:09:04 volumio volumio[1182]: info: MPD running with PID1228 ,establishing connection May 06 15:09:04 volumio volumio[1182]: info: VolumeController:: Volume=undefined Mute =false May 06 15:09:04 volumio volumio[1182]: info: CoreStateMachine::pushState May 06 15:09:04 volumio volumio[1182]: info: CoreStateMachine::getState May 06 15:09:04 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 06 15:09:04 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 06 15:09:04 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 15:09:04 volumio volumio[1182]: info: CoreStateMachine::updateTrackBlock May 06 15:09:04 volumio volumio[1182]: info: CorePlayQueue::getTrackBlock May 06 15:09:04 volumio volumio[1182]: info: CoreCommandRouter::volumioRetrievevolume May 06 15:09:04 volumio volumio[1182]: info: May 06 15:09:04 volumio volumio[1182]: ---------------------------- Client requests Volume 50 May 06 15:09:04 volumio volumio[1182]: info: VolumeController::SetAlsaVolume50 May 06 15:09:05 volumio volumio-remote-updater[569]: [2025-05-06 15:09:05] [connect] Successful connection May 06 15:09:05 volumio volumio[1182]: info: CoreStateMachine::pushState May 06 15:09:05 volumio volumio[1182]: info: CoreStateMachine::getState May 06 15:09:05 volumio volumio[1182]: info: CorePlayQueue::getTrack 0 May 06 15:09:05 volumio volumio[1182]: info: CoreCommandRouter::volumioPushState May 06 15:09:05 volumio volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 15:09:05 volumio volumio[1182]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 15:09:05 volumio volumio[1182]: { AssertionError [ERR_ASSERTION]: false == true May 06 15:09:05 volumio volumio[1182]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) May 06 15:09:05 volumio volumio[1182]: at /volumio/app/plugins/music_service/mpd/index.js:3118:28 May 06 15:09:05 volumio volumio[1182]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 06 15:09:05 volumio volumio[1182]: at process._tickCallback (internal/process/next_tick.js:180:9) May 06 15:09:05 volumio volumio[1182]: generatedMessage: true, May 06 15:09:05 volumio volumio[1182]: name: 'AssertionError [ERR_ASSERTION]', May 06 15:09:05 volumio volumio[1182]: code: 'ERR_ASSERTION', May 06 15:09:05 volumio volumio[1182]: actual: false, May 06 15:09:05 volumio volumio[1182]: expected: true, May 06 15:09:05 volumio volumio[1182]: operator: '==' } May 06 15:09:05 volumio volumio[1182]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 15:09:06 volumio sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-06 15:08 May 06 15:09:06 volumio sudo[1361]: 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"