FAQ  •  Register  •  Login

UPnP interface is gone while Web interface still is visible

<<

hanneshar

Serviio newbie

Posts: 10

Joined: Mon Feb 05, 2018 9:34 pm

Post Mon Feb 05, 2018 9:52 pm

UPnP interface is gone while Web interface still is visible

Hello,

I am using Serviio v1.9 on a Windows Server 2016 until about a week ago very successfully. But suddenly Serviio doesn't appear as UPnP server on my various devices (Internet Radio, Samsung TV, VLC player). Access via the WWW frontend, on the other hand, continues to function without any problems for Serviio. I hadn't touched Serviio in months. Accordingly, I don't think I've changed anything in the Serviio configuration here. Interestingly, restarting Windows Server 2016 will cause Serviio to become partially visible as UPnP server for a few hours on the network. But after a while the UPnP server disappears from my list of devices. What I've tried so far:

1.) I uninstalled and reinstalled Serviio. But that did not bring success.

2.) If I turn off the firewall of Windows Server 2016, however, I can't see the Serviio UPnP server either. From this I deduce that the firewall is not the reason why my UPnP devices don't see Serviio anymore.

3.) Two different network cards are attached to the physical server. Here I have tried the option to let Serviio decide automatically, have one network card and the other network card bound to Serviio. Even without success.

Meanwhile, I'm running out of ideas about what else to try. On the Windows Server 2016 I haven't made any significant changes lately. What I can't rule out, however, is that either Microsoft has been running an update lately. Or that Oracel added a new Java version to the system.


Anybody else have an idea? Or do I have to disconnect from Serviio as UPnP server again? Previously I had been running Twonky as UPnP server for many years and was very happy to have discovered Serviio. But apparently every beautiful relationship comes to an end at some point....




Hannes
<<

hanneshar

Serviio newbie

Posts: 10

Joined: Mon Feb 05, 2018 9:34 pm

Post Mon Feb 05, 2018 9:56 pm

Re: UPnP interface is gone while Web interface still is visi

Hallo,

something I have forgotten to mention: after reinstallation and starting up Serviio the software reports immerdiately the following devices when I check under "status!:

