aMule Forum

English => Backtraces => Topic started by: GrayFox.i0n on February 26, 2008, 09:19:11 AM

Title: Bactrace SVN 20080225
Post by: GrayFox.i0n on February 26, 2008, 09:19:11 AM
Code: [Select]
bigboss@OuterHeaven:~$ amuled -f
amuled: OnInit - starting timer
Initialising aMule
Checking if there is an instance already running...
No other instances are running.
ListenSocket: Ok.

--------------------------------------------------------------------------------
A fatal error has occurred and aMule has crashed.
Please assist us in fixing this problem by posting the backtrace below in our
'aMule Crashes' forum and include as much information as possible regarding the
circumstances of this crash. The forum is located here:
    http://forum.amule.org/index.php?board=67.0
If possible, please try to generate a real backtrace of this crash:
    http://www.amule.org/wiki/index.php/Backtraces

----------------------------=| BACKTRACE FOLLOWS: |=----------------------------
Current version is: aMuled SVN using wxGTK2 v2.8.4 (Snapshot: Mon Feb 25 07:01:56 CET 2008)
Running on: Linux 2.6.22-14-386 i686

[2] CamuleApp::OnFatalException() in amuled [0x8070661]
[3] wxFatalSignalHandler in /usr/lib/libwx_baseu-2.8.so.0[0xb7b0a5d6]
[4] ?? in [0xffffe420]
[5] std::_Rb_tree<std::string const, std::pair<std::string const, CUPnPAction*>, std::_Select1st<std::pair<std::string const, CUPnPAction*> >, std::less<std::string const>, std::allocator<std::pair<std::string const, CUPnPAction*> > >::find(std::string const&) const in amuled [0x8142cbc]
[6] CUPnPService::Execute(std::string const&, std::vector<CUPnPArgumentValue, std::allocator<CUPnPArgumentValue> > const&) const in amuled [0x813c6bc]
[7] CUPnPControlPoint::PrivateAddPortMapping(CUPnPPortMapping&) in amuled [0x813e5e6]
[8] CUPnPControlPoint::AddPortMappings(std::vector<CUPnPPortMapping, std::allocator<CUPnPPortMapping> >&) in amuled [0x81414d8]
[9] CamuleApp::ReinitializeNetwork(wxString*) in amuled [0x80722a8]
[10] CamuleApp::OnInit() in amuled [0x807d05c]
[11] CamuleDaemonApp::OnInit() in amuled [0x806d96f]
[12] wxAppConsole::CallOnInit() in amuled[0x806e6a1]
[13] wxEntry(int&, wchar_t**) in /usr/lib/libwx_baseu-2.8.so.0[0xb7a9a230]
[14] wxEntry(int&, char**) in /usr/lib/libwx_baseu-2.8.so.0[0xb7a9a307]
[15] main in amuled [0x806c1e0]
[16] __libc_start_main in /lib/libc.so.6[0xb77b4050]
[17] CryptoPP::BufferedTransformation::Get(unsigned char*, unsigned int) in amuled[0x806bcb1]


--------------------------------------------------------------------------------
Aborted
bigboss@OuterHeaven:~$

There you have it, i hope it helps :)
Title: Re: Bactrace SVN 20080225
Post by: phoenix on February 26, 2008, 01:32:00 PM
Could you please enable the UPnP debug log in preferences and attach the file ~/.aMule/logfile of a crashed session? Or if you don't have X, change it directly in ~/.aMule/amule.conf:
Quote
[Debug]
Cat_General=1
...
Cat_Universal\ Plug\ and\ Play=1

Would you mind recompiling it with debug information and maybe post a gdb backtrace?

Take a look at the file amule_build_install.sh that comes with the tarballs for hints. Also:

http://www.amule.org/wiki/index.php/Backtraces
http://www.amule.org/wiki/index.php/Using_gdb_and_valgrind
Title: Re: Bactrace SVN 20080225
Post by: GrayFox.i0n on February 26, 2008, 10:16:00 PM
Im on it ;)

