-- Logs begin at Mon 2025-03-03 16:31:36 -03, end at Mon 2025-03-03 16:33:11 -03. -- Mar 03 16:32:00 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:00 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:00 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:00 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:00 volumio-primo volumio[1690]: .......................................................++++ Mar 03 16:32:01 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:01 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:01 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:01 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:01 volumio-primo dhcpcd[1597]: eth0: leased 192.168.1.118 for 25200 seconds Mar 03 16:32:01 volumio-primo dhcpcd[1597]: eth0: adding route to 192.168.1.0/24 Mar 03 16:32:01 volumio-primo dhcpcd[1597]: eth0: changing default route via 192.168.1.1 Mar 03 16:32:01 volumio-primo avahi-daemon[558]: Registering new address record for 192.168.1.118 on eth0.IPv4. Mar 03 16:32:01 volumio-primo dhcpcd[1582]: eth0: deleted default route Mar 03 16:32:01 volumio-primo dhcpcd[1582]: eth0: leased 192.168.1.118 for 25200 seconds Mar 03 16:32:01 volumio-primo dhcpcd[1582]: eth0: adding route to 192.168.1.0/24 Mar 03 16:32:01 volumio-primo dhcpcd[1582]: eth0: adding default route via 192.168.1.1 Mar 03 16:32:02 volumio-primo volumio[778]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 4 Mar 03 16:32:02 volumio-primo ntpd[827]: ntpd exiting on signal 15 (Terminated) Mar 03 16:32:02 volumio-primo systemd[1]: Stopping Network Time Service... Mar 03 16:32:02 volumio-primo volumio[778]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.39 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 5 Mar 03 16:32:02 volumio-primo sudo[1744]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:02 volumio-primo sudo[1740]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:02 volumio-primo volumio[778]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.39 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 6 Mar 03 16:32:02 volumio-primo avahi-daemon[558]: Withdrawing address record for 169.254.28.6 on eth0. Mar 03 16:32:02 volumio-primo avahi-daemon[558]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.28.6. Mar 03 16:32:02 volumio-primo avahi-daemon[558]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.118. Mar 03 16:32:02 volumio-primo dhcpcd[1582]: eth0: deleting route to 169.254.0.0/16 Mar 03 16:32:02 volumio-primo systemd[1]: ntp.service: Succeeded. Mar 03 16:32:02 volumio-primo systemd[1]: Stopped Network Time Service. Mar 03 16:32:02 volumio-primo systemd[1]: Starting Network Time Service... Mar 03 16:32:02 volumio-primo ntpd[1862]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 03 16:32:02 volumio-primo ntpd[1862]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 03 16:32:02 volumio-primo systemd[1]: Started Network Time Service. Mar 03 16:32:02 volumio-primo dhcpcd[1597]: eth0: deleting route to 169.254.0.0/16 Mar 03 16:32:02 volumio-primo ntpd[1872]: proto: precision = 0.583 usec (-21) Mar 03 16:32:02 volumio-primo ntpd[1872]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 03 16:32:02 volumio-primo ntpd[1872]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 03 16:32:02 volumio-primo ntpd[1872]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 797 days ago Mar 03 16:32:02 volumio-primo ntpd[1872]: Listen and drop on 0 v6wildcard [::]:123 Mar 03 16:32:02 volumio-primo ntpd[1872]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 03 16:32:02 volumio-primo ntpd[1872]: Listen normally on 2 lo 127.0.0.1:123 Mar 03 16:32:02 volumio-primo ntpd[1872]: Listen normally on 3 eth0 192.168.1.118:123 Mar 03 16:32:02 volumio-primo ntpd[1872]: Listening on routing socket on fd #20 for interface updates Mar 03 16:32:02 volumio-primo ntpd[1872]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 03 16:32:02 volumio-primo ntpd[1872]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 03 16:32:02 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:02 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:02 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:02 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:02 volumio-primo volumio[778]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.39 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 7 Mar 03 16:32:02 volumio-primo volumio[778]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.39 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 8 Mar 03 16:32:03 volumio-primo ntpd[1872]: Soliciting pool server 162.159.200.123 Mar 03 16:32:03 volumio-primo volumio-time-update[554]: volumio-time-update-util: Fetching time from Volumio... Mar 03 16:32:03 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:03 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:03 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:03 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:04 volumio-primo volumio-time-update[554]: volumio-time-update-util: Setting system time to: 2025-03-03 16:32:02 Mar 03 16:32:04 volumio-primo sudo[1902]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-03-03 16:32:02 Mar 03 16:32:04 volumio-primo sudo[1902]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:04 volumio-primo dbus-daemon[543]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=1907 comm="timedatectl set-time 2025-03-03 16:32:02 ") Mar 03 16:32:04 volumio-primo systemd[1]: Starting Time & Date Service... Mar 03 16:32:04 volumio-primo dbus-daemon[543]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 03 16:32:04 volumio-primo systemd[1]: Started Time & Date Service. Mar 03 16:32:02 volumio-primo systemd-timedated[1908]: Changed local time to Mon Mar 3 16:32:02 2025 Mar 03 16:32:02 volumio-primo sudo[1902]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:02 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 16:32:02 volumio-primo volumio-time-update[554]: volumio-time-update-util: System time updated successfully. Mar 03 16:32:02 volumio-primo systemd[1]: Started Volumio Time Update Utility. Mar 03 16:32:02 volumio-primo systemd[1]: Reached target Multi-User System. Mar 03 16:32:02 volumio-primo systemd[1]: Reached target Graphical Interface. Mar 03 16:32:02 volumio-primo systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 03 16:32:02 volumio-primo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 03 16:32:02 volumio-primo systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 03 16:32:02 volumio-primo systemd[1]: Startup finished in 14.404s (kernel) + 28.250s (userspace) = 42.654s. Mar 03 16:32:02 volumio-primo volumio[778]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.39 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 9 Mar 03 16:32:02 volumio-primo ntpd[1872]: Soliciting pool server 92.63.176.247 Mar 03 16:32:02 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:02 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:02 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:02 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:03 volumio-primo ntpd[1872]: Soliciting pool server 92.255.126.11 Mar 03 16:32:03 volumio-primo ntpd[1872]: Soliciting pool server 217.69.139.133 Mar 03 16:32:03 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:03 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:03 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:03 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:03 volumio-primo ntpd[1872]: Soliciting pool server 151.0.2.54 Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:04 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:04 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 03 16:32:04 volumio-primo volumio[778]: info: Received Get System Info Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 16:32:04 volumio-primo volumio[778]: info: Discovery: Getting this device information Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:04 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:04 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:04 volumio-primo volumio[778]: info: Listing playlists Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetQueue Mar 03 16:32:04 volumio-primo volumio[778]: info: CoreStateMachine::getQueue Mar 03 16:32:04 volumio-primo volumio[778]: info: CorePlayQueue::getQueue Mar 03 16:32:04 volumio-primo ntpd[1872]: Soliciting pool server 5.178.87.93 Mar 03 16:32:04 volumio-primo ntpd[1872]: Soliciting pool server 89.108.115.94 Mar 03 16:32:04 volumio-primo ntpd[1872]: Soliciting pool server 89.179.240.219 Mar 03 16:32:04 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:04 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:04 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:04 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:04 volumio-primo ntpd[1872]: Soliciting pool server 178.208.255.95 Mar 03 16:32:04 volumio-primo volumio[778]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 10 Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 03 16:32:04 volumio-primo volumio[778]: info: Adding plugin bluetooth to MyMusic Plugins Mar 03 16:32:04 volumio-primo volumio[778]: info: Adding plugin multiroom to MyMusic Plugins Mar 03 16:32:04 volumio-primo volumio[778]: info: Adding plugin metavolumio to MyMusic Plugins Mar 03 16:32:04 volumio-primo volumio[778]: info: Adding plugin cd_controller to MyMusic Plugins Mar 03 16:32:04 volumio-primo volumio[778]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 03 16:32:04 volumio-primo volumio[778]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 03 16:32:04 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 03 16:32:05 volumio-primo ntpd[1872]: Soliciting pool server 45.92.177.52 Mar 03 16:32:05 volumio-primo ntpd[1872]: Soliciting pool server 46.160.198.122 Mar 03 16:32:05 volumio-primo ntpd[1872]: Soliciting pool server 92.255.126.12 Mar 03 16:32:05 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:05 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:05 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:05 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:05 volumio-primo systemd[1]: systemd-fsckd.service: Succeeded. Mar 03 16:32:06 volumio-primo volumio[778]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 03 16:32:06 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 03 16:32:06 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:06 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:06 volumio-primo volumio[778]: info: Starting MyVolumio Remote Streaming Endpoints Mar 03 16:32:06 volumio-primo volumio[778]: info: MyVolumio login type: Token Mar 03 16:32:06 volumio-primo volumio[778]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 03 16:32:06 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 03 16:32:06 volumio-primo ntpd[1872]: Soliciting pool server 31.131.251.6 Mar 03 16:32:06 volumio-primo ntpd[1872]: Soliciting pool server 95.79.30.113 Mar 03 16:32:06 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:06 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:06 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:06 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:07 volumio-primo systemd[1]: systemd-hostnamed.service: Succeeded. Mar 03 16:32:07 volumio-primo volumio[778]: info: Starting Streaming Service Transparent Proxy Mar 03 16:32:07 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 03 16:32:07 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 03 16:32:07 volumio-primo volumio[778]: info: Streaming services startup Mar 03 16:32:07 volumio-primo volumio[778]: info: Starting Streaming Daemon Mar 03 16:32:07 volumio-primo sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 03 16:32:07 volumio-primo ntpd[1872]: Soliciting pool server 45.90.217.6 Mar 03 16:32:07 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 03 16:32:07 volumio-primo sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:07 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:07 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:07 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:07 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:07 volumio-primo sudo[1941]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:07 volumio-primo volumio[1690]: .......................................................................................................................++++ Mar 03 16:32:07 volumio-primo volumio[1690]: e is 65537 (0x010001) Mar 03 16:32:07 volumio-primo volumio[1690]: writing RSA key Mar 03 16:32:07 volumio-primo volumio[778]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=tinkerboard&variante=volumio&version=3.779&uuid=545cf6c2025b1edbdd75fe9ef1fa0662" http://updates.volumio.org/downloader-v1/track-device Mar 03 16:32:07 volumio-primo volumio[778]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 03 16:32:07 volumio-primo volumio[778]: Dload Upload Total Spent Left Speed Mar 03 16:32:07 volumio-primo volumio[778]: [132B blob data] Mar 03 16:32:07 volumio-primo volumio[778]: retrying in 5 seconds, trial 1 Mar 03 16:32:07 volumio-primo volumio[778]: info: Volumio Calling Home Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 16:32:07 volumio-primo ntpd[1872]: Soliciting pool server 162.159.200.1 Mar 03 16:32:07 volumio-primo volumio[778]: error: Cannot start Volumio Streaming Daemon Mar 03 16:32:07 volumio-primo volumio[778]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 03 16:32:07 volumio-primo volumio[778]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:07 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 03 16:32:07 volumio-primo volumio[778]: info: Received Get System Info Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 16:32:07 volumio-primo volumio[778]: info: Discovery: Getting this device information Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:07 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:07 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:07 volumio-primo volumio[778]: info: Listing playlists Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:07 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 03 16:32:07 volumio-primo volumio[778]: info: Received Get System Info Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 16:32:07 volumio-primo volumio[778]: info: Discovery: Getting this device information Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:07 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 16:32:07 volumio-primo volumio[778]: info: Volumio called home Mar 03 16:32:08 volumio-primo ntpd[1872]: Soliciting pool server 89.109.251.23 Mar 03 16:32:09 volumio-primo ntpd[1872]: receive: Unexpected origin timestamp 0xeb708238.671d8ad8 does not match aorg 0000000000.00000000 from server@5.178.87.93 xmt 0xeb708239.2f7efa3e Mar 03 16:32:09 volumio-primo volumio[778]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 03 16:32:09 volumio-primo volumio[778]: STREAMING PROXY: Starting server on port 3245 Mar 03 16:32:09 volumio-primo volumio[778]: Node JS runtime: 14 Mar 03 16:32:09 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:09 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:09 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:09 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:09 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 03 16:32:09 volumio-primo ntpd[1872]: Soliciting pool server 195.90.182.235 Mar 03 16:32:10 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 03 16:32:10 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:10 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:10 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:10 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:11 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:11 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:11 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:11 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:12 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:12 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:12 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:12 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:13 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:13 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:13 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:13 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:13 volumio-primo volumio[778]: info: MyVolumio token set successfully Mar 03 16:32:13 volumio-primo volumio[778]: info: MYVOLUMIO: Adding device Mar 03 16:32:13 volumio-primo volumio[778]: info: MYVOLUMIO: Evaluating Server Mar 03 16:32:14 volumio-primo volumio[778]: info: MyVolumio Plan changed: superstar Mar 03 16:32:14 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Mar 03 16:32:14 volumio-primo volumio[778]: info: Adding audio output: Mar 03 16:32:14 volumio-primo volumio[778]: info: MYVOLUMIO: Adding device Mar 03 16:32:14 volumio-primo volumio[778]: info: MYVOLUMIO: Evaluating Server Mar 03 16:32:14 volumio-primo volumio[778]: info: Remote config written successfully Mar 03 16:32:14 volumio-primo volumio[778]: info: Starting Tunnel 1 Mar 03 16:32:14 volumio-primo volumio[778]: info: Starting Tunnel Connection Checker Mar 03 16:32:14 volumio-primo volumio[778]: info: Completed starting MyVolumio Plugin Mar 03 16:32:14 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:14 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:14 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:14 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:14 volumio-primo volumio[778]: info: MYVolumio Device enabled Mar 03 16:32:14 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 03 16:32:14 volumio-primo volumio[778]: info: MyVolumio status changed Mar 03 16:32:14 volumio-primo volumio[778]: info: Streaming services startup Mar 03 16:32:14 volumio-primo volumio[778]: info: Starting Streaming Daemon Mar 03 16:32:14 volumio-primo sudo[2019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 03 16:32:14 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 03 16:32:14 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 03 16:32:14 volumio-primo sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:14 volumio-primo sudo[2019]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:14 volumio-primo volumio[778]: info: [1741030334707] Starting BluetoothController Mar 03 16:32:14 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 03 16:32:15 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:15 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:15 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:15 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:15 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 03 16:32:15 volumio-primo sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 03 16:32:16 volumio-primo sudo[2029]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:16 volumio-primo sudo[2029]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:16 volumio-primo volumio[778]: info: MRS: MultiRoom plugin initialized Mar 03 16:32:16 volumio-primo volumio[778]: info: MRS: STOPPING SNAPCLIENT Mar 03 16:32:16 volumio-primo volumio[778]: info: MRS: Snap server stop Mar 03 16:32:16 volumio-primo volumio[778]: info: MRS: STOPPING volumioStreaming Mar 03 16:32:16 volumio-primo sudo[2052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 03 16:32:16 volumio-primo sudo[2052]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:16 volumio-primo sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 03 16:32:16 volumio-primo sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 03 16:32:16 volumio-primo sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:16 volumio-primo sudo[2052]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:16 volumio-primo sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:16 volumio-primo sudo[2068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 03 16:32:16 volumio-primo sudo[2068]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:16 volumio-primo sudo[2055]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:16 volumio-primo sudo[2068]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 03 16:32:16 volumio-primo sudo[2061]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 03 16:32:16 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:16 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:16 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:16 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 03 16:32:16 volumio-primo volumio[778]: info: Preparing to generate the ALSA configuration file Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 16:32:16 volumio-primo volumio[778]: info: Setting Geolocation for MyVolumio to eu9 Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:16 volumio-primo volumio[778]: info: Setting Geolocation for MyVolumio to eu10 Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 03 16:32:16 volumio-primo volumio[778]: info: MRS: Removed streaming files Mar 03 16:32:16 volumio-primo volumio[778]: info: MRS: volumioStreaming STOPPED Mar 03 16:32:16 volumio-primo volumio[778]: info: MRS: SNAPSERVER STOPPED Mar 03 16:32:16 volumio-primo volumio[778]: info: MRS: SNAPCLIENT STOPPED Mar 03 16:32:16 volumio-primo volumio[778]: error: Cannot start Volumio Streaming Daemon Mar 03 16:32:16 volumio-primo volumio[778]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 03 16:32:16 volumio-primo volumio[778]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 03 16:32:16 volumio-primo volumio[778]: info: Asound.conf file unchanged, so no further update is needed Mar 03 16:32:16 volumio-primo volumio[778]: info: Output device has changed, restarting MPD Mar 03 16:32:16 volumio-primo volumio[778]: info: Output device has changed, restarting Shairport Sync Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:16 volumio-primo sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 16:32:16 volumio-primo sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:16 volumio-primo sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 16:32:16 volumio-primo sudo[2094]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:16 volumio-primo sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:16 volumio-primo volumio[778]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:16 volumio-primo systemd[1]: Stopping Music Player Daemon... Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding METAVOLUMIO REST API Endpoints Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding inputs REST Endpoints Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 03 16:32:16 volumio-primo volumio[778]: info: Scanning Audio Inputs Mar 03 16:32:16 volumio-primo sudo[2116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Primo Mar 03 16:32:16 volumio-primo sudo[2116]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:16 volumio-primo volumio[778]: info: Checking against Known Cards name Mar 03 16:32:16 volumio-primo volumio[778]: info: Checking against Known Cards name Mar 03 16:32:16 volumio-primo volumio[778]: info: Checking against Known Cards name Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding Server instance for streaming Mar 03 16:32:16 volumio-primo systemd[1]: mpd.service: Succeeded. Mar 03 16:32:16 volumio-primo systemd[1]: Stopped Music Player Daemon. Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 03 16:32:16 volumio-primo systemd[1]: Starting Music Player Daemon... Mar 03 16:32:16 volumio-primo volumio[778]: error: Hi Res Audio Failed Login: Missing Login Data Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 03 16:32:16 volumio-primo volumio[778]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Mar 03 16:32:16 volumio-primo volumio[778]: info: Stopping AccessToken refresher cron Mar 03 16:32:16 volumio-primo volumio[778]: info: AccessToken refresher cron started Mar 03 16:32:16 volumio-primo volumio[778]: info: Stopping AccessToken refresher cron for QOBUZ Mar 03 16:32:16 volumio-primo volumio[778]: info: AccessToken refresher cron started for QOBUZ Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding TIDAL REST API Endpoints Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding QOBUZ REST API Endpoints Mar 03 16:32:16 volumio-primo volumio[778]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 03 16:32:16 volumio-primo volumio[778]: [Metrics] CommandRouter: 35s 963.87ms Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::volumiosetStartupVolume Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::Close All Modals sent Mar 03 16:32:16 volumio-primo volumio[778]: info: CoreCommandRouter::Close All Modals sent Mar 03 16:32:16 volumio-primo sudo[2124]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 16:32:16 volumio-primo sudo[2124]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:16 volumio-primo sudo[2124]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:16 volumio-primo volumio[778]: info: MPD Permissions set Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: Starting Shairport Sync Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:17 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::volumioPause Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreStateMachine::pause Mar 03 16:32:17 volumio-primo volumio[778]: info: mpdhttpout --- Volumio set on pause Mar 03 16:32:17 volumio-primo volumio[778]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Mar 03 16:32:17 volumio-primo sudo[2116]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:17 volumio-primo sudo[2143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 16:32:17 volumio-primo volumio[778]: info: Bluetooth name changed to Volumio Primo Mar 03 16:32:17 volumio-primo sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:17 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 16:32:17 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Mar 03 16:32:17 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 16:32:17 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 16:32:17 volumio-primo sudo[2143]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:17 volumio-primo volumio[778]: info: Shairport-Sync Started Mar 03 16:32:17 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:17 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:17 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:17 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:17 volumio-primo volumio[778]: info: Successfully Added MyVolumio device Mar 03 16:32:17 volumio-primo volumio[778]: info: Successfully Added MyVolumio device Mar 03 16:32:17 volumio-primo volumio[778]: info: Updating MyVolumio device info Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:17 volumio-primo volumio-remote-updater[549]: No test mode Mar 03 16:32:17 volumio-primo volumio-remote-updater[549]: No alpha test mode Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 03 16:32:17 volumio-primo volumio[778]: info: Updating MyVolumio device info Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:32:17 volumio-primo volumio[778]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 03 16:32:17 volumio-primo volumio[778]: info: Successfully Updated MyVolumio device Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 03 16:32:17 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 03 16:32:18 volumio-primo volumio[778]: info: Successfully Updated MyVolumio device Mar 03 16:32:18 volumio-primo mpd[2139]: Mar 03 16:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 16:32:18 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:18 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:18 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:18 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:18 volumio-primo systemd[1]: Started Music Player Daemon. Mar 03 16:32:18 volumio-primo sudo[2097]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:18 volumio-primo volumio[778]: error: updateQueue error: null Mar 03 16:32:19 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 03 16:32:19 volumio-primo sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 03 16:32:19 volumio-primo sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:19 volumio-primo sudo[2181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 16:32:19 volumio-primo systemd[1]: Started MyVolumio SSH Tunnel. Mar 03 16:32:19 volumio-primo sudo[2175]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:19 volumio-primo sudo[2181]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:19 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:19 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:19 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:19 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:19 volumio-primo autossh[2188]: port set to 0, monitoring disabled Mar 03 16:32:19 volumio-primo autossh[2188]: starting ssh (count 1) Mar 03 16:32:19 volumio-primo autossh[2188]: ssh child pid is 2194 Mar 03 16:32:19 volumio-primo volumio[778]: info: Remote SSH Started Mar 03 16:32:19 volumio-primo systemd[1]: Stopping Music Player Daemon... Mar 03 16:32:19 volumio-primo systemd[1]: mpd.service: Succeeded. Mar 03 16:32:19 volumio-primo systemd[1]: Stopped Music Player Daemon. Mar 03 16:32:19 volumio-primo systemd[1]: Starting Music Player Daemon... Mar 03 16:32:19 volumio-primo volumio[778]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 03 16:32:19 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:19 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:19 volumio-primo sudo[2196]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 16:32:19 volumio-primo sudo[2196]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:19 volumio-primo sudo[2196]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:19 volumio-primo volumiossh-tunnel[2185]: Warning: Permanently added '[eu10.myvolumio.org]:2222,[46.101.141.203]:2222' (RSA) to the list of known hosts. Mar 03 16:32:19 volumio-primo sudo[2210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 03 16:32:19 volumio-primo sudo[2210]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:19 volumio-primo systemd[1]: Started Volumio Bluetooth Module. Mar 03 16:32:19 volumio-primo sudo[2210]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:19 volumio-primo sudo[2218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 03 16:32:19 volumio-primo volumio[778]: info: Volumio BT Module successfully started Mar 03 16:32:19 volumio-primo sudo[2218]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:19 volumio-primo volumiobt[2216]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 03 16:32:19 volumio-primo sudo[2218]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:19 volumio-primo volumiossh-tunnel[2185]: Warning: remote port forwarding failed for listen port 8091 Mar 03 16:32:20 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:20 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:20 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:20 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:20 volumio-primo mpd[2205]: Mar 03 16:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 16:32:20 volumio-primo systemd[1]: Started Music Player Daemon. Mar 03 16:32:20 volumio-primo sudo[2181]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:20 volumio-primo volumio[778]: error: updateQueue error: null Mar 03 16:32:20 volumio-primo sudo[2242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 03 16:32:20 volumio-primo sudo[2242]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:20 volumio-primo volumiobt[2216]: pulseaudio: no process found Mar 03 16:32:20 volumio-primo sudo[2242]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:21 volumio-primo dbus-daemon[543]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=2253 comm="/usr/bin/pulseaudio --high-priority=true ") Mar 03 16:32:21 volumio-primo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 03 16:32:21 volumio-primo volumio[778]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:32:21 volumio-primo kernel: IPVS: Creating netns size=1496 id=3 Mar 03 16:32:21 volumio-primo kernel: IPVS: ftp: loaded support on port[0] = 21 Mar 03 16:32:21 volumio-primo dbus-daemon[543]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 03 16:32:21 volumio-primo systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 03 16:32:21 volumio-primo rtkit-daemon[2254]: Successfully called chroot. Mar 03 16:32:21 volumio-primo rtkit-daemon[2254]: Successfully dropped privileges. Mar 03 16:32:21 volumio-primo rtkit-daemon[2254]: Successfully limited resources. Mar 03 16:32:21 volumio-primo rtkit-daemon[2254]: Running. Mar 03 16:32:21 volumio-primo dbus-daemon[543]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=2254 comm="/usr/lib/rtkit/rtkit-daemon ") Mar 03 16:32:21 volumio-primo rtkit-daemon[2254]: Canary thread running. Mar 03 16:32:21 volumio-primo rtkit-daemon[2254]: Watchdog thread running. Mar 03 16:32:21 volumio-primo systemd[1]: Starting Authorization Manager... Mar 03 16:32:21 volumio-primo volumio[778]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:32:21 volumio-primo polkitd[2258]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 03 16:32:21 volumio-primo dbus-daemon[543]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 03 16:32:21 volumio-primo systemd[1]: Started Authorization Manager. Mar 03 16:32:21 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:21 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:21 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:21 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:21 volumio-primo pulseaudio[2253]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 03 16:32:21 volumio-primo pulseaudio[2253]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 03 16:32:21 volumio-primo volumiobt[2216]: Applying permissions Mar 03 16:32:21 volumio-primo volumiobt[2216]: Setting BT discoverable and pairable Mar 03 16:32:21 volumio-primo bluetoothd[596]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource Mar 03 16:32:21 volumio-primo bluetoothd[596]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink Mar 03 16:32:21 volumio-primo pulseaudio[2253]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Mar 03 16:32:21 volumio-primo volumiobt[2216]: Agent registered Mar 03 16:32:21 volumio-primo volumiobt[2216]: [bluetooth]# -e power on Mar 03 16:32:21 volumio-primo volumiobt[2216]: Invalid command in menu main: -e Mar 03 16:32:21 volumio-primo volumiobt[2216]: Mar 03 16:32:21 volumio-primo volumiobt[2216]: Use "help" for a list of available commands in a menu. Mar 03 16:32:21 volumio-primo volumiobt[2216]: Use "menu " if you want to enter any submenu. Mar 03 16:32:21 volumio-primo volumiobt[2216]: Use "back" if you want to return to menu main. Mar 03 16:32:21 volumio-primo volumiobt[2216]: [bluetooth]# agent on Mar 03 16:32:21 volumio-primo volumiobt[2216]: Agent is already registered Mar 03 16:32:21 volumio-primo volumiobt[2216]: [bluetooth]# discoverable on Mar 03 16:32:21 volumio-primo volumiobt[2216]: [bluetooth]# pairable on Mar 03 16:32:21 volumio-primo volumiobt[2216]: [bluetooth]# agent NoInputNoOutput Mar 03 16:32:21 volumio-primo volumiobt[2216]: Agent is already registered Mar 03 16:32:21 volumio-primo volumiobt[2216]: [bluetooth]# default-agent Mar 03 16:32:21 volumio-primo volumiobt[2216]: [bluetooth]# quit Mar 03 16:32:21 volumio-primo volumiobt[2216]: [59B blob data] Mar 03 16:32:21 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 03 16:32:21 volumio-primo volumio[778]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Mar 03 16:32:21 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:21 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:22 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:22 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:22 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:22 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:23 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:23 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:23 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:23 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:23 volumio-primo volumio[778]: info: BOOT COMPLETED Mar 03 16:32:23 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 03 16:32:23 volumio-primo volumio[778]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 03 16:32:23 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 03 16:32:23 volumio-primo volumio[778]: info: Received Get System Version Mar 03 16:32:23 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 16:32:23 volumio-primo volumio[778]: info: Received Get System Info Mar 03 16:32:23 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 16:32:23 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 16:32:23 volumio-primo volumio[778]: info: Discovery: Getting this device information Mar 03 16:32:23 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:23 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:23 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 16:32:24 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:24 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:24 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:24 volumio-primo volumio[778]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Mar 03 16:32:24 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:24 volumio-primo volumio[778]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Mar 03 16:32:24 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:24 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:24 volumio-primo volumio[778]: info: CoreCommandRouter::volumioPause Mar 03 16:32:24 volumio-primo volumio[778]: info: CoreStateMachine::pause Mar 03 16:32:24 volumio-primo volumio[778]: info: mpdhttpout --- Volumio set on pause Mar 03 16:32:25 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:25 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:25 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:25 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:26 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:26 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:26 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:26 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:26 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 03 16:32:26 volumio-primo sudo[2286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 16:32:26 volumio-primo sudo[2286]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:26 volumio-primo systemd[1]: Stopping Music Player Daemon... Mar 03 16:32:26 volumio-primo volumio[778]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:32:26 volumio-primo systemd[1]: mpd.service: Succeeded. Mar 03 16:32:26 volumio-primo systemd[1]: Stopped Music Player Daemon. Mar 03 16:32:26 volumio-primo volumio[778]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:32:26 volumio-primo systemd[1]: Starting Music Player Daemon... Mar 03 16:32:26 volumio-primo volumio[778]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:32:26 volumio-primo sudo[2292]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 16:32:26 volumio-primo sudo[2292]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:32:26 volumio-primo sudo[2292]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:26 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:26 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:32:26 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 03 16:32:26 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 03 16:32:26 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 03 16:32:26 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 16:32:26 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 16:32:27 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:27 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:27 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:27 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:27 volumio-primo mpd[2301]: Mar 03 16:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 16:32:27 volumio-primo systemd[1]: Started Music Player Daemon. Mar 03 16:32:27 volumio-primo sudo[2286]: pam_unix(sudo:session): session closed for user root Mar 03 16:32:27 volumio-primo volumio[778]: error: updateQueue error: null Mar 03 16:32:28 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:28 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:28 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:28 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:29 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:29 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:29 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:29 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:30 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:30 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:30 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:30 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:31 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:31 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:31 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:31 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:32 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:32 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:32 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:32 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:32 volumio-primo systemd[1]: systemd-timedated.service: Succeeded. Mar 03 16:32:33 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:33 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:33 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:33 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:34 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:34 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:34 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:34 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:35 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:35 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:35 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:35 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:35 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 03 16:32:35 volumio-primo volumio[778]: info: Received Get System Info Mar 03 16:32:35 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 16:32:35 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 16:32:35 volumio-primo volumio[778]: info: Discovery: Getting this device information Mar 03 16:32:35 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetState Mar 03 16:32:35 volumio-primo volumio[778]: info: CorePlayQueue::getTrack 0 Mar 03 16:32:35 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 16:32:36 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:36 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:36 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:36 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:36 volumio-primo volumiologrotate[547]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Mar 03 16:32:36 volumio-primo volumiologrotate[547]: ls: cannot access 'PRIMO': No such file or directory Mar 03 16:32:37 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:37 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:37 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:37 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:38 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:38 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:38 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:38 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:39 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:39 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:39 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:39 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:40 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:40 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:40 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:40 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:41 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:41 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:41 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:41 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:42 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:42 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:42 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:42 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:43 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:43 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:43 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:43 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:44 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:44 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:44 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:44 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:45 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:45 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:45 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:45 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:46 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:46 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:46 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:46 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:47 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:47 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:47 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:47 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:48 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:48 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:48 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:48 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:49 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:49 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:49 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:49 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:50 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:50 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:50 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:50 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:51 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:51 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:51 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:51 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:52 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:52 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:52 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:52 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:53 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:53 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:53 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:53 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:54 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:54 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:54 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:54 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:55 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:55 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:55 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:55 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:56 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:56 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:56 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:56 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:57 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:57 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:57 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:57 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:58 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:58 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:58 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:58 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:59 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:59 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:32:59 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:32:59 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:00 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:00 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:00 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:00 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:01 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:01 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:01 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:01 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:02 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:02 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:02 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:02 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:33:03 volumio-primo volumio[778]: info: Retrieving Cloud Streaming UI Mar 03 16:33:03 volumio-primo volumio[778]: info: Getting Tidal Cloud Configuration Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:33:03 volumio-primo volumio[778]: info: Getting Qobuz Cloud Configuration Mar 03 16:33:03 volumio-primo volumio[778]: info: Asking plugin for UI Config Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:33:03 volumio-primo volumio[778]: info: Getting Spotify Cloud Configuration Mar 03 16:33:03 volumio-primo volumio[778]: info: Asking plugin for UI Config Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:33:03 volumio-primo volumio[778]: info: Saving Spotify Acccount Mar 03 16:33:03 volumio-primo volumio[778]: info: Got it Mar 03 16:33:03 volumio-primo volumio[778]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 03 16:33:03 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:03 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:03 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:03 volumio-primo volumio[778]: info: Got Tidal Cloud Configuration Mar 03 16:33:03 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:03 volumio-primo volumio[778]: info: Got it Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 16:33:03 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 03 16:33:04 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:04 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:04 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:04 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:05 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:05 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:05 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:05 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:06 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:06 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:06 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:06 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:07 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:07 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:07 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:07 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:07 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 16:33:08 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:08 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:08 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:08 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:09 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:09 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:09 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:09 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:10 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:10 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:10 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:10 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:11 volumio-primo volumio[778]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Mar 03 16:33:11 volumio-primo sudo[2457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Mycloud Mar 03 16:33:11 volumio-primo sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:33:11 volumio-primo sudo[2457]: pam_unix(sudo:session): session closed for user root Mar 03 16:33:11 volumio-primo volumio[778]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 16:33:11 volumio-primo volumio[778]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Mycloud' Mar 03 16:33:11 volumio-primo volumio[778]: at Object.rmdirSync (fs.js:917:10) Mar 03 16:33:11 volumio-primo volumio[778]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Mar 03 16:33:11 volumio-primo volumio[778]: at ChildProcess.exithandler (child_process.js:374:7) Mar 03 16:33:11 volumio-primo volumio[778]: at ChildProcess.emit (events.js:400:28) Mar 03 16:33:11 volumio-primo volumio[778]: at maybeClose (internal/child_process.js:1088:16) Mar 03 16:33:11 volumio-primo volumio[778]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) { Mar 03 16:33:11 volumio-primo volumio[778]: errno: -16, Mar 03 16:33:11 volumio-primo volumio[778]: syscall: 'rmdir', Mar 03 16:33:11 volumio-primo volumio[778]: code: 'EBUSY', Mar 03 16:33:11 volumio-primo volumio[778]: path: '/mnt/NAS/Mycloud' Mar 03 16:33:11 volumio-primo volumio[778]: } Mar 03 16:33:11 volumio-primo volumio[778]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 16:33:11 volumio-primo lircd[1372]: lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:11 volumio-primo lircd[1372]: lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:11 volumio-primo lircd-0.10.1[1372]: Error: could not get file information for /dev/lirc0 Mar 03 16:33:11 volumio-primo lircd-0.10.1[1372]: default_init(): No such file or directory Mar 03 16:33:11 volumio-primo sudo[2479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 16:32 Mar 03 16:33:11 volumio-primo sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:11:38 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="932fc3546f7cc8faa2b4d349036752dd"