-- Logs begin at Sun 2024-07-21 19:34:55 EDT, end at Sun 2024-07-21 19:37:58 EDT. -- Jul 21 19:36:00 volumio wpa_supplicant[1195]: RRM: Ignoring radio measurement request: Not RRM network Jul 21 19:36:04 volumio volumio[1296]: info: Preload queue cleared Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::ClearQueue Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::stop Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::clearPlayQueue Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::addQueueItems Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::addQueueItems Jul 21 19:36:04 volumio volumio[1296]: info: Preload queue cleared Jul 21 19:36:04 volumio volumio[1296]: info: Adding Item to queue: webeighties/5 Jul 21 19:36:04 volumio volumio[1296]: info: Exploding uri webeighties/5 in service 80s80s Jul 21 19:36:04 volumio volumio[1296]: info: [1721604964489] [80s80s] explodeUri: webeighties/5 Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::volumioPlay Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::play index 0 Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::stop Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::play index undefined Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::startPlaybackTimer Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:04 volumio volumio[1296]: info: [1721604964495] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/italohits/mp3-192/volumio Jul 21 19:36:04 volumio volumio[1296]: info: [1721604964495] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=283&count=2 Jul 21 19:36:04 volumio ntpd[1107]: Soliciting pool server 155.138.155.0 Jul 21 19:36:04 volumio ntpd[1107]: Soliciting pool server 208.81.1.244 Jul 21 19:36:04 volumio ntpd[1107]: Soliciting pool server 192.99.168.180 Jul 21 19:36:04 volumio volumio[1296]: info: [1721604964848] [80s80s] received new event containing 2 songs. Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 19:36:04 volumio volumio[1296]: info: sendMpdCommand stop took 1 milliseconds Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand clear Jul 21 19:36:04 volumio volumio[1296]: info: Jul 21 19:36:04 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:04 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:04 volumio volumio[1296]: info: sendMpdCommand clear took 1 milliseconds Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 21 19:36:04 volumio volumio[1296]: info: Jul 21 19:36:04 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:04 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:04 volumio volumio[1296]: info: Jul 21 19:36:04 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:04 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:04 volumio volumio[1296]: error: updateQueue error: null Jul 21 19:36:04 volumio volumio[1296]: info: Jul 21 19:36:04 volumio volumio[1296]: ---------------------------- MPD announces state update: options Jul 21 19:36:04 volumio volumio[1296]: error: updateQueue error: null Jul 21 19:36:04 volumio volumio[1296]: error: updateQueue error: null Jul 21 19:36:04 volumio volumio[1296]: info: ------------------------------ 3ms Jul 21 19:36:04 volumio volumio[1296]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 21 19:36:04 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:04 volumio volumio[1296]: info: ------------------------------ 2ms Jul 21 19:36:04 volumio volumio[1296]: info: ------------------------------ 2ms Jul 21 19:36:04 volumio volumio[1296]: info: [1721604964853] [80s80s] adding url: http://streams.80s80s.de/italohits/mp3-192/volumio Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/italohits/mp3-192/volumio" Jul 21 19:36:04 volumio volumio[1296]: info: Jul 21 19:36:04 volumio volumio[1296]: ---------------------------- MPD announces state update: options Jul 21 19:36:04 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:04 volumio volumio[1296]: info: Jul 21 19:36:04 volumio volumio[1296]: ---------------------------- MPD announces state update: options Jul 21 19:36:04 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:04 volumio volumio[1296]: info: Jul 21 19:36:04 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:04 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:04 volumio volumio[1296]: info: sendMpdCommand status took 3 milliseconds Jul 21 19:36:04 volumio volumio[1296]: info: sendMpdCommand add "http://streams.80s80s.de/italohits/mp3-192/volumio" took 2 milliseconds Jul 21 19:36:04 volumio volumio[1296]: info: sendMpdCommand status took 2 milliseconds Jul 21 19:36:04 volumio volumio[1296]: info: sendMpdCommand status took 1 milliseconds Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand play Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:04 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:04 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:04 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:04 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:04 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:04 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:04 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:04 volumio volumio[1296]: info: ------------------------------ 35ms Jul 21 19:36:04 volumio volumio[1296]: info: ------------------------------ 35ms Jul 21 19:36:04 volumio volumio[1296]: info: ------------------------------ 36ms Jul 21 19:36:04 volumio volumio[1296]: info: Jul 21 19:36:04 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:04 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:04 volumio volumio[1296]: info: Jul 21 19:36:04 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:04 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:04 volumio volumio[1296]: info: ------------------------------ 38ms Jul 21 19:36:04 volumio volumio[1296]: info: sendMpdCommand play took 22 milliseconds Jul 21 19:36:04 volumio volumio[1296]: info: ------------------------------ 3ms Jul 21 19:36:04 volumio volumio[1296]: info: ------------------------------ 3ms Jul 21 19:36:04 volumio volumio[1296]: info: [1721604964894] [80s80s] Pushing the next song state: Gazebo - Telephone Mama Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:04 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:04 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:04 volumio volumio[1296]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music124/v4/ce/ce/d2/ceced2e4-9c4e-4f97-015c-3b1c085f21ec/source/600x600bb.jpg","name":"Gazebo - Telephone Mama","title":"Telephone Mama","artist":"80s80s Italo Disco","album":"","streaming":true,"disableUiControls":true,"duration":"310","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 21 19:36:04 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::syncState stateService play Jul 21 19:36:04 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jul 21 19:36:04 volumio volumio[1296]: info: [1721604964897] [80s80s] PlayNextTrack API delay: 30 Jul 21 19:36:04 volumio volumio[1296]: info: [1721604964902] [80s80s] Setting timer to: 310000 milliseconds. Jul 21 19:36:04 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status stop Jul 21 19:36:04 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status stop Jul 21 19:36:04 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status stop Jul 21 19:36:04 volumio sudo[2461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 21 19:36:04 volumio sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:04 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jul 21 19:36:04 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jul 21 19:36:04 volumio sudo[2461]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:04 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jul 21 19:36:04 volumio volumio[1296]: info: peppyspectrum Daemon Stop Jul 21 19:36:04 volumio sudo[2459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 21 19:36:04 volumio sudo[2459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:04 volumio sudo[2459]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:04 volumio sudo[2465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 21 19:36:04 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:04 volumio volumio[1296]: info: peppyspectrum Daemon Stop Jul 21 19:36:04 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:04 volumio volumio[1296]: info: peppyspectrum Daemon Stop Jul 21 19:36:05 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:05 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:05 volumio volumio[1296]: info: Prefetching next song Jul 21 19:36:05 volumio ntpd[1107]: Soliciting pool server 162.159.200.1 Jul 21 19:36:05 volumio ntpd[1107]: Soliciting pool server 174.142.148.226 Jul 21 19:36:05 volumio ntpd[1107]: Soliciting pool server 216.58.108.188 Jul 21 19:36:06 volumio ntpd[1107]: Soliciting pool server 23.130.139.90 Jul 21 19:36:06 volumio ntpd[1107]: Soliciting pool server 142.4.192.253 Jul 21 19:36:06 volumio ntpd[1107]: Soliciting pool server 162.159.200.123 Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 21 19:36:07 volumio volumio[1296]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 21 19:36:07 volumio volumio[1296]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 19:36:07 volumio volumio[1296]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 21 19:36:07 volumio volumio[1296]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 21 19:36:07 volumio volumio[1296]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 8 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 8 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 8 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 3 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 2 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 1 milliseconds Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 17ms Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 17ms Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 17ms Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 14ms Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 11ms Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 11ms Jul 21 19:36:07 volumio volumio[1296]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 19:36:07 volumio volumio[1296]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:07 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:07 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:07 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:07 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:07 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:07 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 8ms Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 7ms Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 7ms Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 5ms Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 4 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 4ms Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 2 milliseconds Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 2ms Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 1 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 8ms Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 7ms Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 5ms Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:07 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:07 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jul 21 19:36:07 volumio volumio[1296]: info: Ignoring MPD Status Update Jul 21 19:36:07 volumio volumio[1296]: info: Jul 21 19:36:07 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::getState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 3ms Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 3 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 3ms Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 2 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 2ms Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand status took 1 milliseconds Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseState Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 21 19:36:07 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ControllerMpd::pushState Jul 21 19:36:07 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jul 21 19:36:07 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:07 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 7ms Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 6ms Jul 21 19:36:07 volumio volumio[1296]: info: ------------------------------ 5ms Jul 21 19:36:07 volumio ntpd[1107]: Soliciting pool server 51.222.111.13 Jul 21 19:36:07 volumio ntpd[1107]: Soliciting pool server 173.183.146.26 Jul 21 19:36:07 volumio ntpd[1107]: Soliciting pool server 167.114.93.151 Jul 21 19:36:08 volumio ntpd[1107]: Soliciting pool server 2606:4700:f1::1 Jul 21 19:36:08 volumio ntpd[1107]: Soliciting pool server 206.108.0.132 Jul 21 19:36:09 volumio ntpd[1107]: Soliciting pool server 216.232.132.95 Jul 21 19:36:10 volumio ntpd[1107]: Soliciting pool server 216.6.2.70 Jul 21 19:36:11 volumio ntpd[1107]: Soliciting pool server 66.49.222.68 Jul 21 19:36:12 volumio ntpd[1107]: receive: Unexpected origin timestamp 0xea4819eb.bd470a2d does not match aorg 0000000000.00000000 from server@208.81.1.244 xmt 0xea4819ec.40006e89 Jul 21 19:36:31 volumio volumio[1296]: info: CoreCommandRouter::volumioPlay Jul 21 19:36:31 volumio volumio[1296]: info: CoreStateMachine::play index undefined Jul 21 19:36:31 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 19:36:31 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:41 volumio volumio[1296]: info: CoreCommandRouter::volumioSeek Jul 21 19:36:41 volumio volumio[1296]: info: CoreStateMachine::seek Jul 21 19:36:41 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:41 volumio volumio[1296]: info: TRACKBLOCK {"service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"eighties","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music124/v4/ce/ce/d2/ceced2e4-9c4e-4f97-015c-3b1c085f21ec/source/600x600bb.jpg","uri":"http://streams.80s80s.de/italohits/mp3-192/volumio","name":"Gazebo - Telephone Mama","duration":"310","artist":"80s80s Italo Disco","album":"","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 21 19:36:41 volumio volumio[1296]: info: CoreStateMachine::startPlaybackTimer Jul 21 19:36:41 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:41 volumio volumio[1296]: info: [1721605001428] [80s80s] seek to 16000 Jul 21 19:36:41 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:41 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:41 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:36:41 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:41 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status play Jul 21 19:36:41 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jul 21 19:36:41 volumio sudo[2561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 21 19:36:41 volumio sudo[2561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:41 volumio systemd[1]: Started peppyspectrum Daemon. Jul 21 19:36:41 volumio sudo[2561]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:41 volumio volumio[1296]: info: peppyspectrum Daemon Started Jul 21 19:36:41 volumio volumio[2563]: No protocol specified Jul 21 19:36:41 volumio volumio[2563]: No protocol specified Jul 21 19:36:41 volumio volumio[2563]: pygame 1.9.4.post1 Jul 21 19:36:41 volumio volumio[2563]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 21 19:36:41 volumio volumio[2563]: Traceback (most recent call last): Jul 21 19:36:41 volumio volumio[2563]: File "spectrum.py", line 715, in Jul 21 19:36:41 volumio volumio[2563]: pm = Spectrum(None, True) Jul 21 19:36:41 volumio volumio[2563]: File "spectrum.py", line 63, in __init__ Jul 21 19:36:41 volumio volumio[2563]: self.init_display() Jul 21 19:36:41 volumio volumio[2563]: File "spectrum.py", line 104, in init_display Jul 21 19:36:41 volumio volumio[2563]: pygame.display.init() Jul 21 19:36:41 volumio volumio[2563]: pygame.error: Unable to open /dev/fb1 Jul 21 19:36:41 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:36:41 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 21 19:36:49 volumio volumio[1296]: info: VolumeController::SetAlsaVolume79 Jul 21 19:36:49 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:49 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:49 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:36:49 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:49 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status play Jul 21 19:36:49 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 19:36:49 volumio volumio[1296]: SPOTIFY: SPOTIFY VOLUME 10 Jul 21 19:36:49 volumio volumio[1296]: SPOTIFY: VOLUMIO VOLUME 79 Jul 21 19:36:49 volumio volumio[1296]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 21 19:36:49 volumio volumio[1296]: info: Setting Spotify Volume from Volumio: 79 Jul 21 19:36:49 volumio sudo[2582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 21 19:36:49 volumio sudo[2582]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:49 volumio systemd[1]: Started peppyspectrum Daemon. Jul 21 19:36:49 volumio sudo[2582]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:49 volumio volumio[1296]: info: peppyspectrum Daemon Started Jul 21 19:36:49 volumio volumio[2584]: No protocol specified Jul 21 19:36:49 volumio volumio[2584]: No protocol specified Jul 21 19:36:49 volumio volumio[2584]: pygame 1.9.4.post1 Jul 21 19:36:49 volumio volumio[2584]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 21 19:36:49 volumio volumio[2584]: Traceback (most recent call last): Jul 21 19:36:49 volumio volumio[2584]: File "spectrum.py", line 715, in Jul 21 19:36:49 volumio volumio[2584]: pm = Spectrum(None, True) Jul 21 19:36:49 volumio volumio[2584]: File "spectrum.py", line 63, in __init__ Jul 21 19:36:49 volumio volumio[2584]: self.init_display() Jul 21 19:36:49 volumio volumio[2584]: File "spectrum.py", line 104, in init_display Jul 21 19:36:49 volumio volumio[2584]: pygame.display.init() Jul 21 19:36:49 volumio volumio[2584]: pygame.error: Unable to open /dev/fb1 Jul 21 19:36:49 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:36:49 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 21 19:36:49 volumio volumio[1296]: info: VolumeController::SetAlsaVolume87 Jul 21 19:36:49 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:49 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:49 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:36:49 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:49 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status play Jul 21 19:36:49 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Jul 21 19:36:49 volumio volumio[1296]: SPOTIFY: SPOTIFY VOLUME 79 Jul 21 19:36:49 volumio volumio[1296]: SPOTIFY: VOLUMIO VOLUME 87 Jul 21 19:36:49 volumio volumio[1296]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 21 19:36:49 volumio volumio[1296]: info: Setting Spotify Volume from Volumio: 87 Jul 21 19:36:49 volumio sudo[2590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 21 19:36:49 volumio sudo[2590]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:49 volumio systemd[1]: Started peppyspectrum Daemon. Jul 21 19:36:49 volumio sudo[2590]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:49 volumio volumio[1296]: info: peppyspectrum Daemon Started Jul 21 19:36:49 volumio volumio[2592]: No protocol specified Jul 21 19:36:49 volumio volumio[2592]: No protocol specified Jul 21 19:36:49 volumio volumio[2592]: pygame 1.9.4.post1 Jul 21 19:36:49 volumio volumio[2592]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 21 19:36:49 volumio volumio[2592]: Traceback (most recent call last): Jul 21 19:36:49 volumio volumio[2592]: File "spectrum.py", line 715, in Jul 21 19:36:49 volumio volumio[2592]: pm = Spectrum(None, True) Jul 21 19:36:49 volumio volumio[2592]: File "spectrum.py", line 63, in __init__ Jul 21 19:36:49 volumio volumio[2592]: self.init_display() Jul 21 19:36:49 volumio volumio[2592]: File "spectrum.py", line 104, in init_display Jul 21 19:36:49 volumio volumio[2592]: pygame.display.init() Jul 21 19:36:49 volumio volumio[2592]: pygame.error: Unable to open /dev/fb1 Jul 21 19:36:49 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:36:49 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 21 19:36:50 volumio volumio[1296]: info: VolumeController::SetAlsaVolume92 Jul 21 19:36:50 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:50 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:50 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:36:50 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:50 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status play Jul 21 19:36:50 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Jul 21 19:36:50 volumio volumio[1296]: SPOTIFY: SPOTIFY VOLUME 87 Jul 21 19:36:50 volumio volumio[1296]: SPOTIFY: VOLUMIO VOLUME 92 Jul 21 19:36:50 volumio volumio[1296]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 21 19:36:50 volumio volumio[1296]: info: Setting Spotify Volume from Volumio: 92 Jul 21 19:36:50 volumio sudo[2601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 21 19:36:50 volumio sudo[2601]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:50 volumio systemd[1]: Started peppyspectrum Daemon. Jul 21 19:36:50 volumio sudo[2601]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:50 volumio volumio[1296]: info: peppyspectrum Daemon Started Jul 21 19:36:50 volumio volumio[2603]: No protocol specified Jul 21 19:36:50 volumio volumio[2603]: No protocol specified Jul 21 19:36:50 volumio volumio[2603]: pygame 1.9.4.post1 Jul 21 19:36:50 volumio volumio[2603]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 21 19:36:50 volumio volumio[2603]: Traceback (most recent call last): Jul 21 19:36:50 volumio volumio[2603]: File "spectrum.py", line 715, in Jul 21 19:36:50 volumio volumio[2603]: pm = Spectrum(None, True) Jul 21 19:36:50 volumio volumio[2603]: File "spectrum.py", line 63, in __init__ Jul 21 19:36:50 volumio volumio[2603]: self.init_display() Jul 21 19:36:50 volumio volumio[2603]: File "spectrum.py", line 104, in init_display Jul 21 19:36:50 volumio volumio[2603]: pygame.display.init() Jul 21 19:36:50 volumio volumio[2603]: pygame.error: Unable to open /dev/fb1 Jul 21 19:36:50 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:36:50 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 21 19:36:52 volumio volumio[1296]: SPOTIFY: SETTING SPOTIFY VOLUME 92 Jul 21 19:36:52 volumio volumio[1296]: info: Sending Spotify command with payload to local API: /player/volume Jul 21 19:36:52 volumio volumio[1296]: info: VolumeController::SetAlsaVolume95 Jul 21 19:36:52 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:52 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:52 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:36:52 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:52 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status play Jul 21 19:36:52 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Jul 21 19:36:52 volumio volumio[1296]: SPOTIFY: SPOTIFY VOLUME 92 Jul 21 19:36:52 volumio volumio[1296]: SPOTIFY: VOLUMIO VOLUME 95 Jul 21 19:36:52 volumio volumio[1296]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 21 19:36:52 volumio volumio[1296]: info: Setting Spotify Volume from Volumio: 95 Jul 21 19:36:52 volumio sudo[2609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 21 19:36:52 volumio sudo[2609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:52 volumio systemd[1]: Started peppyspectrum Daemon. Jul 21 19:36:52 volumio sudo[2609]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:52 volumio volumio[1296]: info: peppyspectrum Daemon Started Jul 21 19:36:52 volumio volumio[2611]: No protocol specified Jul 21 19:36:52 volumio volumio[2611]: No protocol specified Jul 21 19:36:52 volumio volumio[2611]: pygame 1.9.4.post1 Jul 21 19:36:52 volumio volumio[2611]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 21 19:36:52 volumio volumio[2611]: Traceback (most recent call last): Jul 21 19:36:52 volumio volumio[2611]: File "spectrum.py", line 715, in Jul 21 19:36:52 volumio volumio[2611]: pm = Spectrum(None, True) Jul 21 19:36:52 volumio volumio[2611]: File "spectrum.py", line 63, in __init__ Jul 21 19:36:52 volumio volumio[2611]: self.init_display() Jul 21 19:36:52 volumio volumio[2611]: File "spectrum.py", line 104, in init_display Jul 21 19:36:52 volumio volumio[2611]: pygame.display.init() Jul 21 19:36:52 volumio volumio[2611]: pygame.error: Unable to open /dev/fb1 Jul 21 19:36:52 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:36:52 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 21 19:36:53 volumio volumio[1296]: SPOTIFY: SETTING SPOTIFY VOLUME 95 Jul 21 19:36:53 volumio volumio[1296]: info: Sending Spotify command with payload to local API: /player/volume Jul 21 19:36:54 volumio volumio[1296]: info: VolumeController::SetAlsaVolume50 Jul 21 19:36:54 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:54 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:54 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:36:54 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:54 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status play Jul 21 19:36:54 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 21 19:36:54 volumio volumio[1296]: SPOTIFY: SPOTIFY VOLUME 95 Jul 21 19:36:54 volumio volumio[1296]: SPOTIFY: VOLUMIO VOLUME 50 Jul 21 19:36:54 volumio volumio[1296]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 21 19:36:54 volumio volumio[1296]: info: Setting Spotify Volume from Volumio: 50 Jul 21 19:36:54 volumio sudo[2621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 21 19:36:54 volumio sudo[2621]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:54 volumio systemd[1]: Started peppyspectrum Daemon. Jul 21 19:36:54 volumio sudo[2621]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:54 volumio volumio[1296]: info: peppyspectrum Daemon Started Jul 21 19:36:54 volumio volumio[2623]: No protocol specified Jul 21 19:36:54 volumio volumio[2623]: No protocol specified Jul 21 19:36:54 volumio volumio[2623]: pygame 1.9.4.post1 Jul 21 19:36:54 volumio volumio[2623]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 21 19:36:54 volumio volumio[2623]: Traceback (most recent call last): Jul 21 19:36:54 volumio volumio[2623]: File "spectrum.py", line 715, in Jul 21 19:36:54 volumio volumio[2623]: pm = Spectrum(None, True) Jul 21 19:36:54 volumio volumio[2623]: File "spectrum.py", line 63, in __init__ Jul 21 19:36:54 volumio volumio[2623]: self.init_display() Jul 21 19:36:54 volumio volumio[2623]: File "spectrum.py", line 104, in init_display Jul 21 19:36:54 volumio volumio[2623]: pygame.display.init() Jul 21 19:36:54 volumio volumio[2623]: pygame.error: Unable to open /dev/fb1 Jul 21 19:36:54 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:36:54 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 21 19:36:55 volumio volumio[1296]: info: VolumeController::SetAlsaVolume31 Jul 21 19:36:55 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:55 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:55 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:36:55 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:55 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status play Jul 21 19:36:55 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jul 21 19:36:55 volumio volumio[1296]: SPOTIFY: SPOTIFY VOLUME 50 Jul 21 19:36:55 volumio volumio[1296]: SPOTIFY: VOLUMIO VOLUME 31 Jul 21 19:36:55 volumio volumio[1296]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 21 19:36:55 volumio volumio[1296]: info: Setting Spotify Volume from Volumio: 31 Jul 21 19:36:55 volumio sudo[2629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 21 19:36:55 volumio sudo[2629]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:55 volumio systemd[1]: Started peppyspectrum Daemon. Jul 21 19:36:55 volumio sudo[2629]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:55 volumio volumio[1296]: info: peppyspectrum Daemon Started Jul 21 19:36:55 volumio volumio[2631]: No protocol specified Jul 21 19:36:55 volumio volumio[2631]: No protocol specified Jul 21 19:36:55 volumio volumio[2631]: pygame 1.9.4.post1 Jul 21 19:36:55 volumio volumio[2631]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 21 19:36:55 volumio volumio[2631]: Traceback (most recent call last): Jul 21 19:36:55 volumio volumio[2631]: File "spectrum.py", line 715, in Jul 21 19:36:55 volumio volumio[2631]: pm = Spectrum(None, True) Jul 21 19:36:55 volumio volumio[2631]: File "spectrum.py", line 63, in __init__ Jul 21 19:36:55 volumio volumio[2631]: self.init_display() Jul 21 19:36:55 volumio volumio[2631]: File "spectrum.py", line 104, in init_display Jul 21 19:36:55 volumio volumio[2631]: pygame.display.init() Jul 21 19:36:55 volumio volumio[2631]: pygame.error: Unable to open /dev/fb1 Jul 21 19:36:55 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:36:55 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 21 19:36:56 volumio volumio[1296]: info: VolumeController::SetAlsaVolume20 Jul 21 19:36:56 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:36:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:36:56 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:36:56 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:36:56 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status play Jul 21 19:36:56 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 21 19:36:56 volumio volumio[1296]: SPOTIFY: SPOTIFY VOLUME 31 Jul 21 19:36:56 volumio volumio[1296]: SPOTIFY: VOLUMIO VOLUME 20 Jul 21 19:36:56 volumio volumio[1296]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 21 19:36:56 volumio volumio[1296]: info: Setting Spotify Volume from Volumio: 20 Jul 21 19:36:56 volumio sudo[2637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 21 19:36:56 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:36:56 volumio systemd[1]: Started peppyspectrum Daemon. Jul 21 19:36:56 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root Jul 21 19:36:56 volumio volumio[1296]: info: peppyspectrum Daemon Started Jul 21 19:36:56 volumio volumio[2639]: No protocol specified Jul 21 19:36:56 volumio volumio[2639]: No protocol specified Jul 21 19:36:56 volumio volumio[2639]: pygame 1.9.4.post1 Jul 21 19:36:56 volumio volumio[2639]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 21 19:36:56 volumio volumio[2639]: Traceback (most recent call last): Jul 21 19:36:56 volumio volumio[2639]: File "spectrum.py", line 715, in Jul 21 19:36:56 volumio volumio[2639]: pm = Spectrum(None, True) Jul 21 19:36:56 volumio volumio[2639]: File "spectrum.py", line 63, in __init__ Jul 21 19:36:56 volumio volumio[2639]: self.init_display() Jul 21 19:36:56 volumio volumio[2639]: File "spectrum.py", line 104, in init_display Jul 21 19:36:56 volumio volumio[2639]: pygame.display.init() Jul 21 19:36:56 volumio volumio[2639]: pygame.error: Unable to open /dev/fb1 Jul 21 19:36:56 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:36:56 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 21 19:36:58 volumio volumio[1296]: SPOTIFY: SETTING SPOTIFY VOLUME 20 Jul 21 19:36:58 volumio volumio[1296]: info: Sending Spotify command with payload to local API: /player/volume Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 21 19:36:59 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 21 19:37:16 volumio volumio[1296]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 21 19:37:16 volumio sudo[2731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 21 19:37:16 volumio sudo[2731]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:16 volumio sudo[2731]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:16 volumio volumio[1296]: info: MPD Permissions set Jul 21 19:37:16 volumio sudo[2734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 21 19:37:16 volumio sudo[2734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:16 volumio systemd[1]: Stopping Music Player Daemon... Jul 21 19:37:16 volumio systemd[1]: mpd.service: Succeeded. Jul 21 19:37:16 volumio systemd[1]: Stopped Music Player Daemon. Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 19:37:16 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:16 volumio systemd[1]: Starting Music Player Daemon... Jul 21 19:37:16 volumio volumio[1296]: info: camilladsp spawned new process with pid 2741, instance 1, run: true Jul 21 19:37:16 volumio sudo[2740]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 21 19:37:16 volumio sudo[2740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:16 volumio sudo[2740]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:16 volumio mpd[2749]: Jul 21 19:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 21 19:37:16 volumio systemd[1]: Started Music Player Daemon. Jul 21 19:37:16 volumio sudo[2734]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:16 volumio volumio[1296]: error: updateQueue error: null Jul 21 19:37:17 volumio volumio[1296]: error: Upnp client error: Error: This socket has been ended by the other party Jul 21 19:37:18 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:18 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:18 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:33 volumio volumio[1296]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 21 19:37:33 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 21 19:37:33 volumio volumio[1296]: info: CoreCommandRouter::volumioGetState Jul 21 19:37:33 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:37:33 volumio volumio[1296]: info: Restoring Previous Volume level: 100 false false Jul 21 19:37:33 volumio volumio[1296]: info: Disable softmixer device for audio device Jul 21 19:37:33 volumio volumio[1296]: info: Output device has changed, restarting MPD Jul 21 19:37:33 volumio sudo[2784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 21 19:37:33 volumio sudo[2784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:33 volumio sudo[2784]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:33 volumio volumio[1296]: info: Output device has changed, restarting Shairport Sync Jul 21 19:37:33 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:33 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 19:37:33 volumio sudo[2787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 21 19:37:33 volumio sudo[2787]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:33 volumio systemd[1]: Stopping Music Player Daemon... Jul 21 19:37:33 volumio systemd[1]: mpd.service: Succeeded. Jul 21 19:37:33 volumio systemd[1]: Stopped Music Player Daemon. Jul 21 19:37:33 volumio volumio[1296]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 21 19:37:33 volumio volumio[1296]: info: Volume configurations have been set Jul 21 19:37:34 volumio systemd[1]: Starting Music Player Daemon... Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 21 19:37:34 volumio volumio[1296]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 21 19:37:34 volumio volumio[1296]: info: Disabling external Volume Control Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 19:37:34 volumio sudo[2792]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 21 19:37:34 volumio sudo[2792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:34 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:37:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:37:34 volumio sudo[2792]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:34 volumio volumio[1296]: info: Soft Volume ALSA configuration file deleted Jul 21 19:37:34 volumio volumio[1296]: info: Preparing to generate the ALSA configuration file Jul 21 19:37:34 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status play Jul 21 19:37:34 volumio volumio[1296]: info: MPD Permissions set Jul 21 19:37:34 volumio volumio[1296]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 21 19:37:34 volumio volumio[1296]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jul 21 19:37:34 volumio volumio[1296]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 21 19:37:34 volumio volumio[1296]: info: Reading ALSA contributions from plugins. Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio sudo[2799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 21 19:37:34 volumio sudo[2799]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio systemd[1]: Started peppyspectrum Daemon. Jul 21 19:37:34 volumio sudo[2799]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 21 19:37:34 volumio volumio[1296]: info: peppyspectrum Daemon Started Jul 21 19:37:34 volumio volumio[1296]: info: Starting Shairport Sync Jul 21 19:37:34 volumio sudo[2812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 21 19:37:34 volumio sudo[2812]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 21 19:37:34 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 21 19:37:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 21 19:37:34 volumio volumio[1296]: info: Asound.conf file written Jul 21 19:37:34 volumio sudo[2816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 21 19:37:34 volumio sudo[2816]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 21 19:37:34 volumio sudo[2816]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:34 volumio sudo[2812]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:34 volumio volumio[1296]: info: Output device has changed, restarting MPD Jul 21 19:37:34 volumio sudo[2824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 21 19:37:34 volumio sudo[2824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:34 volumio sudo[2824]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:34 volumio volumio[1296]: info: Output device has changed, restarting Shairport Sync Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 19:37:34 volumio sudo[2826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 21 19:37:34 volumio sudo[2826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:34 volumio volumio[2805]: No protocol specified Jul 21 19:37:34 volumio volumio[2805]: No protocol specified Jul 21 19:37:34 volumio volumio[2805]: pygame 1.9.4.post1 Jul 21 19:37:34 volumio volumio[2805]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 21 19:37:34 volumio volumio[2805]: Traceback (most recent call last): Jul 21 19:37:34 volumio volumio[2805]: File "spectrum.py", line 715, in Jul 21 19:37:34 volumio volumio[2805]: pm = Spectrum(None, True) Jul 21 19:37:34 volumio volumio[2805]: File "spectrum.py", line 63, in __init__ Jul 21 19:37:34 volumio volumio[2805]: self.init_display() Jul 21 19:37:34 volumio volumio[2805]: File "spectrum.py", line 104, in init_display Jul 21 19:37:34 volumio volumio[2805]: pygame.display.init() Jul 21 19:37:34 volumio volumio[2805]: pygame.error: Unable to open /dev/fb1 Jul 21 19:37:34 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 21 19:37:34 volumio systemd[1]: mpd.service: Succeeded. Jul 21 19:37:34 volumio systemd[1]: Stopped Music Player Daemon. Jul 21 19:37:34 volumio volumio[1296]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 21 19:37:34 volumio volumio[1296]: info: MPD Permissions set Jul 21 19:37:34 volumio volumio[1296]: info: Shairport-Sync Started Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio systemd[1]: Starting Music Player Daemon... Jul 21 19:37:34 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:37:34 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:34 volumio volumio[1296]: info: Starting Shairport Sync Jul 21 19:37:34 volumio sudo[2837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 21 19:37:34 volumio sudo[2837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:34 volumio sudo[2831]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 21 19:37:34 volumio sudo[2831]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:34 volumio sudo[2831]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 21 19:37:34 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 21 19:37:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 21 19:37:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 21 19:37:34 volumio sudo[2837]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:34 volumio volumio[1296]: info: Shairport-Sync Started Jul 21 19:37:34 volumio volumio[1296]: error: Upnp client error: Error: This socket has been ended by the other party Jul 21 19:37:34 volumio mpd[2840]: Jul 21 19:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 21 19:37:34 volumio systemd[1]: Started Music Player Daemon. Jul 21 19:37:34 volumio sudo[2826]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:34 volumio sudo[2787]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:34 volumio volumio[1296]: error: MPD error: The expression evaluated to a falsy value: Jul 21 19:37:34 volumio volumio[1296]: assert.ok(self.idling) Jul 21 19:37:34 volumio volumio[1296]: error: The expression evaluated to a falsy value: Jul 21 19:37:34 volumio volumio[1296]: assert.ok(self.idling) Jul 21 19:37:34 volumio volumio[1296]: error: updateQueue error: null Jul 21 19:37:35 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 19:37:35 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 19:37:35 volumio volumio[1296]: info: [squeezelite_mc] Restarting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"0","mixerType":"None","mixer":null} Jul 21 19:37:35 volumio volumio[1296]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jul 21 19:37:35 volumio sudo[2851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jul 21 19:37:35 volumio sudo[2851]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:35 volumio sudo[2851]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:35 volumio volumio[1296]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Jul 21 19:37:35 volumio sudo[2855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Jul 21 19:37:35 volumio sudo[2855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:35 volumio sudo[2855]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:35 volumio volumio[1296]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jul 21 19:37:35 volumio sudo[2859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jul 21 19:37:35 volumio sudo[2859]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:35 volumio sudo[2859]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:35 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Jul 21 19:37:35 volumio sudo[2862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 21 19:37:35 volumio sudo[2862]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:36 volumio systemd[1]: Reloading. Jul 21 19:37:36 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 21 19:37:36 volumio systemd[1]: squeezelite.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jul 21 19:37:36 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Jul 21 19:37:36 volumio sudo[2862]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:36 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 21 19:37:36 volumio sudo[2878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 21 19:37:36 volumio sudo[2878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:36 volumio sudo[2878]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:36 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Jul 21 19:37:36 volumio sudo[2881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Jul 21 19:37:36 volumio sudo[2881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:36 volumio systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Jul 21 19:37:36 volumio systemd[1]: squeezelite.service: Succeeded. Jul 21 19:37:36 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Jul 21 19:37:36 volumio sudo[2881]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:36 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 21 19:37:36 volumio sudo[2887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 21 19:37:36 volumio sudo[2887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:36 volumio sudo[2887]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:36 volumio volumio[1296]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Jul 21 19:37:36 volumio sudo[2891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Jul 21 19:37:36 volumio sudo[2891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:36 volumio sudo[2891]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:36 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Jul 21 19:37:36 volumio sudo[2894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Jul 21 19:37:36 volumio sudo[2894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:36 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jul 21 19:37:36 volumio sudo[2894]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:36 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jul 21 19:37:37 volumio volumio[1296]: info: camilladsp spawned new process with pid 2900, instance 1, run: true Jul 21 19:37:37 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 21 19:37:37 volumio sudo[2909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 21 19:37:37 volumio sudo[2909]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:37 volumio sudo[2909]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:37 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 21 19:37:38 volumio sudo[2914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 21 19:37:38 volumio sudo[2914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:38 volumio sudo[2914]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:38 volumio volumio[1296]: info: VolumeController::SetAlsaVolume100 Jul 21 19:37:38 volumio volumio[1296]: info: CoreStateMachine::pushState Jul 21 19:37:38 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jul 21 19:37:38 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 19:37:38 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jul 21 19:37:38 volumio volumio[1296]: info: PeppySpectrum ---peppyspectrum status play Jul 21 19:37:38 volumio sudo[2917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 21 19:37:38 volumio sudo[2917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:38 volumio systemd[1]: Started peppyspectrum Daemon. Jul 21 19:37:38 volumio sudo[2917]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:38 volumio volumio[1296]: info: peppyspectrum Daemon Started Jul 21 19:37:38 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 21 19:37:38 volumio sudo[2922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 21 19:37:38 volumio sudo[2922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:38 volumio sudo[2922]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:38 volumio volumio[2919]: No protocol specified Jul 21 19:37:38 volumio volumio[2919]: No protocol specified Jul 21 19:37:38 volumio volumio[2919]: pygame 1.9.4.post1 Jul 21 19:37:38 volumio volumio[2919]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 21 19:37:38 volumio volumio[2919]: Traceback (most recent call last): Jul 21 19:37:38 volumio volumio[2919]: File "spectrum.py", line 715, in Jul 21 19:37:38 volumio volumio[2919]: pm = Spectrum(None, True) Jul 21 19:37:38 volumio volumio[2919]: File "spectrum.py", line 63, in __init__ Jul 21 19:37:38 volumio volumio[2919]: self.init_display() Jul 21 19:37:38 volumio volumio[2919]: File "spectrum.py", line 104, in init_display Jul 21 19:37:38 volumio volumio[2919]: pygame.display.init() Jul 21 19:37:38 volumio volumio[2919]: pygame.error: Unable to open /dev/fb1 Jul 21 19:37:38 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:37:38 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 21 19:37:39 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 21 19:37:39 volumio sudo[2927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 21 19:37:39 volumio sudo[2927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:39 volumio sudo[2927]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:39 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 21 19:37:39 volumio sudo[2930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 21 19:37:39 volumio sudo[2930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:39 volumio sudo[2930]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:39 volumio volumio[1296]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 19:37:39 volumio volumio[1296]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 21 19:37:39 volumio sudo[2933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 21 19:37:39 volumio sudo[2933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 19:37:39 volumio sudo[2933]: pam_unix(sudo:session): session closed for user root Jul 21 19:37:58 volumio volumio[1296]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jul 21 19:37:58 volumio volumio[1296]: info: PLUGIN onReboot : networkfs Jul 21 19:37:58 volumio volumio[1296]: info: PLUGIN onReboot : audiophonicsonoff Jul 21 19:37:58 volumio volumio[1296]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 19:37:58 volumio volumio[1296]: TypeError: Cannot read property 'writeSync' of undefined Jul 21 19:37:58 volumio volumio[1296]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Jul 21 19:37:58 volumio volumio[1296]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jul 21 19:37:58 volumio volumio[1296]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jul 21 19:37:58 volumio volumio[1296]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 21 19:37:58 volumio volumio[1296]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 21 19:37:58 volumio volumio[1296]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jul 21 19:37:58 volumio volumio[1296]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Jul 21 19:37:58 volumio volumio[1296]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:857:33) Jul 21 19:37:58 volumio volumio[1296]: at Socket.emit (events.js:315:20) Jul 21 19:37:58 volumio volumio[1296]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 21 19:37:58 volumio volumio[1296]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 21 19:37:58 volumio volumio[1296]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 19:37:58 volumio sudo[3028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-21 19:36 Jul 21 19:37:58 volumio sudo[3028]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"