Status stuck on: SEARCHING FOR RENDERERS

For help and support with PS3 Media Server in general
Forum rules
Please make sure you follow the Problem Reporting Guidelines before posting if you want a reply.

Status stuck on: SEARCHING FOR RENDERERS

Postby cajmeister » Sun Jun 30, 2013 8:54 am

I've updated java to the latest release and it seems PS3MS stopped working.
I updated and changed the snapshot to v1.90.0 but it still isn't working.
Included is the following debug log.
Can anyone help out please??? Need to catch up on Walking Dead!!! :)


Code: Select all
INFO  2013-06-30 17:43:43.575 [main] Starting PS3 Media Server 1.90.0-SNAPSHOT
INFO  2013-06-30 17:43:43.576 [main] by shagrath / 2008-2013
INFO  2013-06-30 17:43:43.576 [main] http://ps3mediaserver.org
INFO  2013-06-30 17:43:43.576 [main] https://github.com/ps3mediaserver/ps3mediaserver
INFO  2013-06-30 17:43:43.576 [main]
INFO  2013-06-30 17:43:43.576 [main] Build: c9579a2d3 (2013-06-29)
INFO  2013-06-30 17:43:43.577 [main] Java: Java HotSpot(TM) Client VM 1.7.0_25 by Oracle Corporation
INFO  2013-06-30 17:43:43.577 [main] OS: Windows 7 x86 6.1
INFO  2013-06-30 17:43:43.577 [main] Encoding: UTF-8
INFO  2013-06-30 17:43:43.579 [main] Memory: 742 MB
INFO  2013-06-30 17:43:43.579 [main]
INFO  2013-06-30 17:43:43.579 [main] Working directory: C:\Program Files (x86)\PS3 Media Server
INFO  2013-06-30 17:43:43.584 [main] Temp directory: C:\Users\David\AppData\Local\Temp\ps3mediaserver
INFO  2013-06-30 17:43:43.585 [main] Logging config file: C:\Program Files (x86)\PS3 Media Server\logback.xml
INFO  2013-06-30 17:43:43.585 [main] debug.log: C:\ProgramData\PMS\debug.log
INFO  2013-06-30 17:43:43.585 [main]
INFO  2013-06-30 17:43:43.585 [main] Profile directory: C:\ProgramData\PMS
INFO  2013-06-30 17:43:43.586 [main] Profile directory permissions: rw
INFO  2013-06-30 17:43:43.587 [main] Profile path: C:\ProgramData\PMS\PMS.conf
INFO  2013-06-30 17:43:43.587 [main] Profile permissions: rw
INFO  2013-06-30 17:43:43.587 [main] Profile name: BlackStar
INFO  2013-06-30 17:43:43.587 [main]
INFO  2013-06-30 17:43:43.588 [main] Web conf path: C:\ProgramData\PMS\WEB.conf
INFO  2013-06-30 17:43:43.588 [main] Web conf permissions: file not found
INFO  2013-06-30 17:43:43.588 [main]
DEBUG 2013-06-30 17:43:43.650 [pool-2-thread-1] Reading auto_update: default: false, current: true
DEBUG 2013-06-30 17:43:43.829 [main] Reading hostname: default: null, current: "192.168.1.10"
DEBUG 2013-06-30 17:43:43.923 [main] Reading folders: default: "", current: "C:\Users\David\Videos,C:\Users\David\Music\iTunes\iTunes Media"
INFO  2013-06-30 17:43:43.923 [main] Checking shared folder: C:\Users\David\Videos
INFO  2013-06-30 17:43:43.924 [main] Checking shared folder: C:\Users\David\Music\iTunes\iTunes Media
INFO  2013-06-30 17:43:44.244 [main] Loading MediaInfo library
INFO  2013-06-30 17:43:44.520 [main] Loaded MediaInfoLib - v0.7.62
INFO  2013-06-30 17:43:44.524 [main] Loading renderer configurations from C:\Program Files (x86)\PS3 Media Server\renderers
INFO  2013-06-30 17:43:44.524 [main] Loading configuration file: AirPlayer.conf
INFO  2013-06-30 17:43:44.527 [main] Loading configuration file: Android.conf
INFO  2013-06-30 17:43:44.529 [main] Loading configuration file: Bravia4500.conf
INFO  2013-06-30 17:43:44.531 [main] Loading configuration file: Bravia5500.conf
INFO  2013-06-30 17:43:44.533 [main] Loading configuration file: BraviaEX.conf
INFO  2013-06-30 17:43:44.535 [main] Loading configuration file: BraviaHX.conf
INFO  2013-06-30 17:43:44.537 [main] Loading configuration file: DirecTVHR.conf
INFO  2013-06-30 17:43:44.539 [main] Loading configuration file: DLinkDSM510.conf
INFO  2013-06-30 17:43:44.541 [main] Loading configuration file: FreeboxHD.conf
INFO  2013-06-30 17:43:44.542 [main] Loading configuration file: FreecomMusicPal.conf
INFO  2013-06-30 17:43:44.543 [main] Loading configuration file: iPad-iPhone.conf
INFO  2013-06-30 17:43:44.544 [main] Loading configuration file: Kuro.conf
INFO  2013-06-30 17:43:44.546 [main] Loading configuration file: LGST600.conf
INFO  2013-06-30 17:43:44.548 [main] Loading configuration file: N900.conf
INFO  2013-06-30 17:43:44.549 [main] Loading configuration file: OPPOBDP83.conf
INFO  2013-06-30 17:43:44.551 [main] Loading configuration file: OPPOBDP93.conf
INFO  2013-06-30 17:43:44.552 [main] Loading configuration file: Panasonic.conf
INFO  2013-06-30 17:43:44.553 [main] Loading configuration file: Philips.conf
INFO  2013-06-30 17:43:44.555 [main] Loading configuration file: PhilipsPFL.conf
INFO  2013-06-30 17:43:44.557 [main] Loading configuration file: PopcornHour.conf
INFO  2013-06-30 17:43:44.559 [main] Loading configuration file: PS3.conf
INFO  2013-06-30 17:43:44.561 [main] Loading configuration file: Realtek.conf
INFO  2013-06-30 17:43:44.562 [main] Loading configuration file: Samsung-SMT-G7400.conf
INFO  2013-06-30 17:43:44.564 [main] Loading configuration file: SamsungAllShare.conf
INFO  2013-06-30 17:43:44.565 [main] Loading configuration file: SamsungWiseLink.conf
INFO  2013-06-30 17:43:44.566 [main] Loading configuration file: Showtime3.conf
INFO  2013-06-30 17:43:44.567 [main] Loading configuration file: SMP-N100.conf
INFO  2013-06-30 17:43:44.569 [main] Loading configuration file: SonyBluray.conf
INFO  2013-06-30 17:43:44.570 [main] Loading configuration file: Streamium.conf
INFO  2013-06-30 17:43:44.571 [main] Loading configuration file: WDTVLive.conf
INFO  2013-06-30 17:43:44.572 [main] Loading configuration file: WMP.conf
INFO  2013-06-30 17:43:44.573 [main] Loading configuration file: XBMC.conf
INFO  2013-06-30 17:43:44.574 [main] Loading configuration file: XBOX360.conf
INFO  2013-06-30 17:43:44.575 [main] Loading configuration file: YamahaRXA1010.conf
INFO  2013-06-30 17:43:44.576 [main] Loading configuration file: YamahaRXV671.conf
INFO  2013-06-30 17:43:44.577 [main] Checking MPlayer font cache. It can take a minute or so.
DEBUG 2013-06-30 17:43:44.577 [main] launching: win32/mplayer.exe
DEBUG 2013-06-30 17:43:44.657 [main] launching: win32/mplayer.exe
INFO  2013-06-30 17:43:44.743 [main] Done!
INFO  2013-06-30 17:43:44.744 [main] Found VLC version 2.0.6 at: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
INFO  2013-06-30 17:43:44.748 [main] Searching for plugins in C:\Program Files (x86)\PS3 Media Server\plugins
INFO  2013-06-30 17:43:44.750 [main] No plugins found
DEBUG 2013-06-30 17:43:44.971 [main] FFmpeg supported protocols: [applehttp, cache, concat, crypto, file, gopher, hls, http, httpproxy, mmsh, mmst, pipe, rtp, tcp, udp, rtmp, rtmpe, rtmps, rtmpt, rtmpte, mms]
INFO  2013-06-30 17:43:44.974 [main] Transcoder profile AviSynth/FFmpeg will not be used because AviSynth was not found
INFO  2013-06-30 17:43:44.975 [main] Registering transcoding engine: FFmpeg Audio
INFO  2013-06-30 17:43:44.979 [main] Registering transcoding engine: MEncoder Video
INFO  2013-06-30 17:43:44.980 [main] Transcoder profile AviSynth/MEncoder will not be used because AviSynth was not found
INFO  2013-06-30 17:43:44.981 [main] Registering transcoding engine: FFmpeg
DEBUG 2013-06-30 17:43:44.982 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
DEBUG 2013-06-30 17:43:44.982 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
DEBUG 2013-06-30 17:43:44.982 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
INFO  2013-06-30 17:43:44.982 [main] Registering transcoding engine: VLC Video
INFO  2013-06-30 17:43:44.983 [main] Registering transcoding engine: FFmpeg Web Video
INFO  2013-06-30 17:43:44.984 [main] Registering transcoding engine: FFmpeg Web Audio
INFO  2013-06-30 17:43:44.984 [main] Registering transcoding engine: MEncoder Web Video
DEBUG 2013-06-30 17:43:44.985 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
DEBUG 2013-06-30 17:43:44.985 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
DEBUG 2013-06-30 17:43:44.985 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
INFO  2013-06-30 17:43:44.985 [main] Registering transcoding engine: VLC Web Video
INFO  2013-06-30 17:43:44.987 [main] Registering transcoding engine: tsMuxeR
INFO  2013-06-30 17:43:44.988 [main] Registering transcoding engine: Audio High Fidelity
DEBUG 2013-06-30 17:43:44.988 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
DEBUG 2013-06-30 17:43:44.988 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
DEBUG 2013-06-30 17:43:44.988 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
INFO  2013-06-30 17:43:44.988 [main] Registering transcoding engine: VLC Web Audio (Legacy)
DEBUG 2013-06-30 17:43:44.989 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
DEBUG 2013-06-30 17:43:44.989 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
DEBUG 2013-06-30 17:43:44.989 [main] Found VLC version 2.0.6 in Windows Registry: C:\Program Files (x86)\VideoLAN\VLC\vlc.exe
INFO  2013-06-30 17:43:44.989 [main] Registering transcoding engine: VLC Web Video (Legacy)
INFO  2013-06-30 17:43:44.989 [main] Registering transcoding engine: FFmpeg DVR-MS Remux
INFO  2013-06-30 17:43:44.990 [main] Registering transcoding engine: dcraw Thumbnailer
DEBUG 2013-06-30 17:43:44.990 [main] Reading engines: default: "mencoder,avsmencoder,tsmuxer,ffmpegvideo,vlctranscoder,ffmpegaudio,tsmuxeraudio,ffmpegwebvideo,vlcwebvideo,vlcvideo,mencoderwebvideo,ffmpegwebaudio,vlcaudio,ffmpegdvrmsremux,rawthumbs", current: "ffmpegvideo,mencoder,tsmuxer,vlctranscoder,ffmpegaudio,mplayeraudio,tsmuxeraudio,ffmpegwebvideo,vlcwebvideo,vlcvideo,mencoderwebvideo,mplayervideodump,mplayerwebaudio,vlcaudio,ffmpegdvrmsremux,rawthumbs"
DEBUG 2013-06-30 17:43:45.024 [main] Reading alternativeffmpegpath: default: null, current: "C:\Program Files (x86)\PS3 Media Server\win32\dvrms\ffmpeg_MPGMUX.exe"
INFO  2013-06-30 17:43:45.053 [main] Using forced address 192.168.1.10
INFO  2013-06-30 17:43:45.053 [main] Created socket: /192.168.1.10:5001
ERROR 2013-06-30 17:43:45.095 [main] A serious error occurred during PMS init
org.jboss.netty.channel.ChannelException: Failed to bind to: /192.168.1.10:5001
   at org.jboss.netty.bootstrap.ServerBootstrap.bind(ServerBootstrap.java:303) ~[pms.jar:1.90.0-SNAPSHOT]
   at net.pms.network.HTTPServer.start(HTTPServer.java:125) ~[pms.jar:1.90.0-SNAPSHOT]
   at net.pms.PMS.init(PMS.java:548) [pms.jar:1.90.0-SNAPSHOT]
   at net.pms.PMS.createInstance(PMS.java:872) [pms.jar:1.90.0-SNAPSHOT]
   at net.pms.PMS.main(PMS.java:949) [pms.jar:1.90.0-SNAPSHOT]
