Latest Windows/Linux beta build discussion

[wjharing]

[main] TRACE 10:00:52.389 Sending ALIVE...
[main] TRACE 10:03:56.468 Sending ALIVE...
[main] TRACE 10:07:00.057 Sending ALIVE...

the rev308 build send an ALIVE after 10 and 30 seconds, then every 180s...

with the latest build (1.10.5) and changing the ALIVE as above, it only helps if I switch on the PS3 at about the same time when starting PS3MS (two rooms far apart). This does mean when restarting the PS3, it no longer finds the Media Server again unless I restart the PS3MS as well.

Is there a particular reason why the ALIVE is not happening every 10 seconds, as it did in v1.03 / v1.04? I would assume bandwidth overhead is little and as such I would hope for this timer to be changed back as it was before (a lot better). PLEASE

[Jagged]

Is there a particular reason why the ALIVE is not happening every 10 seconds, as it did in v1.03 / v1.04? I would assume bandwidth overhead is little and as such I would hope for this timer to be changed back as it was before (a lot better). PLEASE

There is no reason to have an alive sent every 10 seconds. If you check your debug.log you should see M-SEARCH packets coming from the PS3. If not, then that is your problem, NOT the ALIVE interval in PMS. You may want to disable your firewalls and such to help troubleshoot the problem you are having.

[wjharing]

Is there a particular reason why the ALIVE is not happening every 10 seconds, as it did in v1.03 / v1.04? I would assume bandwidth overhead is little and as such I would hope for this timer to be changed back as it was before (a lot better). PLEASE

There is no reason to have an alive sent every 10 seconds. If you check your debug.log you should see M-SEARCH packets coming from the PS3. If not, then that is your problem, NOT the ALIVE interval in PMS. You may want to disable your firewalls and such to help troubleshoot the problem you are having.

The situation is that my PS3 always finds Media Server v1.03/v1.04 the moment the PS3 is switched on (instantly) and while it takes up to several minutes with the newer versions, which to me indicates clearly that a change in the program must have made the impact and not the PS3 itself.

As for M-SEARCH packages, I already see them in the debug file even with the PS3 switched off.

[Thread-8] DEBUG 22:06:39.723 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:06:42.723 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:06:46.723 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-7] TRACE 22:06:49.193 Sending ALIVE...
[Thread-7] DEBUG 22:06:49.193 Setting multicast network interface: name:eth3 (Intel(R) 82567LF-2 Gigabit Network Connection) index: 12 addresses:
/192.168.1.64;

[Thread-7] DEBUG 22:06:49.221 Sending message from multicast socket on network interface: name:eth3 (Intel(R) 82567LF-2 Gigabit Network Connection) index: 12 addresses:
/192.168.1.64;

[Thread-7] DEBUG 22:06:49.221 Multicast socket is on interface: /192.168.1.64
[Thread-7] DEBUG 22:06:49.221 Socket Timeout: 0
[Thread-7] DEBUG 22:06:49.221 Socket TTL: 32
[Thread-8] DEBUG 22:06:49.223 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:49.723 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:06:50.090 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:50.689 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:51.155 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:51.421 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:51.936 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:51.968 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:52.724 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:06:52.730 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:53.047 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:53.249 Receiving a NOTIFY from [192.168.1.64:60583]

So now with the PS3 switched on in v1.10.5, I still only see this in the log file:

[Thread-8] DEBUG 22:11:43.699 Receiving a NOTIFY from [192.168.1.64:1900]
[Thread-8] DEBUG 22:11:43.702 Receiving a NOTIFY from [192.168.1.64:1900]
[Thread-8] DEBUG 22:11:43.702 Receiving a NOTIFY from [192.168.1.64:1900]
[Thread-8] DEBUG 22:11:46.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:11:49.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:11:52.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:11:56.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:11:59.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:12:02.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:12:06.732 Receiving a M-SEARCH from [192.168.1.64:59603]

After 2 minutes, the PS3 finally discovered the Media Server. (instead of instantly with v1.03/v1.04)

Unless there has been other changes causing a delay in the PS3 to find the Media Server, it was my understanding that delaying the NOTIFY messages was the cause for the PS3 to find the Media Server after delayed time.

debug once PS3 finds Media Server

