Dec 28 21:13:03 ap-4-volumio volumio[1246]: info: BOOT COMPLETED
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPlay
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: CoreStateMachine::play index undefined
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: CoreStateMachine::startPlaybackTimer
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: [1766923999826] ControllerWebradio::clearAddPlayTrack
Dec 28 21:13:19 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand stop
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: sendMpdCommand stop took 3 milliseconds
Dec 28 21:13:19 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand clear
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: sendMpdCommand clear took 1 milliseconds
Dec 28 21:13:19 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls"
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:19 ap-4-volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: Ignoring MPD Status Update
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:19 ap-4-volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: Ignoring MPD Status Update
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:19 ap-4-volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Dec 28 21:13:19 ap-4-volumio volumio[1246]: info: Ignoring MPD Status Update
Dec 28 21:13:20 ap-4-volumio upmpdcli[1896]: writing RSA key
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:20 ap-4-volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: Ignoring MPD Status Update
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" took 258 milliseconds
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: ------------------------------ 258ms
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: ------------------------------ 221ms
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: ------------------------------ 218ms
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService mpd
Dec 28 21:13:20 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand play
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:20 ap-4-volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: Ignoring MPD Status Update
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:20 ap-4-volumio volumio[1246]: ---------------------------- MPD announces system playlist update
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: Ignoring MPD Status Update
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: ------------------------------ 57ms
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: sendMpdCommand play took 50 milliseconds
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: ------------------------------ 48ms
Dec 28 21:13:20 ap-4-volumio volumio[1246]: info: ------------------------------ 6ms
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:22 ap-4-volumio volumio[1246]: ---------------------------- MPD announces state update: player
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::getState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:22 ap-4-volumio volumio[1246]: ---------------------------- MPD announces state update: player
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::getState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:22 ap-4-volumio volumio[1246]: ---------------------------- MPD announces state update: player
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:22 ap-4-volumio volumio[1246]: ---------------------------- MPD announces state update: player
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::getState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand status took 66 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::getState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:22 ap-4-volumio volumio[1246]: ---------------------------- MPD announces state update: player
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::getState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info:
Dec 28 21:13:22 ap-4-volumio volumio[1246]: ---------------------------- MPD announces state update: player
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::getState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand status took 99 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand status took 96 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand status took 91 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand playlistinfo took 71 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand status took 29 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"198 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjcwOTU5NTd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=DYBcAWQ9owWFx9a8K5dckaLE9R5RWxzKgULJcsmrOrHKrl69HUPy7pnUwBavCz49SaAFhMCh6dip~DzLk4raktX2CYw1mDT62-tGdbVa6PDZkUtQgjEb9LoI6FPaJ6EoCiOuhRbQJ4GCZuGhfnWxnpFUj9P~KE6bOjcNPgJIfigbn~tBeKOUifLgY1KvAXD3RffgRLbRUzW9H18YR8CBJyBqtl6SI-wF5hhyA-8-LmFVhurJ1lFD1IicwP~uTbmjQwPdO2L85JLFe9aLQMlo~YFGf7Hy2gpfnoODzB6oXHdS1ApkbYCRJPRo5-z96ahf25KYuo5yhU1axyWJvk5fqw__","trackType":""}
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: CURRENT POSITION 0
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState stateService play
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ------------------------------ 289ms
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand status took 94 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand playlistinfo took 78 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand playlistinfo took 75 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand playlistinfo took 74 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"198 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjcwOTU5NTd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=DYBcAWQ9owWFx9a8K5dckaLE9R5RWxzKgULJcsmrOrHKrl69HUPy7pnUwBavCz49SaAFhMCh6dip~DzLk4raktX2CYw1mDT62-tGdbVa6PDZkUtQgjEb9LoI6FPaJ6EoCiOuhRbQJ4GCZuGhfnWxnpFUj9P~KE6bOjcNPgJIfigbn~tBeKOUifLgY1KvAXD3RffgRLbRUzW9H18YR8CBJyBqtl6SI-wF5hhyA-8-LmFVhurJ1lFD1IicwP~uTbmjQwPdO2L85JLFe9aLQMlo~YFGf7Hy2gpfnoODzB6oXHdS1ApkbYCRJPRo5-z96ahf25KYuo5yhU1axyWJvk5fqw__","trackType":""}
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: CURRENT POSITION 0
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState stateService play
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: Received an update from plugin. extracting info from payload
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"198 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjcwOTU5NTd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=DYBcAWQ9owWFx9a8K5dckaLE9R5RWxzKgULJcsmrOrHKrl69HUPy7pnUwBavCz49SaAFhMCh6dip~DzLk4raktX2CYw1mDT62-tGdbVa6PDZkUtQgjEb9LoI6FPaJ6EoCiOuhRbQJ4GCZuGhfnWxnpFUj9P~KE6bOjcNPgJIfigbn~tBeKOUifLgY1KvAXD3RffgRLbRUzW9H18YR8CBJyBqtl6SI-wF5hhyA-8-LmFVhurJ1lFD1IicwP~uTbmjQwPdO2L85JLFe9aLQMlo~YFGf7Hy2gpfnoODzB6oXHdS1ApkbYCRJPRo5-z96ahf25KYuo5yhU1axyWJvk5fqw__","trackType":""}
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: CURRENT POSITION 0
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState stateService play
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: Received an update from plugin. extracting info from payload
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1104,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"198 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjcwOTU5NTd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=DYBcAWQ9owWFx9a8K5dckaLE9R5RWxzKgULJcsmrOrHKrl69HUPy7pnUwBavCz49SaAFhMCh6dip~DzLk4raktX2CYw1mDT62-tGdbVa6PDZkUtQgjEb9LoI6FPaJ6EoCiOuhRbQJ4GCZuGhfnWxnpFUj9P~KE6bOjcNPgJIfigbn~tBeKOUifLgY1KvAXD3RffgRLbRUzW9H18YR8CBJyBqtl6SI-wF5hhyA-8-LmFVhurJ1lFD1IicwP~uTbmjQwPdO2L85JLFe9aLQMlo~YFGf7Hy2gpfnoODzB6oXHdS1ApkbYCRJPRo5-z96ahf25KYuo5yhU1axyWJvk5fqw__","trackType":""}
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: CURRENT POSITION 0
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState stateService play
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: Received an update from plugin. extracting info from payload
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ------------------------------ 532ms
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ------------------------------ 519ms
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ------------------------------ 523ms
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand playlistinfo took 407 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: sendMpdCommand playlistinfo took 341 milliseconds
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: ControllerMpd::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1104,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"198 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjcwOTU5NTd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=DYBcAWQ9owWFx9a8K5dckaLE9R5RWxzKgULJcsmrOrHKrl69HUPy7pnUwBavCz49SaAFhMCh6dip~DzLk4raktX2CYw1mDT62-tGdbVa6PDZkUtQgjEb9LoI6FPaJ6EoCiOuhRbQJ4GCZuGhfnWxnpFUj9P~KE6bOjcNPgJIfigbn~tBeKOUifLgY1KvAXD3RffgRLbRUzW9H18YR8CBJyBqtl6SI-wF5hhyA-8-LmFVhurJ1lFD1IicwP~uTbmjQwPdO2L85JLFe9aLQMlo~YFGf7Hy2gpfnoODzB6oXHdS1ApkbYCRJPRo5-z96ahf25KYuo5yhU1axyWJvk5fqw__","trackType":""}
Dec 28 21:13:22 ap-4-volumio volumio[1246]: verbose: CURRENT POSITION 0
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState stateService play
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: Received an update from plugin. extracting info from payload
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreStateMachine::pushState
Dec 28 21:13:22 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: ControllerMpd::pushState
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: CoreCommandRouter::servicePushState
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: CorePlayQueue::getTrack 0
Dec 28 21:13:23 ap-4-volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1104,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"198 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjcwOTU5NTd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=DYBcAWQ9owWFx9a8K5dckaLE9R5RWxzKgULJcsmrOrHKrl69HUPy7pnUwBavCz49SaAFhMCh6dip~DzLk4raktX2CYw1mDT62-tGdbVa6PDZkUtQgjEb9LoI6FPaJ6EoCiOuhRbQJ4GCZuGhfnWxnpFUj9P~KE6bOjcNPgJIfigbn~tBeKOUifLgY1KvAXD3RffgRLbRUzW9H18YR8CBJyBqtl6SI-wF5hhyA-8-LmFVhurJ1lFD1IicwP~uTbmjQwPdO2L85JLFe9aLQMlo~YFGf7Hy2gpfnoODzB6oXHdS1ApkbYCRJPRo5-z96ahf25KYuo5yhU1axyWJvk5fqw__","trackType":""}
Dec 28 21:13:23 ap-4-volumio volumio[1246]: verbose: CURRENT POSITION 0
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState stateService play
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: Received an update from plugin. extracting info from payload
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: CoreStateMachine::pushState
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: CoreStateMachine::pushState
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: CoreCommandRouter::volumioPushState
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: ------------------------------ 737ms
Dec 28 21:13:23 ap-4-volumio volumio[1246]: info: ------------------------------ 728ms
Dec 28 21:13:40 ap-4-volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 28 21:13:40 ap-4-volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 28 21:13:45 ap-4-volumio volumio[1246]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 28 21:13:45 ap-4-volumio volumio[1246]: info: touch_display: About to detect HDMI ports...
Dec 28 21:13:45 ap-4-volumio volumio[1246]: info: touch_display: X server not ready, cannot detect HDMI ports yet.
Dec 28 21:14:01 ap-4-volumio volumio[1246]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object]
Dec 28 21:14:01 ap-4-volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf
Dec 28 21:14:01 ap-4-volumio sudo[2077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf
Dec 28 21:14:01 ap-4-volumio sudo[2077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 28 21:14:01 ap-4-volumio sudo[2077]: pam_unix(sudo:session): session closed for user root
Dec 28 21:14:01 ap-4-volumio volumio[1246]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set.
Dec 28 21:14:01 ap-4-volumio volumio[1246]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 28 21:14:01 ap-4-volumio volumio[1246]: TypeError: Cannot read properties of undefined (reading 'split')
Dec 28 21:14:01 ap-4-volumio volumio[1246]: at /data/plugins/user_interface/touch_display/index.js:1298:37
Dec 28 21:14:01 ap-4-volumio volumio[1246]: at ChildProcess.exithandler (node:child_process:413:7)
Dec 28 21:14:01 ap-4-volumio volumio[1246]: at ChildProcess.emit (node:events:514:28)
Dec 28 21:14:01 ap-4-volumio volumio[1246]: at maybeClose (node:internal/child_process:1105:16)
Dec 28 21:14:01 ap-4-volumio volumio[1246]: at ChildProcess._handle.onexit (node:internal/child_process:305:5)
Dec 28 21:14:01 ap-4-volumio volumio[1246]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 28 21:14:09 ap-4-volumio sudo[2110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 21:13'
Dec 28 21:14:09 ap-4-volumio sudo[2110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
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="cfdc872d40b979cca36352a7333c940c38dc64b5"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025"
VOLUMIO_VERSION="4.073"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"