-- Logs begin at Thu 2025-06-12 17:28:28 JST, end at Thu 2025-06-12 19:24:48 JST. -- Jun 12 19:23:06 primo volumio[3423]: Searching plugin music_service/qobuz Jun 12 19:23:06 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jun 12 19:23:08 primo volumio[3423]: info: searchQOBUZUri took 1965 milliseconds Jun 12 19:23:08 primo volumio[3423]: info: All search sources collected, pushing search results Jun 12 19:23:10 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 19:23:10 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 19:23:10 primo volumio[3423]: info: Discovery: Getting this device information Jun 12 19:23:10 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:10 primo volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 12 19:23:10 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 19:23:11 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jun 12 19:23:13 primo volumio[3423]: info: handleBrowseUri took 2118 milliseconds Jun 12 19:23:14 primo volumio[3423]: info: Preload queue cleared Jun 12 19:23:14 primo volumio[3423]: info: Executing endpoint getSimilarArtists Jun 12 19:23:14 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jun 12 19:23:14 primo volumio[3423]: info: Executing endpoint metavolumio Jun 12 19:23:14 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 12 19:23:15 primo volumio[3423]: error: Failed request for metavolumio API Jun 12 19:23:34 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jun 12 19:23:34 primo volumio[3423]: info: handleBrowseUri took 905 milliseconds Jun 12 19:23:35 primo volumio[3423]: info: Preload queue cleared Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586158 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586159 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586160 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586161 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586162 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586163 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586164 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586165 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586166 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586167 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586168 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586170 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586172 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586174 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586175 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586177 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586178 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586180 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586182 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586183 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586185 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586187 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586188 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586190 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586191 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586193 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586195 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586197 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586199 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586201 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586203 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586204 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586205 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586206 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586207 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586208 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586209 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586210 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586211 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586212 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586213 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586214 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586215 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586218 Jun 12 19:23:35 primo volumio[3423]: info: Preloading song: qobuz://song/4586220 Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586158 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586159 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586160 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586161 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Executing endpoint getSimilarAlbums Jun 12 19:23:35 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586162 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586163 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Executing endpoint metavolumio Jun 12 19:23:35 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 12 19:23:35 primo volumio[3423]: info: Executing endpoint metavolumio Jun 12 19:23:35 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586164 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586165 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586166 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586167 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586168 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586170 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586172 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586174 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586175 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586177 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: explodeUri took 791 milliseconds Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586178 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: explodeUri took 795 milliseconds Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586180 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:35 primo volumio[3423]: info: explodeUri took 770 milliseconds Jun 12 19:23:35 primo volumio[3423]: info: Exploding uri qobuz://song/4586182 in service qobuz Jun 12 19:23:35 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586183 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586185 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586187 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586188 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 886 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586190 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 855 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 1018 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 967 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 830 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586191 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 810 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586193 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 827 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586195 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 807 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586197 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586199 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586201 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586203 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586204 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 909 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586205 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 1100 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586206 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 1053 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 967 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586207 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 996 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586208 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 912 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 972 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 875 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: explodeUri took 849 milliseconds Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586209 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:36 primo volumio[3423]: info: Exploding uri qobuz://song/4586210 in service qobuz Jun 12 19:23:36 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:37 primo volumio[3423]: info: Exploding uri qobuz://song/4586211 in service qobuz Jun 12 19:23:37 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 876 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 852 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: Exploding uri qobuz://song/4586212 in service qobuz Jun 12 19:23:37 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:37 primo volumio[3423]: info: Exploding uri qobuz://song/4586213 in service qobuz Jun 12 19:23:37 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 871 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: Exploding uri qobuz://song/4586214 in service qobuz Jun 12 19:23:37 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 813 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: Exploding uri qobuz://song/4586215 in service qobuz Jun 12 19:23:37 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 937 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: Exploding uri qobuz://song/4586218 in service qobuz Jun 12 19:23:37 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 860 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: Exploding uri qobuz://song/4586220 in service qobuz Jun 12 19:23:37 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 910 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 847 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 906 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 886 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 884 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 839 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 840 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 876 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 866 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 805 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 886 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 880 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 826 milliseconds Jun 12 19:23:37 primo volumio[3423]: info: explodeUri took 888 milliseconds Jun 12 19:23:38 primo volumio[3423]: info: explodeUri took 870 milliseconds Jun 12 19:23:38 primo volumio[3423]: info: explodeUri took 840 milliseconds Jun 12 19:23:39 primo volumio[3423]: info: Preload queue cleared Jun 12 19:23:39 primo volumio[3423]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 12 19:23:39 primo volumio[3423]: info: CoreStateMachine::ClearQueue Jun 12 19:23:39 primo volumio[3423]: info: CoreStateMachine::stop Jun 12 19:23:39 primo volumio[3423]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 19:23:39 primo volumio[3423]: info: CorePlayQueue::clearPlayQueue Jun 12 19:23:39 primo volumio[3423]: info: CorePlayQueue::saveQueue Jun 12 19:23:39 primo volumio[3423]: info: CoreCommandRouter::volumioPushQueue Jun 12 19:23:39 primo volumio[3423]: info: CoreStateMachine::addQueueItems Jun 12 19:23:39 primo volumio[3423]: info: CorePlayQueue::addQueueItems Jun 12 19:23:39 primo volumio[3423]: info: Preload queue cleared Jun 12 19:23:39 primo volumio[3423]: info: Adding Item to queue: qobuz://album/0825084974425 Jun 12 19:23:39 primo volumio[3423]: info: Exploding uri qobuz://album/0825084974425 in service qobuz Jun 12 19:23:39 primo volumio[3423]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 12 19:23:40 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 19:23:40 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 19:23:40 primo volumio[3423]: info: Discovery: Getting this device information Jun 12 19:23:40 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:40 primo volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 12 19:23:40 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 19:23:41 primo volumio[3423]: info: explodeUri took 5844 milliseconds Jun 12 19:23:41 primo volumio[3423]: info: explodeUri took 4659 milliseconds Jun 12 19:23:42 primo volumio[3423]: info: explodeUri took 2718 milliseconds Jun 12 19:23:42 primo volumio[3423]: info: CoreCommandRouter::volumioPushQueue Jun 12 19:23:42 primo volumio[3423]: info: CorePlayQueue::saveQueue Jun 12 19:23:42 primo volumio[3423]: info: CoreStateMachine::updateTrackBlock Jun 12 19:23:42 primo volumio[3423]: info: CorePlayQueue::getTrackBlock Jun 12 19:23:42 primo volumio[3423]: info: CoreCommandRouter::volumioPlay Jun 12 19:23:42 primo volumio[3423]: info: CoreStateMachine::play index 0 Jun 12 19:23:42 primo volumio[3423]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 19:23:42 primo volumio[3423]: info: CoreStateMachine::stop Jun 12 19:23:42 primo volumio[3423]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 19:23:42 primo volumio[3423]: info: CoreStateMachine::play index undefined Jun 12 19:23:42 primo volumio[3423]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 19:23:42 primo volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 12 19:23:42 primo volumio[3423]: info: CoreStateMachine::startPlaybackTimer Jun 12 19:23:42 primo volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 12 19:23:42 primo volumio[3423]: info: [1749723822135] ControllerQobuz::clearAddPlayTrack Jun 12 19:23:42 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 19:23:42 primo volumio[3423]: info: sendMpdCommand stop took 10 milliseconds Jun 12 19:23:42 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand clear Jun 12 19:23:42 primo volumio[3423]: info: sendMpdCommand clear took 1 milliseconds Jun 12 19:23:42 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/4586158" Jun 12 19:23:42 primo volumio[3423]: info: Jun 12 19:23:42 primo volumio[3423]: ---------------------------- MPD announces system playlist update Jun 12 19:23:42 primo volumio[3423]: info: Ignoring MPD Status Update Jun 12 19:23:42 primo volumio[3423]: STREAMING PROXY: Handling url /?data=qobuz://song/4586158 Jun 12 19:23:42 primo volumio[3423]: info: Jun 12 19:23:42 primo volumio[3423]: ---------------------------- MPD announces system playlist update Jun 12 19:23:42 primo volumio[3423]: info: Ignoring MPD Status Update Jun 12 19:23:42 primo volumio[3423]: info: Jun 12 19:23:42 primo volumio[3423]: ---------------------------- MPD announces system playlist update Jun 12 19:23:42 primo volumio[3423]: info: Ignoring MPD Status Update Jun 12 19:23:42 primo volumio[3423]: error: Upnp client error: Error: This socket has been ended by the other party Jun 12 19:23:42 primo volumio[3423]: info: Executing endpoint getStreamUrlqobuz Jun 12 19:23:42 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 12 19:23:42 primo volumio[3423]: info: getStreamUrl took 700 milliseconds Jun 12 19:23:42 primo volumio[3423]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3285577&eid=4586158&fmt=6&profile=raw&app_id=539451548&cid=2354700&etsp=1749727422&hmac=mQTRwYlJIl_4lkQoZw6F3flyxfk Jun 12 19:23:43 primo volumio[3423]: info: explodeUri took 5907 milliseconds Jun 12 19:23:45 primo volumio[3423]: STREAMING PROXY: Response: 200, length: 113114631 Jun 12 19:23:45 primo volumio[3423]: STREAMING PROXY: Client dropped request, destroying Jun 12 19:23:45 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4586158" Jun 12 19:23:45 primo volumio[3423]: error: updateQueue error: null Jun 12 19:23:45 primo volumio[3423]: error: updateQueue error: null Jun 12 19:23:45 primo volumio[3423]: error: updateQueue error: null Jun 12 19:23:45 primo volumio[3423]: info: Jun 12 19:23:45 primo volumio[3423]: ---------------------------- MPD announces system playlist update Jun 12 19:23:45 primo volumio[3423]: info: Ignoring MPD Status Update Jun 12 19:23:45 primo volumio[3423]: info: ------------------------------ 3022ms Jun 12 19:23:45 primo volumio[3423]: info: ------------------------------ 3020ms Jun 12 19:23:45 primo volumio[3423]: info: ------------------------------ 3019ms Jun 12 19:23:45 primo volumio[3423]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4586158" took 4 milliseconds Jun 12 19:23:45 primo volumio[3423]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 12 19:23:45 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand play Jun 12 19:23:45 primo volumio[3423]: info: Jun 12 19:23:45 primo volumio[3423]: ---------------------------- MPD announces system playlist update Jun 12 19:23:45 primo volumio[3423]: info: Ignoring MPD Status Update Jun 12 19:23:45 primo volumio[3423]: info: Jun 12 19:23:45 primo volumio[3423]: ---------------------------- MPD announces system playlist update Jun 12 19:23:45 primo volumio[3423]: info: Ignoring MPD Status Update Jun 12 19:23:45 primo volumio[3423]: STREAMING PROXY: Handling url /?data=qobuz://song/4586158 Jun 12 19:23:45 primo volumio[3423]: info: ------------------------------ 6ms Jun 12 19:23:45 primo volumio[3423]: info: sendMpdCommand play took 5 milliseconds Jun 12 19:23:45 primo volumio[3423]: info: ------------------------------ 5ms Jun 12 19:23:45 primo volumio[3423]: info: ------------------------------ 3ms Jun 12 19:23:45 primo volumio[3423]: info: Executing endpoint getStreamUrlqobuz Jun 12 19:23:45 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 12 19:23:45 primo volumio[3423]: info: getStreamUrl took 679 milliseconds Jun 12 19:23:45 primo volumio[3423]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3285577&eid=4586158&fmt=6&profile=raw&app_id=539451548&cid=2354700&etsp=1749727425&hmac=WeFYCceWqaU4QRucWj6QbtvkVJI Jun 12 19:23:46 primo volumio[3423]: STREAMING PROXY: Response: 200, length: 113114631 Jun 12 19:23:46 primo volumio[3423]: info: Jun 12 19:23:46 primo volumio[3423]: ---------------------------- MPD announces state update: player Jun 12 19:23:46 primo kernel: aml_tdm_open Jun 12 19:23:46 primo kernel: Not init audio effects Jun 12 19:23:46 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 12 19:23:46 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jun 12 19:23:46 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jun 12 19:23:46 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jun 12 19:23:46 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0501d3818, id(1), clksel(1) Jun 12 19:23:46 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Jun 12 19:23:46 primo kernel: dump_pcm_setting(ffffffc0501d3818) Jun 12 19:23:46 primo kernel: pcm_mode(1) Jun 12 19:23:46 primo kernel: sysclk(11289600) Jun 12 19:23:46 primo kernel: sysclk_bclk_ratio(4) Jun 12 19:23:46 primo kernel: bclk(2822400) Jun 12 19:23:46 primo kernel: bclk_lrclk_ratio(64) Jun 12 19:23:46 primo kernel: lrclk(44100) Jun 12 19:23:46 primo kernel: tx_mask(0x3) Jun 12 19:23:46 primo kernel: rx_mask(0x3) Jun 12 19:23:46 primo kernel: slots(2) Jun 12 19:23:46 primo kernel: slot_width(32) Jun 12 19:23:46 primo kernel: lane_mask_in(0x2) Jun 12 19:23:46 primo kernel: lane_mask_out(0x1) Jun 12 19:23:46 primo kernel: lane_oe_mask_in(0x0) Jun 12 19:23:46 primo kernel: lane_oe_mask_out(0x0) Jun 12 19:23:46 primo kernel: lane_lb_mask_in(0x0) Jun 12 19:23:46 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jun 12 19:23:46 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jun 12 19:23:46 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jun 12 19:23:46 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jun 12 19:23:46 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Jun 12 19:23:46 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jun 12 19:23:46 primo kernel: aml_tdm_prepare(), reset fddr Jun 12 19:23:46 primo kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jun 12 19:23:46 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jun 12 19:23:46 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jun 12 19:23:46 primo kernel: tdm playback mute: 0, lane_cnt = 8 Jun 12 19:23:46 primo kernel: aml_tdm_prepare(), reset fddr Jun 12 19:23:46 primo kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jun 12 19:23:46 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jun 12 19:23:46 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jun 12 19:23:46 primo kernel: tdm playback mute: 0, lane_cnt = 8 Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::getState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand status Jun 12 19:23:46 primo volumio[3423]: info: Jun 12 19:23:46 primo volumio[3423]: ---------------------------- MPD announces state update: player Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::getState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand status Jun 12 19:23:46 primo volumio[3423]: info: Jun 12 19:23:46 primo volumio[3423]: ---------------------------- MPD announces state update: player Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::getState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand status Jun 12 19:23:46 primo volumio[3423]: info: Jun 12 19:23:46 primo volumio[3423]: ---------------------------- MPD announces state update: player Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand status took 8 milliseconds Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::getState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand status Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::parseState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 19:23:46 primo volumio[3423]: info: Jun 12 19:23:46 primo volumio[3423]: ---------------------------- MPD announces state update: player Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::getState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand status Jun 12 19:23:46 primo volumio[3423]: info: Jun 12 19:23:46 primo volumio[3423]: ---------------------------- MPD announces state update: player Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::getState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand status Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand status took 12 milliseconds Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand status took 12 milliseconds Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand status took 12 milliseconds Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand status took 10 milliseconds Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand status took 10 milliseconds Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::parseState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::parseState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::parseState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::parseTrackInfo Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::parseState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::parseState Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::servicePushState Jun 12 19:23:46 primo volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 12 19:23:46 primo volumio[3423]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1065,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4586158","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4586158","trackType":"qobuz"} Jun 12 19:23:46 primo volumio[3423]: verbose: CURRENT POSITION 0 Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState stateService play Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState currentStatus stop Jun 12 19:23:46 primo volumio[3423]: info: ------------------------------ 29ms Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 12 19:23:46 primo volumio[3423]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::parseTrackInfo Jun 12 19:23:46 primo volumio[3423]: verbose: ControllerMpd::parseTrackInfo Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::servicePushState Jun 12 19:23:46 primo volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 12 19:23:46 primo volumio[3423]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1065,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4586158","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4586158","trackType":"qobuz"} Jun 12 19:23:46 primo volumio[3423]: verbose: CURRENT POSITION 0 Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState stateService play Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState currentStatus play Jun 12 19:23:46 primo volumio[3423]: info: Received an update from plugin. extracting info from payload Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::servicePushState Jun 12 19:23:46 primo volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 12 19:23:46 primo volumio[3423]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1065,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4586158","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4586158","trackType":"qobuz"} Jun 12 19:23:46 primo volumio[3423]: verbose: CURRENT POSITION 0 Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState stateService play Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState currentStatus play Jun 12 19:23:46 primo volumio[3423]: info: Received an update from plugin. extracting info from payload Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::servicePushState Jun 12 19:23:46 primo volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 12 19:23:46 primo volumio[3423]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1065,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4586158","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4586158","trackType":"qobuz"} Jun 12 19:23:46 primo volumio[3423]: verbose: CURRENT POSITION 0 Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState stateService play Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState currentStatus play Jun 12 19:23:46 primo volumio[3423]: info: Received an update from plugin. extracting info from payload Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::servicePushState Jun 12 19:23:46 primo volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 12 19:23:46 primo volumio[3423]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1065,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4586158","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4586158","trackType":"qobuz"} Jun 12 19:23:46 primo volumio[3423]: verbose: CURRENT POSITION 0 Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState stateService play Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState currentStatus play Jun 12 19:23:46 primo volumio[3423]: info: Received an update from plugin. extracting info from payload Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: ControllerMpd::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::servicePushState Jun 12 19:23:46 primo volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 12 19:23:46 primo volumio[3423]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1065,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4586158","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4586158","trackType":"qobuz"} Jun 12 19:23:46 primo volumio[3423]: verbose: CURRENT POSITION 0 Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState stateService play Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::syncState currentStatus play Jun 12 19:23:46 primo volumio[3423]: info: Received an update from plugin. extracting info from payload Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: ------------------------------ 133ms Jun 12 19:23:46 primo volumio[3423]: info: ------------------------------ 132ms Jun 12 19:23:46 primo volumio[3423]: info: ------------------------------ 129ms Jun 12 19:23:46 primo volumio[3423]: info: ------------------------------ 127ms Jun 12 19:23:46 primo volumio[3423]: info: ------------------------------ 127ms Jun 12 19:23:46 primo kernel: asoc-aml-card auge_sound: tdm playback enable Jun 12 19:23:46 primo kernel: spdif_a is set to enable Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Updating RAAT Signal Path Jun 12 19:23:46 primo volumio[3423]: info: Updating RAAT Signal Path Jun 12 19:23:46 primo volumio[3423]: info: Updating RAAT Signal Path Jun 12 19:23:46 primo volumio[3423]: info: Updating RAAT Signal Path Jun 12 19:23:46 primo volumio[3423]: info: Updating RAAT Signal Path Jun 12 19:23:46 primo volumio[3423]: info: Updating RAAT Signal Path Jun 12 19:23:46 primo volumio[3423]: info: Updating RAAT Signal Path Jun 12 19:23:46 primo volumio[3423]: info: Updating RAAT Signal Path Jun 12 19:23:46 primo volumio[3423]: info: MCU Signalled Playback Active Jun 12 19:23:46 primo volumio[3423]: info: MCU Signalled Sleep Mode Disabled Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo volumio[3423]: info: CoreStateMachine::pushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 12 19:23:46 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 12 19:23:46 primo volumio[3423]: info: MRS: Pushing multiroomSync output Jun 12 19:23:46 primo sudo[28635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jun 12 19:23:46 primo sudo[28635]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 19:23:46 primo kernel: fb: osd[0] enable: 1 (Xorg) Jun 12 19:23:46 primo kernel: fb: osd[0] enable: 1 (Xorg) Jun 12 19:23:46 primo sudo[28635]: pam_unix(sudo:session): session closed for user root Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Signalling Playback active due to playback status change Jun 12 19:23:46 primo volumio[3423]: info: Updating RAAT Signal Path Jun 12 19:23:46 primo volumio[3423]: info: Updating RAAT Signal Path Jun 12 19:23:46 primo sudo[28638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jun 12 19:23:46 primo sudo[28638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 19:23:46 primo sudo[28638]: pam_unix(sudo:session): session closed for user root Jun 12 19:23:48 primo volumio[3423]: info: Executing endpoint metavolumio Jun 12 19:23:48 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 12 19:23:48 primo volumio[3423]: info: Executing endpoint metavolumio Jun 12 19:23:48 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 12 19:23:48 primo volumio[3423]: info: Executing endpoint metavolumio Jun 12 19:23:48 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 12 19:23:55 primo volumio[3423]: STREAMING PROXY: Client dropped request, destroying Jun 12 19:24:09 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 19:24:09 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 19:24:09 primo volumio[3423]: info: Discovery: Getting this device information Jun 12 19:24:09 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:24:09 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 19:24:14 primo volumio[3423]: verbose: New Socket.io Connection to 192.168.11.11:3000 from 192.168.11.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jun 12 19:24:32 primo volumio[3423]: verbose: New Socket.io Connection to 192.168.11.9:3000 from 192.168.11.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jun 12 19:24:32 primo volumio[3423]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.5 UA: Mozilla/5.0 (Linux; Android 15; Pixel 6a Build/BP1A.250505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.127 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 12 19:24:32 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 12 19:24:32 primo volumio[3423]: info: CoreCommandRouter::volumioGetVisibleSources Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 12 19:24:33 primo volumio[3423]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 12 19:24:33 primo volumio[3423]: info: Received Get System Info Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 19:24:33 primo volumio[3423]: info: Discovery: Getting this device information Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:24:33 primo volumio[3423]: info: Listing playlists Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::volumioGetQueue Jun 12 19:24:33 primo volumio[3423]: info: CoreStateMachine::getQueue Jun 12 19:24:33 primo volumio[3423]: info: CorePlayQueue::getQueue Jun 12 19:24:33 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 12 19:24:44 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 19:24:44 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 19:24:44 primo volumio[3423]: info: Discovery: Getting this device information Jun 12 19:24:44 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:24:44 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 19:24:44 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 19:24:44 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 19:24:44 primo volumio[3423]: info: Discovery: Getting this device information Jun 12 19:24:44 primo volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 12 19:24:44 primo volumio[3423]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 19:24:44 primo volumio[3423]: verbose: New Socket.io Connection to 192.168.11.11:3000 from 192.168.11.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jun 12 19:24:47 primo volumio[3423]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 19:24:47 primo volumio[3423]: Error: connect ETIMEDOUT 151.101.2.79:80 Jun 12 19:24:47 primo volumio[3423]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 12 19:24:47 primo volumio[3423]: errno: -110, Jun 12 19:24:47 primo volumio[3423]: code: 'ETIMEDOUT', Jun 12 19:24:47 primo volumio[3423]: syscall: 'connect', Jun 12 19:24:47 primo volumio[3423]: address: '151.101.2.79', Jun 12 19:24:47 primo volumio[3423]: port: 80 Jun 12 19:24:47 primo volumio[3423]: } Jun 12 19:24:47 primo volumio[3423]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 19:24:48 primo sudo[28927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 19:23 Jun 12 19:24:48 primo sudo[28927]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 07:34:23 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="c7ccfd1c436e37df77d047f13edf35d7"