-- Logs begin at Sun 2024-04-14 14:30:34 UTC, end at Sun 2024-04-14 14:35:55 UTC. -- Apr 14 14:34:00 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:01 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:02 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:03 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:05 volumioliot volumio[771]: info: CoreCommandRouter::volumioPlay Apr 14 14:34:05 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:34:05 volumioliot volumio[771]: info: CoreStateMachine::play index undefined Apr 14 14:34:05 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:34:05 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:05 volumioliot volumio[771]: info: CoreStateMachine::startPlaybackTimer Apr 14 14:34:05 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:05 volumioliot volumio[771]: info: [1713105245031] ControllerWebradio::clearAddPlayTrack Apr 14 14:34:05 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 14:34:05 volumioliot volumio[771]: info: VolumeController::SetAlsaVolume45 Apr 14 14:34:05 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:34:05 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:05 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:34:05 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:34:05 volumioliot volumio[771]: info: VolumeController::SetAlsaVolume65 Apr 14 14:34:05 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:34:05 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:05 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:34:05 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:34:05 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:05 volumioliot volumio[771]: info: VolumeController::SetAlsaVolume70 Apr 14 14:34:05 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:34:05 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:05 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:34:05 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:34:06 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:07 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:07 volumioliot volumio[771]: info: VolumeController::SetAlsaVolume45 Apr 14 14:34:07 volumioliot volumio[771]: info: VolumeController::SetAlsaVolume25 Apr 14 14:34:07 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:34:07 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:07 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:34:07 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:34:07 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:34:07 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:07 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:34:07 volumioliot sudo[1690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 14:34:07 volumioliot sudo[1690]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:34:07 volumioliot sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 14:34:07 volumioliot sudo[1690]: pam_unix(sudo:session): session closed for user root Apr 14 14:34:07 volumioliot sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:34:08 volumioliot sudo[1692]: pam_unix(sudo:session): session closed for user root Apr 14 14:34:08 volumioliot sudo[1695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 14:34:08 volumioliot sudo[1695]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:34:08 volumioliot systemd[1]: Started UPnP Renderer front-end to MPD. Apr 14 14:34:08 volumioliot sudo[1695]: pam_unix(sudo:session): session closed for user root Apr 14 14:34:08 volumioliot volumio[771]: info: Upmpdcli Daemon Started Apr 14 14:34:08 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:08 volumioliot volumio[771]: info: CoreCommandRouter::volumioPlay Apr 14 14:34:08 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:34:08 volumioliot volumio[771]: info: CoreStateMachine::play index undefined Apr 14 14:34:08 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:34:08 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:08 volumioliot volumio[771]: info: CoreStateMachine::startPlaybackTimer Apr 14 14:34:08 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:08 volumioliot volumio[771]: info: [1713105248963] ControllerWebradio::clearAddPlayTrack Apr 14 14:34:08 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 14:34:09 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:10 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:11 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:12 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 14:34:12 volumioliot volumio[771]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 14:34:12 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 14:34:12 volumioliot volumio[771]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Apr 14 14:34:12 volumioliot volumio[771]: info: Received Get System Version Apr 14 14:34:12 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 14:34:12 volumioliot volumio[771]: info: Received Get System Info Apr 14 14:34:12 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 14:34:12 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 14:34:12 volumioliot volumio[771]: info: Discovery: Getting this device information Apr 14 14:34:12 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:34:12 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:12 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 14:34:12 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:13 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetQueue Apr 14 14:34:13 volumioliot volumio[771]: info: CoreStateMachine::getQueue Apr 14 14:34:13 volumioliot volumio[771]: info: CorePlayQueue::getQueue Apr 14 14:34:13 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:14 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:15 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 14:34:15 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 14:34:16 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:17 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:17 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 14:34:18 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:19 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 14:34:19 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:20 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:21 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:21 volumioliot volumio[771]: info: Discovery: adding 8dfc308f-cd74-4df3-b8a9-fb5e484abd28 Apr 14 14:34:21 volumioliot volumio[771]: info: mDNS: Found device VolumioLiot Apr 14 14:34:21 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:34:21 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:21 volumioliot volumio[771]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 14:34:21 volumioliot volumio[771]: info: CoreStateMachine::ClearQueue Apr 14 14:34:21 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:34:21 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:34:21 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:34:21 volumioliot volumio[771]: info: CorePlayQueue::clearPlayQueue Apr 14 14:34:21 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:34:21 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:34:21 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:34:21 volumioliot volumio[771]: info: CoreStateMachine::addQueueItems Apr 14 14:34:21 volumioliot volumio[771]: info: CorePlayQueue::addQueueItems Apr 14 14:34:21 volumioliot volumio[771]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s6553 Apr 14 14:34:21 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 14:34:22 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:23 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand add "http://www.1.fm/tunestream/chilloutlounge/listen.pls" Apr 14 14:34:23 volumioliot volumio[771]: error: updateQueue error: null Apr 14 14:34:23 volumioliot volumio[771]: info: ------------------------------ 25032ms Apr 14 14:34:23 volumioliot volumio[771]: info: sendMpdCommand stop took 18420 milliseconds Apr 14 14:34:23 volumioliot volumio[771]: info: sendMpdCommand stop took 14488 milliseconds Apr 14 14:34:23 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 14:34:23 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 14:34:23 volumioliot volumio[771]: Upnp client error: Error: This socket has been ended by the other party Apr 14 14:34:23 volumioliot volumio[771]: Upnp client error: Error: This socket has been ended by the other party Apr 14 14:34:23 volumioliot volumio[771]: Upnp client error: Error: This socket has been ended by the other party Apr 14 14:34:23 volumioliot volumio[771]: info: Apr 14 14:34:23 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:34:23 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:34:23 volumioliot volumio[771]: info: Apr 14 14:34:23 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:34:23 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:34:23 volumioliot volumio[771]: info: Apr 14 14:34:23 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:34:23 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:34:23 volumioliot volumio[771]: info: sendMpdCommand add "http://www.1.fm/tunestream/chilloutlounge/listen.pls" took 10 milliseconds Apr 14 14:34:23 volumioliot volumio[771]: info: sendMpdCommand clear took 6 milliseconds Apr 14 14:34:23 volumioliot volumio[771]: info: sendMpdCommand clear took 7 milliseconds Apr 14 14:34:23 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 14:34:23 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand play Apr 14 14:34:23 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand load "http://www.1.fm/tunestream/chilloutlounge/listen.pls" Apr 14 14:34:23 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand load "http://www.1.fm/tunestream/chilloutlounge/listen.pls" Apr 14 14:34:23 volumioliot volumio[771]: info: Apr 14 14:34:23 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:34:23 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:34:23 volumioliot volumio[771]: error: updateQueue error: null Apr 14 14:34:23 volumioliot volumio[771]: error: updateQueue error: null Apr 14 14:34:23 volumioliot volumio[771]: error: updateQueue error: null Apr 14 14:34:23 volumioliot volumio[771]: info: ------------------------------ 13ms Apr 14 14:34:23 volumioliot volumio[771]: info: ------------------------------ 12ms Apr 14 14:34:23 volumioliot volumio[771]: info: ------------------------------ 11ms Apr 14 14:34:23 volumioliot volumio[771]: info: sendMpdCommand play took 9 milliseconds Apr 14 14:34:23 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:24 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:25 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:26 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:28 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:29 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:29 volumioliot volumio[771]: info: CoreCommandRouter::volumioPlay Apr 14 14:34:29 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:34:29 volumioliot volumio[771]: info: CoreStateMachine::play index undefined Apr 14 14:34:29 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:34:29 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:30 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:34:30 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:34:30 volumioliot volumio[771]: info: CoreStateMachine::updateTrackBlock Apr 14 14:34:30 volumioliot volumio[771]: info: CorePlayQueue::getTrackBlock Apr 14 14:34:30 volumioliot volumio[771]: info: CoreCommandRouter::volumioPlay Apr 14 14:34:30 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:34:30 volumioliot volumio[771]: info: CoreStateMachine::play index 0 Apr 14 14:34:30 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:34:30 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:34:30 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:34:30 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:34:30 volumioliot volumio[771]: info: CoreStateMachine::play index undefined Apr 14 14:34:30 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:34:30 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:30 volumioliot volumio[771]: info: CoreStateMachine::startPlaybackTimer Apr 14 14:34:30 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:30 volumioliot volumio[771]: info: [1713105270197] ControllerWebradio::clearAddPlayTrack Apr 14 14:34:30 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 14:34:31 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:32 volumioliot volumio[771]: info: VolumeController::SetAlsaVolume30 Apr 14 14:34:32 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:34:32 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:32 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:34:32 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:34:32 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:33 volumioliot volumio[771]: info: VolumeController::SetAlsaVolume40 Apr 14 14:34:33 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:34:33 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:33 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:34:33 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:33 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:34:33 volumioliot volumio[771]: info: VolumeController::SetAlsaVolume35 Apr 14 14:34:33 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:34:33 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:33 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:34:33 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:34:34 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:35 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:36 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:37 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:38 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:39 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:40 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:41 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:42 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:43 volumioliot ntpd[1551]: Soliciting pool server 162.159.200.1 Apr 14 14:34:43 volumioliot ntpd[1551]: Soliciting pool server 162.159.200.123 Apr 14 14:34:43 volumioliot ntpd[1551]: Soliciting pool server 146.59.30.179 Apr 14 14:34:43 volumioliot ntpd[1551]: Soliciting pool server 129.250.35.251 Apr 14 14:34:44 volumioliot ntpd[1551]: Soliciting pool server 51.195.104.188 Apr 14 14:34:44 volumioliot ntpd[1551]: Soliciting pool server 51.68.44.27 Apr 14 14:34:44 volumioliot ntpd[1551]: Soliciting pool server 146.59.35.38 Apr 14 14:34:44 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:45 volumioliot ntpd[1551]: Soliciting pool server 51.15.177.17 Apr 14 14:34:45 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:46 volumioliot ntpd[1551]: Soliciting pool server 51.178.79.86 Apr 14 14:34:46 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:47 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:48 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:49 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:50 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:51 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:52 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:52 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 6 Apr 14 14:34:53 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand add "http://www.1.fm/tunestream/chilloutlounge/listen.pls" Apr 14 14:34:53 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:53 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 7 Apr 14 14:34:54 volumioliot volumio[771]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 14:34:54 volumioliot volumio[771]: info: CoreStateMachine::ClearQueue Apr 14 14:34:54 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:34:54 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:34:54 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:34:54 volumioliot volumio[771]: info: CorePlayQueue::clearPlayQueue Apr 14 14:34:54 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:34:54 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:34:54 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:34:54 volumioliot volumio[771]: info: CoreStateMachine::addQueueItems Apr 14 14:34:54 volumioliot volumio[771]: info: CorePlayQueue::addQueueItems Apr 14 14:34:54 volumioliot volumio[771]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s302899 Apr 14 14:34:54 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 14:34:55 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:56 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:34:56 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:34:56 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 14:34:56 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 14:34:56 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:57 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:58 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:34:59 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:00 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:00 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 8 Apr 14 14:35:01 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 9 Apr 14 14:35:01 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:02 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:03 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:04 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:05 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:05 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 14:35:05 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 14:35:05 volumioliot volumio[771]: info: Discovery: Getting this device information Apr 14 14:35:05 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:35:05 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:05 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 14:35:06 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:07 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:08 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:08 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 9 Apr 14 14:35:09 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:10 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 14:35:10 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 14:35:10 volumioliot volumio[771]: info: Discovery: Getting this device information Apr 14 14:35:10 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:35:10 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:10 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 14:35:10 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 10 Apr 14 14:35:10 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:11 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:13 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 11 Apr 14 14:35:13 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:14 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:14 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 12 Apr 14 14:35:15 volumioliot volumio[771]: Upnp client error: Error: This socket has been ended by the other party Apr 14 14:35:15 volumioliot volumio[771]: error: updateQueue error: null Apr 14 14:35:15 volumioliot volumio[771]: info: Apr 14 14:35:15 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:35:15 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:35:15 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand add "http://www.1.fm/tunestream/chilloutlounge/listen.pls" Apr 14 14:35:15 volumioliot volumio[771]: info: ------------------------------ 52173ms Apr 14 14:35:15 volumioliot volumio[771]: info: sendMpdCommand stop took 45440 milliseconds Apr 14 14:35:15 volumioliot volumio[771]: info: sendMpdCommand add "http://www.1.fm/tunestream/chilloutlounge/listen.pls" took 22063 milliseconds Apr 14 14:35:15 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 14:35:15 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 14:35:15 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand play Apr 14 14:35:15 volumioliot volumio[771]: info: Apr 14 14:35:15 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:35:15 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:35:15 volumioliot volumio[771]: Upnp client error: Error: This socket has been ended by the other party Apr 14 14:35:15 volumioliot volumio[771]: info: Apr 14 14:35:15 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:35:15 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:35:15 volumioliot volumio[771]: info: Apr 14 14:35:15 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:35:15 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:35:15 volumioliot volumio[771]: error: updateQueue error: null Apr 14 14:35:15 volumioliot volumio[771]: info: sendMpdCommand add "http://www.1.fm/tunestream/chilloutlounge/listen.pls" took 10 milliseconds Apr 14 14:35:15 volumioliot volumio[771]: info: ------------------------------ 12ms Apr 14 14:35:15 volumioliot volumio[771]: info: sendMpdCommand clear took 9 milliseconds Apr 14 14:35:15 volumioliot volumio[771]: info: sendMpdCommand play took 8 milliseconds Apr 14 14:35:15 volumioliot volumio[771]: info: ------------------------------ 8ms Apr 14 14:35:15 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 14:35:15 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand play Apr 14 14:35:15 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand load "http://alouette.ice.infomaniak.ch/alouette-high.mp3" Apr 14 14:35:15 volumioliot volumio[771]: info: Apr 14 14:35:15 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:35:15 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:35:15 volumioliot volumio[771]: error: updateQueue error: null Apr 14 14:35:15 volumioliot volumio[771]: error: updateQueue error: null Apr 14 14:35:15 volumioliot volumio[771]: info: ------------------------------ 11ms Apr 14 14:35:15 volumioliot volumio[771]: info: ------------------------------ 9ms Apr 14 14:35:15 volumioliot volumio[771]: info: sendMpdCommand play took 6 milliseconds Apr 14 14:35:15 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:16 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 13 Apr 14 14:35:16 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:16 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 14 Apr 14 14:35:17 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:35:17 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 14:35:17 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 14:35:18 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:19 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:20 volumioliot ntpd[1551]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 14 14:35:21 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::ClearQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:35:23 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::clearPlayQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s302899 Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::ClearQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:35:23 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::clearPlayQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s302899 Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::ClearQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:35:23 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::clearPlayQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s302899 Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPlay Apr 14 14:35:23 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::play index undefined Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::ClearQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:35:23 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::clearPlayQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s302899 Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::ClearQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:35:23 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::clearPlayQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s302899 Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::ClearQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:35:23 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::clearPlayQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s302899 Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::ClearQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:35:23 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::clearPlayQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s302899 Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::ClearQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::stop Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:35:23 volumioliot volumio[771]: verbose: UNSET VOLATILE Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::clearPlayQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::saveQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:35:23 volumioliot volumio[771]: info: CoreStateMachine::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::addQueueItems Apr 14 14:35:23 volumioliot volumio[771]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s302899 Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 14:35:23 volumioliot volumio[771]: info: Discovery: Getting this device information Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:35:23 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:23 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 14:35:23 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:24 volumioliot sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 14:35:24 volumioliot sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:35:24 volumioliot sudo[1716]: pam_unix(sudo:session): session closed for user root Apr 14 14:35:24 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:24 volumioliot sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 14:35:24 volumioliot sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:35:24 volumioliot sudo[1718]: pam_unix(sudo:session): session closed for user root Apr 14 14:35:25 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:26 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:27 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:28 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:28 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 14 Apr 14 14:35:29 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 14:35:29 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 14:35:29 volumioliot volumio[771]: info: Discovery: Getting this device information Apr 14 14:35:29 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:35:29 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:29 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 14:35:29 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 15 Apr 14 14:35:30 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:35:30 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:30 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 14:35:30 volumioliot volumio[771]: info: Listing playlists Apr 14 14:35:30 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 14:35:30 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 14:35:30 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 14:35:30 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 14:35:30 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:35:30 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:30 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 14:35:30 volumioliot volumio[771]: info: Listing playlists Apr 14 14:35:32 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 16 Apr 14 14:35:33 volumioliot volumio[771]: error: updateQueue error: null Apr 14 14:35:33 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand add "http://alouette.ice.infomaniak.ch/alouette-high.mp3" Apr 14 14:35:33 volumioliot volumio[771]: info: ------------------------------ 17874ms Apr 14 14:35:33 volumioliot volumio[771]: Upnp client error: Error: This socket has been ended by the other party Apr 14 14:35:33 volumioliot volumio[771]: info: Apr 14 14:35:33 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:35:33 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:35:33 volumioliot volumio[771]: info: sendMpdCommand add "http://alouette.ice.infomaniak.ch/alouette-high.mp3" took 4 milliseconds Apr 14 14:35:33 volumioliot volumio[771]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 14:35:33 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand play Apr 14 14:35:33 volumioliot volumio[771]: info: Apr 14 14:35:33 volumioliot volumio[771]: ---------------------------- MPD announces system playlist update Apr 14 14:35:33 volumioliot volumio[771]: info: Ignoring MPD Status Update Apr 14 14:35:33 volumioliot volumio[771]: info: ------------------------------ 4ms Apr 14 14:35:33 volumioliot volumio[771]: info: sendMpdCommand play took 4 milliseconds Apr 14 14:35:33 volumioliot volumio[771]: info: ------------------------------ 3ms Apr 14 14:35:33 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:34 volumioliot volumio[771]: info: Apr 14 14:35:34 volumioliot volumio[771]: ---------------------------- MPD announces state update: player Apr 14 14:35:34 volumioliot volumio[771]: info: ControllerMpd::getState Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:35:34 volumioliot volumio[771]: info: Apr 14 14:35:34 volumioliot volumio[771]: ---------------------------- MPD announces state update: player Apr 14 14:35:34 volumioliot volumio[771]: info: ControllerMpd::getState Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:35:34 volumioliot volumio[771]: info: Apr 14 14:35:34 volumioliot volumio[771]: ---------------------------- MPD announces state update: player Apr 14 14:35:34 volumioliot volumio[771]: info: sendMpdCommand status took 25 milliseconds Apr 14 14:35:34 volumioliot volumio[771]: info: sendMpdCommand status took 24 milliseconds Apr 14 14:35:34 volumioliot volumio[771]: info: ControllerMpd::getState Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::parseState Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::parseState Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:35:34 volumioliot volumio[771]: info: sendMpdCommand status took 5 milliseconds Apr 14 14:35:34 volumioliot volumio[771]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 14 14:35:34 volumioliot volumio[771]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::parseState Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:35:34 volumioliot volumio[771]: info: ControllerMpd::pushState Apr 14 14:35:34 volumioliot volumio[771]: info: CoreCommandRouter::servicePushState Apr 14 14:35:34 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:34 volumioliot volumio[771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"alouette-high.mp3","artist":null,"album":null,"uri":"http://alouette.ice.infomaniak.ch/alouette-high.mp3","trackType":"mp3"} Apr 14 14:35:34 volumioliot volumio[771]: verbose: CURRENT POSITION 0 Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::syncState stateService play Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::syncState currentStatus stop Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:35:34 volumioliot volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:35:34 volumioliot volumio[771]: info: CoreCommandRouter::volumioPushState Apr 14 14:35:34 volumioliot volumio[771]: info: ControllerMpd::pushState Apr 14 14:35:34 volumioliot volumio[771]: info: CoreCommandRouter::servicePushState Apr 14 14:35:34 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:34 volumioliot volumio[771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"alouette-high.mp3","artist":null,"album":null,"uri":"http://alouette.ice.infomaniak.ch/alouette-high.mp3","trackType":"mp3"} Apr 14 14:35:34 volumioliot volumio[771]: verbose: CURRENT POSITION 0 Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::syncState stateService play Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::syncState currentStatus play Apr 14 14:35:34 volumioliot volumio[771]: info: Received an update from plugin. extracting info from payload Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:35:34 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:34 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:34 volumioliot volumio[771]: info: ------------------------------ 84ms Apr 14 14:35:34 volumioliot volumio[771]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 14 14:35:34 volumioliot volumio[771]: info: ------------------------------ 83ms Apr 14 14:35:34 volumioliot volumio[771]: info: Apr 14 14:35:34 volumioliot volumio[771]: ---------------------------- MPD announces state update: player Apr 14 14:35:34 volumioliot volumio[771]: info: ControllerMpd::getState Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:35:34 volumioliot volumio[771]: info: sendMpdCommand playlistinfo took 51 milliseconds Apr 14 14:35:34 volumioliot volumio[771]: info: sendMpdCommand status took 2 milliseconds Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::parseState Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:35:34 volumioliot volumio[771]: info: ControllerMpd::pushState Apr 14 14:35:34 volumioliot volumio[771]: info: CoreCommandRouter::servicePushState Apr 14 14:35:34 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:34 volumioliot volumio[771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"alouette-high.mp3","artist":null,"album":null,"uri":"http://alouette.ice.infomaniak.ch/alouette-high.mp3","trackType":"mp3"} Apr 14 14:35:34 volumioliot volumio[771]: verbose: CURRENT POSITION 0 Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::syncState stateService play Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::syncState currentStatus play Apr 14 14:35:34 volumioliot volumio[771]: info: Received an update from plugin. extracting info from payload Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:35:34 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:34 volumioliot volumio[771]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 14 14:35:34 volumioliot volumio[771]: info: ------------------------------ 70ms Apr 14 14:35:34 volumioliot volumio[771]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 14 14:35:34 volumioliot volumio[771]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:35:34 volumioliot volumio[771]: info: ControllerMpd::pushState Apr 14 14:35:34 volumioliot volumio[771]: info: CoreCommandRouter::servicePushState Apr 14 14:35:34 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:34 volumioliot volumio[771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"alouette-high.mp3","artist":null,"album":null,"uri":"http://alouette.ice.infomaniak.ch/alouette-high.mp3","trackType":"mp3"} Apr 14 14:35:34 volumioliot volumio[771]: verbose: CURRENT POSITION 0 Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::syncState stateService play Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::syncState currentStatus play Apr 14 14:35:34 volumioliot volumio[771]: info: Received an update from plugin. extracting info from payload Apr 14 14:35:34 volumioliot volumio[771]: info: CoreStateMachine::pushState Apr 14 14:35:34 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:34 volumioliot volumio[771]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 14 14:35:34 volumioliot volumio[771]: info: ------------------------------ 21ms Apr 14 14:35:36 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:37 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:38 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:39 volumioliot ntpd[1551]: Soliciting pool server 178.32.23.17 Apr 14 14:35:39 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:40 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:42 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:43 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:44 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:45 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:46 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:48 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:49 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:50 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:51 volumioliot ntpd[1551]: Soliciting pool server 82.65.248.56 Apr 14 14:35:51 volumioliot volumio[771]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.69 UA: okhttp/4.9.2 Total Clients: 16 Apr 14 14:35:51 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:52 volumioliot ntpd[1551]: Soliciting pool server 5.135.158.34 Apr 14 14:35:52 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:53 volumioliot wpa_supplicant[1592]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 14 14:35:54 volumioliot volumio[771]: info: CoreCommandRouter::volumioGetState Apr 14 14:35:54 volumioliot volumio[771]: info: CorePlayQueue::getTrack 0 Apr 14 14:35:54 volumioliot volumio[771]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 14:35:54 volumioliot volumio[771]: TypeError: Cannot read property 'name' of undefined Apr 14 14:35:54 volumioliot volumio[771]: at CoreStateMachine.getState (/volumio/app/statemachine.js:122:48) Apr 14 14:35:54 volumioliot volumio[771]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:104:28) Apr 14 14:35:54 volumioliot volumio[771]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:50:38) Apr 14 14:35:54 volumioliot volumio[771]: at Socket.emit (events.js:400:28) Apr 14 14:35:54 volumioliot volumio[771]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 14 14:35:54 volumioliot volumio[771]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 14 14:35:54 volumioliot volumio[771]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 14:35:55 volumioliot sudo[1739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 14:34 Apr 14 14:35:55 volumioliot sudo[1739]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:32:05 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93cb5aa7a8ea68b0c9a3bbd05db9ce8d"