[b91] Unable to find UPnP services

Thoughts, Help, Feature Requests, Bug Reports, Developing code for...

Moderators: dorpond, trevor, Azhrei

Forum rules
PLEASE don't post images of your entire desktop, attach entire campaign files when only a single file is needed, or generally act in some other anti-social behavior. :)
User avatar
aliasmask
RPTools Team
Posts: 9024
Joined: Tue Nov 10, 2009 6:11 pm
Location: Bay Area

[b91] Unable to find UPnP services

Post by aliasmask »

Since I started using b91, I've had this message a couple of times when starting a server. I'm using a Comcast router and when I check the settings it shows it enabled. But I can disable/re-enable it and then the server starts just fine.

This could be a Comcast thing but I was wondering if anyone else has recently run in to this problem using b91. If it happens to me again, I'll be sure to start a server with b89 and see if the problem is the same or if UPnP services are found. I think this will tell me if it's MapTool or Comcast related.

If it MapTool related, then I suspect this patch in the notes:

Code: Select all

[r5976] fixed upnp problem where IP_ANY was being used; added support for port mapping all routers on all connected subnets; viewtopic.php?f=60&t=23526&p=243694#p243694
Note: For the Comcast router, the setting is under Advanced | Device Discovery - Enable UPnP.

User avatar
wolph42
Winter Wolph
Posts: 9999
Joined: Fri Mar 20, 2009 5:40 am
Location: Netherlands
Contact:

Re: [b91] Unable to find UPnP services

Post by wolph42 »

upnp does not work in b89. it worked first in the b90 of Azh. However he warned that there are still quite some routers out there that won't be supported, due to the sheer randomness. I too have games where sometimes i have upnp access and sometimes i don't (we play in several locations). So overall, this is expected behaviours (although unwanted obviously). it still better though than the b80+ version where it didn't work at all.

User avatar
aliasmask
RPTools Team
Posts: 9024
Joined: Tue Nov 10, 2009 6:11 pm
Location: Bay Area

Re: [b91] Unable to find UPnP services

Post by aliasmask »

wolph42 wrote:upnp does not work in b89.
I think you're thinking of b87. I've had UPnP working ever since b89 was released. The only changes to my system is b91 and java 8. Comcast could have done a remote software update or java 8 library could have different protocols for UPnP devices that the router doesn't understand, so it may not be MapTool.

This post is to determine if any other users out there are having similar problems and then we can compare notes.

User avatar
aliasmask
RPTools Team
Posts: 9024
Joined: Tue Nov 10, 2009 6:11 pm
Location: Bay Area

Re: [b91] Unable to find UPnP services

Post by aliasmask »

Well, this does seem to be b91 related. I had this happen again. I tried several times on b91 to connect (getting my screen caps). Then I went to b89 and it started, no problem. I even started another instance and connected to myself. I then went back to b91 and started a server getting the same error. Now here's the weird part. After twiddling with my image below I then tried again and there was no UPnP error and the b91 server started fine without having to reset the UPnP on my router. I'm thinking connecting with b89 may have renewed my lease, but there was a bit of a delay for that to happen which I don't think is normal behavior.
Debugging

Code: Select all

2014-11-03 11:54:37,177 [client.MapTool:298] ERROR - UPnP Error - No Internet Gateway Devices found.<br><br>UPnP port mapping will not be available.
2014-11-03 11:54:37,193 [upnp.DiscoveryListener:175] ERROR - IO Exception during UPNP DiscoveryListener messages listening thread
java.net.SocketException: Socket closed
	at java.net.TwoStacksPlainDatagramSocketImpl.receive0(Native Method)
	at java.net.TwoStacksPlainDatagramSocketImpl.receive(Unknown Source)
	at java.net.DatagramSocket.receive(Unknown Source)
	at net.sbbi.upnp.DiscoveryListener.listenBroadCast(DiscoveryListener.java:190)
	at net.sbbi.upnp.DiscoveryListener.run(DiscoveryListener.java:171)
	at java.lang.Thread.run(Unknown Source)
Error Messages
UPnP Error.jpg
UPnP Error.jpg (53.55 KiB) Viewed 2960 times
Xfinity UPnP
xfinity UPnP.jpg
xfinity UPnP.jpg (81.27 KiB) Viewed 2958 times

User avatar
wolph42
Winter Wolph
Posts: 9999
Joined: Fri Mar 20, 2009 5:40 am
Location: Netherlands
Contact:

Re: [b91] Unable to find UPnP services

Post by wolph42 »

aliasmask wrote:Well, this does seem to be b91 related. I had this happen again. I tried several times on b91 to connect (getting my screen caps). Then I went to b89 and it started, no problem. I even started another instance and connected to myself. I then went back to b91 and started a server getting the same error. Now here's the weird part. After twiddling with my image below I then tried again and there was no UPnP error and the b91 server started fine without having to reset the UPnP on my router. I'm thinking connecting with b89 may have renewed my lease, but there was a bit of a delay for that to happen which I don't think is normal behavior.
Debugging

Code: Select all

2014-11-03 11:54:37,177 [client.MapTool:298] ERROR - UPnP Error - No Internet Gateway Devices found.<br><br>UPnP port mapping will not be available.
2014-11-03 11:54:37,193 [upnp.DiscoveryListener:175] ERROR - IO Exception during UPNP DiscoveryListener messages listening thread
java.net.SocketException: Socket closed
	at java.net.TwoStacksPlainDatagramSocketImpl.receive0(Native Method)
	at java.net.TwoStacksPlainDatagramSocketImpl.receive(Unknown Source)
	at java.net.DatagramSocket.receive(Unknown Source)
	at net.sbbi.upnp.DiscoveryListener.listenBroadCast(DiscoveryListener.java:190)
	at net.sbbi.upnp.DiscoveryListener.run(DiscoveryListener.java:171)
	at java.lang.Thread.run(Unknown Source)
Error Messages
UPnP Error.jpg
Xfinity UPnP
xfinity UPnP.jpg
its weird cause my router doesn't eat version 89...

User avatar
aliasmask
RPTools Team
Posts: 9024
Joined: Tue Nov 10, 2009 6:11 pm
Location: Bay Area

Re: [b91] Unable to find UPnP services

Post by aliasmask »

This is still a problem for me. Am I the only one having this problem?

Before it would sometimes start and other times wouldn't. B89 worked everytime though. Since I don't see this happening to others I re-evaluated what about my system is different from default. For one, I use port 6801. When I changed it back to original 51234 then I connected on the first try. I have a feeling the patch may have that hard coded in there and may be the problem. I'll let you know any further developments.

edit: More weird behavior

I went back to 6801 port and it failed, but this time when I tried to disconnect java locked up and I had to use task manager to quit. I then restarted with port 6801 again and started up. Disconnected and restarted and it loaded again under 6801... weird.

Craig
Great Wyrm
Posts: 2107
Joined: Sun Jun 22, 2008 7:53 pm
Location: Melbourne, Australia

Re: [b91] Unable to find UPnP services

Post by Craig »

aliasmask wrote:This is still a problem for me. Am I the only one having this problem?

Before it would sometimes start and other times wouldn't. B89 worked everytime though. Since I don't see this happening to others I re-evaluated what about my system is different from default. For one, I use port 6801. When I changed it back to original 51234 then I connected on the first try. I have a feeling the patch may have that hard coded in there and may be the problem. I'll let you know any further developments.

edit: More weird behavior

I went back to 6801 port and it failed, but this time when I tried to disconnect java locked up and I had to use task manager to quit. I then restarted with port 6801 again and started up. Disconnected and restarted and it loaded again under 6801... weird.
Can you try a non default port other than 6801? Just to rule out there is nothing else trying to get that port or your router is handeling it differently (Some VOIP services use 6801), it might be what ever change was made isn't gracefully dealing with such a situation.

User avatar
aliasmask
RPTools Team
Posts: 9024
Joined: Tue Nov 10, 2009 6:11 pm
Location: Bay Area

Re: [b91] Unable to find UPnP services

Post by aliasmask »

I just tried 51234, 51235 and 51236 and got the error. So there goes one of my theories. I kept changing ports with no luck. I loaded b89 and started server then disconnected. Then I started b91 with the same port (51234) and it started correctly. Right now I need to use voodoo tactics to get a server running on b91. I also rotated through those same ports in b91 after connecting once and they worked.

Edit: More weirdness

Yeah, so when I did connect I disconnect and then exit out of MapTool. I did wait awhile before that to make sure the campaign reloaded. Anyway, I open it back up and try to reconnect and it said I was already connected to a server. It also unloaded my campaign in that process.
Logging

Code: Select all

2014-11-09 18:04:11,784 [client.MapTool:298] ERROR - Server has disconnected.
2014-11-09 18:04:21,953 [client.MapTool:298] ERROR - UPnP Error - No Internet Gateway Devices found.<br><br>UPnP port mapping will not be available.
2014-11-09 18:04:22,000 [upnp.DiscoveryListener:175] ERROR - IO Exception during UPNP DiscoveryListener messages listening thread
java.net.SocketException: socket closed
	at java.net.TwoStacksPlainDatagramSocketImpl.receive0(Native Method)
	at java.net.TwoStacksPlainDatagramSocketImpl.receive(Unknown Source)
	at java.net.DatagramSocket.receive(Unknown Source)
	at net.sbbi.upnp.DiscoveryListener.listenBroadCast(DiscoveryListener.java:190)
	at net.sbbi.upnp.DiscoveryListener.run(DiscoveryListener.java:171)
	at java.lang.Thread.run(Unknown Source)
