-- Logs begin at Tue 2020-12-22 13:39:52 UTC, end at Mon 2024-10-28 09:20:08 UTC. -- Oct 28 09:20:02 volumio systemd[1]: Time has been changed Oct 28 09:20:02 volumio volumio[820]: info: Applying required configuration parameters for plugin allo_relay_volume_attenuator Oct 28 09:20:02 volumio volumio[820]: info: Loading plugin "RoonBridge"... Oct 28 09:20:02 volumio volumio[820]: Starting albumart workers Oct 28 09:20:02 volumio volumio[820]: Starting albumart workers Oct 28 09:20:02 volumio volumio[820]: Starting albumart workers Oct 28 09:20:02 volumio volumio[820]: info: Applying required configuration parameters for plugin RoonBridge Oct 28 09:20:02 volumio volumio[820]: info: Loading plugin "audiophonicsonoff"... Oct 28 09:20:03 volumio volumio[820]: info: Audiophonics on/off initiated Oct 28 09:20:03 volumio volumio[820]: info: Loading plugin "backup_restore"... Oct 28 09:20:03 volumio volumio[820]: info: ___________ START PLUGINS ___________ Oct 28 09:20:04 volumio volumio[820]: info: [GPIO_Control] Reading config and creating GPIOs Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 09:20:04 volumio volumio[820]: info: [1730107204015] CoreMusicLibrary::Adding element Media Servers Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 09:20:04 volumio volumio[820]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 09:20:04 volumio volumio[820]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 09:20:04 volumio volumio[820]: info: [1730107204195] CoreMusicLibrary::Adding element Last_100 Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 09:20:04 volumio volumio[820]: info: [1730107204199] CoreMusicLibrary::Adding element Webradio Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 09:20:04 volumio volumio[820]: info: Configuring GPIO pins Oct 28 09:20:04 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay lirc-rpi gpio_in_pin=17 Oct 28 09:20:04 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 28 09:20:04 volumio volumio[820]: info: Soft shutdown GPIO binding... OK Oct 28 09:20:04 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 09:20:04 volumio volumio[820]: info: Hardware button GPIO binding... OK Oct 28 09:20:04 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 09:20:04 volumio volumio[820]: info: Boot OK GPIO binding... OK Oct 28 09:20:04 volumio volumio[820]: info: ------------------------------------------- Oct 28 09:20:04 volumio volumio[820]: info: ----- MyVolumio plugins startup ---- Oct 28 09:20:04 volumio volumio[820]: info: ------------------------------------------- Oct 28 09:20:04 volumio volumio[820]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 28 09:20:04 volumio systemd[1]: Starting RoonBridge... Oct 28 09:20:04 volumio systemd[1]: Started RoonBridge. Oct 28 09:20:04 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Oct 28 09:20:04 volumio volumio[820]: info: Loading i18n strings for locale it Oct 28 09:20:04 volumio volumio[820]: Updating browse sources language Oct 28 09:20:04 volumio volumio[820]: Cannot find translation for sourceMedia Servers Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 09:20:04 volumio sudo[1083]: pam_unix(sudo:session): session closed for user root Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::initPlayerControls Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 09:20:04 volumio volumio[820]: info: BOOT COMPLETED Oct 28 09:20:04 volumio volumio[820]: [Metrics] CommandRouter: 10s 178.68ms Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 09:20:04 volumio volumio[820]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card Oct 28 09:20:04 volumio volumio[820]: aplay: main:722: audio open error: No such file or directory Oct 28 09:20:04 volumio volumio[820]: Cannot play startup sound Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::Close All Modals sent Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::Close All Modals sent Oct 28 09:20:04 volumio volumio[820]: Express server listening on port 3000 Oct 28 09:20:04 volumio volumio[820]: [Metrics] WebUI: 11s 7.33ms Oct 28 09:20:04 volumio volumio[820]: Volumio Calling Home Oct 28 09:20:04 volumio volumio[820]: info: [GPIO_Control] GPIOs created Oct 28 09:20:04 volumio volumio[820]: info: CoreStateMachine::resetVolumioState Oct 28 09:20:04 volumio volumio[820]: info: CoreStateMachine::getcurrentVolume Oct 28 09:20:04 volumio volumio[820]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 09:20:05 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Oct 28 09:20:05 volumio volumio[820]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 28 09:20:05 volumio volumio[820]: info: Setting Device type: Raspberry PI Oct 28 09:20:05 volumio volumio[820]: info: MPD running with PID662 Oct 28 09:20:05 volumio volumio[820]: ,establishing connection Oct 28 09:20:05 volumio sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 28 09:20:05 volumio sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 09:20:05 volumio sudo[1279]: pam_unix(sudo:session): session closed for user root Oct 28 09:20:05 volumio volumio[820]: info: VolumeController:: Volume=undefined Mute =false Oct 28 09:20:05 volumio volumio[820]: info: CoreStateMachine::pushState Oct 28 09:20:05 volumio volumio[820]: info: CorePlayQueue::getTrack 0 Oct 28 09:20:05 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Oct 28 09:20:05 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 09:20:05 volumio volumio[820]: info: CoreStateMachine::updateTrackBlock Oct 28 09:20:05 volumio volumio[820]: info: CorePlayQueue::getTrackBlock Oct 28 09:20:05 volumio volumio[820]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 09:20:05 volumio volumio[820]: info: Error enabling lirc-rpi overlay: Error: Command failed: /usr/bin/sudo /usr/bin/dtoverlay lirc-rpi gpio_in_pin=17 Oct 28 09:20:05 volumio volumio[820]: DTOVERLAY[error]: failed to open '/boot/overlays/lirc-rpi.dtbo' Oct 28 09:20:05 volumio volumio[820]: * Failed to read '/boot/overlays/lirc-rpi.dtbo' Oct 28 09:20:05 volumio volumio[820]: info: Oct 28 09:20:05 volumio volumio[820]: ---------------------------- Client requests Volume 95 Oct 28 09:20:05 volumio volumio[820]: info: VolumeController::SetAlsaVolume95 Oct 28 09:20:05 volumio volumio[820]: info: CoreStateMachine::pushState Oct 28 09:20:05 volumio volumio[820]: info: CorePlayQueue::getTrack 0 Oct 28 09:20:05 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Oct 28 09:20:05 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 09:20:05 volumio volumio[820]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 28 09:20:05 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 09:20:05 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 28 09:20:05 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 09:20:05 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 28 09:20:05 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 28 09:20:05 volumio volumio[820]: info: Reloading queue from file Oct 28 09:20:05 volumio volumio[820]: error: updateQueue error: null Oct 28 09:20:05 volumio volumio[820]: error: Failed LSINFO: Error: [50@0] {} No such directory Oct 28 09:20:06 volumio volumio[820]: info: VolumeController:: Volume=undefined Mute =false Oct 28 09:20:06 volumio volumio[820]: info: CoreStateMachine::pushState Oct 28 09:20:06 volumio volumio[820]: info: CorePlayQueue::getTrack 0 Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 09:20:06 volumio volumio[820]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 28 09:20:06 volumio volumio[820]: info: Setting volume on startup at 95 Oct 28 09:20:06 volumio volumio[820]: info: CoreStateMachine::setRepeat null single undefined Oct 28 09:20:06 volumio volumio[820]: info: CoreStateMachine::pushState Oct 28 09:20:06 volumio volumio[820]: info: CorePlayQueue::getTrack 0 Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 09:20:06 volumio volumio[820]: info: CoreStateMachine::setRandom null Oct 28 09:20:06 volumio volumio[820]: info: CoreStateMachine::pushState Oct 28 09:20:06 volumio volumio[820]: info: CorePlayQueue::getTrack 0 Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 09:20:06 volumio volumio[820]: info: mDNS: A device disapperared from network Oct 28 09:20:06 volumio volumio[820]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Oct 28 09:20:06 volumio volumio[820]: info: Starting Shairport Sync Oct 28 09:20:06 volumio volumio[820]: info: Starting Shairport Sync Oct 28 09:20:06 volumio sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 28 09:20:06 volumio volumio[820]: Discovery: adding f3c5d89f-6ccc-4fe3-840b-3f4a9bd8a551 Oct 28 09:20:06 volumio volumio[820]: info: mDNS: Found device Volumio Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::volumioGetState Oct 28 09:20:06 volumio volumio[820]: info: CorePlayQueue::getTrack 0 Oct 28 09:20:06 volumio sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 09:20:06 volumio sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 28 09:20:06 volumio sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 09:20:06 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 28 09:20:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 28 09:20:06 volumio sudo[1296]: pam_unix(sudo:session): session closed for user root Oct 28 09:20:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 28 09:20:06 volumio volumio[820]: info: [ASDebug] CurState: stop PrevState: na Oct 28 09:20:06 volumio volumio[820]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 28 09:20:06 volumio volumio[820]: info: [ASDebug] CurState: stop PrevState: na Oct 28 09:20:06 volumio volumio[820]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 28 09:20:06 volumio volumio[820]: info: [ASDebug] CurState: stop PrevState: na Oct 28 09:20:06 volumio volumio[820]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 28 09:20:06 volumio volumio[820]: info: [ASDebug] CurState: stop PrevState: na Oct 28 09:20:06 volumio volumio[820]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 28 09:20:06 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 28 09:20:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 28 09:20:06 volumio sudo[1298]: pam_unix(sudo:session): session closed for user root Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 09:20:06 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start rattenu.service Oct 28 09:20:06 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 09:20:06 volumio volumio[820]: info: Shairport-Sync Started Oct 28 09:20:06 volumio volumio[820]: Error adding Membership: Error: addMembership EINVAL Oct 28 09:20:06 volumio volumio[820]: info: Shairport-Sync Started Oct 28 09:20:06 volumio volumio[820]: info: ControllerAutoPlay - mpd ready Oct 28 09:20:06 volumio systemd[1]: Starting Allo Relay attenuator Service... Oct 28 09:20:06 volumio systemd[1]: Started Allo Relay attenuator Service. Oct 28 09:20:06 volumio sudo[1326]: pam_unix(sudo:session): session closed for user root Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::volumioGetState Oct 28 09:20:06 volumio volumio[820]: info: CorePlayQueue::getTrack 0 Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::volumioGetState Oct 28 09:20:06 volumio volumio[820]: info: CorePlayQueue::getTrack 0 Oct 28 09:20:06 volumio volumio[820]: info: R Attenu started Oct 28 09:20:06 volumio volumio[820]: info: Adding Allo Relay attenuator parameters{"enabled":true,"setvolumescript":"/data/plugins/miscellanea/allo_relay_volume_attenuator/setvolume.sh","getvolumescript":"/data/plugins/miscellanea/allo_relay_volume_attenuator/getvolume.sh","setmutescript":"/data/plugins/miscellanea/allo_relay_volume_attenuator/setmute.sh","getmutescript":"/data/plugins/miscellanea/allo_relay_volume_attenuator/getmute.sh","minVol":0,"maxVol":63,"mapTo100":false} Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::volumioUpdateVolumeScripts Oct 28 09:20:06 volumio volumio[820]: info: Updating Volume script: {"enabled":true,"setvolumescript":"/data/plugins/miscellanea/allo_relay_volume_attenuator/setvolume.sh","getvolumescript":"/data/plugins/miscellanea/allo_relay_volume_attenuator/getvolume.sh","setmutescript":"/data/plugins/miscellanea/allo_relay_volume_attenuator/setmute.sh","getmutescript":"/data/plugins/miscellanea/allo_relay_volume_attenuator/getmute.sh","minVol":0,"maxVol":63,"mapTo100":false} Oct 28 09:20:06 volumio volumio[820]: info: [ASDebug] CurState: stop PrevState: na Oct 28 09:20:06 volumio volumio[820]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 28 09:20:06 volumio volumio-remote-updater[597]: [2024-10-28 09:20:06] [connect] Successful connection Oct 28 09:20:06 volumio volumio-remote-updater[597]: [2024-10-28 09:20:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730107206 101 Oct 28 09:20:06 volumio rattenu[1331]: Cannot retrive Volume Oct 28 09:20:06 volumio rattenu[1331]: Error: Writing on I2C Oct 28 09:20:06 volumio volumio[820]: Discovery: this is already registered, f3c5d89f-6ccc-4fe3-840b-3f4a9bd8a551 Oct 28 09:20:06 volumio volumio[820]: info: mDNS: Found device Volumio Oct 28 09:20:06 volumio volumio[820]: info: CoreCommandRouter::volumioGetState Oct 28 09:20:06 volumio volumio[820]: info: CorePlayQueue::getTrack 0 Oct 28 09:20:07 volumio volumio[820]: info: CoreCommandRouter::volumioGetState Oct 28 09:20:07 volumio volumio[820]: info: CorePlayQueue::getTrack 0 Oct 28 09:20:07 volumio volumio[820]: info: [ASDebug] Togle GPIO: OFF Oct 28 09:20:07 volumio volumio[820]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 09:20:07 volumio volumio[820]: { Error: EPERM: operation not permitted, write Oct 28 09:20:07 volumio volumio[820]: at Object.fs.writeSync (fs.js:735:20) Oct 28 09:20:07 volumio volumio[820]: at Gpio.writeSync (/data/plugins/miscellanea/ampswitch/node_modules/onoff/onoff.js:212:6) Oct 28 09:20:07 volumio volumio[820]: at AmpSwitchController.off (/data/plugins/miscellanea/ampswitch/index.js:209:23) Oct 28 09:20:07 volumio volumio[820]: at Timeout._onTimeout (/data/plugins/miscellanea/ampswitch/index.js:185:88) Oct 28 09:20:07 volumio volumio[820]: at ontimeout (timers.js:482:11) Oct 28 09:20:07 volumio volumio[820]: at tryOnTimeout (timers.js:317:5) Oct 28 09:20:07 volumio volumio[820]: at Timer.listOnTimeout (timers.js:277:5) errno: -1, code: 'EPERM', syscall: 'write' } Oct 28 09:20:07 volumio volumio[820]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 09:20:08 volumio RoonBridge[1222]: 00:00:00.015 Warn: get lock file path: /tmp/.rnbgem0- Oct 28 09:20:08 volumio sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 09:19 Oct 28 09:20:08 volumio sudo[1346]: 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"