-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Sun 2025-03-02 21:57:02 EET. -- Mar 02 21:56:03 volumio volumio[919]: info: Stopping peppymeterbasic service Mar 02 21:56:03 volumio volumio[919]: info: CoreStateMachine::stop Mar 02 21:56:03 volumio volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 21:56:03 volumio volumio[919]: info: CoreStateMachine::stPlaybackTimer Mar 02 21:56:03 volumio volumio[919]: info: CoreStateMachine::updateTrackBlock Mar 02 21:56:03 volumio volumio[919]: info: CorePlayQueue::getTrackBlock Mar 02 21:56:03 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:03 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:03 volumio volumio[919]: info: CoreStateMachine::serviceStop Mar 02 21:56:03 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::serviceStop Mar 02 21:56:03 volumio volumio[919]: info: [1740945363083] ControllerWebradio::stop Mar 02 21:56:03 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 21:56:03 volumio volumio[919]: info: Disabling plugin peppymeterbasic Mar 02 21:56:03 volumio volumio[919]: info: Preparing to generate the ALSA configuration file Mar 02 21:56:03 volumio volumio[919]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 02 21:56:03 volumio volumio[919]: info: Reading ALSA contributions from plugins. Mar 02 21:56:03 volumio volumio[919]: info: [ASDebug] CurState: stop PrevState: play Mar 02 21:56:03 volumio volumio[919]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 02 21:56:03 volumio volumio[919]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 02 21:56:03 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status stop Mar 02 21:56:03 volumio sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 02 21:56:03 volumio sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:03 volumio volumio[919]: info: Mar 02 21:56:03 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:56:03 volumio volumio[919]: info: sendMpdCommand stop took 244 milliseconds Mar 02 21:56:03 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:56:03 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:56:03 volumio sudo[2251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 02 21:56:03 volumio sudo[2251]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:03 volumio volumio[919]: info: Mar 02 21:56:03 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:56:03 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:56:03 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:56:03 volumio systemd[1]: Stopping peppymeterbasic Daemon... Mar 02 21:56:03 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Mar 02 21:56:03 volumio sudo[2255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 02 21:56:03 volumio systemd[1]: Stopping peppyspectrum Daemon... Mar 02 21:56:03 volumio sudo[2255]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:03 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Mar 02 21:56:03 volumio volumio[919]: info: sendMpdCommand status took 67 milliseconds Mar 02 21:56:03 volumio volumio[919]: info: sendMpdCommand status took 34 milliseconds Mar 02 21:56:03 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:56:03 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:56:03 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:56:03 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:56:03 volumio volumio[919]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 02 21:56:03 volumio volumio[919]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 02 21:56:03 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:56:03 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:56:03 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:56:03 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:03 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:03 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:03 volumio volumio[919]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 02 21:56:03 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:56:03 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:03 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:03 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:03 volumio volumio[919]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 02 21:56:03 volumio volumio[919]: info: ------------------------------ 141ms Mar 02 21:56:03 volumio volumio[919]: info: ------------------------------ 108ms Mar 02 21:56:03 volumio volumio[919]: info: [ASDebug] CurState: stop PrevState: play Mar 02 21:56:03 volumio volumio[919]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 02 21:56:03 volumio systemd[1]: peppyspectrum.service: Succeeded. Mar 02 21:56:03 volumio volumio[919]: info: [ASDebug] CurState: stop PrevState: play Mar 02 21:56:03 volumio volumio[919]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 02 21:56:03 volumio systemd[1]: Stopped peppyspectrum Daemon. Mar 02 21:56:03 volumio systemd[1]: peppymeterbasic.service: Succeeded. Mar 02 21:56:03 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status stop Mar 02 21:56:03 volumio systemd[1]: Stopped peppymeterbasic Daemon. Mar 02 21:56:03 volumio sudo[2251]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:03 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status stop Mar 02 21:56:03 volumio sudo[2255]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:03 volumio sudo[2249]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:03 volumio volumio[919]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 02 21:56:03 volumio sudo[2260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 02 21:56:03 volumio sudo[2260]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:03 volumio volumio[919]: info: peppymeterbasic Daemon Stop Mar 02 21:56:03 volumio volumio[919]: info: peppyspectrum Daemon Stop Mar 02 21:56:03 volumio sudo[2262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 02 21:56:03 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:03 volumio volumio[919]: info: Asound.conf file written Mar 02 21:56:03 volumio sudo[2260]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:03 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:03 volumio sudo[2266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 02 21:56:03 volumio sudo[2266]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:03 volumio sudo[2266]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:03 volumio volumio[919]: No state is present for card BossDAC Mar 02 21:56:03 volumio volumio[919]: Found hardware: "BossDAC" "" "" "" "" Mar 02 21:56:03 volumio volumio[919]: Hardware is initialized using a generic method Mar 02 21:56:03 volumio volumio[919]: No state is present for card BossDAC Mar 02 21:56:03 volumio volumio[919]: info: Output device has changed, restarting MPD Mar 02 21:56:03 volumio sudo[2271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 02 21:56:03 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:03 volumio volumio[919]: info: Output device has changed, restarting Shairport Sync Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 21:56:03 volumio sudo[2274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 02 21:56:03 volumio sudo[2271]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:03 volumio sudo[2274]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:03 volumio systemd[1]: Stopping Music Player Daemon... Mar 02 21:56:03 volumio volumio[919]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 02 21:56:03 volumio volumio[919]: info: Done. Mar 02 21:56:03 volumio volumio[919]: info: MPD Permissions set Mar 02 21:56:03 volumio volumio[919]: info: peppyspectrum Daemon Stop Mar 02 21:56:03 volumio volumio[919]: info: peppyspectrum Daemon Stop Mar 02 21:56:03 volumio volumio[919]: info: Starting Shairport Sync Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:03 volumio sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 02 21:56:03 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 21:56:03 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:03 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 02 21:56:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 02 21:56:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 02 21:56:04 volumio sudo[2280]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:04 volumio volumio[919]: info: Shairport-Sync Started Mar 02 21:56:04 volumio volumio[919]: info: [ASDebug] Togle GPIO: OFF Mar 02 21:56:04 volumio systemd[1]: mpd.service: Succeeded. Mar 02 21:56:04 volumio systemd[1]: Stopped Music Player Daemon. Mar 02 21:56:04 volumio systemd[1]: Starting Music Player Daemon... Mar 02 21:56:04 volumio sudo[2289]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 02 21:56:04 volumio sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:04 volumio sudo[2289]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:06 volumio mpd[2291]: Mar 02 21:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 02 21:56:06 volumio systemd[1]: Started Music Player Daemon. Mar 02 21:56:06 volumio sudo[2274]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:06 volumio volumio[919]: error: updateQueue error: null Mar 02 21:56:17 volumio volumio[919]: info: CoreCommandRouter::volumioGetState Mar 02 21:56:17 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:18 volumio volumio[919]: info: CoreCommandRouter::volumioPlay Mar 02 21:56:18 volumio volumio[919]: info: CoreStateMachine::play index undefined Mar 02 21:56:18 volumio volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 21:56:18 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:18 volumio volumio[919]: info: CoreStateMachine::startPlaybackTimer Mar 02 21:56:18 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:18 volumio volumio[919]: info: [1740945378498] ControllerWebradio::clearAddPlayTrack Mar 02 21:56:18 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 21:56:18 volumio volumio[919]: info: sendMpdCommand stop took 1 milliseconds Mar 02 21:56:18 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 21:56:18 volumio volumio[919]: info: Mar 02 21:56:18 volumio volumio[919]: ---------------------------- MPD announces system playlist update Mar 02 21:56:18 volumio volumio[919]: info: Ignoring MPD Status Update Mar 02 21:56:18 volumio volumio[919]: info: sendMpdCommand clear took 1 milliseconds Mar 02 21:56:18 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Mar 02 21:56:18 volumio volumio[919]: error: updateQueue error: null Mar 02 21:56:18 volumio volumio[919]: info: ------------------------------ 3ms Mar 02 21:56:18 volumio volumio[919]: info: Mar 02 21:56:18 volumio volumio[919]: ---------------------------- MPD announces system playlist update Mar 02 21:56:18 volumio volumio[919]: info: Ignoring MPD Status Update Mar 02 21:56:18 volumio volumio[919]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 481 milliseconds Mar 02 21:56:18 volumio volumio[919]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 02 21:56:18 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand play Mar 02 21:56:18 volumio volumio[919]: info: ------------------------------ 3ms Mar 02 21:56:18 volumio volumio[919]: info: sendMpdCommand play took 2 milliseconds Mar 02 21:56:20 volumio volumio[919]: info: Mar 02 21:56:20 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:56:20 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:56:20 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:56:20 volumio volumio[919]: info: sendMpdCommand status took 31 milliseconds Mar 02 21:56:20 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:56:20 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:56:20 volumio volumio[919]: info: Mar 02 21:56:20 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:56:20 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:56:20 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:56:20 volumio volumio[919]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 02 21:56:20 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:56:20 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:56:20 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:56:20 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:20 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 1935/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 02 21:56:20 volumio volumio[919]: verbose: CURRENT POSITION 0 Mar 02 21:56:20 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Mar 02 21:56:20 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus stop Mar 02 21:56:20 volumio volumio[919]: info: ------------------------------ 40ms Mar 02 21:56:20 volumio volumio[919]: info: sendMpdCommand status took 5 milliseconds Mar 02 21:56:20 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:56:20 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:56:20 volumio volumio[919]: info: Mar 02 21:56:20 volumio volumio[919]: ---------------------------- MPD announces system playlist update Mar 02 21:56:20 volumio volumio[919]: info: Ignoring MPD Status Update Mar 02 21:56:20 volumio volumio[919]: info: Mar 02 21:56:20 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:56:20 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:56:20 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:56:20 volumio volumio[919]: info: Mar 02 21:56:20 volumio volumio[919]: ---------------------------- MPD announces system playlist update Mar 02 21:56:20 volumio volumio[919]: info: Ignoring MPD Status Update Mar 02 21:56:20 volumio volumio[919]: info: Mar 02 21:56:20 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:56:20 volumio volumio[919]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 02 21:56:21 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:56:21 volumio volumio[919]: info: ------------------------------ 11ms Mar 02 21:56:21 volumio volumio[919]: info: sendMpdCommand status took 10 milliseconds Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:56:21 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:56:21 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:21 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 02 21:56:21 volumio volumio[919]: verbose: CURRENT POSITION 0 Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Mar 02 21:56:21 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:21 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:21 volumio volumio[919]: info: ------------------------------ 48ms Mar 02 21:56:21 volumio volumio[919]: info: Mar 02 21:56:21 volumio volumio[919]: ---------------------------- MPD announces system playlist update Mar 02 21:56:21 volumio volumio[919]: info: Ignoring MPD Status Update Mar 02 21:56:21 volumio volumio[919]: info: Mar 02 21:56:21 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:56:21 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:56:21 volumio volumio[919]: info: ------------------------------ 50ms Mar 02 21:56:21 volumio volumio[919]: info: sendMpdCommand status took 48 milliseconds Mar 02 21:56:21 volumio volumio[919]: info: sendMpdCommand playlistinfo took 46 milliseconds Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:56:21 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:56:21 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:21 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nevermore - Final product","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 02 21:56:21 volumio volumio[919]: verbose: CURRENT POSITION 0 Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Mar 02 21:56:21 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:21 volumio volumio[919]: info: ------------------------------ 113ms Mar 02 21:56:21 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: stop Mar 02 21:56:21 volumio volumio[919]: info: [ASDebug] Togle GPIO: ON Mar 02 21:56:21 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:21 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:21 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:21 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:21 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:21 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:21 volumio sudo[2337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:21 volumio sudo[2339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:21 volumio sudo[2339]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:21 volumio sudo[2337]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:21 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:21 volumio sudo[2343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:21 volumio sudo[2343]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:21 volumio volumio[919]: info: ------------------------------ 277ms Mar 02 21:56:21 volumio volumio[919]: info: sendMpdCommand status took 262 milliseconds Mar 02 21:56:21 volumio volumio[919]: info: sendMpdCommand playlistinfo took 259 milliseconds Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:56:21 volumio sudo[2346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:21 volumio sudo[2346]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:56:21 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:56:21 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:21 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nevermore - Final product","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 02 21:56:21 volumio volumio[919]: verbose: CURRENT POSITION 0 Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Mar 02 21:56:21 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:21 volumio systemd[1]: Started peppyspectrum Daemon. Mar 02 21:56:21 volumio volumio[919]: info: ------------------------------ 345ms Mar 02 21:56:21 volumio sudo[2337]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:21 volumio volumio[919]: info: sendMpdCommand playlistinfo took 39 milliseconds Mar 02 21:56:21 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:56:21 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:56:21 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:21 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nevermore - Final product","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 02 21:56:21 volumio volumio[919]: verbose: CURRENT POSITION 0 Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Mar 02 21:56:21 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:21 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:21 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:21 volumio sudo[2339]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:21 volumio sudo[2343]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:21 volumio volumio[919]: info: ------------------------------ 367ms Mar 02 21:56:21 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:21 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:21 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:21 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:21 volumio sudo[2346]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:21 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:21 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:21 volumio sudo[2351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:21 volumio sudo[2351]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:21 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:21 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:21 volumio sudo[2354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:21 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:21 volumio sudo[2356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:21 volumio sudo[2356]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:21 volumio sudo[2351]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:21 volumio volumio[919]: info: peppyspectrum Daemon Started Mar 02 21:56:21 volumio volumio[919]: info: peppyspectrum Daemon Started Mar 02 21:56:21 volumio sudo[2360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:21 volumio sudo[2360]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:21 volumio sudo[2354]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:21 volumio volumio[919]: info: peppyspectrum Daemon Started Mar 02 21:56:21 volumio sudo[2356]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:21 volumio volumio[919]: info: peppyspectrum Daemon Started Mar 02 21:56:21 volumio sudo[2360]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:21 volumio volumio[919]: info: peppyspectrum Daemon Started Mar 02 21:56:22 volumio volumio[919]: info: Mar 02 21:56:22 volumio volumio[919]: ---------------------------- MPD announces system playlist update Mar 02 21:56:22 volumio volumio[919]: info: Ignoring MPD Status Update Mar 02 21:56:22 volumio volumio[919]: info: Mar 02 21:56:22 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:56:22 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:56:22 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:56:22 volumio volumio[919]: info: ------------------------------ 3ms Mar 02 21:56:22 volumio volumio[919]: info: sendMpdCommand status took 2 milliseconds Mar 02 21:56:22 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:56:22 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:56:22 volumio volumio[919]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 02 21:56:22 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:56:22 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:56:22 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:56:22 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:22 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2282,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nevermore - Final product","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 02 21:56:22 volumio volumio[919]: verbose: CURRENT POSITION 0 Mar 02 21:56:22 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Mar 02 21:56:22 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Mar 02 21:56:22 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Mar 02 21:56:22 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 02 21:56:22 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:22 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 21:56:22 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:22 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:22 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:22 volumio volumio[919]: info: ------------------------------ 53ms Mar 02 21:56:22 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:22 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:22 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:22 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:22 volumio sudo[2363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:22 volumio sudo[2363]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:22 volumio sudo[2366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:22 volumio sudo[2366]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:22 volumio sudo[2363]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:22 volumio volumio[919]: info: peppyspectrum Daemon Started Mar 02 21:56:22 volumio sudo[2366]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:22 volumio volumio[919]: info: peppyspectrum Daemon Started Mar 02 21:56:23 volumio volumio[919]: info: Mar 02 21:56:23 volumio volumio[919]: ---------------------------- MPD announces system playlist update Mar 02 21:56:23 volumio volumio[919]: info: Ignoring MPD Status Update Mar 02 21:56:23 volumio volumio[919]: info: Mar 02 21:56:23 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:56:23 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:56:23 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:56:23 volumio volumio[919]: info: ------------------------------ 4ms Mar 02 21:56:23 volumio volumio[919]: info: sendMpdCommand status took 2 milliseconds Mar 02 21:56:23 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:56:23 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:56:23 volumio volumio[919]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 02 21:56:23 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:56:23 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:56:23 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:56:23 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:23 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2987,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nevermore - Final product","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 02 21:56:23 volumio volumio[919]: verbose: CURRENT POSITION 0 Mar 02 21:56:23 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Mar 02 21:56:23 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Mar 02 21:56:23 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Mar 02 21:56:23 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 02 21:56:23 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:23 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 21:56:23 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:23 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:23 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:23 volumio volumio[919]: info: ------------------------------ 52ms Mar 02 21:56:23 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:23 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: play Mar 02 21:56:23 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:23 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status play Mar 02 21:56:23 volumio sudo[2369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:23 volumio sudo[2369]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:23 volumio sudo[2372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 02 21:56:23 volumio sudo[2372]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:23 volumio sudo[2369]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:23 volumio volumio[919]: info: peppyspectrum Daemon Started Mar 02 21:56:23 volumio sudo[2372]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:23 volumio volumio[919]: info: peppyspectrum Daemon Started Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:23 volumio volumio[2347]: libpng warning: iCCP: known incorrect sRGB profile Mar 02 21:56:29 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 02 21:56:29 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 02 21:56:34 volumio volumio[919]: info: Stopping peppyspectrum service Mar 02 21:56:34 volumio volumio[919]: info: CoreStateMachine::stop Mar 02 21:56:34 volumio volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 21:56:34 volumio volumio[919]: info: CoreStateMachine::stPlaybackTimer Mar 02 21:56:34 volumio volumio[919]: info: CoreStateMachine::updateTrackBlock Mar 02 21:56:34 volumio volumio[919]: info: CorePlayQueue::getTrackBlock Mar 02 21:56:34 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:34 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:34 volumio volumio[919]: info: CoreStateMachine::serviceStop Mar 02 21:56:34 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::serviceStop Mar 02 21:56:34 volumio volumio[919]: info: [1740945394432] ControllerWebradio::stop Mar 02 21:56:34 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 21:56:34 volumio volumio[919]: info: Disabling plugin peppyspectrum Mar 02 21:56:34 volumio volumio[919]: info: Preparing to generate the ALSA configuration file Mar 02 21:56:34 volumio volumio[919]: info: [ASDebug] CurState: stop PrevState: play Mar 02 21:56:34 volumio volumio[919]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 02 21:56:34 volumio volumio[919]: info: PeppySpectrum ---peppyspectrum status stop Mar 02 21:56:34 volumio volumio[919]: info: Mar 02 21:56:34 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:56:34 volumio volumio[919]: info: sendMpdCommand stop took 87 milliseconds Mar 02 21:56:34 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:56:34 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:56:34 volumio sudo[2396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 02 21:56:34 volumio sudo[2396]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:34 volumio volumio[919]: info: sendMpdCommand status took 44 milliseconds Mar 02 21:56:34 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:56:34 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:56:34 volumio systemd[1]: Stopping peppyspectrum Daemon... Mar 02 21:56:34 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Mar 02 21:56:34 volumio sudo[2399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 02 21:56:34 volumio sudo[2399]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:34 volumio volumio[919]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 02 21:56:34 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:56:34 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:56:34 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:56:34 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:56:34 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:34 volumio volumio[919]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 02 21:56:34 volumio volumio[919]: info: ------------------------------ 98ms Mar 02 21:56:34 volumio volumio[919]: info: [ASDebug] CurState: stop PrevState: play Mar 02 21:56:34 volumio volumio[919]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 02 21:56:34 volumio volumio[919]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 02 21:56:34 volumio volumio[919]: info: Asound.conf file written Mar 02 21:56:34 volumio sudo[2404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 02 21:56:34 volumio sudo[2404]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:34 volumio sudo[2404]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:34 volumio systemd[1]: peppyspectrum.service: Succeeded. Mar 02 21:56:34 volumio systemd[1]: Stopped peppyspectrum Daemon. Mar 02 21:56:34 volumio sudo[2399]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:34 volumio sudo[2396]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:34 volumio volumio[919]: No state is present for card BossDAC Mar 02 21:56:34 volumio volumio[919]: Found hardware: "BossDAC" "" "" "" "" Mar 02 21:56:34 volumio volumio[919]: Hardware is initialized using a generic method Mar 02 21:56:34 volumio volumio[919]: No state is present for card BossDAC Mar 02 21:56:34 volumio volumio[919]: info: Output device has changed, restarting MPD Mar 02 21:56:34 volumio sudo[2409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 02 21:56:34 volumio sudo[2409]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:34 volumio volumio[919]: info: Output device has changed, restarting Shairport Sync Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 21:56:34 volumio sudo[2409]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:34 volumio sudo[2412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 02 21:56:34 volumio sudo[2412]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:34 volumio systemd[1]: Stopping Music Player Daemon... Mar 02 21:56:34 volumio volumio[919]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 02 21:56:34 volumio volumio[919]: info: Done. Mar 02 21:56:34 volumio systemd[1]: mpd.service: Succeeded. Mar 02 21:56:34 volumio systemd[1]: Stopped Music Player Daemon. Mar 02 21:56:34 volumio volumio[919]: info: MPD Permissions set Mar 02 21:56:34 volumio volumio[919]: info: peppyspectrum Daemon Stop Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 21:56:34 volumio volumio[919]: info: Starting Shairport Sync Mar 02 21:56:34 volumio systemd[1]: Starting Music Player Daemon... Mar 02 21:56:34 volumio sudo[2423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 02 21:56:34 volumio sudo[2423]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 02 21:56:34 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 02 21:56:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 02 21:56:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 02 21:56:34 volumio sudo[2423]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:34 volumio volumio[919]: info: Shairport-Sync Started Mar 02 21:56:35 volumio sudo[2417]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 02 21:56:35 volumio sudo[2417]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 21:56:35 volumio sudo[2417]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:35 volumio volumio[919]: info: [ASDebug] Togle GPIO: OFF Mar 02 21:56:36 volumio mpd[2429]: Mar 02 21:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 02 21:56:36 volumio systemd[1]: Started Music Player Daemon. Mar 02 21:56:36 volumio sudo[2412]: pam_unix(sudo:session): session closed for user root Mar 02 21:56:36 volumio volumio[919]: error: updateQueue error: null Mar 02 21:56:40 volumio volumio[919]: info: Disabling plugin rotaryencoder2 Mar 02 21:56:40 volumio volumio[919]: info: Done. Mar 02 21:56:42 volumio volumio[919]: info: GPIO-Buttons stopped Mar 02 21:56:42 volumio volumio[919]: info: Disabling plugin gpio-buttons Mar 02 21:56:42 volumio volumio[919]: info: Done. Mar 02 21:56:44 volumio volumio[919]: info: performing onStop action Mar 02 21:56:44 volumio volumio[919]: info: Disabling plugin audiophonicsonoff Mar 02 21:56:44 volumio volumio[919]: info: Done. Mar 02 21:56:48 volumio volumio[919]: info: [ASDebug] Port: 24 Mar 02 21:56:48 volumio volumio[919]: info: [ASDebug] Inverted: false Mar 02 21:56:48 volumio volumio[919]: info: [ASDebug] Delay: 720 Mar 02 21:56:58 volumio volumio[919]: info: CoreCommandRouter::volumioGetState Mar 02 21:56:58 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:59 volumio volumio[919]: info: CoreCommandRouter::volumioPlay Mar 02 21:56:59 volumio volumio[919]: info: CoreStateMachine::play index undefined Mar 02 21:56:59 volumio volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 21:56:59 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:59 volumio volumio[919]: info: CoreStateMachine::startPlaybackTimer Mar 02 21:56:59 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:56:59 volumio volumio[919]: info: [1740945419048] ControllerWebradio::clearAddPlayTrack Mar 02 21:56:59 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 21:56:59 volumio volumio[919]: info: sendMpdCommand stop took 1 milliseconds Mar 02 21:56:59 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 21:56:59 volumio volumio[919]: info: Mar 02 21:56:59 volumio volumio[919]: ---------------------------- MPD announces system playlist update Mar 02 21:56:59 volumio volumio[919]: info: Ignoring MPD Status Update Mar 02 21:56:59 volumio volumio[919]: info: sendMpdCommand clear took 1 milliseconds Mar 02 21:56:59 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Mar 02 21:56:59 volumio volumio[919]: error: updateQueue error: null Mar 02 21:56:59 volumio volumio[919]: info: ------------------------------ 3ms Mar 02 21:56:59 volumio volumio[919]: info: Mar 02 21:56:59 volumio volumio[919]: ---------------------------- MPD announces system playlist update Mar 02 21:56:59 volumio volumio[919]: info: Ignoring MPD Status Update Mar 02 21:56:59 volumio volumio[919]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 931 milliseconds Mar 02 21:56:59 volumio volumio[919]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 02 21:56:59 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand play Mar 02 21:56:59 volumio volumio[919]: info: ------------------------------ 3ms Mar 02 21:56:59 volumio volumio[919]: info: sendMpdCommand play took 3 milliseconds Mar 02 21:57:01 volumio volumio[919]: info: Mar 02 21:57:01 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:57:01 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:57:01 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:57:01 volumio volumio[919]: info: Mar 02 21:57:01 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:57:01 volumio volumio[919]: info: sendMpdCommand status took 19 milliseconds Mar 02 21:57:01 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:57:01 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:57:01 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:57:01 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:57:01 volumio volumio[919]: info: sendMpdCommand status took 2 milliseconds Mar 02 21:57:01 volumio volumio[919]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 02 21:57:01 volumio volumio[919]: verbose: ControllerMpd::parseState Mar 02 21:57:01 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 21:57:01 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:57:01 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:57:01 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:57:01 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:57:01 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 1940/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 02 21:57:01 volumio volumio[919]: verbose: CURRENT POSITION 0 Mar 02 21:57:01 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Mar 02 21:57:01 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus stop Mar 02 21:57:01 volumio volumio[919]: info: ------------------------------ 25ms Mar 02 21:57:01 volumio volumio[919]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 02 21:57:01 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Mar 02 21:57:01 volumio volumio[919]: info: ControllerMpd::pushState Mar 02 21:57:01 volumio volumio[919]: info: CoreCommandRouter::servicePushState Mar 02 21:57:01 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:57:01 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 1940/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 02 21:57:01 volumio volumio[919]: verbose: CURRENT POSITION 0 Mar 02 21:57:01 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Mar 02 21:57:01 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Mar 02 21:57:01 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Mar 02 21:57:01 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 02 21:57:01 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:57:01 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Mar 02 21:57:01 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 21:57:01 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:57:01 volumio volumio[919]: info: CoreStateMachine::pushState Mar 02 21:57:01 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Mar 02 21:57:01 volumio volumio[919]: info: ------------------------------ 29ms Mar 02 21:57:01 volumio volumio[919]: info: Mar 02 21:57:01 volumio volumio[919]: ---------------------------- MPD announces system playlist update Mar 02 21:57:01 volumio volumio[919]: info: Ignoring MPD Status Update Mar 02 21:57:01 volumio volumio[919]: info: Mar 02 21:57:01 volumio volumio[919]: ---------------------------- MPD announces state update: player Mar 02 21:57:01 volumio volumio[919]: info: ControllerMpd::getState Mar 02 21:57:01 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Mar 02 21:57:01 volumio volumio[919]: info: [ASDebug] CurState: play PrevState: stop Mar 02 21:57:01 volumio volumio[919]: info: [ASDebug] Togle GPIO: ON Mar 02 21:57:01 volumio volumio[919]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 21:57:01 volumio volumio[919]: Error: EBADF: bad file descriptor, write Mar 02 21:57:01 volumio volumio[919]: at Object.writeSync (fs.js:711:3) Mar 02 21:57:01 volumio volumio[919]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Mar 02 21:57:01 volumio volumio[919]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Mar 02 21:57:01 volumio volumio[919]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Mar 02 21:57:01 volumio volumio[919]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Mar 02 21:57:01 volumio volumio[919]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Mar 02 21:57:01 volumio volumio[919]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Mar 02 21:57:01 volumio volumio[919]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Mar 02 21:57:01 volumio volumio[919]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Mar 02 21:57:01 volumio volumio[919]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Mar 02 21:57:01 volumio volumio[919]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Mar 02 21:57:01 volumio volumio[919]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Mar 02 21:57:01 volumio volumio[919]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Mar 02 21:57:01 volumio volumio[919]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Mar 02 21:57:01 volumio volumio[919]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Mar 02 21:57:01 volumio volumio[919]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Mar 02 21:57:01 volumio volumio[919]: errno: -9, Mar 02 21:57:01 volumio volumio[919]: syscall: 'write', Mar 02 21:57:01 volumio volumio[919]: code: 'EBADF' Mar 02 21:57:01 volumio volumio[919]: } Mar 02 21:57:01 volumio volumio[919]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 21:57:02 volumio sudo[2544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-02 21:56 Mar 02 21:57:02 volumio sudo[2544]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"