-- Logs begin at Fri 2024-08-30 11:35:21 CEST, end at Fri 2024-08-30 13:16:50 CEST. -- Aug 30 13:16:33 volumiosr ntpd[1150]: receive: Unexpected origin timestamp 0xea7c10f2.6b23cc81 does not match aorg 0000000000.00000000 from server@217.196.145.42 xmt 0xea7c2891.2986a556 Aug 30 13:16:33 volumiosr ntpd[1150]: receive: Unexpected origin timestamp 0xea7c10f2.6b262c73 does not match aorg 0000000000.00000000 from server@144.76.197.108 xmt 0xea7c2891.288679c7 Aug 30 13:16:33 volumiosr wireless.js[602]: trying... Aug 30 13:16:33 volumiosr sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 13:16:33 volumiosr sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:33 volumiosr sudo[1252]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:34 volumiosr wireless.js[602]: trying... Aug 30 13:16:34 volumiosr sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 13:16:34 volumiosr sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:34 volumiosr sudo[1255]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:35 volumiosr wireless.js[602]: trying... Aug 30 13:16:35 volumiosr sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 13:16:35 volumiosr sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:35 volumiosr sudo[1259]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:36 volumiosr volumio-remote-updater[609]: [2024-08-30 13:16:36] [info] asio async_connect error: system:111 (Connection refused) Aug 30 13:16:36 volumiosr volumio-remote-updater[609]: [2024-08-30 13:16:36] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 30 13:16:36 volumiosr volumio-remote-updater[609]: [2024-08-30 13:16:36] [error] handle_connect error: Underlying Transport Error Aug 30 13:16:36 volumiosr wireless.js[602]: trying... Aug 30 13:16:36 volumiosr sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 13:16:36 volumiosr sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:36 volumiosr sudo[1262]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:37 volumiosr wireless.js[602]: trying... Aug 30 13:16:37 volumiosr sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 13:16:37 volumiosr sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:37 volumiosr sudo[1265]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:38 volumiosr wireless.js[602]: trying... Aug 30 13:16:38 volumiosr sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 13:16:38 volumiosr sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:38 volumiosr sudo[1268]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:39 volumiosr wireless.js[602]: trying... Aug 30 13:16:39 volumiosr sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 30 13:16:39 volumiosr sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:39 volumiosr sudo[1271]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:40 volumiosr wireless.js[602]: Overtime, starting plan B Aug 30 13:16:40 volumiosr wireless.js[602]: STARTING HOTSPOT Aug 30 13:16:40 volumiosr wpa_supplicant[1105]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Aug 30 13:16:40 volumiosr wpa_supplicant[1105]: nl80211: Failed to set IPv4 unicast in multicast filter Aug 30 13:16:40 volumiosr wpa_supplicant[1105]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Aug 30 13:16:40 volumiosr wpa_supplicant[1105]: nl80211: Failed to set IPv4 unicast in multicast filter Aug 30 13:16:40 volumiosr wpa_supplicant[1105]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Aug 30 13:16:40 volumiosr wpa_supplicant[1105]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Aug 30 13:16:40 volumiosr wpa_supplicant[1105]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Aug 30 13:16:41 volumiosr wpa_supplicant[1105]: wlan0: CTRL-EVENT-TERMINATING Aug 30 13:16:41 volumiosr nmbd[707]: [2024/08/30 13:16:41.050999, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 30 13:16:41 volumiosr nmbd[707]: query_name_response: Multiple (2) responses received for a query on subnet 10.0.0.60 for name WORKGROUP<1d>. Aug 30 13:16:41 volumiosr nmbd[707]: This response was from IP 10.0.0.200, reporting an IP address of 10.0.0.200. Aug 30 13:16:41 volumiosr nmbd[707]: [2024/08/30 13:16:41.053592, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 30 13:16:41 volumiosr nmbd[707]: query_name_response: Multiple (3) responses received for a query on subnet 10.0.0.60 for name WORKGROUP<1d>. Aug 30 13:16:41 volumiosr nmbd[707]: This response was from IP 10.0.0.200, reporting an IP address of 10.0.0.200. Aug 30 13:16:41 volumiosr nmbd[707]: [2024/08/30 13:16:41.054084, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 30 13:16:41 volumiosr nmbd[707]: query_name_response: Multiple (4) responses received for a query on subnet 10.0.0.60 for name WORKGROUP<1d>. Aug 30 13:16:41 volumiosr nmbd[707]: This response was from IP 10.0.0.200, reporting an IP address of 10.0.0.200. Aug 30 13:16:41 volumiosr systemd[1]: systemd-fsckd.service: Succeeded. Aug 30 13:16:43 volumiosr sudo[1281]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 30 13:16:43 volumiosr sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:43 volumiosr sudo[1281]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:43 volumiosr sudo[1283]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 30 13:16:43 volumiosr sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:43 volumiosr sudo[1283]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:43 volumiosr wireless.js[602]: Hotspot is disabled, not starting it Aug 30 13:16:43 volumiosr kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 30 13:16:43 volumiosr systemd[1]: Started Wireless Services. Aug 30 13:16:43 volumiosr kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 30 13:16:43 volumiosr systemd[1]: Started Volumio Backend Module. Aug 30 13:16:43 volumiosr systemd[1]: Started Volumio Cpu Tweaker. Aug 30 13:16:43 volumiosr systemd[1]: Reached target Multi-User System. Aug 30 13:16:43 volumiosr systemd[1]: Reached target Graphical Interface. Aug 30 13:16:43 volumiosr systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 30 13:16:43 volumiosr systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 30 13:16:43 volumiosr systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 30 13:16:43 volumiosr volumio-cpu-tweak[1290]: Setting RT Priority for mpd Aug 30 13:16:43 volumiosr systemd[1]: Startup finished in 14.445s (kernel) + 38.009s (userspace) = 52.454s. Aug 30 13:16:43 volumiosr volumio-cpu-tweak[1290]: pid 35's current scheduling policy: SCHED_OTHER Aug 30 13:16:43 volumiosr volumio-cpu-tweak[1290]: pid 35's current scheduling priority: 0 Aug 30 13:16:43 volumiosr volumio-cpu-tweak[1290]: Setting MPD Affinity Aug 30 13:16:43 volumiosr volumio-cpu-tweak[1290]: pid 3's current affinity mask: f Aug 30 13:16:43 volumiosr volumio-cpu-tweak[1290]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 30 13:16:43 volumiosr volumio-cpu-tweak[1290]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 30 13:16:43 volumiosr systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 30 13:16:44 volumiosr systemd[1]: systemd-hostnamed.service: Succeeded. Aug 30 13:16:45 volumiosr volumio[1289]: info: ------------------------------------------- Aug 30 13:16:45 volumiosr volumio[1289]: info: ----- Volumio3 ---- Aug 30 13:16:45 volumiosr volumio[1289]: info: ------------------------------------------- Aug 30 13:16:45 volumiosr volumio[1289]: info: ----- System startup ---- Aug 30 13:16:45 volumiosr volumio[1289]: info: ------------------------------------------- Aug 30 13:16:45 volumiosr volumio[1289]: info: MYVOLUMIO Environment detected Aug 30 13:16:46 volumiosr volumio-remote-updater[609]: [2024-08-30 13:16:46] [connect] Successful connection Aug 30 13:16:46 volumiosr volumio[1289]: info: Plugin folders cleanup Aug 30 13:16:46 volumiosr volumio[1289]: info: Scanning into folder /volumio/app/plugins/ Aug 30 13:16:46 volumiosr volumio[1289]: info: Scanning category audio_interface Aug 30 13:16:46 volumiosr volumio[1289]: info: Scanning category miscellanea Aug 30 13:16:46 volumiosr volumio[1289]: info: Scanning category music_service Aug 30 13:16:46 volumiosr volumio[1289]: info: Scanning category plugins.json Aug 30 13:16:46 volumiosr volumio[1289]: info: Scanning category system_controller Aug 30 13:16:46 volumiosr volumio[1289]: info: Scanning category user_interface Aug 30 13:16:46 volumiosr volumio[1289]: info: Scanning into folder /data/plugins/ Aug 30 13:16:46 volumiosr volumio[1289]: info: Scanning category system_controller Aug 30 13:16:46 volumiosr volumio[1289]: info: Scanning category user_interface Aug 30 13:16:46 volumiosr volumio[1289]: info: Plugin folders cleanup completed Aug 30 13:16:46 volumiosr volumio[1289]: info: ------------------------------------------- Aug 30 13:16:46 volumiosr volumio[1289]: info: ----- Core plugins startup ---- Aug 30 13:16:46 volumiosr volumio[1289]: info: ------------------------------------------- Aug 30 13:16:46 volumiosr volumio[1289]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 13:16:46 volumiosr volumio[1289]: info: Adding plugin upnp to MyMusic Plugins Aug 30 13:16:46 volumiosr volumio[1289]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 13:16:46 volumiosr volumio[1289]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 13:16:46 volumiosr volumio[1289]: info: Loading plugins from folder /data/plugins/ Aug 30 13:16:46 volumiosr volumio[1289]: info: Loading plugin "system"... Aug 30 13:16:46 volumiosr volumio[1289]: info: Loading plugin "appearance"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "network"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Refreshing Cached IP Addresses Aug 30 13:16:47 volumiosr sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 13:16:47 volumiosr sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "services"... Aug 30 13:16:47 volumiosr sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 13:16:47 volumiosr sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:47 volumiosr sudo[1324]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "alsa_controller"... Aug 30 13:16:47 volumiosr sudo[1326]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:47 volumiosr sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 13:16:47 volumiosr sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:16:47 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "wizard"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "networkfs"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Starting Udev Watcher for removable devices Aug 30 13:16:47 volumiosr volumio[1289]: info: Ignoring mount for partition: boot Aug 30 13:16:47 volumiosr volumio[1289]: info: Ignoring mount for partition: volumio Aug 30 13:16:47 volumiosr volumio[1289]: info: Ignoring mount for partition: volumio_data Aug 30 13:16:47 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "volumio_command_line_client"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "upnp"... Aug 30 13:16:47 volumiosr volumio[1289]: info: [1725016607275] Starting Upmpd Daemon Aug 30 13:16:47 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "my_music"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "mpd"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "upnp_browser"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "alarm-clock"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "airplay_emulation"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Starting Shairport Sync Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "last_100"... Aug 30 13:16:47 volumiosr volumio[1289]: info: Loading plugin "webradio"... Aug 30 13:16:48 volumiosr volumio[1289]: info: Loading plugin "i2s_dacs"... Aug 30 13:16:48 volumiosr volumio[1289]: info: Loading plugin "volumiodiscovery"... Aug 30 13:16:48 volumiosr volumio[1289]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 13:16:48 volumiosr volumio[1289]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 13:16:48 volumiosr node[1289]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 13:16:48 volumiosr volumio[1289]: *** WARNING *** For more information see Aug 30 13:16:48 volumiosr volumio[1289]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 13:16:48 volumiosr volumio[1289]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 13:16:48 volumiosr volumio[1289]: *** WARNING *** For more information see Aug 30 13:16:48 volumiosr node[1289]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 13:16:48 volumiosr node[1289]: *** WARNING *** For more information see Aug 30 13:16:48 volumiosr node[1289]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 13:16:48 volumiosr node[1289]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 13:16:48 volumiosr node[1289]: *** WARNING *** For more information see Aug 30 13:16:48 volumiosr volumio[1289]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 13:16:48 volumiosr volumio[1289]: info: Discovery: Started advertising with name: VolumioSR Aug 30 13:16:48 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 13:16:48 volumiosr volumio[1289]: info: Loading plugin "autostart"... Aug 30 13:16:48 volumiosr CRON[1338]: pam_unix(cron:session): session opened for user root by (uid=0) Aug 30 13:16:48 volumiosr CRON[1339]: (root) CMD (wget "http://127.0.0.1:3000/api/v1/commands/?cmd=playplaylist&name=Jingle2" && sleep 25 && wget "http://127.0.0.1:3000/api/v1/commands/?cmd=playplaylist&name=WebRadio") Aug 30 13:16:48 volumiosr volumio[1289]: info: Applying required configuration parameters for plugin autostart Aug 30 13:16:48 volumiosr volumio[1289]: info: AutoStart - onVolumioStart - read config.json Aug 30 13:16:48 volumiosr volumio[1289]: info: Loading plugin "outputs"... Aug 30 13:16:48 volumiosr volumio[1289]: info: Loading plugin "albumart"... Aug 30 13:16:48 volumiosr volumio[1289]: info: Plugin example_plugin is not enabled Aug 30 13:16:48 volumiosr volumio[1289]: info: Loading plugin "inputs"... Aug 30 13:16:48 volumiosr volumio[1289]: info: Loading plugin "updater_comm"... Aug 30 13:16:48 volumiosr volumio[1289]: info: Plugin mpdemulation is not enabled Aug 30 13:16:48 volumiosr volumio[1289]: info: Loading plugin "rest_api"... Aug 30 13:16:48 volumiosr volumio[1289]: info: Loading plugin "websocket"... Aug 30 13:16:48 volumiosr volumio[1289]: info: Starting Socket.io Server version 2.3.0 Aug 30 13:16:48 volumiosr volumio[1289]: info: Loading plugin "backup_restore"... Aug 30 13:16:48 volumiosr volumio[1289]: Forking 3 albumart workers Aug 30 13:16:49 volumiosr volumio[1289]: info: Applying required configuration parameters for plugin backup_restore Aug 30 13:16:49 volumiosr volumio[1289]: info: Loading plugin "touch_display"... Aug 30 13:16:49 volumiosr volumio[1289]: Starting albumart workers Aug 30 13:16:49 volumiosr volumio[1289]: Starting albumart workers Aug 30 13:16:49 volumiosr volumio[1289]: Starting albumart workers Aug 30 13:16:49 volumiosr volumio[1289]: info: Applying required configuration parameters for plugin touch_display Aug 30 13:16:49 volumiosr sudo[1329]: pam_unix(sudo:session): session closed for user root Aug 30 13:16:49 volumiosr volumio[1289]: info: Loading i18n strings for locale de Aug 30 13:16:49 volumiosr volumio[1289]: Updating browse sources language Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::initPlayerControls Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 13:16:49 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 13:16:49 volumiosr volumio[1289]: Express server listening on port 3000 Aug 30 13:16:49 volumiosr volumio[1289]: [Metrics] WebUI: 5s 300.58ms Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::resetVolumioState Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::getcurrentVolume Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 13:16:50 volumiosr volumio-remote-updater[609]: [2024-08-30 13:16:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725016606 101 Aug 30 13:16:50 volumiosr volumio[1289]: 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 30 13:16:50 volumiosr volumio[1289]: info: Aug 30 13:16:50 volumiosr volumio[1289]: ---------------------------- Client requests Volumio Play Playlist Jingle2 Aug 30 13:16:50 volumiosr volumio[1289]: info: Play playlist Jingle2 Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:16:50 volumiosr volumio[1289]: info: Discovery: Getting this device information Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioGetState Aug 30 13:16:50 volumiosr volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:16:50 volumiosr volumio[1289]: 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 30 13:16:50 volumiosr volumio[1289]: info: Reloading queue from file Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 13:16:50 volumiosr volumio[1289]: info: Executing start script for DAC IQaudIO DAC Plus Aug 30 13:16:50 volumiosr volumio[1289]: info: VolumeController:: Volume=86 Mute =false Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::pushState Aug 30 13:16:50 volumiosr volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::updateTrackBlock Aug 30 13:16:50 volumiosr volumio[1289]: info: CorePlayQueue::getTrackBlock Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::setRepeat false single undefined Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::pushState Aug 30 13:16:50 volumiosr volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::setRandom null Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::pushState Aug 30 13:16:50 volumiosr volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 30 13:16:50 volumiosr volumio[1289]: info: Setting Device type: Raspberry PI Aug 30 13:16:50 volumiosr volumio[1289]: info: DAC script executed Aug 30 13:16:50 volumiosr volumio[1289]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 30 13:16:50 volumiosr volumio[1289]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Aug 30 13:16:50 volumiosr volumio[1289]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 30 13:16:50 volumiosr volumio[1289]: verbose: New Socket.io Connection to 10.0.0.60:3000 from 10.0.0.67 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioGetState Aug 30 13:16:50 volumiosr volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioClearQueue Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::ClearQueue Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::stop Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 13:16:50 volumiosr volumio[1289]: info: CorePlayQueue::clearPlayQueue Aug 30 13:16:50 volumiosr volumio[1289]: info: CorePlayQueue::saveQueue Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioPushQueue Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreCommandRouter::volumioAddQueueItems Aug 30 13:16:50 volumiosr volumio[1289]: info: CoreStateMachine::addQueueItems Aug 30 13:16:50 volumiosr volumio[1289]: info: CorePlayQueue::addQueueItems Aug 30 13:16:50 volumiosr volumio[1289]: info: Preload queue cleared Aug 30 13:16:50 volumiosr volumio[1289]: info: Adding Item to queue: mnt/INTERNAL/WINWIN_2.mp3 Aug 30 13:16:50 volumiosr volumio[1289]: info: Exploding uri mnt/INTERNAL/WINWIN_2.mp3 in service mpd Aug 30 13:16:50 volumiosr volumio[1289]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 13:16:50 volumiosr volumio[1289]: TypeError: Cannot read property 'then' of undefined Aug 30 13:16:50 volumiosr volumio[1289]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Aug 30 13:16:50 volumiosr volumio[1289]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Aug 30 13:16:50 volumiosr volumio[1289]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1365:16) Aug 30 13:16:50 volumiosr volumio[1289]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Aug 30 13:16:50 volumiosr volumio[1289]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Aug 30 13:16:50 volumiosr volumio[1289]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Aug 30 13:16:50 volumiosr volumio[1289]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Aug 30 13:16:50 volumiosr volumio[1289]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:447:28) Aug 30 13:16:50 volumiosr volumio[1289]: at /volumio/app/playlistManager.js:726:30 Aug 30 13:16:50 volumiosr volumio[1289]: at /volumio/node_modules/jsonfile/index.js:46:5 Aug 30 13:16:50 volumiosr volumio[1289]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 Aug 30 13:16:50 volumiosr volumio[1289]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) Aug 30 13:16:50 volumiosr volumio[1289]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 13:16:50 volumiosr sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-30 13:15 Aug 30 13:16:50 volumiosr sudo[1418]: 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"