192.168.178.1 Unrecognized device Generic DLNA profile Bearbeiten
192.168.178.24 Roku SoundBridge M400 Generic DLNA profile Bearbeiten
192.168.178.33 Unrecognized device Generic DLNA profile Bearbeiten
192.168.178.75 Unrecognized device Generic DLNA profile Bearbeiten
192.168.178.91 UE55JU6400 Samsung TV / player (J-...

In other words: I seams there is some kind of UPnP comunication in my LAN. However, the various devices to not see the Serviio UPnP server in the net. Strange. Any ideas?




Greetings,





Hannes
<<

DenyAll

DLNA master

Posts: 2169

Joined: Fri Mar 08, 2013 11:16 pm

Location: Adelaide, Australia

Post Mon Feb 05, 2018 10:02 pm

Re: UPnP interface is gone while Web interface still is visi

Post a detailed log of Serviio starting up.
Can you disable or remove a nic as a test?
DenyAll
Panasonic Viera CS610A | Panasonic Viera V20A | Sony PS4 | Sony PS3 | Panasonic DMP-BD79 | Yamaha RX-V500D | iPad | Windows 10 | Serviio 1.9 Pro
WinHelper | MediaInfo

Beta Tester, Moderator
Please do not PM me for support as any solution cannot be shared with others.
<<

hanneshar

Serviio newbie

Posts: 10

Joined: Mon Feb 05, 2018 9:34 pm

Post Tue Feb 06, 2018 10:06 pm

Re: UPnP interface is gone while Web interface still is visi

Thank you very much for your fast answer and your suggestions.

Disabling NICs:
I have tried all variants - NIC1 off, NIC2 off both NICs on. No success - no UPnP server in my network showing up.


serviio.log - after enabling the logging I see those permanetely growing files (only "serviio.log" is continuously set back to a size of zero bytes):

serviio.log
serviio.log.1
serviio.log.2
serviio.log.3
serviio.log.4
serviio.log.5

All of them only contain only (?) data about files in my media library. For example the beginning of "serviio.log.1" after restaring the serviio service:

2018-02-06 22:56:36,328 DEBUG [LibraryOneTimeScanner] Looking for files to share in folder: c:\HDexpander2\Storage\mp3\Klassik\DE\Mozart\Kammermusik\Quintette, Quartette & Trios für Klavier\CD4
2018-02-06 22:56:36,339 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-08 Act 1 - Aria_ _Per Quel Che Ha Il Mal Di Stomaco_.mp3, ignore case: true
2018-02-06 22:56:36,340 DEBUG [MediaItemDAOImpl] Media item c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-08 Act 1 - Aria_ _Per Quel Che Ha Il Mal Di Stomaco_.mp3 already exists in DB
2018-02-06 22:56:36,341 DEBUG [MediaFileIndexer] Found file 'c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-09 Act 1- Recitativo.mp3', checking if it's already in the Library
2018-02-06 22:56:36,342 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-09 Act 1- Recitativo.mp3
2018-02-06 22:56:36,342 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-09 Act 1- Recitativo.mp3, ignore case: true
2018-02-06 22:56:36,342 DEBUG [MediaItemDAOImpl] Media item c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-09 Act 1- Recitativo.mp3 already exists in DB
2018-02-06 22:56:36,344 DEBUG [MediaFileIndexer] Found file 'c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-10 Act 1 - Aria_ _Caro Volpino Amabile_.mp3', checking if it's already in the Library
2018-02-06 22:56:36,344 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-10 Act 1 - Aria_ _Caro Volpino Amabile_.mp3
2018-02-06 22:56:36,344 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-10 Act 1 - Aria_ _Caro Volpino Amabile_.mp3, ignore case: true
2018-02-06 22:56:36,345 DEBUG [MediaItemDAOImpl] Media item c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-10 Act 1 - Aria_ _Caro Volpino Amabile_.mp3 already exists in DB
2018-02-06 22:56:36,348 DEBUG [MediaFileIndexer] Found file 'c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-11 Act 1- Recitativo.mp3', checking if it's already in the Library
2018-02-06 22:56:36,348 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-11 Act 1- Recitativo.mp3
2018-02-06 22:56:36,348 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-11 Act 1- Recitativo.mp3, ignore case: true
2018-02-06 22:56:36,348 DEBUG [MediaItemDAOImpl] Media item c:\HDexpander2\Storage\mp3\Klassik\DE\Haydn - Franz Joseph\Opern\Lo Speziale - Maestri\CD1\1-11 Act 1- Recitativo.mp3 already exists in DB
2018-02-06 22:56:36,349 DEBUG [LibraryOneTimeScanner] Looking for files to share in folder: c:\HDexpander2\Storage\mp3\Klassik\DE\Mozart\Kammermusik\Quintette, Quartette & Trios für Klavier\CD5
(...)

Is there any specific location in the log files where I can search for data regarding the initialisation of the UPnP interface?


On my "backup server" (as well MS Server 2016) Twonky is still alive and at least currently I see this particular UPnP server in my network.




Greetings from Hannover,





Hannes
<<

atc98092

User avatar

DLNA master

Posts: 2709

Joined: Fri Aug 17, 2012 10:22 pm

Location: Washington (the state)

Post Tue Feb 06, 2018 10:44 pm

Re: UPnP interface is gone while Web interface still is visi

Actually, those are all the log files there are. By default, Serviio allows the log file to reach 500kb, then renames it and starts a new log file. The one without a number is the newest log, and the oldest log has the file name that ends with a 1, sequentially newer as the number gets larger. The log with .1 should contain startup information about your network card binding. If you want a clean log, stop the Serviio service, delete all of the logs in the folder (or move them somewhere else if you want to save them) and then restart Serviio. If you want the logs to use a larger size before making a new file, you can increase the size in the log XML file in the config folder.
Dan

Panasonic ST30 Plasma, Samsung JU7100 4K TV, Sony BDP-S3500, Sharp 4K Roku TV, Insignia Roku TV, Roku 4, Ultra and Stick, Yamaha RX-V583 AVR.
Primary server: Intel i5-6400, 8 gig ram, Windows 10 Pro, 18 TB hard drive space | Test server Windows 7 Home Premium, AMD Phenom II X4 965, 8 gig ram

Enable debug logging Identify media file contents
<<

hanneshar

Serviio newbie

Posts: 10

Joined: Mon Feb 05, 2018 9:34 pm

Post Wed Feb 07, 2018 9:12 pm

Re: UPnP interface is gone while Web interface still is visi

Thank you again for your help. I have stopped serviio and turned off the second NIC so I only see these network devices when I use ipconfig.exe (sorry, it is a German Server 2016 OS):

==============================================================================
c:\Program Files (x86)\Tools\Sound\Serviio\log>ipconfig

Windows-IP-Konfiguration


Ethernet-Adapter vEthernet (Gigabit-Netzwerkverbindung Intel(R) 82574L - Virtual Switch):

Verbindungsspezifisches DNS-Suffix: fritz.box
IPv6-Adresse. . . . . . . . . . . : 2003:d7:b3eb:4100:612d:4ec6:cac6:6ca2
Verbindungslokale IPv6-Adresse . : fe80::612d:4ec6:cac6:6ca2%4
IPv4-Adresse . . . . . . . . . . : 192.168.178.36
Subnetzmaske . . . . . . . . . . : 255.255.255.0
Standardgateway . . . . . . . . . : fe80::c225:6ff:fe19:876f%4
192.168.178.1

Tunneladapter isatap.fritz.box:

Medienstatus. . . . . . . . . . . : Medium getrennt
Verbindungsspezifisches DNS-Suffix: fritz.box

Tunneladapter Teredo Tunneling Pseudo-Interface:

Verbindungsspezifisches DNS-Suffix:
IPv6-Adresse. . . . . . . . . . . : 2001:0:9d38:78cf:2400:3b67:3f57:4ddb
Verbindungslokale IPv6-Adresse . : fe80::2400:3b67:3f57:4ddb%8
Standardgateway . . . . . . . . . :
==============================================================================


Next, I've restarted the serviio service and saved the first entry in the serviio.log file:

  Code:
==============================================================================
2018-02-07 21:57:15,620 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44331
2018-02-07 21:57:15,623 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-07 21:57:15,623 INFO  [MediaServer] Serviio DLNA media streaming server v 1.9 (rev. a39231b8b876)
2018-02-07 21:57:15,623 INFO  [MediaServer] Petr Nejedly 2009-2017
2018-02-07 21:57:15,624 INFO  [MediaServer] http://www.serviio.org
2018-02-07 21:57:15,624 INFO  [MediaServer]
2018-02-07 21:57:15,624 INFO  [MediaServer] Java 1.8.0_131-Oracle Corporation x86
2018-02-07 21:57:15,624 INFO  [MediaServer] OS Windows Server 2016 10.0
2018-02-07 21:57:15,624 INFO  [MediaServer] File encoding: windows-1252
2018-02-07 21:57:15,712 INFO  [MediaServer] Headless mode enabled: true
2018-02-07 21:57:15,712 INFO  [MediaServer] User: ADAMO$
2018-02-07 21:57:15,712 INFO  [MediaServer] User home dir: C:\Windows\system32\config\systemprofile
2018-02-07 21:57:15,712 INFO  [MediaServer] Temp dir: C:\Windows\TEMP\
2018-02-07 21:57:15,712 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-07 21:57:15,717 DEBUG [MediaServer] Runtime params: -Dexe4j.semaphoreName=Local\c:_program_files_(x86)_tools_sound_serviio_bin_serviioservice.exe0, -Dexe4j.isService=true, -Dexe4j.moduleName=C:\Program Files (x86)\Tools\Sound\Serviio\bin\ServiioService.exe, -Dexe4j.tempDir=C:\Windows\TEMP\e4j8F7F.tmp_dir1518037033, -Dexe4j.unextractedPosition=413627, -Djava.library.path=C:\ProgramData\Oracle\Java\javapath;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;c:\Program Files (x86)\Tools\Programming\Python27\;C:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\Windows\system32\config\systemprofile\AppData\Local\Microsoft\WindowsApps;c:\program files (x86)\tools\sound\serviio\jre\bin, -Dexe4j.consoleCodepage=cp0, -Xms20M, -XX:+UseG1GC, -XX:GCTimeRatio=1, -XX:MinHeapFreeRatio=10, -XX:MaxHeapFreeRatio=20, -Djava.net.preferIPv4Stack=true, -Djava.awt.headless=true, -Dorg.restlet.engine.loggerFacadeClass=org.restlet.ext.slf4j.Slf4jLoggerFacade, -Dserviio.home=C:\Program Files (x86)\Tools\Sound\Serviio\bin\\.., -Dderby.system.home=C:\Program Files (x86)\Tools\Sound\Serviio\bin\\..\library, -Xmx512m, -Xrs
2018-02-07 21:57:15,736 INFO  [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2018-02-07 21:57:15,764 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.sql' has been run
2018-02-07 21:57:15,766 INFO  [DatabaseManager] Using DERBY database language
2018-02-07 21:57:16,649 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.1.sql' has been run
2018-02-07 21:57:16,650 DEBUG [DBLogDAOImpl] Checking if script 'script-0.2.sql' has been run
2018-02-07 21:57:16,650 DEBUG [DBLogDAOImpl] Checking if script 'script-0.3.1.sql' has been run
2018-02-07 21:57:16,651 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.sql' has been run
2018-02-07 21:57:16,652 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.1.sql' has been run
2018-02-07 21:57:16,653 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.2.sql' has been run
2018-02-07 21:57:16,653 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.sql' has been run
2018-02-07 21:57:16,654 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.1.sql' has been run
2018-02-07 21:57:16,655 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.sql' has been run
2018-02-07 21:57:16,656 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.1.sql' has been run
2018-02-07 21:57:16,656 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.2.sql' has been run
2018-02-07 21:57:16,657 DEBUG [DBLogDAOImpl] Checking if script 'script-1.0.sql' has been run
2018-02-07 21:57:16,658 DEBUG [DBLogDAOImpl] Checking if script 'script-1.1.sql' has been run
2018-02-07 21:57:16,658 DEBUG [DBLogDAOImpl] Checking if script 'script-1.2.sql' has been run
2018-02-07 21:57:16,659 DEBUG [DBLogDAOImpl] Checking if script 'script-1.2.1.sql' has been run
2018-02-07 21:57:16,661 DEBUG [DBLogDAOImpl] Checking if script 'script-1.3.sql' has been run
2018-02-07 21:57:16,662 DEBUG [DBLogDAOImpl] Checking if script 'script-1.4.sql' has been run
2018-02-07 21:57:16,662 DEBUG [DBLogDAOImpl] Checking if script 'script-1.5.sql' has been run
2018-02-07 21:57:16,663 DEBUG [DBLogDAOImpl] Checking if script 'script-1.6.sql' has been run
2018-02-07 21:57:16,664 DEBUG [DBLogDAOImpl] Checking if script 'script-1.7.sql' has been run
2018-02-07 21:57:16,665 DEBUG [DBLogDAOImpl] Checking if script 'script-1.8.sql' has been run
2018-02-07 21:57:16,665 DEBUG [DBLogDAOImpl] Checking if script 'script-1.9.sql' has been run
2018-02-07 21:57:16,764 INFO  [WebServer] Socket buffer set to 65535 bytes
2018-02-07 21:57:16,765 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/console) exposed on port 23423
2018-02-07 21:57:16,768 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/rest) exposed on port 23423
2018-02-07 21:57:16,770 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/console) exposed on port 23523
2018-02-07 21:57:16,770 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/rest) exposed on port 23523
2018-02-07 21:57:17,645 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/cds) exposed on port 23424
2018-02-07 21:57:17,646 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/mediabrowser) exposed on port 23424
2018-02-07 21:57:17,647 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/cds) exposed on port 23524
2018-02-07 21:57:17,648 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/mediabrowser) exposed on port 23524
2018-02-07 21:57:17,699 DEBUG [LicensingManager] Updating license
2018-02-07 21:57:17,705 DEBUG [ConfigEntryDAOImpl] Reading all ConfigEntries
2018-02-07 21:57:17,726 DEBUG [LicenseValidator] Validating license file (348 bytes)
2018-02-07 21:57:17,810 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-07 21:57:17,810 INFO  [MediaServer] License: PRO (UNLIMITED, id: 1337)
2018-02-07 21:57:17,810 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-07 21:57:17,814 DEBUG [LocalAddressResolverStrategy] Resolving host IP address automatically
2018-02-07 21:57:17,923 INFO  [Device] Created UPnP Device with UUID: 88b65a25-213d-3547-abdf-19ce1282fccc, bound address: 192.168.178.36
2018-02-07 21:57:18,144 INFO  [ProfileManager] Loading profiles from file /profiles.xml
2018-02-07 21:57:18,499 INFO  [TrakttvService] Started trakt.tv sync updater
2018-02-07 21:57:18,499 INFO  [TrakttvService] Started trakt.tv scrobble poster
2018-02-07 21:57:18,544 INFO  [FileDeltaWatcher] Using 'sun.nio.fs.WindowsWatchService' for delta scan
2018-02-07 21:57:18,549 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2018-02-07 21:57:18,552 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 1
2018-02-07 21:57:18,561 DEBUG [MediaItemDAOImpl] Getting counts of media items by type
2018-02-07 21:57:18,608 INFO  [CustomisationManager] No customisation properties found
2018-02-07 21:57:18,724 INFO  [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2018-02-07 21:57:18,724 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 2
2018-02-07 21:57:18,747 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2018-02-07 21:57:18,747 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 3
2018-02-07 21:57:18,753 INFO  [ProfilesDefinitionParser] Added profile 'Xbox 360' (id=3)
2018-02-07 21:57:18,753 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 4
2018-02-07 21:57:18,755 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2018-02-07 21:57:18,755 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile ps4
2018-02-07 21:57:18,757 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 4' (id=ps4)
2018-02-07 21:57:18,757 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 5
2018-02-07 21:57:18,758 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2018-02-07 21:57:18,758 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 6
2018-02-07 21:57:18,764 INFO  [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2018-02-07 21:57:18,764 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sam_h
2018-02-07 21:57:18,766 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (H-series)' (id=sam_h)
2018-02-07 21:57:18,766 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sam_j
2018-02-07 21:57:18,768 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (J-series)' (id=sam_j)
2018-02-07 21:57:18,768 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sam_ef
2018-02-07 21:57:18,769 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (E/F-series)' (id=sam_ef)
2018-02-07 21:57:18,769 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 7
2018-02-07 21:57:18,770 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C/D-series)' (id=7)
2018-02-07 21:57:18,770 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 8
2018-02-07 21:57:18,771 INFO  [ProfilesDefinitionParser] Added profile 'LG TV / player' (id=8)
2018-02-07 21:57:18,771 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2012
2018-02-07 21:57:18,775 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012)' (id=sony2012)
2018-02-07 21:57:18,775 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2013
2018-02-07 21:57:18,777 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2013/14)' (id=sony2013)
2018-02-07 21:57:18,777 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2013
2018-02-07 21:57:18,779 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx100 (2013)' (id=bdp2013)
2018-02-07 21:57:18,779 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2012
2018-02-07 21:57:18,779 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx90 (2012)' (id=bdp2012)
2018-02-07 21:57:18,780 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2010
2018-02-07 21:57:18,783 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx70/SMP-N100 (2010)' (id=bdp2010)
2018-02-07 21:57:18,783 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2010us
2018-02-07 21:57:18,783 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx70/SMP-N100 (2010) - US' (id=bdp2010us)
2018-02-07 21:57:18,783 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2011
2018-02-07 21:57:18,784 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx80/SMP-N200 (2011)' (id=bdp2011)
2018-02-07 21:57:18,784 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 13
2018-02-07 21:57:18,785 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba REGZA' (id=13)
2018-02-07 21:57:18,785 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile tosh2012
2018-02-07 21:57:18,786 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba REGZA 2012-' (id=tosh2012)
2018-02-07 21:57:18,786 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 14
2018-02-07 21:57:18,789 INFO  [ProfilesDefinitionParser] Added profile 'WDTV Live' (id=14)
2018-02-07 21:57:18,790 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 15
2018-02-07 21:57:18,790 INFO  [ProfilesDefinitionParser] Added profile 'LaCie LaCinema Media Player' (id=15)
2018-02-07 21:57:18,790 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 16
2018-02-07 21:57:18,791 INFO  [ProfilesDefinitionParser] Added profile 'Manta WIFI Centre' (id=16)
2018-02-07 21:57:18,791 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 17
2018-02-07 21:57:18,791 INFO  [ProfilesDefinitionParser] Added profile 'Pure Flow' (id=17)
2018-02-07 21:57:18,791 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 18
2018-02-07 21:57:18,792 INFO  [ProfilesDefinitionParser] Added profile 'Sharp Aquos' (id=18)
2018-02-07 21:57:18,792 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2011
2018-02-07 21:57:18,793 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011)' (id=sony2011)
2018-02-07 21:57:18,793 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2010
2018-02-07 21:57:18,794 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2010)' (id=sony2010)
2018-02-07 21:57:18,794 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile plps2010
2018-02-07 21:57:18,794 INFO  [ProfilesDefinitionParser] Added profile 'Philips (2010-)' (id=plps2010)
2018-02-07 21:57:18,795 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 20
2018-02-07 21:57:18,795 INFO  [ProfilesDefinitionParser] Added profile 'Philips (2009)' (id=20)
2018-02-07 21:57:18,795 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2009
2018-02-07 21:57:18,796 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2009)' (id=sony2009)
2018-02-07 21:57:18,796 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 22
2018-02-07 21:57:18,797 INFO  [ProfilesDefinitionParser] Added profile 'Denon AVR' (id=22)
2018-02-07 21:57:18,797 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2013
2018-02-07 21:57:18,798 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2013)' (id=viera2013)
2018-02-07 21:57:18,798 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2011a
2018-02-07 21:57:18,799 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera G/GT/DT/UT/VT (2011/2012)' (id=viera2011a)
2018-02-07 21:57:18,799 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2011
2018-02-07 21:57:18,800 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera E/S/ST/VT (2011)' (id=viera2011)
2018-02-07 21:57:18,800 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2010
2018-02-07 21:57:18,800 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2010)' (id=viera2010)
2018-02-07 21:57:18,800 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2009
2018-02-07 21:57:18,801 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2009)' (id=viera2009)
2018-02-07 21:57:18,801 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2012us
2018-02-07 21:57:18,801 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012) - US' (id=sony2012us)
2018-02-07 21:57:18,801 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2011us
2018-02-07 21:57:18,802 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011) - US' (id=sony2011us)
2018-02-07 21:57:18,802 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile SonySTR
2018-02-07 21:57:18,803 INFO  [ProfilesDefinitionParser] Added profile 'Sony AVReceiver STR' (id=SonySTR)
2018-02-07 21:57:18,803 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile SonyCMT
2018-02-07 21:57:18,803 INFO  [ProfilesDefinitionParser] Added profile 'Sony Audio CMT' (id=SonyCMT)
2018-02-07 21:57:18,804 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viziotv
2018-02-07 21:57:18,804 INFO  [ProfilesDefinitionParser] Added profile 'Vizio TV' (id=viziotv)
2018-02-07 21:57:18,804 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile panasonicBDT220
2018-02-07 21:57:18,805 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic BD player' (id=panasonicBDT220)
2018-02-07 21:57:18,805 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile philips_streamium
2018-02-07 21:57:18,805 INFO  [ProfilesDefinitionParser] Added profile 'Philips NP Streamium' (id=philips_streamium)
2018-02-07 21:57:18,806 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile roku
2018-02-07 21:57:18,806 INFO  [ProfilesDefinitionParser] Added profile 'Chaneru' (id=roku)
2018-02-07 21:57:18,807 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile RokuMPApp
2018-02-07 21:57:18,808 INFO  [ProfilesDefinitionParser] Added profile 'Roku Media Player' (id=RokuMPApp)
2018-02-07 21:57:18,809 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile Roku4kMPApp
2018-02-07 21:57:18,809 INFO  [ProfilesDefinitionParser] Added profile 'Roku 4K Media Player (2016+)' (id=Roku4kMPApp)
2018-02-07 21:57:18,809 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile RokuNo4kApp
2018-02-07 21:57:18,810 INFO  [ProfilesDefinitionParser] Added profile 'Roku 1080 Media Player (2016+)' (id=RokuNo4kApp)
2018-02-07 21:57:18,810 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile RokuTV
2018-02-07 21:57:18,811 INFO  [ProfilesDefinitionParser] Added profile 'Roku TV (non 4K)' (id=RokuTV)
2018-02-07 21:57:18,811 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile Roku4kTV
2018-02-07 21:57:18,812 INFO  [ProfilesDefinitionParser] Added profile 'Roku 4K TV' (id=Roku4kTV)
2018-02-07 21:57:18,812 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile YamBD
2018-02-07 21:57:18,812 INFO  [ProfilesDefinitionParser] Added profile 'Yamaha BD-A1020/BD-A1010' (id=YamBD)
2018-02-07 21:57:18,812 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile TNDC
2018-02-07 21:57:18,813 INFO  [ProfilesDefinitionParser] Added profile 'Telenet Yelo TV' (id=TNDC)
2018-02-07 21:57:18,813 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile xbox_one
2018-02-07 21:57:18,814 INFO  [ProfilesDefinitionParser] Added profile 'Xbox One' (id=xbox_one)
2018-02-07 21:57:18,814 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile raumfeld
2018-02-07 21:57:18,815 INFO  [ProfilesDefinitionParser] Added profile 'Raumfeld' (id=raumfeld)
2018-02-07 21:57:18,828 INFO  [ProfileManager] Loading profiles from file /application-profiles.xml
2018-02-07 21:57:18,879 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2018-02-07 21:57:18,880 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile flv_player
2018-02-07 21:57:18,881 INFO  [ProfilesDefinitionParser] Added profile 'Flash player' (id=flv_player)
2018-02-07 21:57:18,881 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile html5
2018-02-07 21:57:18,882 INFO  [ProfilesDefinitionParser] Added profile 'HTML5' (id=html5)
2018-02-07 21:57:18,882 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile serviigo_standard
2018-02-07 21:57:18,883 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Standard)' (id=serviigo_standard)
2018-02-07 21:57:18,883 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile serviigo
2018-02-07 21:57:18,884 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Legacy Devices)' (id=serviigo)
2018-02-07 21:57:18,884 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile serviigo_hls
2018-02-07 21:57:18,884 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (HLS)' (id=serviigo_hls)
2018-02-07 21:57:18,885 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile serviigo_gtv
2018-02-07 21:57:18,885 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Google TV)' (id=serviigo_gtv)
2018-02-07 21:57:18,885 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile googlecast
2018-02-07 21:57:18,886 INFO  [ProfilesDefinitionParser] Added profile 'Google Cast' (id=googlecast)
2018-02-07 21:57:18,886 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile chromecast
2018-02-07 21:57:18,887 INFO  [ProfilesDefinitionParser] Added profile 'Chromecast' (id=chromecast)
2018-02-07 21:57:18,888 INFO  [ProfileManager] User profiles definition (user-profiles.xml) not found, using built-in profiles only
2018-02-07 21:57:18,980 INFO  [PluginCompilerThread] Looking for plugins at C:\Program Files (x86)\Tools\Sound\Serviio\bin\..\plugins
2018-02-07 21:57:18,981 INFO  [PluginCompilerThread] Started looking for plugins
2018-02-07 21:57:18,984 INFO  [UpdateChecker] Checking if a new version is available
2018-02-07 21:57:19,013 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path C:\Program Files (x86)\Tools\Sound\Serviio\bin\\..\lib\ffmpeg.exe
2018-02-07 21:57:19,030 DEBUG [ProcessExecutor] Starting C:\Program Files (x86)\Tools\Sound\Serviio\bin\\..\lib\ffmpeg.exe
2018-02-07 21:57:19,562 INFO  [FFMPEGWrapper] Found FFmpeg: C:\Program Files (x86)\Tools\Sound\Serviio\bin\\..\lib\ffmpeg.exe
2018-02-07 21:57:19,582 INFO  [FFMPEGWrapper] FFmpeg details: [version=3.2.4, libRtpmPresent=true, libAssPresent=true, libShinePresent=false, libMxfPresent=false]
2018-02-07 21:57:19,585 DEBUG [DCRawWrapper] Invoking DCRAW to check if it exists of path C:\Program Files (x86)\Tools\Sound\Serviio\bin\\..\lib\dcraw.exe
2018-02-07 21:57:19,593 DEBUG [ProcessExecutor] Starting C:\Program Files (x86)\Tools\Sound\Serviio\bin\\..\lib\dcraw.exe
2018-02-07 21:57:19,737 INFO  [AbstractAVTranscodingDeliveryEngine] Cleaning transcode engine and its data
2018-02-07 21:57:19,738 DEBUG [AbstractAVTranscodingDeliveryEngine] Deleting temporary transcoded files from: C:\Windows\TEMP\Serviio
2018-02-07 21:57:19,865 DEBUG [LocalAddressResolverStrategy] Resolving host IP address automatically
2018-02-07 21:57:19,865 INFO  [WebServer] WebServer starting on port 8895
2018-02-07 21:57:20,089 INFO  [Device] Updated bound IP address of Device with UUID: 88b65a25-213d-3547-abdf-19ce1282fccc, bound address: 192.168.178.36
2018-02-07 21:57:20,108 DEBUG [DiscoveryManager] UPNP device 88b65a25-213d-3547-abdf-19ce1282fccc (192.168.178.36) is available
2018-02-07 21:57:20,109 INFO  [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2018-02-07 21:57:20,109 INFO  [EventDispatcher] Starting EventDispatcher
2018-02-07 21:57:20,110 INFO  [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2018-02-07 21:57:20,127 INFO  [RendererExpirationChecker] Starting RendererExpirationChecker
2018-02-07 21:57:20,127 INFO  [RendererSearchSender] Starting Renderer searcher process
2018-02-07 21:57:20,231 INFO  [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface eth2 (Hyper-V-Adapter - virtuelles Ethernet #2) and address 192.168.178.36, timeout = 0
2018-02-07 21:57:20,246 DEBUG [RendererSearchSender] Searching for Renderer devices
2018-02-07 21:57:20,255 DEBUG [RendererSearchSender$RendererSearchWorker] Multicasting SSDP M-SEARCH using interface eth2 (Hyper-V-Adapter - virtuelles Ethernet #2) and address 192.168.178.36, timeout = 0
2018-02-07 21:57:20,299 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth2 (Hyper-V-Adapter - virtuelles Ethernet #2) and address 192.168.178.36, timeout = 0
2018-02-07 21:57:20,318 DEBUG [RendererSearchSender$RendererSearchWorker] Sending 1 'm-search' messages
2018-02-07 21:57:20,320 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 88b65a25-213d-3547-abdf-19ce1282fccc
2018-02-07 21:57:20,389 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.178.36:51855s
2018-02-07 21:57:20,390 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.91:9197/dmr, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,391 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer c826abab-0fa6-418a-b354-2d4d8c7a6d2c from address 192.168.178.91
2018-02-07 21:57:20,398 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.178.36:51855s
2018-02-07 21:57:20,399 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.91:9197/dmr, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,399 DEBUG [ServiioHttpService] Incoming request from /192.168.178.91:38523: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [HOST: 192.168.178.36:8895,ACCEPT-LANGUAGE: en-us,Connection: Keep-Alive,USER-AGENT: DLNADOC/1.50 SEC_HHP_[TV] Schlafzimmer TV/1.0 UPnP/1.0,Content-Length: 0]]
2018-02-07 21:57:20,399 DEBUG [RendererManager] Retrieve device description from http://192.168.178.91:9197/dmr
2018-02-07 21:57:20,407 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer c826abab-0fa6-418a-b354-2d4d8c7a6d2c from address 192.168.178.91
2018-02-07 21:57:20,412 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.178.36:51855s
2018-02-07 21:57:20,413 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.91
2018-02-07 21:57:20,414 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.91:9197/dmr, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,414 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer c826abab-0fa6-418a-b354-2d4d8c7a6d2c from address 192.168.178.91
2018-02-07 21:57:20,478 DEBUG [RendererManager] Adding renderer Renderer[uuid='c826abab-0fa6-418a-b354-2d4d8c7a6d2c', ipAddress='192.168.178.91', name='UE55JU6400', profileId='sam_j', manuallyAdded=false, forcedProfile=false, enabled=true, accessGroupId=1, friendlyName='null'] to list of active renderers
2018-02-07 21:57:20,480 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.91 (headers = [HOST: 192.168.178.36:8895,ACCEPT-LANGUAGE: en-us,Connection: Keep-Alive,USER-AGENT: DLNADOC/1.50 SEC_HHP_[TV] Schlafzimmer TV/1.0 UPnP/1.0,Content-Length: 0])
2018-02-07 21:57:20,490 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.24:80/DeviceDescription.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,490 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 526f6b75-536f-756e-6442-000d4b305321 from address 192.168.178.24
2018-02-07 21:57:20,491 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.24:80/DeviceDescription.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,492 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 526f6b75-536f-756e-6442-000d4b305321 from address 192.168.178.24
2018-02-07 21:57:20,593 DEBUG [RendererManager] Retrieve device description from http://192.168.178.24:80/DeviceDescription.xml
2018-02-07 21:57:20,617 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.64:52525/root_XXYY.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,617 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 54998793-37ab-49d1-9cd7-08ea40eaf641 from address 192.168.178.64
2018-02-07 21:57:20,637 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.64:52525/root_XXYY.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,637 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 54998793-37ab-49d1-9cd7-08ea40eaf641 from address 192.168.178.64
2018-02-07 21:57:20,638 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.64:52525/root_XXYY.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,638 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 54998793-37ab-49d1-9cd7-08ea40eaf641 from address 192.168.178.64
2018-02-07 21:57:20,639 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.64:52525/root_XXYY.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,639 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 54998793-37ab-49d1-9cd7-08ea40eaf641 from address 192.168.178.64
2018-02-07 21:57:20,640 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.64:52525/root_XXYY.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,640 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 54998793-37ab-49d1-9cd7-08ea40eaf641 from address 192.168.178.64
2018-02-07 21:57:20,641 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.64:52525/root_XXYY.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:20,641 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 54998793-37ab-49d1-9cd7-08ea40eaf641 from address 192.168.178.64
2018-02-07 21:57:20,740 DEBUG [RendererManager] Adding renderer Renderer[uuid='526f6b75-536f-756e-6442-000d4b305321', ipAddress='192.168.178.24', name='Roku SoundBridge M400', profileId='1', manuallyAdded=false, forcedProfile=false, enabled=true, accessGroupId=1, friendlyName='null'] to list of active renderers
2018-02-07 21:57:20,790 DEBUG [RendererManager] Retrieve device description from http://192.168.178.24:80/DeviceDescription.xml
2018-02-07 21:57:20,962 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.91
2018-02-07 21:57:20,970 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [HOST: 192.168.178.36:8895,ACCEPT-LANGUAGE: en-us,Connection: Keep-Alive,USER-AGENT: DLNADOC/1.50 SEC_HHP_[TV] Schlafzimmer TV/1.0 UPnP/1.0,Content-Length: 0]
2018-02-07 21:57:20,972 DEBUG [ServiioHttpService] Incoming request from /192.168.178.1:52121: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Client 1.0]]
2018-02-07 21:57:20,973 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.1
2018-02-07 21:57:20,975 DEBUG [RendererManager] Retrieve device description from http://192.168.178.91:9197/dmr
2018-02-07 21:57:20,975 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.91
2018-02-07 21:57:20,977 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.1 (headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Client 1.0])
2018-02-07 21:57:21,022 DEBUG [ServiioHttpService] Incoming request from /192.168.178.33:52682: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [Cache-Control: no-cache,Connection: Keep-Alive,Pragma: no-cache,Accept: text/xml, application/xml,User-Agent: DAFUPnP,Host: 192.168.178.36:8895]]
2018-02-07 21:57:21,023 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.33
2018-02-07 21:57:21,030 DEBUG [ServiioHttpService] Incoming request from /192.168.178.75:53819: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Android Client 1.0]]
2018-02-07 21:57:21,030 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,031 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.33 (headers = [Cache-Control: no-cache,Connection: Keep-Alive,Pragma: no-cache,Accept: text/xml, application/xml,User-Agent: DAFUPnP,Host: 192.168.178.36:8895])
2018-02-07 21:57:21,042 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.75 (headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Android Client 1.0])
2018-02-07 21:57:21,052 DEBUG [RendererManager] Retrieve device description from http://192.168.178.91:9197/dmr
2018-02-07 21:57:21,097 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,099 DEBUG [ServiioHttpService] Incoming request from /192.168.178.75:38176: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Android Client 1.0]]
2018-02-07 21:57:21,099 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Android Client 1.0]
2018-02-07 21:57:21,099 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,100 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,100 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.33
2018-02-07 21:57:21,104 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.75 (headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Android Client 1.0])
2018-02-07 21:57:21,104 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Cache-Control: no-cache,Connection: Keep-Alive,Pragma: no-cache,Accept: text/xml, application/xml,User-Agent: DAFUPnP,Host: 192.168.178.36:8895]
2018-02-07 21:57:21,106 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.33
2018-02-07 21:57:21,106 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.1
2018-02-07 21:57:21,107 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Client 1.0]
2018-02-07 21:57:21,108 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.1
2018-02-07 21:57:21,108 DEBUG [RendererManager] Retrieve device description from http://192.168.178.64:52525/root_XXYY.xml
2018-02-07 21:57:21,166 DEBUG [ServiioHttpService] Incoming request from /192.168.178.35:50795: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [Accept: */*,Host: 192.168.178.36:8895,User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.3, Windows 6.2 (Build 9200); x86_64) DLNADOC/1.50,Connection: close]]
2018-02-07 21:57:21,166 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.35
2018-02-07 21:57:21,168 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.35 (headers = [Accept: */*,Host: 192.168.178.36:8895,User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.3, Windows 6.2 (Build 9200); x86_64) DLNADOC/1.50,Connection: close])
2018-02-07 21:57:21,212 DEBUG [ServiioHttpService] Incoming request from /192.168.178.75:54183: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Android Client 1.0]]
2018-02-07 21:57:21,213 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,214 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.75 (headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Android Client 1.0])
2018-02-07 21:57:21,279 DEBUG [ServiioHttpService] Incoming request from /192.168.178.33:52683: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [Cache-Control: no-cache,Connection: Keep-Alive,Pragma: no-cache,Accept: text/xml, application/xml,User-Agent: DAFUPnP,Host: 192.168.178.36:8895]]
2018-02-07 21:57:21,279 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.33
2018-02-07 21:57:21,281 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.33 (headers = [Cache-Control: no-cache,Connection: Keep-Alive,Pragma: no-cache,Accept: text/xml, application/xml,User-Agent: DAFUPnP,Host: 192.168.178.36:8895])
2018-02-07 21:57:21,488 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2018-02-07 21:57:21,488 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2018-02-07 21:57:21,489 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Samsung TV / player (J-series)'
2018-02-07 21:57:21,488 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2018-02-07 21:57:21,495 DEBUG [ServiioHttpService] Incoming request from /192.168.178.33:52682: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,User-Agent: Microsoft-Windows/10.0 UPnP/1.0,Host: 192.168.178.36:8895]]
2018-02-07 21:57:21,497 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.33
2018-02-07 21:57:21,497 DEBUG [ServiioHttpService] Incoming request from /192.168.178.91:38524: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [HOST: 192.168.178.36:8895,ACCEPT-LANGUAGE: en-us,Connection: Keep-Alive,USER-AGENT: DLNADOC/1.50 SEC_HHP_[TV] Schlafzimmer TV/1.0 UPnP/1.0,Content-Length: 0]]
2018-02-07 21:57:21,497 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.91
2018-02-07 21:57:21,498 DEBUG [ServiioHttpService] Incoming request from /192.168.178.91:38525: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [HOST: 192.168.178.36:8895,ACCEPT-LANGUAGE: en-us,Connection: Keep-Alive,USER-AGENT: DLNADOC/1.50 SEC_HHP_[TV] Schlafzimmer TV/1.0 UPnP/1.0,Content-Length: 0]]
2018-02-07 21:57:21,498 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.33 (headers = [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,User-Agent: Microsoft-Windows/10.0 UPnP/1.0,Host: 192.168.178.36:8895])
2018-02-07 21:57:21,498 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.91
2018-02-07 21:57:21,498 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2018-02-07 21:57:21,499 DEBUG [ServiioHttpService] Incoming request from /192.168.178.91:38526: GET /serviceDescription/X_MS_MediaReceiverRegistrar HTTP/1.1, headers = [HOST: 192.168.178.36:8895,ACCEPT-LANGUAGE: en-us,Connection: Keep-Alive,USER-AGENT: DLNADOC/1.50 SEC_HHP_[TV] Schlafzimmer TV/1.0 UPnP/1.0,Content-Length: 0]]
2018-02-07 21:57:21,499 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.91
2018-02-07 21:57:21,500 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2018-02-07 21:57:21,501 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service X_MS_MediaReceiverRegistrar
2018-02-07 21:57:21,503 DEBUG [ServiioHttpService] Incoming request from /192.168.178.1:50034: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Client 1.0]]
2018-02-07 21:57:21,504 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.1
2018-02-07 21:57:21,505 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2018-02-07 21:57:21,514 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2018-02-07 21:57:21,516 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2018-02-07 21:57:21,524 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2018-02-07 21:57:21,527 DEBUG [ServiioHttpService] Incoming request from /192.168.178.75:51997: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Android Client 1.0]]
2018-02-07 21:57:21,527 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,529 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2018-02-07 21:57:21,529 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2018-02-07 21:57:21,529 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2018-02-07 21:57:21,554 DEBUG [ServiioHttpService] Incoming request from /192.168.178.1:48565: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Client 1.0]]
2018-02-07 21:57:21,554 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.1
2018-02-07 21:57:21,555 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2018-02-07 21:57:21,555 DEBUG [ServiioHttpService] Incoming request from /192.168.178.91:38527: POST /serviceControl HTTP/1.1, headers = [HOST: 192.168.178.36:8895,Accept: */*,CONTENT-LENGTH: 313,CONTENT-TYPE: text/xml; charset="utf-8",USER-AGENT: DLNADOC/1.50 SEC_HHP_[TV] Schlafzimmer TV/1.0 UPnP/1.0,SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSearchCapabilities",Connection: close]]
2018-02-07 21:57:21,556 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2018-02-07 21:57:21,556 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.91
2018-02-07 21:57:21,559 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSearchCapabilities"' from 192.168.178.91
2018-02-07 21:57:21,559 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.91
2018-02-07 21:57:21,561 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.91
2018-02-07 21:57:21,574 DEBUG [ServiioHttpService] Incoming request from /192.168.178.75:53600: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Android Client 1.0]]
2018-02-07 21:57:21,574 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,575 DEBUG [ServiioHttpService] Incoming request from /192.168.178.1:55298: GET /serviceDescription/X_MS_MediaReceiverRegistrar HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Client 1.0]]
2018-02-07 21:57:21,575 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.1
2018-02-07 21:57:21,575 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2018-02-07 21:57:21,576 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2018-02-07 21:57:21,577 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service X_MS_MediaReceiverRegistrar
2018-02-07 21:57:21,578 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2018-02-07 21:57:21,600 DEBUG [ServiioHttpService] Incoming request from /192.168.178.1:60434: SUBSCRIBE /serviceEventing/ConnectionManager HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CALLBACK: <http://192.168.178.1:40303/>,NT: upnp:event,TIMEOUT: Second-infinite]]
2018-02-07 21:57:21,600 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.1
2018-02-07 21:57:21,601 DEBUG [ServiioHttpService] Incoming request from /192.168.178.1:58300: SUBSCRIBE /serviceEventing/ContentDirectory HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CALLBACK: <http://192.168.178.1:40303/>,NT: upnp:event,TIMEOUT: Second-infinite]]
2018-02-07 21:57:21,601 DEBUG [ServiioHttpService] Incoming request from /192.168.178.1:35907: SUBSCRIBE /serviceEventing/X_MS_MediaReceiverRegistrar HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CALLBACK: <http://192.168.178.1:40303/>,NT: upnp:event,TIMEOUT: Second-infinite]]
2018-02-07 21:57:21,601 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.1
2018-02-07 21:57:21,601 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.1
2018-02-07 21:57:21,603 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent subscription request received for service ContentDirectory
2018-02-07 21:57:21,603 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent subscription request received for service ConnectionManager
2018-02-07 21:57:21,603 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent subscription request received for service X_MS_MediaReceiverRegistrar
2018-02-07 21:57:21,607 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription registered (uuid=bda97571-7b2b-487a-8c64-a5c5a8859e70) for service urn:upnp-org:serviceId:ConnectionManager with duration infinite reporting to http://192.168.178.1:40303/
2018-02-07 21:57:21,607 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription registered (uuid=4196cc0a-b305-4f48-84d7-d3a3fe338aeb) for service urn:upnp-org:serviceId:ContentDirectory with duration infinite reporting to http://192.168.178.1:40303/
2018-02-07 21:57:21,607 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription registered (uuid=634a6f9d-4896-495e-a1f9-4dd6febe27b9) for service urn:microsoft.com:serviceId:X_MS_MediaReceiverRegistrar with duration infinite reporting to http://192.168.178.1:40303/
2018-02-07 21:57:21,660 DEBUG [ServiioHttpService] Incoming request from /192.168.178.75:60979: GET /serviceDescription/X_MS_MediaReceiverRegistrar HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CONNECTION: Close,USER-AGENT: AVM UPnP/1.0 Android Client 1.0]]
2018-02-07 21:57:21,660 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,662 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service X_MS_MediaReceiverRegistrar
2018-02-07 21:57:21,662 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2018-02-07 21:57:21,687 DEBUG [ServiioHttpService] Incoming request from /192.168.178.75:49315: SUBSCRIBE /serviceEventing/ConnectionManager HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CALLBACK: <http://192.168.178.75:49746/>,NT: upnp:event,TIMEOUT: Second-infinite]]
2018-02-07 21:57:21,688 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,689 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent subscription request received for service ConnectionManager
2018-02-07 21:57:21,690 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription registered (uuid=b22a1421-5f5a-486d-9c82-013bb24ad1fd) for service urn:upnp-org:serviceId:ConnectionManager with duration infinite reporting to http://192.168.178.75:49746/
2018-02-07 21:57:21,690 DEBUG [ServiioHttpService] Incoming request from /192.168.178.75:48448: SUBSCRIBE /serviceEventing/ContentDirectory HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CALLBACK: <http://192.168.178.75:49746/>,NT: upnp:event,TIMEOUT: Second-infinite]]
2018-02-07 21:57:21,690 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,691 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent subscription request received for service ContentDirectory
2018-02-07 21:57:21,691 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription registered (uuid=04b9a674-30e6-4d96-bb22-966038b61f3b) for service urn:upnp-org:serviceId:ContentDirectory with duration infinite reporting to http://192.168.178.75:49746/
2018-02-07 21:57:21,692 DEBUG [ServiioHttpService] Incoming request from /192.168.178.75:43911: SUBSCRIBE /serviceEventing/X_MS_MediaReceiverRegistrar HTTP/1.1, headers = [HOST: 192.168.178.36:8895,CALLBACK: <http://192.168.178.75:49746/>,NT: upnp:event,TIMEOUT: Second-infinite]]
2018-02-07 21:57:21,692 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.75
2018-02-07 21:57:21,693 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent subscription request received for service X_MS_MediaReceiverRegistrar
2018-02-07 21:57:21,694 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription registered (uuid=1a520b74-3d21-40ff-a0eb-1df153c8a48b) for service urn:microsoft.com:serviceId:X_MS_MediaReceiverRegistrar with duration infinite reporting to http://192.168.178.75:49746/
2018-02-07 21:57:21,780 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2018-02-07 21:57:21,784 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.178.91:3942s
2018-02-07 21:57:21,784 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.178.91:3942s
2018-02-07 21:57:21,785 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:RemoteUIServerDevice:1 from address /192.168.178.91:3942s
2018-02-07 21:57:21,785 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.178.91:3942
2018-02-07 21:57:21,785 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.178.91:3942
2018-02-07 21:57:21,787 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:RemoteUIServerDevice:1 from address /192.168.178.91:3942s
2018-02-07 21:57:21,823 DEBUG [ServiioHttpService] Incoming request from /192.168.178.91:38530: GET /icon/largePNG HTTP/1.1, headers = [Host: 192.168.178.36:8895,Accept: */*]]
2018-02-07 21:57:21,824 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.91
2018-02-07 21:57:21,825 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon largePNG
2018-02-07 21:57:22,124 DEBUG [EventDispatcher] Sending event notification #0 for subscription 04b9a674-30e6-4d96-bb22-966038b61f3b to endpoint http://192.168.178.75:49746/
2018-02-07 21:57:22,139 DEBUG [RequestExecutor] Sending HTTP request to 192.168.178.75:49746
2018-02-07 21:57:22,183 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:08 (advertisement duration is 1800 sec.)
2018-02-07 21:57:22,186 DEBUG [EventDispatcher] Event notification sent and received successfully
2018-02-07 21:57:22,187 DEBUG [EventDispatcher] Sending event notification #0 for subscription 4196cc0a-b305-4f48-84d7-d3a3fe338aeb to endpoint http://192.168.178.1:40303/
2018-02-07 21:57:22,187 DEBUG [RequestExecutor] Sending HTTP request to 192.168.178.1:40303
2018-02-07 21:57:22,193 DEBUG [EventDispatcher] Event notification sent and received successfully
2018-02-07 21:57:22,193 DEBUG [EventDispatcher] Sending event notification #0 for subscription b22a1421-5f5a-486d-9c82-013bb24ad1fd to endpoint http://192.168.178.75:49746/
2018-02-07 21:57:22,194 DEBUG [RequestExecutor] Sending HTTP request to 192.168.178.75:49746
2018-02-07 21:57:22,235 DEBUG [EventDispatcher] Event notification sent and received successfully
2018-02-07 21:57:22,235 DEBUG [EventDispatcher] Sending event notification #0 for subscription bda97571-7b2b-487a-8c64-a5c5a8859e70 to endpoint http://192.168.178.1:40303/
2018-02-07 21:57:22,237 DEBUG [RequestExecutor] Sending HTTP request to 192.168.178.1:40303
2018-02-07 21:57:22,243 DEBUG [EventDispatcher] Event notification sent and received successfully
2018-02-07 21:57:22,244 DEBUG [EventDispatcher] Sending event notification #0 for subscription 1a520b74-3d21-40ff-a0eb-1df153c8a48b to endpoint http://192.168.178.75:49746/
2018-02-07 21:57:22,244 DEBUG [RequestExecutor] Sending HTTP request to 192.168.178.75:49746
2018-02-07 21:57:22,276 DEBUG [EventDispatcher] Event notification sent and received successfully
2018-02-07 21:57:22,276 DEBUG [EventDispatcher] Sending event notification #0 for subscription 634a6f9d-4896-495e-a1f9-4dd6febe27b9 to endpoint http://192.168.178.1:40303/
2018-02-07 21:57:22,278 DEBUG [RequestExecutor] Sending HTTP request to 192.168.178.1:40303
2018-02-07 21:57:22,284 DEBUG [EventDispatcher] Event notification sent and received successfully
2018-02-07 21:57:22,897 DEBUG [MediaItemDAOImpl] Getting counts of media items by type
2018-02-07 21:57:23,483 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.178.24:80/DeviceDescription.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2018-02-07 21:57:23,483 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 526f6b75-536f-756e-6442-000d4b305321 from address 192.168.178.24
2018-02-07 21:57:23,667 DEBUG [ServiioHttpService] Incoming request from /192.168.178.35:50800: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [Accept: */*,Host: 192.168.178.36:8895,User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.3, Windows 6.2 (Build 9200); x86_64) DLNADOC/1.50,Connection: close]]
2018-02-07 21:57:23,667 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.35
2018-02-07 21:57:23,668 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.35 (headers = [Accept: */*,Host: 192.168.178.36:8895,User-Agent: Mozilla/4.0 (Compatible, Aggregation, Twonky, 7.2.3, Windows 6.2 (Build 9200); x86_64) DLNADOC/1.50,Connection: close])
2018-02-07 21:57:24,522 DEBUG [ServiioHttpService] Incoming request from /192.168.178.24:43108: GET /deviceDescription/88b65a25-213d-3547-abdf-19ce1282fccc HTTP/1.1, headers = [Host: 192.168.178.36:8895,Connection: close,User-Agent: Allegro-Software-WebClient/4.32,Accept: */*]]
2018-02-07 21:57:24,523 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.178.24
2018-02-07 21:57:24,524 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 88b65a25-213d-3547-abdf-19ce1282fccc from 192.168.178.24 (headers = [Host: 192.168.178.36:8895,Connection: close,User-Agent: Allegro-Software-WebClient/4.32,Accept: */*])
==============================================================================


