On 2009-10-21 17:48 +0200, Tihomir Culjaga wrote freeswitch-users@lists.fre...: TC>FS = 10.4.62.7 TC>SIP phone = 10.4.62.89 TC>H323 endpoint = 10.1.14.153 TC> TC> TC> TC>> TC>2. hangup from sip side doesn't release the h323 leg (now the difference TC>> is TC>> TC>that FS is not complaining about thread mismatch ant it looks clean but TC>> FS TC>> TC>doesn't send any releasecomplete message... strange) TC>> TC>3. coredumps when i place outgoing calls TC>> TC>> btw, TC>> TC>> TC> 70.552157 10.1.14.153 -> 10.4.62.7 H.245 endSessionCommand TC>> TC> 70.552401 10.1.14.153 -> 10.4.62.7 H.225.0 CS: releaseComplete TC>> TC> 70.553981 10.4.62.7 -> 10.4.62.7 RTP Unknown RTP version 3 TC>> TC> 70.554488 10.4.62.7 -> 10.1.14.153 H.245 endSessionCommand TC>> TC> 70.555056 10.4.62.7 -> 10.1.14.153 H.225.0 CS: releaseComplete TC>> TC>> it send, now i have no way to test h323->sip transit, i will have it TC>> tomorow. TC>> sip->h323 for me work fine now, give backtrace from code dump of 1.0.4 TC>> where it die? TC>> TC>> TC> TC>this endSession is when i hangup from H232 side as well :P ... if i don't TC>hangup on H323 side the H323 leg is not released. Pls chec the time the TC>packets were sent ... TC> TC> TC> TC>Here i hangup on the SIP Phone: TC> TC>68.374916 10.4.62.89 -> 10.4.62.7 SIP Request: BYE TC>sip:mod_sofia@10.4.62.7:5060 TC>68.375342 10.4.62.7 -> 10.4.62.7 RTP Unknown RTP version 3 TC>68.375620 10.4.62.7 -> 10.4.62.89 SIP Status: 200 OK TC> TC>2 sec delay TC> TC>Here i hangup on the H323 endpoint (releaseComplete comes from H323 endpoint TC>first here ) TC>70.552157 10.1.14.153 -> 10.4.62.7 H.245 endSessionCommand TC>70.552401 10.1.14.153 -> 10.4.62.7 H.225.0 CS: releaseComplete TC> TC> TC>FS just acknowlages it here: TC>70.554488 10.4.62.7 -> 10.1.14.153 H.245 endSessionCommand TC>70.555056 10.4.62.7 -> 10.1.14.153 H.225.0 CS: releaseComplete TC> TC> TC> TC>I have enabled crash-protection and when i do SIP => H323 call it doesn't TC>generate coredumps... it is just this thread that is crashing ... pls check TC>the log downbelow: core dump in case enabled crash-protection may be unusable, i have a case then my module crash silently, after this crash-protection is killing sip leg and after this i get core dump where backtrace show me segfault in libc6, i spent one day to understand this situation, and then i disable crash-protection i see there is actualy it crashes. disable crash-protection and show backtrace of crash, i think result will be different. TC> TC>Dialplan: sofia/internal/1001@singtel Regex (FAIL) [EMERGENCY] TC>destination_number(05492122) =~ /^0(112|9[23456])$/ break=on-false TC>Dialplan: sofia/internal/1001@singtel parsing [default->SPECIAL_SERVICES] TC>continue=false TC>Dialplan: sofia/internal/1001@singtel Regex (FAIL) [SPECIAL_SERVICES] TC>destination_number(05492122) =~ /^0(9[01789]\d{3,4})$/ break=on-false TC>Dialplan: sofia/internal/1001@singtel parsing [default->ENYTHING_ELSE] TC>continue=false TC>Dialplan: sofia/internal/1001@singtel Regex (PASS) [ENYTHING_ELSE] TC>destination_number(05492122) =~ TC>/^0([2-8]\d{6,7}|0[1-9]\d{7,8}|00[1-9]\d{8,16})$/ break=on-false TC>Dialplan: sofia/internal/1001@singtel Action TC>set(effective_caller_id_number=1001282122) TC>Dialplan: sofia/internal/1001@singtel Action set(NCX_IP=10.4.4.254) TC>Dialplan: sofia/internal/1001@singtel Action set(call_timeout=30) TC>Dialplan: sofia/internal/1001@singtel Action set(hangup_after_bridge=true) TC>Dialplan: sofia/internal/1001@singtel Action set(bypass_media=false) TC>Dialplan: sofia/internal/1001@singtel Action set(proxy_media=true) TC>Dialplan: sofia/internal/1001@singtel Action bridge(h323/05492122@${NCX_IP}) TC>2009-10-21 17:35:28.682475 [DEBUG] switch_core_state_machine.c:114 TC>(sofia/internal/1001@singtel) State Change CS_ROUTING -> CS_EXECUTE TC>2009-10-21 17:35:28.682475 [DEBUG] switch_core_session.c:932 Send signal TC>sofia/internal/1001@singtel [BREAK] TC>2009-10-21 17:35:28.682475 [DEBUG] switch_core_state_machine.c:484 TC>(sofia/internal/1001@singtel) State ROUTING going to sleep TC>2009-10-21 17:35:28.682475 [DEBUG] switch_core_state_machine.c:398 TC>(sofia/internal/1001@singtel) Running State Change CS_EXECUTE TC>2009-10-21 17:35:28.682475 [DEBUG] switch_core_state_machine.c:491 TC>(sofia/internal/1001@singtel) State EXECUTE TC>2009-10-21 17:35:28.682475 [DEBUG] mod_sofia.c:173 TC>sofia/internal/1001@singtel SOFIA EXECUTE TC>2009-10-21 17:35:28.682475 [DEBUG] switch_core_state_machine.c:151 TC>sofia/internal/1001@singtel Standard EXECUTE TC>EXECUTE sofia/internal/1001@singtel set(open=true) TC>2009-10-21 17:35:28.682475 [DEBUG] mod_dptools.c:748 TC>sofia/internal/1001@singtel SET [open]=[true] TC>EXECUTE sofia/internal/1001@singtelhash(insert/10.4.62.7-spymap/1001/5c3ebda2-be57-11de-a6dd-e7de0b74bdc9) TC>EXECUTE sofia/internal/1001@singtelhash(insert/10.4.62.7-last_dial/1001/05492122) TC>EXECUTE sofia/internal/1001@singtelhash(insert/10.4.62.7-last_dial/global/5c3ebda2-be57-11de-a6dd-e7de0b74bdc9) TC>EXECUTE sofia/internal/1001@singtelset(effective_caller_id_number=1001282122) TC>2009-10-21 17:35:28.685219 [DEBUG] mod_dptools.c:748 TC>sofia/internal/1001@singtel SET [effective_caller_id_number]=[1001282122] TC>EXECUTE sofia/internal/1001@singtel set(NCX_IP=10.4.4.254) TC>2009-10-21 17:35:28.685219 [DEBUG] mod_dptools.c:748 TC>sofia/internal/1001@singtel SET [NCX_IP]=[10.4.4.254] TC>EXECUTE sofia/internal/1001@singtel set(call_timeout=30) TC>2009-10-21 17:35:28.686292 [DEBUG] mod_dptools.c:748 TC>sofia/internal/1001@singtel SET [call_timeout]=[30] TC>EXECUTE sofia/internal/1001@singtel set(hangup_after_bridge=true) TC>2009-10-21 17:35:28.686292 [DEBUG] mod_dptools.c:748 TC>sofia/internal/1001@singtel SET [hangup_after_bridge]=[true] TC>EXECUTE sofia/internal/1001@singtel set(bypass_media=false) TC>2009-10-21 17:35:28.686292 [DEBUG] mod_dptools.c:748 TC>sofia/internal/1001@singtel SET [bypass_media]=[false] TC>EXECUTE sofia/internal/1001@singtel set(proxy_media=true) TC>2009-10-21 17:35:28.686292 [DEBUG] mod_dptools.c:748 TC>sofia/internal/1001@singtel SET [proxy_media]=[true] TC>EXECUTE sofia/internal/1001@singtel bridge(h323/05492122@10.4.4.254) TC>2009-10-21 17:35:28.687357 [DEBUG] mod_h323.cpp:1380 TC>======>create_outgoing_channel DST NUMBER = 05492122@10.4.4.254 TC>2009-10-21 17:35:28.687357 [DEBUG] mod_h323.cpp:1336 TC>======>FSH323EndPoint::FSMakeCall DST NUMBER = TC>05492122@10.4.4.254[FSH323EndPoint] TC>2009-10-21 17:35:28.687357 [DEBUG] mod_h323.cpp:1348 ----> Using 10.4.62.7 TC>for outbound call TC>2009-10-21 17:35:28.687357 [DEBUG] pdns.cxx:498 SRV Lookup 10.4.4.254 TC>service _h323cs._tcp. TC>2009-10-21 17:35:28.689538 [DEBUG] pdns.cxx:471 SRV Lookup Fail no domain TC>h323:05492122@10.4.4.254 <h323%3A05492122@10.4.4.254> TC>2009-10-21 17:35:28.689538 [DEBUG] h323ep.cxx:2315 DNS SRV Cannot resolve TC>remote party 05492122@10.4.4.254 TC>2009-10-21 17:35:28.689538 [DEBUG] h323ep.cxx:2103 Making call to: TC>05492122@10.4.4.254 TC>2009-10-21 17:35:28.689538 [DEBUG] mod_h323.cpp:483 TC>======>FSH323EndPoint::CreateConnection callReference = 12651 userDate = TC>0xb5e0dac0 [FSH323EndPoint] TC>2009-10-21 17:35:28.689538 [DEBUG] mod_h323.cpp:486 ------> TC>SWITCH_CALL_DIRECTION_OUTBOUND TC>2009-10-21 17:35:28.689538 [DEBUG] mod_h323.cpp:496 ------> fsSession = TC>0xb7569548 TC>2009-10-21 17:35:28.689538 [DEBUG] h323caps.cxx:3186 Added capability: TC>G.711-ALaw-64k <1> TC>2009-10-21 17:35:28.689538 [DEBUG] h323caps.cxx:3186 Added capability: TC>G.711-uLaw-64k <2> TC>2009-10-21 17:35:28.689538 [DEBUG] h323caps.cxx:3186 Added capability: TC>GSM-06.10{sw} <3> TC>2009-10-21 17:35:28.689538 [DEBUG] h323caps.cxx:3186 Added capability: TC>G.729A/B{sw} <4> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>G.729A{sw} <5> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>G.729B{sw} <6> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>G.729{sw} <7> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>UserInput/hookflash <8> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>UserInput/basicString <9> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>UserInput/dtmf <10> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>UserInput/RFC2833 <11> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>UserInput/Navigation <12> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>UserInput/Softkey <13> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>UserInput/PointDevice <14> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3186 Added capability: TC>UserInput/Modal <15> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 1 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>G.711-ALaw-64k <1> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 2 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>G.711-uLaw-64k <2> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 3 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>GSM-06.10{sw} <3> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 4 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>G.729A/B{sw} <4> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 5 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>G.729A{sw} <5> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 6 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>G.729B{sw} <6> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 7 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>G.729{sw} <7> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 8 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>UserInput/hookflash <8> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 9 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>UserInput/basicString <9> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 10 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>UserInput/dtmf <10> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 11 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>UserInput/RFC2833 <11> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 12 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>UserInput/Navigation <12> TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3248 FindCapability: 13 TC>2009-10-21 17:35:28.690630 [DEBUG] h323caps.cxx:3252 Found capability: TC>UserInput/Softkey <13> TC>2009-10-21 17:35:28.691688 [DEBUG] h323caps.cxx:3248 FindCapability: 14 TC>2009-10-21 17:35:28.691688 [DEBUG] h323caps.cxx:3252 Found capability: TC>UserInput/PointDevice <14> TC>2009-10-21 17:35:28.691688 [DEBUG] h323caps.cxx:3248 FindCapability: 15 TC>2009-10-21 17:35:28.691688 [DEBUG] h323caps.cxx:3252 Found capability: TC>UserInput/Modal <15> TC>2009-10-21 17:35:28.691688 [DEBUG] h4601.cxx:1725 Endpoint Attached TC>2009-10-21 17:35:28.691688 [DEBUG] rfc2833.cxx:87 Handler created TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:525 TC>======>FSH323Connection::FSH323Connection FSH323Connection TC>2009-10-21 17:35:28.691688 [NOTICE] switch_channel.c:602 New Channel h323/ TC>05492122@10.4.4.254 [5c413af0-be57-11de-a6dd-e7de0b74bdc9] TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:546 (h323/ TC>05492122@10.4.4.254) State Change CS_NEW -> CS_INIT TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:958 TC>======>FSH323Connection::state_change FSH323Connection TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:959 State changed on TC>connection FSH323Connection TC>2009-10-21 17:35:28.691688 [DEBUG] switch_core_session.c:932 Send signal TC>h323/05492122@10.4.4.254 [BREAK] TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:831 TC>======>FSH323Connection::kill_channel FSH323Connection TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:832 Kill 3 on connection TC>FSH323Connection TC>2009-10-21 17:35:28.691688 [DEBUG] h323ep.cxx:2183 Created new connection: TC>ip$localhost/12651 TC>2009-10-21 17:35:28.691688 [DEBUG] osutil.cxx:188 File handle high water TC>mark set: 58 Thread unblock pipe TC>2009-10-21 17:35:28.691688 [DEBUG] h323ep.cxx:1101 Started call thread TC>2009-10-21 17:35:28.691688 [CRIT] switch_core_state_machine.c:239 Obtained TC>10 stack frames. TC>/usr/local/freeswitch/lib/libfreeswitch.so.1 [0xb7f511f5] TC>[0xb80be400] TC>/usr/local/freeswitch/mod/mod_h323.so [0xb6953cc9] TC>/usr/local/freeswitch/lib/libfreeswitch.so.1(switch_core_session_outgoing_channel+0x1ad) TC>[0xb7f4f59d] TC>/usr/local/freeswitch/lib/libfreeswitch.so.1(switch_ivr_originate+0x1b2b) TC>[0xb7f7aa1b] TC>/usr/local/freeswitch/mod/mod_dptools.so [0xb70ea2d5] TC>/usr/local/freeswitch/lib/libfreeswitch.so.1(switch_core_session_exec+0x284) TC>[0xb7f4c5a4] TC>/usr/local/freeswitch/lib/libfreeswitch.so.1(switch_core_session_execute_application+0x8b) TC>[0xb7f4cacb] TC>/usr/local/freeswitch/lib/libfreeswitch.so.1(switch_core_session_run+0x12b8) TC>[0xb7f52658] TC>/usr/local/freeswitch/lib/libfreeswitch.so.1 [0xb7f4e6c5] TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:509 TC>======>FSH323EndPoint::OnSetGatewayPrefixes FSH323EndPoint TC>2009-10-21 17:35:28.691688 [CRIT] switch_core_state_machine.c:352 Thread has TC>crashed for channel sofia/internal/1001@singtel TC>2009-10-21 17:35:28.691688 [NOTICE] switch_core_state_machine.c:353 Hangup TC>sofia/internal/1001@singtel [CS_EXECUTE] [CRASH] TC>2009-10-21 17:35:28.691688 [DEBUG] transports.cxx:1828 Connecting to TC>10.4.4.254:1720 (local port=0) TC>2009-10-21 17:35:28.691688 [DEBUG] osutil.cxx:188 File handle high water TC>mark set: 59 PTCPSocket TC>2009-10-21 17:35:28.691688 [DEBUG] switch_channel.c:1683 Send signal TC>sofia/internal/1001@singtel [KILL] TC>2009-10-21 17:35:28.691688 [DEBUG] switch_core_session.c:932 Send signal TC>sofia/internal/1001@singtel [BREAK] TC>2009-10-21 17:35:28.691688 [DEBUG] switch_core_state_machine.c:398 TC>(sofia/internal/1001@singtel) Running State Change CS_HANGUP TC>2009-10-21 17:35:28.691688 [DEBUG] transports.cxx:1681 Started connection: TC>host=10.4.4.254:1720, if=10.4.62.7:52984, handle=59 TC>2009-10-21 17:35:28.691688 [DEBUG] h323.cxx:2680 Sending Setup PDU TC>2009-10-21 17:35:28.691688 [DEBUG] switch_core_state_machine.c:434 TC>(sofia/internal/1001@singtel) State HANGUP TC>2009-10-21 17:35:28.691688 [DEBUG] mod_sofia.c:338 Channel TC>sofia/internal/1001@singtel hanging up, cause: CRASH TC>2009-10-21 17:35:28.691688 [DEBUG] mod_sofia.c:417 Responding to INVITE TC>with: 480 TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:559 TC>======>FSH323Connection::OnSetLocalCapabilities() [FSH323Connection] TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:565 TC>======>FSH323Connection::SetLocalCapabilities() Size local capability = 15 TC>[FSH323Connection] TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:600 TC>======>FSH323Connection::decodeCapability TC>2009-10-21 17:35:28.691688 [INFO] mod_h323.cpp:619 capability TC>'G.711-ALaw-64k' format 'PCMU' payload 8 TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:600 TC>======>FSH323Connection::decodeCapability TC>2009-10-21 17:35:28.691688 [INFO] mod_h323.cpp:619 capability TC>'G.711-uLaw-64k' format 'PCMA' payload 0 TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:600 TC>======>FSH323Connection::decodeCapability TC>2009-10-21 17:35:28.691688 [INFO] mod_h323.cpp:619 capability 'GSM-06.10' TC>format 'GSM' payload 3 TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:600 TC>======>FSH323Connection::decodeCapability TC>2009-10-21 17:35:28.691688 [INFO] mod_h323.cpp:619 capability 'G.729A/B' TC>format 'G729' payload 18 TC>2009-10-21 17:35:28.691688 [DEBUG] switch_core_state_machine.c:46 TC>sofia/internal/1001@singtel Standard HANGUP, cause: CRASH TC>2009-10-21 17:35:28.691688 [DEBUG] switch_core_state_machine.c:434 TC>(sofia/internal/1001@singtel) State HANGUP going to sleep TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:600 TC>======>FSH323Connection::decodeCapability TC>2009-10-21 17:35:28.691688 [INFO] mod_h323.cpp:619 capability 'G.729A' TC>format 'G729' payload 18 TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:600 TC>======>FSH323Connection::decodeCapability TC>2009-10-21 17:35:28.691688 [INFO] mod_h323.cpp:619 capability 'G.729B' TC>format 'G729b' payload 18 TC>2009-10-21 17:35:28.691688 [DEBUG] mod_h323.cpp:600 TC>======>FSH323Connection::decodeCapability TC> TC> TC> TC>Well, I'm not sure if the backtrace is from 1.0.4 ... i will disable TC>crass-protection and will send new logs to you. TC> TC> TC>Also, if you like i can give you access to the machine itself... TC> TC>T. TC> C уважением With Best Regards Георгиевский Юрий. Georgiewskiy Yuriy +7 4872 711666 +7 4872 711666 факс +7 4872 711143 fax +7 4872 711143 Компания ООО "Ай Ти Сервис" IT Service Ltd http://nkoort.ru http://nkoort.ru JID: GHhost@jabber.tula-ix.net.ru JID: GHhost@jabber.tula-ix.net.ru YG129-RIPE YG129-RIPE