-- Logs begin at Thu 2025-06-05 18:04:42 EEST, end at Thu 2025-06-05 18:06:32 EEST. --
Jun 05 18:05:00 volumiotab wireless.js[918]: trying...
Jun 05 18:05:00 volumiotab sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 05 18:05:00 volumiotab sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:00 volumiotab sudo[1144]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:01 volumiotab wireless.js[918]: trying...
Jun 05 18:05:01 volumiotab sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 05 18:05:01 volumiotab sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:01 volumiotab sudo[1147]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:01 volumiotab dhcpcd[961]: wlan0: leased 4.3.2.55 for 600 seconds
Jun 05 18:05:01 volumiotab avahi-daemon[922]: Joining mDNS multicast group on interface wlan0.IPv4 with address 4.3.2.55.
Jun 05 18:05:01 volumiotab avahi-daemon[922]: New relevant interface wlan0.IPv4 for mDNS.
Jun 05 18:05:01 volumiotab avahi-daemon[922]: Registering new address record for 4.3.2.55 on wlan0.IPv4.
Jun 05 18:05:01 volumiotab dhcpcd[961]: wlan0: adding route to 4.3.2.0/24
Jun 05 18:05:01 volumiotab dhcpcd[961]: wlan0: adding default route via 4.3.2.1
Jun 05 18:05:01 volumiotab volumio-time-update[914]: volumio-time-update-util: Fetching time from Volumio...
Jun 05 18:05:02 volumiotab systemd[1]: Stopping Network Time Service...
Jun 05 18:05:02 volumiotab ntpd[997]: ntpd exiting on signal 15 (Terminated)
Jun 05 18:05:02 volumiotab systemd[1]: ntp.service: Succeeded.
Jun 05 18:05:02 volumiotab systemd[1]: Stopped Network Time Service.
Jun 05 18:05:02 volumiotab volumio-time-update[914]: volumio-time-update-util: Setting system time to: 2025-06-05 18:05:02
Jun 05 18:05:02 volumiotab systemd[1]: Starting Network Time Service...
Jun 05 18:05:02 volumiotab sudo[1188]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-05 18:05:02
Jun 05 18:05:02 volumiotab sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:02 volumiotab ntpd[1186]: ntpd 4.2.8p12@1.3728-o (1): Starting
Jun 05 18:05:02 volumiotab ntpd[1186]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
Jun 05 18:05:02 volumiotab systemd[1]: Started Network Time Service.
Jun 05 18:05:02 volumiotab ntpd[1195]: proto: precision = 0.175 usec (-22)
Jun 05 18:05:02 volumiotab ntpd[1195]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Jun 05 18:05:02 volumiotab ntpd[1195]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Jun 05 18:05:02 volumiotab ntpd[1195]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 891 days ago
Jun 05 18:05:02 volumiotab ntpd[1195]: Listen and drop on 0 v6wildcard [::]:123
Jun 05 18:05:02 volumiotab ntpd[1195]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Jun 05 18:05:02 volumiotab ntpd[1195]: Listen normally on 2 lo 127.0.0.1:123
Jun 05 18:05:02 volumiotab ntpd[1195]: Listen normally on 3 wlan0 4.3.2.55:123
Jun 05 18:05:02 volumiotab ntpd[1195]: Listening on routing socket on fd #20 for interface updates
Jun 05 18:05:02 volumiotab ntpd[1195]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jun 05 18:05:02 volumiotab ntpd[1195]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jun 05 18:05:02 volumiotab dbus-daemon[913]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.7' (uid=0 pid=1193 comm="timedatectl set-time 2025-06-05 18:05:02 ")
Jun 05 18:05:02 volumiotab wireless.js[918]: trying...
Jun 05 18:05:02 volumiotab sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 05 18:05:02 volumiotab sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:02 volumiotab systemd[1]: Starting Time & Date Service...
Jun 05 18:05:02 volumiotab wireless.js[918]: Connected to: ----UniBIT
Jun 05 18:05:02 volumiotab wireless.js[918]: ----
Jun 05 18:05:02 volumiotab sudo[1198]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:02 volumiotab sudo[1202]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 05 18:05:02 volumiotab sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:02 volumiotab sudo[1202]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:02 volumiotab wireless.js[918]: ... joined AP, wlan0 IPv4 is 4.3.2.55, ipV6 is undefined
Jun 05 18:05:02 volumiotab wireless.js[918]: It's done! AP
Jun 05 18:05:02 volumiotab dbus-daemon[913]: [system] Successfully activated service 'org.freedesktop.timedate1'
Jun 05 18:05:02 volumiotab systemd[1]: Started Time & Date Service.
Jun 05 18:05:02 volumiotab systemd-timedated[1199]: Changed local time to Thu Jun 5 18:05:02 2025
Jun 05 18:05:02 volumiotab systemd[1]: Started Wireless Services.
Jun 05 18:05:02 volumiotab sudo[1188]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:02 volumiotab volumio-time-update[914]: volumio-time-update-util: System time updated successfully.
Jun 05 18:05:02 volumiotab systemd[1]: Started Volumio Backend Module.
Jun 05 18:05:02 volumiotab systemd[1]: Started Intel SST and HDA soundcard init service.
Jun 05 18:05:02 volumiotab systemd[1]: Started Volumio Cpu Tweaker.
Jun 05 18:05:02 volumiotab systemd[1]: Started Start Volumio Kiosk.
Jun 05 18:05:02 volumiotab systemd[1]: Started Volumio Time Update Utility.
Jun 05 18:05:02 volumiotab volumio-cpu-tweak[1210]: Setting RT Priority for mpd
Jun 05 18:05:02 volumiotab volumio-cpu-tweak[1210]: Setting MPD Affinity
Jun 05 18:05:02 volumiotab volumio-cpu-tweak[1210]: pid 1002's current affinity mask: f
Jun 05 18:05:02 volumiotab volumio-cpu-tweak[1210]: pid 1002's new affinity mask: 3
Jun 05 18:05:02 volumiotab volumio-cpu-tweak[1210]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
Jun 05 18:05:02 volumiotab systemd[1]: volumio_cpu_tweak.service: Succeeded.
Jun 05 18:05:02 volumiotab startx[1211]: X.Org X Server 1.20.4
Jun 05 18:05:02 volumiotab startx[1211]: X Protocol Version 11, Revision 0
Jun 05 18:05:02 volumiotab startx[1211]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian
Jun 05 18:05:02 volumiotab startx[1211]: Current Operating System: Linux volumiotab 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64
Jun 05 18:05:02 volumiotab startx[1211]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=dc33c633-06b8-4e5a-aa11-bc54b012189b bootpart=UUID=BA30-70EA datapart=UUID=0391f50c-e4f1-4816-b020-02558bcbf08d hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no
Jun 05 18:05:02 volumiotab startx[1211]: Build Date: 18 December 2021 09:05:36AM
Jun 05 18:05:02 volumiotab startx[1211]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support)
Jun 05 18:05:02 volumiotab startx[1211]: Current version of pixman: 0.36.0
Jun 05 18:05:02 volumiotab startx[1211]: Before reporting problems, check http://wiki.x.org
Jun 05 18:05:02 volumiotab startx[1211]: to make sure that you have the latest version.
Jun 05 18:05:02 volumiotab startx[1211]: Markers: (--) probed, (**) from config file, (==) default setting,
Jun 05 18:05:02 volumiotab startx[1211]: (++) from command line, (!!) notice, (II) informational,
Jun 05 18:05:02 volumiotab startx[1211]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Jun 05 18:05:02 volumiotab startx[1211]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jun 5 18:05:02 2025
Jun 05 18:05:02 volumiotab startx[1211]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
Jun 05 18:05:02 volumiotab nmbd[989]: [2025/06/05 18:05:02.321821, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jun 05 18:05:02 volumiotab systemd[1]: Started Samba NMB Daemon.
Jun 05 18:05:02 volumiotab nmbd[989]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Jun 05 18:05:02 volumiotab systemd[1]: Starting Samba Winbind Daemon...
Jun 05 18:05:02 volumiotab winbindd[1277]: [2025/06/05 18:05:02.627102, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Jun 05 18:05:02 volumiotab winbindd[1277]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Jun 05 18:05:02 volumiotab systemd[1]: Started Samba Winbind Daemon.
Jun 05 18:05:02 volumiotab winbindd[1277]: [2025/06/05 18:05:02.642604, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jun 05 18:05:02 volumiotab winbindd[1277]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Jun 05 18:05:02 volumiotab systemd[1]: Starting Samba SMB Daemon...
Jun 05 18:05:02 volumiotab soundcard-init.sh[1209]: cht-bsw-rt5672 detected
Jun 05 18:05:02 volumiotab ntpd[1195]: Soliciting pool server 109.110.82.19
Jun 05 18:05:02 volumiotab soundcard-init.sh[1209]: Headphones plugged in --> output to Headphones
Jun 05 18:05:02 volumiotab kernel: broken atomic modeset userspace detected, disabling atomic
Jun 05 18:05:02 volumiotab soundcard-init.sh[1209]: ALSA lib utils.c:63:(uc_mgr_config_load) could not open configuration file /usr/share/alsa/ucm/DellInc.-Venue8Pro5855--08FVWT/DellInc.-Venue8Pro5855--08FVWT.conf
Jun 05 18:05:02 volumiotab soundcard-init.sh[1209]: ALSA lib parser.c:1425:(load_master_config) error: could not parse configuration for card DellInc.-Venue8Pro5855--08FVWT
Jun 05 18:05:02 volumiotab soundcard-init.sh[1209]: Starting acpid.service for cht-bsw-rt5672 jack detection
Jun 05 18:05:02 volumiotab systemd[1]: Listening on ACPID Listen Socket.
Jun 05 18:05:02 volumiotab systemd[1]: Started ACPI event daemon.
Jun 05 18:05:02 volumiotab soundcard-init.sh[1209]: cht-bsw-rt5672 initialised
Jun 05 18:05:02 volumiotab acpid[1326]: starting up with netlink and the input layer
Jun 05 18:05:02 volumiotab acpid[1326]: 1 rule loaded
Jun 05 18:05:02 volumiotab acpid[1326]: waiting for events: event logging is off
Jun 05 18:05:03 volumiotab startx[1211]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc.
Jun 05 18:05:03 volumiotab smbd[1319]: [2025/06/05 18:05:03.100593, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jun 05 18:05:03 volumiotab systemd[1]: Started Samba SMB Daemon.
Jun 05 18:05:03 volumiotab smbd[1319]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Jun 05 18:05:03 volumiotab systemd[1]: Reached target Multi-User System.
Jun 05 18:05:03 volumiotab systemd[1]: Reached target Graphical Interface.
Jun 05 18:05:03 volumiotab systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jun 05 18:05:03 volumiotab systemd[1]: soundcard-init.service: Succeeded.
Jun 05 18:05:03 volumiotab systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Jun 05 18:05:03 volumiotab systemd[1]: Started Update UTMP about System Runlevel Changes.
Jun 05 18:05:03 volumiotab systemd[1]: Startup finished in 13.110s (firmware) + 8.412s (loader) + 11.923s (kernel) + 21.887s (userspace) = 55.334s.
Jun 05 18:05:03 volumiotab ntpd[1195]: Soliciting pool server 31.28.161.71
Jun 05 18:05:04 volumiotab volumio[1208]: info: -------------------------------------------
Jun 05 18:05:04 volumiotab volumio[1208]: info: ----- Volumio3 ----
Jun 05 18:05:04 volumiotab volumio[1208]: info: -------------------------------------------
Jun 05 18:05:04 volumiotab volumio[1208]: info: ----- System startup ----
Jun 05 18:05:04 volumiotab volumio[1208]: info: -------------------------------------------
Jun 05 18:05:04 volumiotab kernel: i915 0000:00:02.0: [drm] *ERROR* Your hardware requires CONFIG_PMIC_OPREGION and it is not set
Jun 05 18:05:04 volumiotab ntpd[1195]: Soliciting pool server 79.142.192.130
Jun 05 18:05:04 volumiotab ntpd[1195]: Soliciting pool server 194.8.144.107
Jun 05 18:05:04 volumiotab kernel: i915 0000:00:02.0: [drm] *ERROR* Your hardware requires CONFIG_PMIC_OPREGION and it is not set
Jun 05 18:05:04 volumiotab kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=io+mem:owns=io+mem
Jun 05 18:05:05 volumiotab volumio[1208]: info: MYVOLUMIO Environment detected
Jun 05 18:05:05 volumiotab kernel: i915 0000:00:02.0: [drm] *ERROR* Your hardware requires CONFIG_PMIC_OPREGION and it is not set
Jun 05 18:05:05 volumiotab kernel: i915 0000:00:02.0: [drm] *ERROR* Your hardware requires CONFIG_PMIC_OPREGION and it is not set
Jun 05 18:05:05 volumiotab volumio[1208]: info: Plugin folders cleanup
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning into folder /volumio/app/plugins/
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning category audio_interface
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning category miscellanea
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning category music_service
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning category plugins.json
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning category system_controller
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning category user_interface
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning into folder /data/plugins/
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning category audio_interface
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning category music_service
Jun 05 18:05:05 volumiotab volumio[1208]: info: Scanning category user_interface
Jun 05 18:05:05 volumiotab volumio[1208]: info: Plugin folders cleanup completed
Jun 05 18:05:05 volumiotab volumio[1208]: info: -------------------------------------------
Jun 05 18:05:05 volumiotab volumio[1208]: info: ----- Core plugins startup ----
Jun 05 18:05:05 volumiotab volumio[1208]: info: -------------------------------------------
Jun 05 18:05:05 volumiotab volumio[1208]: info: Loading plugins from folder /volumio/app/plugins/
Jun 05 18:05:05 volumiotab volumio[1208]: info: Adding plugin upnp to MyMusic Plugins
Jun 05 18:05:05 volumiotab volumio[1208]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jun 05 18:05:05 volumiotab volumio[1208]: info: Adding plugin upnp_browser to MyMusic Plugins
Jun 05 18:05:05 volumiotab volumio[1208]: info: Loading plugins from folder /data/plugins/
Jun 05 18:05:05 volumiotab volumio[1208]: info: Loading plugin "system"...
Jun 05 18:05:05 volumiotab volumio[1208]: info: Loading plugin "appearance"...
Jun 05 18:05:05 volumiotab ntpd[1195]: Soliciting pool server 62.205.159.153
Jun 05 18:05:05 volumiotab ntpd[1195]: Soliciting pool server 193.84.22.254
Jun 05 18:05:05 volumiotab ntpd[1195]: Soliciting pool server 62.205.159.155
Jun 05 18:05:05 volumiotab kernel: i915 0000:00:02.0: [drm] *ERROR* Your hardware requires CONFIG_PMIC_OPREGION and it is not set
Jun 05 18:05:06 volumiotab ntpd[1195]: Soliciting pool server 78.30.216.96
Jun 05 18:05:06 volumiotab ntpd[1195]: Soliciting pool server 134.249.228.3
Jun 05 18:05:07 volumiotab volumio[1208]: info: Loading plugin "network"...
Jun 05 18:05:07 volumiotab volumio[1208]: info: Refreshing Cached IP Addresses
Jun 05 18:05:07 volumiotab sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 05 18:05:07 volumiotab sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:07 volumiotab sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 05 18:05:07 volumiotab sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:07 volumiotab sudo[1450]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:07 volumiotab sudo[1448]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:07 volumiotab volumio[1208]: info: Loading plugin "services"...
Jun 05 18:05:07 volumiotab volumio[1208]: info: Loading plugin "alsa_controller"...
Jun 05 18:05:07 volumiotab sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jun 05 18:05:07 volumiotab sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:07 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 05 18:05:07 volumiotab volumio[1208]: info: Loading plugin "wizard"...
Jun 05 18:05:07 volumiotab volumio[1208]: info: Loading plugin "networkfs"...
Jun 05 18:05:07 volumiotab volumio[1208]: info: Starting Udev Watcher for removable devices
Jun 05 18:05:07 volumiotab volumio[1208]: info: Ignoring mount for partition: boot
Jun 05 18:05:07 volumiotab volumio[1208]: info: Ignoring mount for partition: volumio
Jun 05 18:05:07 volumiotab volumio[1208]: info: Ignoring mount for partition: volumio_data
Jun 05 18:05:07 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 05 18:05:07 volumiotab volumio[1208]: info: Loading plugin "volumio_command_line_client"...
Jun 05 18:05:07 volumiotab volumio[1208]: info: Loading plugin "upnp"...
Jun 05 18:05:07 volumiotab volumio[1208]: info: [1749135907610] Starting Upmpd Daemon
Jun 05 18:05:07 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 05 18:05:07 volumiotab volumio[1208]: info: Loading plugin "my_music"...
Jun 05 18:05:07 volumiotab volumio[1208]: info: Loading plugin "mpd"...
Jun 05 18:05:07 volumiotab ntpd[1195]: Soliciting pool server 91.236.251.34
Jun 05 18:05:08 volumiotab volumio[1208]: info: Loading plugin "upnp_browser"...
Jun 05 18:05:08 volumiotab volumio[1208]: info: Loading plugin "alarm-clock"...
Jun 05 18:05:08 volumiotab ntpd[1195]: Soliciting pool server 2001:67c:440:f620:91:236:251:34
Jun 05 18:05:08 volumiotab volumio[1208]: info: Loading plugin "airplay_emulation"...
Jun 05 18:05:08 volumiotab volumio[1208]: info: Starting Shairport Sync
Jun 05 18:05:08 volumiotab volumio[1208]: info: Loading plugin "last_100"...
Jun 05 18:05:08 volumiotab volumio[1208]: info: Loading plugin "webradio"...
Jun 05 18:05:08 volumiotab volumio[1208]: info: Loading plugin "i2s_dacs"...
Jun 05 18:05:08 volumiotab volumio[1208]: info: I2S DAC not set, start Auto-detection
Jun 05 18:05:08 volumiotab volumio[1208]: info: Loading plugin "volumiodiscovery"...
Jun 05 18:05:08 volumiotab volumio[1208]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 05 18:05:08 volumiotab volumio[1208]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 05 18:05:08 volumiotab volumio[1208]: *** WARNING *** For more information see
Jun 05 18:05:08 volumiotab volumio[1208]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 05 18:05:08 volumiotab node[1208]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 05 18:05:08 volumiotab volumio[1208]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 05 18:05:08 volumiotab volumio[1208]: *** WARNING *** For more information see
Jun 05 18:05:08 volumiotab node[1208]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 05 18:05:08 volumiotab node[1208]: *** WARNING *** For more information see
Jun 05 18:05:08 volumiotab node[1208]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 05 18:05:08 volumiotab node[1208]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 05 18:05:08 volumiotab node[1208]: *** WARNING *** For more information see
Jun 05 18:05:08 volumiotab volumio[1208]: info: Applying required configuration parameters for plugin volumiodiscovery
Jun 05 18:05:08 volumiotab volumio[1208]: info: Discovery: Started advertising with name: VolumioTab
Jun 05 18:05:08 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 05 18:05:08 volumiotab volumio[1208]: info: Plugin youtube2 is not enabled
Jun 05 18:05:08 volumiotab volumio[1208]: info: Loading plugin "outputs"...
Jun 05 18:05:08 volumiotab volumio[1208]: info: Loading plugin "albumart"...
Jun 05 18:05:09 volumiotab volumio[1208]: info: Plugin example_plugin is not enabled
Jun 05 18:05:09 volumiotab volumio[1208]: info: Loading plugin "inputs"...
Jun 05 18:05:09 volumiotab volumio[1208]: info: Loading plugin "updater_comm"...
Jun 05 18:05:09 volumiotab volumio[1208]: Forking 3 albumart workers
Jun 05 18:05:09 volumiotab volumio[1208]: info: Plugin mpdemulation is not enabled
Jun 05 18:05:09 volumiotab volumio[1208]: info: Loading plugin "rest_api"...
Jun 05 18:05:09 volumiotab volumio[1208]: info: Loading plugin "websocket"...
Jun 05 18:05:09 volumiotab volumio-remote-updater[908]: [2025-06-05 18:05:09] [connect] Successful connection
Jun 05 18:05:09 volumiotab volumio[1208]: info: Starting Socket.io Server version 2.3.0
Jun 05 18:05:09 volumiotab volumio[1208]: info: Loading plugin "fusiondsp"...
Jun 05 18:05:09 volumiotab volumio[1208]: Starting albumart workers
Jun 05 18:05:09 volumiotab volumio[1208]: info: Applying required configuration parameters for plugin fusiondsp
Jun 05 18:05:09 volumiotab volumio[1208]: info: Plugin Systeminfo is not enabled
Jun 05 18:05:09 volumiotab volumio[1208]: info: Plugin lastfm is not enabled
Jun 05 18:05:09 volumiotab volumio[1208]: info: Plugin peppymeterbasic is not enabled
Jun 05 18:05:09 volumiotab volumio[1208]: info: Plugin peppyspectrum is not enabled
Jun 05 18:05:09 volumiotab volumio[1208]: info: Loading i18n strings for locale ua
Jun 05 18:05:09 volumiotab volumio[1208]: Updating browse sources language
Jun 05 18:05:09 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 05 18:05:09 volumiotab volumio[1208]: Starting albumart workers
Jun 05 18:05:09 volumiotab volumio[1208]: Starting albumart workers
Jun 05 18:05:09 volumiotab sudo[1465]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::initPlayerControls
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: Express server listening on port 3000
Jun 05 18:05:10 volumiotab volumio[1208]: [Metrics] WebUI: 6s 897.45ms
Jun 05 18:05:10 volumiotab volumio[1208]: info: Setting Device type: x86
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreStateMachine::resetVolumioState
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreStateMachine::getcurrentVolume
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioRetrievevolume
Jun 05 18:05:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:10 volumiotab volumio[1208]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Jun 05 18:05:10 volumiotab volumio[1208]: info: Completed loading Core Plugins
Jun 05 18:05:10 volumiotab volumio[1208]: info: Preparing to generate the ALSA configuration file
Jun 05 18:05:10 volumiotab volumio[1208]: info: Volumio Network Manager: Network status updated: 2
Jun 05 18:05:10 volumiotab volumio[1208]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Jun 05 18:05:10 volumiotab volumio[1208]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Jun 05 18:05:10 volumiotab volumio[1208]: info: Reading ALSA contributions from plugins.
Jun 05 18:05:11 volumiotab ntpd[1195]: receive: Unexpected origin timestamp 0xebec30a6.a4e66a90 does not match aorg 0000000000.00000000 from server@62.205.159.155 xmt 0xebec30a7.b4d58fbc
Jun 05 18:05:11 volumiotab ntpd[1195]: receive: Unexpected origin timestamp 0xebec30a6.a4ecbf51 does not match aorg 0000000000.00000000 from server@79.142.192.130 xmt 0xebec30a7.b3d9f092
Jun 05 18:05:11 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1
Jun 05 18:05:11 volumiotab volumio-remote-updater[908]: [2025-06-05 18:05:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749135909 101
Jun 05 18:05:11 volumiotab volumio[1208]: 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: 2
Jun 05 18:05:11 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3
Jun 05 18:05:11 volumiotab volumio[1208]: info: Reloading queue from file
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreStateMachine::setRepeat null single undefined
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:11 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreStateMachine::setRandom true
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:11 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:11 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Jun 05 18:05:11 volumiotab volumio[1208]: info: VolumeController:: Volume=64 Mute =false
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:11 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreStateMachine::updateTrackBlock
Jun 05 18:05:11 volumiotab volumio[1208]: info: CorePlayQueue::getTrackBlock
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreCommandRouter::volumioRetrievevolume
Jun 05 18:05:11 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Jun 05 18:05:11 volumiotab volumio[1208]: info: Asound.conf file unchanged, so no further update is needed
Jun 05 18:05:11 volumiotab volumio[1208]: info: Output device has changed, restarting MPD
Jun 05 18:05:11 volumiotab sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 05 18:05:11 volumiotab sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:11 volumiotab volumio[1208]: info: Output device has changed, restarting Shairport Sync
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:11 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:11 volumiotab sudo[1627]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:11 volumiotab sudo[1629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 05 18:05:11 volumiotab sudo[1629]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:12 volumiotab systemd[1]: Stopping Music Player Daemon...
Jun 05 18:05:12 volumiotab volumio[1208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 05 18:05:12 volumiotab volumio[1208]: info: ___________ START PLUGINS ___________
Jun 05 18:05:12 volumiotab volumio[1208]: info: ControllerMpd::onStart: Initializing MPD
Jun 05 18:05:12 volumiotab volumio[1208]: info: Creating MPD Configuration file
Jun 05 18:05:12 volumiotab systemd[1]: mpd.service: Succeeded.
Jun 05 18:05:12 volumiotab systemd[1]: Stopped Music Player Daemon.
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 05 18:05:12 volumiotab volumio[1208]: info: [1749135912075] CoreMusicLibrary::Adding element Media Servers
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 05 18:05:12 volumiotab sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 05 18:05:12 volumiotab sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:12 volumiotab sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 05 18:05:12 volumiotab systemd[1]: Starting Music Player Daemon...
Jun 05 18:05:12 volumiotab sudo[1638]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:12 volumiotab sudo[1636]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:12 volumiotab systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Jun 05 18:05:12 volumiotab systemd[1]: mpd.service: Succeeded.
Jun 05 18:05:12 volumiotab systemd[1]: Stopped Music Player Daemon.
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:12 volumiotab systemd[1]: Starting Music Player Daemon...
Jun 05 18:05:12 volumiotab volumio[1208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 05 18:05:12 volumiotab sudo[1642]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 05 18:05:12 volumiotab sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:12 volumiotab volumio[1208]: info: [1749135912193] CoreMusicLibrary::Adding element Last_100
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 05 18:05:12 volumiotab volumio[1208]: info: [1749135912197] CoreMusicLibrary::Adding element Webradio
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 05 18:05:12 volumiotab sudo[1642]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 05 18:05:12 volumiotab volumio[1208]: info: Initializing BBC Radios
Jun 05 18:05:12 volumiotab volumio[1208]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: Loading i18n strings for locale ua
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: FusionDsp - mixtype--------------------- Hardware
Jun 05 18:05:12 volumiotab volumio[1208]: info: Volumio Calling Home
Jun 05 18:05:12 volumiotab volumio[1208]: info: Preparing to generate the ALSA configuration file
Jun 05 18:05:12 volumiotab volumio[1208]: info: Discovery: adding da1ceeac-3296-4ae0-8737-559db0e27f7a
Jun 05 18:05:12 volumiotab volumio[1208]: info: Discovery: Found device VolumioTab
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:12 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:12 volumiotab volumio[1208]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Jun 05 18:05:12 volumiotab volumio[1208]: info: Reading ALSA contributions from plugins.
Jun 05 18:05:12 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Jun 05 18:05:12 volumiotab volumio[1208]: info: MPD Permissions set
Jun 05 18:05:12 volumiotab volumio[1208]: info: MPD Permissions set
Jun 05 18:05:12 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Jun 05 18:05:12 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Jun 05 18:05:12 volumiotab volumio[1208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Jun 05 18:05:12 volumiotab volumio[1208]: info: VolumeController:: Volume=64 Mute =false
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:12 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:12 volumiotab volumio[1208]: info: Volumio called home
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: error: error
Jun 05 18:05:12 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10
Jun 05 18:05:12 volumiotab volumio[1208]: info: Starting Shairport Sync
Jun 05 18:05:12 volumiotab volumio[1208]: info: Starting Shairport Sync
Jun 05 18:05:12 volumiotab sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 05 18:05:12 volumiotab sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:12 volumiotab volumio[1208]: info: Starting Shairport Sync
Jun 05 18:05:12 volumiotab systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 05 18:05:12 volumiotab sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 05 18:05:12 volumiotab systemd[1]: shairport-sync.service: Succeeded.
Jun 05 18:05:12 volumiotab systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 05 18:05:12 volumiotab sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:12 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11
Jun 05 18:05:12 volumiotab sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 05 18:05:12 volumiotab sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:12 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:12 volumiotab systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 05 18:05:12 volumiotab sudo[1678]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:12 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12
Jun 05 18:05:12 volumiotab systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 05 18:05:12 volumiotab systemd[1]: shairport-sync.service: Succeeded.
Jun 05 18:05:12 volumiotab systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 05 18:05:12 volumiotab volumio[1208]: info: Asound.conf file unchanged, so no further update is needed
Jun 05 18:05:12 volumiotab volumio[1208]: info: Output device has changed, restarting MPD
Jun 05 18:05:12 volumiotab systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 05 18:05:12 volumiotab sudo[1681]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:12 volumiotab sudo[1683]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:12 volumiotab volumio[1208]: info: Output device has changed, restarting Shairport Sync
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:12 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:12 volumiotab sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 05 18:05:12 volumiotab sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:12 volumiotab sudo[1690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 05 18:05:12 volumiotab sudo[1690]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:12 volumiotab sudo[1690]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:12 volumiotab volumio[1208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 05 18:05:12 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13
Jun 05 18:05:12 volumiotab systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Jun 05 18:05:12 volumiotab systemd[1]: mpd.service: Succeeded.
Jun 05 18:05:12 volumiotab systemd[1]: Stopped Music Player Daemon.
Jun 05 18:05:12 volumiotab volumio[1208]: info: Shairport-Sync Started
Jun 05 18:05:12 volumiotab volumio[1208]: Error adding Membership: Error: addMembership EINVAL
Jun 05 18:05:12 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14
Jun 05 18:05:13 volumiotab systemd[1]: Starting Music Player Daemon...
Jun 05 18:05:13 volumiotab volumio[1208]: info: MPD Permissions set
Jun 05 18:05:13 volumiotab volumio[1208]: info: Shairport-Sync Started
Jun 05 18:05:13 volumiotab volumio[1208]: info: Shairport-Sync Started
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jun 05 18:05:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:13 volumiotab volumio[1208]: info: Starting Shairport Sync
Jun 05 18:05:13 volumiotab sudo[1700]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 05 18:05:13 volumiotab sudo[1700]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:13 volumiotab sudo[1700]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:13 volumiotab sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 05 18:05:13 volumiotab sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:13 volumiotab systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 05 18:05:13 volumiotab systemd[1]: shairport-sync.service: Succeeded.
Jun 05 18:05:13 volumiotab systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 05 18:05:13 volumiotab systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 05 18:05:13 volumiotab sudo[1709]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:13 volumiotab volumio[1208]: info: Shairport-Sync Started
Jun 05 18:05:13 volumiotab mpd[1710]: Jun 05 18:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jun 05 18:05:13 volumiotab systemd[1]: Started Music Player Daemon.
Jun 05 18:05:13 volumiotab sudo[1638]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:13 volumiotab sudo[1629]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:13 volumiotab sudo[1692]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:13 volumiotab volumio[1208]: info: Completed starting Core Plugins
Jun 05 18:05:13 volumiotab volumio[1208]: info: -------------------------------------------
Jun 05 18:05:13 volumiotab volumio[1208]: info: ----- MyVolumio plugins startup ----
Jun 05 18:05:13 volumiotab volumio[1208]: info: -------------------------------------------
Jun 05 18:05:13 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Fetching plans data....
Jun 05 18:05:14 volumiotab volumio[1208]: error: MPD error: The expression evaluated to a falsy value:
Jun 05 18:05:14 volumiotab volumio[1208]: assert.ok(self.idling)
Jun 05 18:05:14 volumiotab volumio[1208]: error: The expression evaluated to a falsy value:
Jun 05 18:05:14 volumiotab volumio[1208]: assert.ok(self.idling)
Jun 05 18:05:14 volumiotab volumio[1208]: error: MPD error: The expression evaluated to a falsy value:
Jun 05 18:05:14 volumiotab volumio[1208]: assert.ok(self.idling)
Jun 05 18:05:14 volumiotab volumio[1208]: error: The expression evaluated to a falsy value:
Jun 05 18:05:14 volumiotab volumio[1208]: assert.ok(self.idling)
Jun 05 18:05:14 volumiotab volumio[1208]: info: MPD running with PID1710
Jun 05 18:05:14 volumiotab volumio[1208]: ,establishing connection
Jun 05 18:05:14 volumiotab volumio[1208]: error: MPD error: The expression evaluated to a falsy value:
Jun 05 18:05:14 volumiotab volumio[1208]: assert.ok(self.idling)
Jun 05 18:05:14 volumiotab volumio[1208]: error: The expression evaluated to a falsy value:
Jun 05 18:05:14 volumiotab volumio[1208]: assert.ok(self.idling)
Jun 05 18:05:14 volumiotab volumio[1208]: error: updateQueue error: null
Jun 05 18:05:14 volumiotab volumio[1208]: info: camilladsp service started and running in background, instance 1
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:14 volumiotab kernel: intel_sst_acpi 808622A8:00: FW Version 01.0b.02.02
Jun 05 18:05:14 volumiotab volumio[1208]: info: FusionDsp - AAAAAAAAAAAAAA-> 48000 <-AAAAAAAAAAAAA
Jun 05 18:05:14 volumiotab volumio[1208]: info: FusionDsp loaded
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 05 18:05:14 volumiotab sudo[1736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Jun 05 18:05:14 volumiotab sudo[1736]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:14 volumiotab volumio[1208]: info: FusionDsp - Reporting Fusion DSP Enabled
Jun 05 18:05:14 volumiotab volumio[1208]: info: Adding Signal Path Element [object Object]
Jun 05 18:05:14 volumiotab volumio[1208]: info: Adding fusiondspeq DSP Signal Path Element
Jun 05 18:05:14 volumiotab volumio[1208]: info: FusionDsp - ---- installed callbackRead
Jun 05 18:05:14 volumiotab volumio[1208]: verbose: New Socket.io Connection to 4.3.2.55 from 4.3.2.37 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15
Jun 05 18:05:14 volumiotab volumio[1208]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jun 05 18:05:14 volumiotab systemd[1]: Started FusionDsp Daemon.
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 05 18:05:14 volumiotab sudo[1736]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:14 volumiotab volumio[1208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16
Jun 05 18:05:14 volumiotab volumio[1208]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:14 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:14 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jun 05 18:05:14 volumiotab volumio[1208]: info: Received Get System Info
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 05 18:05:14 volumiotab volumio[1208]: info: Discovery: Getting this device information
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:14 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:14 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:14 volumiotab volumio[1208]: info: Listing playlists
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetQueue
Jun 05 18:05:14 volumiotab volumio[1208]: info: CoreStateMachine::getQueue
Jun 05 18:05:14 volumiotab volumio[1208]: info: CorePlayQueue::getQueue
Jun 05 18:05:15 volumiotab volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 05 18:05:15 volumiotab volumio[1208]: info: FusionDsp - Bass-FusionDsp
Jun 05 18:05:15 volumiotab volumio[1208]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Jun 05 18:05:15 volumiotab volumio[1208]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17
Jun 05 18:05:15 volumiotab systemd[1]: systemd-fsckd.service: Succeeded.
Jun 05 18:05:17 volumiotab volumio[1208]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18
Jun 05 18:05:17 volumiotab systemd[1]: systemd-hostnamed.service: Succeeded.
Jun 05 18:05:18 volumiotab sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 05 18:05:18 volumiotab sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:18 volumiotab volumio[1208]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19
Jun 05 18:05:18 volumiotab sudo[1778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 05 18:05:18 volumiotab sudo[1778]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:18 volumiotab sudo[1776]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:18 volumiotab sudo[1778]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 05 18:05:18 volumiotab sudo[1784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jun 05 18:05:18 volumiotab sudo[1784]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:18 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jun 05 18:05:18 volumiotab volumio[1208]: info: Received Get System Info
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 05 18:05:18 volumiotab volumio[1208]: info: Discovery: Getting this device information
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:18 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:18 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:18 volumiotab volumio[1208]: info: Listing playlists
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:18 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 05 18:05:18 volumiotab volumio[1208]: info: Received Get System Info
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 05 18:05:18 volumiotab volumio[1208]: info: Discovery: Getting this device information
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:18 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:18 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:18 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jun 05 18:05:18 volumiotab volumio[1208]: info: Received Get System Info
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 05 18:05:18 volumiotab volumio[1208]: info: Discovery: Getting this device information
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:18 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 05 18:05:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:18 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:18 volumiotab systemd[1]: Started UPnP Renderer front-end to MPD.
Jun 05 18:05:18 volumiotab volumio[1208]: info: Listing playlists
Jun 05 18:05:18 volumiotab sudo[1784]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:18 volumiotab volumio[1208]: info: Upmpdcli Daemon Started
Jun 05 18:05:18 volumiotab volumio[1786]: Generating RSA private key, 4096 bit long modulus (2 primes)
Jun 05 18:05:19 volumiotab volumio[1208]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Jun 05 18:05:19 volumiotab volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 05 18:05:19 volumiotab volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 05 18:05:19 volumiotab volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 05 18:05:19 volumiotab volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 05 18:05:19 volumiotab volumio[1208]: info: FusionDsp - Bass-FusionDsp
Jun 05 18:05:19 volumiotab volumio[1208]: info: FusionDsp - Bass-FusionDsp
Jun 05 18:05:19 volumiotab volumio[1208]: info: FusionDsp - Bass-FusionDsp
Jun 05 18:05:19 volumiotab volumio[1208]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Jun 05 18:05:19 volumiotab volumio[1208]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Jun 05 18:05:19 volumiotab volumio[1208]: info: FusionDsp - Bass-FusionDsp
Jun 05 18:05:19 volumiotab volumio[1208]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Jun 05 18:05:19 volumiotab volumio[1208]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Jun 05 18:05:20 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jun 05 18:05:21 volumiotab volumio[1786]: .............................................................................................++++
Jun 05 18:05:21 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 05 18:05:21 volumiotab volumio[1208]: info: Received Get System Info
Jun 05 18:05:21 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 05 18:05:21 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 05 18:05:21 volumiotab volumio[1208]: info: Discovery: Getting this device information
Jun 05 18:05:21 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:21 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:21 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 05 18:05:22 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jun 05 18:05:22 volumiotab volumio[1208]: info: Adding plugin bluetooth to MyMusic Plugins
Jun 05 18:05:22 volumiotab volumio[1208]: info: Adding plugin multiroom to MyMusic Plugins
Jun 05 18:05:22 volumiotab volumio[1208]: info: Adding plugin metavolumio to MyMusic Plugins
Jun 05 18:05:22 volumiotab volumio[1208]: info: Adding plugin cd_controller to MyMusic Plugins
Jun 05 18:05:22 volumiotab volumio[1208]: info: Adding plugin smart_inputs to MyMusic Plugins
Jun 05 18:05:22 volumiotab volumio[1208]: info: Adding plugin tidalconnect to MyMusic Plugins
Jun 05 18:05:22 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jun 05 18:05:23 volumiotab volumio[1208]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jun 05 18:05:23 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jun 05 18:05:23 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:23 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:23 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:23 volumiotab volumio[1208]: info: Starting MyVolumio Remote Streaming Endpoints
Jun 05 18:05:23 volumiotab volumio[1208]: info: MyVolumio login type: Token
Jun 05 18:05:23 volumiotab volumio[1208]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jun 05 18:05:23 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jun 05 18:05:24 volumiotab volumio[1786]: ...........................................................................................................................................................++++
Jun 05 18:05:24 volumiotab volumio[1786]: e is 65537 (0x010001)
Jun 05 18:05:24 volumiotab volumio[1786]: writing RSA key
Jun 05 18:05:24 volumiotab volumio[1208]: info: Starting Streaming Service Transparent Proxy
Jun 05 18:05:24 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jun 05 18:05:24 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jun 05 18:05:24 volumiotab volumio[1208]: info: Streaming services startup
Jun 05 18:05:24 volumiotab volumio[1208]: info: Starting Streaming Daemon
Jun 05 18:05:24 volumiotab sudo[2002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jun 05 18:05:24 volumiotab sudo[2002]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:24 volumiotab sudo[2002]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:24 volumiotab volumio[1208]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jun 05 18:05:24 volumiotab volumio[1208]: error: Cannot start Volumio Streaming Daemon
Jun 05 18:05:24 volumiotab volumio[1208]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jun 05 18:05:24 volumiotab volumio[1208]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jun 05 18:05:25 volumiotab volumio[1208]: error: MyVolumio Custom Token format not valid, refreshing it
Jun 05 18:05:25 volumiotab volumio[1208]: STREAMING PROXY: Starting server on port 3245
Jun 05 18:05:25 volumiotab volumio[1208]: Node JS runtime: 14
Jun 05 18:05:25 volumiotab volumio[1208]: info: MyVolumio login type: Token
Jun 05 18:05:26 volumiotab volumio[1208]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Jun 05 18:05:27 volumiotab volumio[1208]: info: MyVolumio token set successfully
Jun 05 18:05:27 volumiotab volumio[1208]: info: MYVOLUMIO: Adding device
Jun 05 18:05:27 volumiotab volumio[1208]: info: MYVOLUMIO: Evaluating Server
Jun 05 18:05:27 volumiotab volumio[1208]: info: MyVolumio status changed
Jun 05 18:05:27 volumiotab volumio[1208]: info: Streaming services startup
Jun 05 18:05:27 volumiotab volumio[1208]: info: Starting Streaming Daemon
Jun 05 18:05:27 volumiotab volumio[1208]: info: Removing browser output: myVolumio user plan is not superstar
Jun 05 18:05:27 volumiotab volumio[1208]: info: Removing audio output:
Jun 05 18:05:27 volumiotab volumio[1208]: info: Stoppping Tunnel 1
Jun 05 18:05:27 volumiotab sudo[2026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jun 05 18:05:27 volumiotab sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:27 volumiotab sudo[2026]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:27 volumiotab sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Jun 05 18:05:27 volumiotab sudo[2029]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:27 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jun 05 18:05:27 volumiotab sudo[2029]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:27 volumiotab volumio[1208]: error: Cannot start Volumio Streaming Daemon
Jun 05 18:05:27 volumiotab volumio[1208]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jun 05 18:05:27 volumiotab volumio[1208]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jun 05 18:05:27 volumiotab volumio[1208]: info: Remote SSH Stopped
Jun 05 18:05:27 volumiotab volumio[1208]: info: Setting Geolocation for MyVolumio to eu7
Jun 05 18:05:27 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:27 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:27 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:27 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:28 volumiotab volumio[1208]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jun 05 18:05:28 volumiotab volumio[1208]: info: Updating MyVolumio device info
Jun 05 18:05:28 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:28 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:28 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:28 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:28 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPlay
Jun 05 18:05:28 volumiotab volumio[1208]: info: CoreStateMachine::play index undefined
Jun 05 18:05:28 volumiotab volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 05 18:05:28 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:28 volumiotab volumio[1208]: info: CoreStateMachine::startPlaybackTimer
Jun 05 18:05:28 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:28 volumiotab volumio[1208]: verbose: ControllerMpd::clearAddPlayTracks NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3
Jun 05 18:05:28 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand stop
Jun 05 18:05:28 volumiotab volumio[1208]: info: sendMpdCommand stop took 1 milliseconds
Jun 05 18:05:28 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand clear
Jun 05 18:05:28 volumiotab volumio[1208]: info: sendMpdCommand clear took 1 milliseconds
Jun 05 18:05:28 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand add "NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3"
Jun 05 18:05:28 volumiotab volumio[1208]: info:
Jun 05 18:05:28 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:05:28 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:05:28 volumiotab volumio[1208]: info:
Jun 05 18:05:28 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:05:28 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:05:28 volumiotab volumio[1208]: info: sendMpdCommand add "NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3" took 3 milliseconds
Jun 05 18:05:28 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand play
Jun 05 18:05:28 volumiotab volumio[1208]: info:
Jun 05 18:05:28 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:05:28 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:05:28 volumiotab volumio[1208]: info:
Jun 05 18:05:28 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:05:28 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:05:28 volumiotab volumio[1208]: info:
Jun 05 18:05:28 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:05:28 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:05:28 volumiotab volumio[1208]: info: ------------------------------ 8ms
Jun 05 18:05:28 volumiotab volumio[1208]: info: ------------------------------ 8ms
Jun 05 18:05:28 volumiotab volumio[1208]: info: sendMpdCommand play took 6 milliseconds
Jun 05 18:05:28 volumiotab volumio[1208]: info: ------------------------------ 5ms
Jun 05 18:05:28 volumiotab volumio[1208]: info: ------------------------------ 4ms
Jun 05 18:05:28 volumiotab volumio[1208]: info: ------------------------------ 3ms
Jun 05 18:05:28 volumiotab volumio[1208]: info:
Jun 05 18:05:28 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:05:28 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:05:28 volumiotab volumio[1208]: info:
Jun 05 18:05:28 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:05:28 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:05:28 volumiotab volumio[1208]: info:
Jun 05 18:05:28 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:05:28 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:05:28 volumiotab volumio[1208]: info: ------------------------------ 5ms
Jun 05 18:05:28 volumiotab volumio[1208]: info: ------------------------------ 4ms
Jun 05 18:05:28 volumiotab volumio[1208]: info: ------------------------------ 3ms
Jun 05 18:05:29 volumiotab volumio[1208]: info:
Jun 05 18:05:29 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:05:29 volumiotab volumio[1208]: info:
Jun 05 18:05:29 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:05:29 volumiotab volumio[1208]: info:
Jun 05 18:05:29 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:05:29 volumiotab volumio[1208]: info:
Jun 05 18:05:29 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:05:29 volumiotab volumio[1208]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jun 05 18:05:29 volumiotab volumio[1208]: info: FusionDsp - ---- read samplerate from file: 44100
Jun 05 18:05:29 volumiotab volumio[1208]: info: camilladsp stopping service pid 1739...
Jun 05 18:05:29 volumiotab volumio[1208]: info: camilladsp service terminated, instance 1
Jun 05 18:05:29 volumiotab volumio[1208]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jun 05 18:05:29 volumiotab volumio[1208]: info: camilladsp service started and running in background, instance 1
Jun 05 18:05:29 volumiotab volumio[1208]: info:
Jun 05 18:05:29 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand status took 156 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand status took 156 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand status took 156 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand status took 149 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:05:29 volumiotab volumio[1208]: info:
Jun 05 18:05:29 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:05:29 volumiotab volumio[1208]: info:
Jun 05 18:05:29 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:05:29 volumiotab volumio[1208]: info:
Jun 05 18:05:29 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand status took 11 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 8 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 8 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 8 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 8 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand status took 5 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand status took 5 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand status took 4 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus stop
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:05:29 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:05:29 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:05:29 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: ------------------------------ 203ms
Jun 05 18:05:29 volumiotab volumio[1208]: info: ------------------------------ 222ms
Jun 05 18:05:29 volumiotab volumio[1208]: info: ------------------------------ 222ms
Jun 05 18:05:29 volumiotab volumio[1208]: info: ------------------------------ 223ms
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 56 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 54 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 54 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 54 milliseconds
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:05:29 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:05:29 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:05:29 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:05:29 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:05:29 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:29 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:29 volumiotab volumio[1208]: info: ------------------------------ 139ms
Jun 05 18:05:29 volumiotab volumio[1208]: info: ------------------------------ 131ms
Jun 05 18:05:29 volumiotab volumio[1208]: info: ------------------------------ 131ms
Jun 05 18:05:29 volumiotab volumio[1208]: info: ------------------------------ 130ms
Jun 05 18:05:29 volumiotab volumio[1208]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jun 05 18:05:32 volumiotab volumio[1208]: info: MYVOLUMIO: Adding device
Jun 05 18:05:32 volumiotab volumio[1208]: info: MYVOLUMIO: Evaluating Server
Jun 05 18:05:33 volumiotab volumio[1208]: info: Setting Geolocation for MyVolumio to eu7
Jun 05 18:05:33 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:33 volumiotab systemd[1]: systemd-timedated.service: Succeeded.
Jun 05 18:05:34 volumiotab volumio[1208]: info: Updating MyVolumio device info
Jun 05 18:05:34 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:34 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:34 volumiotab volumio[1208]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jun 05 18:05:34 volumiotab volumio[1208]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jun 05 18:05:34 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 05 18:05:34 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 05 18:05:34 volumiotab volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 05 18:05:34 volumiotab volumio[1208]: info: FusionDsp - Bass-FusionDsp
Jun 05 18:05:35 volumiotab volumio[1208]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Jun 05 18:05:35 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 05 18:05:35 volumiotab volumio[1208]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jun 05 18:05:35 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jun 05 18:05:35 volumiotab volumio[1208]: info: Received Get System Version
Jun 05 18:05:35 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 05 18:05:35 volumiotab volumio[1208]: info: Received Get System Info
Jun 05 18:05:35 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 05 18:05:35 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 05 18:05:35 volumiotab volumio[1208]: info: Discovery: Getting this device information
Jun 05 18:05:35 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:35 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:35 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 05 18:05:38 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 05 18:05:38 volumiotab volumio[1208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20
Jun 05 18:05:38 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:05:38 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:38 volumiotab volumio[1208]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64
Jun 05 18:05:41 volumiotab volumio[1208]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64
Jun 05 18:05:41 volumiotab volumio[1208]: info: FusionDsp - Stopping FusionDsp service
Jun 05 18:05:41 volumiotab volumio[1208]: info: camilladsp stopping service pid 2056...
Jun 05 18:05:41 volumiotab volumio[1208]: info: camilladsp service terminated, instance 1
Jun 05 18:05:41 volumiotab sudo[2128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service
Jun 05 18:05:41 volumiotab sudo[2128]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:41 volumiotab systemd[1]: Stopping FusionDsp Daemon...
Jun 05 18:05:41 volumiotab volumio[1738]: Backend configuration:
Jun 05 18:05:41 volumiotab volumio[1738]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml
Jun 05 18:05:41 volumiotab volumio[1738]: active_config_txt: null
Jun 05 18:05:41 volumiotab volumio[1738]: camilla_host: 127.0.0.1
Jun 05 18:05:41 volumiotab volumio[1738]: camilla_port: 9876
Jun 05 18:05:41 volumiotab volumio[1738]: coeff_dir: /data/INTERNAL/FusionDsp/filters
Jun 05 18:05:41 volumiotab volumio[1738]: config_dir: /data/configuration/audio_interface/fusiondsp
Jun 05 18:05:41 volumiotab volumio[1738]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml
Jun 05 18:05:41 volumiotab volumio[1738]: log_file: /tmp/camilladsp.log
Jun 05 18:05:41 volumiotab volumio[1738]: on_get_active_config: null
Jun 05 18:05:41 volumiotab volumio[1738]: on_set_active_config: null
Jun 05 18:05:41 volumiotab volumio[1738]: port: 5011
Jun 05 18:05:41 volumiotab volumio[1738]: supported_capture_types: null
Jun 05 18:05:41 volumiotab volumio[1738]: supported_playback_types: null
Jun 05 18:05:41 volumiotab volumio[1738]: update_config_symlink: false
Jun 05 18:05:41 volumiotab volumio[1738]: update_config_txt: false
Jun 05 18:05:41 volumiotab volumio[1738]: ======== Running on http://0.0.0.0:5011 ========
Jun 05 18:05:41 volumiotab volumio[1738]: (Press CTRL+C to quit)
Jun 05 18:05:41 volumiotab systemd[1]: fusiondsp.service: Succeeded.
Jun 05 18:05:41 volumiotab systemd[1]: Stopped FusionDsp Daemon.
Jun 05 18:05:41 volumiotab sudo[2128]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:41 volumiotab volumio[1208]: info: FusionDsp - Reporting Fusion DSP Disabled
Jun 05 18:05:41 volumiotab volumio[1208]: info: Removing fusiondspeq DSP Signal Path Element
Jun 05 18:05:42 volumiotab volumio[1208]: info: Check plugin dependencies
Jun 05 18:05:42 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 05 18:05:42 volumiotab volumio[1208]: info: Rename folder
Jun 05 18:05:42 volumiotab volumio[1208]: info: Folder /tmp/downloaded_plugin.zip removed
Jun 05 18:05:42 volumiotab volumio[1208]: info: Move to category
Jun 05 18:05:42 volumiotab volumio[1208]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory
Jun 05 18:05:42 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:42 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 05 18:05:42 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:42 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 05 18:05:42 volumiotab volumio[1208]: info: Checking if install.sh is present
Jun 05 18:05:42 volumiotab volumio-remote-updater[908]: No test mode
Jun 05 18:05:42 volumiotab volumio-remote-updater[908]: No alpha test mode
Jun 05 18:05:42 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jun 05 18:05:42 volumiotab volumio[1208]: info: Executing install.sh
Jun 05 18:05:42 volumiotab sudo[2145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh
Jun 05 18:05:42 volumiotab sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:42 volumiotab volumio[1208]: info: creating filters folder and copying demo filters
Jun 05 18:05:43 volumiotab volumio[1208]: info: copying demo flters
Jun 05 18:05:43 volumiotab volumio[1208]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Various Fixes for QOBUZ Connect
\n
\nNEW ADDITIONS
\n\n- Qobuz Connect Ready
\n
\n","title":"Update v3.812","updateavailable":true}
Jun 05 18:05:43 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/2XEQ15/
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/2XEQ15/.Bass-FusionDsp.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/2XEQ15/.Classic-FusionDsp.json
Jun 05 18:05:43 volumiotab sudo[2172]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update
Jun 05 18:05:43 volumiotab sudo[2172]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/2XEQ15/.Voice-FusionDsp.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/2XEQ15/.Rock-FusionDsp.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/PEQ/
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/convfir/
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/convfir/kef.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/convfir/test.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/EQ15/
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/EQ15/.Bass-FusionDsp.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/EQ15/.Classic-FusionDsp.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/EQ15/.Voice-FusionDsp.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: ./presets/EQ15/.Rock-FusionDsp.json
Jun 05 18:05:43 volumiotab volumio[1208]: info: Installing/fusiondsp dependencies
Jun 05 18:05:43 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:43 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jun 05 18:05:43 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jun 05 18:05:43 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jun 05 18:05:43 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetBrowseSources
Jun 05 18:05:43 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 05 18:05:43 volumiotab volumio[1208]: info: Hit:1 http://deb.debian.org/debian buster InRelease
Jun 05 18:05:43 volumiotab volumio[1208]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease
Jun 05 18:05:44 volumiotab volumio[1208]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jun 05 18:05:44 volumiotab volumio[1208]: info: Completed starting MyVolumio Plugin
Jun 05 18:05:44 volumiotab volumio[1208]: [Metrics] CommandRouter: 39s 258.88ms
Jun 05 18:05:44 volumiotab volumio[1208]: info: CoreCommandRouter::volumiosetStartupVolume
Jun 05 18:05:44 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:05:44 volumiotab volumio[1208]: info: VolumeController:: Setting startup Volume 10
Jun 05 18:05:44 volumiotab volumio[1208]: info: VolumeController::SetAlsaVolume10
Jun 05 18:05:44 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:05:44 volumiotab volumio[1208]: info: CoreCommandRouter::Close All Modals sent
Jun 05 18:05:44 volumiotab volumio[1208]: info: CoreCommandRouter::Close All Modals sent
Jun 05 18:05:44 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:44 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:44 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:05:44 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:44 volumiotab volumio[1208]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jun 05 18:05:44 volumiotab volumio[1208]: info: FusionDsp - ---- read samplerate from file: 44100
Jun 05 18:05:44 volumiotab volumio[1208]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jun 05 18:05:44 volumiotab volumio[1208]: error: FusionDsp - WebSocket error: [object Object]
Jun 05 18:05:45 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jun 05 18:05:45 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jun 05 18:05:45 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jun 05 18:05:48 volumiotab volumio[1208]: info: Reading package lists...
Jun 05 18:05:49 volumiotab volumio[1208]: info: Building dependency tree...
Jun 05 18:05:49 volumiotab volumio[1208]: info: Reading state information...
Jun 05 18:05:49 volumiotab volumio[1208]: info: All packages are up to date.
Jun 05 18:05:49 volumiotab sudo[2172]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:49 volumiotab sudo[2457]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip
Jun 05 18:05:49 volumiotab sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:49 volumiotab volumio[1208]: info: Reading package lists...
Jun 05 18:05:49 volumiotab volumio[1208]: info: VolumeController::SetAlsaVolume23
Jun 05 18:05:49 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:05:49 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:05:49 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:05:49 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:05:50 volumiotab volumio[1208]: info: Building dependency tree...
Jun 05 18:05:50 volumiotab volumio[1208]: info: Reading state information...
Jun 05 18:05:51 volumiotab sudo[2457]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:51 volumiotab volumio[1208]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1).
Jun 05 18:05:51 volumiotab volumio[1208]: info: python3-pip is already the newest version (18.1-5).
Jun 05 18:05:51 volumiotab volumio[1208]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
Jun 05 18:05:51 volumiotab sudo[2469]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C /
Jun 05 18:05:51 volumiotab sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:51 volumiotab volumio[1208]: info: BOOT COMPLETED
Jun 05 18:05:51 volumiotab volumio[1208]: info: lib/systemd/system/fusiondsp.service
Jun 05 18:05:51 volumiotab sudo[2469]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:53 volumiotab volumio[1208]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant
Jun 05 18:05:53 volumiotab volumio[1208]: info: more info at http://www.winimage.com/zLibDll/unzip.html
Jun 05 18:05:53 volumiotab volumio[1208]: info:
Jun 05 18:05:53 volumiotab volumio[1208]: info: cgui-1.0.0.zip opened
Jun 05 18:05:53 volumiotab sudo[2477]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui
Jun 05 18:05:53 volumiotab volumio[1208]: info: creating directory: cgui/
Jun 05 18:05:53 volumiotab sudo[2477]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/LICENSE.txt
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/README.md
Jun 05 18:05:53 volumiotab volumio[1208]: info: creating directory: cgui/build/
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/robots.txt
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/logo512.png
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/asset-manifest.json
Jun 05 18:05:53 volumiotab sudo[2477]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/index.html
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/.put_statics_here
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/favicon.ico
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/logo192.png
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/manifest.json
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/css-variables.css
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/service-worker.js
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js
Jun 05 18:05:53 volumiotab volumio[1208]: info: creating directory: cgui/build/static/
Jun 05 18:05:53 volumiotab volumio[1208]: info: creating directory: cgui/build/static/css/
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css
Jun 05 18:05:53 volumiotab sudo[2479]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui
Jun 05 18:05:53 volumiotab sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/css/main.1503f6cd.css
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css
Jun 05 18:05:53 volumiotab sudo[2479]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css
Jun 05 18:05:53 volumiotab volumio[1208]: info: creating directory: cgui/build/static/media/
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg
Jun 05 18:05:53 volumiotab volumio[1208]: info: creating directory: cgui/build/static/js/
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/main.71e11955.js
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/main.71e11955.js.map
Jun 05 18:05:53 volumiotab sudo[2481]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip
Jun 05 18:05:53 volumiotab sudo[2481]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt
Jun 05 18:05:53 volumiotab volumio[1208]: info: creating directory: cgui/config/
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/config/camillagui.yml
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/config/gui-config.yml
Jun 05 18:05:53 volumiotab volumio[1208]: info: creating directory: cgui/backend/
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/filters_test.py
Jun 05 18:05:53 volumiotab sudo[2481]: pam_unix(sudo:session): session closed for user root
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/version.py
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/filters.py
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/settings.py
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/views.py
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/filterdefaults.py
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/__init__.py
Jun 05 18:05:53 volumiotab volumio[1208]: info: creating directory: cgui/backend/__pycache__/
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/routes.py
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/backend/filemanagement.py
Jun 05 18:05:53 volumiotab volumio[1208]: info: extracting: cgui/main.py
Jun 05 18:05:56 volumiotab volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 05 18:05:56 volumiotab volumio[1208]: info: FusionDsp - Bass-FusionDsp
Jun 05 18:05:56 volumiotab volumio[1208]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Jun 05 18:05:57 volumiotab volumio[1208]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0
Jun 05 18:05:57 volumiotab volumio[1208]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-4ev9bfdx
Jun 05 18:05:59 volumiotab volumio[1208]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages
Jun 05 18:05:59 volumiotab volumio[1208]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1)
Jun 05 18:06:00 volumiotab volumio[1208]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1)
Jun 05 18:06:00 volumiotab volumio[1208]: info: Building wheels for collected packages: camilladsp
Jun 05 18:06:00 volumiotab volumio[1208]: info: Running setup.py bdist_wheel for camilladsp: started
Jun 05 18:06:01 volumiotab volumio[1208]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done'
Jun 05 18:06:01 volumiotab volumio[1208]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-su959o16/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6
Jun 05 18:06:01 volumiotab volumio[1208]: info: Successfully built camilladsp
Jun 05 18:06:02 volumiotab volumio[1208]: error: error
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPause
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::pause
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::stPlaybackTimer
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::servicePause
Jun 05 18:06:04 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreCommandRouter::servicePause
Jun 05 18:06:04 volumiotab volumio[1208]: info: ControllerMpd::pause
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand pause
Jun 05 18:06:04 volumiotab volumio[1208]: info: sendMpdCommand pause took 3 milliseconds
Jun 05 18:06:04 volumiotab volumio[1208]: info:
Jun 05 18:06:04 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:04 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:04 volumiotab volumio[1208]: info:
Jun 05 18:06:04 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:04 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:04 volumiotab volumio[1208]: info:
Jun 05 18:06:04 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:04 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:04 volumiotab volumio[1208]: info:
Jun 05 18:06:04 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:04 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:04 volumiotab volumio[1208]: info: sendMpdCommand status took 10 milliseconds
Jun 05 18:06:04 volumiotab volumio[1208]: info: sendMpdCommand status took 9 milliseconds
Jun 05 18:06:04 volumiotab volumio[1208]: info: sendMpdCommand status took 6 milliseconds
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:04 volumiotab volumio[1208]: info: sendMpdCommand status took 9 milliseconds
Jun 05 18:06:04 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 5 milliseconds
Jun 05 18:06:04 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jun 05 18:06:04 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:04 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26352,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService pause
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus pause
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::stPlaybackTimer
Jun 05 18:06:04 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26352,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService pause
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus pause
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::stPlaybackTimer
Jun 05 18:06:04 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26352,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:04 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService pause
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus pause
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:04 volumiotab volumio[1208]: info: CoreStateMachine::stPlaybackTimer
Jun 05 18:06:04 volumiotab volumio[1208]: info: ------------------------------ 60ms
Jun 05 18:06:04 volumiotab volumio[1208]: info: ------------------------------ 59ms
Jun 05 18:06:04 volumiotab volumio[1208]: info: ------------------------------ 58ms
Jun 05 18:06:05 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 43 milliseconds
Jun 05 18:06:05 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:05 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:05 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:05 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:05 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26352,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:05 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:05 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService pause
Jun 05 18:06:05 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus pause
Jun 05 18:06:05 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:05 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:05 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:05 volumiotab volumio[1208]: info: CoreStateMachine::stPlaybackTimer
Jun 05 18:06:05 volumiotab volumio[1208]: info: ------------------------------ 78ms
Jun 05 18:06:05 volumiotab volumio[1208]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2
Jun 05 18:06:05 volumiotab volumio[1208]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-ykhmdm77
Jun 05 18:06:06 volumiotab volumio[1208]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages
Jun 05 18:06:06 volumiotab volumio[1208]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1)
Jun 05 18:06:06 volumiotab volumio[1208]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3)
Jun 05 18:06:06 volumiotab volumio[1208]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10)
Jun 05 18:06:07 volumiotab volumio[1208]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3)
Jun 05 18:06:07 volumiotab volumio[1208]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1)
Jun 05 18:06:07 volumiotab volumio[1208]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0)
Jun 05 18:06:07 volumiotab volumio[1208]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0)
Jun 05 18:06:07 volumiotab volumio[1208]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0)
Jun 05 18:06:07 volumiotab volumio[1208]: info: Requirement already satisfied: zipp>=0.5 in /usr/local/lib/python3.7/dist-packages (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) (3.15.0)
Jun 05 18:06:07 volumiotab volumio[1208]: info: Building wheels for collected packages: camilladsp-plot
Jun 05 18:06:07 volumiotab volumio[1208]: info: Running setup.py bdist_wheel for camilladsp-plot: started
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done'
Jun 05 18:06:08 volumiotab volumio[1208]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-pxjp80i_/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9
Jun 05 18:06:08 volumiotab volumio[1208]: info: Successfully built camilladsp-plot
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPlay
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::play index undefined
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::startPlaybackTimer
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: ControllerMpd::resume
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand play
Jun 05 18:06:08 volumiotab volumio[1208]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jun 05 18:06:08 volumiotab volumio[1208]: info: FusionDsp - ---- read samplerate from file: 44100
Jun 05 18:06:08 volumiotab volumio[1208]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jun 05 18:06:08 volumiotab volumio[1208]: info:
Jun 05 18:06:08 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:08 volumiotab volumio[1208]: info: sendMpdCommand play took 45 milliseconds
Jun 05 18:06:08 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:08 volumiotab volumio[1208]: info:
Jun 05 18:06:08 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:08 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:08 volumiotab volumio[1208]: info:
Jun 05 18:06:08 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:08 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:08 volumiotab volumio[1208]: info:
Jun 05 18:06:08 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:08 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:08 volumiotab volumio[1208]: info: sendMpdCommand status took 5 milliseconds
Jun 05 18:06:08 volumiotab volumio[1208]: info: sendMpdCommand status took 4 milliseconds
Jun 05 18:06:08 volumiotab volumio[1208]: info: sendMpdCommand status took 3 milliseconds
Jun 05 18:06:08 volumiotab volumio[1208]: info: sendMpdCommand status took 2 milliseconds
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:08 volumiotab volumio[1208]: error: FusionDsp - WebSocket error: [object Object]
Jun 05 18:06:08 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jun 05 18:06:08 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jun 05 18:06:08 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jun 05 18:06:08 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:08 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26841,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus pause
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26841,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:08 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26841,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:08 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26841,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:08 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:08 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:08 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:08 volumiotab volumio[1208]: info: ------------------------------ 64ms
Jun 05 18:06:08 volumiotab volumio[1208]: info: ------------------------------ 63ms
Jun 05 18:06:08 volumiotab volumio[1208]: info: ------------------------------ 63ms
Jun 05 18:06:08 volumiotab volumio[1208]: info: ------------------------------ 63ms
Jun 05 18:06:08 volumiotab volumio[1208]: info: copying hw detection script
Jun 05 18:06:08 volumiotab volumio[1208]: info: Detected cpu architecture as x86_64
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioSeek
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::seek
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: TRACKBLOCK {"uri":"mnt/NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","service":"mpd","name":"1 - Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=470&web=DJ%20Chebanov/Summer%20MIX/mega&path=%2FNAS&metadata=false","duration":3032,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2}
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::startPlaybackTimer
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: ControllerMpd::seek
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:10 volumiotab volumio[1208]: error: null
Jun 05 18:06:10 volumiotab volumio[1208]: info:
Jun 05 18:06:10 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:10 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:10 volumiotab volumio[1208]: info:
Jun 05 18:06:10 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:10 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:10 volumiotab volumio[1208]: info:
Jun 05 18:06:10 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:10 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:10 volumiotab volumio[1208]: info:
Jun 05 18:06:10 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:10 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:10 volumiotab volumio[1208]: info: sendMpdCommand status took 14 milliseconds
Jun 05 18:06:10 volumiotab volumio[1208]: info: sendMpdCommand status took 11 milliseconds
Jun 05 18:06:10 volumiotab volumio[1208]: info: sendMpdCommand status took 7 milliseconds
Jun 05 18:06:10 volumiotab volumio[1208]: info: sendMpdCommand status took 5 milliseconds
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:10 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jun 05 18:06:10 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jun 05 18:06:10 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jun 05 18:06:10 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:10 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:10 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:10 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:10 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:10 volumiotab sudo[2609]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc
Jun 05 18:06:10 volumiotab sudo[2609]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:06:10 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:10 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:10 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:10 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:10 volumiotab volumio[1208]: info: ------------------------------ 82ms
Jun 05 18:06:10 volumiotab volumio[1208]: info: ------------------------------ 80ms
Jun 05 18:06:10 volumiotab volumio[1208]: info: ------------------------------ 77ms
Jun 05 18:06:10 volumiotab volumio[1208]: info: ------------------------------ 75ms
Jun 05 18:06:10 volumiotab volumio[1208]: info: Reading package lists...
Jun 05 18:06:11 volumiotab volumio[1208]: info: Building dependency tree...
Jun 05 18:06:11 volumiotab volumio[1208]: info: Reading state information...
Jun 05 18:06:13 volumiotab volumio[1208]: info: drc is already the newest version (3.2.2~dfsg0-2).
Jun 05 18:06:13 volumiotab sudo[2609]: pam_unix(sudo:session): session closed for user root
Jun 05 18:06:13 volumiotab sudo[2145]: pam_unix(sudo:session): session closed for user root
Jun 05 18:06:13 volumiotab volumio[1208]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
Jun 05 18:06:13 volumiotab volumio[1208]: info: Install script completed
Jun 05 18:06:13 volumiotab volumio[1208]: info: Done installing plugin.
Jun 05 18:06:13 volumiotab volumio[1208]: info: Enabling plugin fusiondsp
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 05 18:06:13 volumiotab volumio[1208]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.4.2","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"LastFM Scrobbler","name":"lastfm","category":"user_interface","version":"1.5.6","icon":"fa-lastfm","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Peppy-meter-Basic","name":"peppymeterbasic","category":"user_interface","version":"0.0.1","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false}]
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 05 18:06:13 volumiotab volumio[1208]: info: Folder /tmp/plugins removed
Jun 05 18:06:13 volumiotab volumio[1208]: info: Folder /tmp/downloaded_plugin.zip removed
Jun 05 18:06:13 volumiotab volumio[1208]: Plugin install end detected on script
Jun 05 18:06:13 volumiotab volumio[1208]: info: Folder /data/temp removed
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 05 18:06:13 volumiotab volumio[1208]: info: FusionDsp - Bass-FusionDsp
Jun 05 18:06:13 volumiotab volumio[1208]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Jun 05 18:06:13 volumiotab volumio[1208]: info: Preload queue cleared
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::ClearQueue
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::stop
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::stPlaybackTimer
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::updateTrackBlock
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrackBlock
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::serviceStop
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::serviceStop
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::stop
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand stop
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::clearPlayQueue
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::saveQueue
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushQueue
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::addQueueItems
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::addQueueItems
Jun 05 18:06:13 volumiotab volumio[1208]: info: Preload queue cleared
Jun 05 18:06:13 volumiotab volumio[1208]: info: Adding Item to queue: music-library/NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3
Jun 05 18:06:13 volumiotab volumio[1208]: info: Exploding uri music-library/NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3 in service mpd
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ALBUMART /albumart?cacheid=470&web=DJ%20Chebanov/Summer%20MIX/mega&path=%2Fmnt%2FNAS%2FDJ%20Chebanov%20-%20Summer%20MIX%202025%2003%2016.mp3&metadata=false
Jun 05 18:06:13 volumiotab volumio[1208]: info: URI /mnt/NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand stop took 16 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushQueue
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::saveQueue
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::updateTrackBlock
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrackBlock
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPlay
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::play index 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::stop
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::play index undefined
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::startPlaybackTimer
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::clearAddPlayTracks NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand stop
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 16 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand stop took 4 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 4 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 3 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 2 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand clear
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:06:13 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 8 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand clear took 7 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 8 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 7 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 6 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand add "NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3"
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService stop
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus stop
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: No code
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Jun 05 18:06:13 volumiotab volumio[1208]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Jun 05 18:06:13 volumiotab volumio[1208]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 59ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 43ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 42ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 42ms
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:06:13 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:06:13 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:06:13 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:06:13 volumiotab volumio[1208]: error: updateQueue error: null
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:06:13 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 40ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand add "NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3" took 37 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 7ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 5ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 4ms
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand play
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:06:13 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:06:13 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces system playlist update
Jun 05 18:06:13 volumiotab volumio[1208]: info: Ignoring MPD Status Update
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 27ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand play took 27 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 28ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 27ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 23ms
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jun 05 18:06:13 volumiotab volumio[1208]: info: FusionDsp - ---- read samplerate from file: 44100
Jun 05 18:06:13 volumiotab volumio[1208]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jun 05 18:06:13 volumiotab volumio[1208]: error: FusionDsp - WebSocket error: [object Object]
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 18 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 15 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 13 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 12 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus stop
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 28ms
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info:
Jun 05 18:06:13 volumiotab volumio[1208]: ---------------------------- MPD announces state update: player
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::getState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand status
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 9 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 7 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 7 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 7 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 4 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 3 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand status took 2 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseState
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":466,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:13 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:13 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:13 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 73ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 72ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 70ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 48 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 48 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 48 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: info: sendMpdCommand playlistinfo took 48 milliseconds
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: ControllerMpd::parseTrackInfo
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:13 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:13 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:13 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: ControllerMpd::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::servicePushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":3032,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Summer MIX 2025 03 16","artist":"DJ Chebanov","album":"Summer MIX","uri":"NAS/DJ Chebanov - Summer MIX 2025 03 16.mp3","trackType":"mp3"}
Jun 05 18:06:13 volumiotab volumio[1208]: verbose: CURRENT POSITION 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState stateService play
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::syncState currentStatus play
Jun 05 18:06:13 volumiotab volumio[1208]: info: Received an update from plugin. extracting info from payload
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreStateMachine::pushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:13 volumiotab volumio[1208]: info: CoreCommandRouter::volumioPushState
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 130ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 125ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 125ms
Jun 05 18:06:13 volumiotab volumio[1208]: info: ------------------------------ 124ms
Jun 05 18:06:18 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:06:18 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:20 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 05 18:06:20 volumiotab volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 05 18:06:20 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 05 18:06:20 volumiotab volumio[1208]: info: FusionDsp - Bass-FusionDsp
Jun 05 18:06:21 volumiotab volumio[1208]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Jun 05 18:06:23 volumiotab volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 05 18:06:23 volumiotab volumio[1208]: info: FusionDsp - Bass-FusionDsp
Jun 05 18:06:23 volumiotab volumio[1208]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Jun 05 18:06:26 volumiotab volumio[1208]: info: CoreCommandRouter::volumioGetState
Jun 05 18:06:26 volumiotab volumio[1208]: info: CorePlayQueue::getTrack 0
Jun 05 18:06:27 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 05 18:06:27 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jun 05 18:06:32 volumiotab volumio[1208]: info: Enabling plugin fusiondsp
Jun 05 18:06:32 volumiotab volumio[1208]: info: Loading plugin "fusiondsp"...
Jun 05 18:06:32 volumiotab volumio[1208]: info: Applying required configuration parameters for plugin fusiondsp
Jun 05 18:06:32 volumiotab volumio[1208]: info: Preparing to generate the ALSA configuration file
Jun 05 18:06:32 volumiotab volumio[1208]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Jun 05 18:06:32 volumiotab volumio[1208]: info: Reading ALSA contributions from plugins.
Jun 05 18:06:32 volumiotab volumio[1208]: info: Asound.conf file unchanged, so no further update is needed
Jun 05 18:06:32 volumiotab volumio[1208]: info: Output device has changed, restarting MPD
Jun 05 18:06:32 volumiotab sudo[2694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 05 18:06:32 volumiotab sudo[2694]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:06:32 volumiotab sudo[2694]: pam_unix(sudo:session): session closed for user root
Jun 05 18:06:32 volumiotab volumio[1208]: info: Output device has changed, restarting Shairport Sync
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:06:32 volumiotab sudo[2697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 05 18:06:32 volumiotab sudo[2697]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:06:32 volumiotab systemd[1]: Stopping Music Player Daemon...
Jun 05 18:06:32 volumiotab systemd[1]: mpd.service: Succeeded.
Jun 05 18:06:32 volumiotab systemd[1]: Stopped Music Player Daemon.
Jun 05 18:06:32 volumiotab volumio[1208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 05 18:06:32 volumiotab volumio[1208]: info: PLUGIN START: fusiondsp
Jun 05 18:06:32 volumiotab volumio[1208]: info: Loading i18n strings for locale ua
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: FusionDsp - mixtype--------------------- Hardware
Jun 05 18:06:32 volumiotab volumio[1208]: info: Preparing to generate the ALSA configuration file
Jun 05 18:06:32 volumiotab systemd[1]: Starting Music Player Daemon...
Jun 05 18:06:32 volumiotab volumio[1208]: info: Done.
Jun 05 18:06:32 volumiotab volumio[1208]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Jun 05 18:06:32 volumiotab volumio[1208]: info: Reading ALSA contributions from plugins.
Jun 05 18:06:32 volumiotab volumio[1208]: info: MPD Permissions set
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jun 05 18:06:32 volumiotab sudo[2702]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 05 18:06:32 volumiotab sudo[2702]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab sudo[2702]: pam_unix(sudo:session): session closed for user root
Jun 05 18:06:32 volumiotab volumio[1208]: info: Starting Shairport Sync
Jun 05 18:06:32 volumiotab volumio[1208]: info: Asound.conf file unchanged, so no further update is needed
Jun 05 18:06:32 volumiotab volumio[1208]: info: Output device has changed, restarting MPD
Jun 05 18:06:32 volumiotab sudo[2712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 05 18:06:32 volumiotab sudo[2712]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:06:32 volumiotab systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 05 18:06:32 volumiotab systemd[1]: shairport-sync.service: Succeeded.
Jun 05 18:06:32 volumiotab sudo[2715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 05 18:06:32 volumiotab sudo[2715]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:06:32 volumiotab systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 05 18:06:32 volumiotab sudo[2715]: pam_unix(sudo:session): session closed for user root
Jun 05 18:06:32 volumiotab systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 05 18:06:32 volumiotab volumio[1208]: info: Output device has changed, restarting Shairport Sync
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:06:32 volumiotab sudo[2712]: pam_unix(sudo:session): session closed for user root
Jun 05 18:06:32 volumiotab sudo[2719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 05 18:06:32 volumiotab sudo[2719]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:06:32 volumiotab systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Jun 05 18:06:32 volumiotab systemd[1]: mpd.service: Succeeded.
Jun 05 18:06:32 volumiotab systemd[1]: Stopped Music Player Daemon.
Jun 05 18:06:32 volumiotab volumio[1208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 05 18:06:32 volumiotab volumio[1208]: info: MPD Permissions set
Jun 05 18:06:32 volumiotab volumio[1208]: info: Shairport-Sync Started
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab systemd[1]: Starting Music Player Daemon...
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jun 05 18:06:32 volumiotab volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 05 18:06:32 volumiotab volumio[1208]: error: Upnp client error: Error: This socket has been ended by the other party
Jun 05 18:06:32 volumiotab volumio[1208]: info: Starting Shairport Sync
Jun 05 18:06:32 volumiotab sudo[2725]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 05 18:06:32 volumiotab sudo[2725]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:06:32 volumiotab sudo[2725]: pam_unix(sudo:session): session closed for user root
Jun 05 18:06:32 volumiotab sudo[2735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 05 18:06:32 volumiotab sudo[2735]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 05 18:06:32 volumiotab systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 05 18:06:32 volumiotab systemd[1]: shairport-sync.service: Succeeded.
Jun 05 18:06:32 volumiotab systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 05 18:06:32 volumiotab systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 05 18:06:32 volumiotab sudo[2735]: pam_unix(sudo:session): session closed for user root
Jun 05 18:06:32 volumiotab volumio[1208]: info: Shairport-Sync Started
Jun 05 18:06:32 volumiotab volumio[1208]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 05 18:06:32 volumiotab volumio[1208]: TypeError: Cannot read property 'off' of undefined
Jun 05 18:06:32 volumiotab volumio[1208]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15)
Jun 05 18:06:32 volumiotab volumio[1208]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30)
Jun 05 18:06:32 volumiotab volumio[1208]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8)
Jun 05 18:06:32 volumiotab volumio[1208]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29)
Jun 05 18:06:32 volumiotab volumio[1208]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47)
Jun 05 18:06:32 volumiotab volumio[1208]: at Socket.emit (events.js:400:28)
Jun 05 18:06:32 volumiotab volumio[1208]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Jun 05 18:06:32 volumiotab volumio[1208]: at processTicksAndRejections (internal/process/task_queues.js:77:11)
Jun 05 18:06:32 volumiotab volumio[1208]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 05 18:06:32 volumiotab sudo[2750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 18:05
Jun 05 18:06:32 volumiotab sudo[2750]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST"
VOLUMIO_VERSION="3.804"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"