Do I interprete the line

"2018-02-07 21:57:17,923 INFO [Device] Created UPnP Device with UUID: 88b65a25-213d-3547-abdf-19ce1282fccc, bound address: 192.168.178.36"

correctly in a way that the UPnP device is bound to the only active NIC with the IP 192.168.178.36 ?

However, my devices to not see this UPnP server. There are currently two other UPnP service in the LAN: a.) a Fritzbox as a router and b.) my backup server with Twonky. The last one I just turned on to see a video a my samsung TV because Serviio is not visible anymore... :-(


Can you see any strange messages in the log file which migh help understanding the problem?




Greetings,




Hannes
<<

atc98092

User avatar

DLNA master

Posts: 2709

Joined: Fri Aug 17, 2012 10:22 pm

Location: Washington (the state)

Post Wed Feb 07, 2018 10:59 pm

Re: UPnP interface is gone while Web interface still is visi

I put your log into a code block to keep the post size manageable. :D

Yes, Serviio has bound itself to your active NIC. I'll have to let Zip say if there's something amiss in the log, as I don't see anything.

My 2 cents worth is that Microsoft has pushed out an update to your server that reset your firewall settings and is blocking Serviio from advertising on your network. I have seen this happen with an update before. Since Twonky is visible on your network, it's unlikely it's a network issue.
Dan

Panasonic ST30 Plasma, Samsung JU7100 4K TV, Sony BDP-S3500, Sharp 4K Roku TV, Insignia Roku TV, Roku 4, Ultra and Stick, Yamaha RX-V583 AVR.
Primary server: Intel i5-6400, 8 gig ram, Windows 10 Pro, 18 TB hard drive space | Test server Windows 7 Home Premium, AMD Phenom II X4 965, 8 gig ram

Enable debug logging Identify media file contents
<<

hanneshar

Serviio newbie

Posts: 10

Joined: Mon Feb 05, 2018 9:34 pm

Post Thu Feb 08, 2018 9:37 pm

Re: UPnP interface is gone while Web interface still is visi

Thank you again for your help (and sorry for the large log file pasting...)! :-)