[Thread-8] DEBUG 22:13:19.733 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:13:22.733 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-7] TRACE 22:13:24.288 Sending ALIVE...
[Thread-7] DEBUG 22:13:24.288 Setting multicast network interface: name:eth3 (Intel(R) 82567LF-2 Gigabit Network Connection) index: 12 addresses:
/192.168.1.64;

[Thread-7] DEBUG 22:13:24.318 Sending message from multicast socket on network interface: name:eth3 (Intel(R) 82567LF-2 Gigabit Network Connection) index: 12 addresses:
/192.168.1.64;

[Thread-7] DEBUG 22:13:24.318 Multicast socket is on interface: /192.168.1.64
[Thread-7] DEBUG 22:13:24.318 Socket Timeout: 0
[Thread-7] DEBUG 22:13:24.318 Socket TTL: 32
[Thread-8] DEBUG 22:13:24.318 Receiving a NOTIFY from [192.168.1.64:52926]
[Thread-8] DEBUG 22:13:24.810 Receiving a NOTIFY from [192.168.1.64:52926]
[Thread-8] DEBUG 22:13:25.266 Receiving a NOTIFY from [192.168.1.64:52926]
[Thread-8] DEBUG 22:13:25.894 Receiving a NOTIFY from [192.168.1.64:52926]
[Thread-8] DEBUG 22:13:26.698 Receiving a NOTIFY from [192.168.1.64:52926]
[Thread-8] DEBUG 22:13:26.733 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:13:26.987 Receiving a NOTIFY from [192.168.1.64:52926]
[Thread-8] DEBUG 22:13:27.864 Receiving a NOTIFY from [192.168.1.64:52926]
[Thread-4] TRACE 22:13:27.928 Receiving a request from: 192.168.1.65
[Thread-9] DEBUG 22:13:27.955 Opened handler on socket Socket[addr=/192.168.1.65,port=65202,localport=5001]
[Thread-9] DEBUG 22:13:27.955 Received on socket: GET /description/fetch HTTP/1.1
[Thread-9] DEBUG 22:13:27.959 Received on socket: Host: 192.168.1.64:5001
[Thread-9] DEBUG 22:13:27.959 Received on socket: Date: Fri, 13 Mar 2009 22:13:54 GMT
[Thread-9] DEBUG 22:13:27.959 Received on socket: User-Agent: UPnP/1.0
[Thread-9] DEBUG 22:13:27.959 Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment Inc."; mn="PLAYSTATION 3"; mv="1.0";
[Thread-9] INFO 22:13:27.971 HTTP: description/fetch / 0-0
[Thread-9] DEBUG 22:13:27.972 Wrote on socket: HTTP/1.1 200 OK
[Thread-9] DEBUG 22:13:27.972 Wrote on socket: Content-Type: text/xml
[Thread-9] DEBUG 22:13:27.972 Wrote on socket: Cache-Control: no-cache
[Thread-9] DEBUG 22:13:27.972 Wrote on socket: Expires: 0
[Thread-9] DEBUG 22:13:27.972 Wrote on socket: Accept-Ranges: bytes
[Thread-9] DEBUG 22:13:27.972 Wrote on socket: Connection: keep-alive
[Thread-9] DEBUG 22:13:27.984 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-9] DEBUG 22:13:27.984 Available Content-Length: 1779
[Thread-9] DEBUG 22:13:27.985 Wrote on socket: Content-Length: 1779
[Thread-9] DEBUG 22:13:27.985 Wrote on socket:
[Thread-9] DEBUG 22:13:27.985 Sending stream: 1779 bytes of description/fetch
[Thread-9] DEBUG 22:13:27.985 Close Connection
[Thread-10] DEBUG 22:13:28.021 Opened handler on socket Socket[addr=/192.168.1.65,port=65201,localport=5001]
[Thread-10] DEBUG 22:13:28.021 Received on socket: GET /UPnP_AV_ContentDirectory_1.0.xml HTTP/1.1
[Thread-10] DEBUG 22:13:28.021 Received on socket: Host: 192.168.1.64:5001
[Thread-10] DEBUG 22:13:28.021 Received on socket: Date: Fri, 13 Mar 2009 22:13:54 GMT
[Thread-10] DEBUG 22:13:28.021 Received on socket: User-Agent: UPnP/1.0
[Thread-10] DEBUG 22:13:28.021 Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment Inc."; mn="PLAYSTATION 3"; mv="1.0";
[Thread-10] INFO 22:13:28.085 HTTP: UPnP_AV_ContentDirectory_1.0.xml / 0-0
[Thread-10] DEBUG 22:13:28.085 Wrote on socket: HTTP/1.1 200 OK
[Thread-10] DEBUG 22:13:28.085 Wrote on socket: Content-Type: text/xml
[Thread-10] DEBUG 22:13:28.085 Wrote on socket: Cache-Control: no-cache
[Thread-10] DEBUG 22:13:28.086 Wrote on socket: Expires: 0
[Thread-10] DEBUG 22:13:28.086 Wrote on socket: Accept-Ranges: bytes
[Thread-10] DEBUG 22:13:28.086 Wrote on socket: Connection: keep-alive
[Thread-10] DEBUG 22:13:28.086 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-10] DEBUG 22:13:28.086 Available Content-Length: 6132
[Thread-10] DEBUG 22:13:28.086 Wrote on socket: Content-Length: 6132
[Thread-10] DEBUG 22:13:28.086 Wrote on socket:
[Thread-10] DEBUG 22:13:28.087 Sending stream: 6132 bytes of UPnP_AV_ContentDirectory_1.0.xml
[Thread-10] DEBUG 22:13:28.087 Close Connection
[Thread-11] DEBUG 22:13:28.098 Opened handler on socket Socket[addr=/192.168.1.65,port=65200,localport=5001]
[Thread-11] DEBUG 22:13:28.098 Received on socket: POST /upnp/control/content_directory HTTP/1.1
[Thread-11] DEBUG 22:13:28.098 Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment Inc."; mn="PLAYSTATION 3"; mv="1.0";
[Thread-11] DEBUG 22:13:28.104 Received on socket: Host: 192.168.1.64:5001
[Thread-11] DEBUG 22:13:28.104 Received on socket: User-Agent: UPnP/1.0 DLNADOC/1.50
[Thread-11] DEBUG 22:13:28.104 Received on socket: Content-Length: 276
[Thread-11] DEBUG 22:13:28.104 Received on socket: Content-Type: text/xml; charset="utf-8"
[Thread-11] DEBUG 22:13:28.104 Received on socket: SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSystemUpdateID"
[Thread-11] INFO 22:13:28.307 HTTP: upnp/control/content_directory / 0-0
[Thread-11] DEBUG 22:13:28.307 Wrote on socket: HTTP/1.1 200 OK
[Thread-11] DEBUG 22:13:28.307 Wrote on socket: CONTENT-TYPE: text/xml; charset="utf-8"
[Thread-11] DEBUG 22:13:28.307 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-11] DEBUG 22:13:28.308 Wrote on socket: Content-Length: 335
[Thread-11] DEBUG 22:13:28.308 Wrote on socket:
[Thread-11] DEBUG 22:13:28.308 Close Connection
[Thread-12] DEBUG 22:13:28.310 Opened handler on socket Socket[addr=/192.168.1.65,port=65199,localport=5001]
[Thread-12] DEBUG 22:13:28.310 Received on socket: POST /upnp/control/content_directory HTTP/1.1
[Thread-12] DEBUG 22:13:28.311 Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment Inc."; mn="PLAYSTATION 3"; mv="1.0";
[Thread-12] DEBUG 22:13:28.318 Received on socket: Host: 192.168.1.64:5001
[Thread-12] DEBUG 22:13:28.319 Received on socket: User-Agent: UPnP/1.0 DLNADOC/1.50
[Thread-12] DEBUG 22:13:28.319 Received on socket: Content-Length: 901
[Thread-12] DEBUG 22:13:28.319 Received on socket: Content-Type: text/xml; charset="utf-8"
[Thread-12] DEBUG 22:13:28.319 Received on socket: SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
[Thread-12] INFO 22:13:28.511 HTTP: upnp/control/content_directory / 0-0
[Thread-12] DEBUG 22:13:28.511 Wrote on socket: HTTP/1.1 200 OK
[Thread-12] DEBUG 22:13:28.511 Wrote on socket: CONTENT-TYPE: text/xml; charset="utf-8"
[Thread-12] DEBUG 22:13:28.512 Searching for objectId: 0 with children option: false
[Thread-12] DEBUG 22:13:28.514 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-12] DEBUG 22:13:28.514 Wrote on socket: Content-Length: 791
[Thread-12] DEBUG 22:13:28.515 Wrote on socket:
[Thread-12] DEBUG 22:13:28.515 Close Connection
[Thread-8] DEBUG 22:13:28.575 Receiving a NOTIFY from [192.168.1.64:52926]
[Thread-8] DEBUG 22:13:29.291 Receiving a NOTIFY from [192.168.1.64:52926]
[Thread-13] DEBUG 22:13:29.412 Opened handler on socket Socket[addr=/192.168.1.65,port=65198,localport=5001]
[Thread-13] DEBUG 22:13:29.412 Received on socket: HEAD /images/Play1Hot_256.png HTTP/1.0
[Thread-13] DEBUG 22:13:29.412 Received on socket: Host: 192.168.1.64:5001
[Thread-13] DEBUG 22:13:29.412 Received on socket: User-Agent: PLAYSTATION 3
[Thread-13] DEBUG 22:13:29.423 Received on socket: Connection: close
[Thread-13] DEBUG 22:13:29.423 Received on socket: Accept-Encoding: identity
[Thread-13] DEBUG 22:13:29.423 Received on socket: Cache-Control: no-cache
[Thread-13] INFO 22:13:29.423 HTTP: images/Play1Hot_256.png / 0-0
[Thread-13] DEBUG 22:13:29.423 Wrote on socket: HTTP/1.0 200 OK
[Thread-13] DEBUG 22:13:29.424 Wrote on socket: Content-Type: image/png
[Thread-13] DEBUG 22:13:29.424 Wrote on socket: Accept-Ranges: bytes
[Thread-13] DEBUG 22:13:29.424 Wrote on socket: Connection: keep-alive
[Thread-13] DEBUG 22:13:29.424 Wrote on socket: Expires: Tue, 07 Jul 2009 16:00:09 GMT
[Thread-13] DEBUG 22:13:29.425 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-13] DEBUG 22:13:29.425 Available Content-Length: 13000
[Thread-13] DEBUG 22:13:29.425 Wrote on socket: Content-Length: 13000
[Thread-13] DEBUG 22:13:29.425 Wrote on socket:
[Thread-13] DEBUG 22:13:29.426 Sending stream: 0 bytes of images/Play1Hot_256.png
[Thread-13] DEBUG 22:13:29.426 Close Connection
[Thread-14] DEBUG 22:13:29.440 Opened handler on socket Socket[addr=/192.168.1.65,port=65197,localport=5001]
[Thread-14] DEBUG 22:13:29.441 Received on socket: GET /images/Play1Hot_256.png HTTP/1.1
[Thread-14] DEBUG 22:13:29.441 Received on socket: Host: 192.168.1.64:5001
[Thread-14] DEBUG 22:13:29.441 Received on socket: User-Agent: PLAYSTATION 3
[Thread-14] DEBUG 22:13:29.451 Received on socket: Connection: Keep-Alive
[Thread-14] DEBUG 22:13:29.451 Received on socket: Accept-Encoding: identity
[Thread-14] DEBUG 22:13:29.451 Received on socket: transferMode.dlna.org: Interactive
[Thread-14] INFO 22:13:29.451 HTTP: images/Play1Hot_256.png / 0-0
[Thread-14] DEBUG 22:13:29.452 Wrote on socket: HTTP/1.1 200 OK
[Thread-14] DEBUG 22:13:29.452 Wrote on socket: Content-Type: image/png
[Thread-14] DEBUG 22:13:29.452 Wrote on socket: Accept-Ranges: bytes
[Thread-14] DEBUG 22:13:29.452 Wrote on socket: Connection: keep-alive
[Thread-14] DEBUG 22:13:29.452 Wrote on socket: Expires: Tue, 07 Jul 2009 16:00:09 GMT
[Thread-14] DEBUG 22:13:29.453 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-14] DEBUG 22:13:29.453 Available Content-Length: 13000
[Thread-14] DEBUG 22:13:29.453 Wrote on socket: Content-Length: 13000
[Thread-14] DEBUG 22:13:29.453 Wrote on socket:
[Thread-14] DEBUG 22:13:29.455 Sending stream: 13000 bytes of images/Play1Hot_256.png
[Thread-14] DEBUG 22:13:29.456 Close Connection
[Thread-8] DEBUG 22:13:29.734 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-15] DEBUG 22:13:29.811 Opened handler on socket Socket[addr=/192.168.1.65,port=65196,localport=5001]
[Thread-15] DEBUG 22:13:29.811 Received on socket: HEAD /images/Play1Hot_256.png HTTP/1.0
[Thread-15] DEBUG 22:13:29.811 Received on socket: Host: 192.168.1.64:5001
[Thread-15] DEBUG 22:13:29.811 Received on socket: User-Agent: PLAYSTATION 3
[Thread-15] DEBUG 22:13:29.822 Received on socket: Connection: close
[Thread-15] DEBUG 22:13:29.822 Received on socket: Accept-Encoding: identity
[Thread-15] DEBUG 22:13:29.822 Received on socket: Cache-Control: no-cache
[Thread-15] INFO 22:13:29.822 HTTP: images/Play1Hot_256.png / 0-0
[Thread-15] DEBUG 22:13:29.823 Wrote on socket: HTTP/1.0 200 OK
[Thread-15] DEBUG 22:13:29.823 Wrote on socket: Content-Type: image/png
[Thread-15] DEBUG 22:13:29.823 Wrote on socket: Accept-Ranges: bytes
[Thread-15] DEBUG 22:13:29.823 Wrote on socket: Connection: keep-alive
[Thread-15] DEBUG 22:13:29.823 Wrote on socket: Expires: Tue, 07 Jul 2009 16:00:09 GMT
[Thread-15] DEBUG 22:13:29.824 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-15] DEBUG 22:13:29.824 Available Content-Length: 13000
[Thread-15] DEBUG 22:13:29.824 Wrote on socket: Content-Length: 13000
[Thread-15] DEBUG 22:13:29.824 Wrote on socket:
[Thread-15] DEBUG 22:13:29.824 Sending stream: 0 bytes of images/Play1Hot_256.png
[Thread-15] DEBUG 22:13:29.824 Close Connection
[Thread-16] DEBUG 22:13:29.831 Opened handler on socket Socket[addr=/192.168.1.65,port=65195,localport=5001]
[Thread-16] DEBUG 22:13:29.831 Received on socket: GET /images/Play1Hot_256.png HTTP/1.1
[Thread-16] DEBUG 22:13:29.831 Received on socket: Host: 192.168.1.64:5001
[Thread-16] DEBUG 22:13:29.831 Received on socket: User-Agent: PLAYSTATION 3
[Thread-16] DEBUG 22:13:29.842 Received on socket: Connection: Keep-Alive
[Thread-16] DEBUG 22:13:29.842 Received on socket: Accept-Encoding: identity
[Thread-16] DEBUG 22:13:29.842 Received on socket: transferMode.dlna.org: Interactive
[Thread-16] INFO 22:13:29.842 HTTP: images/Play1Hot_256.png / 0-0
[Thread-16] DEBUG 22:13:29.842 Wrote on socket: HTTP/1.1 200 OK
[Thread-16] DEBUG 22:13:29.842 Wrote on socket: Content-Type: image/png
[Thread-16] DEBUG 22:13:29.843 Wrote on socket: Accept-Ranges: bytes
[Thread-16] DEBUG 22:13:29.843 Wrote on socket: Connection: keep-alive
[Thread-16] DEBUG 22:13:29.843 Wrote on socket: Expires: Tue, 07 Jul 2009 16:00:09 GMT
[Thread-16] DEBUG 22:13:29.844 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-16] DEBUG 22:13:29.844 Available Content-Length: 13000
[Thread-16] DEBUG 22:13:29.844 Wrote on socket: Content-Length: 13000
[Thread-16] DEBUG 22:13:29.844 Wrote on socket:
[Thread-16] DEBUG 22:13:29.845 Sending stream: 13000 bytes of images/Play1Hot_256.png
[Thread-16] DEBUG 22:13:29.846 Close Connection
[Thread-8] DEBUG 22:13:30.083 Receiving a NOTIFY from [192.168.1.64:52926]
[Thread-17] DEBUG 22:13:30.195 Opened handler on socket Socket[addr=/192.168.1.65,port=65194,localport=5001]
[Thread-17] DEBUG 22:13:30.195 Received on socket: HEAD /images/Play1Hot_256.png HTTP/1.0
[Thread-17] DEBUG 22:13:30.195 Received on socket: Host: 192.168.1.64:5001
[Thread-17] DEBUG 22:13:30.195 Received on socket: User-Agent: PLAYSTATION 3
[Thread-17] DEBUG 22:13:30.205 Received on socket: Connection: close
[Thread-17] DEBUG 22:13:30.205 Received on socket: Accept-Encoding: identity
[Thread-17] DEBUG 22:13:30.205 Received on socket: Cache-Control: no-cache
[Thread-17] INFO 22:13:30.205 HTTP: images/Play1Hot_256.png / 0-0
[Thread-17] DEBUG 22:13:30.206 Wrote on socket: HTTP/1.0 200 OK
[Thread-17] DEBUG 22:13:30.206 Wrote on socket: Content-Type: image/png
[Thread-17] DEBUG 22:13:30.206 Wrote on socket: Accept-Ranges: bytes
[Thread-17] DEBUG 22:13:30.206 Wrote on socket: Connection: keep-alive
[Thread-17] DEBUG 22:13:30.206 Wrote on socket: Expires: Tue, 07 Jul 2009 16:00:10 GMT
[Thread-17] DEBUG 22:13:30.207 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-17] DEBUG 22:13:30.207 Available Content-Length: 13000
[Thread-17] DEBUG 22:13:30.207 Wrote on socket: Content-Length: 13000
[Thread-17] DEBUG 22:13:30.207 Wrote on socket:
[Thread-17] DEBUG 22:13:30.207 Sending stream: 0 bytes of images/Play1Hot_256.png
[Thread-17] DEBUG 22:13:30.208 Close Connection
[Thread-18] DEBUG 22:13:30.213 Opened handler on socket Socket[addr=/192.168.1.65,port=65193,localport=5001]
[Thread-18] DEBUG 22:13:30.214 Received on socket: GET /images/Play1Hot_256.png HTTP/1.1
[Thread-18] DEBUG 22:13:30.214 Received on socket: Host: 192.168.1.64:5001
[Thread-18] DEBUG 22:13:30.214 Received on socket: User-Agent: PLAYSTATION 3
[Thread-18] DEBUG 22:13:30.224 Received on socket: Connection: Keep-Alive
[Thread-18] DEBUG 22:13:30.224 Received on socket: Accept-Encoding: identity
[Thread-18] DEBUG 22:13:30.224 Received on socket: transferMode.dlna.org: Interactive
[Thread-18] INFO 22:13:30.224 HTTP: images/Play1Hot_256.png / 0-0
[Thread-18] DEBUG 22:13:30.224 Wrote on socket: HTTP/1.1 200 OK
[Thread-18] DEBUG 22:13:30.224 Wrote on socket: Content-Type: image/png
[Thread-18] DEBUG 22:13:30.225 Wrote on socket: Accept-Ranges: bytes
[Thread-18] DEBUG 22:13:30.225 Wrote on socket: Connection: keep-alive
[Thread-18] DEBUG 22:13:30.225 Wrote on socket: Expires: Tue, 07 Jul 2009 16:00:10 GMT
[Thread-18] DEBUG 22:13:30.226 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-18] DEBUG 22:13:30.226 Available Content-Length: 13000
[Thread-18] DEBUG 22:13:30.226 Wrote on socket: Content-Length: 13000
[Thread-18] DEBUG 22:13:30.226 Wrote on socket:
[Thread-18] DEBUG 22:13:30.228 Sending stream: 13000 bytes of images/Play1Hot_256.png
[Thread-18] DEBUG 22:13:30.228 Close Connection
[Thread-8] DEBUG 22:13:32.735 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:13:36.735 Receiving a M-SEARCH from [192.168.1.64:59603]

