-- Logs begin at Fri 2024-09-06 13:03:57 UTC, end at Fri 2024-09-06 13:33:34 UTC. -- Sep 06 13:33:22 volumio systemd[1]: Time has been changed Sep 06 13:33:22 volumio volumio[1006]: info: Loading plugin "network"... Sep 06 13:33:22 volumio volumio[1006]: info: Refreshing Cached IP Addresses Sep 06 13:33:22 volumio sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 13:33:22 volumio volumio[1006]: info: Loading plugin "services"... Sep 06 13:33:22 volumio volumio[1006]: info: Loading plugin "alsa_controller"... Sep 06 13:33:22 volumio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 13:33:22 volumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 13:33:22 volumio sudo[1033]: pam_unix(sudo:session): session closed for user root Sep 06 13:33:22 volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 13:33:22 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Sep 06 13:33:22 volumio volumio-remote-updater[582]: [2024-09-06 13:33:22] [connect] Successful connection Sep 06 13:33:22 volumio volumio[1006]: info: Loading plugin "wizard"... Sep 06 13:33:22 volumio volumio[1006]: info: Loading plugin "volumio_command_line_client"... Sep 06 13:33:22 volumio volumio[1006]: info: Loading plugin "upnp"... Sep 06 13:33:22 volumio volumio[1006]: info: [1725629602706] Starting Upmpd Daemon Sep 06 13:33:22 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 13:33:22 volumio volumio[1006]: info: Loading plugin "my_music"... Sep 06 13:33:22 volumio volumio[1006]: info: Loading plugin "mpd"... Sep 06 13:33:23 volumio volumio[1006]: info: Loading plugin "upnp_browser"... Sep 06 13:33:24 volumio volumio[1006]: info: Loading plugin "networkfs"... Sep 06 13:33:24 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 13:33:24 volumio volumio[1006]: info: Loading plugin "alarm-clock"... Sep 06 13:33:25 volumio volumio[1006]: info: Loading plugin "airplay_emulation"... Sep 06 13:33:25 volumio volumio[1006]: info: Starting Shairport Sync Sep 06 13:33:25 volumio volumio[1006]: info: Loading plugin "last_100"... Sep 06 13:33:25 volumio volumio[1006]: info: Loading plugin "webradio"... Sep 06 13:33:25 volumio volumio[1006]: info: Loading plugin "i2s_dacs"... Sep 06 13:33:25 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 13:33:25 volumio volumio[1006]: info: Loading plugin "volumiodiscovery"... Sep 06 13:33:25 volumio volumio[1006]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 06 13:33:25 volumio node[1006]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 06 13:33:25 volumio node[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 13:33:25 volumio node[1006]: *** WARNING *** For more information see Sep 06 13:33:25 volumio node[1006]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 06 13:33:25 volumio node[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 13:33:25 volumio node[1006]: *** WARNING *** For more information see Sep 06 13:33:25 volumio volumio[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 13:33:25 volumio volumio[1006]: *** WARNING *** For more information see Sep 06 13:33:25 volumio volumio[1006]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 06 13:33:25 volumio volumio[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 06 13:33:25 volumio volumio[1006]: *** WARNING *** For more information see Sep 06 13:33:25 volumio volumio[1006]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 06 13:33:25 volumio volumio[1006]: Discovery: StartAdv! undefined Sep 06 13:33:25 volumio volumio[1006]: Discovery: Started advertising... undefined - undefined Sep 06 13:33:25 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 13:33:25 volumio volumio[1006]: info: Loading plugin "outputs"... Sep 06 13:33:25 volumio volumio[1006]: info: Loading plugin "albumart"... Sep 06 13:33:25 volumio volumio[1006]: info: Plugin example_plugin is not enabled Sep 06 13:33:25 volumio volumio[1006]: info: Loading plugin "inputs"... Sep 06 13:33:25 volumio volumio[1006]: info: Loading plugin "updater_comm"... Sep 06 13:33:26 volumio volumio[1006]: info: Plugin mpdemulation is not enabled Sep 06 13:33:26 volumio volumio[1006]: info: Loading plugin "rest_api"... Sep 06 13:33:26 volumio volumio[1006]: info: Loading plugin "websocket"... Sep 06 13:33:26 volumio volumio[1006]: info: ___________ START PLUGINS ___________ Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 13:33:26 volumio volumio[1006]: info: [1725629606480] CoreMusicLibrary::Adding element Media Servers Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 13:33:26 volumio volumio[1006]: Forking 3 albumart workers Sep 06 13:33:26 volumio volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 13:33:26 volumio volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 13:33:26 volumio volumio[1006]: info: [1725629606745] CoreMusicLibrary::Adding element Last_100 Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 06 13:33:26 volumio volumio[1006]: info: [1725629606754] CoreMusicLibrary::Adding element Webradio Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 13:33:26 volumio volumio[1006]: info: ------------------------------------------- Sep 06 13:33:26 volumio volumio[1006]: info: ----- MyVolumio plugins startup ---- Sep 06 13:33:26 volumio volumio[1006]: info: ------------------------------------------- Sep 06 13:33:26 volumio volumio[1006]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 06 13:33:26 volumio volumio[1006]: info: Loading i18n strings for locale en Sep 06 13:33:26 volumio volumio[1006]: Updating browse sources language Sep 06 13:33:26 volumio volumio[1006]: Cannot find translation for sourceMedia Servers Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::initPlayerControls Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 13:33:26 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 06 13:33:26 volumio dhcpcd[503]: timed out Sep 06 13:33:26 volumio dhcpcd[503]: exited Sep 06 13:33:26 volumio ifup[469]: dhcpcd[503]: timed out Sep 06 13:33:26 volumio ifup[469]: dhcpcd[503]: exited Sep 06 13:33:26 volumio ifup[469]: Failed to bring up eth0. Sep 06 13:33:27 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 13:33:27 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 13:33:27 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 13:33:27 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 13:33:27 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 13:33:27 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 13:33:27 volumio volumio[1006]: info: BOOT COMPLETED Sep 06 13:33:27 volumio volumio[1006]: [Metrics] CommandRouter: 10s 546.05ms Sep 06 13:33:27 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 13:33:27 volumio volumio[1006]: info: CoreCommandRouter::Close All Modals sent Sep 06 13:33:27 volumio volumio[1006]: info: CoreCommandRouter::Close All Modals sent Sep 06 13:33:27 volumio volumio[1006]: Express server listening on port 3000 Sep 06 13:33:27 volumio volumio[1006]: [Metrics] WebUI: 11s 925.02ms Sep 06 13:33:27 volumio volumio[1006]: Volumio Calling Home Sep 06 13:33:27 volumio volumio[1006]: info: CoreStateMachine::resetVolumioState Sep 06 13:33:27 volumio volumio[1006]: info: CoreStateMachine::getcurrentVolume Sep 06 13:33:27 volumio volumio[1006]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 13:33:28 volumio volumio[1006]: Starting albumart workers Sep 06 13:33:28 volumio volumio[1006]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 06 13:33:28 volumio volumio[1006]: Starting albumart workers Sep 06 13:33:28 volumio volumio[1006]: info: Setting Device type: Raspberry PI Sep 06 13:33:28 volumio volumio[1006]: info: MPD running with PID658 Sep 06 13:33:28 volumio volumio[1006]: ,establishing connection Sep 06 13:33:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 06 13:33:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 13:33:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 06 13:33:28 volumio volumio[1006]: info: Version has changed, forcing UI Reload Sep 06 13:33:28 volumio volumio[1006]: info: CoreCommandRouter::Reload Ui Sep 06 13:33:28 volumio volumio[1006]: Starting albumart workers Sep 06 13:33:28 volumio volumio[1006]: info: Volumio called home Sep 06 13:33:28 volumio volumio[1006]: info: VolumeController:: Volume=undefined Mute =false Sep 06 13:33:29 volumio volumio[1006]: info: CoreStateMachine::pushState Sep 06 13:33:29 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Sep 06 13:33:29 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Sep 06 13:33:29 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 13:33:29 volumio volumio[1006]: info: CoreStateMachine::updateTrackBlock Sep 06 13:33:29 volumio volumio[1006]: info: CorePlayQueue::getTrackBlock Sep 06 13:33:29 volumio volumio[1006]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 13:33:29 volumio volumio[1006]: info: Reloading queue from file Sep 06 13:33:29 volumio volumio[1006]: error: updateQueue error: null Sep 06 13:33:29 volumio volumio[1006]: error: Failed LSINFO: Error: [50@0] {} No such directory Sep 06 13:33:29 volumio volumio[1006]: info: CoreStateMachine::setRepeat null single undefined Sep 06 13:33:29 volumio volumio[1006]: info: CoreStateMachine::pushState Sep 06 13:33:29 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Sep 06 13:33:29 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Sep 06 13:33:29 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 13:33:29 volumio volumio[1006]: info: CoreStateMachine::setRandom null Sep 06 13:33:29 volumio volumio[1006]: info: CoreStateMachine::pushState Sep 06 13:33:29 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Sep 06 13:33:29 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Sep 06 13:33:29 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 13:33:29 volumio volumio[1006]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 06 13:33:29 volumio volumio[1006]: info: Starting Shairport Sync Sep 06 13:33:29 volumio volumio[1006]: info: Starting Shairport Sync Sep 06 13:33:29 volumio sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 13:33:29 volumio sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 13:33:29 volumio sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 13:33:29 volumio sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 13:33:29 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 06 13:33:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 13:33:29 volumio volumio[1006]: info: VolumeController:: Volume=undefined Mute =false Sep 06 13:33:29 volumio volumio[1006]: info: CoreStateMachine::pushState Sep 06 13:33:29 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Sep 06 13:33:29 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Sep 06 13:33:29 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 13:33:29 volumio sudo[1126]: pam_unix(sudo:session): session closed for user root Sep 06 13:33:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 13:33:30 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 06 13:33:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 13:33:30 volumio sudo[1128]: pam_unix(sudo:session): session closed for user root Sep 06 13:33:30 volumio volumio[1006]: info: Shairport-Sync Started Sep 06 13:33:30 volumio volumio[1006]: Error adding Membership: Error: addMembership EINVAL Sep 06 13:33:30 volumio volumio[1006]: info: Shairport-Sync Started Sep 06 13:33:30 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Sep 06 13:33:30 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Sep 06 13:33:30 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 13:33:30 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 13:33:30 volumio volumio[1006]: Discovery: ecc TypeError: argument 8 must be an integer (port) Sep 06 13:33:30 volumio volumio[1006]: Discovery: StartAdv! false Sep 06 13:33:30 volumio volumio[1006]: Discovery: Started advertising... undefined - false Sep 06 13:33:30 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Sep 06 13:33:30 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Sep 06 13:33:31 volumio volumio[1006]: info: CoreCommandRouter::volumioGetVisibleSources Sep 06 13:33:31 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 13:33:31 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 06 13:33:31 volumio volumio[1006]: info: Listing playlists Sep 06 13:33:32 volumio volumio[1006]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 13:33:32 volumio volumio[1006]: TypeError: Cannot read property 'charAt' of undefined Sep 06 13:33:32 volumio volumio[1006]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Sep 06 13:33:32 volumio volumio[1006]: at ontimeout (timers.js:482:11) Sep 06 13:33:32 volumio volumio[1006]: at tryOnTimeout (timers.js:317:5) Sep 06 13:33:32 volumio volumio[1006]: at Timer.listOnTimeout (timers.js:277:5) Sep 06 13:33:32 volumio volumio[1006]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 13:33:34 volumio sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 13:32 Sep 06 13:33:34 volumio sudo[1167]: 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="14acf028cf46278bd3fd3dd02523424a9bc825bf" VOLUMIO_FE_VERSION="74f64fbba9dc8053dc27ed7125863d6ad6533965" VOLUMIO_BE_VERSION="0a6008ded215bf284d5d4cd9c4df3333cb320899" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Apr 24 17:39:58 CEST 2021" VOLUMIO_VERSION="2.882" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="1a98a01eef5aa5927a5874c2d3c1b0dc"