-- Logs begin at Fri 2024-11-08 18:57:22 CET, end at Sat 2024-11-30 16:48:47 CET. -- Nov 30 16:47:00 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:04 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:08 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:12 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:16 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:20 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:22 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 16:47:22 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 16:47:22 volumio volumio[765]: info: Discovery: Getting this device information Nov 30 16:47:22 volumio volumio[765]: info: CoreCommandRouter::volumioGetState Nov 30 16:47:22 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:47:22 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 16:47:24 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:25 volumio volumio[765]: info: Enabling MyMusic plugin tidalconnect Nov 30 16:47:25 volumio volumio[765]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service tidalconnect Nov 30 16:47:25 volumio volumio[765]: info: [MyVolumio PluginManager] Plugin music_service tidalconnect not in user plan, not enabling Nov 30 16:47:25 volumio volumio[765]: info: Error: Plugin music_service tidalconnect not in user plan, not enabling Nov 30 16:47:25 volumio volumio[765]: error: Could not Enable MyMusic Plugin: Error Nov 30 16:47:28 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:32 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:36 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:40 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:41 volumio volumio[765]: info: Enabling MyMusic plugin bluetooth Nov 30 16:47:41 volumio volumio[765]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Nov 30 16:47:41 volumio volumio[765]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Nov 30 16:47:41 volumio volumio[765]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Nov 30 16:47:41 volumio volumio[765]: error: Could not Enable MyMusic Plugin: Error Nov 30 16:47:44 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:48 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:52 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 16:47:52 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 16:47:52 volumio volumio[765]: info: Discovery: Getting this device information Nov 30 16:47:52 volumio volumio[765]: info: CoreCommandRouter::volumioGetState Nov 30 16:47:52 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:47:52 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 16:47:52 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:47:56 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:00 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Nov 30 16:48:00 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Nov 30 16:48:00 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:04 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:05 volumio volumio[765]: info: Error browsing device NWDS2 for shares : Error: Command failed: echo volumio | smbclient -L NWDS2.local. Nov 30 16:48:05 volumio volumio[765]: Unable to initialize messaging context Nov 30 16:48:05 volumio volumio[765]: info: Error browsing device HHDS for shares : Error: Command failed: echo volumio | smbclient -L HHDS.local. Nov 30 16:48:05 volumio volumio[765]: Unable to initialize messaging context Nov 30 16:48:08 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:12 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:14 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:14 volumio volumio[765]: info: CorePlayQueue::getTrack 1 Nov 30 16:48:14 volumio volumio[765]: info: Prefetching next song Nov 30 16:48:14 volumio volumio[765]: info: Doing Prefetch in UPNP Nov 30 16:48:14 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.200:8200/MediaItems/23301.mp3" Nov 30 16:48:14 volumio volumio[765]: info: Nov 30 16:48:14 volumio volumio[765]: ---------------------------- MPD announces system playlist update Nov 30 16:48:14 volumio volumio[765]: info: Ignoring MPD Status Update Nov 30 16:48:14 volumio volumio[765]: info: sendMpdCommand add "http://192.168.1.200:8200/MediaItems/23301.mp3" took 41 milliseconds Nov 30 16:48:14 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 30 16:48:14 volumio volumio[765]: info: Nov 30 16:48:14 volumio volumio[765]: ---------------------------- MPD announces system playlist update Nov 30 16:48:14 volumio volumio[765]: info: Ignoring MPD Status Update Nov 30 16:48:14 volumio volumio[765]: info: Nov 30 16:48:14 volumio volumio[765]: ---------------------------- MPD announces state update: options Nov 30 16:48:14 volumio volumio[765]: info: ------------------------------ 106ms Nov 30 16:48:14 volumio volumio[765]: info: sendMpdCommand consume 1 took 99 milliseconds Nov 30 16:48:14 volumio volumio[765]: info: ControllerMpd::getState Nov 30 16:48:14 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand status Nov 30 16:48:14 volumio volumio[765]: info: Nov 30 16:48:14 volumio volumio[765]: ---------------------------- MPD announces state update: options Nov 30 16:48:14 volumio volumio[765]: info: ControllerMpd::getState Nov 30 16:48:14 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand status Nov 30 16:48:14 volumio volumio[765]: info: ------------------------------ 239ms Nov 30 16:48:14 volumio volumio[765]: info: sendMpdCommand status took 133 milliseconds Nov 30 16:48:14 volumio volumio[765]: verbose: ControllerMpd::parseState Nov 30 16:48:14 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 16:48:14 volumio volumio[765]: info: sendMpdCommand status took 124 milliseconds Nov 30 16:48:14 volumio volumio[765]: verbose: ControllerMpd::parseState Nov 30 16:48:14 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 16:48:14 volumio volumio[765]: info: sendMpdCommand playlistinfo took 93 milliseconds Nov 30 16:48:14 volumio volumio[765]: verbose: ControllerMpd::parseTrackInfo Nov 30 16:48:14 volumio volumio[765]: info: ControllerMpd::pushState Nov 30 16:48:14 volumio volumio[765]: info: CoreCommandRouter::servicePushState Nov 30 16:48:14 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:14 volumio volumio[765]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280689,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"421 Kbps","isStreaming":false,"title":"23299.flac","artist":"Sissel Kyrjeby","album":"Stilla Natt","uri":"http://192.168.1.200:8200/MediaItems/23299.flac","trackType":"flac"} Nov 30 16:48:14 volumio volumio[765]: verbose: CURRENT POSITION 0 Nov 30 16:48:14 volumio volumio[765]: info: CoreStateMachine::syncState stateService play Nov 30 16:48:14 volumio volumio[765]: info: CoreStateMachine::syncState currentStatus play Nov 30 16:48:14 volumio volumio[765]: info: Received an update from plugin. extracting info from payload Nov 30 16:48:15 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:15 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:15 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 16:48:15 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:16 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:16 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:16 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:18 volumio volumio[765]: info: ------------------------------ 3607ms Nov 30 16:48:18 volumio volumio[765]: info: Nov 30 16:48:18 volumio volumio[765]: ---------------------------- MPD announces system playlist update Nov 30 16:48:18 volumio volumio[765]: info: Ignoring MPD Status Update Nov 30 16:48:18 volumio volumio[765]: info: Nov 30 16:48:18 volumio volumio[765]: ---------------------------- MPD announces state update: player Nov 30 16:48:18 volumio volumio[765]: info: sendMpdCommand playlistinfo took 3673 milliseconds Nov 30 16:48:18 volumio volumio[765]: info: ControllerMpd::getState Nov 30 16:48:18 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand status Nov 30 16:48:18 volumio volumio[765]: verbose: ControllerMpd::parseTrackInfo Nov 30 16:48:18 volumio volumio[765]: info: ControllerMpd::pushState Nov 30 16:48:18 volumio volumio[765]: info: CoreCommandRouter::servicePushState Nov 30 16:48:18 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:18 volumio volumio[765]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280804,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"408 Kbps","isStreaming":false,"title":"23299.flac","artist":"Sissel Kyrjeby","album":"Stilla Natt","uri":"http://192.168.1.200:8200/MediaItems/23299.flac","trackType":"flac"} Nov 30 16:48:18 volumio volumio[765]: verbose: CURRENT POSITION 0 Nov 30 16:48:18 volumio volumio[765]: info: CoreStateMachine::syncState stateService play Nov 30 16:48:18 volumio volumio[765]: info: CoreStateMachine::syncState currentStatus play Nov 30 16:48:18 volumio volumio[765]: info: Received an update from plugin. extracting info from payload Nov 30 16:48:18 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:18 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:18 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 16:48:18 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:18 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:18 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:18 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:18 volumio volumio[765]: info: ------------------------------ 4424ms Nov 30 16:48:18 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:18 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:18 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:18 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:18 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:18 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:18 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:18 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:18 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:18 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:18 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:19 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:19 volumio volumio[765]: info: Nov 30 16:48:19 volumio volumio[765]: ---------------------------- MPD announces system playlist update Nov 30 16:48:19 volumio volumio[765]: info: Ignoring MPD Status Update Nov 30 16:48:19 volumio volumio[765]: info: Nov 30 16:48:19 volumio volumio[765]: ---------------------------- MPD announces state update: player Nov 30 16:48:19 volumio volumio[765]: info: ControllerMpd::getState Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand status Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:19 volumio volumio[765]: info: Nov 30 16:48:19 volumio volumio[765]: ---------------------------- MPD announces system playlist update Nov 30 16:48:19 volumio volumio[765]: info: Ignoring MPD Status Update Nov 30 16:48:19 volumio volumio[765]: info: Nov 30 16:48:19 volumio volumio[765]: ---------------------------- MPD announces state update: player Nov 30 16:48:19 volumio volumio[765]: info: ControllerMpd::getState Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand status Nov 30 16:48:19 volumio volumio[765]: info: ------------------------------ 897ms Nov 30 16:48:19 volumio volumio[765]: info: sendMpdCommand status took 851 milliseconds Nov 30 16:48:19 volumio volumio[765]: info: ------------------------------ 142ms Nov 30 16:48:19 volumio volumio[765]: info: sendMpdCommand status took 120 milliseconds Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::parseState Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::parseState Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 16:48:19 volumio volumio[765]: info: Nov 30 16:48:19 volumio volumio[765]: ---------------------------- MPD announces system playlist update Nov 30 16:48:19 volumio volumio[765]: info: Ignoring MPD Status Update Nov 30 16:48:19 volumio volumio[765]: info: Nov 30 16:48:19 volumio volumio[765]: ---------------------------- MPD announces state update: player Nov 30 16:48:19 volumio volumio[765]: info: ControllerMpd::getState Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand status Nov 30 16:48:19 volumio volumio[765]: info: ------------------------------ 124ms Nov 30 16:48:19 volumio volumio[765]: info: sendMpdCommand status took 84 milliseconds Nov 30 16:48:19 volumio volumio[765]: info: sendMpdCommand playlistinfo took 62 milliseconds Nov 30 16:48:19 volumio volumio[765]: info: sendMpdCommand playlistinfo took 61 milliseconds Nov 30 16:48:19 volumio volumio[765]: info: ------------------------------ 51ms Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::parseState Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::parseTrackInfo Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::parseTrackInfo Nov 30 16:48:19 volumio volumio[765]: info: ControllerMpd::pushState Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::servicePushState Nov 30 16:48:19 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:19 volumio volumio[765]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":658,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ein Heldenleben (A Hero's Life), tone poem for orchestra, Op. 40: The Hero","artist":null,"album":"Richard Strauss: Ein Heldenleben & Don Juan","uri":"http://192.168.1.200:8200/MediaItems/23301.mp3","trackType":"mp3"} Nov 30 16:48:19 volumio volumio[765]: verbose: CURRENT POSITION 0 Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::syncState stateService play Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::syncState currentStatus play Nov 30 16:48:19 volumio volumio[765]: info: Received an update from plugin. extracting info from payload Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:19 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:19 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:19 volumio volumio[765]: info: ControllerMpd::pushState Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::servicePushState Nov 30 16:48:19 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:19 volumio volumio[765]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2354,"duration":658,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ein Heldenleben (A Hero's Life), tone poem for orchestra, Op. 40: The Hero","artist":null,"album":"Richard Strauss: Ein Heldenleben & Don Juan","uri":"http://192.168.1.200:8200/MediaItems/23301.mp3","trackType":"mp3"} Nov 30 16:48:19 volumio volumio[765]: verbose: CURRENT POSITION 0 Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::syncState stateService play Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::syncState currentStatus play Nov 30 16:48:19 volumio volumio[765]: info: Received an update from plugin. extracting info from payload Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:19 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:19 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:19 volumio volumio[765]: info: ------------------------------ 1429ms Nov 30 16:48:19 volumio volumio[765]: info: ------------------------------ 701ms Nov 30 16:48:19 volumio volumio[765]: info: sendMpdCommand status took 531 milliseconds Nov 30 16:48:19 volumio volumio[765]: info: sendMpdCommand playlistinfo took 490 milliseconds Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::parseState Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 16:48:19 volumio volumio[765]: verbose: ControllerMpd::parseTrackInfo Nov 30 16:48:19 volumio volumio[765]: info: ControllerMpd::pushState Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::servicePushState Nov 30 16:48:19 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:19 volumio volumio[765]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2480,"duration":658,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ein Heldenleben (A Hero's Life), tone poem for orchestra, Op. 40: The Hero","artist":null,"album":"Richard Strauss: Ein Heldenleben & Don Juan","uri":"http://192.168.1.200:8200/MediaItems/23301.mp3","trackType":"mp3"} Nov 30 16:48:19 volumio volumio[765]: verbose: CURRENT POSITION 0 Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::syncState stateService play Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::syncState currentStatus play Nov 30 16:48:19 volumio volumio[765]: info: Received an update from plugin. extracting info from payload Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:19 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:19 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:19 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:19 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:20 volumio volumio[765]: info: ------------------------------ 896ms Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:20 volumio volumio[765]: info: sendMpdCommand playlistinfo took 466 milliseconds Nov 30 16:48:20 volumio volumio[765]: verbose: ControllerMpd::parseTrackInfo Nov 30 16:48:20 volumio volumio[765]: info: ControllerMpd::pushState Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::servicePushState Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:20 volumio volumio[765]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2605,"duration":658,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ein Heldenleben (A Hero's Life), tone poem for orchestra, Op. 40: The Hero","artist":null,"album":"Richard Strauss: Ein Heldenleben & Don Juan","uri":"http://192.168.1.200:8200/MediaItems/23301.mp3","trackType":"mp3"} Nov 30 16:48:20 volumio volumio[765]: verbose: CURRENT POSITION 0 Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::syncState stateService play Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::syncState currentStatus play Nov 30 16:48:20 volumio volumio[765]: info: Received an update from plugin. extracting info from payload Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::pushState Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::volumioPushState Nov 30 16:48:20 volumio volumio[765]: info: ------------------------------ 1248ms Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::volumioGetQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreStateMachine::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CorePlayQueue::getQueue Nov 30 16:48:20 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:22 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 16:48:22 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 16:48:22 volumio volumio[765]: info: Discovery: Getting this device information Nov 30 16:48:22 volumio volumio[765]: info: CoreCommandRouter::volumioGetState Nov 30 16:48:22 volumio volumio[765]: info: CorePlayQueue::getTrack 0 Nov 30 16:48:22 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 16:48:24 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:28 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:32 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:36 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:38 volumio volumio[765]: info: Disabling MyMusic plugin upnp Nov 30 16:48:38 volumio sudo[7182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 30 16:48:39 volumio sudo[7182]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 16:48:39 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 30 16:48:39 volumio volumio[765]: error: Upnp client error: Error: This socket has been ended by the other party Nov 30 16:48:40 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 30 16:48:42 volumio volumio[765]: info: Enabling MyMusic plugin upnp Nov 30 16:48:42 volumio volumio[765]: info: Enabling plugin upnp Nov 30 16:48:42 volumio volumio[765]: info: Loading plugin "upnp"... Nov 30 16:48:42 volumio volumio[765]: info: [1732981722881] Starting Upmpd Daemon Nov 30 16:48:42 volumio volumio[765]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 30 16:48:42 volumio volumio[765]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 16:48:43 volumio volumio[765]: Error: listen EADDRINUSE: address already in use :::6599 Nov 30 16:48:43 volumio volumio[765]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 30 16:48:43 volumio volumio[765]: at listenInCluster (net.js:1366:12) Nov 30 16:48:43 volumio volumio[765]: at Server.listen (net.js:1452:7) Nov 30 16:48:43 volumio volumio[765]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 30 16:48:43 volumio volumio[765]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Nov 30 16:48:43 volumio volumio[765]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Nov 30 16:48:43 volumio volumio[765]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 30 16:48:43 volumio volumio[765]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 30 16:48:43 volumio volumio[765]: code: 'EADDRINUSE', Nov 30 16:48:43 volumio volumio[765]: errno: -98, Nov 30 16:48:43 volumio volumio[765]: syscall: 'listen', Nov 30 16:48:43 volumio volumio[765]: address: '::', Nov 30 16:48:43 volumio volumio[765]: port: 6599 Nov 30 16:48:43 volumio volumio[765]: } Nov 30 16:48:43 volumio volumio[765]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 16:48:47 volumio sudo[7210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-30 16:47 Nov 30 16:48:47 volumio sudo[7210]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"