Log is Activated 26d:13h:35m:0s ( lgr_flow)(633827 ) | | TransactionUserMngr::ReturnDialog - #16 [Time: 13:35:00] 26d:13h:35m:0s ( sip_stack)(633828 ) SIPDialog(#16) changes state from DialogDisconnected to DialogIdle [Time: 13:35:00] 26d:13h:35m:4s ( lgr_ipconn)(633829 ) ?? [WARNING] IP:10.0.0.109 QoS status:QoS UnKnown [Time: 13:35:04] 26d:13h:35m:16s ( sip_stack)(633830 ) New SIPMessage created - #1 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633831 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:16] 26d:13h:35m:16s OPTIONS sip:pstn-5665@10.0.0.110:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK49eac1a1 Max-Forwards: 70 From: "asterisk" ;tag=as5b100c96 To: Contact: Call-ID: 68a55ce4271725362a0f1d0634aed6e2@10.0.0.109:5060 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 16.13.0 Date: Sat, 26 Dec 2020 20:35:23 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633833 ) new AcSIPDialogAPI created - #11 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633834 ) | | new AcSIPDialog created - #7 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633835 ) | |(SIPTU#7)OPTIONS State:DialogIdle() [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633836 ) SIPDialog(#7) changes state from DialogIdle to DialogInitiated [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633837 ) ServicesMngr::GetEndPoint PhoneNum = pstn-5665 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_psbrdif)(633838 ) MotherBoard::GetTrunkGroupId - No entry found for: DstNum:pstn-5665 SrcNum:asterisk SrcIp:a00006d go to default [Time: 13:35:16] 26d:13h:35m:16s ( lgr_TrnkGrp)(633839 ) !! [ERROR] #0:TrunkGroup::AllocateEndPoint- Can't find EndPoint for phone number pstn-5665 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_psbrdif)(633840 ) !! [ERROR] MotherBoard::GetEndPoint- Can't find EndPoint for Dest:pstn-5665 Source:asterisk SourceIp:110fa98 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_call)(633841 ) !! [ERROR] Call::GetEndPoint- Can't find endpoint for phone number pstn-5665 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633842 ) New SIPMessage created - #4 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633843 ) | |(SIPTU#7)GENERAL_RESPONSE_REQ State:DialogInitiated(68a55ce4271725362a0f1d0634aed6e2@10.0.0.109:5060) [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633844 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:16] 26d:13h:35m:16s SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK49eac1a1 From: "asterisk" ;tag=as5b100c96 To: ;tag=1c342240033 Call-ID: 68a55ce4271725362a0f1d0634aed6e2@10.0.0.109:5060 CSeq: 102 OPTIONS Supported: 100rel Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 X-Resources: telchs=4/0;mediachs=0/0 Content-Length: 0 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633846 ) Resource SIPMessage deleted - #4 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633847 ) | |(SIPTU#7)DIALOG_DISCONNECT_REQ State:DialogInitiated(68a55ce4271725362a0f1d0634aed6e2@10.0.0.109:5060) [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633848 ) SIPDialog(#7) changes state from DialogInitiated to DialogDisconnected [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633849 ) AcSIPStackAPI::FreeDialogAPI - #11 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633850 ) Resource SIPMessage deleted - #1 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633851 ) New SIPMessage created - #9 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633852 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:16] 26d:13h:35m:16s OPTIONS sip:pstn-1270@10.0.0.110:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK0ff029f8 Max-Forwards: 70 From: "asterisk" ;tag=as5b50e970 To: Contact: Call-ID: 3507048d2a4f792e13041ddd7b547be9@10.0.0.109:5060 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 16.13.0 Date: Sat, 26 Dec 2020 20:35:23 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633854 ) new AcSIPDialogAPI created - #15 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633855 ) | | new AcSIPDialog created - #0 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633856 ) | |(SIPTU#0)OPTIONS State:DialogIdle() [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633857 ) SIPDialog(#0) changes state from DialogIdle to DialogInitiated [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633858 ) ServicesMngr::GetEndPoint PhoneNum = pstn-1270 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_psbrdif)(633859 ) MotherBoard::GetTrunkGroupId - No entry found for: DstNum:pstn-1270 SrcNum:asterisk SrcIp:a00006d go to default [Time: 13:35:16] 26d:13h:35m:16s ( lgr_TrnkGrp)(633860 ) !! [ERROR] #0:TrunkGroup::AllocateEndPoint- Can't find EndPoint for phone number pstn-1270 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_psbrdif)(633861 ) !! [ERROR] MotherBoard::GetEndPoint- Can't find EndPoint for Dest:pstn-1270 Source:asterisk SourceIp:110fa98 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_call)(633862 ) !! [ERROR] Call::GetEndPoint- Can't find endpoint for phone number pstn-1270 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633863 ) New SIPMessage created - #3 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633864 ) | |(SIPTU#0)GENERAL_RESPONSE_REQ State:DialogInitiated(3507048d2a4f792e13041ddd7b547be9@10.0.0.109:5060) [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633865 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:16] 26d:13h:35m:16s SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK0ff029f8 From: "asterisk" ;tag=as5b50e970 To: ;tag=1c342430484 Call-ID: 3507048d2a4f792e13041ddd7b547be9@10.0.0.109:5060 CSeq: 102 OPTIONS Supported: 100rel Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 X-Resources: telchs=4/0;mediachs=0/0 Content-Length: 0 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633867 ) Resource SIPMessage deleted - #3 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633868 ) | |(SIPTU#0)DIALOG_DISCONNECT_REQ State:DialogInitiated(3507048d2a4f792e13041ddd7b547be9@10.0.0.109:5060) [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633869 ) SIPDialog(#0) changes state from DialogInitiated to DialogDisconnected [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633870 ) AcSIPStackAPI::FreeDialogAPI - #15 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633871 ) Resource SIPMessage deleted - #9 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633872 ) New SIPMessage created - #10 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633873 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:16] 26d:13h:35m:16s OPTIONS sip:55@10.0.0.110:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK03ce62e4 Max-Forwards: 70 From: "asterisk" ;tag=as2fdd6521 To: Contact: Call-ID: 5ef0ca1b239c557127a31d2555d73d68@10.0.0.109:5060 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 16.13.0 Date: Sat, 26 Dec 2020 20:35:23 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633875 ) new AcSIPDialogAPI created - #5 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633876 ) | | new AcSIPDialog created - #9 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633877 ) | |(SIPTU#9)OPTIONS State:DialogIdle() [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633878 ) SIPDialog(#9) changes state from DialogIdle to DialogInitiated [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633879 ) ServicesMngr::GetEndPoint PhoneNum = 55 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_psbrdif)(633880 ) GetTrunkGroupId- TrunkGroup:1 found DstNum:55 DstPfx:55 SrcNum:asterisk SrcPfx:* SrcIp:a00006d SrcIpPfx:* [Time: 13:35:16] 26d:13h:35m:16s ( lgr_psbrdif)(633881 ) QueryOnHookPortStatus (ChannelNum=2), status = 1 Polarity = 0 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_psbrdif)(633882 ) QueryOnHookPortStatus (ChannelNum=3), status = 1 Polarity = 0 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633883 ) New SIPMessage created - #2 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633884 ) | |(SIPTU#9)GENERAL_RESPONSE_REQ State:DialogInitiated(5ef0ca1b239c557127a31d2555d73d68@10.0.0.109:5060) [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633885 ) SIPDialog(#9) changes state from DialogInitiated to DialogConnected [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633886 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:16] 26d:13h:35m:16s SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK03ce62e4 From: "asterisk" ;tag=as2fdd6521 To: ;tag=1c342678793 Call-ID: 5ef0ca1b239c557127a31d2555d73d68@10.0.0.109:5060 CSeq: 102 OPTIONS Contact: Supported: 100rel Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 X-Resources: telchs=4/0;mediachs=0/0 Accept: application/sdp, application/simple-message-summary, message/sipfrag Content-Type: application/sdp Content-Length: 251 v=0 o=AudiocodesGW 342693683 342693555 IN IP4 10.0.0.110 s=Phone-Call c=IN IP4 10.0.0.110 t=0 0 m=audio 6000 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633888 ) Resource SIPMessage deleted - #2 [Time: 13:35:16] 26d:13h:35m:16s ( lgr_flow)(633889 ) | |(SIPTU#9)DIALOG_DISCONNECT_REQ State:DialogConnected(5ef0ca1b239c557127a31d2555d73d68@10.0.0.109:5060) [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633890 ) SIPDialog(#9) changes state from DialogConnected to DialogDisconnected [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633891 ) AcSIPStackAPI::FreeDialogAPI - #5 [Time: 13:35:16] 26d:13h:35m:16s ( sip_stack)(633892 ) Resource SIPMessage deleted - #10 [Time: 13:35:16] 26d:13h:35m:17s ( lgr_psbrdex)(633893 ) recv <-- OFF_HOOK Ch:0 [Time: 13:35:17] 26d:13h:35m:17s ( lgr_flow)(633894 ) #0:OFF_HOOK_EV [Time: 13:35:17] 26d:13h:35m:17s ( lgr_flow)(633895 ) | #0:OFF_HOOK_EV [Time: 13:35:17] 26d:13h:35m:17s ( lgr_psbrdif)(633896 ) UpdateChannelParams, Channel 0 [Time: 13:35:17] 26d:13h:35m:17s ( lgr_psbrdif)(633897 ) #0:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=2, VoiceVol= 1, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 13:35:17] 26d:13h:35m:17s ( lgr_psbrdif)(633898 ) ActivateDigitMap for channel : 0, MaxDialStringLength = 32, MaxEndDialTimer = 4000, MaxLongInterDigitTimer = 8000, MaxStartTimer = 16000, DigitMap = [0-9*#ABCD][0-9ABCD].T, DialPlanIndex = -1 [Time: 13:35:17] 26d:13h:35m:17s ( lgr_flow)(633899 ) #-100: StartDigitMapDetection with params: [Time: 13:35:17] 26d:13h:35m:17s (lgr_digitmap_mngr)(633900 ) #0:Activate DigitMapMngr pattern:[0-9*#ABCD][0-9ABCD].T, Max Length is: 32, DialPlanIndex: -1 [Time: 13:35:17] 26d:13h:35m:17s ( lgr_psbrdif)(633901 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 13:35:17] 26d:13h:35m:17s ( lgr_psbrdex)(633902 ) PCIIFChangeChannelParams failed ECNlpMode [Time: 13:35:17] 26d:13h:35m:17s ( lgr_psbrdif)(633903 ) Changed ECNlpMOde to: 1 [Time: 13:35:17] 26d:13h:35m:17s ( lgr_psbrdif)(633904 ) #0:PSOSBoardInterface::PlayTone - Called Tone=DIAL_TONE Direction=PLAY_TONE_2_TEL [Time: 13:35:17] 26d:13h:35m:19s ( lgr_psbrdex)(633905 ) recv <-- DIGIT(7) Ch:0 OnTime:0 InterTime:247504185 Direction:0 System:1 [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633906 ) #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633907 ) | #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_psbrdif)(633908 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 13:35:19] 26d:13h:35m:19s ( lgr_psbrdex)(633909 ) recv <-- DIGIT(7) Ch:0 OnTime:90 InterTime:247504185 Direction:0 System:1 [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633910 ) #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633911 ) | #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_psbrdex)(633912 ) recv <-- DIGIT(4) Ch:0 OnTime:0 InterTime:110 Direction:0 System:1 [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633913 ) #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633914 ) | #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_psbrdex)(633915 ) recv <-- DIGIT(4) Ch:0 OnTime:90 InterTime:110 Direction:0 System:1 [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633916 ) #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633917 ) | #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_psbrdex)(633918 ) recv <-- DIGIT(7) Ch:0 OnTime:0 InterTime:110 Direction:0 System:1 [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633919 ) #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633920 ) | #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_psbrdex)(633921 ) recv <-- DIGIT(7) Ch:0 OnTime:90 InterTime:110 Direction:0 System:1 [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633922 ) #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633923 ) | #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_psbrdex)(633924 ) recv <-- DIGIT(9) Ch:0 OnTime:0 InterTime:110 Direction:0 System:1 [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633925 ) #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633926 ) | #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_psbrdex)(633927 ) recv <-- DIGIT(9) Ch:0 OnTime:90 InterTime:110 Direction:0 System:1 [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633928 ) #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:19s ( lgr_flow)(633929 ) | #0:DIGIT_EV [Time: 13:35:19] 26d:13h:35m:20s ( lgr_psbrdex)(633930 ) recv <-- DIGIT(1) Ch:0 OnTime:0 InterTime:110 Direction:0 System:1 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633931 ) #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633932 ) | #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_psbrdex)(633933 ) recv <-- DIGIT(1) Ch:0 OnTime:90 InterTime:110 Direction:0 System:1 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633934 ) #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633935 ) | #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_psbrdex)(633936 ) recv <-- DIGIT(2) Ch:0 OnTime:0 InterTime:100 Direction:0 System:1 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633937 ) #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633938 ) | #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_psbrdex)(633939 ) recv <-- DIGIT(2) Ch:0 OnTime:100 InterTime:100 Direction:0 System:1 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633940 ) #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633941 ) | #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_psbrdex)(633942 ) recv <-- DIGIT(7) Ch:0 OnTime:0 InterTime:110 Direction:0 System:1 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633943 ) #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633944 ) | #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_psbrdex)(633945 ) recv <-- DIGIT(7) Ch:0 OnTime:90 InterTime:110 Direction:0 System:1 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633946 ) #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633947 ) | #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_psbrdex)(633948 ) recv <-- DIGIT(0) Ch:0 OnTime:0 InterTime:110 Direction:0 System:1 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633949 ) #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633950 ) | #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_psbrdex)(633951 ) InsertBoardEvent- event 138 inserted channel -100 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_psbrdex)(633952 ) recv <-- DIGIT(0) Ch:0 OnTime:90 InterTime:110 Direction:0 System:1 [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633953 ) new AcSIPDialogAPI created - #10 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633954 ) | | new AcSIPDialog created - #1 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633955 ) | |(SIPTU#1)DIALOG_INIT_REQ State:DialogIdle() [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633956 ) SIPDialog(#1) changes state from DialogIdle to DialogInitiated [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633957 ) New SIPMessage created - #6 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633958 ) | |(SIPTU#1)GENERAL_REQ State:DialogInitiated(35354595526122020133520@10.0.0.110) [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633959 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:20] 26d:13h:35m:20s OPTIONS sip:gateway@10.0.0.110 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac353553947 Max-Forwards: 70 From: ;tag=1c353546463 To: Call-ID: 35354595526122020133520@10.0.0.110 CSeq: 1 OPTIONS Contact: Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Accept: application/sdp, application/simple-message-summary, message/sipfrag Content-Length: 0 [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633961 ) UdpRtxMngr::Transmit 1 OPTIONS Rtx Left: 6 Dest: 10.0.0.109:5060 CallID: (35354595526122020133520@10.0.0.110) [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633962 ) Resource SIPMessage deleted - #6 [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633963 ) New SIPMessage created - #8 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633964 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:20] 26d:13h:35m:20s SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac353553947;received=10.0.0.110 From: ;tag=1c353546463 To: ;tag=as46ec9c57 Call-ID: 35354595526122020133520@10.0.0.110 CSeq: 1 OPTIONS Server: Asterisk PBX 16.13.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Accept: application/sdp Content-Length: 0 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633966 ) | |(SIPTU#1)404 State:DialogInitiated(35354595526122020133520@10.0.0.110) [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633967 ) | |(SIPTU#1)DIALOG_DISCONNECT_REQ State:DialogInitiated(35354595526122020133520@10.0.0.110) [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633968 ) SIPDialog(#1) changes state from DialogInitiated to DialogDisconnected [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633969 ) AcSIPStackAPI::FreeDialogAPI - #10 [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633970 ) UdpRtxMngr::Remove 1 OPTIONS [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633971 ) Resource SIPMessage deleted - #8 [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633972 ) | | TransactionUserMngr::ReturnDialog - #1 [Time: 13:35:20] 26d:13h:35m:20s ( sip_stack)(633973 ) SIPDialog(#1) changes state from DialogDisconnected to DialogIdle [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633974 ) #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:20s ( lgr_flow)(633975 ) | #0:DIGIT_EV [Time: 13:35:20] 26d:13h:35m:21s ( lgr_flow)(633976 ) | | TransactionUserMngr::ReturnDialog - #7 [Time: 13:35:21] 26d:13h:35m:21s ( sip_stack)(633977 ) SIPDialog(#7) changes state from DialogDisconnected to DialogIdle [Time: 13:35:21] 26d:13h:35m:21s ( lgr_flow)(633978 ) | | TransactionUserMngr::ReturnDialog - #0 [Time: 13:35:21] 26d:13h:35m:21s ( sip_stack)(633979 ) SIPDialog(#0) changes state from DialogDisconnected to DialogIdle [Time: 13:35:21] 26d:13h:35m:21s ( lgr_flow)(633980 ) | | TransactionUserMngr::ReturnDialog - #9 [Time: 13:35:21] 26d:13h:35m:21s ( sip_stack)(633981 ) SIPDialog(#9) changes state from DialogDisconnected to DialogIdle [Time: 13:35:21] 26d:13h:35m:24s ( lgr_psbrdex)(633982 ) recv <-- EV_DIALED_STRING Ch:0 Str:74791270 MapNum:0 CM:FM Match:1 EI: [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633983 ) #0:DIALED_STRING_EV [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633984 ) | #0:DIALED_STRING_EV [Time: 13:35:24] 26d:13h:35m:24s (lgr_digitmap_mngr)(633985 ) DigitMapMngr::HandleDialStringEv Match = 1, MatchNum = 0 STR = 74791270 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(633986 ) Call Allocated ResourceID: 2 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633987 ) | #0:NEW_CALL_EV (send) : (UnKnown) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633988 ) | | #2:NEW_CALL_EV:(UnKnown) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_mngr)(633989 ) Resource StackSession <#2> Allocated [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633990 ) | | #2:Call changing states from:IdleState to:NewCallState_Tel2IP [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633991 ) | | | #2:NEW_CALL_EV(Unknown) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(633992 ) | | #2GetNextUI:GlobalUI=129671305, mACAddrLsb=1965951 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(633993 ) | | #2GetNextUI:GlobalUI=129671306 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633994 ) | (to 74791270) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633995 ) | #0:SETUP_EV (send) : (UnKnown) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633996 ) | | #2:SETUP (TO:74791270, FROM:55):(UnKnown) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(633997 ) new call from EndPoint [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633998 ) | | #2:Call changing states from:NewCallState_Tel2IP to:InitiatedState_Tel2IP [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(633999 ) | | | #2:SETUP_EV(Unknown) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stack)(634000 ) FindIpDestination: rmRc:0 (OK) SrcIpGroup:-1 IpconnHndl:151 DstPrefix:74791270 DstIp:10.0.0.109 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stack)(634001 ) RoutingInstance (#2) RTRouting: trying to find a route according to Routing Table [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634002 ) UpdateAfterDecidingRouting: IpProfileId (0), ChargeCode (255), NewIndex (1) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(634003 ) Call::SetCoderListForCall #2 Found 2 Common Coders For Call [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(634004 ) Coder g711Ulaw64k20 : 20 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(634005 ) Coder g711Alaw64k20 : 20 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_profiling)(634006 ) Profiled: JBMinDel=10 JBOptF=10 EEarlyM=1 FaxTM=1 IPDS=46 IsFaxU=1 PI2IP=-1 SigIPDF=40 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 RTPRD=0 SCE=0 VxxTT=2 Dst2Rdrt=0 DTMFVol=20 ECE=1 ECurDis=1 EDigDel=0 ERevP=0 FHPer=700 InG=32 MWIA=1 MWID=1 VVol=33 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DiscOnBrok=1 DPInd=255 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634007 ) DecideRoutingSetup DestIpGroupId:0 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634008 ) New SIPMessage created - #11 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634009 ) new AcSIPCallAPI created - #2 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634010 ) | | new GetNewSIPCall created - #10 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634011 ) SIPSDPSession#2 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERING [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634012 ) | |(SIPTU#10)SETUP_REQ State:Idle() [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634013 ) SIPCall(#10) changes state from Idle to Inviting [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634014 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:24] 26d:13h:35m:24s INVITE sip:74791270@10.0.0.109 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac363670005 Max-Forwards: 70 From: ;tag=1c363665875 To: Call-ID: 36366540026122020133524@10.0.0.110 CSeq: 1 INVITE Contact: Supported: em,100rel,timer,replaces,path,early-session,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Content-Type: application/sdp Content-Disposition: session Content-Length: 251 v=0 o=AudiocodesGW 363659435 363659290 IN IP4 10.0.0.110 s=Phone-Call c=IN IP4 10.0.0.110 t=0 0 m=audio 6020 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634016 ) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 6 Dest: 10.0.0.109:5060 CallID: (36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634017 ) Resource SIPMessage deleted - #11 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634018 ) New SIPMessage created - #0 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634019 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:24] 26d:13h:35m:24s SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac363670005;received=10.0.0.110 From: ;tag=1c363665875 To: ;tag=as38324f21 Call-ID: 36366540026122020133524@10.0.0.110 CSeq: 1 INVITE Server: Asterisk PBX 16.13.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="04de5975" Content-Length: 0 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634021 ) UdpRtxMngr::Remove 1 INVITE [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634022 ) | |(SIPTU#10)401 State:Inviting(36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634023 ) New SIPMessage created - #5 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634024 ) New SIPMessage created - #7 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634025 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:24] 26d:13h:35m:24s ACK sip:74791270@10.0.0.109 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac363670005 Max-Forwards: 70 From: ;tag=1c363665875 To: ;tag=as38324f21 Call-ID: 36366540026122020133524@10.0.0.110 CSeq: 1 ACK Contact: Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Content-Length: 0 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634027 ) Resource SIPMessage deleted - #7 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634028 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:24] 26d:13h:35m:24s INVITE sip:74791270@10.0.0.109 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac363767927 Max-Forwards: 70 From: ;tag=1c363665875 To: Call-ID: 36366540026122020133524@10.0.0.110 CSeq: 2 INVITE Authorization: Digest username="55",realm="asterisk",nonce="04de5975",uri="sip:74791270@10.0.0.109",algorithm=MD5,response="556c1ad599ce45d6d6e89e858663a728" Contact: Supported: em,100rel,timer,replaces,path,early-session,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Content-Type: application/sdp Content-Disposition: session Content-Length: 251 v=0 o=AudiocodesGW 363659435 363659290 IN IP4 10.0.0.110 s=Phone-Call c=IN IP4 10.0.0.110 t=0 0 m=audio 6020 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/800 26d:13h:35m:24s ( sip_stack)(634030 ) UdpRtxMngr::Transmit 2 INVITE Rtx Left: 6 Dest: 10.0.0.109:5060 CallID: (36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634031 ) Resource SIPMessage deleted - #5 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634032 ) Resource SIPMessage deleted - #0 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634033 ) New SIPMessage created - #4 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634034 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:24] 26d:13h:35m:24s SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac363767927;received=10.0.0.110 From: ;tag=1c363665875 To: Call-ID: 36366540026122020133524@10.0.0.110 CSeq: 2 INVITE Server: Asterisk PBX 16.13.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634036 ) UdpRtxMngr::Remove 2 INVITE [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634037 ) | |(SIPTU#10)100 State:Inviting(36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634038 ) SIPCall(#10) changes state from Inviting to Proceeding [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634039 ) Resource SIPMessage deleted - #4 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634040 ) | | | #2:SIP_TRYING_EV(36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634041 ) SendToCall - event: PROCEEDING_EV m_Call#2 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634042 ) | | #2:PROCEEDING_EV:(36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634043 ) | #0:PROCEEDING_EV : (36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634044 ) New SIPMessage created - #1 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634045 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:24] 26d:13h:35m:24s INVITE sip:77804791270@10.0.0.110:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK07b05bd9 Max-Forwards: 70 From: "Joseph" ;tag=as0ac0c8f1 To: Contact: Call-ID: 136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060 CSeq: 102 INVITE User-Agent: Asterisk PBX 16.13.0 Date: Sat, 26 Dec 2020 20:35:32 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Type: application/sdp Content-Length: 281 v=0 o=root 515978054 515978054 IN IP4 10.0.0.109 s=Asterisk PBX 16.13.0 c=IN IP4 10.0.0.109 t=0 0 m=audio 15894 RTP/AVP 0 3 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:150 a=sendrecv [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634047 ) new AcSIPCallAPI created - #6 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634048 ) | | new GetNewSIPCall created - #9 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_mngr)(634049 ) Resource StackSession <#6> Allocated [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634050 ) | |(SIPTU#9)INVITE State:Idle() [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634051 ) SIPCall(#9) changes state from Idle to Invited [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634052 ) | | | #6:SIP_SETUP_EV(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(634053 ) Call Allocated ResourceID: 6 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634054 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634055 ) SendToCall - event: NEW_CALL_EV m_Call#6 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634056 ) | | #6:NEW_CALL_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634057 ) | | #6:Call changing states from:IdleState to:NewCallState_IP2Tel [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634058 ) ServicesMngr::GetEndPoint PhoneNum = 77804791270 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_psbrdif)(634059 ) GetTrunkGroupId- TrunkGroup:2 found DstNum:77804791270 DstPfx:7* SrcNum:55 SrcPfx:* SrcIp:a00006d SrcIpPfx:* [Time: 13:35:24] 26d:13h:35m:24s ( lgr_psbrdif)(634060 ) QueryOnHookPortStatus (ChannelNum=3), status = 1 Polarity = 0 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_num)(634061 ) PhoneNumber::RemovePrefix - Number change from 77804791270 to 7804791270 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(634062 ) Call::SetCoderListForCall #6 Found 2 Common Coders For Call [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(634063 ) Coder g711Ulaw64k20 : 20 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(634064 ) Coder g711Alaw64k20 : 20 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_profiling)(634065 ) Profiled: JBMinDel=10 JBOptF=10 EEarlyM=1 FaxTM=1 IPDS=46 IsFaxU=1 PI2IP=-1 SigIPDF=40 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 RTPRD=0 SCE=0 VxxTT=2 Dst2Rdrt=0 DTMFVol=20 ECE=1 ECurDis=1 EDigDel=0 ERevP=0 FHPer=700 InG=32 MWIA=1 MWID=1 VVol=33 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DiscOnBrok=1 DPInd=255 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(634066 ) | | #6GetNextUI:GlobalUI=129671306, mACAddrLsb=1965951 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_call)(634067 ) | | #6GetNextUI:GlobalUI=129671307 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634068 ) | #3:NEW_CALL_EV : (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634069 ) SIPSDPSession#6 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634070 ) | |(SIPTU#9)TRYING_REQ State:Invited(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634071 ) New SIPMessage created - #3 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634072 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:24] 26d:13h:35m:24s SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK07b05bd9 From: "Joseph" ;tag=as0ac0c8f1 To: ;tag=1c363907365 Call-ID: 136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060 CSeq: 102 INVITE Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Content-Length: 0 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634074 ) Resource SIPMessage deleted - #3 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634075 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634076 ) SendToCall - event: SETUP_EV m_Call#6 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634077 ) | | #6:SETUP (TO:77804791270, FROM:55):(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634078 ) | | #6:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634079 ) | #3:SETUP_EV : (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_psbrdif)(634080 ) UpdateChannelParams, Channel 3 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_psbrdif)(634081 ) #3:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=2, VoiceVol= 1, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_psbrdif)(634082 ) Turn ringer ON for channel 3 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634083 ) | #3:FXO Seize Line [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634084 ) | #3:ALERT_EV (send) : (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634085 ) | | #6:ALERT_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634086 ) | | #6:Call changing states from:InitiatedState_IP2Tel to:AlertingState_IP2Tel [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634087 ) | | | #6:ALERT_EV(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634088 ) New SIPMessage created - #9 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634089 ) SIPSDPSession#6 - Changing state from SIP_MEDIA_OFFERED to SIP_MEDIA_COMPLETED [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634090 ) DtmfCapNegotiationAlgorithm :: TxDtmfMethod = DTMF_RFC2833_SUPPORTED [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634091 ) DtmfCapNegotiationAlgorithm :: TxRtpRfc2833Payload = 101 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634092 ) SendToCall - event: DTMF_CONTROL_EV m_Call#6 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634093 ) | | #6:DTMF_CONTROL_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634094 ) SendToCall - event: OPEN_LOGICAL_CHANNEL_ACK_EV m_Call#6 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634095 ) | | #6:OPEN_LOGICAL_CHANNEL_ACK_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634096 ) | #3:OPEN_LOGICAL_CHANNEL_ACK_EV : (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634097 ) | |(SIPTU#9)ALERT_REQ State:Invited(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634098 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:24] 26d:13h:35m:24s SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK07b05bd9 From: "Joseph" ;tag=as0ac0c8f1 To: ;tag=1c363907365 Call-ID: 136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060 CSeq: 102 INVITE Contact: Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Content-Type: application/sdp Content-Length: 248 v=0 o=AudiocodesGW 363956771 363956643 IN IP4 10.0.0.110 s=Phone-Call c=IN IP4 10.0.0.110 t=0 0 m=audio 6010 RTP/AVP 0 101 c=IN IP4 10.0.0.110 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634100 ) Resource SIPMessage deleted - #9 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634101 ) Resource SIPMessage deleted - #1 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634102 ) New SIPMessage created - #2 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634103 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:24] 26d:13h:35m:24s SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac363767927;received=10.0.0.110 From: ;tag=1c363665875 To: ;tag=as2273b2b1 Call-ID: 36366540026122020133524@10.0.0.110 CSeq: 2 INVITE Server: Asterisk PBX 16.13.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634105 ) | |(SIPTU#10)180 State:Proceeding(36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634106 ) | | | #2:SIP_ALERT_EV(36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634107 ) New SIPMessage created - #10 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634108 ) SendToCall - event: PROGRESS_INDICATOR_EV m_Call#2 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634109 ) | | #2:PROGRESS_INDICATOR_EV(PI=-1)(PC=-1):(36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_stk_ses)(634110 ) SendToCall - event: ALERT_EV m_Call#2 [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634111 ) | | #2:ALERT_EV:(36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634112 ) | | #2:Call changing states from:InitiatedState_Tel2IP to:AlertingState_Tel2IP [Time: 13:35:24] 26d:13h:35m:24s ( lgr_flow)(634113 ) | #0:ALERT_EV : (36366540026122020133524@10.0.0.110) [Time: 13:35:24] 26d:13h:35m:24s ( lgr_psbrdif)(634114 ) #0:PSOSBoardInterface::PlayTone - Called Tone=RINGBACK_TONE Direction=PLAY_TONE_2_TEL [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634115 ) Resource SIPMessage deleted - #10 [Time: 13:35:24] 26d:13h:35m:24s ( sip_stack)(634116 ) Resource SIPMessage deleted - #2 [Time: 13:35:24] 26d:13h:35m:25s ( lgr_psbrdex)(634117 ) InsertBoardEvent- event 102 inserted channel 3 [Time: 13:35:25] 26d:13h:35m:26s ( lgr_flow)(634118 ) #3:WAIT_FOR_DIAL_TIMER_EXPIRED_EV [Time: 13:35:26] 26d:13h:35m:26s ( lgr_flow)(634119 ) | #3:WAIT_FOR_DIAL_TIMER_EXPIRED_EV [Time: 13:35:26] 26d:13h:35m:26s ( lgr_psbrdif)(634120 ) #3 Sending DTMFs (7804791270, Dir=2, Len=10, InterTime=100, DigitLen=100) [Time: 13:35:26] 26d:13h:35m:26s ( lgr_flow)(634121 ) | #3:DialPhoneNumber=7804791270 [Time: 13:35:26] 26d:13h:35m:28s ( lgr_flow)(634122 ) #3:DIGIT_ENDED_EV [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634123 ) | #3:DIGIT_ENDED_EV [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634124 ) | #3:DTMF_DIGIT_ENDED_EV (send) : (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634125 ) | | #6:DTMF_DIGIT_ENDED_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634126 ) | #3:OPEN_VOICE (IP:10.0.0.109, RTP:15894, RTCP:0, VoiceCoder:g711Ulaw64k20, VbdCoder: InvalidCoder255, Dtmf:gwRFC2833RalayDTMF,Rx payload:101,Tx payload:101 ,RTPmode:1, FaxTransportType: 1,AVoIPMediaType: gwMediaTypeAudioOnly) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634127 ) activate channel port=15894, BChannel:3, ip=10.0.0.109 (Voice:1,Vbd:0,T38:0,Video:0) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634128 ) #3:ActivateChannel: Socks=14 CID=3 Trunk:-1 BChannel:3 RemoteIP=10.0.0.109 RemotePort=15894 RemoteT38IP= RemoteT38Port=0 RemoteRTCPIP= RemoteRTCPPort=0 FaxModemDet=NO_FAX_MODEM_DETECTED [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634129 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634130 ) #3:OpenChannel:on Trunk -1 BChannel:3 CID=3 with VoiceCoder: g711Ulaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634131 ) #3:OpenChannel VoiceVolume= 1, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634132 ) RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634133 ) OpenChannel, CoderType = 1, Interval = 3, M = 1 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634134 ) #3:FAXTransportType = 1 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634135 ) #3:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=2, VoiceVol= 1, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634136 ) Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634137 ) #3:Channel will be open WITH DSP [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634138 ) OpenChannel - channel was not reopened only updated!!! [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634139 ) Setting ActivateRTP_RTCPCmd.Cmd.IpTosFieldInUdpPacket to 184 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634140 ) #3:ActivateChannel:RtpPayload: 0 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634141 ) #3:PSOSBoardInterface::StopPlayTone- Called [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634142 ) | #3:CONNECT_EV (send) : (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634143 ) Restart ECE control (CID=3) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634144 ) | | #6:CONNECT_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634145 ) | | #6:Call changing states from:AlertingState_IP2Tel to:ConnectedState [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634146 ) | | | #6:CONNECT_EV(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634147 ) New SIPMessage created - #6 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634148 ) | |(SIPTU#9)CONNECT_REQ State:Invited(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634149 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:28] 26d:13h:35m:28s SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK07b05bd9 From: "Joseph" ;tag=as0ac0c8f1 To: ;tag=1c363907365 Call-ID: 136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060 CSeq: 102 INVITE Contact: Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Content-Type: application/sdp Content-Length: 248 v=0 o=AudiocodesGW 363956771 363956643 IN IP4 10.0.0.110 s=Phone-Call c=IN IP4 10.0.0.110 t=0 0 m=audio 6010 RTP/AVP 0 101 c=IN IP4 10.0.0.110 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634151 ) UdpRtxMngr::Transmit 200 Response 102 INVITE Rtx Left: 6 Dest: 10.0.0.109:5060 CallID: (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634152 ) SIPCall(#9) changes state from Invited to LocalAccepted [Time: 13:35:28] 26d:13h:35m:28s ( lgr_stk_ses)(634153 ) DtmfCapNegotiationAlgorithm :: TxDtmfMethod = DTMF_RFC2833_SUPPORTED [Time: 13:35:28] 26d:13h:35m:28s ( lgr_stk_ses)(634154 ) DtmfCapNegotiationAlgorithm :: TxRtpRfc2833Payload = 101 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_stk_ses)(634155 ) SendToCall - event: DTMF_CONTROL_EV m_Call#6 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634156 ) | | #6:DTMF_CONTROL_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_stk_ses)(634157 ) SendToCall - event: OPEN_LOGICAL_CHANNEL_ACK_EV m_Call#6 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634158 ) | | #6:OPEN_LOGICAL_CHANNEL_ACK_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634159 ) Resource SIPMessage deleted - #6 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634160 ) | #3:DTMF_DIGIT_ENDED_EV (send) : (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634161 ) | | #6:DTMF_DIGIT_ENDED_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634162 ) New SIPMessage created - #8 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634163 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:28] 26d:13h:35m:28s ACK sip:pstn-5665@10.0.0.110:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK6ec0692c Max-Forwards: 70 From: "Joseph" ;tag=as0ac0c8f1 To: ;tag=1c363907365 Contact: Call-ID: 136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060 CSeq: 102 ACK User-Agent: Asterisk PBX 16.13.0 Content-Length: 0 [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634165 ) UdpRtxMngr::Remove 200 Response 102 INVITE [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634166 ) | |(SIPTU#9)ACK State:LocalAccepted(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634167 ) SIPCall(#9) changes state from LocalAccepted to Connected [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634168 ) | | | #6:SIP_ACK_EV(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_stk_ses)(634169 ) SendToCall - event: CONNECT_ACK_EV m_Call#6 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634170 ) | | #6:CONNECT_ACK_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634171 ) | #3:CONNECT_ACK_EV : (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634172 ) Resource SIPMessage deleted - #8 [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634173 ) New SIPMessage created - #11 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634174 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:28] 26d:13h:35m:28s SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac363767927;received=10.0.0.110 From: ;tag=1c363665875 To: ;tag=as2273b2b1 Call-ID: 36366540026122020133524@10.0.0.110 CSeq: 2 INVITE Server: Asterisk PBX 16.13.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 260 v=0 o=root 1343815676 1343815676 IN IP4 10.0.0.109 s=Asterisk PBX 16.13.0 c=IN IP4 10.0.0.109 t=0 0 m=audio 18964 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:150 a=sendrecv [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634176 ) | |(SIPTU#10)200 OK State:Proceeding(36366540026122020133524@10.0.0.110) [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634177 ) SIPCall(#10) changes state from Proceeding to RemoteAccepted [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634178 ) | | | #2:SIP_CONNECT_EV(36366540026122020133524@10.0.0.110) [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634179 ) SIPSDPSession#2 - Changing state from SIP_MEDIA_OFFERING to SIP_MEDIA_COMPLETED [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634180 ) New SIPMessage created - #7 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634181 ) | |(SIPTU#10)ACK_REQ State:RemoteAccepted(36366540026122020133524@10.0.0.110) [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634182 ) SIPCall(#10) changes state from RemoteAccepted to Connected [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634183 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:28] 26d:13h:35m:28s ACK sip:74791270@10.0.0.109:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac371905973 Max-Forwards: 70 From: ;tag=1c363665875 To: ;tag=as2273b2b1 Call-ID: 36366540026122020133524@10.0.0.110 CSeq: 2 ACK Contact: Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Content-Length: 0 [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634185 ) Resource SIPMessage deleted - #7 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_stk_ses)(634186 ) DtmfCapNegotiationAlgorithm :: TxDtmfMethod = DTMF_RFC2833_SUPPORTED [Time: 13:35:28] 26d:13h:35m:28s ( lgr_stk_ses)(634187 ) DtmfCapNegotiationAlgorithm :: TxRtpRfc2833Payload = 101 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_stk_ses)(634188 ) SendToCall - event: DTMF_CONTROL_EV m_Call#2 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634189 ) | | #2:DTMF_CONTROL_EV:(36366540026122020133524@10.0.0.110) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_stk_ses)(634190 ) SendToCall - event: OPEN_LOGICAL_CHANNEL_ACK_EV m_Call#2 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634191 ) | | #2:OPEN_LOGICAL_CHANNEL_ACK_EV:(36366540026122020133524@10.0.0.110) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634192 ) | #0:OPEN_LOGICAL_CHANNEL_ACK_EV : (36366540026122020133524@10.0.0.110) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634193 ) | #0:OPEN_VOICE (IP:10.0.0.109, RTP:18964, RTCP:0, VoiceCoder:g711Ulaw64k20, VbdCoder: InvalidCoder255, Dtmf:gwRFC2833RalayDTMF,Rx payload:101,Tx payload:101 ,RTPmode:1, FaxTransportType: 1,AVoIPMediaType: gwMediaTypeAudioOnly) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634194 ) activate channel port=18964, BChannel:0, ip=10.0.0.109 (Voice:1,Vbd:0,T38:0,Video:0) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634195 ) #0:ActivateChannel: Socks=14 CID=0 Trunk:-1 BChannel:0 RemoteIP=10.0.0.109 RemotePort=18964 RemoteT38IP= RemoteT38Port=0 RemoteRTCPIP= RemoteRTCPPort=0 FaxModemDet=NO_FAX_MODEM_DETECTED [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634196 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634197 ) #0:OpenChannel:on Trunk -1 BChannel:0 CID=0 with VoiceCoder: g711Ulaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634198 ) #0:OpenChannel VoiceVolume= 1, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634199 ) RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634200 ) OpenChannel, CoderType = 1, Interval = 3, M = 1 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634201 ) #0:FAXTransportType = 1 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634202 ) #0:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=2, VoiceVol= 1, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634203 ) Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634204 ) #0:Channel will be open WITH DSP [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdex)(634205 ) PCIIFChangeChannelParams failed ECNlpMode [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634206 ) Setting ActivateRTP_RTCPCmd.Cmd.IpTosFieldInUdpPacket to 184 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634207 ) #0:ActivateChannel:RtpPayload: 0 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634208 ) #0:PSOSBoardInterface::PlayTone - Called Tone=RINGBACK_TONE Direction=PLAY_TONE_2_TEL [Time: 13:35:28] 26d:13h:35m:28s ( lgr_stk_ses)(634209 ) SendToCall - event: CONNECT_EV m_Call#2 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634210 ) | | #2:CONNECT_EV:(36366540026122020133524@10.0.0.110) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634211 ) | | #2:Call changing states from:AlertingState_Tel2IP to:ConnectedState [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634212 ) | #0:CONNECT_EV : (36366540026122020133524@10.0.0.110) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634213 ) Restart ECE control (CID=0) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdif)(634214 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 13:35:28] 26d:13h:35m:28s ( sip_stack)(634215 ) Resource SIPMessage deleted - #11 [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634216 ) #0:LOCAL_END_PLAYING_CALL_PROGRESS_TONE_EV [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634217 ) | #0:LOCAL_END_PLAYING_CALL_PROGRESS_TONE_EV [Time: 13:35:28] 26d:13h:35m:28s ( lgr_psbrdex)(634218 ) recv <-- ANALOG_IF_RING_START Ch:2 type(0) [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634219 ) #2:RING_START_EV [Time: 13:35:28] 26d:13h:35m:28s ( lgr_flow)(634220 ) | #2:RING_START_EV [Time: 13:35:28] 26d:13h:35m:30s ( lgr_psbrdex)(634221 ) recv <-- EV_ANALOG_IF_RING_END Ch:2 type(0) [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634222 ) #2:RING_END_EV [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634223 ) | #2:RING_END_EV [Time: 13:35:30] 26d:13h:35m:30s ( lgr_psbrdex)(634224 ) recv <-- EV_DETECT_CALLER_ID Ch:2 (Name=KMIEC Z, Number=7804715665) [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634225 ) #2:CALLER_ID_DETECTED_EV [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634226 ) | #2:CALLER_ID_DETECTED_EV [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634227 ) | #2:CALLER_ID: Name=KMIEC Z, PhoneNumber=7804715665 Enabled=1 [Time: 13:35:30] 26d:13h:35m:30s ( lgr_call)(634228 ) Call Allocated ResourceID: 1 [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634229 ) | #2:NEW_CALL_EV (send) : (UnKnown) [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634230 ) | | #1:NEW_CALL_EV:(UnKnown) [Time: 13:35:30] 26d:13h:35m:30s ( lgr_stk_mngr)(634231 ) Resource StackSession <#1> Allocated [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634232 ) | | #1:Call changing states from:IdleState to:NewCallState_Tel2IP [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634233 ) | | | #1:NEW_CALL_EV(Unknown) [Time: 13:35:30] 26d:13h:35m:30s ( lgr_call)(634234 ) | | #1GetNextUI:GlobalUI=129671307, mACAddrLsb=1965951 [Time: 13:35:30] 26d:13h:35m:30s ( lgr_call)(634235 ) | | #1GetNextUI:GlobalUI=129671308 [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634236 ) | (to 220) [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634237 ) | #2:SETUP_EV (send) : (UnKnown) [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634238 ) | | #1:SETUP (TO:220, FROM:7804715665):(UnKnown) [Time: 13:35:30] 26d:13h:35m:30s ( lgr_call)(634239 ) new call from EndPoint [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634240 ) | | #1:Call changing states from:NewCallState_Tel2IP to:InitiatedState_Tel2IP [Time: 13:35:30] 26d:13h:35m:30s ( lgr_flow)(634241 ) | | | #1:SETUP_EV(Unknown) [Time: 13:35:30] 26d:13h:35m:30s ( lgr_stack)(634242 ) FindIpDestination: rmRc:0 (OK) SrcIpGroup:-1 IpconnHndl:151 DstPrefix:220 DstIp:10.0.0.109 [Time: 13:35:30] 26d:13h:35m:30s ( lgr_stack)(634243 ) RoutingInstance (#1) RTRouting: trying to find a route according to Routing Table [Time: 13:35:30] 26d:13h:35m:30s ( lgr_stk_ses)(634244 ) UpdateAfterDecidingRouting: IpProfileId (0), ChargeCode (255), NewIndex (1) [Time: 13:35:30] 26d:13h:35m:30s ( lgr_call)(634245 ) Call::SetCoderListForCall #1 Found 2 Common Coders For Call [Time: 13:35:30] 26d:13h:35m:30s ( lgr_call)(634246 ) Coder g711Ulaw64k20 : 20 [Time: 13:35:30] 26d:13h:35m:30s ( lgr_call)(634247 ) Coder g711Alaw64k20 : 20 [Time: 13:35:30] 26d:13h:35m:30s ( lgr_profiling)(634248 ) Profiled: JBMinDel=10 JBOptF=10 EEarlyM=1 FaxTM=1 IPDS=46 IsFaxU=1 PI2IP=-1 SigIPDF=40 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 RTPRD=0 SCE=0 VxxTT=2 Dst2Rdrt=0 DTMFVol=20 ECE=1 ECurDis=1 EDigDel=0 ERevP=0 FHPer=700 InG=32 MWIA=1 MWID=1 VVol=33 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DiscOnBrok=1 DPInd=255 [Time: 13:35:30] 26d:13h:35m:30s ( lgr_stk_ses)(634249 ) DecideRoutingSetup DestIpGroupId:0 [Time: 13:35:30] 26d:13h:35m:30s ( sip_stack)(634250 ) New SIPMessage created - #5 [Time: 13:35:30] 26d:13h:35m:30s ( sip_stack)(634251 ) new AcSIPCallAPI created - #1 [Time: 13:35:30] 26d:13h:35m:31s ( lgr_flow)(634252 ) | | new GetNewSIPCall created - #8 [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634253 ) SIPSDPSession#1 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERING [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634254 ) | |(SIPTU#8)SETUP_REQ State:Idle() [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634255 ) SIPCall(#8) changes state from Idle to Inviting [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634256 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:31] 26d:13h:35m:31s INVITE sip:220@10.0.0.109 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac379174349 Max-Forwards: 70 From: "KMIEC Z" ;tag=1c379170242 To: Call-ID: 37916973726122020133531@10.0.0.110 CSeq: 1 INVITE Contact: Supported: em,100rel,timer,replaces,path,early-session,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Content-Type: application/sdp Content-Disposition: session Content-Length: 251 v=0 o=AudiocodesGW 379159221 379159091 IN IP4 10.0.0.110 s=Phone-Call c=IN IP4 10.0.0.110 t=0 0 m=audio 6030 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634258 ) UdpRtxMngr::Transmit 1 INVITE Rtx Left: 6 Dest: 10.0.0.109:5060 CallID: (37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634259 ) Resource SIPMessage deleted - #5 [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634260 ) New SIPMessage created - #0 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634261 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:31] 26d:13h:35m:31s SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac379174349;received=10.0.0.110 From: "KMIEC Z" ;tag=1c379170242 To: Call-ID: 37916973726122020133531@10.0.0.110 CSeq: 1 INVITE Server: Asterisk PBX 16.13.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634263 ) UdpRtxMngr::Remove 1 INVITE [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634264 ) | |(SIPTU#8)100 State:Inviting(37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634265 ) SIPCall(#8) changes state from Inviting to Proceeding [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634266 ) Resource SIPMessage deleted - #0 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634267 ) | | | #1:SIP_TRYING_EV(37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_stk_ses)(634268 ) SendToCall - event: PROCEEDING_EV m_Call#1 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634269 ) | | #1:PROCEEDING_EV:(37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634270 ) | #2:PROCEEDING_EV : (37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634271 ) New SIPMessage created - #4 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634272 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:35:31] 26d:13h:35m:31s SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac379174349;received=10.0.0.110 From: "KMIEC Z" ;tag=1c379170242 To: ;tag=as5a5ce10e Call-ID: 37916973726122020133531@10.0.0.110 CSeq: 1 INVITE Server: Asterisk PBX 16.13.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 260 v=0 o=root 1279272944 1279272944 IN IP4 10.0.0.109 s=Asterisk PBX 16.13.0 c=IN IP4 10.0.0.109 t=0 0 m=audio 12328 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:150 a=sendrecv [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634274 ) | |(SIPTU#8)200 OK State:Proceeding(37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634275 ) SIPCall(#8) changes state from Proceeding to RemoteAccepted [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634276 ) | | | #1:SIP_CONNECT_EV(37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634277 ) SIPSDPSession#1 - Changing state from SIP_MEDIA_OFFERING to SIP_MEDIA_COMPLETED [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634278 ) New SIPMessage created - #3 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634279 ) | |(SIPTU#8)ACK_REQ State:RemoteAccepted(37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634280 ) SIPCall(#8) changes state from RemoteAccepted to Connected [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634281 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:35:31] 26d:13h:35m:31s ACK sip:220@10.0.0.109:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac379300126 Max-Forwards: 70 From: "KMIEC Z" ;tag=1c379170242 To: ;tag=as5a5ce10e Call-ID: 37916973726122020133531@10.0.0.110 CSeq: 1 ACK Contact: Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Content-Length: 0 [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634283 ) Resource SIPMessage deleted - #3 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_stk_ses)(634284 ) DtmfCapNegotiationAlgorithm :: TxDtmfMethod = DTMF_RFC2833_SUPPORTED [Time: 13:35:31] 26d:13h:35m:31s ( lgr_stk_ses)(634285 ) DtmfCapNegotiationAlgorithm :: TxRtpRfc2833Payload = 101 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_stk_ses)(634286 ) SendToCall - event: DTMF_CONTROL_EV m_Call#1 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634287 ) | | #1:DTMF_CONTROL_EV:(37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_stk_ses)(634288 ) SendToCall - event: OPEN_LOGICAL_CHANNEL_ACK_EV m_Call#1 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634289 ) | | #1:OPEN_LOGICAL_CHANNEL_ACK_EV:(37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634290 ) | #2:OPEN_LOGICAL_CHANNEL_ACK_EV : (37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634291 ) | #2:OPEN_VOICE-NOT_READY (MS:1, HS:0, Ready:0) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_stk_ses)(634292 ) SendToCall - event: CONNECT_EV m_Call#1 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634293 ) | | #1:CONNECT_EV:(37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634294 ) | | #1:Call changing states from:InitiatedState_Tel2IP to:ConnectedState [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634295 ) | #2:CONNECT_EV : (37916973726122020133531@10.0.0.110) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634296 ) #2:PSOSBoardInterface::StopPlayTone- Called [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634297 ) | #2:OPEN_VOICE (IP:10.0.0.109, RTP:12328, RTCP:0, VoiceCoder:g711Ulaw64k20, VbdCoder: InvalidCoder255, Dtmf:gwRFC2833RalayDTMF,Rx payload:101,Tx payload:101 ,RTPmode:1, FaxTransportType: 1,AVoIPMediaType: gwMediaTypeAudioOnly) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634298 ) activate channel port=12328, BChannel:2, ip=10.0.0.109 (Voice:1,Vbd:0,T38:0,Video:0) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634299 ) #2:ActivateChannel: Socks=14 CID=2 Trunk:-1 BChannel:2 RemoteIP=10.0.0.109 RemotePort=12328 RemoteT38IP= RemoteT38Port=0 RemoteRTCPIP= RemoteRTCPPort=0 FaxModemDet=NO_FAX_MODEM_DETECTED [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634300 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634301 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g711Ulaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634302 ) #2:OpenChannel VoiceVolume= 1, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634303 ) RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634304 ) OpenChannel, CoderType = 1, Interval = 3, M = 1 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634305 ) #2:FAXTransportType = 1 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634306 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=2, VoiceVol= 1, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634307 ) Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634308 ) #2:Channel will be open WITH DSP [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634309 ) OpenChannel - channel was not reopened only updated!!! [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634310 ) Setting ActivateRTP_RTCPCmd.Cmd.IpTosFieldInUdpPacket to 184 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634311 ) #2:ActivateChannel:RtpPayload: 0 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_psbrdif)(634312 ) Turn ringer ON for channel 2 [Time: 13:35:31] 26d:13h:35m:31s ( lgr_flow)(634313 ) | #2:FXO Seize Line [Time: 13:35:31] 26d:13h:35m:31s ( sip_stack)(634314 ) Resource SIPMessage deleted - #4 [Time: 13:35:31] 26d:13h:35m:55s ( lgr_psbrdex)(634315 ) InsertBoardEvent- event 138 inserted channel -100 [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634316 ) new AcSIPDialogAPI created - #2 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634317 ) | | new AcSIPDialog created - #14 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634318 ) | |(SIPTU#14)DIALOG_INIT_REQ State:DialogIdle() [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634319 ) SIPDialog(#14) changes state from DialogIdle to DialogInitiated [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634320 ) New SIPMessage created - #9 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634321 ) | |(SIPTU#14)GENERAL_REQ State:DialogInitiated(43997940226122020133555@10.0.0.110) [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634322 ) ---- Outgoing SIP Message to 10.0.0.110:5060 from SIPInterface #0 ---- [Time: 13:35:55] 26d:13h:35m:55s OPTIONS sip:gateway@10.0.0.110 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac439987600 Max-Forwards: 70 From: ;tag=1c439979916 To: Call-ID: 43997940226122020133555@10.0.0.110 CSeq: 1 OPTIONS Contact: Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Accept: application/sdp, application/simple-message-summary, message/sipfrag Content-Length: 0 [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634324 ) UdpRtxMngr::Transmit 1 OPTIONS Rtx Left: 6 Dest: 10.0.0.110:5060 CallID: (43997940226122020133555@10.0.0.110) [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634325 ) Resource SIPMessage deleted - #9 [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634326 ) New SIPMessage created - #1 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634327 ) ---- Incoming SIP Message from 10.0.0.110:5060 to SIPInterface #0 ---- [Time: 13:35:55] 26d:13h:35m:55s OPTIONS sip:gateway@10.0.0.110 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac439987600 Max-Forwards: 70 From: ;tag=1c439979916 To: Call-ID: 43997940226122020133555@10.0.0.110 CSeq: 1 OPTIONS Contact: Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Accept: application/sdp, application/simple-message-summary, message/sipfrag Content-Length: 0 [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634329 ) new AcSIPDialogAPI created - #14 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634330 ) | | new AcSIPDialog created - #10 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634331 ) | |(SIPTU#10)OPTIONS State:DialogIdle() [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634332 ) SIPDialog(#10) changes state from DialogIdle to DialogInitiated [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634333 ) ServicesMngr::GetEndPoint PhoneNum = gateway [Time: 13:35:55] 26d:13h:35m:55s ( lgr_psbrdif)(634334 ) MotherBoard::GetTrunkGroupId - No entry found for: DstNum:gateway SrcNum:gateway SrcIp:a00006e go to default [Time: 13:35:55] 26d:13h:35m:55s ( lgr_TrnkGrp)(634335 ) !! [ERROR] #0:TrunkGroup::AllocateEndPoint- Can't find EndPoint for phone number gateway [Time: 13:35:55] 26d:13h:35m:55s ( lgr_psbrdif)(634336 ) !! [ERROR] MotherBoard::GetEndPoint- Can't find EndPoint for Dest:gateway Source:gateway SourceIp:110fa98 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_call)(634337 ) !! [ERROR] Call::GetEndPoint- Can't find endpoint for phone number gateway [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634338 ) New SIPMessage created - #10 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634339 ) | |(SIPTU#10)GENERAL_RESPONSE_REQ State:DialogInitiated(43997940226122020133555@10.0.0.110) [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634340 ) ---- Outgoing SIP Message to 10.0.0.110:5060 from SIPInterface #0 ---- [Time: 13:35:55] 26d:13h:35m:55s SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac439987600 From: ;tag=1c439979916 To: ;tag=1c440025464 Call-ID: 43997940226122020133555@10.0.0.110 CSeq: 1 OPTIONS Supported: 100rel Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 X-Resources: telchs=4/0;mediachs=0/0 Content-Length: 0 [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634342 ) Resource SIPMessage deleted - #10 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634343 ) | |(SIPTU#10)DIALOG_DISCONNECT_REQ State:DialogInitiated(43997940226122020133555@10.0.0.110) [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634344 ) SIPDialog(#10) changes state from DialogInitiated to DialogDisconnected [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634345 ) AcSIPStackAPI::FreeDialogAPI - #14 [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634346 ) Resource SIPMessage deleted - #1 [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634347 ) New SIPMessage created - #2 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634348 ) ---- Incoming SIP Message from 10.0.0.110:5060 to SIPInterface #0 ---- [Time: 13:35:55] 26d:13h:35m:55s SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac439987600 From: ;tag=1c439979916 To: ;tag=1c440025464 Call-ID: 43997940226122020133555@10.0.0.110 CSeq: 1 OPTIONS Supported: 100rel Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 X-Resources: telchs=4/0;mediachs=0/0 Content-Length: 0 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634350 ) | |(SIPTU#14)404 State:DialogInitiated(43997940226122020133555@10.0.0.110) [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634351 ) | |(SIPTU#14)DIALOG_DISCONNECT_REQ State:DialogInitiated(43997940226122020133555@10.0.0.110) [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634352 ) SIPDialog(#14) changes state from DialogInitiated to DialogDisconnected [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634353 ) AcSIPStackAPI::FreeDialogAPI - #2 [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634354 ) UdpRtxMngr::Remove 1 OPTIONS [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634355 ) Resource SIPMessage deleted - #2 [Time: 13:35:55] 26d:13h:35m:55s ( lgr_flow)(634356 ) | | TransactionUserMngr::ReturnDialog - #14 [Time: 13:35:55] 26d:13h:35m:55s ( sip_stack)(634357 ) SIPDialog(#14) changes state from DialogDisconnected to DialogIdle [Time: 13:35:55] 26d:13h:36m:0s ( lgr_flow)(634358 ) | | TransactionUserMngr::ReturnDialog - #10 [Time: 13:36:00] 26d:13h:36m:0s ( sip_stack)(634359 ) SIPDialog(#10) changes state from DialogDisconnected to DialogIdle [Time: 13:36:00] 26d:13h:36m:2s ( lgr_psbrdex)(634360 ) recv <-- ON_HOOK Ch:0 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634361 ) #0:ON_HOOK_EV [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634362 ) | #0:ON_HOOK_EV [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634363 ) | #0:Close voice Channel [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634364 ) #0:StopRTP_RTCP on channel 0 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634365 ) | #0:RELEASE_EV (send) GWAPP_NORMAL_CALL_CLEAR : (36366540026122020133524@10.0.0.110) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634366 ) | | #2:RELEASE_EV:(36366540026122020133524@10.0.0.110) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634367 ) | | #2:Call changing states from:ConnectedState to:DisconnectingState [Time: 13:36:02] 26d:13h:36m:2s ( lgr_ipconn)(634368 ) ?? [WARNING] IP:10.0.0.109 QoS status:QoS OK [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634369 ) | | #2:RELEASE_ACK_EV:(36366540026122020133524@10.0.0.110) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634370 ) | | | #2:RELEASE_EV(36366540026122020133524@10.0.0.110) [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634371 ) New SIPMessage created - #6 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634372 ) | |(SIPTU#10)DISCONNECT_REQ State:Connected(36366540026122020133524@10.0.0.110) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634373 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:36:02] 26d:13h:36m:2s BYE sip:74791270@10.0.0.109:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac458814456 Max-Forwards: 70 From: ;tag=1c363665875 To: ;tag=as2273b2b1 Call-ID: 36366540026122020133524@10.0.0.110 CSeq: 3 BYE Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Reason: Q.850 ;cause=16 ;text="local" Content-Length: 0 [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634375 ) UdpRtxMngr::Transmit 3 BYE Rtx Left: 6 Dest: 10.0.0.109:5060 CallID: (36366540026122020133524@10.0.0.110) [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634376 ) SIPCall(#10) changes state from Connected to Disconnected [Time: 13:36:02] 26d:13h:36m:2s ( lgr_stk_ses)(634377 ) SendToCall - event: RELEASE_ACK_EV m_Call#2 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634378 ) | | #2:RELEASE_ACK_EV:(36366540026122020133524@10.0.0.110) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_call)(634379 ) Call Returned to Pool ResourceID: 2 [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634380 ) AcSIPStackAPI::FreeCallAPI - #2 [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634381 ) Setting ApplicationCall of AcSIPCall 31564408 to NULL [Time: 13:36:02] 26d:13h:36m:2s ( lgr_stk_mngr)(634382 ) Resource StackSession <#2> Deleted [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634383 ) Resource SIPMessage deleted - #6 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634384 ) #0:PSOSBoardInterface::StopPlayTone- Called [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634385 ) #0:cpDigitMapHndlr_Stop - Stoped (0) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634386 ) #0:CloseChannel: ChannelNum=0 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634387 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634388 ) #0:OpenChannel:on Trunk -1 BChannel:0 CID=0 with VoiceCoder: g711Ulaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634389 ) #0:OpenChannel VoiceVolume= 1, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634390 ) RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634391 ) OpenChannel, CoderType = 1, Interval = 3, M = 1 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634392 ) #0:FAXTransportType = 1 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634393 ) #0:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=2, VoiceVol= 1, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634394 ) Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634395 ) #0:Channel will be open WITH DSP [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634396 ) New SIPMessage created - #8 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634397 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:36:02] 26d:13h:36m:2s SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac458814456;received=10.0.0.110 From: ;tag=1c363665875 To: ;tag=as2273b2b1 Call-ID: 36366540026122020133524@10.0.0.110 CSeq: 3 BYE Server: Asterisk PBX 16.13.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634399 ) | |(SIPTU#10)200 OK State:Disconnected(36366540026122020133524@10.0.0.110) [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634400 ) UdpRtxMngr::Remove 3 BYE [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634401 ) Resource SIPMessage deleted - #8 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634402 ) | | TransactionUserMngr::ReturnSIPCall - #10 [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634403 ) SIPCall(#10) changes state from Disconnected to Idle [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634404 ) New SIPMessage created - #7 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634405 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:36:02] 26d:13h:36m:2s BYE sip:pstn-5665@10.0.0.110:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK297842f2 Max-Forwards: 70 From: "Joseph" ;tag=as0ac0c8f1 To: ;tag=1c363907365 Call-ID: 136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060 CSeq: 103 BYE User-Agent: Asterisk PBX 16.13.0 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634407 ) | |(SIPTU#9)BYE State:Connected(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634408 ) SIPCall(#9) changes state from Connected to Disconnected [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634409 ) | | | #6:SIP_DISCONNECT_EV(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_stk_ses)(634410 ) SendToCall - event: RELEASE_EV m_Call#6 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634411 ) | | #6:RELEASE_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634412 ) | | #6:Call changing states from:ConnectedState to:DisconnectingState [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634413 ) | | #6:RELEASE_ACK_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634414 ) | #3:RELEASE_EV GWAPP_NORMAL_CALL_CLEAR : (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634415 ) | #3:Close voice Channel [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634416 ) #3:StopRTP_RTCP on channel 3 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634417 ) | #3:RELEASE_ACK_EV (send) : (136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634418 ) | | #6:RELEASE_ACK_EV:(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634419 ) | | | #6:RELEASE_ACK_EV(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634420 ) New SIPMessage created - #11 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634421 ) | |(SIPTU#9)DISCONNECT_RESPONSE State:Disconnected(136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634422 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:36:02] 26d:13h:36m:2s SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK297842f2 From: "Joseph" ;tag=as0ac0c8f1 To: ;tag=1c363907365 Call-ID: 136b3cbf79bf266008cdad6c3ca5aef6@10.0.0.109:5060 CSeq: 103 BYE Contact: Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Content-Length: 0 [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634424 ) AcSIPStackAPI::FreeCallAPI - #6 [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634425 ) Setting ApplicationCall of AcSIPCall 31552568 to NULL [Time: 13:36:02] 26d:13h:36m:2s ( lgr_stk_mngr)(634426 ) Resource StackSession <#6> Deleted [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634427 ) Resource SIPMessage deleted - #11 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_call)(634428 ) Call Returned to Pool ResourceID: 6 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdex)(634429 ) InsertBoardEvent- event 105 inserted channel 3 [Time: 13:36:02] 26d:13h:36m:2s ( sip_stack)(634430 ) Resource SIPMessage deleted - #7 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634431 ) #3:RELEASE_BECAUSE_IP_TIMER_EXPIRED_EV [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634432 ) | #3:RELEASE_BECAUSE_IP_TIMER_EXPIRED_EV [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634433 ) #3:cpDigitMapHndlr_Stop - Stoped (0) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634434 ) #3:CloseChannel: ChannelNum=3 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634435 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634436 ) #3:OpenChannel:on Trunk -1 BChannel:3 CID=3 with VoiceCoder: g711Ulaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634437 ) #3:OpenChannel VoiceVolume= 1, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634438 ) RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634439 ) OpenChannel, CoderType = 1, Interval = 3, M = 1 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634440 ) #3:FAXTransportType = 1 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634441 ) #3:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=2, VoiceVol= 1, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634442 ) Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634443 ) #3:Channel will be open WITH DSP [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634444 ) Turn ringer OFF for channel 3 [Time: 13:36:02] 26d:13h:36m:2s ( lgr_flow)(634445 ) | #3:FXO Release Line [Time: 13:36:02] 26d:13h:36m:2s ( lgr_psbrdif)(634446 ) #3:PSOSBoardInterface::StopPlayTone- Called [Time: 13:36:02] 26d:13h:36m:3s ( lgr_psbrdex)(634447 ) InsertBoardEvent- event 116 inserted channel 3 [Time: 13:36:03] 26d:13h:36m:3s ( lgr_flow)(634448 ) #3:GUARD_TIME_TIMER_EXPIRED_EV [Time: 13:36:03] 26d:13h:36m:3s ( lgr_flow)(634449 ) | #3:GUARD_TIME_TIMER_EXPIRED_EV [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634450 ) #3:cpDigitMapHndlr_Stop - Stoped (0) [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634451 ) #3:CloseChannel: ChannelNum=3 [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634452 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634453 ) #3:OpenChannel:on Trunk -1 BChannel:3 CID=3 with VoiceCoder: g711Ulaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634454 ) #3:OpenChannel VoiceVolume= 1, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634455 ) RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634456 ) OpenChannel, CoderType = 1, Interval = 3, M = 1 [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634457 ) #3:FAXTransportType = 1 [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634458 ) #3:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=2, VoiceVol= 1, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634459 ) Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 13:36:03] 26d:13h:36m:3s ( lgr_psbrdif)(634460 ) #3:Channel will be open WITH DSP [Time: 13:36:03] 26d:13h:36m:5s ( lgr_psbrdex)(634461 ) recv <-- EV_ANALOG_IF_LINE_DISCONNECTED Ch:2 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634462 ) #2:LINE_DISCONNECT_DETECTED_EV [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634463 ) | #2:LINE_DISCONNECT_DETECTED_EV [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634464 ) Turn ringer OFF for channel 2 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634465 ) | #2:FXO Release Line [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634466 ) #2:PSOSBoardInterface::StopPlayTone- Called [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634467 ) | #2:Close voice Channel [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634468 ) #2:StopRTP_RTCP on channel 2 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634469 ) | #2:RELEASE_EV (send) GWAPP_NORMAL_CALL_CLEAR : (37916973726122020133531@10.0.0.110) [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634470 ) | | #1:RELEASE_EV:(37916973726122020133531@10.0.0.110) [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634471 ) | | #1:Call changing states from:ConnectedState to:DisconnectingState [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634472 ) | | #1:RELEASE_ACK_EV:(37916973726122020133531@10.0.0.110) [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634473 ) | | | #1:RELEASE_EV(37916973726122020133531@10.0.0.110) [Time: 13:36:05] 26d:13h:36m:5s ( sip_stack)(634474 ) New SIPMessage created - #5 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634475 ) | |(SIPTU#8)DISCONNECT_REQ State:Connected(37916973726122020133531@10.0.0.110) [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634476 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:36:05] 26d:13h:36m:5s BYE sip:220@10.0.0.109:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac465014743 Max-Forwards: 70 From: "KMIEC Z" ;tag=1c379170242 To: ;tag=as5a5ce10e Call-ID: 37916973726122020133531@10.0.0.110 CSeq: 2 BYE Supported: em,timer,replaces,path,early-session,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Reason: Q.850 ;cause=16 ;text="local" Content-Length: 0 [Time: 13:36:05] 26d:13h:36m:5s ( sip_stack)(634478 ) UdpRtxMngr::Transmit 2 BYE Rtx Left: 6 Dest: 10.0.0.109:5060 CallID: (37916973726122020133531@10.0.0.110) [Time: 13:36:05] 26d:13h:36m:5s ( sip_stack)(634479 ) SIPCall(#8) changes state from Connected to Disconnected [Time: 13:36:05] 26d:13h:36m:5s ( lgr_stk_ses)(634480 ) SendToCall - event: RELEASE_ACK_EV m_Call#1 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634481 ) | | #1:RELEASE_ACK_EV:(37916973726122020133531@10.0.0.110) [Time: 13:36:05] 26d:13h:36m:5s ( lgr_call)(634482 ) Call Returned to Pool ResourceID: 1 [Time: 13:36:05] 26d:13h:36m:5s ( sip_stack)(634483 ) AcSIPStackAPI::FreeCallAPI - #1 [Time: 13:36:05] 26d:13h:36m:5s ( sip_stack)(634484 ) Setting ApplicationCall of AcSIPCall 31540728 to NULL [Time: 13:36:05] 26d:13h:36m:5s ( lgr_stk_mngr)(634485 ) Resource StackSession <#1> Deleted [Time: 13:36:05] 26d:13h:36m:5s ( sip_stack)(634486 ) Resource SIPMessage deleted - #5 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634487 ) #2:cpDigitMapHndlr_Stop - Stoped (0) [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634488 ) #2:CloseChannel: ChannelNum=2 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634489 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634490 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g711Ulaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634491 ) #2:OpenChannel VoiceVolume= 1, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634492 ) RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634493 ) OpenChannel, CoderType = 1, Interval = 3, M = 1 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634494 ) #2:FAXTransportType = 1 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634495 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=2, VoiceVol= 1, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634496 ) Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdif)(634497 ) #2:Channel will be open WITH DSP [Time: 13:36:05] 26d:13h:36m:5s ( sip_stack)(634498 ) New SIPMessage created - #0 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634499 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:36:05] 26d:13h:36m:5s SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac465014743;received=10.0.0.110 From: "KMIEC Z" ;tag=1c379170242 To: ;tag=as5a5ce10e Call-ID: 37916973726122020133531@10.0.0.110 CSeq: 2 BYE Server: Asterisk PBX 16.13.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634501 ) | |(SIPTU#8)200 OK State:Disconnected(37916973726122020133531@10.0.0.110) [Time: 13:36:05] 26d:13h:36m:5s ( sip_stack)(634502 ) UdpRtxMngr::Remove 2 BYE [Time: 13:36:05] 26d:13h:36m:5s ( sip_stack)(634503 ) Resource SIPMessage deleted - #0 [Time: 13:36:05] 26d:13h:36m:5s ( lgr_flow)(634504 ) | | TransactionUserMngr::ReturnSIPCall - #8 [Time: 13:36:05] 26d:13h:36m:5s ( sip_stack)(634505 ) SIPCall(#8) changes state from Disconnected to Idle [Time: 13:36:05] 26d:13h:36m:5s ( lgr_psbrdex)(634506 ) recv <-- EV_ANALOG_IF_LINE_CONNECTED Ch:2 [Time: 13:36:05] 26d:13h:36m:6s ( lgr_psbrdex)(634507 ) InsertBoardEvent- event 116 inserted channel 2 [Time: 13:36:06] 26d:13h:36m:6s ( lgr_flow)(634508 ) #2:GUARD_TIME_TIMER_EXPIRED_EV [Time: 13:36:06] 26d:13h:36m:6s ( lgr_flow)(634509 ) | #2:GUARD_TIME_TIMER_EXPIRED_EV [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634510 ) #2:cpDigitMapHndlr_Stop - Stoped (0) [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634511 ) #2:CloseChannel: ChannelNum=2 [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634512 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634513 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g711Ulaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634514 ) #2:OpenChannel VoiceVolume= 1, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634515 ) RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634516 ) OpenChannel, CoderType = 1, Interval = 3, M = 1 [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634517 ) #2:FAXTransportType = 1 [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634518 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=1, VxxTranType=2, VoiceVol= 1, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634519 ) Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 13:36:06] 26d:13h:36m:6s ( lgr_psbrdif)(634520 ) #2:Channel will be open WITH DSP [Time: 13:36:06] 26d:13h:36m:7s ( lgr_flow)(634521 ) | | TransactionUserMngr::ReturnSIPCall - #9 [Time: 13:36:07] 26d:13h:36m:7s ( sip_stack)(634522 ) SIPCall(#9) changes state from Disconnected to Idle [Time: 13:36:07] 26d:13h:36m:16s ( sip_stack)(634523 ) New SIPMessage created - #3 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634524 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:36:16] 26d:13h:36m:16s OPTIONS sip:pstn-5665@10.0.0.110:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK3b72c844 Max-Forwards: 70 From: "asterisk" ;tag=as433e3785 To: Contact: Call-ID: 7c34863363e5f2905fadbf813ae18758@10.0.0.109:5060 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 16.13.0 Date: Sat, 26 Dec 2020 20:36:23 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634526 ) new AcSIPDialogAPI created - #8 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634527 ) | | new AcSIPDialog created - #4 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634528 ) | |(SIPTU#4)OPTIONS State:DialogIdle() [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634529 ) SIPDialog(#4) changes state from DialogIdle to DialogInitiated [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634530 ) ServicesMngr::GetEndPoint PhoneNum = pstn-5665 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_psbrdif)(634531 ) MotherBoard::GetTrunkGroupId - No entry found for: DstNum:pstn-5665 SrcNum:asterisk SrcIp:a00006d go to default [Time: 13:36:16] 26d:13h:36m:16s ( lgr_TrnkGrp)(634532 ) !! [ERROR] #0:TrunkGroup::AllocateEndPoint- Can't find EndPoint for phone number pstn-5665 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_psbrdif)(634533 ) !! [ERROR] MotherBoard::GetEndPoint- Can't find EndPoint for Dest:pstn-5665 Source:asterisk SourceIp:110fa98 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_call)(634534 ) !! [ERROR] Call::GetEndPoint- Can't find endpoint for phone number pstn-5665 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634535 ) New SIPMessage created - #4 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634536 ) | |(SIPTU#4)GENERAL_RESPONSE_REQ State:DialogInitiated(7c34863363e5f2905fadbf813ae18758@10.0.0.109:5060) [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634537 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:36:16] 26d:13h:36m:16s SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK3b72c844 From: "asterisk" ;tag=as433e3785 To: ;tag=1c492309219 Call-ID: 7c34863363e5f2905fadbf813ae18758@10.0.0.109:5060 CSeq: 102 OPTIONS Supported: 100rel Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 X-Resources: telchs=4/0;mediachs=0/0 Content-Length: 0 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634539 ) Resource SIPMessage deleted - #4 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634540 ) | |(SIPTU#4)DIALOG_DISCONNECT_REQ State:DialogInitiated(7c34863363e5f2905fadbf813ae18758@10.0.0.109:5060) [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634541 ) SIPDialog(#4) changes state from DialogInitiated to DialogDisconnected [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634542 ) AcSIPStackAPI::FreeDialogAPI - #8 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634543 ) Resource SIPMessage deleted - #3 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634544 ) New SIPMessage created - #9 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634545 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:36:16] 26d:13h:36m:16s OPTIONS sip:pstn-1270@10.0.0.110:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK60d2a84d Max-Forwards: 70 From: "asterisk" ;tag=as63177000 To: Contact: Call-ID: 27ddc6351cf26b332958d21b6a63870e@10.0.0.109:5060 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 16.13.0 Date: Sat, 26 Dec 2020 20:36:23 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634547 ) new AcSIPDialogAPI created - #9 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634548 ) | | new AcSIPDialog created - #2 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634549 ) | |(SIPTU#2)OPTIONS State:DialogIdle() [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634550 ) SIPDialog(#2) changes state from DialogIdle to DialogInitiated [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634551 ) ServicesMngr::GetEndPoint PhoneNum = pstn-1270 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_psbrdif)(634552 ) MotherBoard::GetTrunkGroupId - No entry found for: DstNum:pstn-1270 SrcNum:asterisk SrcIp:a00006d go to default [Time: 13:36:16] 26d:13h:36m:16s ( lgr_TrnkGrp)(634553 ) !! [ERROR] #0:TrunkGroup::AllocateEndPoint- Can't find EndPoint for phone number pstn-1270 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_psbrdif)(634554 ) !! [ERROR] MotherBoard::GetEndPoint- Can't find EndPoint for Dest:pstn-1270 Source:asterisk SourceIp:110fa98 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_call)(634555 ) !! [ERROR] Call::GetEndPoint- Can't find endpoint for phone number pstn-1270 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634556 ) New SIPMessage created - #10 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634557 ) | |(SIPTU#2)GENERAL_RESPONSE_REQ State:DialogInitiated(27ddc6351cf26b332958d21b6a63870e@10.0.0.109:5060) [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634558 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:36:16] 26d:13h:36m:16s SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK60d2a84d From: "asterisk" ;tag=as63177000 To: ;tag=1c492495589 Call-ID: 27ddc6351cf26b332958d21b6a63870e@10.0.0.109:5060 CSeq: 102 OPTIONS Supported: 100rel Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 X-Resources: telchs=4/0;mediachs=0/0 Content-Length: 0 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634560 ) Resource SIPMessage deleted - #10 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634561 ) | |(SIPTU#2)DIALOG_DISCONNECT_REQ State:DialogInitiated(27ddc6351cf26b332958d21b6a63870e@10.0.0.109:5060) [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634562 ) SIPDialog(#2) changes state from DialogInitiated to DialogDisconnected [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634563 ) AcSIPStackAPI::FreeDialogAPI - #9 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634564 ) Resource SIPMessage deleted - #9 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634565 ) New SIPMessage created - #1 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634566 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:36:16] 26d:13h:36m:16s OPTIONS sip:55@10.0.0.110:5060 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK6b3b3f8d Max-Forwards: 70 From: "asterisk" ;tag=as52a9a12f To: Contact: Call-ID: 3e39b1990e7f3991681df4985e42e1c7@10.0.0.109:5060 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 16.13.0 Date: Sat, 26 Dec 2020 20:36:23 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634568 ) new AcSIPDialogAPI created - #7 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634569 ) | | new AcSIPDialog created - #3 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634570 ) | |(SIPTU#3)OPTIONS State:DialogIdle() [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634571 ) SIPDialog(#3) changes state from DialogIdle to DialogInitiated [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634572 ) ServicesMngr::GetEndPoint PhoneNum = 55 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_psbrdif)(634573 ) GetTrunkGroupId- TrunkGroup:1 found DstNum:55 DstPfx:55 SrcNum:asterisk SrcPfx:* SrcIp:a00006d SrcIpPfx:* [Time: 13:36:16] 26d:13h:36m:16s ( lgr_psbrdif)(634574 ) QueryOnHookPortStatus (ChannelNum=2), status = 1 Polarity = 0 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_psbrdif)(634575 ) QueryOnHookPortStatus (ChannelNum=3), status = 1 Polarity = 0 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634576 ) New SIPMessage created - #2 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634577 ) | |(SIPTU#3)GENERAL_RESPONSE_REQ State:DialogInitiated(3e39b1990e7f3991681df4985e42e1c7@10.0.0.109:5060) [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634578 ) SIPDialog(#3) changes state from DialogInitiated to DialogConnected [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634579 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:36:16] 26d:13h:36m:16s SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.109:5060;branch=z9hG4bK6b3b3f8d From: "asterisk" ;tag=as52a9a12f To: ;tag=1c492744285 Call-ID: 3e39b1990e7f3991681df4985e42e1c7@10.0.0.109:5060 CSeq: 102 OPTIONS Contact: Supported: 100rel Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: Audiocodes-Sip-Gateway-/v.5.80A.032.003 X-Resources: telchs=4/0;mediachs=0/0 Accept: application/sdp, application/simple-message-summary, message/sipfrag Content-Type: application/sdp Content-Length: 251 v=0 o=AudiocodesGW 492757745 492757594 IN IP4 10.0.0.110 s=Phone-Call c=IN IP4 10.0.0.110 t=0 0 m=audio 6000 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634581 ) Resource SIPMessage deleted - #2 [Time: 13:36:16] 26d:13h:36m:16s ( lgr_flow)(634582 ) | |(SIPTU#3)DIALOG_DISCONNECT_REQ State:DialogConnected(3e39b1990e7f3991681df4985e42e1c7@10.0.0.109:5060) [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634583 ) SIPDialog(#3) changes state from DialogConnected to DialogDisconnected [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634584 ) AcSIPStackAPI::FreeDialogAPI - #7 [Time: 13:36:16] 26d:13h:36m:16s ( sip_stack)(634585 ) Resource SIPMessage deleted - #1 [Time: 13:36:16] 26d:13h:36m:20s ( lgr_psbrdex)(634586 ) InsertBoardEvent- event 138 inserted channel -100 [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634587 ) new AcSIPDialogAPI created - #3 [Time: 13:36:20] 26d:13h:36m:20s ( lgr_flow)(634588 ) | | new AcSIPDialog created - #11 [Time: 13:36:20] 26d:13h:36m:20s ( lgr_flow)(634589 ) | |(SIPTU#11)DIALOG_INIT_REQ State:DialogIdle() [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634590 ) SIPDialog(#11) changes state from DialogIdle to DialogInitiated [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634591 ) New SIPMessage created - #6 [Time: 13:36:20] 26d:13h:36m:20s ( lgr_flow)(634592 ) | |(SIPTU#11)GENERAL_REQ State:DialogInitiated(50366735826122020133620@10.0.0.110) [Time: 13:36:20] 26d:13h:36m:20s ( lgr_flow)(634593 ) ---- Outgoing SIP Message to 10.0.0.109:5060 from SIPInterface #0 ---- [Time: 13:36:20] 26d:13h:36m:20s OPTIONS sip:gateway@10.0.0.110 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac503675352 Max-Forwards: 70 From: ;tag=1c503667875 To: Call-ID: 50366735826122020133620@10.0.0.110 CSeq: 1 OPTIONS Contact: Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.032.003 Accept: application/sdp, application/simple-message-summary, message/sipfrag Content-Length: 0 [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634595 ) UdpRtxMngr::Transmit 1 OPTIONS Rtx Left: 6 Dest: 10.0.0.109:5060 CallID: (50366735826122020133620@10.0.0.110) [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634596 ) Resource SIPMessage deleted - #6 [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634597 ) New SIPMessage created - #8 [Time: 13:36:20] 26d:13h:36m:20s ( lgr_flow)(634598 ) ---- Incoming SIP Message from 10.0.0.109:5060 to SIPInterface #0 ---- [Time: 13:36:20] 26d:13h:36m:20s SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.0.0.110;branch=z9hG4bKac503675352;received=10.0.0.110 From: ;tag=1c503667875 To: ;tag=as69cc4ea6 Call-ID: 50366735826122020133620@10.0.0.110 CSeq: 1 OPTIONS Server: Asterisk PBX 16.13.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Accept: application/sdp Content-Length: 0 [Time: 13:36:20] 26d:13h:36m:20s ( lgr_flow)(634600 ) | |(SIPTU#11)404 State:DialogInitiated(50366735826122020133620@10.0.0.110) [Time: 13:36:20] 26d:13h:36m:20s ( lgr_flow)(634601 ) | |(SIPTU#11)DIALOG_DISCONNECT_REQ State:DialogInitiated(50366735826122020133620@10.0.0.110) [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634602 ) SIPDialog(#11) changes state from DialogInitiated to DialogDisconnected [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634603 ) AcSIPStackAPI::FreeDialogAPI - #3 [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634604 ) UdpRtxMngr::Remove 1 OPTIONS [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634605 ) Resource SIPMessage deleted - #8 [Time: 13:36:20] 26d:13h:36m:20s ( lgr_flow)(634606 ) | | TransactionUserMngr::ReturnDialog - #11 [Time: 13:36:20] 26d:13h:36m:20s ( sip_stack)(634607 ) SIPDialog(#11) changes state from DialogDisconnected to DialogIdle [Time: 13:36:20] 26d:13h:36m:21s ( lgr_flow)(634608 ) | | TransactionUserMngr::ReturnDialog - #4 [Time: 13:36:21] 26d:13h:36m:21s ( sip_stack)(634609 ) SIPDialog(#4) changes state from DialogDisconnected to DialogIdle [Time: 13:36:21] 26d:13h:36m:21s ( lgr_flow)(634610 ) | | TransactionUserMngr::ReturnDialog - #2 [Time: 13:36:21] 26d:13h:36m:21s ( sip_stack)(634611 ) SIPDialog(#2) changes state from DialogDisconnected to DialogIdle [Time: 13:36:21] 26d:13h:36m:21s ( lgr_flow)(634612 ) | | TransactionUserMngr::ReturnDialog - #3 [Time: 13:36:21] 26d:13h:36m:21s ( sip_stack)(634613 ) SIPDialog(#3) changes state from DialogDisconnected to DialogIdle [Time: 13:36:21]