-- Logs begin at Sat 2022-10-08 11:18:23 UTC, end at Sun 2024-05-12 22:33:44 UTC. -- May 12 22:33:32 volumiogranja systemd[1]: Time has been changed May 12 22:33:32 volumiogranja volumio[818]: info: Loading plugin "network"... May 12 22:33:32 volumiogranja volumio[818]: info: Loading plugin "services"... May 12 22:33:32 volumiogranja volumio[818]: info: Loading plugin "alsa_controller"... May 12 22:33:33 volumiogranja volumio[818]: info: Loading plugin "wizard"... May 12 22:33:33 volumiogranja volumio[818]: info: Loading plugin "volumio_command_line_client"... May 12 22:33:33 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 22:33:33 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 22:33:33 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 22:33:33 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 22:33:33 volumiogranja volumio[818]: info: Loading plugin "upnp"... May 12 22:33:33 volumiogranja volumio[818]: info: [1715553213229] Starting Upmpd Daemon May 12 22:33:33 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 22:33:33 volumiogranja volumio[818]: info: Loading plugin "mpd"... May 12 22:33:33 volumiogranja volumio[818]: info: Loading plugin "upnp_browser"... May 12 22:33:35 volumiogranja volumio[818]: info: Loading plugin "networkfs"... May 12 22:33:35 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 22:33:35 volumiogranja volumio[818]: info: Loading plugin "alarm-clock"... May 12 22:33:35 volumiogranja volumio[818]: info: Loading plugin "airplay_emulation"... May 12 22:33:35 volumiogranja volumio[818]: info: [1715553215744] Starting Shairport Sync May 12 22:33:35 volumiogranja volumio[818]: info: Loading plugin "last_100"... May 12 22:33:35 volumiogranja volumio[818]: info: Loading plugin "webradio"... May 12 22:33:35 volumiogranja volumio[818]: info: Loading plugin "i2s_dacs"... May 12 22:33:35 volumiogranja volumio[818]: info: I2S DAC not set, start Auto-detection May 12 22:33:35 volumiogranja volumio[818]: info: Loading plugin "volumiodiscovery"... May 12 22:33:35 volumiogranja volumio[818]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 22:33:35 volumiogranja node[818]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 22:33:35 volumiogranja node[818]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 22:33:35 volumiogranja node[818]: *** WARNING *** For more information see May 12 22:33:35 volumiogranja node[818]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 22:33:35 volumiogranja node[818]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 22:33:35 volumiogranja node[818]: *** WARNING *** For more information see May 12 22:33:35 volumiogranja volumio[818]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 22:33:35 volumiogranja volumio[818]: *** WARNING *** For more information see May 12 22:33:35 volumiogranja volumio[818]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 22:33:35 volumiogranja volumio[818]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 22:33:35 volumiogranja volumio[818]: *** WARNING *** For more information see May 12 22:33:35 volumiogranja volumio[818]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 22:33:35 volumiogranja volumio[818]: Discovery: StartAdv! undefined May 12 22:33:35 volumiogranja volumio[818]: Discovery: Started advertising... undefined - undefined May 12 22:33:35 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 22:33:35 volumiogranja volumio[818]: info: Loading plugin "albumart"... May 12 22:33:35 volumiogranja volumio[818]: info: Plugin example_plugin is not enabled May 12 22:33:35 volumiogranja volumio[818]: info: Loading plugin "updater_comm"... May 12 22:33:36 volumiogranja volumio[818]: info: Plugin mpdemulation is not enabled May 12 22:33:36 volumiogranja volumio[818]: info: Loading plugin "rest_api"... May 12 22:33:36 volumiogranja volumio[818]: info: Loading plugin "websocket"... May 12 22:33:36 volumiogranja volumio[818]: info: ___________ START PLUGINS ___________ May 12 22:33:36 volumiogranja volumio[818]: info: PLUGIN START: appearance May 12 22:33:36 volumiogranja volumio[818]: info: PLUGIN START: wizard May 12 22:33:36 volumiogranja volumio[818]: info: PLUGIN START: upnp_browser May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 22:33:36 volumiogranja volumio[818]: info: [1715553216578] CoreMusicLibrary::Adding element Media Servers May 12 22:33:36 volumiogranja volumio[818]: info: PLUGIN START: last_100 May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 22:33:36 volumiogranja volumio[818]: info: [1715553216623] CoreMusicLibrary::Adding element Last_100 May 12 22:33:36 volumiogranja volumio[818]: info: PLUGIN START: webradio May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 22:33:36 volumiogranja volumio[818]: info: [1715553216631] CoreMusicLibrary::Adding element Webradio May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 22:33:36 volumiogranja volumio[818]: info: Loading i18n strings for locale es May 12 22:33:36 volumiogranja volumio[818]: Updating browse sources language May 12 22:33:36 volumiogranja volumio[818]: Cannot find translation for sourceMedia Servers May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::initPlayerControls May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 22:33:36 volumiogranja volumio[818]: info: BOOT COMPLETED May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 22:33:36 volumiogranja volumio[818]: info: CoreCommandRouter::Close All Modals sent May 12 22:33:36 volumiogranja volumio[818]: Express server listening on port 3000 May 12 22:33:36 volumiogranja volumio[818]: Volumio Calling Home May 12 22:33:37 volumiogranja volumio[818]: info: CoreStateMachine::resetVolumioState May 12 22:33:37 volumiogranja volumio[818]: info: CoreStateMachine::getcurrentVolume May 12 22:33:37 volumiogranja volumio[818]: info: CoreCommandRouter::volumioRetrievevolume May 12 22:33:37 volumiogranja volumio[818]: info: CoreStateMachine::updateTrackBlock May 12 22:33:37 volumiogranja volumio[818]: info: CorePlayQueue::getTrackBlock May 12 22:33:37 volumiogranja volumio[818]: info: Setting Device type: Raspberry PI May 12 22:33:37 volumiogranja volumio[818]: info: MPD running with PID558 ,establishing connection May 12 22:33:37 volumiogranja sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 May 12 22:33:37 volumiogranja sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 22:33:38 volumiogranja sudo[936]: pam_unix(sudo:session): session closed for user root May 12 22:33:38 volumiogranja volumio[818]: info: VolumeController:: Volume=90 Mute =false May 12 22:33:38 volumiogranja volumio[818]: info: CoreStateMachine::pushState May 12 22:33:38 volumiogranja volumio[818]: info: CoreStateMachine::getState May 12 22:33:38 volumiogranja volumio[818]: info: CorePlayQueue::getTrack 0 May 12 22:33:38 volumiogranja volumio[818]: info: CoreCommandRouter::volumioPushState May 12 22:33:38 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:33:38 volumiogranja volumio[818]: info: interfaceApi::pushState May 12 22:33:38 volumiogranja volumio[818]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} May 12 22:33:38 volumiogranja volumio[818]: info: Reloading queue from file May 12 22:33:38 volumiogranja volumio[818]: info: May 12 22:33:38 volumiogranja volumio[818]: info: CoreStateMachine::setRepeat null single undefined May 12 22:33:38 volumiogranja volumio[818]: info: CoreStateMachine::pushState May 12 22:33:38 volumiogranja volumio[818]: info: CoreStateMachine::getState May 12 22:33:38 volumiogranja volumio[818]: info: CorePlayQueue::getTrack 0 May 12 22:33:38 volumiogranja volumio[818]: info: CoreCommandRouter::volumioPushState May 12 22:33:38 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:33:38 volumiogranja volumio[818]: info: interfaceApi::pushState May 12 22:33:38 volumiogranja volumio[818]: info: CoreStateMachine::setRandom true May 12 22:33:38 volumiogranja volumio[818]: info: CoreStateMachine::pushState May 12 22:33:38 volumiogranja volumio[818]: info: CoreStateMachine::getState May 12 22:33:38 volumiogranja volumio[818]: info: CorePlayQueue::getTrack 0 May 12 22:33:38 volumiogranja volumio[818]: info: CoreCommandRouter::volumioPushState May 12 22:33:38 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:33:38 volumiogranja volumio[818]: info: interfaceApi::pushState May 12 22:33:38 volumiogranja volumio[818]: info: Pushing Favourites {"service":"webradio","uri":"http://192.168.1.80:8000/sendaviva.m3u","favourite":false} May 12 22:33:38 volumiogranja volumio[818]: info: Pushing Favourites {"service":"webradio","uri":"http://192.168.1.80:8000/sendaviva.m3u","favourite":false} May 12 22:33:38 volumiogranja volumio[818]: Discovery: adding fd334fbf-6eda-4523-953c-fa985f930e1d May 12 22:33:38 volumiogranja volumio[818]: info: mDNS: Found device Volumio-estacion May 12 22:33:38 volumiogranja sudo[949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 12 22:33:38 volumiogranja sudo[949]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 22:33:38 volumiogranja systemd[1]: Starting ShairportSync AirTunes receiver... May 12 22:33:38 volumiogranja systemd[1]: Started ShairportSync AirTunes receiver. May 12 22:33:38 volumiogranja sudo[949]: pam_unix(sudo:session): session closed for user root May 12 22:33:38 volumiogranja volumio[818]: info: May 12 22:33:38 volumiogranja volumio[818]: ---------------------------- Client requests Volume 90 May 12 22:33:38 volumiogranja volumio[818]: info: VolumeController::SetAlsaVolume90 May 12 22:33:38 volumiogranja shairport-sync[954]: Successful Startup May 12 22:33:39 volumiogranja volumio[818]: null May 12 22:33:39 volumiogranja volumio[818]: info: VolumeController::Volume 90 May 12 22:33:39 volumiogranja volumio[818]: info: CoreStateMachine::pushState May 12 22:33:39 volumiogranja volumio[818]: info: CoreStateMachine::getState May 12 22:33:39 volumiogranja volumio[818]: info: CorePlayQueue::getTrack 0 May 12 22:33:39 volumiogranja volumio[818]: info: CoreCommandRouter::volumioPushState May 12 22:33:39 volumiogranja volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:33:39 volumiogranja volumio[818]: info: interfaceApi::pushState May 12 22:33:39 volumiogranja volumio[818]: info: [1715553219208] Shairport-Sync Started May 12 22:33:39 volumiogranja volumio[818]: Error adding Membership: Error: addMembership EINVAL May 12 22:33:39 volumiogranja volumio[818]: info: Setting volume on startup at 90 May 12 22:33:39 volumiogranja volumio[818]: info: Pushing Favourites {"service":"webradio","uri":"http://192.168.1.80:8000/sendaviva.m3u","favourite":false} May 12 22:33:39 volumiogranja volumio[818]: info: CoreCommandRouter::volumioGetState May 12 22:33:39 volumiogranja volumio[818]: info: CoreStateMachine::getState May 12 22:33:39 volumiogranja volumio[818]: info: CorePlayQueue::getTrack 0 May 12 22:33:40 volumiogranja volumio[818]: Discovery: ecc TypeError: argument 8 must be an integer (port) May 12 22:33:40 volumiogranja volumio[818]: Discovery: StartAdv! false May 12 22:33:40 volumiogranja volumio[818]: Discovery: Started advertising... undefined - false May 12 22:33:43 volumiogranja volumio[818]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 22:33:43 volumiogranja volumio[818]: TypeError: Cannot read property 'charAt' of undefined May 12 22:33:43 volumiogranja volumio[818]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:193:27) May 12 22:33:43 volumiogranja volumio[818]: at ontimeout (timers.js:386:14) May 12 22:33:43 volumiogranja volumio[818]: at tryOnTimeout (timers.js:250:5) May 12 22:33:43 volumiogranja volumio[818]: at Timer.listOnTimeout (timers.js:214:5) May 12 22:33:43 volumiogranja volumio[818]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 22:33:43 volumiogranja kernel: Voltage normalised (0x00000000) May 12 22:33:44 volumiogranja sudo[969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 22:32 May 12 22:33:44 volumiogranja sudo[969]: 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="0047b8c9feaa5e4ceb66739aad5c179741acce54" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="9ec9402ca0a423856feda09b210aa4e517a345ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Mar 26 20:20:16 CEST 2018" VOLUMIO_VERSION="2.389" VOLUMIO_HARDWARE="pi"