Maybe indeed MS has modified anything in the firewall with the last update. However, in between I've deinstalled and reinstalled Serviio and I would expect that the install routine would have switched back the firewall to rules that are needed for Serviio. When I look in my firewall configuration I see threee entries with the name "Serviio" (I have to translate from German to English here):

Incoming rules:
1-> ServiioService.exe
1-> Profil: all
1-> Active: yes
1-> Local address: any
1-> Remote address: any
1-> Protocoll: UDP
1-> Local port: any
1-> Remote port: any
1-> All other entires: any

2-> ServiioService.exe
2-> Profil: all
2-> Active: yes
2-> Local address: any
2-> Remote address: any
2-> Protocoll: TCP
2-> Local port: any
2-> Remote port: any
2-> All other entires: any

3-> ServiioConsole.exe
3-> Profil: all
3-> Active: yes
3-> Local address: any
3-> Remote address: any
3-> Protocoll: TCP
3-> Local port: any
3-> Remote port: any
3-> All other entires: any

There are no Serviio-specific entries under outgoing rules. Is there anything typicall missing in my firewall configuration for Serviio for Windows 10/Server 2016?


The funny thing: right now my wife is watching a moving from the Serviio server which just popped up in the LAN. However, this unusuall apperaring of Serviio I've observed already before a few times. Unfortunetly, Serviio will as well disapear as well quickly.