[ExSport]

Same problem on my WIN XP,latest PMS. I thought that PMS missed some M-Search requests but it seems that PMS didn't answered on them and sent alive message in constant interval only(or in most cases)...

[lettcco]

having same problem with ps3 not finding server with the latest builds also....

UPDATE: I opened up port 5001 on my vista firewall and viola! it has found my ps3! still baffle why I didn't have to do it with version 1.0.3 though.

[Jagged]

The situation is that my PS3 always finds Media Server v1.03/v1.04 the moment the PS3 is switched on (instantly) and while it takes up to several minutes with the newer versions, which to me indicates clearly that a change in the program must have made the impact and not the PS3 itself.

As for M-SEARCH packages, I already see them in the debug file even with the PS3 switched off.

[Thread-8] DEBUG 22:06:39.723 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:06:42.723 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:06:46.723 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-7] TRACE 22:06:49.193 Sending ALIVE...
[Thread-7] DEBUG 22:06:49.193 Setting multicast network interface: name:eth3 (Intel(R) 82567LF-2 Gigabit Network Connection) index: 12 addresses:
/192.168.1.64;

[Thread-7] DEBUG 22:06:49.221 Sending message from multicast socket on network interface: name:eth3 (Intel(R) 82567LF-2 Gigabit Network Connection) index: 12 addresses:
/192.168.1.64;