Caused by: java.net.BindException: Cannot assign requested address: bind
   at sun.nio.ch.Net.bind0(Native Method) ~[na:1.7.0_25]
   at sun.nio.ch.Net.bind(Unknown Source) ~[na:1.7.0_25]
   at sun.nio.ch.Net.bind(Unknown Source) ~[na:1.7.0_25]
   at sun.nio.ch.ServerSocketChannelImpl.bind(Unknown Source) ~[na:1.7.0_25]
   at sun.nio.ch.ServerSocketAdaptor.bind(Unknown Source) ~[na:1.7.0_25]
   at org.jboss.netty.channel.socket.nio.NioServerSocketPipelineSink.bind(NioServerSocketPipelineSink.java:148) ~[pms.jar:1.90.0-SNAPSHOT]
   at org.jboss.netty.channel.socket.nio.NioServerSocketPipelineSink.handleServerSocket(NioServerSocketPipelineSink.java:100) ~[pms.jar:1.90.0-SNAPSHOT]
   at org.jboss.netty.channel.socket.nio.NioServerSocketPipelineSink.eventSunk(NioServerSocketPipelineSink.java:74) ~[pms.jar:1.90.0-SNAPSHOT]
   at org.jboss.netty.channel.Channels.bind(Channels.java:468) ~[pms.jar:1.90.0-SNAPSHOT]
   at org.jboss.netty.channel.AbstractChannel.bind(AbstractChannel.java:192) ~[pms.jar:1.90.0-SNAPSHOT]
   at org.jboss.netty.bootstrap.ServerBootstrap$Binder.channelOpen(ServerBootstrap.java:348) ~[pms.jar:1.90.0-SNAPSHOT]
   at org.jboss.netty.channel.Channels.fireChannelOpen(Channels.java:176) ~[pms.jar:1.90.0-SNAPSHOT]
   at org.jboss.netty.channel.socket.nio.NioServerSocketChannel.<init>(NioServerSocketChannel.java:85) ~[pms.jar:1.90.0-SNAPSHOT]
   at org.jboss.netty.channel.socket.nio.NioServerSocketChannelFactory.newChannel(NioServerSocketChannelFactory.java:142) ~[pms.jar:1.90.0-SNAPSHOT]
   at org.jboss.netty.channel.socket.nio.NioServerSocketChannelFactory.newChannel(NioServerSocketChannelFactory.java:90) ~[pms.jar:1.90.0-SNAPSHOT]
   at org.jboss.netty.bootstrap.ServerBootstrap.bind(ServerBootstrap.java:282) ~[pms.jar:1.90.0-SNAPSHOT]
   ... 4 common frames omitted
