-- Logs begin at Sun 2023-12-24 07:39:06 UTC, end at Wed 2024-05-08 00:39:10 UTC. -- May 08 00:38:53 volumio_es9023 systemd[1]: Time has been changed May 08 00:38:54 volumio_es9023 volumio[1007]: info: ------------------------------------------- May 08 00:38:54 volumio_es9023 volumio[1007]: info: ----- Volumio2 ---- May 08 00:38:54 volumio_es9023 volumio[1007]: info: ------------------------------------------- May 08 00:38:54 volumio_es9023 volumio[1007]: info: ----- System startup ---- May 08 00:38:54 volumio_es9023 volumio[1007]: info: ------------------------------------------- May 08 00:38:55 volumio_es9023 volumio-remote-updater[721]: [2024-05-08 00:38:55] [connect] Successful connection May 08 00:38:57 volumio_es9023 volumio[1007]: info: MYVOLUMIO Environment detected May 08 00:38:57 volumio_es9023 volumio[1007]: info: Plugin folders cleanup May 08 00:38:57 volumio_es9023 volumio[1007]: info: Scanning into folder /volumio/app/plugins/ May 08 00:38:57 volumio_es9023 volumio[1007]: info: Scanning category audio_interface May 08 00:38:57 volumio_es9023 volumio[1007]: info: Scanning category miscellanea May 08 00:38:57 volumio_es9023 volumio[1007]: info: Scanning category music_service May 08 00:38:57 volumio_es9023 volumio[1007]: info: Scanning category plugins.json May 08 00:38:57 volumio_es9023 volumio[1007]: info: Scanning category system_controller May 08 00:38:58 volumio_es9023 volumio[1007]: info: Scanning category user_interface May 08 00:38:58 volumio_es9023 volumio[1007]: info: Scanning into folder /data/plugins/ May 08 00:38:58 volumio_es9023 volumio[1007]: info: Scanning category miscellanea May 08 00:38:58 volumio_es9023 volumio[1007]: info: Plugin folders cleanup completed May 08 00:38:58 volumio_es9023 volumio[1007]: info: ------------------------------------------- May 08 00:38:58 volumio_es9023 volumio[1007]: info: ----- Core plugins startup ---- May 08 00:38:58 volumio_es9023 volumio[1007]: info: ------------------------------------------- May 08 00:38:58 volumio_es9023 volumio[1007]: info: Loading plugins from folder /volumio/app/plugins/ May 08 00:38:58 volumio_es9023 volumio[1007]: info: Adding plugin upnp to MyMusic Plugins May 08 00:38:58 volumio_es9023 volumio[1007]: info: Adding plugin airplay_emulation to MyMusic Plugins May 08 00:38:58 volumio_es9023 volumio[1007]: info: Adding plugin upnp_browser to MyMusic Plugins May 08 00:38:58 volumio_es9023 volumio[1007]: info: Loading plugins from folder /data/plugins/ May 08 00:38:58 volumio_es9023 volumio[1007]: info: Loading plugin "system"... May 08 00:38:58 volumio_es9023 volumio[1007]: info: Loading plugin "appearance"... May 08 00:39:00 volumio_es9023 volumio[1007]: info: Loading plugin "network"... May 08 00:39:00 volumio_es9023 volumio[1007]: info: Refreshing Cached IP Addresses May 08 00:39:00 volumio_es9023 sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 00:39:00 volumio_es9023 volumio[1007]: info: Loading plugin "services"... May 08 00:39:00 volumio_es9023 volumio[1007]: info: Loading plugin "alsa_controller"... May 08 00:39:00 volumio_es9023 sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 00:39:00 volumio_es9023 sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 00:39:00 volumio_es9023 sudo[1141]: pam_unix(sudo:session): session closed for user root May 08 00:39:00 volumio_es9023 sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 00:39:00 volumio_es9023 sudo[1143]: pam_unix(sudo:session): session closed for user root May 08 00:39:00 volumio_es9023 volumio[1007]: info: Loading plugin "wizard"... May 08 00:39:01 volumio_es9023 volumio[1007]: info: Loading plugin "volumio_command_line_client"... May 08 00:39:01 volumio_es9023 volumio[1007]: info: Loading plugin "upnp"... May 08 00:39:01 volumio_es9023 volumio[1007]: info: [1715128741090] Starting Upmpd Daemon May 08 00:39:01 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 00:39:01 volumio_es9023 volumio[1007]: info: Loading plugin "my_music"... May 08 00:39:01 volumio_es9023 volumio[1007]: info: Loading plugin "mpd"... May 08 00:39:02 volumio_es9023 volumio[1007]: info: Loading plugin "upnp_browser"... May 08 00:39:03 volumio_es9023 volumio[1007]: info: Loading plugin "networkfs"... May 08 00:39:03 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 00:39:03 volumio_es9023 volumio[1007]: info: Loading plugin "alarm-clock"... May 08 00:39:03 volumio_es9023 volumio[1007]: info: Loading plugin "airplay_emulation"... May 08 00:39:04 volumio_es9023 volumio[1007]: info: Starting Shairport Sync May 08 00:39:04 volumio_es9023 volumio[1007]: info: Loading plugin "last_100"... May 08 00:39:04 volumio_es9023 volumio[1007]: info: Loading plugin "webradio"... May 08 00:39:04 volumio_es9023 volumio[1007]: info: Loading plugin "i2s_dacs"... May 08 00:39:04 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 00:39:04 volumio_es9023 volumio[1007]: info: Loading plugin "volumiodiscovery"... May 08 00:39:04 volumio_es9023 volumio[1007]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 08 00:39:04 volumio_es9023 node[1007]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 08 00:39:04 volumio_es9023 node[1007]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 00:39:04 volumio_es9023 node[1007]: *** WARNING *** For more information see May 08 00:39:04 volumio_es9023 node[1007]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 08 00:39:04 volumio_es9023 node[1007]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 00:39:04 volumio_es9023 node[1007]: *** WARNING *** For more information see May 08 00:39:04 volumio_es9023 volumio[1007]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 00:39:04 volumio_es9023 volumio[1007]: *** WARNING *** For more information see May 08 00:39:04 volumio_es9023 volumio[1007]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 08 00:39:04 volumio_es9023 volumio[1007]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 00:39:04 volumio_es9023 volumio[1007]: *** WARNING *** For more information see May 08 00:39:04 volumio_es9023 volumio[1007]: info: Applying required configuration parameters for plugin volumiodiscovery May 08 00:39:04 volumio_es9023 volumio[1007]: Discovery: StartAdv! undefined May 08 00:39:04 volumio_es9023 volumio[1007]: Discovery: Started advertising... Volumioes9023 - undefined May 08 00:39:04 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 00:39:04 volumio_es9023 volumio[1007]: info: Loading plugin "auto_play"... May 08 00:39:04 volumio_es9023 volumio[1007]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 May 08 00:39:04 volumio_es9023 volumio[1007]: info: Loading plugin "outputs"... May 08 00:39:04 volumio_es9023 volumio[1007]: info: Loading plugin "albumart"... May 08 00:39:04 volumio_es9023 volumio[1007]: info: Plugin example_plugin is not enabled May 08 00:39:04 volumio_es9023 volumio[1007]: info: Loading plugin "inputs"... May 08 00:39:04 volumio_es9023 volumio[1007]: info: Loading plugin "updater_comm"... May 08 00:39:05 volumio_es9023 volumio[1007]: info: Plugin mpdemulation is not enabled May 08 00:39:05 volumio_es9023 volumio[1007]: info: Loading plugin "rest_api"... May 08 00:39:05 volumio_es9023 volumio[1007]: info: Loading plugin "websocket"... May 08 00:39:05 volumio_es9023 volumio[1007]: info: ___________ START PLUGINS ___________ May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 00:39:05 volumio_es9023 volumio[1007]: info: [1715128745177] CoreMusicLibrary::Adding element Media Servers May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: Forking 3 albumart workers May 08 00:39:05 volumio_es9023 volumio[1007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 00:39:05 volumio_es9023 volumio[1007]: info: [1715128745446] CoreMusicLibrary::Adding element Last_100 May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 00:39:05 volumio_es9023 volumio[1007]: info: [1715128745455] CoreMusicLibrary::Adding element Webradio May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 00:39:05 volumio_es9023 volumio[1007]: info: ------------------------------------------- May 08 00:39:05 volumio_es9023 volumio[1007]: info: ----- MyVolumio plugins startup ---- May 08 00:39:05 volumio_es9023 volumio[1007]: info: ------------------------------------------- May 08 00:39:05 volumio_es9023 volumio[1007]: info: [MyVolumio PluginManager] Fetching plans data.... May 08 00:39:05 volumio_es9023 volumio[1007]: info: Loading i18n strings for locale ja May 08 00:39:05 volumio_es9023 volumio[1007]: Updating browse sources language May 08 00:39:05 volumio_es9023 volumio[1007]: Cannot find translation for sourceMedia Servers May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::initPlayerControls May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: info: BOOT COMPLETED May 08 00:39:05 volumio_es9023 volumio[1007]: [Metrics] CommandRouter: 10s 876.91ms May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 00:39:05 volumio_es9023 volumio[1007]: ALSA lib pcm.c:2495:(snd_pcm_open_noupdate) Unknown PCM softvolume May 08 00:39:05 volumio_es9023 volumio[1007]: aplay: main:722: audio open error: No such file or directory May 08 00:39:05 volumio_es9023 volumio[1007]: Cannot play startup sound May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::Close All Modals sent May 08 00:39:05 volumio_es9023 volumio[1007]: info: CoreCommandRouter::Close All Modals sent May 08 00:39:05 volumio_es9023 volumio[1007]: Express server listening on port 3000 May 08 00:39:05 volumio_es9023 volumio[1007]: [Metrics] WebUI: 12s 748.83ms May 08 00:39:05 volumio_es9023 volumio[1007]: Volumio Calling Home May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreStateMachine::resetVolumioState May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreStateMachine::getcurrentVolume May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreCommandRouter::volumioRetrievevolume May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreStateMachine::pushState May 08 00:39:06 volumio_es9023 volumio[1007]: info: CorePlayQueue::getTrack 0 May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreCommandRouter::volumioPushState May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreStateMachine::updateTrackBlock May 08 00:39:06 volumio_es9023 volumio[1007]: info: CorePlayQueue::getTrackBlock May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreCommandRouter::volumioRetrievevolume May 08 00:39:06 volumio_es9023 volumio[1007]: info: Setting Device type: Raspberry PI May 08 00:39:06 volumio_es9023 volumio[1007]: info: MPD running with PID788 May 08 00:39:06 volumio_es9023 volumio[1007]: ,establishing connection May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 00:39:06 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 08 00:39:07 volumio_es9023 volumio[1007]: Starting albumart workers May 08 00:39:07 volumio_es9023 volumio[1007]: Starting albumart workers May 08 00:39:07 volumio_es9023 volumio[1007]: Starting albumart workers May 08 00:39:07 volumio_es9023 volumio[1007]: info: CoreStateMachine::pushState May 08 00:39:07 volumio_es9023 volumio[1007]: info: CorePlayQueue::getTrack 0 May 08 00:39:07 volumio_es9023 volumio[1007]: info: CoreCommandRouter::volumioPushState May 08 00:39:07 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 00:39:07 volumio_es9023 volumio[1007]: info: Reloading queue from file May 08 00:39:07 volumio_es9023 volumio[1007]: info: May 08 00:39:07 volumio_es9023 volumio[1007]: ---------------------------- Client requests Volume 60 May 08 00:39:07 volumio_es9023 volumio[1007]: info: VolumeController::SetAlsaVolume60 May 08 00:39:07 volumio_es9023 volumio[1007]: info: CoreStateMachine::pushState May 08 00:39:07 volumio_es9023 volumio[1007]: info: CorePlayQueue::getTrack 0 May 08 00:39:07 volumio_es9023 volumio[1007]: info: CoreCommandRouter::volumioPushState May 08 00:39:07 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 00:39:07 volumio_es9023 volumio[1007]: error: updateQueue error: null May 08 00:39:08 volumio_es9023 volumio[1007]: info: CoreStateMachine::setRepeat true single undefined May 08 00:39:08 volumio_es9023 volumio[1007]: info: CoreStateMachine::pushState May 08 00:39:08 volumio_es9023 volumio[1007]: info: CorePlayQueue::getTrack 0 May 08 00:39:08 volumio_es9023 volumio[1007]: info: CoreCommandRouter::volumioPushState May 08 00:39:08 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 00:39:08 volumio_es9023 volumio[1007]: info: CoreStateMachine::setRandom true May 08 00:39:08 volumio_es9023 volumio[1007]: info: CoreStateMachine::pushState May 08 00:39:08 volumio_es9023 volumio[1007]: info: CorePlayQueue::getTrack 0 May 08 00:39:08 volumio_es9023 volumio[1007]: info: CoreCommandRouter::volumioPushState May 08 00:39:08 volumio_es9023 volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 00:39:08 volumio_es9023 volumio[1007]: info: Setting volume on startup at 60 May 08 00:39:08 volumio_es9023 volumio[1007]: info: Starting Shairport Sync May 08 00:39:08 volumio_es9023 volumio[1007]: info: Starting Shairport Sync May 08 00:39:08 volumio_es9023 sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 00:39:08 volumio_es9023 sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 00:39:08 volumio_es9023 sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 00:39:08 volumio_es9023 sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 00:39:08 volumio_es9023 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 08 00:39:08 volumio_es9023 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 00:39:08 volumio_es9023 sudo[1279]: pam_unix(sudo:session): session closed for user root May 08 00:39:08 volumio_es9023 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 00:39:08 volumio_es9023 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 08 00:39:08 volumio_es9023 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 00:39:08 volumio_es9023 sudo[1280]: pam_unix(sudo:session): session closed for user root May 08 00:39:08 volumio_es9023 volumio[1007]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 00:39:08 volumio_es9023 volumio[1007]: Error: Non okay return status: "Title: THE GOODBYE LOOK" May 08 00:39:08 volumio_es9023 volumio[1007]: at MPD._checkReturn (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:353:10) May 08 00:39:08 volumio_es9023 volumio[1007]: at MPD. (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:188:18) May 08 00:39:08 volumio_es9023 volumio[1007]: at MPD._handleResponse (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:450:3) May 08 00:39:08 volumio_es9023 volumio[1007]: at MPD._onData (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:344:10) May 08 00:39:08 volumio_es9023 volumio[1007]: at emitOne (events.js:116:13) May 08 00:39:08 volumio_es9023 volumio[1007]: at Socket.emit (events.js:211:7) May 08 00:39:08 volumio_es9023 volumio[1007]: at addChunk (_stream_readable.js:263:12) May 08 00:39:08 volumio_es9023 volumio[1007]: at readableAddChunk (_stream_readable.js:246:13) May 08 00:39:08 volumio_es9023 volumio[1007]: at Socket.Readable.push (_stream_readable.js:208:10) May 08 00:39:08 volumio_es9023 volumio[1007]: at TCP.onread (net.js:607:20) May 08 00:39:08 volumio_es9023 volumio[1007]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 00:39:10 volumio_es9023 sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 00:38 May 08 00:39:10 volumio_es9023 sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 00:39:10 volumio_es9023 volumio-remote-updater[721]: [2024-05-08 00:39:10] [connect] Successful connection 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="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"