Re: [SR-Users] Kamailio 3.0 cann't access the RTPProxy

Hi Daniel, Thanks for your advice. I got the logs at the kamailio startup and the a call: nathelper.so] 0(19400) DEBUG: <core> [sr_module.c:871]: DEBUG: init_mod: nathelper 0(19400) DEBUG: nathelper [nathelper.c:633]: url is udp:127.0.0.1:7722, len is 18 0(19400) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (-127): nathelper 1(19401) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (1): nathelper 1(19401) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 2(19402) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (2): nathelper 2(19402) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 3(19403) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (3): nathelper 3(19403) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 4(19404) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (4): nathelper 4(19404) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 5(19405) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (-1): nathelper 5(19405) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 6(19406) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (-1): nathelper 6(19406) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 0(19400) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (0): nathelper 8(19408) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (-2): nathelper 9(19409) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (-1): nathelper 9(19409) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 10(19410) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (5): nathelper 10(19410) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 11(19411) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (6): nathelper 11(19411) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 12(19412) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (7): nathelper 12(19412) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 13(19413) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (8): nathelper 13(19413) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled 14(19414) DEBUG: <core> [sr_module.c:807]: DEBUG: init_mod_child (-4): nathelper 2(19402) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 2(19402) DEBUG: nathelper [nathelper.c:3196]: proxy reply: 48590 210.13.X.Y 4(19404) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 4(19404) ERROR: nathelper [nathelper.c:2457]: can't send command to a RTP proxy 4(19404) ERROR: nathelper [nathelper.c:2492]: proxy <udp:127.0.0.1:7722> does not respond, disable it 4(19404) ERROR: nathelper [nathelper.c:3144]: no available proxies 4(19404) ERROR: nathelper [nathelper.c:2627]: no available proxies AppSer01:ipunity@/var/log$ AppSer01:ipunity@/var/log$ AppSer01:root@/$ /usr/local/bin/rtpproxy -f -u kamailio -l 210.13.X.Y -d DBUG -s udp:localhost:7722 INFO:main: rtpproxy started, pid 19177 DBUG:handle_command: received command "19401_0 V" DBUG:doreply: sending reply "19401_0 20040107 " DBUG:handle_command: received command "19401_1 VF 20050322" DBUG:doreply: sending reply "19401_1 1 " DBUG:handle_command: received command "19401_2 VF 20071116" DBUG:doreply: sending reply "19401_2 1 " DBUG:handle_command: received command "19402_0 V" DBUG:doreply: sending reply "19402_0 20040107 " DBUG:handle_command: received command "19402_1 VF 20050322" DBUG:doreply: sending reply "19402_1 1 " DBUG:handle_command: received command "19402_2 VF 20071116" DBUG:doreply: sending reply "19402_2 1 " DBUG:handle_command: received command "19401_3 VF 20081102" DBUG:doreply: sending reply "19401_3 1 " DBUG:handle_command: received command "19403_0 V" DBUG:doreply: sending reply "19403_0 20040107 " DBUG:handle_command: received command "19403_1 VF 20050322" DBUG:doreply: sending reply "19403_1 1 " DBUG:handle_command: received command "19403_2 VF 20071116" DBUG:doreply: sending reply "19403_2 1 " DBUG:handle_command: received command "19402_3 VF 20081102" DBUG:doreply: sending reply "19402_3 1 " DBUG:handle_command: received command "19403_3 VF 20081102" DBUG:doreply: sending reply "19403_3 1 " DBUG:handle_command: received command "19404_0 V" DBUG:doreply: sending reply "19404_0 20040107 " DBUG:handle_command: received command "19404_1 VF 20050322" DBUG:doreply: sending reply "19404_1 1 " DBUG:handle_command: received command "19404_2 VF 20071116" DBUG:doreply: sending reply "19404_2 1 " DBUG:handle_command: received command "19404_3 VF 20081102" DBUG:doreply: sending reply "19404_3 1 " DBUG:handle_command: received command "19405_0 V" DBUG:doreply: sending reply "19405_0 20040107 " DBUG:handle_command: received command "19405_1 VF 20050322" DBUG:doreply: sending reply "19405_1 1 " DBUG:handle_command: received command "19405_2 VF 20071116" DBUG:doreply: sending reply "19405_2 1 " DBUG:handle_command: received command "19405_3 VF 20081102" DBUG:doreply: sending reply "19405_3 1 " DBUG:handle_command: received command "19406_0 V" DBUG:doreply: sending reply "19406_0 20040107 " DBUG:handle_command: received command "19406_1 VF 20050322" DBUG:doreply: sending reply "19406_1 1 " DBUG:handle_command: received command "19406_2 VF 20071116" DBUG:doreply: sending reply "19406_2 1 " DBUG:handle_command: received command "19406_3 VF 20081102" DBUG:doreply: sending reply "19406_3 1 " DBUG:handle_command: received command "19409_0 V" DBUG:doreply: sending reply "19409_0 20040107 " DBUG:handle_command: received command "19409_1 VF 20050322" DBUG:doreply: sending reply "19409_1 1 " DBUG:handle_command: received command "19409_2 VF 20071116" DBUG:doreply: sending reply "19409_2 1 " DBUG:handle_command: received command "19410_0 V" DBUG:doreply: sending reply "19410_0 20040107 " DBUG:handle_command: received command "19410_1 VF 20050322" DBUG:doreply: sending reply "19410_1 1 " DBUG:handle_command: received command "19410_2 VF 20071116" DBUG:doreply: sending reply "19410_2 1 " DBUG:handle_command: received command "19409_3 VF 20081102" DBUG:doreply: sending reply "19409_3 1 " DBUG:handle_command: received command "19410_3 VF 20081102" DBUG:doreply: sending reply "19410_3 1 " DBUG:handle_command: received command "19411_0 V" DBUG:doreply: sending reply "19411_0 20040107 " DBUG:handle_command: received command "19411_1 VF 20050322" DBUG:doreply: sending reply "19411_1 1 " DBUG:handle_command: received command "19411_2 VF 20071116" DBUG:doreply: sending reply "19411_2 1 " DBUG:handle_command: received command "19411_3 VF 20081102" DBUG:doreply: sending reply "19411_3 1 " DBUG:handle_command: received command "19412_0 V" DBUG:doreply: sending reply "19412_0 20040107 " DBUG:handle_command: received command "19412_1 VF 20050322" DBUG:doreply: sending reply "19412_1 1 " DBUG:handle_command: received command "19412_2 VF 20071116" DBUG:doreply: sending reply "19412_2 1 " DBUG:handle_command: received command "19412_3 VF 20081102" DBUG:doreply: sending reply "19412_3 1 " DBUG:handle_command: received command "19413_0 V" DBUG:doreply: sending reply "19413_0 20040107 " DBUG:handle_command: received command "19413_1 VF 20050322" DBUG:doreply: sending reply "19413_1 1 " DBUG:handle_command: received command "19413_2 VF 20071116" DBUG:doreply: sending reply "19413_2 1 " DBUG:handle_command: received command "19413_3 VF 20081102" DBUG:doreply: sending reply "19413_3 1 " DBUG:handle_command: received command "19402_4 Uc18,101 NzQ3YWU0MTcxOGYzY2EwYjUwYTUyNWY3NzU0YzBiNmM. 116.237.74.228 5756 f41c0c28;1" INFO:handle_command: new session NzQ3YWU0MTcxOGYzY2EwYjUwYTUyNWY3NzU0YzBiNmM., tag f41c0c28;1 requested, type strong INFO:handle_command: new session on a port 48590 created, tag f41c0c28;1 INFO:handle_command: pre-filling caller's address with 116.237.74.228:5756 DBUG:doreply: sending reply "19402_4 48590 210.13.X.Y" INFO:process_rtp: session timeout INFO:remove_session: RTP stats: 0 in from callee, 0 in from caller, 0 relayed, 0 dropped INFO:remove_session: RTCP stats: 0 in from callee, 0 in from caller, 0 relayed, 0 dropped INFO:remove_session: session on ports 48590/0 is cleaned up Thanks, Kevin Date: Fri, 6 Aug 2010 12:03:39 +0200 From: miconda(a)gmail.com To: kevin.jzh(a)hotmail.com CC: sr-users(a)lists.sip-router.org Subject: Re: [SR-Users] Kamailio 3.0 cann't access the RTPProxy Hello, the commands for starting new sessions happen during calls, is the log from starting kamailio is or taken during testing with calls? Is the rtpproxy used by many instance or just by one? I suggest you take the log only at the time you start kamailio so we know exactly what is printed at that time. Cheers, Daniel On 8/4/10 3:13 AM, KevinJin wrote: Hi Daniel, I have run the RTPProxy in debug model and get the following log, seems rtpproxy is responsding however nathelper is still disable it. Please help what's wrong here. Thanks, Kevin =======RTPProxy Log == Y.Z replaces the real IP====================== AppSer01:root@/$ /usr/local/bin/rtpproxy -f -u kamailio -l 210.13.Y.Z -d DBUG -s udp:localhost:7722 INFO:main: rtpproxy started, pid 28528 DBUG:handle_command: received command "15244_8 Uc18,101 OTIwMjRiMzYyZDU4ZjlkZDM3NWVmOWEwNmRlNjgyYzk. 116.237.74.228 53370 4729f84d;1" INFO:handle_command: new session OTIwMjRiMzYyZDU4ZjlkZDM3NWVmOWEwNmRlNjgyYzk., tag 4729f84d;1 requested, type strong INFO:handle_command: new session on a port 49092 created, tag 4729f84d;1 INFO:handle_command: pre-filling caller's address with 116.237.74.228:53370 DBUG:doreply: sending reply "15244_8 49092 210.13.Y.Z " INFO:process_rtp: session timeout INFO:remove_session: RTP stats: 0 in from callee, 0 in from caller, 0 relayed, 0 dropped INFO:remove_session: RTCP stats: 0 in from callee, 0 in from caller, 0 relayed, 0 dropped INFO:remove_session: session on ports 49092/0 is cleaned up DBUG:handle_command: received command "15247_10 V" DBUG:doreply: sending reply "15247_10 20040107 " DBUG:handle_command: received command "15247_11 VF 20050322" DBUG:doreply: sending reply "15247_11 1 " DBUG:handle_command: received command "15247_12 VF 20071116" DBUG:doreply: sending reply "15247_12 1 " DBUG:handle_command: received command "15247_13 VF 20081102" DBUG:doreply: sending reply "15247_13 1 " DBUG:handle_command: received command "15247_14 Uc18,101 YmYwNWNhM2RiYzM0MmQ4ZTFjMWY0ZjkxMzk1ODkxYTI. 116.237.74.228 22894 963ae84b;1" INFO:handle_command: new session YmYwNWNhM2RiYzM0MmQ4ZTFjMWY0ZjkxMzk1ODkxYTI., tag 963ae84b;1 requested, type strong INFO:handle_command: new session on a port 41750 created, tag 963ae84b;1 INFO:handle_command: pre-filling caller's address with 116.237.74.228:22894 DBUG:doreply: sending reply "15247_14 41750 210.13.Y.Z " INFO:process_rtp: session timeout INFO:remove_session: RTP stats: 0 in from callee, 0 in from caller, 0 relayed, 0 dropped INFO:remove_session: RTCP stats: 0 in from callee, 0 in from caller, 0 relayed, 0 dropped INFO:remove_session: session on ports 41750/0 is cleaned up DBUG:handle_command: received command "15244_9 V" DBUG:doreply: sending reply "15244_9 20040107 " DBUG:handle_command: received command "15244_10 VF 20050322" DBUG:doreply: sending reply "15244_10 1 " DBUG:handle_command: received command "15244_11 VF 20071116" DBUG:doreply: sending reply "15244_11 1 " DBUG:handle_command: received command "15244_12 VF 20081102" DBUG:doreply: sending reply "15244_12 1 " DBUG:handle_command: received command "15244_13 D YmYwNWNhM2RiYzM0MmQ4ZTFjMWY0ZjkxMzk1ODkxYTI. 3e596861-67d7-4c23-bd53-0a7c96e229bd 963ae84b" INFO:handle_command: delete request failed: session YmYwNWNhM2RiYzM0MmQ4ZTFjMWY0ZjkxMzk1ODkxYTI., tags 3e596861-67d7-4c23-bd53-0a7c96e229bd/963ae84b not found DBUG:doreply: sending reply "15244_13 E8 " DBUG:handle_command: received command "15245_19 Uc18,101 ZjA1MDlhOTZkMmI3NjZiMDIwNDY2Mjc4ZWFhMzQzNGQ. 116.237.74.228 49292 3f4f367e;1" INFO:handle_command: new session ZjA1MDlhOTZkMmI3NjZiMDIwNDY2Mjc4ZWFhMzQzNGQ., tag 3f4f367e;1 requested, type strong INFO:handle_command: new session on a port 49698 created, tag 3f4f367e;1 INFO:handle_command: pre-filling caller's address with 116.237.74.228:49292 DBUG:doreply: sending reply "15245_19 49698 210.13.Y.Z " INFO:process_rtp: session timeout INFO:remove_session: RTP stats: 0 in from callee, 0 in from caller, 0 relayed, 0 dropped INFO:remove_session: RTCP stats: 0 in from callee, 0 in from caller, 0 relayed, 0 dropped INFO:remove_session: session on ports 49698/0 is cleaned up DBUG:handle_command: received command "15244_14 V" DBUG:doreply: sending reply "15244_14 20040107 " DBUG:handle_command: received command "15244_15 VF 20050322" DBUG:doreply: sending reply "15244_15 1 " DBUG:handle_command: received command "15244_16 VF 20071116" DBUG:doreply: sending reply "15244_16 1 " DBUG:handle_command: received command "15244_17 VF 20081102" DBUG:doreply: sending reply "15244_17 1 " DBUG:handle_command: received command "15244_18 Uc18,101 MGNiMmM5OWE5Mjg4ZTU1NmQ3NjhlYjIyYmQ2ZTQyNmY. 116.237.74.228 32004 91602c3f;1" INFO:handle_command: new session MGNiMmM5OWE5Mjg4ZTU1NmQ3NjhlYjIyYmQ2ZTQyNmY., tag 91602c3f;1 requested, type strong INFO:handle_command: new session on a port 49824 created, tag 91602c3f;1 INFO:handle_command: pre-filling caller's address with 116.237.74.228:32004 DBUG:doreply: sending reply "15244_18 49824 210.13.Y.Z " INFO:process_rtp: session timeout INFO:remove_session: RTP stats: 0 in from callee, 0 in from caller, 0 relayed, 0 dropped INFO:remove_session: RTCP stats: 0 in from callee, 0 in from caller, 0 relayed, 0 dropped INFO:remove_session: session on ports 49824/0 is cleaned up ========Kamailio Log ========================================== AppSer01:ipunity@/var/log$ tail -f kamailio.log |grep nathelper 1(15244) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 1(15244) DEBUG: nathelper [nathelper.c:3196]: proxy reply: 49092 210.13.Y.Z 2(15245) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 2(15245) ERROR: nathelper [nathelper.c:2457]: can't send command to a RTP proxy 2(15245) ERROR: nathelper [nathelper.c:2492]: proxy <udp:127.0.0.1:7722> does not respond, disable it 2(15245) ERROR: nathelper [nathelper.c:3144]: no available proxies 4(15247) ERROR: nathelper [nathelper.c:2627]: no available proxies 4(15247) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 4(15247) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it re-enabled 4(15247) DEBUG: nathelper [nathelper.c:3196]: proxy reply: 41750 210.13.Y.Z 3(15246) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 3(15246) ERROR: nathelper [nathelper.c:2457]: can't send command to a RTP proxy 3(15246) ERROR: nathelper [nathelper.c:2492]: proxy <udp:127.0.0.1:7722> does not respond, disable it 3(15246) ERROR: nathelper [nathelper.c:3144]: no available proxies 1(15244) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it re-enabled 2(15245) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 2(15245) DEBUG: nathelper [nathelper.c:3196]: proxy reply: 49698 210.13.Y.Z 4(15247) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 4(15247) ERROR: nathelper [nathelper.c:2457]: can't send command to a RTP proxy 4(15247) ERROR: nathelper [nathelper.c:2492]: proxy <udp:127.0.0.1:7722> does not respond, disable it 4(15247) ERROR: nathelper [nathelper.c:3144]: no available proxies 3(15246) ERROR: nathelper [nathelper.c:2627]: no available proxies 1(15244) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 1(15244) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it re-enabled 1(15244) DEBUG: nathelper [nathelper.c:3196]: proxy reply: 49824 210.13.Y.Z 4(15247) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 4(15247) ERROR: nathelper [nathelper.c:2457]: can't send command to a RTP proxy 4(15247) ERROR: nathelper [nathelper.c:2492]: proxy <udp:127.0.0.1:7722> does not respond, disable it 4(15247) ERROR: nathelper [nathelper.c:3144]: no available proxies 4(15247) ERROR: nathelper [nathelper.c:2627]: no available proxies =============END of Nathelper log == From: kevin.jzh(a)hotmail.com To: miconda(a)gmail.com CC: sr-users(a)lists.sip-router.org Subject: RE: [SR-Users] Kamailio 3.0 cann't access the RTPProxy Date: Thu, 29 Jul 2010 01:13:00 +0800 Hi Daniel, Here is the debug message related to nethelper in the kamailio log: The proxy is circling btw enabled and disabled, what could be the cause? 4(15247) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 4(15247) ERROR: nathelper [nathelper.c:3144]: no available proxies 1(15244) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 1(15244) ERROR: nathelper [nathelper.c:3144]: no available proxies 2(15245) INFO: nathelper [nathelper.c:2369]: rtp proxy <udp:127.0.0.1:7722> found, support for it re-enabled 3(15246) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 3(15246) DEBUG: nathelper [nathelper.c:3196]: proxy reply: 46634 210.13.x.y 4(15247) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 4(15247) ERROR: nathelper [nathelper.c:2457]: can't send command to a RTP proxy 4(15247) ERROR: nathelper [nathelper.c:2492]: proxy <udp:127.0.0.1:7722> does not respond, disable it 4(15247) ERROR: nathelper [nathelper.c:3144]: no available proxies 1(15244) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 1(15244) ERROR: nathelper [nathelper.c:3144]: no available proxies 2(15245) ERROR: nathelper [nathelper.c:2627]: no available proxies 4(15247) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 4(15247) ERROR: nathelper [nathelper.c:3144]: no available proxies 3(15246) DEBUG: nathelper [nhelpr_funcs.c:148]: type <application/sdp> found valid 3(15246) ERROR: nathelper [nathelper.c:3144]: no available proxies 1(15244) ERROR: nathelper [nathelper.c:2627]: no available proxies Thanks, Kevin From: kevin.jzh(a)hotmail.com To: miconda(a)gmail.com CC: sr-users(a)lists.sip-router.org Subject: RE: [SR-Users] Kamailio 3.0 cann't access the RTPProxy Date: Wed, 28 Jul 2010 19:42:27 +0800 Hi, Yes, I changed the kamailio config as well, but didn't see any packet from Kamailio on the port. And the error message is as following: WARNING: tm [uac.c:111]: Warning: uac does not spread across the whole hash table ERROR: nathelper [nathelper.c:2457]: can't send command to a RTP proxy ERROR: nathelper [nathelper.c:2492]: proxy <udp:210.13.x.y:7722> does not respond, disable it ERROR: nathelper [nathelper.c:3144]: no available proxies ERROR: nathelper [nathelper.c:2457]: can't send command to a RTP proxy ERROR: nathelper [nathelper.c:2492]: proxy <udp:210.13.x.y:7722> does not respond, disable it ERROR: nathelper [nathelper.c:3144]: no available proxies ERROR: nathelper [nathelper.c:2627]: no available proxies ERROR: nathelper [nathelper.c:2627]: no available proxies Thanks, Kevin -- Daniel-Constantin Mierla http://www.asipto.com/