[Thread-7] DEBUG 22:06:49.221 Multicast socket is on interface: /192.168.1.64
[Thread-7] DEBUG 22:06:49.221 Socket Timeout: 0
[Thread-7] DEBUG 22:06:49.221 Socket TTL: 32
[Thread-8] DEBUG 22:06:49.223 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:49.723 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:06:50.090 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:50.689 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:51.155 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:51.421 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:51.936 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:51.968 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:52.724 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:06:52.730 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:53.047 Receiving a NOTIFY from [192.168.1.64:60583]
[Thread-8] DEBUG 22:06:53.249 Receiving a NOTIFY from [192.168.1.64:60583]

So now with the PS3 switched on in v1.10.5, I still only see this in the log file:

[Thread-8] DEBUG 22:11:43.699 Receiving a NOTIFY from [192.168.1.64:1900]
[Thread-8] DEBUG 22:11:43.702 Receiving a NOTIFY from [192.168.1.64:1900]
[Thread-8] DEBUG 22:11:43.702 Receiving a NOTIFY from [192.168.1.64:1900]
[Thread-8] DEBUG 22:11:46.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:11:49.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:11:52.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:11:56.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:11:59.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:12:02.732 Receiving a M-SEARCH from [192.168.1.64:59603]
[Thread-8] DEBUG 22:12:06.732 Receiving a M-SEARCH from [192.168.1.64:59603]

