-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2022-01-24 10:00:23 UTC. -- Jan 24 09:59:00 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-DISCONNECTED bssid=9a:9d:5d:da:0d:56 reason=0 locally_generated=1 Jan 24 09:59:00 mcintosh dhcpcd[734]: wlan0: carrier lost Jan 24 09:59:00 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 24 09:59:00 mcintosh avahi-daemon[474]: Withdrawing address record for 10.0.0.29 on wlan0. Jan 24 09:59:00 mcintosh avahi-daemon[474]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.0.0.29. Jan 24 09:59:00 mcintosh avahi-daemon[474]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 24 09:59:00 mcintosh dhcpcd[734]: wlan0: deleting route to 10.0.0.0/24 Jan 24 09:59:00 mcintosh dhcpcd[734]: wlan0: deleting default route via 10.0.0.1 Jan 24 09:59:00 mcintosh volumio[802]: info: mDNS: A device disapperared from network Jan 24 09:59:00 mcintosh volumio[802]: info: mDNS: Device mcintosh disapperared from network Jan 24 09:59:00 mcintosh wpa_supplicant[722]: wlan0: Trying to associate with SSID 'This Lan Is My Lan' Jan 24 09:59:02 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jan 24 09:59:02 mcintosh ntpd[641]: Deleting interface #4 wlan0, 10.0.0.29#123, interface stats: received=0, sent=0, dropped=0, active_time=39 secs Jan 24 09:59:05 mcintosh wpa_supplicant[722]: wlan0: Trying to associate with SSID 'This Lan Is My Lan' Jan 24 09:59:05 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jan 24 09:59:07 mcintosh dhcpcd[734]: wlan0: carrier acquired Jan 24 09:59:07 mcintosh dhcpcd[734]: wlan0: IAID eb:e5:da:fc Jan 24 09:59:07 mcintosh dhcpcd[734]: wlan0: soliciting an IPv6 router Jan 24 09:59:08 mcintosh dhcpcd[734]: wlan0: rebinding lease of 10.0.0.29 Jan 24 09:59:08 mcintosh wpa_supplicant[722]: wlan0: Trying to associate with SSID 'This Lan Is My Lan' Jan 24 09:59:08 mcintosh dhcpcd[734]: wlan0: carrier lost Jan 24 09:59:08 mcintosh dhcpcd[734]: wlan0: probing address 10.0.0.29/24 Jan 24 09:59:08 mcintosh ntpd[641]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 24 09:59:10 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jan 24 09:59:10 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="This Lan Is My Lan" auth_failures=1 duration=10 reason=CONN_FAILED Jan 24 09:59:11 mcintosh ntpd[641]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 24 09:59:12 mcintosh ntpd[641]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 24 09:59:13 mcintosh dhcpcd[734]: wlan0: leased 10.0.0.29 for 172800 seconds Jan 24 09:59:13 mcintosh avahi-daemon[474]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.0.29. Jan 24 09:59:13 mcintosh avahi-daemon[474]: New relevant interface wlan0.IPv4 for mDNS. Jan 24 09:59:13 mcintosh dhcpcd[734]: wlan0: adding route to 10.0.0.0/24 Jan 24 09:59:13 mcintosh avahi-daemon[474]: Registering new address record for 10.0.0.29 on wlan0.IPv4. Jan 24 09:59:13 mcintosh dhcpcd[734]: wlan0: adding default route via 10.0.0.1 Jan 24 09:59:14 mcintosh volumio[802]: info: Discovery: adding 04762a42-1f26-4716-83f3-0be25727a555 Jan 24 09:59:14 mcintosh volumio[802]: info: mDNS: Found device McIntosh Jan 24 09:59:14 mcintosh volumio[802]: info: CoreCommandRouter::volumioGetState Jan 24 09:59:14 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:15 mcintosh ntpd[641]: Soliciting pool server 23.186.168.132 Jan 24 09:59:15 mcintosh sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 24 09:59:15 mcintosh sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 24 09:59:15 mcintosh sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:59:15 mcintosh sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 24 09:59:15 mcintosh sudo[1206]: pam_unix(sudo:session): session closed for user root Jan 24 09:59:15 mcintosh sudo[1208]: pam_unix(sudo:session): session closed for user root Jan 24 09:59:15 mcintosh volumio[802]: verbose: New Socket.io Connection to 10.0.0.29 from 10.0.0.102 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Total Clients: 4 Jan 24 09:59:15 mcintosh volumio[802]: info: CoreCommandRouter::volumioGetState Jan 24 09:59:15 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:15 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 24 09:59:15 mcintosh volumio[802]: info: Listing playlists Jan 24 09:59:15 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 24 09:59:15 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 24 09:59:15 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 24 09:59:15 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 24 09:59:19 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="This Lan Is My Lan" Jan 24 09:59:19 mcintosh wpa_supplicant[722]: wlan0: Trying to associate with SSID 'This Lan Is My Lan' Jan 24 09:59:20 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jan 24 09:59:20 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="This Lan Is My Lan" auth_failures=2 duration=23 reason=CONN_FAILED Jan 24 09:59:45 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="This Lan Is My Lan" Jan 24 09:59:45 mcintosh wpa_supplicant[722]: wlan0: Trying to associate with SSID 'This Lan Is My Lan' Jan 24 09:59:50 mcintosh wpa_supplicant[722]: wlan0: Associated with 9a:9d:5d:da:0d:56 Jan 24 09:59:50 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9a:9d:5d:da:0d:56 completed [id=0 id_str=] Jan 24 09:59:50 mcintosh dhcpcd[734]: wlan0: carrier acquired Jan 24 09:59:50 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 24 09:59:50 mcintosh wpa_supplicant[722]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jan 24 09:59:50 mcintosh dhcpcd[734]: wlan0: IAID eb:e5:da:fc Jan 24 09:59:50 mcintosh dhcpcd[734]: wlan0: rebinding lease of 10.0.0.29 Jan 24 09:59:51 mcintosh dhcpcd[734]: wlan0: soliciting an IPv6 router Jan 24 09:59:52 mcintosh ntpd[641]: Listen normally on 5 wlan0 10.0.0.29:123 Jan 24 09:59:52 mcintosh ntpd[641]: new interface(s) found: waking up resolver Jan 24 09:59:55 mcintosh volumio[802]: info: VolumeController::SetAlsaVolume60 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPlay Jan 24 09:59:55 mcintosh volumio[802]: verbose: UNSET VOLATILE Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::play index undefined Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::startPlaybackTimer Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::clearAddPlayTracks USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand stop took 25 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand clear Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces system playlist update Jan 24 09:59:55 mcintosh volumio[802]: info: Ignoring MPD Status Update Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 9 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand clear took 8 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 7 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand add "USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac" Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::servicePushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 24 09:59:55 mcintosh volumio[802]: verbose: CURRENT POSITION 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState stateService stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState currentStatus stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: No code Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 65ms Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces system playlist update Jan 24 09:59:55 mcintosh volumio[802]: info: Ignoring MPD Status Update Jan 24 09:59:55 mcintosh volumio[802]: error: Upnp client error: Error: This socket has been ended by the other party Jan 24 09:59:55 mcintosh volumio[802]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 24 09:59:55 mcintosh volumio[802]: error: updateQueue error: null Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces system playlist update Jan 24 09:59:55 mcintosh volumio[802]: info: Ignoring MPD Status Update Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 74ms Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand playlistinfo took 70 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand add "USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac" took 71 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 14ms Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseTrackInfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand play Jan 24 09:59:55 mcintosh volumio[802]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 90ms Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces system playlist update Jan 24 09:59:55 mcintosh volumio[802]: info: Ignoring MPD Status Update Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 33ms Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand play took 27 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 22ms Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 24 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 25 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 23 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 24 09:59:55 mcintosh volumio[802]: info: Received Get System Version Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand clearerror took 33 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand clearerror took 32 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand clearerror took 29 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseTrackInfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseTrackInfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseTrackInfo Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::servicePushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":187,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't Know Why","artist":"Norah Jones","album":"Come Away with Me","uri":"USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac","trackType":"flac"} Jan 24 09:59:55 mcintosh volumio[802]: verbose: CURRENT POSITION 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState stateService pause Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState currentStatus stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::servicePushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":187,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't Know Why","artist":"Norah Jones","album":"Come Away with Me","uri":"USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac","trackType":"flac"} Jan 24 09:59:55 mcintosh volumio[802]: verbose: CURRENT POSITION 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState stateService pause Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState currentStatus stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::servicePushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":187,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't Know Why","artist":"Norah Jones","album":"Come Away with Me","uri":"USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac","trackType":"flac"} Jan 24 09:59:55 mcintosh volumio[802]: verbose: CURRENT POSITION 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState stateService pause Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState currentStatus stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 125ms Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 125ms Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 119ms Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPlay Jan 24 09:59:55 mcintosh volumio[802]: verbose: UNSET VOLATILE Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::play index undefined Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::startPlaybackTimer Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::clearAddPlayTracks USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand stop Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand stop took 8 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand clear Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces system playlist update Jan 24 09:59:55 mcintosh volumio[802]: info: Ignoring MPD Status Update Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 8 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand clear took 9 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 6 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand add "USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac" Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::servicePushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 24 09:59:55 mcintosh volumio[802]: verbose: CURRENT POSITION 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState stateService stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState currentStatus stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: No code Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 56ms Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces system playlist update Jan 24 09:59:55 mcintosh volumio[802]: info: Ignoring MPD Status Update Jan 24 09:59:55 mcintosh volumio[802]: error: updateQueue error: null Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces system playlist update Jan 24 09:59:55 mcintosh volumio[802]: info: Ignoring MPD Status Update Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 62ms Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand playlistinfo took 57 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand add "USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac" took 57 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 10ms Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseTrackInfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand play Jan 24 09:59:55 mcintosh volumio[802]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 77ms Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces system playlist update Jan 24 09:59:55 mcintosh volumio[802]: info: Ignoring MPD Status Update Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 27ms Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand play took 21 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 20ms Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 18 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 19 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 17 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 24 09:59:55 mcintosh volumio[802]: info: Jan 24 09:59:55 mcintosh volumio[802]: ---------------------------- MPD announces state update: player Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::getState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand status Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioGetVisibleSources Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand clearerror took 43 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand clearerror took 41 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand clearerror took 40 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand status took 15 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseTrackInfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseTrackInfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseTrackInfo Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseState Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::servicePushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":187,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't Know Why","artist":"Norah Jones","album":"Come Away with Me","uri":"USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac","trackType":"flac"} Jan 24 09:59:55 mcintosh volumio[802]: verbose: CURRENT POSITION 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState stateService pause Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState currentStatus stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::servicePushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":187,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't Know Why","artist":"Norah Jones","album":"Come Away with Me","uri":"USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac","trackType":"flac"} Jan 24 09:59:55 mcintosh volumio[802]: verbose: CURRENT POSITION 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState stateService pause Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState currentStatus stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::servicePushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":187,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't Know Why","artist":"Norah Jones","album":"Come Away with Me","uri":"USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac","trackType":"flac"} Jan 24 09:59:55 mcintosh volumio[802]: verbose: CURRENT POSITION 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState stateService pause Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState currentStatus stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 131ms Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 130ms Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 125ms Jan 24 09:59:55 mcintosh volumio[802]: info: sendMpdCommand playlistinfo took 75 milliseconds Jan 24 09:59:55 mcintosh volumio[802]: verbose: ControllerMpd::parseTrackInfo Jan 24 09:59:55 mcintosh volumio[802]: info: ControllerMpd::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::servicePushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":187,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't Know Why","artist":"Norah Jones","album":"Come Away with Me","uri":"USB/Mcintosh_Storage/Norah Jones/Come Away with Me/01 Dont Know Why.flac","trackType":"flac"} Jan 24 09:59:55 mcintosh volumio[802]: verbose: CURRENT POSITION 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState stateService pause Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::syncState currentStatus stop Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::pushState Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getTrack 0 Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioPushState Jan 24 09:59:55 mcintosh volumio[802]: info: ------------------------------ 125ms Jan 24 09:59:55 mcintosh volumio[802]: info: CoreCommandRouter::volumioGetQueue Jan 24 09:59:55 mcintosh volumio[802]: info: CoreStateMachine::getQueue Jan 24 09:59:55 mcintosh volumio[802]: info: CorePlayQueue::getQueue Jan 24 10:00:09 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 24 10:00:09 mcintosh volumio[802]: info: CURURI: genres:// Jan 24 10:00:12 mcintosh ntpd[641]: Soliciting pool server 5.161.191.31 Jan 24 10:00:13 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 24 10:00:13 mcintosh volumio[802]: info: CURURI: artists:// Jan 24 10:00:17 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 24 10:00:17 mcintosh volumio[802]: info: CURURI: artists://Animal%20Collective Jan 24 10:00:18 mcintosh ntpd[641]: Soliciting pool server 99.28.14.242 Jan 24 10:00:19 mcintosh ntpd[641]: Soliciting pool server 66.118.231.14 Jan 24 10:00:19 mcintosh volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 24 10:00:20 mcintosh volumio[802]: info: CURURI: artists://Animal%20Collective/Merriweather%20Post%20Pavilion Jan 24 10:00:20 mcintosh ntpd[641]: Soliciting pool server 172.234.44.141 Jan 24 10:00:20 mcintosh volumio[802]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 24 10:00:20 mcintosh volumio[802]: Error: certificate is not yet valid Jan 24 10:00:20 mcintosh volumio[802]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 24 10:00:20 mcintosh volumio[802]: at TLSSocket.emit (events.js:315:20) Jan 24 10:00:20 mcintosh volumio[802]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 24 10:00:20 mcintosh volumio[802]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 24 10:00:20 mcintosh volumio[802]: code: 'CERT_NOT_YET_VALID' Jan 24 10:00:20 mcintosh volumio[802]: } Jan 24 10:00:20 mcintosh volumio[802]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 24 10:00:22 mcintosh ntpd[641]: Soliciting pool server 72.14.182.49 Jan 24 10:00:23 mcintosh sudo[1256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-01-24 09:59 Jan 24 10:00:23 mcintosh sudo[1256]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"