Greetings from Hannover,





Hannes
<<

atc98092

User avatar

DLNA master

Posts: 2709

Joined: Fri Aug 17, 2012 10:22 pm

Location: Washington (the state)

Post Thu Feb 08, 2018 11:53 pm

Re: UPnP interface is gone while Web interface still is visi

I don't have Server 2016, and I can't access my Windows 10 computer right now. I checked my Windows 7 computer and on that OS I don't see any outbound rules for Serviio. There are three inbound rules, and they appear to be about the same as yours. I know someone else was having issues using an older version of Windows Server (2013, I think) but I believe he finally had it working OK. I can't say what he did.

Zip, need your advice here! :D
Dan

Panasonic ST30 Plasma, Samsung JU7100 4K TV, Sony BDP-S3500, Sharp 4K Roku TV, Insignia Roku TV, Roku 4, Ultra and Stick, Yamaha RX-V583 AVR.
Primary server: Intel i5-6400, 8 gig ram, Windows 10 Pro, 18 TB hard drive space | Test server Windows 7 Home Premium, AMD Phenom II X4 965, 8 gig ram

Enable debug logging Identify media file contents
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 16744

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Fri Feb 09, 2018 9:19 am

Re: UPnP interface is gone while Web interface still is visi

That looksok to me.

You need UDP 1900 (in/out) and TCP 8895 In.
<<

hanneshar

Serviio newbie

Posts: 10

Joined: Mon Feb 05, 2018 9:34 pm

Post Fri Feb 09, 2018 6:17 pm

Re: UPnP interface is gone while Web interface still is visi

Thank you guys for all of your help!

I have now manually configured my Server 2016 firewall in a way that Port 1800 is open of incoming an outgoing connections via UDP and Port 8895 for incoming connections via TCP. However, Serviio still does not show up as an UPnP service in the LAN. The http-based Mediabrowser instead is working fine.

Yesterday Serviio showed up a for a couple of hours as UPnP device and I was able to watch some movies. This morning the UPnP interface again did not show up and also now after modifying the firewall and restarting Serviio I can't see the UPnP Serviio device. Even not from the same computer where Serviio runs on when I use VLC 3.0 and search for UPnP devices. Very strange.

Looking in the Serviio concole the following devices should be connected:

192.168.178.1 Unrecognized device Generic DLNA profile
192.168.178.20 Unrecognized device Generic DLNA profile
192.168.178.24 Roku SoundBridge M400 Generic DLNA profile
192.168.178.33 Unrecognized device Generic DLNA profile
192.168.178.35 Unrecognized device Generic DLNA profile
192.168.178.36 Unrecognized device Generic DLNA profile
192.168.178.40 Unrecognized device Generic DLNA profile
192.168.178.43 Unrecognized device Generic DLNA profile
192.168.178.45 Unrecognized device Generic DLNA profile
192.168.178.58 Unrecognized device Generic DLNA profile
192.168.178.60 Unrecognized device Generic DLNA profile
192.168.178.70 Unrecognized device Generic DLNA profile
192.168.178.75 Unrecognized device Generic DLNA profile
192.168.178.91 UE55JU6400 Samsung TV / player (J-series)

Unfortunetly, these device do not know that they are connected to an UPnP Serviio server.


I'm running out of ideas... Maybe I have to go back to Twonky.


Greetings,


Hannes
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 16744

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Tue Feb 13, 2018 11:00 am

Re: UPnP interface is gone while Web interface still is visi

It looks like you're using pirated version. Conversation over.
<<

rhoyer78

Serviio newbie

Posts: 7

Joined: Thu Feb 15, 2018 10:30 am

Post Thu Feb 15, 2018 11:21 am

Re: UPnP interface is gone while Web interface still is visi

HI!

Same Problem here:

- Windows Server 2016
- Serviio > 1.7
- Firewall turned off
- WebInterface & ServiioGo are working
- DLNA-Server is not found on all Devices (Kodi, Android, Sony BR, FritzPhone...)
- DLNA-Server did work on 1.6. or 1.7.

  Code:
