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