-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Fri 2024-10-11 15:20:28 BST. -- Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::pause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::stPlaybackTimer Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::servicePause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: ControllerMpd::pause Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand pause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: Oct 11 15:19:00 pi5k6.6 volumio[1321]: ---------------------------- MPD announces state update: player Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: sendMpdCommand pause took 2 milliseconds Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: ControllerMpd::getState Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: sendMpdCommand status took 1 milliseconds Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseState Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: Oct 11 15:19:00 pi5k6.6 volumio[1321]: ---------------------------- MPD announces state update: player Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: ControllerMpd::getState Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: Oct 11 15:19:00 pi5k6.6 volumio[1321]: ---------------------------- MPD announces state update: player Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: ControllerMpd::getState Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: sendMpdCommand status took 1 milliseconds Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: sendMpdCommand status took 0 milliseconds Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseState Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseState Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: ControllerMpd::pushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5668,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song of the Lonely Mountain","artist":"Peter Hollens","album":"Misty Mountains: Songs Inspired by The Hobbit and Lord of the Rings","uri":"INTERNAL/Peter Hollens/Misty Mountains- Songs Inspired by The Hobbit and Lord of the Rings/02 - Song of the Lonely Mountain.mp3","trackType":"mp3"} Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: CURRENT POSITION 0 Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState stateService pause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState currentStatus pause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::stPlaybackTimer Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: ------------------------------ 10ms Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: ControllerMpd::pushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5668,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song of the Lonely Mountain","artist":"Peter Hollens","album":"Misty Mountains: Songs Inspired by The Hobbit and Lord of the Rings","uri":"INTERNAL/Peter Hollens/Misty Mountains- Songs Inspired by The Hobbit and Lord of the Rings/02 - Song of the Lonely Mountain.mp3","trackType":"mp3"} Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: CURRENT POSITION 0 Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState stateService pause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState currentStatus pause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::stPlaybackTimer Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: ControllerMpd::pushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5668,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song of the Lonely Mountain","artist":"Peter Hollens","album":"Misty Mountains: Songs Inspired by The Hobbit and Lord of the Rings","uri":"INTERNAL/Peter Hollens/Misty Mountains- Songs Inspired by The Hobbit and Lord of the Rings/02 - Song of the Lonely Mountain.mp3","trackType":"mp3"} Oct 11 15:19:00 pi5k6.6 volumio[1321]: verbose: CURRENT POSITION 0 Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState stateService pause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState currentStatus pause Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: CoreStateMachine::stPlaybackTimer Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: ------------------------------ 23ms Oct 11 15:19:00 pi5k6.6 volumio[1321]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 11 15:19:04 pi5k6.6 ntpd[1193]: Soliciting pool server 178.62.68.79 Oct 11 15:19:05 pi5k6.6 ntpd[1193]: Soliciting pool server 195.171.43.10 Oct 11 15:19:05 pi5k6.6 ntpd[1193]: Soliciting pool server 185.103.119.60 Oct 11 15:19:05 pi5k6.6 ntpd[1193]: Soliciting pool server 213.210.39.123 Oct 11 15:19:05 pi5k6.6 ntpd[1193]: Soliciting pool server 194.164.96.170 Oct 11 15:19:06 pi5k6.6 ntpd[1193]: Soliciting pool server 162.159.200.1 Oct 11 15:19:06 pi5k6.6 ntpd[1193]: Soliciting pool server 162.159.200.1 Oct 11 15:19:06 pi5k6.6 ntpd[1193]: Soliciting pool server 178.215.228.24 Oct 11 15:19:06 pi5k6.6 ntpd[1193]: Soliciting pool server 185.83.169.27 Oct 11 15:19:07 pi5k6.6 ntpd[1193]: Soliciting pool server 131.111.8.63 Oct 11 15:19:07 pi5k6.6 ntpd[1193]: Soliciting pool server 85.199.214.99 Oct 11 15:19:07 pi5k6.6 ntpd[1193]: Soliciting pool server 93.93.131.118 Oct 11 15:19:08 pi5k6.6 ntpd[1193]: Soliciting pool server 94.237.52.57 Oct 11 15:19:08 pi5k6.6 ntpd[1193]: Soliciting pool server 85.199.214.101 Oct 11 15:19:08 pi5k6.6 ntpd[1193]: Soliciting pool server 64:ff9b::c139:9032 Oct 11 15:19:09 pi5k6.6 ntpd[1193]: Soliciting pool server 64:ff9b::5eed:3439 Oct 11 15:19:09 pi5k6.6 ntpd[1193]: Soliciting pool server 2a0f:85c0::50 Oct 11 15:19:34 pi5k6.6 ntpd[1193]: receive: Unexpected origin timestamp 0xeab3b25f.941fb4ca does not match aorg 0000000000.00000000 from server@93.93.131.118 xmt 0xeab3b276.4870e351 Oct 11 15:19:34 pi5k6.6 ntpd[1193]: receive: Unexpected origin timestamp 0xeab3b25f.9421b08b does not match aorg 0000000000.00000000 from server@185.103.119.60 xmt 0xeab3b276.489c935e Oct 11 15:19:34 pi5k6.6 ntpd[1193]: receive: Unexpected origin timestamp 0xeab3b25f.941d9368 does not match aorg 0000000000.00000000 from server@131.111.8.63 xmt 0xeab3b276.48fd5ac6 Oct 11 15:19:34 pi5k6.6 ntpd[1193]: receive: Unexpected origin timestamp 0xeab3b25f.942064cc does not match aorg 0000000000.00000000 from server@185.83.169.27 xmt 0xeab3b276.4920374d Oct 11 15:19:34 pi5k6.6 ntpd[1193]: receive: Unexpected origin timestamp 0xeab3b25f.94223dca does not match aorg 0000000000.00000000 from server@195.171.43.10 xmt 0xeab3b276.495eff9d Oct 11 15:19:34 pi5k6.6 ntpd[1193]: receive: Unexpected origin timestamp 0xeab3b25f.9420f38b does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xeab3b276.49cb2cb6 Oct 11 15:19:35 pi5k6.6 volumio[1321]: info: VolumeController::SetAlsaVolume0 Oct 11 15:19:35 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:35 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:35 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:19:35 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:35 pi5k6.6 volumio[1321]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPlay Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::play index undefined Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::startPlaybackTimer Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: ControllerMpd::resume Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand play Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: sendMpdCommand play took 7 milliseconds Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: Oct 11 15:19:37 pi5k6.6 volumio[1321]: ---------------------------- MPD announces state update: player Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: ControllerMpd::getState Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: Oct 11 15:19:37 pi5k6.6 volumio[1321]: ---------------------------- MPD announces state update: player Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: ControllerMpd::getState Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: Oct 11 15:19:37 pi5k6.6 volumio[1321]: ---------------------------- MPD announces state update: player Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: ControllerMpd::getState Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: sendMpdCommand status took 2 milliseconds Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: sendMpdCommand status took 1 milliseconds Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: sendMpdCommand status took 1 milliseconds Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseState Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseState Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseState Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: ControllerMpd::pushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6560,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song of the Lonely Mountain","artist":"Peter Hollens","album":"Misty Mountains: Songs Inspired by The Hobbit and Lord of the Rings","uri":"INTERNAL/Peter Hollens/Misty Mountains- Songs Inspired by The Hobbit and Lord of the Rings/02 - Song of the Lonely Mountain.mp3","trackType":"mp3"} Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: CURRENT POSITION 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState stateService play Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState currentStatus pause Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: ControllerMpd::pushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6560,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song of the Lonely Mountain","artist":"Peter Hollens","album":"Misty Mountains: Songs Inspired by The Hobbit and Lord of the Rings","uri":"INTERNAL/Peter Hollens/Misty Mountains- Songs Inspired by The Hobbit and Lord of the Rings/02 - Song of the Lonely Mountain.mp3","trackType":"mp3"} Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: CURRENT POSITION 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState stateService play Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: Received an update from plugin. extracting info from payload Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: ControllerMpd::pushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6560,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song of the Lonely Mountain","artist":"Peter Hollens","album":"Misty Mountains: Songs Inspired by The Hobbit and Lord of the Rings","uri":"INTERNAL/Peter Hollens/Misty Mountains- Songs Inspired by The Hobbit and Lord of the Rings/02 - Song of the Lonely Mountain.mp3","trackType":"mp3"} Oct 11 15:19:37 pi5k6.6 volumio[1321]: verbose: CURRENT POSITION 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState stateService play Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: Received an update from plugin. extracting info from payload Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: ------------------------------ 25ms Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: ------------------------------ 24ms Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: ------------------------------ 24ms Oct 11 15:19:37 pi5k6.6 volumio[1321]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::pause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::stPlaybackTimer Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::servicePause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: ControllerMpd::pause Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand pause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: Oct 11 15:19:38 pi5k6.6 volumio[1321]: ---------------------------- MPD announces state update: player Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: sendMpdCommand pause took 2 milliseconds Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: ControllerMpd::getState Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: sendMpdCommand status took 0 milliseconds Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseState Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: Oct 11 15:19:38 pi5k6.6 volumio[1321]: ---------------------------- MPD announces state update: player Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: ControllerMpd::getState Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: Oct 11 15:19:38 pi5k6.6 volumio[1321]: ---------------------------- MPD announces state update: player Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: ControllerMpd::getState Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: sendMpdCommand status took 1 milliseconds Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: sendMpdCommand status took 1 milliseconds Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseState Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseState Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: ControllerMpd::pushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":7257,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song of the Lonely Mountain","artist":"Peter Hollens","album":"Misty Mountains: Songs Inspired by The Hobbit and Lord of the Rings","uri":"INTERNAL/Peter Hollens/Misty Mountains- Songs Inspired by The Hobbit and Lord of the Rings/02 - Song of the Lonely Mountain.mp3","trackType":"mp3"} Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: CURRENT POSITION 0 Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState stateService pause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState currentStatus pause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::stPlaybackTimer Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: ------------------------------ 10ms Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: ControllerMpd::pushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":7257,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song of the Lonely Mountain","artist":"Peter Hollens","album":"Misty Mountains: Songs Inspired by The Hobbit and Lord of the Rings","uri":"INTERNAL/Peter Hollens/Misty Mountains- Songs Inspired by The Hobbit and Lord of the Rings/02 - Song of the Lonely Mountain.mp3","trackType":"mp3"} Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: CURRENT POSITION 0 Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState stateService pause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState currentStatus pause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::stPlaybackTimer Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: ControllerMpd::pushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreCommandRouter::servicePushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":7257,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song of the Lonely Mountain","artist":"Peter Hollens","album":"Misty Mountains: Songs Inspired by The Hobbit and Lord of the Rings","uri":"INTERNAL/Peter Hollens/Misty Mountains- Songs Inspired by The Hobbit and Lord of the Rings/02 - Song of the Lonely Mountain.mp3","trackType":"mp3"} Oct 11 15:19:38 pi5k6.6 volumio[1321]: verbose: CURRENT POSITION 0 Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState stateService pause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::syncState currentStatus pause Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: CoreStateMachine::stPlaybackTimer Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: ------------------------------ 21ms Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: ------------------------------ 21ms Oct 11 15:19:38 pi5k6.6 volumio[1321]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Preparing to save Alsa Options, stopping services first Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioGetState Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPause Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreStateMachine::pause Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Hifiberry DAC"},"i2s":true,"i2sid":{"value":"i2s-dac","label":"R-PI DAC"}} Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Enabling I2S DAC: R-PI DAC Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 sudo[2341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Oct 11 15:20:23 pi5k6.6 sudo[2341]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 15:20:23 pi5k6.6 sudo[2341]: pam_unix(sudo:session): session closed for user root Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: No Overlays Loaded Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 sudo[2344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay i2s-dac Oct 11 15:20:23 pi5k6.6 sudo[2344]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 15:20:23 pi5k6.6 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2s@a0000/status Oct 11 15:20:23 pi5k6.6 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Oct 11 15:20:23 pi5k6.6 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Oct 11 15:20:23 pi5k6.6 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Oct 11 15:20:23 pi5k6.6 volumio[1321]: warn: Unable to locate the device Hifiberry DAC a reboot may be required. Oct 11 15:20:23 pi5k6.6 sudo[2344]: pam_unix(sudo:session): session closed for user root Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Found match in i2s Card Database: setting mixer Digital for card R-PI DAC Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Disabling external Volume Control Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Enable softmixer device for audio device undefined Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: I2S Param [object Object] successfully enabled Oct 11 15:20:23 pi5k6.6 volumio[1321]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Software Volume ALSA configuration written Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Preparing to generate the ALSA configuration file Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Getting Alsa Cards List without I2S DAC Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Reading ALSA contributions from plugins. Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: VolumeController:: Volume=undefined Mute =false Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Asound.conf file written Oct 11 15:20:23 pi5k6.6 sudo[2371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 11 15:20:23 pi5k6.6 sudo[2371]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 15:20:23 pi5k6.6 sudo[2371]: pam_unix(sudo:session): session closed for user root Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Output device has changed, restarting MPD Oct 11 15:20:23 pi5k6.6 sudo[2377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 15:20:23 pi5k6.6 sudo[2377]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 15:20:23 pi5k6.6 sudo[2377]: pam_unix(sudo:session): session closed for user root Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Output device has changed, restarting Shairport Sync Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 15:20:23 pi5k6.6 sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 15:20:23 pi5k6.6 systemd[1]: Stopping Music Player Daemon... Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Output device has changed, restarting MPD Oct 11 15:20:23 pi5k6.6 systemd[1]: mpd.service: Succeeded. Oct 11 15:20:23 pi5k6.6 systemd[1]: Stopped Music Player Daemon. Oct 11 15:20:23 pi5k6.6 sudo[2387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 15:20:23 pi5k6.6 sudo[2387]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 15:20:23 pi5k6.6 systemd[1]: Starting Music Player Daemon... Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Output device has changed, restarting Shairport Sync Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: MPD Permissions set Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 sudo[2387]: pam_unix(sudo:session): session closed for user root Oct 11 15:20:23 pi5k6.6 sudo[2390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 15:20:23 pi5k6.6 sudo[2390]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 15:20:23 pi5k6.6 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 11 15:20:23 pi5k6.6 systemd[1]: mpd.service: Succeeded. Oct 11 15:20:23 pi5k6.6 systemd[1]: Stopped Music Player Daemon. Oct 11 15:20:23 pi5k6.6 systemd[1]: Starting Music Player Daemon... Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Starting Shairport Sync Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Starting Shairport Sync Oct 11 15:20:23 pi5k6.6 sudo[2405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 15:20:23 pi5k6.6 sudo[2399]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 15:20:23 pi5k6.6 sudo[2399]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 15:20:23 pi5k6.6 sudo[2405]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 15:20:23 pi5k6.6 sudo[2399]: pam_unix(sudo:session): session closed for user root Oct 11 15:20:23 pi5k6.6 sudo[2409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 15:20:23 pi5k6.6 sudo[2409]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 15:20:23 pi5k6.6 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 15:20:23 pi5k6.6 systemd[1]: shairport-sync.service: Succeeded. Oct 11 15:20:23 pi5k6.6 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 15:20:23 pi5k6.6 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 15:20:23 pi5k6.6 sudo[2405]: pam_unix(sudo:session): session closed for user root Oct 11 15:20:23 pi5k6.6 sudo[2409]: pam_unix(sudo:session): session closed for user root Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Shairport-Sync Started Oct 11 15:20:23 pi5k6.6 volumio[1321]: info: Shairport-Sync Started Oct 11 15:20:23 pi5k6.6 mpd[2411]: Oct 11 15:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 15:20:23 pi5k6.6 systemd[1]: Started Music Player Daemon. Oct 11 15:20:23 pi5k6.6 sudo[2380]: pam_unix(sudo:session): session closed for user root Oct 11 15:20:23 pi5k6.6 sudo[2390]: pam_unix(sudo:session): session closed for user root Oct 11 15:20:23 pi5k6.6 volumio[1321]: error: MPD error: The expression evaluated to a falsy value: Oct 11 15:20:23 pi5k6.6 volumio[1321]: assert.ok(self.idling) Oct 11 15:20:23 pi5k6.6 volumio[1321]: error: The expression evaluated to a falsy value: Oct 11 15:20:23 pi5k6.6 volumio[1321]: assert.ok(self.idling) Oct 11 15:20:23 pi5k6.6 volumio[1321]: error: updateQueue error: null Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CoreStateMachine::getcurrentVolume Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 15:20:27 pi5k6.6 volumio[1321]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: VolumeController:: Volume=undefined Mute =false Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CoreStateMachine::updateTrackBlock Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrackBlock Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 15:20:27 pi5k6.6 volumio[1321]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: VolumeController:: Volume=undefined Mute =false Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CoreStateMachine::pushState Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CorePlayQueue::getTrack 0 Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: CoreCommandRouter::volumioPushState Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: PLUGIN onReboot : networkfs Oct 11 15:20:27 pi5k6.6 volumio[1321]: info: PLUGIN onReboot : audiophonicsonoff Oct 11 15:20:27 pi5k6.6 volumio[1321]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 15:20:27 pi5k6.6 volumio[1321]: TypeError: Cannot read property 'writeSync' of undefined Oct 11 15:20:27 pi5k6.6 volumio[1321]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) Oct 11 15:20:27 pi5k6.6 volumio[1321]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 11 15:20:27 pi5k6.6 volumio[1321]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 11 15:20:27 pi5k6.6 volumio[1321]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 11 15:20:27 pi5k6.6 volumio[1321]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 11 15:20:27 pi5k6.6 volumio[1321]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 11 15:20:27 pi5k6.6 volumio[1321]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Oct 11 15:20:27 pi5k6.6 volumio[1321]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Oct 11 15:20:27 pi5k6.6 volumio[1321]: at Socket.emit (events.js:315:20) Oct 11 15:20:27 pi5k6.6 volumio[1321]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 11 15:20:27 pi5k6.6 volumio[1321]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 11 15:20:27 pi5k6.6 volumio[1321]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 15:20:28 pi5k6.6 sudo[2430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 15:19 Oct 11 15:20:28 pi5k6.6 sudo[2430]: 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="99ba0a37c11b93215dc3e0ee3c22f0c0313c225b" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8ef80245b1f7b577b29fe08b77efdc88d53369e5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 11 Oct 2024 10:54:46 AM CEST" VOLUMIO_VERSION="3.759" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a1641e68e16692da54c105ebfa2aa2be"