DEBUG 2013-06-30 17:43:47.241 [background-task-0] singleton task restart started
DEBUG 2013-06-30 17:43:47.242 [background-task-0-restart(0)] task started
INFO  2013-06-30 17:43:47.243 [background-task-0-restart(0)] Sending BYEBYE...
DEBUG 2013-06-30 17:43:47.312 [background-task-0-restart(0)] Error sending BYEBYE message
java.io.IOException: No usable network interface found for UPnP multicast
   at net.pms.network.UPNPHelper.getNewMulticastSocket(UPNPHelper.java:212) ~[pms.jar:1.90.0-SNAPSHOT]
   at net.pms.network.UPNPHelper.sendByeBye(UPNPHelper.java:249) ~[pms.jar:1.90.0-SNAPSHOT]
   at net.pms.PMS$5.run(PMS.java:733) ~[pms.jar:1.90.0-SNAPSHOT]
   at net.pms.util.TaskRunner$2.run(TaskRunner.java:105) ~[pms.jar:1.90.0-SNAPSHOT]
   at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) ~[na:1.7.0_25]
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) ~[na:1.7.0_25]
   at java.lang.Thread.run(Unknown Source) ~[na:1.7.0_25]
INFO  2013-06-30 17:43:47.312 [background-task-0-restart(0)] Stopping server on host 192.168.1.10 and port 5001...
INFO  2013-06-30 17:43:48.312 [background-task-0-restart(0)] Using forced address 192.168.1.10
INFO  2013-06-30 17:43:48.312 [background-task-0-restart(0)] Created socket: /192.168.1.10:5001
INFO  2013-06-30 17:43:48.315 [background-task-0] Exception in thread "background-task-0" org.jboss.netty.channel.ChannelException: Failed to bind to: /192.168.1.10:5001
INFO  2013-06-30 17:43:48.316 [background-task-0]    at org.jboss.netty.bootstrap.ServerBootstrap.bind(ServerBootstrap.java:303)
INFO  2013-06-30 17:43:48.317 [background-task-0]    at net.pms.network.HTTPServer.start(HTTPServer.java:125)
INFO  2013-06-30 17:43:48.317 [background-task-0]    at net.pms.PMS$5.run(PMS.java:743)
INFO  2013-06-30 17:43:48.317 [background-task-0]    at net.pms.util.TaskRunner$2.run(TaskRunner.java:105)
INFO  2013-06-30 17:43:48.318 [background-task-0]    at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
INFO  2013-06-30 17:43:48.318 [background-task-0]    at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
INFO  2013-06-30 17:43:48.318 [background-task-0]    at java.lang.Thread.run(Unknown Source)
INFO  2013-06-30 17:43:48.319 [background-task-0] Caused by: java.net.BindException: Cannot assign requested address: bind
INFO  2013-06-30 17:43:48.319 [background-task-0]    at sun.nio.ch.Net.bind0(Native Method)
INFO  2013-06-30 17:43:48.319 [background-task-0]    at sun.nio.ch.Net.bind(Unknown Source)
INFO  2013-06-30 17:43:48.320 [background-task-0]    at sun.nio.ch.Net.bind(Unknown Source)
INFO  2013-06-30 17:43:48.320 [background-task-0]    at sun.nio.ch.ServerSocketChannelImpl.bind(Unknown Source)
INFO  2013-06-30 17:43:48.321 [background-task-0]    at sun.nio.ch.ServerSocketAdaptor.bind(Unknown Source)
INFO  2013-06-30 17:43:48.321 [background-task-0]    at org.jboss.netty.channel.socket.nio.NioServerSocketPipelineSink.bind(NioServerSocketPipelineSink.java:148)
INFO  2013-06-30 17:43:48.321 [background-task-0]    at org.jboss.netty.channel.socket.nio.NioServerSocketPipelineSink.handleServerSocket(NioServerSocketPipelineSink.java:100)
INFO  2013-06-30 17:43:48.322 [background-task-0]    at org.jboss.netty.channel.socket.nio.NioServerSocketPipelineSink.eventSunk(NioServerSocketPipelineSink.java:74)
INFO  2013-06-30 17:43:48.322 [background-task-0]    at org.jboss.netty.channel.Channels.bind(Channels.java:468)
INFO  2013-06-30 17:43:48.323 [background-task-0]    at org.jboss.netty.channel.AbstractChannel.bind(AbstractChannel.java:192)
INFO  2013-06-30 17:43:48.323 [background-task-0]    at org.jboss.netty.bootstrap.ServerBootstrap$Binder.channelOpen(ServerBootstrap.java:348)
INFO  2013-06-30 17:43:48.323 [background-task-0]    at org.jboss.netty.channel.Channels.fireChannelOpen(Channels.java:176)
INFO  2013-06-30 17:43:48.324 [background-task-0]    at org.jboss.netty.channel.socket.nio.NioServerSocketChannel.<init>(NioServerSocketChannel.java:85)
INFO  2013-06-30 17:43:48.324 [background-task-0]    at org.jboss.netty.channel.socket.nio.NioServerSocketChannelFactory.newChannel(NioServerSocketChannelFactory.java:142)
INFO  2013-06-30 17:43:48.324 [background-task-0]    at org.jboss.netty.channel.socket.nio.NioServerSocketChannelFactory.newChannel(NioServerSocketChannelFactory.java:90)
INFO  2013-06-30 17:43:48.325 [background-task-0]    at org.jboss.netty.bootstrap.ServerBootstrap.bind(ServerBootstrap.java:282)
INFO  2013-06-30 17:43:48.325 [background-task-0]    ... 6 more
Last edited by Raptor399 on Sun Jun 30, 2013 9:22 am, edited 1 time in total.
Reason: Edited for code fragments
cajmeister
 
