-- Logs begin at Sun 2024-07-21 05:44:42 UTC, end at Sun 2024-07-21 06:03:34 UTC. -- Jul 21 06:02:15 volumio startx[991]: (EE) client bug: timer event7 debounce short: offset negative (-1ms) Jul 21 06:02:15 volumio volumio[987]: info: CoreCommandRouter::volumioPause Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::pause Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::stPlaybackTimer Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::servicePause Jul 21 06:02:15 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:15 volumio volumio[987]: info: CoreCommandRouter::servicePause Jul 21 06:02:15 volumio volumio[987]: info: ControllerMpd::pause Jul 21 06:02:15 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand pause Jul 21 06:02:15 volumio volumio[987]: info: sendMpdCommand pause took 7 milliseconds Jul 21 06:02:15 volumio volumio[987]: info: Jul 21 06:02:15 volumio volumio[987]: ---------------------------- MPD announces state update: player Jul 21 06:02:15 volumio volumio[987]: info: ControllerMpd::getState Jul 21 06:02:15 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Jul 21 06:02:15 volumio volumio[987]: info: Jul 21 06:02:15 volumio volumio[987]: ---------------------------- MPD announces state update: player Jul 21 06:02:15 volumio volumio[987]: info: ControllerMpd::getState Jul 21 06:02:15 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Jul 21 06:02:15 volumio volumio[987]: info: sendMpdCommand status took 8 milliseconds Jul 21 06:02:15 volumio volumio[987]: info: sendMpdCommand status took 6 milliseconds Jul 21 06:02:15 volumio volumio[987]: verbose: ControllerMpd::parseState Jul 21 06:02:15 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 06:02:15 volumio volumio[987]: verbose: ControllerMpd::parseState Jul 21 06:02:15 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 06:02:15 volumio volumio[987]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 21 06:02:15 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Jul 21 06:02:15 volumio volumio[987]: info: ControllerMpd::pushState Jul 21 06:02:15 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jul 21 06:02:15 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:15 volumio volumio[987]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":268002,"duration":320,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"16934 Kbps","isStreaming":false,"title":"音轨 01 (2).dff","artist":null,"album":null,"uri":"USB/音乐测试盘/多声道/DSD/音轨 01 (2).dff","trackType":"dff"} Jul 21 06:02:15 volumio volumio[987]: verbose: CURRENT POSITION 1 Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::syncState stateService pause Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus pause Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::pushState Jul 21 06:02:15 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:15 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 06:02:15 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::stPlaybackTimer Jul 21 06:02:15 volumio volumio[987]: info: ------------------------------ 61ms Jul 21 06:02:15 volumio volumio[987]: info: sendMpdCommand playlistinfo took 46 milliseconds Jul 21 06:02:15 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Jul 21 06:02:15 volumio volumio[987]: info: ControllerMpd::pushState Jul 21 06:02:15 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jul 21 06:02:15 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:15 volumio volumio[987]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":268002,"duration":320,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"16934 Kbps","isStreaming":false,"title":"音轨 01 (2).dff","artist":null,"album":null,"uri":"USB/音乐测试盘/多声道/DSD/音轨 01 (2).dff","trackType":"dff"} Jul 21 06:02:15 volumio volumio[987]: verbose: CURRENT POSITION 1 Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::syncState stateService pause Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus pause Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::pushState Jul 21 06:02:15 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:15 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:15 volumio volumio[987]: info: CoreStateMachine::stPlaybackTimer Jul 21 06:02:15 volumio volumio[987]: info: ------------------------------ 93ms Jul 21 06:02:25 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jul 21 06:02:25 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::volumioClearQueue Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::ClearQueue Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::stop Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::updateTrackBlock Jul 21 06:02:30 volumio volumio[987]: info: CorePlayQueue::getTrackBlock Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::stPlaybackTimer Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::pushState Jul 21 06:02:30 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::serviceStop Jul 21 06:02:30 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::serviceStop Jul 21 06:02:30 volumio volumio[987]: info: ControllerMpd::stop Jul 21 06:02:30 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 06:02:30 volumio volumio[987]: info: CorePlayQueue::clearPlayQueue Jul 21 06:02:30 volumio volumio[987]: info: CorePlayQueue::saveQueue Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::volumioPushQueue Jul 21 06:02:30 volumio volumio[987]: info: Jul 21 06:02:30 volumio volumio[987]: ---------------------------- MPD announces state update: player Jul 21 06:02:30 volumio volumio[987]: info: sendMpdCommand stop took 49 milliseconds Jul 21 06:02:30 volumio volumio[987]: info: ControllerMpd::getState Jul 21 06:02:30 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Jul 21 06:02:30 volumio volumio[987]: info: sendMpdCommand status took 6 milliseconds Jul 21 06:02:30 volumio volumio[987]: verbose: ControllerMpd::parseState Jul 21 06:02:30 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 06:02:30 volumio volumio[987]: info: Jul 21 06:02:30 volumio volumio[987]: ---------------------------- MPD announces state update: player Jul 21 06:02:30 volumio volumio[987]: info: ControllerMpd::getState Jul 21 06:02:30 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Jul 21 06:02:30 volumio volumio[987]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 21 06:02:30 volumio volumio[987]: info: sendMpdCommand status took 4 milliseconds Jul 21 06:02:30 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Jul 21 06:02:30 volumio volumio[987]: verbose: ControllerMpd::parseState Jul 21 06:02:30 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 06:02:30 volumio volumio[987]: info: ControllerMpd::pushState Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::pushState Jul 21 06:02:30 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:30 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:30 volumio volumio[987]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"音轨 01 (2).dff","artist":null,"album":null,"uri":"USB/音乐测试盘/多声道/DSD/音轨 01 (2).dff","trackType":"dff"} Jul 21 06:02:30 volumio volumio[987]: verbose: CURRENT POSITION 1 Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::syncState stateService stop Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus stop Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::pushState Jul 21 06:02:30 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:30 volumio volumio[987]: info: No code Jul 21 06:02:30 volumio volumio[987]: info: CoreStateMachine::pushState Jul 21 06:02:30 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:30 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:31 volumio volumio[987]: info: ------------------------------ 94ms Jul 21 06:02:31 volumio volumio[987]: info: sendMpdCommand playlistinfo took 77 milliseconds Jul 21 06:02:31 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Jul 21 06:02:31 volumio volumio[987]: info: ControllerMpd::pushState Jul 21 06:02:31 volumio volumio[987]: info: CoreCommandRouter::servicePushState Jul 21 06:02:31 volumio volumio[987]: info: CoreStateMachine::pushState Jul 21 06:02:31 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:31 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:31 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:31 volumio volumio[987]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"音轨 01 (2).dff","artist":null,"album":null,"uri":"USB/音乐测试盘/多声道/DSD/音轨 01 (2).dff","trackType":"dff"} Jul 21 06:02:31 volumio volumio[987]: verbose: CURRENT POSITION 1 Jul 21 06:02:31 volumio volumio[987]: info: CoreStateMachine::syncState stateService stop Jul 21 06:02:31 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus stop Jul 21 06:02:31 volumio volumio[987]: info: CoreStateMachine::pushState Jul 21 06:02:31 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:31 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:31 volumio volumio[987]: info: No code Jul 21 06:02:31 volumio volumio[987]: info: CoreStateMachine::pushState Jul 21 06:02:31 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:31 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:31 volumio volumio[987]: info: ------------------------------ 167ms Jul 21 06:02:34 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jul 21 06:02:34 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:02:44 volumio volumio[987]: info: CoreCommandRouter::volumioClearQueue Jul 21 06:02:44 volumio volumio[987]: info: CoreStateMachine::ClearQueue Jul 21 06:02:44 volumio volumio[987]: info: CoreStateMachine::stop Jul 21 06:02:44 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 06:02:44 volumio volumio[987]: info: CorePlayQueue::clearPlayQueue Jul 21 06:02:44 volumio volumio[987]: info: CorePlayQueue::saveQueue Jul 21 06:02:44 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Jul 21 06:02:44 volumio volumio[987]: info: CoreCommandRouter::volumioPushQueue Jul 21 06:02:47 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jul 21 06:02:47 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:03:01 volumio volumio[987]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 06:03:01 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 21 06:03:02 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 21 06:03:03 volumio sudo[3079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 21 06:03:03 volumio sudo[3079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:03 volumio sudo[3079]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:03 volumio sudo[3084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 21 06:03:03 volumio sudo[3084]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:03 volumio sudo[3084]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:03 volumio sudo[3092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 21 06:03:03 volumio sudo[3092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:03 volumio sudo[3092]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:03 volumio sudo[3097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 21 06:03:03 volumio sudo[3097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:03 volumio sudo[3097]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:03 volumio sudo[3104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 21 06:03:03 volumio sudo[3104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:03 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 21 06:03:03 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 21 06:03:03 volumio sudo[3104]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:03 volumio sudo[3107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 21 06:03:03 volumio sudo[3107]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:03 volumio sudo[3107]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:03 volumio sudo[3111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 21 06:03:03 volumio sudo[3111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:06 volumio wpa_supplicant[905]: nl80211: send_and_recv->nl_recvmsgs failed: -33 Jul 21 06:03:06 volumio sudo[3111]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jul 21 06:03:20 volumio volumio[987]: info: Saving new wireless network Jul 21 06:03:20 volumio sudo[3159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jul 21 06:03:20 volumio sudo[3159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:20 volumio sudo[3159]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:20 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 21 06:03:20 volumio volumio[987]: info: Discovery: Restarting Advertising due to device name change Jul 21 06:03:20 volumio sudo[3162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jul 21 06:03:20 volumio sudo[3162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:20 volumio kernel: wlan0: deauthenticating from dc:73:85:f9:dd:e9 by local choice (Reason: 3=DEAUTH_LEAVING) Jul 21 06:03:20 volumio dhcpcd[607]: wlan0: carrier lost Jul 21 06:03:20 volumio systemd[1]: Stopping Wireless Services... Jul 21 06:03:20 volumio wpa_supplicant[905]: wlan0: CTRL-EVENT-DISCONNECTED bssid=dc:73:85:f9:dd:e9 reason=3 locally_generated=1 Jul 21 06:03:20 volumio avahi-daemon[559]: Withdrawing address record for 192.168.3.189 on wlan0. Jul 21 06:03:20 volumio avahi-daemon[559]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.189. Jul 21 06:03:20 volumio avahi-daemon[559]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 21 06:03:20 volumio volumio[987]: info: Discovery: A device disappeared from network Jul 21 06:03:20 volumio volumio[987]: info: Discovery: Device volumio disappeared from network Jul 21 06:03:20 volumio dhcpcd[607]: wlan0: deleting route to 192.168.3.0/24 Jul 21 06:03:20 volumio dhcpcd[607]: wlan0: deleting default route via 192.168.3.1 Jul 21 06:03:20 volumio dhcpcd[607]: wlan0: dhcp_envoption 213: Operation not supported Jul 21 06:03:20 volumio dhcpcd[607]: wlan0: dhcp_envoption 213: Operation not supported Jul 21 06:03:20 volumio wpa_supplicant[905]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 21 06:03:20 volumio wpa_supplicant[905]: wlan0: CTRL-EVENT-TERMINATING Jul 21 06:03:20 volumio systemd[1]: wireless.service: Succeeded. Jul 21 06:03:20 volumio systemd[1]: Stopped Wireless Services. Jul 21 06:03:20 volumio systemd[1]: Starting Wireless Services... Jul 21 06:03:21 volumio wireless.js[3186]: Cleaning previous... Jul 21 06:03:21 volumio sudo[3204]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 21 06:03:21 volumio sudo[3204]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:21 volumio sudo[3204]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:22 volumio sudo[3206]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 21 06:03:22 volumio sudo[3206]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:22 volumio sudo[3206]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:22 volumio ntpd[1002]: Deleting interface #3 wlan0, 192.168.3.189#123, interface stats: received=175, sent=195, dropped=0, active_time=1097 secs Jul 21 06:03:22 volumio ntpd[1002]: 84.16.73.33 local addr 192.168.3.189 -> Jul 21 06:03:22 volumio ntpd[1002]: 193.182.111.142 local addr 192.168.3.189 -> Jul 21 06:03:22 volumio ntpd[1002]: 193.182.111.12 local addr 192.168.3.189 -> Jul 21 06:03:22 volumio ntpd[1002]: 202.112.29.82 local addr 192.168.3.189 -> Jul 21 06:03:22 volumio ntpd[1002]: 193.182.111.143 local addr 192.168.3.189 -> Jul 21 06:03:22 volumio ntpd[1002]: 193.182.111.141 local addr 192.168.3.189 -> Jul 21 06:03:22 volumio ntpd[1002]: 202.118.1.81 local addr 192.168.3.189 -> Jul 21 06:03:22 volumio ntpd[1002]: 193.182.111.14 local addr 192.168.3.189 -> Jul 21 06:03:22 volumio wireless.js[3186]: Stopped aP Jul 21 06:03:22 volumio sudo[3213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 21 06:03:22 volumio sudo[3213]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:22 volumio sudo[3213]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:22 volumio sudo[3215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 21 06:03:22 volumio sudo[3215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:22 volumio sudo[3215]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:22 volumio sudo[3222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 21 06:03:22 volumio sudo[3222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:22 volumio sudo[3222]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:22 volumio sudo[3224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 21 06:03:22 volumio sudo[3224]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:23 volumio sudo[3224]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:23 volumio wireless.js[3186]: SETTING APPROPRIATE REG DOMAIN: CN Jul 21 06:03:23 volumio sudo[3229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 21 06:03:23 volumio sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:23 volumio sudo[3229]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:23 volumio sudo[3231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Jul 21 06:03:23 volumio sudo[3231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:23 volumio sudo[3231]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:23 volumio wireless.js[3186]: SUCCESSFULLY SET NEW REGDOMAIN: CN Jul 21 06:03:23 volumio wireless.js[3186]: Start wireless flow Jul 21 06:03:23 volumio wireless.js[3186]: Stopped hotspot (if there).. Jul 21 06:03:23 volumio sudo[3236]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 21 06:03:23 volumio sudo[3236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:23 volumio sudo[3236]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:23 volumio sudo[3238]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 21 06:03:23 volumio sudo[3238]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:23 volumio sudo[3238]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:23 volumio wireless.js[3186]: DHCP IP Jul 21 06:03:23 volumio wireless.js[3186]: Start ap Jul 21 06:03:23 volumio wpa_supplicant[3240]: Successfully initialized wpa_supplicant Jul 21 06:03:23 volumio sudo[3241]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 21 06:03:23 volumio sudo[3241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:23 volumio dhcpcd[3242]: sending commands to master dhcpcd process Jul 21 06:03:23 volumio dhcpcd[607]: control command: /sbin/dhcpcd Jul 21 06:03:23 volumio sudo[3241]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:24 volumio wireless.js[3186]: trying... Jul 21 06:03:24 volumio wpa_supplicant[3243]: wlan0: SME: Trying to authenticate with 60:aa:ef:33:83:00 (SSID='ZJHOME' freq=2462 MHz) Jul 21 06:03:24 volumio sudo[3245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 21 06:03:24 volumio kernel: wlan0: authenticate with 60:aa:ef:33:83:00 Jul 21 06:03:24 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Jul 21 06:03:24 volumio sudo[3245]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:24 volumio sudo[3245]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:24 volumio kernel: wlan0: send auth to 60:aa:ef:33:83:00 (try 1/3) Jul 21 06:03:24 volumio wpa_supplicant[3243]: wlan0: Trying to associate with 60:aa:ef:33:83:00 (SSID='ZJHOME' freq=2462 MHz) Jul 21 06:03:24 volumio kernel: wlan0: authenticated Jul 21 06:03:24 volumio kernel: wlan0: associate with 60:aa:ef:33:83:00 (try 1/3) Jul 21 06:03:24 volumio kernel: wlan0: RX AssocResp from 60:aa:ef:33:83:00 (capab=0x1431 status=0 aid=7) Jul 21 06:03:24 volumio kernel: wlan0: associated Jul 21 06:03:24 volumio wpa_supplicant[3243]: wlan0: Associated with 60:aa:ef:33:83:00 Jul 21 06:03:24 volumio wpa_supplicant[3243]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 21 06:03:24 volumio wpa_supplicant[3243]: wlan0: WPA: Key negotiation completed with 60:aa:ef:33:83:00 [PTK=CCMP GTK=CCMP] Jul 21 06:03:24 volumio wpa_supplicant[3243]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:aa:ef:33:83:00 completed [id=0 id_str=] Jul 21 06:03:24 volumio dhcpcd[607]: wlan0: carrier acquired Jul 21 06:03:24 volumio dhcpcd[607]: wlan0: confile file changed, expiring leases Jul 21 06:03:24 volumio dhcpcd[607]: wlan0: IAID d6:7c:46:8b Jul 21 06:03:25 volumio dhcpcd[607]: wlan0: soliciting a DHCP lease Jul 21 06:03:25 volumio dhcpcd[607]: wlan0: offered 192.168.3.189 from 192.168.3.1 Jul 21 06:03:25 volumio dhcpcd[607]: wlan0: soliciting an IPv6 router Jul 21 06:03:25 volumio dhcpcd[607]: wlan0: probing address 192.168.3.189/24 Jul 21 06:03:25 volumio volumio[987]: info: Discovery: Started advertising with name: Volumio Jul 21 06:03:25 volumio wireless.js[3186]: trying... Jul 21 06:03:25 volumio sudo[3250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 21 06:03:25 volumio sudo[3250]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:25 volumio sudo[3250]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:26 volumio wireless.js[3186]: trying... Jul 21 06:03:26 volumio sudo[3253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 21 06:03:26 volumio sudo[3253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:26 volumio sudo[3253]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:27 volumio wireless.js[3186]: trying... Jul 21 06:03:27 volumio sudo[3256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 21 06:03:27 volumio sudo[3256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:27 volumio sudo[3256]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:28 volumio wireless.js[3186]: trying... Jul 21 06:03:28 volumio sudo[3261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 21 06:03:28 volumio sudo[3261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:28 volumio sudo[3261]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:29 volumio wireless.js[3186]: trying... Jul 21 06:03:29 volumio sudo[3264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 21 06:03:29 volumio sudo[3264]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:29 volumio sudo[3264]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:30 volumio dhcpcd[607]: wlan0: leased 192.168.3.189 for 86400 seconds Jul 21 06:03:30 volumio avahi-daemon[559]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.189. Jul 21 06:03:30 volumio dhcpcd[607]: wlan0: adding route to 192.168.3.0/24 Jul 21 06:03:30 volumio dhcpcd[607]: wlan0: adding default route via 192.168.3.1 Jul 21 06:03:30 volumio avahi-daemon[559]: New relevant interface wlan0.IPv4 for mDNS. Jul 21 06:03:30 volumio avahi-daemon[559]: Registering new address record for 192.168.3.189 on wlan0.IPv4. Jul 21 06:03:30 volumio dhcpcd[607]: wlan0: dhcp_envoption 213: Operation not supported Jul 21 06:03:30 volumio dhcpcd[607]: wlan0: dhcp_envoption 213: Operation not supported Jul 21 06:03:30 volumio wireless.js[3186]: trying... Jul 21 06:03:30 volumio systemd[1]: Stopping Network Time Service... Jul 21 06:03:30 volumio ntpd[1002]: ntpd exiting on signal 15 (Terminated) Jul 21 06:03:30 volumio systemd[1]: ntp.service: Succeeded. Jul 21 06:03:30 volumio systemd[1]: Stopped Network Time Service. Jul 21 06:03:30 volumio sudo[3298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 21 06:03:30 volumio sudo[3298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:30 volumio systemd[1]: Starting Network Time Service... Jul 21 06:03:30 volumio sudo[3298]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:30 volumio wireless.js[3186]: Connected to: ----ZJHOME Jul 21 06:03:30 volumio wireless.js[3186]: ---- Jul 21 06:03:30 volumio ntpd[3301]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 21 06:03:31 volumio ntpd[3301]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 21 06:03:31 volumio ntpd[3310]: proto: precision = 0.256 usec (-22) Jul 21 06:03:31 volumio systemd[1]: Started Network Time Service. Jul 21 06:03:31 volumio ntpd[3310]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 21 06:03:31 volumio sudo[3307]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 21 06:03:31 volumio sudo[3307]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:03:31 volumio ntpd[3310]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 21 06:03:31 volumio ntpd[3310]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 572 days ago Jul 21 06:03:31 volumio ntpd[3310]: Listen and drop on 0 v6wildcard [::]:123 Jul 21 06:03:31 volumio ntpd[3310]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 21 06:03:31 volumio ntpd[3310]: Listen normally on 2 lo 127.0.0.1:123 Jul 21 06:03:31 volumio ntpd[3310]: Listen normally on 3 wlan0 192.168.3.189:123 Jul 21 06:03:31 volumio ntpd[3310]: Listening on routing socket on fd #20 for interface updates Jul 21 06:03:31 volumio ntpd[3310]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 21 06:03:31 volumio ntpd[3310]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 21 06:03:31 volumio sudo[3307]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:31 volumio wireless.js[3186]: ... joined AP, wlan0 IPv4 is 192.168.3.189, ipV6 is undefined Jul 21 06:03:31 volumio wireless.js[3186]: It's done! AP Jul 21 06:03:31 volumio iwconfig[3315]: Error for wireless request "Set Power Management" (8B2C) : Jul 21 06:03:31 volumio iwconfig[3315]: SET failed on device wlan0 ; Operation not supported. Jul 21 06:03:31 volumio systemd[1]: Started Wireless Services. Jul 21 06:03:31 volumio sudo[3162]: pam_unix(sudo:session): session closed for user root Jul 21 06:03:32 volumio ntpd[3310]: Soliciting pool server 162.159.200.1 Jul 21 06:03:33 volumio ntpd[3310]: Soliciting pool server 119.28.206.193 Jul 21 06:03:33 volumio volumio[987]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Jul 21 06:03:33 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Jul 21 06:03:33 volumio volumio[987]: info: Discovery: adding 748837d0-15c6-439e-b604-fe2379fb900e Jul 21 06:03:33 volumio volumio[987]: info: Discovery: Found device Volumio Jul 21 06:03:33 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Jul 21 06:03:33 volumio volumio[987]: info: CorePlayQueue::getTrack 1 Jul 21 06:03:33 volumio volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 06:03:33 volumio volumio[987]: Error: The value NaN is not a number Jul 21 06:03:33 volumio volumio[987]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jul 21 06:03:33 volumio volumio[987]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jul 21 06:03:33 volumio volumio[987]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jul 21 06:03:33 volumio volumio[987]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jul 21 06:03:33 volumio volumio[987]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jul 21 06:03:33 volumio volumio[987]: at Browser.emit (events.js:400:28) Jul 21 06:03:33 volumio volumio[987]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jul 21 06:03:33 volumio volumio[987]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jul 21 06:03:33 volumio volumio[987]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jul 21 06:03:33 volumio volumio[987]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jul 21 06:03:33 volumio volumio[987]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jul 21 06:03:33 volumio volumio[987]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jul 21 06:03:33 volumio volumio[987]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jul 21 06:03:33 volumio volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 06:03:34 volumio ntpd[3310]: Soliciting pool server 193.182.111.12 Jul 21 06:03:34 volumio ntpd[3310]: Soliciting pool server 193.182.111.141 Jul 21 06:03:34 volumio sudo[3326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-21 06:02 Jul 21 06:03:34 volumio sudo[3326]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"