-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2024-10-02 19:45:35 CEST. -- Oct 02 19:44:00 volumio-audiophonics volumio[1002]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 02 19:44:00 volumio-audiophonics volumio[1002]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 02 19:44:00 volumio-audiophonics systemd[1]: Starting lircd(8) initialization helper tool... Oct 02 19:44:00 volumio-audiophonics volumio[1002]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 02 19:44:00 volumio-audiophonics volumio[1002]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 02 19:44:00 volumio-audiophonics startx[1358]: X.Org X Server 1.20.4 Oct 02 19:44:00 volumio-audiophonics startx[1358]: X Protocol Version 11, Revision 0 Oct 02 19:44:00 volumio-audiophonics startx[1358]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Oct 02 19:44:00 volumio-audiophonics startx[1358]: Current Operating System: Linux volumio-audiophonics 6.1.69-v7+ #1710 SMP Thu Dec 21 13:14:13 GMT 2023 armv7l Oct 02 19:44:00 volumio-audiophonics startx[1358]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=db7775c5-9234-4b9a-955e-7341a245d498 imgfile=/volumio_current.sqsh bootpart=UUID=F102-DE29 datapart=UUID=4112086d-38d3-4206-a7af-ef93bf4e4008 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Oct 02 19:44:00 volumio-audiophonics startx[1358]: Build Date: 04 April 2023 07:50:56AM Oct 02 19:44:00 volumio-audiophonics startx[1358]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Oct 02 19:44:00 volumio-audiophonics startx[1358]: Current version of pixman: 0.36.0 Oct 02 19:44:00 volumio-audiophonics startx[1358]: Before reporting problems, check http://wiki.x.org Oct 02 19:44:00 volumio-audiophonics startx[1358]: to make sure that you have the latest version. Oct 02 19:44:00 volumio-audiophonics startx[1358]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 02 19:44:00 volumio-audiophonics startx[1358]: (++) from command line, (!!) notice, (II) informational, Oct 02 19:44:00 volumio-audiophonics startx[1358]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 02 19:44:00 volumio-audiophonics startx[1358]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Oct 2 19:44:00 2024 Oct 02 19:44:00 volumio-audiophonics startx[1358]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 02 19:44:00 volumio-audiophonics startx[1358]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 02 19:44:00 volumio-audiophonics volumio[1002]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 02 19:44:00 volumio-audiophonics go-librespot[1359]: time="2024-10-02T19:44:00+02:00" level=info msg="generated new device id: f388fb4397a6211404b72880bac9aa5e4cc5dad2" Oct 02 19:44:01 volumio-audiophonics lircd-setup[1407]: /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. Oct 02 19:44:01 volumio-audiophonics lircd-setup[1407]: parser = configparser.SafeConfigParser() Oct 02 19:44:01 volumio-audiophonics mpd[1283]: Oct 02 19:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 19:44:01 volumio-audiophonics systemd[1]: lircd-setup.service: Succeeded. Oct 02 19:44:01 volumio-audiophonics systemd[1]: Started lircd(8) initialization helper tool. Oct 02 19:44:01 volumio-audiophonics volumio[1002]: Upnp client error: Error: This socket has been ended by the other party Oct 02 19:44:01 volumio-audiophonics volumio[1002]: Upnp client error: Error: This socket has been ended by the other party Oct 02 19:44:01 volumio-audiophonics go-librespot[1359]: time="2024-10-02T19:44:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 19:44:01 volumio-audiophonics go-librespot[1359]: time="2024-10-02T19:44:01+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 19:44:01 volumio-audiophonics go-librespot[1359]: time="2024-10-02T19:44:01+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 19:44:01 volumio-audiophonics systemd[1]: Started evo_remote.service. Oct 02 19:44:01 volumio-audiophonics sudo[1402]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:01 volumio-audiophonics systemd[1]: Started Music Player Daemon. Oct 02 19:44:01 volumio-audiophonics volumio[1002]: info: EVO SABRE : systemd cmd restart evo_remote.service : success Oct 02 19:44:01 volumio-audiophonics go-librespot[1359]: time="2024-10-02T19:44:01+02:00" level=debug msg="zeroconf server listening on port 35653" Oct 02 19:44:01 volumio-audiophonics sudo[1265]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:01 volumio-audiophonics sudo[1255]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Info: lircd: Opening log, level: Info Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Version: lircd 0.10.1 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: System info: Linux volumio-audiophonics 6.1.69-v7+ #1710 SMP Thu Dec 21 13:14:13 GMT 2023 armv7l GNU/Linux Oct 02 19:44:01 volumio-audiophonics sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Info: Initial device: /dev/lirc0 Oct 02 19:44:01 volumio-audiophonics lircd[1418]: lircd-0.10.1[1418]: Info: lircd: Opening log, level: Info Oct 02 19:44:01 volumio-audiophonics lircd[1418]: lircd-0.10.1[1418]: Warning: Running as root Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Info: Initial device: /dev/lirc0 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Info: [lirc] protocol is enabled Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: driver: default Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: output: /var/run/lirc/lircd Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: nodaemon: 1 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: logfile: syslog Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: immediate-init: 0 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: permission: 666 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: driver-options: Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: listen: 0 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: connect: (null) Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: userelease: 0 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: effective_user: (null) Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: release_suffix: _EVUP Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: allow_simulate: 0 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: repeat_max: 600 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Options: dynamic_codes: (null) Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Current driver: default Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Driver API version: 3 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Driver version: 0.10.0 Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Info: lircd: Opening log, level: Info Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Warning: Running as root Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Info: Using remote: ApEvo. Oct 02 19:44:01 volumio-audiophonics lircd[1418]: lircd-0.10.1[1418]: Info: Using remote: ApEvo. Oct 02 19:44:01 volumio-audiophonics lircd[1418]: lircd-0.10.1[1418]: Notice: lircd(default) ready, using /var/run/lirc/lircd Oct 02 19:44:01 volumio-audiophonics sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:01 volumio-audiophonics lircd-0.10.1[1418]: Notice: lircd(default) ready, using /var/run/lirc/lircd Oct 02 19:44:01 volumio-audiophonics volumio[1002]: info: MPD running with PID1283 Oct 02 19:44:01 volumio-audiophonics volumio[1002]: ,establishing connection Oct 02 19:44:01 volumio-audiophonics systemd[1]: Starting lircd(8) initialization helper tool... Oct 02 19:44:02 volumio-audiophonics volumio[1002]: error: MPD error: The expression evaluated to a falsy value: Oct 02 19:44:02 volumio-audiophonics volumio[1002]: assert.ok(self.idling) Oct 02 19:44:02 volumio-audiophonics volumio[1002]: error: The expression evaluated to a falsy value: Oct 02 19:44:02 volumio-audiophonics volumio[1002]: assert.ok(self.idling) Oct 02 19:44:02 volumio-audiophonics volumio[1002]: error: MPD error: The expression evaluated to a falsy value: Oct 02 19:44:02 volumio-audiophonics volumio[1002]: assert.ok(self.idling) Oct 02 19:44:02 volumio-audiophonics volumio[1002]: error: The expression evaluated to a falsy value: Oct 02 19:44:02 volumio-audiophonics volumio[1002]: assert.ok(self.idling) Oct 02 19:44:02 volumio-audiophonics volumio[1002]: error: updateQueue error: null Oct 02 19:44:02 volumio-audiophonics volumio-remote-updater[653]: [2024-10-02 19:44:02] [connect] Successful connection Oct 02 19:44:02 volumio-audiophonics volumio-remote-updater[653]: [2024-10-02 19:44:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1727891042 101 Oct 02 19:44:02 volumio-audiophonics volumio[1002]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Oct 02 19:44:02 volumio-audiophonics lircd-setup[1428]: /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. Oct 02 19:44:02 volumio-audiophonics lircd-setup[1428]: parser = configparser.SafeConfigParser() Oct 02 19:44:02 volumio-audiophonics systemd[1]: lircd-setup.service: Succeeded. Oct 02 19:44:02 volumio-audiophonics systemd[1]: Started lircd(8) initialization helper tool. Oct 02 19:44:02 volumio-audiophonics systemd[1]: Started evo_irexec.service. Oct 02 19:44:02 volumio-audiophonics sudo[1424]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:02 volumio-audiophonics volumio[1002]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success Oct 02 19:44:02 volumio-audiophonics volumio[1002]: info: Completed starting Core Plugins Oct 02 19:44:02 volumio-audiophonics volumio[1002]: info: ------------------------------------------- Oct 02 19:44:02 volumio-audiophonics volumio[1002]: info: ----- MyVolumio plugins startup ---- Oct 02 19:44:02 volumio-audiophonics volumio[1002]: info: ------------------------------------------- Oct 02 19:44:02 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 02 19:44:02 volumio-audiophonics lircd[1418]: lircd-0.10.1[1418]: Notice: accepted new client on /var/run/lirc/lircd Oct 02 19:44:02 volumio-audiophonics lircd-0.10.1[1418]: Notice: accepted new client on /var/run/lirc/lircd Oct 02 19:44:02 volumio-audiophonics lircd[1418]: lircd-0.10.1[1418]: Info: [lirc] protocol is enabled Oct 02 19:44:02 volumio-audiophonics lircd-0.10.1[1418]: Info: [lirc] protocol is enabled Oct 02 19:44:02 volumio-audiophonics volumio[1002]: info: go-librespot daemon successfully initialized Oct 02 19:44:04 volumio-audiophonics startx[1358]: [1B blob data] Oct 02 19:44:04 volumio-audiophonics volumio[1002]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 02 19:44:04 volumio-audiophonics volumio[1002]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 02 19:44:04 volumio-audiophonics volumio[1246]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 02 19:44:04 volumio-audiophonics volumio[1002]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 02 19:44:05 volumio-audiophonics volumio[1002]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 02 19:44:05 volumio-audiophonics volumio[1002]: info: Initializing connection to go-librespot Websocket Oct 02 19:44:05 volumio-audiophonics go-librespot[1359]: time="2024-10-02T19:44:05+02:00" level=debug msg="new websocket client" Oct 02 19:44:05 volumio-audiophonics volumio[1002]: info: Connection to go-librespot Websocket established Oct 02 19:44:05 volumio-audiophonics startx[1358]: waiting for X server to begin accepting connections . Oct 02 19:44:05 volumio-audiophonics volumio[1002]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 02 19:44:06 volumio-audiophonics volumio[1002]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 02 19:44:06 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:06 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:07 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:44:07 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:44:07 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:44:07 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:07 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:08 volumio-audiophonics volumio[1002]: info: Getting Spotify volume Oct 02 19:44:08 volumio-audiophonics volumio[1002]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 19:44:08 volumio-audiophonics volumiologrotate[651]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 02 19:44:08 volumio-audiophonics volumiologrotate[651]: ls: cannot access 'AUDIOPHONICS': No such file or directory Oct 02 19:44:09 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:09 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:09 volumio-audiophonics volumio[1002]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Oct 02 19:44:09 volumio-audiophonics volumio[1002]: SPOTIFY: SPOTIFY VOLUME undefined Oct 02 19:44:09 volumio-audiophonics volumio[1002]: SPOTIFY: VOLUMIO VOLUME 0 Oct 02 19:44:09 volumio-audiophonics volumio[1002]: info: Aligning Spotify Volume to Volumio Volume Oct 02 19:44:09 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:09 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:09 volumio-audiophonics volumio[1002]: info: Setting Spotify Volume from Volumio: 0 Oct 02 19:44:10 volumio-audiophonics volumio[1002]: SPOTIFY: SETTING SPOTIFY VOLUME 0 Oct 02 19:44:10 volumio-audiophonics volumio[1002]: info: Sending Spotify command with payload to local API: /player/volume Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: Adding plugin bluetooth to MyMusic Plugins Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: Adding plugin multiroom to MyMusic Plugins Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: Adding plugin metavolumio to MyMusic Plugins Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: Adding plugin cd_controller to MyMusic Plugins Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 02 19:44:11 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 02 19:44:13 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 02 19:44:13 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 02 19:44:13 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:13 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:13 volumio-audiophonics volumio[1002]: info: Starting MyVolumio Remote Streaming Endpoints Oct 02 19:44:14 volumio-audiophonics volumio[1002]: info: MyVolumio login type: Token Oct 02 19:44:14 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 02 19:44:14 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 02 19:44:17 volumio-audiophonics volumio[1002]: info: Starting Streaming Service Transparent Proxy Oct 02 19:44:17 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 02 19:44:17 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 02 19:44:17 volumio-audiophonics volumio[1002]: info: Streaming services startup Oct 02 19:44:17 volumio-audiophonics volumio[1002]: info: Starting Streaming Daemon Oct 02 19:44:18 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 02 19:44:18 volumio-audiophonics sudo[1680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 19:44:18 volumio-audiophonics sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:18 volumio-audiophonics sudo[1680]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:18 volumio-audiophonics volumio[1002]: error: Cannot start Volumio Streaming Daemon Oct 02 19:44:18 volumio-audiophonics volumio[1002]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 19:44:18 volumio-audiophonics volumio[1002]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 19:44:19 volumio-audiophonics volumio[1002]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 02 19:44:20 volumio-audiophonics volumio[1002]: info: MyVolumio token set successfully Oct 02 19:44:20 volumio-audiophonics volumio[1002]: info: MYVOLUMIO: Adding device Oct 02 19:44:20 volumio-audiophonics volumio[1002]: info: MYVOLUMIO: Evaluating Server Oct 02 19:44:21 volumio-audiophonics volumio[1002]: info: MyVolumio Plan changed: premium Oct 02 19:44:21 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 02 19:44:21 volumio-audiophonics volumio[1002]: info: Removing browser output: myVolumio user plan is not superstar Oct 02 19:44:21 volumio-audiophonics volumio[1002]: info: Removing audio output: Oct 02 19:44:21 volumio-audiophonics volumio[1002]: info: MYVOLUMIO: Adding device Oct 02 19:44:21 volumio-audiophonics volumio[1002]: info: MYVOLUMIO: Evaluating Server Oct 02 19:44:21 volumio-audiophonics volumio[1002]: info: Remote config written successfully Oct 02 19:44:21 volumio-audiophonics volumio[1002]: info: Starting Tunnel 1 Oct 02 19:44:21 volumio-audiophonics volumio[1002]: info: Starting Tunnel Connection Checker Oct 02 19:44:21 volumio-audiophonics volumio[1002]: info: Completed starting MyVolumio Plugin Oct 02 19:44:21 volumio-audiophonics volumio[1002]: STREAMING PROXY: Starting server on port 3245 Oct 02 19:44:21 volumio-audiophonics volumio[1002]: Node JS runtime: 14 Oct 02 19:44:22 volumio-audiophonics volumio[1002]: info: MYVolumio Device enabled Oct 02 19:44:22 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 02 19:44:22 volumio-audiophonics volumio[1002]: info: MyVolumio status changed Oct 02 19:44:22 volumio-audiophonics volumio[1002]: info: Streaming services startup Oct 02 19:44:22 volumio-audiophonics volumio[1002]: info: Starting Streaming Daemon Oct 02 19:44:22 volumio-audiophonics volumio[1002]: info: Setting Geolocation for MyVolumio to eu4 Oct 02 19:44:22 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:22 volumio-audiophonics sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 19:44:22 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:22 volumio-audiophonics sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:22 volumio-audiophonics sudo[1756]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:22 volumio-audiophonics volumio[1002]: error: Cannot start Volumio Streaming Daemon Oct 02 19:44:22 volumio-audiophonics volumio[1002]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 19:44:22 volumio-audiophonics volumio[1002]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 19:44:22 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 02 19:44:22 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 02 19:44:23 volumio-audiophonics volumio[1002]: info: [1727891063413] Starting BluetoothController Oct 02 19:44:23 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 02 19:44:27 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 02 19:44:27 volumio-audiophonics sudo[1759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 02 19:44:27 volumio-audiophonics sudo[1759]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:27 volumio-audiophonics sudo[1759]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:27 volumio-audiophonics volumio[1002]: info: MRS: MultiRoom plugin initialized Oct 02 19:44:27 volumio-audiophonics volumio[1002]: info: MRS: STOPPING SNAPCLIENT Oct 02 19:44:27 volumio-audiophonics volumio[1002]: info: MRS: Snap server stop Oct 02 19:44:27 volumio-audiophonics sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 02 19:44:27 volumio-audiophonics sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:27 volumio-audiophonics volumio[1002]: info: MRS: STOPPING volumioStreaming Oct 02 19:44:27 volumio-audiophonics sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 02 19:44:27 volumio-audiophonics sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:27 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 02 19:44:27 volumio-audiophonics sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 02 19:44:27 volumio-audiophonics sudo[1781]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:27 volumio-audiophonics sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 02 19:44:27 volumio-audiophonics sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:27 volumio-audiophonics sudo[1783]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:27 volumio-audiophonics sudo[1775]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:27 volumio-audiophonics sudo[1777]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:27 volumio-audiophonics sudo[1781]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:27 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 02 19:44:27 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 02 19:44:27 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 02 19:44:27 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: Preparing to generate the ALSA configuration file Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: Setting Geolocation for MyVolumio to eu10 Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: Updating MyVolumio device info Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: Reading ALSA contributions from plugins. Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: MRS: Removed streaming files Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: MRS: volumioStreaming STOPPED Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: MRS: SNAPSERVER STOPPED Oct 02 19:44:28 volumio-audiophonics volumio[1002]: info: MRS: SNAPCLIENT STOPPED Oct 02 19:44:29 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 19:44:29 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 02 19:44:29 volumio-audiophonics volumio[1002]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 02 19:44:29 volumio-audiophonics volumio[1002]: info: Successfully Added MyVolumio device Oct 02 19:44:29 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:29 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:29 volumio-audiophonics volumio-remote-updater[653]: No test mode Oct 02 19:44:29 volumio-audiophonics volumio-remote-updater[653]: No alpha test mode Oct 02 19:44:29 volumio-audiophonics volumio[1002]: info: Asound.conf file written Oct 02 19:44:29 volumio-audiophonics sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 19:44:29 volumio-audiophonics sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:29 volumio-audiophonics sudo[1793]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:29 volumio-audiophonics volumio[1002]: info: Output device has changed, restarting MPD Oct 02 19:44:29 volumio-audiophonics sudo[1799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 19:44:29 volumio-audiophonics sudo[1799]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:29 volumio-audiophonics volumio[1002]: info: Output device has changed, restarting Shairport Sync Oct 02 19:44:29 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:29 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:29 volumio-audiophonics sudo[1799]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:29 volumio-audiophonics sudo[1801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 19:44:29 volumio-audiophonics sudo[1801]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:30 volumio-audiophonics systemd[1]: Stopping Music Player Daemon... Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: MRS: Pushing multiroomSync output for this device Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: MRS: Pushing multiroomSync output Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:30 volumio-audiophonics sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Audiophonics Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:30 volumio-audiophonics sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:30 volumio-audiophonics sudo[1810]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding METAVOLUMIO REST API Endpoints Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Preparing CD Folders Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding CD REST API Endpoints Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Starting UDEV Watcher for CD Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Detecting CD presence with UDEV Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding inputs REST Endpoints Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Scanning Audio Inputs Oct 02 19:44:30 volumio-audiophonics systemd[1]: mpd.service: Succeeded. Oct 02 19:44:30 volumio-audiophonics systemd[1]: Stopped Music Player Daemon. Oct 02 19:44:30 volumio-audiophonics systemd[1]: Starting Music Player Daemon... Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Checking against Known Cards name Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding Server instance for streaming Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 02 19:44:30 volumio-audiophonics volumio[1002]: error: Hi Res Audio Failed Login: Missing Login Data Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Refreshing TIDAL token Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 02 19:44:30 volumio-audiophonics sudo[1819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 19:44:30 volumio-audiophonics sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Stopping AccessToken refresher cron for QOBUZ Oct 02 19:44:30 volumio-audiophonics sudo[1816]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 19:44:30 volumio-audiophonics sudo[1816]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:30 volumio-audiophonics sudo[1816]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: AccessToken refresher cron started for QOBUZ Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding QOBUZ REST API Endpoints Oct 02 19:44:30 volumio-audiophonics sudo[1819]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Updating MyVolumio device info Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Successfully Updated MyVolumio device Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Successfully Added MyVolumio device Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: Bluetooth name changed to Volumio Audiophonics Oct 02 19:44:30 volumio-audiophonics volumio[1002]: info: MPD Permissions set Oct 02 19:44:31 volumio-audiophonics sudo[1825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 02 19:44:31 volumio-audiophonics sudo[1825]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:31 volumio-audiophonics systemd[1]: Started MyVolumio SSH Tunnel. Oct 02 19:44:31 volumio-audiophonics sudo[1825]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:31 volumio-audiophonics autossh[1830]: port set to 0, monitoring disabled Oct 02 19:44:31 volumio-audiophonics autossh[1830]: starting ssh (count 1) Oct 02 19:44:31 volumio-audiophonics autossh[1830]: ssh child pid is 1837 Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: Access Token successfully retrieved Oct 02 19:44:31 volumio-audiophonics volumio[1002]: info: Remote SSH Started Oct 02 19:44:31 volumio-audiophonics volumiossh-tunnel[1828]: Warning: Permanently added '[eu10.myvolumio.org]:2222,[46.101.141.203]:2222' (RSA) to the list of known hosts. Oct 02 19:44:32 volumio-audiophonics volumio[1002]: info: Successfully Updated MyVolumio device Oct 02 19:44:32 volumio-audiophonics volumio[1002]: info: Starting Shairport Sync Oct 02 19:44:32 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Google-Cast-Group-B17F20DB8AF845778C0A71C503806411 Oct 02 19:44:32 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:32 volumio-audiophonics sudo[1841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 19:44:32 volumio-audiophonics sudo[1841]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:32 volumio-audiophonics volumio[1002]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 19:44:32 volumio-audiophonics systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 02 19:44:32 volumio-audiophonics systemd[1]: shairport-sync.service: Succeeded. Oct 02 19:44:32 volumio-audiophonics systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 02 19:44:32 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Google-Home-6dab7a9cb6b262430cbe8c05fee2a406 Oct 02 19:44:32 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:32 volumio-audiophonics systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 02 19:44:32 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Google-Cast-Group-41C913244A3F46CBB1E0F7491953174B Oct 02 19:44:32 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:32 volumio-audiophonics sudo[1841]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: Shairport-Sync Started Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Nest-Audio-2f0337397954f704dc0ae35fcf8470a3 Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Google-Nest-Hub-Max-3a6c60ff8868eb4a981730ef0f07105f Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Google-Cast-Group-9430A898D5C04AB59178AEB0E5D934F6 Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: Successfully retrieved User Session From TIDAL Oct 02 19:44:33 volumio-audiophonics sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 02 19:44:33 volumio-audiophonics sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:33 volumio-audiophonics systemd[1]: Started Volumio Bluetooth Module. Oct 02 19:44:33 volumio-audiophonics sudo[1852]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:33 volumio-audiophonics sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 02 19:44:33 volumio-audiophonics sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:33 volumio-audiophonics volumiobt[1855]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:33 volumio-audiophonics sudo[1856]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Google-Cast-Group-B58D360A66B9488C9B0779CED6D412F0-1 Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: Volumio BT Module successfully started Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Google-Cast-Group-C400C40B76E74D728F65CC4F859A2D96 Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Google-Home-a5badef822420cf5d2403d6587eaa437 Oct 02 19:44:33 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: TidalConnect service stoped! Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Google-Nest-Hub-ae9e661ef2ece95907d68e9bdc085c97 Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: Successfully retrieved User Subscription From TIDAL Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: Adding TIDAL to Browse Sources Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: [1727891074202] CoreMusicLibrary::Adding element TIDAL Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 19:44:34 volumio-audiophonics volumio[1002]: Cannot find translation for source YouTube2 Oct 02 19:44:34 volumio-audiophonics volumio[1002]: Cannot find translation for source YouTube Music Oct 02 19:44:34 volumio-audiophonics volumio[1002]: Cannot find translation for source Radio Paradise Oct 02 19:44:34 volumio-audiophonics volumio[1002]: Cannot find translation for source TIDAL Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: Stopping AccessToken refresher cron Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: AccessToken refresher cron started Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: Adding TIDAL REST API Endpoints Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Google-Cast-Group-A322F8461D344946A6C16633916ABAE6 Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:34 volumio-audiophonics volumio[1002]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: MRS: Found cast device: Chromecast-Ultra-530365af9c5a975ba5518bd7525fc122 Oct 02 19:44:34 volumio-audiophonics volumio[1002]: info: Adding audio output: Oct 02 19:44:34 volumio-audiophonics sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 02 19:44:34 volumio-audiophonics sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:34 volumio-audiophonics sudo[1871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 19:44:34 volumio-audiophonics sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 19:44:34 volumio-audiophonics volumiobt[1855]: pulseaudio: no process found Oct 02 19:44:34 volumio-audiophonics sudo[1870]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:34 volumio-audiophonics systemd[1]: Started Volumio Tidal Connect Service. Oct 02 19:44:34 volumio-audiophonics sudo[1871]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:35 volumio-audiophonics dbus-daemon[647]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.40' (uid=1000 pid=1879 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 02 19:44:35 volumio-audiophonics systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 02 19:44:35 volumio-audiophonics volumio[1002]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 02 19:44:35 volumio-audiophonics volumio[1002]: info: MRS: Getting audio outputs on start Oct 02 19:44:35 volumio-audiophonics volumio[1002]: info: MRS: Requesting all other devices output Oct 02 19:44:35 volumio-audiophonics dbus-daemon[647]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 02 19:44:35 volumio-audiophonics systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 02 19:44:35 volumio-audiophonics rtkit-daemon[1880]: Successfully called chroot. Oct 02 19:44:35 volumio-audiophonics rtkit-daemon[1880]: Successfully dropped privileges. Oct 02 19:44:35 volumio-audiophonics rtkit-daemon[1880]: Successfully limited resources. Oct 02 19:44:35 volumio-audiophonics rtkit-daemon[1880]: Running. Oct 02 19:44:35 volumio-audiophonics rtkit-daemon[1880]: Canary thread running. Oct 02 19:44:35 volumio-audiophonics rtkit-daemon[1880]: Watchdog thread running. Oct 02 19:44:35 volumio-audiophonics dbus-daemon[647]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.41' (uid=0 pid=1880 comm="/usr/lib/rtkit/rtkit-daemon ") Oct 02 19:44:35 volumio-audiophonics systemd[1]: Starting Authorization Manager... Oct 02 19:44:35 volumio-audiophonics volumio[1002]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 02 19:44:35 volumio-audiophonics polkitd[1883]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 02 19:44:35 volumio-audiophonics dbus-daemon[647]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 02 19:44:35 volumio-audiophonics systemd[1]: Started Authorization Manager. Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: Executing endpoint tc_getconfig Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 02 19:44:36 volumio-audiophonics vtcs[1875]: STARTING TidalConnect services, version: 1.4.0.34 Oct 02 19:44:36 volumio-audiophonics vtcs[1875]: STARTED TidalConnect services. Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: Executing endpoint tc_connect Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: Connecting to TidalConnect Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::servicePushState Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CoreStateMachine::pushState Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioPushState Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: MRS: Pushing multiroomSync output update for this device Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: MRS: Pushing multiroomSync output Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::servicePushState Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CoreStateMachine::pushState Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioPushState Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: MRS: Pushing multiroomSync output update for this device Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: MRS: Pushing multiroomSync output Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:36 volumio-audiophonics volumio[1002]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Oct 02 19:44:36 volumio-audiophonics volumio[1002]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Oct 02 19:44:36 volumio-audiophonics volumio[1002]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Oct 02 19:44:36 volumio-audiophonics mpd[1822]: Oct 02 19:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 19:44:36 volumio-audiophonics systemd[1]: Started Music Player Daemon. Oct 02 19:44:36 volumio-audiophonics sudo[1801]: pam_unix(sudo:session): session closed for user root Oct 02 19:44:36 volumio-audiophonics pulseaudio[1879]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 02 19:44:36 volumio-audiophonics pulseaudio[1879]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 02 19:44:37 volumio-audiophonics bluetoothd[818]: Endpoint registered: sender=:1.61 path=/MediaEndpoint/A2DPSource Oct 02 19:44:37 volumio-audiophonics bluetoothd[818]: Endpoint registered: sender=:1.61 path=/MediaEndpoint/A2DPSink Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: Applying permissions Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: Setting BT discoverable and pairable Oct 02 19:44:37 volumio-audiophonics pulseaudio[1879]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Oct 02 19:44:37 volumio-audiophonics volumio[1002]: error: updateQueue error: null Oct 02 19:44:37 volumio-audiophonics kernel: Bluetooth: RFCOMM TTY layer initialized Oct 02 19:44:37 volumio-audiophonics kernel: Bluetooth: RFCOMM socket layer initialized Oct 02 19:44:37 volumio-audiophonics kernel: Bluetooth: RFCOMM ver 1.11 Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [CHG] Controller B8:27:EB:10:14:31 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [117B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [55B blob data] Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [bluetooth]# -e power on Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: Invalid command in menu main: -e Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: Use "help" for a list of available commands in a menu. Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: Use "menu " if you want to enter any submenu. Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: Use "back" if you want to return to menu main. Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [bluetooth]# agent on Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: Agent is already registered Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [bluetooth]# discoverable on Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [bluetooth]# pairable on Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [bluetooth]# agent NoInputNoOutput Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: Agent is already registered Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [bluetooth]# default-agent Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [bluetooth]# quit Oct 02 19:44:37 volumio-audiophonics volumiobt[1855]: [59B blob data] Oct 02 19:44:37 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 19:44:37 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 19:44:37 volumio-audiophonics volumio[1002]: info: TidalConnect service started! Oct 02 19:44:37 volumio-audiophonics volumio[1002]: info: BOOT COMPLETED Oct 02 19:44:37 volumio-audiophonics volumio[1002]: [Metrics] CommandRouter: 65s 36.25ms Oct 02 19:44:37 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumiosetStartupVolume Oct 02 19:44:37 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:44:37 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:44:38 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::Close All Modals sent Oct 02 19:44:38 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::Close All Modals sent Oct 02 19:44:39 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 02 19:44:39 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 19:44:39 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 02 19:44:39 volumio-audiophonics volumio[1002]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 02 19:44:43 volumio-audiophonics volumio[1002]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 02 19:44:46 volumio-audiophonics volumio[1002]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: Received Get System Info Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: Discovery: Getting this device information Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:46 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: Listing playlists Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: Received Get System Info Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: Discovery: Getting this device information Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: Received Get System Info Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: Discovery: Getting this device information Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: Received Get System Info Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: Discovery: Getting this device information Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: Listing playlists Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 19:44:47 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 19:44:49 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 02 19:44:49 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: Received Get System Version Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: Received Get System Info Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: Discovery: Getting this device information Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 0 Oct 02 19:44:57 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:45:07 volumio-audiophonics volumio[1246]: ...................................................................................................................................................................................................................................................++++ Oct 02 19:45:09 volumio-audiophonics volumiologrotate[651]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 02 19:45:09 volumio-audiophonics volumiologrotate[651]: ls: cannot access 'AUDIOPHONICS': No such file or directory Oct 02 19:45:13 volumio-audiophonics kernel: hwmon hwmon1: Voltage normalised Oct 02 19:45:19 volumio-audiophonics kernel: hwmon hwmon1: Undervoltage detected! Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioNext Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CoreStateMachine::next Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CoreStateMachine::stop Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CoreStateMachine::play index undefined Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 1 Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CoreStateMachine::startPlaybackTimer Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 1 Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: [1727891121490] ControllerTidal::clearAddPlayTrack Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: Getting stream with soundQuality LOSSLESS Oct 02 19:45:21 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CoreStateMachine::updateTrackBlock Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrackBlock Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: sendMpdCommand stop took 7 milliseconds Oct 02 19:45:21 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: sendMpdCommand clear took 12 milliseconds Oct 02 19:45:21 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/291112825&soundQuality=LOSSLESS" Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: Oct 02 19:45:21 volumio-audiophonics volumio[1002]: ---------------------------- MPD announces system playlist update Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: Ignoring MPD Status Update Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: Oct 02 19:45:21 volumio-audiophonics volumio[1002]: ---------------------------- MPD announces system playlist update Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: Ignoring MPD Status Update Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/291112825&soundQuality=LOSSLESS" took 8 milliseconds Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 19:45:21 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::sendMpdCommand play Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: ------------------------------ 15ms Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: ------------------------------ 11ms Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: sendMpdCommand play took 8 milliseconds Oct 02 19:45:21 volumio-audiophonics volumio[1002]: STREAMING PROXY: Handling url /?data=tidal://song/291112825&soundQuality=LOSSLESS Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: Executing endpoint getStreamUrltidal Oct 02 19:45:21 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: getStreamUrl took 540 milliseconds Oct 02 19:45:22 volumio-audiophonics volumio[1002]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic0ZWEzZThhYjlhYTE3MTY4ZTQ0YjE1Yjk3MDQ2YjhhNF82MS5tcDQ/0.flac?Expires=1727894673&Signature=YpzRcSIeIbTBs2FhpG9A1iBprmBxhPKsjgJrLis5BMd37uwVkfoea168D22aLLKTAdJPdfTcvNiG6TYQFsNP3crpevCegL5T52AC1nioucUT3MVgYuHyeVvrMZWfdLw2843nFa8mSbo~ngrD2u0Va7G63ns8HgV7kHt5ve0GI~q8JE-twyN4PBaoLW5kMORYvoExjl1OiaI4xaagFbUIfkqe6gDzVKcuKpGkYK3S4ahrYolVaG9T4uSqnQwCJOqMt1-u70mPKp6wknQoMeYP3oOgBK965HO4Zp6AsVFyWYkCrcZE0BVwtocaXf4DSL4zvn45bunfcJciXzcYLdTXQw__&Key-Pair-Id=K14LZCZ9QUI4JL Oct 02 19:45:22 volumio-audiophonics volumio[1002]: STREAMING PROXY: Response: 200, length: 33583949 Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: Oct 02 19:45:22 volumio-audiophonics volumio[1002]: ---------------------------- MPD announces state update: player Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: ControllerMpd::getState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: Oct 02 19:45:22 volumio-audiophonics volumio[1002]: ---------------------------- MPD announces state update: player Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: sendMpdCommand status took 55 milliseconds Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: ControllerMpd::getState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::parseState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: sendMpdCommand status took 12 milliseconds Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::parseState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::parseTrackInfo Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: ControllerMpd::pushState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::servicePushState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 1 Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"661 Kbps","isStreaming":false,"title":"291112825&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/291112825&soundQuality=LOSSLESS","trackType":"tidal"} Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: CURRENT POSITION 1 Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreStateMachine::syncState stateService play Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: ------------------------------ 107ms Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: ControllerMpd::parseTrackInfo Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: ControllerMpd::pushState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::servicePushState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getTrack 1 Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"691 Kbps","isStreaming":false,"title":"291112825&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/291112825&soundQuality=LOSSLESS","trackType":"tidal"} Oct 02 19:45:22 volumio-audiophonics volumio[1002]: verbose: CURRENT POSITION 1 Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreStateMachine::syncState stateService play Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreStateMachine::syncState currentStatus play Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: Received an update from plugin. extracting info from payload Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreStateMachine::pushState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioPushState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: MRS: Pushing multiroomSync output update for this device Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: MRS: Pushing multiroomSync output Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreStateMachine::pushState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioPushState Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: MRS: Pushing multiroomSync output update for this device Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: MRS: Pushing multiroomSync output Oct 02 19:45:22 volumio-audiophonics volumio[1002]: info: ------------------------------ 298ms Oct 02 19:45:22 volumio-audiophonics volumio[1002]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Oct 02 19:45:22 volumio-audiophonics volumio[1002]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Oct 02 19:45:23 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:45:23 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:45:23 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:45:23 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:45:24 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:45:24 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:45:24 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:45:24 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:45:25 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:45:25 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:45:25 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:45:25 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:45:26 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:45:26 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:45:26 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:45:26 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:45:27 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:45:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:45:28 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:45:28 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:45:28 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:45:29 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:45:29 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:45:29 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:45:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:45:30 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:45:30 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:45:30 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:45:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:45:31 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:45:31 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:45:31 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:45:32 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:45:32 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:45:32 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:45:32 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:45:33 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetState Oct 02 19:45:33 volumio-audiophonics volumio[1002]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:45:33 volumio-audiophonics volumio[1002]: info: CoreStateMachine::getQueue Oct 02 19:45:33 volumio-audiophonics volumio[1002]: info: CorePlayQueue::getQueue Oct 02 19:45:33 volumio-audiophonics volumio[1002]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Oct 02 19:45:33 volumio-audiophonics volumio[1002]: info: PLUGIN onShutdown : networkfs Oct 02 19:45:33 volumio-audiophonics volumio[1002]: info: PLUGIN onShutdown : audiophonicsonoff Oct 02 19:45:33 volumio-audiophonics volumio[1002]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 19:45:33 volumio-audiophonics volumio[1002]: TypeError: Cannot read property 'writeSync' of undefined Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1319:24) Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at Socket.emit (events.js:315:20) Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 02 19:45:33 volumio-audiophonics volumio[1002]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 02 19:45:33 volumio-audiophonics volumio[1002]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 19:45:34 volumio-audiophonics volumio[1002]: STREAMING PROXY: Client dropped request, destroying Oct 02 19:45:35 volumio-audiophonics sudo[2161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-02 19:44 Oct 02 19:45:35 volumio-audiophonics sudo[2161]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"