2018-02-15 11:21:22,046 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-15 11:21:22,047 INFO  [MediaServer] Serviio DLNA media streaming server v 1.9 (rev. a39231b8b876)
2018-02-15 11:21:22,047 INFO  [MediaServer] Petr Nejedly 2009-2017
2018-02-15 11:21:22,047 INFO  [MediaServer] http://www.serviio.org
2018-02-15 11:21:22,047 INFO  [MediaServer]
2018-02-15 11:21:22,047 INFO  [MediaServer] Java 1.8.0_131-Oracle Corporation x86
2018-02-15 11:21:22,047 INFO  [MediaServer] OS Windows Server 2016 10.0
2018-02-15 11:21:22,048 INFO  [MediaServer] File encoding: windows-1252
2018-02-15 11:21:22,135 INFO  [MediaServer] Headless mode enabled: true
2018-02-15 11:21:22,135 INFO  [MediaServer] User: RECLICK-S$
2018-02-15 11:21:22,137 INFO  [MediaServer] User home dir: C:\Windows\syswow64\config\systemprofile
2018-02-15 11:21:22,137 INFO  [MediaServer] Temp dir: C:\Windows\TEMP\
2018-02-15 11:21:22,137 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-15 11:21:22,165 INFO  [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2018-02-15 11:21:22,199 INFO  [DatabaseManager] Using DERBY database language
2018-02-15 11:21:24,962 INFO  [WebServer] Socket buffer set to 65535 bytes
2018-02-15 11:21:24,963 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/console) exposed on port 23423
2018-02-15 11:21:24,972 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/rest) exposed on port 23423
2018-02-15 11:21:24,976 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/console) exposed on port 23523
2018-02-15 11:21:24,976 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/rest) exposed on port 23523
2018-02-15 11:21:26,169 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/cds) exposed on port 23424
2018-02-15 11:21:26,169 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/mediabrowser) exposed on port 23424
2018-02-15 11:21:26,170 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/cds) exposed on port 23524
2018-02-15 11:21:26,171 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/mediabrowser) exposed on port 23524
2018-02-15 11:21:26,281 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-15 11:21:26,281 INFO  [MediaServer] License: PRO (NORMAL, id: 18431d9f-820d-4a77-ba13-35ee4b118509)
2018-02-15 11:21:26,281 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-15 11:21:26,507 INFO  [Device] Created UPnP Device with UUID: a93cae75-b5a0-31f2-b2f0-7bcaf4fbba32, bound address: 192.168.64.62
2018-02-15 11:21:26,709 INFO  [ProfileManager] Loading profiles from file /profiles.xml
2018-02-15 11:21:27,059 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2018-02-15 11:21:27,109 INFO  [CustomisationManager] No customisation properties found
2018-02-15 11:21:27,173 INFO  [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2018-02-15 11:21:27,186 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2018-02-15 11:21:27,190 INFO  [ProfilesDefinitionParser] Added profile 'Xbox 360' (id=3)
2018-02-15 11:21:27,192 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2018-02-15 11:21:27,193 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 4' (id=ps4)
2018-02-15 11:21:27,194 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2018-02-15 11:21:27,198 INFO  [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2018-02-15 11:21:27,200 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (H-series)' (id=sam_h)
2018-02-15 11:21:27,201 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (J-series)' (id=sam_j)
2018-02-15 11:21:27,202 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (E/F-series)' (id=sam_ef)
2018-02-15 11:21:27,202 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C/D-series)' (id=7)
2018-02-15 11:21:27,203 INFO  [ProfilesDefinitionParser] Added profile 'LG TV / player' (id=8)
2018-02-15 11:21:27,206 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012)' (id=sony2012)
2018-02-15 11:21:27,207 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2013/14)' (id=sony2013)
2018-02-15 11:21:27,207 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx100 (2013)' (id=bdp2013)
2018-02-15 11:21:27,208 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx90 (2012)' (id=bdp2012)
2018-02-15 11:21:27,209 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx70/SMP-N100 (2010)' (id=bdp2010)
2018-02-15 11:21:27,210 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx70/SMP-N100 (2010) - US' (id=bdp2010us)
2018-02-15 11:21:27,210 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx80/SMP-N200 (2011)' (id=bdp2011)
2018-02-15 11:21:27,211 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba REGZA' (id=13)
2018-02-15 11:21:27,211 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba REGZA 2012-' (id=tosh2012)
2018-02-15 11:21:27,214 INFO  [ProfilesDefinitionParser] Added profile 'WDTV Live' (id=14)
2018-02-15 11:21:27,214 INFO  [ProfilesDefinitionParser] Added profile 'LaCie LaCinema Media Player' (id=15)
2018-02-15 11:21:27,214 INFO  [ProfilesDefinitionParser] Added profile 'Manta WIFI Centre' (id=16)
2018-02-15 11:21:27,215 INFO  [ProfilesDefinitionParser] Added profile 'Pure Flow' (id=17)
2018-02-15 11:21:27,215 INFO  [ProfilesDefinitionParser] Added profile 'Sharp Aquos' (id=18)
2018-02-15 11:21:27,216 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011)' (id=sony2011)
2018-02-15 11:21:27,216 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2010)' (id=sony2010)
2018-02-15 11:21:27,217 INFO  [ProfilesDefinitionParser] Added profile 'Philips (2010-)' (id=plps2010)
2018-02-15 11:21:27,217 INFO  [ProfilesDefinitionParser] Added profile 'Philips (2009)' (id=20)
2018-02-15 11:21:27,218 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2009)' (id=sony2009)
2018-02-15 11:21:27,219 INFO  [ProfilesDefinitionParser] Added profile 'Denon AVR' (id=22)
2018-02-15 11:21:27,220 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2013)' (id=viera2013)
2018-02-15 11:21:27,220 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera G/GT/DT/UT/VT (2011/2012)' (id=viera2011a)
2018-02-15 11:21:27,220 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera E/S/ST/VT (2011)' (id=viera2011)
2018-02-15 11:21:27,221 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2010)' (id=viera2010)
2018-02-15 11:21:27,221 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2009)' (id=viera2009)
2018-02-15 11:21:27,222 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012) - US' (id=sony2012us)
2018-02-15 11:21:27,226 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011) - US' (id=sony2011us)
2018-02-15 11:21:27,227 INFO  [ProfilesDefinitionParser] Added profile 'Sony AVReceiver STR' (id=SonySTR)
2018-02-15 11:21:27,227 INFO  [ProfilesDefinitionParser] Added profile 'Sony Audio CMT' (id=SonyCMT)
2018-02-15 11:21:27,228 INFO  [ProfilesDefinitionParser] Added profile 'Vizio TV' (id=viziotv)
2018-02-15 11:21:27,228 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic BD player' (id=panasonicBDT220)
2018-02-15 11:21:27,229 INFO  [ProfilesDefinitionParser] Added profile 'Philips NP Streamium' (id=philips_streamium)
2018-02-15 11:21:27,229 INFO  [ProfilesDefinitionParser] Added profile 'Chaneru' (id=roku)
2018-02-15 11:21:27,230 INFO  [ProfilesDefinitionParser] Added profile 'Roku Media Player' (id=RokuMPApp)
2018-02-15 11:21:27,231 INFO  [ProfilesDefinitionParser] Added profile 'Roku 4K Media Player (2016+)' (id=Roku4kMPApp)
2018-02-15 11:21:27,231 INFO  [ProfilesDefinitionParser] Added profile 'Roku 1080 Media Player (2016+)' (id=RokuNo4kApp)
2018-02-15 11:21:27,232 INFO  [ProfilesDefinitionParser] Added profile 'Roku TV (non 4K)' (id=RokuTV)
2018-02-15 11:21:27,233 INFO  [ProfilesDefinitionParser] Added profile 'Roku 4K TV' (id=Roku4kTV)
2018-02-15 11:21:27,233 INFO  [ProfilesDefinitionParser] Added profile 'Yamaha BD-A1020/BD-A1010' (id=YamBD)
2018-02-15 11:21:27,234 INFO  [ProfilesDefinitionParser] Added profile 'Telenet Yelo TV' (id=TNDC)
2018-02-15 11:21:27,234 INFO  [ProfilesDefinitionParser] Added profile 'Xbox One' (id=xbox_one)
2018-02-15 11:21:27,235 INFO  [ProfilesDefinitionParser] Added profile 'Raumfeld' (id=raumfeld)
2018-02-15 11:21:27,245 INFO  [ProfileManager] Loading profiles from file /application-profiles.xml
2018-02-15 11:21:27,271 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2018-02-15 11:21:27,272 INFO  [ProfilesDefinitionParser] Added profile 'Flash player' (id=flv_player)
2018-02-15 11:21:27,272 INFO  [ProfilesDefinitionParser] Added profile 'HTML5' (id=html5)
2018-02-15 11:21:27,273 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Standard)' (id=serviigo_standard)
2018-02-15 11:21:27,273 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Legacy Devices)' (id=serviigo)
2018-02-15 11:21:27,274 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (HLS)' (id=serviigo_hls)
2018-02-15 11:21:27,274 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Google TV)' (id=serviigo_gtv)
2018-02-15 11:21:27,275 INFO  [ProfilesDefinitionParser] Added profile 'Google Cast' (id=googlecast)
2018-02-15 11:21:27,275 INFO  [ProfilesDefinitionParser] Added profile 'Chromecast' (id=chromecast)
2018-02-15 11:21:27,275 INFO  [ProfileManager] User profiles definition (user-profiles.xml) not found, using built-in profiles only
2018-02-15 11:21:27,332 INFO  [PluginCompilerThread] Looking for plugins at C:\Program Files\Serviio\bin\..\plugins
2018-02-15 11:21:27,333 INFO  [PluginCompilerThread] Started looking for plugins
2018-02-15 11:21:27,335 INFO  [UpdateChecker] Checking if a new version is available
2018-02-15 11:21:27,897 INFO  [FFMPEGWrapper] Found FFmpeg: C:\Program Files\Serviio\bin\\..\lib\ffmpeg.exe
2018-02-15 11:21:27,898 INFO  [FFMPEGWrapper] FFmpeg details: [version=3.2.4, libRtpmPresent=true, libAssPresent=true, libShinePresent=false, libMxfPresent=false]
2018-02-15 11:21:28,022 INFO  [AbstractAVTranscodingDeliveryEngine] Cleaning transcode engine and its data
2018-02-15 11:21:28,142 INFO  [UserDataUpdater] Updating user data
2018-02-15 11:21:28,174 INFO  [WebServer] WebServer starting on port 8895
2018-02-15 11:21:28,374 INFO  [Device] Updated bound IP address of Device with UUID: a93cae75-b5a0-31f2-b2f0-7bcaf4fbba32, bound address: 192.168.64.62
2018-02-15 11:21:28,383 INFO  [EventDispatcher] Starting EventDispatcher
2018-02-15 11:21:28,383 INFO  [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2018-02-15 11:21:28,384 INFO  [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2018-02-15 11:21:28,391 INFO  [RendererSearchSender] Starting Renderer searcher process
2018-02-15 11:21:28,391 INFO  [RendererExpirationChecker] Starting RendererExpirationChecker
2018-02-15 11:21:28,561 INFO  [PortMappingChecker] Running a check for external mapping of port 23524 on '91.248.75.135'
2018-02-15 11:21:28,603 INFO  [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface eth4 (Hyper-V Virtual Ethernet Adapter) and address 192.168.64.62, timeout = 0
2018-02-15 11:21:47,757 WARN  [RendererManager] Error while retrieving renderer description: Read timed out
2018-02-15 11:21:48,399 INFO  [FeedUpdaterWorker] Started looking for information about online resources
2018-02-15 11:21:48,449 INFO  [TrakttvService] Started trakt.tv sync updater
2018-02-15 11:21:48,449 INFO  [TrakttvService] Started trakt.tv scrobble poster
2018-02-15 11:21:48,477 INFO  [FileDeltaWatcher] Using 'sun.nio.fs.WindowsWatchService' for delta scan
2018-02-15 11:21:48,485 INFO  [RepositoriesStatusCoordinator$RepositoriesStatusThread] Started checking repositories status
2018-02-15 11:21:48,516 INFO  [LocalLibraryManager] Starting automatic library scan
2018-02-15 11:21:48,568 INFO  [SearchManager] Starting up search engine
2018-02-15 11:21:48,741 INFO  [LibraryDeltaScanner] Added repository 'D:\temp' to delta scanner (NATIVE)
2018-02-15 11:21:48,796 WARN  [UserDataUpdater] There was an error updating user data
java.net.SocketTimeoutException: connect timed out
   at java.net.TwoStacksPlainSocketImpl.socketConnect(Native Method)
   at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
   at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
   at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
   at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:172)
   at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
   at java.net.Socket.connect(Socket.java:589)
   at sun.net.NetworkClient.doConnect(NetworkClient.java:175)
   at sun.net.www.http.HttpClient.openServer(HttpClient.java:463)
   at sun.net.www.http.HttpClient.openServer(HttpClient.java:558)
   at sun.net.www.http.HttpClient.<init>(HttpClient.java:242)
   at sun.net.www.http.HttpClient.New(HttpClient.java:339)
   at sun.net.www.http.HttpClient.New(HttpClient.java:357)
   at sun.net.www.protocol.http.HttpURLConnection.getNewHttpClient(HttpURLConnection.java:1202)
   at sun.net.www.protocol.http.HttpURLConnection.plainConnect0(HttpURLConnection.java:1138)
   at sun.net.www.protocol.http.HttpURLConnection.plainConnect(HttpURLConnection.java:1032)
   at sun.net.www.protocol.http.HttpURLConnection.connect(HttpURLConnection.java:966)
   at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1546)
   at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1474)
   at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:480)
   at org.serviio.util.HttpClient.retrieveStreamFromConnection(HttpClient.java:173)
   at org.serviio.util.HttpClient.getStreamFromURL(HttpClient.java:103)
   at org.serviio.util.HttpClient.retrieveTextFileFromURL(HttpClient.java:42)
   at org.serviio.upnp.service.contentdirectory.rest.access.TuqPortCheckerProvider.isPortOpen(TuqPortCheckerProvider.java:20)
   at org.serviio.upnp.service.contentdirectory.rest.access.PortMappingChecker.isPortOpen(PortMappingChecker.java:39)
   at org.serviio.upnp.service.contentdirectory.rest.access.UserDataUpdater.isSSLPortOpen(UserDataUpdater.java:178)
   at org.serviio.upnp.service.contentdirectory.rest.access.UserDataUpdater.doUpdateUserData(UserDataUpdater.java:101)
   at java.lang.Thread.run(Thread.java:748)
2018-02-15 11:21:48,799 INFO  [UserDataUpdater] Updating user data
2018-02-15 11:21:49,016 INFO  [ContentDirectoryDefinitionParser] Parsing ContentDirectory definition
2018-02-15 11:21:49,180 INFO  [PortMappingChecker] Running a check for external mapping of port 23524 on '91.248.75.135'
2018-02-15 11:21:50,122 INFO  [LibraryDeltaScanner] Added repository 'D:\mp3_Hoerspiele' to delta scanner (NATIVE)
2018-02-15 11:21:51,416 INFO  [LibraryDeltaScanner] Added repository 'D:\Musik' to delta scanner (NATIVE)
2018-02-15 11:21:51,452 INFO  [LibraryDeltaScanner] Added repository 'E:\Filme' to delta scanner (NATIVE)
2018-02-15 11:21:51,810 INFO  [LibraryDeltaScanner] Added repository 'D:\mp3_klassik' to delta scanner (NATIVE)
2018-02-15 11:21:51,810 INFO  [LibraryDeltaScanner] Starting the library delta scan
2018-02-15 11:21:51,812 INFO  [PlaylistMaintainerWorker] Started looking for playlist changes
2018-02-15 11:21:54,323 WARN  [PlaylistMaintainerWorker] An error occured while updating playlist, will continue
java.lang.NullPointerException
   at org.serviio.library.dao.MediaItemDAOImpl.getMediaItem(MediaItemDAOImpl.java:84)
   at org.serviio.library.local.service.MediaService.getMediaItem(MediaService.java:47)
   at org.serviio.library.local.metadata.PlaylistMaintainerWorker.checkForMissingPlaylistItems(PlaylistMaintainerWorker.java:125)
   at org.serviio.library.local.metadata.PlaylistMaintainerWorker.refreshPlaylists(PlaylistMaintainerWorker.java:63)
   at org.serviio.library.local.metadata.PlaylistMaintainerWorker.run(PlaylistMaintainerWorker.java:90)
   at java.lang.Thread.run(Thread.java:748)
2018-02-15 11:21:57,565 WARN  [PlaylistMaintainerWorker] An error occured while updating playlist, will continue
java.lang.NullPointerException
   at org.serviio.library.dao.MediaItemDAOImpl.getMediaItem(MediaItemDAOImpl.java:84)
   at org.serviio.library.local.service.MediaService.getMediaItem(MediaService.java:47)
   at org.serviio.library.local.metadata.PlaylistMaintainerWorker.checkForMissingPlaylistItems(PlaylistMaintainerWorker.java:125)
   at org.serviio.library.local.metadata.PlaylistMaintainerWorker.refreshPlaylists(PlaylistMaintainerWorker.java:63)
   at org.serviio.library.local.metadata.PlaylistMaintainerWorker.run(PlaylistMaintainerWorker.java:90)
   at java.lang.Thread.run(Thread.java:748)
2018-02-15 11:21:57,885 WARN  [PlaylistMaintainerWorker] An error occured while updating playlist, will continue
java.lang.NullPointerException
   at org.serviio.library.dao.MediaItemDAOImpl.getMediaItem(MediaItemDAOImpl.java:84)
   at org.serviio.library.local.service.MediaService.getMediaItem(MediaService.java:47)
   at org.serviio.library.local.metadata.PlaylistMaintainerWorker.checkForMissingPlaylistItems(PlaylistMaintainerWorker.java:125)
   at org.serviio.library.local.metadata.PlaylistMaintainerWorker.refreshPlaylists(PlaylistMaintainerWorker.java:63)
   at org.serviio.library.local.metadata.PlaylistMaintainerWorker.run(PlaylistMaintainerWorker.java:90)
   at java.lang.Thread.run(Thread.java:748)


Thanks for your help!
Attachments
Unbenannt.png
UPNP-Scan Kodi (Same Computer as Serviio-Server): Does not show Serviio (reclick-s)
Unbenannt.png (29.48 KiB) Viewed 1003 times
<<

DenyAll

DLNA master

Posts: 2169

Joined: Fri Mar 08, 2013 11:16 pm

Location: Adelaide, Australia

Post Thu Feb 15, 2018 1:46 pm

Re: UPnP interface is gone while Web interface still is visi

You are using a virtual adaptor: (Hyper-V Virtual Ethernet Adapter). Could this be the issue?

There is a bit on the web about UPnP breaking after enabling Hyper-V - most of it is dated (so may have been resolved in later versions of WS). Did you recently enable it?
DenyAll
Panasonic Viera CS610A | Panasonic Viera V20A | Sony PS4 | Sony PS3 | Panasonic DMP-BD79 | Yamaha RX-V500D | iPad | Windows 10 | Serviio 1.9 Pro
WinHelper | MediaInfo

Beta Tester, Moderator
Please do not PM me for support as any solution cannot be shared with others.
<<

rhoyer78

Serviio newbie

Posts: 7

Joined: Thu Feb 15, 2018 10:30 am

Post Sat Feb 17, 2018 10:37 am

Re: UPnP interface is gone while Web interface still is visi

I changed Serviio to a second Network-Card (free of Hyper-V) + Upgrade to 1.9.1. Same result...


  Code:
