-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-05-25 12:26:15 CEST. --
May 25 12:25:03 psira volumio[1013]: info: Checking if install.sh is present
May 25 12:25:03 psira volumio[1013]: info: Executing install.sh
May 25 12:25:03 psira sudo[31138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/audiophonicsonoff/install.sh
May 25 12:25:03 psira sudo[31138]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 25 12:25:03 psira volumio[1013]: info: Installing Audiophonics on/off and its dependencies...
May 25 12:25:03 psira volumio[1013]: info: No packages needed, only node_modules are required
May 25 12:25:03 psira sudo[31138]: pam_unix(sudo:session): session closed for user root
May 25 12:25:03 psira volumio[1013]: info: Install script completed
May 25 12:25:03 psira volumio[1013]: info: Adding reference to registry
May 25 12:25:03 psira volumio[1013]: info: Done installing plugin.
May 25 12:25:03 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 25 12:25:03 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
May 25 12:25:03 psira volumio[1013]: Plugin install end detected on script
May 25 12:25:03 psira volumio[1013]: info: Folder /tmp/plugins removed
May 25 12:25:03 psira volumio[1013]: info: Folder /tmp/downloaded_plugin.zip removed
May 25 12:25:03 psira volumio[1013]: info: Folder /data/temp removed
May 25 12:25:07 psira volumio[1013]: info: Enabling plugin audiophonicsonoff
May 25 12:25:07 psira volumio[1013]: info: Loading plugin "audiophonicsonoff"...
May 25 12:25:07 psira volumio[1013]: info: Audiophonics on/off initiated
May 25 12:25:07 psira volumio[1013]: info: PLUGIN START: audiophonicsonoff
May 25 12:25:07 psira volumio[1013]: info: Configuring GPIO pins
May 25 12:25:07 psira volumio[1013]: info: Error: Error: EBUSY: resource busy or locked, write
May 25 12:25:13 psira volumio[1013]: info: Enabling plugin audiophonicsonoff
May 25 12:25:13 psira volumio[1013]: info: Loading plugin "audiophonicsonoff"...
May 25 12:25:13 psira volumio[1013]: info: Applying required configuration parameters for plugin audiophonicsonoff
May 25 12:25:13 psira volumio[1013]: info: Audiophonics on/off initiated
May 25 12:25:13 psira volumio[1013]: info: PLUGIN START: audiophonicsonoff
May 25 12:25:13 psira volumio[1013]: info: Configuring GPIO pins
May 25 12:25:13 psira volumio[1013]: info: Error: Error: EBUSY: resource busy or locked, write
May 25 12:25:25 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 25 12:25:25 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 25 12:25:25 psira volumio[1013]: info: Discovery: Getting this device information
May 25 12:25:25 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:25 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:25 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 25 12:25:32 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 25 12:25:32 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
May 25 12:25:35 psira volumio[1013]: info: [1748168735146] [80s80s] Pushing the next song state Paul Hardcastle - 19 (Destruction Mix) and getting next track.
May 25 12:25:35 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:35 psira volumio[1013]: info: CoreCommandRouter::servicePushState
May 25 12:25:35 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:35 psira volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music114/v4/e2/ce/74/e2ce749a-0515-54bb-fe52-c0d1d98732e3/source/600x600bb.jpg","name":"Paul Hardcastle - 19 (Destruction Mix)","title":"19 (Destruction Mix)","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"212","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2}
May 25 12:25:35 psira volumio[1013]: verbose: CURRENT POSITION 0
May 25 12:25:35 psira volumio[1013]: info: CoreStateMachine::syncState stateService play
May 25 12:25:35 psira volumio[1013]: info: CoreStateMachine::syncState currentStatus play
May 25 12:25:35 psira volumio[1013]: info: Received an update from plugin. extracting info from payload
May 25 12:25:35 psira volumio[1013]: info: CoreStateMachine::pushState
May 25 12:25:35 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:35 psira volumio[1013]: info: CoreCommandRouter::volumioPushState
May 25 12:25:35 psira volumio[1013]: info: MRS: Pushing multiroomSync output update for this device
May 25 12:25:35 psira volumio[1013]: info: MRS: Pushing multiroomSync output
May 25 12:25:35 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:35 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:35 psira volumio[1013]: info: CoreStateMachine::pushState
May 25 12:25:35 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:35 psira volumio[1013]: info: CoreCommandRouter::volumioPushState
May 25 12:25:35 psira volumio[1013]: info: MRS: Pushing multiroomSync output update for this device
May 25 12:25:35 psira volumio[1013]: info: MRS: Pushing multiroomSync output
May 25 12:25:35 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:35 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:35 psira volumio[1013]: info: [1748168735160] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=1
May 25 12:25:35 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:35 psira volumio[1013]: info: CorePlayQueue::getTrack 1
May 25 12:25:35 psira volumio[1013]: info: [1748168735307] [80s80s] received new event containing 1 songs.
May 25 12:25:35 psira volumio[1013]: info: [1748168735307] [80s80s] PlayNextTrack API delay: 30
May 25 12:25:35 psira volumio[1013]: info: [1748168735309] [80s80s] Setting timer to: 5693 milliseconds.
May 25 12:25:37 psira volumio[1013]: info: Enabling plugin audiophonics_evo_sabre
May 25 12:25:37 psira volumio[1013]: info: Loading plugin "audiophonics_evo_sabre"...
May 25 12:25:37 psira volumio[1013]: info: PLUGIN START: audiophonics_evo_sabre
May 25 12:25:37 psira volumio[1013]: info: EVO SABRE : Starting Plugin
May 25 12:25:37 psira volumio[1013]: info: Loading i18n strings for locale gr
May 25 12:25:37 psira sudo[31271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
May 25 12:25:37 psira sudo[31271]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 25 12:25:37 psira systemd[1]: Reloading.
May 25 12:25:38 psira systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
May 25 12:25:38 psira sudo[31271]: pam_unix(sudo:session): session closed for user root
May 25 12:25:38 psira volumio[1013]: info: EVO SABRE : systemd cmd daemon-reload : success
May 25 12:25:38 psira sudo[31287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service
May 25 12:25:38 psira sudo[31287]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 25 12:25:38 psira systemd[1]: Started OLED Display Service for EVO SABRE.
May 25 12:25:38 psira sudo[31287]: pam_unix(sudo:session): session closed for user root
May 25 12:25:38 psira volumio[1013]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success
May 25 12:25:38 psira sudo[31289]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio
May 25 12:25:38 psira sudo[31289]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 25 12:25:38 psira sudo[31297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service
May 25 12:25:38 psira sudo[31297]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 25 12:25:38 psira systemd[1]: Starting lircd(8) initialization helper tool...
May 25 12:25:38 psira volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running
May 25 12:25:38 psira volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
May 25 12:25:38 psira lircd-setup[31300]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead.
May 25 12:25:38 psira lircd-setup[31300]: parser = configparser.SafeConfigParser()
May 25 12:25:38 psira systemd[1]: lircd-setup.service: Succeeded.
May 25 12:25:38 psira systemd[1]: Started lircd(8) initialization helper tool.
May 25 12:25:39 psira systemd[1]: Started evo_remote.service.
May 25 12:25:39 psira sudo[31297]: pam_unix(sudo:session): session closed for user root
May 25 12:25:39 psira volumio[1013]: info: EVO SABRE : systemd cmd restart evo_remote.service : success
May 25 12:25:39 psira sudo[31307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service
May 25 12:25:39 psira lircd-0.10.1[31305]: Info: lircd: Opening log, level: Info
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Version: lircd 0.10.1
May 25 12:25:39 psira sudo[31307]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: System info: Linux psira 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux
May 25 12:25:39 psira lircd-0.10.1[31305]: Info: Initial device: /dev/lirc0
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols
May 25 12:25:39 psira lircd-0.10.1[31305]: Info: Initial device: /dev/lirc0
May 25 12:25:39 psira lircd-0.10.1[31305]: Info: [lirc] protocol is enabled
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: driver: default
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: output: /var/run/lirc/lircd
May 25 12:25:39 psira lircd[31305]: lircd-0.10.1[31305]: Info: lircd: Opening log, level: Info
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: nodaemon: 1
May 25 12:25:39 psira lircd[31305]: lircd-0.10.1[31305]: Warning: Running as root
May 25 12:25:39 psira lircd[31305]: lircd-0.10.1[31305]: Info: Using remote: ApEvo.
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins
May 25 12:25:39 psira lircd[31305]: lircd-0.10.1[31305]: Notice: lircd(default) ready, using /var/run/lirc/lircd
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: logfile: syslog
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: immediate-init: 0
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: permission: 666
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: driver-options:
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: pidfile: /var/run/lirc/lircd.pid
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: listen: 0
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: connect: (null)
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: userelease: 0
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: effective_user: (null)
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: release_suffix: _EVUP
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: allow_simulate: 0
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: repeat_max: 600
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Options: dynamic_codes: (null)
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Current driver: default
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Driver API version: 3
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Driver version: 0.10.0
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html
May 25 12:25:39 psira lircd-0.10.1[31305]: Info: lircd: Opening log, level: Info
May 25 12:25:39 psira lircd-0.10.1[31305]: Warning: Running as root
May 25 12:25:39 psira lircd-0.10.1[31305]: Info: Using remote: ApEvo.
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: lircd(default) ready, using /var/run/lirc/lircd
May 25 12:25:39 psira systemd[1]: Starting lircd(8) initialization helper tool...
May 25 12:25:39 psira volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running
May 25 12:25:39 psira volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
May 25 12:25:39 psira lircd-setup[31311]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead.
May 25 12:25:39 psira lircd-setup[31311]: parser = configparser.SafeConfigParser()
May 25 12:25:39 psira systemd[1]: lircd-setup.service: Succeeded.
May 25 12:25:39 psira systemd[1]: Started lircd(8) initialization helper tool.
May 25 12:25:39 psira systemd[1]: Started evo_irexec.service.
May 25 12:25:39 psira sudo[31307]: pam_unix(sudo:session): session closed for user root
May 25 12:25:39 psira volumio[1013]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success
May 25 12:25:39 psira volumio[1013]: info: Done.
May 25 12:25:39 psira lircd[31305]: lircd-0.10.1[31305]: Notice: accepted new client on /var/run/lirc/lircd
May 25 12:25:39 psira lircd[31305]: lircd-0.10.1[31305]: Info: [lirc] protocol is enabled
May 25 12:25:39 psira lircd-0.10.1[31305]: Notice: accepted new client on /var/run/lirc/lircd
May 25 12:25:39 psira lircd-0.10.1[31305]: Info: [lirc] protocol is enabled
May 25 12:25:40 psira volumio[1013]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
May 25 12:25:40 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:40 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:40 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:40 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:40 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:40 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:40 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:41 psira volumio[1013]: info: [1748168741002] [80s80s] Pushing the next song state Bananarama - Venus and getting next track.
May 25 12:25:41 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:41 psira volumio[1013]: info: CoreCommandRouter::servicePushState
May 25 12:25:41 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:41 psira volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music125/v4/5c/9c/4c/5c9c4ca2-687f-287f-63f7-7e5db46d7838/source/600x600bb.jpg","name":"Bananarama - Venus","title":"Venus","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"205","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2}
May 25 12:25:41 psira volumio[1013]: verbose: CURRENT POSITION 0
May 25 12:25:41 psira volumio[1013]: info: CoreStateMachine::syncState stateService play
May 25 12:25:41 psira volumio[1013]: info: CoreStateMachine::syncState currentStatus play
May 25 12:25:41 psira volumio[1013]: info: Received an update from plugin. extracting info from payload
May 25 12:25:41 psira volumio[1013]: info: CoreStateMachine::pushState
May 25 12:25:41 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:41 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 25 12:25:41 psira volumio[1013]: info: CoreCommandRouter::volumioPushState
May 25 12:25:41 psira volumio[1013]: info: MRS: Pushing multiroomSync output update for this device
May 25 12:25:41 psira volumio[1013]: info: MRS: Pushing multiroomSync output
May 25 12:25:41 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:41 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:41 psira volumio[1013]: info: CoreStateMachine::pushState
May 25 12:25:41 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:41 psira volumio[1013]: info: CoreCommandRouter::volumioPushState
May 25 12:25:41 psira volumio[1013]: info: MRS: Pushing multiroomSync output update for this device
May 25 12:25:41 psira volumio[1013]: info: MRS: Pushing multiroomSync output
May 25 12:25:41 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:41 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:41 psira volumio[1013]: info: [1748168741014] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=1
May 25 12:25:41 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:41 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:41 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:41 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:41 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:41 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:41 psira volumio[1013]: info: CorePlayQueue::getTrack 1
May 25 12:25:41 psira volumio[1013]: info: [1748168741149] [80s80s] received new event containing 1 songs.
May 25 12:25:41 psira volumio[1013]: info: [1748168741149] [80s80s] PlayNextTrack API delay: 30
May 25 12:25:41 psira volumio[1013]: info: [1748168741150] [80s80s] Setting timer to: 205000 milliseconds.
May 25 12:25:42 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:42 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:42 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:42 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:42 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:43 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:43 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:43 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:43 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:43 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:43 psira volumio[1013]: info: Enabling plugin audiophonicsonoff
May 25 12:25:43 psira volumio[1013]: info: Loading plugin "audiophonicsonoff"...
May 25 12:25:43 psira volumio[1013]: info: Applying required configuration parameters for plugin audiophonicsonoff
May 25 12:25:43 psira volumio[1013]: info: Audiophonics on/off initiated
May 25 12:25:43 psira volumio[1013]: info: PLUGIN START: audiophonicsonoff
May 25 12:25:43 psira volumio[1013]: info: Configuring GPIO pins
May 25 12:25:43 psira volumio[1013]: info: Error: Error: EBUSY: resource busy or locked, write
May 25 12:25:44 psira volumio[1013]: info: Enabling plugin audiophonicsonoff
May 25 12:25:44 psira volumio[1013]: info: Loading plugin "audiophonicsonoff"...
May 25 12:25:44 psira volumio[1013]: info: Applying required configuration parameters for plugin audiophonicsonoff
May 25 12:25:44 psira volumio[1013]: info: Audiophonics on/off initiated
May 25 12:25:44 psira volumio[1013]: info: PLUGIN START: audiophonicsonoff
May 25 12:25:44 psira volumio[1013]: info: Configuring GPIO pins
May 25 12:25:44 psira volumio[1013]: info: Error: Error: EBUSY: resource busy or locked, write
May 25 12:25:44 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:44 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:44 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:44 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:44 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:45 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:45 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:45 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:45 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:45 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:46 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:46 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:46 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:46 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:46 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:47 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:47 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:47 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:47 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:47 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:48 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:48 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:48 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:48 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:48 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:49 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:49 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:49 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:49 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:49 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:50 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:50 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:50 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:50 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:50 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:51 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:51 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:51 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:51 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:51 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:52 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:52 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:52 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:52 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:52 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:53 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:53 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:53 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:53 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:53 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:54 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:54 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:54 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:54 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:54 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:55 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:55 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:55 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:55 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:55 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:55 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 25 12:25:55 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 25 12:25:55 psira volumio[1013]: info: Discovery: Getting this device information
May 25 12:25:55 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:55 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:55 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 25 12:25:56 psira volumio[1013]: info: Enabling plugin audiophonicsonoff
May 25 12:25:56 psira volumio[1013]: info: Loading plugin "audiophonicsonoff"...
May 25 12:25:56 psira volumio[1013]: info: Applying required configuration parameters for plugin audiophonicsonoff
May 25 12:25:56 psira volumio[1013]: info: Audiophonics on/off initiated
May 25 12:25:56 psira volumio[1013]: info: PLUGIN START: audiophonicsonoff
May 25 12:25:56 psira volumio[1013]: info: Configuring GPIO pins
May 25 12:25:56 psira volumio[1013]: info: Error: Error: EBUSY: resource busy or locked, write
May 25 12:25:56 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:56 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:56 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:56 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:56 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:57 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:57 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:57 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:57 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:57 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:57 psira volumio[1013]: info: Enabling plugin audiophonicsonoff
May 25 12:25:57 psira volumio[1013]: info: Loading plugin "audiophonicsonoff"...
May 25 12:25:57 psira volumio[1013]: info: Applying required configuration parameters for plugin audiophonicsonoff
May 25 12:25:57 psira volumio[1013]: info: Audiophonics on/off initiated
May 25 12:25:57 psira volumio[1013]: info: PLUGIN START: audiophonicsonoff
May 25 12:25:57 psira volumio[1013]: info: Configuring GPIO pins
May 25 12:25:57 psira volumio[1013]: info: Error: Error: EBUSY: resource busy or locked, write
May 25 12:25:58 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:58 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:58 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:58 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:58 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:25:59 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:25:59 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:25:59 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:25:59 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:25:59 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:00 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:00 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:00 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:26:00 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:26:00 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:01 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:01 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:01 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:26:01 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:26:01 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:02 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:02 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:02 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:26:02 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:26:02 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:03 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:03 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:03 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:26:03 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:26:03 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:04 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:04 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:04 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:26:04 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:26:04 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:05 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:05 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:05 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:26:05 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:26:05 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:06 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:06 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:06 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:26:06 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:26:06 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:06 psira volumio[1013]: info:
May 25 12:26:06 psira volumio[1013]: ---------------------------- MPD announces system playlist update
May 25 12:26:06 psira volumio[1013]: info: Ignoring MPD Status Update
May 25 12:26:06 psira volumio[1013]: info:
May 25 12:26:06 psira volumio[1013]: ---------------------------- MPD announces state update: player
May 25 12:26:06 psira volumio[1013]: info: ControllerMpd::getState
May 25 12:26:06 psira volumio[1013]: verbose: ControllerMpd::sendMpdCommand status
May 25 12:26:06 psira volumio[1013]: info: ------------------------------ 1ms
May 25 12:26:06 psira volumio[1013]: info: sendMpdCommand status took 1 milliseconds
May 25 12:26:06 psira volumio[1013]: verbose: ControllerMpd::parseState
May 25 12:26:06 psira volumio[1013]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 25 12:26:06 psira volumio[1013]: info: sendMpdCommand playlistinfo took 1 milliseconds
May 25 12:26:06 psira volumio[1013]: verbose: ControllerMpd::parseTrackInfo
May 25 12:26:06 psira volumio[1013]: info: ControllerMpd::pushState
May 25 12:26:06 psira volumio[1013]: info: CoreCommandRouter::servicePushState
May 25 12:26:06 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:06 psira volumio[1013]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 25 12:26:06 psira volumio[1013]: info: ------------------------------ 3ms
May 25 12:26:07 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:07 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:07 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:26:07 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:26:07 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:08 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:08 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:08 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:26:08 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:26:08 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:09 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:09 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:09 psira volumio[1013]: info: CoreCommandRouter::volumioGetQueue
May 25 12:26:09 psira volumio[1013]: info: CoreStateMachine::getQueue
May 25 12:26:09 psira volumio[1013]: info: CorePlayQueue::getQueue
May 25 12:26:09 psira volumio[1013]: info: CoreCommandRouter::volumioPause
May 25 12:26:09 psira volumio[1013]: info: CoreStateMachine::pause
May 25 12:26:09 psira volumio[1013]: info: CoreStateMachine::stPlaybackTimer
May 25 12:26:09 psira volumio[1013]: info: CoreStateMachine::servicePause
May 25 12:26:09 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:09 psira volumio[1013]: info: CoreCommandRouter::servicePause
May 25 12:26:09 psira volumio[1013]: verbose: ControllerMpd::sendMpdCommand pause
May 25 12:26:09 psira volumio[1013]: info: sendMpdCommand pause took 2 milliseconds
May 25 12:26:09 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:09 psira volumio[1013]: info: CoreCommandRouter::servicePushState
May 25 12:26:09 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:09 psira volumio[1013]: verbose: STATE SERVICE {"status":"pause","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music125/v4/5c/9c/4c/5c9c4ca2-687f-287f-63f7-7e5db46d7838/source/600x600bb.jpg","name":"Bananarama - Venus","title":"Venus","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"205","seek":28409,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2}
May 25 12:26:09 psira volumio[1013]: verbose: CURRENT POSITION 0
May 25 12:26:09 psira volumio[1013]: info: CoreStateMachine::syncState stateService pause
May 25 12:26:09 psira volumio[1013]: info: CoreStateMachine::syncState currentStatus pause
May 25 12:26:09 psira volumio[1013]: info: CoreStateMachine::pushState
May 25 12:26:09 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:09 psira volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 25 12:26:09 psira volumio[1013]: info: CoreCommandRouter::volumioPushState
May 25 12:26:09 psira volumio[1013]: info: MRS: Pushing multiroomSync output update for this device
May 25 12:26:09 psira volumio[1013]: info: MRS: Pushing multiroomSync output
May 25 12:26:09 psira volumio[1013]: info: CoreCommandRouter::volumioGetState
May 25 12:26:09 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:09 psira volumio[1013]: info: CoreStateMachine::stPlaybackTimer
May 25 12:26:09 psira volumio[1013]: info:
May 25 12:26:09 psira volumio[1013]: ---------------------------- MPD announces state update: player
May 25 12:26:09 psira volumio[1013]: info: ControllerMpd::getState
May 25 12:26:09 psira volumio[1013]: verbose: ControllerMpd::sendMpdCommand status
May 25 12:26:09 psira volumio[1013]: info: sendMpdCommand status took 5 milliseconds
May 25 12:26:09 psira volumio[1013]: verbose: ControllerMpd::parseState
May 25 12:26:09 psira volumio[1013]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 25 12:26:09 psira volumio[1013]: info: sendMpdCommand playlistinfo took 1 milliseconds
May 25 12:26:09 psira volumio[1013]: verbose: ControllerMpd::parseTrackInfo
May 25 12:26:09 psira volumio[1013]: info: ControllerMpd::pushState
May 25 12:26:09 psira volumio[1013]: info: CoreCommandRouter::servicePushState
May 25 12:26:09 psira volumio[1013]: info: CorePlayQueue::getTrack 0
May 25 12:26:09 psira volumio[1013]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd
May 25 12:26:09 psira volumio[1013]: info: ------------------------------ 7ms
May 25 12:26:15 psira volumio[1013]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________
May 25 12:26:15 psira volumio[1013]: info: PLUGIN onReboot : networkfs
May 25 12:26:15 psira volumio[1013]: info: PLUGIN onReboot : audiophonicsonoff
May 25 12:26:15 psira volumio[1013]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 25 12:26:15 psira volumio[1013]: TypeError: Cannot read property 'writeSync' of undefined
May 25 12:26:15 psira volumio[1013]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25)
May 25 12:26:15 psira volumio[1013]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30)
May 25 12:26:15 psira volumio[1013]: at HashMap. (/volumio/app/pluginmanager.js:668:31)
May 25 12:26:15 psira volumio[1013]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10)
May 25 12:26:15 psira volumio[1013]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7)
May 25 12:26:15 psira volumio[1013]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20)
May 25 12:26:15 psira volumio[1013]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22)
May 25 12:26:15 psira volumio[1013]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33)
May 25 12:26:15 psira volumio[1013]: at Socket.emit (events.js:315:20)
May 25 12:26:15 psira volumio[1013]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
May 25 12:26:15 psira volumio[1013]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
May 25 12:26:15 psira volumio[1013]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 25 12:26:15 psira sudo[31398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 12:25
May 25 12:26:15 psira sudo[31398]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="570c5791513f5bac7da274aba6690c1a961705de"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST"
VOLUMIO_VERSION="3.812"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"