Unable to find Serviio server
Posted: Sun Dec 30, 2012 10:26 am
I have been trying on and off for a few days to fix this problem - I've tried to go through everything in similar related other threads, but have not found anything in particular that's been helpful to rectify the problem.
I previously had a working serviio server on a linux box here, but recently something has changed that prevents it working.
Previously it was running serviio 0.6.2 - though I have also tried to run the old binaries with its database, and it can no longer be seen either. This suggests to me it's something a little wierder.
The problem I guess happened when I upgraded this system to serviio 1.1 - but even rolling back hasn't helped - and yes, the config files are separate. It doesn't make a lot of sense. The database for this installation is ~170MB, so there's a lot of media to be served.
I have half a dozen DLNA devices on the network, none of them can see serviio running anymore. They vary from being things like WD Live TVs, Sony Bravia TVs and Sony BluRay players that support DLNA, a few iPad apps... nothing sees serviio on this host.
All plugins have been removed from /etc/serviio/plugins, just to be sure.
Aditionally the console works and I can connect and see everything appears to be happy when connecting remotely. It says it's up etc.
Here's as much detail as I can think to give that might help.
The host IP is 192.168.0.145.
Netstat output from the host:
And the full log file:
Ideas? Where else could I dig? Is there anything from any of these outputs that looks out of place?
Edit: I have bolded a line which I suspect may be a major problem. I can't say when this might have started occuring, but to be sure I'm recompiling ffmpeg to see if that helps.
Bit of an update - recompiling fmpeg with rtmp stopped the warning, but didn't solve the problem. I've upped the log level to DEBUG and noticed this when I did a scan from, say, my iPad:
So obviously it's listening and seeing the requests, but not responding with anything viable. Ideas?
Also noticed this:
I previously had a working serviio server on a linux box here, but recently something has changed that prevents it working.
Previously it was running serviio 0.6.2 - though I have also tried to run the old binaries with its database, and it can no longer be seen either. This suggests to me it's something a little wierder.
The problem I guess happened when I upgraded this system to serviio 1.1 - but even rolling back hasn't helped - and yes, the config files are separate. It doesn't make a lot of sense. The database for this installation is ~170MB, so there's a lot of media to be served.
I have half a dozen DLNA devices on the network, none of them can see serviio running anymore. They vary from being things like WD Live TVs, Sony Bravia TVs and Sony BluRay players that support DLNA, a few iPad apps... nothing sees serviio on this host.
All plugins have been removed from /etc/serviio/plugins, just to be sure.
Aditionally the console works and I can connect and see everything appears to be happy when connecting remotely. It says it's up etc.
Here's as much detail as I can think to give that might help.
The host IP is 192.168.0.145.
Netstat output from the host:
root@hpnas:/etc/serviio/bin# ps aux |grep serviio
trowe 27193 0.0 0.0 10876 584 pts/2 S+ 21:11 0:00 tail -f serviio.log
root 27194 0.0 0.0 4236 580 pts/1 S 21:11 0:00 /bin/sh ./serviio.sh
root 27199 30.9 1.5 1022956 126508 pts/1 Sl 21:11 1:01 java -Xmx512M -Xms20M -XX:+UseParNewGC -XX:MinHeapFreeRatio=10 -XX:MaxHeapFreeRatio=20 -Djava.net.preferIPv4Stack=true -Djava.awt.headless=true -Dderby.system.home=/etc/serviio/library -Dserviio.home=/etc/serviio -classpath /etc/serviio/lib/*:/etc/serviio/config org.serviio.MediaServer
root 27487 0.0 0.0 13140 1020 pts/1 S+ 21:15 0:00 grep --color=auto serviio
root@hpnas:/etc/serviio/bin# netstat -a -n -p |grep 27199
tcp 0 0 0.0.0.0:8895 0.0.0.0:* LISTEN 27199/java
tcp 0 0 0.0.0.0:23423 0.0.0.0:* LISTEN 27199/java
tcp 0 0 0.0.0.0:23424 0.0.0.0:* LISTEN 27199/java
tcp 0 0 127.0.1.1:44331 0.0.0.0:* LISTEN 27199/java
udp 0 0 0.0.0.0:1900 0.0.0.0:* 27199/java
unix 2 [ ] STREAM CONNECTED 3091255 27199/java
And the full log file:
2012-12-30 21:11:59,455 INFO [MediaServer] ------------------------------------------------------------------------
2012-12-30 21:11:59,456 INFO [MediaServer] Serviio DLNA media streaming server v 1.1 (rev. 6ba0dd933a8e+)
2012-12-30 21:11:59,456 INFO [MediaServer] Petr Nejedly 2009-2012
2012-12-30 21:11:59,456 INFO [MediaServer] http://www.serviio.org
2012-12-30 21:11:59,456 INFO [MediaServer]
2012-12-30 21:11:59,456 INFO [MediaServer] Java 1.6.0_24-Sun Microsystems Inc.
2012-12-30 21:11:59,456 INFO [MediaServer] OS Linux amd64 2.6.38-8-generic
2012-12-30 21:11:59,457 INFO [MediaServer] File encoding: UTF-8
2012-12-30 21:11:59,457 INFO [MediaServer] Headless mode enabled: true
2012-12-30 21:11:59,457 INFO [MediaServer] User: root
2012-12-30 21:11:59,457 INFO [MediaServer] User home dir: /root
2012-12-30 21:11:59,457 INFO [MediaServer] Temp dir: /tmp
2012-12-30 21:11:59,458 INFO [MediaServer] ------------------------------------------------------------------------
2012-12-30 21:11:59,657 INFO [WebServer] Socket buffer set to 65535 bytes
2012-12-30 21:11:59,660 INFO [RestletServer] Starting Restlet server (/rest) exposed on port 23423
2012-12-30 21:11:59,716 INFO [RestletServer] Starting Restlet server (/cds) exposed on port 23424
2012-12-30 21:11:59,717 INFO [RestletServer] Starting Restlet server (/mediabrowser) exposed on port 23424
2012-12-30 21:11:59,739 INFO [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2012-12-30 21:12:02,084 INFO [Device] Created UPnP Device with UUID: 252f7fa9-e4ad-3ab6-982e-71b1ac922759, bound address: 192.168.0.168
2012-12-30 21:12:02,406 INFO [MediaServer] ------------------------------------------------------------------------
2012-12-30 21:12:02,406 INFO [MediaServer] License: PRO (EVALUATION, id: 11111111-1111-1111-1111-111111111113)
2012-12-30 21:12:02,406 INFO [MediaServer] ------------------------------------------------------------------------
2012-12-30 21:12:03,111 INFO [ProfilesDefinitionParser] Parsing Profiles definition
2012-12-30 21:12:04,213 INFO [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2012-12-30 21:12:04,809 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2012-12-30 21:12:05,169 INFO [ProfilesDefinitionParser] Added profile 'Xbox 360' (id=3)
2012-12-30 21:12:05,690 INFO [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2012-12-30 21:12:06,023 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2012-12-30 21:12:06,267 INFO [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2012-12-30 21:12:07,121 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C/D/E-series)' (id=7)
2012-12-30 21:12:07,172 INFO [ProfilesDefinitionParser] Added profile 'LG TV / player' (id=8)
2012-12-30 21:12:08,341 INFO [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012)' (id=sony2012)
2012-12-30 21:12:09,753 INFO [ProfilesDefinitionParser] Added profile 'Sony BD Player / SMP' (id=10)
2012-12-30 21:12:10,312 INFO [ProfilesDefinitionParser] Added profile 'Toshiba REGZA' (id=13)
2012-12-30 21:12:10,632 INFO [ProfilesDefinitionParser] Added profile 'WDTV Live' (id=14)
2012-12-30 21:12:10,690 INFO [ProfilesDefinitionParser] Added profile 'LaCie LaCinema Media Player' (id=15)
2012-12-30 21:12:10,964 INFO [ProfilesDefinitionParser] Added profile 'Manta WIFI Centre' (id=16)
2012-12-30 21:12:11,060 INFO [ProfilesDefinitionParser] Added profile 'Pure Flow' (id=17)
2012-12-30 21:12:11,693 INFO [ProfilesDefinitionParser] Added profile 'Sharp Aquos' (id=18)
2012-12-30 21:12:11,904 INFO [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011)' (id=sony2011)
2012-12-30 21:12:12,390 INFO [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2010)' (id=sony2010)
2012-12-30 21:12:12,944 INFO [ProfilesDefinitionParser] Added profile 'Philips' (id=20)
2012-12-30 21:12:13,158 INFO [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2009)' (id=sony2009)
2012-12-30 21:12:13,263 INFO [ProfilesDefinitionParser] Added profile 'Denon AVR' (id=22)
2012-12-30 21:12:14,262 INFO [ProfilesDefinitionParser] Added profile 'Panasonic Viera G/GT/DT/UT/VT (2011/2012)' (id=viera2011a)
2012-12-30 21:12:14,410 INFO [ProfilesDefinitionParser] Added profile 'Panasonic Viera E/S/ST/VT (2011)' (id=viera2011)
2012-12-30 21:12:14,543 INFO [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2010)' (id=viera2010)
2012-12-30 21:12:14,740 INFO [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012) - US' (id=sony2012us)
2012-12-30 21:12:14,941 INFO [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011) - US' (id=sony2011us)
2012-12-30 21:12:15,240 INFO [ProfilesDefinitionParser] Added profile 'Sony AVReceiver STR' (id=SonySTR)
2012-12-30 21:12:15,399 INFO [ProfilesDefinitionParser] Added profile 'Sony Audio CMT' (id=SonyCMT)
2012-12-30 21:12:15,463 INFO [ProfilesDefinitionParser] Parsing Profiles definition
2012-12-30 21:12:15,838 INFO [ProfilesDefinitionParser] Added profile 'Flash player' (id=flv_player)
2012-12-30 21:12:16,097 INFO [ProfilesDefinitionParser] Added profile 'ServiiGo' (id=serviigo)
2012-12-30 21:12:16,303 INFO [ProfilesDefinitionParser] Added profile 'Windows Phone 8' (id=wp8)
2012-12-30 21:12:16,344 INFO [PluginCompilerThread] Looking for plugins at /etc/serviio/plugins
2012-12-30 21:12:16,345 INFO [PluginCompilerThread] Started looking for plugins
2012-12-30 21:12:16,348 INFO [UpdateChecker] Checking if a new version is available
2012-12-30 21:12:16,417 WARN [FFMPEGWrapper] FFmpeg is not compiled with librtmp support, RTMP streaming will not work.
2012-12-30 21:12:16,442 ERROR [ProcessExecutor] Fatal error in process starting: Cannot run program "dcraw": java.io.IOException: error=2, No such file or directory
2012-12-30 21:12:16,442 WARN [MediaServer] DCRAW not found. Support for raw image files will be missing.
2012-12-30 21:12:16,455 INFO [AbstractTranscodingDeliveryEngine] Cleaning transcode engine and its data
2012-12-30 21:12:16,490 INFO [WebServer] WebServer starting on port 8895
2012-12-30 21:12:16,508 INFO [EventDispatcher] Starting EventDispatcher
2012-12-30 21:12:16,508 INFO [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2012-12-30 21:12:16,512 INFO [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface Unknown and address 192.168.0.168, timeout = 0
2012-12-30 21:12:16,513 INFO [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2012-12-30 21:12:16,521 INFO [RendererExpirationChecker] Starting RendererExpirationChecker
2012-12-30 21:12:16,523 INFO [RendererSearchSender] Searching for Renderer devices, will multicast on 1 NICs
2012-12-30 21:12:42,571 INFO [FeedUpdaterThread] Started looking for information about online resources
2012-12-30 21:12:42,574 INFO [LibraryAdditionsCheckerThread] Started looking for newly added files
2012-12-30 21:12:42,610 INFO [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files
2012-12-30 21:12:42,611 INFO [PlaylistMaintainerThread] Started looking playlist changes
Ideas? Where else could I dig? Is there anything from any of these outputs that looks out of place?
Edit: I have bolded a line which I suspect may be a major problem. I can't say when this might have started occuring, but to be sure I'm recompiling ffmpeg to see if that helps.
Bit of an update - recompiling fmpeg with rtmp stopped the warning, but didn't solve the problem. I've upped the log level to DEBUG and noticed this when I did a scan from, say, my iPad:
2012-12-30 23:17:37,680 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.160
2012-12-30 23:17:37,682 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.160:52029
2012-12-30 23:17:37,767 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.160:52029
So obviously it's listening and seeing the requests, but not responding with anything viable. Ideas?
Also noticed this:
2012-12-30 23:18:16,980 DEBUG [DiscoveryAdvertisementNotifier] Found 1 network interfaces to advertise on
2012-12-30 23:18:16,981 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (eth0) and address 192.168.0.145, timeout = 0
2012-12-30 23:18:16,982 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 252f7fa9-e4ad-3ab6-982e-71b1ac922759
2012-12-30 23:18:18,789 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:04:03 (advertisement duration is 1800 sec.)