-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Sat 2024-08-31 15:41:18 JST. -- Aug 31 15:40:45 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:95:7f:89:1b:70 reason=0 locally_generated=1 Aug 31 15:40:45 volumio dhcpcd[1203]: wlan0: carrier lost Aug 31 15:40:45 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 31 15:40:45 volumio avahi-daemon[489]: Withdrawing address record for 192.168.3.7 on wlan0. Aug 31 15:40:45 volumio avahi-daemon[489]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. Aug 31 15:40:45 volumio avahi-daemon[489]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 31 15:40:45 volumio dhcpcd[1203]: wlan0: deleting route to 192.168.3.0/24 Aug 31 15:40:45 volumio volumio[31929]: info: mDNS: A device disapperared from network Aug 31 15:40:45 volumio volumio[31929]: info: mDNS: Device volumio disapperared from network Aug 31 15:40:47 volumio ntpd[922]: Deleting interface #10 wlan0, 192.168.3.7#123, interface stats: received=102, sent=106, dropped=0, active_time=337 secs Aug 31 15:40:47 volumio ntpd[922]: 162.159.200.1 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 202.182.111.234 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 172.105.204.105 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 160.16.113.133 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 202.181.103.212 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 133.243.238.163 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 167.179.119.205 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 133.130.121.141 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 45.76.221.157 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 129.250.35.250 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 172.105.240.243 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 133.243.238.243 local addr 192.168.3.7 -> Aug 31 15:40:47 volumio ntpd[922]: 45.76.211.39 local addr 192.168.3.7 -> Aug 31 15:40:52 volumio wpa_supplicant[1181]: wlan0: Trying to associate with SSID 'A0957F891B71-2G' Aug 31 15:40:53 volumio dhcpcd[1203]: wlan0: carrier acquired Aug 31 15:40:53 volumio wpa_supplicant[1181]: wlan0: Associated with a0:95:7f:89:1b:70 Aug 31 15:40:53 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:95:7f:89:1b:70 completed [id=0 id_str=] Aug 31 15:40:53 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 31 15:40:53 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Aug 31 15:40:53 volumio dhcpcd[1203]: wlan0: IAID eb:ba:e8:23 Aug 31 15:40:53 volumio dhcpcd[1203]: wlan0: carrier lost Aug 31 15:40:53 volumio dhcpcd[1203]: wlan0: carrier acquired Aug 31 15:40:53 volumio dhcpcd[1203]: wlan0: IAID eb:ba:e8:23 Aug 31 15:40:54 volumio dhcpcd[1203]: wlan0: rebinding lease of 192.168.3.7 Aug 31 15:40:54 volumio dhcpcd[1203]: wlan0: soliciting an IPv6 router Aug 31 15:40:59 volumio dhcpcd[1203]: wlan0: probing for an IPv4LL address Aug 31 15:40:59 volumio dhcpcd[1203]: wlan0: DHCP lease expired Aug 31 15:40:59 volumio dhcpcd[1203]: wlan0: soliciting a DHCP lease Aug 31 15:40:59 volumio dhcpcd[1203]: wlan0: offered 192.168.3.7 from 192.168.3.1 Aug 31 15:40:59 volumio dhcpcd[1203]: wlan0: probing address 192.168.3.7/24 Aug 31 15:41:02 volumio volumio[31929]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:41:02 volumio volumio[31929]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 31 15:41:02 volumio volumio[31929]: at doSend (dgram.js:692:16) Aug 31 15:41:02 volumio volumio[31929]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 31 15:41:02 volumio volumio[31929]: at afterDns (dgram.js:638:5) Aug 31 15:41:02 volumio volumio[31929]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 31 15:41:02 volumio volumio[31929]: errno: -101, Aug 31 15:41:02 volumio volumio[31929]: code: 'ENETUNREACH', Aug 31 15:41:02 volumio volumio[31929]: syscall: 'send', Aug 31 15:41:02 volumio volumio[31929]: address: '255.255.255.255', Aug 31 15:41:02 volumio volumio[31929]: port: 3483 Aug 31 15:41:02 volumio volumio[31929]: } Aug 31 15:41:02 volumio volumio[31929]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:41:03 volumio sudo[32433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 15:40 Aug 31 15:41:03 volumio sudo[32433]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:41:03 volumio sudo[32433]: pam_unix(sudo:session): session closed for user root Aug 31 15:41:03 volumio volumio-remote-updater[501]: [2024-08-31 15:41:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 31 15:41:03 volumio volumio-remote-updater[501]: [2024-08-31 15:41:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 31 15:41:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 31 15:41:03 volumio systemd[1]: media-3013\x2dA0F5.mount: Succeeded. Aug 31 15:41:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 31 15:41:03 volumio systemd[1]: Started dynamicswap service. Aug 31 15:41:03 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 31 15:41:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 31 15:41:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Aug 31 15:41:03 volumio systemd[1]: Started dynamicswap service. Aug 31 15:41:03 volumio systemd[1]: Stopped Volumio Backend Module. Aug 31 15:41:03 volumio systemd[1]: Started Volumio Backend Module. Aug 31 15:41:03 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 31 15:41:03 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 cmd_age=0s Aug 31 15:41:03 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Aug 31 15:41:03 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Aug 31 15:41:03 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 0b 08 00 00 f0 00 Aug 31 15:41:03 volumio kernel: blk_update_request: critical medium error, dev sda, sector 2824 op 0x0:(READ) flags 0x84700 phys_seg 30 prio class 0 Aug 31 15:41:03 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:95:7f:89:1b:70 reason=0 locally_generated=1 Aug 31 15:41:03 volumio dhcpcd[1203]: wlan0: carrier lost Aug 31 15:41:03 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 31 15:41:03 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 cmd_age=0s Aug 31 15:41:03 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Aug 31 15:41:03 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Aug 31 15:41:03 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 0b b8 00 00 08 00 Aug 31 15:41:03 volumio kernel: blk_update_request: critical medium error, dev sda, sector 3000 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Aug 31 15:41:03 volumio kernel: Buffer I/O error on dev sda1, logical block 119, async page read Aug 31 15:41:05 volumio wpa_supplicant[1181]: wlan0: Trying to associate with SSID 'A0957F891B71-2G' Aug 31 15:41:05 volumio volumio[32450]: info: ------------------------------------------- Aug 31 15:41:05 volumio volumio[32450]: info: ----- Volumio3 ---- Aug 31 15:41:05 volumio volumio[32450]: info: ------------------------------------------- Aug 31 15:41:05 volumio volumio[32450]: info: ----- System startup ---- Aug 31 15:41:05 volumio volumio[32450]: info: ------------------------------------------- Aug 31 15:41:06 volumio volumio[32450]: info: MYVOLUMIO Environment detected Aug 31 15:41:06 volumio volumio[32450]: info: Plugin folders cleanup Aug 31 15:41:06 volumio volumio[32450]: info: Scanning into folder /volumio/app/plugins/ Aug 31 15:41:06 volumio volumio[32450]: info: Scanning category audio_interface Aug 31 15:41:06 volumio volumio[32450]: info: Scanning category miscellanea Aug 31 15:41:06 volumio volumio[32450]: info: Scanning category music_service Aug 31 15:41:06 volumio volumio[32450]: info: Scanning category plugins.json Aug 31 15:41:06 volumio volumio[32450]: info: Scanning category system_controller Aug 31 15:41:06 volumio volumio[32450]: info: Scanning category user_interface Aug 31 15:41:06 volumio volumio[32450]: info: Scanning into folder /data/plugins/ Aug 31 15:41:06 volumio volumio[32450]: info: Scanning category music_service Aug 31 15:41:06 volumio volumio[32450]: info: Plugin folders cleanup completed Aug 31 15:41:06 volumio volumio[32450]: info: ------------------------------------------- Aug 31 15:41:06 volumio volumio[32450]: info: ----- Core plugins startup ---- Aug 31 15:41:06 volumio volumio[32450]: info: ------------------------------------------- Aug 31 15:41:06 volumio volumio[32450]: info: Loading plugins from folder /volumio/app/plugins/ Aug 31 15:41:06 volumio volumio[32450]: info: Adding plugin upnp to MyMusic Plugins Aug 31 15:41:06 volumio volumio[32450]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 31 15:41:06 volumio volumio[32450]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 31 15:41:06 volumio volumio[32450]: info: Loading plugins from folder /data/plugins/ Aug 31 15:41:06 volumio volumio[32450]: info: Loading plugin "system"... Aug 31 15:41:06 volumio volumio[32450]: info: Loading plugin "appearance"... Aug 31 15:41:07 volumio volumio[32450]: info: Loading plugin "network"... Aug 31 15:41:07 volumio volumio[32450]: info: Refreshing Cached IP Addresses Aug 31 15:41:07 volumio volumio[32450]: info: Loading plugin "services"... Aug 31 15:41:07 volumio volumio[32450]: info: Loading plugin "alsa_controller"... Aug 31 15:41:07 volumio volumio[32450]: info: Loading plugin "wizard"... Aug 31 15:41:07 volumio volumio[32450]: info: Loading plugin "volumio_command_line_client"... Aug 31 15:41:07 volumio volumio[32450]: info: Loading plugin "upnp"... Aug 31 15:41:07 volumio volumio[32450]: info: [1725086467459] Starting Upmpd Daemon Aug 31 15:41:07 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 15:41:07 volumio volumio[32450]: info: Loading plugin "my_music"... Aug 31 15:41:07 volumio volumio[32450]: info: Loading plugin "mpd"... Aug 31 15:41:07 volumio volumio[32450]: info: Creating MPD Configuration file Aug 31 15:41:07 volumio dhcpcd[1203]: wlan0: carrier acquired Aug 31 15:41:07 volumio volumio[32450]: info: Loading plugin "upnp_browser"... Aug 31 15:41:07 volumio wpa_supplicant[1181]: wlan0: Associated with a0:95:7f:89:1b:70 Aug 31 15:41:07 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:95:7f:89:1b:70 completed [id=0 id_str=] Aug 31 15:41:07 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 31 15:41:07 volumio wpa_supplicant[1181]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Aug 31 15:41:07 volumio sudo[32496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 15:41:07 volumio sudo[32496]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:41:07 volumio sudo[32494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 15:41:07 volumio sudo[32494]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:41:07 volumio dhcpcd[1203]: wlan0: IAID eb:ba:e8:23 Aug 31 15:41:07 volumio dhcpcd[1203]: wlan0: carrier lost Aug 31 15:41:07 volumio sudo[32504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 15:41:07 volumio sudo[32506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 15:41:07 volumio sudo[32506]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:41:07 volumio sudo[32496]: pam_unix(sudo:session): session closed for user root Aug 31 15:41:07 volumio sudo[32498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 31 15:41:07 volumio sudo[32494]: pam_unix(sudo:session): session closed for user root Aug 31 15:41:07 volumio sudo[32504]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:41:07 volumio sudo[32498]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:41:07 volumio sudo[32504]: pam_unix(sudo:session): session closed for user root Aug 31 15:41:07 volumio systemd[1]: Stopping Music Player Daemon... Aug 31 15:41:07 volumio dhcpcd[1203]: wlan0: carrier acquired Aug 31 15:41:08 volumio dhcpcd[1203]: wlan0: IAID eb:ba:e8:23 Aug 31 15:41:08 volumio systemd[1]: mpd.service: Succeeded. Aug 31 15:41:08 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 15:41:08 volumio systemd[1]: Starting Music Player Daemon... Aug 31 15:41:08 volumio dhcpcd[1203]: wlan0: soliciting an IPv6 router Aug 31 15:41:08 volumio volumio-remote-updater[501]: [2024-08-31 15:41:08] [connect] Successful connection Aug 31 15:41:08 volumio volumio[32450]: info: Loading plugin "networkfs"... Aug 31 15:41:08 volumio volumio[32450]: info: Starting Udev Watcher for removable devices Aug 31 15:41:08 volumio dhcpcd[1203]: wlan0: soliciting a DHCP lease Aug 31 15:41:08 volumio volumio[32450]: info: Ignoring mount for partition: boot Aug 31 15:41:08 volumio volumio[32450]: info: Ignoring mount for partition: volumio Aug 31 15:41:08 volumio volumio[32450]: info: Ignoring mount for partition: volumio_data Aug 31 15:41:08 volumio volumio[32450]: info: Mounting Device 3013-A0F5 Aug 31 15:41:09 volumio mpd[32526]: Aug 31 15:41 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 15:41:09 volumio mpd[32526]: Aug 31 15:41 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 31 15:41:09 volumio mpd[32526]: Aug 31 15:41 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 31 15:41:10 volumio systemd[1]: Started Music Player Daemon. Aug 31 15:41:10 volumio sudo[32506]: pam_unix(sudo:session): session closed for user root Aug 31 15:41:10 volumio sudo[32498]: pam_unix(sudo:session): session closed for user root Aug 31 15:41:10 volumio sudo[32532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/3013-A0F5 -o noatime,iocharset=utf8 Aug 31 15:41:10 volumio sudo[32532]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:41:10 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 cmd_age=0s Aug 31 15:41:10 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Aug 31 15:41:10 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Aug 31 15:41:10 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 0b 08 00 00 f0 00 Aug 31 15:41:10 volumio kernel: blk_update_request: critical medium error, dev sda, sector 2824 op 0x0:(READ) flags 0x84700 phys_seg 30 prio class 0 Aug 31 15:41:10 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 cmd_age=0s Aug 31 15:41:10 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Aug 31 15:41:10 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Aug 31 15:41:10 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 0b b8 00 00 08 00 Aug 31 15:41:10 volumio kernel: blk_update_request: critical medium error, dev sda, sector 3000 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Aug 31 15:41:10 volumio kernel: Buffer I/O error on dev sda1, logical block 119, async page read Aug 31 15:41:10 volumio sudo[32532]: pam_unix(sudo:session): session closed for user root Aug 31 15:41:10 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 15:41:10 volumio volumio[32450]: info: Loading plugin "alarm-clock"... Aug 31 15:41:10 volumio volumio[32450]: info: Loading plugin "airplay_emulation"... Aug 31 15:41:10 volumio volumio[32450]: info: Starting Shairport Sync Aug 31 15:41:10 volumio volumio[32450]: info: Loading plugin "last_100"... Aug 31 15:41:10 volumio volumio[32450]: info: Loading plugin "webradio"... Aug 31 15:41:11 volumio volumio[32450]: info: Loading plugin "i2s_dacs"... Aug 31 15:41:11 volumio volumio[32450]: info: Loading plugin "volumiodiscovery"... Aug 31 15:41:11 volumio volumio[32450]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 15:41:11 volumio volumio[32450]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 15:41:11 volumio volumio[32450]: *** WARNING *** For more information see Aug 31 15:41:11 volumio volumio[32450]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 15:41:11 volumio volumio[32450]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 15:41:11 volumio node[32450]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 15:41:11 volumio volumio[32450]: *** WARNING *** For more information see Aug 31 15:41:11 volumio node[32450]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 15:41:11 volumio node[32450]: *** WARNING *** For more information see Aug 31 15:41:11 volumio node[32450]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 15:41:11 volumio node[32450]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 15:41:11 volumio node[32450]: *** WARNING *** For more information see Aug 31 15:41:11 volumio volumio[32450]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 31 15:41:11 volumio volumio[32450]: info: Discovery: Started advertising with name: Volumio Aug 31 15:41:11 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 15:41:11 volumio volumio[32450]: info: Loading plugin "lmsde"... Aug 31 15:41:11 volumio volumio[32450]: info: Loading plugin "squeezelite_mc"... Aug 31 15:41:12 volumio volumio[32450]: info: Loading plugin "youtube2"... Aug 31 15:41:13 volumio volumio[32450]: info: Loading plugin "outputs"... Aug 31 15:41:13 volumio volumio[32450]: info: Loading plugin "albumart"... Aug 31 15:41:13 volumio volumio[32450]: info: Plugin example_plugin is not enabled Aug 31 15:41:13 volumio volumio[32450]: info: Loading plugin "inputs"... Aug 31 15:41:13 volumio volumio[32450]: info: Loading plugin "updater_comm"... Aug 31 15:41:13 volumio volumio[32450]: info: Plugin mpdemulation is not enabled Aug 31 15:41:13 volumio volumio[32450]: info: Loading plugin "rest_api"... Aug 31 15:41:13 volumio volumio[32450]: info: Loading plugin "websocket"... Aug 31 15:41:13 volumio volumio[32450]: info: Loading i18n strings for locale ja Aug 31 15:41:13 volumio volumio[32450]: Updating browse sources language Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::initPlayerControls Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 15:41:13 volumio volumio[32450]: Express server listening on port 3000 Aug 31 15:41:13 volumio volumio[32450]: [Metrics] WebUI: 8s 917.31ms Aug 31 15:41:13 volumio volumio[32450]: info: CoreStateMachine::resetVolumioState Aug 31 15:41:13 volumio volumio[32450]: info: CoreStateMachine::getcurrentVolume Aug 31 15:41:13 volumio volumio[32450]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 15:41:13 volumio dhcpcd[1203]: wlan0: offered 192.168.3.7 from 192.168.3.1 Aug 31 15:41:13 volumio dhcpcd[1203]: wlan0: probing address 192.168.3.7/24 Aug 31 15:41:13 volumio volumio[32450]: info: MPD Permissions set Aug 31 15:41:13 volumio volumio[32450]: Forking 3 albumart workers Aug 31 15:41:14 volumio volumio[32450]: info: MPD running with PID32526 Aug 31 15:41:14 volumio volumio[32450]: ,establishing connection Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:41:14 volumio volumio[32450]: info: Executing start script for DAC IQaudIO DAC Plus Aug 31 15:41:14 volumio volumio[32450]: info: Reloading queue from file Aug 31 15:41:14 volumio volumio[32450]: error: updateQueue error: null Aug 31 15:41:14 volumio volumio[32450]: info: CoreStateMachine::setRepeat false single undefined Aug 31 15:41:14 volumio volumio[32450]: info: CoreStateMachine::pushState Aug 31 15:41:14 volumio volumio[32450]: info: CorePlayQueue::getTrack 0 Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::volumioPushState Aug 31 15:41:14 volumio volumio[32450]: info: CoreStateMachine::setRandom null Aug 31 15:41:14 volumio volumio[32450]: info: CoreStateMachine::pushState Aug 31 15:41:14 volumio volumio[32450]: info: CorePlayQueue::getTrack 0 Aug 31 15:41:14 volumio volumio[32450]: info: CoreCommandRouter::volumioPushState Aug 31 15:41:14 volumio volumio[32450]: info: Setting Device type: Raspberry PI Aug 31 15:41:14 volumio volumio[32450]: Starting albumart workers Aug 31 15:41:15 volumio volumio[32450]: Starting albumart workers Aug 31 15:41:15 volumio volumio[32450]: info: DAC script executed Aug 31 15:41:15 volumio volumio[32450]: info: Completed loading Core Plugins Aug 31 15:41:15 volumio volumio[32450]: info: Preparing to generate the ALSA configuration file Aug 31 15:41:15 volumio volumio[32450]: info: VolumeController:: Volume=79 Mute =false Aug 31 15:41:15 volumio volumio[32450]: info: CoreStateMachine::pushState Aug 31 15:41:15 volumio volumio[32450]: info: CorePlayQueue::getTrack 0 Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::volumioPushState Aug 31 15:41:15 volumio volumio[32450]: info: CoreStateMachine::updateTrackBlock Aug 31 15:41:15 volumio volumio[32450]: info: CorePlayQueue::getTrackBlock Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 15:41:15 volumio volumio[32450]: Starting albumart workers Aug 31 15:41:15 volumio volumio[32450]: error: updateQueue error: null Aug 31 15:41:15 volumio volumio[32450]: info: Asound.conf file unchanged, so no further update is needed Aug 31 15:41:15 volumio volumio[32450]: info: Output device has changed, restarting MPD Aug 31 15:41:15 volumio volumio[32450]: info: Output device has changed, restarting Shairport Sync Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:41:15 volumio volumio[32450]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 15:41:15 volumio volumio[32450]: info: ___________ START PLUGINS ___________ Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 15:41:15 volumio volumio[32450]: info: [1725086475305] CoreMusicLibrary::Adding element Media Servers Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 15:41:15 volumio volumio[32450]: Cannot find translation for sourceMedia Servers Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:41:15 volumio sudo[32592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 15:41:15 volumio sudo[32592]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:41:15 volumio sudo[32594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 15:41:15 volumio sudo[32594]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:41:15 volumio sudo[32592]: pam_unix(sudo:session): session closed for user root Aug 31 15:41:15 volumio systemd[1]: Stopping Music Player Daemon... Aug 31 15:41:15 volumio systemd[1]: mpd.service: Succeeded. Aug 31 15:41:15 volumio volumio[32450]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 15:41:15 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:41:15 volumio systemd[1]: Starting Music Player Daemon... Aug 31 15:41:15 volumio volumio[32450]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 15:41:15 volumio volumio[32450]: info: [1725086475703] CoreMusicLibrary::Adding element Last_100 Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 15:41:15 volumio volumio[32450]: Cannot find translation for sourceMedia Servers Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 15:41:15 volumio volumio[32450]: info: [1725086475712] CoreMusicLibrary::Adding element Webradio Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 15:41:15 volumio volumio[32450]: Cannot find translation for sourceMedia Servers Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 15:41:15 volumio volumio[32450]: info: [lmsde] Executing script: /opt/lmsde/lms.sh start Aug 31 15:41:15 volumio volumio[32450]: info: [squeezelite_mc] Starting proxy server... Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 15:41:15 volumio volumio[32450]: info: [1725086475874] CoreMusicLibrary::Adding element YouTube2 Aug 31 15:41:15 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 15:41:15 volumio volumio[32450]: Cannot find translation for sourceMedia Servers Aug 31 15:41:15 volumio volumio[32450]: Cannot find translation for sourceYouTube2 Aug 31 15:41:15 volumio volumio[32450]: info: Volumio Calling Home Aug 31 15:41:15 volumio sudo[32618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/opt/lmsde/lms.sh start Aug 31 15:41:15 volumio sudo[32618]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:41:16 volumio volumio[32450]: info: [squeezelite_mc] Proxy server started on port 37525 Aug 31 15:41:16 volumio volumio[32450]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 15:41:16 volumio volumio[32450]: info: Discovery: adding 18bcd961-2cad-4825-9d0c-cefd458c8c21 Aug 31 15:41:16 volumio volumio[32450]: info: mDNS: Found device Volumio Aug 31 15:41:16 volumio volumio[32450]: info: CoreCommandRouter::volumioGetState Aug 31 15:41:16 volumio volumio[32450]: info: CorePlayQueue::getTrack 0 Aug 31 15:41:16 volumio volumio[32450]: Unhandled rejection Error: No sockets available, cannot start. Aug 31 15:41:16 volumio volumio[32450]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 31 15:41:16 volumio volumio[32450]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 31 15:41:16 volumio volumio[32450]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 31 15:41:16 volumio volumio[32450]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 31 15:41:16 volumio volumio[32450]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 31 15:41:16 volumio volumio[32450]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 31 15:41:16 volumio volumio[32450]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 31 15:41:16 volumio volumio[32450]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 31 15:41:16 volumio volumio[32450]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 31 15:41:16 volumio volumio[32450]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 31 15:41:16 volumio volumio[32450]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 31 15:41:16 volumio volumio[32450]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 31 15:41:16 volumio volumio[32450]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 31 15:41:16 volumio volumio[32450]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 31 15:41:16 volumio volumio[32450]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 31 15:41:16 volumio volumio[32450]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 31 15:41:16 volumio volumio[32450]: info: MPD Permissions set Aug 31 15:41:16 volumio volumio[32450]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Aug 31 15:41:16 volumio volumio[32450]: info: VolumeController:: Volume=79 Mute =false Aug 31 15:41:16 volumio volumio[32450]: info: CoreStateMachine::pushState Aug 31 15:41:16 volumio volumio[32450]: info: CorePlayQueue::getTrack 0 Aug 31 15:41:16 volumio volumio[32450]: info: CoreCommandRouter::volumioPushState Aug 31 15:41:17 volumio mpd[32619]: Aug 31 15:41 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 15:41:17 volumio mpd[32619]: Aug 31 15:41 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 31 15:41:17 volumio mpd[32619]: Aug 31 15:41 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 31 15:41:17 volumio systemd[1]: Started Music Player Daemon. Aug 31 15:41:17 volumio sudo[32594]: pam_unix(sudo:session): session closed for user root Aug 31 15:41:17 volumio volumio[32450]: info: [squeezelite_mc] Server discovery started Aug 31 15:41:17 volumio volumio[32450]: info: [squeezelite_mc] Player finder started Aug 31 15:41:17 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:17 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:17 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:41:17 volumio volumio[32450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 15:41:17 volumio volumio[32450]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 31 15:41:17 volumio volumio[32450]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:41:17 volumio volumio[32450]: Error: send ENETUNREACH 255.255.255.255:3483 Aug 31 15:41:17 volumio volumio[32450]: at doSend (dgram.js:692:16) Aug 31 15:41:17 volumio volumio[32450]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Aug 31 15:41:17 volumio volumio[32450]: at afterDns (dgram.js:638:5) Aug 31 15:41:17 volumio volumio[32450]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 31 15:41:17 volumio volumio[32450]: errno: -101, Aug 31 15:41:17 volumio volumio[32450]: code: 'ENETUNREACH', Aug 31 15:41:17 volumio volumio[32450]: syscall: 'send', Aug 31 15:41:17 volumio volumio[32450]: address: '255.255.255.255', Aug 31 15:41:17 volumio volumio[32450]: port: 3483 Aug 31 15:41:17 volumio volumio[32450]: } Aug 31 15:41:17 volumio volumio[32450]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:41:18 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Aug 31 15:41:18 volumio sudo[32618]: pam_unix(sudo:session): session closed for user root Aug 31 15:41:18 volumio dhcpcd[1203]: wlan0: leased 192.168.3.7 for 86400 seconds Aug 31 15:41:18 volumio avahi-daemon[489]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. Aug 31 15:41:18 volumio dhcpcd[1203]: wlan0: adding route to 192.168.3.0/24 Aug 31 15:41:18 volumio dhcpcd[1203]: wlan0: adding default route via 192.168.3.1 Aug 31 15:41:18 volumio avahi-daemon[489]: New relevant interface wlan0.IPv4 for mDNS. Aug 31 15:41:18 volumio avahi-daemon[489]: Registering new address record for 192.168.3.7 on wlan0.IPv4. Aug 31 15:41:18 volumio sudo[32689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 15:40 Aug 31 15:41:18 volumio sudo[32689]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea"