-- 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"