search

ChriD / node-raumkernel

A nodeJs lib for controlling the raumfeld multiroom system
MIT License
17 stars 7 forks source link

systemReady state when changing e.g. room config and other events firing too often #28

Closed Alex9779 closed 6 years ago

Alex9779 commented 7 years ago

I just started testing how I can use your raumkernel for implementing nodes for node-red to control several aspects of my raumfeld system with node-red in connection with MQTT and homebridge and Apple Home. It works so far but when I monitor some of the core events, on top the systemReady event, I always get all the stuff I expect when I first connect to a system like systemReady and host found and renderers found when I change the config of my system with the normal iOS app. Is that an intended behaviour? Because this seems not really right to me. When I monitor the systemReady state and I remove a speaker from a zone then the system is not ready and then ready again and all the renderers are rediscovered. This makes it hard to bind action to those events because they are triggered too often. I thought this was because of my noob implementation of my first node, just started using js and node and that stuff. But then I tried just the test.js you provide and this does all the same I see with my implementation: events firing when I just remove a speaker to a zone or remove it it seems the whole kernel sets back as if it just connected again to the host...

ChriD commented 7 years ago

This is not an inteded behaviour. I will check if i can reproduce the problem

ChriD commented 6 years ago

@Alex9779 i can not reproduce this issue with the actual version of the node-raumkernel The 'systemReady' event works fine in my setup. Do you encounter this issue still?

Alex9779 commented 6 years ago

I played with your server too recently and had it still but not always. Mostly when adding or removing rooms from a zone. I will do some further tests with login level 3 or 4 to get a more detailed log I can post...

Alex9779 commented 6 years ago

Ok just as a base info I have three Raumfeld One S in three different rooms, names 'Kiara', 'Küche' and 'Julian' and one Raumfeld Soundbar in 'Wohnzimmer'. The log is not from the beginning of the session of the raumkernel, the state before was playing music in room 'Küche', 'Julian' idle, 'Wohnzimmer' idle and 'Kiara' in ECO mode, think that is AUTOMATIC_STANDBY. What I did is to add 'Kiara' to 'Küche' where music was playing:

debug: [ZoneManager] Zone configuration request returns with updateId: '2843163234'
verbose: [ZoneManager] Zone configuration changed
debug: [DeviceManager] Trying to get virtual media renderer for id or (child)name : uuid:91477E62-7F71-4518-8161-FC47AB2A73E5
debug: [ZoneManager] Zone Configuration changed to: {"zoneConfig":{"$":{"numRooms":"4","spotifyMode":"singleRoom"},"zones":[{"zone":[{"$":{"udn":"uuid:28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D"},"room":[{"$":{"name":"Küche","color":"#006E0A","udn":"uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a","powerState":"ACTIVE"},"renderer":[{"$":{"udn":"uuid:706acf81-6189-4b3d-a373-0f69869f318f","name":"Speaker Küche"}}]},{"$":{"name":"Kiara","color":"#FF6FCF","udn":"uuid:bf3c0812-f90a-4a57-8376-88b2926402c7","powerState":"AUTOMATIC_STANDBY"},"renderer":[{"$":{"udn":"uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0","name":"Speaker Kiara"}}]}]}]}],"unassignedRooms":[{"room":[{"$":{"name":"Julian","color":"#0066FF","udn":"uuid:d7441adb-fbac-4633-b3fc-edaf57cf8018","powerState":"ACTIVE"},"renderer":[{"$":{"udn":"uuid:d2a792b5-dc7f-430b-85b9-018ab55cc7c2","name":"Speaker Julian"}}]},{"$":{"name":"Wohnzimmer","color":"#CC0000","udn":"uuid:fc87e15f-e370-4df5-ab82-632ebb7c7275","powerState":"ACTIVE"},"renderer":[{"$":{"udn":"uuid:04ee9b3c-10e4-41ca-beea-c6ab76f641ed","name":"Teufel Soundbar"}}]}]}]}}
verbose: [ZoneManager] Getting zone configuration information from http://172.16.50.249 with updateId: 2843163234
debug: [MediaRenderer|Speaker Kiara] RenderingControl subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] VolumeDB has changed from '-11177' to '-11136'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 345337
debug: [MediaRenderer|Speaker Kiara] AVTransport subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] PowerState has changed from 'AUTOMATIC_STANDBY' to 'IDLE'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 345338
debug: [ZoneManager] Zone configuration request returns with updateId: '2665258531'
verbose: [ZoneManager] Zone configuration changed
debug: [ZoneManager] Zone Configuration changed to: {"zoneConfig":{"$":{"numRooms":"4","spotifyMode":"singleRoom"},"zones":[{"zone":[{"$":{"udn":"uuid:28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D"},"room":[{"$":{"name":"Küche","color":"#006E0A","udn":"uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a","powerState":"ACTIVE"},"renderer":[{"$":{"udn":"uuid:706acf81-6189-4b3d-a373-0f69869f318f","name":"Speaker Küche"}}]},{"$":{"name":"Kiara","color":"#FF6FCF","udn":"uuid:bf3c0812-f90a-4a57-8376-88b2926402c7","powerState":"ACTIVE"},"renderer":[{"$":{"udn":"uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0","name":"Speaker Kiara"}}]}]}]}],"unassignedRooms":[{"room":[{"$":{"name":"Julian","color":"#0066FF","udn":"uuid:d7441adb-fbac-4633-b3fc-edaf57cf8018","powerState":"ACTIVE"},"renderer":[{"$":{"udn":"uuid:d2a792b5-dc7f-430b-85b9-018ab55cc7c2","name":"Speaker Julian"}}]},{"$":{"name":"Wohnzimmer","color":"#CC0000","udn":"uuid:fc87e15f-e370-4df5-ab82-632ebb7c7275","powerState":"ACTIVE"},"renderer":[{"$":{"udn":"uuid:04ee9b3c-10e4-41ca-beea-c6ab76f641ed","name":"Teufel Soundbar"}}]}]}]}}
verbose: [ZoneManager] Getting zone configuration information from http://172.16.50.249 with updateId: 2665258531
debug: [DeviceManager] Device list request returns with updateId: '2142317972'
verbose: [DeviceManager] Device list changed
verbose: [DeviceManager] Cleaning up devices
debug: [DeviceManager] Cleaning up mediaServers
verbose: Remove service subscriptions for device 'Raumfeld MediaServer'
warn: [DeviceManager] Media server removed: Raumfeld MediaServer
debug: [DeviceManager] Cleaning up virtual renderers
verbose: [MediaRenderer|Küche,Julian] Remove service subscriptions for device 'Küche,Julian'
warn: [DeviceManager] Virtual media renderer removed: Küche,Julian
verbose: [MediaRenderer|Kiara] Remove service subscriptions for device 'Kiara'
warn: [DeviceManager] Virtual media renderer removed: Kiara
debug: [DeviceManager] Trying to get virtual media renderer for id or (child)name : uuid:91477E62-7F71-4518-8161-FC47AB2A73E5
debug: [DeviceManager] Trying to get media renderer for id name : uuid:91477E62-7F71-4518-8161-FC47AB2A73E5
debug: [ZoneManager] Get room information object for: uuid:d2a792b5-dc7f-430b-85b9-018ab55cc7c2
debug: [ZoneManager] Get room information object for: uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0
debug: [ZoneManager] Get room information object for: uuid:706acf81-6189-4b3d-a373-0f69869f318f
debug: [ZoneManager] Get room information object for: uuid:04ee9b3c-10e4-41ca-beea-c6ab76f641ed
debug: [DeviceManager] Cleaning up renderers
verbose: [MediaRenderer|Speaker Julian] Remove service subscriptions for device 'Speaker Julian'
warn: [DeviceManager] Media renderer removed: Speaker Julian
verbose: [MediaRenderer|Speaker Kiara] Remove service subscriptions for device 'Speaker Kiara'
warn: [DeviceManager] Media renderer removed: Speaker Kiara
verbose: [MediaRenderer|Speaker Küche] Remove service subscriptions for device 'Speaker Küche'
warn: [DeviceManager] Media renderer removed: Speaker Küche
verbose: [MediaRenderer|Teufel Soundbar] Remove service subscriptions for device 'Teufel Soundbar'
warn: [DeviceManager] Media renderer removed: Teufel Soundbar
debug: [DeviceManager] Get device description from http://172.16.50.237:53327/01950ecc-be9b-4978-8982-fc2fb0ff69bf.xml
debug: [DeviceManager] Get device description from http://172.16.50.249:49991/04ee9b3c-10e4-41ca-beea-c6ab76f641ed.xml
debug: [DeviceManager] Get device description from http://172.16.45.2:53901/1c60ff4c-f070-43d0-9d41-071d5f0ac513.xml
debug: [DeviceManager] Get device description from http://172.16.35.10:45656/20350731-5b5c-4a9f-8614-319f768ccdec/description-1.xml
debug: [DeviceManager] Get device description from http://172.16.5.4:45555/wps_device.xml
debug: [DeviceManager] Get device description from http://172.16.50.249:55483/28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D.xml
debug: [DeviceManager] Get device description from http://172.16.50.124:49153/description2.xml
debug: [DeviceManager] Get device description from http://172.16.50.124:49153/description1.xml
debug: [DeviceManager] Get device description from http://172.16.50.249:51468/45e2dd60-adcb-4b26-8497-49931191f16e.xml
debug: [DeviceManager] Get device description from http://172.16.50.124:49153/description3.xml
debug: [DeviceManager] Get device description from http://172.16.50.249:57619/52b62261-ee8f-47dd-94da-96ddbae8a351.xml
debug: [DeviceManager] Get device description from http://172.16.50.237:58964/706acf81-6189-4b3d-a373-0f69869f318f.xml
debug: [DeviceManager] Get device description from http://172.16.10.10:80/plugin/discovery/discovery.xml
debug: [DeviceManager] Get device description from http://172.16.10.11:80/plugin/discovery/discovery.xml
debug: [DeviceManager] Get device description from http://172.16.45.1:49835/9552501c-52aa-4c35-be70-40d47bb9d7aa.xml
debug: [DeviceManager] Get device description from http://172.16.5.4:49152/InternetGatewayDevice.xml
debug: [DeviceManager] Get device description from http://172.16.45.2:57775/be4075a1-0bb3-46cc-a15b-df27c00b22f0.xml
debug: [DeviceManager] Get device description from http://172.16.35.10:45656/c5d54202-5127-40b9-bad9-f8605a66d636/description-2.xml
debug: [DeviceManager] Get device description from http://172.16.45.1:51798/d2a792b5-dc7f-430b-85b9-018ab55cc7c2.xml
debug: [DeviceManager] Get device description from http://172.16.50.249:59072/e824a16f-f295-4858-b567-d692e171ded0.xml
error: [MediaRenderer|Kiara] Error: connect ECONNREFUSED 172.16.50.249:60740
error: [MediaRenderer|Kiara] Error: connect ECONNREFUSED 172.16.50.249:60740
debug: [DeviceManager] Got device description from http://172.16.5.4:45555/wps_device.xml
verbose: [DeviceManager] Device 'DAP-1665' of type 'urn:schemas-wifialliance-org:device:WFADevice:1' (Wireless Router) not usable
debug: [DeviceManager] Got device description from http://172.16.5.4:49152/InternetGatewayDevice.xml
verbose: [DeviceManager] Device 'Wireless Ap' of type 'urn:schemas-upnp-org:device:InternetGatewayDevice:1' (D-Link Ap) not usable
debug: [DeviceManager] Got device description from http://172.16.35.10:45656/20350731-5b5c-4a9f-8614-319f768ccdec/description-1.xml
verbose: [DeviceManager] Device 'dm900 WebInterface' of type 'urn:dreambox-de:device:Dreambox:1' (dm900) not usable
verbose: [DeviceManager] Getting device list from http://172.16.50.249 with updateId: 2142317972
debug: [DeviceManager] Got device description from http://172.16.35.10:45656/c5d54202-5127-40b9-bad9-f8605a66d636/description-2.xml
verbose: [DeviceManager] Device 'dm900' of type 'urn:schemas-upnp-org:device:MediaRenderer:2' (Dreambox MediaRenderer) not usable
debug: [DeviceManager] Got device description from http://172.16.45.1:49835/9552501c-52aa-4c35-be70-40d47bb9d7aa.xml
verbose: [DeviceManager] Device 'Raumfeld Device' of type 'urn:schemas-raumfeld-com:device:RaumfeldDevice:1' (undefined) not usable
debug: [DeviceManager] Got device description from http://172.16.50.124:49153/description3.xml
verbose: [DeviceManager] Device '3580449746' of type 'urn:schemas-nds-com:device:SkyRemote:2' (5600) not usable
debug: [DeviceManager] Got device description from http://172.16.50.124:49153/description2.xml
verbose: [DeviceManager] Device '3580449746' of type 'urn:schemas-nds-com:device:SkyControl:2' (5600) not usable
debug: [DeviceManager] Got device description from http://172.16.50.249:51468/45e2dd60-adcb-4b26-8497-49931191f16e.xml
verbose: [DeviceManager] Device 'Raumfeld ConfigDevice' of type 'urn:schemas-raumfeld-com:device:ConfigDevice:1' (undefined) not usable
debug: [DeviceManager] Got device description from http://172.16.50.124:49153/description1.xml
verbose: [DeviceManager] Device '3580449746' of type 'urn:schemas-nds-com:device:SkyServe:2' (5600) not usable
debug: [DeviceManager] Got device description from http://172.16.45.1:51798/d2a792b5-dc7f-430b-85b9-018ab55cc7c2.xml
verbose: [DeviceManager] Media renderer 'Speaker Julian' found
verbose: [DeviceManager] Raumfeld media renderer 'Speaker Julian' is useable
verbose: [MediaRenderer|Speaker Julian] Set up AVTransport subscription on device 'Speaker Julian'
verbose: [MediaRenderer|Speaker Julian] Set up RenderingControl subscription on device 'Speaker Julian'
info: [DeviceManager] Media renderer added: Speaker Julian (uuid:d2a792b5-dc7f-430b-85b9-018ab55cc7c2)
debug: [DeviceManager] Got device description from http://172.16.50.249:59072/e824a16f-f295-4858-b567-d692e171ded0.xml
verbose: [DeviceManager] Device 'Raumfeld Device' of type 'urn:schemas-raumfeld-com:device:RaumfeldDevice:1' (undefined) not usable
debug: [DeviceManager] Got device description from http://172.16.10.10:80/plugin/discovery/discovery.xml
verbose: [DeviceManager] Device 'OctoPrint instance "Ultimaker 2"' of type 'urn:schemas-upnp-org:device:Basic:1' (undefined) not usable
debug: [DeviceManager] Got device description from http://172.16.50.237:53327/01950ecc-be9b-4978-8982-fc2fb0ff69bf.xml
verbose: [DeviceManager] Device 'Raumfeld Device' of type 'urn:schemas-raumfeld-com:device:RaumfeldDevice:1' (undefined) not usable
debug: [DeviceManager] Got device description from http://172.16.10.11:80/plugin/discovery/discovery.xml
verbose: [DeviceManager] Device 'OctoPrint instance "BigBox"' of type 'urn:schemas-upnp-org:device:Basic:1' (undefined) not usable
debug: [DeviceManager] Got device description from http://172.16.50.249:49991/04ee9b3c-10e4-41ca-beea-c6ab76f641ed.xml
verbose: [DeviceManager] Media renderer 'Teufel Soundbar' found
verbose: [DeviceManager] Raumfeld media renderer 'Teufel Soundbar' is useable
verbose: [MediaRenderer|Teufel Soundbar] Set up AVTransport subscription on device 'Teufel Soundbar'
verbose: [MediaRenderer|Teufel Soundbar] Set up RenderingControl subscription on device 'Teufel Soundbar'
info: [DeviceManager] Media renderer added: Teufel Soundbar (uuid:04ee9b3c-10e4-41ca-beea-c6ab76f641ed)
debug: [MediaRenderer|Speaker Julian] AVTransport subscription callback triggered on device 'Speaker Julian'
verbose: [MediaRenderer|Speaker Julian] InstanceID has changed from 'undefined' to '0'
verbose: [MediaRenderer|Speaker Julian] AVTransportURIMetaData has changed from 'undefined' to ''
verbose: [MediaRenderer|Speaker Julian] CurrentTrackDuration has changed from 'undefined' to 'NOT_IMPLEMENTED'
verbose: [MediaRenderer|Speaker Julian] PowerState has changed from 'undefined' to 'IDLE'
verbose: [MediaRenderer|Speaker Julian] AVTransportURI has changed from 'undefined' to ''
verbose: [MediaRenderer|Speaker Julian] CurrentPlayMode has changed from 'undefined' to 'NORMAL'
verbose: [MediaRenderer|Speaker Julian] TransportState has changed from 'undefined' to 'NO_MEDIA_PRESENT'
verbose: [MediaRenderer|Speaker Julian] CurrentTransportActions has changed from 'undefined' to ''
verbose: [MediaRenderer|Speaker Julian] TransportStatus has changed from 'undefined' to 'OK'
debug: [MediaRenderer|Speaker Julian] Set new updateId to: 658365
debug: [DeviceManager] Got device description from http://172.16.50.237:58964/706acf81-6189-4b3d-a373-0f69869f318f.xml
verbose: [DeviceManager] Media renderer 'Speaker Küche' found
verbose: [DeviceManager] Raumfeld media renderer 'Speaker Küche' is useable
verbose: [MediaRenderer|Speaker Küche] Set up AVTransport subscription on device 'Speaker Küche'
verbose: [MediaRenderer|Speaker Küche] Set up RenderingControl subscription on device 'Speaker Küche'
info: [DeviceManager] Media renderer added: Speaker Küche (uuid:706acf81-6189-4b3d-a373-0f69869f318f)
debug: [MediaRenderer|Speaker Julian] RenderingControl subscription callback triggered on device 'Speaker Julian'
verbose: [MediaRenderer|Speaker Julian] LowDB has changed from 'undefined' to '-1.078125'
verbose: [MediaRenderer|Speaker Julian] Mute has changed from 'undefined' to '0'
verbose: [MediaRenderer|Speaker Julian] MidDB has changed from 'undefined' to '0.000000'
verbose: [MediaRenderer|Speaker Julian] Volume has changed from 'undefined' to '50'
verbose: [MediaRenderer|Speaker Julian] VolumeDB has changed from 'undefined' to '-8576'
verbose: [MediaRenderer|Speaker Julian] HighDB has changed from 'undefined' to '1.742188'
debug: [MediaRenderer|Speaker Julian] Set new updateId to: 658366
debug: [DeviceManager] Got device description from http://172.16.45.2:57775/be4075a1-0bb3-46cc-a15b-df27c00b22f0.xml
verbose: [DeviceManager] Media renderer 'Speaker Kiara' found
verbose: [DeviceManager] Raumfeld media renderer 'Speaker Kiara' is useable
verbose: [MediaRenderer|Speaker Kiara] Set up AVTransport subscription on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] Set up RenderingControl subscription on device 'Speaker Kiara'
info: [DeviceManager] Media renderer added: Speaker Kiara (uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0)
debug: [DeviceManager] Got device description from http://172.16.45.2:53901/1c60ff4c-f070-43d0-9d41-071d5f0ac513.xml
verbose: [DeviceManager] Device 'Raumfeld Device' of type 'urn:schemas-raumfeld-com:device:RaumfeldDevice:1' (undefined) not usable
debug: [MediaRenderer|Speaker Küche] AVTransport subscription callback triggered on device 'Speaker Küche'
verbose: [MediaRenderer|Speaker Küche] InstanceID has changed from 'undefined' to '0'
verbose: [MediaRenderer|Speaker Küche] AVTransportURIMetaData has changed from 'undefined' to '<?xml version="1.0"?>
<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:raumfeld="urn:schemas-raumfeld-com:meta-data/raumfeld"><item restricted="1" id="0/RadioTime/Favorites/MyFavorites/2522" refID="0/RadioTime/Search/s-s158432" parentID="0/RadioTime/Favorites/MyFavorites"><dc:title>Absolut relax</dc:title><upnp:signalStrength>65</upnp:signalStrength><upnp:class>object.item.audioItem.audioBroadcast.radio</upnp:class><raumfeld:section>RadioTime</raumfeld:section><raumfeld:ebrowse>http://opml.radiotime.com/Tune.ashx?partnerId=7aJ9pvV5&amp;formats=wma%2Cmp3%2Cogg&amp;serial=54%3A4a%3A16%3Ac5%3A4a%3Ab3&amp;id=s158432&amp;c=ebrowse</raumfeld:ebrowse><upnp:albumArtURI>http://d1i6vahw24eb07.cloudfront.net/s158432q.png</upnp:albumArtURI><raumfeld:name>Station</raumfeld:name><raumfeld:durability>119</raumfeld:durability></item></DIDL-Lite>
'
verbose: [MediaRenderer|Speaker Küche] CurrentTrackDuration has changed from 'undefined' to 'NOT_IMPLEMENTED'
verbose: [MediaRenderer|Speaker Küche] PowerState has changed from 'undefined' to 'ACTIVE'
verbose: [MediaRenderer|Speaker Küche] AVTransportURI has changed from 'undefined' to 'http://172.16.50.249:58509/28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D/a1dd1fbe-d92d-4f9b-958c-4481ae0b137a/706acf81-6189-4b3d-a373-0f69869f318f/e80489ec5858d9125f827e473fc643d1--199308651-0-0.mp3'
verbose: [MediaRenderer|Speaker Küche] CurrentPlayMode has changed from 'undefined' to 'NORMAL'
verbose: [MediaRenderer|Speaker Küche] TransportState has changed from 'undefined' to 'PLAYING'
verbose: [MediaRenderer|Speaker Küche] CurrentTransportActions has changed from 'undefined' to 'Stop'
verbose: [MediaRenderer|Speaker Küche] TransportStatus has changed from 'undefined' to 'OK'
debug: [MediaRenderer|Speaker Küche] Set new updateId to: 661231
debug: [MediaRenderer|Speaker Küche] RenderingControl subscription callback triggered on device 'Speaker Küche'
verbose: [MediaRenderer|Speaker Küche] LowDB has changed from 'undefined' to '0.000000'
verbose: [MediaRenderer|Speaker Küche] Mute has changed from 'undefined' to '0'
verbose: [MediaRenderer|Speaker Küche] MidDB has changed from 'undefined' to '0.000000'
verbose: [MediaRenderer|Speaker Küche] Volume has changed from 'undefined' to '46'
verbose: [MediaRenderer|Speaker Küche] VolumeDB has changed from 'undefined' to '-9344'
verbose: [MediaRenderer|Speaker Küche] HighDB has changed from 'undefined' to '0.000000'
debug: [MediaRenderer|Speaker Küche] Set new updateId to: 661232
debug: [MediaRenderer|Speaker Kiara] AVTransport subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] InstanceID has changed from 'undefined' to '0'
verbose: [MediaRenderer|Speaker Kiara] AVTransportURIMetaData has changed from 'undefined' to ''
verbose: [MediaRenderer|Speaker Kiara] CurrentTrackDuration has changed from 'undefined' to 'NOT_IMPLEMENTED'
verbose: [MediaRenderer|Speaker Kiara] PowerState has changed from 'undefined' to 'IDLE'
verbose: [MediaRenderer|Speaker Kiara] AVTransportURI has changed from 'undefined' to ''
verbose: [MediaRenderer|Speaker Kiara] CurrentPlayMode has changed from 'undefined' to 'NORMAL'
verbose: [MediaRenderer|Speaker Kiara] TransportState has changed from 'undefined' to 'NO_MEDIA_PRESENT'
verbose: [MediaRenderer|Speaker Kiara] CurrentTransportActions has changed from 'undefined' to ''
verbose: [MediaRenderer|Speaker Kiara] TransportStatus has changed from 'undefined' to 'OK'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360527
debug: [MediaRenderer|Speaker Kiara] RenderingControl subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] LowDB has changed from 'undefined' to '-1.300781'
verbose: [MediaRenderer|Speaker Kiara] Mute has changed from 'undefined' to '0'
verbose: [MediaRenderer|Speaker Kiara] MidDB has changed from 'undefined' to '0.000000'
verbose: [MediaRenderer|Speaker Kiara] Volume has changed from 'undefined' to '35'
verbose: [MediaRenderer|Speaker Kiara] VolumeDB has changed from 'undefined' to '-11136'
verbose: [MediaRenderer|Speaker Kiara] HighDB has changed from 'undefined' to '1.792969'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360528
debug: [MediaRenderer|Teufel Soundbar] AVTransport subscription callback triggered on device 'Teufel Soundbar'
verbose: [MediaRenderer|Teufel Soundbar] InstanceID has changed from 'undefined' to '0'
verbose: [MediaRenderer|Teufel Soundbar] AVTransportURIMetaData has changed from 'undefined' to ''
verbose: [MediaRenderer|Teufel Soundbar] CurrentTrackDuration has changed from 'undefined' to 'NOT_IMPLEMENTED'
verbose: [MediaRenderer|Teufel Soundbar] PowerState has changed from 'undefined' to 'IDLE'
verbose: [MediaRenderer|Teufel Soundbar] AVTransportURI has changed from 'undefined' to ''
verbose: [MediaRenderer|Teufel Soundbar] CurrentPlayMode has changed from 'undefined' to 'NORMAL'
verbose: [MediaRenderer|Teufel Soundbar] TransportState has changed from 'undefined' to 'NO_MEDIA_PRESENT'
verbose: [MediaRenderer|Teufel Soundbar] CurrentTransportActions has changed from 'undefined' to ''
verbose: [MediaRenderer|Teufel Soundbar] TransportStatus has changed from 'undefined' to 'OK'
debug: [MediaRenderer|Teufel Soundbar] Set new updateId to: 381235
debug: [MediaRenderer|Teufel Soundbar] RenderingControl subscription callback triggered on device 'Teufel Soundbar'
verbose: [MediaRenderer|Teufel Soundbar] LowDB has changed from 'undefined' to '-1.460938'
verbose: [MediaRenderer|Teufel Soundbar] Volume has changed from 'undefined' to '43'
verbose: [MediaRenderer|Teufel Soundbar] SettingValue has changed from 'undefined' to '140Hz'
verbose: [MediaRenderer|Teufel Soundbar] Mute has changed from 'undefined' to '0'
verbose: [MediaRenderer|Teufel Soundbar] HighDB has changed from 'undefined' to '3.410156'
verbose: [MediaRenderer|Teufel Soundbar] MidDB has changed from 'undefined' to '1.515625'
verbose: [MediaRenderer|Teufel Soundbar] VolumeDB has changed from 'undefined' to '-8000'
debug: [MediaRenderer|Teufel Soundbar] Set new updateId to: 381236
debug: [DeviceManager] Got device description from http://172.16.50.249:55483/28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D.xml
verbose: [DeviceManager] Media renderer 'Küche,Kiara' found
verbose: [DeviceManager] Media Renderer 'Küche,Kiara' is useable
verbose: [MediaRenderer|Küche,Kiara] Set up AVTransport subscription on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] Set up RenderingControl subscription on device 'Küche,Kiara'
info: [DeviceManager] Virtual media renderer added: Küche,Kiara (uuid:28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D)
debug: [DeviceManager] Trying to get virtual media renderer for id or (child)name : uuid:28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D
debug: [MediaRenderer|Küche,Kiara] AVTransport subscription callback triggered on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] InstanceID has changed from 'undefined' to '0'
verbose: [MediaRenderer|Küche,Kiara] CurrentTrackMetaData has changed from 'undefined' to '<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:raumfeld="urn:schemas-raumfeld-com:meta-data/raumfeld"><item restricted="1" id="0/RadioTime/Favorites/MyFavorites/2522" refID="0/RadioTime/Search/s-s158432" parentID="0/RadioTime/Favorites/MyFavorites"><dc:title>Absolut relax</dc:title><upnp:signalStrength>65</upnp:signalStrength><upnp:class>object.item.audioItem.audioBroadcast.radio</upnp:class><raumfeld:section>RadioTime</raumfeld:section><raumfeld:ebrowse>http://opml.radiotime.com/Tune.ashx?partnerId=7aJ9pvV5&amp;formats=wma%2Cmp3%2Cogg&amp;serial=54%3A4a%3A16%3Ac5%3A4a%3Ab3&amp;id=s158432&amp;c=ebrowse</raumfeld:ebrowse><upnp:albumArtURI>http://d1i6vahw24eb07.cloudfront.net/s158432q.png</upnp:albumArtURI><raumfeld:name>Station</raumfeld:name><res bitrate="160" protocolInfo="http-get:*:audio/x-mpegurl:*">http://opml.radiotime.com/Tune.ashx?id=e54501230&amp;sid=s158432&amp;formats=wma,mp3,ogg&amp;partnerId=7aJ9pvV5&amp;serial=54:4a:16:c5:4a:b3</res><raumfeld:durability>119</raumfeld:durability></item></DIDL-Lite>'
verbose: [MediaRenderer|Küche,Kiara] CurrentRecordQualityMode has changed from 'undefined' to 'NOT_IMPLEMENTED'
verbose: [MediaRenderer|Küche,Kiara] AbsoluteTimePosition has changed from 'undefined' to '00:04:19'
verbose: [MediaRenderer|Küche,Kiara] SecondsUntilSleep has changed from 'undefined' to '0'
verbose: [MediaRenderer|Küche,Kiara] CurrentTrack has changed from 'undefined' to '1'
verbose: [MediaRenderer|Küche,Kiara] AVTransportURIMetaData has changed from 'undefined' to '<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:raumfeld="urn:schemas-raumfeld-com:meta-data/raumfeld"><item restricted="1" id="0/RadioTime/Favorites/MyFavorites/2522" refID="0/RadioTime/Search/s-s158432" parentID="0/RadioTime/Favorites/MyFavorites"><dc:title>Absolut relax</dc:title><upnp:signalStrength>65</upnp:signalStrength><upnp:class>object.item.audioItem.audioBroadcast.radio</upnp:class><raumfeld:section>RadioTime</raumfeld:section><raumfeld:ebrowse>http://opml.radiotime.com/Tune.ashx?partnerId=7aJ9pvV5&amp;formats=wma%2Cmp3%2Cogg&amp;serial=54%3A4a%3A16%3Ac5%3A4a%3Ab3&amp;id=s158432&amp;c=ebrowse</raumfeld:ebrowse><upnp:albumArtURI>http://d1i6vahw24eb07.cloudfront.net/s158432q.png</upnp:albumArtURI><raumfeld:name>Station</raumfeld:name><res bitrate="160" protocolInfo="http-get:*:audio/x-mpegurl:*">http://opml.radiotime.com/Tune.ashx?id=e54501230&amp;sid=s158432&amp;formats=wma,mp3,ogg&amp;partnerId=7aJ9pvV5&amp;serial=54:4a:16:c5:4a:b3</res><raumfeld:durability>119</raumfeld:durability></item></DIDL-Lite>'
verbose: [MediaRenderer|Küche,Kiara] PossiblePlaybackStorageMedia has changed from 'undefined' to 'NETWORK'
verbose: [MediaRenderer|Küche,Kiara] TransportPlaySpeed has changed from 'undefined' to '1'
verbose: [MediaRenderer|Küche,Kiara] CurrentTrackDuration has changed from 'undefined' to '00:00:00'
verbose: [MediaRenderer|Küche,Kiara] PossibleRecordQualityModes has changed from 'undefined' to 'NOT_IMPLEMENTED'
verbose: [MediaRenderer|Küche,Kiara] TransportError has changed from 'undefined' to ''
verbose: [MediaRenderer|Küche,Kiara] PossibleRecordStorageMedia has changed from 'undefined' to 'NONE'
verbose: [MediaRenderer|Küche,Kiara] AVTransportURI has changed from 'undefined' to 'http://opml.radiotime.com/Tune.ashx?id=e54501230&sid=s158432&formats=wma,mp3,ogg&partnerId=7aJ9pvV5&serial=54:4a:16:c5:4a:b3'
verbose: [MediaRenderer|Küche,Kiara] RelativeTimePosition has changed from 'undefined' to '00:04:19'
verbose: [MediaRenderer|Küche,Kiara] RelativeCounterPosition has changed from 'undefined' to '1'
verbose: [MediaRenderer|Küche,Kiara] CurrentPlayMode has changed from 'undefined' to 'NORMAL'
verbose: [MediaRenderer|Küche,Kiara] Bitrate has changed from 'undefined' to '96'
verbose: [MediaRenderer|Küche,Kiara] TransportState has changed from 'undefined' to 'PLAYING'
verbose: [MediaRenderer|Küche,Kiara] AbsoluteCounterPosition has changed from 'undefined' to '1'
verbose: [MediaRenderer|Küche,Kiara] CurrentTransportActions has changed from 'undefined' to 'Stop'
verbose: [MediaRenderer|Küche,Kiara] RoomStates has changed from 'undefined' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=PLAYING,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=STOPPED'
verbose: [MediaRenderer|Küche,Kiara] ContentType has changed from 'undefined' to 'MP3'
verbose: [MediaRenderer|Küche,Kiara] NumberOfTracks has changed from 'undefined' to '1'
verbose: [MediaRenderer|Küche,Kiara] SleepTimerActive has changed from 'undefined' to '0'
verbose: [MediaRenderer|Küche,Kiara] TransportStatus has changed from 'undefined' to 'OK'
verbose: [MediaRenderer|Küche,Kiara] CurrentTrackURI has changed from 'undefined' to 'http://absolut.hoerradar.de/absolutradio-relax.mp3?sABC=5n2r7979%230%234610op262rn0p080qoqn1oqpnssppqn5%23gharva&amsparams=playerid:tunein;skey:1512995193'
debug: [ZoneManager] Get room information object for: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a
debug: [MediaRenderer|Küche,Kiara] Room: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a: Set room renderer(s) ["uuid:706acf81-6189-4b3d-a373-0f69869f318f"] online to :true
debug: [ZoneManager] Get room information object for: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7
debug: [MediaRenderer|Küche,Kiara] Room: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7: Set room renderer(s) ["uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0"] online to :true
verbose: [MediaRenderer|Küche,Kiara] roomUDN has changed from '' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a' on room 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a'
verbose: [MediaRenderer|Küche,Kiara] transportState has changed from '' to 'PLAYING' on room 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a'
verbose: [MediaRenderer|Küche,Kiara] online has changed from '' to 'true' on room 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a'
verbose: [MediaRenderer|Küche,Kiara] roomUDN has changed from '' to 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
verbose: [MediaRenderer|Küche,Kiara] transportState has changed from '' to 'STOPPED' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
verbose: [MediaRenderer|Küche,Kiara] online has changed from '' to 'true' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
debug: [MediaRenderer|Küche,Kiara] Set new updateId to: 779893
debug: [DeviceManager] Got device description from http://172.16.50.249:57619/52b62261-ee8f-47dd-94da-96ddbae8a351.xml
verbose: [DeviceManager] Media server 'Raumfeld MediaServer' found
verbose: [DeviceManager] Media server 'Raumfeld MediaServer' is useable
verbose: Set up ContentDirectory subscription on device 'Raumfeld MediaServer'
info: [DeviceManager] Raumfeld media server added: Raumfeld MediaServer (uuid:52b62261-ee8f-47dd-94da-96ddbae8a351)
debug: [MediaListManager] Update media list for uuid:28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D http://opml.radiotime.com/Tune.ashx?id=e54501230&sid=s158432&formats=wma,mp3,ogg&partnerId=7aJ9pvV5&serial=54:4a:16:c5:4a:b3
verbose: [MediaListManager] Get media list for uri http://opml.radiotime.com/Tune.ashx?id=e54501230&sid=s158432&formats=wma,mp3,ogg&partnerId=7aJ9pvV5&serial=54:4a:16:c5:4a:b3
debug: [MediaListManager] Update media list for uuid:706acf81-6189-4b3d-a373-0f69869f318f http://172.16.50.249:58509/28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D/a1dd1fbe-d92d-4f9b-958c-4481ae0b137a/706acf81-6189-4b3d-a373-0f69869f318f/e80489ec5858d9125f827e473fc643d1--199308651-0-0.mp3
verbose: [MediaListManager] Get media list for uri http://172.16.50.249:58509/28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D/a1dd1fbe-d92d-4f9b-958c-4481ae0b137a/706acf81-6189-4b3d-a373-0f69869f318f/e80489ec5858d9125f827e473fc643d1--199308651-0-0.mp3
debug: [MediaListManager] Media list for renderer uuid:28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D is ready
debug: [MediaListManager] Media list for renderer uuid:706acf81-6189-4b3d-a373-0f69869f318f is ready
debug: [MediaRenderer|Küche,Kiara] RenderingControl subscription callback triggered on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] RoomVolumes has changed from 'undefined' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=35'
verbose: [MediaRenderer|Küche,Kiara] Volume has changed from 'undefined' to '46'
verbose: [MediaRenderer|Küche,Kiara] Mute has changed from 'undefined' to '0'
verbose: [MediaRenderer|Küche,Kiara] RoomMutes has changed from 'undefined' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=0,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=0'
debug: [ZoneManager] Get room information object for: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a
debug: [MediaRenderer|Küche,Kiara] Room: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a: Set room renderer(s) ["uuid:706acf81-6189-4b3d-a373-0f69869f318f"] online to :true
debug: [ZoneManager] Get room information object for: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7
debug: [MediaRenderer|Küche,Kiara] Room: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7: Set room renderer(s) ["uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0"] online to :true
verbose: [MediaRenderer|Küche,Kiara] volume has changed from 'undefined' to '46' on room 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a'
verbose: [MediaRenderer|Küche,Kiara] mute has changed from 'undefined' to '0' on room 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a'
verbose: [MediaRenderer|Küche,Kiara] volume has changed from 'undefined' to '35' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
verbose: [MediaRenderer|Küche,Kiara] mute has changed from 'undefined' to '0' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
debug: [MediaRenderer|Küche,Kiara] Set new updateId to: 779894
debug: ContentDirectory subscription callback triggered on device 'Raumfeld MediaServer'
debug: ContentDirectory subscription callback triggered on device 'Raumfeld MediaServer'
debug: [MediaRenderer|Speaker Kiara] AVTransport subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] AVTransportURIMetaData has changed from '' to '<?xml version="1.0"?>
<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:raumfeld="urn:schemas-raumfeld-com:meta-data/raumfeld"><item restricted="1" id="0/RadioTime/Favorites/MyFavorites/2522" refID="0/RadioTime/Search/s-s158432" parentID="0/RadioTime/Favorites/MyFavorites"><dc:title>Absolut relax</dc:title><upnp:signalStrength>65</upnp:signalStrength><upnp:class>object.item.audioItem.audioBroadcast.radio</upnp:class><raumfeld:section>RadioTime</raumfeld:section><raumfeld:ebrowse>http://opml.radiotime.com/Tune.ashx?partnerId=7aJ9pvV5&amp;formats=wma%2Cmp3%2Cogg&amp;serial=54%3A4a%3A16%3Ac5%3A4a%3Ab3&amp;id=s158432&amp;c=ebrowse</raumfeld:ebrowse><upnp:albumArtURI>http://d1i6vahw24eb07.cloudfront.net/s158432q.png</upnp:albumArtURI><raumfeld:name>Station</raumfeld:name><raumfeld:durability>119</raumfeld:durability></item></DIDL-Lite>
'
verbose: [MediaRenderer|Speaker Kiara] AVTransportURI has changed from '' to 'http://172.16.50.249:58509/28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D/bf3c0812-f90a-4a57-8376-88b2926402c7/be4075a1-0bb3-46cc-a15b-df27c00b22f0/e80489ec5858d9125f827e473fc643d1--199308651-0-9839.mp3'
debug: [MediaListManager] Update media list for uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0 http://172.16.50.249:58509/28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D/bf3c0812-f90a-4a57-8376-88b2926402c7/be4075a1-0bb3-46cc-a15b-df27c00b22f0/e80489ec5858d9125f827e473fc643d1--199308651-0-9839.mp3
verbose: [MediaListManager] Get media list for uri http://172.16.50.249:58509/28B23A4B-79C0-4CF4-BAEA-82DEB48BB79D/bf3c0812-f90a-4a57-8376-88b2926402c7/be4075a1-0bb3-46cc-a15b-df27c00b22f0/e80489ec5858d9125f827e473fc643d1--199308651-0-9839.mp3
verbose: [MediaRenderer|Speaker Kiara] TransportState has changed from 'NO_MEDIA_PRESENT' to 'TRANSITIONING'
verbose: [MediaRenderer|Speaker Kiara] CurrentTransportActions has changed from '' to 'Stop'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360529
debug: [MediaListManager] Media list for renderer uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0 is ready
debug: [MediaRenderer|Küche,Kiara] AVTransport subscription callback triggered on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] RoomStates has changed from 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=PLAYING,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=STOPPED' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=PLAYING,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=TRANSITIONING'
debug: [ZoneManager] Get room information object for: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a
debug: [MediaRenderer|Küche,Kiara] Room: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a: Set room renderer(s) ["uuid:706acf81-6189-4b3d-a373-0f69869f318f"] online to :true
debug: [ZoneManager] Get room information object for: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7
debug: [MediaRenderer|Küche,Kiara] Room: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7: Set room renderer(s) ["uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0"] online to :true
verbose: [MediaRenderer|Küche,Kiara] transportState has changed from 'STOPPED' to 'TRANSITIONING' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
debug: [MediaRenderer|Küche,Kiara] Set new updateId to: 779895
debug: ContentDirectory subscription callback triggered on device 'Raumfeld MediaServer'
debug: ContentDirectory subscription callback triggered on device 'Raumfeld MediaServer'
debug: [MediaRenderer|Speaker Kiara] AVTransport subscription callback triggered on device 'Speaker Kiara'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360530
debug: ContentDirectory subscription callback triggered on device 'Raumfeld MediaServer'
debug: ContentDirectory subscription callback triggered on device 'Raumfeld MediaServer'
verbose: [MediaListManager] Get media list for objectId: 0/Line In
debug: Call Search from Raumfeld MediaServer
debug: Result of Search for Raumfeld MediaServer is {"Result":"<DIDL-Lite xmlns=\"urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/\" xmlns:raumfeld=\"urn:schemas-raumfeld-com:meta-data/raumfeld\" lang=\"en\"/>","NumberReturned":"0","TotalMatches":"0","UpdateID":"4390415"}
debug: [MediaDataConverter] Got empty list
debug: [MediaRenderer|Speaker Kiara] AVTransport subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] PowerState has changed from 'IDLE' to 'ACTIVE'
verbose: [MediaRenderer|Speaker Kiara] TransportState has changed from 'TRANSITIONING' to 'PLAYING'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360531
debug: [MediaRenderer|Speaker Kiara] AVTransport subscription callback triggered on device 'Speaker Kiara'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360532
debug: [MediaRenderer|Küche,Kiara] AVTransport subscription callback triggered on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] RoomStates has changed from 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=PLAYING,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=TRANSITIONING' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=PLAYING,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=PLAYING'
debug: [ZoneManager] Get room information object for: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a
debug: [MediaRenderer|Küche,Kiara] Room: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a: Set room renderer(s) ["uuid:706acf81-6189-4b3d-a373-0f69869f318f"] online to :true
debug: [ZoneManager] Get room information object for: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7
debug: [MediaRenderer|Küche,Kiara] Room: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7: Set room renderer(s) ["uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0"] online to :true
verbose: [MediaRenderer|Küche,Kiara] transportState has changed from 'TRANSITIONING' to 'PLAYING' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
debug: [MediaRenderer|Küche,Kiara] Set new updateId to: 779896
debug: ContentDirectory subscription callback triggered on device 'Raumfeld MediaServer'
debug: ContentDirectory subscription callback triggered on device 'Raumfeld MediaServer'
verbose: [MediaListManager] Get media list for objectId: 0/Line In
debug: Call Search from Raumfeld MediaServer
debug: Result of Search for Raumfeld MediaServer is {"Result":"<DIDL-Lite xmlns=\"urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/\" xmlns:raumfeld=\"urn:schemas-raumfeld-com:meta-data/raumfeld\" xmlns:upnp=\"urn:schemas-upnp-org:metadata-1-0/upnp/\" xmlns:dc=\"http://purl.org/dc/elements/1.1/\" lang=\"en\"><item parentID=\"0/Line In\" id=\"0/Line In/uuid%3A04ee9b3c-10e4-41ca-beea-c6ab76f641ed\" restricted=\"1\"><raumfeld:name>Line In</raumfeld:name><upnp:class>object.item.audioItem.audioBroadcast.lineIn</upnp:class><raumfeld:section>Line In</raumfeld:section><dc:title>Teufel Soundbar</dc:title><res protocolInfo=\"http-get:*:audio/x-flac:*\">http://172.16.50.249:8888/stream.flac</res></item></DIDL-Lite>","NumberReturned":"1","TotalMatches":"1","UpdateID":"4390415"}
debug: ContentDirectory subscription callback triggered on device 'Raumfeld MediaServer'
debug: ContentDirectory subscription callback triggered on device 'Raumfeld MediaServer'
error: [MediaRenderer|Kiara] Error: connect ECONNREFUSED 172.16.50.249:60740
error: [MediaRenderer|Kiara] Error: connect ECONNREFUSED 172.16.50.249:60740
debug: [MediaRenderer|Küche,Kiara] RenderingControl subscription callback triggered on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] RoomVolumes has changed from 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=35' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=61'
verbose: [MediaRenderer|Küche,Kiara] Volume has changed from '46' to '61'
debug: [ZoneManager] Get room information object for: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a
debug: [MediaRenderer|Küche,Kiara] Room: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a: Set room renderer(s) ["uuid:706acf81-6189-4b3d-a373-0f69869f318f"] online to :true
debug: [ZoneManager] Get room information object for: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7
debug: [MediaRenderer|Küche,Kiara] Room: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7: Set room renderer(s) ["uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0"] online to :true
verbose: [MediaRenderer|Küche,Kiara] volume has changed from '35' to '61' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
debug: [MediaRenderer|Küche,Kiara] Set new updateId to: 779897
debug: [MediaRenderer|Speaker Kiara] RenderingControl subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] Volume has changed from '35' to '61'
verbose: [MediaRenderer|Speaker Kiara] VolumeDB has changed from '-11136' to '-6656'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360533
debug: [MediaRenderer|Küche,Kiara] RenderingControl subscription callback triggered on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] RoomVolumes has changed from 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=61' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=62'
verbose: [MediaRenderer|Küche,Kiara] Volume has changed from '61' to '62'
debug: [ZoneManager] Get room information object for: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a
debug: [MediaRenderer|Küche,Kiara] Room: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a: Set room renderer(s) ["uuid:706acf81-6189-4b3d-a373-0f69869f318f"] online to :true
debug: [ZoneManager] Get room information object for: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7
debug: [MediaRenderer|Küche,Kiara] Room: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7: Set room renderer(s) ["uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0"] online to :true
verbose: [MediaRenderer|Küche,Kiara] volume has changed from '61' to '62' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
debug: [MediaRenderer|Küche,Kiara] Set new updateId to: 779898
debug: [MediaRenderer|Speaker Kiara] RenderingControl subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] Volume has changed from '61' to '62'
verbose: [MediaRenderer|Speaker Kiara] VolumeDB has changed from '-6656' to '-6528'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360534
debug: [MediaRenderer|Küche,Kiara] RenderingControl subscription callback triggered on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] RoomVolumes has changed from 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=62' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=47'
verbose: [MediaRenderer|Küche,Kiara] Volume has changed from '62' to '47'
debug: [ZoneManager] Get room information object for: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a
debug: [MediaRenderer|Küche,Kiara] Room: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a: Set room renderer(s) ["uuid:706acf81-6189-4b3d-a373-0f69869f318f"] online to :true
debug: [ZoneManager] Get room information object for: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7
debug: [MediaRenderer|Küche,Kiara] Room: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7: Set room renderer(s) ["uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0"] online to :true
verbose: [MediaRenderer|Küche,Kiara] volume has changed from '62' to '47' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
debug: [MediaRenderer|Küche,Kiara] Set new updateId to: 779899
debug: [MediaRenderer|Speaker Kiara] RenderingControl subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] Volume has changed from '62' to '47'
verbose: [MediaRenderer|Speaker Kiara] VolumeDB has changed from '-6528' to '-9088'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360535
debug: [MediaRenderer|Küche,Kiara] RenderingControl subscription callback triggered on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] RoomVolumes has changed from 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=47' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=45'
verbose: [MediaRenderer|Küche,Kiara] Volume has changed from '47' to '46'
debug: [ZoneManager] Get room information object for: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a
debug: [MediaRenderer|Küche,Kiara] Room: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a: Set room renderer(s) ["uuid:706acf81-6189-4b3d-a373-0f69869f318f"] online to :true
debug: [ZoneManager] Get room information object for: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7
debug: [MediaRenderer|Küche,Kiara] Room: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7: Set room renderer(s) ["uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0"] online to :true
verbose: [MediaRenderer|Küche,Kiara] volume has changed from '47' to '45' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
debug: [MediaRenderer|Küche,Kiara] Set new updateId to: 779900
debug: [MediaRenderer|Speaker Kiara] RenderingControl subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] Volume has changed from '47' to '45'
verbose: [MediaRenderer|Speaker Kiara] VolumeDB has changed from '-9088' to '-9472'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360536
debug: [MediaRenderer|Küche,Kiara] RenderingControl subscription callback triggered on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] RoomVolumes has changed from 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=45' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=43'
debug: [ZoneManager] Get room information object for: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a
debug: [MediaRenderer|Küche,Kiara] Room: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a: Set room renderer(s) ["uuid:706acf81-6189-4b3d-a373-0f69869f318f"] online to :true
debug: [ZoneManager] Get room information object for: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7
debug: [MediaRenderer|Küche,Kiara] Room: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7: Set room renderer(s) ["uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0"] online to :true
verbose: [MediaRenderer|Küche,Kiara] volume has changed from '45' to '43' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
debug: [MediaRenderer|Küche,Kiara] Set new updateId to: 779901
debug: [MediaRenderer|Speaker Kiara] RenderingControl subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] Volume has changed from '45' to '43'
verbose: [MediaRenderer|Speaker Kiara] VolumeDB has changed from '-9472' to '-9856'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360537
debug: [MediaRenderer|Küche,Kiara] RenderingControl subscription callback triggered on device 'Küche,Kiara'
verbose: [MediaRenderer|Küche,Kiara] RoomVolumes has changed from 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=43' to 'uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a=46,uuid:bf3c0812-f90a-4a57-8376-88b2926402c7=42'
debug: [ZoneManager] Get room information object for: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a
debug: [MediaRenderer|Küche,Kiara] Room: uuid:a1dd1fbe-d92d-4f9b-958c-4481ae0b137a: Set room renderer(s) ["uuid:706acf81-6189-4b3d-a373-0f69869f318f"] online to :true
debug: [ZoneManager] Get room information object for: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7
debug: [MediaRenderer|Küche,Kiara] Room: uuid:bf3c0812-f90a-4a57-8376-88b2926402c7: Set room renderer(s) ["uuid:be4075a1-0bb3-46cc-a15b-df27c00b22f0"] online to :true
verbose: [MediaRenderer|Küche,Kiara] volume has changed from '43' to '42' on room 'uuid:bf3c0812-f90a-4a57-8376-88b2926402c7'
debug: [MediaRenderer|Küche,Kiara] Set new updateId to: 779902
debug: [MediaRenderer|Speaker Kiara] RenderingControl subscription callback triggered on device 'Speaker Kiara'
verbose: [MediaRenderer|Speaker Kiara] Volume has changed from '43' to '42'
verbose: [MediaRenderer|Speaker Kiara] VolumeDB has changed from '-9856' to '-9984'
debug: [MediaRenderer|Speaker Kiara] Set new updateId to: 360538
Alex9779 commented 6 years ago

At the very top you can see that the zone changes but then also the connection to the server is terminated and everything is read from the start and because all connection to the server are dropped the system goes to 'not ready'...

ChriD commented 6 years ago

after some tests i can confirm the issue. I am not sure yet why it happens. I have to check if its a bug or if the device list i get from the raumfeld host itself may be corrupt-

ChriD commented 6 years ago

I found a bug in the node-raumkernel in lib.manager.deviceManager. Every time the device list changed it's device count the mediaServer device was recreated dua a bug and therfore the system initiated a new init. Version 1.1.10 will fix the issue and will be up shortly

@Alex9779 EDIT: git and NPM is up to date. Would you be so nice to check the fix?

Alex9779 commented 6 years ago

Ya cool will test when got some time and I can use the system for testing...

Alex9779 commented 6 years ago

Ok seems this is fixed, couldn't get the error again not matter what I do... Thanks!