-- Logs begin at Sat 2025-01-18 19:41:45 EST, end at Sat 2025-01-18 19:48:14 EST. -- Jan 18 19:46:00 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 19:46:03 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:03 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:03 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:08 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:08 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:08 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:13 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:13 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:13 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:16 volumio1 volumio[1027]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Jan 18 19:46:16 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Jan 18 19:46:16 volumio1 sudo[3085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jan 18 19:46:16 volumio1 sudo[3085]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:16 volumio1 sudo[3085]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:16 volumio1 sudo[3087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jan 18 19:46:16 volumio1 sudo[3087]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:16 volumio1 sudo[3087]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:16 volumio1 volumio[1027]: info: Permissions for /etc/network/interfaces set Jan 18 19:46:16 volumio1 volumio[1027]: info: Restarting Wireless Network Jan 18 19:46:16 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jan 18 19:46:16 volumio1 volumio[1027]: info: Discovery: Restarting Advertising due to device name change Jan 18 19:46:16 volumio1 sudo[3090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jan 18 19:46:16 volumio1 sudo[3090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:16 volumio1 systemd[1]: Starting Wireless Services... Jan 18 19:46:17 volumio1 wireless.js[3092]: WIRELESS: No wireless interface, exiting Jan 18 19:46:17 volumio1 iw[3103]: command failed: No such device (-19) Jan 18 19:46:17 volumio1 iwconfig[3106]: Error for wireless request "Set Power Management" (8B2C) : Jan 18 19:46:17 volumio1 iwconfig[3106]: SET failed on device wlan0 ; No such device. Jan 18 19:46:17 volumio1 systemd[1]: wireless.service: Succeeded. Jan 18 19:46:17 volumio1 systemd[1]: Started Wireless Services. Jan 18 19:46:17 volumio1 sudo[3090]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:18 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:18 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:18 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:21 volumio1 volumio[1027]: info: Discovery: Started advertising with name: Volumio1 Jan 18 19:46:22 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jan 18 19:46:22 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jan 18 19:46:22 volumio1 sudo[3130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 19:46:22 volumio1 sudo[3130]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:22 volumio1 sudo[3130]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:23 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:23 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:23 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jan 18 19:46:27 volumio1 volumio[1027]: info: Refreshing Cached IP Addresses Jan 18 19:46:27 volumio1 sudo[3190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jan 18 19:46:27 volumio1 sudo[3190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:27 volumio1 sudo[3190]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:27 volumio1 sudo[3193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 18 19:46:27 volumio1 sudo[3193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:27 volumio1 sudo[3195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 19:46:27 volumio1 sudo[3195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:27 volumio1 sudo[3193]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:27 volumio1 volumio[1027]: error: Cannot kill upmpdcli Error: Command failed: /usr/bin/sudo /usr/bin/killall upmpdcli Jan 18 19:46:27 volumio1 volumio[1027]: upmpdcli: no process found Jan 18 19:46:27 volumio1 sudo[3195]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:27 volumio1 volumio[1027]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:46:27 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 18 19:46:27 volumio1 volumio[1027]: info: Received Get System Info Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 19:46:27 volumio1 volumio[1027]: info: Discovery: Getting this device information Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:46:27 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:46:27 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:46:27 volumio1 volumio[1027]: info: Listing playlists Jan 18 19:46:27 volumio1 volumio[1027]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 18 19:46:27 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 18 19:46:28 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 18 19:46:28 volumio1 sudo[3203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 18 19:46:28 volumio1 sudo[3203]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:28 volumio1 sudo[3203]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:28 volumio1 sudo[3209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 18 19:46:28 volumio1 sudo[3209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:28 volumio1 sudo[3216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 18 19:46:28 volumio1 sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:28 volumio1 sudo[3223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 18 19:46:28 volumio1 sudo[3223]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:28 volumio1 sudo[3209]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:28 volumio1 sudo[3234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 18 19:46:28 volumio1 sudo[3216]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:28 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 18 19:46:28 volumio1 sudo[3234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:28 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 18 19:46:28 volumio1 sudo[3241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 19:46:28 volumio1 sudo[3241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:28 volumio1 sudo[3234]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:28 volumio1 sudo[3223]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:28 volumio1 sudo[3241]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:28 volumio1 sudo[3249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 18 19:46:28 volumio1 sudo[3249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:28 volumio1 sudo[3249]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:28 volumio1 volumio[1027]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 18 19:46:28 volumio1 volumio[1027]: wlan0 Interface doesn't support scanning. Jan 18 19:46:28 volumio1 volumio[1027]: info: Cannot use regular scanning, forcing with ap-force Jan 18 19:46:28 volumio1 sudo[3254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 18 19:46:28 volumio1 sudo[3254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:28 volumio1 sudo[3254]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:28 volumio1 volumio[1027]: command failed: No such device (-19) Jan 18 19:46:28 volumio1 volumio[1027]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 18 19:46:28 volumio1 volumio[1027]: command failed: No such device (-19) Jan 18 19:46:28 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:28 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:28 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:29 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 18 19:46:29 volumio1 volumio[1027]: info: Received Get System Info Jan 18 19:46:29 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 19:46:29 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 19:46:29 volumio1 volumio[1027]: info: Discovery: Getting this device information Jan 18 19:46:29 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:46:29 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:46:29 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 19:46:29 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 18 19:46:33 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:33 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:33 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:37 volumio1 sudo[3288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 18 19:46:37 volumio1 sudo[3288]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:37 volumio1 sudo[3288]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:37 volumio1 sudo[3290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 19:46:37 volumio1 sudo[3290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:37 volumio1 sudo[3290]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:37 volumio1 sudo[3294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 18 19:46:37 volumio1 sudo[3294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:46:37 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 18 19:46:37 volumio1 sudo[3294]: pam_unix(sudo:session): session closed for user root Jan 18 19:46:37 volumio1 volumio[1027]: info: Upmpdcli Daemon Started Jan 18 19:46:37 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 19:46:38 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:38 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:38 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:43 volumio1 ntpd[969]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 18 19:46:43 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:43 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:43 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:45 volumio1 kernel: usb 1-6.2: USB disconnect, device number 9 Jan 18 19:46:48 volumio1 ntpd[969]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 18 19:46:48 volumio1 ntpd[969]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 18 19:46:48 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:48 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:48 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:51 volumio1 ntpd[969]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 18 19:46:53 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:53 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:53 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:46:58 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:46:58 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:46:58 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:47:03 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:47:03 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:47:03 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:47:08 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:47:08 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:47:08 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:47:13 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:47:13 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:47:13 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:47:15 volumio1 kernel: usb 1-6.2: new high-speed USB device number 10 using xhci_hcd Jan 18 19:47:15 volumio1 kernel: usb 1-6.2: New USB device found, idVendor=7392, idProduct=7811, bcdDevice= 2.00 Jan 18 19:47:15 volumio1 kernel: usb 1-6.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jan 18 19:47:15 volumio1 kernel: usb 1-6.2: Product: 802.11n WLAN Adapter Jan 18 19:47:15 volumio1 kernel: usb 1-6.2: Manufacturer: Realtek Jan 18 19:47:15 volumio1 kernel: usb 1-6.2: SerialNumber: 00e04c000001 Jan 18 19:47:16 volumio1 kernel: rtl8192cu: Chip version 0x10 Jan 18 19:47:16 volumio1 kernel: rtl8192cu: Board Type 0 Jan 18 19:47:16 volumio1 kernel: rtl_usb: rx_max_size 15360, rx_urb_num 8, in_ep 1 Jan 18 19:47:16 volumio1 kernel: rtl8192cu: Loading firmware rtlwifi/rtl8192cufw_TMSC.bin Jan 18 19:47:16 volumio1 kernel: ieee80211 phy0: Selected rate control algorithm 'rtl_rc' Jan 18 19:47:16 volumio1 kernel: usbcore: registered new interface driver rtl8192cu Jan 18 19:47:16 volumio1 systemd-udevd[3419]: Using default interface naming scheme 'v240'. Jan 18 19:47:16 volumio1 kernel: usbcore: registered new interface driver rtl8xxxu Jan 18 19:47:16 volumio1 kernel: rtl8192cu: MAC auto ON okay! Jan 18 19:47:16 volumio1 systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 18 19:47:16 volumio1 kernel: rtl8192cu: Tx queue select: 0x05 Jan 18 19:47:16 volumio1 systemd[1]: Started Load/Save RF Kill Switch Status. Jan 18 19:47:16 volumio1 dhcpcd[894]: wlan0: waiting for carrier Jan 18 19:47:16 volumio1 systemd-udevd[3419]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 18 19:47:16 volumio1 dhcpcd[894]: wlan0: carrier acquired Jan 18 19:47:16 volumio1 dhcpcd[894]: wlan0: IAID 02:7c:71:9e Jan 18 19:47:16 volumio1 dhcpcd[894]: wlan0: adding address fe80::e462:c650:49fb:5c Jan 18 19:47:16 volumio1 dhcpcd[894]: ipv6_addaddr1: Permission denied Jan 18 19:47:16 volumio1 dhcpcd[894]: wlan0: carrier lost Jan 18 19:47:16 volumio1 systemd[1]: Found device EW-7811Un 802.11n Wireless Adapter [Realtek RTL8188CUS]. Jan 18 19:47:16 volumio1 systemd[1]: Started ifup for wlan0. Jan 18 19:47:16 volumio1 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jan 18 19:47:16 volumio1 sh[3447]: wlan0=wlan0 Jan 18 19:47:18 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:47:18 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:47:18 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:47:21 volumio1 systemd[1]: systemd-rfkill.service: Succeeded. Jan 18 19:47:23 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:47:23 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:47:23 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:47:25 volumio1 volumio[1027]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Jan 18 19:47:25 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Jan 18 19:47:25 volumio1 sudo[3566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jan 18 19:47:25 volumio1 sudo[3566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:25 volumio1 sudo[3566]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:25 volumio1 sudo[3568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jan 18 19:47:25 volumio1 sudo[3568]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:25 volumio1 sudo[3568]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:25 volumio1 volumio[1027]: info: Permissions for /etc/network/interfaces set Jan 18 19:47:25 volumio1 volumio[1027]: info: Restarting Wireless Network Jan 18 19:47:25 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jan 18 19:47:25 volumio1 volumio[1027]: info: Discovery: Restarting Advertising due to device name change Jan 18 19:47:25 volumio1 sudo[3572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jan 18 19:47:25 volumio1 sudo[3572]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:25 volumio1 systemd[1]: Starting Wireless Services... Jan 18 19:47:26 volumio1 wireless.js[3574]: Cleaning previous... Jan 18 19:47:26 volumio1 sudo[3588]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 18 19:47:26 volumio1 sudo[3588]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:26 volumio1 sudo[3588]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:26 volumio1 sudo[3590]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 18 19:47:26 volumio1 sudo[3590]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:26 volumio1 sudo[3590]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:26 volumio1 wireless.js[3574]: Stopped aP Jan 18 19:47:26 volumio1 sudo[3597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 18 19:47:26 volumio1 sudo[3597]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:26 volumio1 kernel: rtl8192cu: MAC auto ON okay! Jan 18 19:47:26 volumio1 kernel: rtl8192cu: Tx queue select: 0x05 Jan 18 19:47:26 volumio1 sudo[3597]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:26 volumio1 sudo[3599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 18 19:47:26 volumio1 sudo[3599]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:26 volumio1 sudo[3599]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:26 volumio1 sudo[3606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 18 19:47:26 volumio1 sudo[3606]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:26 volumio1 sudo[3606]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:26 volumio1 sudo[3608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 18 19:47:26 volumio1 sudo[3608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:27 volumio1 sudo[3608]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:27 volumio1 wireless.js[3574]: SETTING APPROPRIATE REG DOMAIN: US Jan 18 19:47:27 volumio1 sudo[3616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 18 19:47:27 volumio1 sudo[3616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:27 volumio1 sudo[3616]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:27 volumio1 sudo[3618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Jan 18 19:47:27 volumio1 sudo[3618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:27 volumio1 sudo[3618]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:27 volumio1 wireless.js[3574]: SUCCESSFULLY SET NEW REGDOMAIN: US Jan 18 19:47:27 volumio1 sudo[3625]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 18 19:47:27 volumio1 sudo[3625]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:27 volumio1 sudo[3625]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:27 volumio1 sudo[3627]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 18 19:47:27 volumio1 sudo[3627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:27 volumio1 sudo[3627]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:27 volumio1 kernel: rtl8192cu: MAC auto ON okay! Jan 18 19:47:27 volumio1 kernel: rtl8192cu: Tx queue select: 0x05 Jan 18 19:47:28 volumio1 avahi-daemon[782]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 18 19:47:28 volumio1 avahi-daemon[782]: New relevant interface wlan0.IPv4 for mDNS. Jan 18 19:47:28 volumio1 avahi-daemon[782]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jan 18 19:47:28 volumio1 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 18 19:47:28 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:47:28 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:47:28 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:47:28 volumio1 hostapd[3634]: Configuration file: /etc/hostapd/hostapd.conf Jan 18 19:47:28 volumio1 hostapd[3634]: Using interface wlan0 with hwaddr 80:1f:02:7c:71:9e and ssid "Volumio-11CB1" Jan 18 19:47:28 volumio1 hostapd[3634]: wlan0: interface state UNINITIALIZED->ENABLED Jan 18 19:47:28 volumio1 hostapd[3634]: wlan0: AP-ENABLED Jan 18 19:47:28 volumio1 dhcpcd[894]: wlan0: carrier acquired Jan 18 19:47:28 volumio1 dhcpcd[894]: wlan0: confile file changed, expiring leases Jan 18 19:47:28 volumio1 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 18 19:47:28 volumio1 dhcpcd[894]: wlan0: IAID 02:7c:71:9e Jan 18 19:47:28 volumio1 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 18 19:47:28 volumio1 iwconfig[3644]: Error for wireless request "Set Power Management" (8B2C) : Jan 18 19:47:28 volumio1 iwconfig[3644]: SET failed on device wlan0 ; Invalid argument. Jan 18 19:47:28 volumio1 systemd[1]: wireless.service: Succeeded. Jan 18 19:47:28 volumio1 dnsmasq[3642]: dnsmasq: syntax check OK. Jan 18 19:47:28 volumio1 systemd[1]: Started Wireless Services. Jan 18 19:47:28 volumio1 sudo[3572]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:28 volumio1 dnsmasq[3650]: started, version 2.80 cachesize 150 Jan 18 19:47:28 volumio1 dnsmasq[3650]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 18 19:47:28 volumio1 dnsmasq-dhcp[3650]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 18 19:47:28 volumio1 dnsmasq[3650]: reading /etc/resolv.conf Jan 18 19:47:28 volumio1 dnsmasq[3650]: using nameserver 208.67.222.222#53 Jan 18 19:47:28 volumio1 dnsmasq[3650]: using nameserver 208.67.220.220#53 Jan 18 19:47:28 volumio1 dnsmasq[3650]: read /etc/hosts - 1 addresses Jan 18 19:47:28 volumio1 systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 18 19:47:28 volumio1 systemd[1]: Reached target Host and Network Name Lookups. Jan 18 19:47:29 volumio1 dhcpcd[894]: wlan0: soliciting a DHCP lease Jan 18 19:47:29 volumio1 dhcpcd[894]: wlan0: soliciting an IPv6 router Jan 18 19:47:30 volumio1 ntpd[969]: Listen normally on 3 wlan0 192.168.211.1:123 Jan 18 19:47:30 volumio1 ntpd[969]: new interface(s) found: waking up resolver Jan 18 19:47:30 volumio1 volumio[1027]: info: Discovery: Started advertising with name: Volumio1 Jan 18 19:47:31 volumio1 volumio[3298]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 18 19:47:31 volumio1 volumio[1027]: info: Discovery: adding 9f82984f-c6f8-4275-914c-ad4bdfab4248 Jan 18 19:47:31 volumio1 volumio[1027]: info: Discovery: Found device Volumio1 Jan 18 19:47:31 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:47:31 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:47:32 volumio1 volumio[3298]: ...................................................................................................................................++++ Jan 18 19:47:32 volumio1 volumio[3298]: ..................................++++ Jan 18 19:47:32 volumio1 volumio[3298]: e is 65537 (0x010001) Jan 18 19:47:32 volumio1 volumio[3298]: writing RSA key Jan 18 19:47:33 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:47:33 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:47:33 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:47:33 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jan 18 19:47:33 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jan 18 19:47:33 volumio1 sudo[3696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 19:47:33 volumio1 sudo[3696]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:33 volumio1 sudo[3696]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:34 volumio1 dhcpcd[894]: wlan0: probing for an IPv4LL address Jan 18 19:47:38 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:47:38 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:47:38 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:47:38 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jan 18 19:47:38 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jan 18 19:47:38 volumio1 volumio[1027]: info: Refreshing Cached IP Addresses Jan 18 19:47:38 volumio1 sudo[3707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jan 18 19:47:38 volumio1 sudo[3707]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:38 volumio1 sudo[3707]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:38 volumio1 sudo[3709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 18 19:47:38 volumio1 sudo[3709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:38 volumio1 sudo[3709]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:38 volumio1 systemd[1]: upmpdcli.service: Succeeded. Jan 18 19:47:38 volumio1 sudo[3712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 19:47:38 volumio1 sudo[3712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:38 volumio1 sudo[3712]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:38 volumio1 volumio[1027]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:47:39 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 18 19:47:39 volumio1 volumio[1027]: info: Received Get System Info Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 19:47:39 volumio1 volumio[1027]: info: Discovery: Getting this device information Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:47:39 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:47:39 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:47:39 volumio1 volumio[1027]: info: Listing playlists Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 18 19:47:39 volumio1 sudo[3734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 18 19:47:39 volumio1 sudo[3734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:39 volumio1 sudo[3734]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:39 volumio1 sudo[3740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 18 19:47:39 volumio1 sudo[3740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:39 volumio1 sudo[3740]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:39 volumio1 sudo[3748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 18 19:47:39 volumio1 sudo[3748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:39 volumio1 sudo[3748]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:39 volumio1 sudo[3754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 18 19:47:39 volumio1 sudo[3754]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:39 volumio1 sudo[3754]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:39 volumio1 sudo[3759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 18 19:47:39 volumio1 sudo[3759]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:39 volumio1 sudo[3759]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:39 volumio1 sudo[3762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 19:47:39 volumio1 sudo[3762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:39 volumio1 sudo[3762]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 18 19:47:39 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 18 19:47:39 volumio1 sudo[3766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 18 19:47:39 volumio1 sudo[3766]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:39 volumio1 sudo[3766]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:39 volumio1 volumio[1027]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 18 19:47:39 volumio1 volumio[1027]: wlan0 Interface doesn't support scanning : Operation not supported Jan 18 19:47:39 volumio1 volumio[1027]: info: Cannot use regular scanning, forcing with ap-force Jan 18 19:47:39 volumio1 sudo[3769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 18 19:47:39 volumio1 sudo[3769]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:39 volumio1 dhcpcd[894]: wlan0: using IPv4LL address 169.254.161.146 Jan 18 19:47:39 volumio1 dhcpcd[894]: wlan0: adding route to 169.254.0.0/16 Jan 18 19:47:39 volumio1 avahi-daemon[782]: Registering new address record for 169.254.161.146 on wlan0.IPv4. Jan 18 19:47:39 volumio1 dhcpcd[894]: wlan0: adding default route Jan 18 19:47:40 volumio1 sudo[3769]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:40 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 18 19:47:40 volumio1 volumio[1027]: info: Received Get System Info Jan 18 19:47:40 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 19:47:40 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 19:47:40 volumio1 volumio[1027]: info: Discovery: Getting this device information Jan 18 19:47:40 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:47:40 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:47:40 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 19:47:41 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 18 19:47:42 volumio1 ntpd[969]: Listen normally on 4 wlan0 169.254.161.146:123 Jan 18 19:47:42 volumio1 ntpd[969]: new interface(s) found: waking up resolver Jan 18 19:47:43 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:47:48 volumio1 sudo[3792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 18 19:47:48 volumio1 sudo[3792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:48 volumio1 sudo[3792]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:48 volumio1 sudo[3794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 19:47:48 volumio1 sudo[3794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:48 volumio1 sudo[3794]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:48 volumio1 sudo[3798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 18 19:47:48 volumio1 sudo[3798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:48 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 18 19:47:48 volumio1 sudo[3798]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:48 volumio1 volumio[1027]: info: Upmpdcli Daemon Started Jan 18 19:47:48 volumio1 volumio[3800]: writing RSA key Jan 18 19:47:48 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 19:47:57 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jan 18 19:47:57 volumio1 volumio[1027]: info: Saving new wireless network Jan 18 19:47:57 volumio1 sudo[3852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jan 18 19:47:57 volumio1 sudo[3852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:57 volumio1 sudo[3852]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:57 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jan 18 19:47:57 volumio1 volumio[1027]: info: Discovery: Restarting Advertising due to device name change Jan 18 19:47:57 volumio1 sudo[3855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jan 18 19:47:57 volumio1 sudo[3855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:57 volumio1 systemd[1]: Starting Wireless Services... Jan 18 19:47:57 volumio1 wireless.js[3857]: Cleaning previous... Jan 18 19:47:57 volumio1 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jan 18 19:47:57 volumio1 dnsmasq[3650]: exiting on receipt of SIGTERM Jan 18 19:47:57 volumio1 systemd[1]: dnsmasq.service: Succeeded. Jan 18 19:47:57 volumio1 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jan 18 19:47:57 volumio1 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 18 19:47:57 volumio1 dhcpcd[894]: wlan0: carrier lost Jan 18 19:47:57 volumio1 avahi-daemon[782]: Withdrawing address record for 169.254.161.146 on wlan0. Jan 18 19:47:57 volumio1 dhcpcd[894]: wlan0: deleting route to 169.254.0.0/16 Jan 18 19:47:57 volumio1 dhcpcd[894]: wlan0: deleting default route Jan 18 19:47:57 volumio1 systemd[1]: hostapd.service: Succeeded. Jan 18 19:47:57 volumio1 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 18 19:47:57 volumio1 sudo[3900]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 18 19:47:57 volumio1 sudo[3900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:57 volumio1 avahi-daemon[782]: Withdrawing address record for 192.168.211.1 on wlan0. Jan 18 19:47:57 volumio1 avahi-daemon[782]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 18 19:47:57 volumio1 avahi-daemon[782]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 18 19:47:57 volumio1 sudo[3900]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:57 volumio1 volumio[1027]: info: Discovery: A device disappeared from network Jan 18 19:47:57 volumio1 sudo[3902]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 18 19:47:57 volumio1 sudo[3902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:57 volumio1 sudo[3902]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:58 volumio1 wireless.js[3857]: Stopped aP Jan 18 19:47:58 volumio1 sudo[3909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 18 19:47:58 volumio1 sudo[3909]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:58 volumio1 kernel: rtl8192cu: MAC auto ON okay! Jan 18 19:47:58 volumio1 kernel: rtl8192cu: Tx queue select: 0x05 Jan 18 19:47:58 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:47:58 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:47:58 volumio1 sudo[3909]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:58 volumio1 sudo[3912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 18 19:47:58 volumio1 sudo[3912]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:58 volumio1 sudo[3912]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:58 volumio1 sudo[3919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 18 19:47:58 volumio1 sudo[3919]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:58 volumio1 sudo[3919]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:58 volumio1 sudo[3921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 18 19:47:58 volumio1 sudo[3921]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:59 volumio1 sudo[3921]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:59 volumio1 wireless.js[3857]: SETTING APPROPRIATE REG DOMAIN: US Jan 18 19:47:59 volumio1 sudo[3940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 18 19:47:59 volumio1 sudo[3940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:59 volumio1 sudo[3940]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:59 volumio1 sudo[3942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Jan 18 19:47:59 volumio1 sudo[3942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:59 volumio1 sudo[3942]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:59 volumio1 wireless.js[3857]: SUCCESSFULLY SET NEW REGDOMAIN: US Jan 18 19:47:59 volumio1 wireless.js[3857]: Start wireless flow Jan 18 19:47:59 volumio1 wireless.js[3857]: Stopped hotspot (if there).. Jan 18 19:47:59 volumio1 sudo[3947]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 18 19:47:59 volumio1 sudo[3947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:59 volumio1 sudo[3947]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:59 volumio1 sudo[3949]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 18 19:47:59 volumio1 sudo[3949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:59 volumio1 sudo[3949]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:59 volumio1 wireless.js[3857]: DHCP IP Jan 18 19:47:59 volumio1 wireless.js[3857]: Start ap Jan 18 19:47:59 volumio1 wpa_supplicant[3951]: Successfully initialized wpa_supplicant Jan 18 19:47:59 volumio1 sudo[3952]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 18 19:47:59 volumio1 sudo[3952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:47:59 volumio1 kernel: rtl8192cu: MAC auto ON okay! Jan 18 19:47:59 volumio1 dhcpcd[3953]: sending commands to master dhcpcd process Jan 18 19:47:59 volumio1 dhcpcd[894]: control command: /sbin/dhcpcd Jan 18 19:47:59 volumio1 sudo[3952]: pam_unix(sudo:session): session closed for user root Jan 18 19:47:59 volumio1 kernel: rtl8192cu: Tx queue select: 0x05 Jan 18 19:48:00 volumio1 wireless.js[3857]: trying... Jan 18 19:48:00 volumio1 sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 18 19:48:00 volumio1 sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:00 volumio1 sudo[3956]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:01 volumio1 wpa_supplicant[3954]: wlan0: SME: Trying to authenticate with f0:09:0d:ff:36:77 (SSID='MTN' freq=2422 MHz) Jan 18 19:48:01 volumio1 kernel: wlan0: authenticate with f0:09:0d:ff:36:77 Jan 18 19:48:01 volumio1 kernel: wlan0: 80 MHz not supported, disabling VHT Jan 18 19:48:01 volumio1 kernel: wlan0: send auth to f0:09:0d:ff:36:77 (try 1/3) Jan 18 19:48:01 volumio1 wpa_supplicant[3954]: wlan0: Trying to associate with f0:09:0d:ff:36:77 (SSID='MTN' freq=2422 MHz) Jan 18 19:48:01 volumio1 kernel: wlan0: authenticated Jan 18 19:48:01 volumio1 kernel: wlan0: associate with f0:09:0d:ff:36:77 (try 1/3) Jan 18 19:48:01 volumio1 wpa_supplicant[3954]: wlan0: Associated with f0:09:0d:ff:36:77 Jan 18 19:48:01 volumio1 wpa_supplicant[3954]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 18 19:48:01 volumio1 wpa_supplicant[3954]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jan 18 19:48:01 volumio1 kernel: wlan0: RX AssocResp from f0:09:0d:ff:36:77 (capab=0x1411 status=0 aid=22) Jan 18 19:48:01 volumio1 kernel: wlan0: associated Jan 18 19:48:01 volumio1 kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by f0:09:0d:ff:36:77 Jan 18 19:48:01 volumio1 wpa_supplicant[3954]: wlan0: WPA: Key negotiation completed with f0:09:0d:ff:36:77 [PTK=CCMP GTK=CCMP] Jan 18 19:48:01 volumio1 wpa_supplicant[3954]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:09:0d:ff:36:77 completed [id=0 id_str=] Jan 18 19:48:01 volumio1 dhcpcd[894]: wlan0: carrier acquired Jan 18 19:48:01 volumio1 dhcpcd[894]: wlan0: IAID 02:7c:71:9e Jan 18 19:48:01 volumio1 dhcpcd[894]: wlan0: soliciting a DHCP lease Jan 18 19:48:01 volumio1 wireless.js[3857]: trying... Jan 18 19:48:01 volumio1 sudo[3975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 18 19:48:01 volumio1 sudo[3975]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:01 volumio1 sudo[3975]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:01 volumio1 dhcpcd[894]: wlan0: soliciting an IPv6 router Jan 18 19:48:02 volumio1 ntpd[969]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 18 19:48:02 volumio1 ntpd[969]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 18 19:48:02 volumio1 ntpd[969]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 18 19:48:02 volumio1 ntpd[969]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 18 19:48:02 volumio1 wireless.js[3857]: trying... Jan 18 19:48:02 volumio1 sudo[3978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 18 19:48:02 volumio1 sudo[3978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:02 volumio1 sudo[3978]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:02 volumio1 volumio[1027]: info: Discovery: Started advertising with name: Volumio1 Jan 18 19:48:03 volumio1 ntpd[969]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=33 secs Jan 18 19:48:03 volumio1 ntpd[969]: Deleting interface #4 wlan0, 169.254.161.146#123, interface stats: received=0, sent=0, dropped=0, active_time=21 secs Jan 18 19:48:03 volumio1 wireless.js[3857]: trying... Jan 18 19:48:03 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:48:03 volumio1 sudo[3982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 18 19:48:03 volumio1 sudo[3982]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:03 volumio1 sudo[3982]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:03 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:48:03 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:48:04 volumio1 wireless.js[3857]: trying... Jan 18 19:48:04 volumio1 sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 18 19:48:04 volumio1 sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:04 volumio1 sudo[3990]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:04 volumio1 dhcpcd[894]: wlan0: offered 192.168.0.128 from 192.168.0.1 Jan 18 19:48:04 volumio1 dhcpcd[894]: wlan0: probing address 192.168.0.128/24 Jan 18 19:48:05 volumio1 wireless.js[3857]: trying... Jan 18 19:48:05 volumio1 sudo[3993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 18 19:48:05 volumio1 sudo[3993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:05 volumio1 sudo[3993]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:06 volumio1 wireless.js[3857]: trying... Jan 18 19:48:06 volumio1 sudo[3996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 18 19:48:06 volumio1 sudo[3996]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:06 volumio1 sudo[3996]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:07 volumio1 wireless.js[3857]: trying... Jan 18 19:48:07 volumio1 sudo[4002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 18 19:48:07 volumio1 sudo[4002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:07 volumio1 sudo[4002]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:08 volumio1 wireless.js[3857]: trying... Jan 18 19:48:08 volumio1 sudo[4005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 18 19:48:08 volumio1 sudo[4005]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:08 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:48:08 volumio1 sudo[4005]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:08 volumio1 volumio-time-update[790]: volumio-time-update-util: Date not found in response Jan 18 19:48:08 volumio1 volumio-time-update[790]: volumio-time-update-util: Retrying in 5 seconds... Jan 18 19:48:09 volumio1 dhcpcd[894]: wlan0: leased 192.168.0.128 for 7200 seconds Jan 18 19:48:09 volumio1 dhcpcd[894]: wlan0: adding route to 192.168.0.0/24 Jan 18 19:48:09 volumio1 avahi-daemon[782]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.128. Jan 18 19:48:09 volumio1 dhcpcd[894]: wlan0: adding default route via 192.168.0.1 Jan 18 19:48:09 volumio1 avahi-daemon[782]: New relevant interface wlan0.IPv4 for mDNS. Jan 18 19:48:09 volumio1 avahi-daemon[782]: Registering new address record for 192.168.0.128 on wlan0.IPv4. Jan 18 19:48:09 volumio1 ntpd[969]: ntpd exiting on signal 15 (Terminated) Jan 18 19:48:09 volumio1 systemd[1]: Stopping Network Time Service... Jan 18 19:48:09 volumio1 systemd[1]: ntp.service: Succeeded. Jan 18 19:48:09 volumio1 systemd[1]: Stopped Network Time Service. Jan 18 19:48:09 volumio1 systemd[1]: Starting Network Time Service... Jan 18 19:48:09 volumio1 ntpd[4062]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 18 19:48:09 volumio1 ntpd[4062]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Jan 18 19:48:09 volumio1 systemd[1]: Started Network Time Service. Jan 18 19:48:09 volumio1 ntpd[4068]: proto: precision = 0.066 usec (-24) Jan 18 19:48:09 volumio1 ntpd[4068]: Listen and drop on 0 v6wildcard [::]:123 Jan 18 19:48:09 volumio1 ntpd[4068]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 18 19:48:09 volumio1 ntpd[4068]: Listen normally on 2 lo 127.0.0.1:123 Jan 18 19:48:09 volumio1 ntpd[4068]: Listen normally on 3 wlan0 192.168.0.128:123 Jan 18 19:48:09 volumio1 ntpd[4068]: Listening on routing socket on fd #20 for interface updates Jan 18 19:48:09 volumio1 ntpd[4068]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 18 19:48:09 volumio1 ntpd[4068]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 18 19:48:09 volumio1 volumio[1027]: info: Discovery: adding a9dd5703-26d1-4609-8537-c5d280a10be3 Jan 18 19:48:09 volumio1 volumio[1027]: info: Discovery: Found device tablet Jan 18 19:48:09 volumio1 volumio[1027]: info: Discovery: Connecting to remote: 192.168.0.104 Jan 18 19:48:09 volumio1 wireless.js[3857]: trying... Jan 18 19:48:09 volumio1 sudo[4073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 18 19:48:09 volumio1 sudo[4073]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:09 volumio1 sudo[4073]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:09 volumio1 wireless.js[3857]: Connected to: ----MTN Jan 18 19:48:09 volumio1 wireless.js[3857]: ---- Jan 18 19:48:09 volumio1 sudo[4076]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 19:48:09 volumio1 sudo[4076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:09 volumio1 sudo[4076]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:09 volumio1 wireless.js[3857]: ... joined AP, wlan0 IPv4 is 192.168.0.128, ipV6 is undefined Jan 18 19:48:09 volumio1 wireless.js[3857]: It's done! AP Jan 18 19:48:09 volumio1 iwconfig[4080]: Error for wireless request "Set Power Management" (8B2C) : Jan 18 19:48:09 volumio1 iwconfig[4080]: SET failed on device wlan0 ; Operation not supported. Jan 18 19:48:09 volumio1 systemd[1]: Started Wireless Services. Jan 18 19:48:09 volumio1 sudo[3855]: pam_unix(sudo:session): session closed for user root Jan 18 19:48:09 volumio1 volumio[1027]: info: Discovery: Connected to remote: 192.168.0.104 Jan 18 19:48:10 volumio1 volumio[1027]: verbose: New Socket.io Connection to 192.168.0.128:3000 from 192.168.0.104 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 18 19:48:10 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 18 19:48:10 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:48:10 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:48:10 volumio1 volumio[1027]: info: Discovery: adding 9f82984f-c6f8-4275-914c-ad4bdfab4248 Jan 18 19:48:10 volumio1 volumio[1027]: info: Discovery: Found device Volumio1 Jan 18 19:48:10 volumio1 volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 18 19:48:10 volumio1 volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 18 19:48:13 volumio1 volumio-time-update[790]: volumio-time-update-util: Fetching time from Volumio... Jan 18 19:48:13 volumio1 volumio-time-update[790]: volumio-time-update-util: Setting system time to: 2025-01-18 19:41:45 Jan 18 19:48:13 volumio1 sudo[4099]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-01-18 19:41:45 Jan 18 19:48:13 volumio1 sudo[4099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 19:48:13 volumio1 dbus-daemon[778]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.31' (uid=0 pid=4100 comm="timedatectl set-time 2025-01-18 19:41:45 ") Jan 18 19:48:14 volumio1 systemd[1]: Starting Time & Date Service... Jan 18 19:48:14 volumio1 dbus-daemon[778]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 18 19:48:14 volumio1 systemd[1]: Started Time & Date Service. Jan 18 19:46:00 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:04 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:08 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:12 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:16 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:20 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:24 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:24 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jan 18 19:46:24 volumio1 volumio[1027]: info: Adding a new share Jan 18 19:46:24 volumio1 volumio[1027]: info: Checking key NasMounts.108020cf-36d9-403f-950b-9af79a62d1f6 Jan 18 19:46:24 volumio1 volumio[1027]: info: No correspondence found in configuration for share DS223j on IP 192.168.0.159 Jan 18 19:46:24 volumio1 volumio[1027]: info: Error mounting DS223j on IP 192.168.0.159 : Something is already mounted on /mnt/NAS/DS223j Jan 18 19:46:25 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 18 19:46:28 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:32 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:36 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:40 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:44 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:48 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:52 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:46:56 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:47:00 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:47:04 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:47:05 volumio1 volumio[1027]: info: MRS: Updating multiroomSync output Jan 18 19:47:08 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:47:09 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 18 19:47:09 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 18 19:47:12 volumio1 volumio[1027]: info: Error browsing device DS223j for shares : Error: Command failed: echo volumio | smbclient -L DS223j.local. Jan 18 19:47:12 volumio1 volumio[1027]: Unable to initialize messaging context Jan 18 19:47:12 volumio1 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 18 19:47:14 volumio1 volumio[1027]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 18 19:47:14 volumio1 volumio[1027]: Error: dns service error: unknown Jan 18 19:47:14 volumio1 volumio[1027]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jan 18 19:47:14 volumio1 volumio[1027]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jan 18 19:47:14 volumio1 volumio[1027]: errorCode: -65537 Jan 18 19:47:14 volumio1 volumio[1027]: } Jan 18 19:47:14 volumio1 volumio[1027]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 18 19:47:14 volumio1 sudo[5421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-18 19:46 Jan 18 19:47:14 volumio1 sudo[5421]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"