-- Logs begin at Fri 2026-10-02 21:59:42 CST, end at Fri 2026-10-02 22:26:15 CST. -- Oct 02 22:25:00 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:00 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:00 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:01 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:01 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:01 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:02 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:02 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:02 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:04 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:04 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:04 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:05 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:05 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:05 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:06 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:06 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:06 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:07 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:07 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:07 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:08 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:08 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:08 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:09 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:09 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:09 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:11 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:11 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:11 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:12 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:12 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:12 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:13 smsl-n100 volumio[941]: info: Oct 02 22:25:13 smsl-n100 volumio[941]: ---------------------------- MPD announces state update: player Oct 02 22:25:13 smsl-n100 volumio[941]: info: ControllerMpd::getState Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand status Oct 02 22:25:13 smsl-n100 volumio[941]: info: sendMpdCommand status took 3 milliseconds Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::parseState Oct 02 22:25:13 smsl-n100 volumio[941]: info: ControllerMpd::pushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::servicePushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:13 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:13 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 13 Oct 02 22:25:13 smsl-n100 volumio[941]: 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} Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: CURRENT POSITION 13 Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::syncState stateService stop Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::syncState currentStatus play Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::play index undefined Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:13 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:13 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::startPlaybackTimer Oct 02 22:25:13 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::clearAddPlayTracks USB/thinkplus/music/小林愛香 _ 高槻加奈子 _ 小宮有紗 _ 降幡愛 _ 伊波杏樹 _ 斉藤朱夏 _ 諏訪ななか _ 逢田梨香子 _ 鈴木愛奈 - La la 勇気のうた.ogg Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 22:25:13 smsl-n100 volumio[941]: info: ------------------------------ 78ms Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:13 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:13 smsl-n100 volumio[941]: info: sendMpdCommand stop took 30 milliseconds Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Oct 02 22:25:13 smsl-n100 volumio[941]: ---------------------------- MPD announces system playlist update Oct 02 22:25:13 smsl-n100 volumio[941]: info: Ignoring MPD Status Update Oct 02 22:25:13 smsl-n100 volumio[941]: info: sendMpdCommand clear took 60 milliseconds Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand add "USB/thinkplus/music/小林愛香 _ 高槻加奈子 _ 小宮有紗 _ 降幡愛 _ 伊波杏樹 _ 斉藤朱夏 _ 諏訪ななか _ 逢田梨香子 _ 鈴木愛奈 - La la 勇気のうた.ogg" Oct 02 22:25:13 smsl-n100 volumio[941]: error: updateQueue error: null Oct 02 22:25:13 smsl-n100 volumio[941]: info: Oct 02 22:25:13 smsl-n100 volumio[941]: ---------------------------- MPD announces system playlist update Oct 02 22:25:13 smsl-n100 volumio[941]: info: Ignoring MPD Status Update Oct 02 22:25:13 smsl-n100 volumio[941]: info: ------------------------------ 13ms Oct 02 22:25:13 smsl-n100 volumio[941]: info: sendMpdCommand add "USB/thinkplus/music/小林愛香 _ 高槻加奈子 _ 小宮有紗 _ 降幡愛 _ 伊波杏樹 _ 斉藤朱夏 _ 諏訪ななか _ 逢田梨香子 _ 鈴木愛奈 - La la 勇気のうた.ogg" took 9 milliseconds Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand play Oct 02 22:25:13 smsl-n100 volumio[941]: info: ------------------------------ 10ms Oct 02 22:25:13 smsl-n100 volumio[941]: info: sendMpdCommand play took 5 milliseconds Oct 02 22:25:13 smsl-n100 volumio[941]: info: Oct 02 22:25:13 smsl-n100 volumio[941]: ---------------------------- MPD announces state update: player Oct 02 22:25:13 smsl-n100 volumio[941]: info: ControllerMpd::getState Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand status Oct 02 22:25:13 smsl-n100 volumio[941]: info: Oct 02 22:25:13 smsl-n100 volumio[941]: ---------------------------- MPD announces state update: player Oct 02 22:25:13 smsl-n100 volumio[941]: info: sendMpdCommand status took 6 milliseconds Oct 02 22:25:13 smsl-n100 volumio[941]: info: ControllerMpd::getState Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand status Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::parseState Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 22:25:13 smsl-n100 volumio[941]: info: sendMpdCommand status took 10 milliseconds Oct 02 22:25:13 smsl-n100 volumio[941]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::parseState Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::parseTrackInfo Oct 02 22:25:13 smsl-n100 volumio[941]: info: ControllerMpd::pushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::servicePushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 ヨハネ(CV.小林愛香), ハナマル(CV.高槻かなこ), ダイヤ(CV.小宮有紗), ルビィ(CV.降幡 愛), チカ(CV.伊波杏樹) & ヨウ(CV.斉藤朱夏) - La la 勇気のうた","artist":"小林愛香","album":null,"uri":"USB/thinkplus/music/小林愛香 _ 高槻加奈子 _ 小宮有紗 _ 降幡愛 _ 伊波杏樹 _ 斉藤朱夏 _ 諏訪ななか _ 逢田梨香子 _ 鈴木愛奈 - La la 勇気のうた.ogg","trackType":"ogg"} Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: CURRENT POSITION 14 Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::syncState stateService play Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::syncState currentStatus stop Oct 02 22:25:13 smsl-n100 volumio[941]: info: ------------------------------ 43ms Oct 02 22:25:13 smsl-n100 volumio[941]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: ControllerMpd::parseTrackInfo Oct 02 22:25:13 smsl-n100 volumio[941]: info: ControllerMpd::pushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::servicePushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 ヨハネ(CV.小林愛香), ハナマル(CV.高槻かなこ), ダイヤ(CV.小宮有紗), ルビィ(CV.降幡 愛), チカ(CV.伊波杏樹) & ヨウ(CV.斉藤朱夏) - La la 勇気のうた","artist":"小林愛香","album":null,"uri":"USB/thinkplus/music/小林愛香 _ 高槻加奈子 _ 小宮有紗 _ 降幡愛 _ 伊波杏樹 _ 斉藤朱夏 _ 諏訪ななか _ 逢田梨香子 _ 鈴木愛奈 - La la 勇気のうた.ogg","trackType":"ogg"} Oct 02 22:25:13 smsl-n100 volumio[941]: verbose: CURRENT POSITION 14 Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::syncState stateService play Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::syncState currentStatus play Oct 02 22:25:13 smsl-n100 volumio[941]: info: Received an update from plugin. extracting info from payload Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:13 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:13 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:13 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:13 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:13 smsl-n100 volumio[941]: info: ------------------------------ 114ms Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:13 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:14 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:14 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:14 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:15 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:15 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:15 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:16 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:16 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:16 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:17 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 22:25:17 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:17 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:17 smsl-n100 volumio[941]: verbose: New Socket.io Connection to 192.168.0.11 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Oct 02 22:25:18 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 22:25:18 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 22:25:18 smsl-n100 volumio[941]: info: Discovery: Getting this device information Oct 02 22:25:18 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:18 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:18 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 22:25:18 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:18 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:18 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 22:25:18 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 22:25:19 smsl-n100 volumio[941]: verbose: New Socket.io Connection to 192.168.0.11 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Oct 02 22:25:19 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 22:25:19 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:19 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:19 smsl-n100 volumio[941]: verbose: New Socket.io Connection to 192.168.0.11 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:19 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 22:25:19 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 22:25:19 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:20 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 22:25:20 smsl-n100 volumio[941]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 02 22:25:20 smsl-n100 volumio[941]: info: Received Get System Info Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 22:25:20 smsl-n100 volumio[941]: info: Discovery: Getting this device information Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:20 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:20 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:20 smsl-n100 volumio[941]: info: Listing playlists Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetQueue Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreStateMachine::getQueue Oct 02 22:25:20 smsl-n100 volumio[941]: info: CorePlayQueue::getQueue Oct 02 22:25:20 smsl-n100 volumio[941]: info: Received Get System Info Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 22:25:20 smsl-n100 volumio[941]: info: Discovery: Getting this device information Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:20 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 22:25:20 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:20 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:21 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 22:25:21 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:21 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:21 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:22 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:22 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:22 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:24 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:24 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:24 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:26 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:26 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:26 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:27 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:27 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:27 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:28 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:28 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:28 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:29 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 02 22:25:29 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:29 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:29 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:29 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 02 22:25:30 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:30 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:30 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:31 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:31 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:31 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:32 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:32 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:32 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:34 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:34 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:34 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:35 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:35 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:35 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:36 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:36 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:36 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:37 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:37 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:37 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:38 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:38 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:38 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:39 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:39 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:39 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:40 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:40 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:40 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:42 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:42 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:42 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:43 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:43 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:43 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:44 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:44 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:44 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:46 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 22:25:46 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:46 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPlay Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreStateMachine::play index 20 Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreStateMachine::stop Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreStateMachine::stPlaybackTimer Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreStateMachine::updateTrackBlock Oct 02 22:25:52 smsl-n100 volumio[941]: info: CorePlayQueue::getTrackBlock Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:52 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:52 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:52 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreStateMachine::serviceStop Oct 02 22:25:52 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 14 Oct 02 22:25:52 smsl-n100 volumio[941]: info: CoreCommandRouter::serviceStop Oct 02 22:25:52 smsl-n100 volumio[941]: info: ControllerMpd::stop Oct 02 22:25:52 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Oct 02 22:25:53 smsl-n100 volumio[941]: ---------------------------- MPD announces state update: player Oct 02 22:25:53 smsl-n100 volumio[941]: info: sendMpdCommand stop took 998 milliseconds Oct 02 22:25:53 smsl-n100 volumio[941]: info: ControllerMpd::getState Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand status Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreStateMachine::play index undefined Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 22:25:53 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreStateMachine::startPlaybackTimer Oct 02 22:25:53 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::clearAddPlayTracks USB/thinkplus/music/折戸伸治 - 散花.mp3 Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 22:25:53 smsl-n100 volumio[941]: info: sendMpdCommand status took 18 milliseconds Oct 02 22:25:53 smsl-n100 volumio[941]: info: sendMpdCommand stop took 11 milliseconds Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::parseState Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 22:25:53 smsl-n100 volumio[941]: info: Oct 02 22:25:53 smsl-n100 volumio[941]: ---------------------------- MPD announces system playlist update Oct 02 22:25:53 smsl-n100 volumio[941]: info: Ignoring MPD Status Update Oct 02 22:25:53 smsl-n100 volumio[941]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 02 22:25:53 smsl-n100 volumio[941]: info: sendMpdCommand clear took 30 milliseconds Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::parseTrackInfo Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand add "USB/thinkplus/music/折戸伸治 - 散花.mp3" Oct 02 22:25:53 smsl-n100 volumio[941]: info: ControllerMpd::pushState Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreCommandRouter::servicePushState Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:53 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:53 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:53 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:53 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:53 smsl-n100 volumio[941]: 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":"01 ヨハネ(CV.小林愛香), ハナマル(CV.高槻かなこ), ダイヤ(CV.小宮有紗), ルビィ(CV.降幡 愛), チカ(CV.伊波杏樹) & ヨウ(CV.斉藤朱夏) - La la 勇気のうた","artist":"小林愛香","album":null,"uri":"USB/thinkplus/music/小林愛香 _ 高槻加奈子 _ 小宮有紗 _ 降幡愛 _ 伊波杏樹 _ 斉藤朱夏 _ 諏訪ななか _ 逢田梨香子 _ 鈴木愛奈 - La la 勇気のうた.ogg","trackType":"ogg"} Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: CURRENT POSITION 20 Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreStateMachine::syncState stateService stop Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreStateMachine::syncState currentStatus stop Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:53 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:53 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:53 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:53 smsl-n100 volumio[941]: info: No code Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:53 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:53 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:53 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:53 smsl-n100 volumio[941]: info: ------------------------------ 130ms Oct 02 22:25:53 smsl-n100 volumio[941]: error: updateQueue error: null Oct 02 22:25:53 smsl-n100 volumio[941]: info: Oct 02 22:25:53 smsl-n100 volumio[941]: ---------------------------- MPD announces system playlist update Oct 02 22:25:53 smsl-n100 volumio[941]: info: Ignoring MPD Status Update Oct 02 22:25:53 smsl-n100 volumio[941]: info: ------------------------------ 118ms Oct 02 22:25:53 smsl-n100 volumio[941]: info: sendMpdCommand add "USB/thinkplus/music/折戸伸治 - 散花.mp3" took 112 milliseconds Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand play Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:53 smsl-n100 volumio[941]: info: Oct 02 22:25:53 smsl-n100 volumio[941]: ---------------------------- MPD announces state update: player Oct 02 22:25:53 smsl-n100 volumio[941]: info: ------------------------------ 61ms Oct 02 22:25:53 smsl-n100 volumio[941]: info: sendMpdCommand play took 58 milliseconds Oct 02 22:25:53 smsl-n100 volumio[941]: info: ControllerMpd::getState Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand status Oct 02 22:25:53 smsl-n100 volumio[941]: info: Oct 02 22:25:53 smsl-n100 volumio[941]: ---------------------------- MPD announces state update: player Oct 02 22:25:53 smsl-n100 volumio[941]: info: ControllerMpd::getState Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand status Oct 02 22:25:53 smsl-n100 volumio[941]: info: sendMpdCommand status took 18 milliseconds Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::parseState Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:53 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:53 smsl-n100 volumio[941]: info: sendMpdCommand status took 35 milliseconds Oct 02 22:25:53 smsl-n100 volumio[941]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::parseState Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: ControllerMpd::parseTrackInfo Oct 02 22:25:53 smsl-n100 volumio[941]: info: ControllerMpd::pushState Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreCommandRouter::servicePushState Oct 02 22:25:53 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"折戸伸治 - 散花.mp3","artist":null,"album":null,"uri":"USB/thinkplus/music/折戸伸治 - 散花.mp3","trackType":"mp3"} Oct 02 22:25:53 smsl-n100 volumio[941]: verbose: CURRENT POSITION 20 Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreStateMachine::syncState stateService play Oct 02 22:25:53 smsl-n100 volumio[941]: info: CoreStateMachine::syncState currentStatus stop Oct 02 22:25:53 smsl-n100 volumio[941]: info: ------------------------------ 74ms Oct 02 22:25:54 smsl-n100 volumio[941]: info: sendMpdCommand playlistinfo took 1049 milliseconds Oct 02 22:25:54 smsl-n100 volumio[941]: verbose: ControllerMpd::parseTrackInfo Oct 02 22:25:54 smsl-n100 volumio[941]: info: ControllerMpd::pushState Oct 02 22:25:54 smsl-n100 volumio[941]: info: CoreCommandRouter::servicePushState Oct 02 22:25:54 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:54 smsl-n100 volumio[941]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":216,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"折戸伸治 - 散花.mp3","artist":null,"album":null,"uri":"USB/thinkplus/music/折戸伸治 - 散花.mp3","trackType":"mp3"} Oct 02 22:25:54 smsl-n100 volumio[941]: verbose: CURRENT POSITION 20 Oct 02 22:25:54 smsl-n100 volumio[941]: info: CoreStateMachine::syncState stateService play Oct 02 22:25:54 smsl-n100 volumio[941]: info: CoreStateMachine::syncState currentStatus play Oct 02 22:25:54 smsl-n100 volumio[941]: info: Received an update from plugin. extracting info from payload Oct 02 22:25:54 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:54 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:54 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 22:25:54 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:54 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:54 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:54 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:25:54 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:54 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:25:54 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:25:54 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:25:54 smsl-n100 volumio[941]: info: ------------------------------ 1154ms Oct 02 22:25:55 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:55 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:55 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:55 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:55 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:55 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:55 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:55 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:25:57 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 22:25:58 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:58 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:25:59 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 22:25:59 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:25:59 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:00 smsl-n100 ntpd[10846]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 02 22:26:01 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , setInfinityPlayback Oct 02 22:26:01 smsl-n100 volumio[941]: info: METAVOLUMIO: Enabling Infinity Playback Oct 02 22:26:01 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:26:01 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:01 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 22:26:01 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioRandom Oct 02 22:26:01 smsl-n100 volumio[941]: info: CoreCommandRouter::writePlayerControls Oct 02 22:26:01 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:01 smsl-n100 volumio[941]: info: CoreStateMachine::setRepeat false single false Oct 02 22:26:01 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:26:01 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:01 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 22:26:01 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:26:01 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:26:01 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:26:01 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:26:01 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:26:01 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:26:01 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:26:01 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 22:26:02 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:26:02 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:05 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioRandom Oct 02 22:26:05 smsl-n100 volumio[941]: info: CoreCommandRouter::writePlayerControls Oct 02 22:26:05 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:05 smsl-n100 volumio[941]: info: CoreStateMachine::setRandom true Oct 02 22:26:05 smsl-n100 volumio[941]: info: CoreStateMachine::pushState Oct 02 22:26:05 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:05 smsl-n100 volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 22:26:05 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioPushState Oct 02 22:26:05 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output update for this device Oct 02 22:26:05 smsl-n100 volumio[941]: info: MRS: Pushing multiroomSync output Oct 02 22:26:05 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 22:26:05 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:26:05 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:26:05 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:26:05 smsl-n100 volumio[941]: info: Updating RAAT Signal Path Oct 02 22:26:05 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:26:05 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:07 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 22:26:08 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:26:08 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:09 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 22:26:09 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:26:09 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:11 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 22:26:11 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:26:11 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:12 smsl-n100 volumio[941]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 22:26:12 smsl-n100 volumio[941]: info: CoreCommandRouter::volumioGetState Oct 02 22:26:12 smsl-n100 volumio[941]: info: CorePlayQueue::getTrack 20 Oct 02 22:26:13 smsl-n100 volumio[941]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 22:26:13 smsl-n100 volumio[941]: TypeError: Cannot read property 'slice' of null Oct 02 22:26:13 smsl-n100 volumio[941]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 02 22:26:13 smsl-n100 volumio[941]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 02 22:26:13 smsl-n100 volumio[941]: at Object.onceWrapper (events.js:520:26) Oct 02 22:26:13 smsl-n100 volumio[941]: at ClientRequest.emit (events.js:400:28) Oct 02 22:26:13 smsl-n100 volumio[941]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Oct 02 22:26:13 smsl-n100 volumio[941]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Oct 02 22:26:13 smsl-n100 volumio[941]: at TLSSocket.socketOnData (_http_client.js:515:22) Oct 02 22:26:13 smsl-n100 volumio[941]: at TLSSocket.emit (events.js:400:28) Oct 02 22:26:13 smsl-n100 volumio[941]: at addChunk (internal/streams/readable.js:293:12) Oct 02 22:26:13 smsl-n100 volumio[941]: at readableAddChunk (internal/streams/readable.js:267:9) Oct 02 22:26:13 smsl-n100 volumio[941]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Oct 02 22:26:13 smsl-n100 volumio[941]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Oct 02 22:26:13 smsl-n100 volumio[941]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 22:26:15 smsl-n100 sudo[11696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-02 22:25 Oct 02 22:26:15 smsl-n100 sudo[11696]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 13 Nov 2025 12:39:08 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="7a92d73f7a04bc7854b1812143743fd4"