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