Feb 22 09:39:08 rasp2 volumio[1172]: info: [ytmusic] Going to refresh session PO token in 43099 seconds Feb 22 09:39:08 rasp2 volumio[1172]: info: [ytmusic] Obtained PO token for video #14cxcH1eiPc: MlVEs7QObCNSuUSNKKskVqDUpZufeEMafPu2KAIyalMIFBHlvgJU5DXbs_9qalKCh9jZ3urlyvTgXSxgYgZJ03heV_dnBUtX1Z87EBh4zuODL2Oldfqg Feb 22 09:39:08 rasp2 volumio[1172]: info: [ytmusic] Obtained PO token for video #14cxcH1eiPc: MlUa7QMB8Sn7IRrTn6S5XAlM-8Uod94Q1WPodrU991mhjE-7CQ3J7pxD7aHdZc-ILkCHgF3qV_5JxXI-1QnU2dHGCanQCtZdfAcCPnASPo5O_c2zKlnk Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPlay Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreStateMachine::play index 13 Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreStateMachine::stop Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreStateMachine::stPlaybackTimer Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreStateMachine::updateTrackBlock Feb 22 09:39:31 rasp2 volumio[1172]: info: CorePlayQueue::getTrackBlock Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:31 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 1 Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreStateMachine::serviceStop Feb 22 09:39:31 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 1 Feb 22 09:39:31 rasp2 volumio[1172]: info: CoreCommandRouter::serviceStop Feb 22 09:39:31 rasp2 volumio[1172]: info: ControllerMpd::stop Feb 22 09:39:31 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 09:39:32 rasp2 volumio[1172]: info: Feb 22 09:39:32 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:39:32 rasp2 volumio[1172]: info: sendMpdCommand stop took 1030 milliseconds Feb 22 09:39:32 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:39:32 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:39:32 rasp2 volumio[1172]: info: CoreStateMachine::play index undefined Feb 22 09:39:32 rasp2 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 09:39:32 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:32 rasp2 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Feb 22 09:39:32 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:32 rasp2 volumio[1172]: verbose: ControllerMpd::clearAddPlayTracks NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/05 運動会の歌.m4a Feb 22 09:39:32 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 09:39:32 rasp2 volumio[1172]: info: sendMpdCommand status took 19 milliseconds Feb 22 09:39:32 rasp2 volumio[1172]: info: sendMpdCommand stop took 17 milliseconds Feb 22 09:39:32 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:39:32 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:39:32 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 09:39:32 rasp2 volumio[1172]: info: Feb 22 09:39:32 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:39:32 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:39:32 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:39:32 rasp2 volumio[1172]: info: Feb 22 09:39:32 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:39:32 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:39:32 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:39:33 rasp2 volumio[1172]: info: Feb 22 09:39:33 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:39:33 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:39:33 rasp2 volumio[1172]: info: sendMpdCommand playlistinfo took 974 milliseconds Feb 22 09:39:33 rasp2 volumio[1172]: info: sendMpdCommand clear took 973 milliseconds Feb 22 09:39:33 rasp2 volumio[1172]: info: sendMpdCommand status took 970 milliseconds Feb 22 09:39:33 rasp2 volumio[1172]: info: sendMpdCommand status took 968 milliseconds Feb 22 09:39:33 rasp2 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Feb 22 09:39:33 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/05 運動会の歌.m4a" Feb 22 09:39:33 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:39:33 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:39:33 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: 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":"02肢体部「肢体部ソング」","artist":"所沢おおぞら特別支援学校","album":"10周年記念","uri":"NAS/USB/音楽/所沢おおぞら特別支援学校/10周年記念/02 02肢体部「肢体部ソング」.m4a","trackType":"m4a"} Feb 22 09:39:33 rasp2 volumio[1172]: verbose: CURRENT POSITION 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService stop Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:33 rasp2 volumio[1172]: info: No code Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:33 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: 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} Feb 22 09:39:33 rasp2 volumio[1172]: verbose: CURRENT POSITION 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService stop Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:33 rasp2 volumio[1172]: info: No code Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:33 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: 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} Feb 22 09:39:33 rasp2 volumio[1172]: verbose: CURRENT POSITION 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService stop Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:33 rasp2 volumio[1172]: info: No code Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:33 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:33 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:33 rasp2 volumio[1172]: info: ------------------------------ 1284ms Feb 22 09:39:33 rasp2 volumio[1172]: info: ------------------------------ 1259ms Feb 22 09:39:33 rasp2 volumio[1172]: info: ------------------------------ 1258ms Feb 22 09:39:33 rasp2 volumio[1172]: info: Feb 22 09:39:33 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:39:33 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:39:33 rasp2 volumio[1172]: info: Feb 22 09:39:33 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:39:33 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:39:34 rasp2 volumio[1172]: error: updateQueue error: null Feb 22 09:39:34 rasp2 volumio[1172]: info: Feb 22 09:39:34 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:39:34 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:39:34 rasp2 volumio[1172]: info: ------------------------------ 1040ms Feb 22 09:39:34 rasp2 volumio[1172]: info: sendMpdCommand add "NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/05 運動会の歌.m4a" took 1035 milliseconds Feb 22 09:39:34 rasp2 volumio[1172]: info: ------------------------------ 747ms Feb 22 09:39:34 rasp2 volumio[1172]: info: ------------------------------ 744ms Feb 22 09:39:34 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Feb 22 09:39:34 rasp2 volumio[1172]: info: Feb 22 09:39:34 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:39:34 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:39:34 rasp2 volumio[1172]: info: Feb 22 09:39:34 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:39:34 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:39:41 rasp2 volumio[1172]: info: Feb 22 09:39:41 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:39:41 rasp2 volumio[1172]: info: ------------------------------ 7260ms Feb 22 09:39:41 rasp2 volumio[1172]: info: sendMpdCommand play took 7257 milliseconds Feb 22 09:39:41 rasp2 volumio[1172]: info: ------------------------------ 7254ms Feb 22 09:39:41 rasp2 volumio[1172]: info: ------------------------------ 7253ms Feb 22 09:39:41 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:39:41 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:39:42 rasp2 volumio[1172]: info: Feb 22 09:39:42 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:39:42 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:39:42 rasp2 volumio[1172]: info: sendMpdCommand status took 886 milliseconds Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:39:42 rasp2 volumio[1172]: info: Feb 22 09:39:42 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:39:42 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:39:42 rasp2 volumio[1172]: info: Feb 22 09:39:42 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:39:42 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:39:42 rasp2 volumio[1172]: info: sendMpdCommand status took 36 milliseconds Feb 22 09:39:42 rasp2 volumio[1172]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 22 09:39:42 rasp2 volumio[1172]: info: sendMpdCommand status took 31 milliseconds Feb 22 09:39:42 rasp2 volumio[1172]: info: sendMpdCommand status took 29 milliseconds Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:39:42 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:39:42 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:39:42 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:42 rasp2 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6607,"duration":140,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"259 Kbps","isStreaming":false,"title":"運動会の歌","artist":"所沢おおぞら特別支援学校","album":"肢体部 小低 音楽CD 2017年度","uri":"NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/05 運動会の歌.m4a","trackType":"m4a"} Feb 22 09:39:42 rasp2 volumio[1172]: verbose: CURRENT POSITION 13 Feb 22 09:39:42 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService play Feb 22 09:39:42 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Feb 22 09:39:42 rasp2 volumio[1172]: info: ------------------------------ 933ms Feb 22 09:39:42 rasp2 volumio[1172]: info: Feb 22 09:39:42 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:39:42 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:39:42 rasp2 volumio[1172]: info: Feb 22 09:39:42 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:39:42 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:39:42 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:39:43 rasp2 volumio[1172]: info: sendMpdCommand playlistinfo took 944 milliseconds Feb 22 09:39:43 rasp2 volumio[1172]: info: sendMpdCommand playlistinfo took 943 milliseconds Feb 22 09:39:43 rasp2 volumio[1172]: info: sendMpdCommand playlistinfo took 944 milliseconds Feb 22 09:39:43 rasp2 volumio[1172]: info: sendMpdCommand status took 938 milliseconds Feb 22 09:39:43 rasp2 volumio[1172]: info: sendMpdCommand status took 937 milliseconds Feb 22 09:39:43 rasp2 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Feb 22 09:39:43 rasp2 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Feb 22 09:39:43 rasp2 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Feb 22 09:39:43 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:39:43 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:39:43 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:39:43 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:39:43 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:43 rasp2 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7477,"duration":140,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"259 Kbps","isStreaming":false,"title":"運動会の歌","artist":"所沢おおぞら特別支援学校","album":"肢体部 小低 音楽CD 2017年度","uri":"NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/05 運動会の歌.m4a","trackType":"m4a"} Feb 22 09:39:43 rasp2 volumio[1172]: verbose: CURRENT POSITION 13 Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService play Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Feb 22 09:39:43 rasp2 volumio[1172]: info: Received an update from plugin. extracting info from payload Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:43 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:43 rasp2 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7603,"duration":140,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"259 Kbps","isStreaming":false,"title":"運動会の歌","artist":"所沢おおぞら特別支援学校","album":"肢体部 小低 音楽CD 2017年度","uri":"NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/05 運動会の歌.m4a","trackType":"m4a"} Feb 22 09:39:43 rasp2 volumio[1172]: verbose: CURRENT POSITION 13 Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService play Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Feb 22 09:39:43 rasp2 volumio[1172]: info: Received an update from plugin. extracting info from payload Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:43 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:43 rasp2 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7603,"duration":140,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"259 Kbps","isStreaming":false,"title":"運動会の歌","artist":"所沢おおぞら特別支援学校","album":"肢体部 小低 音楽CD 2017年度","uri":"NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/05 運動会の歌.m4a","trackType":"m4a"} Feb 22 09:39:43 rasp2 volumio[1172]: verbose: CURRENT POSITION 13 Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService play Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Feb 22 09:39:43 rasp2 volumio[1172]: info: Received an update from plugin. extracting info from payload Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:43 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:43 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:43 rasp2 volumio[1172]: info: ------------------------------ 1214ms Feb 22 09:39:43 rasp2 volumio[1172]: info: ------------------------------ 1207ms Feb 22 09:39:43 rasp2 volumio[1172]: info: ------------------------------ 1207ms Feb 22 09:39:44 rasp2 volumio[1172]: info: sendMpdCommand playlistinfo took 1108 milliseconds Feb 22 09:39:44 rasp2 volumio[1172]: info: sendMpdCommand playlistinfo took 1108 milliseconds Feb 22 09:39:44 rasp2 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Feb 22 09:39:44 rasp2 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Feb 22 09:39:44 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:39:44 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:44 rasp2 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7603,"duration":140,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"259 Kbps","isStreaming":false,"title":"運動会の歌","artist":"所沢おおぞら特別支援学校","album":"肢体部 小低 音楽CD 2017年度","uri":"NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/05 運動会の歌.m4a","trackType":"m4a"} Feb 22 09:39:44 rasp2 volumio[1172]: verbose: CURRENT POSITION 13 Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService play Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Feb 22 09:39:44 rasp2 volumio[1172]: info: Received an update from plugin. extracting info from payload Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:44 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:44 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:44 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:39:44 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:44 rasp2 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7603,"duration":140,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"259 Kbps","isStreaming":false,"title":"運動会の歌","artist":"所沢おおぞら特別支援学校","album":"肢体部 小低 音楽CD 2017年度","uri":"NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/05 運動会の歌.m4a","trackType":"m4a"} Feb 22 09:39:44 rasp2 volumio[1172]: verbose: CURRENT POSITION 13 Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService play Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Feb 22 09:39:44 rasp2 volumio[1172]: info: Received an update from plugin. extracting info from payload Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:44 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:39:44 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:44 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:39:44 rasp2 volumio[1172]: info: ------------------------------ 2212ms Feb 22 09:39:44 rasp2 volumio[1172]: info: ------------------------------ 2212ms Feb 22 09:39:45 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 09:39:45 rasp2 volumio[1172]: info: Received Get System Info Feb 22 09:39:45 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 09:39:45 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 09:39:45 rasp2 volumio[1172]: info: Discovery: Getting this device information Feb 22 09:39:45 rasp2 volumio[1172]: info: CoreCommandRouter::volumioGetState Feb 22 09:39:45 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:39:45 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 09:39:56 rasp2 volumio[1172]: info: [ytmusic] (好きすぎて滅！) validating stream URL "https://rr4---sn-oguesndr.googlevideo.com/videoplayback?expire=1771742356&ei=NFCaadWNMuXo1d8P_YyMgA0&ip=217.178.25.53&id=o-ADmVdjTaTVr2OOXyXaB71m4paKNf32mLM0Zgqwfro52o&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=966&met=1771720756%2C&mh=xM&mm=31%2C29&mn=sn-oguesndr%2Csn-oguelnz7&ms=au%2Crdu&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=jp&initcwndbps=3762500&bui=AVNa5-y_XwKPOsevTc4bcD9Vo_PjWKhZ_azO2b2UKmcZW_Su5vXCIqGFS9JZNOY1jfrSjO1U4lu1LXhz&spc=6dlaFIvC_vi6DcLY-D78kyvzwHUOt6Yk8K3IL3WreAu7fJPTruna8Flqkz_PQpx2d4s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=uj5RBWibYkMWRLW0PiE-GkMS&rqh=1&gir=yes&clen=3430191&dur=212.421&lmt=1761933360957127&mt=1771720145&fvip=4&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=5532534&n=C54efDQzqCMeJg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAPN0bXuLVr-ciQHSB3d5jSYDmzcfATXkfPTqDmkHCpbEAiBFkPKUHd_hRdL5ya0KO6zqCN_1AsG7uWCeBCNJNkCaiQ%3D%3D&sig=AJEij0EwRQIhAKtSVVKyzCiQsNzkknw8TEl6oVgu3IeeduNTcjn4qJFfAiAZgXL_oFqstv4oJ9o9dnkmAV4-D06UveguN72FSlwgFw%3D%3D&pot=MlVEs7QObCNSuUSNKKskVqDUpZufeEMafPu2KAIyalMIFBHlvgJU5DXbs_9qalKCh9jZ3urlyvTgXSxgYgZJ03heV_dnBUtX1Z87EBh4zuODL2Oldfqg&cver=1.20250219.01.00"... Feb 22 09:39:56 rasp2 volumio[1172]: info: [ytmusic] (好きすぎて滅！) validating stream URL "https://rr4---sn-oguelnz7.googlevideo.com/videoplayback?expire=1771742357&ei=NVCaadWaK4KXvcAP5qLSoAU&ip=217.178.25.53&id=o-AAtcJ6190fr1OB7StYQoiSv63xLVI7Em2wvZPnqYOCm6&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=977&met=1771720757%2C&mh=xM&mm=31%2C26&mn=sn-oguelnz7%2Csn-un57enez&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=jp&initcwndbps=3670000&bui=AVNa5-ymDR8Sj8FlCorBpKLr1j_xXzObji6O0fOK0VIxCFaih3D_FqW8udI6VKiCcknW9Vj-ztoE70al&spc=6dlaFKL35f5E9mUVIWiQpq2cfjnLtnXWN6Dx5LkNlmgQEk1EAylPAU0WwnKFg5F_cvM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5j-7WVuxOEcl3qPlgDCD4AgS&rqh=1&gir=yes&clen=3430191&dur=212.421&lmt=1761933360957127&mt=1771720145&fvip=4&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=5532534&n=_Jwk0G-geW6wtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAOtcS2U6RC8rrvN9sE5gvDZzsAwWKTSXalMjd5rdf7exAiBdYTnfkoTScOI5Ux31OrpiDoBfiF5lYiOTqqPhdAM4KQ%3D%3D&sig=AJEij0EwRQIgFkXC_BZ5__vkkZ9lc8zNZoJkE-KHkDB4oiR_8-ZEB4wCIQCTWhCCLTfbjrSpI71UJuujsCNgTwlABqTZwRw-RFHiqQ%3D%3D&pot=MlUa7QMB8Sn7IRrTn6S5XAlM-8Uod94Q1WPodrU991mhjE-7CQ3J7pxD7aHdZc-ILkCHgF3qV_5JxXI-1QnU2dHGCanQCtZdfAcCPnASPo5O_c2zKlnk&cver=1.20250219.01.00"... Feb 22 09:40:07 rasp2 volumio[1172]: info: [ytmusic] (好きすぎて滅！) stream validated in 10.207s. Feb 22 09:40:07 rasp2 volumio[1172]: info: [ytmusic] (AutoplayManager) Added volumioStateListener Feb 22 09:40:07 rasp2 volumio[1172]: info: [ytmusic] (AutoplayManager) Enabled Feb 22 09:40:07 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 09:40:07 rasp2 wpa_supplicant[1021]: wlan0: CTRL-EVENT-BEACON-LOSS Feb 22 09:40:10 rasp2 volumio[1172]: info: Feb 22 09:40:10 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:40:10 rasp2 volumio[1172]: info: sendMpdCommand stop took 3543 milliseconds Feb 22 09:40:10 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:40:10 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:40:10 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 09:40:10 rasp2 volumio[1172]: info: Feb 22 09:40:10 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:40:10 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:40:10 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:40:10 rasp2 volumio[1172]: info: Feb 22 09:40:10 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:40:10 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:40:10 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:40:12 rasp2 volumio[1172]: info: Feb 22 09:40:12 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:12 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:12 rasp2 volumio[1172]: info: sendMpdCommand status took 1362 milliseconds Feb 22 09:40:12 rasp2 volumio[1172]: info: sendMpdCommand clear took 1362 milliseconds Feb 22 09:40:12 rasp2 volumio[1172]: info: sendMpdCommand status took 1360 milliseconds Feb 22 09:40:12 rasp2 volumio[1172]: info: sendMpdCommand status took 1359 milliseconds Feb 22 09:40:12 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:40:12 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:40:12 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-oguelnz7.googlevideo.com/videoplayback?expire=1771742357&ei=NVCaadWaK4KXvcAP5qLSoAU&ip=217.178.25.53&id=o-AAtcJ6190fr1OB7StYQoiSv63xLVI7Em2wvZPnqYOCm6&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=977&met=1771720757%2C&mh=xM&mm=31%2C26&mn=sn-oguelnz7%2Csn-un57enez&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=jp&initcwndbps=3670000&bui=AVNa5-ymDR8Sj8FlCorBpKLr1j_xXzObji6O0fOK0VIxCFaih3D_FqW8udI6VKiCcknW9Vj-ztoE70al&spc=6dlaFKL35f5E9mUVIWiQpq2cfjnLtnXWN6Dx5LkNlmgQEk1EAylPAU0WwnKFg5F_cvM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5j-7WVuxOEcl3qPlgDCD4AgS&rqh=1&gir=yes&clen=3430191&dur=212.421&lmt=1761933360957127&mt=1771720145&fvip=4&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=5532534&n=_Jwk0G-geW6wtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAOtcS2U6RC8rrvN9sE5gvDZzsAwWKTSXalMjd5rdf7exAiBdYTnfkoTScOI5Ux31OrpiDoBfiF5lYiOTqqPhdAM4KQ%3D%3D&sig=AJEij0EwRQIgFkXC_BZ5__vkkZ9lc8zNZoJkE-KHkDB4oiR_8-ZEB4wCIQCTWhCCLTfbjrSpI71UJuujsCNgTwlABqTZwRw-RFHiqQ%3D%3D&pot=MlUa7QMB8Sn7IRrTn6S5XAlM-8Uod94Q1WPodrU991mhjE-7CQ3J7pxD7aHdZc-ILkCHgF3qV_5JxXI-1QnU2dHGCanQCtZdfAcCPnASPo5O_c2zKlnk&cver=1.20250219.01.00&t.YouTube" Feb 22 09:40:12 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:40:12 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:40:12 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 13 Feb 22 09:40:12 rasp2 volumio[1172]: 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} Feb 22 09:40:12 rasp2 volumio[1172]: verbose: CURRENT POSITION 13 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService stop Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::play index undefined Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 62 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:40:12 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 62 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 62 Feb 22 09:40:12 rasp2 volumio[1172]: 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} Feb 22 09:40:12 rasp2 volumio[1172]: verbose: CURRENT POSITION 62 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService stop Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 62 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:40:12 rasp2 volumio[1172]: info: No code Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 62 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 62 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 62 Feb 22 09:40:12 rasp2 volumio[1172]: verbose: ControllerMpd::clearAddPlayTracks NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小高 音楽CD 2018年度/26 ありがとうの花.m4a Feb 22 09:40:12 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 09:40:12 rasp2 volumio[1172]: info: ------------------------------ 1610ms Feb 22 09:40:12 rasp2 volumio[1172]: info: ------------------------------ 1610ms Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::pushState Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 62 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPushState Feb 22 09:40:12 rasp2 volumio[1172]: info: Feb 22 09:40:12 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:12 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:12 rasp2 volumio[1172]: info: Feb 22 09:40:12 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:12 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreCommandRouter::volumioPlay Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::play index 29 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::stop Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::play index undefined Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 29 Feb 22 09:40:12 rasp2 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Feb 22 09:40:12 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 29 Feb 22 09:40:12 rasp2 volumio[1172]: verbose: ControllerMpd::clearAddPlayTracks NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/21 マイクで歌おう.m4a Feb 22 09:40:12 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 09:40:14 rasp2 volumio[1172]: error: updateQueue error: null Feb 22 09:40:14 rasp2 volumio[1172]: info: Feb 22 09:40:14 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:14 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:14 rasp2 volumio[1172]: info: ------------------------------ 2135ms Feb 22 09:40:14 rasp2 volumio[1172]: info: sendMpdCommand playlistinfo took 1897 milliseconds Feb 22 09:40:14 rasp2 volumio[1172]: info: sendMpdCommand addid "https://rr4---sn-oguelnz7.googlevideo.com/videoplayback?expire=1771742357&ei=NVCaadWaK4KXvcAP5qLSoAU&ip=217.178.25.53&id=o-AAtcJ6190fr1OB7StYQoiSv63xLVI7Em2wvZPnqYOCm6&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=977&met=1771720757%2C&mh=xM&mm=31%2C26&mn=sn-oguelnz7%2Csn-un57enez&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=jp&initcwndbps=3670000&bui=AVNa5-ymDR8Sj8FlCorBpKLr1j_xXzObji6O0fOK0VIxCFaih3D_FqW8udI6VKiCcknW9Vj-ztoE70al&spc=6dlaFKL35f5E9mUVIWiQpq2cfjnLtnXWN6Dx5LkNlmgQEk1EAylPAU0WwnKFg5F_cvM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5j-7WVuxOEcl3qPlgDCD4AgS&rqh=1&gir=yes&clen=3430191&dur=212.421&lmt=1761933360957127&mt=1771720145&fvip=4&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=5532534&n=_Jwk0G-geW6wtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAOtcS2U6RC8rrvN9sE5gvDZzsAwWKTSXalMjd5rdf7exAiBdYTnfkoTScOI5Ux31OrpiDoBfiF5lYiOTqqPhdAM4KQ%3D%3D&sig=AJEij0EwRQIgFkXC_BZ5__vkkZ9lc8zNZoJkE-KHkDB4oiR_8-ZEB4wCIQCTWhCCLTfbjrSpI71UJuujsCNgTwlABqTZwRw-RFHiqQ%3D%3D&pot=MlUa7QMB8Sn7IRrTn6S5XAlM-8Uod94Q1WPodrU991mhjE-7CQ3J7pxD7aHdZc-ILkCHgF3qV_5JxXI-1QnU2dHGCanQCtZdfAcCPnASPo5O_c2zKlnk&cver=1.20250219.01.00&t.YouTube" took 1895 milliseconds Feb 22 09:40:14 rasp2 volumio[1172]: info: sendMpdCommand stop took 1654 milliseconds Feb 22 09:40:14 rasp2 volumio[1172]: info: ------------------------------ 1608ms Feb 22 09:40:14 rasp2 volumio[1172]: info: ------------------------------ 1607ms Feb 22 09:40:14 rasp2 volumio[1172]: info: sendMpdCommand stop took 1592 milliseconds Feb 22 09:40:14 rasp2 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Feb 22 09:40:14 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 09:40:14 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 09:40:14 rasp2 volumio[1172]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 22 09:40:14 rasp2 volumio[1172]: verbose: MPD COMMAND [object Object] Feb 22 09:40:14 rasp2 volumio[1172]: verbose: MPD COMMAND [object Object] Feb 22 09:40:14 rasp2 volumio[1172]: verbose: MPD COMMAND [object Object] Feb 22 09:40:14 rasp2 volumio[1172]: info: ------------------------------ 3281ms Feb 22 09:40:14 rasp2 volumio[1172]: info: Feb 22 09:40:14 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:14 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:14 rasp2 volumio[1172]: info: Feb 22 09:40:14 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:14 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:17 rasp2 volumio[1172]: info: Feb 22 09:40:17 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:17 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:17 rasp2 volumio[1172]: info: Feb 22 09:40:17 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:17 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:17 rasp2 volumio[1172]: info: Feb 22 09:40:17 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:17 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:17 rasp2 volumio[1172]: error: updateQueue error: null Feb 22 09:40:17 rasp2 volumio[1172]: error: updateQueue error: null Feb 22 09:40:17 rasp2 volumio[1172]: info: ------------------------------ 3570ms Feb 22 09:40:17 rasp2 volumio[1172]: info: sendMpdCommand clear took 3559 milliseconds Feb 22 09:40:17 rasp2 volumio[1172]: info: sendMpdCommand clear took 3558 milliseconds Feb 22 09:40:17 rasp2 volumio[1172]: info: ------------------------------ 3552ms Feb 22 09:40:17 rasp2 volumio[1172]: info: ------------------------------ 3550ms Feb 22 09:40:17 rasp2 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 22 09:40:17 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Feb 22 09:40:17 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小高 音楽CD 2018年度/26 ありがとうの花.m4a" Feb 22 09:40:17 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/21 マイクで歌おう.m4a" Feb 22 09:40:17 rasp2 volumio[1172]: info: Feb 22 09:40:17 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:17 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:17 rasp2 volumio[1172]: info: Feb 22 09:40:17 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:17 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:19 rasp2 volumio[1172]: error: updateQueue error: null Feb 22 09:40:19 rasp2 volumio[1172]: error: updateQueue error: null Feb 22 09:40:19 rasp2 volumio[1172]: error: updateQueue error: null Feb 22 09:40:19 rasp2 volumio[1172]: info: Feb 22 09:40:19 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:19 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:19 rasp2 volumio[1172]: info: Feb 22 09:40:19 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:19 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:19 rasp2 volumio[1172]: info: ------------------------------ 1551ms Feb 22 09:40:19 rasp2 volumio[1172]: info: ------------------------------ 1550ms Feb 22 09:40:19 rasp2 volumio[1172]: info: ------------------------------ 1549ms Feb 22 09:40:19 rasp2 volumio[1172]: info: sendMpdCommand play took 1542 milliseconds Feb 22 09:40:19 rasp2 volumio[1172]: info: sendMpdCommand add "NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小高 音楽CD 2018年度/26 ありがとうの花.m4a" took 1542 milliseconds Feb 22 09:40:19 rasp2 volumio[1172]: info: sendMpdCommand add "NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小低 音楽CD 2017年度/21 マイクで歌おう.m4a" took 1541 milliseconds Feb 22 09:40:19 rasp2 volumio[1172]: info: ------------------------------ 1540ms Feb 22 09:40:19 rasp2 volumio[1172]: info: ------------------------------ 1538ms Feb 22 09:40:19 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Feb 22 09:40:19 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Feb 22 09:40:19 rasp2 volumio[1172]: info: Feb 22 09:40:19 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:19 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:19 rasp2 volumio[1172]: info: Feb 22 09:40:19 rasp2 volumio[1172]: ---------------------------- MPD announces system playlist update Feb 22 09:40:19 rasp2 volumio[1172]: info: Ignoring MPD Status Update Feb 22 09:40:26 rasp2 volumio[1172]: info: Feb 22 09:40:26 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:40:26 rasp2 volumio[1172]: info: ------------------------------ 7452ms Feb 22 09:40:26 rasp2 volumio[1172]: info: ------------------------------ 7452ms Feb 22 09:40:26 rasp2 volumio[1172]: info: sendMpdCommand play took 7446 milliseconds Feb 22 09:40:26 rasp2 volumio[1172]: info: sendMpdCommand play took 7445 milliseconds Feb 22 09:40:26 rasp2 volumio[1172]: info: ------------------------------ 7417ms Feb 22 09:40:26 rasp2 volumio[1172]: info: ------------------------------ 7415ms Feb 22 09:40:26 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:40:26 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:40:29 rasp2 volumio[1172]: info: Feb 22 09:40:29 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:40:29 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:40:29 rasp2 volumio[1172]: info: sendMpdCommand status took 2953 milliseconds Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:40:29 rasp2 volumio[1172]: info: Feb 22 09:40:29 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:40:29 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:40:29 rasp2 volumio[1172]: info: Feb 22 09:40:29 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:40:29 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:40:29 rasp2 volumio[1172]: info: sendMpdCommand status took 31 milliseconds Feb 22 09:40:29 rasp2 volumio[1172]: info: sendMpdCommand playlistinfo took 28 milliseconds Feb 22 09:40:29 rasp2 volumio[1172]: info: sendMpdCommand status took 25 milliseconds Feb 22 09:40:29 rasp2 volumio[1172]: info: sendMpdCommand status took 22 milliseconds Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::parseState Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 09:40:29 rasp2 volumio[1172]: info: ControllerMpd::pushState Feb 22 09:40:29 rasp2 volumio[1172]: info: CoreCommandRouter::servicePushState Feb 22 09:40:29 rasp2 volumio[1172]: info: CorePlayQueue::getTrack 29 Feb 22 09:40:29 rasp2 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7351,"duration":174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"259 Kbps","isStreaming":false,"title":"ありがとうの花","artist":"所沢おおぞら特別支援学校","album":"肢体部 小高 音楽CD 2018年度","uri":"NAS/USB/音楽/所沢おおぞら特別支援学校/肢体部 小高 音楽CD 2018年度/26 ありがとうの花.m4a","trackType":"m4a"} Feb 22 09:40:29 rasp2 volumio[1172]: verbose: CURRENT POSITION 29 Feb 22 09:40:29 rasp2 volumio[1172]: info: CoreStateMachine::syncState stateService play Feb 22 09:40:29 rasp2 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Feb 22 09:40:29 rasp2 volumio[1172]: info: ------------------------------ 3007ms Feb 22 09:40:29 rasp2 volumio[1172]: info: Feb 22 09:40:29 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:40:29 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:40:29 rasp2 volumio[1172]: info: Feb 22 09:40:29 rasp2 volumio[1172]: ---------------------------- MPD announces state update: player Feb 22 09:40:29 rasp2 volumio[1172]: info: ControllerMpd::getState Feb 22 09:40:29 rasp2 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Feb 22 09:40:29 rasp2 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Feb 22 09:40:29 rasp2 volumio[1172]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 09:40:29 rasp2 volumio[1172]: TypeError: fetch failed Feb 22 09:40:29 rasp2 volumio[1172]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Feb 22 09:40:29 rasp2 volumio[1172]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 09:40:29 rasp2 volumio[1172]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 09:40:29 rasp2 volumio[1172]: at process.processImmediate (node:internal/timers:449:9) Feb 22 09:40:29 rasp2 volumio[1172]: at async HTTPClient.fetch (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:2449:22) Feb 22 09:40:29 rasp2 volumio[1172]: at async Actions.stats (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:33591:12) Feb 22 09:40:29 rasp2 volumio[1172]: at async TrackInfo.addToWatchHistory (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:30717:12) { Feb 22 09:40:29 rasp2 volumio[1172]: cause: ConnectTimeoutError: Connect Timeout Error Feb 22 09:40:29 rasp2 volumio[1172]: at onConnectTimeout (node:internal/deps/undici/undici:8522:28) Feb 22 09:40:29 rasp2 volumio[1172]: at node:internal/deps/undici/undici:8480:50 Feb 22 09:40:29 rasp2 volumio[1172]: at Immediate._onImmediate (node:internal/deps/undici/undici:8511:13) Feb 22 09:40:29 rasp2 volumio[1172]: at process.processImmediate (node:internal/timers:478:21) { Feb 22 09:40:29 rasp2 volumio[1172]: code: 'UND_ERR_CONNECT_TIMEOUT' Feb 22 09:40:29 rasp2 volumio[1172]: } Feb 22 09:40:29 rasp2 volumio[1172]: } Feb 22 09:40:29 rasp2 volumio[1172]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 09:40:31 rasp2 sudo[2531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 09:39' Feb 22 09:40:31 rasp2 sudo[2531]: 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="f8b25437696d030ba01136ff33a129a42b97a4aa" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Feb 10 17:57:40 UTC 2026" VOLUMIO_VERSION="4.097" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67b01b7f4046986651bf7f5bfadaefdf"