-- Logs begin at Thu 2019-02-14 19:12:00 JST, end at Sun 2024-12-01 21:50:48 JST. -- Dec 01 21:49:49 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-DISCONNECTED bssid=28:87:ba:ea:c3:8a reason=0 locally_generated=1 Dec 01 21:49:49 volumio dhcpcd[933]: wlan0: carrier lost Dec 01 21:49:49 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 01 21:49:49 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=JP Dec 01 21:49:50 volumio avahi-daemon[638]: Withdrawing address record for 192.168.0.40 on wlan0. Dec 01 21:49:50 volumio avahi-daemon[638]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.40. Dec 01 21:49:50 volumio avahi-daemon[638]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 01 21:49:50 volumio dhcpcd[933]: wlan0: deleting route to 192.168.0.0/24 Dec 01 21:49:50 volumio dhcpcd[933]: wlan0: deleting default route via 192.168.0.1 Dec 01 21:49:50 volumio volumio[8541]: info: Discovery: A device disappeared from network Dec 01 21:49:50 volumio volumio[8541]: info: Discovery: Device volumio disappeared from network Dec 01 21:49:51 volumio ntpd[807]: Deleting interface #17 wlan0, 192.168.0.40#123, interface stats: received=58, sent=63, dropped=0, active_time=123 secs Dec 01 21:49:51 volumio ntpd[807]: 133.130.121.141 local addr 192.168.0.40 -> Dec 01 21:49:51 volumio ntpd[807]: 133.243.238.163 local addr 192.168.0.40 -> Dec 01 21:49:51 volumio ntpd[807]: 133.243.238.243 local addr 192.168.0.40 -> Dec 01 21:49:51 volumio ntpd[807]: 160.16.113.133 local addr 192.168.0.40 -> Dec 01 21:49:51 volumio ntpd[807]: 45.77.20.103 local addr 192.168.0.40 -> Dec 01 21:49:51 volumio ntpd[807]: 45.76.211.39 local addr 192.168.0.40 -> Dec 01 21:49:51 volumio ntpd[807]: 163.44.97.196 local addr 192.168.0.40 -> Dec 01 21:49:51 volumio ntpd[807]: 202.182.111.234 local addr 192.168.0.40 -> Dec 01 21:49:51 volumio ntpd[807]: 45.76.218.37 local addr 192.168.0.40 -> Dec 01 21:49:56 volumio wpa_supplicant[921]: wlan0: Trying to associate with SSID 'IODATA-e0e2b4-2G_EXT' Dec 01 21:49:57 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 01 21:49:58 volumio wpa_supplicant[921]: wlan0: Trying to associate with SSID 'IODATA-e0e2b4-2G_EXT' Dec 01 21:49:59 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 01 21:49:59 volumio volumio[8541]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 21:49:59 volumio volumio[8541]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 01 21:49:59 volumio volumio[8541]: at doSend (dgram.js:692:16) Dec 01 21:49:59 volumio volumio[8541]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 01 21:49:59 volumio volumio[8541]: at afterDns (dgram.js:638:5) Dec 01 21:49:59 volumio volumio[8541]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 01 21:49:59 volumio volumio[8541]: errno: -101, Dec 01 21:49:59 volumio volumio[8541]: code: 'ENETUNREACH', Dec 01 21:49:59 volumio volumio[8541]: syscall: 'send', Dec 01 21:49:59 volumio volumio[8541]: address: '255.255.255.255', Dec 01 21:49:59 volumio volumio[8541]: port: 3483 Dec 01 21:49:59 volumio volumio[8541]: } Dec 01 21:49:59 volumio volumio[8541]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 21:50:00 volumio sudo[9114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 21:49 Dec 01 21:50:00 volumio sudo[9114]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:00 volumio sudo[9114]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:00 volumio wpa_supplicant[921]: wlan0: Trying to associate with SSID 'IODATA-e0e2b4-2G_EXT' Dec 01 21:50:00 volumio volumio-remote-updater[639]: [2024-12-01 21:50:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 01 21:50:00 volumio volumio-remote-updater[639]: [2024-12-01 21:50:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 01 21:50:00 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 01 21:50:00 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 01 21:50:01 volumio systemd[1]: Started dynamicswap service. Dec 01 21:50:01 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 01 21:50:01 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 01 21:50:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Dec 01 21:50:01 volumio systemd[1]: Started dynamicswap service. Dec 01 21:50:01 volumio systemd[1]: Stopped Volumio Backend Module. Dec 01 21:50:01 volumio systemd[1]: Started Volumio Backend Module. Dec 01 21:50:01 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 01 21:50:01 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 01 21:50:01 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="IODATA-e0e2b4-2G_EXT" auth_failures=1 duration=10 reason=CONN_FAILED Dec 01 21:50:05 volumio volumio[9129]: info: ------------------------------------------- Dec 01 21:50:05 volumio volumio[9129]: info: ----- Volumio3 ---- Dec 01 21:50:05 volumio volumio[9129]: info: ------------------------------------------- Dec 01 21:50:05 volumio volumio[9129]: info: ----- System startup ---- Dec 01 21:50:05 volumio volumio[9129]: info: ------------------------------------------- Dec 01 21:50:05 volumio volumio-remote-updater[639]: [2024-12-01 21:50:05] [connect] Successful connection Dec 01 21:50:06 volumio volumio[9129]: info: MYVOLUMIO Environment detected Dec 01 21:50:06 volumio volumio[9129]: info: Plugin folders cleanup Dec 01 21:50:06 volumio volumio[9129]: info: Scanning into folder /volumio/app/plugins/ Dec 01 21:50:06 volumio volumio[9129]: info: Scanning category audio_interface Dec 01 21:50:06 volumio volumio[9129]: info: Scanning category miscellanea Dec 01 21:50:06 volumio volumio[9129]: info: Scanning category music_service Dec 01 21:50:06 volumio volumio[9129]: info: Scanning category plugins.json Dec 01 21:50:06 volumio volumio[9129]: info: Scanning category system_controller Dec 01 21:50:06 volumio volumio[9129]: info: Scanning category user_interface Dec 01 21:50:06 volumio volumio[9129]: info: Scanning into folder /data/plugins/ Dec 01 21:50:06 volumio volumio[9129]: info: Scanning category music_service Dec 01 21:50:06 volumio volumio[9129]: info: Scanning category user_interface Dec 01 21:50:06 volumio volumio[9129]: info: Plugin folders cleanup completed Dec 01 21:50:06 volumio volumio[9129]: info: ------------------------------------------- Dec 01 21:50:06 volumio volumio[9129]: info: ----- Core plugins startup ---- Dec 01 21:50:06 volumio volumio[9129]: info: ------------------------------------------- Dec 01 21:50:06 volumio volumio[9129]: info: Loading plugins from folder /volumio/app/plugins/ Dec 01 21:50:06 volumio volumio[9129]: info: Adding plugin upnp to MyMusic Plugins Dec 01 21:50:06 volumio volumio[9129]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 01 21:50:06 volumio volumio[9129]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 01 21:50:06 volumio volumio[9129]: info: Loading plugins from folder /data/plugins/ Dec 01 21:50:06 volumio volumio[9129]: info: Loading plugin "system"... Dec 01 21:50:07 volumio volumio[9129]: info: Loading plugin "appearance"... Dec 01 21:50:09 volumio volumio[9129]: info: Loading plugin "network"... Dec 01 21:50:09 volumio volumio[9129]: info: Refreshing Cached IP Addresses Dec 01 21:50:09 volumio sudo[9156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 21:50:09 volumio sudo[9156]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:09 volumio sudo[9156]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:09 volumio volumio[9129]: info: Loading plugin "services"... Dec 01 21:50:09 volumio volumio[9129]: info: Loading plugin "alsa_controller"... Dec 01 21:50:09 volumio sudo[9159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 21:50:09 volumio sudo[9159]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:09 volumio sudo[9162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 01 21:50:09 volumio sudo[9159]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:09 volumio sudo[9162]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:09 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:50:09 volumio volumio[9129]: info: Loading plugin "wizard"... Dec 01 21:50:09 volumio volumio[9129]: info: Loading plugin "networkfs"... Dec 01 21:50:09 volumio volumio[9129]: info: Starting Udev Watcher for removable devices Dec 01 21:50:09 volumio volumio[9129]: info: Ignoring mount for partition: boot Dec 01 21:50:09 volumio volumio[9129]: info: Ignoring mount for partition: volumio Dec 01 21:50:09 volumio volumio[9129]: info: Ignoring mount for partition: volumio_data Dec 01 21:50:09 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 21:50:09 volumio volumio[9129]: info: Loading plugin "volumio_command_line_client"... Dec 01 21:50:09 volumio volumio[9129]: info: Loading plugin "upnp"... Dec 01 21:50:09 volumio volumio[9129]: info: [1733057409930] Starting Upmpd Daemon Dec 01 21:50:09 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 21:50:09 volumio volumio[9129]: info: Loading plugin "my_music"... Dec 01 21:50:09 volumio volumio[9129]: info: Loading plugin "mpd"... Dec 01 21:50:10 volumio sudo[9162]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:10 volumio volumio[9129]: info: Loading plugin "upnp_browser"... Dec 01 21:50:11 volumio volumio[9129]: info: Loading plugin "alarm-clock"... Dec 01 21:50:11 volumio volumio[9129]: info: Loading plugin "airplay_emulation"... Dec 01 21:50:11 volumio volumio[9129]: info: Starting Shairport Sync Dec 01 21:50:11 volumio volumio[9129]: info: Loading plugin "last_100"... Dec 01 21:50:11 volumio volumio[9129]: info: Loading plugin "webradio"... Dec 01 21:50:11 volumio volumio[9129]: info: Loading plugin "i2s_dacs"... Dec 01 21:50:11 volumio volumio[9129]: info: I2S DAC not set, start Auto-detection Dec 01 21:50:11 volumio volumio[9129]: info: Loading plugin "volumiodiscovery"... Dec 01 21:50:12 volumio volumio[9129]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 21:50:12 volumio node[9129]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 21:50:12 volumio volumio[9129]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:50:12 volumio node[9129]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:50:12 volumio volumio[9129]: *** WARNING *** For more information see Dec 01 21:50:12 volumio node[9129]: *** WARNING *** For more information see Dec 01 21:50:12 volumio volumio[9129]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 21:50:12 volumio node[9129]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 21:50:12 volumio volumio[9129]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:50:12 volumio node[9129]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:50:12 volumio volumio[9129]: *** WARNING *** For more information see Dec 01 21:50:12 volumio node[9129]: *** WARNING *** For more information see Dec 01 21:50:12 volumio volumio[9129]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 01 21:50:12 volumio volumio[9129]: info: Discovery: Started advertising with name: Volumio Dec 01 21:50:12 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 21:50:12 volumio volumio[9129]: info: Loading plugin "squeezelite_mc"... Dec 01 21:50:12 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="IODATA-e0e2b4-2G_EXT" Dec 01 21:50:12 volumio wpa_supplicant[921]: wlan0: Trying to associate with SSID 'IODATA-e0e2b4-2G_EXT' Dec 01 21:50:12 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 01 21:50:12 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="IODATA-e0e2b4-2G_EXT" auth_failures=2 duration=26 reason=CONN_FAILED Dec 01 21:50:13 volumio volumio[9129]: info: Loading plugin "outputs"... Dec 01 21:50:13 volumio volumio[9129]: info: Loading plugin "albumart"... Dec 01 21:50:13 volumio volumio[9129]: info: Plugin example_plugin is not enabled Dec 01 21:50:13 volumio volumio[9129]: info: Loading plugin "inputs"... Dec 01 21:50:13 volumio volumio[9129]: info: Loading plugin "updater_comm"... Dec 01 21:50:14 volumio volumio[9129]: info: Plugin mpdemulation is not enabled Dec 01 21:50:14 volumio volumio[9129]: info: Loading plugin "rest_api"... Dec 01 21:50:14 volumio volumio[9129]: info: Loading plugin "websocket"... Dec 01 21:50:14 volumio volumio[9129]: info: Starting Socket.io Server version 2.3.0 Dec 01 21:50:14 volumio volumio[9129]: info: Loading plugin "lms"... Dec 01 21:50:14 volumio volumio[9129]: info: Loading plugin "touch_display"... Dec 01 21:50:14 volumio volumio[9129]: Forking 3 albumart workers Dec 01 21:50:15 volumio volumio[9129]: info: Applying required configuration parameters for plugin touch_display Dec 01 21:50:15 volumio volumio[9129]: info: Loading i18n strings for locale ja Dec 01 21:50:15 volumio volumio[9129]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 01 21:50:15 volumio volumio[9129]: Updating browse sources language Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::initPlayerControls Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:15 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:50:16 volumio volumio[9129]: Express server listening on port 3000 Dec 01 21:50:16 volumio volumio[9129]: [Metrics] WebUI: 12s 104.58ms Dec 01 21:50:16 volumio volumio[9129]: info: CoreStateMachine::resetVolumioState Dec 01 21:50:16 volumio volumio[9129]: info: CoreStateMachine::getcurrentVolume Dec 01 21:50:16 volumio volumio[9129]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 21:50:16 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:16 volumio volumio[9129]: info: Cannot read play queue from file Dec 01 21:50:16 volumio volumio[9129]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 01 21:50:16 volumio volumio[9129]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 01 21:50:16 volumio volumio[9129]: Starting albumart workers Dec 01 21:50:16 volumio volumio[9129]: Starting albumart workers Dec 01 21:50:16 volumio volumio[9129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 01 21:50:16 volumio volumio[9129]: Starting albumart workers Dec 01 21:50:17 volumio volumio[9129]: info: CoreStateMachine::setRepeat null single undefined Dec 01 21:50:17 volumio volumio[9129]: info: CoreStateMachine::pushState Dec 01 21:50:17 volumio volumio[9129]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:17 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:50:17 volumio volumio[9129]: info: CoreCommandRouter::volumioPushState Dec 01 21:50:17 volumio volumio[9129]: info: CoreStateMachine::setRandom null Dec 01 21:50:17 volumio volumio[9129]: info: CoreStateMachine::pushState Dec 01 21:50:17 volumio volumio[9129]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:17 volumio volumio[9129]: info: CoreCommandRouter::volumioPushState Dec 01 21:50:17 volumio volumio[9129]: info: Setting Device type: Raspberry PI Dec 01 21:50:17 volumio volumio[9129]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 01 21:50:17 volumio volumio[9129]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 Dec 01 21:50:17 volumio volumio[9129]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 01 21:50:17 volumio volumio[9129]: info: VolumeController:: Volume=86 Mute =false Dec 01 21:50:17 volumio volumio[9129]: info: CoreStateMachine::pushState Dec 01 21:50:17 volumio volumio[9129]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:17 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:50:17 volumio volumio[9129]: info: CoreCommandRouter::volumioPushState Dec 01 21:50:17 volumio volumio[9129]: info: CoreStateMachine::updateTrackBlock Dec 01 21:50:17 volumio volumio[9129]: info: CorePlayQueue::getTrackBlock Dec 01 21:50:17 volumio volumio[9129]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 21:50:17 volumio volumio[9129]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 01 21:50:17 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 01 21:50:17 volumio volumio[9129]: info: Completed loading Core Plugins Dec 01 21:50:17 volumio volumio[9129]: info: Preparing to generate the ALSA configuration file Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::volumioGetState Dec 01 21:50:18 volumio volumio[9129]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 01 21:50:18 volumio volumio[9129]: info: Received Get System Info Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 21:50:18 volumio volumio[9129]: info: Discovery: Getting this device information Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::volumioGetState Dec 01 21:50:18 volumio volumio[9129]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::volumioGetState Dec 01 21:50:18 volumio volumio[9129]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:18 volumio volumio[9129]: info: Listing playlists Dec 01 21:50:18 volumio volumio[9129]: info: VolumeController:: Volume=86 Mute =false Dec 01 21:50:18 volumio volumio[9129]: info: CoreStateMachine::pushState Dec 01 21:50:18 volumio volumio[9129]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::volumioPushState Dec 01 21:50:18 volumio volumio[9129]: info: Asound.conf file unchanged, so no further update is needed Dec 01 21:50:18 volumio volumio[9129]: info: Output device has changed, restarting MPD Dec 01 21:50:18 volumio volumio-remote-updater[639]: [2024-12-01 21:50:18] [connect] Successful connection Dec 01 21:50:18 volumio volumio[9129]: info: Output device has changed, restarting Shairport Sync Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:18 volumio sudo[9215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 21:50:18 volumio sudo[9215]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:18 volumio sudo[9215]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:18 volumio sudo[9218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 21:50:18 volumio sudo[9218]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:18 volumio systemd[1]: Stopping Music Player Daemon... Dec 01 21:50:18 volumio systemd[1]: mpd.service: Succeeded. Dec 01 21:50:18 volumio systemd[1]: Stopped Music Player Daemon. Dec 01 21:50:18 volumio volumio[9129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 21:50:18 volumio volumio[9129]: info: ___________ START PLUGINS ___________ Dec 01 21:50:18 volumio volumio[9129]: info: ControllerMpd::onStart: Initializing MPD Dec 01 21:50:18 volumio volumio[9129]: info: Creating MPD Configuration file Dec 01 21:50:18 volumio systemd[1]: Starting Music Player Daemon... Dec 01 21:50:18 volumio sudo[9238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 21:50:18 volumio sudo[9238]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:18 volumio sudo[9238]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:50:18 volumio volumio[9129]: info: [1733057418917] CoreMusicLibrary::Adding element メディアサーバー Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:18 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:19 volumio sudo[9248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 21:50:19 volumio sudo[9248]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:19 volumio sudo[9227]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 21:50:19 volumio sudo[9227]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:19 volumio sudo[9227]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:19 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 01 21:50:19 volumio systemd[1]: mpd.service: Succeeded. Dec 01 21:50:19 volumio systemd[1]: Stopped Music Player Daemon. Dec 01 21:50:19 volumio volumio[9129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 21:50:19 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:19 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:19 volumio systemd[1]: Starting Music Player Daemon... Dec 01 21:50:19 volumio volumio[9129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 21:50:19 volumio volumio[9129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:50:19 volumio volumio[9129]: info: [1733057419254] CoreMusicLibrary::Adding element Last_100 Dec 01 21:50:19 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:50:19 volumio volumio[9129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:50:19 volumio volumio[9129]: info: [1733057419261] CoreMusicLibrary::Adding element Webradio Dec 01 21:50:19 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:50:19 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:50:19 volumio volumio[9129]: info: Initializing BBC Radios Dec 01 21:50:19 volumio sudo[9255]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 21:50:19 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:50:19 volumio sudo[9255]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:19 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:19 volumio sudo[9255]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:19 volumio volumio[9129]: info: [squeezelite_mc] Starting proxy server... Dec 01 21:50:19 volumio volumio[9129]: info: Loading i18n strings for locale ja Dec 01 21:50:19 volumio volumio[9129]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 01 21:50:19 volumio sudo[9272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Dec 01 21:50:19 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:50:19 volumio sudo[9272]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:19 volumio volumio[9129]: info: Volumio Calling Home Dec 01 21:50:19 volumio systemd[1]: Stopping Logitech Media Server Daemon... Dec 01 21:50:19 volumio volumio[9129]: info: [squeezelite_mc] Proxy server started on port 44401 Dec 01 21:50:19 volumio systemd[1]: logitechmediaserver.service: Succeeded. Dec 01 21:50:19 volumio systemd[1]: Stopped Logitech Media Server Daemon. Dec 01 21:50:19 volumio sudo[9278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 01 21:50:19 volumio sudo[9278]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:19 volumio sudo[9281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 01 21:50:19 volumio sudo[9281]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:19 volumio systemd[1]: Starting Logitech Media Server Daemon... Dec 01 21:50:19 volumio sudo[9285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 01 21:50:19 volumio sudo[9285]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:20 volumio systemd[1]: Started Logitech Media Server Daemon. Dec 01 21:50:20 volumio sudo[9272]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 21:50:20 volumio systemd[1]: Reloading. Dec 01 21:50:20 volumio volumio[9129]: Unhandled rejection Error: No sockets available, cannot start. Dec 01 21:50:20 volumio volumio[9129]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 01 21:50:20 volumio volumio[9129]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 01 21:50:20 volumio volumio[9129]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 01 21:50:20 volumio volumio[9129]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 01 21:50:20 volumio volumio[9129]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 01 21:50:20 volumio volumio[9129]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 01 21:50:20 volumio volumio[9129]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 01 21:50:20 volumio volumio[9129]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 01 21:50:20 volumio volumio[9129]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 01 21:50:20 volumio volumio[9129]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 01 21:50:20 volumio volumio[9129]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 01 21:50:20 volumio volumio[9129]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 01 21:50:20 volumio volumio[9129]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 01 21:50:20 volumio volumio[9129]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 01 21:50:20 volumio volumio[9129]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 01 21:50:20 volumio volumio[9129]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 01 21:50:20 volumio volumio[9129]: info: touch_display: No backlight interface detected. Dec 01 21:50:20 volumio sudo[9302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 21:50:20 volumio sudo[9302]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:20 volumio sudo[9302]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:20 volumio sudo[9306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 21:50:20 volumio sudo[9306]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:20 volumio sudo[9306]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:20 volumio volumio[9129]: info: logitechmediaserver started Dec 01 21:50:20 volumio volumio[9129]: info: MPD Permissions set Dec 01 21:50:20 volumio volumio[9129]: info: MPD Permissions set Dec 01 21:50:20 volumio volumio-remote-updater[639]: [2024-12-01 21:50:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1733057418 101 Dec 01 21:50:20 volumio volumio[9129]: 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: 5 Dec 01 21:50:20 volumio volumio[9129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:20 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:21 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:21 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:21 volumio volumio[9129]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 01 21:50:21 volumio volumio[9129]: info: VolumeController:: Volume=86 Mute =false Dec 01 21:50:21 volumio volumio[9129]: info: CoreStateMachine::pushState Dec 01 21:50:21 volumio volumio[9129]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:21 volumio volumio[9129]: info: CoreCommandRouter::volumioPushState Dec 01 21:50:21 volumio volumio[9129]: info: [squeezelite_mc] Server discovery started Dec 01 21:50:21 volumio volumio[9129]: info: [squeezelite_mc] Player finder started Dec 01 21:50:21 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:21 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:21 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:21 volumio volumio[9129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:50:21 volumio volumio[9129]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 01 21:50:21 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 01 21:50:21 volumio volumio[9129]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 21:50:21 volumio sudo[9319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 01 21:50:21 volumio sudo[9319]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:21 volumio sudo[9319]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:21 volumio volumio[9129]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 01 21:50:21 volumio volumio[9129]: at doSend (dgram.js:692:16) Dec 01 21:50:21 volumio volumio[9129]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 01 21:50:21 volumio volumio[9129]: at afterDns (dgram.js:638:5) Dec 01 21:50:21 volumio volumio[9129]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 01 21:50:21 volumio volumio[9129]: errno: -101, Dec 01 21:50:21 volumio volumio[9129]: code: 'ENETUNREACH', Dec 01 21:50:21 volumio volumio[9129]: syscall: 'send', Dec 01 21:50:21 volumio volumio[9129]: address: '255.255.255.255', Dec 01 21:50:21 volumio volumio[9129]: port: 3483 Dec 01 21:50:21 volumio volumio[9129]: } Dec 01 21:50:21 volumio volumio[9129]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 21:50:21 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Dec 01 21:50:22 volumio systemd[1]: Reloading. Dec 01 21:50:22 volumio sudo[9285]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:23 volumio sudo[9345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 21:49 Dec 01 21:50:23 volumio sudo[9345]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:23 volumio sudo[9345]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:23 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 01 21:50:23 volumio mpd[9270]: Dec 01 21:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 01 21:50:23 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Dec 01 21:50:23 volumio volumio-remote-updater[639]: [2024-12-01 21:50:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 01 21:50:23 volumio volumio-remote-updater[639]: [2024-12-01 21:50:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 01 21:50:24 volumio systemd[1]: Started Music Player Daemon. Dec 01 21:50:24 volumio sudo[9248]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 01 21:50:24 volumio sudo[9218]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:24 volumio sudo[9278]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:24 volumio sudo[9281]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 01 21:50:24 volumio systemd[1]: Started dynamicswap service. Dec 01 21:50:24 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 01 21:50:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Dec 01 21:50:24 volumio systemd[1]: Stopped Volumio Backend Module. Dec 01 21:50:24 volumio systemd[1]: Started Volumio Backend Module. Dec 01 21:50:24 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 01 21:50:28 volumio volumio-remote-updater[639]: [2024-12-01 21:50:28] [connect] Successful connection Dec 01 21:50:28 volumio volumio[9355]: info: ------------------------------------------- Dec 01 21:50:28 volumio volumio[9355]: info: ----- Volumio3 ---- Dec 01 21:50:28 volumio volumio[9355]: info: ------------------------------------------- Dec 01 21:50:28 volumio volumio[9355]: info: ----- System startup ---- Dec 01 21:50:28 volumio volumio[9355]: info: ------------------------------------------- Dec 01 21:50:29 volumio nmbd[797]: [2024/12/01 21:50:29.436452, 0] ../source3/libsmb/nmblib.c:917(send_udp) Dec 01 21:50:29 volumio nmbd[797]: Packet send failed to 192.168.0.255(137) ERRNO=Network is unreachable Dec 01 21:50:29 volumio nmbd[797]: [2024/12/01 21:50:29.437092, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 01 21:50:29 volumio nmbd[797]: send_netbios_packet: send_packet() to IP 192.168.0.255 port 137 failed Dec 01 21:50:29 volumio nmbd[797]: [2024/12/01 21:50:29.437317, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Dec 01 21:50:29 volumio nmbd[797]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Dec 01 21:50:30 volumio squeezeboxserver[9288]: [24-12-01 21:50:28.0300] main::init (386) Starting Logitech Media Server (v8.5.3, 1727189510, Wed 25 Sep 2024 04:24:32 AM CEST) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Dec 01 21:50:30 volumio squeezeboxserver[9288]: [24-12-01 21:50:28.9435] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Dec 01 21:50:30 volumio volumio[9355]: info: MYVOLUMIO Environment detected Dec 01 21:50:30 volumio volumio[9355]: info: Plugin folders cleanup Dec 01 21:50:30 volumio volumio[9355]: info: Scanning into folder /volumio/app/plugins/ Dec 01 21:50:30 volumio volumio[9355]: info: Scanning category audio_interface Dec 01 21:50:30 volumio volumio[9355]: info: Scanning category miscellanea Dec 01 21:50:30 volumio volumio[9355]: info: Scanning category music_service Dec 01 21:50:30 volumio volumio[9355]: info: Scanning category plugins.json Dec 01 21:50:30 volumio volumio[9355]: info: Scanning category system_controller Dec 01 21:50:30 volumio volumio[9355]: info: Scanning category user_interface Dec 01 21:50:30 volumio volumio[9355]: info: Scanning into folder /data/plugins/ Dec 01 21:50:30 volumio volumio[9355]: info: Scanning category music_service Dec 01 21:50:30 volumio volumio[9355]: info: Scanning category user_interface Dec 01 21:50:30 volumio volumio[9355]: info: Plugin folders cleanup completed Dec 01 21:50:30 volumio volumio[9355]: info: ------------------------------------------- Dec 01 21:50:30 volumio volumio[9355]: info: ----- Core plugins startup ---- Dec 01 21:50:30 volumio volumio[9355]: info: ------------------------------------------- Dec 01 21:50:30 volumio volumio[9355]: info: Loading plugins from folder /volumio/app/plugins/ Dec 01 21:50:30 volumio volumio[9355]: info: Adding plugin upnp to MyMusic Plugins Dec 01 21:50:30 volumio volumio[9355]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 01 21:50:30 volumio volumio[9355]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 01 21:50:30 volumio volumio[9355]: info: Loading plugins from folder /data/plugins/ Dec 01 21:50:30 volumio volumio[9355]: info: Loading plugin "system"... Dec 01 21:50:30 volumio volumio[9355]: info: Loading plugin "appearance"... Dec 01 21:50:32 volumio volumio[9355]: info: Loading plugin "network"... Dec 01 21:50:32 volumio volumio[9355]: info: Refreshing Cached IP Addresses Dec 01 21:50:32 volumio sudo[9433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 21:50:32 volumio sudo[9433]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:32 volumio volumio[9355]: info: Loading plugin "services"... Dec 01 21:50:32 volumio sudo[9433]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:33 volumio volumio[9355]: info: Loading plugin "alsa_controller"... Dec 01 21:50:33 volumio sudo[9435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 21:50:33 volumio sudo[9435]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:33 volumio sudo[9438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 01 21:50:33 volumio sudo[9438]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:33 volumio sudo[9435]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:33 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:50:33 volumio volumio[9355]: info: Loading plugin "wizard"... Dec 01 21:50:33 volumio volumio[9355]: info: Loading plugin "networkfs"... Dec 01 21:50:33 volumio volumio[9355]: info: Starting Udev Watcher for removable devices Dec 01 21:50:33 volumio volumio[9355]: info: Ignoring mount for partition: boot Dec 01 21:50:33 volumio volumio[9355]: info: Ignoring mount for partition: volumio Dec 01 21:50:33 volumio volumio[9355]: info: Ignoring mount for partition: volumio_data Dec 01 21:50:33 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 21:50:33 volumio volumio[9355]: info: Loading plugin "volumio_command_line_client"... Dec 01 21:50:33 volumio volumio[9355]: info: Loading plugin "upnp"... Dec 01 21:50:33 volumio volumio[9355]: info: [1733057433408] Starting Upmpd Daemon Dec 01 21:50:33 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 21:50:33 volumio volumio[9355]: info: Loading plugin "my_music"... Dec 01 21:50:33 volumio volumio[9355]: info: Loading plugin "mpd"... Dec 01 21:50:33 volumio sudo[9438]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:34 volumio squeezeboxserver[9288]: [24-12-01 21:50:33.5544] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Dec 01 21:50:34 volumio squeezeboxserver[9288]: [24-12-01 21:50:33.5563] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Dec 01 21:50:34 volumio squeezeboxserver[9288]: [24-12-01 21:50:33.5578] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Dec 01 21:50:34 volumio squeezeboxserver[9288]: [24-12-01 21:50:33.5711] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Dec 01 21:50:34 volumio squeezeboxserver[9288]: [24-12-01 21:50:33.5756] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Dec 01 21:50:34 volumio squeezeboxserver[9288]: [24-12-01 21:50:33.5805] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Dec 01 21:50:34 volumio squeezeboxserver[9288]: [24-12-01 21:50:33.5870] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Dec 01 21:50:34 volumio squeezeboxserver[9288]: [24-12-01 21:50:33.6029] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Dec 01 21:50:34 volumio squeezeboxserver[9288]: [24-12-01 21:50:33.6096] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Dec 01 21:50:34 volumio squeezeboxserver[9288]: [24-12-01 21:50:34.1292] main::checkDataSource (1109) Warning: Schema updated or no media found in the database, initiating scan. Dec 01 21:50:34 volumio volumio[9355]: info: Loading plugin "upnp_browser"... Dec 01 21:50:34 volumio volumio[9355]: info: Loading plugin "alarm-clock"... Dec 01 21:50:35 volumio volumio[9355]: info: Loading plugin "airplay_emulation"... Dec 01 21:50:35 volumio volumio[9355]: info: Starting Shairport Sync Dec 01 21:50:35 volumio volumio[9355]: info: Loading plugin "last_100"... Dec 01 21:50:35 volumio volumio[9355]: info: Loading plugin "webradio"... Dec 01 21:50:35 volumio volumio[9355]: info: Loading plugin "i2s_dacs"... Dec 01 21:50:35 volumio volumio[9355]: info: I2S DAC not set, start Auto-detection Dec 01 21:50:35 volumio volumio[9355]: info: Loading plugin "volumiodiscovery"... Dec 01 21:50:35 volumio volumio[9355]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 21:50:35 volumio volumio[9355]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:50:35 volumio volumio[9355]: *** WARNING *** For more information see Dec 01 21:50:35 volumio volumio[9355]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 21:50:35 volumio node[9355]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 21:50:35 volumio volumio[9355]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:50:35 volumio volumio[9355]: *** WARNING *** For more information see Dec 01 21:50:35 volumio node[9355]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:50:35 volumio node[9355]: *** WARNING *** For more information see Dec 01 21:50:35 volumio node[9355]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 21:50:35 volumio node[9355]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 21:50:35 volumio node[9355]: *** WARNING *** For more information see Dec 01 21:50:35 volumio volumio[9355]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 01 21:50:35 volumio volumio[9355]: info: Discovery: Started advertising with name: Volumio Dec 01 21:50:35 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 21:50:35 volumio volumio[9355]: info: Loading plugin "squeezelite_mc"... Dec 01 21:50:37 volumio volumio[9355]: info: Loading plugin "outputs"... Dec 01 21:50:37 volumio volumio[9355]: info: Loading plugin "albumart"... Dec 01 21:50:37 volumio volumio[9355]: info: Plugin example_plugin is not enabled Dec 01 21:50:37 volumio volumio[9355]: info: Loading plugin "inputs"... Dec 01 21:50:37 volumio volumio[9355]: info: Loading plugin "updater_comm"... Dec 01 21:50:37 volumio volumio[9355]: info: Plugin mpdemulation is not enabled Dec 01 21:50:37 volumio volumio[9355]: info: Loading plugin "rest_api"... Dec 01 21:50:38 volumio volumio[9355]: info: Loading plugin "websocket"... Dec 01 21:50:38 volumio volumio[9355]: info: Starting Socket.io Server version 2.3.0 Dec 01 21:50:38 volumio volumio[9355]: info: Loading plugin "lms"... Dec 01 21:50:38 volumio volumio[9355]: info: Loading plugin "touch_display"... Dec 01 21:50:38 volumio volumio[9355]: Forking 3 albumart workers Dec 01 21:50:38 volumio squeezeboxserver[9288]: [24-12-01 21:50:38.7655] main::main (212) Starting Logitech Media Server scanner (v8.5.3, 1727189510, Wed 25 Sep 2024 04:24:32 AM CEST) perl 5.028001 Dec 01 21:50:39 volumio volumio[9355]: info: Applying required configuration parameters for plugin touch_display Dec 01 21:50:39 volumio volumio[9355]: info: Loading i18n strings for locale ja Dec 01 21:50:39 volumio volumio[9355]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 01 21:50:39 volumio volumio[9355]: Updating browse sources language Dec 01 21:50:39 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:50:40 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="IODATA-e0e2b4-2G_EXT" Dec 01 21:50:40 volumio wpa_supplicant[921]: wlan0: Trying to associate with SSID 'IODATA-e0e2b4-2G_EXT' Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::initPlayerControls Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:50:40 volumio volumio[9355]: Express server listening on port 3000 Dec 01 21:50:40 volumio volumio[9355]: [Metrics] WebUI: 13s 140.58ms Dec 01 21:50:40 volumio volumio[9355]: info: CoreStateMachine::resetVolumioState Dec 01 21:50:40 volumio volumio[9355]: info: CoreStateMachine::getcurrentVolume Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 21:50:40 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:40 volumio squeezeboxserver[9288]: [24-12-01 21:50:40.8635] Slim::Music::Import::runImporter (580) Starting Slim::Media::MediaFolderScan scan Dec 01 21:50:40 volumio squeezeboxserver[9288]: [24-12-01 21:50:40.8655] Slim::Music::Import::endImporter (713) Completed Slim::Media::MediaFolderScan Scan in 0.002 seconds. Dec 01 21:50:40 volumio squeezeboxserver[9288]: [24-12-01 21:50:40.8723] Slim::Music::Import::runImporter (580) Starting Slim::Plugin::FullTextSearch::Plugin scan Dec 01 21:50:40 volumio squeezeboxserver[9288]: [24-12-01 21:50:40.8781] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Dec 01 21:50:40 volumio squeezeboxserver[9288]: [24-12-01 21:50:40.8803] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Dec 01 21:50:40 volumio squeezeboxserver[9288]: [24-12-01 21:50:40.8887] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Dec 01 21:50:40 volumio volumio[9355]: info: Cannot read play queue from file Dec 01 21:50:40 volumio dhcpcd[933]: wlan0: carrier acquired Dec 01 21:50:40 volumio wpa_supplicant[921]: wlan0: Associated with 28:87:ba:ea:c3:8a Dec 01 21:50:40 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-CONNECTED - Connection to 28:87:ba:ea:c3:8a completed [id=0 id_str=] Dec 01 21:50:40 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 01 21:50:41 volumio wpa_supplicant[921]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Dec 01 21:50:41 volumio dhcpcd[933]: wlan0: IAID eb:98:8f:08 Dec 01 21:50:41 volumio dhcpcd[933]: wlan0: carrier lost Dec 01 21:50:41 volumio volumio[9355]: Starting albumart workers Dec 01 21:50:41 volumio dhcpcd[933]: wlan0: carrier acquired Dec 01 21:50:41 volumio dhcpcd[933]: wlan0: IAID eb:98:8f:08 Dec 01 21:50:41 volumio volumio-remote-updater[639]: [2024-12-01 21:50:41] [connect] Successful connection Dec 01 21:50:41 volumio dhcpcd[933]: wlan0: rebinding lease of 192.168.0.40 Dec 01 21:50:41 volumio volumio[9355]: Starting albumart workers Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.4699] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.4772] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.4823] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Dec 01 21:50:41 volumio dhcpcd[933]: wlan0: probing address 192.168.0.40/24 Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.5042] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Dec 01 21:50:41 volumio volumio[9355]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 01 21:50:41 volumio volumio[9355]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.5436] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.5465] Slim::Music::Import::endImporter (713) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.674 seconds. Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.5497] Slim::Music::Import::runImporter (580) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.5522] Slim::Music::Import::endImporter (713) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.002 seconds. Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.5587] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.5609] Slim::Music::Import::endImporter (713) Completed precacheArtwork Scan in 0.004 seconds. Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.5645] Slim::Music::Import::runScanPostProcessing (482) Starting Database optimization. Dec 01 21:50:41 volumio dhcpcd[933]: wlan0: soliciting an IPv6 router Dec 01 21:50:41 volumio volumio[9355]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 01 21:50:41 volumio squeezeboxserver[9288]: [24-12-01 21:50:41.9081] Slim::Music::Import::endImporter (713) Completed dbOptimize Scan in 0.341 seconds. Dec 01 21:50:41 volumio volumio[9355]: Starting albumart workers Dec 01 21:50:41 volumio volumio-remote-updater[639]: [2024-12-01 21:50:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1733057441 101 Dec 01 21:50:41 volumio volumio[9355]: 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: 4 Dec 01 21:50:42 volumio volumio[9355]: info: CoreStateMachine::setRepeat null single undefined Dec 01 21:50:42 volumio volumio[9355]: info: CoreStateMachine::pushState Dec 01 21:50:42 volumio volumio[9355]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:42 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:50:42 volumio volumio[9355]: info: CoreCommandRouter::volumioPushState Dec 01 21:50:42 volumio volumio[9355]: info: CoreStateMachine::setRandom null Dec 01 21:50:42 volumio volumio[9355]: info: CoreStateMachine::pushState Dec 01 21:50:42 volumio volumio[9355]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:42 volumio volumio[9355]: info: CoreCommandRouter::volumioPushState Dec 01 21:50:42 volumio volumio[9355]: info: Setting Device type: Raspberry PI Dec 01 21:50:42 volumio volumio[9355]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 01 21:50:42 volumio volumio[9355]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 Dec 01 21:50:42 volumio volumio[9355]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 01 21:50:42 volumio volumio[9355]: info: VolumeController:: Volume=86 Mute =false Dec 01 21:50:42 volumio volumio[9355]: info: CoreStateMachine::pushState Dec 01 21:50:42 volumio volumio[9355]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:42 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 21:50:42 volumio volumio[9355]: info: CoreCommandRouter::volumioPushState Dec 01 21:50:42 volumio volumio[9355]: info: CoreStateMachine::updateTrackBlock Dec 01 21:50:42 volumio volumio[9355]: info: CorePlayQueue::getTrackBlock Dec 01 21:50:42 volumio volumio[9355]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 21:50:42 volumio volumio[9355]: info: Completed loading Core Plugins Dec 01 21:50:42 volumio volumio[9355]: info: Preparing to generate the ALSA configuration file Dec 01 21:50:43 volumio volumio[9355]: info: VolumeController:: Volume=86 Mute =false Dec 01 21:50:43 volumio volumio[9355]: info: CoreStateMachine::pushState Dec 01 21:50:43 volumio volumio[9355]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::volumioPushState Dec 01 21:50:43 volumio volumio[9355]: info: Asound.conf file unchanged, so no further update is needed Dec 01 21:50:43 volumio volumio[9355]: info: Output device has changed, restarting MPD Dec 01 21:50:43 volumio volumio[9355]: info: Output device has changed, restarting Shairport Sync Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:43 volumio sudo[9508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 21:50:43 volumio sudo[9508]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:43 volumio sudo[9510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 21:50:43 volumio sudo[9510]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:43 volumio sudo[9508]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:43 volumio volumio[9355]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 21:50:43 volumio volumio[9355]: info: ___________ START PLUGINS ___________ Dec 01 21:50:43 volumio volumio[9355]: info: ControllerMpd::onStart: Initializing MPD Dec 01 21:50:43 volumio volumio[9355]: info: Creating MPD Configuration file Dec 01 21:50:43 volumio systemd[1]: Stopping Music Player Daemon... Dec 01 21:50:43 volumio systemd[1]: mpd.service: Succeeded. Dec 01 21:50:43 volumio systemd[1]: Stopped Music Player Daemon. Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:50:43 volumio volumio[9355]: info: [1733057443485] CoreMusicLibrary::Adding element メディアサーバー Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:50:43 volumio systemd[1]: Starting Music Player Daemon... Dec 01 21:50:43 volumio sudo[9521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 21:50:43 volumio sudo[9521]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:43 volumio sudo[9521]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:43 volumio sudo[9525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 21:50:43 volumio sudo[9525]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:43 volumio volumio[9355]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:43 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 01 21:50:43 volumio systemd[1]: mpd.service: Succeeded. Dec 01 21:50:43 volumio systemd[1]: Stopped Music Player Daemon. Dec 01 21:50:43 volumio systemd[1]: Starting Music Player Daemon... Dec 01 21:50:43 volumio volumio[9355]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:50:43 volumio volumio[9355]: info: [1733057443856] CoreMusicLibrary::Adding element Last_100 Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 21:50:43 volumio volumio[9355]: info: [1733057443865] CoreMusicLibrary::Adding element Webradio Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 21:50:43 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:50:43 volumio volumio[9355]: info: Initializing BBC Radios Dec 01 21:50:44 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:50:44 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:44 volumio volumio[9355]: info: [squeezelite_mc] Starting proxy server... Dec 01 21:50:44 volumio sudo[9545]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 21:50:44 volumio sudo[9545]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:44 volumio sudo[9545]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:44 volumio volumio[9355]: info: Loading i18n strings for locale ja Dec 01 21:50:44 volumio volumio[9355]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 01 21:50:44 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 21:50:44 volumio sudo[9561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Dec 01 21:50:44 volumio sudo[9561]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:44 volumio volumio[9355]: info: Volumio Calling Home Dec 01 21:50:44 volumio systemd[1]: Stopping Logitech Media Server Daemon... Dec 01 21:50:44 volumio squeezeboxserver[9288]: [24-12-01 21:50:42.4662] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Dec 01 21:50:44 volumio volumio[9355]: info: [squeezelite_mc] Proxy server started on port 40195 Dec 01 21:50:44 volumio sudo[9567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 01 21:50:44 volumio systemd[1]: logitechmediaserver.service: Succeeded. Dec 01 21:50:44 volumio systemd[1]: Stopped Logitech Media Server Daemon. Dec 01 21:50:44 volumio sudo[9567]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:44 volumio sudo[9571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 01 21:50:44 volumio sudo[9569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 01 21:50:44 volumio systemd[1]: Starting Logitech Media Server Daemon... Dec 01 21:50:44 volumio sudo[9569]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:44 volumio sudo[9571]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:44 volumio systemd[1]: Started Logitech Media Server Daemon. Dec 01 21:50:44 volumio sudo[9561]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:44 volumio volumio[9355]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 21:50:44 volumio systemd[1]: Reloading. Dec 01 21:50:45 volumio volumio[9355]: Unhandled rejection Error: No sockets available, cannot start. Dec 01 21:50:45 volumio volumio[9355]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 01 21:50:45 volumio volumio[9355]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 01 21:50:45 volumio volumio[9355]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 01 21:50:45 volumio volumio[9355]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 01 21:50:45 volumio volumio[9355]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 01 21:50:45 volumio volumio[9355]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 01 21:50:45 volumio volumio[9355]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 01 21:50:45 volumio volumio[9355]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 01 21:50:45 volumio volumio[9355]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 01 21:50:45 volumio volumio[9355]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 01 21:50:45 volumio volumio[9355]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 01 21:50:45 volumio volumio[9355]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 01 21:50:45 volumio volumio[9355]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 01 21:50:45 volumio volumio[9355]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 01 21:50:45 volumio volumio[9355]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 01 21:50:45 volumio volumio[9355]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 01 21:50:45 volumio volumio[9355]: info: touch_display: No backlight interface detected. Dec 01 21:50:45 volumio sudo[9595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 21:50:45 volumio sudo[9595]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:45 volumio sudo[9594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 21:50:45 volumio sudo[9594]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 21:50:45 volumio sudo[9595]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:45 volumio sudo[9594]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:45 volumio volumio[9355]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 01 21:50:45 volumio volumio[9355]: info: logitechmediaserver started Dec 01 21:50:45 volumio volumio[9355]: info: MPD Permissions set Dec 01 21:50:45 volumio volumio[9355]: info: MPD Permissions set Dec 01 21:50:45 volumio volumio[9355]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:45 volumio volumio[9355]: info: VolumeController:: Volume=86 Mute =false Dec 01 21:50:45 volumio volumio[9355]: info: CoreStateMachine::pushState Dec 01 21:50:45 volumio volumio[9355]: info: CorePlayQueue::getTrack 0 Dec 01 21:50:45 volumio volumio[9355]: info: CoreCommandRouter::volumioPushState Dec 01 21:50:46 volumio volumio[9355]: info: [squeezelite_mc] Server discovery started Dec 01 21:50:46 volumio volumio[9355]: info: [squeezelite_mc] Player finder started Dec 01 21:50:46 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:46 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:46 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 21:50:46 volumio volumio[9355]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 21:50:46 volumio volumio[9355]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 01 21:50:46 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 01 21:50:46 volumio volumio[9355]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 21:50:46 volumio volumio[9355]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 01 21:50:46 volumio volumio[9355]: at doSend (dgram.js:692:16) Dec 01 21:50:46 volumio volumio[9355]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 01 21:50:46 volumio volumio[9355]: at afterDns (dgram.js:638:5) Dec 01 21:50:46 volumio volumio[9355]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 01 21:50:46 volumio volumio[9355]: errno: -101, Dec 01 21:50:46 volumio volumio[9355]: code: 'ENETUNREACH', Dec 01 21:50:46 volumio volumio[9355]: syscall: 'send', Dec 01 21:50:46 volumio volumio[9355]: address: '255.255.255.255', Dec 01 21:50:46 volumio volumio[9355]: port: 3483 Dec 01 21:50:46 volumio volumio[9355]: } Dec 01 21:50:46 volumio volumio[9355]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 21:50:46 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Dec 01 21:50:47 volumio dhcpcd[933]: wlan0: leased 192.168.0.40 for 86400 seconds Dec 01 21:50:47 volumio avahi-daemon[638]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.40. Dec 01 21:50:47 volumio dhcpcd[933]: wlan0: adding route to 192.168.0.0/24 Dec 01 21:50:47 volumio dhcpcd[933]: wlan0: adding default route via 192.168.0.1 Dec 01 21:50:47 volumio avahi-daemon[638]: New relevant interface wlan0.IPv4 for mDNS. Dec 01 21:50:47 volumio avahi-daemon[638]: Registering new address record for 192.168.0.40 on wlan0.IPv4. Dec 01 21:50:47 volumio sudo[9571]: pam_unix(sudo:session): session closed for user root Dec 01 21:50:47 volumio systemd[1]: Reloading. Dec 01 21:50:48 volumio sudo[9641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 21:49 Dec 01 21:50:48 volumio sudo[9641]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"