-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Fri 2025-05-02 23:11:45 +07. -- May 02 23:10:03 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 02 23:10:03 smsl-n100 volumio[945]: info: browseTIDALUri took 210 milliseconds May 02 23:10:03 smsl-n100 volumio[945]: info: Preload queue cleared May 02 23:10:03 smsl-n100 volumio[945]: info: Preload queue cleared May 02 23:10:15 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 02 23:10:15 smsl-n100 volumio[945]: info: browseTIDALUri took 222 milliseconds May 02 23:10:15 smsl-n100 volumio[945]: info: Preload queue cleared May 02 23:10:15 smsl-n100 volumio[945]: info: Preload queue cleared May 02 23:10:19 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 02 23:10:19 smsl-n100 volumio[945]: info: browseTIDALUri took 589 milliseconds May 02 23:10:19 smsl-n100 volumio[945]: info: Preload queue cleared May 02 23:10:19 smsl-n100 volumio[945]: info: Preload queue cleared May 02 23:10:30 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 02 23:10:30 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 23:10:30 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 23:10:30 smsl-n100 volumio[945]: info: Discovery: Getting this device information May 02 23:10:30 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioGetState May 02 23:10:30 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:30 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 23:10:31 smsl-n100 volumio[945]: info: browseTIDALUri took 859 milliseconds May 02 23:10:31 smsl-n100 volumio[945]: info: Preload queue cleared May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/352421939 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/120272 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/73029243 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/1630079 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/64872226 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/87118485 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/313954110 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/270386428 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/164562 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/335964430 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/111213080 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/3120614 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/139906856 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/4098386 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/78447274 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/80389201 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/21398046 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/77633679 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/87132901 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/116394791 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/103436452 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/7928393 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/233329783 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/46816832 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/88039320 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/420815622 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/88039319 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/122874 May 02 23:10:31 smsl-n100 volumio[945]: info: Preloading song: tidal://song/334888776 May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/352421939 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/120272 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/73029243 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/1630079 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/64872226 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/87118485 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/313954110 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/270386428 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/164562 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/335964430 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/111213080 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: explodeTIDALUri took 518 milliseconds May 02 23:10:31 smsl-n100 volumio[945]: info: explodeTIDALUri took 476 milliseconds May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/3120614 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: explodeTIDALUri took 484 milliseconds May 02 23:10:31 smsl-n100 volumio[945]: info: Exploding uri tidal://song/139906856 in service tidal May 02 23:10:31 smsl-n100 volumio[945]: info: explodeTIDALUri took 496 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/4098386 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 470 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/78447274 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 505 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/80389201 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/21398046 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/77633679 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 579 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 496 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/87132901 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/116394791 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 524 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/103436452 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/7928393 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 507 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 597 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/233329783 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 532 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 498 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/46816832 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/88039320 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 545 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/420815622 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 539 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 962 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/88039319 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/122874 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 575 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 559 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: Exploding uri tidal://song/334888776 in service tidal May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 483 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 593 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 492 milliseconds May 02 23:10:32 smsl-n100 volumio[945]: info: explodeTIDALUri took 499 milliseconds May 02 23:10:33 smsl-n100 volumio[945]: info: explodeTIDALUri took 502 milliseconds May 02 23:10:33 smsl-n100 volumio[945]: info: explodeTIDALUri took 565 milliseconds May 02 23:10:33 smsl-n100 volumio[945]: info: explodeTIDALUri took 557 milliseconds May 02 23:10:33 smsl-n100 volumio[945]: info: explodeTIDALUri took 503 milliseconds May 02 23:10:33 smsl-n100 volumio[945]: info: explodeTIDALUri took 525 milliseconds May 02 23:10:33 smsl-n100 volumio[945]: info: explodeTIDALUri took 571 milliseconds May 02 23:10:33 smsl-n100 volumio[945]: info: Preload queue cleared May 02 23:10:33 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioReplaceandPlayItems May 02 23:10:33 smsl-n100 volumio[945]: info: CoreStateMachine::ClearQueue May 02 23:10:33 smsl-n100 volumio[945]: info: CoreStateMachine::stop May 02 23:10:33 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:10:33 smsl-n100 volumio[945]: info: CorePlayQueue::clearPlayQueue May 02 23:10:33 smsl-n100 volumio[945]: info: CorePlayQueue::saveQueue May 02 23:10:33 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushQueue May 02 23:10:33 smsl-n100 volumio[945]: info: CoreStateMachine::addQueueItems May 02 23:10:33 smsl-n100 volumio[945]: info: CorePlayQueue::addQueueItems May 02 23:10:33 smsl-n100 volumio[945]: info: Preload queue cleared May 02 23:10:33 smsl-n100 volumio[945]: info: Adding Item to queue: tidal://playlist/bd5bcbf6-b4cd-47c3-8f2f-6da305c87e22 May 02 23:10:33 smsl-n100 volumio[945]: info: Exploding uri tidal://playlist/bd5bcbf6-b4cd-47c3-8f2f-6da305c87e22 in service tidal May 02 23:10:33 smsl-n100 volumio[945]: info: explodeTIDALUri took 934 milliseconds May 02 23:10:34 smsl-n100 volumio[945]: info: explodeTIDALUri took 1181 milliseconds May 02 23:10:34 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushQueue May 02 23:10:34 smsl-n100 volumio[945]: info: CorePlayQueue::saveQueue May 02 23:10:34 smsl-n100 volumio[945]: info: CoreStateMachine::updateTrackBlock May 02 23:10:34 smsl-n100 volumio[945]: info: CorePlayQueue::getTrackBlock May 02 23:10:34 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPlay May 02 23:10:34 smsl-n100 volumio[945]: info: CoreStateMachine::play index 0 May 02 23:10:34 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:10:34 smsl-n100 volumio[945]: info: CoreStateMachine::stop May 02 23:10:34 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:10:34 smsl-n100 volumio[945]: info: CoreStateMachine::play index undefined May 02 23:10:34 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:10:34 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:34 smsl-n100 volumio[945]: info: CoreStateMachine::startPlaybackTimer May 02 23:10:34 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:34 smsl-n100 volumio[945]: info: [1746202234865] ControllerTidal::clearAddPlayTrack May 02 23:10:34 smsl-n100 volumio[945]: info: Getting stream with soundQuality HI_RES May 02 23:10:34 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand stop May 02 23:10:34 smsl-n100 volumio[945]: info: sendMpdCommand stop took 16 milliseconds May 02 23:10:34 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand clear May 02 23:10:34 smsl-n100 volumio[945]: info: May 02 23:10:34 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:10:34 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:10:34 smsl-n100 volumio[945]: info: sendMpdCommand clear took 7 milliseconds May 02 23:10:34 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES" May 02 23:10:34 smsl-n100 volumio[945]: info: May 02 23:10:34 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:10:34 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:10:34 smsl-n100 volumio[945]: info: May 02 23:10:34 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:10:34 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:10:34 smsl-n100 volumio[945]: info: May 02 23:10:34 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:10:34 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:10:34 smsl-n100 volumio[945]: info: May 02 23:10:34 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:10:34 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:10:34 smsl-n100 volumio[945]: error: updateQueue error: null May 02 23:10:34 smsl-n100 volumio[945]: info: May 02 23:10:34 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:10:34 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:10:34 smsl-n100 volumio[945]: info: ------------------------------ 32ms May 02 23:10:34 smsl-n100 volumio[945]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES" took 27 milliseconds May 02 23:10:34 smsl-n100 volumio[945]: info: ------------------------------ 26ms May 02 23:10:34 smsl-n100 volumio[945]: info: ------------------------------ 23ms May 02 23:10:34 smsl-n100 volumio[945]: info: ------------------------------ 21ms May 02 23:10:34 smsl-n100 volumio[945]: info: ------------------------------ 18ms May 02 23:10:34 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 23:10:34 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand play May 02 23:10:34 smsl-n100 volumio[945]: info: May 02 23:10:34 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:10:34 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:10:34 smsl-n100 volumio[945]: info: May 02 23:10:34 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:10:34 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:10:34 smsl-n100 volumio[945]: info: May 02 23:10:34 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:10:34 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:10:34 smsl-n100 volumio[945]: info: May 02 23:10:34 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:10:34 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:10:34 smsl-n100 volumio[945]: info: ------------------------------ 41ms May 02 23:10:34 smsl-n100 volumio[945]: info: sendMpdCommand play took 28 milliseconds May 02 23:10:34 smsl-n100 volumio[945]: info: ------------------------------ 27ms May 02 23:10:34 smsl-n100 volumio[945]: info: ------------------------------ 24ms May 02 23:10:34 smsl-n100 volumio[945]: info: ------------------------------ 15ms May 02 23:10:34 smsl-n100 volumio[945]: info: ------------------------------ 12ms May 02 23:10:34 smsl-n100 volumio[945]: STREAMING PROXY: Handling url /?data=tidal://song/352421939&soundQuality=HI_RES May 02 23:10:35 smsl-n100 volumio[945]: info: Executing endpoint getStreamUrltidal May 02 23:10:35 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 02 23:10:35 smsl-n100 volumio[945]: info: getStreamUrl took 506 milliseconds May 02 23:10:35 smsl-n100 volumio[945]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKAgDEiQ3NDcxNjNjYTZiZWU5ZDc5MTM1YjAyY2RjNzRkMDVkMy5tcDQ/0.flac?Expires=1746205835&Signature=tqbNTzW3PXDqT7dROrWt5ulrnkgS1v7jOzGF2BeRsEs64neCXUX~-F7b6L~8wLvJLD2Xip3YWhDHmTFlrPg-5cjXcXdoJyAiKFzrDw2MoHolUjxlea9EnNFvbx6mOy0-7lAYOgXiDqlrJnNhCf5SHEpYhQhoQKWTVY6FqJVHvYXXs5gIBr1IeS0L0IBVB5pU-el1XXtg-x0atN1llcLzAIcRNyX0bV8rrwvQ-HLdMW7uRcWO133DRSDd8DcVq8AE9opuo1x7UxqTIr3hNKmg5AdGiX7VXxqNSisXRiholQshF4npIYGw4f5wOMfHwThs7gA-ftcvsDTKUuaxIruyhw__&Key-Pair-Id=K14LZCZ9QUI4JL May 02 23:10:36 smsl-n100 volumio[945]: STREAMING PROXY: Response: 200, length: 19220157 May 02 23:10:36 smsl-n100 volumio[945]: info: May 02 23:10:36 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:10:36 smsl-n100 volumio[945]: info: May 02 23:10:36 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:10:36 smsl-n100 volumio[945]: info: May 02 23:10:36 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:10:36 smsl-n100 volumio[945]: info: May 02 23:10:36 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:10:36 smsl-n100 volumio[945]: info: May 02 23:10:36 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand status took 68 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:10:36 smsl-n100 volumio[945]: info: May 02 23:10:36 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand status took 81 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand status took 75 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand status took 60 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand status took 55 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 16 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:10:36 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:36 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"352421939&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES","trackType":"tidal"} May 02 23:10:36 smsl-n100 volumio[945]: verbose: CURRENT POSITION 0 May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus stop May 02 23:10:36 smsl-n100 volumio[945]: info: ------------------------------ 123ms May 02 23:10:36 smsl-n100 volumio[945]: info: May 02 23:10:36 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:10:36 smsl-n100 volumio[945]: info: May 02 23:10:36 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:10:36 smsl-n100 volumio[945]: info: May 02 23:10:36 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:10:36 smsl-n100 volumio[945]: info: May 02 23:10:36 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand status took 58 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 49 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 47 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 46 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 45 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand status took 26 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand status took 22 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand status took 17 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand status took 13 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:10:36 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:36 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"352421939&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES","trackType":"tidal"} May 02 23:10:36 smsl-n100 volumio[945]: verbose: CURRENT POSITION 0 May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:10:36 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:10:36 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:36 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"352421939&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES","trackType":"tidal"} May 02 23:10:36 smsl-n100 volumio[945]: verbose: CURRENT POSITION 0 May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:10:36 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:10:36 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:36 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"352421939&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES","trackType":"tidal"} May 02 23:10:36 smsl-n100 volumio[945]: verbose: CURRENT POSITION 0 May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:10:36 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:10:36 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:36 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"352421939&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES","trackType":"tidal"} May 02 23:10:36 smsl-n100 volumio[945]: verbose: CURRENT POSITION 0 May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:10:36 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: ------------------------------ 472ms May 02 23:10:36 smsl-n100 volumio[945]: info: ------------------------------ 468ms May 02 23:10:36 smsl-n100 volumio[945]: info: ------------------------------ 465ms May 02 23:10:36 smsl-n100 volumio[945]: info: ------------------------------ 450ms May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 332 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 325 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 324 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 320 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 319 milliseconds May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:10:36 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:10:36 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:36 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"352421939&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES","trackType":"tidal"} May 02 23:10:36 smsl-n100 volumio[945]: verbose: CURRENT POSITION 0 May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:10:36 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:10:36 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:36 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"352421939&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES","trackType":"tidal"} May 02 23:10:36 smsl-n100 volumio[945]: verbose: CURRENT POSITION 0 May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:10:36 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:10:36 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:36 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"352421939&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES","trackType":"tidal"} May 02 23:10:36 smsl-n100 volumio[945]: verbose: CURRENT POSITION 0 May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:10:36 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:10:36 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:36 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"352421939&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES","trackType":"tidal"} May 02 23:10:36 smsl-n100 volumio[945]: verbose: CURRENT POSITION 0 May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:10:36 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:10:36 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:10:36 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"352421939&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/352421939&soundQuality=HI_RES","trackType":"tidal"} May 02 23:10:36 smsl-n100 volumio[945]: verbose: CURRENT POSITION 0 May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:10:36 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:36 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:36 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:36 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:37 smsl-n100 volumio[945]: info: ------------------------------ 713ms May 02 23:10:37 smsl-n100 volumio[945]: info: ------------------------------ 678ms May 02 23:10:37 smsl-n100 volumio[945]: info: ------------------------------ 676ms May 02 23:10:37 smsl-n100 volumio[945]: info: ------------------------------ 675ms May 02 23:10:37 smsl-n100 volumio[945]: info: ------------------------------ 672ms May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:37 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:37 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:10:37 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:37 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:37 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:37 smsl-n100 volumio[945]: error: Serial API: Failed to decode command: 100, message: undefined May 02 23:10:37 smsl-n100 volumio[945]: error: Serial API: Failed to decode command: MAXVOL, message: May 02 23:10:37 smsl-n100 volumio[945]: error: Serial API: Failed to decode command: 100, message: undefined May 02 23:10:37 smsl-n100 volumio[945]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 02 23:10:37 smsl-n100 volumio[945]: info: VolumeController::SetAlsaVolume0 May 02 23:10:37 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:10:37 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:10:37 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:10:37 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:10:37 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:10:37 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:10:39 smsl-n100 volumio[945]: STREAMING PROXY: Client dropped request, destroying May 02 23:10:57 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 23:10:57 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 23:10:57 smsl-n100 volumio[945]: info: Discovery: Getting this device information May 02 23:10:57 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioGetState May 02 23:10:57 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioNext May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::next May 02 23:11:01 smsl-n100 volumio[945]: info: [1746202261288] ControllerTidal::next May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::next May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::stop May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::stPlaybackTimer May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::updateTrackBlock May 02 23:11:01 smsl-n100 volumio[945]: info: CorePlayQueue::getTrackBlock May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:01 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:01 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:01 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::serviceStop May 02 23:11:01 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 0 May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::serviceStop May 02 23:11:01 smsl-n100 volumio[945]: info: [1746202261324] ControllerTidal::stop May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 23:11:01 smsl-n100 volumio[945]: info: ControllerMpd::stop May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand stop May 02 23:11:01 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand stop took 85 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::play index undefined May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 23:11:01 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::startPlaybackTimer May 02 23:11:01 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:01 smsl-n100 volumio[945]: info: [1746202261432] ControllerTidal::clearAddPlayTrack May 02 23:11:01 smsl-n100 volumio[945]: info: Getting stream with soundQuality HI_RES May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand stop May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::updateTrackBlock May 02 23:11:01 smsl-n100 volumio[945]: info: CorePlayQueue::getTrackBlock May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:01 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:01 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:01 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:01 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand status took 43 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand stop took 34 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand status took 28 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand status took 23 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand status took 19 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand status took 14 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand clear May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:11:01 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 14 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand clear took 14 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 13 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 13 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 13 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 12 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES" May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:01 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:01 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:01 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:01 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:01 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:01 smsl-n100 volumio[945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd May 02 23:11:01 smsl-n100 volumio[945]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 02 23:11:01 smsl-n100 volumio[945]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 02 23:11:01 smsl-n100 volumio[945]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 02 23:11:01 smsl-n100 volumio[945]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 138ms May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 111ms May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 107ms May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 103ms May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 98ms May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:11:01 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:11:01 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:11:01 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:11:01 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:01 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:01 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:01 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:01 smsl-n100 volumio[945]: error: Serial API: Failed to decode command: 100, message: undefined May 02 23:11:01 smsl-n100 volumio[945]: error: Serial API: Failed to decode command: MAXVOL, message: May 02 23:11:01 smsl-n100 volumio[945]: error: Serial API: Failed to decode command: 100, message: undefined May 02 23:11:01 smsl-n100 volumio[945]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 02 23:11:01 smsl-n100 volumio[945]: error: updateQueue error: null May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:11:01 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 155ms May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES" took 147 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 89ms May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 86ms May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 84ms May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 81ms May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 23:11:01 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand play May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:11:01 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:11:01 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:11:01 smsl-n100 volumio[945]: STREAMING PROXY: Handling url /?data=tidal://song/120272&soundQuality=HI_RES May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:11:01 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:11:01 smsl-n100 volumio[945]: info: May 02 23:11:01 smsl-n100 volumio[945]: ---------------------------- MPD announces system playlist update May 02 23:11:01 smsl-n100 volumio[945]: info: Ignoring MPD Status Update May 02 23:11:01 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 65ms May 02 23:11:01 smsl-n100 volumio[945]: info: sendMpdCommand play took 56 milliseconds May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 52ms May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 48ms May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 44ms May 02 23:11:01 smsl-n100 volumio[945]: info: ------------------------------ 39ms May 02 23:11:01 smsl-n100 volumio[945]: info: Executing endpoint getStreamUrltidal May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 02 23:11:01 smsl-n100 volumio[945]: info: VolumeController::SetAlsaVolume0 May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:01 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:01 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:01 smsl-n100 volumio[945]: info: Updating RAAT Signal Path May 02 23:11:01 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:11:01 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:01 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:01 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:01 smsl-n100 volumio[945]: error: Serial API: Failed to decode command: 100, message: undefined May 02 23:11:01 smsl-n100 volumio[945]: error: Serial API: Failed to decode command: MAXVOL, message: May 02 23:11:01 smsl-n100 volumio[945]: error: Serial API: Failed to decode command: 100, message: undefined May 02 23:11:01 smsl-n100 volumio[945]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 02 23:11:02 smsl-n100 volumio[945]: info: getStreamUrl took 603 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic1ZTFmMzdjNjQwOTA0N2JlYmNmZTI0NWM2NTEyNzc2YV82MS5tcDQ/0.flac?Expires=1746205803&Signature=ivxxpqExm5XqXC9mmeFC8aUEt7S4Mu3m2ZJOLrlnTARNUYqdCtzmFsGdugai2~EcW~vTLwIrPEopNumLB2WQLzp8NMFTuUfMDHS-vSmgS87yKPiCm3nWhz8SHgzuL07DEHYWiLgI~f0DE~nDriUcrlzjA9zWhN56fDWv-ZTOz1Ad~eeNT3MczrUKY2uYZSQDmfZbDwvuLviIF9HlG0ZXViyiI6jMco8QNE5AlWOcdFmsdhDrwaW3o3jsiW0E28NmK9~xeGGbVCI~NLnJ9u~5pfuhZcfwYgnTGA-jUOJ~1ylcxZZj7JupL5rH43TZMempht4Q7cEAkLHQCapjetqe7A__&Key-Pair-Id=K14LZCZ9QUI4JL May 02 23:11:02 smsl-n100 volumio[945]: STREAMING PROXY: Response: 200, length: 33115679 May 02 23:11:02 smsl-n100 volumio[945]: info: May 02 23:11:02 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:02 smsl-n100 volumio[945]: info: May 02 23:11:02 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:02 smsl-n100 volumio[945]: info: May 02 23:11:02 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:02 smsl-n100 volumio[945]: info: May 02 23:11:02 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:02 smsl-n100 volumio[945]: info: May 02 23:11:02 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:02 smsl-n100 volumio[945]: info: May 02 23:11:02 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand status took 51 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand status took 42 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand status took 42 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand status took 37 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand status took 21 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:02 smsl-n100 volumio[945]: info: May 02 23:11:02 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:02 smsl-n100 volumio[945]: info: May 02 23:11:02 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:02 smsl-n100 volumio[945]: info: May 02 23:11:02 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:02 smsl-n100 volumio[945]: info: May 02 23:11:02 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand status took 72 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 57 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 56 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 54 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 55 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 54 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand status took 41 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand status took 34 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand status took 30 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand status took 24 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:02 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:02 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:02 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus stop May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:02 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:02 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:02 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:11:02 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:02 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:02 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:02 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:11:02 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:02 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:02 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"829 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:02 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:11:02 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:02 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:02 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"860 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:02 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:11:02 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: ------------------------------ 372ms May 02 23:11:02 smsl-n100 volumio[945]: info: ------------------------------ 448ms May 02 23:11:02 smsl-n100 volumio[945]: info: ------------------------------ 443ms May 02 23:11:02 smsl-n100 volumio[945]: info: ------------------------------ 442ms May 02 23:11:02 smsl-n100 volumio[945]: info: ------------------------------ 433ms May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 325 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 314 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 311 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 307 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 304 milliseconds May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:02 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:02 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:02 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"860 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:02 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:11:02 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:02 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:02 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"860 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:02 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:11:02 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:02 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:02 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"860 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:02 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:11:02 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:02 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:02 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"860 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:02 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:11:02 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:02 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:02 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:02 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:02 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1258,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"860 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:02 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService play May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus play May 02 23:11:02 smsl-n100 volumio[945]: info: Received an update from plugin. extracting info from payload May 02 23:11:02 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:02 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:03 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:03 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:03 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:03 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:03 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:03 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:03 smsl-n100 volumio[945]: info: ------------------------------ 771ms May 02 23:11:03 smsl-n100 volumio[945]: info: ------------------------------ 731ms May 02 23:11:03 smsl-n100 volumio[945]: info: ------------------------------ 724ms May 02 23:11:03 smsl-n100 volumio[945]: info: ------------------------------ 720ms May 02 23:11:03 smsl-n100 volumio[945]: info: ------------------------------ 715ms May 02 23:11:09 smsl-n100 volumio[945]: STREAMING PROXY: Client dropped request, destroying May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::stPlaybackTimer May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::servicePause May 02 23:11:17 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePause May 02 23:11:17 smsl-n100 volumio[945]: info: [1746202277816] ControllerTidal::pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::pause May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand pause May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand pause took 3 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: info: May 02 23:11:17 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:17 smsl-n100 volumio[945]: info: May 02 23:11:17 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:17 smsl-n100 volumio[945]: info: May 02 23:11:17 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:17 smsl-n100 volumio[945]: info: May 02 23:11:17 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:17 smsl-n100 volumio[945]: info: May 02 23:11:17 smsl-n100 volumio[945]: ---------------------------- MPD announces state update: player May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::getState May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand status May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand status took 17 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand status took 14 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand status took 11 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand status took 9 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand status took 6 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::parseState May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 9 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 9 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 9 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 7 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: info: sendMpdCommand playlistinfo took 7 milliseconds May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:17 smsl-n100 volumio[945]: verbose: ControllerMpd::parseTrackInfo May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:17 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:17 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16725,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1072 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:17 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:17 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:17 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::stPlaybackTimer May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:17 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:17 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16725,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1072 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:17 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:17 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:17 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::stPlaybackTimer May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:17 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:17 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16725,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1072 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:17 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:17 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:17 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::stPlaybackTimer May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:17 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:17 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16725,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1072 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:17 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:17 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:17 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::stPlaybackTimer May 02 23:11:17 smsl-n100 volumio[945]: info: ControllerMpd::pushState May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::servicePushState May 02 23:11:17 smsl-n100 volumio[945]: info: CorePlayQueue::getTrack 1 May 02 23:11:17 smsl-n100 volumio[945]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16725,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1072 Kbps","isStreaming":false,"title":"120272&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120272&soundQuality=HI_RES","trackType":"tidal"} May 02 23:11:17 smsl-n100 volumio[945]: verbose: CURRENT POSITION 1 May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::syncState stateService pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::syncState currentStatus pause May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::pushState May 02 23:11:17 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioPushState May 02 23:11:17 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output update for this device May 02 23:11:17 smsl-n100 volumio[945]: info: MRS: Pushing multiroomSync output May 02 23:11:17 smsl-n100 volumio[945]: info: CoreStateMachine::stPlaybackTimer May 02 23:11:17 smsl-n100 volumio[945]: info: ------------------------------ 149ms May 02 23:11:17 smsl-n100 volumio[945]: info: ------------------------------ 146ms May 02 23:11:17 smsl-n100 volumio[945]: info: ------------------------------ 144ms May 02 23:11:17 smsl-n100 volumio[945]: info: ------------------------------ 142ms May 02 23:11:17 smsl-n100 volumio[945]: info: ------------------------------ 140ms May 02 23:11:27 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 23:11:27 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 23:11:27 smsl-n100 volumio[945]: info: Discovery: Getting this device information May 02 23:11:27 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioGetState May 02 23:11:27 smsl-n100 volumio[945]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 23:11:36 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 02 23:11:36 smsl-n100 dhcpcd[632]: eth0: carrier acquired May 02 23:11:36 smsl-n100 dhcpcd[632]: eth0: IAID fa:44:5a:20 May 02 23:11:36 smsl-n100 dhcpcd[632]: eth0: adding address fe80::6e79:af58:cca6:dfa3 May 02 23:11:36 smsl-n100 dhcpcd[632]: ipv6_addaddr1: Permission denied May 02 23:11:36 smsl-n100 dhcpcd[632]: eth0: soliciting a DHCP lease May 02 23:11:36 smsl-n100 dhcpcd[632]: eth0: soliciting an IPv6 router May 02 23:11:36 smsl-n100 ifplugd(eth0)[717]: Link beat detected. May 02 23:11:36 smsl-n100 ifplugd(eth0)[717]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 02 23:11:36 smsl-n100 ifplugd(eth0)[717]: client: sending commands to master dhcpcd process May 02 23:11:36 smsl-n100 dhcpcd[6761]: sending commands to master dhcpcd process May 02 23:11:36 smsl-n100 dhcpcd[632]: control command: /sbin/dhcpcd eth0 May 02 23:11:37 smsl-n100 ifplugd(eth0)[717]: Program executed successfully. May 02 23:11:38 smsl-n100 dhcpcd[632]: eth0: offered 192.168.1.109 from 192.168.1.1 May 02 23:11:38 smsl-n100 dhcpcd[632]: eth0: probing address 192.168.1.109/24 May 02 23:11:43 smsl-n100 dhcpcd[632]: eth0: leased 192.168.1.109 for 86400 seconds May 02 23:11:43 smsl-n100 dhcpcd[632]: eth0: adding route to 192.168.1.0/24 May 02 23:11:43 smsl-n100 dhcpcd[632]: eth0: adding default route via 192.168.1.1 May 02 23:11:43 smsl-n100 avahi-daemon[540]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.109. May 02 23:11:43 smsl-n100 avahi-daemon[540]: New relevant interface eth0.IPv4 for mDNS. May 02 23:11:43 smsl-n100 avahi-daemon[540]: Registering new address record for 192.168.1.109 on eth0.IPv4. May 02 23:11:43 smsl-n100 volumio[945]: info: MRS: Found cast device: AISPLAYBOX-092cc2a38c58da446b9e353245024bfd May 02 23:11:43 smsl-n100 ntpd[968]: ntpd exiting on signal 15 (Terminated) May 02 23:11:43 smsl-n100 ntpd[968]: 202.28.117.7 local addr 192.168.1.119 -> May 02 23:11:43 smsl-n100 systemd[1]: Stopping Network Time Service... May 02 23:11:43 smsl-n100 ntpd[968]: 122.155.180.231 local addr 192.168.1.119 -> May 02 23:11:43 smsl-n100 systemd[1]: ntp.service: Succeeded. May 02 23:11:43 smsl-n100 ntpd[968]: 147.50.17.138 local addr 192.168.1.119 -> May 02 23:11:43 smsl-n100 ntpd[968]: 202.80.227.130 local addr 192.168.1.119 -> May 02 23:11:43 smsl-n100 ntpd[968]: 202.28.116.236 local addr 192.168.1.119 -> May 02 23:11:43 smsl-n100 ntpd[968]: 171.103.24.86 local addr 192.168.1.119 -> May 02 23:11:43 smsl-n100 systemd[1]: Stopped Network Time Service. May 02 23:11:43 smsl-n100 volumio[945]: ------------------------------------ BT MESSAGE: BT STATUS: running May 02 23:11:43 smsl-n100 volumio[945]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 02 23:11:43 smsl-n100 systemd[1]: Starting Network Time Service... May 02 23:11:43 smsl-n100 volumio[945]: ------------------------------------ BT MESSAGE: BT STATUS: running May 02 23:11:43 smsl-n100 ntpd[6860]: ntpd 4.2.8p12@1.3728-o (1): Starting May 02 23:11:43 smsl-n100 ntpd[6860]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 02 23:11:43 smsl-n100 systemd[1]: Started Network Time Service. May 02 23:11:43 smsl-n100 ntpd[6866]: proto: precision = 1.166 usec (-20) May 02 23:11:43 smsl-n100 ntpd[6866]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 02 23:11:43 smsl-n100 ntpd[6866]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 02 23:11:43 smsl-n100 ntpd[6866]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 857 days ago May 02 23:11:43 smsl-n100 ntpd[6866]: Listen and drop on 0 v6wildcard [::]:123 May 02 23:11:43 smsl-n100 ntpd[6866]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 02 23:11:43 smsl-n100 ntpd[6866]: Listen normally on 2 lo 127.0.0.1:123 May 02 23:11:43 smsl-n100 ntpd[6866]: Listen normally on 3 eth0 192.168.1.109:123 May 02 23:11:43 smsl-n100 ntpd[6866]: Listen normally on 4 wlan0 192.168.1.119:123 May 02 23:11:43 smsl-n100 ntpd[6866]: Listening on routing socket on fd #21 for interface updates May 02 23:11:43 smsl-n100 ntpd[6866]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 02 23:11:43 smsl-n100 ntpd[6866]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 02 23:11:44 smsl-n100 volumio[945]: info: Discovery: this is already registered, 6aa0af51-d3d5-457a-9b79-6188e8cbe815 May 02 23:11:44 smsl-n100 volumio[945]: info: Discovery: Found device SMSL N100 May 02 23:11:44 smsl-n100 volumio[945]: info: CoreCommandRouter::volumioGetState May 02 23:11:44 smsl-n100 volumio[945]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 23:11:44 smsl-n100 volumio[945]: Error: The value NaN is not a number May 02 23:11:44 smsl-n100 volumio[945]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 02 23:11:44 smsl-n100 volumio[945]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 02 23:11:44 smsl-n100 volumio[945]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 02 23:11:44 smsl-n100 volumio[945]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 02 23:11:44 smsl-n100 volumio[945]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 02 23:11:44 smsl-n100 volumio[945]: at Browser.emit (events.js:400:28) May 02 23:11:44 smsl-n100 volumio[945]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 02 23:11:44 smsl-n100 volumio[945]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 02 23:11:44 smsl-n100 volumio[945]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 02 23:11:44 smsl-n100 volumio[945]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 02 23:11:44 smsl-n100 volumio[945]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 02 23:11:44 smsl-n100 volumio[945]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 02 23:11:44 smsl-n100 volumio[945]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 02 23:11:44 smsl-n100 volumio[945]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 23:11:44 smsl-n100 ntpd[6866]: Soliciting pool server 202.80.227.130 May 02 23:11:45 smsl-n100 sudo[6878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 23:10 May 02 23:11:45 smsl-n100 sudo[6878]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"