-- Logs begin at Tue 2024-08-20 13:28:48 KST, end at Tue 2024-08-20 13:30:41 KST. -- Aug 20 13:29:00 volumio dhcpcd[883]: eth0: rebinding lease of 192.168.0.105 Aug 20 13:29:00 volumio dhcpcd[883]: eth0: soliciting an IPv6 router Aug 20 13:29:00 volumio dhcpcd[883]: eth0: leased 192.168.0.105 for 7200 seconds Aug 20 13:29:00 volumio dhcpcd[883]: eth0: adding route to 192.168.0.0/24 Aug 20 13:29:00 volumio dhcpcd[883]: eth0: adding default route via 192.168.0.1 Aug 20 13:29:00 volumio ifplugd(eth0)[812]: client: ifup: interface eth0 already configured Aug 20 13:29:00 volumio sh[545]: eth0=eth0 Aug 20 13:29:00 volumio wpa_supplicant[896]: wlan0: Trying to associate with SSID 'HJB' Aug 20 13:29:00 volumio dhcpcd[883]: forked to background, child pid 981 Aug 20 13:29:00 volumio sudo[882]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:00 volumio ifplugd(eth0)[812]: Program executed successfully. Aug 20 13:29:00 volumio ifplugd[759]: Network Interface Plugging Daemon...start eth0...done. Aug 20 13:29:00 volumio systemd[1]: Started LSB: Brings up/down network automatically. Aug 20 13:29:00 volumio wireless.js[640]: trying... Aug 20 13:29:00 volumio sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 20 13:29:00 volumio sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:00 volumio sudo[983]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:00 volumio dhcpcd[981]: wlan0: carrier acquired Aug 20 13:29:00 volumio dhcpcd[981]: wlan0: connected to Access Point `HJB' Aug 20 13:29:00 volumio wpa_supplicant[896]: wlan0: Associated with 70:5d:cc:6f:99:66 Aug 20 13:29:00 volumio wpa_supplicant[896]: wlan0: CTRL-EVENT-CONNECTED - Connection to 70:5d:cc:6f:99:66 completed [id=0 id_str=] Aug 20 13:29:00 volumio wpa_supplicant[896]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 20 13:29:00 volumio dhcpcd[981]: wlan0: IAID eb:6d:17:4d Aug 20 13:29:00 volumio dhcpcd[981]: wlan0: adding address fe80::942e:fcaa:f653:958 Aug 20 13:29:00 volumio dhcpcd[981]: ipv6_addaddr1: Permission denied Aug 20 13:29:00 volumio dhcpcd[981]: wlan0: carrier lost Aug 20 13:29:00 volumio dhcpcd[981]: wlan0: carrier acquired Aug 20 13:29:00 volumio dhcpcd[981]: wlan0: IAID eb:6d:17:4d Aug 20 13:29:01 volumio dhcpcd[981]: wlan0: rebinding lease of 192.168.0.123 Aug 20 13:29:01 volumio dhcpcd[981]: wlan0: probing address 192.168.0.123/24 Aug 20 13:29:01 volumio wireless.js[640]: trying... Aug 20 13:29:01 volumio sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 20 13:29:01 volumio sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:01 volumio sudo[1005]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:01 volumio nmbd[782]: [2024/08/20 13:29:01.547395, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 20 13:29:01 volumio nmbd[782]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 20 13:29:01 volumio systemd[1]: Started Samba NMB Daemon. Aug 20 13:29:01 volumio systemd[1]: Starting Samba Winbind Daemon... Aug 20 13:29:01 volumio dhcpcd[981]: wlan0: soliciting an IPv6 router Aug 20 13:29:01 volumio winbindd[1007]: [2024/08/20 13:29:01.936151, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 20 13:29:01 volumio winbindd[1007]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 20 13:29:01 volumio winbindd[1007]: [2024/08/20 13:29:01.970074, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 20 13:29:01 volumio winbindd[1007]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 20 13:29:01 volumio systemd[1]: Started Samba Winbind Daemon. Aug 20 13:29:02 volumio systemd[1]: Starting Samba SMB Daemon... Aug 20 13:29:02 volumio ntpd[796]: Listen normally on 3 eth0 192.168.0.105:123 Aug 20 13:29:02 volumio ntpd[796]: new interface(s) found: waking up resolver Aug 20 13:29:02 volumio wireless.js[640]: trying... Aug 20 13:29:02 volumio sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 20 13:29:02 volumio sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:02 volumio sudo[1014]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:02 volumio smbd[1012]: [2024/08/20 13:29:02.589234, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 20 13:29:02 volumio systemd[1]: Started Samba SMB Daemon. Aug 20 13:29:02 volumio smbd[1012]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 20 13:29:03 volumio wireless.js[640]: trying... Aug 20 13:29:03 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 20 13:29:03 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:03 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:04 volumio wireless.js[640]: trying... Aug 20 13:29:04 volumio sudo[1027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 20 13:29:04 volumio sudo[1027]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:04 volumio sudo[1027]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:05 volumio wireless.js[640]: trying... Aug 20 13:29:05 volumio sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 20 13:29:05 volumio sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:05 volumio sudo[1030]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:06 volumio wireless.js[640]: trying... Aug 20 13:29:06 volumio sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 20 13:29:06 volumio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:06 volumio sudo[1033]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:06 volumio dhcpcd[981]: wlan0: leased 192.168.0.123 for 7200 seconds Aug 20 13:29:06 volumio avahi-daemon[688]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.123. Aug 20 13:29:06 volumio avahi-daemon[688]: New relevant interface wlan0.IPv4 for mDNS. Aug 20 13:29:06 volumio dhcpcd[981]: wlan0: adding route to 192.168.0.0/24 Aug 20 13:29:06 volumio avahi-daemon[688]: Registering new address record for 192.168.0.123 on wlan0.IPv4. Aug 20 13:29:06 volumio dhcpcd[981]: wlan0: adding default route via 192.168.0.1 Aug 20 13:29:06 volumio volumio-remote-updater[647]: [2024-08-20 13:29:06] [info] asio async_connect error: system:111 (Connection refused) Aug 20 13:29:06 volumio volumio-remote-updater[647]: [2024-08-20 13:29:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 20 13:29:06 volumio volumio-remote-updater[647]: [2024-08-20 13:29:06] [error] handle_connect error: Underlying Transport Error Aug 20 13:29:07 volumio wireless.js[640]: trying... Aug 20 13:29:07 volumio sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 20 13:29:07 volumio sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:07 volumio sudo[1056]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:07 volumio wireless.js[640]: Connected to: ----HJB Aug 20 13:29:07 volumio wireless.js[640]: ---- Aug 20 13:29:07 volumio sudo[1059]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 13:29:07 volumio sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:07 volumio sudo[1059]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:07 volumio wireless.js[640]: ... joined AP, wlan0 IPv4 is 192.168.0.123, ipV6 is undefined Aug 20 13:29:07 volumio wireless.js[640]: It's done! AP Aug 20 13:29:07 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 20 13:29:07 volumio systemd[1]: Started Wireless Services. Aug 20 13:29:07 volumio systemd[1]: Started Volumio Backend Module. Aug 20 13:29:07 volumio systemd[1]: Started Volumio Cpu Tweaker. Aug 20 13:29:07 volumio systemd[1]: Reached target Multi-User System. Aug 20 13:29:07 volumio systemd[1]: Reached target Graphical Interface. Aug 20 13:29:07 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 20 13:29:07 volumio volumio-cpu-tweak[1065]: Setting RT Priority for mpd Aug 20 13:29:07 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 20 13:29:07 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 20 13:29:07 volumio systemd[1]: Startup finished in 17.521s (kernel) + 21.839s (userspace) = 39.361s. Aug 20 13:29:07 volumio volumio-cpu-tweak[1065]: pid 35's current scheduling policy: SCHED_OTHER Aug 20 13:29:07 volumio volumio-cpu-tweak[1065]: pid 35's current scheduling priority: 0 Aug 20 13:29:07 volumio volumio-cpu-tweak[1065]: Setting MPD Affinity Aug 20 13:29:07 volumio volumio-cpu-tweak[1065]: pid 3's current affinity mask: f Aug 20 13:29:07 volumio volumio-cpu-tweak[1065]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 20 13:29:07 volumio volumio-cpu-tweak[1065]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 20 13:29:07 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 20 13:29:08 volumio ntpd[796]: Listen normally on 4 wlan0 192.168.0.123:123 Aug 20 13:29:08 volumio ntpd[796]: new interface(s) found: waking up resolver Aug 20 13:29:10 volumio volumio[1064]: info: ------------------------------------------- Aug 20 13:29:10 volumio volumio[1064]: info: ----- Volumio3 ---- Aug 20 13:29:10 volumio volumio[1064]: info: ------------------------------------------- Aug 20 13:29:10 volumio volumio[1064]: info: ----- System startup ---- Aug 20 13:29:10 volumio volumio[1064]: info: ------------------------------------------- Aug 20 13:29:11 volumio volumio[1064]: info: MYVOLUMIO Environment detected Aug 20 13:29:11 volumio volumio[1064]: info: Plugin folders cleanup Aug 20 13:29:11 volumio volumio[1064]: info: Scanning into folder /volumio/app/plugins/ Aug 20 13:29:11 volumio volumio[1064]: info: Scanning category audio_interface Aug 20 13:29:11 volumio volumio[1064]: info: Scanning category miscellanea Aug 20 13:29:11 volumio volumio[1064]: info: Scanning category music_service Aug 20 13:29:11 volumio volumio[1064]: info: Scanning category plugins.json Aug 20 13:29:11 volumio volumio[1064]: info: Scanning category system_controller Aug 20 13:29:11 volumio volumio[1064]: info: Scanning category user_interface Aug 20 13:29:11 volumio volumio[1064]: info: Scanning into folder /data/plugins/ Aug 20 13:29:11 volumio volumio[1064]: info: Scanning category music_service Aug 20 13:29:11 volumio volumio[1064]: info: Plugin folders cleanup completed Aug 20 13:29:11 volumio volumio[1064]: info: ------------------------------------------- Aug 20 13:29:11 volumio volumio[1064]: info: ----- Core plugins startup ---- Aug 20 13:29:11 volumio volumio[1064]: info: ------------------------------------------- Aug 20 13:29:11 volumio volumio[1064]: info: Loading plugins from folder /volumio/app/plugins/ Aug 20 13:29:11 volumio volumio[1064]: info: Adding plugin upnp to MyMusic Plugins Aug 20 13:29:11 volumio volumio[1064]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 20 13:29:11 volumio volumio[1064]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 20 13:29:11 volumio volumio[1064]: info: Loading plugins from folder /data/plugins/ Aug 20 13:29:11 volumio volumio[1064]: info: Loading plugin "system"... Aug 20 13:29:11 volumio volumio[1064]: info: Loading plugin "appearance"... Aug 20 13:29:13 volumio volumio[1064]: info: Loading plugin "network"... Aug 20 13:29:13 volumio volumio[1064]: info: Refreshing Cached IP Addresses Aug 20 13:29:13 volumio sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 20 13:29:13 volumio sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:13 volumio sudo[1096]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:13 volumio volumio[1064]: info: Loading plugin "services"... Aug 20 13:29:13 volumio sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 13:29:13 volumio sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:13 volumio volumio[1064]: info: Loading plugin "alsa_controller"... Aug 20 13:29:13 volumio sudo[1098]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:13 volumio sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 20 13:29:13 volumio sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:13 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 13:29:13 volumio volumio[1064]: info: Loading plugin "wizard"... Aug 20 13:29:13 volumio volumio[1064]: info: Loading plugin "networkfs"... Aug 20 13:29:13 volumio volumio[1064]: info: Starting Udev Watcher for removable devices Aug 20 13:29:13 volumio sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=blackwj,password=Audrkaehr85,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.12/music /mnt/NAS/Nass Aug 20 13:29:13 volumio sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:13 volumio volumio[1064]: info: Ignoring mount for partition: boot Aug 20 13:29:13 volumio volumio[1064]: info: Ignoring mount for partition: volumio Aug 20 13:29:13 volumio volumio[1064]: info: Ignoring mount for partition: volumio_data Aug 20 13:29:13 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 20 13:29:13 volumio volumio[1064]: info: Loading plugin "volumio_command_line_client"... Aug 20 13:29:13 volumio volumio[1064]: info: Loading plugin "upnp"... Aug 20 13:29:13 volumio volumio[1064]: info: [1724128153799] Starting Upmpd Daemon Aug 20 13:29:13 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 20 13:29:13 volumio volumio[1064]: info: Loading plugin "my_music"... Aug 20 13:29:13 volumio volumio[1064]: info: Loading plugin "mpd"... Aug 20 13:29:13 volumio kernel: Key type cifs.spnego registered Aug 20 13:29:13 volumio kernel: Key type cifs.idmap registered Aug 20 13:29:13 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Aug 20 13:29:13 volumio kernel: CIFS: Attempting to mount \\192.168.0.12\music Aug 20 13:29:14 volumio sudo[1109]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:14 volumio volumio[1064]: info: Loading plugin "upnp_browser"... Aug 20 13:29:14 volumio sudo[1101]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:14 volumio volumio[1064]: info: Loading plugin "alarm-clock"... Aug 20 13:29:15 volumio volumio[1064]: info: Loading plugin "airplay_emulation"... Aug 20 13:29:15 volumio volumio[1064]: info: Starting Shairport Sync Aug 20 13:29:15 volumio volumio[1064]: info: Loading plugin "last_100"... Aug 20 13:29:15 volumio volumio[1064]: info: Loading plugin "webradio"... Aug 20 13:29:15 volumio volumio[1064]: info: Loading plugin "i2s_dacs"... Aug 20 13:29:15 volumio volumio[1064]: info: I2S DAC not set, start Auto-detection Aug 20 13:29:15 volumio volumio[1064]: info: Loading plugin "volumiodiscovery"... Aug 20 13:29:15 volumio volumio[1064]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 20 13:29:15 volumio volumio[1064]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 13:29:15 volumio volumio[1064]: *** WARNING *** For more information see Aug 20 13:29:15 volumio volumio[1064]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 20 13:29:15 volumio node[1064]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 20 13:29:15 volumio volumio[1064]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 13:29:15 volumio volumio[1064]: *** WARNING *** For more information see Aug 20 13:29:15 volumio node[1064]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 13:29:15 volumio node[1064]: *** WARNING *** For more information see Aug 20 13:29:15 volumio node[1064]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 20 13:29:15 volumio node[1064]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 13:29:15 volumio node[1064]: *** WARNING *** For more information see Aug 20 13:29:15 volumio volumio[1064]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 20 13:29:15 volumio volumio[1064]: info: Discovery: Started advertising with name: Volumio Aug 20 13:29:15 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 20 13:29:15 volumio volumio[1064]: info: Loading plugin "ytmusic"... Aug 20 13:29:16 volumio volumio-remote-updater[647]: [2024-08-20 13:29:16] [connect] Successful connection Aug 20 13:29:17 volumio volumio[1064]: info: Loading plugin "outputs"... Aug 20 13:29:17 volumio volumio[1064]: info: Loading plugin "albumart"... Aug 20 13:29:17 volumio volumio[1064]: info: Plugin example_plugin is not enabled Aug 20 13:29:17 volumio volumio[1064]: info: Loading plugin "inputs"... Aug 20 13:29:17 volumio volumio[1064]: info: Loading plugin "updater_comm"... Aug 20 13:29:17 volumio volumio[1064]: info: Plugin mpdemulation is not enabled Aug 20 13:29:17 volumio volumio[1064]: info: Loading plugin "rest_api"... Aug 20 13:29:17 volumio volumio[1064]: info: Loading plugin "websocket"... Aug 20 13:29:17 volumio volumio[1064]: info: Starting Socket.io Server version 2.3.0 Aug 20 13:29:17 volumio volumio[1064]: info: Loading i18n strings for locale ko Aug 20 13:29:17 volumio volumio[1064]: Updating browse sources language Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 13:29:17 volumio volumio[1064]: Forking 3 albumart workers Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::initPlayerControls Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 13:29:17 volumio volumio[1064]: Express server listening on port 3000 Aug 20 13:29:17 volumio volumio[1064]: [Metrics] WebUI: 8s 301.76ms Aug 20 13:29:17 volumio volumio[1064]: info: CoreStateMachine::resetVolumioState Aug 20 13:29:17 volumio volumio[1064]: info: CoreStateMachine::getcurrentVolume Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::volumioRetrievevolume Aug 20 13:29:17 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:18 volumio volumio-remote-updater[647]: [2024-08-20 13:29:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724128156 101 Aug 20 13:29:18 volumio volumio[1064]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Aug 20 13:29:18 volumio volumio[1064]: info: Reloading queue from file Aug 20 13:29:18 volumio volumio[1064]: info: CoreStateMachine::setRepeat true single undefined Aug 20 13:29:18 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:18 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:18 volumio volumio[1064]: info: CoreStateMachine::setRandom null Aug 20 13:29:18 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:18 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:18 volumio volumio[1064]: info: Setting Device type: Raspberry PI Aug 20 13:29:18 volumio volumio[1064]: info: VolumeController:: Volume=100 Mute =false Aug 20 13:29:18 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:18 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:18 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock Aug 20 13:29:18 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::volumioRetrievevolume Aug 20 13:29:18 volumio volumio[1064]: info: Completed loading Core Plugins Aug 20 13:29:18 volumio volumio[1064]: info: Preparing to generate the ALSA configuration file Aug 20 13:29:18 volumio volumio[1064]: info: Discovery: adding 8cb40448-d827-424c-8904-1f08b72f6663 Aug 20 13:29:18 volumio volumio[1064]: info: Discovery: Found device Volumio Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:18 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:18 volumio volumio[1064]: info: Discovery: this is already registered, 8cb40448-d827-424c-8904-1f08b72f6663 Aug 20 13:29:18 volumio volumio[1064]: info: Discovery: Found device Volumio Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:18 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:18 volumio volumio[1064]: info: Asound.conf file unchanged, so no further update is needed Aug 20 13:29:18 volumio volumio[1064]: info: Output device has changed, restarting MPD Aug 20 13:29:18 volumio volumio[1064]: info: Output device has changed, restarting Shairport Sync Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:18 volumio sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 20 13:29:18 volumio sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 20 13:29:18 volumio sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:18 volumio sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:18 volumio sudo[1178]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:18 volumio volumio[1064]: Starting albumart workers Aug 20 13:29:18 volumio volumio[1064]: Starting albumart workers Aug 20 13:29:18 volumio volumio[1064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 20 13:29:18 volumio volumio[1064]: info: ___________ START PLUGINS ___________ Aug 20 13:29:18 volumio volumio[1064]: Starting albumart workers Aug 20 13:29:18 volumio volumio[1064]: info: ControllerMpd::onStart: Initializing MPD Aug 20 13:29:18 volumio volumio[1064]: info: Creating MPD Configuration file Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 20 13:29:18 volumio volumio[1064]: info: [1724128158753] CoreMusicLibrary::Adding element 미디어 서버 Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 13:29:18 volumio systemd[1]: Listening on mpd.socket. Aug 20 13:29:18 volumio systemd[1]: Starting Music Player Daemon... Aug 20 13:29:18 volumio sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 20 13:29:18 volumio sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:18 volumio sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 20 13:29:18 volumio sudo[1189]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:18 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:18 volumio sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:18 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 20 13:29:18 volumio systemd[1]: mpd.service: Succeeded. Aug 20 13:29:18 volumio systemd[1]: Stopped Music Player Daemon. Aug 20 13:29:18 volumio systemd[1]: Starting Music Player Daemon... Aug 20 13:29:19 volumio volumio[1064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:19 volumio sudo[1197]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 20 13:29:19 volumio sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:19 volumio sudo[1197]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 20 13:29:19 volumio sudo[1197]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:19 volumio volumio[1064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 20 13:29:19 volumio volumio[1064]: info: [1724128159108] CoreMusicLibrary::Adding element Last_100 Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 20 13:29:19 volumio volumio[1064]: info: [1724128159117] CoreMusicLibrary::Adding element Webradio Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 13:29:19 volumio volumio[1064]: info: Initializing BBC Radios Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 20 13:29:19 volumio volumio[1064]: info: [1724128159382] CoreMusicLibrary::Adding element YouTube Music Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 13:29:19 volumio volumio[1064]: Cannot find translation for source YouTube Music Aug 20 13:29:19 volumio volumio[1064]: info: Volumio Calling Home Aug 20 13:29:19 volumio volumio[1064]: info: MPD Permissions set Aug 20 13:29:19 volumio volumio[1064]: info: MPD Permissions set Aug 20 13:29:19 volumio volumio[1064]: info: VolumeController:: Volume=100 Mute =false Aug 20 13:29:19 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:19 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:19 volumio volumio[1064]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:20 volumio volumio[1064]: info: Volumio called home Aug 20 13:29:20 volumio volumio[1064]: info: Starting Shairport Sync Aug 20 13:29:20 volumio volumio[1064]: info: Starting Shairport Sync Aug 20 13:29:20 volumio volumio[1064]: info: Starting Shairport Sync Aug 20 13:29:20 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 20 13:29:20 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:20 volumio sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 20 13:29:20 volumio sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:20 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:20 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 20 13:29:20 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 20 13:29:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 20 13:29:20 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 20 13:29:20 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 20 13:29:20 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:20 volumio volumio[1064]: info: Shairport-Sync Started Aug 20 13:29:20 volumio volumio[1064]: Error adding Membership: Error: addMembership EINVAL Aug 20 13:29:20 volumio sudo[1251]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 20 13:29:20 volumio volumio[1064]: info: Shairport-Sync Started Aug 20 13:29:20 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 20 13:29:20 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 20 13:29:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 20 13:29:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 20 13:29:20 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:20 volumio volumio[1064]: info: Shairport-Sync Started Aug 20 13:29:21 volumio systemd[1]: systemd-fsckd.service: Succeeded. Aug 20 13:29:22 volumio mpd[1202]: Aug 20 13:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 20 13:29:22 volumio systemd[1]: Started Music Player Daemon. Aug 20 13:29:22 volumio sudo[1180]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:22 volumio sudo[1190]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:22 volumio volumio[1064]: info: Completed starting Core Plugins Aug 20 13:29:22 volumio volumio[1064]: info: ------------------------------------------- Aug 20 13:29:22 volumio volumio[1064]: info: ----- MyVolumio plugins startup ---- Aug 20 13:29:22 volumio volumio[1064]: info: ------------------------------------------- Aug 20 13:29:22 volumio volumio[1064]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 20 13:29:22 volumio volumio[1064]: error: MPD error: The expression evaluated to a falsy value: Aug 20 13:29:22 volumio volumio[1064]: assert.ok(self.idling) Aug 20 13:29:22 volumio volumio[1064]: error: The expression evaluated to a falsy value: Aug 20 13:29:22 volumio volumio[1064]: assert.ok(self.idling) Aug 20 13:29:22 volumio volumio[1064]: error: updateQueue error: null Aug 20 13:29:22 volumio volumio[1064]: info: MPD running with PID1202 Aug 20 13:29:22 volumio volumio[1064]: ,establishing connection Aug 20 13:29:22 volumio volumio[1064]: error: updateQueue error: null Aug 20 13:29:23 volumio sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 20 13:29:23 volumio sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:23 volumio sudo[1267]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:23 volumio sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 13:29:23 volumio sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:23 volumio sudo[1269]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:23 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 20 13:29:23 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:23 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 20 13:29:23 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:23 volumio volumio[1064]: info: Upmpdcli Daemon Started Aug 20 13:29:24 volumio volumio[1275]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 20 13:29:26 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:26 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:26 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:26 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:26 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:26 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.105:3000 from 192.168.0.121 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:26 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 20 13:29:26 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 20 13:29:27 volumio volumio[1064]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 20 13:29:28 volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 20 13:29:28 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:28 volumio sudo[1294]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:28 volumio sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 13:29:28 volumio sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:28 volumio sudo[1296]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:28 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.121 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Aug 20 13:29:28 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 20 13:29:29 volumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 20 13:29:29 volumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:29 volumio sudo[1300]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:29 volumio sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 13:29:29 volumio sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:29 volumio sudo[1302]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:29 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.121 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::volumioGetVisibleSources Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:29 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 20 13:29:29 volumio volumio[1064]: info: Received Get System Info Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:29 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:29 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:29 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:29 volumio volumio[1064]: info: Listing playlists Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:29 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:29 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:30 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 20 13:29:30 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 20 13:29:30 volumio volumio[1064]: info: Received Get System Info Aug 20 13:29:30 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:30 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:30 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:30 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:30 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:30 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 20 13:29:30 volumio volumio[1064]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 20 13:29:30 volumio volumio[1064]: info: Adding plugin bluetooth to MyMusic Plugins Aug 20 13:29:30 volumio volumio[1064]: info: Adding plugin multiroom to MyMusic Plugins Aug 20 13:29:31 volumio volumio[1064]: info: Adding plugin metavolumio to MyMusic Plugins Aug 20 13:29:31 volumio volumio[1064]: info: Adding plugin cd_controller to MyMusic Plugins Aug 20 13:29:31 volumio volumio[1064]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 20 13:29:31 volumio volumio[1064]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 20 13:29:31 volumio volumio[1064]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 20 13:29:32 volumio volumio[1064]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 20 13:29:32 volumio volumio[1064]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 20 13:29:32 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:32 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:32 volumio volumio[1064]: info: Starting MyVolumio Remote Streaming Endpoints Aug 20 13:29:32 volumio volumio[1064]: info: MyVolumio login type: Token Aug 20 13:29:32 volumio volumio[1064]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 20 13:29:32 volumio volumio[1064]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 20 13:29:33 volumio volumio[1064]: info: Starting Streaming Service Transparent Proxy Aug 20 13:29:33 volumio volumio[1064]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 20 13:29:33 volumio volumio[1064]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 20 13:29:33 volumio volumio[1064]: info: Streaming services startup Aug 20 13:29:33 volumio volumio[1064]: info: Starting Streaming Daemon Aug 20 13:29:34 volumio volumio[1064]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 20 13:29:34 volumio sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 20 13:29:34 volumio sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:34 volumio sudo[1312]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:34 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 20 13:29:34 volumio volumio[1064]: info: Received Get System Info Aug 20 13:29:34 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:34 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:34 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:34 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:34 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:34 volumio volumio[1064]: error: Cannot start Volumio Streaming Daemon Aug 20 13:29:34 volumio volumio[1064]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 20 13:29:34 volumio volumio[1064]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 20 13:29:35 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 20 13:29:35 volumio volumio[1064]: info: MyVolumio login type: Token Aug 20 13:29:35 volumio volumio[1064]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 20 13:29:35 volumio volumio[1064]: STREAMING PROXY: Starting server on port 3245 Aug 20 13:29:35 volumio volumio[1064]: Node JS runtime: 14 Aug 20 13:29:36 volumio volumio[1064]: info: MyVolumio token set successfully Aug 20 13:29:36 volumio volumio[1064]: info: MYVOLUMIO: Adding device Aug 20 13:29:36 volumio volumio[1064]: info: MYVOLUMIO: Evaluating Server Aug 20 13:29:36 volumio volumio[1064]: info: MyVolumio status changed Aug 20 13:29:36 volumio volumio[1064]: info: Streaming services startup Aug 20 13:29:36 volumio volumio[1064]: info: Starting Streaming Daemon Aug 20 13:29:36 volumio volumio[1064]: info: Removing browser output: myVolumio user plan is not superstar Aug 20 13:29:36 volumio volumio[1064]: info: Removing audio output: Aug 20 13:29:36 volumio volumio[1064]: info: Stoppping Tunnel 1 Aug 20 13:29:36 volumio sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 20 13:29:36 volumio sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:36 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 20 13:29:36 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:36 volumio sudo[1337]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:36 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:36 volumio volumio[1064]: info: Remote SSH Stopped Aug 20 13:29:36 volumio volumio[1064]: error: Cannot start Volumio Streaming Daemon Aug 20 13:29:36 volumio volumio[1064]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 20 13:29:36 volumio volumio[1064]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 20 13:29:37 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 20 13:29:37 volumio volumio[1064]: info: Received Get System Info Aug 20 13:29:37 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:37 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:37 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:37 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:37 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:37 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:38 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Aug 20 13:29:38 volumio volumio[1064]: info: CoreStateMachine::getQueue Aug 20 13:29:38 volumio volumio[1064]: info: CorePlayQueue::getQueue Aug 20 13:29:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 20 13:29:38 volumio volumio[1064]: info: Received Get System Info Aug 20 13:29:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:38 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:38 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:38 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:39 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 20 13:29:41 volumio volumio[1064]: info: Setting Geolocation for MyVolumio to as1 Aug 20 13:29:41 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:41 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:41 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:42 volumio volumio[1064]: info: Updating MyVolumio device info Aug 20 13:29:42 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:42 volumio volumio[1064]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 20 13:29:43 volumio volumio[1064]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 20 13:29:43 volumio volumio[1275]: ...............................................................................................................................................++++ Aug 20 13:29:44 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 13:29:44 volumio volumio[1064]: info: CURURI: albums:// Aug 20 13:29:44 volumio volumio[1064]: info: listAlbums - loading Albums from cache Aug 20 13:29:44 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:29:47 volumio volumio[1064]: info: MYVOLUMIO: Adding device Aug 20 13:29:47 volumio volumio[1064]: info: MYVOLUMIO: Evaluating Server Aug 20 13:29:47 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 13:29:47 volumio volumio[1064]: info: CURURI: albums://AC%2FDC/Blow%20Up%20Your%20Video Aug 20 13:29:47 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:29:47 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-02 That's the Way I Wanna Rock 'N.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-03 Meanstreak.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-04 Go Zone.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-05 Kissin' Dynamite.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-06 Nick of Time.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-07 Some Sin for Nuthin'.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-08 Ruff Stuff.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-09 Two's Up.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-10 This Means War.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 20 13:29:47 volumio volumio[1064]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 20 13:29:47 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3 in service mpd Aug 20 13:29:47 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Blow%20Up%20Your%20Video/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B13%5DBlow%20Up%20Your%20Video%2F13-01%20Heatseeker.mp3&metadata=false Aug 20 13:29:47 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:47 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:47 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-02 That's the Way I Wanna Rock 'N.mp3 in service mpd Aug 20 13:29:47 volumio volumio[1064]: info: No valid Plugin REST Endpoint Aug 20 13:29:47 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Blow%20Up%20Your%20Video/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B13%5DBlow%20Up%20Your%20Video%2F13-02%20That's%20the%20Way%20I%20Wanna%20Rock%20'N.mp3&metadata=false Aug 20 13:29:47 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-02 That's the Way I Wanna Rock 'N.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-03 Meanstreak.mp3 in service mpd Aug 20 13:29:47 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-04 Go Zone.mp3 in service mpd Aug 20 13:29:47 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-05 Kissin' Dynamite.mp3 in service mpd Aug 20 13:29:47 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-06 Nick of Time.mp3 in service mpd Aug 20 13:29:47 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-07 Some Sin for Nuthin'.mp3 in service mpd Aug 20 13:29:47 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-08 Ruff Stuff.mp3 in service mpd Aug 20 13:29:47 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-09 Two's Up.mp3 in service mpd Aug 20 13:29:47 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Blow%20Up%20Your%20Video/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B13%5DBlow%20Up%20Your%20Video%2F13-03%20Meanstreak.mp3&metadata=false Aug 20 13:29:47 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-03 Meanstreak.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Blow%20Up%20Your%20Video/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B13%5DBlow%20Up%20Your%20Video%2F13-04%20Go%20Zone.mp3&metadata=false Aug 20 13:29:47 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-04 Go Zone.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Blow%20Up%20Your%20Video/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B13%5DBlow%20Up%20Your%20Video%2F13-05%20Kissin'%20Dynamite.mp3&metadata=false Aug 20 13:29:47 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-05 Kissin' Dynamite.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Blow%20Up%20Your%20Video/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B13%5DBlow%20Up%20Your%20Video%2F13-06%20Nick%20of%20Time.mp3&metadata=false Aug 20 13:29:47 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-06 Nick of Time.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Blow%20Up%20Your%20Video/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B13%5DBlow%20Up%20Your%20Video%2F13-07%20Some%20Sin%20for%20Nuthin'.mp3&metadata=false Aug 20 13:29:47 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-07 Some Sin for Nuthin'.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Blow%20Up%20Your%20Video/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B13%5DBlow%20Up%20Your%20Video%2F13-08%20Ruff%20Stuff.mp3&metadata=false Aug 20 13:29:47 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-08 Ruff Stuff.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Blow%20Up%20Your%20Video/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B13%5DBlow%20Up%20Your%20Video%2F13-09%20Two's%20Up.mp3&metadata=false Aug 20 13:29:47 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-09 Two's Up.mp3 Aug 20 13:29:47 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 20 13:29:47 volumio volumio[1064]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 20 13:29:47 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 20 13:29:47 volumio volumio[1064]: info: Received Get System Version Aug 20 13:29:47 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 13:29:47 volumio volumio[1064]: info: Received Get System Info Aug 20 13:29:47 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:47 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:47 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:47 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:47 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:47 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:47 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-10 This Means War.mp3 in service mpd Aug 20 13:29:47 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Blow%20Up%20Your%20Video/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B13%5DBlow%20Up%20Your%20Video%2F13-10%20This%20Means%20War.mp3&metadata=false Aug 20 13:29:47 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-10 This Means War.mp3 Aug 20 13:29:48 volumio volumio[1064]: info: Setting Geolocation for MyVolumio to as1 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:48 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::ClearQueue Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::stop Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::clearPlayQueue Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::saveQueue Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::addQueueItems Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::addQueueItems Aug 20 13:29:48 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:29:48 volumio volumio[1064]: info: Adding Item to queue: albums://AC%2FDC/Blow%20Up%20Your%20Video Aug 20 13:29:48 volumio volumio[1064]: info: Exploding uri albums://AC%2FDC/Blow%20Up%20Your%20Video in service mpd Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::saveQueue Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPlay Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::play index 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::stop Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::play index undefined Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::startPlaybackTimer Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::clearAddPlayTracks NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3 Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand stop took 3 milliseconds Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand clear Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand clear took 1 milliseconds Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand add "NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3" Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:29:48 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:29:48 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand add "NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3" took 4 milliseconds Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand play Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:29:48 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:29:48 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 13ms Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 12ms Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand play took 10 milliseconds Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 10ms Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 8ms Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:29:48 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:29:48 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 7ms Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 5ms Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand status took 37 milliseconds Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand status took 33 milliseconds Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand status took 44 milliseconds Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand status took 14 milliseconds Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand status took 7 milliseconds Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heatseeker","artist":"AC/DC","album":"Blow Up Your Video","uri":"NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3","trackType":"mp3"} Aug 20 13:29:48 volumio volumio[1064]: verbose: CURRENT POSITION 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heatseeker","artist":"AC/DC","album":"Blow Up Your Video","uri":"NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3","trackType":"mp3"} Aug 20 13:29:48 volumio volumio[1064]: verbose: CURRENT POSITION 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Aug 20 13:29:48 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 106ms Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 120ms Aug 20 13:29:48 volumio volumio[1064]: info: Aug 20 13:29:48 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 68 milliseconds Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 68 milliseconds Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 63 milliseconds Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand status took 6 milliseconds Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heatseeker","artist":"AC/DC","album":"Blow Up Your Video","uri":"NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3","trackType":"mp3"} Aug 20 13:29:48 volumio volumio[1064]: verbose: CURRENT POSITION 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Aug 20 13:29:48 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Heatseeker","artist":"AC/DC","album":"Blow Up Your Video","uri":"NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3","trackType":"mp3"} Aug 20 13:29:48 volumio volumio[1064]: verbose: CURRENT POSITION 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Aug 20 13:29:48 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Heatseeker","artist":"AC/DC","album":"Blow Up Your Video","uri":"NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3","trackType":"mp3"} Aug 20 13:29:48 volumio volumio[1064]: verbose: CURRENT POSITION 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Aug 20 13:29:48 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 256ms Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 232ms Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 218ms Aug 20 13:29:48 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 160 milliseconds Aug 20 13:29:48 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:29:48 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Heatseeker","artist":"AC/DC","album":"Blow Up Your Video","uri":"NAS/Nass/ROCK/ACDC/[13]Blow Up Your Video/13-01 Heatseeker.mp3","trackType":"mp3"} Aug 20 13:29:48 volumio volumio[1064]: verbose: CURRENT POSITION 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Aug 20 13:29:48 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:48 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:29:48 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:48 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:29:48 volumio volumio[1064]: info: ------------------------------ 197ms Aug 20 13:29:48 volumio volumio[1064]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 20 13:29:48 volumio volumio[1275]: .....................................++++ Aug 20 13:29:48 volumio volumio[1275]: e is 65537 (0x010001) Aug 20 13:29:48 volumio volumio[1275]: writing RSA key Aug 20 13:29:49 volumio volumio[1064]: info: Updating MyVolumio device info Aug 20 13:29:49 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:49 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 13:29:49 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:49 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 20 13:29:49 volumio volumio[1064]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 20 13:29:49 volumio volumio-remote-updater[647]: No test mode Aug 20 13:29:49 volumio volumio-remote-updater[647]: No alpha test mode Aug 20 13:29:49 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 20 13:29:50 volumio volumio[1064]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 20 13:29:50 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 20 13:29:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 20 13:29:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 20 13:29:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 20 13:29:52 volumio volumio[1064]: info: CoreCommandRouter::volumioGetBrowseSources Aug 20 13:29:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 13:29:54 volumio volumio[1064]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 20 13:29:54 volumio volumio[1064]: info: Completed starting MyVolumio Plugin Aug 20 13:29:54 volumio volumio[1064]: info: BOOT COMPLETED Aug 20 13:29:54 volumio volumio[1064]: [Metrics] CommandRouter: 43s 583.71ms Aug 20 13:29:54 volumio volumio[1064]: info: CoreCommandRouter::volumiosetStartupVolume Aug 20 13:29:54 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 13:29:54 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 13:29:54 volumio volumio[1064]: info: CoreCommandRouter::Close All Modals sent Aug 20 13:29:54 volumio volumio[1064]: info: CoreCommandRouter::Close All Modals sent Aug 20 13:29:54 volumio volumio[1064]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 20 13:29:54 volumio volumio[1064]: aplay: main:828: audio open error: Device or resource busy Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 20 13:29:55 volumio volumio[1064]: info: Version has changed, forcing UI Reload Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::Reload Ui Aug 20 13:29:55 volumio sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 20 13:29:55 volumio sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:55 volumio sudo[1449]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:55 volumio sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 13:29:55 volumio sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 13:29:55 volumio sudo[1451]: pam_unix(sudo:session): session closed for user root Aug 20 13:29:55 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.121 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::volumioGetVisibleSources Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:55 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 20 13:29:55 volumio volumio[1064]: info: Received Get System Info Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:55 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:55 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:55 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:55 volumio volumio[1064]: info: Listing playlists Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 20 13:29:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 20 13:29:57 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 20 13:29:57 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 20 13:29:57 volumio volumio[1064]: info: Received Get System Info Aug 20 13:29:57 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:57 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:57 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:57 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:57 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:57 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:58 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 20 13:29:58 volumio volumio[1064]: info: Received Get System Info Aug 20 13:29:58 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:58 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:58 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:58 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:58 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:58 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:29:59 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:29:59 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:29:59 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:29:59 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:29:59 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:29:59 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:30:00 volumio ntpd[796]: Soliciting pool server 175.193.3.234 Aug 20 13:30:01 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Aug 20 13:30:01 volumio volumio[1064]: info: CoreStateMachine::getQueue Aug 20 13:30:01 volumio volumio[1064]: info: CorePlayQueue::getQueue Aug 20 13:30:02 volumio ntpd[796]: Soliciting pool server 194.0.5.123 Aug 20 13:30:03 volumio ntpd[796]: Soliciting pool server 175.195.167.194 Aug 20 13:30:04 volumio ntpd[796]: Soliciting pool server 39.118.108.191 Aug 20 13:30:05 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 20 13:30:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 13:30:09 volumio volumio[1064]: info: CURURI: albums://AC%2FDC/Live%20(Collector's%20Edition) Aug 20 13:30:09 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-01 Thunderstruck (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-02 Shoot to Thrill (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-04 Sin City (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-05 Who Made Who (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-06 Heatseeker (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-07 Fire Your Guns (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-08 Jailbreak (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-09 The Jack (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-10 The Razors Edge (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-11 Dirty Deeds Done Dirt Cheap (L.mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-12 Moneytalks (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-01 Hells Bells (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-02 Are You Ready (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-03 That's the Way I Wanna Rock 'N.mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-04 High Voltage (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-05 You Shook Me All Night Long (L.mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-06 Whole Lotta Rosie (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-07 Let There Be Rock (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-08 Bonny (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-09 Highway to Hell (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-10 T.N.T. (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Preloading song: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-11 For Those About to Rock (We Sa.mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-01 Thunderstruck (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-01%20Thunderstruck%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-01 Thunderstruck (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: No valid Plugin REST Endpoint Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-02 Shoot to Thrill (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-02%20Shoot%20to%20Thrill%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-02 Shoot to Thrill (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-03%20Back%20In%20Black%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-04 Sin City (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-04%20Sin%20City%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-04 Sin City (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-05 Who Made Who (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-05%20Who%20Made%20Who%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-05 Who Made Who (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-06 Heatseeker (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-06%20Heatseeker%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-06 Heatseeker (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-07 Fire Your Guns (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-07%20Fire%20Your%20Guns%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-07 Fire Your Guns (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-08 Jailbreak (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-08%20Jailbreak%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-08 Jailbreak (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-09 The Jack (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-09%20The%20Jack%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-09 The Jack (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-10 The Razors Edge (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-10%20The%20Razors%20Edge%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-10 The Razors Edge (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-11 Dirty Deeds Done Dirt Cheap (L.mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-11%20Dirty%20Deeds%20Done%20Dirt%20Cheap%20(L.mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-11 Dirty Deeds Done Dirt Cheap (L.mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-12 Moneytalks (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B15%5DLive%20(Collector's%20Edition)%2F15-12%20Moneytalks%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-12 Moneytalks (Live).mp3 Aug 20 13:30:09 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-01 Hells Bells (Live).mp3 in service mpd Aug 20 13:30:09 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-01%20Hells%20Bells%20(Live).mp3&metadata=false Aug 20 13:30:09 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-01 Hells Bells (Live).mp3 Aug 20 13:30:10 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-02 Are You Ready (Live).mp3 in service mpd Aug 20 13:30:10 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-02%20Are%20You%20Ready%20(Live).mp3&metadata=false Aug 20 13:30:10 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-02 Are You Ready (Live).mp3 Aug 20 13:30:10 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-03 That's the Way I Wanna Rock 'N.mp3 in service mpd Aug 20 13:30:10 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-03%20That's%20the%20Way%20I%20Wanna%20Rock%20'N.mp3&metadata=false Aug 20 13:30:10 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-03 That's the Way I Wanna Rock 'N.mp3 Aug 20 13:30:10 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-04 High Voltage (Live).mp3 in service mpd Aug 20 13:30:10 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-04%20High%20Voltage%20(Live).mp3&metadata=false Aug 20 13:30:10 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-04 High Voltage (Live).mp3 Aug 20 13:30:10 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-05 You Shook Me All Night Long (L.mp3 in service mpd Aug 20 13:30:10 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-05%20You%20Shook%20Me%20All%20Night%20Long%20(L.mp3&metadata=false Aug 20 13:30:10 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-05 You Shook Me All Night Long (L.mp3 Aug 20 13:30:10 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-06 Whole Lotta Rosie (Live).mp3 in service mpd Aug 20 13:30:10 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-06%20Whole%20Lotta%20Rosie%20(Live).mp3&metadata=false Aug 20 13:30:10 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-06 Whole Lotta Rosie (Live).mp3 Aug 20 13:30:10 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-07 Let There Be Rock (Live).mp3 in service mpd Aug 20 13:30:10 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-07%20Let%20There%20Be%20Rock%20(Live).mp3&metadata=false Aug 20 13:30:10 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-07 Let There Be Rock (Live).mp3 Aug 20 13:30:10 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-08 Bonny (Live).mp3 in service mpd Aug 20 13:30:10 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-08%20Bonny%20(Live).mp3&metadata=false Aug 20 13:30:10 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-08 Bonny (Live).mp3 Aug 20 13:30:10 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-09 Highway to Hell (Live).mp3 in service mpd Aug 20 13:30:10 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-09%20Highway%20to%20Hell%20(Live).mp3&metadata=false Aug 20 13:30:10 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-09 Highway to Hell (Live).mp3 Aug 20 13:30:10 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-10 T.N.T. (Live).mp3 in service mpd Aug 20 13:30:10 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-10%20T.N.T.%20(Live).mp3&metadata=false Aug 20 13:30:10 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-10 T.N.T. (Live).mp3 Aug 20 13:30:10 volumio volumio[1064]: info: Exploding uri music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-11 For Those About to Rock (We Sa.mp3 in service mpd Aug 20 13:30:10 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=100&web=AC%2FDC/Live%20(Collector's%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FNass%2FROCK%2FACDC%2F%5B16%5DLive%20(Collector's%20Edition)%2F16-11%20For%20Those%20About%20to%20Rock%20(We%20Sa.mp3&metadata=false Aug 20 13:30:10 volumio volumio[1064]: info: URI /mnt/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-11 For Those About to Rock (We Sa.mp3 Aug 20 13:30:13 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 20 13:30:13 volumio volumio[1064]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 20 13:30:13 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 20 13:30:13 volumio volumio[1064]: info: Received Get System Version Aug 20 13:30:13 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 13:30:13 volumio volumio[1064]: info: Received Get System Info Aug 20 13:30:13 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:30:13 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:30:13 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:30:13 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:30:13 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:30:13 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:30:15 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::ClearQueue Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::stop Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::stPlaybackTimer Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::serviceStop Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::serviceStop Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::stop Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::clearPlayQueue Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::saveQueue Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::addQueueItems Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::addQueueItems Aug 20 13:30:15 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-01 Thunderstruck (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-01 Thunderstruck (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-02 Shoot to Thrill (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-02 Shoot to Thrill (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-04 Sin City (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-04 Sin City (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-05 Who Made Who (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-05 Who Made Who (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-06 Heatseeker (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-06 Heatseeker (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-07 Fire Your Guns (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-07 Fire Your Guns (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-08 Jailbreak (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-08 Jailbreak (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-09 The Jack (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-09 The Jack (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-10 The Razors Edge (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-10 The Razors Edge (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-11 Dirty Deeds Done Dirt Cheap (L.mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-11 Dirty Deeds Done Dirt Cheap (L.mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-12 Moneytalks (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-12 Moneytalks (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-01 Hells Bells (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-01 Hells Bells (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-02 Are You Ready (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-02 Are You Ready (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-03 That's the Way I Wanna Rock 'N.mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-03 That's the Way I Wanna Rock 'N.mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-04 High Voltage (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-04 High Voltage (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-05 You Shook Me All Night Long (L.mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-05 You Shook Me All Night Long (L.mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-06 Whole Lotta Rosie (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-06 Whole Lotta Rosie (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-07 Let There Be Rock (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-07 Let There Be Rock (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-08 Bonny (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-08 Bonny (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-09 Highway to Hell (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-09 Highway to Hell (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-10 T.N.T. (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-10 T.N.T. (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Adding Item to queue: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-11 For Those About to Rock (We Sa.mp3 Aug 20 13:30:15 volumio volumio[1064]: info: Using cached record of: music-library/NAS/Nass/ROCK/ACDC/[16]Live (Collector's Edition)/16-11 For Those About to Rock (We Sa.mp3 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::saveQueue Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPlay Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::play index 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::stop Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::play index undefined Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::startPlaybackTimer Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::clearAddPlayTracks NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3 Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand stop took 80 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand stop took 10 milliseconds Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand clear Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:30:15 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand status took 13 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand clear took 12 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand status took 9 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand status took 7 milliseconds Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand add "NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3" Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: 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} Aug 20 13:30:15 volumio volumio[1064]: verbose: CURRENT POSITION 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState stateService stop Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: No code Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: 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} Aug 20 13:30:15 volumio volumio[1064]: verbose: CURRENT POSITION 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState stateService stop Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: No code Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 83ms Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 82ms Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:30:15 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:30:15 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:30:15 volumio volumio[1064]: error: updateQueue error: null Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:30:15 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 89ms Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 83 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand add "NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3" took 82 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 12ms Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 10ms Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand play Aug 20 13:30:15 volumio volumio[1064]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 109ms Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:30:15 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Aug 20 13:30:15 volumio volumio[1064]: info: Ignoring MPD Status Update Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 26ms Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand play took 22 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 20ms Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 20ms Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand status took 15 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand status took 12 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand status took 9 milliseconds Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:30:15 volumio volumio[1064]: info: Aug 20 13:30:15 volumio volumio[1064]: ---------------------------- MPD announces state update: player Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::getState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand status took 31 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 27 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 25 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand status took 22 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand status took 19 milliseconds Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseState Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Back In Black (Live)","artist":"AC/DC","album":"Live (Collector's Edition)","uri":"NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3","trackType":"mp3"} Aug 20 13:30:15 volumio volumio[1064]: verbose: CURRENT POSITION 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Back In Black (Live)","artist":"AC/DC","album":"Live (Collector's Edition)","uri":"NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3","trackType":"mp3"} Aug 20 13:30:15 volumio volumio[1064]: verbose: CURRENT POSITION 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Aug 20 13:30:15 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Back In Black (Live)","artist":"AC/DC","album":"Live (Collector's Edition)","uri":"NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3","trackType":"mp3"} Aug 20 13:30:15 volumio volumio[1064]: verbose: CURRENT POSITION 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Aug 20 13:30:15 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 119ms Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 170ms Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 168ms Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 131 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 126 milliseconds Aug 20 13:30:15 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 125 milliseconds Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:30:15 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Back In Black (Live)","artist":"AC/DC","album":"Live (Collector's Edition)","uri":"NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3","trackType":"mp3"} Aug 20 13:30:15 volumio volumio[1064]: verbose: CURRENT POSITION 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Aug 20 13:30:15 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Back In Black (Live)","artist":"AC/DC","album":"Live (Collector's Edition)","uri":"NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3","trackType":"mp3"} Aug 20 13:30:15 volumio volumio[1064]: verbose: CURRENT POSITION 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Aug 20 13:30:15 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: ControllerMpd::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Back In Black (Live)","artist":"AC/DC","album":"Live (Collector's Edition)","uri":"NAS/Nass/ROCK/ACDC/[15]Live (Collector's Edition)/15-03 Back In Black (Live).mp3","trackType":"mp3"} Aug 20 13:30:15 volumio volumio[1064]: verbose: CURRENT POSITION 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Aug 20 13:30:15 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: CoreStateMachine::pushState Aug 20 13:30:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 291ms Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 277ms Aug 20 13:30:15 volumio volumio[1064]: info: ------------------------------ 273ms Aug 20 13:30:22 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 13:30:22 volumio volumio[1064]: info: CURURI: albums:// Aug 20 13:30:22 volumio volumio[1064]: info: listAlbums - loading Albums from cache Aug 20 13:30:22 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:30:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 20 13:30:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 20 13:30:29 volumio volumio[1064]: info: Discovery: Getting this device information Aug 20 13:30:29 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Aug 20 13:30:29 volumio volumio[1064]: info: CorePlayQueue::getTrack 2 Aug 20 13:30:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 20 13:30:30 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 20 13:30:30 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:30:31 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 20 13:30:31 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:30:31 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 20 13:30:31 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:30:33 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 20 13:30:33 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:30:36 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 20 13:30:36 volumio volumio[1064]: info: Preload queue cleared Aug 20 13:30:40 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 20 13:30:40 volumio volumio[1064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 13:30:40 volumio volumio[1064]: TypeError: Cannot read property 'length' of undefined Aug 20 13:30:40 volumio volumio[1064]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 20 13:30:40 volumio volumio[1064]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 20 13:30:40 volumio volumio[1064]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 20 13:30:40 volumio volumio[1064]: at Parser.emit (events.js:315:20) Aug 20 13:30:40 volumio volumio[1064]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 20 13:30:40 volumio volumio[1064]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 20 13:30:40 volumio volumio[1064]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 20 13:30:40 volumio volumio[1064]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 20 13:30:40 volumio volumio[1064]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 20 13:30:40 volumio volumio[1064]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 20 13:30:40 volumio volumio[1064]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 20 13:30:40 volumio volumio[1064]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 20 13:30:40 volumio volumio[1064]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 20 13:30:40 volumio volumio[1064]: at IncomingMessage.emit (events.js:327:22) Aug 20 13:30:40 volumio volumio[1064]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 20 13:30:40 volumio volumio[1064]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 20 13:30:40 volumio volumio[1064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 13:30:41 volumio sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-20 13:29 Aug 20 13:30:41 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"