-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-06-04 03:25:05 CEST. -- Jun 04 03:24:00 volumio dhcpcd-run-hooks[881]: wlan0: failed to start wpa_supplicant Jun 04 03:24:00 volumio dhcpcd-run-hooks[882]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jun 04 03:24:00 volumio dhcpcd[858]: wlan0: connected to Access Point `' Jun 04 03:24:00 volumio dhcpcd[858]: no interfaces have a carrier Jun 04 03:24:00 volumio dhcpcd[858]: forked to background, child pid 892 Jun 04 03:24:00 volumio dhcpcd[892]: eth0: waiting for carrier Jun 04 03:24:00 volumio dhcpcd[892]: wlan0: waiting for carrier Jun 04 03:24:00 volumio sudo[857]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:00 volumio wireless.js[639]: trying... Jun 04 03:24:00 volumio sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:00 volumio sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:00 volumio sudo[894]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:01 volumio wireless.js[639]: trying... Jun 04 03:24:01 volumio sudo[897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:01 volumio sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:01 volumio sudo[897]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:02 volumio wpa_supplicant[880]: wlan0: Trying to associate with SSID 'TOMY' Jun 04 03:24:02 volumio wireless.js[639]: trying... Jun 04 03:24:02 volumio sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:02 volumio sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:02 volumio sudo[900]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:03 volumio wireless.js[639]: trying... Jun 04 03:24:03 volumio sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:03 volumio sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:03 volumio sudo[903]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:04 volumio wireless.js[639]: trying... Jun 04 03:24:04 volumio sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:04 volumio sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:04 volumio sudo[906]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:05 volumio dhcpcd[892]: wlan0: carrier acquired Jun 04 03:24:05 volumio dhcpcd[892]: wlan0: connected to Access Point `TOMY' Jun 04 03:24:05 volumio wpa_supplicant[880]: wlan0: Associated with 02:eb:d8:75:0f:57 Jun 04 03:24:05 volumio wpa_supplicant[880]: wlan0: CTRL-EVENT-CONNECTED - Connection to 02:eb:d8:75:0f:57 completed [id=0 id_str=] Jun 04 03:24:05 volumio wpa_supplicant[880]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 04 03:24:05 volumio wpa_supplicant[880]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jun 04 03:24:05 volumio dhcpcd[892]: DUID 00:01:00:01:2d:69:1c:d4:dc:a6:32:a7:32:90 Jun 04 03:24:05 volumio dhcpcd[892]: wlan0: IAID 32:a7:32:91 Jun 04 03:24:05 volumio dhcpcd[892]: wlan0: adding address fe80::dea6:32ff:fea7:3291 Jun 04 03:24:05 volumio dhcpcd[892]: ipv6_addaddr1: Permission denied Jun 04 03:24:05 volumio dhcpcd[892]: wlan0: carrier lost Jun 04 03:24:05 volumio dhcpcd[892]: wlan0: carrier acquired Jun 04 03:24:05 volumio dhcpcd[892]: wlan0: IAID 32:a7:32:91 Jun 04 03:24:05 volumio dhcpcd[892]: wlan0: soliciting an IPv6 router Jun 04 03:24:05 volumio wireless.js[639]: trying... Jun 04 03:24:05 volumio sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:05 volumio sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:05 volumio sudo[921]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:06 volumio dhcpcd[892]: wlan0: rebinding lease of 192.168.68.102 Jun 04 03:24:06 volumio volumio-remote-updater[660]: [2024-06-04 03:24:06] [info] asio async_connect error: system:111 (Connection refused) Jun 04 03:24:06 volumio volumio-remote-updater[660]: [2024-06-04 03:24:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 04 03:24:06 volumio volumio-remote-updater[660]: [2024-06-04 03:24:06] [error] handle_connect error: Underlying Transport Error Jun 04 03:24:06 volumio dhcpcd[892]: wlan0: probing address 192.168.68.102/22 Jun 04 03:24:06 volumio wireless.js[639]: trying... Jun 04 03:24:06 volumio sudo[924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:06 volumio sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:06 volumio sudo[924]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:07 volumio wireless.js[639]: trying... Jun 04 03:24:07 volumio sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:07 volumio sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:07 volumio sudo[927]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:08 volumio wireless.js[639]: trying... Jun 04 03:24:08 volumio sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:08 volumio sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:08 volumio sudo[930]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:09 volumio wireless.js[639]: trying... Jun 04 03:24:09 volumio sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:09 volumio sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:09 volumio sudo[933]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:10 volumio wireless.js[639]: trying... Jun 04 03:24:10 volumio sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:10 volumio sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:10 volumio sudo[936]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:11 volumio wireless.js[639]: trying... Jun 04 03:24:11 volumio sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:11 volumio sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:11 volumio sudo[939]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:12 volumio dhcpcd[892]: wlan0: leased 192.168.68.102 for 7200 seconds Jun 04 03:24:12 volumio avahi-daemon[654]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.102. Jun 04 03:24:12 volumio dhcpcd[892]: wlan0: adding route to 192.168.68.0/22 Jun 04 03:24:12 volumio avahi-daemon[654]: New relevant interface wlan0.IPv4 for mDNS. Jun 04 03:24:12 volumio dhcpcd[892]: wlan0: adding default route via 192.168.68.1 Jun 04 03:24:12 volumio avahi-daemon[654]: Registering new address record for 192.168.68.102 on wlan0.IPv4. Jun 04 03:24:12 volumio wireless.js[639]: trying... Jun 04 03:24:12 volumio sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 03:24:12 volumio sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:12 volumio sudo[962]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:12 volumio wireless.js[639]: Connected to: ----TOMY Jun 04 03:24:12 volumio wireless.js[639]: ---- Jun 04 03:24:12 volumio sudo[965]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 03:24:12 volumio sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:12 volumio sudo[965]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:12 volumio wireless.js[639]: ... joined AP, wlan0 IPv4 is 192.168.68.102, ipV6 is undefined Jun 04 03:24:12 volumio wireless.js[639]: It's done! AP Jun 04 03:24:13 volumio systemd[1]: Started Wireless Services. Jun 04 03:24:13 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 04 03:24:13 volumio systemd[1]: Started Volumio Backend Module. Jun 04 03:24:13 volumio systemd[1]: Started Volumio Cpu Tweaker. Jun 04 03:24:13 volumio volumio-cpu-tweak[971]: Setting RT Priority for mpd Jun 04 03:24:13 volumio volumio-cpu-tweak[971]: pid 35's current scheduling policy: SCHED_OTHER Jun 04 03:24:13 volumio volumio-cpu-tweak[971]: pid 35's current scheduling priority: 0 Jun 04 03:24:13 volumio volumio-cpu-tweak[971]: Setting MPD Affinity Jun 04 03:24:13 volumio volumio-cpu-tweak[971]: pid 3's current affinity mask: f Jun 04 03:24:13 volumio volumio-cpu-tweak[971]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 04 03:24:13 volumio volumio-cpu-tweak[971]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 04 03:24:13 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 04 03:24:13 volumio ntpd[777]: Listen normally on 3 wlan0 192.168.68.102:123 Jun 04 03:24:13 volumio ntpd[777]: new interface(s) found: waking up resolver Jun 04 03:24:14 volumio volumio[970]: info: ------------------------------------------- Jun 04 03:24:14 volumio volumio[970]: info: ----- Volumio3 ---- Jun 04 03:24:14 volumio volumio[970]: info: ------------------------------------------- Jun 04 03:24:14 volumio volumio[970]: info: ----- System startup ---- Jun 04 03:24:14 volumio volumio[970]: info: ------------------------------------------- Jun 04 03:24:15 volumio nmbd[757]: [2024/06/04 03:24:15.433587, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 03:24:15 volumio nmbd[757]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 04 03:24:15 volumio systemd[1]: Started Samba NMB Daemon. Jun 04 03:24:15 volumio volumio[970]: info: MYVOLUMIO Environment detected Jun 04 03:24:15 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 04 03:24:15 volumio volumio[970]: info: Plugin folders cleanup Jun 04 03:24:15 volumio volumio[970]: info: Scanning into folder /volumio/app/plugins/ Jun 04 03:24:15 volumio volumio[970]: info: Scanning category audio_interface Jun 04 03:24:15 volumio volumio[970]: info: Scanning category miscellanea Jun 04 03:24:15 volumio volumio[970]: info: Scanning category music_service Jun 04 03:24:15 volumio volumio[970]: info: Scanning category plugins.json Jun 04 03:24:15 volumio volumio[970]: info: Scanning category system_controller Jun 04 03:24:15 volumio volumio[970]: info: Scanning category user_interface Jun 04 03:24:15 volumio volumio[970]: info: Scanning into folder /data/plugins/ Jun 04 03:24:15 volumio volumio[970]: info: Scanning category user_interface Jun 04 03:24:15 volumio volumio[970]: info: Plugin folders cleanup completed Jun 04 03:24:15 volumio volumio[970]: info: ------------------------------------------- Jun 04 03:24:15 volumio volumio[970]: info: ----- Core plugins startup ---- Jun 04 03:24:15 volumio volumio[970]: info: ------------------------------------------- Jun 04 03:24:15 volumio volumio[970]: info: Loading plugins from folder /volumio/app/plugins/ Jun 04 03:24:15 volumio volumio[970]: info: Adding plugin upnp to MyMusic Plugins Jun 04 03:24:15 volumio volumio[970]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 04 03:24:15 volumio volumio[970]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 04 03:24:15 volumio volumio[970]: info: Loading plugins from folder /data/plugins/ Jun 04 03:24:15 volumio volumio[970]: info: Loading plugin "system"... Jun 04 03:24:15 volumio volumio[970]: info: Loading plugin "appearance"... Jun 04 03:24:15 volumio winbindd[997]: [2024/06/04 03:24:15.736468, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 04 03:24:15 volumio winbindd[997]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 04 03:24:15 volumio systemd[1]: Started Samba Winbind Daemon. Jun 04 03:24:15 volumio winbindd[997]: [2024/06/04 03:24:15.846833, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 03:24:15 volumio winbindd[997]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 04 03:24:15 volumio systemd[1]: Starting Samba SMB Daemon... Jun 04 03:24:16 volumio volumio-remote-updater[660]: [2024-06-04 03:24:16] [connect] Successful connection Jun 04 03:24:16 volumio winbindd[997]: [2024/06/04 03:24:16.249462, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jun 04 03:24:16 volumio winbindd[997]: res_names->count = 1, expected 2 Jun 04 03:24:16 volumio systemd[1]: Started Samba SMB Daemon. Jun 04 03:24:16 volumio systemd[1]: Reached target Multi-User System. Jun 04 03:24:16 volumio systemd[1]: Reached target Graphical Interface. Jun 04 03:24:16 volumio smbd[1005]: [2024/06/04 03:24:16.276171, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 03:24:16 volumio smbd[1005]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 04 03:24:16 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 04 03:24:16 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 04 03:24:16 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 04 03:24:16 volumio systemd[1]: Startup finished in 27.332s (kernel) + 30.059s (userspace) = 57.391s. Jun 04 03:24:16 volumio volumio[970]: info: Loading plugin "network"... Jun 04 03:24:16 volumio volumio[970]: info: Refreshing Cached IP Addresses Jun 04 03:24:16 volumio sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 03:24:16 volumio sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:16 volumio sudo[1015]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:16 volumio sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 03:24:16 volumio sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:16 volumio volumio[970]: info: Loading plugin "services"... Jun 04 03:24:16 volumio sudo[1017]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:16 volumio volumio[970]: info: Loading plugin "alsa_controller"... Jun 04 03:24:16 volumio sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 04 03:24:16 volumio sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:16 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 03:24:16 volumio volumio[970]: info: Loading plugin "wizard"... Jun 04 03:24:16 volumio volumio[970]: info: Loading plugin "networkfs"... Jun 04 03:24:16 volumio volumio[970]: info: Starting Udev Watcher for removable devices Jun 04 03:24:16 volumio volumio[970]: info: Ignoring mount for partition: boot Jun 04 03:24:16 volumio volumio[970]: info: Ignoring mount for partition: volumio Jun 04 03:24:16 volumio volumio[970]: info: Ignoring mount for partition: volumio_data Jun 04 03:24:17 volumio volumio[970]: info: Mounting Device flac Jun 04 03:24:17 volumio sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/flac -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 04 03:24:17 volumio sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:17 volumio ntfs-3g[1035]: Version 2017.3.23AR.3 integrated FUSE 28 Jun 04 03:24:17 volumio ntfs-3g[1035]: Mounted /dev/sda1 (Read-Write, label "flac", NTFS 3.1) Jun 04 03:24:17 volumio ntfs-3g[1035]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 04 03:24:17 volumio ntfs-3g[1035]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Jun 04 03:24:17 volumio ntfs-3g[1035]: Global ownership and permissions enforced, configuration type 7 Jun 04 03:24:17 volumio sudo[1031]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:17 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 03:24:17 volumio volumio[970]: info: Loading plugin "volumio_command_line_client"... Jun 04 03:24:17 volumio volumio[970]: info: Loading plugin "upnp"... Jun 04 03:24:17 volumio volumio[970]: info: [1717464257518] Starting Upmpd Daemon Jun 04 03:24:17 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 03:24:17 volumio volumio[970]: info: Loading plugin "my_music"... Jun 04 03:24:17 volumio volumio[970]: info: Loading plugin "mpd"... Jun 04 03:24:17 volumio volumio[970]: info: Loading plugin "upnp_browser"... Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "alarm-clock"... Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "airplay_emulation"... Jun 04 03:24:18 volumio volumio[970]: info: Starting Shairport Sync Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "last_100"... Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "webradio"... Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "i2s_dacs"... Jun 04 03:24:18 volumio volumio[970]: info: I2S DAC not set, start Auto-detection Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "volumiodiscovery"... Jun 04 03:24:18 volumio volumio[970]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 04 03:24:18 volumio node[970]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 04 03:24:18 volumio volumio[970]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 03:24:18 volumio node[970]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 03:24:18 volumio volumio[970]: *** WARNING *** For more information see Jun 04 03:24:18 volumio node[970]: *** WARNING *** For more information see Jun 04 03:24:18 volumio volumio[970]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 04 03:24:18 volumio node[970]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 04 03:24:18 volumio volumio[970]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 03:24:18 volumio node[970]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 03:24:18 volumio volumio[970]: *** WARNING *** For more information see Jun 04 03:24:18 volumio node[970]: *** WARNING *** For more information see Jun 04 03:24:18 volumio volumio[970]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 04 03:24:18 volumio volumio[970]: info: Discovery: Started advertising with name: Volumio Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "outputs"... Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "albumart"... Jun 04 03:24:18 volumio volumio[970]: info: Plugin example_plugin is not enabled Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "inputs"... Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "updater_comm"... Jun 04 03:24:18 volumio volumio[970]: info: Plugin mpdemulation is not enabled Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "rest_api"... Jun 04 03:24:18 volumio volumio[970]: info: Loading plugin "websocket"... Jun 04 03:24:18 volumio volumio[970]: Forking 3 albumart workers Jun 04 03:24:18 volumio volumio[970]: info: Starting Socket.io Server version 2.3.0 Jun 04 03:24:18 volumio volumio[970]: info: Plugin touch_display is not enabled Jun 04 03:24:18 volumio volumio[970]: info: Loading i18n strings for locale es Jun 04 03:24:18 volumio volumio[970]: Updating browse sources language Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::initPlayerControls Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:18 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 03:24:18 volumio volumio[970]: Express server listening on port 3000 Jun 04 03:24:18 volumio volumio[970]: [Metrics] WebUI: 4s 850.15ms Jun 04 03:24:19 volumio volumio[970]: info: CoreStateMachine::resetVolumioState Jun 04 03:24:19 volumio volumio[970]: info: CoreStateMachine::getcurrentVolume Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::volumioRetrievevolume Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 03:24:19 volumio volumio[970]: verbose: New Socket.io Connection to 192.168.68.102 from 192.168.68.100 UA: Mozilla/5.0 (Linux; Android 14; 22101320G Build/UKQ1.231003.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 04 03:24:19 volumio volumio-remote-updater[660]: [2024-06-04 03:24:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1717464256 101 Jun 04 03:24:19 volumio volumio[970]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jun 04 03:24:19 volumio volumio[970]: MPD error: Cannot assign requested address Jun 04 03:24:19 volumio volumio[970]: Starting albumart workers Jun 04 03:24:19 volumio volumio[970]: info: Reloading queue from file Jun 04 03:24:19 volumio volumio[970]: info: Setting Device type: Raspberry PI Jun 04 03:24:19 volumio volumio[970]: info: CoreStateMachine::setRepeat false single undefined Jun 04 03:24:19 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:19 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:19 volumio volumio[970]: Starting albumart workers Jun 04 03:24:19 volumio volumio[970]: info: CoreStateMachine::setRandom false Jun 04 03:24:19 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:19 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:19 volumio volumio[970]: info: VolumeController:: Volume=100 Mute =false Jun 04 03:24:19 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:19 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 03:24:19 volumio volumio[970]: Starting albumart workers Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:19 volumio volumio[970]: info: CoreStateMachine::updateTrackBlock Jun 04 03:24:19 volumio volumio[970]: info: CorePlayQueue::getTrackBlock Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::volumioRetrievevolume Jun 04 03:24:19 volumio volumio[970]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 04 03:24:19 volumio volumio[970]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Jun 04 03:24:19 volumio volumio[970]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 04 03:24:19 volumio volumio[970]: info: Completed loading Core Plugins Jun 04 03:24:19 volumio volumio[970]: info: Preparing to generate the ALSA configuration file Jun 04 03:24:19 volumio volumio[970]: info: Asound.conf file unchanged, so no further update is needed Jun 04 03:24:19 volumio volumio[970]: info: Output device has changed, restarting MPD Jun 04 03:24:19 volumio sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 03:24:19 volumio sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:19 volumio volumio[970]: info: Output device has changed, restarting Shairport Sync Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 03:24:19 volumio sudo[1081]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:19 volumio sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 03:24:19 volumio sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:19 volumio volumio[970]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 03:24:19 volumio volumio[970]: info: ___________ START PLUGINS ___________ Jun 04 03:24:19 volumio volumio[970]: info: ControllerMpd::onStart: Initializing MPD Jun 04 03:24:19 volumio volumio[970]: info: Creating MPD Configuration file Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 03:24:19 volumio volumio[970]: info: [1717464259560] CoreMusicLibrary::Adding element Media Servers Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 03:24:19 volumio systemd[1]: Listening on mpd.socket. Jun 04 03:24:19 volumio systemd[1]: Starting Music Player Daemon... Jun 04 03:24:19 volumio sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 03:24:19 volumio sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 03:24:19 volumio sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:19 volumio sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 03:24:19 volumio sudo[1092]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:19 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 04 03:24:19 volumio systemd[1]: mpd.service: Succeeded. Jun 04 03:24:19 volumio systemd[1]: Stopped Music Player Daemon. Jun 04 03:24:19 volumio systemd[1]: Starting Music Player Daemon... Jun 04 03:24:19 volumio volumio[970]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 03:24:19 volumio volumio[970]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 03:24:19 volumio volumio[970]: info: [1717464259741] CoreMusicLibrary::Adding element Last_100 Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 03:24:19 volumio volumio[970]: info: [1717464259744] CoreMusicLibrary::Adding element Webradio Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 03:24:19 volumio volumio[970]: info: Initializing BBC Radios Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 03:24:19 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 03:24:19 volumio volumio[970]: info: Volumio Calling Home Jun 04 03:24:19 volumio volumio[970]: info: MPD Permissions set Jun 04 03:24:19 volumio volumio[970]: info: MPD Permissions set Jun 04 03:24:20 volumio volumio[970]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:20 volumio volumio[970]: info: VolumeController:: Volume=100 Mute =false Jun 04 03:24:20 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:20 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:20 volumio volumio[970]: info: Starting Shairport Sync Jun 04 03:24:20 volumio volumio[970]: info: Starting Shairport Sync Jun 04 03:24:20 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 03:24:20 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:20 volumio volumio[970]: info: Starting Shairport Sync Jun 04 03:24:20 volumio sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 03:24:20 volumio sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:20 volumio sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 03:24:20 volumio sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 04 03:24:20 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 04 03:24:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 04 03:24:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 03:24:20 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:20 volumio sudo[1152]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:20 volumio volumio[970]: info: Shairport-Sync Started Jun 04 03:24:20 volumio volumio[970]: Error adding Membership: Error: addMembership EINVAL Jun 04 03:24:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 04 03:24:20 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 04 03:24:20 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 04 03:24:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 04 03:24:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 03:24:20 volumio sudo[1156]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:20 volumio volumio[970]: info: CoreCommandRouter::volumioGetState Jun 04 03:24:20 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:20 volumio volumio[970]: info: Shairport-Sync Started Jun 04 03:24:20 volumio volumio[970]: info: Shairport-Sync Started Jun 04 03:24:20 volumio sudo[1020]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:20 volumio volumio[970]: info: Volumio called home Jun 04 03:24:21 volumio mpd[1103]: Jun 04 03:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 04 03:24:21 volumio systemd[1]: Started Music Player Daemon. Jun 04 03:24:21 volumio sudo[1091]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:21 volumio sudo[1083]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:21 volumio volumio[970]: info: Completed starting Core Plugins Jun 04 03:24:21 volumio volumio[970]: info: ------------------------------------------- Jun 04 03:24:21 volumio volumio[970]: info: ----- MyVolumio plugins startup ---- Jun 04 03:24:21 volumio volumio[970]: info: ------------------------------------------- Jun 04 03:24:21 volumio volumio[970]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 04 03:24:21 volumio volumio[970]: verbose: New Socket.io Connection to 192.168.68.102 from 192.168.68.100 UA: Mozilla/5.0 (Linux; Android 14; 22101320G Build/UKQ1.231003.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 04 03:24:21 volumio volumio[970]: error: MPD error: The expression evaluated to a falsy value: Jun 04 03:24:21 volumio volumio[970]: assert.ok(self.idling) Jun 04 03:24:21 volumio volumio[970]: error: The expression evaluated to a falsy value: Jun 04 03:24:21 volumio volumio[970]: assert.ok(self.idling) Jun 04 03:24:21 volumio volumio[970]: info: MPD running with PID1103 Jun 04 03:24:21 volumio volumio[970]: ,establishing connection Jun 04 03:24:21 volumio volumio[970]: error: MPD error: The expression evaluated to a falsy value: Jun 04 03:24:21 volumio volumio[970]: assert.ok(self.idling) Jun 04 03:24:21 volumio volumio[970]: error: The expression evaluated to a falsy value: Jun 04 03:24:21 volumio volumio[970]: assert.ok(self.idling) Jun 04 03:24:21 volumio volumio[970]: error: updateQueue error: null Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::volumioGetState Jun 04 03:24:21 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::volumioGetQueue Jun 04 03:24:21 volumio volumio[970]: info: CoreStateMachine::getQueue Jun 04 03:24:21 volumio volumio[970]: info: CorePlayQueue::getQueue Jun 04 03:24:21 volumio volumio[970]: info: Listing playlists Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 04 03:24:21 volumio volumio[970]: info: Received Get System Info Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 03:24:21 volumio volumio[970]: info: Discovery: Getting this device information Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::volumioGetState Jun 04 03:24:21 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 03:24:21 volumio volumio[970]: info: CoreCommandRouter::volumioGetState Jun 04 03:24:21 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:22 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 04 03:24:23 volumio sh[542]: timed out Jun 04 03:24:23 volumio dhcpcd[579]: timed out Jun 04 03:24:23 volumio sh[542]: dhcpcd exited Jun 04 03:24:23 volumio dhcpcd[579]: dhcpcd exited Jun 04 03:24:23 volumio sh[542]: ifup: failed to bring up eth0 Jun 04 03:24:23 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 04 03:24:23 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 04 03:24:25 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 04 03:24:27 volumio sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 03:24:27 volumio sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:27 volumio sudo[1171]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:27 volumio sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 03:24:27 volumio sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:27 volumio sudo[1173]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:27 volumio sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 04 03:24:27 volumio sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:27 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 04 03:24:27 volumio sudo[1176]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:27 volumio volumio[970]: info: Upmpdcli Daemon Started Jun 04 03:24:27 volumio volumio[1179]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 04 03:24:28 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 04 03:24:28 volumio volumio[970]: info: CURURI: music-library Jun 04 03:24:28 volumio volumio[970]: info: Preload queue cleared Jun 04 03:24:29 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 04 03:24:29 volumio volumio[970]: info: CURURI: music-library/USB Jun 04 03:24:29 volumio volumio[970]: info: Preload queue cleared Jun 04 03:24:31 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 04 03:24:34 volumio volumio[1179]: .........................................................................++++ Jun 04 03:24:34 volumio kernel: usb 1-1.3: USB disconnect, device number 4 Jun 04 03:24:34 volumio volumio[970]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/flac /dev/disk/by-id/usb-SanDisk_Ultra_USB_3.0_01012e4ebbe32fede80cc9df5845bf44fcdd3664fc13a6daad982cbe617ee92d99d100000000000000000000a080bfecff8701009155810739a902cf-0:0-part1 /dev/disk/by-partuuid/6f9f694a-01 /dev/disk/by-uuid/F2FA134CFA130C8B /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"28","ID_BUS":"usb","ID_FS_LABEL":"flac","ID_FS_LABEL_ENC":"flac","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"F2FA134CFA130C8B","ID_FS_UUID_ENC":"F2FA134CFA130C8B","ID_INSTANCE":"0:0","ID_MODEL":"Ultra_USB_3.0","ID_MODEL_ENC":"Ultra\\x20USB\\x203.0\\x20\\x20\\x20","ID_MODEL_ID":"5591","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"240353248","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"6f9f694a-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"6f9f694a","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"SanDisk_Ultra_USB_3.0_01012e4ebbe32fede80cc9df5845bf44fcdd3664fc13a6daad982cbe617ee92d99d100000000000000000000a080bfecff8701009155810739a902cf-0:0","ID_SERIAL_SHORT":"01012e4ebbe32fede80cc9df5845bf44fcdd3664fc13a6daad982cbe617ee92d99d100000000000000000000a080bfecff8701009155810739a902cf","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"SanDisk","ID_VENDOR_ENC":"SanDisk\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2338","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4947811"} Jun 04 03:24:34 volumio sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Jun 04 03:24:34 volumio sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:34 volumio ntfs-3g[1035]: Unmounting /dev/sda1 (flac) Jun 04 03:24:34 volumio systemd[1]: media-flac.mount: Succeeded. Jun 04 03:24:34 volumio sudo[1200]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:38 volumio volumio[970]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/flac Jun 04 03:24:39 volumio volumio[970]: info: Scanning removed location : "USB/flac" Jun 04 03:24:39 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:39 volumio volumio[970]: info: Jun 04 03:24:39 volumio volumio[970]: ---------------------------- MPD announces state update: update Jun 04 03:24:39 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:24:39 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:39 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:39 volumio volumio[970]: info: Jun 04 03:24:39 volumio volumio[970]: ---------------------------- MPD announces state update: update Jun 04 03:24:39 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:24:39 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:39 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:39 volumio volumio[970]: info: Jun 04 03:24:39 volumio volumio[970]: ---------------------------- MPD announces state update: update Jun 04 03:24:39 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:24:39 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:39 volumio volumio[970]: info: sendMpdCommand status took 11 milliseconds Jun 04 03:24:39 volumio volumio[970]: info: sendMpdCommand status took 9 milliseconds Jun 04 03:24:39 volumio volumio[970]: info: sendMpdCommand status took 8 milliseconds Jun 04 03:24:39 volumio volumio[970]: info: sendMpdCommand status took 7 milliseconds Jun 04 03:24:39 volumio volumio[970]: info: sendMpdCommand status took 6 milliseconds Jun 04 03:24:39 volumio volumio[970]: info: sendMpdCommand status took 4 milliseconds Jun 04 03:24:39 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: info: Command Router : Notfying DB Updatetrue Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: info: Command Router : Notfying DB Updatetrue Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: info: Command Router : Notfying DB Updatetrue Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 03:24:40 volumio volumio[970]: verbose: CURRENT POSITION 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState stateService stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: No code Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 03:24:40 volumio volumio[970]: verbose: CURRENT POSITION 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState stateService stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: No code Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 03:24:40 volumio volumio[970]: verbose: CURRENT POSITION 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState stateService stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: No code Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: ------------------------------ 519ms Jun 04 03:24:40 volumio volumio[970]: info: ------------------------------ 516ms Jun 04 03:24:40 volumio volumio[970]: info: ------------------------------ 515ms Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:40 volumio volumio[970]: info: Jun 04 03:24:40 volumio volumio[970]: ---------------------------- MPD announces state update: update Jun 04 03:24:40 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:40 volumio volumio[970]: info: Jun 04 03:24:40 volumio volumio[970]: ---------------------------- MPD announces state update: update Jun 04 03:24:40 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:40 volumio volumio[970]: info: Jun 04 03:24:40 volumio volumio[970]: ---------------------------- MPD announces state update: update Jun 04 03:24:40 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:40 volumio volumio[970]: info: sendMpdCommand status took 13 milliseconds Jun 04 03:24:40 volumio volumio[970]: info: sendMpdCommand status took 12 milliseconds Jun 04 03:24:40 volumio volumio[970]: info: sendMpdCommand status took 7 milliseconds Jun 04 03:24:40 volumio volumio[970]: info: sendMpdCommand status took 6 milliseconds Jun 04 03:24:40 volumio volumio[970]: info: sendMpdCommand status took 6 milliseconds Jun 04 03:24:40 volumio volumio[970]: info: sendMpdCommand status took 4 milliseconds Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: info: Command Router : Notfying DB Updatefalse Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: info: Command Router : Notfying DB Updatefalse Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: info: Command Router : Notfying DB Updatefalse Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Jun 04 03:24:40 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:40 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 03:24:40 volumio volumio[970]: verbose: CURRENT POSITION 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState stateService stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: No code Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 03:24:40 volumio volumio[970]: verbose: CURRENT POSITION 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState stateService stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: No code Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 03:24:40 volumio volumio[970]: verbose: CURRENT POSITION 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState stateService stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus stop Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: No code Jun 04 03:24:40 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:40 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:40 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:40 volumio volumio[970]: info: ------------------------------ 126ms Jun 04 03:24:40 volumio volumio[970]: info: ------------------------------ 119ms Jun 04 03:24:40 volumio volumio[970]: info: ------------------------------ 119ms Jun 04 03:24:41 volumio volumio[970]: error: MyVolumio Plugin failed to start in a timely fashion Jun 04 03:24:41 volumio volumio[970]: info: BOOT COMPLETED Jun 04 03:24:41 volumio volumio[970]: [Metrics] CommandRouter: 27s 59.61ms Jun 04 03:24:41 volumio volumio[970]: info: CoreCommandRouter::volumiosetStartupVolume Jun 04 03:24:41 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 03:24:41 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 03:24:41 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Jun 04 03:24:41 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Jun 04 03:24:42 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 04 03:24:42 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 04 03:24:42 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 04 03:24:44 volumio kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci_hcd Jun 04 03:24:44 volumio kernel: usb 2-1: New USB device found, idVendor=0781, idProduct=5591, bcdDevice= 1.00 Jun 04 03:24:44 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 04 03:24:44 volumio kernel: usb 2-1: Product: Ultra USB 3.0 Jun 04 03:24:44 volumio kernel: usb 2-1: Manufacturer: SanDisk Jun 04 03:24:44 volumio kernel: usb 2-1: SerialNumber: 01012e4ebbe32fede80cc9df5845bf44fcdd3664fc13a6daad982cbe617ee92d99d100000000000000000000a080bfecff8701009155810739a902cf Jun 04 03:24:44 volumio kernel: usb-storage 2-1:1.0: USB Mass Storage device detected Jun 04 03:24:44 volumio kernel: scsi host0: usb-storage 2-1:1.0 Jun 04 03:24:45 volumio kernel: scsi 0:0:0:0: Direct-Access SanDisk Ultra USB 3.0 1.00 PQ: 0 ANSI: 6 Jun 04 03:24:45 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jun 04 03:24:45 volumio kernel: sd 0:0:0:0: [sda] 240353280 512-byte logical blocks: (123 GB/115 GiB) Jun 04 03:24:45 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Jun 04 03:24:45 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Jun 04 03:24:45 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Jun 04 03:24:45 volumio kernel: sda: sda1 Jun 04 03:24:45 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Jun 04 03:24:46 volumio volumio[970]: info: Mounting Device flac Jun 04 03:24:46 volumio sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/flac -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 04 03:24:46 volumio sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:24:46 volumio ntfs-3g[1235]: Version 2017.3.23AR.3 integrated FUSE 28 Jun 04 03:24:46 volumio ntfs-3g[1235]: Mounted /dev/sda1 (Read-Write, label "flac", NTFS 3.1) Jun 04 03:24:46 volumio ntfs-3g[1235]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 04 03:24:46 volumio ntfs-3g[1235]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Jun 04 03:24:46 volumio ntfs-3g[1235]: Global ownership and permissions enforced, configuration type 7 Jun 04 03:24:46 volumio sudo[1232]: pam_unix(sudo:session): session closed for user root Jun 04 03:24:46 volumio volumio[970]: info: Scanning new location : "USB/flac" Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:46 volumio volumio[970]: info: Jun 04 03:24:46 volumio volumio[970]: ---------------------------- MPD announces state update: update Jun 04 03:24:46 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:46 volumio volumio[970]: info: Jun 04 03:24:46 volumio volumio[970]: ---------------------------- MPD announces state update: update Jun 04 03:24:46 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:46 volumio volumio[970]: info: Jun 04 03:24:46 volumio volumio[970]: ---------------------------- MPD announces state update: update Jun 04 03:24:46 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:24:46 volumio volumio[970]: info: sendMpdCommand status took 5 milliseconds Jun 04 03:24:46 volumio volumio[970]: info: sendMpdCommand status took 4 milliseconds Jun 04 03:24:46 volumio volumio[970]: info: sendMpdCommand status took 4 milliseconds Jun 04 03:24:46 volumio volumio[970]: info: sendMpdCommand status took 3 milliseconds Jun 04 03:24:46 volumio volumio[970]: info: sendMpdCommand status took 3 milliseconds Jun 04 03:24:46 volumio volumio[970]: info: sendMpdCommand status took 2 milliseconds Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:46 volumio volumio[970]: info: Command Router : Notfying DB Updatetrue Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:46 volumio volumio[970]: info: Command Router : Notfying DB Updatetrue Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:46 volumio volumio[970]: info: Command Router : Notfying DB Updatetrue Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::Close All Modals sent Jun 04 03:24:46 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:24:46 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 03:24:46 volumio volumio[970]: verbose: CURRENT POSITION 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::syncState stateService stop Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus stop Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:46 volumio volumio[970]: info: No code Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:46 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 03:24:46 volumio volumio[970]: verbose: CURRENT POSITION 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::syncState stateService stop Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus stop Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:46 volumio volumio[970]: info: No code Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:46 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 03:24:46 volumio volumio[970]: verbose: CURRENT POSITION 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::syncState stateService stop Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus stop Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:46 volumio volumio[970]: info: No code Jun 04 03:24:46 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:24:46 volumio volumio[970]: info: CorePlayQueue::getTrack 0 Jun 04 03:24:46 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:24:46 volumio volumio[970]: info: ------------------------------ 127ms Jun 04 03:24:46 volumio volumio[970]: info: ------------------------------ 127ms Jun 04 03:24:46 volumio volumio[970]: info: ------------------------------ 127ms Jun 04 03:24:46 volumio volumio[1179]: .........................................................................................................................................++++ Jun 04 03:24:46 volumio volumio[1179]: e is 65537 (0x010001) Jun 04 03:24:46 volumio volumio[1179]: writing RSA key Jun 04 03:24:47 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Jun 04 03:24:47 volumio kernel: xhci_hcd 0000:01:00.0: @000000042a114490 00000000 00000000 0e000000 02028001 Jun 04 03:24:49 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 03:24:49 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 03:24:49 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 03:24:51 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 04 03:24:51 volumio volumio[970]: info: CURURI: music-library/USB Jun 04 03:24:51 volumio volumio[970]: info: Preload queue cleared Jun 04 03:24:52 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 04 03:24:52 volumio volumio[970]: info: CURURI: music-library/USB/flac Jun 04 03:24:52 volumio volumio[970]: info: Preload queue cleared Jun 04 03:24:56 volumio ntfs-3g[1235]: ntfs_mst_post_read_fixup_warn: magic: 0x2c14ebdd size: 4096 usa_ofs: 44103 usa_count: 45400: Invalid argument Jun 04 03:24:56 volumio ntfs-3g[1235]: Corrupt index block signature: vcn 0 inode 1985 Jun 04 03:24:56 volumio ntfs-3g[1235]: ntfs_mst_post_read_fixup_warn: magic: 0x2c14ebdd size: 4096 usa_ofs: 44103 usa_count: 45400: Invalid argument Jun 04 03:24:56 volumio ntfs-3g[1235]: Corrupt index block signature: vcn 0 inode 1985 Jun 04 03:24:56 volumio ntfs-3g[1235]: ntfs_mst_post_read_fixup_warn: magic: 0x02b1f073 size: 4096 usa_ofs: 55981 usa_count: 14492: Invalid argument Jun 04 03:24:56 volumio ntfs-3g[1235]: Corrupt index block signature: vcn 0 inode 1990 Jun 04 03:24:56 volumio ntfs-3g[1235]: ntfs_mst_post_read_fixup_warn: magic: 0x02b1f073 size: 4096 usa_ofs: 55981 usa_count: 14492: Invalid argument Jun 04 03:24:56 volumio ntfs-3g[1235]: Corrupt index block signature: vcn 0 inode 1990 Jun 04 03:24:56 volumio ntfs-3g[1235]: ntfs_mst_post_read_fixup_warn: magic: 0xd2850c51 size: 4096 usa_ofs: 15255 usa_count: 34870: Invalid argument Jun 04 03:24:56 volumio ntfs-3g[1235]: Corrupt index block signature: vcn 0 inode 2002 Jun 04 03:24:56 volumio ntfs-3g[1235]: ntfs_mst_post_read_fixup_warn: magic: 0xd2850c51 size: 4096 usa_ofs: 15255 usa_count: 34870: Invalid argument Jun 04 03:24:56 volumio ntfs-3g[1235]: Corrupt index block signature: vcn 0 inode 2002 Jun 04 03:24:56 volumio ntfs-3g[1235]: ntfs_mst_post_read_fixup_warn: magic: 0x38f2253d size: 4096 usa_ofs: 5580 usa_count: 30995: Invalid argument Jun 04 03:24:56 volumio ntfs-3g[1235]: Corrupt index block signature: vcn 0 inode 2014 Jun 04 03:24:56 volumio ntfs-3g[1235]: ntfs_mst_post_read_fixup_warn: magic: 0x38f2253d size: 4096 usa_ofs: 5580 usa_count: 30995: Invalid argument Jun 04 03:24:56 volumio ntfs-3g[1235]: Corrupt index block signature: vcn 0 inode 2014 Jun 04 03:24:59 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 04 03:24:59 volumio volumio[970]: info: CURURI: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res Jun 04 03:24:59 volumio volumio[970]: info: Preload queue cleared Jun 04 03:25:00 volumio ntpd[777]: Soliciting pool server 162.159.200.1 Jun 04 03:25:00 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 04 03:25:00 volumio volumio[970]: info: CURURI: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res Jun 04 03:25:00 volumio ntpd[777]: Soliciting pool server 217.71.200.210 Jun 04 03:25:00 volumio volumio[970]: info: Preload queue cleared Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/01. Bohemio.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/02. Tuyo Siempre.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/03. Estadio Azteca.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/04. Para No Olvidar.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/05. Mi Bandera.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/06. Flaca.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/07. Tantas Veces.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/09. Jugar Con Fuego.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/10. En Un Hotel De Mil Estrellas.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/11. Enganchate Conmigo.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/12. Pasemos A Otro Tema.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/13. Gaviotas.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/14. Horizontes.flac Jun 04 03:25:00 volumio volumio[970]: info: Preloading song: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/15. Paloma.flac Jun 04 03:25:00 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/01. Bohemio.flac in service mpd Jun 04 03:25:00 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F01.%20Bohemio.flac&metadata=false Jun 04 03:25:00 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/01. Bohemio.flac Jun 04 03:25:00 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/02. Tuyo Siempre.flac in service mpd Jun 04 03:25:00 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F02.%20Tuyo%20Siempre.flac&metadata=false Jun 04 03:25:00 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/02. Tuyo Siempre.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/03. Estadio Azteca.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F03.%20Estadio%20Azteca.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/03. Estadio Azteca.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/04. Para No Olvidar.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F04.%20Para%20No%20Olvidar.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/04. Para No Olvidar.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/05. Mi Bandera.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F05.%20Mi%20Bandera.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/05. Mi Bandera.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/06. Flaca.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F06.%20Flaca.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/06. Flaca.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/07. Tantas Veces.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F07.%20Tantas%20Veces.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/07. Tantas Veces.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F08.%20Alg%C3%BAn%20Lugar%20Encontrar%C3%A9.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/09. Jugar Con Fuego.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F09.%20Jugar%20Con%20Fuego.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/09. Jugar Con Fuego.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/10. En Un Hotel De Mil Estrellas.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F10.%20En%20Un%20Hotel%20De%20Mil%20Estrellas.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/10. En Un Hotel De Mil Estrellas.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/11. Enganchate Conmigo.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F11.%20Enganchate%20Conmigo.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/11. Enganchate Conmigo.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/12. Pasemos A Otro Tema.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F12.%20Pasemos%20A%20Otro%20Tema.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/12. Pasemos A Otro Tema.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/13. Gaviotas.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F13.%20Gaviotas.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/13. Gaviotas.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/14. Horizontes.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F14.%20Horizontes.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/14. Horizontes.flac Jun 04 03:25:01 volumio volumio[970]: info: Exploding uri music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/15. Paloma.flac in service mpd Jun 04 03:25:01 volumio volumio[970]: info: ALBUMART /albumart?cacheid=2&web=Andr%C3%A9s%20Calamaro/Dios%20Los%20Cr%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2Fflac%2F2021_Dios_Los_Cr%C3%ADa_Hi-Res%2F2021%20Dios%20Los%20Cr%C3%ADa%20Hi-Res%2F15.%20Paloma.flac&metadata=false Jun 04 03:25:01 volumio volumio[970]: info: URI /mnt/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/15. Paloma.flac Jun 04 03:25:01 volumio ntpd[777]: Soliciting pool server 94.143.139.219 Jun 04 03:25:01 volumio ntpd[777]: Soliciting pool server 178.32.88.247 Jun 04 03:25:01 volumio ntpd[777]: Soliciting pool server 162.159.200.123 Jun 04 03:25:02 volumio ntpd[777]: Soliciting pool server 178.32.88.247 Jun 04 03:25:03 volumio ntpd[777]: Soliciting pool server 90.68.206.60 Jun 04 03:25:04 volumio volumio[970]: info: Preload queue cleared Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::ClearQueue Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::stop Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::clearPlayQueue Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::saveQueue Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushQueue Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::addQueueItems Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::addQueueItems Jun 04 03:25:04 volumio volumio[970]: info: Preload queue cleared Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/01. Bohemio.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/01. Bohemio.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/02. Tuyo Siempre.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/02. Tuyo Siempre.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/03. Estadio Azteca.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/03. Estadio Azteca.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/04. Para No Olvidar.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/04. Para No Olvidar.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/05. Mi Bandera.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/05. Mi Bandera.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/06. Flaca.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/06. Flaca.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/07. Tantas Veces.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/07. Tantas Veces.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/09. Jugar Con Fuego.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/09. Jugar Con Fuego.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/10. En Un Hotel De Mil Estrellas.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/10. En Un Hotel De Mil Estrellas.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/11. Enganchate Conmigo.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/11. Enganchate Conmigo.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/12. Pasemos A Otro Tema.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/12. Pasemos A Otro Tema.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/13. Gaviotas.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/13. Gaviotas.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/14. Horizontes.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/14. Horizontes.flac Jun 04 03:25:04 volumio volumio[970]: info: Adding Item to queue: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/15. Paloma.flac Jun 04 03:25:04 volumio volumio[970]: info: Using cached record of: music-library/USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/15. Paloma.flac Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushQueue Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::saveQueue Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::updateTrackBlock Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrackBlock Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPlay Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::play index 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::stop Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::play index undefined Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::startPlaybackTimer Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::clearAddPlayTracks USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand stop took 2 milliseconds Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces system playlist update Jun 04 03:25:04 volumio volumio[970]: info: Ignoring MPD Status Update Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand clear took 3 milliseconds Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand add "USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac" Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces system playlist update Jun 04 03:25:04 volumio volumio[970]: info: Ignoring MPD Status Update Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces system playlist update Jun 04 03:25:04 volumio volumio[970]: info: Ignoring MPD Status Update Jun 04 03:25:04 volumio volumio[970]: error: updateQueue error: null Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces system playlist update Jun 04 03:25:04 volumio volumio[970]: info: Ignoring MPD Status Update Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 9ms Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand add "USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac" took 7 milliseconds Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 7ms Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 5ms Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand play Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces system playlist update Jun 04 03:25:04 volumio volumio[970]: info: Ignoring MPD Status Update Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces system playlist update Jun 04 03:25:04 volumio volumio[970]: info: Ignoring MPD Status Update Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces state update: player Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 14ms Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand play took 10 milliseconds Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 11ms Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 9ms Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces state update: player Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces state update: player Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces state update: player Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand status took 38 milliseconds Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand status took 36 milliseconds Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand status took 35 milliseconds Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand status took 8 milliseconds Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseTrackInfo Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseTrackInfo Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseTrackInfo Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: verbose: STATE SERVICE {"status":"play","position":0,"seek":52,"duration":255,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Algún Lugar Encontraré","artist":"Andrés Calamaro","album":"Dios Los Cría","uri":"USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac","trackType":"flac"} Jun 04 03:25:04 volumio volumio[970]: verbose: CURRENT POSITION 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState stateService play Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus stop Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73,"duration":255,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Algún Lugar Encontraré","artist":"Andrés Calamaro","album":"Dios Los Cría","uri":"USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac","trackType":"flac"} Jun 04 03:25:04 volumio volumio[970]: verbose: CURRENT POSITION 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState stateService play Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus play Jun 04 03:25:04 volumio volumio[970]: info: Received an update from plugin. extracting info from payload Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94,"duration":255,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1328 Kbps","isStreaming":false,"title":"Algún Lugar Encontraré","artist":"Andrés Calamaro","album":"Dios Los Cría","uri":"USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac","trackType":"flac"} Jun 04 03:25:04 volumio volumio[970]: verbose: CURRENT POSITION 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState stateService play Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus play Jun 04 03:25:04 volumio volumio[970]: info: Received an update from plugin. extracting info from payload Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 75ms Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 82ms Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 81ms Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces state update: player Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:25:04 volumio volumio[970]: info: Jun 04 03:25:04 volumio volumio[970]: ---------------------------- MPD announces state update: player Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::getState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand status Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand playlistinfo took 40 milliseconds Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand status took 4 milliseconds Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand status took 3 milliseconds Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseTrackInfo Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseState Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":255,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1603 Kbps","isStreaming":false,"title":"Algún Lugar Encontraré","artist":"Andrés Calamaro","album":"Dios Los Cría","uri":"USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac","trackType":"flac"} Jun 04 03:25:04 volumio volumio[970]: verbose: CURRENT POSITION 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState stateService play Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus play Jun 04 03:25:04 volumio volumio[970]: info: Received an update from plugin. extracting info from payload Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 74ms Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 04 03:25:04 volumio volumio[970]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseTrackInfo Jun 04 03:25:04 volumio volumio[970]: verbose: ControllerMpd::parseTrackInfo Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":255,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1603 Kbps","isStreaming":false,"title":"Algún Lugar Encontraré","artist":"Andrés Calamaro","album":"Dios Los Cría","uri":"USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac","trackType":"flac"} Jun 04 03:25:04 volumio volumio[970]: verbose: CURRENT POSITION 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState stateService play Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus play Jun 04 03:25:04 volumio volumio[970]: info: Received an update from plugin. extracting info from payload Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:25:04 volumio volumio[970]: info: ControllerMpd::pushState Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::servicePushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":255,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1603 Kbps","isStreaming":false,"title":"Algún Lugar Encontraré","artist":"Andrés Calamaro","album":"Dios Los Cría","uri":"USB/flac/2021_Dios_Los_Cría_Hi-Res/2021 Dios Los Cría Hi-Res/08. Algún Lugar Encontraré.flac","trackType":"flac"} Jun 04 03:25:04 volumio volumio[970]: verbose: CURRENT POSITION 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState stateService play Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::syncState currentStatus play Jun 04 03:25:04 volumio volumio[970]: info: Received an update from plugin. extracting info from payload Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:25:04 volumio volumio[970]: info: CoreStateMachine::pushState Jun 04 03:25:04 volumio volumio[970]: info: CorePlayQueue::getTrack 7 Jun 04 03:25:04 volumio volumio[970]: info: CoreCommandRouter::volumioPushState Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 57ms Jun 04 03:25:04 volumio volumio[970]: info: ------------------------------ 56ms Jun 04 03:25:05 volumio volumio[970]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 03:25:05 volumio volumio[970]: Error: certificate is not yet valid Jun 04 03:25:05 volumio volumio[970]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jun 04 03:25:05 volumio volumio[970]: at TLSSocket.emit (events.js:315:20) Jun 04 03:25:05 volumio volumio[970]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jun 04 03:25:05 volumio volumio[970]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jun 04 03:25:05 volumio volumio[970]: code: 'CERT_NOT_YET_VALID' Jun 04 03:25:05 volumio volumio[970]: } Jun 04 03:25:05 volumio volumio[970]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 03:25:05 volumio sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-04 03:24 Jun 04 03:25:05 volumio sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 03:25:05 volumio ntpd[777]: Soliciting pool server 158.227.98.15 PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ef589a0572b08c3d1e9a984ec6a655ef3698e415" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="abe72e92bc7de90ab6193cbc17e21ad2750980a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 28 May 2024 12:17:12 PM CEST" VOLUMIO_VERSION="3.695" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f37ff40efaaf3752fac55d6a95d61cb"