-- 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"