-- 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"