After 2 minutes, the PS3 finally discovered the Media Server. (instead of instantly with v1.03/v1.04)
...(clipped)

The above M-SEARCH and NOTIFY packets are coming from another multicast application on the same machine (Tversity, PlayOn, etc..). Since they are on the same interface as PMS, they are likely bypassing the firewall and able to be received by PMS.

I believe that the reason it would instantly find it is because it was sending ALIVE msgs more frequently. The real issue though, is that you aren't getting M-SEARCH packets from the PS3. This could be caused by a firewall on the desktop running PMS, or perhaps some settings within your router are blocking multicast packets (this seems less likely, since ALIVE multicast messages are working). I would try disabling the firewall on the PC running PMS temporarily, and see if you can then do a "search for media servers" from the PS3 and see if it quickly finds the PS3.

So basically, Multicast is only partly working on your network. PMS can send Multicast messages, but it cannot receive them (or else something is blocking the Multicast messages being sent by the PS3). The only time multicast is used is during the discovery process (ALIVE/NOTIFY/M-SEARCH). Everything else uses unicast between the ps3 and PMS via port 5001.

[Barry]

He want to download and install java... why?
i have already.
back to "stable build"

[Flak]

Build 618 doesn't notice Java 6 update 12. After install it says it needs Java 6 and then downloads and installs Java 6 update 10 even though update 12 is installed and works fine with the current stable release.