2014-11-09 18:04:23,176 [client.MapTool:298] ERROR - msg.error.alreadyRunningServer
2014-11-09 18:04:25,301 [client.MapTool:298] ERROR - msg.error.failedConnect
java.net.ConnectException: Connection refused: connect
	at java.net.DualStackPlainSocketImpl.connect0(Native Method)
	at java.net.DualStackPlainSocketImpl.socketConnect(Unknown Source)
	at java.net.AbstractPlainSocketImpl.doConnect(Unknown Source)
	at java.net.AbstractPlainSocketImpl.connectToAddress(Unknown Source)
	at java.net.AbstractPlainSocketImpl.connect(Unknown Source)
	at java.net.PlainSocketImpl.connect(Unknown Source)
	at java.net.SocksSocketImpl.connect(Unknown Source)
	at java.net.Socket.connect(Unknown Source)
	at java.net.Socket.connect(Unknown Source)
	at java.net.Socket.<init>(Unknown Source)
	at java.net.Socket.<init>(Unknown Source)
	at net.rptools.clientserver.simple.client.ClientConnection.<init>(ClientConnection.java:42)
	at net.rptools.clientserver.hessian.client.ClientConnection.<init>(ClientConnection.java:29)
	at net.rptools.maptool.client.MapToolConnection.<init>(MapToolConnection.java:28)
	at net.rptools.maptool.client.MapTool.createConnection(MapTool.java:1019)
	at net.rptools.maptool.client.AppActions$51$1.run(AppActions.java:1893)
	at net.rptools.maptool.client.AppActions$ClientAction$1.run(AppActions.java:2813)
2014-11-09 18:04:27,036 [client.MapTool:298] ERROR - msg.error.alreadyRunningServer
2014-11-09 18:04:29,320 [client.MapTool:298] ERROR - msg.error.failedStartPersonalServer
java.net.ConnectException: Connection refused: connect
	at java.net.DualStackPlainSocketImpl.connect0(Native Method)
	at java.net.DualStackPlainSocketImpl.socketConnect(Unknown Source)
	at java.net.AbstractPlainSocketImpl.doConnect(Unknown Source)
	at java.net.AbstractPlainSocketImpl.connectToAddress(Unknown Source)
	at java.net.AbstractPlainSocketImpl.connect(Unknown Source)
	at java.net.PlainSocketImpl.connect(Unknown Source)
	at java.net.SocksSocketImpl.connect(Unknown Source)
	at java.net.Socket.connect(Unknown Source)
	at java.net.Socket.connect(Unknown Source)
	at java.net.Socket.<init>(Unknown Source)
	at java.net.Socket.<init>(Unknown Source)
	at net.rptools.clientserver.simple.client.ClientConnection.<init>(ClientConnection.java:42)
	at net.rptools.clientserver.hessian.client.ClientConnection.<init>(ClientConnection.java:29)
	at net.rptools.maptool.client.MapToolConnection.<init>(MapToolConnection.java:28)
	at net.rptools.maptool.client.MapTool.createConnection(MapTool.java:1019)
	at net.rptools.maptool.client.MapTool.startPersonalServer(MapTool.java:1009)
	at net.rptools.maptool.client.AppActions$51$1.run(AppActions.java:1911)
	at net.rptools.maptool.client.AppActions$ClientAction$1.run(AppActions.java:2813)
Is there something I can add to logging for connectivity? I can usually get a server started by trying to reconnect several times with various techniques and success, but no one surefire way of doing it. Some of those methods include changing the port number after a failed server start and loading b89 first. I've had both work and both fail.

User avatar
aliasmask
RPTools Team
Posts: 9024
Joined: Tue Nov 10, 2009 6:11 pm
Location: Bay Area

Re: [b91] Unable to find UPnP services

Post by aliasmask »

I posted a boring 10 minute video of me trying to start and stop a server with varying degrees of success. I just can't place my finger on why is starts sometimes and other times it doesn't.

https://www.youtube.com/watch?v=6ZfhAp-2LFY

User avatar
Azhrei
Site Admin
Posts: 12086
Joined: Mon Jun 12, 2006 1:20 pm
Location: Tampa, FL

Re: [b91] Unable to find UPnP services

Post by Azhrei »

Hi aliasmask. :)

Craig sent me the link to this thread.

In the OP you say that you reset the router and it worked. This makes me think the router may have some kind of bug. But then later you say that b89 works and when you shut down b89 and start b91, it still works. (That's probably because the router didn't really delete the port forwarding. Some routers will add a timeout to the deletion if they see that the destination IP address is still valid. So when you bring up b91, it works because the router never really deleted it. YMMV.)

There should be some additional logging available in the UPNP library. When I revamped that code to support XML namespaces (used by the latest UPNP incarnation but not used in the original library) I added a bunch of logging. You can use the attached file to cause all kind of extra logging to appear in the "upnplib" module (this is the low-level library) and MT's UPnPUtil code that actually calls the library to add/remove the port forwarding. Put it in the "logging" subdirectory of your MT installation and the launcher should update the Logging tab to show a new entry.

Try turning on the logging for both b89 and b91. We can compare the two results and see what's different. It might be best to try b91 first (which fails), then try it with b89 (which works), and then with b91 again (which works). Attach the results here (as a single ZIP if you wish) and that should help locate the problem.
Attachments
upnp.xml
UPNP logging in MapTool
(2.17 KiB) Downloaded 81 times

User avatar
aliasmask
RPTools Team
Posts: 9024
Joined: Tue Nov 10, 2009 6:11 pm
Location: Bay Area

Re: [b91] Unable to find UPnP services

Post by aliasmask »

Thanks for the debugging file. I do get a different behavior now that I'm using it.

Start MapTool B91
Start Server (UPnP Error)
Disconnect from Server
Connect to Server and cancel (to verify not in list)
Start Server (No Error)
I try to disconnect, but option is not there like I'm not actually running a server.
I check my server info and it shows my server name and port number.
I look at menu again and it shows that I'm running a server. (could be some interface lag?)
Disconnect from Server (closePort error)
Verify Server info. I'm still running a server.
Disconnect from Server (MapTool locks up, but I wait)
I use task manager to close MapTool (this only happened twice before)
log.txt

Code: Select all

2014-11-11 14:46:57,638 [util.UPnPUtil:91] INFO - UPnP:  Trying interface Microsoft Teredo Tunneling Adapter
2014-11-11 14:47:02,684 [util.UPnPUtil:120] INFO - Found 0 IGDs on interface Microsoft Teredo Tunneling Adapter
2014-11-11 14:47:02,684 [util.UPnPUtil:91] INFO - UPnP:  Trying interface Realtek PCIe GBE Family Controller
2014-11-11 14:47:02,718 [upnp.Discovery:246] DEBUG - Sending discovery message on 239.255.255.250:1900 multicast address from ip 10.0.0.5:
M-SEARCH * HTTP/1.1
HOST: 239.255.255.250:1900
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1
MAN: "ssdp:discover"
MX: 3


2014-11-11 14:47:07,722 [util.UPnPUtil:120] INFO - Found 0 IGDs on interface Realtek PCIe GBE Family Controller
2014-11-11 14:47:07,753 [client.MapTool:298] ERROR - UPnP Error - No Internet Gateway Devices found.<br><br>UPnP port mapping will not be available.
2014-11-11 14:47:07,768 [upnp.DiscoveryListener:175] ERROR - IO Exception during UPNP DiscoveryListener messages listening thread
java.net.SocketException: socket closed
	at java.net.TwoStacksPlainDatagramSocketImpl.receive0(Native Method)
	at java.net.TwoStacksPlainDatagramSocketImpl.receive(Unknown Source)
	at java.net.DatagramSocket.receive(Unknown Source)
	at net.sbbi.upnp.DiscoveryListener.listenBroadCast(DiscoveryListener.java:190)
	at net.sbbi.upnp.DiscoveryListener.run(DiscoveryListener.java:171)
	at java.lang.Thread.run(Unknown Source)
2014-11-11 14:48:45,366 [util.UPnPUtil:91] INFO - UPnP:  Trying interface Microsoft Teredo Tunneling Adapter
2014-11-11 14:48:50,396 [util.UPnPUtil:120] INFO - Found 0 IGDs on interface Microsoft Teredo Tunneling Adapter
2014-11-11 14:48:50,414 [util.UPnPUtil:91] INFO - UPnP:  Trying interface Realtek PCIe GBE Family Controller
2014-11-11 14:48:50,449 [upnp.Discovery:246] DEBUG - Sending discovery message on 239.255.255.250:1900 multicast address from ip 10.0.0.5:
M-SEARCH * HTTP/1.1
HOST: 239.255.255.250:1900
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1
MAN: "ssdp:discover"
MX: 3


2014-11-11 14:48:51,449 [upnp.HttpResponse:54] DEBUG - Response: Cache-Control: max-age=1900

2014-11-11 14:48:51,449 [upnp.HttpResponse:54] DEBUG - Response: Location: http://10.0.0.1:80/RootDevice.xml

2014-11-11 14:48:51,449 [upnp.HttpResponse:54] DEBUG - Response: Server: UPnP/1.0 UPnP/1.0 UPnP-Device-Host/1.0

2014-11-11 14:48:51,449 [upnp.HttpResponse:54] DEBUG - Response: ST:urn:schemas-upnp-org:device:InternetGatewayDevice:1

2014-11-11 14:48:51,449 [upnp.HttpResponse:54] DEBUG - Response: USN: uuid:8e62b38b-f3c0-07e6-288f-cc3540420108::urn:schemas-upnp-org:device:InternetGatewayDevice:1

2014-11-11 14:48:51,449 [upnp.HttpResponse:54] DEBUG - Response: EXT:

2014-11-11 14:48:51,449 [upnp.DiscoveryListener:223] DEBUG - Processing http://10.0.0.1:80/RootDevice.xml device description location
2014-11-11 14:48:51,649 [upnp.JXPathParser:48] DEBUG - Raw xml doc:
<?xml version="1.0" encoding="utf-8"?>
<root xmlns="urn:schemas-upnp-org:device-1-0">
<specVersion>
<major>1</major>
<minor>0</minor>
</specVersion>
<URLBase>http://10.0.0.1:80</URLBase><device>
<deviceType>urn:schemas-upnp-org:device:InternetGatewayDevice:1</deviceType>
<friendlyName>Technicolor TC8305C</friendlyName>
<manufacturer>Technicolor</manufacturer>
<manufacturerURL>http://www.technicolor.com</manufacturerURL>
<modelDescription>TC8305C</modelDescription>
<modelName>TC8305C</modelName>
<modelNumber>TC8305C</modelNumber>
<modelURL>http://customer.comcast.com/Pages/HelpNFC.aspx?id=e70e9d0e-d9eb-4748-b163-636a323e7950</modelURL>
<serialNumber>1329212555</serialNumber>
<UDN>uuid:8e62b38b-f3c0-07e6-288f-cc3540420108</UDN>
<UPC>123456789012</UPC>
<serviceList>
<service>
<serviceType>urn:schemas-upnp-org:service:Layer3Forwarding:1</serviceType>
<serviceId>urn:upnp-org:serviceId:L3Forwarding1</serviceId>
<SCPDURL>/Layer3Forwarding.xml</SCPDURL>
<controlURL>/Layer3Forwarding</controlURL>
<eventSubURL>/Layer3Forwarding</eventSubURL>
</service>
</serviceList>
<deviceList>
<device>
<deviceType>urn:schemas-upnp-org:device:WANDevice:1</deviceType>
<friendlyName>Technicolor TC8305C</friendlyName>
<manufacturer>Technicolor</manufacturer>
<manufacturerURL>http://www.technicolor.com</manufacturerURL>
<modelDescription>TC8305C</modelDescription>
<modelName>TC8305C</modelName>
<modelNumber>TC8305C</modelNumber>
<modelURL>http://customer.comcast.com/Pages/HelpNFC.aspx?id=e70e9d0e-d9eb-4748-b163-636a323e7950</modelURL>
<serialNumber>1329212555</serialNumber>
<UDN>uuid:463b460d-3c29-8e33-4af7-cc3540420108</UDN>
<UPC>123456789012</UPC>
<serviceList>
<service>
<serviceType>urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1</serviceType>
<serviceId>urn:upnp-org:serviceId:WANCommonIFC1</serviceId>
<SCPDURL>/WANCommonInterfaceConfig.xml</SCPDURL>
<controlURL>/WANCommonInterfaceConfig</controlURL>
<eventSubURL>/WANCommonInterfaceConfig</eventSubURL>
</service>
</serviceList>
<deviceList>
<device>
<deviceType>urn:schemas-upnp-org:device:WANConnectionDevice:1</deviceType>
<friendlyName>Technicolor TC8305C</friendlyName>
<manufacturer>Technicolor</manufacturer>
<manufacturerURL>http://www.technicolor.com</manufacturerURL>
<modelDescription>TC8305C</modelDescription>
<modelName>TC8305C</modelName>
<modelNumber>TC8305C</modelNumber>
<modelURL>http://customer.comcast.com/Pages/HelpNFC.aspx?id=e70e9d0e-d9eb-4748-b163-636a323e7950</modelURL>
<serialNumber>1329212555</serialNumber>
<UDN>uuid:bc36cead-42f6-dd55-0824-cc3540420108</UDN>
<UPC>123456789012</UPC>
<serviceList>
<service>
<serviceType>urn:schemas-upnp-org:service:WANIPConnection:1</serviceType>
<serviceId>urn:upnp-org:serviceId:WANIPConn1</serviceId>
<SCPDURL>/WANIPConnection.xml</SCPDURL>
<controlURL>/WANIPConnection</controlURL>
<eventSubURL>/WANIPConnection</eventSubURL>
</service>
</serviceList>
</device>
</deviceList>
</device>
</deviceList>
<presentationURL>/</presentationURL>
</device>
</root>

2014-11-11 14:48:51,665 [devices.UPNPRootDevice:154] DEBUG - device specified URLBase as http://10.0.0.1:80
2014-11-11 14:48:51,665 [devices.UPNPRootDevice:263] DEBUG - parsing device urn:schemas-upnp-org:device:InternetGatewayDevice:1
2014-11-11 14:48:51,665 [devices.UPNPRootDevice:364] DEBUG - device services count is 1.0
2014-11-11 14:48:51,665 [devices.UPNPRootDevice:311] DEBUG - child devices count is 1.0
2014-11-11 14:48:51,680 [devices.UPNPRootDevice:263] DEBUG - parsing device urn:schemas-upnp-org:device:WANDevice:1
2014-11-11 14:48:51,680 [devices.UPNPRootDevice:364] DEBUG - device services count is 1.0
2014-11-11 14:48:51,680 [devices.UPNPRootDevice:311] DEBUG - child devices count is 1.0
2014-11-11 14:48:51,680 [devices.UPNPRootDevice:263] DEBUG - parsing device urn:schemas-upnp-org:device:WANConnectionDevice:1
2014-11-11 14:48:51,680 [devices.UPNPRootDevice:364] DEBUG - device services count is 1.0
2014-11-11 14:48:51,680 [devices.UPNPRootDevice:320] DEBUG - adding child device urn:schemas-upnp-org:device:WANConnectionDevice:1
2014-11-11 14:48:51,680 [devices.UPNPRootDevice:320] DEBUG - adding child device urn:schemas-upnp-org:device:WANDevice:1
2014-11-11 14:48:55,449 [devices.UPNPDevice:180] DEBUG - searching for device URI:urn:schemas-upnp-org:device:WANConnectionDevice:1
2014-11-11 14:48:55,449 [devices.UPNPDevice:180] DEBUG - searching for device URI:urn:schemas-upnp-org:device:WANConnectionDevice:1
2014-11-11 14:48:55,449 [devices.UPNPDevice:180] DEBUG - searching for device URI:urn:schemas-upnp-org:device:WANConnectionDevice:1
2014-11-11 14:48:55,449 [devices.UPNPDevice:219] DEBUG - searching for service URI:urn:schemas-upnp-org:service:WANIPConnection:1
2014-11-11 14:48:55,449 [devices.UPNPDevice:219] DEBUG - searching for service URI:urn:schemas-upnp-org:service:WANPPPConnection:1
2014-11-11 14:48:55,449 [util.UPnPUtil:100] INFO - UPnP:  Found IGD: TC8305C
2014-11-11 14:48:55,465 [util.UPnPUtil:120] INFO - Found 1 IGDs on interface Realtek PCIe GBE Family Controller
2014-11-11 14:48:55,480 [util.UPnPUtil:159] INFO - IP address 10.0.0.5 on interface Realtek PCIe GBE Family Controller
2014-11-11 14:48:55,649 [upnp.JXPathParser:48] DEBUG - Raw xml doc:
<?xml version="1.0" encoding="utf-8"?>
<scpd xmlns="urn:schemas-upnp-org:service-1-0">
<specVersion>
<major>1</major>
<minor>0</minor>
</specVersion>
<actionList>
<action>
<name>SetConnectionType</name>
<argumentList>
<argument>
<name>NewConnectionType</name>
<direction>in</direction>
<relatedStateVariable>ConnectionType</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>GetConnectionTypeInfo</name>
<argumentList>
<argument>
<name>NewConnectionType</name>
<direction>out</direction>
<relatedStateVariable>ConnectionType</relatedStateVariable>
</argument>
<argument>
<name>NewPossibleConnectionTypes</name>
<direction>out</direction>
<relatedStateVariable>PossibleConnectionTypes</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>RequestConnection</name>
</action>
<action>
<name>ForceTermination</name>
</action>
<action>
<name>GetStatusInfo</name>
<argumentList>
<argument>
<name>NewConnectionStatus</name>
<direction>out</direction>
<relatedStateVariable>ConnectionStatus</relatedStateVariable>
</argument>
<argument>
<name>NewLastConnectionError</name>
<direction>out</direction>
<relatedStateVariable>LastConnectionError</relatedStateVariable>
</argument>
<argument>
<name>NewUptime</name>
<direction>out</direction>
<relatedStateVariable>Uptime</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>GetNATRSIPStatus</name>
<argumentList>
<argument>
<name>NewRSIPAvailable</name>
<direction>out</direction>
<relatedStateVariable>RSIPAvailable</relatedStateVariable>
</argument>
<argument>
<name>NewNATEnabled</name>
<direction>out</direction>
<relatedStateVariable>NATEnabled</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>GetGenericPortMappingEntry</name>
<argumentList>
<argument>
<name>NewPortMappingIndex</name>
<direction>in</direction>
<relatedStateVariable>PortMappingNumberOfEntries</relatedStateVariable>
</argument>
<argument>
<name>NewRemoteHost</name>
<direction>out</direction>
<relatedStateVariable>RemoteHost</relatedStateVariable>
</argument>
<argument>
<name>NewExternalPort</name>
<direction>out</direction>
<relatedStateVariable>ExternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewProtocol</name>
<direction>out</direction>
<relatedStateVariable>PortMappingProtocol</relatedStateVariable>
</argument>
<argument>
<name>NewInternalPort</name>
<direction>out</direction>
<relatedStateVariable>InternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewInternalClient</name>
<direction>out</direction>
<relatedStateVariable>InternalClient</relatedStateVariable>
</argument>
<argument>
<name>NewEnabled</name>
<direction>out</direction>
<relatedStateVariable>PortMappingEnabled</relatedStateVariable>
</argument>
<argument>
<name>NewPortMappingDescription</name>
<direction>out</direction>
<relatedStateVariable>PortMappingDescription</relatedStateVariable>
</argument>
<argument>
<name>NewLeaseDuration</name>
<direction>out</direction>
<relatedStateVariable>PortMappingLeaseDuration</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>GetSpecificPortMappingEntry</name>
<argumentList>
<argument>
<name>NewRemoteHost</name>
<direction>in</direction>
<relatedStateVariable>RemoteHost</relatedStateVariable>
</argument>
<argument>
<name>NewExternalPort</name>
<direction>in</direction>
<relatedStateVariable>ExternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewProtocol</name>
<direction>in</direction>
<relatedStateVariable>PortMappingProtocol</relatedStateVariable>
</argument>
<argument>
<name>NewInternalPort</name>
<direction>out</direction>
<relatedStateVariable>InternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewInternalClient</name>
<direction>out</direction>
<relatedStateVariable>InternalClient</relatedStateVariable>
</argument>
<argument>
<name>NewEnabled</name>
<direction>out</direction>
<relatedStateVariable>PortMappingEnabled</relatedStateVariable>
</argument>
<argument>
<name>NewPortMappingDescription</name>
<direction>out</direction>
<relatedStateVariable>PortMappingDescription</relatedStateVariable>
</argument>
<argument>
<name>NewLeaseDuration</name>
<direction>out</direction>
<relatedStateVariable>PortMappingLeaseDuration</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>AddPortMapping</name>
<argumentList>
<argument>
<name>NewRemoteHost</name>
<direction>in</direction>
<relatedStateVariable>RemoteHost</relatedStateVariable>
</argument>
<argument>
<name>NewExternalPort</name>
<direction>in</direction>
<relatedStateVariable>ExternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewProtocol</name>
<direction>in</direction>
<relatedStateVariable>PortMappingProtocol</relatedStateVariable>
</argument>
<argument>
<name>NewInternalPort</name>
<direction>in</direction>
<relatedStateVariable>InternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewInternalClient</name>
<direction>in</direction>
<relatedStateVariable>InternalClient</relatedStateVariable>
</argument>
<argument>
<name>NewEnabled</name>
<direction>in</direction>
<relatedStateVariable>PortMappingEnabled</relatedStateVariable>
</argument>
<argument>
<name>NewPortMappingDescription</name>
<direction>in</direction>
<relatedStateVariable>PortMappingDescription</relatedStateVariable>
</argument>
<argument>
<name>NewLeaseDuration</name>
<direction>in</direction>
<relatedStateVariable>PortMappingLeaseDuration</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>DeletePortMapping</name>
<argumentList>
<argument>
<name>NewRemoteHost</name>
<direction>in</direction>
<relatedStateVariable>RemoteHost</relatedStateVariable>
</argument>
<argument>
<name>NewExternalPort</name>
<direction>in</direction>
<relatedStateVariable>ExternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewProtocol</name>
<direction>in</direction>
<relatedStateVariable>PortMappingProtocol</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>GetExternalIPAddress</name>
<argumentList>
<argument>
<name>NewExternalIPAddress</name>
<direction>out</direction>
<relatedStateVariable>ExternalIPAddress</relatedStateVariable>
</argument>
</argumentList>
</action>
</actionList>
<serviceStateTable>
<stateVariable sendEvents="no">
<name>ConnectionType</name>
<dataType>string</dataType>
</stateVariable>
<stateVariable sendEvents="yes">
<name>PossibleConnectionTypes</name>
<dataType>string</dataType>
<allowedValueList>
<allowedValue>Unconfigured</allowedValue>
<allowedValue>IP_Routed</allowedValue>
<allowedValue>IP_Bridged</allowedValue>
</allowedValueList>
</stateVariable>
<stateVariable sendEvents="yes">
<name>ConnectionStatus</name>
<dataType>string</dataType>
<allowedValueList>
<allowedValue>Unconfigured</allowedValue>
<allowedValue>Connected</allowedValue>
<allowedValue>Disconnected</allowedValue>
</allowedValueList>
</stateVariable>
<stateVariable sendEvents="no">
<name>Uptime</name>
<dataType>ui4</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>LastConnectionError</name>
<dataType>string</dataType>
<allowedValueList>
<allowedValue>ERROR_NONE</allowedValue>
<allowedValue>ERROR_UNKNOWN</allowedValue>
</allowedValueList>
</stateVariable>
<stateVariable sendEvents="no">
<name>RSIPAvailable</name>
<dataType>boolean</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>NATEnabled</name>
<dataType>boolean</dataType>
</stateVariable>
<stateVariable sendEvents="yes">
<name>ExternalIPAddress</name>
<dataType>string</dataType>
</stateVariable>
<stateVariable sendEvents="yes">
<name>PortMappingNumberOfEntries</name>
<dataType>ui2</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>PortMappingEnabled</name>
<dataType>boolean</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>PortMappingLeaseDuration</name>
<dataType>ui4</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>RemoteHost</name>
<dataType>string</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>ExternalPort</name>
<dataType>ui2</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>InternalPort</name>
<dataType>ui2</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>PortMappingProtocol</name>
<dataType>string</dataType>
<allowedValueList>
<allowedValue>TCP</allowedValue>
<allowedValue>UDP</allowedValue>
</allowedValueList>
</stateVariable>
<stateVariable sendEvents="no">
<name>InternalClient</name>
<dataType>string</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>PortMappingDescription</name>
<dataType>string</dataType>
</stateVariable>
</serviceStateTable>
</scpd>