2018-02-17 11:29:07,060 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-17 11:29:07,062 INFO  [MediaServer] Serviio DLNA media streaming server v 1.9.1 (rev. 5ccd9739d6ec)
2018-02-17 11:29:07,062 INFO  [MediaServer] Petr Nejedly 2009-2017
2018-02-17 11:29:07,062 INFO  [MediaServer] http://www.serviio.org
2018-02-17 11:29:07,062 INFO  [MediaServer]
2018-02-17 11:29:07,062 INFO  [MediaServer] Java 1.8.0_162-Oracle Corporation x86
2018-02-17 11:29:07,062 INFO  [MediaServer] OS Windows Server 2016 10.0
2018-02-17 11:29:07,062 INFO  [MediaServer] File encoding: windows-1252
2018-02-17 11:29:07,152 INFO  [MediaServer] Headless mode enabled: true
2018-02-17 11:29:07,152 INFO  [MediaServer] User: RECLICK-S$
2018-02-17 11:29:07,154 INFO  [MediaServer] User home dir: C:\Windows\syswow64\config\systemprofile
2018-02-17 11:29:07,154 INFO  [MediaServer] Temp dir: C:\Windows\TEMP\
2018-02-17 11:29:07,154 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-17 11:29:07,173 INFO  [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2018-02-17 11:29:07,200 INFO  [DatabaseManager] Using DERBY database language
2018-02-17 11:29:08,386 INFO  [WebServer] Socket buffer set to 65535 bytes
2018-02-17 11:29:08,386 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/console) exposed on port 23423
2018-02-17 11:29:08,389 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/rest) exposed on port 23423
2018-02-17 11:29:08,391 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/console) exposed on port 23523
2018-02-17 11:29:08,391 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/rest) exposed on port 23523
2018-02-17 11:29:09,374 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/cds) exposed on port 23424
2018-02-17 11:29:09,375 INFO  [RestletServer] Starting HTTP/1.1 Restlet server (/mediabrowser) exposed on port 23424
2018-02-17 11:29:09,376 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/cds) exposed on port 23524
2018-02-17 11:29:09,376 INFO  [RestletServer] Starting HTTPS/1.1 Restlet server (/mediabrowser) exposed on port 23524
2018-02-17 11:29:09,479 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-17 11:29:09,479 INFO  [MediaServer] License: PRO (NORMAL, id: 18431d9f-820d-4a77-ba13-35ee4b118509)
2018-02-17 11:29:09,479 INFO  [MediaServer] ------------------------------------------------------------------------
2018-02-17 11:29:09,701 INFO  [Device] Created UPnP Device with UUID: f4616abd-bd98-3bcf-9fd5-6730b1437585, bound address: 192.168.64.4
2018-02-17 11:29:09,877 INFO  [ProfileManager] Loading profiles from file /profiles.xml
2018-02-17 11:29:10,027 INFO  [TrakttvService] Started trakt.tv sync updater
2018-02-17 11:29:10,028 INFO  [TrakttvService] Started trakt.tv scrobble poster
2018-02-17 11:29:10,060 INFO  [FileDeltaWatcher] Using 'sun.nio.fs.WindowsWatchService' for delta scan
2018-02-17 11:29:10,250 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2018-02-17 11:29:10,298 INFO  [CustomisationManager] No customisation properties found
2018-02-17 11:29:10,375 INFO  [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2018-02-17 11:29:10,400 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2018-02-17 11:29:10,408 INFO  [ProfilesDefinitionParser] Added profile 'Xbox 360' (id=3)
2018-02-17 11:29:10,409 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2018-02-17 11:29:10,411 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 4' (id=ps4)
2018-02-17 11:29:10,411 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2018-02-17 11:29:10,423 INFO  [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2018-02-17 11:29:10,426 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (H-series)' (id=sam_h)
2018-02-17 11:29:10,427 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (J-series)' (id=sam_j)
2018-02-17 11:29:10,428 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (M-series)' (id=sam_m)
2018-02-17 11:29:10,429 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (E/F-series)' (id=sam_ef)
2018-02-17 11:29:10,429 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C/D-series)' (id=7)
2018-02-17 11:29:10,430 INFO  [ProfilesDefinitionParser] Added profile 'LG TV / player' (id=8)
2018-02-17 11:29:10,440 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012)' (id=sony2012)
2018-02-17 11:29:10,441 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2013/14)' (id=sony2013)
2018-02-17 11:29:10,442 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx100 (2013)' (id=bdp2013)
2018-02-17 11:29:10,443 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx90 (2012)' (id=bdp2012)
2018-02-17 11:29:10,444 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx70/SMP-N100 (2010)' (id=bdp2010)
2018-02-17 11:29:10,445 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx70/SMP-N100 (2010) - US' (id=bdp2010us)
2018-02-17 11:29:10,445 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx80/SMP-N200 (2011)' (id=bdp2011)
2018-02-17 11:29:10,446 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba REGZA' (id=13)
2018-02-17 11:29:10,452 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba REGZA 2012-' (id=tosh2012)
2018-02-17 11:29:10,453 INFO  [ProfilesDefinitionParser] Added profile 'WDTV Live' (id=14)
2018-02-17 11:29:10,454 INFO  [ProfilesDefinitionParser] Added profile 'LaCie LaCinema Media Player' (id=15)
2018-02-17 11:29:10,454 INFO  [ProfilesDefinitionParser] Added profile 'Manta WIFI Centre' (id=16)
2018-02-17 11:29:10,455 INFO  [ProfilesDefinitionParser] Added profile 'Pure Flow' (id=17)
2018-02-17 11:29:10,455 INFO  [ProfilesDefinitionParser] Added profile 'Sharp Aquos' (id=18)
2018-02-17 11:29:10,455 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011)' (id=sony2011)
2018-02-17 11:29:10,456 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2010)' (id=sony2010)
2018-02-17 11:29:10,456 INFO  [ProfilesDefinitionParser] Added profile 'Philips (2010-)' (id=plps2010)
2018-02-17 11:29:10,457 INFO  [ProfilesDefinitionParser] Added profile 'Philips (2009)' (id=20)
2018-02-17 11:29:10,457 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2009)' (id=sony2009)
2018-02-17 11:29:10,458 INFO  [ProfilesDefinitionParser] Added profile 'Denon AVR' (id=22)
2018-02-17 11:29:10,463 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2013)' (id=viera2013)
2018-02-17 11:29:10,463 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera G/GT/DT/UT/VT (2011/2012)' (id=viera2011a)
2018-02-17 11:29:10,464 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera E/S/ST/VT (2011)' (id=viera2011)
2018-02-17 11:29:10,464 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2010)' (id=viera2010)
2018-02-17 11:29:10,464 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2009)' (id=viera2009)
2018-02-17 11:29:10,465 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012) - US' (id=sony2012us)
2018-02-17 11:29:10,465 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011) - US' (id=sony2011us)
2018-02-17 11:29:10,466 INFO  [ProfilesDefinitionParser] Added profile 'Sony AVReceiver STR' (id=SonySTR)
2018-02-17 11:29:10,466 INFO  [ProfilesDefinitionParser] Added profile 'Sony Audio CMT' (id=SonyCMT)
2018-02-17 11:29:10,466 INFO  [ProfilesDefinitionParser] Added profile 'Vizio TV' (id=viziotv)
2018-02-17 11:29:10,467 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic BD player' (id=panasonicBDT220)
2018-02-17 11:29:10,467 INFO  [ProfilesDefinitionParser] Added profile 'Philips NP Streamium' (id=philips_streamium)
2018-02-17 11:29:10,468 INFO  [ProfilesDefinitionParser] Added profile 'Chaneru' (id=roku)
2018-02-17 11:29:10,469 INFO  [ProfilesDefinitionParser] Added profile 'Roku Media Player' (id=RokuMPApp)
2018-02-17 11:29:10,469 INFO  [ProfilesDefinitionParser] Added profile 'Roku 4K Media Player (2016+)' (id=Roku4kMPApp)
2018-02-17 11:29:10,470 INFO  [ProfilesDefinitionParser] Added profile 'Roku 1080 Media Player (2016+)' (id=RokuNo4kApp)
2018-02-17 11:29:10,474 INFO  [ProfilesDefinitionParser] Added profile 'Roku TV (non 4K)' (id=RokuTV)
2018-02-17 11:29:10,474 INFO  [ProfilesDefinitionParser] Added profile 'Roku 4K TV' (id=Roku4kTV)
2018-02-17 11:29:10,475 INFO  [ProfilesDefinitionParser] Added profile 'Yamaha BD-A1020/BD-A1010' (id=YamBD)
2018-02-17 11:29:10,475 INFO  [ProfilesDefinitionParser] Added profile 'Telenet Yelo TV' (id=TNDC)
2018-02-17 11:29:10,476 INFO  [ProfilesDefinitionParser] Added profile 'Xbox One' (id=xbox_one)
2018-02-17 11:29:10,477 INFO  [ProfilesDefinitionParser] Added profile 'Raumfeld' (id=raumfeld)
2018-02-17 11:29:10,478 INFO  [ProfileManager] Loading profiles from file /application-profiles.xml
2018-02-17 11:29:10,532 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2018-02-17 11:29:10,533 INFO  [ProfilesDefinitionParser] Added profile 'Flash player' (id=flv_player)
2018-02-17 11:29:10,534 INFO  [ProfilesDefinitionParser] Added profile 'HTML5' (id=html5)
2018-02-17 11:29:10,539 INFO  [ProfilesDefinitionParser] Added profile 'HTML5 Mobile' (id=html5_mobile)
2018-02-17 11:29:10,540 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Standard)' (id=serviigo_standard)
2018-02-17 11:29:10,540 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Legacy Devices)' (id=serviigo)
2018-02-17 11:29:10,541 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (HLS)' (id=serviigo_hls)
2018-02-17 11:29:10,541 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Google TV)' (id=serviigo_gtv)
2018-02-17 11:29:10,542 INFO  [ProfilesDefinitionParser] Added profile 'Google Cast' (id=googlecast)
2018-02-17 11:29:10,543 INFO  [ProfilesDefinitionParser] Added profile 'Chromecast' (id=chromecast)
2018-02-17 11:29:10,543 INFO  [ProfileManager] User profiles definition (user-profiles.xml) not found, using built-in profiles only
2018-02-17 11:29:10,608 INFO  [PluginCompilerThread] Looking for plugins at C:\Program Files\Serviio\bin\..\plugins
2018-02-17 11:29:10,609 INFO  [PluginCompilerThread] Started looking for plugins
2018-02-17 11:29:10,618 INFO  [UpdateChecker] Checking if a new version is available
2018-02-17 11:29:11,139 INFO  [FFMPEGWrapper] Found FFmpeg: C:\Program Files\Serviio\bin\\..\lib\ffmpeg.exe
2018-02-17 11:29:11,141 INFO  [FFMPEGWrapper] FFmpeg details: [version=3.2.4, libRtpmPresent=true, libAssPresent=true, libShinePresent=false, libMxfPresent=false]
2018-02-17 11:29:11,266 INFO  [AbstractAVTranscodingDeliveryEngine] Cleaning transcode engine and its data
2018-02-17 11:29:11,356 INFO  [PlaybackProgressUpdater] Started playback progress updater
2018-02-17 11:29:11,382 INFO  [WebServer] WebServer starting on port 8895
2018-02-17 11:29:11,625 INFO  [Device] Updated bound IP address of Device with UUID: f4616abd-bd98-3bcf-9fd5-6730b1437585, bound address: 192.168.64.4
2018-02-17 11:29:11,643 INFO  [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2018-02-17 11:29:11,643 INFO  [EventDispatcher] Starting EventDispatcher
2018-02-17 11:29:11,644 INFO  [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2018-02-17 11:29:11,658 INFO  [RendererExpirationChecker] Starting RendererExpirationChecker
2018-02-17 11:29:11,657 INFO  [RendererSearchSender] Starting Renderer searcher process
2018-02-17 11:29:11,989 INFO  [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface eth5 (Intel(R) I210 Gigabit Network Connection #2) and address 192.168.64.4, timeout = 0
2018-02-17 11:29:31,326 WARN  [RendererManager] Error while retrieving renderer description: Read timed out
2018-02-17 11:29:31,671 INFO  [FeedUpdaterWorker] Started looking for information about online resources
2018-02-17 11:29:31,671 INFO  [RepositoriesStatusCoordinator$RepositoriesStatusThread] Started checking repositories status
2018-02-17 11:29:31,747 INFO  [LocalLibraryManager] Starting automatic library scan
2018-02-17 11:29:31,971 INFO  [LibraryDeltaScanner] Added repository 'D:\temp' to delta scanner (NATIVE)
2018-02-17 11:29:33,246 INFO  [LibraryDeltaScanner] Added repository 'D:\mp3_Hoerspiele' to delta scanner (NATIVE)
2018-02-17 11:29:34,487 INFO  [LibraryDeltaScanner] Added repository 'D:\Musik' to delta scanner (NATIVE)
2018-02-17 11:29:34,517 INFO  [LibraryDeltaScanner] Added repository 'E:\Filme' to delta scanner (NATIVE)
2018-02-17 11:29:34,851 INFO  [LibraryDeltaScanner] Added repository 'D:\mp3_klassik' to delta scanner (NATIVE)
2018-02-17 11:29:34,851 INFO  [LibraryDeltaScanner] Starting the library delta scan
2018-02-17 11:29:34,854 INFO  [PlaylistMaintainerWorker] Started looking for playlist changes
<<

DenyAll

DLNA master

Posts: 2169

Joined: Fri Mar 08, 2013 11:16 pm

Location: Adelaide, Australia

Post Sat Feb 17, 2018 11:29 pm

Re: UPnP interface is gone while Web interface still is visi

WARN [RendererManager] Error while retrieving renderer description: Read timed out

Turn on debug log and see if there's anything more when this happens.
Did something recently change anywhere on your network?
Do you have IMGP settings on your router - have they changed?
DenyAll
Panasonic Viera CS610A | Panasonic Viera V20A | Sony PS4 | Sony PS3 | Panasonic DMP-BD79 | Yamaha RX-V500D | iPad | Windows 10 | Serviio 1.9 Pro
WinHelper | MediaInfo

Beta Tester, Moderator
Please do not PM me for support as any solution cannot be shared with others.
<<

rhoyer78

Serviio newbie

Posts: 7

Joined: Thu Feb 15, 2018 10:30 am

Post Tue Feb 20, 2018 12:31 pm

Re: UPnP interface is gone while Web interface still is visi

Hi!

Thanks for your time & help.

Turn on debug log and see if there's anything more when this happens.


Please find attached the debug-log, I removed all PlaylistMaintainerWorker|MediaItemDAOImpl|PlaylistDAOImpl|PlaylistParser|SubtitleFileIndexer|MediaFileIndexer|PlaylistFileIndexer|PersonDAOImpl|VideoDAOImpl|CoverImageDAOImpl|FolderDAOImpl|RepositoryDAOImpl|GenreDAOImpl|ImageUtils|VideoService|OpenSubtitlesService|MetadataDescriptorDAOImpl|TheMovieDBTvSourceAdaptor|OnlineVideoSourcesMetadataExtractor|OnlineRepositoryDAOImpl|LibraryHelper|TheTVDBv2SourceAdaptor|MusicAlbumDAOImpl|AudioService|ExternalIPRetriever|PortMappingChecker - lines

Did something recently change anywhere on your network?

Nope - the upgrade to Serviio 1.8./1.9. is the only change

Do you have IMGP settings on your router - have they changed?


No IMGP-Settings.

I installed the "Universal Media Server" on the same Computer: All Devices can see the "Universal Media Server" via upnp but do not see the Serviio-UPNP. I think Hyper-V, Router and Network are not the cause....

Thanks
Attachments
debuglog_clean.zip
(84.44 KiB) Downloaded 9 times
serviio_2018-02-20 12_45_22-Kodi.png
serviio_2018-02-20 12_45_22-Kodi.png (157.23 KiB) Viewed 898 times
<<

rhoyer78

Serviio newbie

Posts: 7

Joined: Thu Feb 15, 2018 10:30 am

Post Tue Feb 20, 2018 12:54 pm

Re: UPnP interface is gone while Web interface still is visi

NOTIFY * HTTP/1.1
HOST: 239.255.255.250:1900
CACHE-CONTROL: max-age = 1800
LOCATION: http://192.168.64.4:8895/deviceDescript ... 30b1437585
SERVER: Windows_Server_2016 UPnP/1.0 DLNADOC/1.50 Serviio/1.9.1
NTS: ssdp:alive
NT: urn:schemas-upnp-org:device:MediaServer:1
USN: uuid:f4616abd-bd98-3bcf-9fd5-6730b1437585::urn:schemas-upnp-org:device:MediaServer:1

Opening the URL:
http://192.168.64.4:8895/deviceDescript ... 30b1437585

Result: Timeout

Opening the URL:
http://localhost:8895/deviceDescription ... 30b1437585

Result:

  Code:
<root xmlns="urn:schemas-upnp-org:device-1-0" xmlns:dlna="urn:schemas-dlna-org:device-1-0" xmlns:sec="http://www.sec.co.kr/dlna">
<specVersion>
<major>1</major>
<minor>0</minor>
</specVersion>
<device>
<dlna:X_DLNACAP/>
<dlna:X_DLNADOC>DMS-1.50</dlna:X_DLNADOC>
<dlna:X_DLNADOC>M-DMS-1.50</dlna:X_DLNADOC>
<UDN>uuid:f4616abd-bd98-3bcf-9fd5-6730b1437585</UDN>
<friendlyName>Serviio (Reclick-s)</friendlyName>
<deviceType>urn:schemas-upnp-org:device:MediaServer:1</deviceType>
<manufacturer>Petr Nejedly</manufacturer>
<manufacturerURL>http://www.serviio.org</manufacturerURL>
<modelName>Serviio Media Server</modelName>
<modelDescription>Serviio, a DLNA media server</modelDescription>
<modelNumber>1.9.1</modelNumber>
<modelURL>http://www.serviio.org</modelURL>
<serialNumber/>
<iconList>
<icon>
<mimetype>image/png</mimetype>
<width>48</width>
<height>48</height>
<depth>24</depth>
<url>/icon/smallPNG</url>
</icon>
<icon>
<mimetype>image/png</mimetype>
<width>120</width>
<height>120</height>
<depth>24</depth>
<url>/icon/largePNG</url>
</icon>
<icon>
<mimetype>image/jpeg</mimetype>
<width>48</width>
<height>48</height>
<depth>24</depth>
<url>/icon/smallJPG</url>
</icon>
<icon>
<mimetype>image/jpeg</mimetype>
<width>120</width>
<height>120</height>
<depth>24</depth>
<url>/icon/largeJPG</url>
</icon>
</iconList>
<serviceList>
<service>
<serviceType>urn:schemas-upnp-org:service:ConnectionManager:1</serviceType>
<serviceId>urn:upnp-org:serviceId:ConnectionManager</serviceId>
<SCPDURL>/serviceDescription/ConnectionManager</SCPDURL>
<controlURL>/serviceControl</controlURL>
<eventSubURL>/serviceEventing/ConnectionManager</eventSubURL>
</service>
<service>
<serviceType>urn:schemas-upnp-org:service:ContentDirectory:1</serviceType>
<serviceId>urn:upnp-org:serviceId:ContentDirectory</serviceId>
<SCPDURL>/serviceDescription/ContentDirectory</SCPDURL>
<controlURL>/serviceControl</controlURL>
<eventSubURL>/serviceEventing/ContentDirectory</eventSubURL>
</service>
<service>
<serviceType>
urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1
</serviceType>
<serviceId>
urn:microsoft.com:serviceId:X_MS_MediaReceiverRegistrar
</serviceId>
<SCPDURL>/serviceDescription/X_MS_MediaReceiverRegistrar</SCPDURL>
<controlURL>/serviceControl</controlURL>
<eventSubURL>/serviceEventing/X_MS_MediaReceiverRegistrar</eventSubURL>
</service>
</serviceList>
</device>
</root>


Binding of the WebServer on port 8895 is not correct...
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 16744

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Tue Feb 20, 2018 2:02 pm

Re: UPnP interface is gone while Web interface still is visi

that's your problem... somehow the IP is not accesible. Serviio binds to the IP address and port 8895.
<<

rhoyer78

Serviio newbie

Posts: 7

Joined: Thu Feb 15, 2018 10:30 am

Post Tue Feb 20, 2018 5:51 pm

Re: UPnP interface is gone while Web interface still is visi

Sorry, I was wrong: there is a binding to 192.168.64.4:8895, but the response is taking about 5 minutes (localhost < 1 sec).

WEGT on localhost:8895

  Code:
wget -S -d  http://localhost:8895/deviceDescription/f4616abd-bd98-3bcf-9fd5-6730b1437585
DEBUG output created by Wget 1.9 on MS-Windows.

set_sleep_mode(): mode 0x80000001, rc 0x80000000
--18:10:25--  http://localhost:8895/deviceDescription/f4616abd-bd98-3bcf-9fd5-6730b1437585
           => `f4616abd-bd98-3bcf-9fd5-6730b1437585.2'
Resolving localhost... seconds 0,00, 127.0.0.1
Caching localhost => 127.0.0.1
Connecting to localhost[127.0.0.1]:8895... seconds 0,00, connected.
Created socket 536.
Releasing 02AA6028 (new refcount 1).
---request begin---
GET /deviceDescription/f4616abd-bd98-3bcf-9fd5-6730b1437585 HTTP/1.0
User-Agent: Wget/1.9
Host: localhost:8895
Accept: */*
Connection: Keep-Alive

---request end---
HTTP request sent, awaiting response... HTTP/1.1 200 OK
DATE: Tue, 20 Feb 2018 17:10:25 GMT

 2 DATE: Tue, 20 Feb 2018 17:10:25 GMTServer: Windows_Server_2016 UPnP/1.0 DLNADOC/1.50 Serviio/1.9.1

 3 Server: Windows_Server_2016 UPnP/1.0 DLNADOC/1.50 Serviio/1.9.1Content-Length: 2481

 4 Content-Length: 2481Content-Type: text/xml

 5 Content-Type: text/xmlConnection: Keep-Alive

 6 Connection: Keep-Alive

Found localhost in host_name_addresses_map (02AA6028)
Registered fd 536 for persistent reuse.

100%[====================================>] 2,481         --.--K/s

18:10:25 (2,37 MB/s) - `f4616abd-bd98-3bcf-9fd5-6730b1437585.2' saved [2481/2481]


Serviio-Log:

  Code:
2018-02-20 18:10:25,580 DEBUG [ServiioHttpService] Incoming request from /127.0.0.1:19422: GET /deviceDescription/f4616abd-bd98-3bcf-9fd5-6730b1437585 HTTP/1.0, headers = [User-Agent: Wget/1.9,Host: localhost:8895,Accept: */*,Connection: Keep-Alive]]
2018-02-20 18:10:25,580 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 127.0.0.1
2018-02-20 18:10:25,581 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device f4616abd-bd98-3bcf-9fd5-6730b1437585 from 127.0.0.1 (headers = [User-Agent: Wget/1.9,Host: localhost:8895,Accept: */*,Connection: Keep-Alive])
2018-02-20 18:10:25,581 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 127.0.0.1
2018-02-20 18:10:25,581 DEBUG [ProfileManager] Cannot find stored renderer, using Generic
2018-02-20 18:10:25,584 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2018-02-20 18:10:25,668 DEBUG [WebServer] I/O error: Connection reset


WGET on 192.168.64.4:8895

  Code:
wget -S -d http://192.168.64.4:8895/deviceDescription/f4616abd-bd98-3bcf-9fd5-6730b1437585
DEBUG output created by Wget 1.9 on MS-Windows.

set_sleep_mode(): mode 0x80000001, rc 0x80000000
--18:12:43--  http://192.168.64.4:8895/deviceDescription/f4616abd-bd98-3bcf-9fd5-6730b1437585
           => `f4616abd-bd98-3bcf-9fd5-6730b1437585.3'
Connecting to 192.168.64.4:8895... seconds 0,00, connected.
Created socket 432.
Releasing 029FBD70 (new refcount 0).
Deleting unused 029FBD70.
---request begin---
GET /deviceDescription/f4616abd-bd98-3bcf-9fd5-6730b1437585 HTTP/1.0
User-Agent: Wget/1.9
Host: 192.168.64.4:8895
Accept: */*
Connection: Keep-Alive

---request end---
HTTP request sent, awaiting response... HTTP/1.1 200 OK
DATE: Tue, 20 Feb 2018 17:17:32 GMT

 2 DATE: Tue, 20 Feb 2018 17:17:32 GMTServer: Windows_Server_2016 UPnP/1.0 DLNADOC/1.50 Serviio/1.9.1

 3 Server: Windows_Server_2016 UPnP/1.0 DLNADOC/1.50 Serviio/1.9.1Content-Length: 2481

 4 Content-Length: 2481Content-Type: text/xml

 5 Content-Type: text/xmlConnection: Keep-Alive

 6 Connection: Keep-Alive

Registered fd 432 for persistent reuse.

100%[====================================>] 2,481         --.--K/s

18:17:32 (2,37 MB/s) - `f4616abd-bd98-3bcf-9fd5-6730b1437585.3' saved [2481/2481]


Serviio-Log

  Code:
2018-02-20 18:12:43,445 DEBUG [ServiioHttpService] Incoming request from /192.168.64.4:19452: GET /deviceDescription/f4616abd-bd98-3bcf-9fd5-6730b1437585 HTTP/1.0, headers = [User-Agent: Wget/1.9,Host: 192.168.64.4:8895,Accept: */*,Connection: Keep-Alive]]
2018-02-20 18:12:43,445 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.64.4
2018-02-20 18:12:43,445 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device f4616abd-bd98-3bcf-9fd5-6730b1437585 from 192.168.64.4 (headers = [User-Agent: Wget/1.9,Host: 192.168.64.4:8895,Accept: */*,Connection: Keep-Alive])
2018-02-20 18:13:02,202 WARN  [RendererManager] Error while retrieving renderer description: Read timed out
2018-02-20 18:13:21,111 WARN  [RendererManager] Error while retrieving renderer description: Read timed out
2018-02-20 18:13:37,647 DEBUG [RendererSearchSender] Searching for Renderer devices
2018-02-20 18:13:40,022 WARN  [RendererManager] Error while retrieving renderer description: Read timed out
2018-02-20 18:13:40,025 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2018-02-20 18:13:40,026 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2018-02-20 18:14:17,843 WARN  [RendererManager] Error while retrieving renderer description: Read timed out
2018-02-20 18:17:13,309 WARN  [RendererManager] Error while retrieving renderer description: Read timed out
2018-02-20 18:17:13,309 DEBUG [RendererManager] Retrieve device description from http://192.168.64.83:52525/root_XXYY.xml
2018-02-20 18:17:32,221 WARN  [RendererManager] Error while retrieving renderer description: Read timed out
2018-02-20 18:17:32,221 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.64.4
2018-02-20 18:17:32,222 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [User-Agent: Wget/1.9,Host: 192.168.64.4:8895,Accept: */*,Connection: Keep-Alive]
2018-02-20 18:17:32,223 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.64.4
2018-02-20 18:17:32,226 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2018-02-20 18:17:32,259 DEBUG [WebServer] I/O error: Connection reset


There is nothing blocking the interface, the request (starting at 18:12:43) is received at 18:12:43,445 by Serviio but i takes about 5 minutes to select a profile and send it to the client...
Next

Return to Serviio Support & Help

Who is online

Users browsing this forum: No registered users and 2 guests

cron
Powered by phpBB © 2000, 2002, 2005, 2007 phpBB Group.
Designed by ST Software for PTF.