So, i must add that lines to the end of the amule.conf file (I didnt have that [Debug] tag before in it.

Code: [Select]
[Debug]
Cat_General=1
Cat_Universal\ Plug\ and\ Play=1
Then, compile with the --enable-debug flag

And basically, i installed gdb and valgrind..... i can't see it clear... how to use them?
Just launch amuled -f with any parameter?


Thanks

BTW... i just installed the latest version of libupnp : 1.6.5 (Feb. 2nd)
Title: Re: Bactrace SVN 20080225
Post by: GrayFox.i0n on February 28, 2008, 11:12:35 PM
Code: [Select]
2008-02-28 22:12:21: UPnP.cpp(91): Universal Plug and Play: Successfully opened libixml.so.2.
2008-02-28 22:12:21: UPnP.cpp(91): Universal Plug and Play: Successfully opened libupnp.so.2.
2008-02-28 22:12:21: UPnP.cpp(91): Universal Plug and Play: Successfully opened libupnp.so.3.
2008-02-28 22:12:21: UPnP.cpp(1063): Universal Plug and Play: bound to 192.168.1.100:50000.
2008-02-28 22:12:24: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-02-28 22:12:24: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:WANDSLLinkConfig:1'. Ignoring.
2008-02-28 22:12:24: UPnP.cpp(746): Universal Plug and Play: WAN Service Detected: 'urn:schemas-upnp-org:service:WANPPPConnection:1'.
2008-02-28 22:12:24: UPnP.cpp(1134): Universal Plug and Play: UPnP Error: CUPnPControlPoint::AddPortMapping: Wan Service not detected.

Is this clearing anything?

Thanks ;)
Title: Re: Bactrace SVN 20080225
Post by: phoenix on March 02, 2008, 05:51:01 AM
Quote
2008-02-28 22:12:24: UPnP.cpp(1134): Universal Plug and Play: UPnP Error: CUPnPControlPoint::AddPortMapping: Wan Service not detected.
Well, this means UPnP is not working. Also, somehow the program did not took into account the debug option. Maybe you have to add it to ~/.aMule/remote.conf?

To learn how to use gdb, take a look at the link I gave you before.

BTW, you no longer need libupnp.so.2 in your system, now that you have libupnp.so.3. I suggest you delete it, although it does not harm. aMule will complain that it can't find it, but this is normal.
Title: Re: Bactrace SVN 20080225
Post by: GrayFox.i0n on March 02, 2008, 03:09:37 PM
It's strange, im checking the upnp configuration in my router and looks ok:

Code: [Select]
UPnP configuration:

  Enabled: true
     Port: 2800
And it has been working perfectly until now. All the UPnP problems come out in the recent SVNs......
I will try to compile today's one and see if i still have the same problem

Thanks! ;)
Title: Re: Bactrace SVN 20080225
Post by: phoenix on March 02, 2008, 08:44:48 PM
Hi GrayFox.i0n,

UPnP code in aMule has not been touched recently. Please check for a recent upgrade or change in your system. For example, check whether the UPnP ports on the firewall are open, in your case it is ports tcp dest 50000, udp dest 1900, udp source 1900 (to make it faster). For a first test, I would disable iptables at all with "$iptables -F".

