-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Sun 2024-07-14 05:32:25 JST. -- Jul 14 05:31:00 volumio212 sh[16516]: eth0: carrier acquired Jul 14 05:31:00 volumio212 dhcpcd[16532]: eth0: carrier acquired Jul 14 05:31:00 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 14 05:31:00 volumio212 sh[16516]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:00 volumio212 sh[16516]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:00 volumio212 sh[16516]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:00 volumio212 dhcpcd[16532]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:00 volumio212 sh[16516]: ipv6_addaddr1: Permission denied Jul 14 05:31:00 volumio212 dhcpcd[16532]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:00 volumio212 dhcpcd[16532]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:00 volumio212 dhcpcd[16532]: ipv6_addaddr1: Permission denied Jul 14 05:31:00 volumio212 sh[16516]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:00 volumio212 dhcpcd[16532]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:00 volumio212 sh[16516]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:00 volumio212 dhcpcd[16532]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:00 volumio212 ifplugd(eth0)[683]: Link beat detected. Jul 14 05:31:00 volumio212 sh[16516]: eth0: soliciting an IPv6 router Jul 14 05:31:00 volumio212 dhcpcd[16532]: eth0: soliciting an IPv6 router Jul 14 05:31:00 volumio212 kernel: Jul 14 05:31:00 volumio212 kernel: ERROR::dwc_otg_hcd_urb_enqueue:501: Not connected Jul 14 05:31:00 volumio212 volumio[16477]: info: ------------------------------------------- Jul 14 05:31:00 volumio212 volumio[16477]: info: ----- Volumio3 ---- Jul 14 05:31:00 volumio212 volumio[16477]: info: ------------------------------------------- Jul 14 05:31:00 volumio212 volumio[16477]: info: ----- System startup ---- Jul 14 05:31:00 volumio212 volumio[16477]: info: ------------------------------------------- Jul 14 05:31:01 volumio212 sh[16516]: eth0: carrier lost Jul 14 05:31:01 volumio212 dhcpcd[16532]: eth0: carrier lost Jul 14 05:31:01 volumio212 kernel: usb 1-1: USB disconnect, device number 86 Jul 14 05:31:01 volumio212 kernel: usb 1-1.1: USB disconnect, device number 87 Jul 14 05:31:01 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Jul 14 05:31:01 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:01 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:01 volumio212 systemd[1]: Stopping ifup for eth0... Jul 14 05:31:01 volumio212 ifdown[16560]: ifdown: waiting for lock on /run/network/ifstate.eth0 Jul 14 05:31:01 volumio212 sh[16516]: eth0: removing interface Jul 14 05:31:01 volumio212 dhcpcd[16532]: eth0: removing interface Jul 14 05:31:01 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:31:01 volumio212 sh[16516]: dhcpcd exited Jul 14 05:31:01 volumio212 dhcpcd[16532]: dhcpcd exited Jul 14 05:31:01 volumio212 sh[16516]: ifup: failed to bring up eth0 Jul 14 05:31:01 volumio212 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 14 05:31:01 volumio212 ifdown[16560]: ifdown: interface eth0 not configured Jul 14 05:31:01 volumio212 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 14 05:31:01 volumio212 systemd[1]: Stopped ifup for eth0. Jul 14 05:31:01 volumio212 kernel: usb 1-1: new high-speed USB device number 88 using dwc_otg Jul 14 05:31:01 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:31:01 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:31:01 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:01 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:31:01 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:31:01 volumio212 ifplugd(eth0)[683]: Link beat lost. Jul 14 05:31:02 volumio212 kernel: usb 1-1.1: new high-speed USB device number 89 using dwc_otg Jul 14 05:31:02 volumio212 volumio-remote-updater[523]: [2024-07-14 05:31:02] [connect] Successful connection Jul 14 05:31:02 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:31:02 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:02 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:31:02 volumio212 volumio[16477]: info: MYVOLUMIO Environment detected Jul 14 05:31:02 volumio212 kernel: SMSC LAN8700 usb-001:089:01: attached PHY driver (mii_bus:phy_addr=usb-001:089:01, irq=199) Jul 14 05:31:02 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:3a:76 Jul 14 05:31:02 volumio212 systemd-udevd[16346]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 14 05:31:02 volumio212 volumio[16477]: info: Plugin folders cleanup Jul 14 05:31:02 volumio212 volumio[16477]: info: Scanning into folder /volumio/app/plugins/ Jul 14 05:31:02 volumio212 volumio[16477]: info: Scanning category audio_interface Jul 14 05:31:02 volumio212 volumio[16477]: info: Scanning category miscellanea Jul 14 05:31:02 volumio212 volumio[16477]: info: Scanning category music_service Jul 14 05:31:02 volumio212 volumio[16477]: info: Scanning category plugins.json Jul 14 05:31:02 volumio212 volumio[16477]: info: Scanning category system_controller Jul 14 05:31:02 volumio212 volumio[16477]: info: Scanning category user_interface Jul 14 05:31:02 volumio212 volumio[16477]: info: Scanning into folder /data/plugins/ Jul 14 05:31:02 volumio212 volumio[16477]: info: Scanning category music_service Jul 14 05:31:02 volumio212 volumio[16477]: info: Plugin folders cleanup completed Jul 14 05:31:02 volumio212 volumio[16477]: info: ------------------------------------------- Jul 14 05:31:02 volumio212 volumio[16477]: info: ----- Core plugins startup ---- Jul 14 05:31:02 volumio212 volumio[16477]: info: ------------------------------------------- Jul 14 05:31:02 volumio212 volumio[16477]: info: Loading plugins from folder /volumio/app/plugins/ Jul 14 05:31:02 volumio212 volumio[16477]: info: Adding plugin upnp to MyMusic Plugins Jul 14 05:31:02 volumio212 volumio[16477]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 14 05:31:02 volumio212 volumio[16477]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 14 05:31:02 volumio212 volumio[16477]: info: Loading plugins from folder /data/plugins/ Jul 14 05:31:02 volumio212 volumio[16477]: info: Loading plugin "system"... Jul 14 05:31:02 volumio212 volumio[16477]: info: Loading plugin "appearance"... Jul 14 05:31:02 volumio212 systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jul 14 05:31:02 volumio212 systemd[1]: Started ifup for eth0. Jul 14 05:31:02 volumio212 sh[16630]: eth0: waiting for carrier Jul 14 05:31:02 volumio212 dhcpcd[16646]: eth0: waiting for carrier Jul 14 05:31:02 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:02 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:03 volumio212 sh[16630]: eth0: carrier acquired Jul 14 05:31:03 volumio212 dhcpcd[16646]: eth0: carrier acquired Jul 14 05:31:03 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 14 05:31:03 volumio212 sh[16630]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:03 volumio212 sh[16630]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:03 volumio212 sh[16630]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:03 volumio212 dhcpcd[16646]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:03 volumio212 sh[16630]: ipv6_addaddr1: Permission denied Jul 14 05:31:03 volumio212 dhcpcd[16646]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:03 volumio212 dhcpcd[16646]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:03 volumio212 dhcpcd[16646]: ipv6_addaddr1: Permission denied Jul 14 05:31:04 volumio212 ifplugd(eth0)[683]: Link beat detected. Jul 14 05:31:04 volumio212 sh[16630]: eth0: soliciting an IPv6 router Jul 14 05:31:04 volumio212 dhcpcd[16646]: eth0: soliciting an IPv6 router Jul 14 05:31:04 volumio212 volumio[16477]: info: Loading plugin "network"... Jul 14 05:31:04 volumio212 volumio[16477]: info: Refreshing Cached IP Addresses Jul 14 05:31:04 volumio212 sudo[16660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 14 05:31:04 volumio212 sudo[16660]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:04 volumio212 sudo[16660]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:04 volumio212 volumio[16477]: info: Loading plugin "services"... Jul 14 05:31:04 volumio212 sudo[16662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 14 05:31:04 volumio212 sudo[16662]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:04 volumio212 volumio[16477]: info: Loading plugin "alsa_controller"... Jul 14 05:31:04 volumio212 sudo[16662]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:04 volumio212 sudo[16665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 14 05:31:04 volumio212 sudo[16665]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:04 volumio212 sh[16630]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:04 volumio212 dhcpcd[16646]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:04 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 14 05:31:04 volumio212 volumio[16477]: info: Loading plugin "wizard"... Jul 14 05:31:04 volumio212 volumio[16477]: info: Loading plugin "networkfs"... Jul 14 05:31:04 volumio212 sh[16630]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:04 volumio212 dhcpcd[16646]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:04 volumio212 volumio[16477]: info: Cannot mount NAS ks at system boot, trial number 1 ,retrying in 5 seconds Jul 14 05:31:04 volumio212 volumio[16477]: info: Starting Udev Watcher for removable devices Jul 14 05:31:05 volumio212 volumio[16477]: info: Ignoring mount for partition: boot Jul 14 05:31:05 volumio212 volumio[16477]: info: Ignoring mount for partition: volumio Jul 14 05:31:05 volumio212 volumio[16477]: info: Ignoring mount for partition: volumio_data Jul 14 05:31:05 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 14 05:31:05 volumio212 volumio[16477]: info: Loading plugin "volumio_command_line_client"... Jul 14 05:31:05 volumio212 volumio[16477]: info: Loading plugin "upnp"... Jul 14 05:31:05 volumio212 volumio[16477]: info: [1720902665068] Starting Upmpd Daemon Jul 14 05:31:05 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 14 05:31:05 volumio212 volumio[16477]: info: Loading plugin "my_music"... Jul 14 05:31:05 volumio212 volumio[16477]: info: Loading plugin "mpd"... Jul 14 05:31:05 volumio212 sudo[16665]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:05 volumio212 volumio[16477]: info: Loading plugin "upnp_browser"... Jul 14 05:31:06 volumio212 volumio[16477]: info: Loading plugin "alarm-clock"... Jul 14 05:31:06 volumio212 volumio[16477]: info: Loading plugin "airplay_emulation"... Jul 14 05:31:06 volumio212 volumio[16477]: info: Starting Shairport Sync Jul 14 05:31:06 volumio212 volumio[16477]: info: Loading plugin "last_100"... Jul 14 05:31:06 volumio212 volumio[16477]: info: Loading plugin "webradio"... Jul 14 05:31:06 volumio212 sh[16630]: eth0: carrier lost Jul 14 05:31:06 volumio212 dhcpcd[16646]: eth0: carrier lost Jul 14 05:31:06 volumio212 kernel: usb 1-1: USB disconnect, device number 88 Jul 14 05:31:06 volumio212 kernel: usb 1-1.1: USB disconnect, device number 89 Jul 14 05:31:06 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Jul 14 05:31:06 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:06 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:06 volumio212 volumio[16477]: info: Loading plugin "i2s_dacs"... Jul 14 05:31:06 volumio212 volumio[16477]: info: Loading plugin "volumiodiscovery"... Jul 14 05:31:06 volumio212 systemd[1]: Stopping ifup for eth0... Jul 14 05:31:06 volumio212 ifdown[16683]: ifdown: waiting for lock on /run/network/ifstate.eth0 Jul 14 05:31:07 volumio212 volumio[16477]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 14 05:31:07 volumio212 volumio[16477]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 05:31:07 volumio212 volumio[16477]: *** WARNING *** For more information see Jul 14 05:31:07 volumio212 node[16477]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 14 05:31:07 volumio212 volumio[16477]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 14 05:31:07 volumio212 volumio[16477]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 05:31:07 volumio212 volumio[16477]: *** WARNING *** For more information see Jul 14 05:31:07 volumio212 node[16477]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 05:31:07 volumio212 node[16477]: *** WARNING *** For more information see Jul 14 05:31:07 volumio212 node[16477]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 14 05:31:07 volumio212 node[16477]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 05:31:07 volumio212 node[16477]: *** WARNING *** For more information see Jul 14 05:31:07 volumio212 volumio[16477]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 14 05:31:07 volumio212 volumio[16477]: info: Discovery: Started advertising with name: Volumio212 Jul 14 05:31:07 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 14 05:31:07 volumio212 volumio[16477]: info: Loading plugin "squeezelite_mc"... Jul 14 05:31:07 volumio212 sh[16630]: eth0: removing interface Jul 14 05:31:07 volumio212 dhcpcd[16646]: eth0: removing interface Jul 14 05:31:07 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:31:07 volumio212 sh[16630]: dhcpcd exited Jul 14 05:31:07 volumio212 dhcpcd[16646]: dhcpcd exited Jul 14 05:31:07 volumio212 sh[16630]: ifup: failed to bring up eth0 Jul 14 05:31:07 volumio212 ifdown[16683]: ifdown: interface eth0 not configured Jul 14 05:31:07 volumio212 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 14 05:31:07 volumio212 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 14 05:31:07 volumio212 systemd[1]: Stopped ifup for eth0. Jul 14 05:31:07 volumio212 kernel: usb 1-1: new high-speed USB device number 90 using dwc_otg Jul 14 05:31:07 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:31:07 volumio212 ifplugd(eth0)[683]: Link beat lost. Jul 14 05:31:07 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:31:07 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:07 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:31:07 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:31:07 volumio212 kernel: usb 1-1.1: new high-speed USB device number 91 using dwc_otg Jul 14 05:31:08 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:31:08 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:08 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:31:08 volumio212 systemd-udevd[16689]: Using default interface naming scheme 'v240'. Jul 14 05:31:08 volumio212 kernel: SMSC LAN8700 usb-001:091:01: attached PHY driver (mii_bus:phy_addr=usb-001:091:01, irq=199) Jul 14 05:31:08 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:3a:76 Jul 14 05:31:08 volumio212 systemd-udevd[16689]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 14 05:31:08 volumio212 systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jul 14 05:31:08 volumio212 systemd[1]: Started ifup for eth0. Jul 14 05:31:08 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:08 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:08 volumio212 sh[16748]: eth0: waiting for carrier Jul 14 05:31:08 volumio212 dhcpcd[16764]: eth0: waiting for carrier Jul 14 05:31:08 volumio212 volumio[16477]: info: Loading plugin "youtube2"... Jul 14 05:31:09 volumio212 sh[16748]: eth0: carrier acquired Jul 14 05:31:09 volumio212 dhcpcd[16764]: eth0: carrier acquired Jul 14 05:31:09 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 14 05:31:09 volumio212 sh[16748]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:09 volumio212 sh[16748]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:09 volumio212 sh[16748]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:09 volumio212 dhcpcd[16764]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:09 volumio212 sh[16748]: ipv6_addaddr1: Permission denied Jul 14 05:31:09 volumio212 dhcpcd[16764]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:09 volumio212 dhcpcd[16764]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:09 volumio212 dhcpcd[16764]: ipv6_addaddr1: Permission denied Jul 14 05:31:09 volumio212 sh[16748]: eth0: soliciting an IPv6 router Jul 14 05:31:09 volumio212 dhcpcd[16764]: eth0: soliciting an IPv6 router Jul 14 05:31:09 volumio212 sh[16748]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:09 volumio212 dhcpcd[16764]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:09 volumio212 sh[16748]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:09 volumio212 dhcpcd[16764]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:10 volumio212 ifplugd(eth0)[683]: Link beat detected. Jul 14 05:31:10 volumio212 kernel: Jul 14 05:31:10 volumio212 kernel: ERROR::dwc_otg_hcd_urb_enqueue:501: Not connected Jul 14 05:31:11 volumio212 sh[16748]: eth0: carrier lost Jul 14 05:31:11 volumio212 dhcpcd[16764]: eth0: carrier lost Jul 14 05:31:11 volumio212 kernel: usb 1-1: USB disconnect, device number 90 Jul 14 05:31:11 volumio212 kernel: usb 1-1.1: USB disconnect, device number 91 Jul 14 05:31:11 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Jul 14 05:31:11 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:11 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:11 volumio212 volumio[16477]: info: Loading plugin "outputs"... Jul 14 05:31:11 volumio212 systemd[1]: Stopping ifup for eth0... Jul 14 05:31:11 volumio212 volumio[16477]: info: Loading plugin "albumart"... Jul 14 05:31:11 volumio212 ifdown[16785]: ifdown: waiting for lock on /run/network/ifstate.eth0 Jul 14 05:31:11 volumio212 volumio[16477]: info: Plugin example_plugin is not enabled Jul 14 05:31:11 volumio212 volumio[16477]: info: Loading plugin "inputs"... Jul 14 05:31:11 volumio212 volumio[16477]: info: Loading plugin "updater_comm"... Jul 14 05:31:11 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:31:11 volumio212 sh[16748]: eth0: removing interface Jul 14 05:31:11 volumio212 dhcpcd[16764]: eth0: removing interface Jul 14 05:31:11 volumio212 sh[16748]: dhcpcd exited Jul 14 05:31:11 volumio212 dhcpcd[16764]: dhcpcd exited Jul 14 05:31:11 volumio212 sh[16748]: ifup: failed to bring up eth0 Jul 14 05:31:11 volumio212 ifdown[16785]: ifdown: interface eth0 not configured Jul 14 05:31:11 volumio212 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 14 05:31:11 volumio212 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 14 05:31:11 volumio212 systemd[1]: Stopped ifup for eth0. Jul 14 05:31:11 volumio212 volumio[16477]: info: Plugin mpdemulation is not enabled Jul 14 05:31:11 volumio212 volumio[16477]: info: Loading plugin "rest_api"... Jul 14 05:31:11 volumio212 kernel: usb 1-1: new high-speed USB device number 92 using dwc_otg Jul 14 05:31:11 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:31:11 volumio212 volumio[16477]: info: Loading plugin "websocket"... Jul 14 05:31:11 volumio212 volumio[16477]: info: Loading i18n strings for locale ja Jul 14 05:31:11 volumio212 volumio[16477]: Updating browse sources language Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 05:31:11 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:31:11 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:11 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:31:11 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::initPlayerControls Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:11 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 05:31:11 volumio212 ifplugd(eth0)[683]: Link beat lost. Jul 14 05:31:12 volumio212 volumio[16477]: Express server listening on port 3000 Jul 14 05:31:12 volumio212 volumio[16477]: [Metrics] WebUI: 12s 121.90ms Jul 14 05:31:12 volumio212 volumio[16477]: Forking 3 albumart workers Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreStateMachine::resetVolumioState Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreStateMachine::getcurrentVolume Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreCommandRouter::volumioRetrievevolume Jul 14 05:31:12 volumio212 kernel: usb 1-1.1: new high-speed USB device number 93 using dwc_otg Jul 14 05:31:12 volumio212 volumio[16477]: info: Cannot mount NAS ks at system boot, trial number 2 ,retrying in 5 seconds Jul 14 05:31:12 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:31:12 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:12 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:31:12 volumio212 kernel: usb 1-1: USB disconnect, device number 92 Jul 14 05:31:12 volumio212 kernel: SMSC LAN8700 usb-001:093:01: attached PHY driver (mii_bus:phy_addr=usb-001:093:01, irq=199) Jul 14 05:31:12 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: set_features() failed (-19); wanted 0x0000010000004002, left 0x0000010000004802 Jul 14 05:31:12 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:3a:76 Jul 14 05:31:12 volumio212 kernel: usb 1-1.1: USB disconnect, device number 93 Jul 14 05:31:12 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Jul 14 05:31:12 volumio212 systemd-udevd[16689]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 14 05:31:12 volumio212 systemd-udevd[16689]: link_config: could not get ethtool features for eth0 Jul 14 05:31:12 volumio212 systemd-udevd[16689]: Could not set offload features of eth0: No such device Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 05:31:12 volumio212 volumio[16477]: info: Reloading queue from file Jul 14 05:31:12 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreStateMachine::setRepeat null single undefined Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreStateMachine::pushState Jul 14 05:31:12 volumio212 volumio[16477]: info: CorePlayQueue::getTrack 0 Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreCommandRouter::volumioPushState Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreStateMachine::setRandom null Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreStateMachine::pushState Jul 14 05:31:12 volumio212 volumio[16477]: info: CorePlayQueue::getTrack 0 Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreCommandRouter::volumioPushState Jul 14 05:31:12 volumio212 volumio[16477]: info: Setting Device type: Raspberry PI Jul 14 05:31:12 volumio212 volumio[16477]: info: VolumeController:: Volume=100 Mute =false Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreStateMachine::pushState Jul 14 05:31:12 volumio212 volumio[16477]: info: CorePlayQueue::getTrack 0 Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreCommandRouter::volumioPushState Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreStateMachine::updateTrackBlock Jul 14 05:31:12 volumio212 volumio[16477]: info: CorePlayQueue::getTrackBlock Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreCommandRouter::volumioRetrievevolume Jul 14 05:31:12 volumio212 systemd[1]: Found device /sys/subsystem/net/devices/eth0. Jul 14 05:31:12 volumio212 systemd[1]: ifup@eth0.service: Bound to unit sys-subsystem-net-devices-eth0.device, but unit isn't active. Jul 14 05:31:12 volumio212 systemd[1]: Dependency failed for ifup for eth0. Jul 14 05:31:12 volumio212 systemd[1]: ifup@eth0.service: Job ifup@eth0.service/start failed with result 'dependency'. Jul 14 05:31:12 volumio212 volumio[16477]: info: Discovery: adding 02a57288-0b93-4a22-aad5-3158a5e574f3 Jul 14 05:31:12 volumio212 volumio[16477]: info: Discovery: Found device Volumio212 Jul 14 05:31:12 volumio212 volumio[16477]: info: CoreCommandRouter::volumioGetState Jul 14 05:31:12 volumio212 volumio[16477]: info: CorePlayQueue::getTrack 0 Jul 14 05:31:12 volumio212 kernel: usb 1-1: new high-speed USB device number 94 using dwc_otg Jul 14 05:31:12 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:31:13 volumio212 volumio[16477]: info: VolumeController:: Volume=100 Mute =false Jul 14 05:31:13 volumio212 volumio[16477]: info: CoreStateMachine::pushState Jul 14 05:31:13 volumio212 volumio[16477]: info: CorePlayQueue::getTrack 0 Jul 14 05:31:13 volumio212 volumio[16477]: info: CoreCommandRouter::volumioPushState Jul 14 05:31:13 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:31:13 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:13 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:31:13 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:31:13 volumio212 kernel: usb 1-1.1: new high-speed USB device number 95 using dwc_otg Jul 14 05:31:13 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:31:13 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:13 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:31:13 volumio212 kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Jul 14 05:31:13 volumio212 kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Jul 14 05:31:13 volumio212 kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Jul 14 05:31:13 volumio212 kernel: smsc95xx 1-1.1:1.0 (unnamed net_device) (uninitialized): Failed to read reg index 0x00000014: -71 Jul 14 05:31:13 volumio212 kernel: smsc95xx: probe of 1-1.1:1.0 failed with error -71 Jul 14 05:31:13 volumio212 kernel: usb 1-1: USB disconnect, device number 94 Jul 14 05:31:13 volumio212 kernel: usb 1-1.1: USB disconnect, device number 95 Jul 14 05:31:13 volumio212 volumio[16477]: Starting albumart workers Jul 14 05:31:14 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:31:14 volumio212 volumio[16477]: Starting albumart workers Jul 14 05:31:14 volumio212 volumio[16477]: Starting albumart workers Jul 14 05:31:14 volumio212 kernel: usb 1-1: new high-speed USB device number 96 using dwc_otg Jul 14 05:31:14 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:31:14 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:31:14 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:14 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:31:14 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:31:14 volumio212 volumio-remote-updater[523]: [2024-07-14 05:31:14] [connect] Successful connection Jul 14 05:31:14 volumio212 volumio-remote-updater[523]: [2024-07-14 05:31:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720902674 101 Jul 14 05:31:14 volumio212 kernel: usb 1-1.1: new high-speed USB device number 97 using dwc_otg Jul 14 05:31:14 volumio212 volumio[16477]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 14 05:31:14 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:31:14 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:14 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:31:15 volumio212 kernel: SMSC LAN8700 usb-001:097:01: attached PHY driver (mii_bus:phy_addr=usb-001:097:01, irq=199) Jul 14 05:31:15 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:3a:76 Jul 14 05:31:15 volumio212 systemd-udevd[16689]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 14 05:31:15 volumio212 sudo[16947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 14 05:31:15 volumio212 sudo[16947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:15 volumio212 sudo[16947]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:15 volumio212 sudo[16953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 14 05:31:15 volumio212 sudo[16953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:15 volumio212 sudo[16953]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:15 volumio212 systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jul 14 05:31:15 volumio212 sudo[16966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 14 05:31:15 volumio212 sudo[16966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:15 volumio212 systemd[1]: Started ifup for eth0. Jul 14 05:31:15 volumio212 sudo[16966]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:15 volumio212 volumio[16477]: info: Upmpdcli Daemon Started Jul 14 05:31:15 volumio212 sh[16967]: eth0: waiting for carrier Jul 14 05:31:15 volumio212 dhcpcd[16996]: eth0: waiting for carrier Jul 14 05:31:15 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:15 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:16 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 14 05:31:16 volumio212 sh[16967]: eth0: carrier acquired Jul 14 05:31:16 volumio212 dhcpcd[16996]: eth0: carrier acquired Jul 14 05:31:16 volumio212 sh[16967]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:16 volumio212 sh[16967]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:16 volumio212 sh[16967]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:16 volumio212 dhcpcd[16996]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:16 volumio212 sh[16967]: ipv6_addaddr1: Permission denied Jul 14 05:31:16 volumio212 dhcpcd[16996]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:16 volumio212 dhcpcd[16996]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:16 volumio212 dhcpcd[16996]: ipv6_addaddr1: Permission denied Jul 14 05:31:16 volumio212 ifplugd(eth0)[683]: Link beat detected. Jul 14 05:31:17 volumio212 sh[16967]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:17 volumio212 dhcpcd[16996]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:17 volumio212 sh[16967]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:17 volumio212 dhcpcd[16996]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:17 volumio212 volumio[16477]: info: Cannot mount NAS ks at system boot, trial number 3 ,retrying in 5 seconds Jul 14 05:31:17 volumio212 sh[16967]: eth0: soliciting an IPv6 router Jul 14 05:31:17 volumio212 dhcpcd[16996]: eth0: soliciting an IPv6 router Jul 14 05:31:22 volumio212 sh[16967]: eth0: leased 192.168.1.212 for 86400 seconds Jul 14 05:31:22 volumio212 sh[16967]: eth0: adding route to 192.168.1.0/24 Jul 14 05:31:22 volumio212 sh[16967]: eth0: adding default route via 192.168.1.1 Jul 14 05:31:22 volumio212 dhcpcd[16996]: eth0: leased 192.168.1.212 for 86400 seconds Jul 14 05:31:22 volumio212 dhcpcd[16996]: eth0: adding route to 192.168.1.0/24 Jul 14 05:31:22 volumio212 dhcpcd[16996]: eth0: adding default route via 192.168.1.1 Jul 14 05:31:22 volumio212 avahi-daemon[530]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.212. Jul 14 05:31:22 volumio212 avahi-daemon[530]: New relevant interface eth0.IPv4 for mDNS. Jul 14 05:31:22 volumio212 avahi-daemon[530]: Registering new address record for 192.168.1.212 on eth0.IPv4. Jul 14 05:31:22 volumio212 volumio[16477]: info: Cannot mount NAS ks at system boot, trial number 4 ,retrying in 5 seconds Jul 14 05:31:22 volumio212 dnsmasq[818]: reading /etc/resolv.conf Jul 14 05:31:22 volumio212 dnsmasq[818]: using nameserver 119.29.29.29#53 Jul 14 05:31:22 volumio212 dnsmasq[818]: using nameserver 208.67.222.222#53 Jul 14 05:31:22 volumio212 dnsmasq[818]: using nameserver 208.67.220.220#53 Jul 14 05:31:22 volumio212 sh[16967]: forked to background, child pid 17032 Jul 14 05:31:22 volumio212 dhcpcd[16996]: forked to background, child pid 17032 Jul 14 05:31:22 volumio212 volumio[16477]: info: Discovery: adding d6f9068f-d19f-494d-abd8-145972f2c39e Jul 14 05:31:22 volumio212 volumio[16477]: info: Discovery: Found device Volumio21 Jul 14 05:31:22 volumio212 sh[16967]: eth0=eth0 Jul 14 05:31:22 volumio212 volumio[16477]: info: Discovery: Connecting to remote: 192.168.1.231 Jul 14 05:31:22 volumio212 volumio[16477]: info: Discovery: Connected to remote: 192.168.1.231 Jul 14 05:31:23 volumio212 volumio[16477]: info: Discovery: this is already registered, 02a57288-0b93-4a22-aad5-3158a5e574f3 Jul 14 05:31:23 volumio212 volumio[16477]: info: Discovery: Found device Volumio212 Jul 14 05:31:23 volumio212 volumio[16477]: info: CoreCommandRouter::volumioGetState Jul 14 05:31:23 volumio212 volumio[16477]: info: CorePlayQueue::getTrack 0 Jul 14 05:31:23 volumio212 ntpd[678]: Listen normally on 39 eth0 192.168.1.212:123 Jul 14 05:31:23 volumio212 ntpd[678]: new interface(s) found: waking up resolver Jul 14 05:31:25 volumio212 volumio[16477]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.114 UA: okhttp/4.9.2 Total Clients: 2 Jul 14 05:31:25 volumio212 volumio[16477]: info: CoreCommandRouter::volumioGetState Jul 14 05:31:25 volumio212 volumio[16477]: info: CorePlayQueue::getTrack 0 Jul 14 05:31:25 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 14 05:31:25 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 14 05:31:27 volumio212 volumio[16477]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jul 14 05:31:31 volumio212 kernel: Jul 14 05:31:31 volumio212 kernel: ERROR::dwc_otg_hcd_urb_enqueue:501: Not connected Jul 14 05:31:31 volumio212 dhcpcd[17032]: eth0: carrier lost Jul 14 05:31:31 volumio212 avahi-daemon[530]: Interface eth0.IPv4 no longer relevant for mDNS. Jul 14 05:31:31 volumio212 avahi-daemon[530]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.212. Jul 14 05:31:31 volumio212 kernel: usb 1-1: USB disconnect, device number 96 Jul 14 05:31:31 volumio212 kernel: usb 1-1.1: USB disconnect, device number 97 Jul 14 05:31:31 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Jul 14 05:31:31 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:31 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:31 volumio212 avahi-daemon[530]: Withdrawing address record for 192.168.1.212 on eth0. Jul 14 05:31:31 volumio212 volumio[16477]: info: Discovery: A device disappeared from network Jul 14 05:31:31 volumio212 volumio[16477]: info: Discovery: Device volumio212 disappeared from network Jul 14 05:31:31 volumio212 volumio[16477]: info: Discovery: A device disappeared from network Jul 14 05:31:31 volumio212 volumio[16477]: info: Discovery: Device volumio21 disappeared from network Jul 14 05:31:31 volumio212 volumio[16477]: info: Discovery: Disconnected from remote: 192.168.1.231 Jul 14 05:31:31 volumio212 systemd[1]: Stopping ifup for eth0... Jul 14 05:31:31 volumio212 ifdown[17074]: sending signal ALRM to pid 17032 Jul 14 05:31:31 volumio212 ifdown[17074]: waiting for pid 17032 to exit Jul 14 05:31:31 volumio212 dhcpcd[17090]: sending signal ALRM to pid 17032 Jul 14 05:31:31 volumio212 dhcpcd[17090]: waiting for pid 17032 to exit Jul 14 05:31:31 volumio212 dhcpcd[17032]: eth0: deleting route to 192.168.1.0/24 Jul 14 05:31:31 volumio212 dhcpcd[17032]: eth0: deleting default route via 192.168.1.1 Jul 14 05:31:32 volumio212 dnsmasq[818]: reading /etc/resolv.conf Jul 14 05:31:32 volumio212 dnsmasq[818]: using nameserver 208.67.222.222#53 Jul 14 05:31:32 volumio212 dnsmasq[818]: using nameserver 208.67.220.220#53 Jul 14 05:31:32 volumio212 dhcpcd[17032]: eth0: removing interface Jul 14 05:31:32 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:31:32 volumio212 dhcpcd[17032]: dhcpcd exited Jul 14 05:31:32 volumio212 ifdown[17074]: Cannot find device "eth0" Jul 14 05:31:32 volumio212 systemd[1]: ifup@eth0.service: Succeeded. Jul 14 05:31:32 volumio212 systemd[1]: Stopped ifup for eth0. Jul 14 05:31:32 volumio212 ifplugd(eth0)[683]: Link beat lost. Jul 14 05:31:32 volumio212 kernel: usb 1-1: new high-speed USB device number 98 using dwc_otg Jul 14 05:31:32 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:31:32 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:31:32 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:32 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:31:32 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:31:32 volumio212 kernel: usb 1-1.1: new high-speed USB device number 99 using dwc_otg Jul 14 05:31:33 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:31:33 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:33 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:31:33 volumio212 systemd-udevd[17063]: Using default interface naming scheme 'v240'. Jul 14 05:31:33 volumio212 kernel: SMSC LAN8700 usb-001:099:01: attached PHY driver (mii_bus:phy_addr=usb-001:099:01, irq=199) Jul 14 05:31:33 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:3a:76 Jul 14 05:31:33 volumio212 systemd-udevd[17063]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 14 05:31:33 volumio212 systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jul 14 05:31:33 volumio212 systemd[1]: Started ifup for eth0. Jul 14 05:31:33 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:33 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:33 volumio212 sh[17162]: eth0: waiting for carrier Jul 14 05:31:33 volumio212 dhcpcd[17178]: eth0: waiting for carrier Jul 14 05:31:34 volumio212 sh[17162]: eth0: carrier acquired Jul 14 05:31:34 volumio212 dhcpcd[17178]: eth0: carrier acquired Jul 14 05:31:34 volumio212 ifplugd(eth0)[683]: Link beat detected. Jul 14 05:31:34 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 14 05:31:34 volumio212 sh[17162]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:34 volumio212 sh[17162]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:34 volumio212 sh[17162]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:34 volumio212 dhcpcd[17178]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:34 volumio212 sh[17162]: ipv6_addaddr1: Permission denied Jul 14 05:31:34 volumio212 dhcpcd[17178]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:34 volumio212 dhcpcd[17178]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:34 volumio212 dhcpcd[17178]: ipv6_addaddr1: Permission denied Jul 14 05:31:34 volumio212 sh[17162]: eth0: soliciting an IPv6 router Jul 14 05:31:34 volumio212 dhcpcd[17178]: eth0: soliciting an IPv6 router Jul 14 05:31:35 volumio212 sh[17162]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:35 volumio212 dhcpcd[17178]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:35 volumio212 sh[17162]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:35 volumio212 dhcpcd[17178]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:35 volumio212 ntpd[678]: Deleting interface #39 eth0, 192.168.1.212#123, interface stats: received=19, sent=19, dropped=12, active_time=12 secs Jul 14 05:31:35 volumio212 ntpd[678]: 160.16.113.133 local addr 192.168.1.212 -> Jul 14 05:31:35 volumio212 ntpd[678]: 172.105.240.243 local addr 192.168.1.212 -> Jul 14 05:31:35 volumio212 ntpd[678]: 133.243.238.163 local addr 192.168.1.212 -> Jul 14 05:31:35 volumio212 ntpd[678]: 202.182.111.234 local addr 192.168.1.212 -> Jul 14 05:31:35 volumio212 ntpd[678]: 133.243.238.243 local addr 192.168.1.212 -> Jul 14 05:31:35 volumio212 ntpd[678]: 172.105.204.105 local addr 192.168.1.212 -> Jul 14 05:31:36 volumio212 ntpd[678]: Soliciting pool server 2400:8902::f03c:91ff:fe50:f5f0 Jul 14 05:31:39 volumio212 ntpd[678]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 14 05:31:40 volumio212 sh[17162]: eth0: carrier lost Jul 14 05:31:40 volumio212 dhcpcd[17178]: eth0: carrier lost Jul 14 05:31:40 volumio212 kernel: usb 1-1: USB disconnect, device number 98 Jul 14 05:31:40 volumio212 kernel: usb 1-1.1: USB disconnect, device number 99 Jul 14 05:31:40 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Jul 14 05:31:40 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:40 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:40 volumio212 systemd[1]: Stopping ifup for eth0... Jul 14 05:31:40 volumio212 ifdown[17202]: ifdown: waiting for lock on /run/network/ifstate.eth0 Jul 14 05:31:41 volumio212 sh[17162]: eth0: removing interface Jul 14 05:31:41 volumio212 dhcpcd[17178]: eth0: removing interface Jul 14 05:31:41 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:31:41 volumio212 sh[17162]: dhcpcd exited Jul 14 05:31:41 volumio212 dhcpcd[17178]: dhcpcd exited Jul 14 05:31:41 volumio212 sh[17162]: ifup: failed to bring up eth0 Jul 14 05:31:41 volumio212 ifdown[17202]: ifdown: interface eth0 not configured Jul 14 05:31:41 volumio212 systemd[1]: ifup@eth0.service: Main process exited, code=killed, status=15/TERM Jul 14 05:31:41 volumio212 systemd[1]: ifup@eth0.service: Succeeded. Jul 14 05:31:41 volumio212 systemd[1]: Stopped ifup for eth0. Jul 14 05:31:41 volumio212 kernel: usb 1-1: new high-speed USB device number 100 using dwc_otg Jul 14 05:31:41 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:31:41 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:31:41 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:41 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:31:41 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:31:41 volumio212 volumio[16477]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Jul 14 05:31:41 volumio212 volumio[16477]: info: Preparing to generate the ALSA configuration file Jul 14 05:31:41 volumio212 volumio[16477]: info: Asound.conf file unchanged, so no further update is needed Jul 14 05:31:41 volumio212 volumio[16477]: info: Output device has changed, restarting MPD Jul 14 05:31:41 volumio212 sudo[17248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 14 05:31:41 volumio212 sudo[17248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:41 volumio212 volumio[16477]: info: Output device has changed, restarting Shairport Sync Jul 14 05:31:41 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:41 volumio212 sudo[17248]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:41 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 05:31:41 volumio212 sudo[17254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 14 05:31:41 volumio212 sudo[17254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:41 volumio212 kernel: usb 1-1.1: new high-speed USB device number 101 using dwc_otg Jul 14 05:31:41 volumio212 systemd[1]: Stopping Music Player Daemon... Jul 14 05:31:41 volumio212 volumio[16477]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 14 05:31:41 volumio212 volumio[16477]: info: ___________ START PLUGINS ___________ Jul 14 05:31:41 volumio212 volumio[16477]: info: ControllerMpd::onStart: Initializing MPD Jul 14 05:31:41 volumio212 volumio[16477]: info: Creating MPD Configuration file Jul 14 05:31:41 volumio212 ifplugd(eth0)[683]: Link beat lost. Jul 14 05:31:41 volumio212 systemd[1]: mpd.service: Succeeded. Jul 14 05:31:41 volumio212 systemd[1]: Stopped Music Player Daemon. Jul 14 05:31:41 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:31:41 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:41 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:31:41 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 05:31:41 volumio212 volumio[16477]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 14 05:31:41 volumio212 volumio[16477]: info: [1720902701995] CoreMusicLibrary::Adding element メディアサーバー Jul 14 05:31:41 volumio212 sudo[17267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 14 05:31:42 volumio212 sudo[17267]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:42 volumio212 systemd[1]: Starting Music Player Daemon... Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 05:31:42 volumio212 sudo[17267]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:42 volumio212 sudo[17273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 14 05:31:42 volumio212 sudo[17273]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 05:31:42 volumio212 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 14 05:31:42 volumio212 systemd[1]: mpd.service: Succeeded. Jul 14 05:31:42 volumio212 kernel: SMSC LAN8700 usb-001:101:01: attached PHY driver (mii_bus:phy_addr=usb-001:101:01, irq=199) Jul 14 05:31:42 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:3a:76 Jul 14 05:31:42 volumio212 systemd-udevd[17201]: Using default interface naming scheme 'v240'. Jul 14 05:31:42 volumio212 systemd-udevd[17201]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 14 05:31:42 volumio212 systemd[1]: Stopped Music Player Daemon. Jul 14 05:31:42 volumio212 systemd[1]: Starting Music Player Daemon... Jul 14 05:31:42 volumio212 volumio[16477]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 14 05:31:42 volumio212 volumio[16477]: info: [1720902702292] CoreMusicLibrary::Adding element Last_100 Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 14 05:31:42 volumio212 volumio[16477]: info: [1720902702300] CoreMusicLibrary::Adding element Webradio Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 14 05:31:42 volumio212 volumio[16477]: info: [squeezelite_mc] Starting proxy server... Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 14 05:31:42 volumio212 volumio[16477]: info: [1720902702375] CoreMusicLibrary::Adding element YouTube2 Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 05:31:42 volumio212 volumio[16477]: Cannot find translation for source YouTube2 Jul 14 05:31:42 volumio212 systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jul 14 05:31:42 volumio212 volumio[16477]: info: Volumio Calling Home Jul 14 05:31:42 volumio212 systemd[1]: Started ifup for eth0. Jul 14 05:31:42 volumio212 volumio[16477]: info: [squeezelite_mc] Proxy server started on port 43407 Jul 14 05:31:42 volumio212 sh[17292]: eth0: waiting for carrier Jul 14 05:31:42 volumio212 dhcpcd[17311]: eth0: waiting for carrier Jul 14 05:31:42 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:42 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::volumioRetrievevolume Jul 14 05:31:42 volumio212 volumio[16477]: info: MPD Permissions set Jul 14 05:31:42 volumio212 volumio[16477]: info: MPD Permissions set Jul 14 05:31:42 volumio212 volumio[16477]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:42 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:43 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 05:31:43 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:43 volumio212 volumio[16477]: info: VolumeController:: Volume=100 Mute =false Jul 14 05:31:43 volumio212 volumio[16477]: info: CoreStateMachine::pushState Jul 14 05:31:43 volumio212 volumio[16477]: info: CorePlayQueue::getTrack 0 Jul 14 05:31:43 volumio212 volumio[16477]: info: CoreCommandRouter::volumioPushState Jul 14 05:31:43 volumio212 volumio[16477]: info: [squeezelite_mc] Server discovery started Jul 14 05:31:43 volumio212 volumio[16477]: info: [squeezelite_mc] Player finder started Jul 14 05:31:43 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:43 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:43 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:43 volumio212 volumio[16477]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 05:31:43 volumio212 volumio[16477]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 14 05:31:43 volumio212 volumio[16477]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 05:31:43 volumio212 volumio[16477]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 14 05:31:43 volumio212 volumio[16477]: at doSend (dgram.js:692:16) Jul 14 05:31:43 volumio212 volumio[16477]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 14 05:31:43 volumio212 volumio[16477]: at afterDns (dgram.js:638:5) Jul 14 05:31:43 volumio212 volumio[16477]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 14 05:31:43 volumio212 volumio[16477]: errno: -101, Jul 14 05:31:43 volumio212 volumio[16477]: code: 'ENETUNREACH', Jul 14 05:31:43 volumio212 volumio[16477]: syscall: 'send', Jul 14 05:31:43 volumio212 volumio[16477]: address: '255.255.255.255', Jul 14 05:31:43 volumio212 volumio[16477]: port: 3483 Jul 14 05:31:43 volumio212 volumio[16477]: } Jul 14 05:31:43 volumio212 volumio[16477]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 05:31:43 volumio212 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 14 05:31:43 volumio212 sh[17292]: eth0: carrier acquired Jul 14 05:31:43 volumio212 dhcpcd[17311]: eth0: carrier acquired Jul 14 05:31:43 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 14 05:31:43 volumio212 sh[17292]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:43 volumio212 sh[17292]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:43 volumio212 sh[17292]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:43 volumio212 dhcpcd[17311]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:43 volumio212 sh[17292]: ipv6_addaddr1: Permission denied Jul 14 05:31:43 volumio212 dhcpcd[17311]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:43 volumio212 dhcpcd[17311]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:43 volumio212 dhcpcd[17311]: ipv6_addaddr1: Permission denied Jul 14 05:31:43 volumio212 sh[17292]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:43 volumio212 dhcpcd[17311]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:43 volumio212 sh[17292]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:43 volumio212 dhcpcd[17311]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:43 volumio212 sh[17292]: eth0: soliciting an IPv6 router Jul 14 05:31:43 volumio212 dhcpcd[17311]: eth0: soliciting an IPv6 router Jul 14 05:31:44 volumio212 ifplugd(eth0)[683]: Link beat detected. Jul 14 05:31:44 volumio212 sudo[17345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-14 05:30 Jul 14 05:31:44 volumio212 sudo[17345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:44 volumio212 mpd[17284]: Jul 14 05:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 14 05:31:44 volumio212 sudo[17345]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:44 volumio212 volumio-remote-updater[523]: [2024-07-14 05:31:44] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 14 05:31:44 volumio212 volumio-remote-updater[523]: [2024-07-14 05:31:44] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 14 05:31:44 volumio212 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 14 05:31:44 volumio212 sudo[17254]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:44 volumio212 sudo[17273]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:44 volumio212 sh[17292]: eth0: carrier lost Jul 14 05:31:44 volumio212 dhcpcd[17311]: eth0: carrier lost Jul 14 05:31:44 volumio212 kernel: usb 1-1: USB disconnect, device number 100 Jul 14 05:31:44 volumio212 kernel: usb 1-1.1: USB disconnect, device number 101 Jul 14 05:31:44 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Jul 14 05:31:44 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:44 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:44 volumio212 systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 14 05:31:44 volumio212 systemd[1]: Started dynamicswap service. Jul 14 05:31:44 volumio212 systemd[1]: Stopping ifup for eth0... Jul 14 05:31:44 volumio212 ifdown[17365]: ifdown: waiting for lock on /run/network/ifstate.eth0 Jul 14 05:31:44 volumio212 systemd[1]: dynamicswap.service: Succeeded. Jul 14 05:31:44 volumio212 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 14 05:31:44 volumio212 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Jul 14 05:31:44 volumio212 systemd[1]: Stopped Volumio Backend Module. Jul 14 05:31:44 volumio212 systemd[1]: Started Volumio Backend Module. Jul 14 05:31:45 volumio212 systemd[1]: Started dynamicswap service. Jul 14 05:31:45 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:31:45 volumio212 systemd[1]: dynamicswap.service: Succeeded. Jul 14 05:31:45 volumio212 sh[17292]: eth0: removing interface Jul 14 05:31:45 volumio212 dhcpcd[17311]: eth0: removing interface Jul 14 05:31:45 volumio212 systemd[1]: Started Music Player Daemon. Jul 14 05:31:45 volumio212 kernel: usb 1-1: new high-speed USB device number 102 using dwc_otg Jul 14 05:31:45 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:31:45 volumio212 sh[17292]: dhcpcd exited Jul 14 05:31:45 volumio212 dhcpcd[17311]: dhcpcd exited Jul 14 05:31:45 volumio212 sh[17292]: ifup: failed to bring up eth0 Jul 14 05:31:45 volumio212 ifdown[17365]: ifdown: interface eth0 not configured Jul 14 05:31:45 volumio212 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 14 05:31:45 volumio212 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 14 05:31:45 volumio212 systemd[1]: Stopped ifup for eth0. Jul 14 05:31:45 volumio212 ifplugd(eth0)[683]: Link beat lost. Jul 14 05:31:45 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:31:45 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:45 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:31:45 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:31:45 volumio212 kernel: usb 1-1.1: new high-speed USB device number 103 using dwc_otg Jul 14 05:31:46 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:31:46 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:46 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:31:46 volumio212 kernel: SMSC LAN8700 usb-001:103:01: attached PHY driver (mii_bus:phy_addr=usb-001:103:01, irq=199) Jul 14 05:31:46 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:3a:76 Jul 14 05:31:46 volumio212 systemd-udevd[17201]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 14 05:31:46 volumio212 systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jul 14 05:31:46 volumio212 systemd[1]: Started ifup for eth0. Jul 14 05:31:46 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:46 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:46 volumio212 sh[17446]: eth0: waiting for carrier Jul 14 05:31:46 volumio212 dhcpcd[17462]: eth0: waiting for carrier Jul 14 05:31:47 volumio212 sh[17446]: eth0: carrier acquired Jul 14 05:31:47 volumio212 dhcpcd[17462]: eth0: carrier acquired Jul 14 05:31:47 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 14 05:31:47 volumio212 sh[17446]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:47 volumio212 sh[17446]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:47 volumio212 sh[17446]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:47 volumio212 dhcpcd[17462]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:47 volumio212 sh[17446]: ipv6_addaddr1: Permission denied Jul 14 05:31:47 volumio212 dhcpcd[17462]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:47 volumio212 dhcpcd[17462]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:47 volumio212 dhcpcd[17462]: ipv6_addaddr1: Permission denied Jul 14 05:31:48 volumio212 ntpd[678]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 14 05:31:48 volumio212 ifplugd(eth0)[683]: Link beat detected. Jul 14 05:31:48 volumio212 volumio[17379]: info: ------------------------------------------- Jul 14 05:31:48 volumio212 volumio[17379]: info: ----- Volumio3 ---- Jul 14 05:31:48 volumio212 volumio[17379]: info: ------------------------------------------- Jul 14 05:31:48 volumio212 volumio[17379]: info: ----- System startup ---- Jul 14 05:31:48 volumio212 volumio[17379]: info: ------------------------------------------- Jul 14 05:31:48 volumio212 sh[17446]: eth0: soliciting an IPv6 router Jul 14 05:31:48 volumio212 dhcpcd[17462]: eth0: soliciting an IPv6 router Jul 14 05:31:48 volumio212 sh[17446]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:48 volumio212 dhcpcd[17462]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:48 volumio212 sh[17446]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:48 volumio212 dhcpcd[17462]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:49 volumio212 volumio-remote-updater[523]: [2024-07-14 05:31:49] [connect] Successful connection Jul 14 05:31:50 volumio212 volumio[17379]: info: MYVOLUMIO Environment detected Jul 14 05:31:50 volumio212 volumio[17379]: info: Plugin folders cleanup Jul 14 05:31:50 volumio212 volumio[17379]: info: Scanning into folder /volumio/app/plugins/ Jul 14 05:31:50 volumio212 volumio[17379]: info: Scanning category audio_interface Jul 14 05:31:50 volumio212 volumio[17379]: info: Scanning category miscellanea Jul 14 05:31:50 volumio212 volumio[17379]: info: Scanning category music_service Jul 14 05:31:50 volumio212 volumio[17379]: info: Scanning category plugins.json Jul 14 05:31:50 volumio212 volumio[17379]: info: Scanning category system_controller Jul 14 05:31:50 volumio212 volumio[17379]: info: Scanning category user_interface Jul 14 05:31:50 volumio212 volumio[17379]: info: Scanning into folder /data/plugins/ Jul 14 05:31:50 volumio212 volumio[17379]: info: Scanning category music_service Jul 14 05:31:50 volumio212 volumio[17379]: info: Plugin folders cleanup completed Jul 14 05:31:50 volumio212 volumio[17379]: info: ------------------------------------------- Jul 14 05:31:50 volumio212 volumio[17379]: info: ----- Core plugins startup ---- Jul 14 05:31:50 volumio212 volumio[17379]: info: ------------------------------------------- Jul 14 05:31:50 volumio212 volumio[17379]: info: Loading plugins from folder /volumio/app/plugins/ Jul 14 05:31:50 volumio212 volumio[17379]: info: Adding plugin upnp to MyMusic Plugins Jul 14 05:31:50 volumio212 volumio[17379]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 14 05:31:50 volumio212 volumio[17379]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 14 05:31:50 volumio212 volumio[17379]: info: Loading plugins from folder /data/plugins/ Jul 14 05:31:50 volumio212 volumio[17379]: info: Loading plugin "system"... Jul 14 05:31:50 volumio212 volumio[17379]: info: Loading plugin "appearance"... Jul 14 05:31:52 volumio212 volumio[17379]: info: Loading plugin "network"... Jul 14 05:31:52 volumio212 volumio[17379]: info: Refreshing Cached IP Addresses Jul 14 05:31:52 volumio212 sudo[17489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 14 05:31:52 volumio212 sudo[17489]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:52 volumio212 sudo[17489]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:52 volumio212 volumio[17379]: info: Loading plugin "services"... Jul 14 05:31:52 volumio212 sudo[17491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 14 05:31:52 volumio212 sudo[17491]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:52 volumio212 volumio[17379]: info: Loading plugin "alsa_controller"... Jul 14 05:31:52 volumio212 sudo[17491]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:52 volumio212 sudo[17494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 14 05:31:52 volumio212 sudo[17494]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:31:52 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 14 05:31:52 volumio212 volumio[17379]: info: Loading plugin "wizard"... Jul 14 05:31:52 volumio212 volumio[17379]: info: Loading plugin "networkfs"... Jul 14 05:31:52 volumio212 volumio[17379]: info: Cannot mount NAS ks at system boot, trial number 1 ,retrying in 5 seconds Jul 14 05:31:52 volumio212 volumio[17379]: info: Starting Udev Watcher for removable devices Jul 14 05:31:52 volumio212 volumio[17379]: info: Ignoring mount for partition: boot Jul 14 05:31:52 volumio212 volumio[17379]: info: Ignoring mount for partition: volumio Jul 14 05:31:52 volumio212 volumio[17379]: info: Ignoring mount for partition: volumio_data Jul 14 05:31:52 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 14 05:31:52 volumio212 volumio[17379]: info: Loading plugin "volumio_command_line_client"... Jul 14 05:31:52 volumio212 volumio[17379]: info: Loading plugin "upnp"... Jul 14 05:31:52 volumio212 volumio[17379]: info: [1720902712548] Starting Upmpd Daemon Jul 14 05:31:52 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 14 05:31:52 volumio212 volumio[17379]: info: Loading plugin "my_music"... Jul 14 05:31:52 volumio212 volumio[17379]: info: Loading plugin "mpd"... Jul 14 05:31:52 volumio212 kernel: Jul 14 05:31:52 volumio212 kernel: ERROR::dwc_otg_hcd_urb_enqueue:501: Not connected Jul 14 05:31:53 volumio212 sh[17446]: eth0: carrier lost Jul 14 05:31:53 volumio212 dhcpcd[17462]: eth0: carrier lost Jul 14 05:31:53 volumio212 kernel: usb 1-1: USB disconnect, device number 102 Jul 14 05:31:53 volumio212 kernel: usb 1-1.1: USB disconnect, device number 103 Jul 14 05:31:53 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Jul 14 05:31:53 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:53 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:53 volumio212 sudo[17494]: pam_unix(sudo:session): session closed for user root Jul 14 05:31:53 volumio212 systemd[1]: Stopping ifup for eth0... Jul 14 05:31:53 volumio212 ifdown[17511]: ifdown: waiting for lock on /run/network/ifstate.eth0 Jul 14 05:31:53 volumio212 volumio[17379]: info: Loading plugin "upnp_browser"... Jul 14 05:31:53 volumio212 sh[17446]: eth0: removing interface Jul 14 05:31:53 volumio212 dhcpcd[17462]: eth0: removing interface Jul 14 05:31:53 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:31:53 volumio212 sh[17446]: dhcpcd exited Jul 14 05:31:53 volumio212 dhcpcd[17462]: dhcpcd exited Jul 14 05:31:53 volumio212 sh[17446]: ifup: failed to bring up eth0 Jul 14 05:31:53 volumio212 ifdown[17511]: ifdown: interface eth0 not configured Jul 14 05:31:53 volumio212 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 14 05:31:53 volumio212 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 14 05:31:53 volumio212 systemd[1]: Stopped ifup for eth0. Jul 14 05:31:53 volumio212 kernel: usb 1-1: new high-speed USB device number 104 using dwc_otg Jul 14 05:31:53 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:31:53 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:31:53 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:53 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:31:53 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:31:53 volumio212 volumio[17379]: info: Loading plugin "alarm-clock"... Jul 14 05:31:53 volumio212 ifplugd(eth0)[683]: Link beat lost. Jul 14 05:31:54 volumio212 kernel: usb 1-1.1: new high-speed USB device number 105 using dwc_otg Jul 14 05:31:54 volumio212 volumio[17379]: info: Loading plugin "airplay_emulation"... Jul 14 05:31:54 volumio212 volumio[17379]: info: Starting Shairport Sync Jul 14 05:31:54 volumio212 volumio[17379]: info: Loading plugin "last_100"... Jul 14 05:31:54 volumio212 volumio[17379]: info: Loading plugin "webradio"... Jul 14 05:31:54 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:31:54 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:31:54 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:31:54 volumio212 systemd-udevd[17503]: Using default interface naming scheme 'v240'. Jul 14 05:31:54 volumio212 kernel: SMSC LAN8700 usb-001:105:01: attached PHY driver (mii_bus:phy_addr=usb-001:105:01, irq=199) Jul 14 05:31:54 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:3a:76 Jul 14 05:31:54 volumio212 systemd-udevd[17503]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 14 05:31:54 volumio212 volumio[17379]: info: Loading plugin "i2s_dacs"... Jul 14 05:31:54 volumio212 volumio[17379]: info: Loading plugin "volumiodiscovery"... Jul 14 05:31:54 volumio212 volumio[17379]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 14 05:31:54 volumio212 node[17379]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 14 05:31:54 volumio212 node[17379]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 05:31:54 volumio212 volumio[17379]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 05:31:54 volumio212 volumio[17379]: *** WARNING *** For more information see Jul 14 05:31:54 volumio212 volumio[17379]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 14 05:31:54 volumio212 volumio[17379]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 05:31:54 volumio212 volumio[17379]: *** WARNING *** For more information see Jul 14 05:31:54 volumio212 node[17379]: *** WARNING *** For more information see Jul 14 05:31:54 volumio212 node[17379]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 14 05:31:54 volumio212 node[17379]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 05:31:54 volumio212 node[17379]: *** WARNING *** For more information see Jul 14 05:31:54 volumio212 volumio[17379]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 14 05:31:54 volumio212 volumio[17379]: info: Discovery: Started advertising with name: Volumio212 Jul 14 05:31:54 volumio212 systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jul 14 05:31:54 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 14 05:31:54 volumio212 volumio[17379]: info: Loading plugin "squeezelite_mc"... Jul 14 05:31:54 volumio212 systemd[1]: Started ifup for eth0. Jul 14 05:31:55 volumio212 sh[17611]: eth0: waiting for carrier Jul 14 05:31:55 volumio212 dhcpcd[17631]: eth0: waiting for carrier Jul 14 05:31:55 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:55 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:55 volumio212 sh[17611]: eth0: carrier acquired Jul 14 05:31:55 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 14 05:31:55 volumio212 dhcpcd[17631]: eth0: carrier acquired Jul 14 05:31:55 volumio212 sh[17611]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:55 volumio212 sh[17611]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:55 volumio212 sh[17611]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:55 volumio212 sh[17611]: ipv6_addaddr1: Permission denied Jul 14 05:31:55 volumio212 dhcpcd[17631]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:31:55 volumio212 dhcpcd[17631]: eth0: IAID eb:e1:3a:76 Jul 14 05:31:55 volumio212 dhcpcd[17631]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:31:55 volumio212 dhcpcd[17631]: ipv6_addaddr1: Permission denied Jul 14 05:31:55 volumio212 sh[17611]: eth0: soliciting an IPv6 router Jul 14 05:31:55 volumio212 dhcpcd[17631]: eth0: soliciting an IPv6 router Jul 14 05:31:56 volumio212 sh[17611]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:56 volumio212 dhcpcd[17631]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:31:56 volumio212 ifplugd(eth0)[683]: Link beat detected. Jul 14 05:31:56 volumio212 sh[17611]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:56 volumio212 dhcpcd[17631]: eth0: probing address 192.168.1.212/24 Jul 14 05:31:56 volumio212 volumio[17379]: info: Loading plugin "youtube2"... Jul 14 05:31:58 volumio212 volumio[17379]: info: Loading plugin "outputs"... Jul 14 05:31:58 volumio212 volumio[17379]: info: Loading plugin "albumart"... Jul 14 05:31:58 volumio212 volumio[17379]: info: Plugin example_plugin is not enabled Jul 14 05:31:58 volumio212 volumio[17379]: info: Loading plugin "inputs"... Jul 14 05:31:58 volumio212 volumio[17379]: info: Loading plugin "updater_comm"... Jul 14 05:31:59 volumio212 volumio[17379]: info: Plugin mpdemulation is not enabled Jul 14 05:31:59 volumio212 volumio[17379]: info: Loading plugin "rest_api"... Jul 14 05:31:59 volumio212 volumio[17379]: info: Loading plugin "websocket"... Jul 14 05:31:59 volumio212 volumio[17379]: info: Loading i18n strings for locale ja Jul 14 05:31:59 volumio212 volumio[17379]: Updating browse sources language Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::initPlayerControls Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 05:31:59 volumio212 volumio[17379]: Express server listening on port 3000 Jul 14 05:31:59 volumio212 volumio[17379]: [Metrics] WebUI: 11s 877.29ms Jul 14 05:31:59 volumio212 volumio[17379]: Forking 3 albumart workers Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreStateMachine::resetVolumioState Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreStateMachine::getcurrentVolume Jul 14 05:31:59 volumio212 volumio[17379]: info: CoreCommandRouter::volumioRetrievevolume Jul 14 05:31:59 volumio212 volumio[17379]: info: Cannot mount NAS ks at system boot, trial number 2 ,retrying in 5 seconds Jul 14 05:31:59 volumio212 sh[17611]: eth0: carrier lost Jul 14 05:31:59 volumio212 dhcpcd[17631]: eth0: carrier lost Jul 14 05:31:59 volumio212 kernel: usb 1-1: USB disconnect, device number 104 Jul 14 05:31:59 volumio212 kernel: usb 1-1.1: USB disconnect, device number 105 Jul 14 05:31:59 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Jul 14 05:31:59 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:31:59 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:31:59 volumio212 systemd[1]: Stopping ifup for eth0... Jul 14 05:31:59 volumio212 ifdown[17689]: ifdown: waiting for lock on /run/network/ifstate.eth0 Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 05:32:00 volumio212 volumio[17379]: info: Reloading queue from file Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreStateMachine::setRepeat null single undefined Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreStateMachine::pushState Jul 14 05:32:00 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 05:32:00 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreCommandRouter::volumioPushState Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreStateMachine::setRandom null Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreStateMachine::pushState Jul 14 05:32:00 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:00 volumio212 sh[17611]: eth0: removing interface Jul 14 05:32:00 volumio212 dhcpcd[17631]: eth0: removing interface Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreCommandRouter::volumioPushState Jul 14 05:32:00 volumio212 volumio[17379]: info: Setting Device type: Raspberry PI Jul 14 05:32:00 volumio212 volumio[17379]: info: VolumeController:: Volume=100 Mute =false Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreStateMachine::pushState Jul 14 05:32:00 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreCommandRouter::volumioPushState Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreStateMachine::updateTrackBlock Jul 14 05:32:00 volumio212 volumio[17379]: info: CorePlayQueue::getTrackBlock Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreCommandRouter::volumioRetrievevolume Jul 14 05:32:00 volumio212 kernel: usb 1-1: new high-speed USB device number 106 using dwc_otg Jul 14 05:32:00 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:32:00 volumio212 volumio[17379]: info: Discovery: adding 02a57288-0b93-4a22-aad5-3158a5e574f3 Jul 14 05:32:00 volumio212 volumio[17379]: info: Discovery: Found device Volumio212 Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetState Jul 14 05:32:00 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:00 volumio212 sh[17611]: dhcpcd exited Jul 14 05:32:00 volumio212 dhcpcd[17631]: dhcpcd exited Jul 14 05:32:00 volumio212 sh[17611]: ifup: failed to bring up eth0 Jul 14 05:32:00 volumio212 ifdown[17689]: ifdown: interface eth0 not configured Jul 14 05:32:00 volumio212 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 14 05:32:00 volumio212 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 14 05:32:00 volumio212 systemd[1]: Stopped ifup for eth0. Jul 14 05:32:00 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:32:00 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:32:00 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:32:00 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:32:00 volumio212 volumio[17379]: info: VolumeController:: Volume=100 Mute =false Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreStateMachine::pushState Jul 14 05:32:00 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:00 volumio212 volumio[17379]: info: CoreCommandRouter::volumioPushState Jul 14 05:32:00 volumio212 ifplugd(eth0)[683]: Link beat lost. Jul 14 05:32:00 volumio212 kernel: usb 1-1.1: new high-speed USB device number 107 using dwc_otg Jul 14 05:32:01 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:32:01 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:32:01 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:32:01 volumio212 kernel: SMSC LAN8700 usb-001:107:01: attached PHY driver (mii_bus:phy_addr=usb-001:107:01, irq=199) Jul 14 05:32:01 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:3a:76 Jul 14 05:32:01 volumio212 systemd-udevd[17678]: Using default interface naming scheme 'v240'. Jul 14 05:32:01 volumio212 systemd-udevd[17678]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 14 05:32:01 volumio212 systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jul 14 05:32:01 volumio212 systemd[1]: Started ifup for eth0. Jul 14 05:32:01 volumio212 volumio[17379]: Starting albumart workers Jul 14 05:32:01 volumio212 volumio[17379]: Starting albumart workers Jul 14 05:32:01 volumio212 volumio[17379]: Starting albumart workers Jul 14 05:32:01 volumio212 sh[17757]: eth0: waiting for carrier Jul 14 05:32:01 volumio212 dhcpcd[17773]: eth0: waiting for carrier Jul 14 05:32:01 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:32:01 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:32:02 volumio212 volumio-remote-updater[523]: [2024-07-14 05:32:02] [connect] Successful connection Jul 14 05:32:02 volumio212 volumio-remote-updater[523]: [2024-07-14 05:32:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720902722 101 Jul 14 05:32:02 volumio212 volumio[17379]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 14 05:32:02 volumio212 sh[17757]: eth0: carrier acquired Jul 14 05:32:02 volumio212 dhcpcd[17773]: eth0: carrier acquired Jul 14 05:32:02 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 14 05:32:02 volumio212 sh[17757]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:32:02 volumio212 sh[17757]: eth0: IAID eb:e1:3a:76 Jul 14 05:32:02 volumio212 sh[17757]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:32:02 volumio212 dhcpcd[17773]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:32:02 volumio212 sh[17757]: ipv6_addaddr1: Permission denied Jul 14 05:32:02 volumio212 dhcpcd[17773]: eth0: IAID eb:e1:3a:76 Jul 14 05:32:02 volumio212 dhcpcd[17773]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:32:02 volumio212 dhcpcd[17773]: ipv6_addaddr1: Permission denied Jul 14 05:32:02 volumio212 sh[17757]: eth0: soliciting an IPv6 router Jul 14 05:32:02 volumio212 dhcpcd[17773]: eth0: soliciting an IPv6 router Jul 14 05:32:02 volumio212 kernel: Jul 14 05:32:02 volumio212 kernel: ERROR::dwc_otg_hcd_urb_enqueue:501: Not connected Jul 14 05:32:02 volumio212 sudo[17793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 14 05:32:02 volumio212 sudo[17793]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:32:02 volumio212 sudo[17793]: pam_unix(sudo:session): session closed for user root Jul 14 05:32:02 volumio212 sudo[17802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 14 05:32:02 volumio212 sudo[17802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:32:02 volumio212 sudo[17802]: pam_unix(sudo:session): session closed for user root Jul 14 05:32:02 volumio212 sudo[17809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 14 05:32:02 volumio212 sudo[17809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:32:02 volumio212 sh[17757]: eth0: carrier lost Jul 14 05:32:02 volumio212 dhcpcd[17773]: eth0: carrier lost Jul 14 05:32:02 volumio212 kernel: usb 1-1: USB disconnect, device number 106 Jul 14 05:32:02 volumio212 kernel: usb 1-1.1: USB disconnect, device number 107 Jul 14 05:32:02 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Jul 14 05:32:02 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:32:02 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:32:02 volumio212 systemd[1]: Stopping ifup for eth0... Jul 14 05:32:02 volumio212 ifdown[17820]: ifdown: waiting for lock on /run/network/ifstate.eth0 Jul 14 05:32:02 volumio212 sh[17757]: eth0: removing interface Jul 14 05:32:02 volumio212 dhcpcd[17773]: eth0: removing interface Jul 14 05:32:02 volumio212 sudo[17809]: pam_unix(sudo:session): session closed for user root Jul 14 05:32:02 volumio212 volumio[17379]: info: Upmpdcli Daemon Started Jul 14 05:32:03 volumio212 sh[17757]: dhcpcd exited Jul 14 05:32:03 volumio212 dhcpcd[17773]: dhcpcd exited Jul 14 05:32:03 volumio212 sh[17757]: ifup: failed to bring up eth0 Jul 14 05:32:03 volumio212 ifdown[17820]: ifdown: interface eth0 not configured Jul 14 05:32:03 volumio212 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 14 05:32:03 volumio212 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 14 05:32:03 volumio212 systemd[1]: Stopped ifup for eth0. Jul 14 05:32:03 volumio212 kernel: Indeed it is in host mode hprt0 = 00021501 Jul 14 05:32:03 volumio212 kernel: usb 1-1: new high-speed USB device number 108 using dwc_otg Jul 14 05:32:03 volumio212 kernel: Indeed it is in host mode hprt0 = 00001101 Jul 14 05:32:03 volumio212 ntpd[678]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 14 05:32:03 volumio212 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jul 14 05:32:03 volumio212 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:32:03 volumio212 kernel: hub 1-1:1.0: USB hub found Jul 14 05:32:03 volumio212 kernel: hub 1-1:1.0: 5 ports detected Jul 14 05:32:03 volumio212 kernel: usb 1-1.1: new high-speed USB device number 109 using dwc_otg Jul 14 05:32:04 volumio212 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jul 14 05:32:04 volumio212 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jul 14 05:32:04 volumio212 kernel: smsc95xx v2.0.0 Jul 14 05:32:04 volumio212 systemd-udevd[17830]: Using default interface naming scheme 'v240'. Jul 14 05:32:04 volumio212 kernel: SMSC LAN8700 usb-001:109:01: attached PHY driver (mii_bus:phy_addr=usb-001:109:01, irq=199) Jul 14 05:32:04 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:3a:76 Jul 14 05:32:04 volumio212 systemd-udevd[17830]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 14 05:32:04 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jul 14 05:32:04 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jul 14 05:32:04 volumio212 systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jul 14 05:32:04 volumio212 systemd[1]: Started ifup for eth0. Jul 14 05:32:04 volumio212 volumio[17379]: info: Cannot mount NAS ks at system boot, trial number 3 ,retrying in 5 seconds Jul 14 05:32:04 volumio212 sh[17880]: eth0: waiting for carrier Jul 14 05:32:04 volumio212 dhcpcd[17896]: eth0: waiting for carrier Jul 14 05:32:05 volumio212 sh[17880]: eth0: carrier acquired Jul 14 05:32:05 volumio212 dhcpcd[17896]: eth0: carrier acquired Jul 14 05:32:05 volumio212 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 14 05:32:05 volumio212 sh[17880]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:32:05 volumio212 dhcpcd[17896]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:e1:3a:76 Jul 14 05:32:05 volumio212 sh[17880]: eth0: IAID eb:e1:3a:76 Jul 14 05:32:05 volumio212 dhcpcd[17896]: eth0: IAID eb:e1:3a:76 Jul 14 05:32:05 volumio212 sh[17880]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:32:05 volumio212 dhcpcd[17896]: eth0: adding address fe80::b90a:5740:29a:a0a7 Jul 14 05:32:05 volumio212 sh[17880]: ipv6_addaddr1: Permission denied Jul 14 05:32:05 volumio212 dhcpcd[17896]: ipv6_addaddr1: Permission denied Jul 14 05:32:05 volumio212 sh[17880]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:32:05 volumio212 dhcpcd[17896]: eth0: rebinding lease of 192.168.1.212 Jul 14 05:32:05 volumio212 sh[17880]: eth0: probing address 192.168.1.212/24 Jul 14 05:32:05 volumio212 dhcpcd[17896]: eth0: probing address 192.168.1.212/24 Jul 14 05:32:06 volumio212 sh[17880]: eth0: soliciting an IPv6 router Jul 14 05:32:06 volumio212 dhcpcd[17896]: eth0: soliciting an IPv6 router Jul 14 05:32:06 volumio212 ifplugd(eth0)[683]: Link beat detected. Jul 14 05:32:09 volumio212 volumio[17379]: info: Cannot mount NAS ks at system boot, trial number 4 ,retrying in 5 seconds Jul 14 05:32:10 volumio212 sh[17880]: eth0: leased 192.168.1.212 for 86400 seconds Jul 14 05:32:10 volumio212 sh[17880]: eth0: adding route to 192.168.1.0/24 Jul 14 05:32:10 volumio212 sh[17880]: eth0: adding default route via 192.168.1.1 Jul 14 05:32:10 volumio212 dhcpcd[17896]: eth0: leased 192.168.1.212 for 86400 seconds Jul 14 05:32:10 volumio212 avahi-daemon[530]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.212. Jul 14 05:32:10 volumio212 dhcpcd[17896]: eth0: adding route to 192.168.1.0/24 Jul 14 05:32:10 volumio212 avahi-daemon[530]: New relevant interface eth0.IPv4 for mDNS. Jul 14 05:32:10 volumio212 dhcpcd[17896]: eth0: adding default route via 192.168.1.1 Jul 14 05:32:10 volumio212 avahi-daemon[530]: Registering new address record for 192.168.1.212 on eth0.IPv4. Jul 14 05:32:11 volumio212 dnsmasq[818]: reading /etc/resolv.conf Jul 14 05:32:11 volumio212 dnsmasq[818]: using nameserver 119.29.29.29#53 Jul 14 05:32:11 volumio212 dnsmasq[818]: using nameserver 208.67.222.222#53 Jul 14 05:32:11 volumio212 dnsmasq[818]: using nameserver 208.67.220.220#53 Jul 14 05:32:11 volumio212 sh[17880]: forked to background, child pid 17930 Jul 14 05:32:11 volumio212 dhcpcd[17896]: forked to background, child pid 17930 Jul 14 05:32:11 volumio212 sh[17880]: eth0=eth0 Jul 14 05:32:11 volumio212 volumio[17379]: info: Discovery: adding d6f9068f-d19f-494d-abd8-145972f2c39e Jul 14 05:32:11 volumio212 volumio[17379]: info: Discovery: Found device Volumio21 Jul 14 05:32:11 volumio212 volumio[17379]: info: Discovery: Connecting to remote: 192.168.1.231 Jul 14 05:32:11 volumio212 volumio[17379]: info: Discovery: Connected to remote: 192.168.1.231 Jul 14 05:32:12 volumio212 volumio[17379]: info: Discovery: this is already registered, 02a57288-0b93-4a22-aad5-3158a5e574f3 Jul 14 05:32:12 volumio212 volumio[17379]: info: Discovery: Found device Volumio212 Jul 14 05:32:12 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetState Jul 14 05:32:12 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:12 volumio212 ntpd[678]: Listen normally on 40 eth0 192.168.1.212:123 Jul 14 05:32:12 volumio212 ntpd[678]: new interface(s) found: waking up resolver Jul 14 05:32:13 volumio212 sudo[17959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 14 05:32:13 volumio212 sudo[17959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:32:13 volumio212 sudo[17959]: pam_unix(sudo:session): session closed for user root Jul 14 05:32:13 volumio212 sudo[17961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 14 05:32:13 volumio212 sudo[17961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 05:32:13 volumio212 sudo[17961]: pam_unix(sudo:session): session closed for user root Jul 14 05:32:13 volumio212 volumio[17379]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.114 UA: okhttp/4.9.2 Total Clients: 2 Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetVisibleSources Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetState Jul 14 05:32:13 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 14 05:32:13 volumio212 volumio[17379]: info: Received Get System Info Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 14 05:32:13 volumio212 volumio[17379]: info: Discovery: Getting this device information Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetState Jul 14 05:32:13 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetState Jul 14 05:32:13 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:13 volumio212 volumio[17379]: info: Listing playlists Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 14 05:32:13 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 14 05:32:14 volumio212 volumio[17379]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jul 14 05:32:14 volumio212 volumio[17379]: verbose: New Socket.io Connection to 192.168.1.212 from 192.168.1.114 UA: okhttp/4.9.2 Total Clients: 3 Jul 14 05:32:14 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetState Jul 14 05:32:14 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:14 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 14 05:32:14 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 14 05:32:15 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 14 05:32:15 volumio212 volumio[17379]: info: Received Get System Info Jul 14 05:32:15 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 14 05:32:15 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 14 05:32:15 volumio212 volumio[17379]: info: Discovery: Getting this device information Jul 14 05:32:15 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetState Jul 14 05:32:15 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:15 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 14 05:32:15 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 14 05:32:16 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 14 05:32:16 volumio212 volumio[17379]: info: Received Get System Info Jul 14 05:32:16 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 14 05:32:16 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 14 05:32:16 volumio212 volumio[17379]: info: Discovery: Getting this device information Jul 14 05:32:16 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetState Jul 14 05:32:16 volumio212 volumio[17379]: info: CorePlayQueue::getTrack 0 Jul 14 05:32:16 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 14 05:32:16 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetBrowseSources Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetBrowseSources Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::volumioGetBrowseSources Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 14 05:32:19 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 14 05:32:23 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 14 05:32:24 volumio212 volumio[17379]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 14 05:32:24 volumio212 volumio[17379]: verbose: ControllerMpd::sendMpdCommand update Jul 14 05:32:24 volumio212 volumio[17379]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 05:32:24 volumio212 volumio[17379]: TypeError: Cannot read property 'then' of undefined Jul 14 05:32:24 volumio212 volumio[17379]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Jul 14 05:32:24 volumio212 volumio[17379]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15) Jul 14 05:32:24 volumio212 volumio[17379]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Jul 14 05:32:24 volumio212 volumio[17379]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1049:26) Jul 14 05:32:24 volumio212 volumio[17379]: at Socket.emit (events.js:315:20) Jul 14 05:32:24 volumio212 volumio[17379]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 14 05:32:24 volumio212 volumio[17379]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 14 05:32:24 volumio212 volumio[17379]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 05:32:25 volumio212 sudo[17977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-14 05:31 Jul 14 05:32:25 volumio212 sudo[17977]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"