-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2024-11-27 18:08:31 CET. -- Nov 27 18:07:00 volumio volumio[8098]: cset: --> shielding system active with Nov 27 18:07:00 volumio volumio[8098]: cset: "system" cpuset of CPUSPEC(1-3) with 93 tasks running Nov 27 18:07:00 volumio volumio[8098]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 27 18:07:00 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 27 18:07:01 volumio volumio[8051]: info: ------------------------------------------- Nov 27 18:07:01 volumio volumio[8051]: info: ----- Volumio3 ---- Nov 27 18:07:01 volumio volumio[8051]: info: ------------------------------------------- Nov 27 18:07:01 volumio volumio[8051]: info: ----- System startup ---- Nov 27 18:07:01 volumio volumio[8051]: info: ------------------------------------------- Nov 27 18:07:01 volumio volumio-remote-updater[533]: [2024-11-27 18:07:01] [connect] Successful connection Nov 27 18:07:03 volumio ntpd[848]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 27 18:07:03 volumio volumio[8051]: info: MYVOLUMIO Environment detected Nov 27 18:07:03 volumio volumio[8051]: info: Plugin folders cleanup Nov 27 18:07:03 volumio volumio[8051]: info: Scanning into folder /volumio/app/plugins/ Nov 27 18:07:03 volumio volumio[8051]: info: Scanning category audio_interface Nov 27 18:07:03 volumio volumio[8051]: info: Scanning category miscellanea Nov 27 18:07:03 volumio volumio[8051]: info: Scanning category music_service Nov 27 18:07:03 volumio volumio[8051]: info: Scanning category plugins.json Nov 27 18:07:03 volumio volumio[8051]: info: Scanning category system_controller Nov 27 18:07:03 volumio volumio[8051]: info: Scanning category user_interface Nov 27 18:07:03 volumio volumio[8051]: info: Scanning into folder /data/plugins/ Nov 27 18:07:03 volumio volumio[8051]: info: Scanning category music_service Nov 27 18:07:03 volumio volumio[8051]: info: Scanning category system_hardware Nov 27 18:07:03 volumio volumio[8051]: info: Plugin folders cleanup completed Nov 27 18:07:03 volumio volumio[8051]: info: ------------------------------------------- Nov 27 18:07:03 volumio volumio[8051]: info: ----- Core plugins startup ---- Nov 27 18:07:03 volumio volumio[8051]: info: ------------------------------------------- Nov 27 18:07:03 volumio volumio[8051]: info: Loading plugins from folder /volumio/app/plugins/ Nov 27 18:07:03 volumio volumio[8051]: info: Adding plugin upnp to MyMusic Plugins Nov 27 18:07:03 volumio volumio[8051]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 27 18:07:03 volumio volumio[8051]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 27 18:07:03 volumio volumio[8051]: info: Loading plugins from folder /data/plugins/ Nov 27 18:07:03 volumio volumio[8051]: info: Loading plugin "system"... Nov 27 18:07:03 volumio volumio[8051]: info: Loading plugin "appearance"... Nov 27 18:07:03 volumio sh[8074]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:07:03 volumio sh[8074]: eth0: adding route to 192.168.1.0/24 Nov 27 18:07:03 volumio sh[8074]: eth0: adding default route via 192.168.1.254 Nov 27 18:07:03 volumio dhcpcd[8092]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:07:03 volumio avahi-daemon[534]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:07:03 volumio dhcpcd[8092]: eth0: adding route to 192.168.1.0/24 Nov 27 18:07:03 volumio avahi-daemon[534]: New relevant interface eth0.IPv4 for mDNS. Nov 27 18:07:03 volumio dhcpcd[8092]: eth0: adding default route via 192.168.1.254 Nov 27 18:07:03 volumio avahi-daemon[534]: Registering new address record for 192.168.1.28 on eth0.IPv4. Nov 27 18:07:03 volumio sh[8074]: forked to background, child pid 8154 Nov 27 18:07:03 volumio dhcpcd[8092]: forked to background, child pid 8154 Nov 27 18:07:03 volumio sh[8074]: eth0=eth0 Nov 27 18:07:05 volumio ntpd[848]: Listen normally on 5 eth0 192.168.1.28:123 Nov 27 18:07:05 volumio ntpd[848]: new interface(s) found: waking up resolver Nov 27 18:07:05 volumio volumio[8051]: info: Loading plugin "network"... Nov 27 18:07:05 volumio volumio[8051]: info: Refreshing Cached IP Addresses Nov 27 18:07:05 volumio sudo[8181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 18:07:05 volumio sudo[8181]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:05 volumio sudo[8181]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:05 volumio sudo[8183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 18:07:05 volumio sudo[8183]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:05 volumio volumio[8051]: info: Loading plugin "services"... Nov 27 18:07:05 volumio volumio[8051]: info: Loading plugin "alsa_controller"... Nov 27 18:07:05 volumio kernel: Nov 27 18:07:05 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:502: Not connected Nov 27 18:07:05 volumio sudo[8183]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:05 volumio sudo[8192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 27 18:07:05 volumio sudo[8192]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:05 volumio sudo[8192]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:05 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Nov 27 18:07:05 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Nov 27 18:07:05 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Nov 27 18:07:05 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:07:05 volumio volumio[8051]: info: Loading plugin "wizard"... Nov 27 18:07:05 volumio volumio[8051]: info: Loading plugin "networkfs"... Nov 27 18:07:05 volumio volumio[8051]: info: Starting Udev Watcher for removable devices Nov 27 18:07:05 volumio dhcpcd[8154]: eth0: carrier lost Nov 27 18:07:05 volumio avahi-daemon[534]: Interface eth0.IPv4 no longer relevant for mDNS. Nov 27 18:07:05 volumio kernel: usb 1-1: USB disconnect, device number 78 Nov 27 18:07:05 volumio kernel: usb 1-1.1: USB disconnect, device number 79 Nov 27 18:07:05 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Nov 27 18:07:05 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:07:05 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:07:05 volumio avahi-daemon[534]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:07:05 volumio avahi-daemon[534]: Withdrawing address record for 192.168.1.28 on eth0. Nov 27 18:07:05 volumio volumio[8051]: info: Ignoring mount for partition: boot Nov 27 18:07:05 volumio volumio[8051]: info: Ignoring mount for partition: volumio Nov 27 18:07:05 volumio volumio[8051]: info: Ignoring mount for partition: volumio_data Nov 27 18:07:05 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:07:05 volumio volumio[8051]: info: Loading plugin "volumio_command_line_client"... Nov 27 18:07:05 volumio kernel: usb 1-1.3: USB disconnect, device number 80 Nov 27 18:07:05 volumio volumio[8051]: info: Loading plugin "upnp"... Nov 27 18:07:05 volumio volumio[8051]: info: [1732727225846] Starting Upmpd Daemon Nov 27 18:07:05 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:07:05 volumio volumio[8051]: info: Loading plugin "my_music"... Nov 27 18:07:05 volumio systemd[1]: Stopping ifup for eth0... Nov 27 18:07:05 volumio volumio[8051]: info: Loading plugin "mpd"... Nov 27 18:07:05 volumio dhcpcd[8154]: eth0: deleting route to 192.168.1.0/24 Nov 27 18:07:05 volumio dhcpcd[8154]: eth0: deleting default route via 192.168.1.254 Nov 27 18:07:05 volumio ifdown[8225]: sending signal ALRM to pid 8154 Nov 27 18:07:05 volumio dhcpcd[8244]: sending signal ALRM to pid 8154 Nov 27 18:07:05 volumio ifdown[8225]: waiting for pid 8154 to exit Nov 27 18:07:05 volumio dhcpcd[8244]: waiting for pid 8154 to exit Nov 27 18:07:06 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Nov 27 18:07:06 volumio dhcpcd[8154]: eth0: removing interface Nov 27 18:07:06 volumio dhcpcd[8154]: dhcpcd exited Nov 27 18:07:06 volumio kernel: usb 1-1: new high-speed USB device number 81 using dwc_otg Nov 27 18:07:06 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Nov 27 18:07:06 volumio ifdown[8225]: Cannot find device "eth0" Nov 27 18:07:06 volumio systemd[1]: ifup@eth0.service: Succeeded. Nov 27 18:07:06 volumio systemd[1]: Stopped ifup for eth0. Nov 27 18:07:06 volumio ifplugd(eth0)[834]: Link beat lost. Nov 27 18:07:06 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Nov 27 18:07:06 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:07:06 volumio kernel: hub 1-1:1.0: USB hub found Nov 27 18:07:06 volumio kernel: hub 1-1:1.0: 5 ports detected Nov 27 18:07:06 volumio volumio[8051]: info: Loading plugin "upnp_browser"... Nov 27 18:07:06 volumio kernel: usb 1-1.1: new high-speed USB device number 82 using dwc_otg Nov 27 18:07:06 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Nov 27 18:07:06 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:07:06 volumio kernel: smsc95xx v2.0.0 Nov 27 18:07:07 volumio kernel: SMSC LAN8700 usb-001:082:01: attached PHY driver (mii_bus:phy_addr=usb-001:082:01, irq=199) Nov 27 18:07:07 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1c:bd:5f Nov 27 18:07:07 volumio volumio[8051]: info: Loading plugin "alarm-clock"... Nov 27 18:07:07 volumio volumio[8051]: info: Loading plugin "airplay_emulation"... Nov 27 18:07:07 volumio kernel: usb 1-1.3: new high-speed USB device number 83 using dwc_otg Nov 27 18:07:07 volumio volumio[8051]: info: Starting Shairport Sync Nov 27 18:07:07 volumio volumio[8051]: info: Loading plugin "last_100"... Nov 27 18:07:07 volumio volumio[8051]: info: Loading plugin "webradio"... Nov 27 18:07:07 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:07:07 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:07:07 volumio kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Nov 27 18:07:07 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Nov 27 18:07:07 volumio kernel: usb 1-1.3: Product: SMSL USB AUDIO Nov 27 18:07:07 volumio kernel: usb 1-1.3: Manufacturer: SMSL Nov 27 18:07:07 volumio volumio[8051]: info: Loading plugin "i2s_dacs"... Nov 27 18:07:07 volumio volumio[8051]: info: Loading plugin "volumiodiscovery"... Nov 27 18:07:07 volumio volumio[8051]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:07 volumio volumio[8051]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:07 volumio volumio[8051]: *** WARNING *** For more information see Nov 27 18:07:07 volumio node[8051]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:07 volumio volumio[8051]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:07 volumio volumio[8051]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:07 volumio volumio[8051]: *** WARNING *** For more information see Nov 27 18:07:07 volumio node[8051]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:07 volumio node[8051]: *** WARNING *** For more information see Nov 27 18:07:07 volumio node[8051]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:07 volumio node[8051]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:07 volumio node[8051]: *** WARNING *** For more information see Nov 27 18:07:07 volumio volumio[8051]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 27 18:07:07 volumio volumio[8051]: info: Discovery: Started advertising with name: Volumio Nov 27 18:07:08 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:07:08 volumio volumio[8051]: info: Loading plugin "squeezelite_mc"... Nov 27 18:07:08 volumio kernel: usb 1-1.3: 1:3 : unsupported format bits 0x100000000 Nov 27 18:07:08 volumio kernel: hid-generic 0003:152A:85DD.001C: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-3f980000.usb-1.3/input3 Nov 27 18:07:08 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Nov 27 18:07:08 volumio ifplugd(eth0)[834]: Link beat detected. Nov 27 18:07:09 volumio volumio[8051]: info: Loading plugin "outputs"... Nov 27 18:07:09 volumio volumio[8051]: info: Loading plugin "albumart"... Nov 27 18:07:09 volumio volumio[8051]: info: Plugin example_plugin is not enabled Nov 27 18:07:09 volumio volumio[8051]: info: Loading plugin "inputs"... Nov 27 18:07:09 volumio volumio[8051]: info: Loading plugin "updater_comm"... Nov 27 18:07:09 volumio volumio[8051]: info: Plugin mpdemulation is not enabled Nov 27 18:07:09 volumio volumio[8051]: info: Loading plugin "rest_api"... Nov 27 18:07:09 volumio volumio[8051]: info: Loading plugin "websocket"... Nov 27 18:07:09 volumio volumio[8051]: info: Starting Socket.io Server version 2.3.0 Nov 27 18:07:10 volumio volumio[8051]: info: Loading plugin "music_services_shield"... Nov 27 18:07:10 volumio ntpd[848]: Deleting interface #5 eth0, 192.168.1.28#123, interface stats: received=0, sent=0, dropped=6, active_time=5 secs Nov 27 18:07:10 volumio ntpd[848]: 162.159.200.1 local addr 192.168.1.28 -> Nov 27 18:07:10 volumio ntpd[848]: 51.75.17.219 local addr 192.168.1.28 -> Nov 27 18:07:10 volumio ntpd[848]: 37.59.63.125 local addr 192.168.1.28 -> Nov 27 18:07:10 volumio ntpd[848]: 51.195.104.188 local addr 192.168.1.28 -> Nov 27 18:07:10 volumio ntpd[848]: 51.68.44.27 local addr 192.168.1.28 -> Nov 27 18:07:10 volumio ntpd[848]: 82.64.84.116 local addr 192.168.1.28 -> Nov 27 18:07:10 volumio volumio[8051]: Forking 3 albumart workers Nov 27 18:07:10 volumio volumio[8051]: info: Applying required configuration parameters for plugin music_services_shield Nov 27 18:07:10 volumio volumio[8051]: info: Loading i18n strings for locale ru Nov 27 18:07:10 volumio volumio[8051]: Updating browse sources language Nov 27 18:07:10 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:07:10 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:10 volumio volumio[8051]: info: CoreCommandRouter::initPlayerControls Nov 27 18:07:10 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:10 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:10 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:10 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:10 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:10 volumio volumio[8051]: Express server listening on port 3000 Nov 27 18:07:10 volumio volumio[8051]: [Metrics] WebUI: 10s 421.31ms Nov 27 18:07:11 volumio volumio[8051]: info: CoreStateMachine::resetVolumioState Nov 27 18:07:11 volumio volumio[8051]: info: CoreStateMachine::getcurrentVolume Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 18:07:11 volumio volumio[8051]: info: CoreStateMachine::pushState Nov 27 18:07:11 volumio volumio[8051]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:11 volumio volumio[8051]: info: Volumio Network Manager: Network status updated: 1 Nov 27 18:07:11 volumio volumio[8051]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 27 18:07:11 volumio volumio[8051]: wlan0 Interface doesn't support scanning. Nov 27 18:07:11 volumio volumio[8051]: info: Cannot use regular scanning, forcing with ap-force Nov 27 18:07:11 volumio sudo[8347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 27 18:07:11 volumio sudo[8347]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:11 volumio sudo[8347]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:11 volumio volumio[8051]: command failed: No such device (-19) Nov 27 18:07:11 volumio volumio[8051]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 27 18:07:11 volumio volumio[8051]: command failed: No such device (-19) Nov 27 18:07:11 volumio volumio[8051]: info: Nov 27 18:07:11 volumio volumio[8051]: ---------------------------- USB Audio Device Detached Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:11 volumio volumio[8051]: info: Reloading queue from file Nov 27 18:07:11 volumio volumio[8051]: info: CoreStateMachine::setRepeat true single undefined Nov 27 18:07:11 volumio volumio[8051]: info: CoreStateMachine::pushState Nov 27 18:07:11 volumio volumio[8051]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:11 volumio volumio[8051]: info: CoreStateMachine::setRandom false Nov 27 18:07:11 volumio volumio[8051]: info: CoreStateMachine::pushState Nov 27 18:07:11 volumio volumio[8051]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:07:11 volumio volumio[8051]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:12 volumio systemd-udevd[8230]: Using default interface naming scheme 'v240'. Nov 27 18:07:12 volumio systemd-udevd[8230]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 27 18:07:12 volumio volumio[8051]: info: Setting Device type: Raspberry PI Nov 27 18:07:12 volumio volumio[8051]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 27 18:07:12 volumio volumio[8051]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Nov 27 18:07:12 volumio volumio[8051]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 27 18:07:12 volumio volumio[8051]: info: Completed loading Core Plugins Nov 27 18:07:12 volumio volumio[8051]: info: Preparing to generate the ALSA configuration file Nov 27 18:07:12 volumio volumio[8051]: info: Discovery: adding 510e3eae-56c4-4ded-ab5e-7d0d103ffa4f Nov 27 18:07:12 volumio volumio[8051]: info: Discovery: Found device Volumio Nov 27 18:07:12 volumio volumio[8051]: info: CoreCommandRouter::volumioGetState Nov 27 18:07:12 volumio volumio[8051]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:12 volumio volumio[8051]: info: Nov 27 18:07:12 volumio volumio[8051]: ---------------------------- USB Audio Device Attached Nov 27 18:07:12 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 27 18:07:12 volumio systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Nov 27 18:07:12 volumio systemd[1]: Started ifup for eth0. Nov 27 18:07:12 volumio volumio[8051]: info: CoreCommandRouter::Close All Modals sent Nov 27 18:07:12 volumio volumio[8051]: info: Preparing to save Alsa Options, stopping services first Nov 27 18:07:12 volumio volumio[8051]: info: CoreCommandRouter::volumioGetState Nov 27 18:07:12 volumio volumio[8051]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:12 volumio volumio[8051]: info: CoreCommandRouter::volumioPause Nov 27 18:07:12 volumio volumio[8051]: info: CoreStateMachine::pause Nov 27 18:07:12 volumio volumio[8051]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Nov 27 18:07:12 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:07:12 volumio sh[8371]: command failed: No such device (-19) Nov 27 18:07:12 volumio sh[8371]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:07:12 volumio sh[8371]: eth0: IAID eb:1c:bd:5f Nov 27 18:07:12 volumio sh[8371]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:07:12 volumio sh[8371]: ipv6_addaddr1: Permission denied Nov 27 18:07:12 volumio dhcpcd[8390]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:07:12 volumio dhcpcd[8390]: eth0: IAID eb:1c:bd:5f Nov 27 18:07:12 volumio dhcpcd[8390]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:07:12 volumio dhcpcd[8390]: ipv6_addaddr1: Permission denied Nov 27 18:07:12 volumio sh[8371]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:07:12 volumio dhcpcd[8390]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:07:13 volumio sh[8371]: eth0: probing address 192.168.1.28/24 Nov 27 18:07:13 volumio dhcpcd[8390]: eth0: probing address 192.168.1.28/24 Nov 27 18:07:13 volumio sh[8371]: eth0: soliciting an IPv6 router Nov 27 18:07:13 volumio dhcpcd[8390]: eth0: soliciting an IPv6 router Nov 27 18:07:13 volumio volumio[8051]: Starting albumart workers Nov 27 18:07:13 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:07:13 volumio volumio[8051]: info: Device 5 does not have any Mixer Control Available Nov 27 18:07:13 volumio volumio[8051]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 27 18:07:13 volumio volumio[8051]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 27 18:07:13 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 27 18:07:13 volumio volumio[8051]: info: Disabling external Volume Control Nov 27 18:07:13 volumio volumio[8051]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 18:07:13 volumio volumio[8051]: Starting albumart workers Nov 27 18:07:13 volumio volumio[8051]: info: CoreStateMachine::pushState Nov 27 18:07:13 volumio volumio[8051]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:13 volumio volumio[8051]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:13 volumio volumio[8051]: info: Asound.conf file unchanged, so no further update is needed Nov 27 18:07:13 volumio volumio[8051]: info: Output device has changed, restarting MPD Nov 27 18:07:13 volumio volumio[8051]: Starting albumart workers Nov 27 18:07:14 volumio sudo[8401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 18:07:14 volumio sudo[8401]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:14 volumio volumio[8051]: info: Output device has changed, restarting Shairport Sync Nov 27 18:07:14 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:14 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:14 volumio sudo[8401]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:14 volumio sudo[8403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 18:07:14 volumio sudo[8403]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:14 volumio systemd[1]: musicservicesshield.service: Succeeded. Nov 27 18:07:14 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 27 18:07:14 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 27 18:07:14 volumio systemd[1]: Stopping Music Player Daemon... Nov 27 18:07:14 volumio systemd[1]: mpd.service: Succeeded. Nov 27 18:07:14 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 18:07:14 volumio volumio[8051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:07:14 volumio volumio[8051]: info: Preparing to generate the ALSA configuration file Nov 27 18:07:14 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:07:14 volumio volumio[8051]: info: ___________ START PLUGINS ___________ Nov 27 18:07:14 volumio volumio[8051]: info: ControllerMpd::onStart: Initializing MPD Nov 27 18:07:14 volumio volumio[8051]: info: Creating MPD Configuration file Nov 27 18:07:14 volumio volumio-remote-updater[533]: [2024-11-27 18:07:14] [connect] Successful connection Nov 27 18:07:14 volumio sudo[8409]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:07:14 volumio sudo[8409]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:14 volumio sudo[8409]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:14 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:14 volumio volumio[8051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:07:14 volumio volumio[8051]: info: [1732727234765] CoreMusicLibrary::Adding element Медиа-серверы Nov 27 18:07:14 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:07:14 volumio sudo[8415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 18:07:14 volumio sudo[8415]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:14 volumio sudo[8411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 18:07:14 volumio sudo[8411]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:14 volumio sudo[8411]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:14 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 27 18:07:14 volumio systemd[1]: mpd.service: Succeeded. Nov 27 18:07:14 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 18:07:14 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:14 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:15 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:07:15 volumio volumio[8051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:07:15 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:15 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:15 volumio sudo[8418]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:07:15 volumio sudo[8418]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:15 volumio sudo[8418]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:15 volumio volumio[8051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:07:15 volumio volumio[8051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:07:15 volumio volumio[8051]: info: [1732727235573] CoreMusicLibrary::Adding element Last_100 Nov 27 18:07:15 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:07:15 volumio volumio[8051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:07:15 volumio volumio[8051]: info: [1732727235591] CoreMusicLibrary::Adding element Webradio Nov 27 18:07:15 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:07:15 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:07:15 volumio volumio[8051]: info: Initializing BBC Radios Nov 27 18:07:16 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:07:16 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:16 volumio volumio[8051]: info: [squeezelite_mc] Starting proxy server... Nov 27 18:07:16 volumio volumio[8051]: info: Volumio Calling Home Nov 27 18:07:16 volumio volumio[8051]: info: [squeezelite_mc] Proxy server started on port 33065 Nov 27 18:07:16 volumio sudo[8502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 27 18:07:16 volumio sudo[8502]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:17 volumio volumio[8051]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 18:07:17 volumio volumio[8051]: info: CoreStateMachine::pushState Nov 27 18:07:17 volumio volumio[8051]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:17 volumio volumio[8051]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:17 volumio volumio[8051]: info: [squeezelite_mc] Server discovery started Nov 27 18:07:17 volumio volumio[8051]: info: [squeezelite_mc] Player finder started Nov 27 18:07:17 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:17 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:17 volumio volumio[8051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:17 volumio volumio[8051]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 27 18:07:17 volumio volumio[8051]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 18:07:17 volumio volumio[8051]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 27 18:07:17 volumio volumio[8051]: at doSend (dgram.js:692:16) Nov 27 18:07:17 volumio volumio[8051]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 27 18:07:17 volumio volumio[8051]: at afterDns (dgram.js:638:5) Nov 27 18:07:17 volumio volumio[8051]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 27 18:07:17 volumio volumio[8051]: errno: -101, Nov 27 18:07:17 volumio volumio[8051]: code: 'ENETUNREACH', Nov 27 18:07:17 volumio volumio[8051]: syscall: 'send', Nov 27 18:07:17 volumio volumio[8051]: address: '255.255.255.255', Nov 27 18:07:17 volumio volumio[8051]: port: 3483 Nov 27 18:07:17 volumio volumio[8051]: } Nov 27 18:07:17 volumio volumio[8051]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 18:07:18 volumio sh[8371]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:07:18 volumio sh[8371]: eth0: adding route to 192.168.1.0/24 Nov 27 18:07:18 volumio sh[8371]: eth0: adding default route via 192.168.1.254 Nov 27 18:07:18 volumio dhcpcd[8390]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:07:18 volumio dhcpcd[8390]: eth0: adding route to 192.168.1.0/24 Nov 27 18:07:18 volumio dhcpcd[8390]: eth0: adding default route via 192.168.1.254 Nov 27 18:07:18 volumio avahi-daemon[534]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:07:18 volumio avahi-daemon[534]: New relevant interface eth0.IPv4 for mDNS. Nov 27 18:07:18 volumio avahi-daemon[534]: Registering new address record for 192.168.1.28 on eth0.IPv4. Nov 27 18:07:18 volumio kernel: Nov 27 18:07:18 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:502: Not connected Nov 27 18:07:18 volumio sh[8371]: forked to background, child pid 8554 Nov 27 18:07:18 volumio dhcpcd[8390]: forked to background, child pid 8554 Nov 27 18:07:18 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Nov 27 18:07:18 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Nov 27 18:07:18 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Nov 27 18:07:18 volumio dhcpcd[8554]: eth0: carrier lost Nov 27 18:07:18 volumio kernel: usb 1-1: USB disconnect, device number 81 Nov 27 18:07:18 volumio kernel: usb 1-1.1: USB disconnect, device number 82 Nov 27 18:07:18 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Nov 27 18:07:18 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:07:18 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:07:18 volumio avahi-daemon[534]: Interface eth0.IPv4 no longer relevant for mDNS. Nov 27 18:07:18 volumio avahi-daemon[534]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:07:18 volumio avahi-daemon[534]: Withdrawing address record for 192.168.1.28 on eth0. Nov 27 18:07:18 volumio kernel: usb 1-1.3: USB disconnect, device number 83 Nov 27 18:07:18 volumio sudo[8502]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:18 volumio systemd[1]: Stopping ifup for eth0... Nov 27 18:07:18 volumio ifdown[8588]: ifdown: waiting for lock on /run/network/ifstate.eth0 Nov 27 18:07:18 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Nov 27 18:07:18 volumio kernel: usb 1-1: new high-speed USB device number 84 using dwc_otg Nov 27 18:07:18 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Nov 27 18:07:19 volumio dhcpcd[8554]: eth0: deleting route to 192.168.1.0/24 Nov 27 18:07:19 volumio dhcpcd[8554]: eth0: deleting default route via 192.168.1.254 Nov 27 18:07:19 volumio dhcpcd[8554]: eth0: removing interface Nov 27 18:07:19 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 27 18:07:19 volumio ifdown[8588]: sending signal ALRM to pid 8554 Nov 27 18:07:19 volumio dhcpcd[8651]: sending signal ALRM to pid 8554 Nov 27 18:07:19 volumio ifdown[8588]: waiting for pid 8554 to exit Nov 27 18:07:19 volumio dhcpcd[8651]: waiting for pid 8554 to exit Nov 27 18:07:19 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Nov 27 18:07:19 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:07:19 volumio kernel: hub 1-1:1.0: USB hub found Nov 27 18:07:19 volumio kernel: hub 1-1:1.0: 5 ports detected Nov 27 18:07:19 volumio sudo[8655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 18:06 Nov 27 18:07:19 volumio sudo[8655]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:19 volumio dhcpcd[8554]: dhcpcd exited Nov 27 18:07:19 volumio ifplugd(eth0)[834]: Link beat lost. Nov 27 18:07:19 volumio ifdown[8588]: Cannot find device "eth0" Nov 27 18:07:19 volumio mpd[8435]: Nov 27 18:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 18:07:19 volumio systemd[1]: Started Music Player Daemon. Nov 27 18:07:19 volumio sudo[8415]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:19 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 27 18:07:19 volumio sudo[8403]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:19 volumio systemd[1]: Stopped ifup for eth0. Nov 27 18:07:19 volumio kernel: usb 1-1.1: new high-speed USB device number 85 using dwc_otg Nov 27 18:07:19 volumio sudo[8655]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:19 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 27 18:07:19 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Nov 27 18:07:19 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:07:19 volumio kernel: smsc95xx v2.0.0 Nov 27 18:07:19 volumio kernel: SMSC LAN8700 usb-001:085:01: attached PHY driver (mii_bus:phy_addr=usb-001:085:01, irq=199) Nov 27 18:07:19 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1c:bd:5f Nov 27 18:07:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 27 18:07:20 volumio systemd-udevd[8572]: Process '/bin/bash -c '/usr/local/bin/volumio usbdetach'' failed with exit code 56. Nov 27 18:07:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 27 18:07:20 volumio systemd-udevd[8562]: Using default interface naming scheme 'v240'. Nov 27 18:07:20 volumio systemd-udevd[8562]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 27 18:07:20 volumio systemd[1]: Started dynamicswap service. Nov 27 18:07:20 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 27 18:07:20 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Nov 27 18:07:20 volumio systemd[1]: Stopped Volumio Backend Module. Nov 27 18:07:20 volumio systemd[1]: Started Volumio Backend Module. Nov 27 18:07:20 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 27 18:07:20 volumio kernel: usb 1-1.3: new high-speed USB device number 86 using dwc_otg Nov 27 18:07:20 volumio kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Nov 27 18:07:20 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Nov 27 18:07:20 volumio kernel: usb 1-1.3: Product: SMSL USB AUDIO Nov 27 18:07:20 volumio kernel: usb 1-1.3: Manufacturer: SMSL Nov 27 18:07:20 volumio systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Nov 27 18:07:20 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:07:20 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:07:20 volumio systemd[1]: Started ifup for eth0. Nov 27 18:07:20 volumio sh[8732]: command failed: No such device (-19) Nov 27 18:07:20 volumio sh[8732]: eth0: waiting for carrier Nov 27 18:07:20 volumio dhcpcd[8752]: eth0: waiting for carrier Nov 27 18:07:20 volumio kernel: usb 1-1.3: 1:3 : unsupported format bits 0x100000000 Nov 27 18:07:20 volumio kernel: hid-generic 0003:152A:85DD.001D: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-3f980000.usb-1.3/input3 Nov 27 18:07:21 volumio volumio[8690]: cset: --> shielding system active with Nov 27 18:07:21 volumio volumio[8690]: cset: "system" cpuset of CPUSPEC(1-3) with 95 tasks running Nov 27 18:07:21 volumio volumio[8690]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 27 18:07:21 volumio ntpd[848]: Soliciting pool server 2a01:e0a:431:b527::a123 Nov 27 18:07:21 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 27 18:07:21 volumio systemd-udevd[8562]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Nov 27 18:07:21 volumio sh[8732]: eth0: carrier acquired Nov 27 18:07:21 volumio dhcpcd[8752]: eth0: carrier acquired Nov 27 18:07:21 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Nov 27 18:07:21 volumio sh[8732]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:07:21 volumio sh[8732]: eth0: IAID eb:1c:bd:5f Nov 27 18:07:21 volumio sh[8732]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:07:21 volumio sh[8732]: ipv6_addaddr1: Permission denied Nov 27 18:07:21 volumio dhcpcd[8752]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:07:21 volumio dhcpcd[8752]: eth0: IAID eb:1c:bd:5f Nov 27 18:07:21 volumio dhcpcd[8752]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:07:21 volumio dhcpcd[8752]: ipv6_addaddr1: Permission denied Nov 27 18:07:21 volumio ifplugd(eth0)[834]: Link beat detected. Nov 27 18:07:22 volumio sh[8732]: eth0: soliciting an IPv6 router Nov 27 18:07:22 volumio dhcpcd[8752]: eth0: soliciting an IPv6 router Nov 27 18:07:22 volumio sh[8732]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:07:22 volumio dhcpcd[8752]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:07:22 volumio sh[8732]: eth0: probing address 192.168.1.28/24 Nov 27 18:07:22 volumio dhcpcd[8752]: eth0: probing address 192.168.1.28/24 Nov 27 18:07:24 volumio volumio[8723]: info: ------------------------------------------- Nov 27 18:07:24 volumio volumio[8723]: info: ----- Volumio3 ---- Nov 27 18:07:24 volumio volumio[8723]: info: ------------------------------------------- Nov 27 18:07:24 volumio volumio[8723]: info: ----- System startup ---- Nov 27 18:07:24 volumio volumio[8723]: info: ------------------------------------------- Nov 27 18:07:25 volumio volumio[8723]: info: MYVOLUMIO Environment detected Nov 27 18:07:25 volumio volumio[8723]: info: Plugin folders cleanup Nov 27 18:07:25 volumio volumio[8723]: info: Scanning into folder /volumio/app/plugins/ Nov 27 18:07:25 volumio volumio[8723]: info: Scanning category audio_interface Nov 27 18:07:25 volumio volumio[8723]: info: Scanning category miscellanea Nov 27 18:07:25 volumio volumio[8723]: info: Scanning category music_service Nov 27 18:07:25 volumio volumio[8723]: info: Scanning category plugins.json Nov 27 18:07:25 volumio volumio[8723]: info: Scanning category system_controller Nov 27 18:07:25 volumio volumio[8723]: info: Scanning category user_interface Nov 27 18:07:25 volumio volumio[8723]: info: Scanning into folder /data/plugins/ Nov 27 18:07:25 volumio volumio[8723]: info: Scanning category music_service Nov 27 18:07:25 volumio volumio[8723]: info: Scanning category system_hardware Nov 27 18:07:25 volumio volumio[8723]: info: Plugin folders cleanup completed Nov 27 18:07:25 volumio volumio[8723]: info: ------------------------------------------- Nov 27 18:07:25 volumio volumio[8723]: info: ----- Core plugins startup ---- Nov 27 18:07:25 volumio volumio[8723]: info: ------------------------------------------- Nov 27 18:07:25 volumio volumio[8723]: info: Loading plugins from folder /volumio/app/plugins/ Nov 27 18:07:25 volumio volumio[8723]: info: Adding plugin upnp to MyMusic Plugins Nov 27 18:07:25 volumio volumio[8723]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 27 18:07:25 volumio volumio[8723]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 27 18:07:25 volumio volumio[8723]: info: Loading plugins from folder /data/plugins/ Nov 27 18:07:25 volumio volumio[8723]: info: Loading plugin "system"... Nov 27 18:07:26 volumio volumio[8723]: info: Loading plugin "appearance"... Nov 27 18:07:27 volumio sh[8732]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:07:27 volumio dhcpcd[8752]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:07:27 volumio dhcpcd[8752]: eth0: adding route to 192.168.1.0/24 Nov 27 18:07:27 volumio sh[8732]: eth0: adding route to 192.168.1.0/24 Nov 27 18:07:27 volumio sh[8732]: eth0: adding default route via 192.168.1.254 Nov 27 18:07:27 volumio dhcpcd[8752]: eth0: adding default route via 192.168.1.254 Nov 27 18:07:27 volumio avahi-daemon[534]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:07:27 volumio avahi-daemon[534]: New relevant interface eth0.IPv4 for mDNS. Nov 27 18:07:27 volumio avahi-daemon[534]: Registering new address record for 192.168.1.28 on eth0.IPv4. Nov 27 18:07:27 volumio sh[8732]: forked to background, child pid 8811 Nov 27 18:07:27 volumio dhcpcd[8752]: forked to background, child pid 8811 Nov 27 18:07:27 volumio sh[8732]: eth0=eth0 Nov 27 18:07:27 volumio volumio[8723]: info: Loading plugin "network"... Nov 27 18:07:27 volumio volumio[8723]: info: Refreshing Cached IP Addresses Nov 27 18:07:27 volumio sudo[8838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 18:07:27 volumio sudo[8838]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:27 volumio sudo[8838]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:27 volumio sudo[8840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 18:07:27 volumio sudo[8840]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:28 volumio volumio[8723]: info: Loading plugin "services"... Nov 27 18:07:28 volumio volumio[8723]: info: Loading plugin "alsa_controller"... Nov 27 18:07:28 volumio sudo[8847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 27 18:07:28 volumio kernel: Nov 27 18:07:28 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:502: Not connected Nov 27 18:07:28 volumio sudo[8847]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:28 volumio sudo[8840]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:28 volumio sudo[8847]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:28 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Nov 27 18:07:28 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Nov 27 18:07:28 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Nov 27 18:07:28 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:07:28 volumio volumio[8723]: info: Loading plugin "wizard"... Nov 27 18:07:28 volumio volumio[8723]: info: Loading plugin "networkfs"... Nov 27 18:07:28 volumio avahi-daemon[534]: Interface eth0.IPv4 no longer relevant for mDNS. Nov 27 18:07:28 volumio avahi-daemon[534]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:07:28 volumio dhcpcd[8811]: eth0: carrier lost Nov 27 18:07:28 volumio kernel: usb 1-1: USB disconnect, device number 84 Nov 27 18:07:28 volumio kernel: usb 1-1.1: USB disconnect, device number 85 Nov 27 18:07:28 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Nov 27 18:07:28 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:07:28 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:07:28 volumio avahi-daemon[534]: Withdrawing address record for 192.168.1.28 on eth0. Nov 27 18:07:28 volumio volumio[8723]: info: Starting Udev Watcher for removable devices Nov 27 18:07:28 volumio kernel: usb 1-1.3: USB disconnect, device number 86 Nov 27 18:07:28 volumio systemd[1]: Stopping ifup for eth0... Nov 27 18:07:28 volumio volumio[8723]: info: Ignoring mount for partition: boot Nov 27 18:07:28 volumio volumio[8723]: info: Ignoring mount for partition: volumio Nov 27 18:07:28 volumio volumio[8723]: info: Ignoring mount for partition: volumio_data Nov 27 18:07:28 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:07:28 volumio volumio[8723]: info: Loading plugin "volumio_command_line_client"... Nov 27 18:07:28 volumio volumio[8723]: info: Loading plugin "upnp"... Nov 27 18:07:28 volumio ifdown[8875]: sending signal ALRM to pid 8811 Nov 27 18:07:28 volumio ifdown[8875]: waiting for pid 8811 to exit Nov 27 18:07:28 volumio dhcpcd[8901]: sending signal ALRM to pid 8811 Nov 27 18:07:28 volumio dhcpcd[8901]: waiting for pid 8811 to exit Nov 27 18:07:28 volumio volumio[8723]: info: [1732727248429] Starting Upmpd Daemon Nov 27 18:07:28 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:07:28 volumio volumio[8723]: info: Loading plugin "my_music"... Nov 27 18:07:28 volumio volumio[8723]: info: Loading plugin "mpd"... Nov 27 18:07:28 volumio dhcpcd[8811]: eth0: deleting route to 192.168.1.0/24 Nov 27 18:07:28 volumio dhcpcd[8811]: eth0: deleting default route via 192.168.1.254 Nov 27 18:07:28 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Nov 27 18:07:28 volumio dhcpcd[8811]: eth0: removing interface Nov 27 18:07:28 volumio dhcpcd[8811]: dhcpcd exited Nov 27 18:07:28 volumio kernel: usb 1-1: new high-speed USB device number 87 using dwc_otg Nov 27 18:07:28 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Nov 27 18:07:28 volumio ifdown[8875]: Cannot find device "eth0" Nov 27 18:07:28 volumio systemd[1]: ifup@eth0.service: Succeeded. Nov 27 18:07:28 volumio systemd[1]: Stopped ifup for eth0. Nov 27 18:07:28 volumio ifplugd(eth0)[834]: Link beat lost. Nov 27 18:07:29 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Nov 27 18:07:29 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:07:29 volumio kernel: hub 1-1:1.0: USB hub found Nov 27 18:07:29 volumio kernel: hub 1-1:1.0: 5 ports detected Nov 27 18:07:29 volumio volumio[8723]: info: Loading plugin "upnp_browser"... Nov 27 18:07:29 volumio kernel: usb 1-1.1: new high-speed USB device number 88 using dwc_otg Nov 27 18:07:29 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Nov 27 18:07:29 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:07:29 volumio kernel: smsc95xx v2.0.0 Nov 27 18:07:29 volumio volumio-remote-updater[533]: [2024-11-27 18:07:29] [connect] Successful connection Nov 27 18:07:29 volumio kernel: SMSC LAN8700 usb-001:088:01: attached PHY driver (mii_bus:phy_addr=usb-001:088:01, irq=199) Nov 27 18:07:29 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1c:bd:5f Nov 27 18:07:29 volumio volumio[8723]: info: Loading plugin "alarm-clock"... Nov 27 18:07:30 volumio kernel: usb 1-1.3: new high-speed USB device number 89 using dwc_otg Nov 27 18:07:30 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:07:30 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:07:30 volumio volumio[8723]: info: Loading plugin "airplay_emulation"... Nov 27 18:07:30 volumio volumio[8723]: info: Starting Shairport Sync Nov 27 18:07:30 volumio volumio[8723]: info: Loading plugin "last_100"... Nov 27 18:07:30 volumio volumio[8723]: info: Loading plugin "webradio"... Nov 27 18:07:30 volumio kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Nov 27 18:07:30 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Nov 27 18:07:30 volumio kernel: usb 1-1.3: Product: SMSL USB AUDIO Nov 27 18:07:30 volumio kernel: usb 1-1.3: Manufacturer: SMSL Nov 27 18:07:30 volumio volumio[8723]: info: Loading plugin "i2s_dacs"... Nov 27 18:07:30 volumio volumio[8723]: info: Loading plugin "volumiodiscovery"... Nov 27 18:07:30 volumio volumio[8723]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:30 volumio volumio[8723]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:30 volumio volumio[8723]: *** WARNING *** For more information see Nov 27 18:07:30 volumio volumio[8723]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:30 volumio node[8723]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:30 volumio volumio[8723]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:30 volumio volumio[8723]: *** WARNING *** For more information see Nov 27 18:07:30 volumio node[8723]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:30 volumio node[8723]: *** WARNING *** For more information see Nov 27 18:07:30 volumio node[8723]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:30 volumio node[8723]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:30 volumio node[8723]: *** WARNING *** For more information see Nov 27 18:07:30 volumio volumio[8723]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 27 18:07:30 volumio volumio[8723]: info: Discovery: Started advertising with name: Volumio Nov 27 18:07:30 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:07:30 volumio volumio[8723]: info: Loading plugin "squeezelite_mc"... Nov 27 18:07:30 volumio kernel: usb 1-1.3: 1:3 : unsupported format bits 0x100000000 Nov 27 18:07:30 volumio kernel: hid-generic 0003:152A:85DD.001E: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-3f980000.usb-1.3/input3 Nov 27 18:07:30 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Nov 27 18:07:31 volumio ifplugd(eth0)[834]: Link beat detected. Nov 27 18:07:31 volumio volumio[8723]: info: Loading plugin "outputs"... Nov 27 18:07:32 volumio volumio[8723]: info: Loading plugin "albumart"... Nov 27 18:07:32 volumio volumio[8723]: info: Plugin example_plugin is not enabled Nov 27 18:07:32 volumio volumio[8723]: info: Loading plugin "inputs"... Nov 27 18:07:32 volumio volumio[8723]: info: Loading plugin "updater_comm"... Nov 27 18:07:32 volumio volumio[8723]: info: Plugin mpdemulation is not enabled Nov 27 18:07:32 volumio volumio[8723]: info: Loading plugin "rest_api"... Nov 27 18:07:32 volumio volumio[8723]: info: Loading plugin "websocket"... Nov 27 18:07:32 volumio volumio[8723]: info: Starting Socket.io Server version 2.3.0 Nov 27 18:07:32 volumio volumio[8723]: info: Loading plugin "music_services_shield"... Nov 27 18:07:32 volumio volumio[8723]: Forking 3 albumart workers Nov 27 18:07:33 volumio volumio[8723]: info: Applying required configuration parameters for plugin music_services_shield Nov 27 18:07:33 volumio volumio[8723]: info: Loading i18n strings for locale ru Nov 27 18:07:33 volumio volumio[8723]: Updating browse sources language Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::initPlayerControls Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:33 volumio volumio[8723]: Express server listening on port 3000 Nov 27 18:07:33 volumio volumio[8723]: [Metrics] WebUI: 10s 430.76ms Nov 27 18:07:33 volumio volumio[8723]: info: CoreStateMachine::resetVolumioState Nov 27 18:07:33 volumio volumio[8723]: info: CoreStateMachine::getcurrentVolume Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 18:07:33 volumio volumio[8723]: info: CoreStateMachine::pushState Nov 27 18:07:33 volumio volumio[8723]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:07:33 volumio volumio[8723]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:33 volumio volumio[8723]: info: Volumio Network Manager: Network status updated: 1 Nov 27 18:07:33 volumio volumio[8723]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 27 18:07:33 volumio volumio[8723]: wlan0 Interface doesn't support scanning. Nov 27 18:07:33 volumio volumio[8723]: info: Cannot use regular scanning, forcing with ap-force Nov 27 18:07:33 volumio sudo[9051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 27 18:07:33 volumio sudo[9051]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:34 volumio sudo[9051]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:34 volumio volumio[8723]: command failed: No such device (-19) Nov 27 18:07:34 volumio volumio[8723]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 27 18:07:34 volumio volumio[8723]: command failed: No such device (-19) Nov 27 18:07:34 volumio volumio-remote-updater[533]: [2024-11-27 18:07:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732727249 101 Nov 27 18:07:34 volumio volumio[8723]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 27 18:07:34 volumio volumio[8723]: info: Nov 27 18:07:34 volumio volumio[8723]: ---------------------------- USB Audio Device Detached Nov 27 18:07:34 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Nov 27 18:07:34 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:07:34 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Nov 27 18:07:34 volumio systemd-udevd[8876]: Using default interface naming scheme 'v240'. Nov 27 18:07:34 volumio systemd-udevd[8876]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 27 18:07:34 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:34 volumio volumio[8723]: info: Reloading queue from file Nov 27 18:07:34 volumio volumio[8723]: info: CoreStateMachine::setRepeat true single undefined Nov 27 18:07:34 volumio volumio[8723]: info: CoreStateMachine::pushState Nov 27 18:07:34 volumio volumio[8723]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:34 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:07:34 volumio volumio[8723]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:34 volumio volumio[8723]: info: CoreStateMachine::setRandom false Nov 27 18:07:34 volumio volumio[8723]: info: CoreStateMachine::pushState Nov 27 18:07:34 volumio volumio[8723]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:34 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:07:34 volumio volumio[8723]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:34 volumio volumio[8723]: info: Setting Device type: Raspberry PI Nov 27 18:07:34 volumio volumio[8723]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 27 18:07:34 volumio volumio[8723]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Nov 27 18:07:34 volumio volumio[8723]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 27 18:07:35 volumio volumio[8723]: info: Discovery: adding 510e3eae-56c4-4ded-ab5e-7d0d103ffa4f Nov 27 18:07:35 volumio volumio[8723]: info: Discovery: Found device Volumio Nov 27 18:07:35 volumio volumio[8723]: info: CoreCommandRouter::volumioGetState Nov 27 18:07:35 volumio volumio[8723]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:35 volumio systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Nov 27 18:07:35 volumio volumio[8723]: info: Nov 27 18:07:35 volumio volumio[8723]: ---------------------------- USB Audio Device Attached Nov 27 18:07:35 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 27 18:07:35 volumio systemd[1]: Started ifup for eth0. Nov 27 18:07:35 volumio sh[9073]: command failed: No such device (-19) Nov 27 18:07:35 volumio volumio[8723]: info: CoreCommandRouter::Close All Modals sent Nov 27 18:07:35 volumio sh[9073]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:07:35 volumio sh[9073]: eth0: IAID eb:1c:bd:5f Nov 27 18:07:35 volumio sh[9073]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:07:35 volumio sh[9073]: ipv6_addaddr1: Permission denied Nov 27 18:07:35 volumio dhcpcd[9093]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:07:35 volumio dhcpcd[9093]: eth0: IAID eb:1c:bd:5f Nov 27 18:07:35 volumio dhcpcd[9093]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:07:35 volumio dhcpcd[9093]: ipv6_addaddr1: Permission denied Nov 27 18:07:35 volumio volumio[8723]: info: Preparing to save Alsa Options, stopping services first Nov 27 18:07:35 volumio volumio[8723]: info: CoreCommandRouter::volumioGetState Nov 27 18:07:35 volumio volumio[8723]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:35 volumio volumio[8723]: info: CoreCommandRouter::volumioPause Nov 27 18:07:35 volumio volumio[8723]: info: CoreStateMachine::pause Nov 27 18:07:35 volumio volumio[8723]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Nov 27 18:07:35 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:07:35 volumio sh[9073]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:07:35 volumio dhcpcd[9093]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:07:35 volumio sh[9073]: eth0: probing address 192.168.1.28/24 Nov 27 18:07:35 volumio dhcpcd[9093]: eth0: probing address 192.168.1.28/24 Nov 27 18:07:35 volumio sh[9073]: eth0: soliciting an IPv6 router Nov 27 18:07:35 volumio dhcpcd[9093]: eth0: soliciting an IPv6 router Nov 27 18:07:35 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:07:35 volumio volumio[8723]: Starting albumart workers Nov 27 18:07:36 volumio volumio[8723]: info: Device 5 does not have any Mixer Control Available Nov 27 18:07:36 volumio volumio[8723]: Starting albumart workers Nov 27 18:07:36 volumio volumio[8723]: Starting albumart workers Nov 27 18:07:36 volumio volumio[8723]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 27 18:07:36 volumio volumio[8723]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 27 18:07:36 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 27 18:07:36 volumio volumio[8723]: info: Disabling external Volume Control Nov 27 18:07:36 volumio volumio[8723]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 18:07:36 volumio volumio[8723]: info: CoreStateMachine::pushState Nov 27 18:07:36 volumio volumio[8723]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:36 volumio volumio[8723]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:36 volumio volumio[8723]: info: Preparing to generate the ALSA configuration file Nov 27 18:07:36 volumio volumio[8723]: info: Completed loading Core Plugins Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Nov 27 18:07:37 volumio volumio[8723]: info: Asound.conf file unchanged, so no further update is needed Nov 27 18:07:37 volumio volumio[8723]: info: Output device has changed, restarting MPD Nov 27 18:07:37 volumio volumio[8723]: info: Output device has changed, restarting Shairport Sync Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:37 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:37 volumio sudo[9113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 18:07:37 volumio sudo[9113]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:37 volumio sudo[9113]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:37 volumio sudo[9119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 18:07:37 volumio sudo[9119]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:37 volumio systemd[1]: musicservicesshield.service: Succeeded. Nov 27 18:07:37 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 27 18:07:37 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 27 18:07:37 volumio systemd[1]: Stopping Music Player Daemon... Nov 27 18:07:37 volumio systemd[1]: mpd.service: Succeeded. Nov 27 18:07:37 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 18:07:37 volumio volumio[8723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:07:37 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:07:37 volumio volumio[8723]: info: Preparing to generate the ALSA configuration file Nov 27 18:07:37 volumio kernel: Nov 27 18:07:37 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:502: Not connected Nov 27 18:07:37 volumio sh[9073]: eth0: carrier lost Nov 27 18:07:37 volumio dhcpcd[9093]: eth0: carrier lost Nov 27 18:07:37 volumio kernel: usb 1-1: USB disconnect, device number 87 Nov 27 18:07:38 volumio kernel: usb 1-1.1: USB disconnect, device number 88 Nov 27 18:07:38 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Nov 27 18:07:38 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:07:38 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:07:38 volumio volumio[8723]: info: MPD Permissions set Nov 27 18:07:38 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:38 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:38 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:38 volumio systemd[1]: Stopping ifup for eth0... Nov 27 18:07:38 volumio ifdown[9141]: ifdown: waiting for lock on /run/network/ifstate.eth0 Nov 27 18:07:38 volumio kernel: usb 1-1.3: USB disconnect, device number 89 Nov 27 18:07:38 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:38 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:38 volumio volumio[8723]: info: Asound.conf file unchanged, so no further update is needed Nov 27 18:07:38 volumio volumio[8723]: info: Output device has changed, restarting MPD Nov 27 18:07:38 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Nov 27 18:07:38 volumio sudo[9125]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:07:38 volumio sudo[9125]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:38 volumio sudo[9125]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:38 volumio sh[9073]: eth0: removing interface Nov 27 18:07:38 volumio dhcpcd[9093]: eth0: removing interface Nov 27 18:07:38 volumio sudo[9196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 18:07:38 volumio kernel: usb 1-1: new high-speed USB device number 90 using dwc_otg Nov 27 18:07:38 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Nov 27 18:07:38 volumio sudo[9196]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:38 volumio sudo[9196]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:38 volumio volumio[8723]: info: Output device has changed, restarting Shairport Sync Nov 27 18:07:38 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:38 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:38 volumio sudo[9205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 18:07:38 volumio sudo[9205]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:38 volumio ifplugd(eth0)[834]: Link beat lost. Nov 27 18:07:38 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 27 18:07:38 volumio systemd[1]: mpd.service: Succeeded. Nov 27 18:07:38 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 18:07:38 volumio sh[9073]: dhcpcd exited Nov 27 18:07:38 volumio dhcpcd[9093]: dhcpcd exited Nov 27 18:07:38 volumio sh[9073]: ifup: failed to bring up eth0 Nov 27 18:07:38 volumio ifdown[9141]: ifdown: interface eth0 not configured Nov 27 18:07:38 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:07:38 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 27 18:07:38 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 27 18:07:38 volumio systemd[1]: Stopped ifup for eth0. Nov 27 18:07:38 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Nov 27 18:07:38 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:07:38 volumio kernel: hub 1-1:1.0: USB hub found Nov 27 18:07:38 volumio kernel: hub 1-1:1.0: 5 ports detected Nov 27 18:07:38 volumio volumio[8723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:07:38 volumio volumio[8723]: info: ___________ START PLUGINS ___________ Nov 27 18:07:38 volumio volumio[8723]: info: ControllerMpd::onStart: Initializing MPD Nov 27 18:07:38 volumio volumio[8723]: info: Creating MPD Configuration file Nov 27 18:07:38 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:38 volumio volumio[8723]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:07:38 volumio volumio[8723]: info: [1732727258966] CoreMusicLibrary::Adding element Медиа-серверы Nov 27 18:07:38 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:07:38 volumio sudo[9236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 18:07:38 volumio sudo[9236]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:39 volumio sudo[9236]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:39 volumio sudo[9238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 18:07:39 volumio sudo[9238]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:39 volumio sudo[9230]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:07:39 volumio sudo[9230]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:39 volumio sudo[9230]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:39 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 27 18:07:39 volumio systemd[1]: mpd.service: Succeeded. Nov 27 18:07:39 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 18:07:39 volumio kernel: usb 1-1.1: new high-speed USB device number 91 using dwc_otg Nov 27 18:07:39 volumio volumio[8723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:39 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:07:39 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Nov 27 18:07:39 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:07:39 volumio kernel: smsc95xx v2.0.0 Nov 27 18:07:39 volumio volumio[8723]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:07:39 volumio volumio[8723]: info: [1732727259313] CoreMusicLibrary::Adding element Last_100 Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:07:39 volumio volumio[8723]: info: [1732727259324] CoreMusicLibrary::Adding element Webradio Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:07:39 volumio volumio[8723]: info: Initializing BBC Radios Nov 27 18:07:39 volumio volumio[8723]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:39 volumio kernel: SMSC LAN8700 usb-001:091:01: attached PHY driver (mii_bus:phy_addr=usb-001:091:01, irq=199) Nov 27 18:07:39 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1c:bd:5f Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:07:39 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:39 volumio volumio[8723]: info: [squeezelite_mc] Starting proxy server... Nov 27 18:07:39 volumio sudo[9244]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:07:39 volumio sudo[9244]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:39 volumio sudo[9244]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:39 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:07:39 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:07:39 volumio kernel: usb 1-1.3: new high-speed USB device number 92 using dwc_otg Nov 27 18:07:39 volumio volumio[8723]: info: Volumio Calling Home Nov 27 18:07:39 volumio kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Nov 27 18:07:39 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Nov 27 18:07:39 volumio kernel: usb 1-1.3: Product: SMSL USB AUDIO Nov 27 18:07:39 volumio kernel: usb 1-1.3: Manufacturer: SMSL Nov 27 18:07:40 volumio volumio[8723]: info: [squeezelite_mc] Proxy server started on port 45441 Nov 27 18:07:40 volumio sudo[9290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 27 18:07:40 volumio sudo[9290]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:40 volumio volumio[8723]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 18:07:40 volumio volumio[8723]: info: CoreStateMachine::pushState Nov 27 18:07:40 volumio volumio[8723]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:40 volumio volumio[8723]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:40 volumio volumio[8723]: info: [squeezelite_mc] Server discovery started Nov 27 18:07:40 volumio volumio[8723]: info: [squeezelite_mc] Player finder started Nov 27 18:07:40 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:40 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:40 volumio kernel: usb 1-1.3: 1:3 : unsupported format bits 0x100000000 Nov 27 18:07:40 volumio kernel: hid-generic 0003:152A:85DD.001F: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-3f980000.usb-1.3/input3 Nov 27 18:07:40 volumio volumio[8723]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:40 volumio volumio[8723]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 27 18:07:40 volumio volumio[8723]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 18:07:40 volumio volumio[8723]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 27 18:07:40 volumio volumio[8723]: at doSend (dgram.js:692:16) Nov 27 18:07:40 volumio volumio[8723]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 27 18:07:40 volumio volumio[8723]: at afterDns (dgram.js:638:5) Nov 27 18:07:40 volumio volumio[8723]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 27 18:07:40 volumio volumio[8723]: errno: -101, Nov 27 18:07:40 volumio volumio[8723]: code: 'ENETUNREACH', Nov 27 18:07:40 volumio volumio[8723]: syscall: 'send', Nov 27 18:07:40 volumio volumio[8723]: address: '255.255.255.255', Nov 27 18:07:40 volumio volumio[8723]: port: 3483 Nov 27 18:07:40 volumio volumio[8723]: } Nov 27 18:07:40 volumio volumio[8723]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 18:07:40 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Nov 27 18:07:41 volumio sudo[9290]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:41 volumio ifplugd(eth0)[834]: Link beat detected. Nov 27 18:07:41 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 27 18:07:41 volumio systemd[1]: mpd.service: Failed with result 'protocol'. Nov 27 18:07:41 volumio systemd[1]: Failed to start Music Player Daemon. Nov 27 18:07:41 volumio sudo[9119]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:41 volumio sudo[9238]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:41 volumio sudo[9205]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:42 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:07:42 volumio sudo[9329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 18:06 Nov 27 18:07:42 volumio sudo[9329]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:42 volumio sudo[9327]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:07:42 volumio sudo[9327]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:42 volumio sudo[9327]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:42 volumio sudo[9329]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:42 volumio volumio-remote-updater[533]: [2024-11-27 18:07:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 27 18:07:42 volumio volumio-remote-updater[533]: [2024-11-27 18:07:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 27 18:07:42 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 27 18:07:43 volumio systemd-udevd[8869]: Process '/bin/bash -c '/usr/local/bin/volumio usbdetach'' failed with exit code 56. Nov 27 18:07:43 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 27 18:07:43 volumio systemd-udevd[8876]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 27 18:07:43 volumio systemd[1]: Started dynamicswap service. Nov 27 18:07:43 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 27 18:07:43 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Nov 27 18:07:43 volumio systemd[1]: Stopped Volumio Backend Module. Nov 27 18:07:43 volumio systemd[1]: Started Volumio Backend Module. Nov 27 18:07:43 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 27 18:07:43 volumio systemd-udevd[8869]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Nov 27 18:07:43 volumio systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Nov 27 18:07:43 volumio systemd[1]: Started ifup for eth0. Nov 27 18:07:43 volumio sh[9361]: command failed: No such device (-19) Nov 27 18:07:43 volumio sh[9361]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:07:43 volumio sh[9361]: eth0: IAID eb:1c:bd:5f Nov 27 18:07:43 volumio sh[9361]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:07:43 volumio sh[9361]: ipv6_addaddr1: Permission denied Nov 27 18:07:43 volumio dhcpcd[9379]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:07:43 volumio dhcpcd[9379]: eth0: IAID eb:1c:bd:5f Nov 27 18:07:43 volumio dhcpcd[9379]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:07:43 volumio dhcpcd[9379]: ipv6_addaddr1: Permission denied Nov 27 18:07:44 volumio sh[9361]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:07:44 volumio dhcpcd[9379]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:07:44 volumio sh[9361]: eth0: probing address 192.168.1.28/24 Nov 27 18:07:44 volumio dhcpcd[9379]: eth0: probing address 192.168.1.28/24 Nov 27 18:07:44 volumio sh[9361]: eth0: soliciting an IPv6 router Nov 27 18:07:44 volumio dhcpcd[9379]: eth0: soliciting an IPv6 router Nov 27 18:07:46 volumio mpd[9332]: Nov 27 18:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 18:07:46 volumio systemd[1]: Started Music Player Daemon. Nov 27 18:07:46 volumio mpd_monitor.sh[547]: MPD restarted due to no mpc output. Nov 27 18:07:46 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 27 18:07:47 volumio volumio[9385]: cset: --> shielding system active with Nov 27 18:07:47 volumio volumio[9385]: cset: "system" cpuset of CPUSPEC(1-3) with 91 tasks running Nov 27 18:07:47 volumio volumio[9385]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 27 18:07:47 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 27 18:07:47 volumio volumio[9345]: info: ------------------------------------------- Nov 27 18:07:47 volumio volumio[9345]: info: ----- Volumio3 ---- Nov 27 18:07:47 volumio volumio[9345]: info: ------------------------------------------- Nov 27 18:07:47 volumio volumio[9345]: info: ----- System startup ---- Nov 27 18:07:47 volumio volumio[9345]: info: ------------------------------------------- Nov 27 18:07:47 volumio volumio-remote-updater[533]: [2024-11-27 18:07:47] [connect] Successful connection Nov 27 18:07:49 volumio volumio[9345]: info: MYVOLUMIO Environment detected Nov 27 18:07:49 volumio volumio[9345]: info: Plugin folders cleanup Nov 27 18:07:49 volumio volumio[9345]: info: Scanning into folder /volumio/app/plugins/ Nov 27 18:07:49 volumio volumio[9345]: info: Scanning category audio_interface Nov 27 18:07:49 volumio volumio[9345]: info: Scanning category miscellanea Nov 27 18:07:49 volumio volumio[9345]: info: Scanning category music_service Nov 27 18:07:49 volumio volumio[9345]: info: Scanning category plugins.json Nov 27 18:07:49 volumio volumio[9345]: info: Scanning category system_controller Nov 27 18:07:49 volumio volumio[9345]: info: Scanning category user_interface Nov 27 18:07:49 volumio volumio[9345]: info: Scanning into folder /data/plugins/ Nov 27 18:07:49 volumio volumio[9345]: info: Scanning category music_service Nov 27 18:07:49 volumio volumio[9345]: info: Scanning category system_hardware Nov 27 18:07:49 volumio volumio[9345]: info: Plugin folders cleanup completed Nov 27 18:07:49 volumio volumio[9345]: info: ------------------------------------------- Nov 27 18:07:49 volumio volumio[9345]: info: ----- Core plugins startup ---- Nov 27 18:07:49 volumio volumio[9345]: info: ------------------------------------------- Nov 27 18:07:49 volumio volumio[9345]: info: Loading plugins from folder /volumio/app/plugins/ Nov 27 18:07:49 volumio volumio[9345]: info: Adding plugin upnp to MyMusic Plugins Nov 27 18:07:49 volumio volumio[9345]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 27 18:07:49 volumio volumio[9345]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 27 18:07:49 volumio volumio[9345]: info: Loading plugins from folder /data/plugins/ Nov 27 18:07:49 volumio volumio[9345]: info: Loading plugin "system"... Nov 27 18:07:49 volumio volumio[9345]: info: Loading plugin "appearance"... Nov 27 18:07:49 volumio sh[9361]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:07:49 volumio sh[9361]: eth0: adding route to 192.168.1.0/24 Nov 27 18:07:49 volumio sh[9361]: eth0: adding default route via 192.168.1.254 Nov 27 18:07:49 volumio dhcpcd[9379]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:07:49 volumio avahi-daemon[534]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:07:49 volumio avahi-daemon[534]: New relevant interface eth0.IPv4 for mDNS. Nov 27 18:07:49 volumio dhcpcd[9379]: eth0: adding route to 192.168.1.0/24 Nov 27 18:07:49 volumio avahi-daemon[534]: Registering new address record for 192.168.1.28 on eth0.IPv4. Nov 27 18:07:49 volumio dhcpcd[9379]: eth0: adding default route via 192.168.1.254 Nov 27 18:07:49 volumio sh[9361]: forked to background, child pid 9442 Nov 27 18:07:49 volumio dhcpcd[9379]: forked to background, child pid 9442 Nov 27 18:07:50 volumio sh[9361]: eth0=eth0 Nov 27 18:07:51 volumio ntpd[848]: Listen normally on 6 eth0 192.168.1.28:123 Nov 27 18:07:51 volumio ntpd[848]: new interface(s) found: waking up resolver Nov 27 18:07:51 volumio ntpd[848]: Soliciting pool server 54.36.61.42 Nov 27 18:07:51 volumio volumio[9345]: info: Loading plugin "network"... Nov 27 18:07:51 volumio volumio[9345]: info: Refreshing Cached IP Addresses Nov 27 18:07:51 volumio sudo[9469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 18:07:51 volumio sudo[9469]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:51 volumio sudo[9469]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:51 volumio sudo[9471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 18:07:51 volumio sudo[9471]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:51 volumio volumio[9345]: info: Loading plugin "services"... Nov 27 18:07:51 volumio sudo[9471]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:51 volumio volumio[9345]: info: Loading plugin "alsa_controller"... Nov 27 18:07:51 volumio sudo[9483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 27 18:07:51 volumio sudo[9483]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:51 volumio sudo[9483]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:51 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:07:51 volumio volumio[9345]: info: Loading plugin "wizard"... Nov 27 18:07:51 volumio volumio[9345]: info: Loading plugin "networkfs"... Nov 27 18:07:51 volumio volumio[9345]: info: Starting Udev Watcher for removable devices Nov 27 18:07:52 volumio volumio[9345]: info: Ignoring mount for partition: boot Nov 27 18:07:52 volumio volumio[9345]: info: Ignoring mount for partition: volumio Nov 27 18:07:52 volumio volumio[9345]: info: Ignoring mount for partition: volumio_data Nov 27 18:07:52 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:07:52 volumio volumio[9345]: info: Loading plugin "volumio_command_line_client"... Nov 27 18:07:52 volumio volumio[9345]: info: Loading plugin "upnp"... Nov 27 18:07:52 volumio volumio[9345]: info: [1732727272072] Starting Upmpd Daemon Nov 27 18:07:52 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:07:52 volumio volumio[9345]: info: Loading plugin "my_music"... Nov 27 18:07:52 volumio volumio[9345]: info: Loading plugin "mpd"... Nov 27 18:07:52 volumio ntpd[848]: Soliciting pool server 95.81.173.74 Nov 27 18:07:52 volumio volumio[9345]: info: Loading plugin "upnp_browser"... Nov 27 18:07:53 volumio ntpd[848]: 162.159.200.1 local addr 192.168.1.28 -> Nov 27 18:07:53 volumio volumio[9345]: info: Loading plugin "alarm-clock"... Nov 27 18:07:53 volumio volumio[9345]: info: Loading plugin "airplay_emulation"... Nov 27 18:07:53 volumio volumio[9345]: info: Starting Shairport Sync Nov 27 18:07:53 volumio volumio[9345]: info: Loading plugin "last_100"... Nov 27 18:07:53 volumio volumio[9345]: info: Loading plugin "webradio"... Nov 27 18:07:53 volumio volumio[9345]: info: Loading plugin "i2s_dacs"... Nov 27 18:07:53 volumio volumio[9345]: info: Loading plugin "volumiodiscovery"... Nov 27 18:07:53 volumio volumio[9345]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:53 volumio volumio[9345]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:53 volumio volumio[9345]: *** WARNING *** For more information see Nov 27 18:07:53 volumio volumio[9345]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:53 volumio node[9345]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:53 volumio volumio[9345]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:53 volumio volumio[9345]: *** WARNING *** For more information see Nov 27 18:07:53 volumio node[9345]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:53 volumio node[9345]: *** WARNING *** For more information see Nov 27 18:07:53 volumio node[9345]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 18:07:53 volumio node[9345]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:07:53 volumio node[9345]: *** WARNING *** For more information see Nov 27 18:07:53 volumio volumio[9345]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 27 18:07:53 volumio volumio[9345]: info: Discovery: Started advertising with name: Volumio Nov 27 18:07:53 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:07:53 volumio volumio[9345]: info: Loading plugin "squeezelite_mc"... Nov 27 18:07:55 volumio volumio[9345]: info: Loading plugin "outputs"... Nov 27 18:07:55 volumio volumio[9345]: info: Loading plugin "albumart"... Nov 27 18:07:55 volumio volumio[9345]: info: Plugin example_plugin is not enabled Nov 27 18:07:55 volumio volumio[9345]: info: Loading plugin "inputs"... Nov 27 18:07:55 volumio volumio[9345]: info: Loading plugin "updater_comm"... Nov 27 18:07:55 volumio volumio[9345]: info: Plugin mpdemulation is not enabled Nov 27 18:07:55 volumio volumio[9345]: info: Loading plugin "rest_api"... Nov 27 18:07:55 volumio volumio[9345]: info: Loading plugin "websocket"... Nov 27 18:07:56 volumio volumio[9345]: info: Starting Socket.io Server version 2.3.0 Nov 27 18:07:56 volumio volumio[9345]: info: Loading plugin "music_services_shield"... Nov 27 18:07:56 volumio volumio[9345]: Forking 3 albumart workers Nov 27 18:07:56 volumio volumio[9345]: info: Applying required configuration parameters for plugin music_services_shield Nov 27 18:07:56 volumio volumio[9345]: info: Loading i18n strings for locale ru Nov 27 18:07:56 volumio volumio[9345]: Updating browse sources language Nov 27 18:07:56 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::initPlayerControls Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:07:57 volumio volumio[9345]: Express server listening on port 3000 Nov 27 18:07:57 volumio volumio[9345]: [Metrics] WebUI: 10s 973.50ms Nov 27 18:07:57 volumio volumio[9345]: info: CoreStateMachine::resetVolumioState Nov 27 18:07:57 volumio volumio[9345]: info: CoreStateMachine::getcurrentVolume Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 18:07:57 volumio volumio[9345]: info: CoreStateMachine::pushState Nov 27 18:07:57 volumio volumio[9345]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:07:57 volumio volumio[9345]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:57 volumio kernel: Nov 27 18:07:57 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:502: Not connected Nov 27 18:07:57 volumio volumio[9345]: info: Volumio Network Manager: Network status updated: 1 Nov 27 18:07:57 volumio volumio[9345]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 27 18:07:57 volumio volumio[9345]: wlan0 Interface doesn't support scanning. Nov 27 18:07:57 volumio volumio[9345]: info: Cannot use regular scanning, forcing with ap-force Nov 27 18:07:57 volumio avahi-daemon[534]: Interface eth0.IPv4 no longer relevant for mDNS. Nov 27 18:07:57 volumio avahi-daemon[534]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:07:57 volumio kernel: usb 1-1: USB disconnect, device number 90 Nov 27 18:07:58 volumio kernel: usb 1-1.1: USB disconnect, device number 91 Nov 27 18:07:58 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Nov 27 18:07:58 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:07:58 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:07:57 volumio dhcpcd[9442]: eth0: carrier lost Nov 27 18:07:57 volumio avahi-daemon[534]: Withdrawing address record for 192.168.1.28 on eth0. Nov 27 18:07:58 volumio kernel: usb 1-1.3: USB disconnect, device number 92 Nov 27 18:07:58 volumio sudo[9539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 27 18:07:58 volumio sudo[9539]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:58 volumio systemd[1]: Stopping ifup for eth0... Nov 27 18:07:58 volumio sudo[9539]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:58 volumio volumio[9345]: command failed: No such device (-19) Nov 27 18:07:58 volumio volumio[9345]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 27 18:07:58 volumio volumio[9345]: command failed: No such device (-19) Nov 27 18:07:58 volumio ifdown[9548]: sending signal ALRM to pid 9442 Nov 27 18:07:58 volumio ifdown[9548]: waiting for pid 9442 to exit Nov 27 18:07:58 volumio dhcpcd[9569]: sending signal ALRM to pid 9442 Nov 27 18:07:58 volumio dhcpcd[9569]: waiting for pid 9442 to exit Nov 27 18:07:58 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Nov 27 18:07:58 volumio dhcpcd[9442]: eth0: deleting route to 192.168.1.0/24 Nov 27 18:07:58 volumio dhcpcd[9442]: eth0: deleting default route via 192.168.1.254 Nov 27 18:07:58 volumio kernel: usb 1-1: new high-speed USB device number 93 using dwc_otg Nov 27 18:07:58 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Nov 27 18:07:58 volumio dhcpcd[9442]: eth0: removing interface Nov 27 18:07:58 volumio dhcpcd[9442]: dhcpcd exited Nov 27 18:07:58 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Nov 27 18:07:58 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:07:58 volumio kernel: hub 1-1:1.0: USB hub found Nov 27 18:07:58 volumio kernel: hub 1-1:1.0: 5 ports detected Nov 27 18:07:58 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:58 volumio volumio[9345]: info: Reloading queue from file Nov 27 18:07:58 volumio volumio[9345]: info: Discovery: Browse raised the following error Error: index has no corresponding interface Nov 27 18:07:58 volumio ifdown[9548]: Cannot find device "eth0" Nov 27 18:07:58 volumio volumio[9345]: info: CoreStateMachine::setRepeat true single undefined Nov 27 18:07:58 volumio volumio[9345]: info: CoreStateMachine::pushState Nov 27 18:07:58 volumio volumio[9345]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:58 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:07:58 volumio volumio[9345]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:58 volumio volumio[9345]: info: CoreStateMachine::setRandom false Nov 27 18:07:58 volumio volumio[9345]: info: CoreStateMachine::pushState Nov 27 18:07:58 volumio volumio[9345]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:58 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:07:58 volumio volumio[9345]: info: CoreCommandRouter::volumioPushState Nov 27 18:07:58 volumio volumio[9345]: info: Setting Device type: Raspberry PI Nov 27 18:07:58 volumio volumio[9345]: Starting albumart workers Nov 27 18:07:58 volumio systemd[1]: ifup@eth0.service: Succeeded. Nov 27 18:07:58 volumio volumio[9345]: info: Nov 27 18:07:58 volumio volumio[9345]: ---------------------------- USB Audio Device Detached Nov 27 18:07:58 volumio systemd[1]: Stopped ifup for eth0. Nov 27 18:07:58 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Nov 27 18:07:58 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:07:59 volumio kernel: usb 1-1.1: new high-speed USB device number 94 using dwc_otg Nov 27 18:07:59 volumio volumio[9345]: Starting albumart workers Nov 27 18:07:59 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Nov 27 18:07:59 volumio ntpd[848]: Deleting interface #6 eth0, 192.168.1.28#123, interface stats: received=18, sent=18, dropped=7, active_time=8 secs Nov 27 18:07:59 volumio ntpd[848]: 95.81.173.74 local addr 192.168.1.28 -> Nov 27 18:07:59 volumio ntpd[848]: 54.36.61.42 local addr 192.168.1.28 -> Nov 27 18:07:59 volumio ntpd[848]: 51.75.17.219 local addr 192.168.1.28 -> Nov 27 18:07:59 volumio ntpd[848]: 37.59.63.125 local addr 192.168.1.28 -> Nov 27 18:07:59 volumio ntpd[848]: 51.195.104.188 local addr 192.168.1.28 -> Nov 27 18:07:59 volumio ntpd[848]: 51.68.44.27 local addr 192.168.1.28 -> Nov 27 18:07:59 volumio ntpd[848]: 82.64.84.116 local addr 192.168.1.28 -> Nov 27 18:07:59 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Nov 27 18:07:59 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:07:59 volumio kernel: smsc95xx v2.0.0 Nov 27 18:07:59 volumio volumio[9345]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 27 18:07:59 volumio volumio[9345]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Nov 27 18:07:59 volumio volumio[9345]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 27 18:07:59 volumio volumio[9345]: info: Discovery: Browse raised the following error Error: index has no corresponding interface Nov 27 18:07:59 volumio volumio[9345]: info: Discovery: A device disappeared from network Nov 27 18:07:59 volumio ifplugd(eth0)[834]: Link beat lost. Nov 27 18:07:59 volumio volumio[9345]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Nov 27 18:07:59 volumio kernel: SMSC LAN8700 usb-001:094:01: attached PHY driver (mii_bus:phy_addr=usb-001:094:01, irq=199) Nov 27 18:07:59 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1c:bd:5f Nov 27 18:07:59 volumio volumio[9345]: info: Discovery: adding 510e3eae-56c4-4ded-ab5e-7d0d103ffa4f Nov 27 18:07:59 volumio volumio[9345]: info: Discovery: Found device Volumio Nov 27 18:07:59 volumio systemd-udevd[9550]: Using default interface naming scheme 'v240'. Nov 27 18:07:59 volumio volumio[9345]: info: CoreCommandRouter::volumioGetState Nov 27 18:07:59 volumio systemd-udevd[9550]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 27 18:07:59 volumio volumio[9345]: info: CorePlayQueue::getTrack 0 Nov 27 18:07:59 volumio volumio[9345]: info: Completed loading Core Plugins Nov 27 18:07:59 volumio volumio[9345]: info: Preparing to generate the ALSA configuration file Nov 27 18:07:59 volumio volumio[9345]: info: Asound.conf file unchanged, so no further update is needed Nov 27 18:07:59 volumio volumio[9345]: info: Output device has changed, restarting MPD Nov 27 18:07:59 volumio volumio[9345]: info: Output device has changed, restarting Shairport Sync Nov 27 18:07:59 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:07:59 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:07:59 volumio sudo[9654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 18:07:59 volumio sudo[9654]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:59 volumio sudo[9654]: pam_unix(sudo:session): session closed for user root Nov 27 18:07:59 volumio sudo[9656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 18:07:59 volumio sudo[9656]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:07:59 volumio kernel: usb 1-1.3: new high-speed USB device number 95 using dwc_otg Nov 27 18:07:59 volumio systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Nov 27 18:07:59 volumio volumio[9345]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:07:59 volumio kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Nov 27 18:07:59 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Nov 27 18:07:59 volumio kernel: usb 1-1.3: Product: SMSL USB AUDIO Nov 27 18:07:59 volumio kernel: usb 1-1.3: Manufacturer: SMSL Nov 27 18:07:59 volumio systemd[1]: Started ifup for eth0. Nov 27 18:07:59 volumio volumio[9345]: info: ___________ START PLUGINS ___________ Nov 27 18:07:59 volumio systemd[1]: musicservicesshield.service: Succeeded. Nov 27 18:07:59 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 27 18:07:59 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 27 18:07:59 volumio systemd[1]: Stopping Music Player Daemon... Nov 27 18:08:00 volumio volumio[9345]: info: ControllerMpd::onStart: Initializing MPD Nov 27 18:08:00 volumio systemd[1]: mpd.service: Succeeded. Nov 27 18:08:00 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 18:08:00 volumio volumio[9345]: info: Creating MPD Configuration file Nov 27 18:08:00 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:08:00 volumio ntpd[848]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 27 18:08:00 volumio sh[9663]: command failed: No such device (-19) Nov 27 18:08:00 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:00 volumio sudo[9679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 18:08:00 volumio sudo[9679]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:00 volumio volumio[9345]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:08:00 volumio volumio[9345]: info: [1732727280299] CoreMusicLibrary::Adding element Медиа-серверы Nov 27 18:08:00 volumio volumio[9345]: Starting albumart workers Nov 27 18:08:00 volumio sudo[9679]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:00 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:08:00 volumio kernel: usb 1-1.3: 1:3 : unsupported format bits 0x100000000 Nov 27 18:08:00 volumio kernel: hid-generic 0003:152A:85DD.0020: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-3f980000.usb-1.3/input3 Nov 27 18:08:00 volumio sudo[9685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 18:08:00 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:08:00 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:08:00 volumio dhcpcd[9686]: eth0: waiting for carrier Nov 27 18:08:00 volumio sh[9663]: eth0: waiting for carrier Nov 27 18:08:00 volumio volumio-remote-updater[533]: [2024-11-27 18:08:00] [connect] Successful connection Nov 27 18:08:00 volumio sudo[9685]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:00 volumio sudo[9670]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:08:00 volumio sudo[9670]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:00 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:00 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:00 volumio sudo[9670]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:00 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 27 18:08:00 volumio systemd[1]: mpd.service: Succeeded. Nov 27 18:08:00 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 18:08:00 volumio volumio[9345]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:08:00 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:00 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:00 volumio sh[9663]: eth0: carrier acquired Nov 27 18:08:00 volumio dhcpcd[9686]: eth0: carrier acquired Nov 27 18:08:00 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Nov 27 18:08:00 volumio sh[9663]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:08:00 volumio sh[9663]: eth0: IAID eb:1c:bd:5f Nov 27 18:08:00 volumio sh[9663]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:08:00 volumio sh[9663]: ipv6_addaddr1: Permission denied Nov 27 18:08:00 volumio dhcpcd[9686]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:08:00 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:08:00 volumio dhcpcd[9686]: eth0: IAID eb:1c:bd:5f Nov 27 18:08:00 volumio dhcpcd[9686]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:08:00 volumio dhcpcd[9686]: ipv6_addaddr1: Permission denied Nov 27 18:08:01 volumio volumio[9345]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:08:01 volumio volumio[9345]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:08:01 volumio volumio[9345]: info: [1732727281159] CoreMusicLibrary::Adding element Last_100 Nov 27 18:08:01 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:08:01 volumio volumio[9345]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:08:01 volumio volumio[9345]: info: [1732727281188] CoreMusicLibrary::Adding element Webradio Nov 27 18:08:01 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:08:01 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:08:01 volumio volumio[9345]: info: Initializing BBC Radios Nov 27 18:08:01 volumio sudo[9708]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:08:01 volumio sudo[9708]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:01 volumio sudo[9708]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:01 volumio ifplugd(eth0)[834]: Link beat detected. Nov 27 18:08:01 volumio sh[9663]: eth0: soliciting an IPv6 router Nov 27 18:08:01 volumio dhcpcd[9686]: eth0: soliciting an IPv6 router Nov 27 18:08:01 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:08:01 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:01 volumio sh[9663]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:08:01 volumio dhcpcd[9686]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:08:01 volumio sh[9663]: eth0: probing address 192.168.1.28/24 Nov 27 18:08:01 volumio dhcpcd[9686]: eth0: probing address 192.168.1.28/24 Nov 27 18:08:01 volumio volumio[9345]: info: [squeezelite_mc] Starting proxy server... Nov 27 18:08:02 volumio volumio[9345]: info: Volumio Calling Home Nov 27 18:08:03 volumio volumio[9345]: info: [squeezelite_mc] Proxy server started on port 46647 Nov 27 18:08:03 volumio sudo[9785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 27 18:08:03 volumio sudo[9785]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:03 volumio volumio[9345]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 18:08:03 volumio volumio[9345]: info: CoreStateMachine::pushState Nov 27 18:08:03 volumio volumio[9345]: info: CorePlayQueue::getTrack 0 Nov 27 18:08:03 volumio volumio[9345]: info: CoreCommandRouter::volumioPushState Nov 27 18:08:03 volumio volumio[9345]: info: [squeezelite_mc] Server discovery started Nov 27 18:08:03 volumio volumio[9345]: info: [squeezelite_mc] Player finder started Nov 27 18:08:03 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:03 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:03 volumio volumio[9345]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:03 volumio volumio[9345]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 27 18:08:03 volumio volumio[9345]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 18:08:03 volumio volumio[9345]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 27 18:08:03 volumio volumio[9345]: at doSend (dgram.js:692:16) Nov 27 18:08:03 volumio volumio[9345]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 27 18:08:03 volumio volumio[9345]: at afterDns (dgram.js:638:5) Nov 27 18:08:03 volumio volumio[9345]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 27 18:08:03 volumio volumio[9345]: errno: -101, Nov 27 18:08:03 volumio volumio[9345]: code: 'ENETUNREACH', Nov 27 18:08:03 volumio volumio[9345]: syscall: 'send', Nov 27 18:08:03 volumio volumio[9345]: address: '255.255.255.255', Nov 27 18:08:03 volumio volumio[9345]: port: 3483 Nov 27 18:08:03 volumio volumio[9345]: } Nov 27 18:08:03 volumio volumio[9345]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 18:08:04 volumio sudo[9785]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:05 volumio sudo[9825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 18:07 Nov 27 18:08:05 volumio sudo[9825]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:05 volumio mpd[9715]: Nov 27 18:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 18:08:05 volumio systemd[1]: Started Music Player Daemon. Nov 27 18:08:05 volumio sudo[9656]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:05 volumio sudo[9685]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:05 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 27 18:08:05 volumio sudo[9825]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 27 18:08:05 volumio systemd-udevd[9547]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 56. Nov 27 18:08:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 27 18:08:06 volumio systemd[1]: Started dynamicswap service. Nov 27 18:08:06 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 27 18:08:06 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Nov 27 18:08:06 volumio systemd[1]: Stopped Volumio Backend Module. Nov 27 18:08:06 volumio systemd[1]: Started Volumio Backend Module. Nov 27 18:08:06 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 27 18:08:06 volumio volumio[9829]: cset: --> shielding system active with Nov 27 18:08:06 volumio volumio[9829]: cset: "system" cpuset of CPUSPEC(1-3) with 92 tasks running Nov 27 18:08:06 volumio volumio[9829]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 27 18:08:06 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 27 18:08:06 volumio sh[9663]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:08:06 volumio dhcpcd[9686]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:08:06 volumio avahi-daemon[534]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:08:06 volumio sh[9663]: eth0: adding route to 192.168.1.0/24 Nov 27 18:08:06 volumio sh[9663]: eth0: adding default route via 192.168.1.254 Nov 27 18:08:06 volumio dhcpcd[9686]: eth0: adding route to 192.168.1.0/24 Nov 27 18:08:06 volumio avahi-daemon[534]: New relevant interface eth0.IPv4 for mDNS. Nov 27 18:08:06 volumio dhcpcd[9686]: eth0: adding default route via 192.168.1.254 Nov 27 18:08:06 volumio avahi-daemon[534]: Registering new address record for 192.168.1.28 on eth0.IPv4. Nov 27 18:08:07 volumio sh[9663]: forked to background, child pid 9894 Nov 27 18:08:07 volumio dhcpcd[9686]: forked to background, child pid 9894 Nov 27 18:08:07 volumio sh[9663]: eth0=eth0 Nov 27 18:08:08 volumio ntpd[848]: Listen normally on 7 eth0 192.168.1.28:123 Nov 27 18:08:08 volumio ntpd[848]: new interface(s) found: waking up resolver Nov 27 18:08:10 volumio volumio[9852]: info: ------------------------------------------- Nov 27 18:08:10 volumio volumio[9852]: info: ----- Volumio3 ---- Nov 27 18:08:10 volumio volumio[9852]: info: ------------------------------------------- Nov 27 18:08:10 volumio volumio[9852]: info: ----- System startup ---- Nov 27 18:08:10 volumio volumio[9852]: info: ------------------------------------------- Nov 27 18:08:10 volumio ntpd[848]: Soliciting pool server 194.177.34.116 Nov 27 18:08:11 volumio ntpd[848]: Soliciting pool server 162.159.200.123 Nov 27 18:08:11 volumio volumio[9852]: info: MYVOLUMIO Environment detected Nov 27 18:08:11 volumio volumio[9852]: info: Plugin folders cleanup Nov 27 18:08:11 volumio volumio[9852]: info: Scanning into folder /volumio/app/plugins/ Nov 27 18:08:11 volumio volumio[9852]: info: Scanning category audio_interface Nov 27 18:08:11 volumio volumio[9852]: info: Scanning category miscellanea Nov 27 18:08:11 volumio volumio[9852]: info: Scanning category music_service Nov 27 18:08:11 volumio volumio[9852]: info: Scanning category plugins.json Nov 27 18:08:11 volumio volumio[9852]: info: Scanning category system_controller Nov 27 18:08:11 volumio volumio[9852]: info: Scanning category user_interface Nov 27 18:08:11 volumio volumio[9852]: info: Scanning into folder /data/plugins/ Nov 27 18:08:11 volumio volumio[9852]: info: Scanning category music_service Nov 27 18:08:11 volumio volumio[9852]: info: Scanning category system_hardware Nov 27 18:08:11 volumio volumio[9852]: info: Plugin folders cleanup completed Nov 27 18:08:11 volumio volumio[9852]: info: ------------------------------------------- Nov 27 18:08:11 volumio volumio[9852]: info: ----- Core plugins startup ---- Nov 27 18:08:11 volumio volumio[9852]: info: ------------------------------------------- Nov 27 18:08:11 volumio volumio[9852]: info: Loading plugins from folder /volumio/app/plugins/ Nov 27 18:08:11 volumio volumio[9852]: info: Adding plugin upnp to MyMusic Plugins Nov 27 18:08:11 volumio volumio[9852]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 27 18:08:11 volumio volumio[9852]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 27 18:08:11 volumio volumio[9852]: info: Loading plugins from folder /data/plugins/ Nov 27 18:08:11 volumio volumio[9852]: info: Loading plugin "system"... Nov 27 18:08:11 volumio volumio[9852]: info: Loading plugin "appearance"... Nov 27 18:08:12 volumio ntpd[848]: Soliciting pool server 79.143.250.33 Nov 27 18:08:13 volumio ntpd[848]: Soliciting pool server 82.64.230.205 Nov 27 18:08:13 volumio volumio[9852]: info: Loading plugin "network"... Nov 27 18:08:13 volumio volumio[9852]: info: Refreshing Cached IP Addresses Nov 27 18:08:13 volumio sudo[9934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 18:08:13 volumio sudo[9934]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:13 volumio sudo[9934]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:13 volumio sudo[9936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 18:08:13 volumio sudo[9936]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:13 volumio volumio[9852]: info: Loading plugin "services"... Nov 27 18:08:13 volumio volumio[9852]: info: Loading plugin "alsa_controller"... Nov 27 18:08:13 volumio sudo[9936]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:13 volumio kernel: Nov 27 18:08:13 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:502: Not connected Nov 27 18:08:13 volumio sudo[9946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 27 18:08:13 volumio sudo[9946]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:13 volumio sudo[9946]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:13 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:08:13 volumio volumio[9852]: info: Loading plugin "wizard"... Nov 27 18:08:13 volumio volumio[9852]: info: Loading plugin "networkfs"... Nov 27 18:08:13 volumio volumio[9852]: info: Starting Udev Watcher for removable devices Nov 27 18:08:13 volumio dhcpcd[9894]: eth0: carrier lost Nov 27 18:08:13 volumio avahi-daemon[534]: Interface eth0.IPv4 no longer relevant for mDNS. Nov 27 18:08:13 volumio avahi-daemon[534]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:08:13 volumio kernel: usb 1-1: USB disconnect, device number 93 Nov 27 18:08:13 volumio kernel: usb 1-1.1: USB disconnect, device number 94 Nov 27 18:08:13 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Nov 27 18:08:13 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:08:13 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:08:13 volumio avahi-daemon[534]: Withdrawing address record for 192.168.1.28 on eth0. Nov 27 18:08:14 volumio volumio[9852]: info: Ignoring mount for partition: boot Nov 27 18:08:14 volumio volumio[9852]: info: Ignoring mount for partition: volumio Nov 27 18:08:14 volumio volumio[9852]: info: Ignoring mount for partition: volumio_data Nov 27 18:08:14 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:08:14 volumio volumio[9852]: info: Loading plugin "volumio_command_line_client"... Nov 27 18:08:14 volumio kernel: usb 1-1.3: USB disconnect, device number 95 Nov 27 18:08:14 volumio systemd[1]: Stopping ifup for eth0... Nov 27 18:08:14 volumio volumio[9852]: info: Loading plugin "upnp"... Nov 27 18:08:14 volumio volumio[9852]: info: [1732727294114] Starting Upmpd Daemon Nov 27 18:08:14 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:08:14 volumio volumio[9852]: info: Loading plugin "my_music"... Nov 27 18:08:14 volumio ntpd[848]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 27 18:08:14 volumio volumio[9852]: info: Loading plugin "mpd"... Nov 27 18:08:14 volumio ifdown[9974]: sending signal ALRM to pid 9894 Nov 27 18:08:14 volumio ifdown[9974]: waiting for pid 9894 to exit Nov 27 18:08:14 volumio dhcpcd[9992]: sending signal ALRM to pid 9894 Nov 27 18:08:14 volumio dhcpcd[9992]: waiting for pid 9894 to exit Nov 27 18:08:14 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Nov 27 18:08:14 volumio dhcpcd[9894]: eth0: deleting route to 192.168.1.0/24 Nov 27 18:08:14 volumio dhcpcd[9894]: eth0: deleting default route via 192.168.1.254 Nov 27 18:08:14 volumio dhcpcd[9894]: eth0: removing interface Nov 27 18:08:14 volumio dhcpcd[9894]: dhcpcd exited Nov 27 18:08:14 volumio kernel: usb 1-1: new high-speed USB device number 96 using dwc_otg Nov 27 18:08:14 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Nov 27 18:08:14 volumio ifdown[9974]: Cannot find device "eth0" Nov 27 18:08:14 volumio systemd[1]: ifup@eth0.service: Succeeded. Nov 27 18:08:14 volumio systemd[1]: Stopped ifup for eth0. Nov 27 18:08:14 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Nov 27 18:08:14 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:08:14 volumio kernel: hub 1-1:1.0: USB hub found Nov 27 18:08:14 volumio kernel: hub 1-1:1.0: 5 ports detected Nov 27 18:08:14 volumio volumio[9852]: info: Loading plugin "upnp_browser"... Nov 27 18:08:14 volumio ifplugd(eth0)[834]: Link beat lost. Nov 27 18:08:15 volumio kernel: usb 1-1.1: new high-speed USB device number 97 using dwc_otg Nov 27 18:08:15 volumio ntpd[848]: Deleting interface #7 eth0, 192.168.1.28#123, interface stats: received=17, sent=17, dropped=9, active_time=7 secs Nov 27 18:08:15 volumio ntpd[848]: 82.64.230.205 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio ntpd[848]: 79.143.250.33 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio ntpd[848]: 162.159.200.123 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio ntpd[848]: 194.177.34.116 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio ntpd[848]: 51.75.17.219 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio ntpd[848]: 37.59.63.125 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio ntpd[848]: 51.195.104.188 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio ntpd[848]: 51.68.44.27 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio ntpd[848]: 82.64.84.116 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio ntpd[848]: 54.36.61.42 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio ntpd[848]: 95.81.173.74 local addr 192.168.1.28 -> Nov 27 18:08:15 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Nov 27 18:08:15 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:08:15 volumio kernel: smsc95xx v2.0.0 Nov 27 18:08:15 volumio kernel: SMSC LAN8700 usb-001:097:01: attached PHY driver (mii_bus:phy_addr=usb-001:097:01, irq=199) Nov 27 18:08:15 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1c:bd:5f Nov 27 18:08:15 volumio volumio[9852]: info: Loading plugin "alarm-clock"... Nov 27 18:08:15 volumio volumio-remote-updater[533]: [2024-11-27 18:08:15] [connect] Successful connection Nov 27 18:08:15 volumio kernel: usb 1-1.3: new high-speed USB device number 98 using dwc_otg Nov 27 18:08:15 volumio volumio[9852]: info: Loading plugin "airplay_emulation"... Nov 27 18:08:15 volumio volumio[9852]: info: Starting Shairport Sync Nov 27 18:08:15 volumio volumio[9852]: info: Loading plugin "last_100"... Nov 27 18:08:15 volumio volumio[9852]: info: Loading plugin "webradio"... Nov 27 18:08:15 volumio kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Nov 27 18:08:15 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Nov 27 18:08:15 volumio kernel: usb 1-1.3: Product: SMSL USB AUDIO Nov 27 18:08:15 volumio kernel: usb 1-1.3: Manufacturer: SMSL Nov 27 18:08:16 volumio volumio[9852]: info: Loading plugin "i2s_dacs"... Nov 27 18:08:16 volumio volumio[9852]: info: Loading plugin "volumiodiscovery"... Nov 27 18:08:16 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:08:16 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:08:16 volumio volumio[9852]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 18:08:16 volumio volumio[9852]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:08:16 volumio volumio[9852]: *** WARNING *** For more information see Nov 27 18:08:16 volumio volumio[9852]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 18:08:16 volumio node[9852]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 27 18:08:16 volumio volumio[9852]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:08:16 volumio volumio[9852]: *** WARNING *** For more information see Nov 27 18:08:16 volumio node[9852]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:08:16 volumio node[9852]: *** WARNING *** For more information see Nov 27 18:08:16 volumio node[9852]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 27 18:08:16 volumio node[9852]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 27 18:08:16 volumio node[9852]: *** WARNING *** For more information see Nov 27 18:08:16 volumio volumio[9852]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 27 18:08:16 volumio volumio[9852]: info: Discovery: Started advertising with name: Volumio Nov 27 18:08:16 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 18:08:16 volumio volumio[9852]: info: Loading plugin "squeezelite_mc"... Nov 27 18:08:16 volumio kernel: usb 1-1.3: 1:3 : unsupported format bits 0x100000000 Nov 27 18:08:16 volumio kernel: hid-generic 0003:152A:85DD.0021: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-3f980000.usb-1.3/input3 Nov 27 18:08:16 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Nov 27 18:08:17 volumio ifplugd(eth0)[834]: Link beat detected. Nov 27 18:08:17 volumio volumio[9852]: info: Loading plugin "outputs"... Nov 27 18:08:17 volumio volumio[9852]: info: Loading plugin "albumart"... Nov 27 18:08:17 volumio volumio[9852]: info: Plugin example_plugin is not enabled Nov 27 18:08:17 volumio volumio[9852]: info: Loading plugin "inputs"... Nov 27 18:08:17 volumio volumio[9852]: info: Loading plugin "updater_comm"... Nov 27 18:08:18 volumio volumio[9852]: info: Plugin mpdemulation is not enabled Nov 27 18:08:18 volumio volumio[9852]: info: Loading plugin "rest_api"... Nov 27 18:08:18 volumio volumio[9852]: info: Loading plugin "websocket"... Nov 27 18:08:18 volumio volumio[9852]: info: Starting Socket.io Server version 2.3.0 Nov 27 18:08:18 volumio volumio[9852]: info: Loading plugin "music_services_shield"... Nov 27 18:08:18 volumio volumio[9852]: Forking 3 albumart workers Nov 27 18:08:18 volumio volumio[9852]: info: Applying required configuration parameters for plugin music_services_shield Nov 27 18:08:18 volumio volumio[9852]: info: Loading i18n strings for locale ru Nov 27 18:08:18 volumio volumio[9852]: Updating browse sources language Nov 27 18:08:18 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::initPlayerControls Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:19 volumio volumio[9852]: Express server listening on port 3000 Nov 27 18:08:19 volumio volumio[9852]: [Metrics] WebUI: 10s 365.65ms Nov 27 18:08:19 volumio volumio[9852]: info: CoreStateMachine::resetVolumioState Nov 27 18:08:19 volumio volumio[9852]: info: CoreStateMachine::getcurrentVolume Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 18:08:19 volumio volumio[9852]: info: CoreStateMachine::pushState Nov 27 18:08:19 volumio volumio[9852]: info: CorePlayQueue::getTrack 0 Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::volumioPushState Nov 27 18:08:19 volumio volumio[9852]: info: Volumio Network Manager: Network status updated: 1 Nov 27 18:08:19 volumio volumio[9852]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 27 18:08:19 volumio volumio[9852]: wlan0 Interface doesn't support scanning. Nov 27 18:08:19 volumio volumio[9852]: info: Cannot use regular scanning, forcing with ap-force Nov 27 18:08:19 volumio sudo[10100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 27 18:08:19 volumio sudo[10100]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:19 volumio sudo[10100]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:19 volumio volumio[9852]: command failed: No such device (-19) Nov 27 18:08:19 volumio volumio[9852]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 27 18:08:19 volumio volumio[9852]: command failed: No such device (-19) Nov 27 18:08:19 volumio volumio-remote-updater[533]: [2024-11-27 18:08:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732727295 101 Nov 27 18:08:19 volumio volumio[9852]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 27 18:08:19 volumio volumio[9852]: info: Nov 27 18:08:19 volumio volumio[9852]: ---------------------------- USB Audio Device Detached Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Nov 27 18:08:19 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:08:20 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Nov 27 18:08:20 volumio systemd-udevd[9977]: Using default interface naming scheme 'v240'. Nov 27 18:08:20 volumio systemd-udevd[9977]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 27 18:08:20 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:20 volumio volumio[9852]: info: Reloading queue from file Nov 27 18:08:20 volumio volumio[9852]: info: CoreStateMachine::setRepeat true single undefined Nov 27 18:08:20 volumio volumio[9852]: info: CoreStateMachine::pushState Nov 27 18:08:20 volumio volumio[9852]: info: CorePlayQueue::getTrack 0 Nov 27 18:08:20 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:08:20 volumio volumio[9852]: info: CoreCommandRouter::volumioPushState Nov 27 18:08:20 volumio volumio[9852]: info: CoreStateMachine::setRandom false Nov 27 18:08:20 volumio volumio[9852]: info: CoreStateMachine::pushState Nov 27 18:08:20 volumio volumio[9852]: info: CorePlayQueue::getTrack 0 Nov 27 18:08:20 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:08:20 volumio volumio[9852]: info: CoreCommandRouter::volumioPushState Nov 27 18:08:20 volumio volumio[9852]: info: Setting Device type: Raspberry PI Nov 27 18:08:20 volumio volumio[9852]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 27 18:08:20 volumio volumio[9852]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Nov 27 18:08:20 volumio volumio[9852]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 27 18:08:20 volumio volumio[9852]: info: Nov 27 18:08:20 volumio volumio[9852]: ---------------------------- USB Audio Device Attached Nov 27 18:08:20 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 27 18:08:20 volumio systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Nov 27 18:08:20 volumio systemd[1]: Started ifup for eth0. Nov 27 18:08:20 volumio sh[10122]: command failed: No such device (-19) Nov 27 18:08:20 volumio volumio[9852]: info: CoreCommandRouter::Close All Modals sent Nov 27 18:08:21 volumio volumio[9852]: info: Preparing to save Alsa Options, stopping services first Nov 27 18:08:21 volumio volumio[9852]: info: CoreCommandRouter::volumioGetState Nov 27 18:08:21 volumio volumio[9852]: info: CorePlayQueue::getTrack 0 Nov 27 18:08:21 volumio volumio[9852]: info: CoreCommandRouter::volumioPause Nov 27 18:08:21 volumio volumio[9852]: info: CoreStateMachine::pause Nov 27 18:08:21 volumio volumio[9852]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Nov 27 18:08:21 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:08:21 volumio kernel: Nov 27 18:08:21 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:502: Not connected Nov 27 18:08:21 volumio sh[10122]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:08:21 volumio sh[10122]: eth0: IAID eb:1c:bd:5f Nov 27 18:08:21 volumio sh[10122]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:08:21 volumio sh[10122]: ipv6_addaddr1: Permission denied Nov 27 18:08:21 volumio dhcpcd[10142]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:08:21 volumio dhcpcd[10142]: eth0: IAID eb:1c:bd:5f Nov 27 18:08:21 volumio dhcpcd[10142]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:08:21 volumio dhcpcd[10142]: ipv6_addaddr1: Permission denied Nov 27 18:08:21 volumio sh[10122]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:08:21 volumio dhcpcd[10142]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:08:21 volumio kernel: usb 1-1: USB disconnect, device number 96 Nov 27 18:08:21 volumio kernel: usb 1-1.1: USB disconnect, device number 97 Nov 27 18:08:21 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Nov 27 18:08:21 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:08:21 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:08:21 volumio sh[10122]: eth0: bpf_send: No such device or address Nov 27 18:08:21 volumio dhcpcd[10142]: eth0: bpf_send: No such device or address Nov 27 18:08:21 volumio volumio[9852]: Starting albumart workers Nov 27 18:08:21 volumio kernel: usb 1-1.3: USB disconnect, device number 98 Nov 27 18:08:21 volumio systemd[1]: Stopping ifup for eth0... Nov 27 18:08:21 volumio ifdown[10153]: ifdown: waiting for lock on /run/network/ifstate.eth0 Nov 27 18:08:21 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:08:21 volumio sh[10122]: eth0: carrier lost Nov 27 18:08:21 volumio dhcpcd[10142]: eth0: carrier lost Nov 27 18:08:21 volumio volumio[9852]: Invalid card number. Nov 27 18:08:21 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Nov 27 18:08:21 volumio sh[10122]: eth0: removing interface Nov 27 18:08:21 volumio dhcpcd[10142]: eth0: removing interface Nov 27 18:08:21 volumio volumio[9852]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 27 18:08:21 volumio volumio[9852]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 27 18:08:21 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 27 18:08:21 volumio volumio[9852]: info: Disabling external Volume Control Nov 27 18:08:21 volumio volumio[9852]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 18:08:21 volumio volumio[9852]: info: Preparing to generate the ALSA configuration file Nov 27 18:08:21 volumio sh[10122]: dhcpcd exited Nov 27 18:08:21 volumio dhcpcd[10142]: dhcpcd exited Nov 27 18:08:21 volumio sh[10122]: ifup: failed to bring up eth0 Nov 27 18:08:21 volumio ifdown[10153]: ifdown: interface eth0 not configured Nov 27 18:08:21 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 27 18:08:21 volumio kernel: usb 1-1: new high-speed USB device number 99 using dwc_otg Nov 27 18:08:21 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Nov 27 18:08:21 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 27 18:08:21 volumio systemd[1]: Stopped ifup for eth0. Nov 27 18:08:21 volumio volumio[9852]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 27 18:08:21 volumio volumio[9852]: info: Completed loading Core Plugins Nov 27 18:08:21 volumio volumio[9852]: info: Discovery: adding 510e3eae-56c4-4ded-ab5e-7d0d103ffa4f Nov 27 18:08:21 volumio volumio[9852]: info: Discovery: Found device Volumio Nov 27 18:08:21 volumio volumio[9852]: info: CoreCommandRouter::volumioGetState Nov 27 18:08:22 volumio volumio[9852]: info: CorePlayQueue::getTrack 0 Nov 27 18:08:22 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Nov 27 18:08:22 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:08:22 volumio kernel: hub 1-1:1.0: USB hub found Nov 27 18:08:22 volumio kernel: hub 1-1:1.0: 5 ports detected Nov 27 18:08:22 volumio volumio[9852]: info: VolumeController:: Volume=undefined Mute =false Nov 27 18:08:22 volumio volumio[9852]: info: CoreStateMachine::pushState Nov 27 18:08:22 volumio volumio[9852]: info: CorePlayQueue::getTrack 0 Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::volumioPushState Nov 27 18:08:22 volumio volumio[9852]: Starting albumart workers Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:08:22 volumio kernel: usb 1-1.1: new high-speed USB device number 100 using dwc_otg Nov 27 18:08:22 volumio volumio[9852]: Invalid card number. Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 27 18:08:22 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Nov 27 18:08:22 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Nov 27 18:08:22 volumio volumio[9852]: info: Asound.conf file unchanged, so no further update is needed Nov 27 18:08:22 volumio volumio[9852]: info: Output device has changed, restarting MPD Nov 27 18:08:22 volumio kernel: smsc95xx v2.0.0 Nov 27 18:08:22 volumio volumio[9852]: info: Output device has changed, restarting Shairport Sync Nov 27 18:08:22 volumio ifplugd(eth0)[834]: Link beat lost. Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:22 volumio sudo[10222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 18:08:22 volumio sudo[10222]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:22 volumio volumio[9852]: Starting albumart workers Nov 27 18:08:22 volumio kernel: SMSC LAN8700 usb-001:100:01: attached PHY driver (mii_bus:phy_addr=usb-001:100:01, irq=199) Nov 27 18:08:22 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1c:bd:5f Nov 27 18:08:22 volumio sudo[10225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 18:08:22 volumio sudo[10225]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:22 volumio sudo[10222]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:22 volumio systemd[1]: musicservicesshield.service: Succeeded. Nov 27 18:08:22 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 27 18:08:22 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 27 18:08:22 volumio systemd[1]: Stopping Music Player Daemon... Nov 27 18:08:22 volumio systemd[1]: mpd.service: Succeeded. Nov 27 18:08:22 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 18:08:22 volumio volumio[9852]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:08:22 volumio volumio[9852]: info: Preparing to generate the ALSA configuration file Nov 27 18:08:22 volumio volumio[9852]: info: Nov 27 18:08:22 volumio volumio[9852]: ---------------------------- USB Audio Device Detached Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Nov 27 18:08:22 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:08:22 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:08:23 volumio kernel: usb 1-1.3: new high-speed USB device number 101 using dwc_otg Nov 27 18:08:23 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Nov 27 18:08:23 volumio sudo[10232]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:08:23 volumio kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Nov 27 18:08:23 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Nov 27 18:08:23 volumio kernel: usb 1-1.3: Product: SMSL USB AUDIO Nov 27 18:08:23 volumio kernel: usb 1-1.3: Manufacturer: SMSL Nov 27 18:08:23 volumio sudo[10232]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:23 volumio sudo[10232]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:23 volumio systemd-udevd[9978]: Using default interface naming scheme 'v240'. Nov 27 18:08:23 volumio systemd-udevd[9978]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 27 18:08:23 volumio volumio[9852]: info: MPD Permissions set Nov 27 18:08:23 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:23 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:23 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:23 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:23 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:23 volumio kernel: usb 1-1.3: 1:3 : unsupported format bits 0x100000000 Nov 27 18:08:23 volumio kernel: hid-generic 0003:152A:85DD.0022: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-3f980000.usb-1.3/input3 Nov 27 18:08:23 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 18:08:23 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Nov 27 18:08:23 volumio systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Nov 27 18:08:23 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:23 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:23 volumio systemd[1]: Started ifup for eth0. Nov 27 18:08:24 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Nov 27 18:08:24 volumio sh[10251]: command failed: No such device (-19) Nov 27 18:08:24 volumio sh[10251]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:08:24 volumio sh[10251]: eth0: IAID eb:1c:bd:5f Nov 27 18:08:24 volumio sh[10251]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:08:24 volumio sh[10251]: ipv6_addaddr1: Permission denied Nov 27 18:08:24 volumio dhcpcd[10278]: DUID 00:01:00:01:2d:bb:b4:8a:b8:27:eb:1c:bd:5f Nov 27 18:08:24 volumio dhcpcd[10278]: eth0: IAID eb:1c:bd:5f Nov 27 18:08:24 volumio dhcpcd[10278]: eth0: adding address fe80::ba27:ebff:fe1c:bd5f Nov 27 18:08:24 volumio dhcpcd[10278]: ipv6_addaddr1: Permission denied Nov 27 18:08:24 volumio volumio[9852]: info: Asound.conf file unchanged, so no further update is needed Nov 27 18:08:24 volumio volumio[9852]: info: Output device has changed, restarting MPD Nov 27 18:08:24 volumio sudo[10290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 27 18:08:24 volumio sudo[10290]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:24 volumio sudo[10290]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:24 volumio sudo[10293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 27 18:08:24 volumio sudo[10293]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:24 volumio volumio[9852]: info: Output device has changed, restarting Shairport Sync Nov 27 18:08:24 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:24 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:24 volumio sudo[10293]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:24 volumio sudo[10300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 18:08:24 volumio sudo[10300]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:24 volumio sudo[10304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 18:08:24 volumio sudo[10304]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:24 volumio ifplugd(eth0)[834]: Link beat detected. Nov 27 18:08:24 volumio sudo[10300]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:24 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 27 18:08:24 volumio systemd[1]: mpd.service: Succeeded. Nov 27 18:08:24 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 18:08:24 volumio volumio[9852]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:08:25 volumio sh[10251]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:08:25 volumio volumio[9852]: info: ___________ START PLUGINS ___________ Nov 27 18:08:25 volumio dhcpcd[10278]: eth0: rebinding lease of 192.168.1.28 Nov 27 18:08:25 volumio volumio[9852]: info: ControllerMpd::onStart: Initializing MPD Nov 27 18:08:25 volumio volumio[9852]: info: Creating MPD Configuration file Nov 27 18:08:25 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:08:25 volumio sh[10251]: eth0: probing address 192.168.1.28/24 Nov 27 18:08:25 volumio dhcpcd[10278]: eth0: probing address 192.168.1.28/24 Nov 27 18:08:25 volumio sh[10251]: eth0: soliciting an IPv6 router Nov 27 18:08:25 volumio dhcpcd[10278]: eth0: soliciting an IPv6 router Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:08:25 volumio volumio[9852]: info: [1732727305311] CoreMusicLibrary::Adding element Медиа-серверы Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:08:25 volumio sudo[10315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 18:08:25 volumio sudo[10315]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:25 volumio sudo[10311]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:08:25 volumio sudo[10311]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:25 volumio sudo[10311]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:25 volumio sudo[10313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 18:08:25 volumio sudo[10313]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:25 volumio sudo[10313]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:25 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 27 18:08:25 volumio systemd[1]: mpd.service: Succeeded. Nov 27 18:08:25 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 18:08:25 volumio volumio[9852]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:25 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:08:25 volumio volumio[9852]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:08:25 volumio volumio[9852]: info: [1732727305857] CoreMusicLibrary::Adding element Last_100 Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 27 18:08:25 volumio volumio[9852]: info: [1732727305881] CoreMusicLibrary::Adding element Webradio Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 27 18:08:25 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:08:25 volumio volumio[9852]: info: Initializing BBC Radios Nov 27 18:08:26 volumio sudo[10323]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:08:26 volumio sudo[10323]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:26 volumio sudo[10323]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:26 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:08:26 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 18:08:26 volumio volumio[9852]: info: [squeezelite_mc] Starting proxy server... Nov 27 18:08:26 volumio volumio[9852]: info: Volumio Calling Home Nov 27 18:08:27 volumio sudo[10384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 27 18:08:27 volumio sudo[10384]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:27 volumio volumio[9852]: info: [squeezelite_mc] Proxy server started on port 41175 Nov 27 18:08:27 volumio ntpd[848]: Soliciting pool server 2a06:ac01:cafe:3084::51 Nov 27 18:08:27 volumio volumio[9852]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 18:08:27 volumio volumio[9852]: info: Volumio Network Manager: Network status updated: 0 Nov 27 18:08:27 volumio volumio[9852]: Unhandled rejection Error: No sockets available, cannot start. Nov 27 18:08:27 volumio volumio[9852]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 27 18:08:27 volumio volumio[9852]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 27 18:08:27 volumio volumio[9852]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 27 18:08:27 volumio volumio[9852]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 27 18:08:27 volumio volumio[9852]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 27 18:08:27 volumio volumio[9852]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 27 18:08:27 volumio volumio[9852]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 27 18:08:27 volumio volumio[9852]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 27 18:08:27 volumio volumio[9852]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 27 18:08:27 volumio volumio[9852]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 27 18:08:27 volumio volumio[9852]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 27 18:08:27 volumio volumio[9852]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 27 18:08:27 volumio volumio[9852]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 27 18:08:27 volumio volumio[9852]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 27 18:08:27 volumio volumio[9852]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 27 18:08:27 volumio volumio[9852]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 27 18:08:27 volumio volumio[9852]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 27 18:08:27 volumio volumio[9852]: info: CoreStateMachine::getcurrentVolume Nov 27 18:08:27 volumio volumio[9852]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 18:08:27 volumio volumio[9852]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 27 18:08:27 volumio volumio[9852]: info: MPD Permissions set Nov 27 18:08:27 volumio volumio[9852]: info: MPD Permissions set Nov 27 18:08:27 volumio volumio[9852]: info: Starting Shairport Sync Nov 27 18:08:27 volumio volumio[9852]: info: Nov 27 18:08:27 volumio volumio[9852]: ---------------------------- USB Audio Device Attached Nov 27 18:08:27 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 27 18:08:27 volumio sudo[10404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 18:08:27 volumio sudo[10404]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 27 18:08:28 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 27 18:08:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 27 18:08:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 27 18:08:28 volumio sudo[10404]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:28 volumio volumio[9852]: info: CoreCommandRouter::Close All Modals sent Nov 27 18:08:28 volumio volumio[9852]: info: Preparing to save Alsa Options, stopping services first Nov 27 18:08:28 volumio volumio[9852]: info: CoreCommandRouter::volumioGetState Nov 27 18:08:28 volumio volumio[9852]: info: CorePlayQueue::getTrack 0 Nov 27 18:08:28 volumio volumio[9852]: info: CoreCommandRouter::volumioPause Nov 27 18:08:28 volumio volumio[9852]: info: CoreStateMachine::pause Nov 27 18:08:28 volumio volumio[9852]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Nov 27 18:08:28 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:08:28 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 27 18:08:28 volumio systemd[1]: mpd.service: Failed with result 'protocol'. Nov 27 18:08:28 volumio systemd[1]: Failed to start Music Player Daemon. Nov 27 18:08:28 volumio sudo[10304]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:28 volumio sudo[10315]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:28 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 18:08:28 volumio sudo[10225]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:28 volumio volumio[9852]: info: Device 5 does not have any Mixer Control Available Nov 27 18:08:28 volumio systemd[1]: Starting Music Player Daemon... Nov 27 18:08:28 volumio volumio[9852]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 27 18:08:28 volumio volumio[9852]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 27 18:08:28 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 27 18:08:28 volumio volumio[9852]: info: Disabling external Volume Control Nov 27 18:08:28 volumio volumio[9852]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 18:08:28 volumio volumio[9852]: info: CoreStateMachine::pushState Nov 27 18:08:28 volumio volumio[9852]: info: CorePlayQueue::getTrack 0 Nov 27 18:08:28 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:08:28 volumio volumio[9852]: info: CoreCommandRouter::volumioPushState Nov 27 18:08:28 volumio volumio[9852]: info: Preparing to generate the ALSA configuration file Nov 27 18:08:28 volumio sudo[10384]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:28 volumio volumio[9852]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 27 18:08:28 volumio sudo[10422]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 18:08:28 volumio sudo[10422]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:29 volumio sudo[10422]: pam_unix(sudo:session): session closed for user root Nov 27 18:08:29 volumio volumio[9852]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 27 18:08:29 volumio volumio[9852]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 27 18:08:29 volumio volumio[9852]: info: Shairport-Sync Started Nov 27 18:08:29 volumio volumio[9852]: Error adding Membership: Error: addMembership EINVAL Nov 27 18:08:29 volumio volumio[9852]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Nov 27 18:08:29 volumio volumio[9852]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Nov 27 18:08:29 volumio volumio[9852]: See "systemctl status mpd.service" and "journalctl -xe" for details. Nov 27 18:08:29 volumio volumio[9852]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Nov 27 18:08:29 volumio volumio[9852]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Nov 27 18:08:29 volumio volumio[9852]: See "systemctl status mpd.service" and "journalctl -xe" for details. Nov 27 18:08:29 volumio volumio[9852]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Nov 27 18:08:29 volumio volumio[9852]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Nov 27 18:08:29 volumio volumio[9852]: See "systemctl status mpd.service" and "journalctl -xe" for details. Nov 27 18:08:29 volumio volumio[9852]: info: VolumeController:: Volume=undefined Mute =false Nov 27 18:08:29 volumio volumio[9852]: info: CoreStateMachine::pushState Nov 27 18:08:29 volumio volumio[9852]: info: CorePlayQueue::getTrack 0 Nov 27 18:08:29 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:08:29 volumio volumio[9852]: info: CoreCommandRouter::volumioPushState Nov 27 18:08:29 volumio volumio[9852]: info: [squeezelite_mc] Server discovery started Nov 27 18:08:29 volumio volumio[9852]: info: [squeezelite_mc] Player finder started Nov 27 18:08:29 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:29 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 18:08:29 volumio volumio[9852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 18:08:29 volumio volumio[9852]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 27 18:08:29 volumio volumio[9852]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 18:08:29 volumio volumio[9852]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 27 18:08:29 volumio volumio[9852]: at doSend (dgram.js:692:16) Nov 27 18:08:29 volumio volumio[9852]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 27 18:08:29 volumio volumio[9852]: at afterDns (dgram.js:638:5) Nov 27 18:08:29 volumio volumio[9852]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 27 18:08:29 volumio volumio[9852]: errno: -101, Nov 27 18:08:29 volumio volumio[9852]: code: 'ENETUNREACH', Nov 27 18:08:29 volumio volumio[9852]: syscall: 'send', Nov 27 18:08:29 volumio volumio[9852]: address: '255.255.255.255', Nov 27 18:08:29 volumio volumio[9852]: port: 3483 Nov 27 18:08:29 volumio volumio[9852]: } Nov 27 18:08:29 volumio volumio[9852]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 18:08:29 volumio sudo[10436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Nov 27 18:08:29 volumio sudo[10436]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:30 volumio sh[10251]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:08:30 volumio dhcpcd[10278]: eth0: leased 192.168.1.28 for 86400 seconds Nov 27 18:08:30 volumio sh[10251]: eth0: adding route to 192.168.1.0/24 Nov 27 18:08:30 volumio dhcpcd[10278]: eth0: adding route to 192.168.1.0/24 Nov 27 18:08:30 volumio avahi-daemon[534]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Nov 27 18:08:30 volumio avahi-daemon[534]: New relevant interface eth0.IPv4 for mDNS. Nov 27 18:08:30 volumio avahi-daemon[534]: Registering new address record for 192.168.1.28 on eth0.IPv4. Nov 27 18:08:30 volumio sh[10251]: eth0: adding default route via 192.168.1.254 Nov 27 18:08:30 volumio dhcpcd[10278]: eth0: adding default route via 192.168.1.254 Nov 27 18:08:30 volumio sh[10251]: forked to background, child pid 10472 Nov 27 18:08:30 volumio dhcpcd[10278]: forked to background, child pid 10472 Nov 27 18:08:31 volumio sudo[10495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 18:07 Nov 27 18:08:31 volumio sudo[10495]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:08:31 volumio sh[10251]: eth0=eth0 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"