[Martin Smith]

Hi,

DTS 5.1/7.1 Audio CDs,Transferred to PC for ease of use ,unable to play on PS3.

I note several comments regarding this apparent Ps3 design defect.I have tried all ways to get the DTS files to Play on The Ps3 without success,however the software program MKV2Vob has in its latest update included a system which Patches the DTS to the video.Whilst it will no longer play on any other machine inc the PC.It does play 5.1/7.1 audio on the Ps3 and the Amp recognizes it as a DTS file.

If anything similar could be configured to enable DTS audio files to be streamed & played on the Ps3 it would solve this gap in the PS3's ability's,I understand some form of dummy video file would possibly be needed to act as a carrier.I could then dispose of 2 very old streaming media players I have retained alongside my Ps3,s.to have streamed to and play a very large collection of loss less DTS (Wave) Cds.I have transferred to my PC for streaming.

The video abilities of this free open software are without doubt years in front of any other similar commercial software.However most people I believe from my fellow enthusiasts who stream video and audio would chose DTS over AC3. It is a necessary component of a HRHD video and is by far the best Audio out.I look forward to anything that can be done, to fill this Ps3 design defect.

[Audioboxer]

Build 618 doesn't notice Java 6 update 12. After install it says it needs Java 6 and then downloads and installs Java 6 update 10 even though update 12 is installed and works fine with the current stable release.

Yeah I have this issue as well