-- Logs begin at Mon 2025-01-20 23:17:35 SAST, end at Tue 2025-01-21 00:09:03 SAST. -- Jan 21 00:08:01 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:01] [connect] Successful connection Jan 21 00:08:01 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737410881 101 Jan 21 00:08:01 volumiosrvroom volumio[13493]: 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: 3 Jan 21 00:08:01 volumiosrvroom volumio[13493]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: Received Get System Info Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: Discovery: Getting this device information Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: Listing playlists Jan 21 00:08:01 volumiosrvroom volumio[13493]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Jan 21 00:08:01 volumiosrvroom volumio[13493]: info: Discovery: Started advertising with name: undefined Jan 21 00:08:02 volumiosrvroom volumio[13493]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 21 00:08:03 volumiosrvroom volumio[13493]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 21 00:08:06 volumiosrvroom volumio[13493]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 00:08:06 volumiosrvroom volumio[13493]: TypeError: Cannot read property 'charAt' of undefined Jan 21 00:08:06 volumiosrvroom volumio[13493]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Jan 21 00:08:06 volumiosrvroom volumio[13493]: at listOnTimeout (internal/timers.js:557:17) Jan 21 00:08:06 volumiosrvroom volumio[13493]: at processTimers (internal/timers.js:500:7) Jan 21 00:08:06 volumiosrvroom volumio[13493]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 00:08:06 volumiosrvroom sudo[13646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-21 00:07 Jan 21 00:08:06 volumiosrvroom sudo[13646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:06 volumiosrvroom sudo[13646]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:06 volumiosrvroom ntpd[845]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 00:08:09 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-BEACON-LOSS Jan 21 00:08:17 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-BEACON-LOSS Jan 21 00:08:18 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-BEACON-LOSS Jan 21 00:08:26 volumiosrvroom ntpd[845]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 00:08:28 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-BEACON-LOSS Jan 21 00:08:31 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:31] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jan 21 00:08:32 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-BEACON-LOSS Jan 21 00:08:35 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-BEACON-LOSS Jan 21 00:08:36 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-BEACON-LOSS Jan 21 00:08:36 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:36] [connect] Successful connection Jan 21 00:08:38 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:38] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Jan 21 00:08:38 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:38] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Jan 21 00:08:38 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 21 00:08:38 volumiosrvroom systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 21 00:08:38 volumiosrvroom systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 21 00:08:38 volumiosrvroom systemd[1]: Started dynamicswap service. Jan 21 00:08:38 volumiosrvroom systemd[1]: dynamicswap.service: Succeeded. Jan 21 00:08:38 volumiosrvroom systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 21 00:08:38 volumiosrvroom systemd[1]: volumio.service: Scheduled restart job, restart counter is at 59. Jan 21 00:08:38 volumiosrvroom systemd[1]: Started dynamicswap service. Jan 21 00:08:38 volumiosrvroom systemd[1]: Stopped Volumio Backend Module. Jan 21 00:08:38 volumiosrvroom systemd[1]: Started Volumio Backend Module. Jan 21 00:08:38 volumiosrvroom systemd[1]: dynamicswap.service: Succeeded. Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: ------------------------------------------- Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: ----- Volumio3 ---- Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: ------------------------------------------- Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: ----- System startup ---- Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: ------------------------------------------- Jan 21 00:08:39 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-BEACON-LOSS Jan 21 00:08:39 volumiosrvroom dhcpcd[1033]: wlan0: carrier lost Jan 21 00:08:39 volumiosrvroom avahi-daemon[693]: Withdrawing address record for 169.254.50.165 on wlan0. Jan 21 00:08:39 volumiosrvroom avahi-daemon[693]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.50.165. Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: MYVOLUMIO Environment detected Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Plugin folders cleanup Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Scanning into folder /volumio/app/plugins/ Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Scanning category audio_interface Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Scanning category miscellanea Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Scanning category music_service Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Scanning category plugins.json Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Scanning category system_controller Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Scanning category user_interface Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Scanning into folder /data/plugins/ Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Scanning category music_service Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Plugin folders cleanup completed Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: ------------------------------------------- Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: ----- Core plugins startup ---- Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: ------------------------------------------- Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Loading plugins from folder /volumio/app/plugins/ Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Adding plugin upnp to MyMusic Plugins Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Loading plugins from folder /data/plugins/ Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Loading plugin "system"... Jan 21 00:08:39 volumiosrvroom volumio[13662]: info: Loading plugin "appearance"... Jan 21 00:08:39 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-DISCONNECTED bssid=de:89:60:77:ae:24 reason=4 locally_generated=1 Jan 21 00:08:39 volumiosrvroom dhcpcd[1033]: wlan0: deleting route to 169.254.0.0/16 Jan 21 00:08:39 volumiosrvroom dhcpcd[1033]: wlan0: deleting default route Jan 21 00:08:39 volumiosrvroom avahi-daemon[693]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 21 00:08:39 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 21 00:08:39 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Jan 21 00:08:40 volumiosrvroom wpa_supplicant[972]: wlan0: SME: Trying to authenticate with 14:02:ec:4f:8c:30 (SSID='www.thavinci.za.net' freq=2437 MHz) Jan 21 00:08:40 volumiosrvroom kernel: wlan0: authenticate with 14:02:ec:4f:8c:30 Jan 21 00:08:40 volumiosrvroom kernel: wlan0: 80 MHz not supported, disabling VHT Jan 21 00:08:40 volumiosrvroom kernel: wlan0: send auth to 14:02:ec:4f:8c:30 (try 1/3) Jan 21 00:08:40 volumiosrvroom wpa_supplicant[972]: wlan0: Trying to associate with 14:02:ec:4f:8c:30 (SSID='www.thavinci.za.net' freq=2437 MHz) Jan 21 00:08:40 volumiosrvroom kernel: wlan0: authenticated Jan 21 00:08:40 volumiosrvroom kernel: wlan0: associate with 14:02:ec:4f:8c:30 (try 1/3) Jan 21 00:08:40 volumiosrvroom kernel: wlan0: RX AssocResp from 14:02:ec:4f:8c:30 (capab=0x31 status=0 aid=2) Jan 21 00:08:40 volumiosrvroom wpa_supplicant[972]: wlan0: Associated with 14:02:ec:4f:8c:30 Jan 21 00:08:40 volumiosrvroom kernel: wlan0: associated Jan 21 00:08:40 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "network"... Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Refreshing Cached IP Addresses Jan 21 00:08:40 volumiosrvroom sudo[13709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 00:08:40 volumiosrvroom sudo[13709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "services"... Jan 21 00:08:40 volumiosrvroom sudo[13709]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "alsa_controller"... Jan 21 00:08:40 volumiosrvroom sudo[13711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 00:08:40 volumiosrvroom sudo[13711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:40 volumiosrvroom sudo[13713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 21 00:08:40 volumiosrvroom sudo[13713]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:40 volumiosrvroom sudo[13711]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "wizard"... Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "networkfs"... Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Starting Udev Watcher for removable devices Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Ignoring mount for partition: boot Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Ignoring mount for partition: volumio Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Ignoring mount for partition: volumio_data Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "volumio_command_line_client"... Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "upnp"... Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: [1737410920622] Starting Upmpd Daemon Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "my_music"... Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "mpd"... Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "upnp_browser"... Jan 21 00:08:40 volumiosrvroom volumio[13662]: info: Loading plugin "alarm-clock"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "airplay_emulation"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Starting Shairport Sync Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "last_100"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "webradio"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "i2s_dacs"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: I2S DAC not set, start Auto-detection Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "volumiodiscovery"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 00:08:41 volumiosrvroom volumio[13662]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 00:08:41 volumiosrvroom volumio[13662]: *** WARNING *** For more information see Jan 21 00:08:41 volumiosrvroom node[13662]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 00:08:41 volumiosrvroom volumio[13662]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 00:08:41 volumiosrvroom volumio[13662]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 00:08:41 volumiosrvroom volumio[13662]: *** WARNING *** For more information see Jan 21 00:08:41 volumiosrvroom node[13662]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 00:08:41 volumiosrvroom node[13662]: *** WARNING *** For more information see Jan 21 00:08:41 volumiosrvroom node[13662]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 00:08:41 volumiosrvroom node[13662]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 00:08:41 volumiosrvroom node[13662]: *** WARNING *** For more information see Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Discovery: Started advertising with name: undefined Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Plugin youtube2 is not enabled Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Plugin ytcr is not enabled Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Plugin ytmusic is not enabled Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "outputs"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "albumart"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Plugin example_plugin is not enabled Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "inputs"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "updater_comm"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Plugin mpdemulation is not enabled Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "rest_api"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading plugin "websocket"... Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Starting Socket.io Server version 2.3.0 Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Loading i18n strings for locale en Jan 21 00:08:41 volumiosrvroom volumio[13662]: Updating browse sources language Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:41 volumiosrvroom volumio[13662]: Forking 1 albumart workers Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::initPlayerControls Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: Express server listening on port 3000 Jan 21 00:08:41 volumiosrvroom volumio[13662]: [Metrics] WebUI: 2s 380.96ms Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Setting Device type: x86 Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreStateMachine::resetVolumioState Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreStateMachine::getcurrentVolume Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Completed loading Core Plugins Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Preparing to generate the ALSA configuration file Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Reloading queue from file Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: VolumeController:: Volume=0 Mute =false Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreStateMachine::pushState Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioPushState Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreStateMachine::updateTrackBlock Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CorePlayQueue::getTrackBlock Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Asound.conf file unchanged, so no further update is needed Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Output device has changed, restarting MPD Jan 21 00:08:41 volumiosrvroom sudo[13744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 00:08:41 volumiosrvroom sudo[13744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Output device has changed, restarting Shairport Sync Jan 21 00:08:41 volumiosrvroom sudo[13744]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:41 volumiosrvroom sudo[13746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 00:08:41 volumiosrvroom sudo[13746]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:41 volumiosrvroom systemd[1]: Stopping Music Player Daemon... Jan 21 00:08:41 volumiosrvroom systemd[1]: mpd.service: Succeeded. Jan 21 00:08:41 volumiosrvroom systemd[1]: Stopped Music Player Daemon. Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: ___________ START PLUGINS ___________ Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: ControllerMpd::onStart: Initializing MPD Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: Creating MPD Configuration file Jan 21 00:08:41 volumiosrvroom systemd[1]: Starting Music Player Daemon... Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom ntpd[845]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: [1737410921955] CoreMusicLibrary::Adding element Media Servers Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:41 volumiosrvroom volumio[13662]: Starting albumart workers Jan 21 00:08:41 volumiosrvroom sudo[13753]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 00:08:41 volumiosrvroom sudo[13753]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:41 volumiosrvroom sudo[13753]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:41 volumiosrvroom sudo[13755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 00:08:41 volumiosrvroom sudo[13755]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:41 volumiosrvroom sudo[13757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 00:08:41 volumiosrvroom sudo[13757]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:41 volumiosrvroom sudo[13755]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:41 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:42 volumiosrvroom systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 21 00:08:42 volumiosrvroom systemd[1]: mpd.service: Succeeded. Jan 21 00:08:42 volumiosrvroom systemd[1]: Stopped Music Player Daemon. Jan 21 00:08:42 volumiosrvroom systemd[1]: Starting Music Player Daemon... Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: [1737410922101] CoreMusicLibrary::Adding element Last_100 Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: [1737410922103] CoreMusicLibrary::Adding element Webradio Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 00:08:42 volumiosrvroom ntpd[845]: Deleting interface #3 wlan0, 169.254.50.165#123, interface stats: received=0, sent=0, dropped=0, active_time=3047 secs Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: Initializing BBC Radios Jan 21 00:08:42 volumiosrvroom sudo[13765]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 00:08:42 volumiosrvroom sudo[13765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:42 volumiosrvroom sudo[13765]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: Volumio Calling Home Jan 21 00:08:42 volumiosrvroom wpa_supplicant[972]: wlan0: WPA: Key negotiation completed with 14:02:ec:4f:8c:30 [PTK=CCMP GTK=TKIP] Jan 21 00:08:42 volumiosrvroom wpa_supplicant[972]: wlan0: CTRL-EVENT-CONNECTED - Connection to 14:02:ec:4f:8c:30 completed [id=0 id_str=] Jan 21 00:08:42 volumiosrvroom dhcpcd[1033]: wlan0: carrier acquired Jan 21 00:08:42 volumiosrvroom dhcpcd[1033]: wlan0: IAID 42:4a:1a:19 Jan 21 00:08:42 volumiosrvroom sudo[13713]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:42 volumiosrvroom dhcpcd[1033]: wlan0: soliciting an IPv6 router Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreStateMachine::setRepeat null single undefined Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreStateMachine::pushState Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioPushState Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreStateMachine::setRandom null Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreStateMachine::pushState Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioPushState Jan 21 00:08:42 volumiosrvroom volumio[13662]: Unhandled rejection Error: No sockets available, cannot start. Jan 21 00:08:42 volumiosrvroom volumio[13662]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 21 00:08:42 volumiosrvroom volumio[13662]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 21 00:08:42 volumiosrvroom volumio[13662]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: MPD Permissions set Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: MPD Permissions set Jan 21 00:08:42 volumiosrvroom volumio[13662]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: VolumeController:: Volume=0 Mute =false Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreStateMachine::pushState Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioPushState Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: Starting Shairport Sync Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: Starting Shairport Sync Jan 21 00:08:42 volumiosrvroom sudo[13807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 00:08:42 volumiosrvroom sudo[13807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:42 volumiosrvroom volumio[13662]: info: Starting Shairport Sync Jan 21 00:08:42 volumiosrvroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 00:08:42 volumiosrvroom systemd[1]: shairport-sync.service: Succeeded. Jan 21 00:08:42 volumiosrvroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:42 volumiosrvroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:42 volumiosrvroom dhcpcd[1033]: wlan0: soliciting a DHCP lease Jan 21 00:08:42 volumiosrvroom sudo[13807]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:42 volumiosrvroom sudo[13810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 00:08:42 volumiosrvroom sudo[13810]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:42 volumiosrvroom sudo[13813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 00:08:43 volumiosrvroom sudo[13813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:43 volumiosrvroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 00:08:43 volumiosrvroom systemd[1]: shairport-sync.service: Succeeded. Jan 21 00:08:43 volumiosrvroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:43 volumiosrvroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:43 volumiosrvroom sudo[13810]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:43 volumiosrvroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 00:08:43 volumiosrvroom systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 21 00:08:43 volumiosrvroom systemd[1]: shairport-sync.service: Succeeded. Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: Shairport-Sync Started Jan 21 00:08:43 volumiosrvroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:43 volumiosrvroom volumio[13662]: Error adding Membership: Error: addMembership EINVAL Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: Shairport-Sync Started Jan 21 00:08:43 volumiosrvroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:43 volumiosrvroom sudo[13813]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: Shairport-Sync Started Jan 21 00:08:43 volumiosrvroom mpd[13777]: Jan 21 00:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 21 00:08:43 volumiosrvroom systemd[1]: Started Music Player Daemon. Jan 21 00:08:43 volumiosrvroom sudo[13757]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:43 volumiosrvroom sudo[13746]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: Completed starting Core Plugins Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: ------------------------------------------- Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: ----- MyVolumio plugins startup ---- Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: ------------------------------------------- Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 21 00:08:43 volumiosrvroom volumio[13662]: error: MPD error: The expression evaluated to a falsy value: Jan 21 00:08:43 volumiosrvroom volumio[13662]: assert.ok(self.idling) Jan 21 00:08:43 volumiosrvroom volumio[13662]: error: The expression evaluated to a falsy value: Jan 21 00:08:43 volumiosrvroom volumio[13662]: assert.ok(self.idling) Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: MPD running with PID13777 Jan 21 00:08:43 volumiosrvroom volumio[13662]: ,establishing connection Jan 21 00:08:43 volumiosrvroom volumio[13662]: error: MPD error: The expression evaluated to a falsy value: Jan 21 00:08:43 volumiosrvroom volumio[13662]: assert.ok(self.idling) Jan 21 00:08:43 volumiosrvroom volumio[13662]: error: The expression evaluated to a falsy value: Jan 21 00:08:43 volumiosrvroom volumio[13662]: assert.ok(self.idling) Jan 21 00:08:43 volumiosrvroom volumio[13662]: error: updateQueue error: null Jan 21 00:08:43 volumiosrvroom volumio[13662]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: Received Get System Info Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: Discovery: Getting this device information Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:43 volumiosrvroom volumio[13662]: info: Listing playlists Jan 21 00:08:45 volumiosrvroom dhcpcd[1033]: wlan0: offered 192.168.12.130 from 192.168.12.1 Jan 21 00:08:45 volumiosrvroom dhcpcd[1033]: wlan0: probing address 192.168.12.130/24 Jan 21 00:08:45 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:45] [connect] Successful connection Jan 21 00:08:45 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737410925 101 Jan 21 00:08:45 volumiosrvroom volumio[13662]: 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: 3 Jan 21 00:08:46 volumiosrvroom ntpd[845]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 21 00:08:46 volumiosrvroom volumio[13662]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Jan 21 00:08:46 volumiosrvroom volumio[13662]: info: Discovery: Started advertising with name: undefined Jan 21 00:08:48 volumiosrvroom volumio[13662]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 21 00:08:48 volumiosrvroom nmbd[829]: [2025/01/21 00:08:48.477215, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jan 21 00:08:48 volumiosrvroom nmbd[829]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jan 21 00:08:48 volumiosrvroom nmbd[829]: [2025/01/21 00:08:48.477506, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jan 21 00:08:48 volumiosrvroom nmbd[829]: send_netbios_packet: send_packet() to IP 169.254.255.255 port 137 failed Jan 21 00:08:48 volumiosrvroom nmbd[829]: [2025/01/21 00:08:48.477610, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Jan 21 00:08:48 volumiosrvroom nmbd[829]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Jan 21 00:08:50 volumiosrvroom volumio[13662]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 00:08:50 volumiosrvroom volumio[13662]: TypeError: Cannot read property 'charAt' of undefined Jan 21 00:08:50 volumiosrvroom volumio[13662]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Jan 21 00:08:50 volumiosrvroom volumio[13662]: at listOnTimeout (internal/timers.js:557:17) Jan 21 00:08:50 volumiosrvroom volumio[13662]: at processTimers (internal/timers.js:500:7) Jan 21 00:08:50 volumiosrvroom volumio[13662]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 00:08:50 volumiosrvroom sudo[13880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-21 00:07 Jan 21 00:08:50 volumiosrvroom sudo[13880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:50 volumiosrvroom sudo[13880]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:50 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 21 00:08:50 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 21 00:08:50 volumiosrvroom systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 21 00:08:50 volumiosrvroom systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 21 00:08:50 volumiosrvroom systemd[1]: Started dynamicswap service. Jan 21 00:08:51 volumiosrvroom systemd[1]: dynamicswap.service: Succeeded. Jan 21 00:08:51 volumiosrvroom systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 21 00:08:51 volumiosrvroom systemd[1]: volumio.service: Scheduled restart job, restart counter is at 60. Jan 21 00:08:51 volumiosrvroom systemd[1]: Started dynamicswap service. Jan 21 00:08:51 volumiosrvroom systemd[1]: Stopped Volumio Backend Module. Jan 21 00:08:51 volumiosrvroom systemd[1]: Started Volumio Backend Module. Jan 21 00:08:51 volumiosrvroom systemd[1]: dynamicswap.service: Succeeded. Jan 21 00:08:51 volumiosrvroom dhcpcd[1033]: wlan0: leased 192.168.12.130 for 14400 seconds Jan 21 00:08:51 volumiosrvroom dhcpcd[1033]: wlan0: adding route to 192.168.12.0/24 Jan 21 00:08:51 volumiosrvroom dhcpcd[1033]: wlan0: adding default route via 192.168.12.7 Jan 21 00:08:51 volumiosrvroom avahi-daemon[693]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.12.130. Jan 21 00:08:51 volumiosrvroom avahi-daemon[693]: New relevant interface wlan0.IPv4 for mDNS. Jan 21 00:08:51 volumiosrvroom avahi-daemon[693]: Registering new address record for 192.168.12.130 on wlan0.IPv4. Jan 21 00:08:51 volumiosrvroom ntpd[845]: ntpd exiting on signal 15 (Terminated) Jan 21 00:08:51 volumiosrvroom systemd[1]: Stopping Network Time Service... Jan 21 00:08:51 volumiosrvroom systemd[1]: ntp.service: Succeeded. Jan 21 00:08:51 volumiosrvroom systemd[1]: Stopped Network Time Service. Jan 21 00:08:51 volumiosrvroom systemd[1]: Starting Network Time Service... Jan 21 00:08:51 volumiosrvroom ntpd[13940]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 21 00:08:51 volumiosrvroom systemd[1]: Started Network Time Service. Jan 21 00:08:51 volumiosrvroom ntpd[13940]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 21 00:08:51 volumiosrvroom ntpd[13946]: proto: precision = 0.156 usec (-23) Jan 21 00:08:51 volumiosrvroom ntpd[13946]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 21 00:08:51 volumiosrvroom ntpd[13946]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 21 00:08:51 volumiosrvroom ntpd[13946]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 755 days ago Jan 21 00:08:51 volumiosrvroom ntpd[13946]: Listen and drop on 0 v6wildcard [::]:123 Jan 21 00:08:51 volumiosrvroom ntpd[13946]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 21 00:08:51 volumiosrvroom ntpd[13946]: Listen normally on 2 lo 127.0.0.1:123 Jan 21 00:08:51 volumiosrvroom ntpd[13946]: Listen normally on 3 wlan0 192.168.12.130:123 Jan 21 00:08:51 volumiosrvroom ntpd[13946]: Listening on routing socket on fd #20 for interface updates Jan 21 00:08:51 volumiosrvroom ntpd[13946]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jan 21 00:08:51 volumiosrvroom ntpd[13946]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: ------------------------------------------- Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: ----- Volumio3 ---- Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: ------------------------------------------- Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: ----- System startup ---- Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: ------------------------------------------- Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: MYVOLUMIO Environment detected Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Plugin folders cleanup Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Scanning into folder /volumio/app/plugins/ Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Scanning category audio_interface Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Scanning category miscellanea Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Scanning category music_service Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Scanning category plugins.json Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Scanning category system_controller Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Scanning category user_interface Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Scanning into folder /data/plugins/ Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Scanning category music_service Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Plugin folders cleanup completed Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: ------------------------------------------- Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: ----- Core plugins startup ---- Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: ------------------------------------------- Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Loading plugins from folder /volumio/app/plugins/ Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Adding plugin upnp to MyMusic Plugins Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Loading plugins from folder /data/plugins/ Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Loading plugin "system"... Jan 21 00:08:52 volumiosrvroom volumio[13894]: info: Loading plugin "appearance"... Jan 21 00:08:53 volumiosrvroom ntpd[13946]: Soliciting pool server 196.10.98.182 Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "network"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Refreshing Cached IP Addresses Jan 21 00:08:53 volumiosrvroom sudo[13963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 00:08:53 volumiosrvroom sudo[13963]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "services"... Jan 21 00:08:53 volumiosrvroom sudo[13963]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:53 volumiosrvroom sudo[13965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 00:08:53 volumiosrvroom sudo[13965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:53 volumiosrvroom sudo[13965]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "alsa_controller"... Jan 21 00:08:53 volumiosrvroom sudo[13968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 21 00:08:53 volumiosrvroom sudo[13968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "wizard"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "networkfs"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Starting Udev Watcher for removable devices Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Ignoring mount for partition: boot Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Ignoring mount for partition: volumio Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Ignoring mount for partition: volumio_data Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "volumio_command_line_client"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "upnp"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: [1737410933422] Starting Upmpd Daemon Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "my_music"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "mpd"... Jan 21 00:08:53 volumiosrvroom ntpd[13946]: Soliciting pool server 102.64.112.165 Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "upnp_browser"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "alarm-clock"... Jan 21 00:08:53 volumiosrvroom ntpd[13946]: Soliciting pool server 102.64.113.152 Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "airplay_emulation"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Starting Shairport Sync Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "last_100"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "webradio"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "i2s_dacs"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: I2S DAC not set, start Auto-detection Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Loading plugin "volumiodiscovery"... Jan 21 00:08:53 volumiosrvroom volumio[13894]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 00:08:53 volumiosrvroom volumio[13894]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 00:08:53 volumiosrvroom volumio[13894]: *** WARNING *** For more information see Jan 21 00:08:53 volumiosrvroom node[13894]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 00:08:53 volumiosrvroom volumio[13894]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 00:08:53 volumiosrvroom volumio[13894]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 00:08:53 volumiosrvroom volumio[13894]: *** WARNING *** For more information see Jan 21 00:08:53 volumiosrvroom node[13894]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 00:08:53 volumiosrvroom node[13894]: *** WARNING *** For more information see Jan 21 00:08:53 volumiosrvroom node[13894]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 00:08:53 volumiosrvroom node[13894]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 00:08:53 volumiosrvroom node[13894]: *** WARNING *** For more information see Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 21 00:08:53 volumiosrvroom volumio[13894]: info: Discovery: Started advertising with name: undefined Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Plugin youtube2 is not enabled Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Plugin ytcr is not enabled Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Plugin ytmusic is not enabled Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Loading plugin "outputs"... Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Loading plugin "albumart"... Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Plugin example_plugin is not enabled Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Loading plugin "inputs"... Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Loading plugin "updater_comm"... Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Plugin mpdemulation is not enabled Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Loading plugin "rest_api"... Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Loading plugin "websocket"... Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Starting Socket.io Server version 2.3.0 Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Loading i18n strings for locale en Jan 21 00:08:54 volumiosrvroom volumio[13894]: Updating browse sources language Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:54 volumiosrvroom volumio[13894]: Forking 1 albumart workers Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::initPlayerControls Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: Express server listening on port 3000 Jan 21 00:08:54 volumiosrvroom volumio[13894]: [Metrics] WebUI: 2s 395.81ms Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Setting Device type: x86 Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreStateMachine::resetVolumioState Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreStateMachine::getcurrentVolume Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Completed loading Core Plugins Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Preparing to generate the ALSA configuration file Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 21 00:08:54 volumiosrvroom volumio[13894]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 00:08:54 volumiosrvroom ntpd[13946]: Soliciting pool server 196.10.52.57 Jan 21 00:08:54 volumiosrvroom ntpd[13946]: Soliciting pool server 196.10.55.57 Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Reloading queue from file Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:54 volumiosrvroom volumio[13894]: Starting albumart workers Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Received Get System Info Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Discovery: Getting this device information Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Listing playlists Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: VolumeController:: Volume=0 Mute =false Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreStateMachine::pushState Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioPushState Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreStateMachine::updateTrackBlock Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CorePlayQueue::getTrackBlock Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Asound.conf file unchanged, so no further update is needed Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Output device has changed, restarting MPD Jan 21 00:08:54 volumiosrvroom sudo[13998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 00:08:54 volumiosrvroom sudo[13998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Output device has changed, restarting Shairport Sync Jan 21 00:08:54 volumiosrvroom sudo[13998]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:54 volumiosrvroom sudo[14001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 00:08:54 volumiosrvroom sudo[14001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:54 volumiosrvroom systemd[1]: Stopping Music Player Daemon... Jan 21 00:08:54 volumiosrvroom systemd[1]: mpd.service: Succeeded. Jan 21 00:08:54 volumiosrvroom systemd[1]: Stopped Music Player Daemon. Jan 21 00:08:54 volumiosrvroom systemd[1]: Starting Music Player Daemon... Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: ___________ START PLUGINS ___________ Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: ControllerMpd::onStart: Initializing MPD Jan 21 00:08:54 volumiosrvroom volumio[13894]: info: Creating MPD Configuration file Jan 21 00:08:54 volumiosrvroom sudo[14006]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 00:08:54 volumiosrvroom sudo[14006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:54 volumiosrvroom sudo[14006]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:54 volumiosrvroom sudo[14008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 00:08:54 volumiosrvroom sudo[14008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:54 volumiosrvroom sudo[14008]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: [1737410935005] CoreMusicLibrary::Adding element Media Servers Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:55 volumiosrvroom sudo[14012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 00:08:55 volumiosrvroom sudo[14012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:55 volumiosrvroom systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 21 00:08:55 volumiosrvroom systemd[1]: mpd.service: Succeeded. Jan 21 00:08:55 volumiosrvroom systemd[1]: Stopped Music Player Daemon. Jan 21 00:08:55 volumiosrvroom systemd[1]: Starting Music Player Daemon... Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: [1737410935165] CoreMusicLibrary::Adding element Last_100 Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: [1737410935167] CoreMusicLibrary::Adding element Webradio Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: Initializing BBC Radios Jan 21 00:08:55 volumiosrvroom sudo[14018]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 00:08:55 volumiosrvroom sudo[14018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:55 volumiosrvroom sudo[14018]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: Volumio Calling Home Jan 21 00:08:55 volumiosrvroom sudo[13968]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:55 volumiosrvroom kernel: ------------[ cut here ]------------ Jan 21 00:08:55 volumiosrvroom kernel: queue 11 already used - expect issues Jan 21 00:08:55 volumiosrvroom kernel: WARNING: CPU: 1 PID: 11712 at drivers/net/wireless/intel/iwlwifi/pcie/tx.c:728 iwl_trans_pcie_txq_enable+0x4a8/0x4d0 [iwlwifi] Jan 21 00:08:55 volumiosrvroom kernel: Modules linked in: ctr ccm nft_chain_nat xt_REDIRECT nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 nft_compat bnep nf_tables libcrc32c nfnetlink 8021q garp stp mrp llc btusb btrtl btintel btbcm bluetooth intel_rapl_common snd_hda_codec_hdmi intel_soc_dts_thermal snd_hda_codec_realtek sha3_generic snd_hda_codec_generic jitterentropy_rng ledtrig_audio intel_soc_dts_iosf sha512_generic intel_powerclamp snd_hda_intel hmac rtl8188ee snd_intel_dspcfg coretemp snd_hda_codec drbg kvm_intel rtl_pci iwldvm snd_hda_core rtlwifi snd_hwdep mac80211 snd_pcm aes_generic iwlwifi kvm crypto_simd cryptd ansi_cprng snd_seq ecdh_generic ecc libaes libarc4 snd_seq_device snd_timer irqbypass rtsx_usb_ms memstick crc32_pclmul cfg80211 snd soundcore sg mei_txe mei efi_pstore at24 sunrpc efivarfs ip_tables autofs4 loop ext4 crc32c_generic crc16 mbcache jbd2 sata_vsc sata_via sata_sx4 sata_svw sata_sis sata_sil sata_sil24 sata_qstor sata_promise sata_nv sata_mv pdc_adma pata_via pata_triflex pata_sil680 pata_serverworks Jan 21 00:08:55 volumiosrvroom kernel: pata_sch pata_rz1000 pata_rdc pata_piccolo pata_pdc202xx_old pata_pdc2027x pata_opti pata_oldpiix pata_ns87415 pata_ns87410 pata_ninja32 pata_netcell pata_mpiix pata_marvell pata_jmicron pata_it821x pata_it8213 pata_hpt37x pata_hpt366 pata_efar pata_cmd64x pata_atp867x pata_atiixp pata_artop pata_amd pata_ali acard_ahci radeon drm_suballoc_helper nouveau mxm_wmi drm_exec gpu_sched drm_ttm_helper uas usb_storage fuse vfat fat nls_utf8 nls_cp437 hid_generic hid_cherry usbhid hid sdhci_acpi sdhci_pci cqhci sdhci nvme nvme_core mmc_block squashfs overlay i915 drm_buddy i2c_algo_bit drm_display_helper ttm cec rc_core r8169 realtek rtsx_usb_sdmmc drm_kms_helper mdio_devres rtsx_usb ahci crc32c_intel libphy i2c_i801 i2c_smbus libahci drm video wmi backlight Jan 21 00:08:55 volumiosrvroom kernel: CPU: 1 PID: 11712 Comm: kworker/u4:2 Not tainted 6.6.32-volumio #2 Jan 21 00:08:55 volumiosrvroom kernel: Hardware name: To be filled by O.E.M. To be filled by O.E.M./nT-iBT18_nT-iBT19_nT-iBT29, BIOS D72P7S01_x64 09/12/2014 Jan 21 00:08:55 volumiosrvroom kernel: Workqueue: events_unbound wiphy_delayed_work_queue [cfg80211] Jan 21 00:08:55 volumiosrvroom kernel: RIP: 0010:iwl_trans_pcie_txq_enable+0x4a8/0x4d0 [iwlwifi] Jan 21 00:08:55 volumiosrvroom kernel: Code: fd ff ff c1 e2 10 0f b7 c0 09 d0 e9 88 fe ff ff 8b 75 c4 48 c7 c7 08 a8 13 a1 44 89 45 b8 c6 05 d8 88 02 00 01 e8 e8 56 fa df <0f> 0b 44 8b 45 b8 e9 a7 fb ff ff 0f 0b e9 64 fd ff ff 0f 0b e9 9e Jan 21 00:08:55 volumiosrvroom kernel: RSP: 0018:ffffc90000f87bd8 EFLAGS: 00010282 Jan 21 00:08:55 volumiosrvroom kernel: RAX: 0000000000000000 RBX: 0000000000000038 RCX: 0000000000000027 Jan 21 00:08:55 volumiosrvroom kernel: RDX: ffff88813bd1c5c8 RSI: 0000000000000001 RDI: ffff88813bd1c5c0 Jan 21 00:08:55 volumiosrvroom kernel: RBP: ffffc90000f87c30 R08: 00000000ffffefff R09: ffffc90000f87a38 Jan 21 00:08:55 volumiosrvroom kernel: R10: 0000000000000001 R11: ffffffff82450a80 R12: ffff888111484028 Jan 21 00:08:55 volumiosrvroom kernel: R13: ffffc90000f87c60 R14: 0000000000000038 R15: ffff888108fcd898 Jan 21 00:08:55 volumiosrvroom kernel: FS: 0000000000000000(0000) GS:ffff88813bd00000(0000) knlGS:0000000000000000 Jan 21 00:08:55 volumiosrvroom kernel: CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 Jan 21 00:08:55 volumiosrvroom kernel: CR2: 00007f1264fbf200 CR3: 00000000044ee000 CR4: 00000000001006e0 Jan 21 00:08:55 volumiosrvroom kernel: Call Trace: Jan 21 00:08:55 volumiosrvroom kernel: Jan 21 00:08:55 volumiosrvroom kernel: ? show_regs+0x69/0x80 Jan 21 00:08:55 volumiosrvroom kernel: ? __warn+0x8d/0x150 Jan 21 00:08:55 volumiosrvroom kernel: ? iwl_trans_pcie_txq_enable+0x4a8/0x4d0 [iwlwifi] Jan 21 00:08:55 volumiosrvroom kernel: ? report_bug+0x171/0x1a0 Jan 21 00:08:55 volumiosrvroom kernel: ? handle_bug+0x42/0x80 Jan 21 00:08:55 volumiosrvroom kernel: ? exc_invalid_op+0x1c/0x70 Jan 21 00:08:55 volumiosrvroom kernel: ? asm_exc_invalid_op+0x1f/0x30 Jan 21 00:08:55 volumiosrvroom kernel: ? iwl_trans_pcie_txq_enable+0x4a8/0x4d0 [iwlwifi] Jan 21 00:08:55 volumiosrvroom kernel: ? iwl_trans_pcie_txq_enable+0x4a8/0x4d0 [iwlwifi] Jan 21 00:08:55 volumiosrvroom kernel: iwlagn_tx_agg_oper+0x118/0x210 [iwldvm] Jan 21 00:08:55 volumiosrvroom kernel: iwlagn_rate_control_unregister+0x616/0x24a0 [iwldvm] Jan 21 00:08:55 volumiosrvroom kernel: drv_ampdu_action+0xde/0x1a0 [mac80211] Jan 21 00:08:55 volumiosrvroom kernel: ieee80211_refresh_tx_agg_session_timer+0x437/0x760 [mac80211] Jan 21 00:08:55 volumiosrvroom kernel: ieee80211_process_addba_resp+0x19a/0x1b0 [mac80211] Jan 21 00:08:55 volumiosrvroom kernel: ieee80211_ibss_leave+0xdc3/0x1e80 [mac80211] Jan 21 00:08:55 volumiosrvroom kernel: wiphy_delayed_work_queue+0x11a/0x130 [cfg80211] Jan 21 00:08:55 volumiosrvroom kernel: process_one_work+0x141/0x300 Jan 21 00:08:55 volumiosrvroom kernel: worker_thread+0x2f6/0x430 Jan 21 00:08:55 volumiosrvroom kernel: ? __pfx_worker_thread+0x10/0x10 Jan 21 00:08:55 volumiosrvroom kernel: kthread+0xf6/0x130 Jan 21 00:08:55 volumiosrvroom kernel: ? __pfx_kthread+0x10/0x10 Jan 21 00:08:55 volumiosrvroom kernel: ret_from_fork+0x41/0x60 Jan 21 00:08:55 volumiosrvroom kernel: ? __pfx_kthread+0x10/0x10 Jan 21 00:08:55 volumiosrvroom kernel: ret_from_fork_asm+0x1b/0x30 Jan 21 00:08:55 volumiosrvroom kernel: Jan 21 00:08:55 volumiosrvroom kernel: ---[ end trace 0000000000000000 ]--- Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreStateMachine::setRepeat null single undefined Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreStateMachine::pushState Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioPushState Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreStateMachine::setRandom null Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreStateMachine::pushState Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioPushState Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: MPD Permissions set Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: MPD Permissions set Jan 21 00:08:55 volumiosrvroom volumio[13894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom ntpd[13946]: Soliciting pool server 102.64.113.151 Jan 21 00:08:55 volumiosrvroom ntpd[13946]: Soliciting pool server 196.49.100.57 Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: VolumeController:: Volume=0 Mute =false Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreStateMachine::pushState Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioPushState Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: Starting Shairport Sync Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: Starting Shairport Sync Jan 21 00:08:55 volumiosrvroom sudo[14059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 00:08:55 volumiosrvroom sudo[14059]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: Starting Shairport Sync Jan 21 00:08:55 volumiosrvroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 00:08:55 volumiosrvroom systemd[1]: shairport-sync.service: Succeeded. Jan 21 00:08:55 volumiosrvroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:55 volumiosrvroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:55 volumiosrvroom sudo[14059]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:55 volumiosrvroom sudo[14062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 00:08:55 volumiosrvroom volumio[13894]: info: Shairport-Sync Started Jan 21 00:08:55 volumiosrvroom sudo[14062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:55 volumiosrvroom sudo[14065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 00:08:55 volumiosrvroom sudo[14065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 00:08:55 volumiosrvroom volumio[13894]: Error adding Membership: Error: addMembership EINVAL Jan 21 00:08:55 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:55] [connect] Successful connection Jan 21 00:08:55 volumiosrvroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 00:08:55 volumiosrvroom volumio-remote-updater[704]: [2025-01-21 00:08:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737410935 101 Jan 21 00:08:55 volumiosrvroom systemd[1]: shairport-sync.service: Succeeded. Jan 21 00:08:55 volumiosrvroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:56 volumiosrvroom volumio[13894]: 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: 3 Jan 21 00:08:56 volumiosrvroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:56 volumiosrvroom sudo[14062]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:56 volumiosrvroom volumio[13894]: info: Shairport-Sync Started Jan 21 00:08:56 volumiosrvroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 00:08:56 volumiosrvroom systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 21 00:08:56 volumiosrvroom systemd[1]: shairport-sync.service: Succeeded. Jan 21 00:08:56 volumiosrvroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:56 volumiosrvroom ntpd[13946]: Soliciting pool server 102.222.156.150 Jan 21 00:08:56 volumiosrvroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 00:08:56 volumiosrvroom sudo[14065]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:56 volumiosrvroom volumio[13894]: info: Shairport-Sync Started Jan 21 00:08:56 volumiosrvroom mpd[14041]: Jan 21 00:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 21 00:08:56 volumiosrvroom systemd[1]: Started Music Player Daemon. Jan 21 00:08:56 volumiosrvroom sudo[14012]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:56 volumiosrvroom sudo[14001]: pam_unix(sudo:session): session closed for user root Jan 21 00:08:56 volumiosrvroom volumio[13894]: info: Completed starting Core Plugins Jan 21 00:08:56 volumiosrvroom volumio[13894]: info: ------------------------------------------- Jan 21 00:08:56 volumiosrvroom volumio[13894]: info: ----- MyVolumio plugins startup ---- Jan 21 00:08:56 volumiosrvroom volumio[13894]: info: ------------------------------------------- Jan 21 00:08:56 volumiosrvroom volumio[13894]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 21 00:08:56 volumiosrvroom volumio[13894]: error: MPD error: The expression evaluated to a falsy value: Jan 21 00:08:56 volumiosrvroom volumio[13894]: assert.ok(self.idling) Jan 21 00:08:56 volumiosrvroom volumio[13894]: error: The expression evaluated to a falsy value: Jan 21 00:08:56 volumiosrvroom volumio[13894]: assert.ok(self.idling) Jan 21 00:08:56 volumiosrvroom volumio[13894]: info: MPD running with PID14041 Jan 21 00:08:56 volumiosrvroom volumio[13894]: ,establishing connection Jan 21 00:08:56 volumiosrvroom volumio[13894]: error: updateQueue error: null Jan 21 00:08:56 volumiosrvroom volumio[13894]: error: updateQueue error: null Jan 21 00:08:56 volumiosrvroom ntpd[13946]: Soliciting pool server 196.10.52.58 Jan 21 00:08:56 volumiosrvroom ntpd[13946]: Soliciting pool server 196.49.100.58 Jan 21 00:08:57 volumiosrvroom ntpd[13946]: Soliciting pool server 196.10.54.58 Jan 21 00:08:57 volumiosrvroom ntpd[13946]: Soliciting pool server 41.175.49.55 Jan 21 00:08:57 volumiosrvroom ntpd[13946]: Soliciting pool server 196.10.55.58 Jan 21 00:08:57 volumiosrvroom volumio[13894]: info: CoreCommandRouter::volumioGetState Jan 21 00:08:57 volumiosrvroom volumio[13894]: info: CorePlayQueue::getTrack 0 Jan 21 00:08:58 volumiosrvroom volumio[13894]: info: Volumio called home Jan 21 00:08:58 volumiosrvroom ntpd[13946]: Soliciting pool server 155.232.19.6 Jan 21 00:08:58 volumiosrvroom ntpd[13946]: Soliciting pool server 155.232.19.7 Jan 21 00:08:58 volumiosrvroom volumio[13894]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Jan 21 00:08:59 volumiosrvroom volumio[13894]: info: Discovery: Started advertising with name: undefined Jan 21 00:08:59 volumiosrvroom ntpd[13946]: Soliciting pool server 102.64.112.164 Jan 21 00:08:59 volumiosrvroom ntpd[13946]: Soliciting pool server 2a12:8fc6:c27a:60::150 Jan 21 00:09:00 volumiosrvroom volumio[13894]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 21 00:09:02 volumiosrvroom volumio[13894]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 00:09:02 volumiosrvroom volumio[13894]: TypeError: Cannot read property 'charAt' of undefined Jan 21 00:09:02 volumiosrvroom volumio[13894]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Jan 21 00:09:02 volumiosrvroom volumio[13894]: at listOnTimeout (internal/timers.js:557:17) Jan 21 00:09:02 volumiosrvroom volumio[13894]: at processTimers (internal/timers.js:500:7) Jan 21 00:09:02 volumiosrvroom volumio[13894]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 00:09:03 volumiosrvroom sudo[14089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-21 00:08 Jan 21 00:09:03 volumiosrvroom sudo[14089]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"