-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2024-09-04 14:35:18 UTC. -- Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::stop Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::stPlaybackTimer Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::updateTrackBlock Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrackBlock Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::pushState Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21485 Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPushState Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output update for this device Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::serviceStop Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21485 Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreCommandRouter::serviceStop Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: [1725460440100] ControllerTidal::stop Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: ControllerMpd::stop Sep 04 14:34:00 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: Sep 04 14:34:00 volumiogranaten3 volumio[829]: ---------------------------- MPD announces state update: player Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: sendMpdCommand stop took 480 milliseconds Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: ControllerMpd::getState Sep 04 14:34:00 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand status Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::play index undefined Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21485 Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::startPlaybackTimer Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21485 Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: [1725460440601] ControllerTidal::clearAddPlayTrack Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: Getting stream with soundQuality LOSSLESS Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: sendMpdCommand status took 40 milliseconds Sep 04 14:34:00 volumiogranaten3 volumio[829]: verbose: ControllerMpd::parseState Sep 04 14:34:00 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: sendMpdCommand playlistinfo took 23 milliseconds Sep 04 14:34:00 volumiogranaten3 volumio[829]: verbose: ControllerMpd::parseTrackInfo Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: ControllerMpd::pushState Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreCommandRouter::servicePushState Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreStateMachine::pushState Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21485 Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPushState Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output update for this device Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21485 Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Sep 04 14:34:00 volumiogranaten3 volumio[829]: info: ------------------------------ 102ms Sep 04 14:34:01 volumiogranaten3 volumio[829]: info: getStreamUrl took 1080 milliseconds Sep 04 14:34:01 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 14:34:01 volumiogranaten3 volumio[829]: info: sendMpdCommand stop took 22 milliseconds Sep 04 14:34:01 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: Sep 04 14:34:02 volumiogranaten3 volumio[829]: ---------------------------- MPD announces system playlist update Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: Ignoring MPD Status Update Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: sendMpdCommand clear took 370 milliseconds Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand add "http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic1ODNhNTcwMTFkNDcwYjFiMWQ4NTk0NGE4Y2EzODU0MV82MS5tcDQ/0.flac?Expires=1725464036&Signature=o-LGsUBICQi2kN5xBJrUgs4cSuEyw6gR9ZFN8qMloI32F7BG4ogXE60utVfqFxrqMh8SMQCXeFa9IZ58L94W1oDxT5psF9~xpwtV-nW8-j6VlIu98lw2uGD~GoJTj~LHda71OwGIKMb46Zh6ixCf3SKn-Bg5dmU-C5tuzBMy3WhqU7IDsUak2KkZMGFy1PWXT2OeZeLHVO0b43XzGCQuEe0fO~p5q5f1dtm9ZUGjrH04WA7xyET8PyD9gWXNi8wvLeO90dNCmW7bKrsbC6gVoFtdQREf09vPuQRIukPxoyJncVMg9BDIT21DbARnK7l7UJqQzOBAlOtTZQ3iHQDyPg__&Key-Pair-Id=K14LZCZ9QUI4JL" Sep 04 14:34:02 volumiogranaten3 volumio[829]: error: updateQueue error: null Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: Sep 04 14:34:02 volumiogranaten3 volumio[829]: ---------------------------- MPD announces system playlist update Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: Ignoring MPD Status Update Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: ------------------------------ 23ms Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: sendMpdCommand add "http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic1ODNhNTcwMTFkNDcwYjFiMWQ4NTk0NGE4Y2EzODU0MV82MS5tcDQ/0.flac?Expires=1725464036&Signature=o-LGsUBICQi2kN5xBJrUgs4cSuEyw6gR9ZFN8qMloI32F7BG4ogXE60utVfqFxrqMh8SMQCXeFa9IZ58L94W1oDxT5psF9~xpwtV-nW8-j6VlIu98lw2uGD~GoJTj~LHda71OwGIKMb46Zh6ixCf3SKn-Bg5dmU-C5tuzBMy3WhqU7IDsUak2KkZMGFy1PWXT2OeZeLHVO0b43XzGCQuEe0fO~p5q5f1dtm9ZUGjrH04WA7xyET8PyD9gWXNi8wvLeO90dNCmW7bKrsbC6gVoFtdQREf09vPuQRIukPxoyJncVMg9BDIT21DbARnK7l7UJqQzOBAlOtTZQ3iHQDyPg__&Key-Pair-Id=K14LZCZ9QUI4JL" took 20 milliseconds Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand play Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: ------------------------------ 17ms Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: sendMpdCommand play took 13 milliseconds Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: Sep 04 14:34:02 volumiogranaten3 volumio[829]: ---------------------------- MPD announces state update: player Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: ControllerMpd::getState Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand status Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: Sep 04 14:34:02 volumiogranaten3 volumio[829]: ---------------------------- MPD announces state update: player Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: ControllerMpd::getState Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand status Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: sendMpdCommand status took 7 milliseconds Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: ControllerMpd::parseState Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: sendMpdCommand status took 9 milliseconds Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: ControllerMpd::parseState Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: ControllerMpd::parseTrackInfo Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: ControllerMpd::pushState Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreCommandRouter::servicePushState Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21485 Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?Expires=1725464036&Signature=o-LGsUBICQi2kN5xBJrUgs4cSuEyw6gR9ZFN8qMloI32F7BG4ogXE60utVfqFxrqMh8SMQCXeFa9IZ58L94W1oDxT5psF9~xpwtV-nW8-j6VlIu98lw2uGD~GoJTj~LHda71OwGIKMb46Zh6ixCf3SKn-Bg5dmU-C5tuzBMy3WhqU7IDsUak2KkZMGFy1PWXT2OeZeLHVO0b43XzGCQuEe0fO~p5q5f1dtm9ZUGjrH04WA7xyET8PyD9gWXNi8wvLeO90dNCmW7bKrsbC6gVoFtdQREf09vPuQRIukPxoyJncVMg9BDIT21DbARnK7l7UJqQzOBAlOtTZQ3iHQDyPg__&Key-Pair-Id=K14LZCZ9QUI4JL","artist":null,"album":null,"uri":"http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic1ODNhNTcwMTFkNDcwYjFiMWQ4NTk0NGE4Y2EzODU0MV82MS5tcDQ/0.flac?Expires=1725464036&Signature=o-LGsUBICQi2kN5xBJrUgs4cSuEyw6gR9ZFN8qMloI32F7BG4ogXE60utVfqFxrqMh8SMQCXeFa9IZ58L94W1oDxT5psF9~xpwtV-nW8-j6VlIu98lw2uGD~GoJTj~LHda71OwGIKMb46Zh6ixCf3SKn-Bg5dmU-C5tuzBMy3WhqU7IDsUak2KkZMGFy1PWXT2OeZeLHVO0b43XzGCQuEe0fO~p5q5f1dtm9ZUGjrH04WA7xyET8PyD9gWXNi8wvLeO90dNCmW7bKrsbC6gVoFtdQREf09vPuQRIukPxoyJncVMg9BDIT21DbARnK7l7UJqQzOBAlOtTZQ3iHQDyPg__&Key-Pair-Id=K14LZCZ9QUI4JL","trackType":"tidal"} Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: CURRENT POSITION 21485 Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreStateMachine::syncState stateService play Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreStateMachine::syncState currentStatus stop Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreStateMachine::pushState Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPushState Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output update for this device Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: ------------------------------ 58ms Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: sendMpdCommand playlistinfo took 39 milliseconds Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: ControllerMpd::parseTrackInfo Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: ControllerMpd::pushState Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreCommandRouter::servicePushState Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21485 Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?Expires=1725464036&Signature=o-LGsUBICQi2kN5xBJrUgs4cSuEyw6gR9ZFN8qMloI32F7BG4ogXE60utVfqFxrqMh8SMQCXeFa9IZ58L94W1oDxT5psF9~xpwtV-nW8-j6VlIu98lw2uGD~GoJTj~LHda71OwGIKMb46Zh6ixCf3SKn-Bg5dmU-C5tuzBMy3WhqU7IDsUak2KkZMGFy1PWXT2OeZeLHVO0b43XzGCQuEe0fO~p5q5f1dtm9ZUGjrH04WA7xyET8PyD9gWXNi8wvLeO90dNCmW7bKrsbC6gVoFtdQREf09vPuQRIukPxoyJncVMg9BDIT21DbARnK7l7UJqQzOBAlOtTZQ3iHQDyPg__&Key-Pair-Id=K14LZCZ9QUI4JL","artist":null,"album":null,"uri":"http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic1ODNhNTcwMTFkNDcwYjFiMWQ4NTk0NGE4Y2EzODU0MV82MS5tcDQ/0.flac?Expires=1725464036&Signature=o-LGsUBICQi2kN5xBJrUgs4cSuEyw6gR9ZFN8qMloI32F7BG4ogXE60utVfqFxrqMh8SMQCXeFa9IZ58L94W1oDxT5psF9~xpwtV-nW8-j6VlIu98lw2uGD~GoJTj~LHda71OwGIKMb46Zh6ixCf3SKn-Bg5dmU-C5tuzBMy3WhqU7IDsUak2KkZMGFy1PWXT2OeZeLHVO0b43XzGCQuEe0fO~p5q5f1dtm9ZUGjrH04WA7xyET8PyD9gWXNi8wvLeO90dNCmW7bKrsbC6gVoFtdQREf09vPuQRIukPxoyJncVMg9BDIT21DbARnK7l7UJqQzOBAlOtTZQ3iHQDyPg__&Key-Pair-Id=K14LZCZ9QUI4JL","trackType":"tidal"} Sep 04 14:34:02 volumiogranaten3 volumio[829]: verbose: CURRENT POSITION 21485 Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreStateMachine::syncState stateService play Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreStateMachine::syncState currentStatus play Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: Received an update from plugin. extracting info from payload Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreStateMachine::pushState Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPushState Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output update for this device Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreStateMachine::pushState Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPushState Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output update for this device Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output Sep 04 14:34:02 volumiogranaten3 volumio[829]: info: ------------------------------ 89ms Sep 04 14:34:08 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 14:34:08 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 14:34:08 volumiogranaten3 volumio[829]: info: Discovery: Getting this device information Sep 04 14:34:08 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioGetState Sep 04 14:34:08 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 14:34:38 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 14:34:38 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 14:34:38 volumiogranaten3 volumio[829]: info: Discovery: Getting this device information Sep 04 14:34:38 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioGetState Sep 04 14:34:38 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 14:34:52 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioAddQueueItems Sep 04 14:34:52 volumiogranaten3 volumio[829]: info: CoreStateMachine::addQueueItems Sep 04 14:34:52 volumiogranaten3 volumio[829]: info: CorePlayQueue::addQueueItems Sep 04 14:34:52 volumiogranaten3 volumio[829]: info: Adding Item to queue: tidal://song/145405533 Sep 04 14:34:52 volumiogranaten3 volumio[829]: info: Exploding uri tidal://song/145405533 in service tidal Sep 04 14:34:53 volumiogranaten3 volumio[829]: info: explodeTIDALUri took 1206 milliseconds Sep 04 14:34:53 volumiogranaten3 volumio[829]: info: CorePlayQueue::saveQueue Sep 04 14:34:54 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPushQueue Sep 04 14:34:56 volumiogranaten3 volumio[829]: info: CoreStateMachine::updateTrackBlock Sep 04 14:34:56 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrackBlock Sep 04 14:34:56 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPlay Sep 04 14:34:56 volumiogranaten3 volumio[829]: info: CoreStateMachine::play index 21486 Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::stop Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::stPlaybackTimer Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::updateTrackBlock Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrackBlock Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::pushState Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21485 Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPushState Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output update for this device Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::serviceStop Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21485 Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreCommandRouter::serviceStop Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: [1725460497098] ControllerTidal::stop Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: ControllerMpd::stop Sep 04 14:34:57 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: Sep 04 14:34:57 volumiogranaten3 volumio[829]: ---------------------------- MPD announces state update: player Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: sendMpdCommand stop took 121 milliseconds Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: ControllerMpd::getState Sep 04 14:34:57 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand status Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::play index undefined Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21486 Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::startPlaybackTimer Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21486 Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: [1725460497261] ControllerTidal::clearAddPlayTrack Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: Getting stream with soundQuality LOSSLESS Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: sendMpdCommand status took 57 milliseconds Sep 04 14:34:57 volumiogranaten3 volumio[829]: verbose: ControllerMpd::parseState Sep 04 14:34:57 volumiogranaten3 volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 04 14:34:57 volumiogranaten3 volumio[829]: verbose: ControllerMpd::parseTrackInfo Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: ControllerMpd::pushState Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreCommandRouter::servicePushState Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::pushState Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21486 Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPushState Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output update for this device Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: MRS: Pushing multiroomSync output Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21486 Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: ------------------------------ 117ms Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioAddQueueItems Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::addQueueItems Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CorePlayQueue::addQueueItems Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: Adding Item to queue: tidal://song/145405533 Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: Exploding uri tidal://song/145405533 in service tidal Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioAddQueueItems Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CoreStateMachine::addQueueItems Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CorePlayQueue::addQueueItems Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: Adding Item to queue: tidal://song/145405533 Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: Exploding uri tidal://song/145405533 in service tidal Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: explodeTIDALUri took 192 milliseconds Sep 04 14:34:57 volumiogranaten3 volumio[829]: info: CorePlayQueue::saveQueue Sep 04 14:34:58 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPushQueue Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CoreStateMachine::updateTrackBlock Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrackBlock Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPlay Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CoreStateMachine::play index 21486 Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CoreStateMachine::stop Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CoreStateMachine::play index undefined Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21486 Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CoreStateMachine::startPlaybackTimer Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CorePlayQueue::getTrack 21486 Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: [1725460499805] ControllerTidal::clearAddPlayTrack Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: Getting stream with soundQuality LOSSLESS Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: explodeTIDALUri took 2518 milliseconds Sep 04 14:34:59 volumiogranaten3 volumio[829]: info: CorePlayQueue::saveQueue Sep 04 14:35:00 volumiogranaten3 volumio[829]: info: CoreCommandRouter::volumioPushQueue Sep 04 14:35:02 volumiogranaten3 volumio[829]: <--- Last few GCs ---> Sep 04 14:35:02 volumiogranaten3 volumio[829]: [829:0x430e780] 7386135 ms: Mark-sweep (reduce) 233.8 (244.2) -> 233.6 (244.7) MB, 227.6 / 0.1 ms (average mu = 0.649, current mu = 0.026) allocation failure scavenge might not succeed Sep 04 14:35:02 volumiogranaten3 volumio[829]: [829:0x430e780] 7386358 ms: Mark-sweep (reduce) 234.1 (243.2) -> 234.0 (243.9) MB, 217.3 / 0.2 ms (average mu = 0.447, current mu = 0.026) allocation failure scavenge might not succeed Sep 04 14:35:02 volumiogranaten3 volumio[829]: <--- JS stacktrace ---> Sep 04 14:35:02 volumiogranaten3 volumio[829]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Sep 04 14:35:02 volumiogranaten3 vtcs[1352]: [2024-09-04 14:35:02.322] [tisoc] [error] [SpkconServer.cpp:368] recv error. socket disconnected Sep 04 14:35:02 volumiogranaten3 volumio-remote-updater[481]: [2024-09-04 14:35:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 04 14:35:02 volumiogranaten3 systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Sep 04 14:35:02 volumiogranaten3 systemd[1]: volumio.service: Failed with result 'signal'. Sep 04 14:35:02 volumiogranaten3 volumio-remote-updater[481]: [2024-09-04 14:35:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 04 14:35:02 volumiogranaten3 systemd[1]: Started dynamicswap service. Sep 04 14:35:02 volumiogranaten3 systemd[1]: dynamicswap.service: Succeeded. Sep 04 14:35:02 volumiogranaten3 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 04 14:35:02 volumiogranaten3 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 04 14:35:02 volumiogranaten3 systemd[1]: Stopped Volumio Backend Module. Sep 04 14:35:02 volumiogranaten3 systemd[1]: Started Volumio Backend Module. Sep 04 14:35:02 volumiogranaten3 systemd[1]: Started dynamicswap service. Sep 04 14:35:02 volumiogranaten3 systemd[1]: dynamicswap.service: Succeeded. Sep 04 14:35:04 volumiogranaten3 volumio[2161]: info: ------------------------------------------- Sep 04 14:35:04 volumiogranaten3 volumio[2161]: info: ----- Volumio3 ---- Sep 04 14:35:04 volumiogranaten3 volumio[2161]: info: ------------------------------------------- Sep 04 14:35:04 volumiogranaten3 volumio[2161]: info: ----- System startup ---- Sep 04 14:35:04 volumiogranaten3 volumio[2161]: info: ------------------------------------------- Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: MYVOLUMIO Environment detected Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin music_service/airplay_emulation. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin music_service/inputs. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin music_service/mpd. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin music_service/webradio. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin music_service/example_plugin. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin music_service/last_100. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin music_service/upnp_browser. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin audio_interface/outputs. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin audio_interface/upnp. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin audio_interface/alsa_controller. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin miscellanea/alarm-clock. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin miscellanea/albumart. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin miscellanea/appearance. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin miscellanea/wizard. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin miscellanea/my_music. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin user_interface/websocket. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin user_interface/mpdemulation. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin user_interface/rest_api. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin system_controller/updater_comm. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin system_controller/network. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin system_controller/networkfs. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin system_controller/services. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin system_controller/system. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin system_controller/i2s_dacs. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin system_controller/volumiodiscovery. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Found new core plugin system_controller/volumio_command_line_client. Adding it Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Plugin folders cleanup Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Scanning into folder /volumio/app/plugins/ Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Scanning category audio_interface Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Scanning category miscellanea Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Scanning category music_service Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Scanning category plugins.json Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Scanning category system_controller Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Scanning category user_interface Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Scanning into folder /data/plugins/ Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Plugin folders cleanup completed Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: ------------------------------------------- Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: ----- Core plugins startup ---- Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: ------------------------------------------- Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Loading plugins from folder /volumio/app/plugins/ Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Adding plugin upnp to MyMusic Plugins Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Loading plugins from folder /data/plugins/ Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Loading plugin "system"... Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: No id defined. Creating one Sep 04 14:35:05 volumiogranaten3 volumio[2161]: info: Loading plugin "appearance"... Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: Loading plugin "network"... Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: Refreshing Cached IP Addresses Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: Loading plugin "services"... Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: Loading plugin "alsa_controller"... Sep 04 14:35:07 volumiogranaten3 volumio-remote-updater[481]: [2024-09-04 14:35:07] [connect] Successful connection Sep 04 14:35:07 volumiogranaten3 sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 14:35:07 volumiogranaten3 sudo[2187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 14:35:07 volumiogranaten3 sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 04 14:35:07 volumiogranaten3 sudo[2187]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:07 volumiogranaten3 sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:07 volumiogranaten3 sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:07 volumiogranaten3 sudo[2187]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:07 volumiogranaten3 sudo[2189]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:07 volumiogranaten3 volumio[2161]: warn: The ALSA output card is not set, defaulting to card [object Object] Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: Found match in Cards Database: setting mixer HDMI for card HDMI Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: Loading plugin "wizard"... Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: Loading plugin "volumio_command_line_client"... Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: Loading plugin "upnp"... Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: [1725460507834] Starting Upmpd Daemon Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: Loading plugin "my_music"... Sep 04 14:35:07 volumiogranaten3 volumio[2161]: info: Loading plugin "mpd"... Sep 04 14:35:08 volumiogranaten3 volumio[2161]: info: Creating MPD Configuration file Sep 04 14:35:08 volumiogranaten3 volumio[2161]: info: Loading plugin "upnp_browser"... Sep 04 14:35:08 volumiogranaten3 sudo[2208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 04 14:35:08 volumiogranaten3 sudo[2208]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:08 volumiogranaten3 sudo[2208]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:08 volumiogranaten3 sudo[2210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 04 14:35:08 volumiogranaten3 sudo[2210]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:08 volumiogranaten3 systemd[1]: Stopping Music Player Daemon... Sep 04 14:35:08 volumiogranaten3 systemd[1]: mpd.service: Succeeded. Sep 04 14:35:08 volumiogranaten3 systemd[1]: Stopped Music Player Daemon. Sep 04 14:35:08 volumiogranaten3 systemd[1]: Starting Music Player Daemon... Sep 04 14:35:08 volumiogranaten3 volumio[2161]: info: Loading plugin "networkfs"... Sep 04 14:35:09 volumiogranaten3 volumio[2161]: info: Cannot mount NAS Remonten3 at system boot, trial number 1 ,retrying in 5 seconds Sep 04 14:35:09 volumiogranaten3 volumio[2161]: info: Starting Udev Watcher for removable devices Sep 04 14:35:09 volumiogranaten3 volumio[2161]: info: Ignoring mount for partition: boot Sep 04 14:35:09 volumiogranaten3 volumio[2161]: info: Ignoring mount for partition: volumio Sep 04 14:35:09 volumiogranaten3 volumio[2161]: info: Ignoring mount for partition: volumio_data Sep 04 14:35:09 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 04 14:35:09 volumiogranaten3 volumio[2161]: info: Loading plugin "alarm-clock"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "airplay_emulation"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Starting Shairport Sync Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "last_100"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "webradio"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "i2s_dacs"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "volumiodiscovery"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 04 14:35:10 volumiogranaten3 volumio[2161]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 14:35:10 volumiogranaten3 volumio[2161]: *** WARNING *** For more information see Sep 04 14:35:10 volumiogranaten3 node[2161]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 04 14:35:10 volumiogranaten3 volumio[2161]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 04 14:35:10 volumiogranaten3 volumio[2161]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 14:35:10 volumiogranaten3 volumio[2161]: *** WARNING *** For more information see Sep 04 14:35:10 volumiogranaten3 node[2161]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 14:35:10 volumiogranaten3 node[2161]: *** WARNING *** For more information see Sep 04 14:35:10 volumiogranaten3 node[2161]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 04 14:35:10 volumiogranaten3 node[2161]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 14:35:10 volumiogranaten3 node[2161]: *** WARNING *** For more information see Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Discovery: Started advertising with name: undefined Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "outputs"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "albumart"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Plugin example_plugin is not enabled Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "inputs"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "updater_comm"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Plugin mpdemulation is not enabled Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "rest_api"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading plugin "websocket"... Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: Loading i18n strings for locale sv Sep 04 14:35:10 volumiogranaten3 volumio[2161]: Updating browse sources language Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::initPlayerControls Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 14:35:10 volumiogranaten3 volumio[2161]: Express server listening on port 3000 Sep 04 14:35:10 volumiogranaten3 volumio[2161]: [Metrics] WebUI: 6s 711.23ms Sep 04 14:35:10 volumiogranaten3 volumio[2161]: Forking 3 albumart workers Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreStateMachine::resetVolumioState Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreStateMachine::getcurrentVolume Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 14:35:10 volumiogranaten3 mpd[2215]: exception: Error in /etc/mpd.conf line 1 Sep 04 14:35:10 volumiogranaten3 mpd[2215]: exception: nested: Letter expected Sep 04 14:35:10 volumiogranaten3 volumio[2161]: info: MPD Permissions set Sep 04 14:35:10 volumiogranaten3 systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Sep 04 14:35:10 volumiogranaten3 systemd[1]: mpd.service: Failed with result 'exit-code'. Sep 04 14:35:10 volumiogranaten3 systemd[1]: Failed to start Music Player Daemon. Sep 04 14:35:10 volumiogranaten3 sudo[2210]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:11 volumiogranaten3 sudo[2191]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:11 volumiogranaten3 volumio[2161]: verbose: New Socket.io Connection to 192.168.0.165 from 192.168.0.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 1 Sep 04 14:35:11 volumiogranaten3 volumio[2161]: verbose: New Socket.io Connection to 192.168.0.165:3000 from 192.168.0.178 UA: Dart/3.4 (dart:io) Total Clients: 2 Sep 04 14:35:11 volumiogranaten3 volumio-remote-updater[481]: [2024-09-04 14:35:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725460507 101 Sep 04 14:35:11 volumiogranaten3 volumio[2161]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Sep 04 14:35:11 volumiogranaten3 volumio[2161]: verbose: New Socket.io Connection to 192.168.0.165 from 192.168.0.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 4 Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: Discovery: Getting this device information Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioGetState Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 14:35:11 volumiogranaten3 systemd[1]: Starting Music Player Daemon... Sep 04 14:35:11 volumiogranaten3 volumio[2161]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Sep 04 14:35:11 volumiogranaten3 volumio[2161]: Job for mpd.service failed because the control process exited with error code. Sep 04 14:35:11 volumiogranaten3 volumio[2161]: See "systemctl status mpd.service" and "journalctl -xe" for details. Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: Cannot read play queue from file Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: VolumeController:: Volume=76 Mute =false Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreStateMachine::pushState Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioPushState Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreStateMachine::updateTrackBlock Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrackBlock Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreStateMachine::setRepeat null single undefined Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreStateMachine::pushState Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioPushState Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreStateMachine::setRandom true Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreStateMachine::pushState Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioPushState Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: Setting Device type: Raspberry PI Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: Completed loading Core Plugins Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: Preparing to generate the ALSA configuration file Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreStateMachine::getcurrentVolume Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: Discovery: adding 8fd4021d-a8df-48f3-a348-eaca3cba31da Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: Discovery: Found device Volumiogranaten2 Sep 04 14:35:11 volumiogranaten3 volumio[2161]: info: Discovery: Connecting to remote: 192.168.0.99 Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: Discovery: Connected to remote: 192.168.0.99 Sep 04 14:35:12 volumiogranaten3 volumio[2161]: Starting albumart workers Sep 04 14:35:12 volumiogranaten3 volumio[2161]: Starting albumart workers Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: VolumeController:: Volume=76 Mute =false Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreStateMachine::pushState Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioPushState Sep 04 14:35:12 volumiogranaten3 volumio[2161]: Starting albumart workers Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreStateMachine::updateTrackBlock Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrackBlock Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: VolumeController:: Volume=76 Mute =false Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreStateMachine::pushState Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioPushState Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: Asound.conf file written Sep 04 14:35:12 volumiogranaten3 sudo[2273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 04 14:35:12 volumiogranaten3 sudo[2273]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:12 volumiogranaten3 sudo[2273]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: Output device has changed, restarting MPD Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: Output device has changed, restarting Shairport Sync Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 14:35:12 volumiogranaten3 sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 04 14:35:12 volumiogranaten3 sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:12 volumiogranaten3 sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 04 14:35:12 volumiogranaten3 sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:12 volumiogranaten3 sudo[2279]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:12 volumiogranaten3 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 04 14:35:12 volumiogranaten3 systemd[1]: mpd.service: Succeeded. Sep 04 14:35:12 volumiogranaten3 systemd[1]: Stopped Music Player Daemon. Sep 04 14:35:12 volumiogranaten3 systemd[1]: Starting Music Player Daemon... Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: ___________ START PLUGINS ___________ Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: [1725460512939] CoreMusicLibrary::Adding element Media Servers Sep 04 14:35:12 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 14:35:12 volumiogranaten3 volumio[2161]: Cannot find translation for sourceMedia Servers Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: [1725460513255] CoreMusicLibrary::Adding element Last_100 Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 14:35:13 volumiogranaten3 volumio[2161]: Cannot find translation for sourceMedia Servers Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: [1725460513261] CoreMusicLibrary::Adding element Webradio Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 14:35:13 volumiogranaten3 volumio[2161]: Cannot find translation for sourceMedia Servers Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Volumio Calling Home Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Completed starting Core Plugins Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: ------------------------------------------- Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: ----- MyVolumio plugins startup ---- Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: ------------------------------------------- Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: MPD Permissions set Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: VolumeController:: Volume=76 Mute =false Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreStateMachine::pushState Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioPushState Sep 04 14:35:13 volumiogranaten3 volumio[2161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Volumio called home Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Starting Shairport Sync Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Starting Shairport Sync Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Starting Shairport Sync Sep 04 14:35:13 volumiogranaten3 sudo[2327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 14:35:13 volumiogranaten3 sudo[2327]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:13 volumiogranaten3 sudo[2329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 14:35:13 volumiogranaten3 sudo[2329]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:13 volumiogranaten3 sudo[2333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 14:35:13 volumiogranaten3 sudo[2333]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 14:35:13 volumiogranaten3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 04 14:35:13 volumiogranaten3 systemd[1]: shairport-sync.service: Succeeded. Sep 04 14:35:13 volumiogranaten3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioGetState Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:13 volumiogranaten3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 04 14:35:13 volumiogranaten3 sudo[2327]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:13 volumiogranaten3 sudo[2329]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:13 volumiogranaten3 sudo[2333]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Shairport-Sync Started Sep 04 14:35:13 volumiogranaten3 volumio[2161]: Error adding Membership: Error: addMembership EINVAL Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Shairport-Sync Started Sep 04 14:35:13 volumiogranaten3 volumio[2161]: info: Shairport-Sync Started Sep 04 14:35:14 volumiogranaten3 mpd[2300]: Sep 04 14:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: Cannot mount NAS Remonten3 at system boot, trial number 2 ,retrying in 5 seconds Sep 04 14:35:14 volumiogranaten3 volumio[2161]: verbose: New Socket.io Connection to 192.168.0.165 from 192.168.0.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 6 Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioGetState Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioGetVisibleSources Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioGetState Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioGetState Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: Received Get System Info Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: Discovery: Getting this device information Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioGetState Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioGetState Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getTrack 0 Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: Listing playlists Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreCommandRouter::volumioGetQueue Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CoreStateMachine::getQueue Sep 04 14:35:14 volumiogranaten3 volumio[2161]: info: CorePlayQueue::getQueue Sep 04 14:35:14 volumiogranaten3 mpd[2300]: Sep 04 14:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 04 14:35:14 volumiogranaten3 mpd[2300]: Sep 04 14:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 04 14:35:14 volumiogranaten3 systemd[1]: Started Music Player Daemon. Sep 04 14:35:14 volumiogranaten3 sudo[2280]: pam_unix(sudo:session): session closed for user root Sep 04 14:35:14 volumiogranaten3 volumio[2161]: error: updateQueue error: null Sep 04 14:35:14 volumiogranaten3 volumio[2161]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 04 14:35:15 volumiogranaten3 volumio[2161]: error: updateQueue error: null Sep 04 14:35:15 volumiogranaten3 volumio[2161]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Sep 04 14:35:15 volumiogranaten3 volumio[2161]: info: Discovery: Started advertising with name: undefined Sep 04 14:35:17 volumiogranaten3 volumio[2161]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 14:35:17 volumiogranaten3 volumio[2161]: TypeError: Cannot read property 'charAt' of undefined Sep 04 14:35:17 volumiogranaten3 volumio[2161]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Sep 04 14:35:17 volumiogranaten3 volumio[2161]: at listOnTimeout (internal/timers.js:554:17) Sep 04 14:35:17 volumiogranaten3 volumio[2161]: at processTimers (internal/timers.js:497:7) Sep 04 14:35:17 volumiogranaten3 volumio[2161]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 14:35:18 volumiogranaten3 sudo[2351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 14:34 Sep 04 14:35:18 volumiogranaten3 sudo[2351]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"