Raumserver ohne Funktion und automatisch beendet

[NSmartHome]

Hi,
ich habe den Raumserver auf einem Raspberry 3 installiert und irgendwie auch zum Laufen gebracht (Anpassung S99raumserver). Leider läuft der Server immer nur eine zeitlang und beendet sich dann. Zudem werden die requests zwar angenommen und auch versucht sie auszuführen, aber das Raumfeld Gerät (Sounddeck) zeigt sich davon unbeeindruckt. Logdatei sieht z.B. wie folgt aus.

2017.03.18 12:21:23.017 DEBUG: Log level was set to: DEBUG [init]
2017.03.18 12:21:23.018 DEBUG: Init OpenHome UPNP Control Stack [init]
2017.03.18 12:21:23.020 DEBUG: Retrieve network adapter list [init]
2017.03.18 12:21:23.020 DEBUG: Adapter 0: eth0 [init]
2017.03.18 12:21:23.020 DEBUG: Waiting for Adapter eth0 to appear [init]
2017.03.18 12:21:23.021 DEBUG: Using Network Adapter : eth0 [init]
2017.03.18 12:21:23.021 DEBUG: Getting IP-Subnet of adapter [init]
2017.03.18 12:21:23.021 INFO: Starting OpenHome UPNP Control Stack with network adapter: eth0 [init]
2017.03.18 12:21:23.024 DEBUG: Starting UPNP-Stack auto referesh thread [discover]
2017.03.18 12:21:23.025 INFO: Kernel initialized! Waiting for Raumfeld System to appear! [init]
2017.03.18 12:21:23.025 DEBUG: Preparing Server-Manager-Engineer... [init]
2017.03.18 12:21:23.026 DEBUG: Create RequestActionManager-Manager... [createManagers]
2017.03.18 12:21:23.026 DEBUG: Raumserver Manager-Engineer is prepared [init]
2017.03.18 12:21:23.027 DEBUG: Starting webserver for requests on port: 8080 [start]
2017.03.18 12:21:23.039 INFO: Webserver for requests started (Port: 8080) [start]
2017.03.18 12:21:23.683 DEBUG: UPNP Device found: ATP IGD(00e0fc37-2626-2828-2600-e8cd2d1c48e8) [onDeviceFound]
2017.03.18 12:21:23.683 DEBUG: Adding device 'ATP IGD' to manager [addDevice]
2017.03.18 12:21:23.684 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:21:23.684 DEBUG: No device was created for uuid:00e0fc37-2626-2828-2600-e8cd2d1c48e8. Device is not usable! [createDeviceFromDeviceXML]
2017.03.18 12:21:23.688 DEBUG: UPNP Device found: WANDevice(00e0fc37-2626-2828-2601-e8cd2d1c48e8) [onDeviceFound]
2017.03.18 12:21:23.688 DEBUG: Adding device 'WANDevice' to manager [addDevice]
2017.03.18 12:21:23.688 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:21:23.689 DEBUG: No device was created for uuid:00e0fc37-2626-2828-2600-e8cd2d1c48e8. Device is not usable! [createDeviceFromDeviceXML]
2017.03.18 12:21:23.693 DEBUG: UPNP Device found: WANConnectionDevice(00e0fc37-2626-2828-2602-e8cd2d1c48e8) [onDeviceFound]
2017.03.18 12:21:23.693 DEBUG: Adding device 'WANConnectionDevice' to manager [addDevice]
2017.03.18 12:21:23.694 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:21:23.694 DEBUG: No device was created for uuid:00e0fc37-2626-2828-2600-e8cd2d1c48e8. Device is not usable! [createDeviceFromDeviceXML]
2017.03.18 12:21:23.695 DEBUG: UPNP Device found: DMR-BST740/745(4D454930-0300-1000-8000-A813741F5791) [onDeviceFound]
2017.03.18 12:21:23.696 DEBUG: Adding device 'DMR-BST740/745' to manager [addDevice]
2017.03.18 12:21:23.696 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:21:23.696 DEBUG: Createing device (uuid:4D454930-0300-1000-8000-A813741F5791) [createDeviceFromDeviceXML]
2017.03.18 12:21:23.697 DEBUG: Media Renderer device created (uuid:4D454930-0300-1000-8000-A813741F5791) [createDeviceFromDeviceXML]
2017.03.18 12:21:23.697 DEBUG: Create AVTransport-Proxy for media renderer uuid:4D454930-0300-1000-8000-A813741F5791 [createProxies]
2017.03.18 12:21:23.700 DEBUG: Create RenderingControl-Proxy for media renderer uuid:4D454930-0300-1000-8000-A813741F5791 [createProxies]
2017.03.18 12:21:23.703 DEBUG: Create ConnectionManager-Proxy for media renderer uuid:4D454930-0300-1000-8000-A813741F5791 [createProxies]
2017.03.18 12:21:23.706 DEBUG: Media Renderer 'DMR-BST740/745' is now useable! [addDevice]
2017.03.18 12:21:23.706 DEBUG: UPNP Device found: LANDevice(00e0fc37-2626-2828-2603-e8cd2d1c48e8) [onDeviceFound]
2017.03.18 12:21:23.707 DEBUG: Adding device 'LANDevice' to manager [addDevice]
2017.03.18 12:21:23.707 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:21:23.707 DEBUG: No device was created for uuid:00e0fc37-2626-2828-2600-e8cd2d1c48e8. Device is not usable! [createDeviceFromDeviceXML]
2017.03.18 12:21:53.530 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:21:54.054 DEBUG: UPNP Device found: DMR-BST740/745(4D454930-0100-1000-8000-A813741F5791) [onDeviceFound]
2017.03.18 12:21:54.054 DEBUG: Adding device 'DMR-BST740/745' to manager [addDevice]
2017.03.18 12:21:54.054 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:21:54.055 DEBUG: Media Server device created (uuid:4D454930-0100-1000-8000-A813741F5791) [createDeviceFromDeviceXML]
2017.03.18 12:21:54.055 DEBUG: Create ContentDirectory-Proxy for media server uuid:4D454930-0100-1000-8000-A813741F5791 [createProxies]
2017.03.18 12:21:54.059 DEBUG: Create ConnectionManager-Proxy for media server uuid:4D454930-0100-1000-8000-A813741F5791 [createProxies]
2017.03.18 12:21:54.063 DEBUG: Media Server 'DMR-BST740/745' is now useable! [addDevice]
2017.03.18 12:22:24.048 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:22:54.553 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:22:55.481 DEBUG: UPNP Device found: Raumfeld Device(73b332f3-b42d-4859-9683-da951ba1c3f5) [onDeviceFound]
2017.03.18 12:22:55.482 DEBUG: Adding device 'Raumfeld Device' to manager [addDevice]
2017.03.18 12:22:55.482 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:22:55.482 DEBUG: No device was created for uuid:73b332f3-b42d-4859-9683-da951ba1c3f5. Device is not usable! [createDeviceFromDeviceXML]
2017.03.18 12:23:25.062 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:23:27.481 DEBUG: UPNP Device found: Raumfeld ConfigDevice(a2bfa6bc-0a0e-4ace-9ced-90eb87582b17) [onDeviceFound]
2017.03.18 12:23:27.482 DEBUG: Adding device 'Raumfeld ConfigDevice' to manager [addDevice]
2017.03.18 12:23:27.482 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:23:27.482 DEBUG: No device was created for uuid:a2bfa6bc-0a0e-4ace-9ced-90eb87582b17. Device is not usable! [createDeviceFromDeviceXML]
2017.03.18 12:23:31.483 DEBUG: UPNP Device found: Speaker Wohnzimmer(976b8da0-73b9-431e-879d-254ba8ac17dc) [onDeviceFound]
2017.03.18 12:23:31.483 DEBUG: Adding device 'Speaker Wohnzimmer' to manager [addDevice]
2017.03.18 12:23:31.483 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:23:31.483 DEBUG: Createing device (uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [createDeviceFromDeviceXML]
2017.03.18 12:23:31.483 DEBUG: Media Renderer device created (uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [createDeviceFromDeviceXML]
2017.03.18 12:23:31.484 DEBUG: Create AVTransport-Proxy for media renderer uuid:976b8da0-73b9-431e-879d-254ba8ac17dc [createProxies]
2017.03.18 12:23:31.485 DEBUG: Create RenderingControl-Proxy for media renderer uuid:976b8da0-73b9-431e-879d-254ba8ac17dc [createProxies]
2017.03.18 12:23:31.486 DEBUG: Create ConnectionManager-Proxy for media renderer uuid:976b8da0-73b9-431e-879d-254ba8ac17dc [createProxies]
2017.03.18 12:23:31.487 DEBUG: Media Renderer 'Speaker Wohnzimmer' is now useable! [addDevice]
2017.03.18 12:23:31.553 DEBUG: A property on AvTransport proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedAvTransportProxy]
2017.03.18 12:23:31.554 DEBUG: A property on AvTransport proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedAvTransportProxy]
2017.03.18 12:23:31.554 DEBUG: A property on AvTransport proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedAvTransportProxy]
2017.03.18 12:23:31.557 DEBUG: A property on RenderingControl proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedRenderingControlProxy]
2017.03.18 12:23:31.558 DEBUG: A property on RenderingControl proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedRenderingControlProxy]
2017.03.18 12:23:31.558 DEBUG: A property on RenderingControl proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedRenderingControlProxy]
2017.03.18 12:23:39.064 DEBUG: UPNP Device lost: Raumfeld Device(73b332f3-b42d-4859-9683-da951ba1c3f5) [onDeviceLost]
2017.03.18 12:23:39.064 DEBUG: Removing device 'Raumfeld Device' from manager [removeDevice]
2017.03.18 12:23:39.064 DEBUG: UPNP device 'Raumfeld Device' removed [removeDevice]
2017.03.18 12:23:53.517 DEBUG: UPNP Device found: Raumfeld MediaServer(251aa88d-92e7-4e5f-ab3f-28fe711b8b2a) [onDeviceFound]
2017.03.18 12:23:53.518 DEBUG: Adding device 'Raumfeld MediaServer' to manager [addDevice]
2017.03.18 12:23:53.518 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:23:53.518 DEBUG: Media Server device created (uuid:251aa88d-92e7-4e5f-ab3f-28fe711b8b2a) [createDeviceFromDeviceXML]
2017.03.18 12:23:53.518 DEBUG: Create ContentDirectory-Proxy for media server uuid:251aa88d-92e7-4e5f-ab3f-28fe711b8b2a [createProxies]
2017.03.18 12:23:53.519 DEBUG: Create ConnectionManager-Proxy for media server uuid:251aa88d-92e7-4e5f-ab3f-28fe711b8b2a [createProxies]
2017.03.18 12:23:53.521 DEBUG: Media Server 'Raumfeld MediaServer' is now useable! [addDevice]
2017.03.18 12:23:53.521 DEBUG: Starting all automatic requests of zone manager! [startZoneRequests]
2017.03.18 12:23:53.521 DEBUG: Get zone configuration with updateId: [doGetZoneRequest]
2017.03.18 12:23:53.521 DEBUG: Try to create new request: http://192.168.2.126:47365/getZones [request]
2017.03.18 12:23:53.522 DEBUG: Request '3338135' (http://192.168.2.126:47365/getZones) started [request]
2017.03.18 12:23:53.522 INFO: Raumfeld System is now online! [onRaumfeldSystemOnline]
2017.03.18 12:23:53.553 DEBUG: Redirection to url 'http://192.168.2.126:47365/06f34f96-5607-45a5-9a41-83f09e453ee0/getZones' found! Do redirect request 3338135 [requestHandlerThread]
2017.03.18 12:23:53.554 DEBUG: Aborting current request [requestHandlerThread]
2017.03.18 12:23:53.563 DEBUG: Aborting of request object thread done! [requestHandlerThread]
2017.03.18 12:23:53.603 DEBUG: Emitting signal for finished requests [requestHandlerThread]
2017.03.18 12:23:53.603 DEBUG: Zone configuration request finished [zoneRequestFinished]
2017.03.18 12:23:53.603 DEBUG: Parsing zone configuration XML [parseZoneConfiguration]
2017.03.18 12:23:53.604 DEBUG: Found zone in XML -> Parse! [parseZoneConfiguration]
2017.03.18 12:23:53.604 DEBUG: Parsing zone node... [addZoneInformationFromXmlNode]
2017.03.18 12:23:53.604 DEBUG: Signaling one configuration changed! [parseZoneConfiguration]
2017.03.18 12:23:53.604 DEBUG: Parsing zone configuration XML finished [parseZoneConfiguration]
2017.03.18 12:23:53.604 DEBUG: Get zone configuration with updateId: 3491329637 [doGetZoneRequest]
2017.03.18 12:23:53.604 DEBUG: Try to create new request: http://192.168.2.126:47365/getZones [request]
2017.03.18 12:23:53.604 DEBUG: Request '3338136' (http://192.168.2.126:47365/getZones) started [request]
2017.03.18 12:23:53.605 DEBUG: Emitting signal for finished requests finished [requestHandlerThread]
2017.03.18 12:23:53.605 DEBUG: Clearing finished requests list [requestHandlerThread]
2017.03.18 12:23:53.605 DEBUG: Delete request '3338135' from pending list [cleanupRequests]
2017.03.18 12:23:53.605 DEBUG: Requests cleaned up! Count: 1 [cleanupRequests]
2017.03.18 12:23:53.645 DEBUG: Redirection to url 'http://192.168.2.126:47365/522435af-1a3c-4634-abbe-649fdc27253a/getZones' found! Do redirect request 3338136 [requestHandlerThread]
2017.03.18 12:23:53.645 DEBUG: Aborting current request [requestHandlerThread]
2017.03.18 12:23:53.646 DEBUG: Aborting of request object thread done! [requestHandlerThread]
2017.03.18 12:23:55.568 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:24:09.570 DEBUG: UPNP Device lost: Raumfeld MediaServer(251aa88d-92e7-4e5f-ab3f-28fe711b8b2a) [onDeviceLost]
2017.03.18 12:24:09.571 DEBUG: Removing device 'Raumfeld MediaServer' from manager [removeDevice]
2017.03.18 12:24:09.571 DEBUG: UPNP device 'Raumfeld MediaServer' removed [removeDevice]
2017.03.18 12:24:09.572 DEBUG: Media Server 'Raumfeld MediaServer' removed [removeDevice]
2017.03.18 12:24:09.572 DEBUG: Starting all automatic requests of zone manager! [startZoneRequests]
2017.03.18 12:24:09.572 ERROR: Trying to start zone configuration request without host-IP [doGetZoneRequest]
2017.03.18 12:24:09.572 INFO: Raumfeld System is now online! [onRaumfeldSystemOnline]
2017.03.18 12:24:09.573 DEBUG: UPNP Device lost: Speaker Wohnzimmer(976b8da0-73b9-431e-879d-254ba8ac17dc) [onDeviceLost]
2017.03.18 12:24:09.573 DEBUG: Removing device 'Speaker Wohnzimmer' from manager [removeDevice]
2017.03.18 12:24:09.573 DEBUG: UPNP device 'Speaker Wohnzimmer' removed [removeDevice]
2017.03.18 12:24:09.573 DEBUG: Media Renderer 'Speaker Wohnzimmer' removed [removeDevice]
2017.03.18 12:24:09.574 DEBUG: UPNP Device lost: Raumfeld ConfigDevice(a2bfa6bc-0a0e-4ace-9ced-90eb87582b17) [onDeviceLost]
2017.03.18 12:24:09.574 DEBUG: Removing device 'Raumfeld ConfigDevice' from manager [removeDevice]
2017.03.18 12:24:09.574 DEBUG: UPNP device 'Raumfeld ConfigDevice' removed [removeDevice]
2017.03.18 12:24:26.073 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:24:56.579 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:25:27.084 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:25:57.590 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:26:28.095 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:26:58.601 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:27:29.106 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:27:59.612 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:28:30.117 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:28:30.559 DEBUG: Processing Request: MUTE / : [requestProcessingWorkerThread]
2017.03.18 12:28:30.560 DEBUG: Request duration: 1ms: MUTE / : [execute]
2017.03.18 12:28:30.560 DEBUG: Popping Request: MUTE / : [requestProcessingWorkerThread]
2017.03.18 12:29:00.623 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:29:31.128 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:30:01.634 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:30:11.493 DEBUG: UPNP Device found: Wohnzimmer(C0B7AE93-98DA-45C1-B743-CA271F7366D1) [onDeviceFound]
2017.03.18 12:30:11.494 DEBUG: Adding device 'Wohnzimmer' to manager [addDevice]
2017.03.18 12:30:11.494 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:30:11.494 DEBUG: Createing device (uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [createDeviceFromDeviceXML]
2017.03.18 12:30:11.494 DEBUG: Media Renderer device created (uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [createDeviceFromDeviceXML]
2017.03.18 12:30:11.494 DEBUG: Create AVTransport-Proxy for media renderer uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1 [createProxies]
2017.03.18 12:30:11.495 DEBUG: Create RenderingControl-Proxy for media renderer uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1 [createProxies]
2017.03.18 12:30:11.495 DEBUG: Create ConnectionManager-Proxy for media renderer uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1 [createProxies]
2017.03.18 12:30:11.496 DEBUG: Media Renderer 'Wohnzimmer' is now useable! [addDevice]
2017.03.18 12:30:11.557 DEBUG: A property on AvTransport proxy has changed (Device: uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [propertyChangedAvTransportProxy]
2017.03.18 12:30:11.558 DEBUG: Get MediaList: ztl:uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1 for container: 0/Playlists/MyPlaylists/Einslive%20Webradio [loadMediaItemListByContainerId]
2017.03.18 12:30:11.558 DEBUG: A property on AvTransport proxy has changed (Device: uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [propertyChangedAvTransportProxy]
2017.03.18 12:30:11.558 DEBUG: A property on AvTransport proxy has changed (Device: uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [propertyChangedAvTransportProxy]
2017.03.18 12:30:11.563 DEBUG: A property on RenderingControl proxy has changed (Device: uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [propertyChangedRenderingControlProxy]
2017.03.18 12:30:11.564 DEBUG: A property on RenderingControl proxy has changed (Device: uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [propertyChangedRenderingControlProxy]
2017.03.18 12:30:11.564 DEBUG: A property on RenderingControl proxy has changed (Device: uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [propertyChangedRenderingControlProxy]
2017.03.18 12:30:12.108 DEBUG: A property on AvTransport proxy has changed (Device: uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [propertyChangedAvTransportProxy]
2017.03.18 12:30:12.109 DEBUG: A property on AvTransport proxy has changed (Device: uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [propertyChangedAvTransportProxy]
2017.03.18 12:30:12.810 DEBUG: A property on AvTransport proxy has changed (Device: uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [propertyChangedAvTransportProxy]
2017.03.18 12:30:12.811 DEBUG: A property on AvTransport proxy has changed (Device: uuid:C0B7AE93-98DA-45C1-B743-CA271F7366D1) [propertyChangedAvTransportProxy]
2017.03.18 12:30:32.139 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:30:46.141 DEBUG: UPNP Device lost: Wohnzimmer(C0B7AE93-98DA-45C1-B743-CA271F7366D1) [onDeviceLost]
2017.03.18 12:30:46.142 DEBUG: Removing device 'Wohnzimmer' from manager [removeDevice]
2017.03.18 12:30:46.142 DEBUG: UPNP device 'Wohnzimmer' removed [removeDevice]
2017.03.18 12:30:46.143 DEBUG: Media Renderer 'Wohnzimmer' removed [removeDevice]
2017.03.18 12:31:02.645 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:31:33.150 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:32:03.657 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:32:34.163 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:32:54.490 DEBUG: UPNP Device found: Raumfeld Device(73b332f3-b42d-4859-9683-da951ba1c3f5) [onDeviceFound]
2017.03.18 12:32:54.490 DEBUG: Adding device 'Raumfeld Device' to manager [addDevice]
2017.03.18 12:32:54.491 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:32:54.491 DEBUG: No device was created for uuid:73b332f3-b42d-4859-9683-da951ba1c3f5. Device is not usable! [createDeviceFromDeviceXML]
2017.03.18 12:33:04.668 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:33:18.669 DEBUG: UPNP Device lost: Raumfeld Device(73b332f3-b42d-4859-9683-da951ba1c3f5) [onDeviceLost]
2017.03.18 12:33:18.670 DEBUG: Removing device 'Raumfeld Device' from manager [removeDevice]
2017.03.18 12:33:18.670 DEBUG: UPNP device 'Raumfeld Device' removed [removeDevice]
2017.03.18 12:33:26.489 DEBUG: UPNP Device found: Raumfeld ConfigDevice(a2bfa6bc-0a0e-4ace-9ced-90eb87582b17) [onDeviceFound]
2017.03.18 12:33:26.489 DEBUG: Adding device 'Raumfeld ConfigDevice' to manager [addDevice]
2017.03.18 12:33:26.490 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:33:26.490 DEBUG: No device was created for uuid:a2bfa6bc-0a0e-4ace-9ced-90eb87582b17. Device is not usable! [createDeviceFromDeviceXML]
2017.03.18 12:33:30.488 DEBUG: UPNP Device found: Speaker Wohnzimmer(976b8da0-73b9-431e-879d-254ba8ac17dc) [onDeviceFound]
2017.03.18 12:33:30.489 DEBUG: Adding device 'Speaker Wohnzimmer' to manager [addDevice]
2017.03.18 12:33:30.489 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:33:30.489 DEBUG: Createing device (uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [createDeviceFromDeviceXML]
2017.03.18 12:33:30.490 DEBUG: Media Renderer device created (uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [createDeviceFromDeviceXML]
2017.03.18 12:33:30.490 DEBUG: Create AVTransport-Proxy for media renderer uuid:976b8da0-73b9-431e-879d-254ba8ac17dc [createProxies]
2017.03.18 12:33:30.491 DEBUG: Create RenderingControl-Proxy for media renderer uuid:976b8da0-73b9-431e-879d-254ba8ac17dc [createProxies]
2017.03.18 12:33:30.495 DEBUG: Create ConnectionManager-Proxy for media renderer uuid:976b8da0-73b9-431e-879d-254ba8ac17dc [createProxies]
2017.03.18 12:33:30.496 DEBUG: Media Renderer 'Speaker Wohnzimmer' is now useable! [addDevice]
2017.03.18 12:33:30.537 DEBUG: A property on AvTransport proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedAvTransportProxy]
2017.03.18 12:33:30.538 DEBUG: A property on AvTransport proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedAvTransportProxy]
2017.03.18 12:33:30.538 DEBUG: A property on AvTransport proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedAvTransportProxy]
2017.03.18 12:33:30.542 DEBUG: A property on RenderingControl proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedRenderingControlProxy]
2017.03.18 12:33:30.542 DEBUG: A property on RenderingControl proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedRenderingControlProxy]
2017.03.18 12:33:30.542 DEBUG: A property on RenderingControl proxy has changed (Device: uuid:976b8da0-73b9-431e-879d-254ba8ac17dc) [propertyChangedRenderingControlProxy]
2017.03.18 12:33:35.174 DEBUG: Refreshing UPNP device list [refreshDeviceListThread]
2017.03.18 12:33:49.176 DEBUG: UPNP Device lost: Speaker Wohnzimmer(976b8da0-73b9-431e-879d-254ba8ac17dc) [onDeviceLost]
2017.03.18 12:33:49.177 DEBUG: Removing device 'Speaker Wohnzimmer' from manager [removeDevice]
2017.03.18 12:33:49.177 DEBUG: UPNP device 'Speaker Wohnzimmer' removed [removeDevice]
2017.03.18 12:33:49.178 DEBUG: Media Renderer 'Speaker Wohnzimmer' removed [removeDevice]
2017.03.18 12:33:49.178 DEBUG: UPNP Device lost: Raumfeld ConfigDevice(a2bfa6bc-0a0e-4ace-9ced-90eb87582b17) [onDeviceLost]
2017.03.18 12:33:49.178 DEBUG: Removing device 'Raumfeld ConfigDevice' from manager [removeDevice]
2017.03.18 12:33:49.178 DEBUG: UPNP device 'Raumfeld ConfigDevice' removed [removeDevice]
2017.03.18 12:33:52.508 DEBUG: UPNP Device found: Raumfeld MediaServer(251aa88d-92e7-4e5f-ab3f-28fe711b8b2a) [onDeviceFound]
2017.03.18 12:33:52.508 DEBUG: Adding device 'Raumfeld MediaServer' to manager [addDevice]
2017.03.18 12:33:52.508 DEBUG: Try to create device from device XML [createDeviceFromDeviceXML]
2017.03.18 12:33:52.509 DEBUG: Media Server device created (uuid:251aa88d-92e7-4e5f-ab3f-28fe711b8b2a) [createDeviceFromDeviceXML]
2017.03.18 12:33:52.509 DEBUG: Create ContentDirectory-Proxy for media server uuid:251aa88d-92e7-4e5f-ab3f-28fe711b8b2a [createProxies]
2017.03.18 12:33:52.510 DEBUG: Create ConnectionManager-Proxy for media server uuid:251aa88d-92e7-4e5f-ab3f-28fe711b8b2a [createProxies]
2017.03.18 12:33:52.513 DEBUG: Media Server 'Raumfeld MediaServer' is now useable! [addDevice]
2017.03.18 12:33:52.513 DEBUG: Starting all automatic requests of zone manager! [startZoneRequests]
2017.03.18 12:33:52.513 DEBUG: Get zone configuration with updateId: [doGetZoneRequest]
2017.03.18 12:33:52.514 DEBUG: Try to create new request: http://192.168.2.126:47365/getZones [request]
2017.03.18 12:33:52.514 DEBUG: Request '3154892' (http://192.168.2.126:47365/getZones) started [request]

Es endet dann immer mit dem letzten Request. Im Anschluss ist der Server beendet und muss neu gestartet werden.

Auffällig ist auch, dass der Windows Raumserver installer offensichtlich das Gerät nicht finden kann. In der Device Liste wird es nicht aufgeführt (Liste bleibt leer).

Im Logfile hat er das Gerät aber zumindest gefunden (IP ...126 ist das Sounddeck).

Hast Du eine Ahnung was falsch ist?

Vielen Dank!

[ChriD]

magst du lieber mal den node-raumserver probieren?

[NSmartHome]

ja, würde ich. War allerdings schon ein ziemlicher Akt mit der Installation. Kenne mich mit dem Raspberry leider 0,0 aus. So wie es aussieht, habe ich den node-raumserver soweit mal installiert. Wie wird er denn dann gestartet? Muss ich dazu noch eine Datei ins init_d kopieren auf dem Raspi? Sorry für meine Unwissenheit :(

pi@iRasPi:/ $ npm list
/
└─┬ node-raumserver@0.0.4
└─┬ node-raumkernel@1.0.14
├─┬ node-ssdp@3.2.0
│ ├─┬ async@2.1.5
│ │ └── lodash@4.17.4
│ ├── bluebird@3.5.0
│ ├─┬ debug@2.6.3
│ │ └── ms@0.7.2
│ ├── extend@3.0.0
│ └── ip@1.1.5
├─┬ query-string@4.3.2
│ ├── object-assign@4.1.1
│ └── strict-uri-encode@1.1.0
├─┬ request@2.81.0
│ ├── aws-sign2@0.6.0
│ ├── aws4@1.6.0
│ ├── caseless@0.12.0
│ ├─┬ combined-stream@1.0.5
│ │ └── delayed-stream@1.0.0
│ ├── forever-agent@0.6.1
│ ├─┬ form-data@2.1.2
│ │ └── asynckit@0.4.0
│ ├─┬ har-validator@4.2.1
│ │ ├─┬ ajv@4.11.5
│ │ │ ├── co@4.6.0
│ │ │ └─┬ json-stable-stringify@1.0.1
│ │ │ └── jsonify@0.0.0
│ │ └── har-schema@1.0.5
│ ├─┬ hawk@3.1.3
│ │ ├── boom@2.10.1
│ │ ├── cryptiles@2.0.5
│ │ ├── hoek@2.16.3
│ │ └── sntp@1.0.9
│ ├─┬ http-signature@1.1.1
│ │ ├── assert-plus@0.2.0
│ │ ├─┬ jsprim@1.4.0
│ │ │ ├── assert-plus@1.0.0
│ │ │ ├── extsprintf@1.0.2
│ │ │ ├── json-schema@0.2.3
│ │ │ └── verror@1.3.6
│ │ └─┬ sshpk@1.11.0
│ │ ├── asn1@0.2.3
│ │ ├── assert-plus@1.0.0
│ │ ├── bcrypt-pbkdf@1.0.1
│ │ ├─┬ dashdash@1.14.1
│ │ │ └── assert-plus@1.0.0
│ │ ├── ecc-jsbn@0.1.1
│ │ ├─┬ getpass@0.1.6
│ │ │ └── assert-plus@1.0.0
│ │ ├── jodid25519@1.0.2
│ │ ├── jsbn@0.1.1
│ │ └── tweetnacl@0.14.5
│ ├── is-typedarray@1.0.0
│ ├── isstream@0.1.2
│ ├── json-stringify-safe@5.0.1
│ ├─┬ mime-types@2.1.14
│ │ └── mime-db@1.26.0
│ ├── oauth-sign@0.8.2
│ ├── performance-now@0.2.0
│ ├── qs@6.4.0
│ ├── safe-buffer@5.0.1
│ ├── stringstream@0.0.5
│ ├─┬ tough-cookie@2.3.2
│ │ └── punycode@1.4.1
│ ├── tunnel-agent@0.6.0
│ └── uuid@3.0.1
├─┬ upnp-device-client@1.0.2
│ ├─┬ concat-stream@1.6.0
│ │ ├── inherits@2.0.3
│ │ ├─┬ readable-stream@2.2.6
│ │ │ ├── buffer-shims@1.0.0
│ │ │ ├── core-util-is@1.0.2
│ │ │ ├── isarray@1.0.0
│ │ │ ├── process-nextick-args@1.0.7
│ │ │ ├── string_decoder@0.10.31
│ │ │ └── util-deprecate@1.0.2
│ │ └── typedarray@0.0.6
│ ├── elementtree@0.1.7
│ └── network-address@1.1.2
├─┬ winston@2.3.1
│ ├── async@1.0.0
│ ├── colors@1.0.3
│ ├── cycle@1.0.3
│ ├── eyes@0.1.8
│ └── stack-trace@0.0.9
├── winston-daily-rotate-file@1.4.6
└─┬ xml2js@0.4.17
├── sax@1.1.4
└── xmlbuilder@4.2.1

[ChriD]

In dem ordner wo du den install gemacht hast einfach 'node test.js' in der console eintipseln.
Wie man das beim start des rpi autom. macht wär z.B. so
http://stackoverflow.com/questions/21542304/start-node-js-app-on-boot

[NSmartHome]

scheint schon mal zu starten (zusätzlich "npm start node-raumserver" vorher). Leider erkennt er den Raumfeld Host nicht, obwohl er läuft (letzte Meldung wird weiter wiederholt).

pi@iRasPi:~/node_modules/node-raumserver $ node test.js
info: [Raumserver] Welcome to raumserver v0.0.4 (raumkernel v1.0.14)
verbose: [Raumserver] Setting up raumkernel
verbose: [Raumkernel] Setting up manager disposer
verbose: [ManagerDisposer] Creating device manager
verbose: [ManagerDisposer] Creating zone manager
verbose: [ManagerDisposer] Creating media list manager
verbose: [Raumkernel] Creating controllers
debug: [Raumkernel] Bind manager events
info: [DeviceManager] Start searching for raumfeld host
verbose: [Raumserver] Starting HTTP server to receive requests
verbose: [Raumserver] Starting bonjour server for advertising
verbose: [Raumserver] Raumserver listening on port 8080
error: uncaughtException: ENOENT: no such file or directory, open 'logs/app.2017-03-20-0754.log' date=Mon Mar 20 2017 08:54:05 GMT+0100 (CET), pid=26527, uid=1000, gid=1000, cwd=/home/pi/node_modules/node-raumserver, execPath=/usr/bin/nodejs, version=v7.7.3, argv=[/usr/bin/nodejs, /home/pi/node_modules/node-raumserver/test.js], rss=39493632, heapTotal=19193856, heapUsed=13897908, external=429934, loadavg=[0.17724609375, 0.05517578125, 0.013671875], uptime=37941
Error: ENOENT: no such file or directory, open 'logs/app.2017-03-20-0754.log'
warn: [DeviceManager] Raumfeld host not found. Retrying discovery!
warn: [DeviceManager] Raumfeld host not found. Retrying discovery!
warn: [DeviceManager] Raumfeld host not found. Retrying discovery!
warn: [DeviceManager] Raumfeld host not found. Retrying discovery!
...

[NSmartHome]

habe mal versucht den raumkernel separat zu starten, aber mit ähnlichem Resultat

pi@RasPi:/node_modules/node-raumkernel $ node test.js
info: [DeviceManager] Start searching for raumfeld host
error: uncaughtException: EACCES: permission denied, open '/app.2017-03-20-0853.log' date=Mon Mar 20 2017 09:53:48 GMT+0100 (CET), pid=32005, uid=1000, gid=1000, cwd=/node_modules/node-raumkernel, execPath=/usr/bin/nodejs, version=v7.7.3, argv=[/usr/bin/nodejs, /node_modules/node-raumkernel/test.js], rss=40615936, heapTotal=20242432, heapUsed=12256456, external=141603, loadavg=[0.080078125, 0.0166015625, 0.00537109375], uptime=41524
Error: EACCES: permission denied, open '/app.2017-03-20-0853.log'
warn: [DeviceManager] Raumfeld host not found. Retrying discovery!
warn: [DeviceManager] Raumfeld host not found. Retrying discovery!
warn: [DeviceManager] Raumfeld host not found. Retrying discovery!

[ChriD]

Du hast nur das sounddeck?
Der node-kernel such nacht folgendem UPNP Device
"urn:schemas-raumfeld-com:device:ConfigDevice:1"
Eventuell hat das sounddeck als HOST das device nicht. Vielleicht muss ich das noch umbauen. Der alte kernel sucht sich nämlich das medienserver device und nimmt diesen das als host. Ich wollte das im node-kernel aber vermeiden, denn wer weiß ob in Zukunft der medienserver immer am Host läuft.

Naja mal schaun ich kuck am Abend mal kurz drüber

[NSmartHome]

Ja, nur das Sounddeck. Habe es jetzt zum Laufen gebracht. Problem war, dass das Raumfeld Gerät irgendwie nicht dauerhaft über upnp erreichbar war (oder wie auch immer man das ausdrücken will). Habe im iOBroker über den upnp Adapter gesehen, dass er das Raumfeld nur kurz erkannt hat, wenn man es neu eingeschaltet hat. Jetzt habe ich es anstelle über den Switch direkt am Router angeschlossen und dann läuft es stabil und damit auch der node-raumserver.
Mannomann. Da soll man einer drauf kommen. Werde jetzt mal noch etwas experimentieren. Wollte da eigentlich nicht noch ein separates Kabel zum Router legen.
Danke schon mal für Deine Unterstützung!

[ChriD]

ist das ein stinknormaler oder ein layer 3 switch?
Was ich so im internet lese kann es schon sein das dein switch ein problem hat die UPNP multicast messages weiterzuleiten. Traurig aber wahr...

UPnP uses a L3 multicast address of 239.255.255.250, some cheap layer2 switches will not process L2 multicasts correctly. You can verfiy this using wireshark if desperate. if you can't see the packets leaving one PC, and arriving at the others, invest in a switch which supports multicast, check the specs before buying. sorry, no better news :|

Die Raumfeld App findet dein Sounddeck aber oder? Weil die benutzen zuerst glaub ich bonjour/zeroconf und nur falls sie da den host nicht finden dann erst multicasting über die UPNP adressen.

Aber ich schau mal ob ich das so einbauen kann dass ich den HOST vielleicht auch zuerst über ein Bonjour finden kann. Dann brauchst du kein extra Kabel legen

[NSmartHome]

Ist ein billiger 2 Layer (Gigabit TL-SG105E). Dann wäre das ja zumindest zu lösen über einen 3 Layer:)
Ja, die Raumfeld App findet das Gerät. Daher habe ich auch nicht nach Problemen bei der Verbindung gesucht. Aktuell habe ich es direkt am Router und kann so ein wenig testen.
Mir ist aufgefallen, dass es kein request zum Laden der Playlist gibt so wie im "alten" Raumserver. Geht das nicht oder hast Du das nur noch nicht umgesetzt?
Danke vielmals!

[ChriD]

das ist noch nicht umgesetzt. Der node-raumkernel ist noch nicht so weit. Aber dauert nicht mehr lang

[NSmartHome]

ok, prima. was macht denn der request enterManualStandby (bzw. leave). Hatte gehofft damit das Gerät ein- und ausschalten zu können (also in standby). Macht bei mir allerdings gar nichts. Laut UPNP Adapter müsste es die Funktion aber geben. Im Log sieht man eine Fehlermeldung. Ansonsten ist der Log auch um einiges kürzer als z.B. mute, das problemlos funktioniert.

verbose: [Raumserver] Request received: GET /raumserver/controller/enterManualStandby
debug: [Raumserver] Request to raumserver recognized: /raumserver/controller/enterManualStandby
debug: [Raumserver] Handle action 'enterManualStandby' with query: {}
debug: [Request.enterManualStandby] Calling action 'enterManualStandby' for renderer Wohnzimmer
error: [Request.enterManualStandby] Exception thrown:
debug: [Request.enterManualStandby] Request was accepted and was successfully executed

[ChriD]

Ja das sollte es machen. Funktioniert bei mir auch, aber nur bei den Geräten die das auch können.
Sonst mach mal einen Ordner "logs" im ordner wo du den server installiert hast und führ den request nochmal aus. Dann kannst du mir das hier reinstellen was im exception.log file steht

[NSmartHome]

eine Datei exception.log wird leider nicht erzeugt (zumindest finde ich keine). nur die app.2017... aber das ist ja quasi der log der Infos, die auch im Terminal angezeigt werden, wenn ich das richtig sehe. Und da steht ja leider nicht viel (siehe oben).

[ChriD]

Also bei mir funktioniert das.
Probier mal &scope=room dazu also so in etwa
/raumserver/controller/enterManualStandby?id=Kueche&scope=room

[ChriD]

Ach ja. Ich hab den raumkernel abgeändert das er zuerst mal mit bonjour sucht. Wenn du also das node-raumkernel package updatest sollte er den host auch über deinen switch finden.