-- Logs begin at Thu 2019-02-14 13:12:00 MSK, end at Thu 2025-03-13 23:27:48 MSK. -- Mar 13 23:26:06 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::volumioNext Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::next Mar 13 23:26:07 volumio volumio[1034]: info: ControllerMpd::next Mar 13 23:26:07 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand next Mar 13 23:26:07 volumio volumio[1034]: info: sendMpdCommand next took 2 milliseconds Mar 13 23:26:07 volumio volumio[1034]: info: Mar 13 23:26:07 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:26:07 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:26:07 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:26:07 volumio volumio[1034]: info: Mar 13 23:26:07 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:26:07 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:26:07 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:26:07 volumio volumio[1034]: info: sendMpdCommand status took 3 milliseconds Mar 13 23:26:07 volumio volumio[1034]: info: sendMpdCommand status took 2 milliseconds Mar 13 23:26:07 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:26:07 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:26:07 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:26:07 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:26:07 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 13 23:26:07 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 13 23:26:07 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:26:07 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:26:07 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:26:07 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Mar 13 23:26:07 volumio volumio[1034]: verbose: STATE SERVICE {"status":"play","position":1,"seek":709,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"316 Kbps","isStreaming":false,"title":"Deceived Heart Again (Piano)","artist":"Umar Keyn","album":"Deceived Heart Again (Piano)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-120.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D95f322cf345580259baf1947ce66da2a6cfce183ea98539af3fa44b5decb96c7%2Clid%3D1521%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e135d7%2F0%2F13481838%2Fe72cb0f8.184240243.12.127330314%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:26:07 volumio volumio[1034]: verbose: CURRENT POSITION 0 Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::syncState stateService play Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus play Mar 13 23:26:07 volumio volumio[1034]: info: Received an update from plugin. extracting info from payload Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:07 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:26:07 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Mar 13 23:26:07 volumio volumio[1034]: verbose: STATE SERVICE {"status":"play","position":1,"seek":709,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"427 Kbps","isStreaming":false,"title":"Deceived Heart Again (Piano)","artist":"Umar Keyn","album":"Deceived Heart Again (Piano)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-120.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D95f322cf345580259baf1947ce66da2a6cfce183ea98539af3fa44b5decb96c7%2Clid%3D1521%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e135d7%2F0%2F13481838%2Fe72cb0f8.184240243.12.127330314%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:26:07 volumio volumio[1034]: verbose: CURRENT POSITION 0 Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::syncState stateService play Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus play Mar 13 23:26:07 volumio volumio[1034]: info: Received an update from plugin. extracting info from payload Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:07 volumio volumio[1034]: info: ------------------------------ 65ms Mar 13 23:26:07 volumio volumio[1034]: info: ------------------------------ 64ms Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:26:07 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:26:07 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:26:07 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:26:07 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:26:07 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:26:07 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:26:10 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 23:26:10 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 13 23:26:14 volumio volumio[1034]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 23:26:23 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 23:26:23 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::stop Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::stPlaybackTimer Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::updateTrackBlock Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getTrackBlock Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::serviceStop Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::serviceStop Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 13 23:26:27 volumio volumio[1034]: info: ControllerMpd::stop Mar 13 23:26:27 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 23:26:27 volumio volumio[1034]: info: Disabling plugin peppy_screensaver Mar 13 23:26:27 volumio volumio[1034]: info: Preparing to generate the ALSA configuration file Mar 13 23:26:27 volumio volumio[1034]: info: Mar 13 23:26:27 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:26:27 volumio volumio[1034]: info: sendMpdCommand stop took 49 milliseconds Mar 13 23:26:27 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:26:27 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:26:27 volumio volumio[1034]: info: Mar 13 23:26:27 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:26:27 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:26:27 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:26:27 volumio volumio[1034]: info: sendMpdCommand status took 73 milliseconds Mar 13 23:26:27 volumio volumio[1034]: info: sendMpdCommand status took 45 milliseconds Mar 13 23:26:27 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:26:27 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:26:27 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:26:27 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:26:27 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 13 23:26:27 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 13 23:26:27 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:26:27 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:26:27 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:27 volumio sudo[3438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Mar 13 23:26:27 volumio sudo[3438]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Mar 13 23:26:27 volumio volumio[1034]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Deceived Heart Again (Piano)","artist":"Umar Keyn","album":"Deceived Heart Again (Piano)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-120.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D95f322cf345580259baf1947ce66da2a6cfce183ea98539af3fa44b5decb96c7%2Clid%3D1521%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e135d7%2F0%2F13481838%2Fe72cb0f8.184240243.12.127330314%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:26:27 volumio volumio[1034]: verbose: CURRENT POSITION 0 Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::syncState stateService stop Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus stop Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:27 volumio volumio[1034]: info: No code Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:27 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Mar 13 23:26:27 volumio volumio[1034]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Deceived Heart Again (Piano)","artist":"Umar Keyn","album":"Deceived Heart Again (Piano)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-120.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D95f322cf345580259baf1947ce66da2a6cfce183ea98539af3fa44b5decb96c7%2Clid%3D1521%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e135d7%2F0%2F13481838%2Fe72cb0f8.184240243.12.127330314%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:26:27 volumio volumio[1034]: verbose: CURRENT POSITION 0 Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::syncState stateService stop Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus stop Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:27 volumio systemd[2502]: volumio-app-plugins-music_service-mpd-mpd.conf.tmpl.mount: Succeeded. Mar 13 23:26:27 volumio systemd[1]: volumio-app-plugins-music_service-mpd-mpd.conf.tmpl.mount: Succeeded. Mar 13 23:26:27 volumio volumio[1034]: info: No code Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:26:27 volumio sudo[3438]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:27 volumio volumio[1034]: info: ------------------------------ 189ms Mar 13 23:26:27 volumio volumio[1034]: info: ------------------------------ 158ms Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:26:27 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:26:27 volumio volumio[1034]: info: touch_display: Setting screensaver timeout to 3600 seconds. Mar 13 23:26:27 volumio volumio[1034]: info: Asound.conf file written Mar 13 23:26:27 volumio sudo[3447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 13 23:26:27 volumio sudo[3447]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:27 volumio sudo[3447]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:28 volumio volumio[1034]: No state is present for card Dummy Mar 13 23:26:28 volumio volumio[1034]: Found hardware: "Dummy" "Dummy Mixer" "" "" "" Mar 13 23:26:28 volumio volumio[1034]: Hardware is initialized using a generic method Mar 13 23:26:28 volumio volumio[1034]: No state is present for card Dummy Mar 13 23:26:28 volumio volumio[1034]: info: Output device has changed, restarting MPD Mar 13 23:26:28 volumio sudo[3452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 23:26:28 volumio sudo[3452]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:28 volumio sudo[3452]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:28 volumio volumio[1034]: info: Output device has changed, restarting Shairport Sync Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 23:26:28 volumio sudo[3455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 23:26:28 volumio sudo[3455]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:28 volumio systemd[1]: Stopping Music Player Daemon... Mar 13 23:26:28 volumio volumio[1034]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 23:26:28 volumio volumio[1034]: info: Done. Mar 13 23:26:28 volumio volumio[1034]: info: MPD Permissions set Mar 13 23:26:28 volumio volumio[1034]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio systemd[1]: mpd.service: Succeeded. Mar 13 23:26:28 volumio systemd[1]: Stopped Music Player Daemon. Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: Starting Shairport Sync Mar 13 23:26:28 volumio sudo[3466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 23:26:28 volumio sudo[3466]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:28 volumio systemd[1]: Starting Music Player Daemon... Mar 13 23:26:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 23:26:28 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 13 23:26:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 23:26:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 23:26:28 volumio sudo[3466]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:28 volumio volumio[1034]: info: Shairport-Sync Started Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:28 volumio mpd[3461]: Mar 13 23:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 13 23:26:29 volumio systemd[1]: Started Music Player Daemon. Mar 13 23:26:29 volumio sudo[3455]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:29 volumio volumio[1034]: error: updateQueue error: null Mar 13 23:26:30 volumio volumio[1034]: info: Enabling plugin peppy_screensaver Mar 13 23:26:30 volumio volumio[1034]: info: Loading plugin "peppy_screensaver"... Mar 13 23:26:30 volumio volumio[1034]: info: Applying required configuration parameters for plugin peppy_screensaver Mar 13 23:26:30 volumio volumio[1034]: info: Preparing to generate the ALSA configuration file Mar 13 23:26:30 volumio volumio[1034]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 13 23:26:30 volumio volumio[1034]: info: Reading ALSA contributions from plugins. Mar 13 23:26:30 volumio volumio[1034]: info: Asound.conf file written Mar 13 23:26:30 volumio sudo[3474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 13 23:26:30 volumio sudo[3474]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:30 volumio sudo[3474]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:30 volumio volumio[1034]: No state is present for card Dummy Mar 13 23:26:30 volumio volumio[1034]: Found hardware: "Dummy" "Dummy Mixer" "" "" "" Mar 13 23:26:30 volumio volumio[1034]: Hardware is initialized using a generic method Mar 13 23:26:30 volumio volumio[1034]: No state is present for card Dummy Mar 13 23:26:30 volumio volumio[1034]: info: Output device has changed, restarting MPD Mar 13 23:26:30 volumio sudo[3479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 23:26:30 volumio sudo[3479]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:30 volumio sudo[3479]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:30 volumio volumio[1034]: info: Output device has changed, restarting Shairport Sync Mar 13 23:26:30 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:30 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 23:26:30 volumio sudo[3482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 23:26:30 volumio sudo[3482]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:30 volumio systemd[1]: Stopping Music Player Daemon... Mar 13 23:26:30 volumio volumio[1034]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 23:26:30 volumio volumio[1034]: info: PLUGIN START: peppy_screensaver Mar 13 23:26:30 volumio volumio[1034]: info: Loading i18n strings for locale ru Mar 13 23:26:30 volumio volumio[1034]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 13 23:26:30 volumio systemd[1]: mpd.service: Succeeded. Mar 13 23:26:30 volumio systemd[1]: Stopped Music Player Daemon. Mar 13 23:26:30 volumio volumio[1034]: info: /tmp/myfifo created Mar 13 23:26:30 volumio volumio[1034]: info: /tmp/myfifosa created Mar 13 23:26:30 volumio systemd[1]: Starting Music Player Daemon... Mar 13 23:26:30 volumio sudo[3493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Mar 13 23:26:30 volumio sudo[3493]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:30 volumio sudo[3493]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:30 volumio volumio[1034]: info: snd-dummy loaded Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 23:26:31 volumio volumio[1034]: info: Done. Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Mar 13 23:26:31 volumio volumio[1034]: info: MPD Permissions set Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: Starting Shairport Sync Mar 13 23:26:31 volumio volumio[1034]: info: Starting Shairport Sync Mar 13 23:26:31 volumio sudo[3514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 23:26:31 volumio sudo[3514]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 23:26:31 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 13 23:26:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 23:26:31 volumio sudo[3518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 23:26:31 volumio sudo[3518]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 23:26:31 volumio sudo[3523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio sudo[3523]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:31 volumio sudo[3514]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:31 volumio sudo[3523]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:31 volumio volumio[1034]: info: Shairport-Sync Started Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Mar 13 23:26:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 23:26:31 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 13 23:26:31 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 13 23:26:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 23:26:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 23:26:31 volumio sudo[3518]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:31 volumio volumio[1034]: info: Shairport-Sync Started Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio sudo[3528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 23:26:31 volumio sudo[3528]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:31 volumio sudo[3528]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 23:26:31 volumio volumio[1034]: info: MPD Permissions set Mar 13 23:26:31 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 13 23:26:31 volumio sudo[3537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 23:26:31 volumio sudo[3537]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 23:26:31 volumio mpd[3488]: Mar 13 23:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 13 23:26:31 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 13 23:26:31 volumio systemd[1]: mpd.service: Succeeded. Mar 13 23:26:31 volumio systemd[1]: Stopped Music Player Daemon. Mar 13 23:26:32 volumio systemd[1]: Starting Music Player Daemon... Mar 13 23:26:32 volumio volumio[1034]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 23:26:32 volumio mpd[3539]: Mar 13 23:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 13 23:26:33 volumio systemd[1]: Started Music Player Daemon. Mar 13 23:26:33 volumio sudo[3482]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:33 volumio sudo[3537]: pam_unix(sudo:session): session closed for user root Mar 13 23:26:33 volumio volumio[1034]: error: MPD error: The expression evaluated to a falsy value: Mar 13 23:26:33 volumio volumio[1034]: assert.ok(self.idling) Mar 13 23:26:33 volumio volumio[1034]: error: The expression evaluated to a falsy value: Mar 13 23:26:33 volumio volumio[1034]: assert.ok(self.idling) Mar 13 23:26:33 volumio volumio[1034]: error: updateQueue error: null Mar 13 23:26:41 volumio volumio[1034]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 13 23:26:41 volumio volumio[1034]: Traceback (most recent call last): Mar 13 23:26:41 volumio volumio[1034]: File "./screensaver/volumio_peppymeter.py", line 26, in Mar 13 23:26:41 volumio volumio[1034]: from peppymeter.peppymeter import Peppymeter Mar 13 23:26:41 volumio volumio[1034]: ModuleNotFoundError: No module named 'peppymeter' Mar 13 23:26:41 volumio volumio[1034]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Mar 13 23:26:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Mar 13 23:26:44 volumio volumio[1034]: info: CALLMETHOD: user_interface peppy_screensaver saveVUMeterConf [object Object] Mar 13 23:26:44 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , saveVUMeterConf Mar 13 23:27:01 volumio volumio[1034]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Mar 13 23:27:01 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Mar 13 23:27:03 volumio volumio[1034]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Mar 13 23:27:03 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Mar 13 23:27:07 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Mar 13 23:27:09 volumio volumio[1034]: info: CoreCommandRouter::volumioNext Mar 13 23:27:09 volumio volumio[1034]: info: CoreStateMachine::next Mar 13 23:27:09 volumio volumio[1034]: info: ControllerMpd::next Mar 13 23:27:09 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand next Mar 13 23:27:14 volumio volumio[1034]: info: CoreCommandRouter::volumioNext Mar 13 23:27:14 volumio volumio[1034]: info: CoreStateMachine::next Mar 13 23:27:14 volumio volumio[1034]: info: ControllerMpd::next Mar 13 23:27:14 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand next Mar 13 23:27:18 volumio volumio[1034]: info: Preload queue cleared Mar 13 23:27:18 volumio volumio[1034]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::ClearQueue Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::stop Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::clearPlayQueue Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::saveQueue Mar 13 23:27:18 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::addQueueItems Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::addQueueItems Mar 13 23:27:18 volumio volumio[1034]: info: Preload queue cleared Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/127330314:31770388@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/127330314:31770388@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/4425412:40622@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/4425412:40622@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/54014103:7755667@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/54014103:7755667@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/20585136:476831@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/20585136:476831@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/36664873:4620359@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/36664873:4620359@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/120966517:28964387@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/120966517:28964387@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/6877472:734756@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/6877472:734756@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/2200853:217939@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/2200853:217939@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/60363095:9317591@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/60363095:9317591@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/110081927:24402993@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/110081927:24402993@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/64357978:10329735@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/64357978:10329735@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/138905:4813230@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/138905:4813230@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/113872062:25875592@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/113872062:25875592@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/6098551:91137@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/6098551:91137@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/122041048:29465469@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/122041048:29465469@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/78780542:14189169@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/78780542:14189169@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/28192829:3369432@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/28192829:3369432@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/68348390:11322908@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/68348390:11322908@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/95981460:29627983@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/95981460:29627983@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/28098490:3356224@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/28098490:3356224@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/67538121:11107121@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/67538121:11107121@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/40618149:102138@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/40618149:102138@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/36999048:32833196@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/36999048:32833196@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/18172490:14505755@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/18172490:14505755@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/49958998:7007788@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/49958998:7007788@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/33877745:32407305@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/33877745:32407305@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/42667908:151727@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/42667908:151727@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/42981726:10059@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/42981726:10059@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/76434671:13494460@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/76434671:13494460@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/4447862:505078@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/4447862:505078@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::saveQueue Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::updateTrackBlock Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::getTrackBlock Mar 13 23:27:18 volumio volumio[1034]: info: CoreCommandRouter::volumioPlay Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::play index 15 Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::stop Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::play index undefined Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:18 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 23:27:18 volumio volumio[1034]: info: sendMpdCommand stop took 3 milliseconds Mar 13 23:27:18 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: info: sendMpdCommand clear took 7 milliseconds Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: error: updateQueue error: null Mar 13 23:27:18 volumio volumio[1034]: error: updateQueue error: null Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 8ms Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 2ms Mar 13 23:27:18 volumio volumio[1034]: info: Preload queue cleared Mar 13 23:27:18 volumio volumio[1034]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::ClearQueue Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::stop Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::clearPlayQueue Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::saveQueue Mar 13 23:27:18 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::addQueueItems Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::addQueueItems Mar 13 23:27:18 volumio volumio[1034]: info: Preload queue cleared Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/127330314:31770388@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/127330314:31770388@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/4425412:40622@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/4425412:40622@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/54014103:7755667@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/54014103:7755667@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/20585136:476831@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/20585136:476831@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/36664873:4620359@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/36664873:4620359@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/120966517:28964387@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/120966517:28964387@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/6877472:734756@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/6877472:734756@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/2200853:217939@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/2200853:217939@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/60363095:9317591@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/60363095:9317591@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/110081927:24402993@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/110081927:24402993@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/64357978:10329735@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/64357978:10329735@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/138905:4813230@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/138905:4813230@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/113872062:25875592@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/113872062:25875592@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/6098551:91137@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/6098551:91137@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/122041048:29465469@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/122041048:29465469@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/78780542:14189169@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/78780542:14189169@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/28192829:3369432@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/28192829:3369432@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/68348390:11322908@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/68348390:11322908@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/95981460:29627983@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/95981460:29627983@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/28098490:3356224@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/28098490:3356224@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/67538121:11107121@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/67538121:11107121@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/40618149:102138@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/40618149:102138@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/36999048:32833196@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/36999048:32833196@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/18172490:14505755@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/18172490:14505755@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/49958998:7007788@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/49958998:7007788@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/33877745:32407305@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/33877745:32407305@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/42667908:151727@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/42667908:151727@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/42981726:10059@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/42981726:10059@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/76434671:13494460@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/76434671:13494460@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/4447862:505078@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: Using cached record of: yandex_music/track/4447862:505078@692528232:106021857 Mar 13 23:27:18 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::saveQueue Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::updateTrackBlock Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::getTrackBlock Mar 13 23:27:18 volumio volumio[1034]: info: CoreCommandRouter::volumioPlay Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::play index 15 Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::stop Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::play index undefined Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Mar 13 23:27:18 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:18 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 23:27:18 volumio volumio[1034]: info: sendMpdCommand stop took 2 milliseconds Mar 13 23:27:18 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: info: sendMpdCommand clear took 3 milliseconds Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: error: updateQueue error: null Mar 13 23:27:18 volumio volumio[1034]: error: updateQueue error: null Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 9ms Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 1ms Mar 13 23:27:18 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac" Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac" took 2 milliseconds Mar 13 23:27:18 volumio volumio[1034]: verbose: MPD COMMAND [object Object] Mar 13 23:27:18 volumio volumio[1034]: verbose: MPD COMMAND [object Object] Mar 13 23:27:18 volumio volumio[1034]: verbose: MPD COMMAND [object Object] Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 3ms Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 1ms Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 13 23:27:18 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand play Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 2ms Mar 13 23:27:18 volumio volumio[1034]: info: sendMpdCommand play took 1 milliseconds Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 2ms Mar 13 23:27:18 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac" Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac" took 3 milliseconds Mar 13 23:27:18 volumio volumio[1034]: verbose: MPD COMMAND [object Object] Mar 13 23:27:18 volumio volumio[1034]: verbose: MPD COMMAND [object Object] Mar 13 23:27:18 volumio volumio[1034]: verbose: MPD COMMAND [object Object] Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 2ms Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 2ms Mar 13 23:27:18 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 13 23:27:18 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand play Mar 13 23:27:18 volumio volumio[1034]: info: Mar 13 23:27:18 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:18 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 2ms Mar 13 23:27:18 volumio volumio[1034]: info: sendMpdCommand play took 1 milliseconds Mar 13 23:27:18 volumio volumio[1034]: info: ------------------------------ 1ms Mar 13 23:27:19 volumio volumio[1034]: info: Mar 13 23:27:19 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:27:19 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:27:19 volumio volumio[1034]: info: Mar 13 23:27:19 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:27:19 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:27:19 volumio volumio[1034]: info: sendMpdCommand status took 20 milliseconds Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:27:19 volumio volumio[1034]: info: Mar 13 23:27:19 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:27:19 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:27:19 volumio volumio[1034]: info: sendMpdCommand status took 24 milliseconds Mar 13 23:27:19 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:27:19 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:19 volumio volumio[1034]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I'm Alone","artist":"Melisa","album":"I'm Alone","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:27:19 volumio volumio[1034]: verbose: CURRENT POSITION 15 Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::syncState stateService play Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus stop Mar 13 23:27:19 volumio volumio[1034]: info: ------------------------------ 30ms Mar 13 23:27:19 volumio volumio[1034]: info: Mar 13 23:27:19 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:27:19 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:27:19 volumio volumio[1034]: info: sendMpdCommand status took 9 milliseconds Mar 13 23:27:19 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 13 23:27:19 volumio volumio[1034]: info: sendMpdCommand status took 2 milliseconds Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:27:19 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:19 volumio volumio[1034]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I'm Alone","artist":"Melisa","album":"I'm Alone","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:27:19 volumio volumio[1034]: verbose: CURRENT POSITION 15 Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::syncState stateService play Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus play Mar 13 23:27:19 volumio volumio[1034]: info: Received an update from plugin. extracting info from payload Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:19 volumio volumio[1034]: info: ------------------------------ 63ms Mar 13 23:27:19 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 13 23:27:19 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:27:19 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:19 volumio volumio[1034]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"390 Kbps","isStreaming":false,"title":"I'm Alone","artist":"Melisa","album":"I'm Alone","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:27:19 volumio volumio[1034]: verbose: CURRENT POSITION 15 Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::syncState stateService play Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus play Mar 13 23:27:19 volumio volumio[1034]: info: Received an update from plugin. extracting info from payload Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:19 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:19 volumio volumio[1034]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"390 Kbps","isStreaming":false,"title":"I'm Alone","artist":"Melisa","album":"I'm Alone","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:27:19 volumio volumio[1034]: verbose: CURRENT POSITION 15 Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::syncState stateService play Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus play Mar 13 23:27:19 volumio volumio[1034]: info: Received an update from plugin. extracting info from payload Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:19 volumio volumio[1034]: info: ------------------------------ 121ms Mar 13 23:27:19 volumio volumio[1034]: info: ------------------------------ 115ms Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:19 volumio volumio[1034]: info: Preload queue cleared Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::ClearQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::stop Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::stPlaybackTimer Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::updateTrackBlock Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getTrackBlock Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::serviceStop Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::serviceStop Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 13 23:27:19 volumio volumio[1034]: info: ControllerMpd::stop Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::clearPlayQueue Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::saveQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::addQueueItems Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::addQueueItems Mar 13 23:27:19 volumio volumio[1034]: info: Preload queue cleared Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/127330314:31770388@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/127330314:31770388@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/4425412:40622@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/4425412:40622@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/54014103:7755667@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/54014103:7755667@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/20585136:476831@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/20585136:476831@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/36664873:4620359@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/36664873:4620359@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/120966517:28964387@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/120966517:28964387@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/6877472:734756@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/6877472:734756@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/2200853:217939@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/2200853:217939@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/60363095:9317591@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/60363095:9317591@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/110081927:24402993@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/110081927:24402993@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/64357978:10329735@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/64357978:10329735@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/138905:4813230@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/138905:4813230@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/113872062:25875592@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/113872062:25875592@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/6098551:91137@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/6098551:91137@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/122041048:29465469@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/122041048:29465469@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/78780542:14189169@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/78780542:14189169@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/28192829:3369432@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/28192829:3369432@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/68348390:11322908@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/68348390:11322908@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/95981460:29627983@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/95981460:29627983@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/28098490:3356224@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/28098490:3356224@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/67538121:11107121@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/67538121:11107121@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/40618149:102138@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/40618149:102138@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/36999048:32833196@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/36999048:32833196@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/18172490:14505755@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/18172490:14505755@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/49958998:7007788@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/49958998:7007788@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/33877745:32407305@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/33877745:32407305@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/42667908:151727@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/42667908:151727@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/42981726:10059@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/42981726:10059@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/76434671:13494460@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/76434671:13494460@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Adding Item to queue: yandex_music/track/4447862:505078@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: Using cached record of: yandex_music/track/4447862:505078@692528232:106021857 Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::saveQueue Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::updateTrackBlock Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getTrackBlock Mar 13 23:27:19 volumio volumio[1034]: info: CoreCommandRouter::volumioPlay Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::play index 15 Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::stop Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::play index undefined Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:19 volumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Mar 13 23:27:19 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:19 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand stop took 68 milliseconds Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand stop took 19 milliseconds Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:20 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand status took 59 milliseconds Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand clear took 59 milliseconds Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand status took 58 milliseconds Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:20 volumio volumio[1034]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 93ms Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:20 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:20 volumio volumio[1034]: error: updateQueue error: null Mar 13 23:27:20 volumio volumio[1034]: error: updateQueue error: null Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 58ms Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 46 milliseconds Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 6ms Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:27:20 volumio volumio[1034]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 113ms Mar 13 23:27:20 volumio volumio[1034]: info: touch_display: Setting screensaver timeout to 3600 seconds. Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac" Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:20 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac" took 2 milliseconds Mar 13 23:27:20 volumio volumio[1034]: verbose: MPD COMMAND [object Object] Mar 13 23:27:20 volumio volumio[1034]: verbose: MPD COMMAND [object Object] Mar 13 23:27:20 volumio volumio[1034]: verbose: MPD COMMAND [object Object] Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:20 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:20 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 3ms Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 1ms Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand play Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces system playlist update Mar 13 23:27:20 volumio volumio[1034]: info: Ignoring MPD Status Update Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 3ms Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand play took 2 milliseconds Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 1ms Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand status took 5 milliseconds Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand status took 6 milliseconds Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:20 volumio volumio[1034]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I'm Alone","artist":"Melisa","album":"I'm Alone","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:27:20 volumio volumio[1034]: verbose: CURRENT POSITION 15 Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::syncState stateService play Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus stop Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 12ms Mar 13 23:27:20 volumio volumio[1034]: info: Mar 13 23:27:20 volumio volumio[1034]: ---------------------------- MPD announces state update: player Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::getState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand status Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand status took 5 milliseconds Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:20 volumio volumio[1034]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I'm Alone","artist":"Melisa","album":"I'm Alone","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:27:20 volumio volumio[1034]: verbose: CURRENT POSITION 15 Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::syncState stateService play Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus play Mar 13 23:27:20 volumio volumio[1034]: info: Received an update from plugin. extracting info from payload Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 40ms Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand status took 35 milliseconds Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseState Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:20 volumio volumio[1034]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"I'm Alone","artist":"Melisa","album":"I'm Alone","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:27:20 volumio volumio[1034]: verbose: CURRENT POSITION 15 Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::syncState stateService play Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus play Mar 13 23:27:20 volumio volumio[1034]: info: Received an update from plugin. extracting info from payload Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 80ms Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: sendMpdCommand playlistinfo took 116 milliseconds Mar 13 23:27:20 volumio volumio[1034]: verbose: ControllerMpd::parseTrackInfo Mar 13 23:27:20 volumio volumio[1034]: info: ControllerMpd::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getTrack 15 Mar 13 23:27:20 volumio volumio[1034]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"507 Kbps","isStreaming":false,"title":"I'm Alone","artist":"Melisa","album":"I'm Alone","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-rndttk-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D848630c2cfd967fa586a4eef4824e4023b8ef4babe5f8f39563be19737da3c8a%2Clid%3D1595%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e0f106%2F0%2F43747%2Fbaf3d356.119705617.21.78780542%2Fflac&ext=.flac","trackType":"flac"} Mar 13 23:27:20 volumio volumio[1034]: verbose: CURRENT POSITION 15 Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::syncState stateService play Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus play Mar 13 23:27:20 volumio volumio[1034]: info: Received an update from plugin. extracting info from payload Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::pushState Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Mar 13 23:27:20 volumio volumio[1034]: info: ------------------------------ 177ms Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Mar 13 23:27:20 volumio volumio[1034]: info: CoreStateMachine::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: CorePlayQueue::getQueue Mar 13 23:27:20 volumio volumio[1034]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 13 23:27:22 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Mar 13 23:27:29 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 23:27:29 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 13 23:27:31 volumio volumio[1034]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 13 23:27:31 volumio volumio[1034]: Traceback (most recent call last): Mar 13 23:27:31 volumio volumio[1034]: File "./screensaver/volumio_peppymeter.py", line 26, in Mar 13 23:27:31 volumio volumio[1034]: from peppymeter.peppymeter import Peppymeter Mar 13 23:27:31 volumio volumio[1034]: ModuleNotFoundError: No module named 'peppymeter' Mar 13 23:27:39 volumio volumio[1034]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/autostart/volumio/buster/armhf Mar 13 23:27:40 volumio volumio[1034]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/autostart/volumio/buster/armhf Mar 13 23:27:41 volumio volumio[1034]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 13 23:27:41 volumio volumio[1034]: Traceback (most recent call last): Mar 13 23:27:41 volumio volumio[1034]: File "./screensaver/volumio_peppymeter.py", line 26, in Mar 13 23:27:41 volumio volumio[1034]: from peppymeter.peppymeter import Peppymeter Mar 13 23:27:41 volumio volumio[1034]: ModuleNotFoundError: No module named 'peppymeter' Mar 13 23:27:48 volumio volumio[1034]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 23:27:48 volumio volumio[1034]: Error: read ECONNRESET Mar 13 23:27:48 volumio volumio[1034]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Mar 13 23:27:48 volumio volumio[1034]: errno: -104, Mar 13 23:27:48 volumio volumio[1034]: code: 'ECONNRESET', Mar 13 23:27:48 volumio volumio[1034]: syscall: 'read' Mar 13 23:27:48 volumio volumio[1034]: } Mar 13 23:27:48 volumio volumio[1034]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 23:27:48 volumio sudo[3630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-13 23:26 Mar 13 23:27:48 volumio sudo[3630]: 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="51755a7ab41793587aa858aae43b662293a9df12" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 02 Jun 2024 06:06:05 PM CEST" VOLUMIO_VERSION="3.701" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe4d1e78c5ecc2059f504259e3e8eb08"