2014-11-11 14:48:55,665 [services.UPNPService:164] DEBUG - Found SCPD major,minor version 1,0
2014-11-11 14:48:55,665 [services.UPNPService:228] DEBUG - child stateVariable count is 17.0
2014-11-11 14:48:55,665 [services.UPNPService:259] DEBUG - child allowedValue count is 3.0
2014-11-11 14:48:55,665 [services.UPNPService:259] DEBUG - child allowedValue count is 3.0
2014-11-11 14:48:55,665 [services.UPNPService:259] DEBUG - child allowedValue count is 2.0
2014-11-11 14:48:55,680 [services.UPNPService:259] DEBUG - child allowedValue count is 2.0
2014-11-11 14:48:55,680 [services.UPNPService:172] DEBUG - child action count is 11.0
2014-11-11 14:48:55,696 [services.UPNPService:186] DEBUG - Processing name,argumentList of SetConnectionType,/UPNPService/upnp:scpd[1]/upnp:actionList[1]/upnp:action[1]/upnp:argumentList[1]
2014-11-11 14:48:55,696 [services.UPNPService:191] DEBUG - number of argument elements is 1.0
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewConnectionType,in
2014-11-11 14:48:55,696 [services.UPNPService:186] DEBUG - Processing name,argumentList of GetConnectionTypeInfo,/UPNPService/upnp:scpd[1]/upnp:actionList[1]/upnp:action[2]/upnp:argumentList[1]
2014-11-11 14:48:55,696 [services.UPNPService:191] DEBUG - number of argument elements is 2.0
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewConnectionType,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewPossibleConnectionTypes,out
2014-11-11 14:48:55,696 [services.UPNPService:186] DEBUG - Processing name,argumentList of RequestConnection,null
2014-11-11 14:48:55,696 [services.UPNPService:186] DEBUG - Processing name,argumentList of ForceTermination,null
2014-11-11 14:48:55,696 [services.UPNPService:186] DEBUG - Processing name,argumentList of GetStatusInfo,/UPNPService/upnp:scpd[1]/upnp:actionList[1]/upnp:action[5]/upnp:argumentList[1]
2014-11-11 14:48:55,696 [services.UPNPService:191] DEBUG - number of argument elements is 3.0
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewConnectionStatus,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewLastConnectionError,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewUptime,out
2014-11-11 14:48:55,696 [services.UPNPService:186] DEBUG - Processing name,argumentList of GetNATRSIPStatus,/UPNPService/upnp:scpd[1]/upnp:actionList[1]/upnp:action[6]/upnp:argumentList[1]
2014-11-11 14:48:55,696 [services.UPNPService:191] DEBUG - number of argument elements is 2.0
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewRSIPAvailable,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewNATEnabled,out
2014-11-11 14:48:55,696 [services.UPNPService:186] DEBUG - Processing name,argumentList of GetGenericPortMappingEntry,/UPNPService/upnp:scpd[1]/upnp:actionList[1]/upnp:action[7]/upnp:argumentList[1]
2014-11-11 14:48:55,696 [services.UPNPService:191] DEBUG - number of argument elements is 9.0
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewPortMappingIndex,in
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewRemoteHost,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewExternalPort,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewProtocol,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewInternalPort,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewInternalClient,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewEnabled,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewPortMappingDescription,out
2014-11-11 14:48:55,696 [services.UPNPService:209] DEBUG - found argument name,direction of NewLeaseDuration,out
2014-11-11 14:48:55,712 [services.UPNPService:186] DEBUG - Processing name,argumentList of GetSpecificPortMappingEntry,/UPNPService/upnp:scpd[1]/upnp:actionList[1]/upnp:action[8]/upnp:argumentList[1]
2014-11-11 14:48:55,712 [services.UPNPService:191] DEBUG - number of argument elements is 8.0
2014-11-11 14:48:55,712 [services.UPNPService:209] DEBUG - found argument name,direction of NewRemoteHost,in
2014-11-11 14:48:55,713 [services.UPNPService:209] DEBUG - found argument name,direction of NewExternalPort,in
2014-11-11 14:48:55,713 [services.UPNPService:209] DEBUG - found argument name,direction of NewProtocol,in
2014-11-11 14:48:55,713 [services.UPNPService:209] DEBUG - found argument name,direction of NewInternalPort,out
2014-11-11 14:48:55,714 [services.UPNPService:209] DEBUG - found argument name,direction of NewInternalClient,out
2014-11-11 14:48:55,714 [services.UPNPService:209] DEBUG - found argument name,direction of NewEnabled,out
2014-11-11 14:48:55,714 [services.UPNPService:209] DEBUG - found argument name,direction of NewPortMappingDescription,out
2014-11-11 14:48:55,715 [services.UPNPService:209] DEBUG - found argument name,direction of NewLeaseDuration,out
2014-11-11 14:48:55,715 [services.UPNPService:186] DEBUG - Processing name,argumentList of AddPortMapping,/UPNPService/upnp:scpd[1]/upnp:actionList[1]/upnp:action[9]/upnp:argumentList[1]
2014-11-11 14:48:55,715 [services.UPNPService:191] DEBUG - number of argument elements is 8.0
2014-11-11 14:48:55,716 [services.UPNPService:209] DEBUG - found argument name,direction of NewRemoteHost,in
2014-11-11 14:48:55,716 [services.UPNPService:209] DEBUG - found argument name,direction of NewExternalPort,in
2014-11-11 14:48:55,716 [services.UPNPService:209] DEBUG - found argument name,direction of NewProtocol,in
2014-11-11 14:48:55,716 [services.UPNPService:209] DEBUG - found argument name,direction of NewInternalPort,in
2014-11-11 14:48:55,717 [services.UPNPService:209] DEBUG - found argument name,direction of NewInternalClient,in
2014-11-11 14:48:55,717 [services.UPNPService:209] DEBUG - found argument name,direction of NewEnabled,in
2014-11-11 14:48:55,717 [services.UPNPService:209] DEBUG - found argument name,direction of NewPortMappingDescription,in
2014-11-11 14:48:55,718 [services.UPNPService:209] DEBUG - found argument name,direction of NewLeaseDuration,in
2014-11-11 14:48:55,718 [services.UPNPService:186] DEBUG - Processing name,argumentList of DeletePortMapping,/UPNPService/upnp:scpd[1]/upnp:actionList[1]/upnp:action[10]/upnp:argumentList[1]
2014-11-11 14:48:55,718 [services.UPNPService:191] DEBUG - number of argument elements is 3.0
2014-11-11 14:48:55,718 [services.UPNPService:209] DEBUG - found argument name,direction of NewRemoteHost,in
2014-11-11 14:48:55,718 [services.UPNPService:209] DEBUG - found argument name,direction of NewExternalPort,in
2014-11-11 14:48:55,718 [services.UPNPService:209] DEBUG - found argument name,direction of NewProtocol,in
2014-11-11 14:48:55,718 [services.UPNPService:186] DEBUG - Processing name,argumentList of GetExternalIPAddress,/UPNPService/upnp:scpd[1]/upnp:actionList[1]/upnp:action[11]/upnp:argumentList[1]
2014-11-11 14:48:55,718 [services.UPNPService:191] DEBUG - number of argument elements is 1.0
2014-11-11 14:48:55,718 [services.UPNPService:209] DEBUG - found argument name,direction of NewExternalIPAddress,out
2014-11-11 14:48:55,718 [messages.ActionMessage:111] DEBUG - POST prepared for URL http://10.0.0.1:80/WANIPConnection
2014-11-11 14:48:55,718 [messages.ActionMessage:132] DEBUG - executing query :
<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:AddPortMapping xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1"><NewRemoteHost></NewRemoteHost><NewExternalPort>51235</NewExternalPort><NewProtocol>TCP</NewProtocol><NewInternalPort>51235</NewInternalPort><NewInternalClient>10.0.0.5</NewInternalClient><NewEnabled>1</NewEnabled><NewPortMappingDescription>MapTool</NewPortMappingDescription><NewLeaseDuration>0</NewLeaseDuration></u:AddPortMapping></s:Body></s:Envelope>
2014-11-11 14:48:55,833 [messages.ActionMessage:146] DEBUG - received response :
<?xml version="1.0" encoding="utf-8"?>
<s:Envelope
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"
s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<m:AddPortMappingResponse xmlns:m="urn:schemas-upnp-org:service:WANIPConnection:1">
</m:AddPortMappingResponse>
</s:Body>
</s:Envelope>
2014-11-11 14:48:55,848 [util.UPnPUtil:168] INFO - UPnP: Port 51235 mapped on Realtek PCIe GBE Family Controller at address 10.0.0.5
2014-11-11 14:52:50,993 [messages.ActionMessage:111] DEBUG - POST prepared for URL http://10.0.0.1:80/WANIPConnection
2014-11-11 14:52:50,997 [messages.ActionMessage:132] DEBUG - executing query :
<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetSpecificPortMappingEntry xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1"><NewRemoteHost></NewRemoteHost><NewExternalPort>51235</NewExternalPort><NewProtocol>TCP</NewProtocol></u:GetSpecificPortMappingEntry></s:Body></s:Envelope>
2014-11-11 14:52:51,111 [messages.ActionMessage:146] DEBUG - received response :
<?xml version="1.0" encoding="utf-8"?>
<s:Envelope
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"
s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<m:GetSpecificPortMappingEntryResponse xmlns:m="urn:schemas-upnp-org:service:WANIPConnection:1">
<NewInternalPort>51235</NewInternalPort><NewInternalClient>10.0.0.5</NewInternalClient><NewEnabled>1</NewEnabled><NewPortMappingDescription>MapTool</NewPortMappingDescription><NewLeaseDuration>0</NewLeaseDuration></m:GetSpecificPortMappingEntryResponse>
</s:Body>
</s:Envelope>
2014-11-11 14:52:51,114 [messages.ActionMessage:111] DEBUG - POST prepared for URL http://10.0.0.1:80/WANIPConnection
2014-11-11 14:52:51,118 [messages.ActionMessage:132] DEBUG - executing query :
<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:DeletePortMapping xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1"><NewRemoteHost></NewRemoteHost><NewExternalPort>51235</NewExternalPort><NewProtocol>TCP</NewProtocol></u:DeletePortMapping></s:Body></s:Envelope>
2014-11-11 14:52:51,228 [messages.ActionMessage:146] DEBUG - received response :
<?xml version="1.0" encoding="utf-8"?>
<s:Envelope
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"
s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<m:DeletePortMappingResponse xmlns:m="urn:schemas-upnp-org:service:WANIPConnection:1">
</m:DeletePortMappingResponse>
</s:Body>
</s:Envelope>
2014-11-11 14:52:51,230 [swing.MapToolEventQueue:43] ERROR - java.lang.NullPointerException
java.lang.NullPointerException
	at net.rptools.maptool.util.UPnPUtil.closePort(UPnPUtil.java:202)
	at net.rptools.maptool.client.MapTool.disconnect(MapTool.java:1058)
	at net.rptools.maptool.client.MapTool.stopServer(MapTool.java:942)
	at net.rptools.maptool.client.AppActions.disconnectFromServer(AppActions.java:2012)
	at net.rptools.maptool.client.AppActions$53.execute(AppActions.java:2004)
	at net.rptools.maptool.client.AppActions$ClientAction.actionPerformed(AppActions.java:2802)
	at javax.swing.AbstractButton.fireActionPerformed(Unknown Source)
	at javax.swing.AbstractButton$Handler.actionPerformed(Unknown Source)
	at javax.swing.DefaultButtonModel.fireActionPerformed(Unknown Source)
	at javax.swing.DefaultButtonModel.setPressed(Unknown Source)
	at javax.swing.AbstractButton.doClick(Unknown Source)
	at de.muntjak.tinylookandfeel.TinyMenuItemUI.doClick(Unknown Source)
	at de.muntjak.tinylookandfeel.TinyMenuItemUI$MouseInputHandler.mouseReleased(Unknown Source)
	at java.awt.Component.processMouseEvent(Unknown Source)
	at javax.swing.JComponent.processMouseEvent(Unknown Source)
	at java.awt.Component.processEvent(Unknown Source)
	at java.awt.Container.processEvent(Unknown Source)
	at java.awt.Component.dispatchEventImpl(Unknown Source)
	at java.awt.Container.dispatchEventImpl(Unknown Source)
	at java.awt.Component.dispatchEvent(Unknown Source)
	at java.awt.LightweightDispatcher.retargetMouseEvent(Unknown Source)
	at java.awt.LightweightDispatcher.processMouseEvent(Unknown Source)
	at java.awt.LightweightDispatcher.dispatchEvent(Unknown Source)
	at java.awt.Container.dispatchEventImpl(Unknown Source)
	at java.awt.Window.dispatchEventImpl(Unknown Source)
	at java.awt.Component.dispatchEvent(Unknown Source)
	at java.awt.EventQueue.dispatchEventImpl(Unknown Source)
	at java.awt.EventQueue.access$400(Unknown Source)
	at java.awt.EventQueue$3.run(Unknown Source)
	at java.awt.EventQueue$3.run(Unknown Source)
	at java.security.AccessController.doPrivileged(Native Method)
	at java.security.ProtectionDomain$1.doIntersectionPrivilege(Unknown Source)
	at java.security.ProtectionDomain$1.doIntersectionPrivilege(Unknown Source)
	at java.awt.EventQueue$4.run(Unknown Source)
	at java.awt.EventQueue$4.run(Unknown Source)
	at java.security.AccessController.doPrivileged(Native Method)
	at java.security.ProtectionDomain$1.doIntersectionPrivilege(Unknown Source)
	at java.awt.EventQueue.dispatchEvent(Unknown Source)
	at net.rptools.maptool.client.swing.MapToolEventQueue.dispatchEvent(MapToolEventQueue.java:36)
	at java.awt.EventDispatchThread.pumpOneEventForFilters(Unknown Source)
	at java.awt.EventDispatchThread.pumpEventsForFilter(Unknown Source)
	at java.awt.EventDispatchThread.pumpEventsForHierarchy(Unknown Source)
	at java.awt.EventDispatchThread.pumpEvents(Unknown Source)
	at java.awt.EventDispatchThread.pumpEvents(Unknown Source)
	at java.awt.EventDispatchThread.run(Unknown Source)
