-- Logs begin at Fri 2024-10-11 12:54:47 MSK, end at Fri 2024-10-11 12:56:49 MSK. -- Oct 11 12:55:00 volumio-nvme wpa_supplicant[1163]: wlan0: Associated with de:8e:8d:76:f2:e8 Oct 11 12:55:00 volumio-nvme wpa_supplicant[1163]: wlan0: CTRL-EVENT-CONNECTED - Connection to de:8e:8d:76:f2:e8 completed [id=0 id_str=] Oct 11 12:55:00 volumio-nvme dhcpcd[1150]: wlan0: carrier acquired Oct 11 12:55:00 volumio-nvme wpa_supplicant[1163]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 11 12:55:00 volumio-nvme wpa_supplicant[1163]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=RU Oct 11 12:55:00 volumio-nvme dhcpcd[1150]: wlan0: connected to Access Point `E_258-5G' Oct 11 12:55:00 volumio-nvme kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Oct 11 12:55:00 volumio-nvme dhcpcd[1150]: wlan0: IAID 67:11:a5:18 Oct 11 12:55:00 volumio-nvme dhcpcd[1150]: wlan0: adding address fe80::7424:5526:3970:fd14 Oct 11 12:55:00 volumio-nvme dhcpcd[1150]: ipv6_addaddr1: Permission denied Oct 11 12:55:00 volumio-nvme dhcpcd[1150]: eth0: leased 192.168.1.155 for 43200 seconds Oct 11 12:55:00 volumio-nvme dhcpcd[1150]: eth0: adding route to 192.168.1.0/24 Oct 11 12:55:00 volumio-nvme avahi-daemon[816]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.155. Oct 11 12:55:00 volumio-nvme dhcpcd[1150]: eth0: adding default route via 192.168.1.1 Oct 11 12:55:00 volumio-nvme avahi-daemon[816]: New relevant interface eth0.IPv4 for mDNS. Oct 11 12:55:00 volumio-nvme avahi-daemon[816]: Registering new address record for 192.168.1.155 on eth0.IPv4. Oct 11 12:55:00 volumio-nvme dhcpcd[1150]: forked to background, child pid 1211 Oct 11 12:55:00 volumio-nvme wireless.js[801]: trying... Oct 11 12:55:00 volumio-nvme sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 11 12:55:00 volumio-nvme sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:00 volumio-nvme sudo[1213]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:00 volumio-nvme sudo[1149]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:00 volumio-nvme sh[768]: eth0: leased 192.168.1.155 for 43200 seconds Oct 11 12:55:00 volumio-nvme dhcpcd[851]: eth0: leased 192.168.1.155 for 43200 seconds Oct 11 12:55:00 volumio-nvme sh[768]: eth0: adding route to 192.168.1.0/24 Oct 11 12:55:00 volumio-nvme sh[768]: eth0: adding default route via 192.168.1.1 Oct 11 12:55:00 volumio-nvme dhcpcd[851]: eth0: adding route to 192.168.1.0/24 Oct 11 12:55:00 volumio-nvme dhcpcd[851]: eth0: adding default route via 192.168.1.1 Oct 11 12:55:00 volumio-nvme sh[768]: forked to background, child pid 1236 Oct 11 12:55:00 volumio-nvme dhcpcd[851]: forked to background, child pid 1236 Oct 11 12:55:00 volumio-nvme ifplugd(eth0)[1044]: client: ifup: interface eth0 already configured Oct 11 12:55:00 volumio-nvme sh[768]: eth0=eth0 Oct 11 12:55:00 volumio-nvme ifplugd(eth0)[1044]: Program executed successfully. Oct 11 12:55:00 volumio-nvme dhcpcd[1211]: wlan0: soliciting an IPv6 router Oct 11 12:55:00 volumio-nvme dhcpcd[1211]: wlan0: rebinding lease of 192.168.1.244 Oct 11 12:55:01 volumio-nvme dhcpcd[1211]: wlan0: probing address 192.168.1.244/24 Oct 11 12:55:01 volumio-nvme wireless.js[801]: trying... Oct 11 12:55:01 volumio-nvme sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 11 12:55:01 volumio-nvme sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:01 volumio-nvme sudo[1263]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:01 volumio-nvme volumio-remote-updater[803]: [2024-10-11 12:55:01] [info] asio async_connect error: system:111 (Connection refused) Oct 11 12:55:01 volumio-nvme volumio-remote-updater[803]: [2024-10-11 12:55:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 11 12:55:01 volumio-nvme volumio-remote-updater[803]: [2024-10-11 12:55:01] [error] handle_connect error: Underlying Transport Error Oct 11 12:55:02 volumio-nvme ntpd[1039]: Listen normally on 3 eth0 192.168.1.155:123 Oct 11 12:55:02 volumio-nvme ntpd[1039]: new interface(s) found: waking up resolver Oct 11 12:55:02 volumio-nvme wireless.js[801]: trying... Oct 11 12:55:02 volumio-nvme sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 11 12:55:02 volumio-nvme sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:02 volumio-nvme sudo[1266]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:03 volumio-nvme wireless.js[801]: trying... Oct 11 12:55:03 volumio-nvme sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 11 12:55:03 volumio-nvme sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:03 volumio-nvme sudo[1269]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:04 volumio-nvme wireless.js[801]: trying... Oct 11 12:55:04 volumio-nvme sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 11 12:55:04 volumio-nvme sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:04 volumio-nvme sudo[1272]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:04 volumio-nvme nmbd[1017]: [2024/10/11 12:55:04.481952, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 11 12:55:04 volumio-nvme nmbd[1017]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 11 12:55:04 volumio-nvme systemd[1]: Started Samba NMB Daemon. Oct 11 12:55:04 volumio-nvme systemd[1]: Starting Samba Winbind Daemon... Oct 11 12:55:04 volumio-nvme winbindd[1274]: [2024/10/11 12:55:04.567005, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 11 12:55:04 volumio-nvme winbindd[1274]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 11 12:55:04 volumio-nvme winbindd[1274]: [2024/10/11 12:55:04.569934, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 11 12:55:04 volumio-nvme systemd[1]: Started Samba Winbind Daemon. Oct 11 12:55:04 volumio-nvme winbindd[1274]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 11 12:55:04 volumio-nvme systemd[1]: Starting Samba SMB Daemon... Oct 11 12:55:04 volumio-nvme winbindd[1274]: [2024/10/11 12:55:04.697739, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Oct 11 12:55:04 volumio-nvme winbindd[1274]: res_names->count = 3, expected 4 Oct 11 12:55:04 volumio-nvme smbd[1279]: [2024/10/11 12:55:04.706613, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 11 12:55:04 volumio-nvme systemd[1]: Started Samba SMB Daemon. Oct 11 12:55:04 volumio-nvme smbd[1279]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 11 12:55:05 volumio-nvme wireless.js[801]: trying... Oct 11 12:55:05 volumio-nvme sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 11 12:55:05 volumio-nvme sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:05 volumio-nvme sudo[1288]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:06 volumio-nvme wireless.js[801]: trying... Oct 11 12:55:06 volumio-nvme sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 11 12:55:06 volumio-nvme sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:06 volumio-nvme sudo[1291]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:06 volumio-nvme dhcpcd[1211]: wlan0: leased 192.168.1.244 for 43200 seconds Oct 11 12:55:06 volumio-nvme avahi-daemon[816]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.244. Oct 11 12:55:06 volumio-nvme avahi-daemon[816]: New relevant interface wlan0.IPv4 for mDNS. Oct 11 12:55:06 volumio-nvme dhcpcd[1211]: wlan0: adding route to 192.168.1.0/24 Oct 11 12:55:06 volumio-nvme dhcpcd[1211]: wlan0: adding default route via 192.168.1.1 Oct 11 12:55:06 volumio-nvme avahi-daemon[816]: Registering new address record for 192.168.1.244 on wlan0.IPv4. Oct 11 12:55:07 volumio-nvme wireless.js[801]: trying... Oct 11 12:55:07 volumio-nvme sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 11 12:55:07 volumio-nvme sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:07 volumio-nvme sudo[1315]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:07 volumio-nvme wireless.js[801]: Connected to: ----E_258-5G Oct 11 12:55:07 volumio-nvme wireless.js[801]: ---- Oct 11 12:55:07 volumio-nvme sudo[1318]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 12:55:07 volumio-nvme sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:07 volumio-nvme sudo[1318]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:07 volumio-nvme wireless.js[801]: ... joined AP, wlan0 IPv4 is 192.168.1.244, ipV6 is undefined Oct 11 12:55:07 volumio-nvme wireless.js[801]: It's done! AP Oct 11 12:55:07 volumio-nvme systemd[1]: Started Wireless Services. Oct 11 12:55:07 volumio-nvme systemd[1]: Started Volumio Backend Module. Oct 11 12:55:07 volumio-nvme kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 11 12:55:07 volumio-nvme systemd[1]: Started Volumio Cpu Tweaker. Oct 11 12:55:07 volumio-nvme systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 11 12:55:07 volumio-nvme volumio-cpu-tweak[1324]: Setting RT Priority for mpd Oct 11 12:55:07 volumio-nvme systemd[1]: Received SIGRTMIN+21 from PID 396 (plymouthd). Oct 11 12:55:07 volumio-nvme volumio-cpu-tweak[1324]: pid 35's current scheduling policy: SCHED_OTHER Oct 11 12:55:07 volumio-nvme volumio-cpu-tweak[1324]: pid 35's current scheduling priority: 0 Oct 11 12:55:07 volumio-nvme volumio-cpu-tweak[1324]: Setting MPD Affinity Oct 11 12:55:07 volumio-nvme volumio-cpu-tweak[1324]: pid 3's current affinity mask: f Oct 11 12:55:07 volumio-nvme volumio-cpu-tweak[1324]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 11 12:55:07 volumio-nvme volumio-cpu-tweak[1324]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Oct 11 12:55:07 volumio-nvme systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 11 12:55:07 volumio-nvme systemd[1]: Received SIGRTMIN+21 from PID 396 (plymouthd). Oct 11 12:55:07 volumio-nvme systemd[1]: Started Hold until boot process finishes up. Oct 11 12:55:07 volumio-nvme systemd[1]: Started Terminate Plymouth Boot Screen. Oct 11 12:55:07 volumio-nvme systemd[1]: Started Serial Getty on ttyAMA10. Oct 11 12:55:07 volumio-nvme systemd[1]: Reached target Login Prompts. Oct 11 12:55:07 volumio-nvme systemd[1]: Reached target Multi-User System. Oct 11 12:55:07 volumio-nvme systemd[1]: Reached target Graphical Interface. Oct 11 12:55:07 volumio-nvme systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 11 12:55:07 volumio-nvme systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 11 12:55:07 volumio-nvme systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 11 12:55:07 volumio-nvme systemd[1]: Startup finished in 10.964s (kernel) + 20.334s (userspace) = 31.298s. Oct 11 12:55:07 volumio-nvme volumio[1323]: info: ------------------------------------------- Oct 11 12:55:07 volumio-nvme volumio[1323]: info: ----- Volumio3 ---- Oct 11 12:55:07 volumio-nvme volumio[1323]: info: ------------------------------------------- Oct 11 12:55:07 volumio-nvme volumio[1323]: info: ----- System startup ---- Oct 11 12:55:07 volumio-nvme volumio[1323]: info: ------------------------------------------- Oct 11 12:55:08 volumio-nvme kernel: hwmon hwmon4: Undervoltage detected! Oct 11 12:55:08 volumio-nvme volumio[1323]: info: MYVOLUMIO Environment detected Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Plugin folders cleanup Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning into folder /volumio/app/plugins/ Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning category audio_interface Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning category miscellanea Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning category music_service Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning category plugins.json Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning category system_controller Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning category user_interface Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning into folder /data/plugins/ Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning category audio_interface Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning category music_service Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning category system_controller Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Scanning category user_interface Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Plugin folders cleanup completed Oct 11 12:55:08 volumio-nvme volumio[1323]: info: ------------------------------------------- Oct 11 12:55:08 volumio-nvme volumio[1323]: info: ----- Core plugins startup ---- Oct 11 12:55:08 volumio-nvme volumio[1323]: info: ------------------------------------------- Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugins from folder /volumio/app/plugins/ Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Adding plugin upnp to MyMusic Plugins Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugins from folder /data/plugins/ Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "system"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "appearance"... Oct 11 12:55:08 volumio-nvme ntpd[1039]: Listen normally on 4 wlan0 192.168.1.244:123 Oct 11 12:55:08 volumio-nvme ntpd[1039]: new interface(s) found: waking up resolver Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "network"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Refreshing Cached IP Addresses Oct 11 12:55:08 volumio-nvme sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "services"... Oct 11 12:55:08 volumio-nvme sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:08 volumio-nvme sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 12:55:08 volumio-nvme sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:08 volumio-nvme sudo[1364]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:08 volumio-nvme sudo[1362]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "alsa_controller"... Oct 11 12:55:08 volumio-nvme sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 11 12:55:08 volumio-nvme sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:08 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "wizard"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "networkfs"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Starting Udev Watcher for removable devices Oct 11 12:55:08 volumio-nvme sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.251/MEDIA /mnt/NAS/MEDIA Oct 11 12:55:08 volumio-nvme sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:08 volumio-nvme sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.251/Torrents /mnt/NAS/Torrents Oct 11 12:55:08 volumio-nvme sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Ignoring mount for partition: boot Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Ignoring mount for partition: volumio Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Ignoring mount for partition: volumio_data Oct 11 12:55:08 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "volumio_command_line_client"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "upnp"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: [1728640508677] Starting Upmpd Daemon Oct 11 12:55:08 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "my_music"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "mpd"... Oct 11 12:55:08 volumio-nvme kernel: Key type cifs.spnego registered Oct 11 12:55:08 volumio-nvme kernel: Key type cifs.idmap registered Oct 11 12:55:08 volumio-nvme kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 11 12:55:08 volumio-nvme kernel: CIFS: Attempting to mount //192.168.1.251/MEDIA Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "upnp_browser"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "alarm-clock"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "airplay_emulation"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Starting Shairport Sync Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "last_100"... Oct 11 12:55:08 volumio-nvme volumio[1323]: info: Loading plugin "webradio"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "i2s_dacs"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: I2S DAC not set, start Auto-detection Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "volumiodiscovery"... Oct 11 12:55:09 volumio-nvme volumio[1323]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 12:55:09 volumio-nvme node[1323]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 12:55:09 volumio-nvme node[1323]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 12:55:09 volumio-nvme volumio[1323]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 12:55:09 volumio-nvme volumio[1323]: *** WARNING *** For more information see Oct 11 12:55:09 volumio-nvme volumio[1323]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 12:55:09 volumio-nvme volumio[1323]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 12:55:09 volumio-nvme volumio[1323]: *** WARNING *** For more information see Oct 11 12:55:09 volumio-nvme node[1323]: *** WARNING *** For more information see Oct 11 12:55:09 volumio-nvme node[1323]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 12:55:09 volumio-nvme node[1323]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 12:55:09 volumio-nvme node[1323]: *** WARNING *** For more information see Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Discovery: Started advertising with name: Volumio nvme Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "autostart"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Applying required configuration parameters for plugin autostart Oct 11 12:55:09 volumio-nvme volumio[1323]: info: AutoStart - onVolumioStart - read config.json Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Plugin now_playing is not enabled Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Plugin peppy_screensaver is not enabled Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "outputs"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "albumart"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Plugin example_plugin is not enabled Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "inputs"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "updater_comm"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Plugin mpdemulation is not enabled Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "rest_api"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "websocket"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Starting Socket.io Server version 2.3.0 Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Plugin fusiondsp is not enabled Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "minidlna"... Oct 11 12:55:09 volumio-nvme volumio[1323]: Forking 3 albumart workers Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Applying required configuration parameters for plugin minidlna Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "radio_paradise"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Applying required configuration parameters for plugin radio_paradise Oct 11 12:55:09 volumio-nvme volumio[1323]: info: [1728640509493] [RadioParadise] API delay: 5 Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "backup_restore"... Oct 11 12:55:09 volumio-nvme volumio[1323]: Starting albumart workers Oct 11 12:55:09 volumio-nvme volumio[1323]: Starting albumart workers Oct 11 12:55:09 volumio-nvme volumio[1323]: Starting albumart workers Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Applying required configuration parameters for plugin backup_restore Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "crossfader_switch"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Applying required configuration parameters for plugin crossfader_switch Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading plugin "touch_display"... Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Applying required configuration parameters for plugin touch_display Oct 11 12:55:09 volumio-nvme volumio[1323]: info: Loading i18n strings for locale ru Oct 11 12:55:09 volumio-nvme volumio[1323]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 11 12:55:09 volumio-nvme volumio[1323]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 11 12:55:09 volumio-nvme volumio[1323]: Updating browse sources language Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::initPlayerControls Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 12:55:09 volumio-nvme volumio[1323]: Express server listening on port 3000 Oct 11 12:55:09 volumio-nvme volumio[1323]: [Metrics] WebUI: 2s 208.89ms Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreStateMachine::resetVolumioState Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreStateMachine::getcurrentVolume Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 12:55:09 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreStateMachine::updateTrackBlock Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrackBlock Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Reloading queue from file Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Setting Device type: Raspberry PI Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreStateMachine::setRepeat null single undefined Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreStateMachine::setRandom true Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 11 12:55:10 volumio-nvme volumio[1323]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Discovery: adding 0802a0fe-e398-4f95-87b0-8c5c9a118015 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Discovery: Found device Volumio nvme Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Discovery: this is already registered, 0802a0fe-e398-4f95-87b0-8c5c9a118015 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Discovery: Found device Volumio nvme Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 26 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 27 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 28 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 29 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 30 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 32 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 33 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 34 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 35 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 36 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 37 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 38 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 39 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 40 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 41 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 42 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 43 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 44 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 45 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 46 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 47 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 48 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Received Get System Info Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Discovery: Getting this device information Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Received Get System Info Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Discovery: Getting this device information Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: Listing playlists Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 49 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 50 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 51 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 52 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 53 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 54 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 55 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 56 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 57 Oct 11 12:55:10 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.154 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 58 Oct 11 12:55:10 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:11 volumio-nvme volumio-remote-updater[803]: [2024-10-11 12:55:11] [connect] Successful connection Oct 11 12:55:11 volumio-nvme volumio-remote-updater[803]: [2024-10-11 12:55:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728640511 101 Oct 11 12:55:11 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 59 Oct 11 12:55:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:12 volumio-nvme kernel: hwmon hwmon4: Voltage normalised Oct 11 12:55:12 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:12 volumio-nvme sudo[1367]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:12 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:13 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:13 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:14 volumio-nvme sudo[1373]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:14 volumio-nvme volumio[1323]: info: Cannot mount NAS MEDIA at system boot, trial number 1 ,retrying in 5 seconds Oct 11 12:55:14 volumio-nvme kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 11 12:55:14 volumio-nvme kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 11 12:55:14 volumio-nvme kernel: CIFS: Attempting to mount //192.168.1.251/Torrents Oct 11 12:55:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:16 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:16 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 12:55:16 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:17 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:17 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:18 volumio-nvme systemd[1]: systemd-fsckd.service: Succeeded. Oct 11 12:55:18 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:18 volumio-nvme sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 12:55:18 volumio-nvme sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:18 volumio-nvme sudo[1471]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:18 volumio-nvme sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 12:55:18 volumio-nvme sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:18 volumio-nvme sudo[1473]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:18 volumio-nvme sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 11 12:55:18 volumio-nvme sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:18 volumio-nvme systemd[1]: Started UPnP Renderer front-end to MPD. Oct 11 12:55:18 volumio-nvme sudo[1477]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:18 volumio-nvme volumio[1323]: info: CoreCommandRouter::Close All Modals sent Oct 11 12:55:18 volumio-nvme volumio[1323]: info: Upmpdcli Daemon Started Oct 11 12:55:18 volumio-nvme volumio[1323]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 11 12:55:18 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:19 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:19 volumio-nvme systemd[1]: systemd-hostnamed.service: Succeeded. Oct 11 12:55:19 volumio-nvme sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.251/MEDIA /mnt/NAS/MEDIA Oct 11 12:55:19 volumio-nvme sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:19 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:20 volumio-nvme sudo[1375]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:20 volumio-nvme volumio[1323]: info: Cannot mount NAS Torrents at system boot, trial number 1 ,retrying in 5 seconds Oct 11 12:55:20 volumio-nvme volumio[1323]: info: Completed loading Core Plugins Oct 11 12:55:20 volumio-nvme volumio[1323]: info: Preparing to generate the ALSA configuration file Oct 11 12:55:20 volumio-nvme kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 11 12:55:20 volumio-nvme kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 11 12:55:20 volumio-nvme kernel: CIFS: Attempting to mount //192.168.1.251/MEDIA Oct 11 12:55:21 volumio-nvme volumio[1323]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Reading ALSA contributions from plugins. Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Asound.conf file unchanged, so no further update is needed Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Output device has changed, restarting MPD Oct 11 12:55:21 volumio-nvme sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Output device has changed, restarting Shairport Sync Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:21 volumio-nvme sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme sudo[1489]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:21 volumio-nvme sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 12:55:21 volumio-nvme sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 12:55:21 volumio-nvme volumio[1323]: info: ___________ START PLUGINS ___________ Oct 11 12:55:21 volumio-nvme volumio[1323]: info: ControllerMpd::onStart: Initializing MPD Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Creating MPD Configuration file Oct 11 12:55:21 volumio-nvme sudo[1499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 12:55:21 volumio-nvme sudo[1499]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 12:55:21 volumio-nvme volumio[1323]: info: [1728640521047] CoreMusicLibrary::Adding element Медиа-серверы Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:21 volumio-nvme sudo[1499]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:21 volumio-nvme sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 12:55:21 volumio-nvme sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme systemd[1]: Listening on mpd.socket. Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:21 volumio-nvme systemd[1]: Starting Music Player Daemon... Oct 11 12:55:21 volumio-nvme systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 11 12:55:21 volumio-nvme systemd[1]: mpd.service: Succeeded. Oct 11 12:55:21 volumio-nvme systemd[1]: Stopped Music Player Daemon. Oct 11 12:55:21 volumio-nvme systemd[1]: Starting Music Player Daemon... Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 12:55:21 volumio-nvme volumio[1323]: info: [1728640521086] CoreMusicLibrary::Adding element Last_100 Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 12:55:21 volumio-nvme volumio[1323]: info: [1728640521087] CoreMusicLibrary::Adding element Webradio Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Initializing BBC Radios Oct 11 12:55:21 volumio-nvme sudo[1511]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 12:55:21 volumio-nvme sudo[1511]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme sudo[1511]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 11 12:55:21 volumio-nvme sudo[1511]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: AutoStart - onStart Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Loading i18n strings for locale ru Oct 11 12:55:21 volumio-nvme volumio[1323]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 11 12:55:21 volumio-nvme volumio[1323]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 12:55:21 volumio-nvme volumio[1323]: info: [1728640521123] CoreMusicLibrary::Adding element Radio Paradise Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 12:55:21 volumio-nvme volumio[1323]: Cannot find translation for source Radio Paradise Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Loading i18n strings for locale ru Oct 11 12:55:21 volumio-nvme volumio[1323]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 11 12:55:21 volumio-nvme volumio[1323]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Volumio Calling Home Oct 11 12:55:21 volumio-nvme sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 11 12:55:21 volumio-nvme sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 11 12:55:21 volumio-nvme sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 11 12:55:21 volumio-nvme sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme volumio[1323]: info: touch_display: No backlight interface detected. Oct 11 12:55:21 volumio-nvme volumio[1323]: info: MPD Permissions set Oct 11 12:55:21 volumio-nvme volumio[1323]: info: MPD Permissions set Oct 11 12:55:21 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 60 Oct 11 12:55:21 volumio-nvme systemd[1]: Reloading. Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Volumio called home Oct 11 12:55:21 volumio-nvme volumio[1323]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Starting Shairport Sync Oct 11 12:55:21 volumio-nvme sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Starting Shairport Sync Oct 11 12:55:21 volumio-nvme sudo[1562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 12:55:21 volumio-nvme volumio[1323]: info: Starting Shairport Sync Oct 11 12:55:21 volumio-nvme sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme sudo[1560]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:21 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:21 volumio-nvme sudo[1568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 12:55:21 volumio-nvme sudo[1568]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 12:55:21 volumio-nvme sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 11 12:55:21 volumio-nvme volumio[1323]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 11 12:55:21 volumio-nvme systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 11 12:55:21 volumio-nvme sudo[1535]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:21 volumio-nvme volumio[1323]: info: touch_display: systemctl daemon-reload succeeded. Oct 11 12:55:21 volumio-nvme sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 11 12:55:21 volumio-nvme systemd[1]: Reloading. Oct 11 12:55:21 volumio-nvme sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme volumio[1323]: info: minidlna: Found miniDLNA version 1.2.1 Oct 11 12:55:21 volumio-nvme volumio[1323]: info: minidlna: Starting minidlna.service Oct 11 12:55:21 volumio-nvme sudo[1588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 11 12:55:21 volumio-nvme sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:21 volumio-nvme systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 11 12:55:21 volumio-nvme mpd[1520]: Oct 11 12:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 12:55:21 volumio-nvme sudo[1532]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:21 volumio-nvme volumio[1323]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 11 12:55:21 volumio-nvme systemd[1]: Started Music Player Daemon. Oct 11 12:55:21 volumio-nvme sudo[1501]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:21 volumio-nvme sudo[1491]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:21 volumio-nvme systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 11 12:55:21 volumio-nvme sudo[1588]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:21 volumio-nvme systemd[1]: Started Volumio Kiosk. Oct 11 12:55:21 volumio-nvme sudo[1572]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:21 volumio-nvme systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 12:55:21 volumio-nvme systemd[1]: shairport-sync.service: Succeeded. Oct 11 12:55:21 volumio-nvme systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 12:55:21 volumio-nvme volumio[1323]: error: MPD error: The expression evaluated to a falsy value: Oct 11 12:55:21 volumio-nvme volumio[1323]: assert.ok(self.idling) Oct 11 12:55:21 volumio-nvme volumio[1323]: error: The expression evaluated to a falsy value: Oct 11 12:55:21 volumio-nvme volumio[1323]: assert.ok(self.idling) Oct 11 12:55:21 volumio-nvme volumio[1323]: info: MPD running with PID1520 Oct 11 12:55:21 volumio-nvme volumio[1323]: ,establishing connection Oct 11 12:55:21 volumio-nvme volumio[1323]: error: updateQueue error: null Oct 11 12:55:21 volumio-nvme volumio[1323]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 11 12:55:21 volumio-nvme volumio[1323]: info: touch_display: Volumio Kiosk started. Oct 11 12:55:21 volumio-nvme sudo[1530]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:22 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:22 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:22 volumio-nvme volumio[1323]: info: minidlna: systemctl start minidlna.service succeeded. Oct 11 12:55:22 volumio-nvme volumio[1323]: info: Completed starting Core Plugins Oct 11 12:55:22 volumio-nvme volumio[1323]: info: ------------------------------------------- Oct 11 12:55:22 volumio-nvme volumio[1323]: info: ----- MyVolumio plugins startup ---- Oct 11 12:55:22 volumio-nvme volumio[1323]: info: ------------------------------------------- Oct 11 12:55:22 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 11 12:55:22 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:22 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 11 12:55:22 volumio-nvme volumio[1323]: error: updateQueue error: null Oct 11 12:55:22 volumio-nvme systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 12:55:22 volumio-nvme sudo[1568]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:22 volumio-nvme sudo[1562]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:22 volumio-nvme sudo[1564]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:22 volumio-nvme volumio[1323]: info: Shairport-Sync Started Oct 11 12:55:22 volumio-nvme volumio[1323]: Error adding Membership: Error: addMembership EINVAL Oct 11 12:55:22 volumio-nvme volumio[1323]: info: Shairport-Sync Started Oct 11 12:55:22 volumio-nvme volumio[1323]: info: Shairport-Sync Started Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:22 volumio-nvme startx[1596]: X.Org X Server 1.20.4 Oct 11 12:55:22 volumio-nvme startx[1596]: X Protocol Version 11, Revision 0 Oct 11 12:55:22 volumio-nvme startx[1596]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Oct 11 12:55:22 volumio-nvme startx[1596]: Current Operating System: Linux volumio-nvme 6.6.47-v8+ #1792 SMP PREEMPT Thu Aug 22 15:11:43 BST 2024 aarch64 Oct 11 12:55:22 volumio-nvme startx[1596]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe smsc95xx.macaddr=2C:CF:67:11:A5:16 vc_mem.mem_base=0x3fc00000 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=ttyAMA10,115200 console=tty1 imgpart=UUID=e0b3cf5f-de4a-474b-b7c5-c7af3b84f86a imgfile=/volumio_current.sqsh bootpart=UUID=B360-0502 datapart=UUID=6ee0f402-5113-40aa-8549-08a31499c8eb uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Oct 11 12:55:22 volumio-nvme startx[1596]: Build Date: 04 April 2023 07:50:56AM Oct 11 12:55:22 volumio-nvme startx[1596]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Oct 11 12:55:22 volumio-nvme startx[1596]: Current version of pixman: 0.36.0 Oct 11 12:55:22 volumio-nvme startx[1596]: Before reporting problems, check http://wiki.x.org Oct 11 12:55:22 volumio-nvme startx[1596]: to make sure that you have the latest version. Oct 11 12:55:22 volumio-nvme startx[1596]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 11 12:55:22 volumio-nvme startx[1596]: (++) from command line, (!!) notice, (II) informational, Oct 11 12:55:22 volumio-nvme startx[1596]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 11 12:55:22 volumio-nvme startx[1596]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Oct 11 12:55:22 2024 Oct 11 12:55:22 volumio-nvme startx[1596]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 11 12:55:22 volumio-nvme startx[1596]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 11 12:55:22 volumio-nvme startx[1596]: (EE) Oct 11 12:55:22 volumio-nvme startx[1596]: Fatal server error: Oct 11 12:55:22 volumio-nvme startx[1596]: (EE) Cannot run in framebuffer mode. Please specify busIDs for all framebuffer devices Oct 11 12:55:22 volumio-nvme startx[1596]: (EE) Oct 11 12:55:22 volumio-nvme startx[1596]: Please consult the The X.Org Foundation support Oct 11 12:55:22 volumio-nvme startx[1596]: at http://wiki.x.org Oct 11 12:55:22 volumio-nvme startx[1596]: for help. Oct 11 12:55:22 volumio-nvme startx[1596]: (EE) Please also check the log file at "/var/log/Xorg.0.log" for additional information. Oct 11 12:55:22 volumio-nvme startx[1596]: (EE) Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:22 volumio-nvme kernel: hwmon hwmon4: Undervoltage detected! Oct 11 12:55:22 volumio-nvme startx[1596]: (EE) Server terminated with error (1). Closing log file. Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:22 volumio-nvme volumio[1479]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:22 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:23 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:23 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:23 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:23 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:23 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:23 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:23 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:23 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:23 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:24 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:24 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:24 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:24 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:24 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:24 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:24 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:24 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:24 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:24 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:25 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:25 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:25 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:25 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:25 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:25 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:25 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:25 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:25 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:26 volumio-nvme sudo[1719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.251/Torrents /mnt/NAS/Torrents Oct 11 12:55:26 volumio-nvme sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:26 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:26 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:26 volumio-nvme kernel: hwmon hwmon4: Voltage normalised Oct 11 12:55:26 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:26 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:26 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:26 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:26 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:26 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:26 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:26 volumio-nvme volumio[1323]: Cannot compose Albumart path Oct 11 12:55:27 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:27 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:27 volumio-nvme kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 11 12:55:27 volumio-nvme kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 11 12:55:27 volumio-nvme kernel: CIFS: Attempting to mount //192.168.1.251/Torrents Oct 11 12:55:27 volumio-nvme sudo[1484]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:27 volumio-nvme volumio[1323]: info: Cannot mount NAS MEDIA at system boot, trial number 2 ,retrying in 5 seconds Oct 11 12:55:27 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:27 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:27 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:27 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:27 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:27 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:27 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:27 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:28 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:28 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:28 volumio-nvme kernel: hwmon hwmon4: Undervoltage detected! Oct 11 12:55:28 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:28 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:28 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:28 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:28 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:28 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:28 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:28 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:29 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:29 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:29 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:29 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:29 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:29 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:29 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:29 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:29 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:30 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:30 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:30 volumio-nvme kernel: hwmon hwmon4: Voltage normalised Oct 11 12:55:30 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:30 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:30 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 11 12:55:30 volumio-nvme volumio[1323]: info: Adding plugin bluetooth to MyMusic Plugins Oct 11 12:55:30 volumio-nvme volumio[1323]: info: Adding plugin multiroom to MyMusic Plugins Oct 11 12:55:30 volumio-nvme volumio[1323]: info: Adding plugin metavolumio to MyMusic Plugins Oct 11 12:55:30 volumio-nvme volumio[1323]: info: Adding plugin cd_controller to MyMusic Plugins Oct 11 12:55:30 volumio-nvme volumio[1323]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 11 12:55:30 volumio-nvme volumio[1323]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 11 12:55:30 volumio-nvme volumio[1479]: ...............................................................................................................................................................++++ Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 11 12:55:30 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:30 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:30 volumio-nvme volumio[1323]: info: Starting MyVolumio Remote Streaming Endpoints Oct 11 12:55:30 volumio-nvme volumio[1323]: info: MyVolumio login type: Token Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 11 12:55:30 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 11 12:55:31 volumio-nvme volumio[1323]: info: Starting Streaming Service Transparent Proxy Oct 11 12:55:31 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 11 12:55:31 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 11 12:55:31 volumio-nvme volumio[1323]: info: Streaming services startup Oct 11 12:55:31 volumio-nvme volumio[1323]: info: Starting Streaming Daemon Oct 11 12:55:31 volumio-nvme sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 11 12:55:31 volumio-nvme sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:31 volumio-nvme sudo[1817]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:31 volumio-nvme volumio[1323]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 11 12:55:31 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:31 volumio-nvme volumio[1323]: error: Cannot start Volumio Streaming Daemon Oct 11 12:55:31 volumio-nvme volumio[1323]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 11 12:55:31 volumio-nvme volumio[1323]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 11 12:55:31 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:31 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:31 volumio-nvme volumio[1323]: STREAMING PROXY: Starting server on port 3245 Oct 11 12:55:31 volumio-nvme volumio[1323]: Node JS runtime: 14 Oct 11 12:55:31 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:31 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:31 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:32 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:32 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:32 volumio-nvme sudo[1841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.251/MEDIA /mnt/NAS/MEDIA Oct 11 12:55:32 volumio-nvme sudo[1841]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:32 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:32 volumio-nvme kernel: hwmon hwmon4: Undervoltage detected! Oct 11 12:55:32 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:32 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:32 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:32 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:32 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:32 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:32 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:33 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:33 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:33 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:33 volumio-nvme sudo[1719]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:33 volumio-nvme volumio[1323]: info: Cannot mount NAS Torrents at system boot, trial number 2 ,retrying in 5 seconds Oct 11 12:55:33 volumio-nvme kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 11 12:55:33 volumio-nvme kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 11 12:55:33 volumio-nvme kernel: CIFS: Attempting to mount //192.168.1.251/MEDIA Oct 11 12:55:33 volumio-nvme volumio[1323]: error: touch_display: Connecting to the Xserver failed. Oct 11 12:55:33 volumio-nvme volumio[1323]: info: touch_display: X display number found: 0 Oct 11 12:55:33 volumio-nvme volumio[1323]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 11 12:55:34 volumio-nvme volumio[1323]: info: MyVolumio token set successfully Oct 11 12:55:34 volumio-nvme volumio[1323]: info: MYVOLUMIO: Adding device Oct 11 12:55:34 volumio-nvme volumio[1323]: info: MYVOLUMIO: Evaluating Server Oct 11 12:55:34 volumio-nvme volumio[1323]: info: MyVolumio status changed Oct 11 12:55:34 volumio-nvme volumio[1323]: info: Streaming services startup Oct 11 12:55:34 volumio-nvme volumio[1323]: info: Starting Streaming Daemon Oct 11 12:55:34 volumio-nvme volumio[1323]: info: Removing browser output: myVolumio user plan is not superstar Oct 11 12:55:34 volumio-nvme volumio[1323]: info: Removing audio output: Oct 11 12:55:34 volumio-nvme volumio[1323]: info: Stoppping Tunnel 1 Oct 11 12:55:34 volumio-nvme sudo[1887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 11 12:55:34 volumio-nvme sudo[1887]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:34 volumio-nvme sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 11 12:55:34 volumio-nvme sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:34 volumio-nvme sudo[1887]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:34 volumio-nvme volumio[1323]: error: Cannot start Volumio Streaming Daemon Oct 11 12:55:34 volumio-nvme volumio[1323]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 11 12:55:34 volumio-nvme volumio[1323]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 11 12:55:34 volumio-nvme sudo[1890]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:34 volumio-nvme volumio[1323]: info: Remote SSH Stopped Oct 11 12:55:34 volumio-nvme volumio[1323]: info: Setting Geolocation for MyVolumio to eu6 Oct 11 12:55:34 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:34 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:34 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:34 volumio-nvme volumio[1323]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 11 12:55:35 volumio-nvme volumio[1323]: info: Updating MyVolumio device info Oct 11 12:55:35 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:35 volumio-nvme volumio[1323]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 11 12:55:36 volumio-nvme kernel: hwmon hwmon4: Voltage normalised Oct 11 12:55:37 volumio-nvme startx[1596]: xinit: giving up Oct 11 12:55:37 volumio-nvme startx[1596]: xinit: unable to connect to X server: Connection refused Oct 11 12:55:37 volumio-nvme startx[1596]: xinit: server error Oct 11 12:55:37 volumio-nvme systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Oct 11 12:55:37 volumio-nvme systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Oct 11 12:55:38 volumio-nvme sudo[1896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.251/Torrents /mnt/NAS/Torrents Oct 11 12:55:38 volumio-nvme sudo[1896]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:38 volumio-nvme volumio[1323]: info: MYVOLUMIO: Adding device Oct 11 12:55:38 volumio-nvme volumio[1323]: info: MYVOLUMIO: Evaluating Server Oct 11 12:55:39 volumio-nvme sudo[1841]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:39 volumio-nvme volumio[1323]: info: Cannot mount NAS MEDIA at system boot, trial number 3 ,retrying in 5 seconds Oct 11 12:55:39 volumio-nvme kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 11 12:55:39 volumio-nvme kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 11 12:55:39 volumio-nvme kernel: CIFS: Attempting to mount //192.168.1.251/Torrents Oct 11 12:55:39 volumio-nvme volumio[1323]: info: Setting Geolocation for MyVolumio to eu6 Oct 11 12:55:39 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:39 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:39 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:39 volumio-nvme volumio[1479]: ...........................................................................................................................................................................................++++ Oct 11 12:55:39 volumio-nvme volumio[1479]: e is 65537 (0x010001) Oct 11 12:55:39 volumio-nvme volumio[1479]: writing RSA key Oct 11 12:55:40 volumio-nvme volumio[1323]: info: Updating MyVolumio device info Oct 11 12:55:40 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:40 volumio-nvme volumio[1323]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 11 12:55:41 volumio-nvme volumio[1323]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 11 12:55:41 volumio-nvme volumio[1323]: info: AutoStart - Plugin is starting Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetQueue Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CoreStateMachine::getQueue Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CorePlayQueue::getQueue Oct 11 12:55:41 volumio-nvme volumio[1323]: info: AutoStart - start playing Oct 11 12:55:41 volumio-nvme volumio[1323]: info: AutoStart - start playing with no specific position Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPlay Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CoreStateMachine::play index 0 Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CoreStateMachine::stop Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CoreStateMachine::play index undefined Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CoreStateMachine::startPlaybackTimer Oct 11 12:55:41 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:41 volumio-nvme volumio[1323]: info: [1728640541115] ControllerWebradio::clearAddPlayTrack Oct 11 12:55:41 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 12:55:41 volumio-nvme volumio[1323]: info: sendMpdCommand stop took 1 milliseconds Oct 11 12:55:41 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 12:55:41 volumio-nvme volumio[1323]: info: sendMpdCommand clear took 0 milliseconds Oct 11 12:55:41 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 11 12:55:41 volumio-nvme volumio[1323]: info: Oct 11 12:55:41 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:41 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:41 volumio-nvme volumio[1323]: info: Oct 11 12:55:41 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:41 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:41 volumio-nvme volumio[1323]: info: Oct 11 12:55:41 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:41 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:42 volumio-nvme volumio[1323]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 1243 milliseconds Oct 11 12:55:42 volumio-nvme volumio[1323]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 12:55:42 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand play Oct 11 12:55:42 volumio-nvme volumio[1323]: info: Oct 11 12:55:42 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:42 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:42 volumio-nvme volumio[1323]: info: ------------------------------ 1245ms Oct 11 12:55:42 volumio-nvme volumio[1323]: info: ------------------------------ 1243ms Oct 11 12:55:42 volumio-nvme volumio[1323]: info: ------------------------------ 1243ms Oct 11 12:55:42 volumio-nvme volumio[1323]: info: Oct 11 12:55:42 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:42 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:42 volumio-nvme volumio[1323]: info: Oct 11 12:55:42 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:42 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:42 volumio-nvme volumio[1323]: info: sendMpdCommand play took 3 milliseconds Oct 11 12:55:42 volumio-nvme volumio[1323]: info: ------------------------------ 3ms Oct 11 12:55:42 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:55:42 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 6 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 6 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 5 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 2 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: 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":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 11751/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus stop Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: 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":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 11751/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: 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":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 11751/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 19ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 24ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 24ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: 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":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 11751/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 26ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: 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":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 11751/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: 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":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 11751/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 24ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 23ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 3ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 4 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 2ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 2 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Oct 11 12:55:44 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 3ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 3 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 19ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 17ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 16ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:44 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:44 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 26ms Oct 11 12:55:44 volumio-nvme volumio[1323]: info: ------------------------------ 25ms Oct 11 12:55:44 volumio-nvme sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.251/MEDIA /mnt/NAS/MEDIA Oct 11 12:55:44 volumio-nvme sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Oct 11 12:55:45 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Oct 11 12:55:45 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Oct 11 12:55:45 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Oct 11 12:55:45 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Oct 11 12:55:45 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Oct 11 12:55:45 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ------------------------------ 3ms Oct 11 12:55:45 volumio-nvme volumio[1323]: info: sendMpdCommand status took 2 milliseconds Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ------------------------------ 2ms Oct 11 12:55:45 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:55:45 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:45 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 11 12:55:45 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 11 12:55:45 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ------------------------------ 18ms Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ------------------------------ 17ms Oct 11 12:55:45 volumio-nvme volumio[1323]: info: ------------------------------ 17ms Oct 11 12:55:45 volumio-nvme sudo[1896]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:45 volumio-nvme volumio[1323]: info: Cannot mount NAS Torrents at system boot, trial number 3 ,retrying in 5 seconds Oct 11 12:55:45 volumio-nvme kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 11 12:55:45 volumio-nvme kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 11 12:55:45 volumio-nvme kernel: CIFS: Attempting to mount //192.168.1.251/MEDIA Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 12:55:45 volumio-nvme volumio[1323]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 11 12:55:45 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Oct 11 12:55:46 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Oct 11 12:55:46 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Oct 11 12:55:46 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Oct 11 12:55:46 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ------------------------------ 2ms Oct 11 12:55:46 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:55:46 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Oct 11 12:55:46 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Oct 11 12:55:46 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:46 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ------------------------------ 10ms Oct 11 12:55:46 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ------------------------------ 8ms Oct 11 12:55:46 volumio-nvme volumio[1323]: info: sendMpdCommand status took 8 milliseconds Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ------------------------------ 16ms Oct 11 12:55:46 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:46 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:46 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:46 volumio-nvme volumio[1323]: info: ------------------------------ 26ms Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Oct 11 12:55:47 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Oct 11 12:55:47 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Oct 11 12:55:47 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Oct 11 12:55:47 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Oct 11 12:55:47 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Oct 11 12:55:47 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ------------------------------ 2ms Oct 11 12:55:47 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:55:47 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:47 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:55:47 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 11 12:55:47 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3786,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3786,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ------------------------------ 14ms Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ------------------------------ 13ms Oct 11 12:55:47 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3786,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harry Styles - As It Was","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:55:47 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:47 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:47 volumio-nvme volumio[1323]: info: ------------------------------ 18ms Oct 11 12:55:48 volumio-nvme volumiologrotate[799]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 11 12:55:48 volumio-nvme volumiologrotate[799]: ls: cannot access 'NVME': No such file or directory Oct 11 12:55:50 volumio-nvme sudo[2018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.251/Torrents /mnt/NAS/Torrents Oct 11 12:55:50 volumio-nvme sudo[2018]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:50 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:50 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:50 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 11 12:55:50 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 11 12:55:50 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 11 12:55:50 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 12:55:50 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 12:55:51 volumio-nvme volumio-remote-updater[803]: Test mode enabled Oct 11 12:55:51 volumio-nvme volumio-remote-updater[803]: No alpha test mode Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 11 12:55:51 volumio-nvme volumio[1323]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 11 12:55:51 volumio-nvme volumio[1323]: info: Completed starting MyVolumio Plugin Oct 11 12:55:51 volumio-nvme volumio[1323]: info: BOOT COMPLETED Oct 11 12:55:51 volumio-nvme volumio[1323]: [Metrics] CommandRouter: 43s 381.10ms Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumiosetStartupVolume Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:55:51 volumio-nvme volumio[1323]: info: VolumeController:: Setting startup Volume 50 Oct 11 12:55:51 volumio-nvme volumio[1323]: info: VolumeController::SetAlsaVolume50 Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::Close All Modals sent Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::Close All Modals sent Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:51 volumio-nvme volumio[1323]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 11 12:55:51 volumio-nvme volumio[1323]: aplay: main:828: audio open error: Device or resource busy Oct 11 12:55:51 volumio-nvme volumio[1323]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 11 12:55:51 volumio-nvme sudo[1948]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:51 volumio-nvme volumio[1323]: info: Cannot mount NAS MEDIA at system boot, trial number 4 ,retrying in 5 seconds Oct 11 12:55:51 volumio-nvme kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 11 12:55:51 volumio-nvme kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 11 12:55:51 volumio-nvme kernel: CIFS: Attempting to mount //192.168.1.251/Torrents Oct 11 12:55:51 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioGetState Oct 11 12:55:52 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 11 12:55:52 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 12:55:52 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 11 12:55:53 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 12:55:53 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 12:55:55 volumio-nvme ntpd[1039]: Soliciting pool server 176.215.178.239 Oct 11 12:55:56 volumio-nvme ntpd[1039]: Soliciting pool server 91.209.94.10 Oct 11 12:55:56 volumio-nvme sudo[2043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.251/MEDIA /mnt/NAS/MEDIA Oct 11 12:55:56 volumio-nvme sudo[2043]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:55:57 volumio-nvme ntpd[1039]: Soliciting pool server 37.79.216.163 Oct 11 12:55:57 volumio-nvme ntpd[1039]: Soliciting pool server 162.159.200.1 Oct 11 12:55:57 volumio-nvme sudo[2018]: pam_unix(sudo:session): session closed for user root Oct 11 12:55:57 volumio-nvme volumio[1323]: info: Cannot mount NAS Torrents at system boot, trial number 4 ,retrying in 5 seconds Oct 11 12:55:57 volumio-nvme kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 11 12:55:57 volumio-nvme kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 11 12:55:57 volumio-nvme kernel: CIFS: Attempting to mount //192.168.1.251/MEDIA Oct 11 12:55:58 volumio-nvme ntpd[1039]: Soliciting pool server 80.249.144.180 Oct 11 12:55:58 volumio-nvme ntpd[1039]: Soliciting pool server 188.225.9.167 Oct 11 12:55:58 volumio-nvme ntpd[1039]: Soliciting pool server 195.3.254.2 Oct 11 12:55:59 volumio-nvme ntpd[1039]: Soliciting pool server 217.195.85.6 Oct 11 12:55:59 volumio-nvme ntpd[1039]: Soliciting pool server 162.159.200.123 Oct 11 12:55:59 volumio-nvme ntpd[1039]: Soliciting pool server 193.192.36.3 Oct 11 12:56:00 volumio-nvme ntpd[1039]: Soliciting pool server 194.190.168.1 Oct 11 12:56:00 volumio-nvme ntpd[1039]: Soliciting pool server 2a03:aa00::136:55 Oct 11 12:56:00 volumio-nvme ntpd[1039]: Soliciting pool server 37.153.16.171 Oct 11 12:56:01 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 12:56:01 volumio-nvme ntpd[1039]: Soliciting pool server 195.218.227.230 Oct 11 12:56:02 volumio-nvme ntpd[1039]: Soliciting pool server 91.206.16.3 Oct 11 12:56:02 volumio-nvme sudo[2060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.251/Torrents /mnt/NAS/Torrents Oct 11 12:56:02 volumio-nvme sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:56:03 volumio-nvme volumio[1323]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.5.1/volumio/buster/armhf Oct 11 12:56:03 volumio-nvme sudo[2043]: pam_unix(sudo:session): session closed for user root Oct 11 12:56:03 volumio-nvme volumio[1323]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 11 12:56:03 volumio-nvme kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 11 12:56:03 volumio-nvme kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 11 12:56:03 volumio-nvme kernel: CIFS: Attempting to mount //192.168.1.251/Torrents Oct 11 12:56:07 volumio-nvme volumio[1323]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.5.1/volumio/buster/armhf Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Folder /tmp/plugins removed Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Check plugin dependencies Oct 11 12:56:07 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Checking if plugin already exists Oct 11 12:56:07 volumio-nvme volumio[1323]: info: An error occurred installing the plugin. Rolling back config Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Plugin folders cleanup Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning into folder /volumio/app/plugins/ Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning category audio_interface Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning category miscellanea Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning category music_service Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning category plugins.json Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning category system_controller Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning category user_interface Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning into folder /data/plugins/ Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning category audio_interface Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning category music_service Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning category system_controller Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Scanning category user_interface Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Plugin folders cleanup completed Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Error: Error Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Folder /tmp/plugins removed Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Folder /tmp/downloaded_plugin.zip removed Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Folder /tmp/downloaded_plugin.zip removed Oct 11 12:56:07 volumio-nvme volumio[1323]: info: Folder /data/temp removed Oct 11 12:56:10 volumio-nvme sudo[2060]: pam_unix(sudo:session): session closed for user root Oct 11 12:56:10 volumio-nvme volumio[1323]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 11 12:56:10 volumio-nvme kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 11 12:56:10 volumio-nvme kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Oct 11 12:56:11 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Oct 11 12:56:11 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:56:11 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Oct 11 12:56:11 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Oct 11 12:56:11 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:56:11 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Oct 11 12:56:11 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Oct 11 12:56:11 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:56:11 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:56:11 volumio-nvme volumio[1323]: info: ------------------------------ 2ms Oct 11 12:56:11 volumio-nvme volumio[1323]: info: sendMpdCommand status took 2 milliseconds Oct 11 12:56:11 volumio-nvme volumio[1323]: info: ------------------------------ 2ms Oct 11 12:56:11 volumio-nvme volumio[1323]: info: sendMpdCommand status took 2 milliseconds Oct 11 12:56:11 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:56:11 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:56:11 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 12:56:11 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 12:56:11 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:56:11 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:56:11 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:56:11 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:11 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:12 volumio-nvme volumio[1323]: info: ------------------------------ 22ms Oct 11 12:56:12 volumio-nvme volumio[1323]: info: ------------------------------ 21ms Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Oct 11 12:56:14 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Oct 11 12:56:14 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Oct 11 12:56:14 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Oct 11 12:56:14 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Oct 11 12:56:14 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Oct 11 12:56:14 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ------------------------------ 2ms Oct 11 12:56:14 volumio-nvme volumio[1323]: info: sendMpdCommand status took 2 milliseconds Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:56:14 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:56:14 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:56:14 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 12:56:14 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 12:56:14 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31283,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31283,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31283,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:56:14 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:56:14 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ------------------------------ 22ms Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ------------------------------ 21ms Oct 11 12:56:14 volumio-nvme volumio[1323]: info: ------------------------------ 21ms Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Oct 11 12:56:15 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Oct 11 12:56:15 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Oct 11 12:56:15 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Oct 11 12:56:15 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:56:15 volumio-nvme volumio[1323]: info: sendMpdCommand status took 2 milliseconds Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:56:15 volumio-nvme volumio[1323]: info: sendMpdCommand status took 1 milliseconds Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Oct 11 12:56:15 volumio-nvme volumio[1323]: ---------------------------- MPD announces system playlist update Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Ignoring MPD Status Update Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Oct 11 12:56:15 volumio-nvme volumio[1323]: ---------------------------- MPD announces state update: player Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ControllerMpd::getState Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:56:15 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 12:56:15 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ------------------------------ 1ms Oct 11 12:56:15 volumio-nvme volumio[1323]: info: sendMpdCommand status took 0 milliseconds Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseState Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32285,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32285,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ------------------------------ 17ms Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ------------------------------ 16ms Oct 11 12:56:15 volumio-nvme volumio[1323]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ControllerMpd::pushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::servicePushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CorePlayQueue::getTrack 0 Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32285,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Bayerns bester Musikmix","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 11 12:56:15 volumio-nvme volumio[1323]: verbose: CURRENT POSITION 0 Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState stateService play Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:56:15 volumio-nvme volumio[1323]: info: Received an update from plugin. extracting info from payload Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreStateMachine::pushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: CoreCommandRouter::volumioPushState Oct 11 12:56:15 volumio-nvme volumio[1323]: info: ------------------------------ 22ms Oct 11 12:56:45 volumio-nvme volumio[1323]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/touch_display/volumio/buster/armhf Oct 11 12:56:48 volumio-nvme volumiologrotate[799]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 11 12:56:48 volumio-nvme volumiologrotate[799]: ls: cannot access 'NVME': No such file or directory Oct 11 12:56:49 volumio-nvme volumio[1323]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/touch_display/volumio/buster/armhf Oct 11 12:56:49 volumio-nvme sudo[2218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 11 12:56:49 volumio-nvme sudo[2218]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:56:49 volumio-nvme sudo[2218]: pam_unix(sudo:session): session closed for user root Oct 11 12:56:49 volumio-nvme sudo[2221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Oct 11 12:56:49 volumio-nvme sudo[2221]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:56:49 volumio-nvme sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Oct 11 12:56:49 volumio-nvme sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:56:49 volumio-nvme volumio[1323]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Oct 11 12:56:49 volumio-nvme volumio[1323]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 12:56:49 volumio-nvme sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Oct 11 12:56:49 volumio-nvme volumio[1323]: TypeError: Cannot read property 'split' of undefined Oct 11 12:56:49 volumio-nvme volumio[1323]: at /data/plugins/user_interface/touch_display/index.js:1258:37 Oct 11 12:56:49 volumio-nvme volumio[1323]: at ChildProcess.exithandler (child_process.js:299:7) Oct 11 12:56:49 volumio-nvme volumio[1323]: at ChildProcess.emit (events.js:315:20) Oct 11 12:56:49 volumio-nvme volumio[1323]: at maybeClose (internal/child_process.js:1048:16) Oct 11 12:56:49 volumio-nvme volumio[1323]: at Socket. (internal/child_process.js:439:11) Oct 11 12:56:49 volumio-nvme volumio[1323]: at Socket.emit (events.js:315:20) Oct 11 12:56:49 volumio-nvme volumio[1323]: at Pipe. (net.js:673:12) Oct 11 12:56:49 volumio-nvme volumio[1323]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 12:56:49 volumio-nvme sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 12:56:49 volumio-nvme systemd[1]: Created slice system-getty.slice. Oct 11 12:56:49 volumio-nvme systemd[1]: Started Getty on tty1. Oct 11 12:56:49 volumio-nvme sudo[2221]: pam_unix(sudo:session): session closed for user root Oct 11 12:56:49 volumio-nvme systemd[1]: Reloading. Oct 11 12:56:49 volumio-nvme systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 11 12:56:49 volumio-nvme sudo[2252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 12:55 Oct 11 12:56:49 volumio-nvme sudo[2252]: 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="99ba0a37c11b93215dc3e0ee3c22f0c0313c225b" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8ef80245b1f7b577b29fe08b77efdc88d53369e5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 11 Oct 2024 10:54:46 AM CEST" VOLUMIO_VERSION="3.759" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a1641e68e16692da54c105ebfa2aa2be"