-- Logs begin at Fri 2024-03-29 12:52:52 CET, end at Fri 2024-03-29 12:54:59 CET. -- Mar 29 12:53:00 main ntpd[710]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 29 12:53:00 main haveged[469]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 29 12:53:00 main haveged[469]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 29 12:53:00 main haveged[469]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99911 Mar 29 12:53:00 main haveged[469]: haveged: fills: 0, generated: 0 Mar 29 12:53:00 main nmbd[648]: [2024/03/29 12:53:00.246624, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 29 12:53:00 main nmbd[648]: started asyncdns process 735 Mar 29 12:53:00 main nmbd[648]: [2024/03/29 12:53:00.248514, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 29 12:53:00 main nmbd[648]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 29 12:53:00 main nmbd[648]: [2024/03/29 12:53:00.248636, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 29 12:53:00 main nmbd[648]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 29 12:53:00 main kernel: new mount options do not match the existing superblock, will be ignored Mar 29 12:53:00 main volumio[529]: cset: --> shielding system active with Mar 29 12:53:00 main volumio[529]: cset: "system" cpuset of CPUSPEC(1-3) with 69 tasks running Mar 29 12:53:00 main volumio[529]: cset: "user" cpuset of CPUSPEC(0) with 0 tasks running Mar 29 12:53:00 main wireless.js[547]: Cleaning previous... Mar 29 12:53:00 main systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 29 12:53:00 main sudo[755]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 29 12:53:00 main sudo[755]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:00 main sudo[755]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:00 main sudo[757]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 29 12:53:00 main sudo[757]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:00 main kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Mar 29 12:53:01 main systemd[1]: systemd-rfkill.service: Succeeded. Mar 29 12:53:01 main ntpd[710]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 29 12:53:01 main dhcpcd[606]: eth0: carrier acquired Mar 29 12:53:01 main sudo[757]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:01 main dhcpcd[606]: DUID 00:01:00:01:2d:69:1c:d0:e4:5f:01:59:93:04 Mar 29 12:53:01 main dhcpcd[606]: eth0: IAID 01:59:93:04 Mar 29 12:53:01 main dhcpcd[606]: eth0: adding address fe80::e65f:1ff:fe59:9304 Mar 29 12:53:01 main dhcpcd[606]: ipv6_addaddr1: Permission denied Mar 29 12:53:01 main dhcpcd[606]: eth0: probing address 192.168.0.171/24 Mar 29 12:53:01 main ifplugd(eth0)[712]: Link beat detected. Mar 29 12:53:01 main wireless.js[547]: Stopped aP Mar 29 12:53:01 main wireless.js[547]: Wireless Networking DISABLED, not starting wireless flow Mar 29 12:53:01 main systemd[1]: wireless.service: Succeeded. Mar 29 12:53:01 main systemd[1]: Started Wireless Services. Mar 29 12:53:01 main systemd[1]: Started Volumio Backend Module. Mar 29 12:53:01 main systemd[1]: Started Volumio Cpu Tweaker. Mar 29 12:53:01 main ifplugd(eth0)[712]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Mar 29 12:53:01 main ifplugd(eth0)[712]: client: ifup: interface eth0 already configured Mar 29 12:53:01 main volumio-cpu-tweak[767]: Setting RT Priority for mpd Mar 29 12:53:01 main volumio-cpu-tweak[767]: pid 35's current scheduling policy: SCHED_OTHER Mar 29 12:53:01 main volumio-cpu-tweak[767]: pid 35's current scheduling priority: 0 Mar 29 12:53:01 main volumio-cpu-tweak[767]: Setting MPD Affinity Mar 29 12:53:01 main volumio-cpu-tweak[767]: pid 3's current affinity mask: f Mar 29 12:53:01 main volumio-cpu-tweak[767]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 29 12:53:01 main volumio-cpu-tweak[767]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 29 12:53:01 main systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 29 12:53:01 main ifplugd(eth0)[712]: Program executed successfully. Mar 29 12:53:01 main dhcpcd[606]: eth0: soliciting an IPv6 router Mar 29 12:53:02 main ntpd[710]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 29 12:53:02 main bthelper[567]: Changing power off succeeded Mar 29 12:53:02 main bthelper[567]: [CHG] Controller E4:5F:01:59:93:08 Powered: no Mar 29 12:53:02 main bthelper[567]: [CHG] Controller E4:5F:01:59:93:08 Discovering: no Mar 29 12:53:02 main bthelper[567]: [CHG] Controller E4:5F:01:59:93:08 Class: 0x00000000 Mar 29 12:53:02 main bthelper[567]: [CHG] Controller E4:5F:01:59:93:08 Class: 0x0000041c Mar 29 12:53:02 main bthelper[567]: Changing power on succeeded Mar 29 12:53:03 main volumio[766]: info: ------------------------------------------- Mar 29 12:53:03 main volumio[766]: info: ----- Volumio3 ---- Mar 29 12:53:03 main volumio[766]: info: ------------------------------------------- Mar 29 12:53:03 main volumio[766]: info: ----- System startup ---- Mar 29 12:53:03 main volumio[766]: info: ------------------------------------------- Mar 29 12:53:03 main ntpd[710]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 29 12:53:03 main volumio-remote-updater[527]: [2024-03-29 12:53:03] [connect] Successful connection Mar 29 12:53:03 main volumio[766]: info: MYVOLUMIO Environment detected Mar 29 12:53:03 main volumio[766]: info: Plugin folders cleanup Mar 29 12:53:03 main volumio[766]: info: Scanning into folder /volumio/app/plugins/ Mar 29 12:53:03 main volumio[766]: info: Scanning category audio_interface Mar 29 12:53:03 main volumio[766]: info: Scanning category miscellanea Mar 29 12:53:03 main volumio[766]: info: Scanning category music_service Mar 29 12:53:03 main volumio[766]: info: Scanning category plugins.json Mar 29 12:53:03 main volumio[766]: info: Scanning category system_controller Mar 29 12:53:03 main volumio[766]: info: Scanning category user_interface Mar 29 12:53:03 main volumio[766]: info: Scanning into folder /data/plugins/ Mar 29 12:53:03 main volumio[766]: info: Scanning category audio_interface Mar 29 12:53:03 main volumio[766]: info: Scanning category system_hardware Mar 29 12:53:03 main volumio[766]: info: Plugin folders cleanup completed Mar 29 12:53:03 main volumio[766]: info: ------------------------------------------- Mar 29 12:53:03 main volumio[766]: info: ----- Core plugins startup ---- Mar 29 12:53:03 main volumio[766]: info: ------------------------------------------- Mar 29 12:53:03 main volumio[766]: info: Loading plugins from folder /volumio/app/plugins/ Mar 29 12:53:03 main volumio[766]: info: Adding plugin upnp to MyMusic Plugins Mar 29 12:53:03 main volumio[766]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 29 12:53:03 main volumio[766]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 29 12:53:03 main volumio[766]: info: Loading plugins from folder /data/plugins/ Mar 29 12:53:03 main volumio[766]: info: Loading plugin "system"... Mar 29 12:53:04 main volumio[766]: info: Loading plugin "appearance"... Mar 29 12:53:04 main volumio[766]: info: Loading plugin "network"... Mar 29 12:53:04 main volumio[766]: info: Refreshing Cached IP Addresses Mar 29 12:53:05 main volumio[766]: info: Loading plugin "services"... Mar 29 12:53:05 main sudo[808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 12:53:05 main sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:05 main volumio[766]: info: Loading plugin "alsa_controller"... Mar 29 12:53:05 main sudo[808]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:05 main sudo[810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 12:53:05 main sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:05 main sudo[810]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:05 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 12:53:05 main volumio[766]: info: Loading plugin "wizard"... Mar 29 12:53:05 main volumio[766]: info: Loading plugin "networkfs"... Mar 29 12:53:05 main volumio[766]: info: Starting Udev Watcher for removable devices Mar 29 12:53:05 main volumio[766]: info: Ignoring mount for partition: boot Mar 29 12:53:05 main volumio[766]: info: Ignoring mount for partition: volumio Mar 29 12:53:05 main volumio[766]: info: Ignoring mount for partition: volumio_data Mar 29 12:53:05 main volumio[766]: info: Mounting Device T7_Shield Mar 29 12:53:05 main sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/T7_Shield -o noatime Mar 29 12:53:05 main sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:05 main mount.exfat[821]: volume was not unmounted cleanly Mar 29 12:53:05 main kernel: fuse: init (API version 7.37) Mar 29 12:53:05 main sudo[819]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:05 main volumio[766]: WARN: volume was not unmounted cleanly. Mar 29 12:53:05 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 12:53:05 main volumio[766]: info: Loading plugin "volumio_command_line_client"... Mar 29 12:53:05 main volumio[766]: info: Plugin upnp is not enabled Mar 29 12:53:05 main volumio[766]: info: Loading plugin "my_music"... Mar 29 12:53:05 main volumio[766]: info: Loading plugin "mpd"... Mar 29 12:53:05 main systemd[1]: Mounting FUSE Control File System... Mar 29 12:53:05 main systemd[1]: Mounted FUSE Control File System. Mar 29 12:53:05 main volumio[766]: info: Plugin upnp_browser is not enabled Mar 29 12:53:05 main volumio[766]: info: Loading plugin "alarm-clock"... Mar 29 12:53:05 main volumio[766]: info: Plugin airplay_emulation is not enabled Mar 29 12:53:05 main volumio[766]: info: Loading plugin "last_100"... Mar 29 12:53:05 main volumio[766]: info: Loading plugin "webradio"... Mar 29 12:53:06 main volumio[766]: info: Loading plugin "i2s_dacs"... Mar 29 12:53:06 main volumio[766]: info: Loading plugin "volumiodiscovery"... Mar 29 12:53:06 main volumio[766]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 29 12:53:06 main node[766]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 29 12:53:06 main volumio[766]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 12:53:06 main node[766]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 12:53:06 main volumio[766]: *** WARNING *** For more information see Mar 29 12:53:06 main node[766]: *** WARNING *** For more information see Mar 29 12:53:06 main volumio[766]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 29 12:53:06 main node[766]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 29 12:53:06 main volumio[766]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 12:53:06 main node[766]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 12:53:06 main volumio[766]: *** WARNING *** For more information see Mar 29 12:53:06 main node[766]: *** WARNING *** For more information see Mar 29 12:53:06 main volumio[766]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 29 12:53:06 main volumio[766]: info: Discovery: Started advertising with name: MAIN Mar 29 12:53:06 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 12:53:06 main volumio[766]: info: Loading plugin "outputs"... Mar 29 12:53:06 main volumio[766]: info: Loading plugin "albumart"... Mar 29 12:53:06 main volumio[766]: info: Plugin example_plugin is not enabled Mar 29 12:53:06 main volumio[766]: info: Loading plugin "inputs"... Mar 29 12:53:06 main volumio[766]: info: Loading plugin "updater_comm"... Mar 29 12:53:06 main volumio[766]: info: Plugin mpdemulation is not enabled Mar 29 12:53:06 main volumio[766]: info: Loading plugin "rest_api"... Mar 29 12:53:06 main volumio[766]: info: Loading plugin "websocket"... Mar 29 12:53:06 main volumio[766]: info: Loading plugin "fusiondsp"... Mar 29 12:53:06 main volumio[766]: Forking 3 albumart workers Mar 29 12:53:06 main dhcpcd[606]: eth0: using static address 192.168.0.171/24 Mar 29 12:53:06 main avahi-daemon[541]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.171. Mar 29 12:53:06 main dhcpcd[606]: eth0: adding route to 192.168.0.0/24 Mar 29 12:53:06 main avahi-daemon[541]: New relevant interface eth0.IPv4 for mDNS. Mar 29 12:53:06 main dhcpcd[606]: eth0: adding default route via 192.168.0.1 Mar 29 12:53:06 main avahi-daemon[541]: Registering new address record for 192.168.0.171 on eth0.IPv4. Mar 29 12:53:06 main volumio[766]: info: Applying required configuration parameters for plugin fusiondsp Mar 29 12:53:06 main volumio[766]: info: Loading plugin "music_services_shield"... Mar 29 12:53:07 main volumio[766]: Starting albumart workers Mar 29 12:53:07 main volumio[766]: Starting albumart workers Mar 29 12:53:07 main volumio[766]: info: Applying required configuration parameters for plugin music_services_shield Mar 29 12:53:07 main volumio[766]: Starting albumart workers Mar 29 12:53:07 main volumio[766]: info: Loading i18n strings for locale en Mar 29 12:53:07 main volumio[766]: Updating browse sources language Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::initPlayerControls Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:53:07 main volumio[766]: Express server listening on port 3000 Mar 29 12:53:07 main volumio[766]: [Metrics] WebUI: 5s 66.03ms Mar 29 12:53:07 main volumio[766]: info: CoreStateMachine::resetVolumioState Mar 29 12:53:07 main volumio[766]: info: CoreStateMachine::getcurrentVolume Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 12:53:07 main volumio[766]: info: CoreStateMachine::pushState Mar 29 12:53:07 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::volumioPushState Mar 29 12:53:07 main volumio-remote-updater[527]: [2024-03-29 12:53:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711713183 101 Mar 29 12:53:07 main volumio[766]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 29 12:53:07 main volumio[766]: verbose: New Socket.io Connection to 192.168.0.171 from 192.168.0.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 2 Mar 29 12:53:07 main volumio[766]: verbose: New Socket.io Connection to 192.168.0.171 from 192.168.0.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 3 Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:07 main volumio[766]: info: Reloading queue from file Mar 29 12:53:07 main volumio[766]: verbose: New Socket.io Connection to 192.168.0.171 from 192.168.0.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 4 Mar 29 12:53:07 main volumio[766]: verbose: New Socket.io Connection to 192.168.0.171 from 192.168.0.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 5 Mar 29 12:53:07 main volumio[766]: info: CoreStateMachine::setRepeat null single undefined Mar 29 12:53:07 main volumio[766]: info: CoreStateMachine::pushState Mar 29 12:53:07 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::volumioPushState Mar 29 12:53:07 main volumio[766]: info: CoreStateMachine::setRandom null Mar 29 12:53:07 main volumio[766]: info: CoreStateMachine::pushState Mar 29 12:53:07 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::volumioPushState Mar 29 12:53:07 main volumio[766]: info: Setting Device type: Raspberry PI Mar 29 12:53:07 main volumio[766]: verbose: New Socket.io Connection to 192.168.0.171 from 192.168.0.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 6 Mar 29 12:53:07 main volumio[766]: verbose: New Socket.io Connection to 192.168.0.171 from 192.168.0.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 7 Mar 29 12:53:07 main volumio[766]: info: Completed loading Core Plugins Mar 29 12:53:07 main volumio[766]: info: Preparing to generate the ALSA configuration file Mar 29 12:53:07 main volumio[766]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 29 12:53:07 main volumio[766]: info: Reading ALSA contributions from plugins. Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:07 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::volumioGetQueue Mar 29 12:53:07 main volumio[766]: info: CoreStateMachine::getQueue Mar 29 12:53:07 main volumio[766]: info: CorePlayQueue::getQueue Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Mar 29 12:53:07 main volumio[766]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Mar 29 12:53:07 main volumio[766]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Mar 29 12:53:07 main volumio[766]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Mar 29 12:53:07 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Mar 29 12:53:08 main volumio[766]: info: Discovery: adding f0377915-0688-4464-901b-010d80af48c7 Mar 29 12:53:08 main volumio[766]: info: Discovery: Found device MAIN Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:08 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:08 main volumio[766]: info: Asound.conf file unchanged, so no further update is needed Mar 29 12:53:08 main volumio[766]: info: Output device has changed, restarting MPD Mar 29 12:53:08 main sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 12:53:08 main volumio[766]: info: ___________ START PLUGINS ___________ Mar 29 12:53:08 main sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:08 main volumio[766]: info: ControllerMpd::onStart: Initializing MPD Mar 29 12:53:08 main volumio[766]: info: Creating MPD Configuration file Mar 29 12:53:08 main sudo[908]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:08 main sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 12:53:08 main sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:08 main systemd[1]: musicservicesshield.service: Succeeded. Mar 29 12:53:08 main systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 29 12:53:08 main systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 12:53:08 main volumio[766]: info: [1711713188077] CoreMusicLibrary::Adding element Last_100 Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 12:53:08 main volumio[766]: info: [1711713188080] CoreMusicLibrary::Adding element Webradio Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 12:53:08 main sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 12:53:08 main sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:08 main volumio[766]: info: Loading i18n strings for locale en Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: FusionDsp - mixtype--------------------- None Mar 29 12:53:08 main sudo[914]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:08 main sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 12:53:08 main sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:08 main systemd[1]: Listening on mpd.socket. Mar 29 12:53:08 main systemd[1]: Starting Music Player Daemon... Mar 29 12:53:08 main ntpd[710]: Listen normally on 3 eth0 192.168.0.171:123 Mar 29 12:53:08 main ntpd[710]: new interface(s) found: waking up resolver Mar 29 12:53:08 main systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 29 12:53:08 main systemd[1]: mpd.service: Succeeded. Mar 29 12:53:08 main systemd[1]: Stopped Music Player Daemon. Mar 29 12:53:08 main systemd[1]: Starting Music Player Daemon... Mar 29 12:53:08 main volumio[766]: info: Volumio Calling Home Mar 29 12:53:08 main volumio[766]: info: Preparing to generate the ALSA configuration file Mar 29 12:53:08 main sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 29 12:53:08 main sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:08 main volumio[766]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 29 12:53:08 main volumio[766]: info: Reading ALSA contributions from plugins. Mar 29 12:53:08 main volumio[766]: info: MPD Permissions set Mar 29 12:53:08 main volumio[766]: info: MPD Permissions set Mar 29 12:53:08 main volumio[766]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: Volumio called home Mar 29 12:53:08 main volumio[766]: info: Asound.conf file unchanged, so no further update is needed Mar 29 12:53:08 main volumio[766]: info: Output device has changed, restarting MPD Mar 29 12:53:08 main sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 12:53:08 main sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:08 main sudo[973]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:08 main sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 12:53:08 main sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:08 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:08 main volumio[766]: info: MPD Permissions set Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 29 12:53:08 main systemd[1]: mpd.service: Succeeded. Mar 29 12:53:08 main systemd[1]: Stopped Music Player Daemon. Mar 29 12:53:08 main systemd[1]: Starting Music Player Daemon... Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:08 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:08 main sudo[948]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:08 main sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Mar 29 12:53:08 main sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:10 main mpd[982]: Mar 29 12:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 12:53:10 main systemd[1]: Started Music Player Daemon. Mar 29 12:53:10 main sudo[910]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:10 main sudo[975]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:10 main sudo[916]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:10 main volumio[766]: info: camilladsp spawned new process with pid 1007, instance 1, run: true Mar 29 12:53:10 main volumio[766]: info: camilladsp service started and running in background, instance 1 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:10 main systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 29 12:53:10 main volumio[766]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Mar 29 12:53:10 main volumio[766]: info: FusionDsp loaded Mar 29 12:53:10 main systemd[1]: Started Samba NMB Daemon. Mar 29 12:53:10 main nmbd[648]: [2024/03/29 12:53:10.254393, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 29 12:53:10 main nmbd[648]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 29 12:53:10 main systemd[1]: Starting Samba Winbind Daemon... Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:10 main sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 29 12:53:10 main sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:10 main volumio[766]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 29 12:53:10 main volumio[766]: info: Adding Signal Path Element [object Object] Mar 29 12:53:10 main volumio[766]: info: Adding fusiondspeq DSP Signal Path Element Mar 29 12:53:10 main volumio[766]: info: FusionDsp - ---- installed callbackRead Mar 29 12:53:10 main volumio[766]: verbose: New Socket.io Connection to 192.168.0.171 from 192.168.0.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 9 Mar 29 12:53:10 main systemd[1]: Started FusionDsp Daemon. Mar 29 12:53:10 main sudo[1019]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:10 main volumio[766]: info: Completed starting Core Plugins Mar 29 12:53:10 main volumio[766]: info: ------------------------------------------- Mar 29 12:53:10 main volumio[766]: info: ----- MyVolumio plugins startup ---- Mar 29 12:53:10 main volumio[766]: info: ------------------------------------------- Mar 29 12:53:10 main volumio[766]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 29 12:53:10 main volumio[766]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 29 12:53:10 main volumio[766]: info: FusionDsp - else 1 -2.5 Mar 29 12:53:10 main volumio[766]: info: MPD running with PID982 Mar 29 12:53:10 main volumio[766]: ,establishing connection Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: error: MPD error: The expression evaluated to a falsy value: Mar 29 12:53:10 main volumio[766]: assert.ok(self.idling) Mar 29 12:53:10 main volumio[766]: error: The expression evaluated to a falsy value: Mar 29 12:53:10 main volumio[766]: assert.ok(self.idling) Mar 29 12:53:10 main volumio[766]: error: MPD error: The expression evaluated to a falsy value: Mar 29 12:53:10 main volumio[766]: assert.ok(self.idling) Mar 29 12:53:10 main volumio[766]: error: The expression evaluated to a falsy value: Mar 29 12:53:10 main volumio[766]: assert.ok(self.idling) Mar 29 12:53:10 main volumio[766]: error: MPD error: The expression evaluated to a falsy value: Mar 29 12:53:10 main volumio[766]: assert.ok(self.idling) Mar 29 12:53:10 main volumio[766]: error: The expression evaluated to a falsy value: Mar 29 12:53:10 main volumio[766]: assert.ok(self.idling) Mar 29 12:53:10 main volumio[766]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Mar 29 12:53:10 main volumio[766]: info: Received Get System Info Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:10 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 12:53:10 main volumio[766]: info: Received Get System Info Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:10 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: Listing playlists Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetQueue Mar 29 12:53:10 main volumio[766]: info: CoreStateMachine::getQueue Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getQueue Mar 29 12:53:10 main volumio[766]: info: Received Get System Info Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:10 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main winbindd[1020]: [2024/03/29 12:53:10.627116, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 29 12:53:10 main winbindd[1020]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 12:53:10 main volumio[766]: info: Received Get System Info Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:10 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: Listing playlists Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetQueue Mar 29 12:53:10 main volumio[766]: info: CoreStateMachine::getQueue Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getQueue Mar 29 12:53:10 main winbindd[1020]: [2024/03/29 12:53:10.651277, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 29 12:53:10 main systemd[1]: Started Samba Winbind Daemon. Mar 29 12:53:10 main winbindd[1020]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 29 12:53:10 main systemd[1]: Starting Samba SMB Daemon... Mar 29 12:53:10 main volumio[766]: info: Received Get System Info Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:10 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 12:53:10 main volumio[766]: info: Received Get System Info Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:10 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: Listing playlists Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetQueue Mar 29 12:53:10 main volumio[766]: info: CoreStateMachine::getQueue Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getQueue Mar 29 12:53:10 main volumio[766]: info: Received Get System Info Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:10 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:10 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:10 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:10 main volumio[1006]: cset: --> shielding system active with Mar 29 12:53:10 main volumio[1006]: cset: "system" cpuset of CPUSPEC(1-3) with 102 tasks running Mar 29 12:53:10 main volumio[1006]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 29 12:53:10 main systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 29 12:53:10 main systemd[1]: Reloading. Mar 29 12:53:11 main volumio[766]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 29 12:53:11 main winbindd[1020]: [2024/03/29 12:53:11.164842, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Mar 29 12:53:11 main winbindd[1020]: res_names->count = 3, expected 4 Mar 29 12:53:11 main volumio[766]: error: updateQueue error: null Mar 29 12:53:11 main smbd[1042]: [2024/03/29 12:53:11.233496, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 29 12:53:11 main smbd[1042]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 29 12:53:11 main systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:11 main systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 29 12:53:11 main systemd[1]: Started Samba SMB Daemon. Mar 29 12:53:11 main systemd[1]: Reached target Multi-User System. Mar 29 12:53:11 main systemd[1]: Reached target Graphical Interface. Mar 29 12:53:11 main systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 29 12:53:11 main sudo[994]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:11 main systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 29 12:53:11 main systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 29 12:53:11 main systemd[1]: Startup finished in 9.189s (kernel) + 21.391s (userspace) = 30.580s. Mar 29 12:53:16 main volumio[766]: info: succeeded Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 29 12:53:22 main volumio[766]: info: Adding plugin bluetooth to MyMusic Plugins Mar 29 12:53:22 main volumio[766]: info: Adding plugin multiroom to MyMusic Plugins Mar 29 12:53:22 main volumio[766]: info: Adding plugin metavolumio to MyMusic Plugins Mar 29 12:53:22 main volumio[766]: info: Adding plugin cd_controller to MyMusic Plugins Mar 29 12:53:22 main volumio[766]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 29 12:53:22 main volumio[766]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 29 12:53:22 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 29 12:53:23 main volumio[766]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 29 12:53:23 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 29 12:53:23 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:23 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:23 main volumio[766]: info: Starting MyVolumio Remote Streaming Endpoints Mar 29 12:53:23 main volumio[766]: info: MyVolumio login type: Token Mar 29 12:53:23 main volumio[766]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 29 12:53:23 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 29 12:53:24 main volumio[766]: info: Starting Streaming Service Transparent Proxy Mar 29 12:53:24 main volumio[766]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 29 12:53:24 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 29 12:53:24 main volumio[766]: info: Streaming services startup Mar 29 12:53:24 main volumio[766]: info: Starting Streaming Daemon Mar 29 12:53:24 main sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 12:53:24 main sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:24 main volumio[766]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 29 12:53:24 main sudo[1078]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:24 main volumio[766]: error: Cannot start Volumio Streaming Daemon Mar 29 12:53:24 main volumio[766]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 12:53:24 main volumio[766]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 12:53:25 main volumio[766]: STREAMING PROXY: Starting server on port 3245 Mar 29 12:53:25 main volumio[766]: Node JS runtime: 14 Mar 29 12:53:25 main volumio[766]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 29 12:53:25 main volumio[766]: info: MyVolumio token set successfully Mar 29 12:53:25 main volumio[766]: info: MYVOLUMIO: Adding device Mar 29 12:53:25 main volumio[766]: info: MYVOLUMIO: Evaluating Server Mar 29 12:53:26 main systemd[1]: systemd-fsckd.service: Succeeded. Mar 29 12:53:26 main volumio[766]: info: MyVolumio Plan changed: premium Mar 29 12:53:26 main volumio[766]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 29 12:53:26 main volumio[766]: info: Removing browser output: myVolumio user plan is not superstar Mar 29 12:53:26 main volumio[766]: info: Removing audio output: Mar 29 12:53:26 main volumio[766]: info: MYVOLUMIO: Adding device Mar 29 12:53:26 main volumio[766]: info: MYVOLUMIO: Evaluating Server Mar 29 12:53:26 main volumio[766]: info: Remote config written successfully Mar 29 12:53:26 main volumio[766]: info: Starting Tunnel 1 Mar 29 12:53:26 main volumio[766]: info: Starting Tunnel Connection Checker Mar 29 12:53:26 main volumio[766]: info: Completed starting MyVolumio Plugin Mar 29 12:53:26 main volumio[766]: info: MYVolumio Device enabled Mar 29 12:53:26 main volumio[766]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 29 12:53:26 main volumio[766]: info: MyVolumio status changed Mar 29 12:53:26 main volumio[766]: info: Streaming services startup Mar 29 12:53:26 main volumio[766]: info: Starting Streaming Daemon Mar 29 12:53:26 main sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 12:53:26 main sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:26 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 29 12:53:26 main sudo[1118]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:26 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 29 12:53:27 main volumio[766]: info: [1711713207124] Starting BluetoothController Mar 29 12:53:27 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 29 12:53:28 main volumio[766]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 29 12:53:28 main sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 29 12:53:28 main sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:28 main sudo[1121]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:28 main volumio[766]: info: MRS: MultiRoom plugin initialized Mar 29 12:53:28 main volumio[766]: info: MRS: STOPPING SNAPCLIENT Mar 29 12:53:28 main volumio[766]: info: MRS: Snap server stop Mar 29 12:53:28 main sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 29 12:53:28 main sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:28 main volumio[766]: info: MRS: STOPPING volumioStreaming Mar 29 12:53:28 main sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 29 12:53:28 main sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:28 main sudo[1137]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:28 main sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 29 12:53:28 main sudo[1140]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:28 main sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:28 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 29 12:53:28 main sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 29 12:53:28 main sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:28 main sudo[1143]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:28 main sudo[1146]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:28 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 29 12:53:28 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 29 12:53:28 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 29 12:53:28 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 29 12:53:28 main volumio[766]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 29 12:53:28 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 29 12:53:28 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 29 12:53:28 main volumio[766]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 29 12:53:28 main volumio[766]: info: Preparing to generate the ALSA configuration file Mar 29 12:53:28 main volumio[766]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 29 12:53:28 main volumio[766]: info: Reading ALSA contributions from plugins. Mar 29 12:53:28 main volumio[766]: info: Setting Geolocation for MyVolumio to eu6 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:28 main volumio[766]: info: Setting Geolocation for MyVolumio to eu4 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:28 main volumio[766]: verbose: New Socket.io Connection to 192.168.0.171 from 192.168.0.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 11 Mar 29 12:53:28 main volumio[766]: info: MRS: Removed streaming files Mar 29 12:53:28 main volumio[766]: info: MRS: volumioStreaming STOPPED Mar 29 12:53:28 main volumio[766]: info: MRS: SNAPSERVER STOPPED Mar 29 12:53:28 main volumio[766]: info: MRS: SNAPCLIENT STOPPED Mar 29 12:53:28 main volumio[766]: error: Cannot start Volumio Streaming Daemon Mar 29 12:53:28 main volumio[766]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 12:53:28 main volumio[766]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: Received Get System Info Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:28 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 12:53:28 main volumio[766]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 29 12:53:28 main volumio[766]: info: Received Get System Info Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:28 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: Listing playlists Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetQueue Mar 29 12:53:28 main volumio[766]: info: CoreStateMachine::getQueue Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getQueue Mar 29 12:53:28 main volumio[766]: info: Received Get System Info Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:28 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 12:53:28 main volumio[766]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 29 12:53:28 main volumio[766]: info: Received Get System Info Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:28 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: Listing playlists Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetQueue Mar 29 12:53:28 main volumio[766]: info: CoreStateMachine::getQueue Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getQueue Mar 29 12:53:28 main volumio[766]: info: Received Get System Info Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:28 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 12:53:28 main volumio[766]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 29 12:53:28 main volumio[766]: info: Received Get System Info Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:28 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: Listing playlists Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetQueue Mar 29 12:53:28 main volumio[766]: info: CoreStateMachine::getQueue Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getQueue Mar 29 12:53:28 main volumio[766]: info: Received Get System Info Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:53:28 main volumio[766]: info: Discovery: Getting this device information Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:53:28 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:28 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:29 main volumio[766]: info: Asound.conf file unchanged, so no further update is needed Mar 29 12:53:29 main volumio[766]: info: Output device has changed, restarting MPD Mar 29 12:53:29 main sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 12:53:29 main sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:29 main sudo[1149]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:29 main volumio[766]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:29 main sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 12:53:29 main sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Mar 29 12:53:29 main systemd[1]: musicservicesshield.service: Succeeded. Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Plugin metavolumio disabled by user. Not starting Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 29 12:53:29 main systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 29 12:53:29 main volumio[766]: info: Adding Manifest REST API Endpoints Mar 29 12:53:29 main systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 29 12:53:29 main systemd[1]: Stopping Music Player Daemon... Mar 29 12:53:29 main volumio[766]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 29 12:53:29 main volumio[766]: error: Hi Res Audio Failed Login: Missing Login Data Mar 29 12:53:29 main volumio[766]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 29 12:53:29 main volumio[766]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 29 12:53:29 main volumio[766]: info: Refreshing TIDAL token Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 29 12:53:29 main volumio[766]: info: Refreshing QOBUZ token Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 29 12:53:29 main volumio[766]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Mar 29 12:53:29 main volumio[766]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 29 12:53:29 main volumio[766]: info: MPD Permissions set Mar 29 12:53:29 main systemd[1]: mpd.service: Succeeded. Mar 29 12:53:29 main systemd[1]: Stopped Music Player Daemon. Mar 29 12:53:29 main systemd[1]: Starting Music Player Daemon... Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:29 main volumio[766]: info: Access Token successfully retrieved Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 12:53:29 main volumio[766]: info: [1711713209533] CoreMusicLibrary::Adding element QOBUZ Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:29 main volumio[766]: Cannot find translation for source QOBUZ Mar 29 12:53:29 main volumio[766]: info: Stopping AccessToken refresher cron for QOBUZ Mar 29 12:53:29 main volumio[766]: info: AccessToken refresher cron started for QOBUZ Mar 29 12:53:29 main volumio[766]: info: Adding QOBUZ REST API Endpoints Mar 29 12:53:29 main volumio[766]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:29 main volumio[766]: info: Successfully Added MyVolumio device Mar 29 12:53:29 main volumio[766]: info: Successfully Added MyVolumio device Mar 29 12:53:29 main volumio[766]: info: Updating MyVolumio device info Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:29 main volumio[766]: info: Updating MyVolumio device info Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:29 main volumio[766]: info: Access Token successfully retrieved Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:29 main systemd[1]: systemd-hostnamed.service: Succeeded. Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 12:53:29 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:30 main mpd[1156]: Mar 29 12:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:30 main systemd[1]: Started Music Player Daemon. Mar 29 12:53:30 main sudo[1152]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:30 main systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 29 12:53:30 main volumio[766]: error: updateQueue error: null Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:30 main volumio[766]: info: Successfully Updated MyVolumio device Mar 29 12:53:30 main volumio[766]: info: Successfully retrieved User Session From TIDAL Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:30 main volumio[766]: info: Successfully Updated MyVolumio device Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:30 main volumio[1166]: cset: --> shielding system active with Mar 29 12:53:30 main volumio[1166]: cset: "system" cpuset of CPUSPEC(1-3) with 108 tasks running Mar 29 12:53:30 main volumio[1166]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 29 12:53:30 main systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:30 main volumio[766]: info: Successfully retrieved User Subscription From TIDAL Mar 29 12:53:30 main volumio[766]: info: Adding TIDAL to Browse Sources Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 12:53:30 main volumio[766]: info: [1711713210921] CoreMusicLibrary::Adding element TIDAL Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:53:30 main volumio[766]: Cannot find translation for source QOBUZ Mar 29 12:53:30 main volumio[766]: Cannot find translation for source TIDAL Mar 29 12:53:30 main volumio[766]: info: Stopping AccessToken refresher cron Mar 29 12:53:30 main volumio[766]: info: AccessToken refresher cron started Mar 29 12:53:30 main volumio[766]: info: Adding TIDAL REST API Endpoints Mar 29 12:53:30 main volumio[766]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 29 12:53:30 main volumio[766]: info: BOOT COMPLETED Mar 29 12:53:30 main volumio[766]: [Metrics] CommandRouter: 27s 823.36ms Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::volumiosetStartupVolume Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::Close All Modals sent Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::Close All Modals sent Mar 29 12:53:30 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 29 12:53:31 main sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:31 main systemd[1]: Started MyVolumio SSH Tunnel. Mar 29 12:53:31 main sudo[1192]: pam_unix(sudo:session): session closed for user root Mar 29 12:53:31 main autossh[1195]: port set to 0, monitoring disabled Mar 29 12:53:31 main autossh[1195]: starting ssh (count 1) Mar 29 12:53:31 main autossh[1195]: ssh child pid is 1198 Mar 29 12:53:31 main volumio[766]: info: Remote SSH Started Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 29 12:53:31 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumiossh-tunnel[1194]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Mar 29 12:53:32 main volumio[766]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:32 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:32 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:33 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:34 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:35 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:36 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:37 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:38 main volumio-remote-updater[527]: Test mode enabled Mar 29 12:53:38 main volumio-remote-updater[527]: No alpha test mode Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:38 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:38 main volumio[766]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 12:53:38 main volumio[766]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 Mar 29 12:53:38 main volumio[766]: info: CoreCommandRouter::volumioGetState Mar 29 12:53:38 main volumio[766]: info: CorePlayQueue::getTrack 0 Mar 29 12:53:43 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:43 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:53:43 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 29 12:53:43 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 29 12:53:43 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 12:53:43 main volumio[766]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 12:53:43 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 12:53:50 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 12:53:50 main volumio[766]: info: CURURI: music-library Mar 29 12:53:51 main volumio[766]: info: Preload queue cleared Mar 29 12:53:51 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 12:53:51 main volumio[766]: info: CURURI: music-library/USB Mar 29 12:53:52 main volumio[766]: info: Preload queue cleared Mar 29 12:53:53 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 12:53:53 main volumio[766]: info: CURURI: music-library/USB/T7_Shield Mar 29 12:53:53 main volumio[766]: info: Preload queue cleared Mar 29 12:53:53 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 12:53:53 main volumio[766]: info: CURURI: music-library/USB/T7_Shield/MUSIK Mar 29 12:53:53 main volumio[766]: info: Preload queue cleared Mar 29 12:53:58 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 12:53:58 main volumio[766]: info: CURURI: music-library/USB/T7_Shield/MUSIK/ACID Mar 29 12:53:58 main volumio[766]: info: Preload queue cleared Mar 29 12:54:00 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 12:54:00 main volumio[766]: info: CURURI: music-library/USB/T7_Shield/MUSIK/ACID/Maniac Mar 29 12:54:01 main volumio[766]: info: Preload queue cleared Mar 29 12:54:04 main ntpd[710]: Soliciting pool server 217.175.196.134 Mar 29 12:54:05 main ntpd[710]: Soliciting pool server 162.159.200.1 Mar 29 12:54:05 main ntpd[710]: Soliciting pool server 178.251.64.52 Mar 29 12:54:06 main ntpd[710]: Soliciting pool server 162.159.200.123 Mar 29 12:54:06 main ntpd[710]: Soliciting pool server 91.206.8.36 Mar 29 12:54:06 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 12:54:06 main volumio[766]: info: CURURI: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue Mar 29 12:54:06 main volumio[766]: info: Preload queue cleared Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0001 Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0002 Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0003 Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0004 Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0005 Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0006 Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0007 Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0008 Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0009 Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0010 Mar 29 12:54:06 main volumio[766]: info: Preloading song: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0011 Mar 29 12:54:06 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0001 in service mpd Mar 29 12:54:06 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0001' Mar 29 12:54:06 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0002 in service mpd Mar 29 12:54:06 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0002' Mar 29 12:54:06 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0003 in service mpd Mar 29 12:54:06 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0003' Mar 29 12:54:06 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0004 in service mpd Mar 29 12:54:06 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0004' Mar 29 12:54:06 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0005 in service mpd Mar 29 12:54:06 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0005' Mar 29 12:54:06 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0006 in service mpd Mar 29 12:54:06 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0006' Mar 29 12:54:06 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0007 in service mpd Mar 29 12:54:06 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0007' Mar 29 12:54:07 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0008 in service mpd Mar 29 12:54:07 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0008' Mar 29 12:54:07 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0009 in service mpd Mar 29 12:54:07 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0009' Mar 29 12:54:07 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0010 in service mpd Mar 29 12:54:07 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0010' Mar 29 12:54:07 main ntpd[710]: Soliciting pool server 185.242.177.6 Mar 29 12:54:07 main ntpd[710]: Soliciting pool server 91.206.8.34 Mar 29 12:54:07 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0011 in service mpd Mar 29 12:54:07 main volumio[766]: error: scanFolder - failure to stat '/mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue/track0011' Mar 29 12:54:08 main ntpd[710]: Soliciting pool server 86.59.80.170 Mar 29 12:54:09 main ntpd[710]: Soliciting pool server 185.144.161.170 Mar 29 12:54:09 main ntpd[710]: Soliciting pool server 144.76.197.108 Mar 29 12:54:10 main ntpd[710]: Soliciting pool server 152.53.15.127 Mar 29 12:54:10 main ntpd[710]: Soliciting pool server 46.102.157.67 Mar 29 12:54:10 main volumio[766]: info: Preload queue cleared Mar 29 12:54:10 main volumio[766]: info: Mar 29 12:54:10 main volumio[766]: [1711713250304] ---------------------------- Client requests add and Play Volumio CUE entry Mar 29 12:54:10 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Mar 29 12:54:10 main volumio[766]: info: Adding CUE individual entry: 0 USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue Mar 29 12:54:10 main volumio[766]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 12:54:10 main volumio[766]: info: CoreStateMachine::addQueueItems Mar 29 12:54:10 main volumio[766]: info: CorePlayQueue::addQueueItems Mar 29 12:54:10 main volumio[766]: info: Preload queue cleared Mar 29 12:54:10 main volumio[766]: info: Adding Item to queue: cue://USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue@0 Mar 29 12:54:10 main volumio[766]: info: Exploding uri cue://USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue@0 in service mpd Mar 29 12:54:10 main volumio[766]: info: CoreCommandRouter::volumioPlay Mar 29 12:54:10 main volumio[766]: info: CoreStateMachine::play index 271 Mar 29 12:54:10 main volumio[766]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:54:10 main volumio[766]: info: CoreStateMachine::stop Mar 29 12:54:10 main volumio[766]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:54:10 main volumio[766]: info: CoreStateMachine::play index undefined Mar 29 12:54:10 main volumio[766]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:54:10 main volumio[766]: info: CorePlayQueue::getTrack 271 Mar 29 12:54:10 main volumio[766]: info: CoreCommandRouter::volumioPushQueue Mar 29 12:54:10 main volumio[766]: info: CorePlayQueue::saveQueue Mar 29 12:54:10 main volumio[766]: info: CoreStateMachine::updateTrackBlock Mar 29 12:54:10 main volumio[766]: info: CorePlayQueue::getTrackBlock Mar 29 12:54:11 main ntpd[710]: Soliciting pool server 83.215.130.11 Mar 29 12:54:11 main ntpd[710]: Soliciting pool server 2606:4700:f1::123 Mar 29 12:54:37 main ntpd[710]: receive: Unexpected origin timestamp 0xe9b12a64.29e5b59f does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xe9b12a7d.1e7bda75 Mar 29 12:54:37 main ntpd[710]: receive: Unexpected origin timestamp 0xe9b12a64.29e28ba8 does not match aorg 0000000000.00000000 from server@86.59.80.170 xmt 0xe9b12a7d.1e48c0d5 Mar 29 12:54:37 main ntpd[710]: receive: Unexpected origin timestamp 0xe9b12a64.29e3aacb does not match aorg 0000000000.00000000 from server@185.242.177.6 xmt 0xe9b12a7d.1ef7cc21 Mar 29 12:54:37 main ntpd[710]: receive: Unexpected origin timestamp 0xe9b12a64.29e0060c does not match aorg 0000000000.00000000 from server@144.76.197.108 xmt 0xe9b12a7d.1f348ac8 Mar 29 12:54:39 main volumio[766]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 12:54:39 main volumio[766]: info: CURURI: music-library/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue Mar 29 12:54:39 main volumio[766]: info: Preload queue cleared Mar 29 12:54:59 main volumio[766]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 12:54:59 main volumio[766]: info: CoreStateMachine::ClearQueue Mar 29 12:54:59 main volumio[766]: info: CoreStateMachine::stop Mar 29 12:54:59 main volumio[766]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:54:59 main volumio[766]: info: CorePlayQueue::clearPlayQueue Mar 29 12:54:59 main volumio[766]: info: CorePlayQueue::saveQueue Mar 29 12:54:59 main volumio[766]: info: CoreCommandRouter::volumioPushQueue Mar 29 12:54:59 main volumio[766]: info: CoreStateMachine::addQueueItems Mar 29 12:54:59 main volumio[766]: info: CorePlayQueue::addQueueItems Mar 29 12:54:59 main volumio[766]: info: Preload queue cleared Mar 29 12:54:59 main volumio[766]: info: Adding Item to queue: music-library/USB/T7_Shield/MUSIK/ACID/Maniac Mar 29 12:54:59 main volumio[766]: info: Exploding uri music-library/USB/T7_Shield/MUSIK/ACID/Maniac in service mpd Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F01%20Max%20Overload.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/01 Max Overload.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F02%20Maniac.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/02 Maniac.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F03%20Black%20Car.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/03 Black Car.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F04%20America.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/04 America.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F05%20Lucifera.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/05 Lucifera.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F06%20No%20Time.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/06 No Time.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F07%20Prince%20of%20Hell%20and%20Fire.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/07 Prince of Hell and Fire.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F08%20Bottoms%20Up.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/08 Bottoms Up.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F09%20Drop%20Dead.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/09 Drop Dead.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F10%20The%20Day%20You%20Die.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/10 The Day You Die.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2F11%20Exterminator.wav&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/11 Exterminator.wav Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2FManiac.cuetools.wav.cue&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue Mar 29 12:54:59 main volumio[766]: info: ALBUMART /albumart?cacheid=200&path=%2Fmnt%2FUSB%2FT7_Shield%2FMUSIK%2FACID%2FManiac%2FManiac.cuetools.wav.cue&metadata=false Mar 29 12:54:59 main volumio[766]: info: URI /mnt/USB/T7_Shield/MUSIK/ACID/Maniac/Maniac.cuetools.wav.cue Mar 29 12:54:59 main volumio[766]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 12:54:59 main volumio[766]: Error: Unable to resolve or reject the same promise twice Mar 29 12:54:59 main volumio[766]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 29 12:54:59 main volumio[766]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Mar 29 12:54:59 main volumio[766]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 29 12:54:59 main volumio[766]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 29 12:54:59 main volumio[766]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 29 12:54:59 main volumio[766]: at Socket.emit (events.js:315:20) Mar 29 12:54:59 main volumio[766]: at addChunk (internal/streams/readable.js:309:12) Mar 29 12:54:59 main volumio[766]: at readableAddChunk (internal/streams/readable.js:280:11) Mar 29 12:54:59 main volumio[766]: at Socket.Readable.push (internal/streams/readable.js:223:10) Mar 29 12:54:59 main volumio[766]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Mar 29 12:54:59 main volumio[766]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 12:54:59 main sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-29 12:53 Mar 29 12:54:59 main sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="a641dd71dd22f560f71c5c8af897751297274d11" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 13 Mar 2024 10:44:09 PM CET" VOLUMIO_VERSION="3.636" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="10aad550287be934f715117e7cad5535"