-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Fri 2024-11-29 16:10:22 CET. -- Nov 29 16:09:44 volumio6 systemd-timedated[1433]: Changed local time to Fri Nov 29 16:09:44 2024 Nov 29 16:09:44 volumio6 sudo[1427]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:44 volumio6 systemd[1]: Starting Daily apt download activities... Nov 29 16:09:44 volumio6 systemd[1]: Starting Daily man-db regeneration... Nov 29 16:09:44 volumio6 volumio-time-update[609]: volumio-time-update-util: System time updated successfully. Nov 29 16:09:44 volumio6 systemd[1]: Started Volumio Time Update Utility. Nov 29 16:09:44 volumio6 systemd[1]: Reached target Multi-User System. Nov 29 16:09:44 volumio6 systemd[1]: Reached target Graphical Interface. Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.042280, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 29 16:09:44 volumio6 nmbd[736]: Packet send failed to 169.254.255.255(138) ERRNO=Invalid argument Nov 29 16:09:44 volumio6 systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.042719, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 29 16:09:44 volumio6 nmbd[736]: Packet send failed to 127.0.0.1(137) ERRNO=Invalid argument Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.042808, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Nov 29 16:09:44 volumio6 nmbd[736]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.042884, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 29 16:09:44 volumio6 nmbd[736]: Packet send failed to 127.0.0.1(137) ERRNO=Invalid argument Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.042963, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Nov 29 16:09:44 volumio6 nmbd[736]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.043035, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 29 16:09:44 volumio6 nmbd[736]: Packet send failed to 127.0.0.1(137) ERRNO=Invalid argument Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.043101, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Nov 29 16:09:44 volumio6 nmbd[736]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.043171, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 29 16:09:44 volumio6 nmbd[736]: Packet send failed to 127.0.0.1(137) ERRNO=Invalid argument Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.043229, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Nov 29 16:09:44 volumio6 nmbd[736]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.043310, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 29 16:09:44 volumio6 nmbd[736]: Packet send failed to 127.0.0.1(137) ERRNO=Invalid argument Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.043376, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Nov 29 16:09:44 volumio6 nmbd[736]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Nov 29 16:09:44 volumio6 nmbd[736]: [2024/11/29 16:09:44.044348, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 29 16:09:44 volumio6 nmbd[736]: Packet send failed to 169.254.255.255(138) ERRNO=Invalid argument Nov 29 16:09:44 volumio6 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 29 16:09:44 volumio6 systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 29 16:09:44 volumio6 volumio[1216]: info: MYVOLUMIO Environment detected Nov 29 16:09:44 volumio6 ntpd[1365]: Soliciting pool server 93.44.243.48 Nov 29 16:09:44 volumio6 ntpd[1365]: Soliciting pool server 162.159.200.1 Nov 29 16:09:44 volumio6 ntpd[1365]: Soliciting pool server 212.6.50.243 Nov 29 16:09:44 volumio6 volumio[1216]: info: Plugin folders cleanup Nov 29 16:09:44 volumio6 volumio[1216]: info: Scanning into folder /volumio/app/plugins/ Nov 29 16:09:44 volumio6 volumio[1216]: info: Scanning category audio_interface Nov 29 16:09:44 volumio6 volumio[1216]: info: Scanning category miscellanea Nov 29 16:09:44 volumio6 volumio[1216]: info: Scanning category music_service Nov 29 16:09:44 volumio6 volumio[1216]: info: Scanning category plugins.json Nov 29 16:09:44 volumio6 volumio[1216]: info: Scanning category system_controller Nov 29 16:09:44 volumio6 volumio[1216]: info: Scanning category user_interface Nov 29 16:09:44 volumio6 volumio[1216]: info: Scanning into folder /data/plugins/ Nov 29 16:09:44 volumio6 volumio[1216]: info: Plugin folders cleanup completed Nov 29 16:09:44 volumio6 volumio[1216]: info: ------------------------------------------- Nov 29 16:09:44 volumio6 volumio[1216]: info: ----- Core plugins startup ---- Nov 29 16:09:44 volumio6 volumio[1216]: info: ------------------------------------------- Nov 29 16:09:44 volumio6 volumio[1216]: info: Loading plugins from folder /volumio/app/plugins/ Nov 29 16:09:44 volumio6 volumio[1216]: info: Adding plugin upnp to MyMusic Plugins Nov 29 16:09:44 volumio6 volumio[1216]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 29 16:09:44 volumio6 volumio[1216]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 29 16:09:44 volumio6 volumio[1216]: info: Loading plugins from folder /data/plugins/ Nov 29 16:09:44 volumio6 volumio[1216]: info: Loading plugin "system"... Nov 29 16:09:45 volumio6 volumio[1216]: info: Loading plugin "appearance"... Nov 29 16:09:45 volumio6 systemd[1]: man-db.service: Succeeded. Nov 29 16:09:45 volumio6 systemd[1]: Started Daily man-db regeneration. Nov 29 16:09:45 volumio6 systemd[1]: apt-daily.service: Succeeded. Nov 29 16:09:45 volumio6 systemd[1]: Started Daily apt download activities. Nov 29 16:09:45 volumio6 systemd[1]: Starting Daily apt upgrade and clean activities... Nov 29 16:09:45 volumio6 systemd[1]: apt-daily-upgrade.service: Succeeded. Nov 29 16:09:45 volumio6 systemd[1]: Started Daily apt upgrade and clean activities. Nov 29 16:09:45 volumio6 systemd[1]: Startup finished in 17.716s (kernel) + 28.073s (userspace) = 45.790s. Nov 29 16:09:45 volumio6 ntpd[1365]: Soliciting pool server 37.247.53.178 Nov 29 16:09:45 volumio6 ntpd[1365]: Soliciting pool server 89.46.74.148 Nov 29 16:09:45 volumio6 ntpd[1365]: Soliciting pool server 45.135.69.197 Nov 29 16:09:45 volumio6 ntpd[1365]: Soliciting pool server 129.152.16.145 Nov 29 16:09:45 volumio6 volumio-remote-updater[606]: [2024-11-29 16:09:45] [connect] Successful connection Nov 29 16:09:46 volumio6 volumio[1216]: info: Loading plugin "network"... Nov 29 16:09:46 volumio6 volumio[1216]: info: Refreshing Cached IP Addresses Nov 29 16:09:46 volumio6 sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 16:09:46 volumio6 sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 16:09:46 volumio6 volumio[1216]: info: Loading plugin "services"... Nov 29 16:09:46 volumio6 volumio[1216]: info: Loading plugin "alsa_controller"... Nov 29 16:09:46 volumio6 sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:46 volumio6 sudo[1562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 29 16:09:46 volumio6 sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:46 volumio6 sudo[1554]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:46 volumio6 sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:46 volumio6 sudo[1556]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:46 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 16:09:46 volumio6 volumio[1216]: info: Loading plugin "wizard"... Nov 29 16:09:46 volumio6 volumio[1216]: info: Loading plugin "networkfs"... Nov 29 16:09:46 volumio6 volumio[1216]: info: Starting Udev Watcher for removable devices Nov 29 16:09:46 volumio6 volumio[1216]: info: Ignoring mount for partition: BOOT Nov 29 16:09:46 volumio6 volumio[1216]: info: Ignoring mount for partition: volumio Nov 29 16:09:46 volumio6 volumio[1216]: info: Ignoring mount for partition: volumio_data Nov 29 16:09:46 volumio6 volumio[1216]: info: Mounting Device BOOT Nov 29 16:09:46 volumio6 sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/BOOT -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 29 16:09:46 volumio6 sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:46 volumio6 sudo[1562]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:46 volumio6 dhcpcd[1167]: wlan0: leased 192.168.1.8 for 3600 seconds Nov 29 16:09:46 volumio6 avahi-daemon[602]: Registering new address record for 192.168.1.8 on wlan0.IPv4. Nov 29 16:09:46 volumio6 dhcpcd[1167]: wlan0: adding route to 192.168.1.0/24 Nov 29 16:09:46 volumio6 dhcpcd[1167]: wlan0: adding default route via 192.168.1.1 Nov 29 16:09:46 volumio6 dhcpcd[1167]: eth0: deleting route to 169.254.0.0/16 Nov 29 16:09:46 volumio6 ntpd[1365]: ntpd exiting on signal 15 (Terminated) Nov 29 16:09:46 volumio6 ntpd[1365]: 216.128.27.239 local addr 192.168.1.5 -> Nov 29 16:09:46 volumio6 ntpd[1365]: 212.45.144.206 local addr 192.168.1.5 -> Nov 29 16:09:46 volumio6 systemd[1]: Stopping Network Time Service... Nov 29 16:09:46 volumio6 ntpd[1365]: 185.19.184.35 local addr 192.168.1.5 -> Nov 29 16:09:46 volumio6 ntpd[1365]: 162.159.200.1 local addr 192.168.1.5 -> Nov 29 16:09:46 volumio6 ntpd[1365]: 93.44.243.48 local addr 192.168.1.5 -> Nov 29 16:09:46 volumio6 ntpd[1365]: 212.6.50.243 local addr 192.168.1.5 -> Nov 29 16:09:46 volumio6 ntpd[1365]: 37.247.53.178 local addr 192.168.1.5 -> Nov 29 16:09:46 volumio6 ntpd[1365]: 89.46.74.148 local addr 192.168.1.5 -> Nov 29 16:09:46 volumio6 ntpd[1365]: 129.152.16.145 local addr 192.168.1.5 -> Nov 29 16:09:46 volumio6 ntpd[1365]: 45.135.69.197 local addr 192.168.1.5 -> Nov 29 16:09:46 volumio6 sudo[1610]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:46 volumio6 volumio[1216]: info: Ignoring mount for partition: volumio Nov 29 16:09:46 volumio6 volumio[1216]: info: Ignoring mount for partition: volumio_data Nov 29 16:09:46 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 16:09:46 volumio6 volumio[1216]: info: Loading plugin "volumio_command_line_client"... Nov 29 16:09:46 volumio6 volumio[1216]: info: Loading plugin "upnp"... Nov 29 16:09:46 volumio6 volumio[1216]: info: [1732892986717] Starting Upmpd Daemon Nov 29 16:09:46 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 16:09:46 volumio6 volumio[1216]: info: Loading plugin "my_music"... Nov 29 16:09:46 volumio6 volumio[1216]: info: Loading plugin "mpd"... Nov 29 16:09:46 volumio6 systemd[1]: ntp.service: Succeeded. Nov 29 16:09:46 volumio6 systemd[1]: Stopped Network Time Service. Nov 29 16:09:46 volumio6 systemd[1]: Starting Network Time Service... Nov 29 16:09:46 volumio6 ntpd[1655]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 29 16:09:46 volumio6 ntpd[1655]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 29 16:09:46 volumio6 systemd[1]: Started Network Time Service. Nov 29 16:09:46 volumio6 ntpd[1661]: proto: precision = 0.583 usec (-21) Nov 29 16:09:46 volumio6 avahi-daemon[602]: Withdrawing address record for 169.254.51.36 on wlan0. Nov 29 16:09:46 volumio6 avahi-daemon[602]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.51.36. Nov 29 16:09:46 volumio6 avahi-daemon[602]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.8. Nov 29 16:09:46 volumio6 dhcpcd[1167]: wlan0: deleting route to 169.254.0.0/16 Nov 29 16:09:46 volumio6 ntpd[1661]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 29 16:09:46 volumio6 ntpd[1661]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 29 16:09:46 volumio6 ntpd[1661]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 703 days ago Nov 29 16:09:46 volumio6 ntpd[1661]: Listen and drop on 0 v6wildcard [::]:123 Nov 29 16:09:46 volumio6 ntpd[1661]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 29 16:09:46 volumio6 ntpd[1661]: Listen normally on 2 lo 127.0.0.1:123 Nov 29 16:09:46 volumio6 ntpd[1661]: Listen normally on 3 eth0 192.168.1.5:123 Nov 29 16:09:46 volumio6 ntpd[1661]: Listen normally on 4 wlan0 192.168.1.8:123 Nov 29 16:09:46 volumio6 ntpd[1661]: Listening on routing socket on fd #21 for interface updates Nov 29 16:09:46 volumio6 ntpd[1661]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Nov 29 16:09:46 volumio6 ntpd[1661]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "upnp_browser"... Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "alarm-clock"... Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "airplay_emulation"... Nov 29 16:09:47 volumio6 volumio[1216]: info: Starting Shairport Sync Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "last_100"... Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "webradio"... Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "i2s_dacs"... Nov 29 16:09:47 volumio6 volumio[1216]: info: I2S DAC not set, start Auto-detection Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "volumiodiscovery"... Nov 29 16:09:47 volumio6 volumio[1216]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 16:09:47 volumio6 volumio[1216]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 16:09:47 volumio6 volumio[1216]: *** WARNING *** For more information see Nov 29 16:09:47 volumio6 volumio[1216]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 16:09:47 volumio6 volumio[1216]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 16:09:47 volumio6 node[1216]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 16:09:47 volumio6 volumio[1216]: *** WARNING *** For more information see Nov 29 16:09:47 volumio6 node[1216]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 16:09:47 volumio6 node[1216]: *** WARNING *** For more information see Nov 29 16:09:47 volumio6 node[1216]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 16:09:47 volumio6 node[1216]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 16:09:47 volumio6 node[1216]: *** WARNING *** For more information see Nov 29 16:09:47 volumio6 volumio[1216]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 29 16:09:47 volumio6 volumio[1216]: info: Discovery: Started advertising with name: Volumio6 Nov 29 16:09:47 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "outputs"... Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "albumart"... Nov 29 16:09:47 volumio6 volumio[1216]: info: Plugin example_plugin is not enabled Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "inputs"... Nov 29 16:09:47 volumio6 volumio[1216]: info: Loading plugin "updater_comm"... Nov 29 16:09:47 volumio6 ntpd[1661]: Soliciting pool server 216.128.27.239 Nov 29 16:09:48 volumio6 volumio[1216]: info: Plugin mpdemulation is not enabled Nov 29 16:09:48 volumio6 volumio[1216]: info: Loading plugin "rest_api"... Nov 29 16:09:48 volumio6 volumio[1216]: Forking 3 albumart workers Nov 29 16:09:48 volumio6 volumio[1216]: info: Loading plugin "websocket"... Nov 29 16:09:48 volumio6 volumio[1216]: info: Starting Socket.io Server version 2.3.0 Nov 29 16:09:48 volumio6 volumio[1216]: info: Loading i18n strings for locale it Nov 29 16:09:48 volumio6 volumio[1216]: Updating browse sources language Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 16:09:48 volumio6 nmbd[736]: [2024/11/29 16:09:48.074539, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 29 16:09:48 volumio6 nmbd[736]: Packet send failed to 169.254.255.255(138) ERRNO=Invalid argument Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::initPlayerControls Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: Express server listening on port 3000 Nov 29 16:09:48 volumio6 volumio[1216]: [Metrics] WebUI: 6s 196.02ms Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreStateMachine::resetVolumioState Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreStateMachine::getcurrentVolume Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:09:48 volumio6 volumio[1216]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 29 16:09:48 volumio6 volumio[1216]: info: Completed loading Core Plugins Nov 29 16:09:48 volumio6 volumio[1216]: info: Preparing to generate the ALSA configuration file Nov 29 16:09:48 volumio6 volumio[1216]: info: Volumio Network Manager: Network status updated: 3 Nov 29 16:09:48 volumio6 volumio[1216]: Starting albumart workers Nov 29 16:09:48 volumio6 volumio-remote-updater[606]: [2024-11-29 16:09:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732892985 101 Nov 29 16:09:48 volumio6 volumio[1216]: Starting albumart workers Nov 29 16:09:48 volumio6 ntpd[1661]: Soliciting pool server 212.45.144.206 Nov 29 16:09:48 volumio6 ntpd[1661]: Soliciting pool server 185.19.184.35 Nov 29 16:09:48 volumio6 volumio[1216]: Starting albumart workers Nov 29 16:09:48 volumio6 volumio[1216]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreStateMachine::setRepeat false single undefined Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:09:48 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreStateMachine::setRandom null Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:09:48 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:09:48 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:09:48 volumio6 volumio[1216]: info: Setting Device type: Tinkerboard Nov 29 16:09:49 volumio6 volumio[1216]: info: VolumeController:: Volume=42 Mute =false Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:09:49 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreStateMachine::updateTrackBlock Nov 29 16:09:49 volumio6 volumio[1216]: info: CorePlayQueue::getTrackBlock Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 16:09:49 volumio6 volumio[1216]: info: Asound.conf file written Nov 29 16:09:49 volumio6 sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 29 16:09:49 volumio6 sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:49 volumio6 sudo[1738]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:49 volumio6 volumio[1216]: info: Output device has changed, restarting MPD Nov 29 16:09:49 volumio6 volumio[1216]: info: Output device has changed, restarting Shairport Sync Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:09:49 volumio6 sudo[1758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 16:09:49 volumio6 sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 16:09:49 volumio6 sudo[1758]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:49 volumio6 sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:49 volumio6 sudo[1758]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:49 volumio6 volumio[1216]: info: ___________ START PLUGINS ___________ Nov 29 16:09:49 volumio6 volumio[1216]: info: ControllerMpd::onStart: Initializing MPD Nov 29 16:09:49 volumio6 volumio[1216]: info: Creating MPD Configuration file Nov 29 16:09:49 volumio6 systemd[1]: Stopping Music Player Daemon... Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 16:09:49 volumio6 volumio[1216]: info: [1732892989317] CoreMusicLibrary::Adding element Server multimediali Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 16:09:49 volumio6 sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 16:09:49 volumio6 sudo[1788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:09:49 volumio6 sudo[1788]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:49 volumio6 sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:49 volumio6 sudo[1786]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 16:09:49 volumio6 volumio[1216]: info: [1732892989419] CoreMusicLibrary::Adding element Last_100 Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 16:09:49 volumio6 volumio[1216]: info: [1732892989422] CoreMusicLibrary::Adding element Webradio Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 16:09:49 volumio6 volumio[1216]: info: Initializing BBC Radios Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: Volumio Calling Home Nov 29 16:09:49 volumio6 volumio[1216]: info: Discovery: adding 5b1fcfa2-2cf2-45ee-9f36-0b98c6583b8b Nov 29 16:09:49 volumio6 volumio[1216]: info: Discovery: Found device Volumio6 Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:09:49 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:09:49 volumio6 volumio[1216]: info: Discovery: this is already registered, 5b1fcfa2-2cf2-45ee-9f36-0b98c6583b8b Nov 29 16:09:49 volumio6 volumio[1216]: info: Discovery: Found device Volumio6 Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:09:49 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:09:49 volumio6 volumio[1216]: info: MPD Permissions set Nov 29 16:09:49 volumio6 volumio[1216]: info: MPD Permissions set Nov 29 16:09:49 volumio6 volumio[1216]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 systemd[1]: mpd.service: Succeeded. Nov 29 16:09:49 volumio6 systemd[1]: Stopped Music Player Daemon. Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 systemd[1]: Starting Music Player Daemon... Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:09:49 volumio6 ntpd[1661]: Soliciting pool server 93.94.88.50 Nov 29 16:09:49 volumio6 ntpd[1661]: Soliciting pool server 162.159.200.1 Nov 29 16:09:49 volumio6 ntpd[1661]: Soliciting pool server 212.6.50.243 Nov 29 16:09:49 volumio6 volumio[1216]: info: VolumeController:: Volume=42 Mute =false Nov 29 16:09:49 volumio6 sudo[1828]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:09:49 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:09:49 volumio6 volumio[1216]: info: Volumio called home Nov 29 16:09:49 volumio6 sudo[1828]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:49 volumio6 volumio[1216]: info: Starting Shairport Sync Nov 29 16:09:49 volumio6 sudo[1828]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:49 volumio6 volumio[1216]: info: Starting Shairport Sync Nov 29 16:09:49 volumio6 volumio[1216]: info: Starting Shairport Sync Nov 29 16:09:49 volumio6 sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 16:09:49 volumio6 sudo[1853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 16:09:49 volumio6 sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:49 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:09:49 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:09:49 volumio6 sudo[1857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 16:09:49 volumio6 sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:49 volumio6 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 16:09:49 volumio6 systemd[1]: shairport-sync.service: Succeeded. Nov 29 16:09:49 volumio6 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 16:09:49 volumio6 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 16:09:49 volumio6 sudo[1849]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:49 volumio6 sudo[1857]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:49 volumio6 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 16:09:49 volumio6 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 29 16:09:49 volumio6 systemd[1]: shairport-sync.service: Succeeded. Nov 29 16:09:49 volumio6 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 16:09:49 volumio6 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 16:09:49 volumio6 sudo[1853]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:49 volumio6 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 16:09:49 volumio6 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 29 16:09:49 volumio6 systemd[1]: shairport-sync.service: Succeeded. Nov 29 16:09:49 volumio6 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 16:09:49 volumio6 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 16:09:49 volumio6 sudo[1857]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:49 volumio6 volumio[1216]: info: Shairport-Sync Started Nov 29 16:09:49 volumio6 volumio[1216]: Error adding Membership: Error: addMembership EINVAL Nov 29 16:09:49 volumio6 volumio[1216]: info: Shairport-Sync Started Nov 29 16:09:50 volumio6 volumio[1216]: info: Shairport-Sync Started Nov 29 16:09:50 volumio6 ntpd[1661]: Soliciting pool server 89.46.74.148 Nov 29 16:09:50 volumio6 ntpd[1661]: Soliciting pool server 89.46.74.148 Nov 29 16:09:50 volumio6 ntpd[1661]: Soliciting pool server 45.135.69.197 Nov 29 16:09:50 volumio6 ntpd[1661]: Soliciting pool server 162.159.200.123 Nov 29 16:09:50 volumio6 mpd[1876]: Nov 29 16:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 16:09:50 volumio6 systemd[1]: Started Music Player Daemon. Nov 29 16:09:50 volumio6 sudo[1788]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:50 volumio6 sudo[1761]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:50 volumio6 volumio[1216]: info: Completed starting Core Plugins Nov 29 16:09:50 volumio6 volumio[1216]: info: ------------------------------------------- Nov 29 16:09:50 volumio6 volumio[1216]: info: ----- MyVolumio plugins startup ---- Nov 29 16:09:50 volumio6 volumio[1216]: info: ------------------------------------------- Nov 29 16:09:50 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 29 16:09:50 volumio6 volumio[1216]: info: MPD running with PID1876 Nov 29 16:09:50 volumio6 volumio[1216]: ,establishing connection Nov 29 16:09:50 volumio6 volumio[1216]: error: MPD error: The expression evaluated to a falsy value: Nov 29 16:09:50 volumio6 volumio[1216]: assert.ok(self.idling) Nov 29 16:09:50 volumio6 volumio[1216]: error: The expression evaluated to a falsy value: Nov 29 16:09:50 volumio6 volumio[1216]: assert.ok(self.idling) Nov 29 16:09:50 volumio6 volumio[1216]: error: MPD error: The expression evaluated to a falsy value: Nov 29 16:09:50 volumio6 volumio[1216]: assert.ok(self.idling) Nov 29 16:09:50 volumio6 volumio[1216]: error: The expression evaluated to a falsy value: Nov 29 16:09:50 volumio6 volumio[1216]: assert.ok(self.idling) Nov 29 16:09:50 volumio6 volumio[1216]: error: updateQueue error: null Nov 29 16:09:51 volumio6 systemd[1]: systemd-fsckd.service: Succeeded. Nov 29 16:09:51 volumio6 ntpd[1661]: Soliciting pool server 151.22.209.90 Nov 29 16:09:51 volumio6 ntpd[1661]: Soliciting pool server 37.247.53.178 Nov 29 16:09:52 volumio6 ntpd[1661]: Soliciting pool server 129.152.16.145 Nov 29 16:09:52 volumio6 ntpd[1661]: Soliciting pool server 2603:c027:2:c801:1979::1 Nov 29 16:09:53 volumio6 ntpd[1661]: Soliciting pool server 93.94.88.51 Nov 29 16:09:53 volumio6 systemd[1]: systemd-hostnamed.service: Succeeded. Nov 29 16:09:56 volumio6 ntpd[1661]: receive: Unexpected origin timestamp 0xeaf457c3.c18f2b46 does not match aorg 0000000000.00000000 from server@93.94.88.51 xmt 0xeaf457c4.53136e0c Nov 29 16:09:56 volumio6 ntpd[1661]: receive: Unexpected origin timestamp 0xeaf457c3.c197984e does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xeaf457c4.530dfee6 Nov 29 16:09:56 volumio6 ntpd[1661]: receive: Unexpected origin timestamp 0xeaf457c3.c196704d does not match aorg 0000000000.00000000 from server@45.135.69.197 xmt 0xeaf457c4.57478534 Nov 29 16:09:57 volumio6 sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 16:09:57 volumio6 sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:57 volumio6 sudo[1990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 16:09:57 volumio6 sudo[1987]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:57 volumio6 sudo[1990]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:57 volumio6 sudo[1993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 29 16:09:57 volumio6 sudo[1990]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:57 volumio6 sudo[1993]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:09:57 volumio6 systemd[1]: Started UPnP Renderer front-end to MPD. Nov 29 16:09:57 volumio6 sudo[1993]: pam_unix(sudo:session): session closed for user root Nov 29 16:09:57 volumio6 volumio[1216]: info: Upmpdcli Daemon Started Nov 29 16:09:57 volumio6 volumio[2013]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 29 16:09:59 volumio6 volumio[2013]: .................................++++ Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 29 16:09:59 volumio6 volumio[1216]: info: Adding plugin bluetooth to MyMusic Plugins Nov 29 16:09:59 volumio6 volumio[1216]: info: Adding plugin multiroom to MyMusic Plugins Nov 29 16:09:59 volumio6 volumio[1216]: info: Adding plugin metavolumio to MyMusic Plugins Nov 29 16:09:59 volumio6 volumio[1216]: info: Adding plugin cd_controller to MyMusic Plugins Nov 29 16:09:59 volumio6 volumio[1216]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 29 16:09:59 volumio6 volumio[1216]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 29 16:09:59 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 29 16:10:01 volumio6 volumio[2013]: ..............................................++++ Nov 29 16:10:01 volumio6 volumio[2013]: e is 65537 (0x010001) Nov 29 16:10:01 volumio6 volumio[2013]: writing RSA key Nov 29 16:10:02 volumio6 volumio[1216]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 29 16:10:02 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 29 16:10:02 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:02 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:02 volumio6 volumio[1216]: info: Starting MyVolumio Remote Streaming Endpoints Nov 29 16:10:02 volumio6 volumio[1216]: info: MyVolumio login type: Token Nov 29 16:10:02 volumio6 volumio[1216]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 29 16:10:02 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 29 16:10:03 volumio6 volumio[1216]: info: Starting Streaming Service Transparent Proxy Nov 29 16:10:03 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 29 16:10:03 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 29 16:10:03 volumio6 volumio[1216]: info: Streaming services startup Nov 29 16:10:03 volumio6 volumio[1216]: info: Starting Streaming Daemon Nov 29 16:10:03 volumio6 sudo[2130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 29 16:10:03 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 29 16:10:03 volumio6 sudo[2130]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:03 volumio6 sudo[2130]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:03 volumio6 volumio[1216]: error: Cannot start Volumio Streaming Daemon Nov 29 16:10:03 volumio6 volumio[1216]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 29 16:10:03 volumio6 volumio[1216]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 29 16:10:03 volumio6 volumio[1216]: error: MyVolumio Custom Token format not valid, refreshing it Nov 29 16:10:04 volumio6 volumio[1216]: STREAMING PROXY: Starting server on port 3245 Nov 29 16:10:04 volumio6 volumio[1216]: Node JS runtime: 14 Nov 29 16:10:04 volumio6 volumio[1216]: info: MyVolumio login type: Token Nov 29 16:10:05 volumio6 volumio[1216]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 29 16:10:05 volumio6 volumio[1216]: info: MyVolumio token set successfully Nov 29 16:10:05 volumio6 volumio[1216]: info: MYVOLUMIO: Adding device Nov 29 16:10:05 volumio6 volumio[1216]: info: MYVOLUMIO: Evaluating Server Nov 29 16:10:05 volumio6 volumio[1216]: info: MyVolumio Plan changed: superstar Nov 29 16:10:05 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Nov 29 16:10:05 volumio6 volumio[1216]: info: Adding audio output: Nov 29 16:10:05 volumio6 volumio[1216]: info: MYVOLUMIO: Adding device Nov 29 16:10:05 volumio6 volumio[1216]: info: MYVOLUMIO: Evaluating Server Nov 29 16:10:06 volumio6 volumio[1216]: info: Remote config written successfully Nov 29 16:10:06 volumio6 volumio[1216]: info: Starting Tunnel 1 Nov 29 16:10:06 volumio6 volumio[1216]: info: Starting Tunnel Connection Checker Nov 29 16:10:06 volumio6 volumio[1216]: info: Completed starting MyVolumio Plugin Nov 29 16:10:06 volumio6 volumio[1216]: info: MYVolumio Device enabled Nov 29 16:10:06 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 29 16:10:06 volumio6 volumio[1216]: info: MyVolumio status changed Nov 29 16:10:06 volumio6 volumio[1216]: info: Streaming services startup Nov 29 16:10:06 volumio6 volumio[1216]: info: Starting Streaming Daemon Nov 29 16:10:06 volumio6 sudo[2198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 29 16:10:06 volumio6 volumio[1216]: info: Setting Geolocation for MyVolumio to eu6 Nov 29 16:10:06 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:06 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:06 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:06 volumio6 sudo[2198]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:06 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 29 16:10:06 volumio6 volumio[1216]: error: [MyVolumio PluginManager] Cache data is invalid! Nov 29 16:10:06 volumio6 sudo[2198]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:06 volumio6 volumio[1216]: error: Cannot start Volumio Streaming Daemon Nov 29 16:10:06 volumio6 volumio[1216]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 29 16:10:06 volumio6 volumio[1216]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 29 16:10:06 volumio6 volumio[1216]: info: Setting Geolocation for MyVolumio to eu6 Nov 29 16:10:06 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:06 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:06 volumio6 volumio[1216]: info: Successfully Added MyVolumio device Nov 29 16:10:06 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 29 16:10:07 volumio6 volumio[1216]: info: [1732893007095] Starting BluetoothController Nov 29 16:10:07 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Nov 29 16:10:08 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 29 16:10:08 volumio6 sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 29 16:10:08 volumio6 sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:08 volumio6 sudo[2226]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:08 volumio6 volumio[1216]: info: MRS: MultiRoom plugin initialized Nov 29 16:10:08 volumio6 volumio[1216]: info: MRS: STOPPING SNAPCLIENT Nov 29 16:10:08 volumio6 volumio[1216]: info: MRS: Snap server stop Nov 29 16:10:08 volumio6 volumio[1216]: info: MRS: STOPPING volumioStreaming Nov 29 16:10:08 volumio6 sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 29 16:10:08 volumio6 sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:08 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 29 16:10:08 volumio6 sudo[2252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 29 16:10:08 volumio6 sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 29 16:10:08 volumio6 sudo[2261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 29 16:10:08 volumio6 sudo[2252]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:08 volumio6 sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:08 volumio6 sudo[2249]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:08 volumio6 sudo[2261]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:08 volumio6 sudo[2261]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:08 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Nov 29 16:10:08 volumio6 sudo[2252]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:08 volumio6 sudo[2256]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:08 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 29 16:10:08 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 29 16:10:08 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 29 16:10:08 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 29 16:10:08 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 29 16:10:08 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 29 16:10:08 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 29 16:10:08 volumio6 volumio[1216]: info: Preparing to generate the ALSA configuration file Nov 29 16:10:08 volumio6 volumio[1216]: info: Updating MyVolumio device info Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: Updating MyVolumio device info Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 29 16:10:09 volumio6 volumio[1216]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 29 16:10:09 volumio6 volumio[1216]: info: Reading ALSA contributions from plugins. Nov 29 16:10:09 volumio6 volumio[1216]: info: Successfully Added MyVolumio device Nov 29 16:10:09 volumio6 volumio[1216]: info: MRS: Removed streaming files Nov 29 16:10:09 volumio6 volumio[1216]: info: MRS: volumioStreaming STOPPED Nov 29 16:10:09 volumio6 volumio[1216]: info: MRS: SNAPSERVER STOPPED Nov 29 16:10:09 volumio6 volumio[1216]: info: MRS: SNAPCLIENT STOPPED Nov 29 16:10:09 volumio6 volumio[1216]: 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: 3 Nov 29 16:10:09 volumio6 volumio[1216]: 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 Nov 29 16:10:09 volumio6 volumio[1216]: info: Asound.conf file written Nov 29 16:10:09 volumio6 sudo[2289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 29 16:10:09 volumio6 sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:09 volumio6 sudo[2289]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:09 volumio6 volumio[1216]: info: Output device has changed, restarting MPD Nov 29 16:10:09 volumio6 volumio[1216]: info: Output device has changed, restarting Shairport Sync Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:09 volumio6 sudo[2301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 16:10:09 volumio6 sudo[2303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 16:10:09 volumio6 sudo[2301]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:09 volumio6 sudo[2301]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:09 volumio6 sudo[2303]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:09 volumio6 volumio[1216]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 29 16:10:09 volumio6 systemd[1]: Stopping Music Player Daemon... Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 16:10:09 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 29 16:10:09 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output for this device Nov 29 16:10:09 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding audio output: Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding audio output: Nov 29 16:10:09 volumio6 volumio[1216]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 systemd[1]: mpd.service: Succeeded. Nov 29 16:10:09 volumio6 systemd[1]: Stopped Music Player Daemon. Nov 29 16:10:09 volumio6 systemd[1]: Starting Music Player Daemon... Nov 29 16:10:09 volumio6 sudo[2323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio6 Nov 29 16:10:09 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding METAVOLUMIO REST API Endpoints Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 29 16:10:09 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Nov 29 16:10:09 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 29 16:10:09 volumio6 volumio[1216]: info: Preparing CD Folders Nov 29 16:10:09 volumio6 sudo[2323]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding CD REST API Endpoints Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 29 16:10:09 volumio6 volumio[1216]: info: Starting UDEV Watcher for CD Nov 29 16:10:09 volumio6 volumio[1216]: info: Detecting CD presence with UDEV Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 29 16:10:09 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding inputs REST Endpoints Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 29 16:10:09 volumio6 volumio[1216]: info: Scanning Audio Inputs Nov 29 16:10:09 volumio6 sudo[2326]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 16:10:09 volumio6 sudo[2326]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:09 volumio6 sudo[2326]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:09 volumio6 volumio[1216]: info: Checking against Known Cards name Nov 29 16:10:09 volumio6 volumio[1216]: info: Checking against Known Cards name Nov 29 16:10:09 volumio6 volumio[1216]: info: Checking against Known Cards name Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding Server instance for streaming Nov 29 16:10:09 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 29 16:10:09 volumio6 volumio[1216]: error: Hi Res Audio Failed Login: Missing Login Data Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 29 16:10:09 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 29 16:10:09 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 29 16:10:09 volumio6 volumio[1216]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 29 16:10:09 volumio6 volumio[1216]: info: Stopping AccessToken refresher cron Nov 29 16:10:09 volumio6 sudo[2344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 29 16:10:09 volumio6 sudo[2344]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:09 volumio6 volumio[1216]: info: AccessToken refresher cron started Nov 29 16:10:09 volumio6 volumio[1216]: info: Stopping AccessToken refresher cron for QOBUZ Nov 29 16:10:09 volumio6 sudo[2344]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:09 volumio6 volumio[1216]: info: AccessToken refresher cron started for QOBUZ Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding TIDAL REST API Endpoints Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding QOBUZ REST API Endpoints Nov 29 16:10:09 volumio6 volumio[1216]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 29 16:10:09 volumio6 volumio[1216]: info: MPD Permissions set Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 29 16:10:09 volumio6 sudo[2323]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:09 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:09 volumio6 volumio[1216]: info: Starting Shairport Sync Nov 29 16:10:09 volumio6 volumio[1216]: info: Bluetooth name changed to Volumio6 Nov 29 16:10:09 volumio6 sudo[2364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 16:10:09 volumio6 sudo[2364]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:09 volumio6 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 16:10:09 volumio6 systemd[1]: shairport-sync.service: Succeeded. Nov 29 16:10:09 volumio6 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 16:10:09 volumio6 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 16:10:09 volumio6 sudo[2364]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:09 volumio6 volumio[1216]: info: Shairport-Sync Started Nov 29 16:10:09 volumio6 volumio[1216]: info: Successfully Updated MyVolumio device Nov 29 16:10:10 volumio6 volumio[1216]: 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: 5 Nov 29 16:10:10 volumio6 mpd[2348]: Nov 29 16:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 16:10:10 volumio6 systemd[1]: Started Music Player Daemon. Nov 29 16:10:10 volumio6 sudo[2303]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:10 volumio6 volumio[1216]: error: updateQueue error: null Nov 29 16:10:10 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 16:10:11 volumio6 volumio[1216]: info: Successfully Updated MyVolumio device Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 16:10:11 volumio6 sudo[2387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 29 16:10:11 volumio6 sudo[2387]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:11 volumio6 systemd[1]: Started MyVolumio SSH Tunnel. Nov 29 16:10:11 volumio6 sudo[2387]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:11 volumio6 autossh[2395]: port set to 0, monitoring disabled Nov 29 16:10:11 volumio6 autossh[2395]: starting ssh (count 1) Nov 29 16:10:11 volumio6 autossh[2395]: ssh child pid is 2400 Nov 29 16:10:11 volumio6 volumio[1216]: info: Remote SSH Started Nov 29 16:10:11 volumio6 volumio[1216]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:10:11 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:10:11 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 16:10:11 volumio6 volumio[1216]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 29 16:10:11 volumio6 volumio[1216]: info: Received Get System Info Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 16:10:11 volumio6 volumio[1216]: info: Discovery: Getting this device information Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:10:11 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:10:11 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:11 volumio6 volumio[1216]: info: Listing playlists Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 16:10:11 volumio6 volumiossh-tunnel[2393]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Nov 29 16:10:11 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:10:11 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:12 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 16:10:12 volumio6 volumio[1216]: info: Received Get System Info Nov 29 16:10:12 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 16:10:12 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 16:10:12 volumio6 volumio[1216]: info: Discovery: Getting this device information Nov 29 16:10:12 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:10:12 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:12 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 16:10:12 volumio6 sudo[2402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 29 16:10:12 volumio6 sudo[2402]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:12 volumio6 systemd[1]: Started Volumio Bluetooth Module. Nov 29 16:10:12 volumio6 sudo[2402]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:12 volumio6 sudo[2409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 29 16:10:12 volumio6 volumio[1216]: info: Volumio BT Module successfully started Nov 29 16:10:12 volumio6 sudo[2409]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:12 volumio6 volumiobt[2408]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 29 16:10:12 volumio6 sudo[2409]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:12 volumio6 volumio[1216]: info: TidalConnect service stoped! Nov 29 16:10:12 volumio6 volumio[1216]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 29 16:10:12 volumio6 volumio[1216]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 29 16:10:12 volumio6 sudo[2431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 29 16:10:12 volumio6 sudo[2431]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:12 volumio6 systemd[1]: Started Volumio Tidal Connect Service. Nov 29 16:10:12 volumio6 sudo[2431]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:12 volumio6 volumio[1216]: info: Executing endpoint tc_getconfig Nov 29 16:10:12 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 29 16:10:12 volumio6 vtcs[2437]: STARTING TidalConnect services, version: 1.4.0.34 Nov 29 16:10:12 volumio6 vtcs[2437]: STARTED TidalConnect services. Nov 29 16:10:12 volumio6 volumio[1216]: info: Executing endpoint tc_connect Nov 29 16:10:12 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 29 16:10:12 volumio6 volumio[1216]: info: Connecting to TidalConnect Nov 29 16:10:12 volumio6 volumio[1216]: info: CoreCommandRouter::servicePushState Nov 29 16:10:12 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:10:12 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:12 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output Nov 29 16:10:13 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:13 volumio6 volumio[1216]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 29 16:10:13 volumio6 volumio[1216]: verbose: CURRENT POSITION 0 Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreStateMachine::syncState stateService stop Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreStateMachine::syncState currentStatus stop Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:10:13 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output Nov 29 16:10:13 volumio6 volumio[1216]: info: No code Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:10:13 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreCommandRouter::servicePushState Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:10:13 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output Nov 29 16:10:13 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:13 volumio6 volumio[1216]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 29 16:10:13 volumio6 volumio[1216]: verbose: CURRENT POSITION 0 Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreStateMachine::syncState stateService stop Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreStateMachine::syncState currentStatus stop Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:10:13 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output Nov 29 16:10:13 volumio6 volumio[1216]: info: No code Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:10:13 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Nov 29 16:10:13 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output Nov 29 16:10:13 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 29 16:10:13 volumio6 sudo[2459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 29 16:10:13 volumio6 sudo[2459]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:13 volumio6 volumiobt[2408]: pulseaudio: no process found Nov 29 16:10:13 volumio6 sudo[2459]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:13 volumio6 dbus-daemon[600]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.24' (uid=1000 pid=2470 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 29 16:10:13 volumio6 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 29 16:10:13 volumio6 volumio[1216]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 29 16:10:13 volumio6 kernel: IPVS: Creating netns size=1496 id=3 Nov 29 16:10:13 volumio6 kernel: IPVS: ftp: loaded support on port[0] = 21 Nov 29 16:10:13 volumio6 dbus-daemon[600]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 29 16:10:13 volumio6 systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 29 16:10:13 volumio6 rtkit-daemon[2471]: Successfully called chroot. Nov 29 16:10:13 volumio6 rtkit-daemon[2471]: Successfully dropped privileges. Nov 29 16:10:13 volumio6 rtkit-daemon[2471]: Successfully limited resources. Nov 29 16:10:13 volumio6 rtkit-daemon[2471]: Running. Nov 29 16:10:13 volumio6 rtkit-daemon[2471]: Canary thread running. Nov 29 16:10:13 volumio6 rtkit-daemon[2471]: Watchdog thread running. Nov 29 16:10:13 volumio6 dbus-daemon[600]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.25' (uid=0 pid=2471 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 29 16:10:13 volumio6 systemd[1]: Starting Authorization Manager... Nov 29 16:10:13 volumio6 volumio[1216]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 29 16:10:13 volumio6 polkitd[2475]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 29 16:10:13 volumio6 dbus-daemon[600]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 29 16:10:13 volumio6 systemd[1]: Started Authorization Manager. Nov 29 16:10:14 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 16:10:14 volumio6 volumio[1216]: info: Received Get System Info Nov 29 16:10:14 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 16:10:14 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 16:10:14 volumio6 volumio[1216]: info: Discovery: Getting this device information Nov 29 16:10:14 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:10:14 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:14 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 16:10:14 volumio6 pulseaudio[2470]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 29 16:10:14 volumio6 pulseaudio[2470]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 29 16:10:14 volumio6 bluetoothd[707]: Endpoint registered: sender=:1.45 path=/MediaEndpoint/A2DPSource Nov 29 16:10:14 volumio6 volumiobt[2408]: Applying permissions Nov 29 16:10:14 volumio6 volumiobt[2408]: Setting BT discoverable and pairable Nov 29 16:10:14 volumio6 bluetoothd[707]: Endpoint registered: sender=:1.45 path=/MediaEndpoint/A2DPSink Nov 29 16:10:14 volumio6 pulseaudio[2470]: 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 Nov 29 16:10:14 volumio6 volumiobt[2408]: Agent registered Nov 29 16:10:14 volumio6 volumiobt[2408]: [bluetooth]# -e power on Nov 29 16:10:14 volumio6 volumiobt[2408]: Invalid command in menu main: -e Nov 29 16:10:14 volumio6 volumiobt[2408]: Nov 29 16:10:14 volumio6 volumiobt[2408]: Use "help" for a list of available commands in a menu. Nov 29 16:10:14 volumio6 volumiobt[2408]: Use "menu " if you want to enter any submenu. Nov 29 16:10:14 volumio6 volumiobt[2408]: Use "back" if you want to return to menu main. Nov 29 16:10:14 volumio6 volumiobt[2408]: [bluetooth]# agent on Nov 29 16:10:14 volumio6 volumiobt[2408]: Agent is already registered Nov 29 16:10:14 volumio6 volumiobt[2408]: [bluetooth]# discoverable on Nov 29 16:10:14 volumio6 volumiobt[2408]: [bluetooth]# pairable on Nov 29 16:10:14 volumio6 volumiobt[2408]: [bluetooth]# agent NoInputNoOutput Nov 29 16:10:14 volumio6 volumiobt[2408]: Agent is already registered Nov 29 16:10:14 volumio6 volumiobt[2408]: [bluetooth]# default-agent Nov 29 16:10:14 volumio6 volumiobt[2408]: [bluetooth]# quit Nov 29 16:10:14 volumio6 volumiobt[2408]: [59B blob data] Nov 29 16:10:14 volumio6 volumio[1216]: info: MRS: Getting audio outputs on start Nov 29 16:10:14 volumio6 volumio[1216]: info: MRS: Requesting all other devices output Nov 29 16:10:14 volumio6 systemd[1]: systemd-timedated.service: Succeeded. Nov 29 16:10:15 volumio6 volumio[1216]: info: TidalConnect service started! Nov 29 16:10:15 volumio6 volumio[1216]: [Metrics] CommandRouter: 31s 959.32ms Nov 29 16:10:15 volumio6 volumio[1216]: info: CoreCommandRouter::volumiosetStartupVolume Nov 29 16:10:15 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:15 volumio6 volumio[1216]: info: VolumeController:: Setting startup Volume 30 Nov 29 16:10:15 volumio6 volumio[1216]: info: VolumeController::SetAlsaVolume30 Nov 29 16:10:15 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:15 volumio6 volumio[1216]: info: CoreCommandRouter::Close All Modals sent Nov 29 16:10:15 volumio6 volumio[1216]: info: CoreCommandRouter::Close All Modals sent Nov 29 16:10:15 volumio6 volumio[1216]: info: CoreStateMachine::pushState Nov 29 16:10:15 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:15 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 16:10:15 volumio6 volumio[1216]: info: CoreCommandRouter::volumioPushState Nov 29 16:10:15 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Nov 29 16:10:15 volumio6 volumio[1216]: info: MRS: Pushing multiroomSync output Nov 29 16:10:15 volumio6 volumio[1216]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 29 16:10:15 volumio6 volumio[1216]: xcb_connection_has_error() returned true Nov 29 16:10:16 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 29 16:10:16 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 29 16:10:16 volumio6 volumio[1216]: No protocol specified Nov 29 16:10:16 volumio6 volumio[1216]: xcb_connection_has_error() returned true Nov 29 16:10:16 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 29 16:10:17 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 16:10:17 volumio6 volumio[1216]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 29 16:10:17 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetState Nov 29 16:10:17 volumio6 volumio[1216]: info: CorePlayQueue::getTrack 0 Nov 29 16:10:18 volumio6 kernel: usb 1-1.2: USB disconnect, device number 3 Nov 29 16:10:18 volumio6 kernel: EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 395216 (offset 0 size 106496 starting block 2253082) Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on device sda3, logical block 1642752 Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on device sda3, logical block 1642753 Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on device sda3, logical block 1642754 Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on device sda3, logical block 1642755 Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on device sda3, logical block 1642756 Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on device sda3, logical block 1642757 Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on device sda3, logical block 1642758 Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on device sda3, logical block 1642759 Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on device sda3, logical block 1642760 Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on device sda3, logical block 1642761 Nov 29 16:10:18 volumio6 kernel: EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 395206 (offset 0 size 606208 starting block 2289998) Nov 29 16:10:18 volumio6 kernel: EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 393582 (offset 4096 size 4096 starting block 2402382) Nov 29 16:10:18 volumio6 kernel: EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 393410 (offset 0 size 24576 starting block 2419750) Nov 29 16:10:18 volumio6 kernel: EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 393582 (offset 4096 size 4096 starting block 2419751) Nov 29 16:10:18 volumio6 kernel: EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 656625 (offset 0 size 8192 starting block 3266600) Nov 29 16:10:18 volumio6 kernel: EXT4-fs warning (device sda3): ext4_end_bio:330: I/O error -5 writing to inode 656624 (offset 0 size 4096 starting block 3266621) Nov 29 16:10:18 volumio6 kernel: Aborting journal on device sda3-8. Nov 29 16:10:18 volumio6 kernel: JBD2: Error -5 detected when updating journal superblock for sda3-8. Nov 29 16:10:18 volumio6 kernel: EXT4-fs error (device sda3): ext4_find_entry:1450: inode #393499: comm node: reading directory lblock 0 Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on dev sda3, logical block 0, lost sync page write Nov 29 16:10:18 volumio6 volumio[1216]: info: Partition removed: {"syspath":"/sys/devices/platform/ff540000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda3","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/4b139667-03 /dev/disk/by-id/usb-WD_Elements_25A2_575844314132373244464A32-0:0-part3 /dev/disk/by-label/volumio_data /dev/disk/by-uuid/3c54d769-ff34-4936-b713-af0b7e168555 /dev/disk/by-path/platform-ff540000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part3","DEVNAME":"/dev/sda3","DEVPATH":"/devices/platform/ff540000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda3","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"3c54d769-ff34-4936-b713-af0b7e168555","ID_FS_UUID_ENC":"3c54d769-ff34-4936-b713-af0b7e168555","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"Elements_25A2","ID_MODEL_ENC":"Elements\\x2025A2\\x20\\x20\\x20","ID_MODEL_ID":"25a2","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"4882432","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"1948575744","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"4b139667-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"4b139667","ID_PATH":"platform-ff540000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-ff540000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"1019","ID_SERIAL":"WD_Elements_25A2_575844314132373244464A32-0:0","ID_SERIAL_SHORT":"575844314132373244464A32","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"WD","ID_VENDOR_ENC":"WD\\x20\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"1058","MAJOR":"8","MINOR":"3","PARTN":"3","SEQNUM":"3063","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"7268745"} Nov 29 16:10:18 volumio6 systemd[1]: Stopped target Local File Systems. Nov 29 16:10:18 volumio6 volumio[1216]: info: Partition removed: {"syspath":"/sys/devices/platform/ff540000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/9a4ddb3d-db44-44c2-8b32-71fe74cb4a6a /dev/disk/by-path/platform-ff540000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part2 /dev/disk/by-label/volumio /dev/disk/by-partuuid/4b139667-02 /dev/disk/by-id/usb-WD_Elements_25A2_575844314132373244464A32-0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/ff540000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"9a4ddb3d-db44-44c2-8b32-71fe74cb4a6a","ID_FS_UUID_ENC":"9a4ddb3d-db44-44c2-8b32-71fe74cb4a6a","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"Elements_25A2","ID_MODEL_ENC":"Elements\\x2025A2\\x20\\x20\\x20","ID_MODEL_ID":"25a2","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"126976","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"4755456","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"4b139667-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"4b139667","ID_PATH":"platform-ff540000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-ff540000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"1019","ID_SERIAL":"WD_Elements_25A2_575844314132373244464A32-0:0","ID_SERIAL_SHORT":"575844314132373244464A32","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"WD","ID_VENDOR_ENC":"WD\\x20\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"1058","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"3064","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"6989172"} Nov 29 16:10:18 volumio6 systemd[1]: Unmounting /boot... Nov 29 16:10:18 volumio6 kernel: EXT4-fs error (device sda3): ext4_find_entry:1450: inode #393499: comm node: reading directory lblock 0 Nov 29 16:10:18 volumio6 kernel: EXT4-fs (sda3): previous I/O error to superblock detected Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on dev sda3, logical block 0, lost sync page write Nov 29 16:10:18 volumio6 kernel: EXT4-fs error (device sda3): ext4_find_entry:1450: inode #393263: comm (umount): reading directory lblock 0 Nov 29 16:10:18 volumio6 kernel: EXT4-fs (sda3): previous I/O error to superblock detected Nov 29 16:10:18 volumio6 kernel: Buffer I/O error on dev sda3, logical block 0, lost sync page write Nov 29 16:10:18 volumio6 volumio[1216]: info: Partition removed: {"syspath":"/sys/devices/platform/ff540000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/6393-5E0E /dev/disk/by-path/platform-ff540000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-WD_Elements_25A2_575844314132373244464A32-0:0-part1 /dev/disk/by-partuuid/4b139667-01 /dev/disk/by-label/BOOT","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/ff540000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"BOOT","ID_FS_LABEL_ENC":"BOOT","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6393-5E0E","ID_FS_UUID_ENC":"6393-5E0E","ID_FS_VERSION":"FAT16","ID_INSTANCE":"0:0","ID_MODEL":"Elements_25A2","ID_MODEL_ENC":"Elements\\x2025A2\\x20\\x20\\x20","ID_MODEL_ID":"25a2","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"122880","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"4b139667-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"4b139667","ID_PATH":"platform-ff540000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-ff540000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"1019","ID_SERIAL":"WD_Elements_25A2_575844314132373244464A32-0:0","ID_SERIAL_SHORT":"575844314132373244464A32","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"WD","ID_VENDOR_ENC":"WD\\x20\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"1058","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"3065","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"7250120"} Nov 29 16:10:18 volumio6 systemd[1]: boot.mount: Succeeded. Nov 29 16:10:18 volumio6 systemd[1]: Unmounted /boot. Nov 29 16:10:18 volumio6 sudo[2516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Nov 29 16:10:18 volumio6 sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 16:10:18 volumio6 systemd[1]: media-BOOT.mount: Succeeded. Nov 29 16:10:18 volumio6 sudo[2516]: pam_unix(sudo:session): session closed for user root Nov 29 16:10:18 volumio6 kernel: FAT-fs (sda1): unable to read boot sector to mark fs as dirty Nov 29 16:10:18 volumio6 volumio[1216]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 29 16:10:19 volumio6 kernel: EXT4-fs error (device sda3): ext4_journal_check_start:56: Detected aborted journal Nov 29 16:10:19 volumio6 kernel: EXT4-fs (sda3): Remounting filesystem read-only Nov 29 16:10:19 volumio6 kernel: ------------[ cut here ]------------ Nov 29 16:10:19 volumio6 kernel: WARNING: CPU: 0 PID: 1903 at fs/fs-writeback.c:2112 __mark_inode_dirty+0x3bc/0x400() Nov 29 16:10:19 volumio6 kernel: bdi-block not registered Nov 29 16:10:19 volumio6 kernel: Modules linked in: snd_usb_audio snd_hwdep snd_usbmidi_lib 8723bs snd_rawmidi overlay Nov 29 16:10:19 volumio6 kernel: CPU: 0 PID: 1903 Comm: ThreadPoolForeg Not tainted 4.4.71+ #23 Nov 29 16:10:19 volumio6 kernel: Hardware name: Rockchip (Device Tree) Nov 29 16:10:19 volumio6 kernel: [] (unwind_backtrace) from [] (show_stack+0x20/0x24) Nov 29 16:10:19 volumio6 kernel: [] (show_stack) from [] (dump_stack+0x84/0xa0) Nov 29 16:10:19 volumio6 kernel: [] (dump_stack) from [] (warn_slowpath_common+0x98/0xc4) Nov 29 16:10:19 volumio6 kernel: [] (warn_slowpath_common) from [] (warn_slowpath_fmt+0x40/0x48) Nov 29 16:10:19 volumio6 kernel: [] (warn_slowpath_fmt) from [] (__mark_inode_dirty+0x3bc/0x400) Nov 29 16:10:19 volumio6 kernel: [] (__mark_inode_dirty) from [] (generic_update_time+0xa8/0xb0) Nov 29 16:10:19 volumio6 kernel: [] (generic_update_time) from [] (file_update_time+0xe4/0x10c) Nov 29 16:10:19 volumio6 kernel: [] (file_update_time) from [] (__generic_file_write_iter+0x84/0x1ac) Nov 29 16:10:19 volumio6 kernel: [] (__generic_file_write_iter) from [] (ext4_file_write_iter+0x390/0x47c) Nov 29 16:10:19 volumio6 kernel: [] (ext4_file_write_iter) from [] (__vfs_write+0xb8/0xe4) Nov 29 16:10:19 volumio6 kernel: [] (__vfs_write) from [] (vfs_write+0xbc/0x18c) Nov 29 16:10:19 volumio6 kernel: [] (vfs_write) from [] (SyS_write+0x50/0x88) Nov 29 16:10:19 volumio6 kernel: [] (SyS_write) from [] (ret_fast_syscall+0x0/0x3c) Nov 29 16:10:19 volumio6 kernel: ---[ end trace 29bf269737980ff5 ]--- Nov 29 16:10:20 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 16:10:20 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 16:10:20 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 16:10:20 volumio6 kernel: EXT4-fs warning (device sda3): dx_probe:739: inode #655582: lblock 0: comm ThreadPoolForeg: error -5 reading directory block Nov 29 16:10:20 volumio6 kernel: EXT4-fs warning (device sda3): dx_probe:739: inode #655582: lblock 0: comm ThreadPoolForeg: error -5 reading directory block Nov 29 16:10:20 volumio6 kernel: EXT4-fs warning (device sda3): dx_probe:739: inode #655582: lblock 0: comm ThreadPoolForeg: error -5 reading directory block Nov 29 16:10:20 volumio6 volumio-remote-updater[606]: No test mode Nov 29 16:10:20 volumio6 volumio-remote-updater[606]: No alpha test mode Nov 29 16:10:20 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 29 16:10:20 volumio6 kernel: EXT4-fs error (device sda3): ext4_find_entry:1450: inode #393309: comm volumio-remote-: reading directory lblock 0 Nov 29 16:10:20 volumio6 kernel: EXT4-fs error (device sda3): ext4_find_entry:1450: inode #393309: comm volumio-remote-: reading directory lblock 0 Nov 29 16:10:20 volumio6 volumio[1216]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 29 16:10:20 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 29 16:10:20 volumio6 kernel: EXT4-fs error (device sda3): ext4_find_entry:1450: inode #393309: comm volumio-remote-: reading directory lblock 0 Nov 29 16:10:20 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 16:10:20 volumio6 kernel: EXT4-fs error (device sda3): ext4_find_entry:1450: inode #655598: comm ThreadPoolForeg: reading directory lblock 0 Nov 29 16:10:20 volumio6 kernel: ------------[ cut here ]------------ Nov 29 16:10:20 volumio6 kernel: WARNING: CPU: 2 PID: 1694 at fs/fs-writeback.c:2112 __mark_inode_dirty+0x3bc/0x400() Nov 29 16:10:20 volumio6 kernel: bdi-block not registered Nov 29 16:10:20 volumio6 kernel: Modules linked in: snd_usb_audio snd_hwdep snd_usbmidi_lib 8723bs snd_rawmidi overlay Nov 29 16:10:20 volumio6 kernel: CPU: 2 PID: 1694 Comm: cat Tainted: G W 4.4.71+ #23 Nov 29 16:10:20 volumio6 kernel: Hardware name: Rockchip (Device Tree) Nov 29 16:10:20 volumio6 kernel: [] (unwind_backtrace) from [] (show_stack+0x20/0x24) Nov 29 16:10:20 volumio6 kernel: [] (show_stack) from [] (dump_stack+0x84/0xa0) Nov 29 16:10:20 volumio6 kernel: [] (dump_stack) from [] (warn_slowpath_common+0x98/0xc4) Nov 29 16:10:20 volumio6 kernel: [] (warn_slowpath_common) from [] (warn_slowpath_fmt+0x40/0x48) Nov 29 16:10:20 volumio6 kernel: [] (warn_slowpath_fmt) from [] (__mark_inode_dirty+0x3bc/0x400) Nov 29 16:10:20 volumio6 kernel: [] (__mark_inode_dirty) from [] (generic_update_time+0xa8/0xb0) Nov 29 16:10:20 volumio6 kernel: [] (generic_update_time) from [] (file_update_time+0xe4/0x10c) Nov 29 16:10:20 volumio6 kernel: [] (file_update_time) from [] (__generic_file_write_iter+0x84/0x1ac) Nov 29 16:10:20 volumio6 kernel: [] (__generic_file_write_iter) from [] (ext4_file_write_iter+0x390/0x47c) Nov 29 16:10:20 volumio6 kernel: [] (ext4_file_write_iter) from [] (__vfs_write+0xb8/0xe4) Nov 29 16:10:20 volumio6 kernel: [] (__vfs_write) from [] (vfs_write+0xbc/0x18c) Nov 29 16:10:20 volumio6 kernel: [] (vfs_write) from [] (SyS_write+0x50/0x88) Nov 29 16:10:20 volumio6 kernel: [] (SyS_write) from [] (ret_fast_syscall+0x0/0x3c) Nov 29 16:10:20 volumio6 kernel: ---[ end trace 29bf269737980ff6 ]--- Nov 29 16:10:21 volumio6 kernel: EXT4-fs error (device sda3): ext4_find_entry:1450: inode #393388: comm ThreadPoolForeg: reading directory lblock 0 Nov 29 16:10:21 volumio6 kernel: EXT4-fs error (device sda3): ext4_find_entry:1450: inode #655600: comm ThreadPoolForeg: reading directory lblock 0 Nov 29 16:10:21 volumio6 kernel: EXT4-fs error (device sda3): ext4_find_entry:1450: inode #393437: comm ThreadPoolForeg: reading directory lblock 0 Nov 29 16:10:22 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:22 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 16:10:22 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 29 16:10:22 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 29 16:10:22 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 29 16:10:22 volumio6 volumio[1216]: info: CoreCommandRouter::volumioGetBrowseSources Nov 29 16:10:22 volumio6 volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 16:10:22 volumio6 volumio[1216]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 16:10:22 volumio6 volumio[1216]: Error: EIO: i/o error, scandir '/data/plugins/' Nov 29 16:10:22 volumio6 volumio[1216]: at Object.readdirSync (fs.js:1048:3) Nov 29 16:10:22 volumio6 volumio[1216]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1627:30) Nov 29 16:10:22 volumio6 volumio[1216]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1535:29) Nov 29 16:10:22 volumio6 volumio[1216]: at myVolumio.identifyUserProperties (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:42317) Nov 29 16:10:22 volumio6 volumio[1216]: at Timeout._onTimeout (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:2508) Nov 29 16:10:22 volumio6 volumio[1216]: at listOnTimeout (internal/timers.js:557:17) Nov 29 16:10:22 volumio6 volumio[1216]: at processTimers (internal/timers.js:500:7) { Nov 29 16:10:22 volumio6 volumio[1216]: errno: -5, Nov 29 16:10:22 volumio6 volumio[1216]: syscall: 'scandir', Nov 29 16:10:22 volumio6 volumio[1216]: code: 'EIO', Nov 29 16:10:22 volumio6 volumio[1216]: path: '/data/plugins/' Nov 29 16:10:22 volumio6 volumio[1216]: } Nov 29 16:10:22 volumio6 volumio[1216]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 16:10:22 volumio6 sudo[2537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 16:09 Nov 29 16:10:22 volumio6 sudo[2537]: 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"