Start MapTool B89
Connect to Server and Cancel (I see I'm still running a server)
Close MapTool B89
Start MapTool B91
Start Server (No Error)
Disconnect from Server
Connect to Server and Cancel (No longer in list)
Close MapTool B91
log.txt

Code: Select all

2014-11-11 15:04:23,129 [util.UPnPUtil:91] INFO - UPnP:  Trying interface Microsoft Teredo Tunneling Adapter
2014-11-11 15:04:28,168 [util.UPnPUtil:120] INFO - Found 0 IGDs on interface Microsoft Teredo Tunneling Adapter
2014-11-11 15:04:28,172 [util.UPnPUtil:91] INFO - UPnP:  Trying interface Realtek PCIe GBE Family Controller
2014-11-11 15:04:28,189 [upnp.Discovery:246] DEBUG - Sending discovery message on 239.255.255.250:1900 multicast address from ip 10.0.0.5:
M-SEARCH * HTTP/1.1
HOST: 239.255.255.250:1900
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1
MAN: "ssdp:discover"
MX: 3


2014-11-11 15:04:29,461 [upnp.DiscoveryListener:199] DEBUG - Skipping non-compliant HTTP message NULL (2048 times)
2014-11-11 15:04:33,195 [util.UPnPUtil:120] INFO - Found 0 IGDs on interface Realtek PCIe GBE Family Controller
2014-11-11 15:04:33,217 [client.MapTool:298] ERROR - UPnP Error - No Internet Gateway Devices found.<br><br>UPnP port mapping will not be available.
2014-11-11 15:04:33,281 [upnp.DiscoveryListener:175] ERROR - IO Exception during UPNP DiscoveryListener messages listening thread
java.net.SocketException: socket closed
	at java.net.TwoStacksPlainDatagramSocketImpl.receive0(Native Method)
	at java.net.TwoStacksPlainDatagramSocketImpl.receive(Unknown Source)
	at java.net.DatagramSocket.receive(Unknown Source)
	at net.sbbi.upnp.DiscoveryListener.listenBroadCast(DiscoveryListener.java:190)
	at net.sbbi.upnp.DiscoveryListener.run(DiscoveryListener.java:171)
	at java.lang.Thread.run(Unknown Source)
Eventhough I didn't get an error the log shows NULL (2048 times) for discovery listener.

User avatar
aliasmask
RPTools Team
Posts: 9024
Joined: Tue Nov 10, 2009 6:11 pm
Location: Bay Area

Re: [b91] Unable to find UPnP services

Post by aliasmask »

Continued...


Start MapTool B89
Connect to Server and Cancel (verify I am not running a server)
Start Server
Check Server Info
Disconnect
(I realize I have no logging info for B89 so I paste the logging.xml file from B91 in to .maptool directory)
Start Server
Check Server Info
Disconnect
(Still no logging info)
Close MapTool B89
Start MapTool B89
(I notice logging.xml is removed from .maptool folder)
Close MapTool B89
(I add logging folder to B89)
Start MapTool B89
Start Server
Check Server Info
Disconnect
log.txt

Code: Select all

2014-11-11 15:22:31,704 [upnp.Discovery:273] DEBUG - Sending discovery message on 239.255.255.250:1900 multicast address form ip 10.0.0.5:
M-SEARCH * HTTP/1.1
HOST: 239.255.255.250:1900
MAN: "ssdp:discover"
MX: 3
ST: urn:schemas-upnp-org:device:InternetGatewayDevice:1


2014-11-11 15:22:32,716 [upnp.DiscoveryListener:253] DEBUG - Processing http://10.0.0.1:80/RootDevice.xml device description location
2014-11-11 15:22:32,900 [upnp.JXPathParser:74] DEBUG - Readen raw xml doc:
<?xml version="1.0" encoding="utf-8"?>
<root xmlns="urn:schemas-upnp-org:device-1-0">
<specVersion>
<major>1</major>
<minor>0</minor>
</specVersion>
<URLBase>http://10.0.0.1:80</URLBase><device>
<deviceType>urn:schemas-upnp-org:device:InternetGatewayDevice:1</deviceType>
<friendlyName>Technicolor TC8305C</friendlyName>
<manufacturer>Technicolor</manufacturer>
<manufacturerURL>http://www.technicolor.com</manufacturerURL>
<modelDescription>TC8305C</modelDescription>
<modelName>TC8305C</modelName>
<modelNumber>TC8305C</modelNumber>
<modelURL>http://customer.comcast.com/Pages/HelpNFC.aspx?id=e70e9d0e-d9eb-4748-b163-636a323e7950</modelURL>
<serialNumber>1329212555</serialNumber>
<UDN>uuid:8e62b38b-f3c0-07e6-288f-cc3540420108</UDN>
<UPC>123456789012</UPC>
<serviceList>
<service>
<serviceType>urn:schemas-upnp-org:service:Layer3Forwarding:1</serviceType>
<serviceId>urn:upnp-org:serviceId:L3Forwarding1</serviceId>
<SCPDURL>/Layer3Forwarding.xml</SCPDURL>
<controlURL>/Layer3Forwarding</controlURL>
<eventSubURL>/Layer3Forwarding</eventSubURL>
</service>
</serviceList>
<deviceList>
<device>
<deviceType>urn:schemas-upnp-org:device:WANDevice:1</deviceType>
<friendlyName>Technicolor TC8305C</friendlyName>
<manufacturer>Technicolor</manufacturer>
<manufacturerURL>http://www.technicolor.com</manufacturerURL>
<modelDescription>TC8305C</modelDescription>
<modelName>TC8305C</modelName>
<modelNumber>TC8305C</modelNumber>
<modelURL>http://customer.comcast.com/Pages/HelpNFC.aspx?id=e70e9d0e-d9eb-4748-b163-636a323e7950</modelURL>
<serialNumber>1329212555</serialNumber>
<UDN>uuid:463b460d-3c29-8e33-4af7-cc3540420108</UDN>
<UPC>123456789012</UPC>
<serviceList>
<service>
<serviceType>urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1</serviceType>
<serviceId>urn:upnp-org:serviceId:WANCommonIFC1</serviceId>
<SCPDURL>/WANCommonInterfaceConfig.xml</SCPDURL>
<controlURL>/WANCommonInterfaceConfig</controlURL>
<eventSubURL>/WANCommonInterfaceConfig</eventSubURL>
</service>
</serviceList>
<deviceList>
<device>
<deviceType>urn:schemas-upnp-org:device:WANConnectionDevice:1</deviceType>
<friendlyName>Technicolor TC8305C</friendlyName>
<manufacturer>Technicolor</manufacturer>
<manufacturerURL>http://www.technicolor.com</manufacturerURL>
<modelDescription>TC8305C</modelDescription>
<modelName>TC8305C</modelName>
<modelNumber>TC8305C</modelNumber>
<modelURL>http://customer.comcast.com/Pages/HelpNFC.aspx?id=e70e9d0e-d9eb-4748-b163-636a323e7950</modelURL>
<serialNumber>1329212555</serialNumber>
<UDN>uuid:bc36cead-42f6-dd55-0824-cc3540420108</UDN>
<UPC>123456789012</UPC>
<serviceList>
<service>
<serviceType>urn:schemas-upnp-org:service:WANIPConnection:1</serviceType>
<serviceId>urn:upnp-org:serviceId:WANIPConn1</serviceId>
<SCPDURL>/WANIPConnection.xml</SCPDURL>
<controlURL>/WANIPConnection</controlURL>
<eventSubURL>/WANIPConnection</eventSubURL>
</service>
</serviceList>
</device>
</deviceList>
</device>
</deviceList>
<presentationURL>/</presentationURL>
</device>
</root>

2014-11-11 15:22:32,916 [devices.UPNPRootDevice:171] DEBUG - device URLBase http://10.0.0.1:80
2014-11-11 15:22:32,916 [devices.UPNPRootDevice:275] DEBUG - parsing device urn:schemas-upnp-org:device:InternetGatewayDevice:1
2014-11-11 15:22:32,935 [devices.UPNPRootDevice:371] DEBUG - device services count is 1.0
2014-11-11 15:22:32,944 [devices.UPNPRootDevice:321] DEBUG - child devices count is 1.0
2014-11-11 15:22:32,944 [devices.UPNPRootDevice:275] DEBUG - parsing device urn:schemas-upnp-org:device:WANDevice:1
2014-11-11 15:22:32,945 [devices.UPNPRootDevice:371] DEBUG - device services count is 1.0
2014-11-11 15:22:32,947 [devices.UPNPRootDevice:321] DEBUG - child devices count is 1.0
2014-11-11 15:22:32,947 [devices.UPNPRootDevice:275] DEBUG - parsing device urn:schemas-upnp-org:device:WANConnectionDevice:1
2014-11-11 15:22:32,948 [devices.UPNPRootDevice:371] DEBUG - device services count is 1.0
2014-11-11 15:22:32,949 [devices.UPNPRootDevice:330] DEBUG - adding child device urn:schemas-upnp-org:device:WANConnectionDevice:1
2014-11-11 15:22:32,949 [devices.UPNPRootDevice:330] DEBUG - adding child device urn:schemas-upnp-org:device:WANDevice:1
2014-11-11 15:22:36,716 [devices.UPNPDevice:208] DEBUG - searching for device URI:urn:schemas-upnp-org:device:WANConnectionDevice:1
2014-11-11 15:22:36,716 [devices.UPNPDevice:208] DEBUG - searching for device URI:urn:schemas-upnp-org:device:WANConnectionDevice:1
2014-11-11 15:22:36,716 [devices.UPNPDevice:208] DEBUG - searching for device URI:urn:schemas-upnp-org:device:WANConnectionDevice:1
2014-11-11 15:22:36,716 [devices.UPNPDevice:239] DEBUG - searching for service URI:urn:schemas-upnp-org:service:WANIPConnection:1
2014-11-11 15:22:36,716 [devices.UPNPDevice:239] DEBUG - searching for service URI:urn:schemas-upnp-org:service:WANPPPConnection:1
2014-11-11 15:22:36,716 [util.UPnPUtil:46] INFO - UPnP:  Found device: TC8305C
2014-11-11 15:22:36,885 [upnp.JXPathParser:74] DEBUG - Readen raw xml doc:
<?xml version="1.0" encoding="utf-8"?>
<scpd xmlns="urn:schemas-upnp-org:service-1-0">
<specVersion>
<major>1</major>
<minor>0</minor>
</specVersion>
<actionList>
<action>
<name>SetConnectionType</name>
<argumentList>
<argument>
<name>NewConnectionType</name>
<direction>in</direction>
<relatedStateVariable>ConnectionType</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>GetConnectionTypeInfo</name>
<argumentList>
<argument>
<name>NewConnectionType</name>
<direction>out</direction>
<relatedStateVariable>ConnectionType</relatedStateVariable>
</argument>
<argument>
<name>NewPossibleConnectionTypes</name>
<direction>out</direction>
<relatedStateVariable>PossibleConnectionTypes</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>RequestConnection</name>
</action>
<action>
<name>ForceTermination</name>
</action>
<action>
<name>GetStatusInfo</name>
<argumentList>
<argument>
<name>NewConnectionStatus</name>
<direction>out</direction>
<relatedStateVariable>ConnectionStatus</relatedStateVariable>
</argument>
<argument>
<name>NewLastConnectionError</name>
<direction>out</direction>
<relatedStateVariable>LastConnectionError</relatedStateVariable>
</argument>
<argument>
<name>NewUptime</name>
<direction>out</direction>
<relatedStateVariable>Uptime</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>GetNATRSIPStatus</name>
<argumentList>
<argument>
<name>NewRSIPAvailable</name>
<direction>out</direction>
<relatedStateVariable>RSIPAvailable</relatedStateVariable>
</argument>
<argument>
<name>NewNATEnabled</name>
<direction>out</direction>
<relatedStateVariable>NATEnabled</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>GetGenericPortMappingEntry</name>
<argumentList>
<argument>
<name>NewPortMappingIndex</name>
<direction>in</direction>
<relatedStateVariable>PortMappingNumberOfEntries</relatedStateVariable>
</argument>
<argument>
<name>NewRemoteHost</name>
<direction>out</direction>
<relatedStateVariable>RemoteHost</relatedStateVariable>
</argument>
<argument>
<name>NewExternalPort</name>
<direction>out</direction>
<relatedStateVariable>ExternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewProtocol</name>
<direction>out</direction>
<relatedStateVariable>PortMappingProtocol</relatedStateVariable>
</argument>
<argument>
<name>NewInternalPort</name>
<direction>out</direction>
<relatedStateVariable>InternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewInternalClient</name>
<direction>out</direction>
<relatedStateVariable>InternalClient</relatedStateVariable>
</argument>
<argument>
<name>NewEnabled</name>
<direction>out</direction>
<relatedStateVariable>PortMappingEnabled</relatedStateVariable>
</argument>
<argument>
<name>NewPortMappingDescription</name>
<direction>out</direction>
<relatedStateVariable>PortMappingDescription</relatedStateVariable>
</argument>
<argument>
<name>NewLeaseDuration</name>
<direction>out</direction>
<relatedStateVariable>PortMappingLeaseDuration</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>GetSpecificPortMappingEntry</name>
<argumentList>
<argument>
<name>NewRemoteHost</name>
<direction>in</direction>
<relatedStateVariable>RemoteHost</relatedStateVariable>
</argument>
<argument>
<name>NewExternalPort</name>
<direction>in</direction>
<relatedStateVariable>ExternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewProtocol</name>
<direction>in</direction>
<relatedStateVariable>PortMappingProtocol</relatedStateVariable>
</argument>
<argument>
<name>NewInternalPort</name>
<direction>out</direction>
<relatedStateVariable>InternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewInternalClient</name>
<direction>out</direction>
<relatedStateVariable>InternalClient</relatedStateVariable>
</argument>
<argument>
<name>NewEnabled</name>
<direction>out</direction>
<relatedStateVariable>PortMappingEnabled</relatedStateVariable>
</argument>
<argument>
<name>NewPortMappingDescription</name>
<direction>out</direction>
<relatedStateVariable>PortMappingDescription</relatedStateVariable>
</argument>
<argument>
<name>NewLeaseDuration</name>
<direction>out</direction>
<relatedStateVariable>PortMappingLeaseDuration</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>AddPortMapping</name>
<argumentList>
<argument>
<name>NewRemoteHost</name>
<direction>in</direction>
<relatedStateVariable>RemoteHost</relatedStateVariable>
</argument>
<argument>
<name>NewExternalPort</name>
<direction>in</direction>
<relatedStateVariable>ExternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewProtocol</name>
<direction>in</direction>
<relatedStateVariable>PortMappingProtocol</relatedStateVariable>
</argument>
<argument>
<name>NewInternalPort</name>
<direction>in</direction>
<relatedStateVariable>InternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewInternalClient</name>
<direction>in</direction>
<relatedStateVariable>InternalClient</relatedStateVariable>
</argument>
<argument>
<name>NewEnabled</name>
<direction>in</direction>
<relatedStateVariable>PortMappingEnabled</relatedStateVariable>
</argument>
<argument>
<name>NewPortMappingDescription</name>
<direction>in</direction>
<relatedStateVariable>PortMappingDescription</relatedStateVariable>
</argument>
<argument>
<name>NewLeaseDuration</name>
<direction>in</direction>
<relatedStateVariable>PortMappingLeaseDuration</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>DeletePortMapping</name>
<argumentList>
<argument>
<name>NewRemoteHost</name>
<direction>in</direction>
<relatedStateVariable>RemoteHost</relatedStateVariable>
</argument>
<argument>
<name>NewExternalPort</name>
<direction>in</direction>
<relatedStateVariable>ExternalPort</relatedStateVariable>
</argument>
<argument>
<name>NewProtocol</name>
<direction>in</direction>
<relatedStateVariable>PortMappingProtocol</relatedStateVariable>
</argument>
</argumentList>
</action>
<action>
<name>GetExternalIPAddress</name>
<argumentList>
<argument>
<name>NewExternalIPAddress</name>
<direction>out</direction>
<relatedStateVariable>ExternalIPAddress</relatedStateVariable>
</argument>
</argumentList>
</action>
</actionList>
<serviceStateTable>
<stateVariable sendEvents="no">
<name>ConnectionType</name>
<dataType>string</dataType>
</stateVariable>
<stateVariable sendEvents="yes">
<name>PossibleConnectionTypes</name>
<dataType>string</dataType>
<allowedValueList>
<allowedValue>Unconfigured</allowedValue>
<allowedValue>IP_Routed</allowedValue>
<allowedValue>IP_Bridged</allowedValue>
</allowedValueList>
</stateVariable>
<stateVariable sendEvents="yes">
<name>ConnectionStatus</name>
<dataType>string</dataType>
<allowedValueList>
<allowedValue>Unconfigured</allowedValue>
<allowedValue>Connected</allowedValue>
<allowedValue>Disconnected</allowedValue>
</allowedValueList>
</stateVariable>
<stateVariable sendEvents="no">
<name>Uptime</name>
<dataType>ui4</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>LastConnectionError</name>
<dataType>string</dataType>
<allowedValueList>
<allowedValue>ERROR_NONE</allowedValue>
<allowedValue>ERROR_UNKNOWN</allowedValue>
</allowedValueList>
</stateVariable>
<stateVariable sendEvents="no">
<name>RSIPAvailable</name>
<dataType>boolean</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>NATEnabled</name>
<dataType>boolean</dataType>
</stateVariable>
<stateVariable sendEvents="yes">
<name>ExternalIPAddress</name>
<dataType>string</dataType>
</stateVariable>
<stateVariable sendEvents="yes">
<name>PortMappingNumberOfEntries</name>
<dataType>ui2</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>PortMappingEnabled</name>
<dataType>boolean</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>PortMappingLeaseDuration</name>
<dataType>ui4</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>RemoteHost</name>
<dataType>string</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>ExternalPort</name>
<dataType>ui2</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>InternalPort</name>
<dataType>ui2</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>PortMappingProtocol</name>
<dataType>string</dataType>
<allowedValueList>
<allowedValue>TCP</allowedValue>
<allowedValue>UDP</allowedValue>
</allowedValueList>
</stateVariable>
<stateVariable sendEvents="no">
<name>InternalClient</name>
<dataType>string</dataType>
</stateVariable>
<stateVariable sendEvents="no">
<name>PortMappingDescription</name>
<dataType>string</dataType>
</stateVariable>
</serviceStateTable>
</scpd>

2014-11-11 15:22:36,956 [messages.ActionMessage:130] DEBUG - POST prepared for URL http://10.0.0.1:80/WANIPConnection
2014-11-11 15:22:36,959 [messages.ActionMessage:150] DEBUG - executing query :
<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:AddPortMapping xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1"><NewRemoteHost></NewRemoteHost><NewExternalPort>51235</NewExternalPort><NewProtocol>TCP</NewProtocol><NewInternalPort>51235</NewInternalPort><NewInternalClient>10.0.0.5</NewInternalClient><NewEnabled>1</NewEnabled><NewPortMappingDescription>MapTool</NewPortMappingDescription><NewLeaseDuration>0</NewLeaseDuration></u:AddPortMapping></s:Body></s:Envelope>
2014-11-11 15:22:37,070 [messages.ActionMessage:163] DEBUG - received response :
<?xml version="1.0" encoding="utf-8"?>
<s:Envelope
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"
s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<m:AddPortMappingResponse xmlns:m="urn:schemas-upnp-org:service:WANIPConnection:1">
</m:AddPortMappingResponse>
</s:Body>
</s:Envelope>
2014-11-11 15:22:37,070 [util.UPnPUtil:84] INFO - UPnP: Port 51235 mapped
2014-11-11 15:23:13,560 [messages.ActionMessage:130] DEBUG - POST prepared for URL http://10.0.0.1:80/WANIPConnection
2014-11-11 15:23:13,564 [messages.ActionMessage:150] DEBUG - executing query :
<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetSpecificPortMappingEntry xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1"><NewRemoteHost></NewRemoteHost><NewExternalPort>51235</NewExternalPort><NewProtocol>TCP</NewProtocol></u:GetSpecificPortMappingEntry></s:Body></s:Envelope>
2014-11-11 15:23:13,677 [messages.ActionMessage:163] DEBUG - received response :
<?xml version="1.0" encoding="utf-8"?>
<s:Envelope
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"
s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<m:GetSpecificPortMappingEntryResponse xmlns:m="urn:schemas-upnp-org:service:WANIPConnection:1">
<NewInternalPort>51235</NewInternalPort><NewInternalClient>10.0.0.5</NewInternalClient><NewEnabled>1</NewEnabled><NewPortMappingDescription>MapTool</NewPortMappingDescription><NewLeaseDuration>0</NewLeaseDuration></m:GetSpecificPortMappingEntryResponse>
</s:Body>
</s:Envelope>
2014-11-11 15:23:13,682 [messages.ActionMessage:130] DEBUG - POST prepared for URL http://10.0.0.1:80/WANIPConnection
2014-11-11 15:23:13,685 [messages.ActionMessage:150] DEBUG - executing query :
<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:DeletePortMapping xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1"><NewRemoteHost></NewRemoteHost><NewExternalPort>51235</NewExternalPort><NewProtocol>TCP</NewProtocol></u:DeletePortMapping></s:Body></s:Envelope>
2014-11-11 15:23:13,794 [messages.ActionMessage:163] DEBUG - received response :
<?xml version="1.0" encoding="utf-8"?>
<s:Envelope
xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"
s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<m:DeletePortMappingResponse xmlns:m="urn:schemas-upnp-org:service:WANIPConnection:1">
</m:DeletePortMappingResponse>
</s:Body>
</s:Envelope>
2014-11-11 15:23:13,797 [util.UPnPUtil:108] INFO - UPnP: Port unmapped
2014-11-11 15:23:14,131 [messages.ActionMessage:130] DEBUG - POST prepared for URL http://10.0.0.1:80/WANIPConnection
2014-11-11 15:23:14,124 [messages.ActionMessage:130] DEBUG - POST prepared for URL http://10.0.0.1:80/WANIPConnection
2014-11-11 15:23:14,138 [messages.ActionMessage:150] DEBUG - executing query :
<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetSpecificPortMappingEntry xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1"><NewRemoteHost></NewRemoteHost><NewExternalPort>51235</NewExternalPort><NewProtocol>TCP</NewProtocol></u:GetSpecificPortMappingEntry></s:Body></s:Envelope>
2014-11-11 15:23:14,139 [messages.ActionMessage:150] DEBUG - executing query :
<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetSpecificPortMappingEntry xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1"><NewRemoteHost></NewRemoteHost><NewExternalPort>51235</NewExternalPort><NewProtocol>TCP</NewProtocol></u:GetSpecificPortMappingEntry></s:Body></s:Envelope>
2014-11-11 15:23:14,234 [messages.ActionMessage:163] DEBUG - received response :
<?xml version="1.0" encoding="utf-8"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><s:Fault><faultcode>s:Client</faultcode><faultstring>UPnPError</faultstring><detail><UPnPError xmlns="urn:schemas-upnp-org:control-1-0"><errorCode>714</errorCode><errorDescription>The specified value does not exist in the array.</errorDescription></UPnPError></detail></s:Fault></s:Body></s:Envelope>
2014-11-11 15:23:14,265 [messages.ActionMessage:163] DEBUG - received response :
<?xml version="1.0" encoding="utf-8"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><s:Fault><faultcode>s:Client</faultcode><faultstring>UPnPError</faultstring><detail><UPnPError xmlns="urn:schemas-upnp-org:control-1-0"><errorCode>714</errorCode><errorDescription>The specified value does not exist in the array.</errorDescription></UPnPError></detail></s:Fault></s:Body></s:Envelope>
I find this part interesting, but it could be just verifying I'm still not connected to server:

Close MapTool B89
log.txt

Code: Select all

2014-11-11 15:32:34,511 [messages.ActionMessage:130] DEBUG - POST prepared for URL http://10.0.0.1:80/WANIPConnection
2014-11-11 15:32:34,511 [messages.ActionMessage:150] DEBUG - executing query :
<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetSpecificPortMappingEntry xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1"><NewRemoteHost></NewRemoteHost><NewExternalPort>51235</NewExternalPort><NewProtocol>TCP</NewProtocol></u:GetSpecificPortMappingEntry></s:Body></s:Envelope>
2014-11-11 15:32:34,627 [messages.ActionMessage:163] DEBUG - received response :
<?xml version="1.0" encoding="utf-8"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><s:Fault><faultcode>s:Client</faultcode><faultstring>UPnPError</faultstring><detail><UPnPError xmlns="urn:schemas-upnp-org:control-1-0"><errorCode>714</errorCode><errorDescription>The specified value does not exist in the array.</errorDescription></UPnPError></detail></s:Fault></s:Body></s:Envelope>
2014-11-11 15:32:34,642 [messages.ActionMessage:130] DEBUG - POST prepared for URL http://10.0.0.1:80/WANIPConnection
2014-11-11 15:32:34,642 [messages.ActionMessage:150] DEBUG - executing query :
<?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetSpecificPortMappingEntry xmlns:u="urn:schemas-upnp-org:service:WANIPConnection:1"><NewRemoteHost></NewRemoteHost><NewExternalPort>51235</NewExternalPort><NewProtocol>TCP</NewProtocol></u:GetSpecificPortMappingEntry></s:Body></s:Envelope>
2014-11-11 15:32:34,766 [messages.ActionMessage:163] DEBUG - received response :
<?xml version="1.0" encoding="utf-8"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><s:Fault><faultcode>s:Client</faultcode><faultstring>UPnPError</faultstring><detail><UPnPError xmlns="urn:schemas-upnp-org:control-1-0"><errorCode>714</errorCode><errorDescription>The specified value does not exist in the array.</errorDescription></UPnPError></detail></s:Fault></s:Body></s:Envelope>

User avatar
Azhrei
Site Admin
Posts: 12086
Joined: Mon Jun 12, 2006 1:20 pm
Location: Tampa, FL

Re: [b91] Unable to find UPnP services

Post by Azhrei »

Sorry, I'm just getting back to this. :(

I looked through your b91 log from a couple posts back. It states that two different network interfaces were scanned looking for routers with UPNP enabled (called Internet Gateway Devices, or IGD's). The second one (the RealTek adapter) seemed to have a router, but when the upnp code tried to access it, it received this error message:

Code: Select all

2014-11-11 14:47:07,753 [client.MapTool:298] ERROR - UPnP Error - No Internet Gateway Devices found.<br><br>UPnP port mapping will not be available.
I'll need to pull the source code out for the library and walk through your log with the code in front of me, but that message means that MT broadcast onto the network for all interfaces it found that contained routers (just RealTek, in this case) and the code never got an affirmative response. I don't recall what the conditions are that might trigger that (a negative response from the router, a timeout while waiting for the response, a general error from the router, etc). And that's why I'll need the code in front of me.

I believe I have a tool somewhere that will contact your router and dump all of it's options as text. I know wolph ran that for me, as did others, but I'll need to find it. It was just a quick hack to probe the XML configuration of the router and dump it to the screen. (Hm, I have a couple dozen log files, but no instructions on how to activate them. Probably on the forum here somewhere. Looking at the logs, it looks like something added to the logging configuration would enable the output. I'll need to check the source code.)

User avatar
RPTroll
TheBard
Posts: 3159
Joined: Tue Mar 21, 2006 7:26 pm
Location: Austin, Tx
Contact:

Re: [b91] Unable to find UPnP services

Post by RPTroll »

Az, your knowledge of this stuff is really amazing. :shock:
ImageImage ImageImageImageImage
Support RPTools by shopping
Image
Image

User avatar
aliasmask
RPTools Team
Posts: 9024
Joined: Tue Nov 10, 2009 6:11 pm
Location: Bay Area

Re: [b91] Unable to find UPnP services

Post by aliasmask »

It's just the regular Xfinity router/modem you get from comcast, model TC8305C.

I've checked my windows services and both upnphost and ssdpsvr are running. It could be something with my router understanding this particular library because only when I mess with it does it connect sometimes. But like a I mentioned above, b89 has no problems. For b91 I ended up just using port forwarding.

Post Reply

Return to “MapTool”