-- Logs begin at Thu 2024-05-02 10:44:15 CEST, end at Thu 2024-05-02 10:46:17 CEST. -- May 02 10:45:00 volumio volumio[857]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd May 02 10:45:00 volumio systemd[1]: Starting lircd(8) initialization helper tool... May 02 10:45:00 volumio volumio-remote-updater[548]: [2024-05-02 10:45:00] [connect] Successful connection May 02 10:45:00 volumio systemd[1]: Started Volumio Kiosk. May 02 10:45:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 10:45:00 volumio sudo[1139]: pam_unix(sudo:session): session closed for user root May 02 10:45:00 volumio systemd[1]: Started go-librespot Daemon. May 02 10:45:00 volumio sudo[1082]: pam_unix(sudo:session): session closed for user root May 02 10:45:00 volumio go-librespot[1152]: Librespot-go daemon starting... May 02 10:45:00 volumio volumio-remote-updater[548]: [2024-05-02 10:45:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714639500 101 May 02 10:45:00 volumio systemd[1]: shairport-sync.service: Succeeded. May 02 10:45:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 10:45:00 volumio volumio[857]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 10 May 02 10:45:00 volumio sudo[1055]: pam_unix(sudo:session): session closed for user root May 02 10:45:00 volumio volumio[857]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 02 10:45:00 volumio volumio[857]: info: touch_display: Volumio Kiosk started. May 02 10:45:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 10:45:00 volumio sudo[1135]: pam_unix(sudo:session): session closed for user root May 02 10:45:00 volumio sudo[1114]: pam_unix(sudo:session): session closed for user root May 02 10:45:00 volumio sudo[1131]: pam_unix(sudo:session): session closed for user root May 02 10:45:00 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root May 02 10:45:00 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:00 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:00 volumio mpd[1018]: May 02 10:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 10:45:00 volumio volumio[857]: info: [IrActivityLed] lirc socket connect: attempt 4 of 20 May 02 10:45:00 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:00 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:00 volumio volumio[857]: Upnp client error: Error: This socket has been ended by the other party May 02 10:45:00 volumio volumio[857]: info: Shairport-Sync Started May 02 10:45:00 volumio volumio[857]: Error adding Membership: Error: addMembership EINVAL May 02 10:45:00 volumio volumio[857]: info: Shairport-Sync Started May 02 10:45:00 volumio volumio[857]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 02 10:45:00 volumio systemd[1]: Started Music Player Daemon. May 02 10:45:00 volumio sudo[1013]: pam_unix(sudo:session): session closed for user root May 02 10:45:00 volumio sudo[1003]: pam_unix(sudo:session): session closed for user root May 02 10:45:00 volumio volumio[857]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd May 02 10:45:00 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:00 volumio startx[1151]: X.Org X Server 1.20.4 May 02 10:45:00 volumio startx[1151]: X Protocol Version 11, Revision 0 May 02 10:45:00 volumio startx[1151]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian May 02 10:45:00 volumio startx[1151]: Current Operating System: Linux volumio 6.1.69-v7+ #1710 SMP Thu Dec 21 13:14:13 GMT 2023 armv7l May 02 10:45:00 volumio startx[1151]: 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=320 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=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop 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 May 02 10:45:00 volumio startx[1151]: Build Date: 04 April 2023 07:50:56AM May 02 10:45:00 volumio startx[1151]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) May 02 10:45:00 volumio startx[1151]: Current version of pixman: 0.36.0 May 02 10:45:00 volumio startx[1151]: Before reporting problems, check http://wiki.x.org May 02 10:45:00 volumio startx[1151]: to make sure that you have the latest version. May 02 10:45:00 volumio startx[1151]: Markers: (--) probed, (**) from config file, (==) default setting, May 02 10:45:00 volumio startx[1151]: (++) from command line, (!!) notice, (II) informational, May 02 10:45:00 volumio startx[1151]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 02 10:45:00 volumio startx[1151]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu May 2 10:45:00 2024 May 02 10:45:00 volumio go-librespot[1152]: time="2024-05-02T10:45:00+02:00" level=info msg="generated new device id: 19e66d2b0ea3ac4781f948608078533a0e027ce4" May 02 10:45:00 volumio go-librespot[1152]: time="2024-05-02T10:45:00+02:00" level=debug msg="stored credentials found for 21iwmbfpqm7cn53tbmsjqqwbq" May 02 10:45:00 volumio startx[1151]: (==) Using config directory: "/etc/X11/xorg.conf.d" May 02 10:45:00 volumio startx[1151]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 02 10:45:01 volumio volumio[857]: error: MPD error: The expression evaluated to a falsy value: May 02 10:45:01 volumio volumio[857]: assert.ok(self.idling) May 02 10:45:01 volumio volumio[857]: error: The expression evaluated to a falsy value: May 02 10:45:01 volumio volumio[857]: assert.ok(self.idling) May 02 10:45:01 volumio volumio[857]: info: MPD running with PID1018 May 02 10:45:01 volumio volumio[857]: ,establishing connection May 02 10:45:01 volumio volumio[857]: info: [IrActivityLed] lirc socket connect: attempt 5 of 20 May 02 10:45:01 volumio startx[1151]: (EE) May 02 10:45:01 volumio startx[1151]: Fatal server error: May 02 10:45:01 volumio startx[1151]: (EE) no screens found(EE) May 02 10:45:01 volumio startx[1151]: (EE) May 02 10:45:01 volumio startx[1151]: Please consult the The X.Org Foundation support May 02 10:45:01 volumio startx[1151]: at http://wiki.x.org May 02 10:45:01 volumio startx[1151]: for help. May 02 10:45:01 volumio startx[1151]: (EE) Please also check the log file at "/var/log/Xorg.0.log" for additional information. May 02 10:45:01 volumio startx[1151]: (EE) May 02 10:45:01 volumio volumio[857]: error: updateQueue error: null May 02 10:45:01 volumio startx[1151]: (EE) Server terminated with error (1). Closing log file. May 02 10:45:01 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:01 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:01 volumio volumio[857]: error: updateQueue error: null May 02 10:45:01 volumio volumio[857]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd May 02 10:45:01 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:01 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:01 volumio volumio[857]: info: [IrActivityLed] lirc socket connect: attempt 6 of 20 May 02 10:45:01 volumio lircd-setup[1150]: /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 02 10:45:01 volumio lircd-setup[1150]: parser = configparser.SafeConfigParser() May 02 10:45:01 volumio volumio[857]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd May 02 10:45:01 volumio systemd[1]: lircd-setup.service: Succeeded. May 02 10:45:01 volumio systemd[1]: Started lircd(8) initialization helper tool. May 02 10:45:01 volumio systemd[1]: Starting Flexible IR remote input/output application support... May 02 10:45:01 volumio lircd-0.10.1[1206]: Info: lircd: Opening log, level: Info May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Version: lircd 0.10.1 May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: System info: Linux volumio 6.1.69-v7+ #1710 SMP Thu Dec 21 13:14:13 GMT 2023 armv7l GNU/Linux May 02 10:45:01 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:01 volumio lircd-0.10.1[1206]: Info: Initial device: /dev/lirc0 May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols May 02 10:45:01 volumio lircd-0.10.1[1206]: Info: Initial device: /dev/lirc0 May 02 10:45:01 volumio lircd-0.10.1[1206]: Info: [lirc] protocol is enabled May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: driver: default May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: output: /var/run/lirc/lircd May 02 10:45:01 volumio lircd[1206]: lircd-0.10.1[1206]: Info: lircd: Opening log, level: Info May 02 10:45:01 volumio lircd[1206]: lircd-0.10.1[1206]: Notice: Using systemd fd May 02 10:45:01 volumio lircd[1206]: lircd-0.10.1[1206]: Warning: Running as root May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: nodaemon: 1 May 02 10:45:01 volumio lircd[1206]: lircd-0.10.1[1206]: Info: Using remote: lircd.conf. May 02 10:45:01 volumio lircd[1206]: lircd-0.10.1[1206]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 02 10:45:01 volumio systemd[1]: Started Flexible IR remote input/output application support. May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: logfile: syslog May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: immediate-init: 0 May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: permission: 666 May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: driver-options: May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: listen: 0 May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: connect: (null) May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: userelease: 0 May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: effective_user: (null) May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: release_suffix: _EVUP May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: allow_simulate: 0 May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: repeat_max: 600 May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: configfile: /etc/lirc/lircd.conf May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Options: dynamic_codes: (null) May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Current driver: default May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Driver API version: 3 May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Driver version: 0.10.0 May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 02 10:45:01 volumio lircd-0.10.1[1206]: Info: lircd: Opening log, level: Info May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: Using systemd fd May 02 10:45:01 volumio lircd-0.10.1[1206]: Warning: Running as root May 02 10:45:01 volumio lircd-0.10.1[1206]: Info: Using remote: lircd.conf. May 02 10:45:01 volumio lircd-0.10.1[1206]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 02 10:45:01 volumio sudo[1148]: pam_unix(sudo:session): session closed for user root May 02 10:45:01 volumio volumio[857]: info: ir_controller: systemctl restart lircd.service succeeded. May 02 10:45:01 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:01 volumio sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 02 10:45:01 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:01 volumio go-librespot[1152]: time="2024-05-02T10:45:01+02:00" level=debug msg="obtained new client token: AAD3jgrOMC8NZ6ZuK+JLPP5H7htHmU+k3L4aH5OGWnveB2P4f9WO+BoQmYpergYcJwCS1E+UMf7vuZ9bAFqkwcSuZH9dzcoolWnoEED2k3jtmU0K8wUQT3VnhShedjJedmISil/joj8VX0Dhrj6JETHlLNMjOwkExqXchJ88IkEk9jG4CAXk3GTFKBnOi1ukVaDdxlCL2N9aOT6ZXNrjs3KAvUqspJzS99R4mRzUirA2FvMmxmqOCUPBsMwvsg==" May 02 10:45:01 volumio volumio[857]: info: [IrActivityLed] lirc socket connect: attempt 7 of 20 May 02 10:45:01 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:02 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 02 10:45:02 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root May 02 10:45:02 volumio lircd[1206]: lircd-0.10.1[1206]: Notice: accepted new client on /var/run/lirc/lircd May 02 10:45:02 volumio lircd-0.10.1[1206]: Notice: accepted new client on /var/run/lirc/lircd May 02 10:45:02 volumio lircd[1206]: lircd-0.10.1[1206]: Info: [lirc] protocol is enabled May 02 10:45:02 volumio lircd-0.10.1[1206]: Info: [lirc] protocol is enabled May 02 10:45:02 volumio volumio[857]: info: ir_controller: systemctl restart irexec.service succeeded. May 02 10:45:02 volumio volumio[857]: info: [IrActivityLed] lircd service status: active May 02 10:45:02 volumio lircd[1206]: lircd-0.10.1[1206]: Notice: accepted new client on /var/run/lirc/lircd May 02 10:45:02 volumio lircd-0.10.1[1206]: Notice: accepted new client on /var/run/lirc/lircd May 02 10:45:02 volumio volumio[857]: info: [IrActivityLed] Connected to lirc socket May 02 10:45:02 volumio volumio[857]: info: [IrActivityLed] initializing built-in LED May 02 10:45:02 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:02 volumio go-librespot[1152]: time="2024-05-02T10:45:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 02 10:45:02 volumio sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /sys/class/leds/led1/brightness /sys/class/leds/led1/trigger May 02 10:45:02 volumio sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:02 volumio sudo[1223]: pam_unix(sudo:session): session closed for user root May 02 10:45:02 volumio volumio[857]: info: [IrActivityLed] Error: Command failed: /usr/bin/sudo /bin/chmod a+rw /sys/class/leds/led1/brightness /sys/class/leds/led1/trigger May 02 10:45:02 volumio volumio[857]: /bin/chmod: cannot access '/sys/class/leds/led1/brightness': No such file or directory May 02 10:45:02 volumio volumio[857]: /bin/chmod: cannot access '/sys/class/leds/led1/trigger': No such file or directory May 02 10:45:02 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:02 volumio go-librespot[1152]: time="2024-05-02T10:45:02+02:00" level=debug msg="completed keyexchange" May 02 10:45:02 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:02 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:02 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:02 volumio go-librespot[1152]: time="2024-05-02T10:45:02+02:00" level=debug msg="completed challenge" May 02 10:45:02 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:02 volumio go-librespot[1152]: time="2024-05-02T10:45:02+02:00" level=debug msg="authenticated as 21iwmbfpqm7cn53tbmsjqqwbq" May 02 10:45:02 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="authenticated as 21iwmbfpqm7cn53tbmsjqqwbq" May 02 10:45:03 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 02 10:45:03 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="dealer connection opened" May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="received connection id: MTQ5ZTdiNzAtYjA0ZS00Zjc1LWJlMDEtNDMxNGU1MThhODFkK2RlYWxlcit0Y3A6Ly9nZXcxLWRlYWxlci1hLXI2ZmcuZ2V3MS5zcG90aWZ5Lm5ldDo1NzAwKzM3N0FDNUFBMURBQTBERkE1M0NCNjY5NTEzQzZDRkE2Q0E3NEZBMjU0RkJCNjY5OTIyOUIwRDI2NTVDQjkyQ0Q=" May 02 10:45:03 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:03 volumio volumio[857]: info: go-librespot daemon successfully initialized May 02 10:45:03 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:03 volumio go-librespot[1152]: time="2024-05-02T10:45:03+02:00" level=debug msg="put connect state because NEW_DEVICE" May 02 10:45:03 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:03 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:03 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:03 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:03 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:03 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:03 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:04 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:04 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:04 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:04 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:04 volumio volumio[992]: Generating RSA private key, 4096 bit long modulus (2 primes) May 02 10:45:04 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:04 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:04 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:04 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:05 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:05 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:05 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:05 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:05 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:05 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:05 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:05 volumio volumio[857]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 02 10:45:05 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:06 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:06 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:06 volumio volumio[992]: ...........++++ May 02 10:45:06 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:06 volumio volumio[857]: info: Initializing connection to go-librespot Websocket May 02 10:45:06 volumio go-librespot[1152]: time="2024-05-02T10:45:06+02:00" level=debug msg="new websocket client" May 02 10:45:06 volumio volumio[857]: info: Connection to go-librespot Websocket established May 02 10:45:06 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:06 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:06 volumio kernel: hwmon hwmon1: Voltage normalised May 02 10:45:06 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:06 volumio volumio[992]: .++++ May 02 10:45:06 volumio volumio[992]: e is 65537 (0x010001) May 02 10:45:06 volumio volumio[992]: writing RSA key May 02 10:45:06 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:06 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:06 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:07 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:07 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:07 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:07 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:07 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:07 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 10:45:07 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:07 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:08 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:08 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:08 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:08 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:08 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:08 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:08 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:08 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:09 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:09 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:09 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:09 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:09 volumio volumio[857]: info: Getting Spotify volume May 02 10:45:09 volumio volumio[857]: info: Spotify volume: 100 May 02 10:45:09 volumio volumio[857]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 May 02 10:45:09 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:09 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:09 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:09 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:09 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:09 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:10 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:10 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:10 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:10 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:10 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:10 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:10 volumio kernel: hwmon hwmon1: Undervoltage detected! May 02 10:45:10 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:10 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:11 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:11 volumio sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 10:45:11 volumio sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:11 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:11 volumio sudo[1406]: pam_unix(sudo:session): session closed for user root May 02 10:45:11 volumio sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 10:45:11 volumio sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:11 volumio sudo[1408]: pam_unix(sudo:session): session closed for user root May 02 10:45:11 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:11 volumio volumio[857]: verbose: New Socket.io Connection to 192.168.86.41 from 192.168.86.39 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 12 May 02 10:45:11 volumio sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 10:45:11 volumio sudo[1414]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:11 volumio sudo[1414]: pam_unix(sudo:session): session closed for user root May 02 10:45:11 volumio sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 10:45:11 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:11 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:11 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:11 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:11 volumio volumio[857]: verbose: New Socket.io Connection to 192.168.86.41 from 192.168.86.39 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 12 May 02 10:45:11 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:11 volumio volumio[857]: info: Received Get System Info May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:11 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:11 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:11 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::volumioGetVisibleSources May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:11 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:11 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 10:45:11 volumio volumio[857]: info: Received Get System Info May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:11 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:11 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:11 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:11 volumio volumio[857]: info: Listing playlists May 02 10:45:11 volumio volumio[857]: info: CoreCommandRouter::volumioGetQueue May 02 10:45:11 volumio volumio[857]: info: CoreStateMachine::getQueue May 02 10:45:11 volumio volumio[857]: info: CorePlayQueue::getQueue May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:12 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:12 volumio volumio[857]: info: Received Get System Info May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:12 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:12 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:12 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::volumioGetVisibleSources May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:12 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:12 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 10:45:12 volumio volumio[857]: info: Received Get System Info May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:12 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:12 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:12 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:12 volumio volumio[857]: info: Listing playlists May 02 10:45:12 volumio volumio[857]: info: CoreCommandRouter::volumioGetQueue May 02 10:45:12 volumio volumio[857]: info: CoreStateMachine::getQueue May 02 10:45:12 volumio volumio[857]: info: CorePlayQueue::getQueue May 02 10:45:12 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:12 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:13 volumio volumio[857]: info: VolumeController::SetAlsaVolume0 May 02 10:45:13 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:13 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:13 volumio volumio[857]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 02 10:45:13 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:13 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:13 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 10:45:13 volumio volumio[857]: info: Received Get System Info May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:13 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:13 volumio sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 10:45:13 volumio sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:13 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root May 02 10:45:13 volumio sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 10:45:13 volumio sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:13 volumio volumio[857]: info: Listing playlists May 02 10:45:13 volumio volumio[857]: info: Listing playlists May 02 10:45:13 volumio sudo[1451]: pam_unix(sudo:session): session closed for user root May 02 10:45:13 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:13 volumio volumio[857]: verbose: New Socket.io Connection to 192.168.86.41 from 192.168.86.39 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 12 May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:13 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:13 volumio volumio[857]: info: Received Get System Info May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:13 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetVisibleSources May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 10:45:13 volumio volumio[857]: info: Received Get System Info May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:13 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:13 volumio volumio[857]: info: Listing playlists May 02 10:45:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetQueue May 02 10:45:13 volumio volumio[857]: info: CoreStateMachine::getQueue May 02 10:45:13 volumio volumio[857]: info: CorePlayQueue::getQueue May 02 10:45:14 volumio volumio[857]: info: Received Get System Info May 02 10:45:14 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:14 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:14 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:14 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:14 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:14 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:14 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:14 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:14 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:14 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:14 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 02 10:45:14 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:14 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:14 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:14 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:14 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:14 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:14 volumio volumio[857]: error: touch_display: Connecting to the Xserver failed. May 02 10:45:14 volumio volumio[857]: info: touch_display: X display number found: 0 May 02 10:45:15 volumio volumio[857]: info: AutoStart - Plugin is starting May 02 10:45:15 volumio volumio[857]: info: CoreCommandRouter::volumioGetQueue May 02 10:45:15 volumio volumio[857]: info: CoreStateMachine::getQueue May 02 10:45:15 volumio volumio[857]: info: CorePlayQueue::getQueue May 02 10:45:15 volumio volumio[857]: info: AutoStart - start playing May 02 10:45:15 volumio volumio[857]: info: AutoStart - start playing with no specific position May 02 10:45:15 volumio volumio[857]: info: CoreCommandRouter::volumioPlay May 02 10:45:15 volumio volumio[857]: info: CoreStateMachine::play index 0 May 02 10:45:15 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 10:45:15 volumio volumio[857]: info: CoreStateMachine::stop May 02 10:45:15 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 10:45:15 volumio volumio[857]: info: CoreStateMachine::play index undefined May 02 10:45:15 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 10:45:15 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:15 volumio volumio[857]: info: CoreStateMachine::startPlaybackTimer May 02 10:45:15 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:15 volumio volumio[857]: info: [1714639515027] ControllerWebradio::clearAddPlayTrack May 02 10:45:15 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop May 02 10:45:15 volumio volumio[857]: info: sendMpdCommand stop took 2 milliseconds May 02 10:45:15 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand clear May 02 10:45:15 volumio volumio[857]: info: May 02 10:45:15 volumio volumio[857]: ---------------------------- MPD announces system playlist update May 02 10:45:15 volumio volumio[857]: info: Ignoring MPD Status Update May 02 10:45:15 volumio volumio[857]: info: sendMpdCommand clear took 3 milliseconds May 02 10:45:15 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand load "http://ouifm.ice.infomaniak.ch/ouifm-high.mp3" May 02 10:45:15 volumio volumio[857]: info: May 02 10:45:15 volumio volumio[857]: ---------------------------- MPD announces system playlist update May 02 10:45:15 volumio volumio[857]: info: Ignoring MPD Status Update May 02 10:45:15 volumio volumio[857]: info: May 02 10:45:15 volumio volumio[857]: ---------------------------- MPD announces system playlist update May 02 10:45:15 volumio volumio[857]: info: Ignoring MPD Status Update May 02 10:45:15 volumio volumio[857]: error: updateQueue error: null May 02 10:45:15 volumio volumio[857]: info: ------------------------------ 10ms May 02 10:45:15 volumio startx[1151]: xinit: giving up May 02 10:45:15 volumio startx[1151]: xinit: unable to connect to X server: Cannot assign requested address May 02 10:45:15 volumio startx[1151]: xinit: server error May 02 10:45:15 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE May 02 10:45:15 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand add "http://ouifm.ice.infomaniak.ch/ouifm-high.mp3" May 02 10:45:16 volumio volumio[857]: error: updateQueue error: null May 02 10:45:16 volumio volumio[857]: error: updateQueue error: null May 02 10:45:16 volumio volumio[857]: info: ------------------------------ 995ms May 02 10:45:16 volumio volumio[857]: info: ------------------------------ 993ms May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces system playlist update May 02 10:45:16 volumio volumio[857]: info: Ignoring MPD Status Update May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand add "http://ouifm.ice.infomaniak.ch/ouifm-high.mp3" took 4 milliseconds May 02 10:45:16 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand play May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces system playlist update May 02 10:45:16 volumio volumio[857]: info: Ignoring MPD Status Update May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces system playlist update May 02 10:45:16 volumio volumio[857]: info: Ignoring MPD Status Update May 02 10:45:16 volumio volumio[857]: info: ------------------------------ 12ms May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand play took 9 milliseconds May 02 10:45:16 volumio volumio[857]: info: ------------------------------ 9ms May 02 10:45:16 volumio volumio[857]: info: ------------------------------ 6ms May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:45:16 volumio volumio[857]: info: ControllerMpd::getState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:45:16 volumio volumio[857]: info: ControllerMpd::getState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:45:16 volumio volumio[857]: info: ControllerMpd::getState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:45:16 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand status took 646 milliseconds May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces system playlist update May 02 10:45:16 volumio volumio[857]: info: Ignoring MPD Status Update May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:45:16 volumio volumio[857]: info: ControllerMpd::getState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand status took 661 milliseconds May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand status took 657 milliseconds May 02 10:45:16 volumio volumio[857]: info: ControllerMpd::getState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand playlistinfo took 17 milliseconds May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:45:16 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:45:16 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:45:16 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:16 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":null,"album":null,"uri":"http://ouifm.ice.infomaniak.ch/ouifm-high.mp3","trackType":"mp3"} May 02 10:45:16 volumio volumio[857]: verbose: CURRENT POSITION 0 May 02 10:45:16 volumio volumio[857]: info: CoreStateMachine::syncState stateService play May 02 10:45:16 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop May 02 10:45:16 volumio volumio[857]: info: ------------------------------ 702ms May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:45:16 volumio volumio[857]: info: ControllerMpd::getState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:45:16 volumio volumio[857]: info: May 02 10:45:16 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:45:16 volumio volumio[857]: info: ControllerMpd::getState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand status took 63 milliseconds May 02 10:45:16 volumio volumio[857]: info: ------------------------------ 71ms May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand status took 62 milliseconds May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand playlistinfo took 57 milliseconds May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand playlistinfo took 56 milliseconds May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand status took 31 milliseconds May 02 10:45:16 volumio volumio[857]: info: sendMpdCommand status took 27 milliseconds May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:45:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:45:16 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:45:16 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:45:16 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:16 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":null,"album":null,"uri":"http://ouifm.ice.infomaniak.ch/ouifm-high.mp3","trackType":"mp3"} May 02 10:45:16 volumio volumio[857]: verbose: CURRENT POSITION 0 May 02 10:45:16 volumio volumio[857]: info: CoreStateMachine::syncState stateService play May 02 10:45:16 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play May 02 10:45:16 volumio volumio[857]: info: Received an update from plugin. extracting info from payload May 02 10:45:16 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:16 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 10:45:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:45:17 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:17 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":null,"album":null,"uri":"http://ouifm.ice.infomaniak.ch/ouifm-high.mp3","trackType":"mp3"} May 02 10:45:17 volumio volumio[857]: verbose: CURRENT POSITION 0 May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState stateService play May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play May 02 10:45:17 volumio volumio[857]: info: Received an update from plugin. extracting info from payload May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: ------------------------------ 868ms May 02 10:45:17 volumio volumio[857]: info: ------------------------------ 864ms May 02 10:45:17 volumio volumio[857]: info: May 02 10:45:17 volumio volumio[857]: ---------------------------- MPD announces system playlist update May 02 10:45:17 volumio volumio[857]: info: Ignoring MPD Status Update May 02 10:45:17 volumio volumio[857]: info: May 02 10:45:17 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:45:17 volumio volumio[857]: info: ControllerMpd::getState May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:45:17 volumio volumio[857]: info: May 02 10:45:17 volumio volumio[857]: ---------------------------- MPD announces system playlist update May 02 10:45:17 volumio volumio[857]: info: Ignoring MPD Status Update May 02 10:45:17 volumio volumio[857]: info: May 02 10:45:17 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:45:17 volumio volumio[857]: info: ControllerMpd::getState May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:45:17 volumio volumio[857]: info: sendMpdCommand playlistinfo took 147 milliseconds May 02 10:45:17 volumio volumio[857]: info: sendMpdCommand playlistinfo took 146 milliseconds May 02 10:45:17 volumio volumio[857]: info: sendMpdCommand playlistinfo took 142 milliseconds May 02 10:45:17 volumio volumio[857]: info: sendMpdCommand playlistinfo took 141 milliseconds May 02 10:45:17 volumio volumio[857]: info: ------------------------------ 14ms May 02 10:45:17 volumio volumio[857]: info: sendMpdCommand status took 12 milliseconds May 02 10:45:17 volumio volumio[857]: info: ------------------------------ 11ms May 02 10:45:17 volumio volumio[857]: info: sendMpdCommand status took 9 milliseconds May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:45:17 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:45:17 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:17 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":null,"album":null,"uri":"http://ouifm.ice.infomaniak.ch/ouifm-high.mp3","trackType":"mp3"} May 02 10:45:17 volumio volumio[857]: verbose: CURRENT POSITION 0 May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState stateService play May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play May 02 10:45:17 volumio volumio[857]: info: Received an update from plugin. extracting info from payload May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:45:17 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:17 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":null,"album":null,"uri":"http://ouifm.ice.infomaniak.ch/ouifm-high.mp3","trackType":"mp3"} May 02 10:45:17 volumio volumio[857]: verbose: CURRENT POSITION 0 May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState stateService play May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play May 02 10:45:17 volumio volumio[857]: info: Received an update from plugin. extracting info from payload May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:45:17 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:17 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":null,"album":null,"uri":"http://ouifm.ice.infomaniak.ch/ouifm-high.mp3","trackType":"mp3"} May 02 10:45:17 volumio volumio[857]: verbose: CURRENT POSITION 0 May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState stateService play May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play May 02 10:45:17 volumio volumio[857]: info: Received an update from plugin. extracting info from payload May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:45:17 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:17 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":null,"album":null,"uri":"http://ouifm.ice.infomaniak.ch/ouifm-high.mp3","trackType":"mp3"} May 02 10:45:17 volumio volumio[857]: verbose: CURRENT POSITION 0 May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState stateService play May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play May 02 10:45:17 volumio volumio[857]: info: Received an update from plugin. extracting info from payload May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: ------------------------------ 457ms May 02 10:45:17 volumio volumio[857]: info: ------------------------------ 454ms May 02 10:45:17 volumio volumio[857]: info: ------------------------------ 417ms May 02 10:45:17 volumio volumio[857]: info: ------------------------------ 414ms May 02 10:45:17 volumio volumio[857]: info: sendMpdCommand playlistinfo took 376 milliseconds May 02 10:45:17 volumio volumio[857]: info: sendMpdCommand playlistinfo took 376 milliseconds May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:45:17 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:45:17 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:45:17 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:17 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":914,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":null,"album":null,"uri":"http://ouifm.ice.infomaniak.ch/ouifm-high.mp3","trackType":"mp3"} May 02 10:45:17 volumio volumio[857]: verbose: CURRENT POSITION 0 May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState stateService play May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play May 02 10:45:17 volumio volumio[857]: info: Received an update from plugin. extracting info from payload May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:45:17 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:45:17 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":914,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":null,"album":null,"uri":"http://ouifm.ice.infomaniak.ch/ouifm-high.mp3","trackType":"mp3"} May 02 10:45:17 volumio volumio[857]: verbose: CURRENT POSITION 0 May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState stateService play May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play May 02 10:45:17 volumio volumio[857]: info: Received an update from plugin. extracting info from payload May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:45:17 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:45:17 volumio volumio[857]: info: ------------------------------ 499ms May 02 10:45:17 volumio volumio[857]: info: ------------------------------ 496ms May 02 10:45:18 volumio sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 10:45:18 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:18 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root May 02 10:45:18 volumio sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 10:45:18 volumio sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:18 volumio sudo[1487]: pam_unix(sudo:session): session closed for user root May 02 10:45:18 volumio volumio[857]: verbose: New Socket.io Connection to 192.168.86.41 from 192.168.86.39 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 12 May 02 10:45:18 volumio sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 10:45:18 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root May 02 10:45:18 volumio sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 10:45:18 volumio sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:18 volumio sudo[1493]: pam_unix(sudo:session): session closed for user root May 02 10:45:18 volumio volumio[857]: info: Received Get System Info May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:18 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio volumio[857]: info: Received Get System Info May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:18 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetVisibleSources May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 10:45:18 volumio volumio[857]: info: Received Get System Info May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:18 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio volumio[857]: info: Listing playlists May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetQueue May 02 10:45:18 volumio volumio[857]: info: CoreStateMachine::getQueue May 02 10:45:18 volumio volumio[857]: info: CorePlayQueue::getQueue May 02 10:45:18 volumio volumio[857]: info: Received Get System Info May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:18 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:18 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:18 volumio volumio[857]: verbose: New Socket.io Connection to 192.168.86.41 from 192.168.86.39 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 12 May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:19 volumio volumio[857]: info: Received Get System Info May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:19 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:19 volumio volumio[857]: info: Received Get System Info May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:19 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetVisibleSources May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 10:45:19 volumio volumio[857]: info: Received Get System Info May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:19 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:19 volumio volumio[857]: info: Listing playlists May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetQueue May 02 10:45:19 volumio volumio[857]: info: CoreStateMachine::getQueue May 02 10:45:19 volumio volumio[857]: info: CorePlayQueue::getQueue May 02 10:45:19 volumio volumio[857]: info: Received Get System Info May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:19 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:19 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:20 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 10:45:20 volumio volumio[857]: info: Received Get System Info May 02 10:45:20 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:20 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:20 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:20 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:20 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:21 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 02 10:45:23 volumio kernel: hwmon hwmon1: Voltage normalised May 02 10:45:23 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 10:45:24 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:24 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root May 02 10:45:24 volumio sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 10:45:24 volumio sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:24 volumio sudo[1564]: pam_unix(sudo:session): session closed for user root May 02 10:45:24 volumio volumio[857]: verbose: New Socket.io Connection to 192.168.86.41 from 192.168.86.39 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 12 May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: Received Get System Info May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:24 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: Received Get System Info May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:24 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetVisibleSources May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 10:45:24 volumio volumio[857]: info: Received Get System Info May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:24 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: Listing playlists May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetQueue May 02 10:45:24 volumio volumio[857]: info: CoreStateMachine::getQueue May 02 10:45:24 volumio volumio[857]: info: CorePlayQueue::getQueue May 02 10:45:24 volumio volumio[857]: info: Received Get System Info May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:24 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: Received Get System Info May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:24 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:24 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:25 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:25 volumio kernel: hwmon hwmon1: Undervoltage detected! May 02 10:45:25 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 10:45:25 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:45:25 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 10:45:25 volumio volumio[857]: error: Plugin system_hardware iractivityled failed to complete 'onStart' in a timely fashion May 02 10:45:25 volumio volumio[857]: info: ------------------------------------------- May 02 10:45:25 volumio volumio[857]: info: ----- MyVolumio plugins startup ---- May 02 10:45:25 volumio volumio[857]: info: ------------------------------------------- May 02 10:45:25 volumio volumio[857]: info: [MyVolumio PluginManager] Fetching plans data.... May 02 10:45:29 volumio ntpd[680]: Soliciting pool server 195.154.226.102 May 02 10:45:29 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 10:45:30 volumio ntpd[680]: Soliciting pool server 212.83.158.83 May 02 10:45:30 volumio ntpd[680]: Soliciting pool server 51.15.177.17 May 02 10:45:31 volumio ntpd[680]: Soliciting pool server 51.195.104.188 May 02 10:45:31 volumio ntpd[680]: Soliciting pool server 162.159.200.123 May 02 10:45:31 volumio kernel: hwmon hwmon1: Voltage normalised May 02 10:45:31 volumio sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 10:45:31 volumio sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:31 volumio sudo[1587]: pam_unix(sudo:session): session closed for user root May 02 10:45:31 volumio sudo[1590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 10:45:31 volumio sudo[1590]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:31 volumio sudo[1590]: pam_unix(sudo:session): session closed for user root May 02 10:45:31 volumio volumio[857]: verbose: New Socket.io Connection to 192.168.86.41 from 192.168.86.39 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 12 May 02 10:45:31 volumio sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 10:45:31 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:31 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 10:45:31 volumio sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:31 volumio sudo[1596]: pam_unix(sudo:session): session closed for user root May 02 10:45:31 volumio volumio[857]: info: Received Get System Info May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:31 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio volumio[857]: info: Received Get System Info May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:31 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio volumio[857]: info: Received Get System Info May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:31 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetVisibleSources May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 10:45:31 volumio volumio[857]: info: Received Get System Info May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:31 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:31 volumio volumio[857]: info: Listing playlists May 02 10:45:31 volumio volumio[857]: info: CoreCommandRouter::volumioGetQueue May 02 10:45:31 volumio volumio[857]: info: CoreStateMachine::getQueue May 02 10:45:31 volumio volumio[857]: info: CorePlayQueue::getQueue May 02 10:45:32 volumio volumio[857]: info: Received Get System Info May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:32 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: Received Get System Info May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:32 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio ntpd[680]: Soliciting pool server 82.127.104.72 May 02 10:45:32 volumio ntpd[680]: Soliciting pool server 185.254.100.25 May 02 10:45:32 volumio ntpd[680]: Soliciting pool server 176.137.36.37 May 02 10:45:32 volumio volumio[857]: verbose: New Socket.io Connection to 192.168.86.41 from 192.168.86.39 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 12 May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: Received Get System Info May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:32 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: Received Get System Info May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:32 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: Received Get System Info May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:32 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetVisibleSources May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 10:45:32 volumio volumio[857]: info: Received Get System Info May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:32 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: Listing playlists May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetQueue May 02 10:45:32 volumio volumio[857]: info: CoreStateMachine::getQueue May 02 10:45:32 volumio volumio[857]: info: CorePlayQueue::getQueue May 02 10:45:32 volumio volumio[857]: info: Received Get System Info May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:32 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: Received Get System Info May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:32 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:32 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:33 volumio ntpd[680]: Soliciting pool server 37.59.63.125 May 02 10:45:33 volumio ntpd[680]: Soliciting pool server 82.64.131.93 May 02 10:45:33 volumio ntpd[680]: Soliciting pool server 178.32.23.17 May 02 10:45:33 volumio ntpd[680]: Soliciting pool server 162.19.224.29 May 02 10:45:33 volumio kernel: hwmon hwmon1: Undervoltage detected! May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 02 10:45:33 volumio volumio[857]: info: Adding plugin bluetooth to MyMusic Plugins May 02 10:45:33 volumio volumio[857]: info: Adding plugin multiroom to MyMusic Plugins May 02 10:45:33 volumio volumio[857]: info: Adding plugin metavolumio to MyMusic Plugins May 02 10:45:33 volumio volumio[857]: info: Adding plugin cd_controller to MyMusic Plugins May 02 10:45:33 volumio volumio[857]: info: Adding plugin smart_inputs to MyMusic Plugins May 02 10:45:33 volumio volumio[857]: info: Adding plugin tidalconnect to MyMusic Plugins May 02 10:45:33 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 02 10:45:34 volumio ntpd[680]: Soliciting pool server 82.64.247.11 May 02 10:45:34 volumio ntpd[680]: Soliciting pool server 2001:19f0:6801:7ed::123 May 02 10:45:34 volumio ntpd[680]: Soliciting pool server 51.38.99.44 May 02 10:45:34 volumio ntpd[680]: Soliciting pool server 79.143.250.33 May 02 10:45:35 volumio ntpd[680]: Soliciting pool server 91.224.149.41 May 02 10:45:35 volumio ntpd[680]: Soliciting pool server 51.255.33.56 May 02 10:45:35 volumio ntpd[680]: Soliciting pool server 95.179.212.126 May 02 10:45:55 volumio ntpd[680]: receive: Unexpected origin timestamp 0xe9ddd130.39f2b9fd does not match aorg 0000000000.00000000 from server@212.83.158.83 xmt 0xe9ddd143.ba50216d May 02 10:45:55 volumio ntpd[680]: receive: Unexpected origin timestamp 0xe9ddd130.39e1e94f does not match aorg 0000000000.00000000 from server@91.224.149.41 xmt 0xe9ddd143.ba2075ad May 02 10:45:55 volumio ntpd[680]: receive: Unexpected origin timestamp 0xe9ddd130.39f03966 does not match aorg 0000000000.00000000 from server@51.195.104.188 xmt 0xe9ddd143.bb264f20 May 02 10:45:55 volumio ntpd[680]: receive: Unexpected origin timestamp 0xe9ddd130.39ee4e2f does not match aorg 0000000000.00000000 from server@176.137.36.37 xmt 0xe9ddd143.bb9c6636 May 02 10:45:55 volumio volumio[857]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 02 10:45:55 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 02 10:45:55 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:45:55 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:45:55 volumio volumio[857]: info: Starting MyVolumio Remote Streaming Endpoints May 02 10:45:55 volumio volumio[857]: info: MyVolumio login type: Token May 02 10:45:55 volumio volumio[857]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 02 10:45:55 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 02 10:45:57 volumio volumio[857]: info: Starting Streaming Service Transparent Proxy May 02 10:45:57 volumio volumio[857]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 02 10:45:57 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 02 10:45:57 volumio volumio[857]: info: Streaming services startup May 02 10:45:57 volumio volumio[857]: info: Starting Streaming Daemon May 02 10:45:57 volumio sudo[1625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 02 10:45:57 volumio sudo[1625]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:57 volumio volumio[857]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 02 10:45:57 volumio sudo[1625]: pam_unix(sudo:session): session closed for user root May 02 10:45:57 volumio volumio[857]: error: Cannot start Volumio Streaming Daemon May 02 10:45:57 volumio volumio[857]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 02 10:45:57 volumio volumio[857]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 02 10:45:57 volumio volumio[857]: Upnp client error: Error: This socket has been ended by the other party May 02 10:45:57 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:57 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 10:45:57 volumio volumio[857]: info: Received Get System Info May 02 10:45:57 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:57 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:57 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:57 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:57 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:57 volumio volumio[857]: info: Listing playlists May 02 10:45:57 volumio volumio[857]: info: Listing playlists May 02 10:45:57 volumio volumio[857]: info: CoreCommandRouter::Close All Modals sent May 02 10:45:58 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 02 10:45:58 volumio volumio[857]: info: MyVolumio login type: Token May 02 10:45:58 volumio volumio[857]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 02 10:45:59 volumio sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 10:45:59 volumio sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:59 volumio sudo[1648]: pam_unix(sudo:session): session closed for user root May 02 10:45:59 volumio sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 10:45:59 volumio sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:45:59 volumio sudo[1650]: pam_unix(sudo:session): session closed for user root May 02 10:45:59 volumio volumio[857]: verbose: New Socket.io Connection to 192.168.86.41 from 192.168.86.39 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 12 May 02 10:45:59 volumio volumio[857]: STREAMING PROXY: Starting server on port 3245 May 02 10:45:59 volumio volumio[857]: Node JS runtime: 14 May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: Received Get System Info May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:59 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: Received Get System Info May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:59 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: Received Get System Info May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:59 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetVisibleSources May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 10:45:59 volumio volumio[857]: info: Received Get System Info May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:59 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: Listing playlists May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetQueue May 02 10:45:59 volumio volumio[857]: info: CoreStateMachine::getQueue May 02 10:45:59 volumio volumio[857]: info: CorePlayQueue::getQueue May 02 10:45:59 volumio volumio[857]: info: Received Get System Info May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:59 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: Received Get System Info May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:59 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: Received Get System Info May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 10:45:59 volumio volumio[857]: info: Discovery: Getting this device information May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 10:45:59 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:46:00 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 02 10:46:00 volumio volumio[857]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/peppymeter/run_peppymeter.sh May 02 10:46:00 volumio volumio[857]: error: XDG_RUNTIME_DIR not set in the environment. May 02 10:46:00 volumio volumio[857]: info: MyVolumio token set successfully May 02 10:46:00 volumio volumio[857]: info: MYVOLUMIO: Adding device May 02 10:46:00 volumio volumio[857]: info: MYVOLUMIO: Evaluating Server May 02 10:46:01 volumio volumio[857]: info: MyVolumio Plan changed: premium May 02 10:46:01 volumio volumio[857]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 02 10:46:01 volumio volumio[857]: info: Removing browser output: myVolumio user plan is not superstar May 02 10:46:01 volumio volumio[857]: info: Removing audio output: May 02 10:46:01 volumio volumio[857]: info: MYVOLUMIO: Adding device May 02 10:46:01 volumio volumio[857]: info: MYVOLUMIO: Evaluating Server May 02 10:46:01 volumio volumio[857]: info: Remote config written successfully May 02 10:46:01 volumio volumio[857]: info: Starting Tunnel 1 May 02 10:46:01 volumio volumio[857]: info: Starting Tunnel Connection Checker May 02 10:46:01 volumio volumio[857]: info: Completed starting MyVolumio Plugin May 02 10:46:01 volumio volumio[857]: info: MYVolumio Device enabled May 02 10:46:01 volumio volumio[857]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 02 10:46:01 volumio volumio[857]: info: MyVolumio status changed May 02 10:46:01 volumio volumio[857]: info: Streaming services startup May 02 10:46:01 volumio volumio[857]: info: Starting Streaming Daemon May 02 10:46:01 volumio sudo[1703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 02 10:46:01 volumio sudo[1703]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:01 volumio volumio[857]: info: Setting Geolocation for MyVolumio to eu2 May 02 10:46:01 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:01 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:01 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:01 volumio sudo[1703]: pam_unix(sudo:session): session closed for user root May 02 10:46:01 volumio volumio[857]: error: Cannot start Volumio Streaming Daemon May 02 10:46:01 volumio volumio[857]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 02 10:46:01 volumio volumio[857]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 02 10:46:01 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 02 10:46:01 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 02 10:46:02 volumio volumio[857]: info: [1714639562221] Starting BluetoothController May 02 10:46:02 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 02 10:46:03 volumio kernel: hwmon hwmon1: Voltage normalised May 02 10:46:04 volumio volumio[857]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 02 10:46:04 volumio sudo[1707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 02 10:46:04 volumio sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:04 volumio sudo[1707]: pam_unix(sudo:session): session closed for user root May 02 10:46:04 volumio volumio[857]: info: MRS: MultiRoom plugin initialized May 02 10:46:04 volumio volumio[857]: info: MRS: STOPPING SNAPCLIENT May 02 10:46:04 volumio volumio[857]: info: MRS: Snap server stop May 02 10:46:04 volumio sudo[1723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 02 10:46:04 volumio sudo[1723]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:04 volumio volumio[857]: info: MRS: STOPPING volumioStreaming May 02 10:46:04 volumio sudo[1726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 02 10:46:04 volumio sudo[1726]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:04 volumio sudo[1723]: pam_unix(sudo:session): session closed for user root May 02 10:46:04 volumio sudo[1726]: pam_unix(sudo:session): session closed for user root May 02 10:46:04 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 02 10:46:04 volumio sudo[1729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 02 10:46:04 volumio sudo[1729]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:04 volumio sudo[1731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 02 10:46:04 volumio sudo[1731]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:04 volumio sudo[1731]: pam_unix(sudo:session): session closed for user root May 02 10:46:04 volumio sudo[1729]: pam_unix(sudo:session): session closed for user root May 02 10:46:04 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 02 10:46:04 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 02 10:46:04 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 02 10:46:04 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 02 10:46:04 volumio volumio[857]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 02 10:46:04 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 02 10:46:04 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 02 10:46:04 volumio volumio[857]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 02 10:46:04 volumio volumio[857]: info: Preparing to generate the ALSA configuration file May 02 10:46:04 volumio volumio[857]: info: Updating MyVolumio device info May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:04 volumio volumio[857]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 02 10:46:04 volumio volumio[857]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 02 10:46:04 volumio volumio[857]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 02 10:46:04 volumio volumio[857]: info: Reading ALSA contributions from plugins. May 02 10:46:04 volumio volumio[857]: info: Setting Geolocation for MyVolumio to eu2 May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:04 volumio volumio[857]: info: MRS: Removed streaming files May 02 10:46:04 volumio volumio[857]: info: MRS: volumioStreaming STOPPED May 02 10:46:04 volumio volumio[857]: info: MRS: SNAPSERVER STOPPED May 02 10:46:04 volumio volumio[857]: info: MRS: SNAPCLIENT STOPPED May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:46:04 volumio volumio[857]: Upnp client error: Error: This socket has been ended by the other party May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::volumioStop May 02 10:46:04 volumio volumio[857]: info: CoreStateMachine::stop May 02 10:46:04 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 10:46:04 volumio volumio[857]: info: CoreStateMachine::stPlaybackTimer May 02 10:46:04 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock May 02 10:46:04 volumio volumio[857]: info: CorePlayQueue::getTrackBlock May 02 10:46:04 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:46:04 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:46:04 volumio volumio[857]: info: CoreStateMachine::serviceStop May 02 10:46:04 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::serviceStop May 02 10:46:04 volumio volumio[857]: info: [1714639564916] ControllerWebradio::stop May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop May 02 10:46:04 volumio volumio[857]: info: May 02 10:46:04 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:46:04 volumio volumio[857]: info: sendMpdCommand stop took 38 milliseconds May 02 10:46:04 volumio volumio[857]: info: ControllerMpd::getState May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:46:04 volumio volumio[857]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X0' May 02 10:46:04 volumio volumio[857]: info: sendMpdCommand status took 12 milliseconds May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:46:04 volumio volumio[857]: info: May 02 10:46:04 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:46:04 volumio volumio[857]: info: ControllerMpd::getState May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:46:04 volumio volumio[857]: info: May 02 10:46:04 volumio volumio[857]: ---------------------------- MPD announces state update: player May 02 10:46:04 volumio volumio[857]: info: ControllerMpd::getState May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::volumioStop May 02 10:46:04 volumio volumio[857]: info: CoreStateMachine::stop May 02 10:46:04 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 10:46:04 volumio volumio[857]: info: sendMpdCommand playlistinfo took 12 milliseconds May 02 10:46:04 volumio volumio[857]: info: sendMpdCommand status took 9 milliseconds May 02 10:46:04 volumio volumio[857]: info: sendMpdCommand status took 7 milliseconds May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::parseState May 02 10:46:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 10:46:04 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:46:04 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:46:04 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:46:04 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:04 volumio volumio[857]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 02 10:46:05 volumio volumio[857]: info: ------------------------------ 48ms May 02 10:46:05 volumio volumio[857]: info: sendMpdCommand playlistinfo took 32 milliseconds May 02 10:46:05 volumio volumio[857]: info: sendMpdCommand playlistinfo took 32 milliseconds May 02 10:46:05 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:46:05 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo May 02 10:46:05 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:46:05 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:46:05 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:46:05 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:05 volumio volumio[857]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 02 10:46:05 volumio volumio[857]: info: ControllerMpd::pushState May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:46:05 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:46:05 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:46:05 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:05 volumio volumio[857]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 02 10:46:05 volumio volumio[857]: info: ------------------------------ 83ms May 02 10:46:05 volumio volumio[857]: info: ------------------------------ 82ms May 02 10:46:05 volumio volumio[857]: info: Asound.conf file written May 02 10:46:05 volumio sudo[1737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 02 10:46:05 volumio sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:05 volumio sudo[1737]: pam_unix(sudo:session): session closed for user root May 02 10:46:05 volumio volumio[857]: info: Output device has changed, restarting MPD May 02 10:46:05 volumio sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 10:46:05 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:05 volumio volumio[857]: info: Output device has changed, restarting Shairport Sync May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:05 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root May 02 10:46:05 volumio sudo[1745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 10:46:05 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:05 volumio systemd[1]: Stopping Music Player Daemon... May 02 10:46:05 volumio volumio[857]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 10:46:05 volumio volumio[857]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:05 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 02 10:46:05 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 02 10:46:05 volumio volumio[857]: info: MRS: Pushing multiroomSync output for this device May 02 10:46:05 volumio volumio[857]: info: MRS: Pushing multiroomSync output May 02 10:46:05 volumio volumio[857]: info: Adding audio output: May 02 10:46:05 volumio volumio[857]: info: Adding audio output: May 02 10:46:05 volumio sudo[1753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio May 02 10:46:05 volumio sudo[1753]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:05 volumio systemd[1]: mpd.service: Succeeded. May 02 10:46:05 volumio sudo[1753]: pam_unix(sudo:session): session closed for user root May 02 10:46:05 volumio systemd[1]: Stopped Music Player Daemon. May 02 10:46:05 volumio volumio[857]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:05 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 02 10:46:05 volumio volumio[857]: info: Adding METAVOLUMIO REST API Endpoints May 02 10:46:05 volumio volumio[857]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 02 10:46:05 volumio volumio[857]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 02 10:46:05 volumio volumio[857]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 02 10:46:05 volumio volumio[857]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 02 10:46:05 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 02 10:46:05 volumio volumio[857]: info: Adding Manifest REST API Endpoints May 02 10:46:05 volumio volumio[857]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui May 02 10:46:05 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 02 10:46:05 volumio volumio[857]: info: Preparing CD Folders May 02 10:46:05 volumio volumio[857]: info: Adding CD REST API Endpoints May 02 10:46:05 volumio volumio[857]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 02 10:46:05 volumio volumio[857]: info: Starting UDEV Watcher for CD May 02 10:46:05 volumio volumio[857]: info: Detecting CD presence with UDEV May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 02 10:46:05 volumio systemd[1]: Starting Music Player Daemon... May 02 10:46:05 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 02 10:46:05 volumio volumio[857]: info: Adding inputs REST Endpoints May 02 10:46:05 volumio volumio[857]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 02 10:46:05 volumio volumio[857]: info: Scanning Audio Inputs May 02 10:46:05 volumio volumio[857]: info: Checking against Known Cards name May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 02 10:46:05 volumio volumio[857]: info: [1714639565584] CoreMusicLibrary::Adding element Dummy May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 10:46:05 volumio volumio[857]: Cannot find translation for source Spotify May 02 10:46:05 volumio volumio[857]: Cannot find translation for source Dummy May 02 10:46:05 volumio volumio[857]: info: Checking against Known Cards name May 02 10:46:05 volumio volumio[857]: info: Adding Server instance for streaming May 02 10:46:05 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 02 10:46:05 volumio volumio[857]: error: Hi Res Audio Failed Login: Missing Login Data May 02 10:46:05 volumio volumio[857]: info: Adding HIGHRESAUDIO REST API Endpoints May 02 10:46:05 volumio volumio[857]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 02 10:46:05 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 02 10:46:05 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 02 10:46:05 volumio volumio[857]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 02 10:46:05 volumio sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 02 10:46:05 volumio sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:05 volumio volumio[857]: info: Stopping AccessToken refresher cron May 02 10:46:05 volumio sudo[1762]: pam_unix(sudo:session): session closed for user root May 02 10:46:05 volumio volumio[857]: info: AccessToken refresher cron started May 02 10:46:05 volumio volumio[857]: info: Stopping AccessToken refresher cron for QOBUZ May 02 10:46:05 volumio volumio[857]: info: AccessToken refresher cron started for QOBUZ May 02 10:46:05 volumio volumio[857]: info: Adding TIDAL REST API Endpoints May 02 10:46:05 volumio volumio[857]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 02 10:46:05 volumio volumio[857]: info: Adding QOBUZ REST API Endpoints May 02 10:46:05 volumio volumio[857]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 02 10:46:05 volumio volumio[857]: info: Successfully Added MyVolumio device May 02 10:46:05 volumio volumio[857]: info: Successfully Updated MyVolumio device May 02 10:46:05 volumio volumio[857]: info: Successfully Added MyVolumio device May 02 10:46:05 volumio volumio[857]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio' May 02 10:46:05 volumio volumio[857]: Can't change local name on hci0: Network is down (100) May 02 10:46:05 volumio volumio[857]: info: MPD Permissions set May 02 10:46:05 volumio volumio[857]: info: Updating MyVolumio device info May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:05 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:05 volumio volumio[857]: error: Upnp client error: Error: This socket has been ended by the other party May 02 10:46:06 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:06 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:06 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:06 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:06 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:06 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:06 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:06 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:06 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:06 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:06 volumio volumio[857]: info: Adding audio output: May 02 10:46:06 volumio volumio[857]: info: Adding audio output: May 02 10:46:06 volumio volumio[857]: info: Starting Shairport Sync May 02 10:46:06 volumio sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 10:46:06 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:06 volumio volumio[857]: info: MRS: Found cast device: Google-Cast-Group-6527D1A04F624E71A907B3A61CAB2C5A May 02 10:46:06 volumio volumio[857]: info: Adding audio output: May 02 10:46:06 volumio volumio[857]: info: Preparing to generate the ALSA configuration file May 02 10:46:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 10:46:06 volumio systemd[1]: shairport-sync.service: Succeeded. May 02 10:46:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 10:46:06 volumio volumio[857]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 02 10:46:06 volumio volumio[857]: info: MRS: Found cast device: Chromecast-4400ca10f4d7dfa0d9ba645b00f12c73 May 02 10:46:06 volumio volumio[857]: info: Adding audio output: May 02 10:46:06 volumio volumio[857]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 02 10:46:06 volumio volumio[857]: info: Reading ALSA contributions from plugins. May 02 10:46:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 10:46:06 volumio sudo[1775]: pam_unix(sudo:session): session closed for user root May 02 10:46:06 volumio volumio[857]: info: MRS: Found cast device: Nest-Wifi-point-d65755c7b56e07b00e37ce4e52b108f7 May 02 10:46:06 volumio volumio[857]: info: Adding audio output: May 02 10:46:06 volumio volumio[857]: info: Shairport-Sync Started May 02 10:46:06 volumio volumio[857]: info: Adding audio output: May 02 10:46:06 volumio volumio[857]: info: Adding audio output: May 02 10:46:06 volumio volumio[857]: info: MRS: Found cast device: Google-Home-Mini-59f9bebe471ec612c4df7a6def6519f3 May 02 10:46:06 volumio volumio[857]: info: Adding audio output: May 02 10:46:06 volumio sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 02 10:46:06 volumio sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:07 volumio volumio[857]: info: Successfully Updated MyVolumio device May 02 10:46:07 volumio systemd[1]: Started MyVolumio SSH Tunnel. May 02 10:46:07 volumio sudo[1786]: pam_unix(sudo:session): session closed for user root May 02 10:46:07 volumio autossh[1789]: port set to 0, monitoring disabled May 02 10:46:07 volumio autossh[1789]: starting ssh (count 1) May 02 10:46:07 volumio autossh[1789]: ssh child pid is 1792 May 02 10:46:07 volumio mpd[1756]: May 02 10:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 10:46:07 volumio systemd[1]: Started Music Player Daemon. May 02 10:46:07 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root May 02 10:46:07 volumio volumio[857]: info: Remote SSH Started May 02 10:46:07 volumio volumiossh-tunnel[1788]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. May 02 10:46:07 volumio volumio[857]: info: Executing endpoint metavolumio May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 02 10:46:07 volumio volumio[857]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 May 02 10:46:07 volumio volumio[857]: error: updateQueue error: null May 02 10:46:07 volumio volumio[857]: info: Asound.conf file written May 02 10:46:07 volumio sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 02 10:46:07 volumio sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:07 volumio sudo[1796]: pam_unix(sudo:session): session closed for user root May 02 10:46:07 volumio volumio[857]: info: Output device has changed, restarting MPD May 02 10:46:07 volumio sudo[1801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 10:46:07 volumio sudo[1801]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:07 volumio sudo[1801]: pam_unix(sudo:session): session closed for user root May 02 10:46:07 volumio volumio[857]: info: Output device has changed, restarting Shairport Sync May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:07 volumio sudo[1804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 10:46:07 volumio sudo[1804]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:07 volumio systemd[1]: Stopping Music Player Daemon... May 02 10:46:07 volumio volumio[857]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 10:46:07 volumio volumio[857]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio systemd[1]: mpd.service: Succeeded. May 02 10:46:07 volumio systemd[1]: Stopped Music Player Daemon. May 02 10:46:07 volumio volumio[857]: info: Preparing to generate the ALSA configuration file May 02 10:46:07 volumio systemd[1]: Starting Music Player Daemon... May 02 10:46:07 volumio volumio[857]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 02 10:46:07 volumio volumio[857]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 02 10:46:07 volumio volumio[857]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 02 10:46:07 volumio volumio[857]: info: Reading ALSA contributions from plugins. May 02 10:46:07 volumio volumio[857]: info: MPD Permissions set May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:07 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:46:07 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:07 volumio volumio[857]: info: Starting Shairport Sync May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 02 10:46:08 volumio sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 10:46:08 volumio sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 10:46:08 volumio systemd[1]: shairport-sync.service: Succeeded. May 02 10:46:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 10:46:08 volumio volumio[857]: info: Asound.conf file written May 02 10:46:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 10:46:08 volumio sudo[1817]: pam_unix(sudo:session): session closed for user root May 02 10:46:08 volumio sudo[1821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 02 10:46:08 volumio sudo[1821]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:08 volumio sudo[1821]: pam_unix(sudo:session): session closed for user root May 02 10:46:08 volumio volumio[857]: info: Output device has changed, restarting MPD May 02 10:46:08 volumio sudo[1828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 10:46:08 volumio sudo[1828]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:08 volumio volumio[857]: info: Output device has changed, restarting Shairport Sync May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:08 volumio sudo[1828]: pam_unix(sudo:session): session closed for user root May 02 10:46:08 volumio sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 10:46:08 volumio sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 02 10:46:08 volumio systemd[1]: mpd.service: Succeeded. May 02 10:46:08 volumio systemd[1]: Stopped Music Player Daemon. May 02 10:46:08 volumio volumio[857]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 10:46:08 volumio volumio[857]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio systemd[1]: Starting Music Player Daemon... May 02 10:46:08 volumio sudo[1840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 02 10:46:08 volumio sudo[1840]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:08 volumio volumio[857]: info: MPD Permissions set May 02 10:46:08 volumio volumio[857]: info: Shairport-Sync Started May 02 10:46:08 volumio systemd[1]: Started Volumio Bluetooth Module. May 02 10:46:08 volumio sudo[1840]: pam_unix(sudo:session): session closed for user root May 02 10:46:08 volumio volumio[857]: info: Volumio BT Module successfully started May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio sudo[1843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 02 10:46:08 volumio sudo[1843]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:08 volumio volumiobt[1842]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 02 10:46:08 volumio sudo[1843]: pam_unix(sudo:session): session closed for user root May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: Starting Shairport Sync May 02 10:46:08 volumio sudo[1851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 10:46:08 volumio sudo[1851]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 10:46:08 volumio systemd[1]: shairport-sync.service: Succeeded. May 02 10:46:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 10:46:08 volumio volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: running May 02 10:46:08 volumio volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 02 10:46:08 volumio volumio[857]: info: TidalConnect service stoped! May 02 10:46:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 10:46:08 volumio sudo[1851]: pam_unix(sudo:session): session closed for user root May 02 10:46:08 volumio volumio[857]: info: Shairport-Sync Started May 02 10:46:08 volumio volumio[857]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 02 10:46:08 volumio volumio[857]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:08 volumio sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 02 10:46:08 volumio sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:08 volumio systemd[1]: Started Volumio Tidal Connect Service. May 02 10:46:08 volumio sudo[1865]: pam_unix(sudo:session): session closed for user root May 02 10:46:09 volumio volumio[857]: info: Executing endpoint tc_getconfig May 02 10:46:09 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 02 10:46:09 volumio vtcs[1867]: STARTING TidalConnect services, version: 1.4.0.34 May 02 10:46:09 volumio vtcs[1867]: STARTED TidalConnect services. May 02 10:46:09 volumio volumio[857]: info: Executing endpoint tc_connect May 02 10:46:09 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 02 10:46:09 volumio volumio[857]: info: Connecting to TidalConnect May 02 10:46:09 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:46:09 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:46:09 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:09 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:46:09 volumio volumio[857]: info: MRS: Pushing multiroomSync output update for this device May 02 10:46:09 volumio volumio[857]: info: MRS: Pushing multiroomSync output May 02 10:46:09 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:09 volumio volumio[857]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 02 10:46:09 volumio volumio[857]: info: CoreCommandRouter::servicePushState May 02 10:46:09 volumio volumio[857]: info: CoreStateMachine::pushState May 02 10:46:09 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:09 volumio volumio[857]: info: CoreCommandRouter::volumioPushState May 02 10:46:09 volumio volumio[857]: info: MRS: Pushing multiroomSync output update for this device May 02 10:46:09 volumio volumio[857]: info: MRS: Pushing multiroomSync output May 02 10:46:09 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:09 volumio volumio[857]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 02 10:46:09 volumio sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 02 10:46:09 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 10:46:09 volumio volumiobt[1842]: pulseaudio: no process found May 02 10:46:09 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root May 02 10:46:09 volumio dbus-daemon[549]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.132' (uid=1000 pid=1890 comm="/usr/bin/pulseaudio --high-priority=true ") May 02 10:46:09 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 02 10:46:09 volumio volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: running May 02 10:46:09 volumio dbus-daemon[549]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 02 10:46:09 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. May 02 10:46:09 volumio rtkit-daemon[1891]: Successfully called chroot. May 02 10:46:09 volumio rtkit-daemon[1891]: Successfully dropped privileges. May 02 10:46:09 volumio rtkit-daemon[1891]: Successfully limited resources. May 02 10:46:09 volumio rtkit-daemon[1891]: Running. May 02 10:46:09 volumio rtkit-daemon[1891]: Watchdog thread running. May 02 10:46:09 volumio rtkit-daemon[1891]: Canary thread running. May 02 10:46:09 volumio dbus-daemon[549]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.133' (uid=0 pid=1891 comm="/usr/lib/rtkit/rtkit-daemon ") May 02 10:46:09 volumio systemd[1]: Starting Authorization Manager... May 02 10:46:09 volumio volumio[857]: ------------------------------------ BT MESSAGE: BT STATUS: running May 02 10:46:09 volumio polkitd[1894]: started daemon version 0.105 using authority implementation `local' version `0.105' May 02 10:46:09 volumio dbus-daemon[549]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 02 10:46:09 volumio systemd[1]: Started Authorization Manager. May 02 10:46:10 volumio mpd[1836]: May 02 10:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 10:46:10 volumio systemd[1]: Started Music Player Daemon. May 02 10:46:10 volumio volumio[857]: Upnp client error: Error: This socket has been ended by the other party May 02 10:46:10 volumio sudo[1830]: pam_unix(sudo:session): session closed for user root May 02 10:46:10 volumio sudo[1804]: pam_unix(sudo:session): session closed for user root May 02 10:46:10 volumio volumio[857]: error: MPD error: The expression evaluated to a falsy value: May 02 10:46:10 volumio volumio[857]: assert.ok(self.idling) May 02 10:46:10 volumio volumio[857]: error: The expression evaluated to a falsy value: May 02 10:46:10 volumio volumio[857]: assert.ok(self.idling) May 02 10:46:10 volumio volumio[857]: error: updateQueue error: null May 02 10:46:10 volumio pulseaudio[1890]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 02 10:46:10 volumio pulseaudio[1890]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 02 10:46:10 volumio volumiobt[1842]: Applying permissions May 02 10:46:10 volumio volumiobt[1842]: Setting BT discoverable and pairable May 02 10:46:10 volumio pulseaudio[1890]: 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 May 02 10:46:10 volumio volumiobt[1842]: [bluetooth]# -e power on May 02 10:46:10 volumio volumiobt[1842]: Invalid command in menu main: -e May 02 10:46:10 volumio volumiobt[1842]: May 02 10:46:10 volumio volumiobt[1842]: Use "help" for a list of available commands in a menu. May 02 10:46:10 volumio volumiobt[1842]: Use "menu " if you want to enter any submenu. May 02 10:46:10 volumio volumiobt[1842]: Use "back" if you want to return to menu main. May 02 10:46:10 volumio volumiobt[1842]: [bluetooth]# agent on May 02 10:46:10 volumio volumiobt[1842]: Failed to register agent object May 02 10:46:10 volumio volumiobt[1842]: [bluetooth]# discoverable on May 02 10:46:10 volumio volumiobt[1842]: No default controller available May 02 10:46:10 volumio volumiobt[1842]: [bluetooth]# pairable on May 02 10:46:10 volumio volumiobt[1842]: No default controller available May 02 10:46:10 volumio volumiobt[1842]: [bluetooth]# agent NoInputNoOutput May 02 10:46:10 volumio volumiobt[1842]: Failed to register agent object May 02 10:46:10 volumio volumiobt[1842]: [bluetooth]# default-agent May 02 10:46:10 volumio volumiobt[1842]: No agent is registered May 02 10:46:10 volumio volumiobt[1842]: [bluetooth]# quit May 02 10:46:10 volumio volumiobt[1842]: [59B blob data] May 02 10:46:10 volumio volumio[857]: info: MRS: Getting audio outputs on start May 02 10:46:10 volumio volumio[857]: info: MRS: Requesting all other devices output May 02 10:46:10 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 02 10:46:10 volumio volumio[857]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 May 02 10:46:11 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:46:11 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:11 volumio volumio[857]: error: Upnp client error: Error: This socket has been ended by the other party May 02 10:46:11 volumio volumio[857]: info: TidalConnect service started! May 02 10:46:11 volumio volumio[857]: info: BOOT COMPLETED May 02 10:46:11 volumio volumio[857]: [Metrics] CommandRouter: 74s 248.20ms May 02 10:46:11 volumio volumio[857]: info: CoreCommandRouter::volumiosetStartupVolume May 02 10:46:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:11 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 10:46:11 volumio volumio[857]: info: CoreCommandRouter::Close All Modals sent May 02 10:46:12 volumio volumio[857]: info: CoreCommandRouter::Close All Modals sent May 02 10:46:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 02 10:46:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 10:46:13 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 02 10:46:13 volumio volumio[857]: info: CoreCommandRouter::volumioGetState May 02 10:46:13 volumio volumio[857]: info: CorePlayQueue::getTrack 0 May 02 10:46:13 volumio volumio[857]: info: Listing playlists May 02 10:46:13 volumio volumio[857]: info: Listing playlists May 02 10:46:14 volumio volumio[857]: error: Failed request for metavolumio API May 02 10:46:15 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:15 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 10:46:15 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 02 10:46:15 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 02 10:46:15 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 02 10:46:15 volumio volumio[857]: info: CoreCommandRouter::volumioGetBrowseSources May 02 10:46:15 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 10:46:17 volumio volumio[857]: info: Starting Uninstall of plugin system_hardware - iractivityled May 02 10:46:17 volumio volumio[857]: info: Uninstalling plugin iractivityled May 02 10:46:17 volumio lircd[1206]: lircd-0.10.1[1206]: Info: removed client May 02 10:46:17 volumio lircd-0.10.1[1206]: Info: removed client May 02 10:46:17 volumio volumio[857]: info: [IrActivityLed] releasing built-in LED May 02 10:46:17 volumio volumio[857]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 10:46:17 volumio volumio[857]: TypeError: Cannot read property 'toString' of undefined May 02 10:46:17 volumio volumio[857]: at IrActivityLedController.releaseLed (/data/plugins/system_hardware/iractivityled/index.js:232:61) May 02 10:46:17 volumio volumio[857]: at IrActivityLedController.onStop (/data/plugins/system_hardware/iractivityled/index.js:68:17) May 02 10:46:17 volumio volumio[857]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 02 10:46:17 volumio volumio[857]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) May 02 10:46:17 volumio volumio[857]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1459:22) May 02 10:46:17 volumio volumio[857]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1218:45) May 02 10:46:17 volumio volumio[857]: at Socket.emit (events.js:315:20) May 02 10:46:17 volumio volumio[857]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 02 10:46:17 volumio volumio[857]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 02 10:46:17 volumio volumio[857]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 10:46:17 volumio sudo[1928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-02 10:45 May 02 10:46:17 volumio sudo[1928]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"