-- Logs begin at Wed 2024-04-03 22:17:01 UTC, end at Tue 2024-04-16 16:22:40 UTC. -- Apr 16 16:22:02 ek-musica systemd[1]: Time has been changed Apr 16 16:22:03 ek-musica ntpd[839]: Listen normally on 4 wlan0 192.168.211.1 UDP 123 Apr 16 16:22:03 ek-musica ntpd[839]: peers refreshed Apr 16 16:22:08 ek-musica volumio-remote-updater[664]: [2024-04-16 16:22:08] [info] asio async_connect error: system:111 (Connection refused) Apr 16 16:22:08 ek-musica volumio-remote-updater[664]: [2024-04-16 16:22:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 16 16:22:08 ek-musica volumio-remote-updater[664]: [2024-04-16 16:22:08] [error] handle_connect error: Underlying Transport Error Apr 16 16:22:18 ek-musica volumio-remote-updater[664]: [2024-04-16 16:22:18] [info] asio async_connect error: system:111 (Connection refused) Apr 16 16:22:18 ek-musica volumio-remote-updater[664]: [2024-04-16 16:22:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 16 16:22:18 ek-musica volumio-remote-updater[664]: [2024-04-16 16:22:18] [error] handle_connect error: Underlying Transport Error Apr 16 16:22:23 ek-musica exim[1230]: 2024-04-16 16:22:23 1rs8ux-0000Jq-Ma <= root@ek-musica U=root P=local S=448 Apr 16 16:22:23 ek-musica exim[1230]: 2024-04-16 16:22:23 1rs8ux-0000Jq-Ma Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 16 16:22:23 ek-musica exim[1230]: exim: could not open panic log - aborting: see message(s) above Apr 16 16:22:24 ek-musica nmbd[1163]: [2024/04/16 16:22:24.010714, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 16 16:22:24 ek-musica nmbd[1163]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.2 for name WORKGROUP<1d>. Apr 16 16:22:24 ek-musica nmbd[1163]: This response was from IP 192.168.1.200, reporting an IP address of 192.168.1.200. Apr 16 16:22:26 ek-musica volumio[1246]: info: ------------------------------------------- Apr 16 16:22:26 ek-musica volumio[1246]: info: ----- Volumio2 ---- Apr 16 16:22:26 ek-musica volumio[1246]: info: ------------------------------------------- Apr 16 16:22:26 ek-musica volumio[1246]: info: ----- System startup ---- Apr 16 16:22:26 ek-musica volumio[1246]: info: ------------------------------------------- Apr 16 16:22:27 ek-musica volumio[1246]: info: MYVOLUMIO Environment detected Apr 16 16:22:27 ek-musica volumio[1246]: info: Plugin folders cleanup Apr 16 16:22:27 ek-musica volumio[1246]: info: Scanning into folder /volumio/app/plugins/ Apr 16 16:22:27 ek-musica volumio[1246]: info: Scanning category audio_interface Apr 16 16:22:27 ek-musica volumio[1246]: info: Scanning category miscellanea Apr 16 16:22:27 ek-musica volumio[1246]: info: Scanning category music_service Apr 16 16:22:27 ek-musica volumio[1246]: info: Scanning category plugins.json Apr 16 16:22:27 ek-musica volumio[1246]: info: Scanning category system_controller Apr 16 16:22:27 ek-musica volumio[1246]: info: Scanning category user_interface Apr 16 16:22:27 ek-musica volumio[1246]: info: Scanning into folder /data/plugins/ Apr 16 16:22:27 ek-musica volumio[1246]: info: Scanning category miscellanea Apr 16 16:22:27 ek-musica volumio[1246]: info: Plugin folders cleanup completed Apr 16 16:22:27 ek-musica volumio[1246]: info: ------------------------------------------- Apr 16 16:22:27 ek-musica volumio[1246]: info: ----- Core plugins startup ---- Apr 16 16:22:27 ek-musica volumio[1246]: info: ------------------------------------------- Apr 16 16:22:27 ek-musica volumio[1246]: info: Loading plugins from folder /volumio/app/plugins/ Apr 16 16:22:27 ek-musica volumio[1246]: info: Adding plugin upnp to MyMusic Plugins Apr 16 16:22:27 ek-musica volumio[1246]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 16 16:22:27 ek-musica volumio[1246]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 16 16:22:27 ek-musica volumio[1246]: info: Loading plugins from folder /data/plugins/ Apr 16 16:22:27 ek-musica volumio[1246]: info: Loading plugin "system"... Apr 16 16:22:27 ek-musica volumio[1246]: info: Loading plugin "appearance"... Apr 16 16:22:28 ek-musica volumio-remote-updater[664]: [2024-04-16 16:22:28] [connect] Successful connection Apr 16 16:22:29 ek-musica volumio[1246]: info: Loading plugin "network"... Apr 16 16:22:29 ek-musica volumio[1246]: info: Refreshing Cached IP Addresses Apr 16 16:22:29 ek-musica volumio[1246]: info: Loading plugin "services"... Apr 16 16:22:29 ek-musica volumio[1246]: info: Loading plugin "alsa_controller"... Apr 16 16:22:29 ek-musica volumio[1246]: info: Loading plugin "wizard"... Apr 16 16:22:29 ek-musica sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 16:22:29 ek-musica volumio[1246]: info: Loading plugin "volumio_command_line_client"... Apr 16 16:22:29 ek-musica sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:22:29 ek-musica sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:22:29 ek-musica sudo[1293]: pam_unix(sudo:session): session closed for user root Apr 16 16:22:29 ek-musica sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:22:29 ek-musica sudo[1295]: pam_unix(sudo:session): session closed for user root Apr 16 16:22:29 ek-musica volumio[1246]: info: Loading plugin "upnp"... Apr 16 16:22:29 ek-musica volumio[1246]: info: [1713284549486] Starting Upmpd Daemon Apr 16 16:22:29 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 16 16:22:29 ek-musica volumio[1246]: info: Loading plugin "my_music"... Apr 16 16:22:29 ek-musica volumio[1246]: info: Loading plugin "mpd"... Apr 16 16:22:30 ek-musica volumio[1246]: info: Loading plugin "upnp_browser"... Apr 16 16:22:30 ek-musica volumio[1246]: info: Loading plugin "networkfs"... Apr 16 16:22:30 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 16 16:22:30 ek-musica volumio[1246]: info: Loading plugin "alarm-clock"... Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "airplay_emulation"... Apr 16 16:22:31 ek-musica volumio[1246]: info: Starting Shairport Sync Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "last_100"... Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "webradio"... Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "i2s_dacs"... Apr 16 16:22:31 ek-musica volumio[1246]: info: I2S DAC not set, start Auto-detection Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "volumiodiscovery"... Apr 16 16:22:31 ek-musica volumio[1246]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 16 16:22:31 ek-musica node[1246]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 16 16:22:31 ek-musica node[1246]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 16:22:31 ek-musica node[1246]: *** WARNING *** For more information see Apr 16 16:22:31 ek-musica node[1246]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 16 16:22:31 ek-musica node[1246]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 16:22:31 ek-musica node[1246]: *** WARNING *** For more information see Apr 16 16:22:31 ek-musica volumio[1246]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 16:22:31 ek-musica volumio[1246]: *** WARNING *** For more information see Apr 16 16:22:31 ek-musica volumio[1246]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 16 16:22:31 ek-musica volumio[1246]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 16:22:31 ek-musica volumio[1246]: *** WARNING *** For more information see Apr 16 16:22:31 ek-musica volumio[1246]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 16 16:22:31 ek-musica volumio[1246]: Discovery: StartAdv! undefined Apr 16 16:22:31 ek-musica volumio[1246]: Discovery: Started advertising... undefined - undefined Apr 16 16:22:31 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "auto_play"... Apr 16 16:22:31 ek-musica volumio[1246]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "autostart"... Apr 16 16:22:31 ek-musica volumio[1246]: info: Applying required configuration parameters for plugin autostart Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "outputs"... Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "albumart"... Apr 16 16:22:31 ek-musica volumio[1246]: info: Plugin example_plugin is not enabled Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "inputs"... Apr 16 16:22:31 ek-musica volumio[1246]: info: Loading plugin "updater_comm"... Apr 16 16:22:32 ek-musica volumio[1246]: info: Plugin mpdemulation is not enabled Apr 16 16:22:32 ek-musica volumio[1246]: info: Loading plugin "rest_api"... Apr 16 16:22:32 ek-musica volumio[1246]: info: Loading plugin "websocket"... Apr 16 16:22:32 ek-musica volumio[1246]: info: ___________ START PLUGINS ___________ Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 16 16:22:32 ek-musica volumio[1246]: info: [1713284552077] CoreMusicLibrary::Adding element Media Servers Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 16:22:32 ek-musica volumio[1246]: Forking 3 albumart workers Apr 16 16:22:32 ek-musica volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 16:22:32 ek-musica volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 16 16:22:32 ek-musica volumio[1246]: info: [1713284552222] CoreMusicLibrary::Adding element Last_100 Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 16 16:22:32 ek-musica volumio[1246]: info: [1713284552228] CoreMusicLibrary::Adding element Webradio Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 16:22:32 ek-musica volumio[1246]: info: ------------------------------------------- Apr 16 16:22:32 ek-musica volumio[1246]: info: ----- MyVolumio plugins startup ---- Apr 16 16:22:32 ek-musica volumio[1246]: info: ------------------------------------------- Apr 16 16:22:32 ek-musica volumio[1246]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 16 16:22:32 ek-musica volumio[1246]: info: Loading i18n strings for locale es Apr 16 16:22:32 ek-musica volumio[1246]: Updating browse sources language Apr 16 16:22:32 ek-musica volumio[1246]: Cannot find translation for sourceMedia Servers Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::initPlayerControls Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 16:22:32 ek-musica volumio[1246]: info: BOOT COMPLETED Apr 16 16:22:32 ek-musica volumio[1246]: [Metrics] CommandRouter: 6s 35.43ms Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::Close All Modals sent Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::Close All Modals sent Apr 16 16:22:32 ek-musica volumio[1246]: Express server listening on port 3000 Apr 16 16:22:32 ek-musica volumio[1246]: [Metrics] WebUI: 6s 929.31ms Apr 16 16:22:32 ek-musica volumio[1246]: Volumio Calling Home Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreStateMachine::resetVolumioState Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreStateMachine::getcurrentVolume Apr 16 16:22:32 ek-musica volumio[1246]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 16:22:33 ek-musica volumio[1246]: info: Setting Device type: Raspberry PI Apr 16 16:22:33 ek-musica volumio[1246]: info: MPD running with PID735 Apr 16 16:22:33 ek-musica volumio[1246]: ,establishing connection Apr 16 16:22:33 ek-musica volumio[1246]: Starting albumart workers Apr 16 16:22:33 ek-musica volumio[1246]: Starting albumart workers Apr 16 16:22:33 ek-musica volumio[1246]: Starting albumart workers Apr 16 16:22:33 ek-musica volumio[1246]: info: VolumeController:: Volume=40 Mute =false Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreStateMachine::pushState Apr 16 16:22:33 ek-musica sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Apr 16 16:22:33 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::volumioPushState Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreStateMachine::updateTrackBlock Apr 16 16:22:33 ek-musica sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:22:33 ek-musica volumio[1246]: info: CorePlayQueue::getTrackBlock Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 16:22:33 ek-musica volumio[1246]: info: Reloading queue from file Apr 16 16:22:33 ek-musica sudo[1362]: pam_unix(sudo:session): session closed for user root Apr 16 16:22:33 ek-musica volumio[1246]: error: updateQueue error: null Apr 16 16:22:33 ek-musica volumio[1246]: error: Failed LSINFO: Error: [50@0] {} No such directory Apr 16 16:22:33 ek-musica hotspot[1243]: wlan0: STA a6:10:c4:b3:e6:64 IEEE 802.11: associated Apr 16 16:22:33 ek-musica hostapd[1275]: wlan0: STA a6:10:c4:b3:e6:64 IEEE 802.11: associated Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 16:22:33 ek-musica hotspot[1243]: wlan0: AP-STA-CONNECTED a6:10:c4:b3:e6:64 Apr 16 16:22:33 ek-musica hostapd[1275]: wlan0: STA a6:10:c4:b3:e6:64 RADIUS: starting accounting session 86F49E078628BFEE Apr 16 16:22:33 ek-musica hostapd[1275]: wlan0: STA a6:10:c4:b3:e6:64 WPA: pairwise key handshake completed (RSN) Apr 16 16:22:33 ek-musica hotspot[1243]: wlan0: STA a6:10:c4:b3:e6:64 RADIUS: starting accounting session 86F49E078628BFEE Apr 16 16:22:33 ek-musica hotspot[1243]: wlan0: STA a6:10:c4:b3:e6:64 WPA: pairwise key handshake completed (RSN) Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreStateMachine::setRepeat true single undefined Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreStateMachine::pushState Apr 16 16:22:33 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::volumioPushState Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreStateMachine::setRandom false Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreStateMachine::pushState Apr 16 16:22:33 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::volumioPushState Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 16:22:33 ek-musica volumio[1246]: info: Starting Shairport Sync Apr 16 16:22:33 ek-musica volumio[1246]: info: Starting Shairport Sync Apr 16 16:22:33 ek-musica dhcpd[1263]: DHCPDISCOVER from a6:10:c4:b3:e6:64 via wlan0 Apr 16 16:22:33 ek-musica volumio[1246]: info: VolumeController:: Volume=40 Mute =false Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreStateMachine::pushState Apr 16 16:22:33 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::volumioPushState Apr 16 16:22:33 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:22:33 ek-musica dhcpd[1263]: Wrote 6 leases to leases file. Apr 16 16:22:33 ek-musica volumio[1246]: info: ControllerAutoPlay - mpd ready Apr 16 16:22:34 ek-musica sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 16:22:34 ek-musica sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 16:22:34 ek-musica sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:22:34 ek-musica sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:22:34 ek-musica systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 16 16:22:34 ek-musica systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 16 16:22:34 ek-musica sudo[1397]: pam_unix(sudo:session): session closed for user root Apr 16 16:22:34 ek-musica volumio[1246]: info: Shairport-Sync Started Apr 16 16:22:34 ek-musica volumio[1246]: Error adding Membership: Error: addMembership EINVAL Apr 16 16:22:34 ek-musica sudo[1391]: pam_unix(sudo:session): session closed for user root Apr 16 16:22:34 ek-musica volumio[1246]: info: Shairport-Sync Started Apr 16 16:22:34 ek-musica dhcpd[1263]: DHCPOFFER on 192.168.211.5 to a6:10:c4:b3:e6:64 via wlan0 Apr 16 16:22:34 ek-musica dhcpd[1263]: DHCPREQUEST for 192.168.211.5 (192.168.211.1) from a6:10:c4:b3:e6:64 via wlan0 Apr 16 16:22:34 ek-musica dhcpd[1263]: DHCPACK on 192.168.211.5 to a6:10:c4:b3:e6:64 via wlan0 Apr 16 16:22:35 ek-musica volumio[1246]: info: CoreCommandRouter::volumioGetState Apr 16 16:22:35 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:36 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 16:22:36 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:22:36 ek-musica volumio[1246]: Discovery: ecc TypeError: argument 8 must be an integer (port) Apr 16 16:22:36 ek-musica volumio[1246]: Discovery: StartAdv! false Apr 16 16:22:36 ek-musica volumio[1246]: Discovery: Started advertising... undefined - false Apr 16 16:22:38 ek-musica volumio[1246]: info: ControllerAutoPlay - getting queue Apr 16 16:22:38 ek-musica volumio[1246]: info: CoreCommandRouter::volumioGetQueue Apr 16 16:22:38 ek-musica volumio[1246]: info: CoreStateMachine::getQueue Apr 16 16:22:38 ek-musica volumio[1246]: info: CorePlayQueue::getQueue Apr 16 16:22:38 ek-musica volumio[1246]: info: ControllerAutoPlay - start playing -> queue is not empty Apr 16 16:22:38 ek-musica volumio[1246]: info: CoreCommandRouter::volumioPlay Apr 16 16:22:38 ek-musica volumio[1246]: UNSET VOLATILE Apr 16 16:22:38 ek-musica volumio[1246]: info: CoreStateMachine::play index undefined Apr 16 16:22:38 ek-musica volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:22:38 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:38 ek-musica volumio[1246]: info: CoreStateMachine::startPlaybackTimer Apr 16 16:22:38 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:38 ek-musica volumio[1246]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/EKM_Normalizado/Angèle - Oui ou Non.mp3 Apr 16 16:22:38 ek-musica volumio[1246]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 16:22:38 ek-musica volumio[1246]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 16:22:39 ek-musica volumio[1246]: info: Apr 16 16:22:39 ek-musica volumio[1246]: ---------------------------- MPD announces system playlist update Apr 16 16:22:39 ek-musica volumio[1246]: info: Ignoring MPD Status Update Apr 16 16:22:39 ek-musica volumio[1246]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/EKM_Normalizado/Angèle - Oui ou Non.mp3" Apr 16 16:22:39 ek-musica volumio[1246]: error: updateQueue error: null Apr 16 16:22:39 ek-musica volumio[1246]: info: Apr 16 16:22:39 ek-musica volumio[1246]: ---------------------------- MPD announces system playlist update Apr 16 16:22:39 ek-musica volumio[1246]: info: Ignoring MPD Status Update Apr 16 16:22:39 ek-musica volumio[1246]: info: ------------------------------ 8ms Apr 16 16:22:39 ek-musica volumio[1246]: verbose: ControllerMpd::sendMpdCommand play Apr 16 16:22:39 ek-musica volumio[1246]: info: ------------------------------ 6ms Apr 16 16:22:39 ek-musica volumio[1246]: info: Apr 16 16:22:39 ek-musica volumio[1246]: ---------------------------- MPD announces state update: player Apr 16 16:22:39 ek-musica volumio[1246]: info: ControllerMpd::getState Apr 16 16:22:39 ek-musica volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:22:39 ek-musica volumio[1246]: info: Apr 16 16:22:39 ek-musica volumio[1246]: ---------------------------- MPD announces state update: player Apr 16 16:22:39 ek-musica volumio[1246]: info: ControllerMpd::getState Apr 16 16:22:39 ek-musica volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:22:39 ek-musica volumio[1246]: verbose: ControllerMpd::parseState Apr 16 16:22:39 ek-musica volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:22:39 ek-musica volumio[1246]: verbose: ControllerMpd::parseState Apr 16 16:22:39 ek-musica volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:22:39 ek-musica volumio[1246]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:22:39 ek-musica volumio[1246]: info: ControllerMpd::pushState Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreCommandRouter::servicePushState Apr 16 16:22:39 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:39 ek-musica volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":216,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Angèle - Oui ou Non.mp3","artist":null,"album":null,"uri":"INTERNAL/EKM_Normalizado/Angèle - Oui ou Non.mp3","trackType":"mp3"} Apr 16 16:22:39 ek-musica volumio[1246]: verbose: CURRENT POSITION 0 Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreStateMachine::syncState stateService play Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreStateMachine::syncState currentStatus stop Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreStateMachine::pushState Apr 16 16:22:39 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreCommandRouter::volumioPushState Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:22:39 ek-musica volumio[1246]: info: ------------------------------ 36ms Apr 16 16:22:39 ek-musica volumio[1246]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:22:39 ek-musica volumio[1246]: info: ControllerMpd::pushState Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreCommandRouter::servicePushState Apr 16 16:22:39 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:39 ek-musica volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":216,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Angèle - Oui ou Non.mp3","artist":null,"album":null,"uri":"INTERNAL/EKM_Normalizado/Angèle - Oui ou Non.mp3","trackType":"mp3"} Apr 16 16:22:39 ek-musica volumio[1246]: verbose: CURRENT POSITION 0 Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreStateMachine::syncState stateService play Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:22:39 ek-musica volumio[1246]: info: Received an update from plugin. extracting info from payload Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreStateMachine::pushState Apr 16 16:22:39 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreCommandRouter::volumioPushState Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreStateMachine::pushState Apr 16 16:22:39 ek-musica volumio[1246]: info: CorePlayQueue::getTrack 0 Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreCommandRouter::volumioPushState Apr 16 16:22:39 ek-musica volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:22:39 ek-musica volumio[1246]: info: ------------------------------ 51ms Apr 16 16:22:39 ek-musica volumio[1246]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 16:22:39 ek-musica volumio[1246]: TypeError: Cannot read property 'charAt' of undefined Apr 16 16:22:39 ek-musica volumio[1246]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 16 16:22:39 ek-musica volumio[1246]: at ontimeout (timers.js:482:11) Apr 16 16:22:39 ek-musica volumio[1246]: at tryOnTimeout (timers.js:317:5) Apr 16 16:22:39 ek-musica volumio[1246]: at Timer.listOnTimeout (timers.js:277:5) Apr 16 16:22:39 ek-musica volumio[1246]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 16:22:40 ek-musica sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-16 16:21 Apr 16 16:22:40 ek-musica sudo[1434]: 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="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 22 14:39:52 CET 2020" VOLUMIO_VERSION="2.861" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="097e28ee6263e31d0bf6b690a02dcab3"