Another information you can provide is the exact svn date when aMule stopped working for you.
Title: Re: Bactrace SVN 20080225
Post by: GrayFox.i0n on March 03, 2008, 02:52:52 PM
Later, i will compile today's CVS and i will check if the problem still persist.
I have been thinking that the only change that i did on my system and that could affect upnp, is the update of libupnp to version 1.6.5, because in other computers i have version 1.6.4 and upnp works correctly with amule (What makes me be sure that the problem is not with the router config)
What i will do is, as i said, compile today's cvs and check if is working ... if not, i will reinstall a lower version of libupnp.....
I hope it works..... its been a lot of days without amule working right in my box!!  >:(

Thanks
Title: Re: Bactrace SVN 20080225
Post by: phoenix on March 04, 2008, 03:11:01 AM
Ok, please report your results.

Cheers!
Title: Re: Bactrace SVN 20080225
Post by: GrayFox.i0n on March 04, 2008, 08:22:58 AM
Code: [Select]
2008-03-04 07:22:14: UPnP.cpp(91): Universal Plug and Play: Successfully opened libixml.so.2.
2008-03-04 07:22:15: UPnP.cpp(91): Universal Plug and Play: Successfully opened libupnp.so.2.
2008-03-04 07:22:15: UPnP.cpp(91): Universal Plug and Play: Successfully opened libupnp.so.3.
2008-03-04 07:22:15: UPnP.cpp(1063): Universal Plug and Play: bound to 192.168.1.100:50000.
2008-03-04 07:22:33: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-04 07:22:33: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:WANDSLLinkConfig:1'. Ignoring.
2008-03-04 07:22:33: UPnP.cpp(746): Universal Plug and Play: WAN Service Detected: 'urn:schemas-upnp-org:service:WANPPPConnection:1'.
2008-03-04 07:22:33: UPnP.cpp(1718): Universal Plug and Play: Successfully subscribed to service urn:schemas-upnp-org:service:WANPPPConnection:1, absEventSubURL: http://192.168$
2008-03-04 07:22:33: UPnP.cpp(1653): Universal Plug and Play: UPNP_EVENT_RECEIVED:
    SID: uuid:55c3feb2-1dd2-11b2-886e-836996b6c7a0
    Key: 0
    Property list:
        PossibleConnectionTypes='IP_Routed'
        ConnectionStatus='Connected'
        ExternalIPAddress='xx.xx.xxx.xxx'
        PortMappingNumberOfEntries='47'
2008-03-04 07:22:33: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1'. Ignoring.
2008-03-04 07:22:33: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:Layer3Forwarding:1'. Ignoring.
2008-03-04 07:22:33: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-04 07:22:33: UPnP.cpp(1653): Universal Plug and Play: UPNP_EVENT_RECEIVED:
    SID: uuid:55c3feb2-1dd2-11b2-886e-836996b6c7a0
    Key: 1
    Property list:
        PortMappingNumberOfEntries='49'
2008-03-04 07:22:33: UPnP.cpp(1653): Universal Plug and Play: UPNP_EVENT_RECEIVED:
    SID: uuid:55c3feb2-1dd2-11b2-886e-836996b6c7a0
    Key: 2
    Property list:
        PortMappingNumberOfEntries='51'

Ok, version 1.6.4 of libupnp works fine. Newer versions, until the date, such as 1.6.5 are not working properly.
I connected with high id to ed2k, but kad is still firewalled.... hoping that status will change after some minutes.
I will keep on trying amule, now from my job, and see if there is anything wrong still, or if this time was only a matter of luck that the upnp is working.
I will report any other strange things i see

Cheers!
Title: Re: Bactrace SVN 20080225
Post by: GrayFox.i0n on March 04, 2008, 01:20:56 PM
After this many hours, i checked it again.
Kad is not firewalled anymore and i have normal transfers.
There must be something wrong with libupnp in the most recent version that was not letting me connect.
Now seems to be OK  :D
Thanks
Title: Re: Bactrace SVN 20080225
Post by: phoenix on March 05, 2008, 06:00:35 AM
Hi,

It is unlikely that the problem can be caused by a change from libupnp 1.6.4 to 1.6.5. Here is the diff between the two libs:
Quote
@@ -335,7 +335,7 @@ CreateServicePacket( IN int msg_type,
             &buf, 1, 1,
             "Q" "sssdc" "sdc" "ssc" "ssc" "ssc" "S" "Xc" "sscc",
             HTTPMETHOD_NOTIFY, "*",
-            1, "HOST: ", SSDP_IP, ":", SSDP_PORT,
+            (size_t)1, "HOST: ", SSDP_IP, ":", SSDP_PORT,
             "CACHE-CONTROL: max-age=", duration,
             "LOCATION: ", location,
             "NT: ", nt,
They differ in one single line. And it is a bug fix for sure.

Cheers!
Title: Re: Bactrace SVN 20080225
Post by: GrayFox.i0n on March 05, 2008, 07:39:13 AM
Yes, the problems are back again....  ??? ??? ??? ??? ??? now i really have no idea of why can it be....  :-[ :-\
Thanks, anyway....
Title: Re: Bactrace SVN 20080225
Post by: phoenix on March 06, 2008, 02:26:11 AM
You still have not posted a full log at the time of the problem as I requested, with enabled UPnP debug in preferences. Maybe it can contain valuable information. If you wish, you can also PM me.
Title: Re: Bactrace SVN 20080225
Post by: GrayFox.i0n on March 07, 2008, 12:26:22 AM
OK, this is the full loogfile of a not working UPnP session:

Code: [Select]
2008-03-06 23:24:47: ClientCreditsList.cpp(168): Archivo de créditos cargado, 6615 clientes conocidos
2008-03-06 23:24:47: IPFilter.cpp(109): Cargando filtros IP 'ipfilter.dat' y 'ipfilter_static.dat'
2008-03-06 23:25:06: IPFilter.cpp(333): Cargado 240736 rangos IP desde '/home/bigboss/.aMule/ipfilter.dat'. 0 líneas malformadas fueron descartadas
2008-03-06 23:25:06: IPFilter.cpp(333): Cargado 0 rangos IP desde '/home/bigboss/.aMule/ipfilter_static.dat'. 0 líneas malformadas fueron descartadas
2008-03-06 23:25:07: ExternalConn.cpp(160): *** TCP socket (ECServer) listening on 0.0.0.0:4712
2008-03-06 23:25:07: MuleUDPSocket.cpp(78): Created Server UDP-Socket at port 4661
2008-03-06 23:25:07: MuleUDPSocket.cpp(78): Created Client UDP-Socket at port 4647
2008-03-06 23:25:07: UPnP.cpp(91): Universal Plug and Play: Successfully opened libixml.so.2.
2008-03-06 23:25:07: UPnP.cpp(91): Universal Plug and Play: Successfully opened libupnp.so.2.
2008-03-06 23:25:07: UPnP.cpp(91): Universal Plug and Play: Successfully opened libupnp.so.3.
2008-03-06 23:25:07: UPnP.cpp(1063): Universal Plug and Play: bound to 192.168.1.100:50000.
2008-03-06 23:25:10: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-06 23:25:10: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:WANDSLLinkConfig:1'. Ignoring.
2008-03-06 23:25:10: UPnP.cpp(746): Universal Plug and Play: WAN Service Detected: 'urn:schemas-upnp-org:service:WANPPPConnection:1'.
2008-03-06 23:25:10: UPnP.cpp(1134): Universal Plug and Play: UPnP Error: CUPnPControlPoint::AddPortMapping: Wan Service not detected.
2008-03-06 23:25:10: ServerList.cpp(83): Cargando archivo server.met: /home/bigboss/.aMule/server.met
2008-03-06 23:25:10: ServerList.cpp(168): 4 servidores encontrados en server.met
2008-03-06 23:25:10: DownloadQueue.cpp(173): No se han encontrado archivos .part
2008-03-06 23:25:10: SharedFileList.cpp(352): Encontrado 0 archivos compartidos
2008-03-06 23:25:10: amule.cpp(782): Conectando
2008-03-06 23:25:10: ServerSocket.cpp(625): Servers: Trying to connect
2008-03-06 23:25:10: ServerSocket.cpp(758): Conectando a Razorback 3.0 (85.17.52.92 - 85.17.52.92:5000) usando ofuscación de protocolo.
2008-03-06 23:25:10: RoutingZone.cpp(141): Read 164 Kad contacts
2008-03-06 23:25:11: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-06 23:25:11: UPnP.cpp(1718): Universal Plug and Play: Successfully subscribed to service urn:schemas-upnp-org:service:WANPPPConnection:1, absEventSubURL: http://192.168$
2008-03-06 23:25:11: amule.cpp(825): servidor web ejecutándose con pid 4206
2008-03-06 23:25:11: Logger.cpp(269): Deleted stale lock file '/home/bigboss/.aMule/muleLock'.
2008-03-06 23:25:11: ServerConnect.cpp(200): Conectado a Razorback 3.0 (85.17.52.92:5000)
2008-03-06 23:25:11: Kad iniciado.
2008-03-06 23:25:12: ThreadScheduler.cpp(308): ThreadScheduler: Completed task 'AICH Syncronizing', 0 tasks remaining.
2008-03-06 23:25:12: UPnP.cpp(1653): Universal Plug and Play: UPNP_EVENT_RECEIVED:
    SID: uuid:2f32c402-1dd2-11b2-9466-b85e2b1f5f44
    Key: 0
    Property list:
        PossibleConnectionTypes='IP_Routed'
        ConnectionStatus='Connected'
        ExternalIPAddress='88.11.215.161'
        PortMappingNumberOfEntries='22'
2008-03-06 23:25:12: ServerSocket.cpp(625): Servers: Trying to connect
2008-03-06 23:25:12: ServerSocket.cpp(758): Conectando a Razorback 3.1 (193.138.205.25 - 193.138.205.25:5000) usando ofuscación de protocolo.
2008-03-06 23:25:12: ExternalConn.cpp(218): Nueva conexión externa, aceptada
2008-03-06 23:25:12: ServerConnect.cpp(200): Conectado a Razorback 3.1 (193.138.205.25:5000)
2008-03-06 23:25:12: ExternalConn.cpp(252): Conectando con cliente: aMuleweb SVN
2008-03-06 23:25:12: ExternalConn.cpp(307): Acceso concedido.
2008-03-06 23:25:12: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1'. Ignoring.
2008-03-06 23:25:12: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:Layer3Forwarding:1'. Ignoring.
2008-03-06 23:25:19: UPnP.cpp(1653): Universal Plug and Play: UPNP_EVENT_RECEIVED:
    SID: uuid:2f32c402-1dd2-11b2-9466-b85e2b1f5f44
    Key: 1
    Property list:
        PortMappingNumberOfEntries='22'
2008-03-06 23:25:19: ExternalConn.cpp(218): Nueva conexión externa, aceptada
2008-03-06 23:25:19: ExternalConn.cpp(252): Conectando con cliente: amule-remote 0x0001
2008-03-06 23:25:19: ExternalConn.cpp(307): Acceso concedido.
2008-03-06 23:25:21: Kad detenido.
2008-03-06 23:25:21: Conectado a Kad (tras cortafuegos)
2008-03-06 23:25:22: UPnP.cpp(1653): Universal Plug and Play: UPNP_EVENT_RECEIVED:
    SID: uuid:2f32c402-1dd2-11b2-9466-b85e2b1f5f44
    Key: 2
    Property list:
        PortMappingNumberOfEntries='22'
2008-03-06 23:25:22: ServerSocket.cpp(277): Alerta: Razorback 3.1 (193.138.205.25:5000) - NG : You have a lowid. Please review your network config and/or your settings.
2008-03-06 23:25:22: ServerSocket.cpp(409): Servers: Connected
2008-03-06 23:25:22: ServerConnect.cpp(273): Conexión establecida en: Razorback 3.1
2008-03-06 23:25:22: Conectado a Razorback 3.1 con IDBaja
2008-03-06 23:25:22: ServerSocket.cpp(417): El nuevo ID-Cliente es 9287156
2008-03-06 23:25:22: ServerSocket.cpp(419): ALERTA: ¡Has recibido ID-Baja!
2008-03-06 23:25:22: ServerSocket.cpp(420):     Esto sucede porque estás detrás de un cortafuegos o de un router.
2008-03-06 23:25:22: ServerSocket.cpp(421):     Para mas información, por favor ves a http://wiki.amule.org
2008-03-06 23:25:22: Mensaje del servidor: server version 17.15 (lugdunum)
2008-03-06 23:25:22: Mensaje del servidor: en http://es.razorback3.com o que todos puedan ver tu IP (P2P con intercambio de IP)?!
2008-03-06 23:25:26: UPnP.cpp(1653): Universal Plug and Play: UPNP_EVENT_RECEIVED:
    SID: uuid:2f32c402-1dd2-11b2-9466-b85e2b1f5f44
    Key: 3
    Property list:
        PortMappingNumberOfEntries='22'
2008-03-06 23:25:28: Desconectado de ED2K
2008-03-06 23:25:28: Desconectado de Kad
2008-03-06 23:25:29: ExternalConn.cpp(117): Conexión externa cerrada.
2008-03-06 23:25:30: UPnP.cpp(1653): Universal Plug and Play: UPNP_EVENT_RECEIVED:
    SID: uuid:2f32c402-1dd2-11b2-9466-b85e2b1f5f44
    Key: 4
    Property list:
        PortMappingNumberOfEntries='23'
2008-03-06 23:25:32: TerminationProcess.cpp(49): Comando `'amuleweb' '--amule-config-file=/home/bigboss/.aMule/amule.conf'' con pid `4206' ha terminado con código `-1'.
2008-03-06 23:25:33: UPnP.cpp(1299): Universal Plug and Play: UPnP Error: CUPnPControlPoint::DeletePortMapping: Mapping was not found in the active mapping map.
2008-03-06 23:25:33: UPnP.cpp(347): Universal Plug and Play: Error: m_UpnpSendAction: Error code :'501', Error description :'Action failed, No Matching Waiting Session Found'.
2008-03-06 23:25:33: UPnP.cpp(1299): Universal Plug and Play: UPnP Error: CUPnPControlPoint::DeletePortMapping: Mapping was not found in the active mapping map.UPnP Error: CUPn$
2008-03-06 23:25:34: UPnP.cpp(347): Universal Plug and Play: Error: m_UpnpSendAction: Error code :'501', Error description :'Action failed, No Matching Waiting Session Found'.
2008-03-06 23:25:34: UPnP.cpp(1299): Universal Plug and Play: UPnP Error: CUPnPControlPoint::DeletePortMapping: Mapping was not found in the active mapping map.UPnP Error: CUPn$
2008-03-06 23:25:35: UPnP.cpp(347): Universal Plug and Play: Error: m_UpnpSendAction: Error code :'501', Error description :'Action failed, No Matching Waiting Session Found'.
2008-03-06 23:25:35: UPnP.cpp(1299): Universal Plug and Play: UPnP Error: CUPnPControlPoint::DeletePortMapping: Mapping was not found in the active mapping map.UPnP Error: CUPn$
2008-03-06 23:25:36: UPnP.cpp(347): Universal Plug and Play: Error: m_UpnpSendAction: Error code :'501', Error description :'Action failed, No Matching Waiting Session Found'.
2008-03-06 23:25:38: UPnP.cpp(1299): Universal Plug and Play: UPnP Error: CUPnPControlPoint::DeletePortMapping: Mapping was not found in the active mapping map.
2008-03-06 23:25:39: UPnP.cpp(347): Universal Plug and Play: Error: m_UpnpSendAction: Error code :'501', Error description :'Action failed, No Matching Waiting Session Found'.
2008-03-06 23:25:39: UPnP.cpp(1299): Universal Plug and Play: UPnP Error: CUPnPControlPoint::DeletePortMapping: Mapping was not found in the active mapping map.UPnP Error: CUPn$
2008-03-06 23:25:40: UPnP.cpp(347): Universal Plug and Play: Error: m_UpnpSendAction: Error code :'501', Error description :'Action failed, No Matching Waiting Session Found'.
2008-03-06 23:25:40: UPnP.cpp(1299): Universal Plug and Play: UPnP Error: CUPnPControlPoint::DeletePortMapping: Mapping was not found in the active mapping map.UPnP Error: CUPn$
2008-03-06 23:25:40: UPnP.cpp(347): Universal Plug and Play: Error: m_UpnpSendAction: Error code :'501', Error description :'Action failed, No Matching Waiting Session Found'.
2008-03-06 23:25:40: UPnP.cpp(1299): Universal Plug and Play: UPnP Error: CUPnPControlPoint::DeletePortMapping: Mapping was not found in the active mapping map.UPnP Error: CUPn$
2008-03-06 23:25:42: UPnP.cpp(347): Universal Plug and Play: Error: m_UpnpSendAction: Error code :'501', Error description :'Action failed, No Matching Waiting Session Found'.
2008-03-06 23:25:45: UPnP.cpp(109): Universal Plug and Play: Successfully closed libupnp.so.3.
2008-03-06 23:25:45: UPnP.cpp(109): Universal Plug and Play: Successfully closed libupnp.so.2.
2008-03-06 23:25:45: UPnP.cpp(109): Universal Plug and Play: Successfully closed libixml.so.2.

I hope this helps

Thanks!
Title: Re: Bactrace SVN 20080225
Post by: phoenix on March 09, 2008, 12:52:19 AM
Hi GrayFox.i0n,

Quote
2008-03-06 23:25:11: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-06 23:25:11: UPnP.cpp(1718): Universal Plug and Play: Successfully subscribed to service urn:schemas-upnp-org:service:WANPPPConnection:1, absEventSubURL: http://192.168$

I doubt that the absEventSubURL is correct. For example, here I have a router and a dsl modem in two different networks, both can be used, one at a time, by selecting the appropriate default interface. Both devices support UPnP. Without verbose debugging enabled in aMule preferences I get:

Quote
2008-03-08 19:32:32: ExternalConn.cpp(160): *** TCP socket (ECServer) listening on 0.0.0.0:4712
2008-03-08 19:32:32: MuleUDPSocket.cpp(78): Created Server UDP-Socket at port 8665
2008-03-08 19:32:32: MuleUDPSocket.cpp(78): Created Client UDP-Socket at port 8672
2008-03-08 19:32:32: UPnP.cpp(91): Universal Plug and Play: Successfully opened libixml.so.2.
2008-03-08 19:32:32: UPnP.cpp(87): Universal Plug and Play: error(CDynamicLibHandle): Unable to dlopen libupnp.so.2. Check PATH and LD_LIBRARY_PATH.
2008-03-08 19:32:32: UPnP.cpp(91): Universal Plug and Play: Successfully opened libupnp.so.3.
2008-03-08 19:32:32: UPnP.cpp(1063): Universal Plug and Play: bound to 192.168.1.2:50000.
2008-03-08 19:32:39: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-08 19:32:39: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:WANDSLLinkConfig:1'. Ignoring.
2008-03-08 19:32:39: UPnP.cpp(746): Universal Plug and Play: WAN Service Detected: 'urn:schemas-upnp-org:service:WANPPPConnection:1'.
2008-03-08 19:32:39: UPnP.cpp(1718): Universal Plug and Play: Successfully subscribed to service urn:schemas-upnp-org:service:WANPPPConnection:1, absEventSubURL: http://10.0.0.1:80/upnp/WANPPPConnection?dev=2.
2008-03-08 19:32:39: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1'. Ignoring.
2008-03-08 19:32:39: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:Layer3Forwarding:1'. Ignoring.
2008-03-08 19:32:39: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-efficient-com:service:DSLCPEStatus:1'. Ignoring.
2008-03-08 19:32:39: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-efficient-com:service:DSLCPECfg:1'. Ignoring.
2008-03-08 19:32:39: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-08 19:32:39: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-08 19:32:39: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-08 19:32:39: UPnP.cpp(746): Universal Plug and Play: WAN Service Detected: 'urn:schemas-upnp-org:service:WANIPConnection:1'.
2008-03-08 19:32:39: UPnP.cpp(1718): Universal Plug and Play: Successfully subscribed to service urn:schemas-upnp-org:service:WANIPConnection:1, absEventSubURL: http://192.168.1.1:80/event?WANIPConnection.
2008-03-08 19:32:39: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-08 19:32:39: UPnP.cpp(746): Universal Plug and Play: WAN Service Detected: 'urn:schemas-upnp-org:service:WANPPPConnection:1'.
2008-03-08 19:32:39: UPnP.cpp(1718): Universal Plug and Play: Successfully subscribed to service urn:schemas-upnp-org:service:WANPPPConnection:1, absEventSubURL: http://192.168.1.1:80/event?WANPPPConnection.
2008-03-08 19:32:39: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:WANEthernetLinkConfig:1'. Ignoring.
2008-03-08 19:32:39: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1'. Ignoring.
2008-03-08 19:32:39: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:LANHostConfigManagement:1'. Ignoring.
2008-03-08 19:32:39: UPnP.cpp(763): Universal Plug and Play: Uninteresting service detected: 'urn:schemas-upnp-org:service:Layer3Forwarding:1'. Ignoring.
2008-03-08 19:32:39: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-08 19:32:39: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-08 19:32:39: UPnP.cpp(1420): Universal Plug and Play: Internet Gateway Device Detected.
2008-03-08 19:32:39: amuleDlg.cpp(217):
2008-03-08 19:32:39: amuleDlg.cpp(219):  - This is aMule SVN using wxGTK2 v2.8.7 (Debugging) (Snapshot: rev. 8208) based on eMule.
2008-03-08 19:32:39: amuleDlg.cpp(221):    Running on Linux 2.6.18.8-0.9-default i686
2008-03-08 19:32:39: amuleDlg.cpp(223):  - Visit http://www.amule.org to check if a new version is available.
2008-03-08 19:32:39: amuleDlg.cpp(224):

If you enable verbose debugging for UPnP you will probably get more information and maybe a clue of what is going wrong.
Title: Re: Bactrace SVN 20080225
Post by: phoenix on March 09, 2008, 12:55:36 AM
Replying to myself and adding some more information:

There is a chance that your device is returning a wrong address (absEventSubURL: http://192.168$). But it could be also that the UPnP module is misinterpreting your device's response. It could be enlightening to run wireshark on your network interface to sniff UPnP traffic and see if there is any problem with the returned value of absEventSubURL.