-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Mon 2025-03-03 20:11:27 MSK. -- Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioNext Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::next Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::stop Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::stPlaybackTimer Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::updateTrackBlock Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrackBlock Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 16 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::serviceStop Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 16 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::serviceStop Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::stop Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 20:10:07 volumio volumio[917]: info: Mar 03 20:10:07 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand stop took 10 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::play index undefined Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::startPlaybackTimer Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::clearAddPlayTracks USB/01ED-DF7E/Music/Cerrone - Supernature Variations _ Acte I.flac Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::updateTrackBlock Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrackBlock Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand status took 25 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand stop took 24 milliseconds Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand clear Mar 03 20:10:07 volumio volumio[917]: info: Mar 03 20:10:07 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:10:07 volumio volumio[917]: info: Mar 03 20:10:07 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:10:07 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand clear took 7 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand status took 6 milliseconds Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand add "USB/01ED-DF7E/Music/Cerrone - Supernature Variations _ Acte I.flac" Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bohemian rhapsody","artist":"Made in KZ","album":"Bohemian rhapsody","uri":"USB/01ED-DF7E/Music/Made in KZ - Bohemian rhapsody.flac","trackType":"flac"} Mar 03 20:10:07 volumio volumio[917]: verbose: CURRENT POSITION 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState stateService stop Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus stop Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: No code Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 03 20:10:07 volumio volumio[917]: verbose: CURRENT POSITION 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState stateService stop Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus stop Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: No code Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: ------------------------------ 85ms Mar 03 20:10:07 volumio volumio[917]: info: ------------------------------ 56ms Mar 03 20:10:07 volumio volumio[917]: info: Mar 03 20:10:07 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:10:07 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:10:07 volumio volumio[917]: error: updateQueue error: null Mar 03 20:10:07 volumio volumio[917]: info: Mar 03 20:10:07 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:10:07 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:10:07 volumio volumio[917]: info: ------------------------------ 64ms Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand add "USB/01ED-DF7E/Music/Cerrone - Supernature Variations _ Acte I.flac" took 60 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: ------------------------------ 12ms Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand play Mar 03 20:10:07 volumio volumio[917]: info: Mar 03 20:10:07 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:10:07 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:10:07 volumio volumio[917]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 03 20:10:07 volumio volumio[917]: info: Mar 03 20:10:07 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:10:07 volumio volumio[917]: info: ------------------------------ 25ms Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand play took 25 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: ------------------------------ 25ms Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:10:07 volumio volumio[917]: info: Mar 03 20:10:07 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:10:07 volumio volumio[917]: info: Mar 03 20:10:07 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand status took 20 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand status took 9 milliseconds Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:10:07 volumio volumio[917]: info: Mar 03 20:10:07 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand status took 11 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand status took 5 milliseconds Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernature Variations : Acte I","artist":"Cerrone","album":"Cerrone Symphony : Variations of Supernature","uri":"USB/01ED-DF7E/Music/Cerrone - Supernature Variations _ Acte I.flac","trackType":"flac"} Mar 03 20:10:07 volumio volumio[917]: verbose: CURRENT POSITION 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus stop Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernature Variations : Acte I","artist":"Cerrone","album":"Cerrone Symphony : Variations of Supernature","uri":"USB/01ED-DF7E/Music/Cerrone - Supernature Variations _ Acte I.flac","trackType":"flac"} Mar 03 20:10:07 volumio volumio[917]: verbose: CURRENT POSITION 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 03 20:10:07 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: ------------------------------ 57ms Mar 03 20:10:07 volumio volumio[917]: info: ------------------------------ 57ms Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand playlistinfo took 51 milliseconds Mar 03 20:10:07 volumio volumio[917]: info: sendMpdCommand playlistinfo took 49 milliseconds Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:10:07 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernature Variations : Acte I","artist":"Cerrone","album":"Cerrone Symphony : Variations of Supernature","uri":"USB/01ED-DF7E/Music/Cerrone - Supernature Variations _ Acte I.flac","trackType":"flac"} Mar 03 20:10:07 volumio volumio[917]: verbose: CURRENT POSITION 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 03 20:10:07 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernature Variations : Acte I","artist":"Cerrone","album":"Cerrone Symphony : Variations of Supernature","uri":"USB/01ED-DF7E/Music/Cerrone - Supernature Variations _ Acte I.flac","trackType":"flac"} Mar 03 20:10:07 volumio volumio[917]: verbose: CURRENT POSITION 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 03 20:10:07 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:10:07 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:07 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:10:07 volumio volumio[917]: info: ------------------------------ 115ms Mar 03 20:10:07 volumio volumio[917]: info: ------------------------------ 106ms Mar 03 20:10:08 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 20:10:08 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 20:10:08 volumio volumio[917]: info: Discovery: Getting this device information Mar 03 20:10:08 volumio volumio[917]: info: CoreCommandRouter::volumioGetState Mar 03 20:10:08 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:08 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 20:10:12 volumio nmbd[787]: [2025/03/03 20:10:12.359514, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 03 20:10:12 volumio nmbd[787]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Mar 03 20:10:16 volumio nmbd[787]: [2025/03/03 20:10:16.367919, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 03 20:10:16 volumio nmbd[787]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Mar 03 20:10:17 volumio volumio[917]: info: Enabling plugin audiophonicsonoff Mar 03 20:10:17 volumio volumio[917]: info: Loading plugin "audiophonicsonoff"... Mar 03 20:10:17 volumio volumio[917]: info: Applying required configuration parameters for plugin audiophonicsonoff Mar 03 20:10:17 volumio volumio[917]: info: Audiophonics on/off initiated Mar 03 20:10:17 volumio volumio[917]: info: PLUGIN START: audiophonicsonoff Mar 03 20:10:17 volumio volumio[917]: info: Configuring GPIO pins Mar 03 20:10:17 volumio volumio[917]: info: Error: Error: EBUSY: resource busy or locked, write Mar 03 20:10:17 volumio ntpd[810]: 5.178.87.15 local addr 192.168.1.112 -> Mar 03 20:10:17 volumio ntpd[810]: 31.131.251.6 local addr 192.168.1.112 -> Mar 03 20:10:22 volumio volumio[917]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 20:10:35 volumio volumio[917]: info: CALLMETHOD: system_hardware music_services_shield saveConfig [object Object] Mar 03 20:10:35 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: music_services_shield , saveConfig Mar 03 20:10:35 volumio sudo[9382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 03 20:10:35 volumio sudo[9382]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:10:36 volumio sudo[9382]: pam_unix(sudo:session): session closed for user root Mar 03 20:10:36 volumio volumio[917]: info: succeeded Mar 03 20:10:38 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 20:10:38 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 20:10:38 volumio volumio[917]: info: Discovery: Getting this device information Mar 03 20:10:38 volumio volumio[917]: info: CoreCommandRouter::volumioGetState Mar 03 20:10:38 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:10:38 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 20:10:40 volumio volumio[917]: info: CALLMETHOD: system_hardware music_services_shield listUserTasks undefined Mar 03 20:10:40 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: music_services_shield , listUserTasks Mar 03 20:10:41 volumio volumio[917]: info: user tasks Mar 03 20:10:41 volumio volumio[917]: Mar 03 20:10:41 volumio volumio[917]: Mar 03 20:10:41 volumio volumio[917]:
cset: --> shielding system active with
cset: "system" cpuset of CPUSPEC(0-1,3) with 199 tasks running
cset: "user" cpuset of CPUSPEC(2) with 6 tasks running
Mar 03 20:10:41 volumio volumio[917]: Mar 03 20:10:41 volumio volumio[917]: Mar 03 20:10:41 volumio volumio[917]: Mar 03 20:10:41 volumio volumio[917]: Mar 03 20:10:41 volumio volumio[917]: Mar 03 20:10:41 volumio volumio[917]: Mar 03 20:10:41 volumio volumio[917]:
USERPIDPPIDSPPrTASKNAME
mpd83921Soth[mpd]
mpd83971Soth[io]
mpd83981Sf40[rtio]
mpd84991Soth[player]
mpd85001Soth[decoder]
mpd85011Sf40[output]
Mar 03 20:10:59 volumio volumio[917]: info: CoreCommandRouter::Close All Modals sent Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioNext Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::next Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::stop Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::stPlaybackTimer Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::updateTrackBlock Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrackBlock Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::serviceStop Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 17 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::serviceStop Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::stop Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 20:11:01 volumio volumio[917]: info: Mar 03 20:11:01 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand stop took 12 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::play index undefined Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::startPlaybackTimer Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::clearAddPlayTracks USB/01ED-DF7E/Music/Cerrone - Supernature Project (Single Version).flac Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::updateTrackBlock Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrackBlock Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand status took 21 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand stop took 21 milliseconds Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand clear Mar 03 20:11:01 volumio volumio[917]: info: Mar 03 20:11:01 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:01 volumio volumio[917]: info: Mar 03 20:11:01 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:11:01 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand clear took 6 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand status took 2 milliseconds Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand add "USB/01ED-DF7E/Music/Cerrone - Supernature Project (Single Version).flac" Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernature Variations : Acte I","artist":"Cerrone","album":"Cerrone Symphony : Variations of Supernature","uri":"USB/01ED-DF7E/Music/Cerrone - Supernature Variations _ Acte I.flac","trackType":"flac"} Mar 03 20:11:01 volumio volumio[917]: verbose: CURRENT POSITION 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState stateService stop Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus stop Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: No code Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 03 20:11:01 volumio volumio[917]: verbose: CURRENT POSITION 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState stateService stop Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus stop Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: No code Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: ------------------------------ 47ms Mar 03 20:11:01 volumio volumio[917]: info: ------------------------------ 22ms Mar 03 20:11:01 volumio volumio[917]: info: Mar 03 20:11:01 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:11:01 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:11:01 volumio volumio[917]: error: updateQueue error: null Mar 03 20:11:01 volumio volumio[917]: info: Mar 03 20:11:01 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:11:01 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:11:01 volumio volumio[917]: info: ------------------------------ 24ms Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand add "USB/01ED-DF7E/Music/Cerrone - Supernature Project (Single Version).flac" took 21 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: ------------------------------ 3ms Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand play Mar 03 20:11:01 volumio volumio[917]: info: Mar 03 20:11:01 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:11:01 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:11:01 volumio volumio[917]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 03 20:11:01 volumio volumio[917]: info: Mar 03 20:11:01 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:01 volumio volumio[917]: info: ------------------------------ 20ms Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand play took 19 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: ------------------------------ 19ms Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:01 volumio volumio[917]: info: Mar 03 20:11:01 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:01 volumio volumio[917]: info: Mar 03 20:11:01 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand status took 8 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand status took 8 milliseconds Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:01 volumio volumio[917]: info: Mar 03 20:11:01 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand status took 5 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand status took 2 milliseconds Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernature Project (Single Version)","artist":"Cerrone","album":"Cerrone Symphony : Variations of Supernature","uri":"USB/01ED-DF7E/Music/Cerrone - Supernature Project (Single Version).flac","trackType":"flac"} Mar 03 20:11:01 volumio volumio[917]: verbose: CURRENT POSITION 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus stop Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernature Project (Single Version)","artist":"Cerrone","album":"Cerrone Symphony : Variations of Supernature","uri":"USB/01ED-DF7E/Music/Cerrone - Supernature Project (Single Version).flac","trackType":"flac"} Mar 03 20:11:01 volumio volumio[917]: verbose: CURRENT POSITION 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 03 20:11:01 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: ------------------------------ 23ms Mar 03 20:11:01 volumio volumio[917]: info: ------------------------------ 25ms Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 03 20:11:01 volumio volumio[917]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:01 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernature Project (Single Version)","artist":"Cerrone","album":"Cerrone Symphony : Variations of Supernature","uri":"USB/01ED-DF7E/Music/Cerrone - Supernature Project (Single Version).flac","trackType":"flac"} Mar 03 20:11:01 volumio volumio[917]: verbose: CURRENT POSITION 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 03 20:11:01 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernature Project (Single Version)","artist":"Cerrone","album":"Cerrone Symphony : Variations of Supernature","uri":"USB/01ED-DF7E/Music/Cerrone - Supernature Project (Single Version).flac","trackType":"flac"} Mar 03 20:11:01 volumio volumio[917]: verbose: CURRENT POSITION 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 03 20:11:01 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:01 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:01 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:01 volumio volumio[917]: info: ------------------------------ 33ms Mar 03 20:11:01 volumio volumio[917]: info: ------------------------------ 31ms Mar 03 20:11:04 volumio volumio[917]: info: VolumeController::SetAlsaVolume91 Mar 03 20:11:04 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:04 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:04 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:11:04 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioNext Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::next Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::stop Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::stPlaybackTimer Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::updateTrackBlock Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrackBlock Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::serviceStop Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 18 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::serviceStop Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::stop Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 20:11:16 volumio volumio[917]: info: Mar 03 20:11:16 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand stop took 29 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::play index undefined Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::startPlaybackTimer Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::clearAddPlayTracks USB/01ED-DF7E/Music/Dani Hageman - Chicks 4 Free (Original Mix).flac Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::updateTrackBlock Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrackBlock Mar 03 20:11:16 volumio volumio[917]: info: Mar 03 20:11:16 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand status took 4 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand stop took 3 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand status took 2 milliseconds Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand clear Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:16 volumio volumio[917]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 03 20:11:16 volumio volumio[917]: info: Mar 03 20:11:16 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:11:16 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand clear took 2 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand add "USB/01ED-DF7E/Music/Dani Hageman - Chicks 4 Free (Original Mix).flac" Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernature Project (Single Version)","artist":"Cerrone","album":"Cerrone Symphony : Variations of Supernature","uri":"USB/01ED-DF7E/Music/Cerrone - Supernature Project (Single Version).flac","trackType":"flac"} Mar 03 20:11:16 volumio volumio[917]: verbose: CURRENT POSITION 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::syncState stateService stop Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus stop Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: info: No code Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 03 20:11:16 volumio volumio[917]: info: ------------------------------ 27ms Mar 03 20:11:16 volumio volumio[917]: info: ------------------------------ 23ms Mar 03 20:11:16 volumio volumio[917]: info: Mar 03 20:11:16 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:11:16 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:11:16 volumio volumio[917]: error: updateQueue error: null Mar 03 20:11:16 volumio volumio[917]: info: Mar 03 20:11:16 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:11:16 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:11:16 volumio volumio[917]: info: ------------------------------ 20ms Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand add "USB/01ED-DF7E/Music/Dani Hageman - Chicks 4 Free (Original Mix).flac" took 20 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: ------------------------------ 3ms Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand play Mar 03 20:11:16 volumio volumio[917]: info: Mar 03 20:11:16 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 03 20:11:16 volumio volumio[917]: info: Ignoring MPD Status Update Mar 03 20:11:16 volumio volumio[917]: info: Mar 03 20:11:16 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:16 volumio volumio[917]: info: ------------------------------ 9ms Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand play took 9 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: ------------------------------ 8ms Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:16 volumio volumio[917]: info: Mar 03 20:11:16 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:16 volumio volumio[917]: info: Mar 03 20:11:16 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand status took 30 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand status took 30 milliseconds Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:16 volumio volumio[917]: info: Mar 03 20:11:16 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand status took 5 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand status took 2 milliseconds Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":380,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chicks 4 Free (Original Mix)","artist":"Dani Hageman","album":"Chicks 4 Free / Hello (Original Mix)","uri":"USB/01ED-DF7E/Music/Dani Hageman - Chicks 4 Free (Original Mix).flac","trackType":"flac"} Mar 03 20:11:16 volumio volumio[917]: verbose: CURRENT POSITION 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus stop Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":380,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chicks 4 Free (Original Mix)","artist":"Dani Hageman","album":"Chicks 4 Free / Hello (Original Mix)","uri":"USB/01ED-DF7E/Music/Dani Hageman - Chicks 4 Free (Original Mix).flac","trackType":"flac"} Mar 03 20:11:16 volumio volumio[917]: verbose: CURRENT POSITION 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 03 20:11:16 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: info: ------------------------------ 48ms Mar 03 20:11:16 volumio volumio[917]: info: ------------------------------ 46ms Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 03 20:11:16 volumio volumio[917]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:16 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":380,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chicks 4 Free (Original Mix)","artist":"Dani Hageman","album":"Chicks 4 Free / Hello (Original Mix)","uri":"USB/01ED-DF7E/Music/Dani Hageman - Chicks 4 Free (Original Mix).flac","trackType":"flac"} Mar 03 20:11:16 volumio volumio[917]: verbose: CURRENT POSITION 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 03 20:11:16 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":380,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chicks 4 Free (Original Mix)","artist":"Dani Hageman","album":"Chicks 4 Free / Hello (Original Mix)","uri":"USB/01ED-DF7E/Music/Dani Hageman - Chicks 4 Free (Original Mix).flac","trackType":"flac"} Mar 03 20:11:16 volumio volumio[917]: verbose: CURRENT POSITION 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 03 20:11:16 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:16 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:16 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:16 volumio volumio[917]: info: ------------------------------ 32ms Mar 03 20:11:16 volumio volumio[917]: info: ------------------------------ 27ms Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 03 20:11:19 volumio volumio[917]: info: Getting Alsa Cards List without I2S DAC Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 03 20:11:19 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 03 20:11:24 volumio volumio[917]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 03 20:11:24 volumio volumio[917]: info: Preparing to save Alsa Options, stopping services first Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::volumioGetState Mar 03 20:11:24 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::volumioPause Mar 03 20:11:24 volumio volumio[917]: info: CoreStateMachine::pause Mar 03 20:11:24 volumio volumio[917]: info: CoreStateMachine::stPlaybackTimer Mar 03 20:11:24 volumio volumio[917]: info: CoreStateMachine::servicePause Mar 03 20:11:24 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::servicePause Mar 03 20:11:24 volumio volumio[917]: info: ControllerMpd::pause Mar 03 20:11:24 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand pause Mar 03 20:11:24 volumio volumio[917]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Headphones"},"i2s":true,"i2sid":{"value":"allo-boss-dac-pi5","label":"Allo BOSS [Pi5]"}} Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:24 volumio volumio[917]: info: Enabling I2S DAC: Allo BOSS [Pi5] Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:24 volumio sudo[9571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 03 20:11:24 volumio sudo[9571]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:11:24 volumio sudo[9571]: pam_unix(sudo:session): session closed for user root Mar 03 20:11:24 volumio volumio[917]: info: No Overlays Loaded Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 03 20:11:24 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:24 volumio sudo[9574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay allo-boss-dac-pcm512x-audio Mar 03 20:11:24 volumio sudo[9574]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:11:25 volumio sudo[9574]: pam_unix(sudo:session): session closed for user root Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: Found match in i2s Card Database: setting mixer Digital for card Allo BOSS [Pi5] Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 03 20:11:25 volumio volumio[917]: info: Updating Volume Controller Parameters: Device: 1 Name: Allo BOSS [Pi5] Mixer: Digital Max Vol: 100 Vol Curve; linear Vol Steps: 5 Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 03 20:11:25 volumio volumio[917]: info: Disabling external Volume Control Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Mar 03 20:11:25 volumio volumio[917]: info: Preparing to generate the ALSA configuration file Mar 03 20:11:25 volumio volumio[917]: info: Mar 03 20:11:25 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:25 volumio volumio[917]: info: sendMpdCommand pause took 238 milliseconds Mar 03 20:11:25 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:25 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:25 volumio volumio[917]: info: Mar 03 20:11:25 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 03 20:11:25 volumio volumio[917]: info: ControllerMpd::getState Mar 03 20:11:25 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 03 20:11:25 volumio volumio[917]: error: Cannot enable I2S Param: Error: Command failed: /usr/bin/sudo /usr/bin/dtoverlay allo-boss-dac-pcm512x-audio Mar 03 20:11:25 volumio volumio[917]: * Failed to apply overlay '0_allo-boss-dac-pcm512x-audio' (kernel) Mar 03 20:11:25 volumio volumio[917]: info: sendMpdCommand status took 24 milliseconds Mar 03 20:11:25 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:25 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:25 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 03 20:11:25 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 20:11:25 volumio volumio[917]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 03 20:11:25 volumio volumio[917]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 03 20:11:25 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:25 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 03 20:11:25 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:25 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:25 volumio volumio[917]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9357,"duration":380,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"847 Kbps","isStreaming":false,"title":"Chicks 4 Free (Original Mix)","artist":"Dani Hageman","album":"Chicks 4 Free / Hello (Original Mix)","uri":"USB/01ED-DF7E/Music/Dani Hageman - Chicks 4 Free (Original Mix).flac","trackType":"flac"} Mar 03 20:11:25 volumio volumio[917]: verbose: CURRENT POSITION 19 Mar 03 20:11:25 volumio volumio[917]: info: CoreStateMachine::syncState stateService pause Mar 03 20:11:25 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus pause Mar 03 20:11:25 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:25 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:25 volumio volumio[917]: info: CoreStateMachine::stPlaybackTimer Mar 03 20:11:25 volumio volumio[917]: info: ControllerMpd::pushState Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 03 20:11:25 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:25 volumio volumio[917]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9357,"duration":380,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"847 Kbps","isStreaming":false,"title":"Chicks 4 Free (Original Mix)","artist":"Dani Hageman","album":"Chicks 4 Free / Hello (Original Mix)","uri":"USB/01ED-DF7E/Music/Dani Hageman - Chicks 4 Free (Original Mix).flac","trackType":"flac"} Mar 03 20:11:25 volumio volumio[917]: verbose: CURRENT POSITION 19 Mar 03 20:11:25 volumio volumio[917]: info: CoreStateMachine::syncState stateService pause Mar 03 20:11:25 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus pause Mar 03 20:11:25 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:25 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:25 volumio volumio[917]: info: CoreStateMachine::stPlaybackTimer Mar 03 20:11:25 volumio volumio[917]: info: ------------------------------ 39ms Mar 03 20:11:25 volumio volumio[917]: info: ------------------------------ 37ms Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 03 20:11:25 volumio volumio[917]: info: Getting Alsa Cards List without I2S DAC Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 03 20:11:25 volumio volumio[917]: info: Asound.conf file unchanged, so no further update is needed Mar 03 20:11:25 volumio volumio[917]: info: Output device has changed, restarting MPD Mar 03 20:11:25 volumio volumio[917]: info: Output device has changed, restarting Shairport Sync Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:25 volumio sudo[9599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 20:11:25 volumio sudo[9599]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:11:25 volumio sudo[9601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 20:11:25 volumio sudo[9601]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:11:25 volumio sudo[9599]: pam_unix(sudo:session): session closed for user root Mar 03 20:11:25 volumio volumio[917]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 20:11:25 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 03 20:11:25 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 03 20:11:25 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 03 20:11:25 volumio systemd[1]: Stopping Music Player Daemon... Mar 03 20:11:25 volumio volumio[917]: info: VolumeController:: Volume=91 Mute =false Mar 03 20:11:25 volumio systemd[1]: mpd.service: Succeeded. Mar 03 20:11:25 volumio volumio[917]: info: CoreStateMachine::pushState Mar 03 20:11:25 volumio volumio[917]: info: CorePlayQueue::getTrack 19 Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 03 20:11:25 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 20:11:25 volumio volumio[917]: info: MPD Permissions set Mar 03 20:11:25 volumio volumio[917]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 20:11:25 volumio volumio[917]: info: Starting Shairport Sync Mar 03 20:11:25 volumio systemd[1]: Starting Music Player Daemon... Mar 03 20:11:25 volumio sudo[9616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 20:11:25 volumio sudo[9616]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:11:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 20:11:25 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 03 20:11:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 20:11:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 20:11:25 volumio sudo[9616]: pam_unix(sudo:session): session closed for user root Mar 03 20:11:25 volumio volumio[917]: info: Shairport-Sync Started Mar 03 20:11:25 volumio sudo[9609]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 20:11:25 volumio sudo[9609]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 20:11:25 volumio sudo[9609]: pam_unix(sudo:session): session closed for user root Mar 03 20:11:26 volumio mpd[9620]: Mar 03 20:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 20:11:26 volumio systemd[1]: Started Music Player Daemon. Mar 03 20:11:26 volumio sudo[9601]: pam_unix(sudo:session): session closed for user root Mar 03 20:11:26 volumio volumio[917]: error: updateQueue error: null Mar 03 20:11:26 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 03 20:11:26 volumio volumio[917]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Mar 03 20:11:26 volumio volumio[917]: info: PLUGIN onReboot : networkfs Mar 03 20:11:26 volumio volumio[917]: info: PLUGIN onReboot : audiophonicsonoff Mar 03 20:11:26 volumio volumio[917]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 20:11:26 volumio volumio[917]: TypeError: Cannot read property 'writeSync' of undefined Mar 03 20:11:26 volumio volumio[917]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Mar 03 20:11:26 volumio volumio[917]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Mar 03 20:11:26 volumio volumio[917]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Mar 03 20:11:26 volumio volumio[917]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 03 20:11:26 volumio volumio[917]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 03 20:11:26 volumio volumio[917]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Mar 03 20:11:26 volumio volumio[917]: at CoreCommandRouter.reboot (/volumio/app/index.js:1344:22) Mar 03 20:11:26 volumio volumio[917]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Mar 03 20:11:26 volumio volumio[917]: at Socket.emit (events.js:315:20) Mar 03 20:11:26 volumio volumio[917]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 03 20:11:26 volumio volumio[917]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 03 20:11:26 volumio volumio[917]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 20:11:26 volumio volumio[9625]: cset: --> shielding system active with Mar 03 20:11:26 volumio volumio[9625]: cset: "system" cpuset of CPUSPEC(0-1,3) with 206 tasks running Mar 03 20:11:26 volumio volumio[9625]: cset: "user" cpuset of CPUSPEC(2) with 3 tasks running Mar 03 20:11:26 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 03 20:11:27 volumio sudo[9649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 20:10 Mar 03 20:11:27 volumio sudo[9649]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"