Posts: 1
Joined: Sun Jun 30, 2013 8:45 am

Re: Status stuck on: SEARCHING FOR RENDERERS

Postby Raptor399 » Sun Jun 30, 2013 9:25 am

Code: Select all
ERROR 2013-06-30 17:43:45.095 [main] A serious error occurred during PMS init
org.jboss.netty.channel.ChannelException: Failed to bind to: /192.168.1.10:5001
   at org.jboss.netty.bootstrap.ServerBootstrap.bind(ServerBootstrap.java:303) ~[pms.jar:1.90.0-SNAPSHOT]
   at net.pms.network.HTTPServer.start(HTTPServer.java:125) ~[pms.jar:1.90.0-SNAPSHOT]
   at net.pms.PMS.init(PMS.java:548) [pms.jar:1.90.0-SNAPSHOT]
   at net.pms.PMS.createInstance(PMS.java:872) [pms.jar:1.90.0-SNAPSHOT]
   at net.pms.PMS.main(PMS.java:949) [pms.jar:1.90.0-SNAPSHOT]
Caused by: java.net.BindException: Cannot assign requested address: bind


Looks like that port is already taken, check the FAQ.

FAQ: PMS cannot bind to the IP-address or port on the network interface
Raptor399
Project Member
 
Posts: 1916
Joined: Thu Mar 10, 2011 12:06 am


Return to General Help and Support

Who is online

Users browsing this forum: No registered users and 7 guests