alibek Posted August 1, 2019 Posted August 1, 2019 Есть пара шлюзов MP114/FXO и MP114/FXS. Настраивались по этому примеру: https://habr.com/ru/company/arttel/blog/404399/ Сейчас связь в направлении FXO -> FXS работает нормально. При попытке звонка в направлении FXS -> FXO в трубки тишина, затем короткие гудки. В логах шлюза FXO я фиксирую попытку дозвона, там целая куча сообщений, но все заканчивается ошибкой 302. Полный лог под спойлером. Скрытый текст Log is Activated 1d:11h:21m:28s ( lgr_flow)(1183 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:28s INVITE sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac399189380 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c399179360 To: <sip:100@192.168.1.221;user=phone> Call-ID: 399178768182019112128@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222:5060> Supported: em,100rel,timer,replaces,path,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Type: application/sdp Content-Length: 254 v=0 o=AudiocodesGW 399146081 399146080 IN IP4 192.168.1.222 s=Phone-Call c=IN IP4 192.168.1.222 t=0 0 m=audio 6020 RTP/AVP 8 0 96 a=ptime:20 a=sendrecv a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 1d:11h:21m:28s ( sip_stack)(1185 ) New SIPMessage created - #3 1d:11h:21m:28s ( sip_stack)(1186 ) New AcSIPCallAPI created - #5 1d:11h:21m:28s ( sip_stack)(1187 ) New SIPStackSession created - #5 1d:11h:21m:28s ( sip_stack)(1188 ) New AcSIPCall created - #11 1d:11h:21m:28s ( lgr_flow)(1189 ) | |(SIPTU#11)INVITE State:Idle(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( sip_stack)(1190 ) SIPSessionTimer<TU#11>::HandleSTRequest - Session-Timer mode: SUPPORTED 1d:11h:21m:28s ( sip_stack)(1191 ) SIPCall(#11) changes state from Idle to Invited 1d:11h:21m:28s ( sip_stack)(1192 ) SDPBody::New - id = 0 1d:11h:21m:28s ( sip_stack)(1193 ) SDPBodyMedia::New - id = 30 1d:11h:21m:28s ( lgr_flow)(1194 ) | | | #5:SIP_SETUP_EV(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_call)(1195 ) (#5) CALL Allocated. 1d:11h:21m:28s ( lgr_stk_ses)(1196 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 1d:11h:21m:28s ( lgr_stk_ses)(1197 ) <SESSION #5> SendToCall - event: NEW_CALL_EV m_Call#5 1d:11h:21m:28s ( lgr_flow)(1198 ) | | #5:NEW_CALL_EV:(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_flow)(1199 ) | | #5:Call changing states from:IdleState to:NewCallState_IP2Tel 1d:11h:21m:28s ( lgr_flow)(1200 ) ServicesMngr::GetEndPoint PhoneNum = 100 1d:11h:21m:28s ( lgr_psbrdif)(1201 ) GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:100 DstPfx:* SrcNum:112 SrcPfx:* SrcIp:c0a801de SrcIpPfx:192.168.1.222 1d:11h:21m:28s ( lgr_psbrdif)(1202 ) QueryOnHookPortStatus (ChannelNum=0), status = 1 Polarity = 0 Validate = 0 1d:11h:21m:28s ( lgr_psbrdif)(1203 ) QueryOnHookPortStatus (ChannelNum=1), status = 1 Polarity = 0 Validate = 0 1d:11h:21m:28s ( lgr_psbrdif)(1204 ) QueryOnHookPortStatus (ChannelNum=2), status = 1 Polarity = 0 Validate = 0 1d:11h:21m:28s ( lgr_call)(1205 ) Call::SetCoderListForCall #5 Found 2 Common Coders For Call 1d:11h:21m:28s ( lgr_call)(1206 ) <Call #5> Coder g711Alaw64k20 : 20 1d:11h:21m:28s ( lgr_call)(1207 ) <Call #5> Coder g711Ulaw64k20 : 20 1d:11h:21m:28s ( lgr_profiling)(1208 ) <Call 5> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 IPDS=46 SigIPDF=40 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 EEarlyM=0 FaxTM=0 IsFaxU=0 PI2IP=-1 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 SCE=0 VxxTT=0 Dst2Rdrt=0 DTMFVol=20 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 MWIA=0 MWID=0 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DPInd=255 AGC=0 NLP=0 1d:11h:21m:28s ( lgr_call)(1209 ) | | #5GetNextUI:GlobalUI=466533578, mACAddrLsb=11400015 1d:11h:21m:28s ( lgr_call)(1210 ) | | #5GetNextUI:GlobalUI=466533579 1d:11h:21m:28s ( lgr_flow)(1211 ) | #2:NEW_CALL_EV : (399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_flow)(1212 ) EndPoint::MediaResourceList::AllocateMediaIpPortsByMediaRealmID Perform NEW allocation of Media ports for RealmIndex(0) port(6030) current allocations are:(1) Owner:(0) 1d:11h:21m:28s ( sip_stack)(1213 ) SDPBodyMedia::New - id = 29 1d:11h:21m:28s ( sip_stack)(1214 ) SIPSDPSession#5 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 1d:11h:21m:28s ( sip_stack)(1215 ) <BaseSIP SDPSESSION #5> UpdateChosenMediaByCN - CN as Remote -1 1d:11h:21m:28s ( sip_stack)(1216 ) SDPBodyMedia::Delete - id = 29 1d:11h:21m:28s ( sip_stack)(1217 ) SDPBodyMedia::New - id = 28 1d:11h:21m:28s ( lgr_flow)(1218 ) | |(SIPTU#11)TRYING_REQ State:Invited(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( sip_stack)(1219 ) New SIPMessage created - #1 1d:11h:21m:28s ( lgr_flow)(1220 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:28s SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac399189380 From: <sip:112@192.168.1.222>;tag=1c399179360 To: <sip:100@192.168.1.221;user=phone>;tag=1c179153607 Call-ID: 399178768182019112128@192.168.1.222 CSeq: 1 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: MP-114 FXO/v.6.60A.350.001 Content-Length: 0 1d:11h:21m:28s ( sip_stack)(1222 ) Resource SIPMessage deleted - #1 1d:11h:21m:28s ( lgr_stk_ses)(1223 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 1d:11h:21m:28s ( lgr_stk_ses)(1224 ) <SESSION #5> SendToCall - event: SETUP_EV m_Call#5 1d:11h:21m:28s ( lgr_flow)(1225 ) | | #5:SETUP (TO:100, FROM:112):(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_flow)(1226 ) | | #5:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel 1d:11h:21m:28s ( lgr_flow)(1227 ) | #2:SETUP_EV : (399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_flow)(1228 ) | #2:FWD_CALL_EV (send) : (399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_flow)(1229 ) | | #5:FWD_CALL_EV:(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_flow)(1230 ) | | | (#5)SIPStackSession <- (#0)ENDPOINT: FWD_CALL_EV (399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( sip_stack)(1231 ) New SIPMessage created - #2 1d:11h:21m:28s ( lgr_flow)(1232 ) | |(SIPTU#11)FORWARD_REQ State:Invited(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( sip_stack)(1233 ) SIPCall(#11) changes state from Invited to Disconnected 1d:11h:21m:28s ( lgr_flow)(1234 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:28s SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac399189380 From: <sip:112@192.168.1.222>;tag=1c399179360 To: <sip:100@192.168.1.221;user=phone>;tag=1c179153607 Call-ID: 399178768182019112128@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222;user=phone> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Diversion: <tel:112>;reason=unconditional;counter=1 Server: MP-114 FXO/v.6.60A.350.001 Reason: SIP ;cause=302 ;text="302 Moved Temporarily" Content-Length: 0 1d:11h:21m:28s ( sip_stack)(1236 ) UdpRtxMngr::Transmit 302 Response 1 INVITE Rtx Left: 6 Dest: 192.168.1.222:5060 CallID: (399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_stk_ses)(1237 ) <SESSION #5> SendToCall - event: RELEASE_EV m_Call#5 1d:11h:21m:28s ( lgr_flow)(1238 ) | | #5:RELEASE_EV:(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_flow)(1239 ) | | #5:Call changing states from:InitiatedState_IP2Tel to:DisconnectingState 1d:11h:21m:28s |CALL_END |2 |312822472 |-1 |2 |0 |1 |FXO |RMT |192.168.1.222 |192.168.1.221 |0 |0 |112 |112 |0 |0 |100 |100 |0 |N/A |255 | |0 |RMT |RELEASE_BECAUSE_FORWARD |0 |-1 |-1 |0 |0 |399178768182019112128@192.168.1.222 |11:21:28.625 UTC Thu Aug 01 2019 | |11:21:28.650 UTC Thu Aug 01 2019 |-1 |-1 |-1 |-1 |-1 |0 |0 | |0 |192.168.1.222 |192.168.1.222 |192.168.1.221 |192.168.1.221 |0 () |192.168.1.221 |6030 | | |FORWARDED | |0 (DefaultSRD) |0 |0 |0 () |0 (DefaultRealm) |UDP |46 |40 |127 |127 |127 |127 |5060 |5060 |AUDIO | | |302 | |0 | |0 1d:11h:21m:28s ( lgr_flow)(1241 ) | | #5:RELEASE_ACK_EV:(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_flow)(1242 ) EndPoint::MediaResourceList::FreeMediaIpPorts Perform dellocation of Media ports for RealmIndex(0) port(6030) current allocations are:(0) Owner:(0) 1d:11h:21m:28s ( lgr_flow)(1243 ) | #2:RELEASE_EV RELEASE_BECAUSE_FORWARD : (399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_flow)(1244 ) | | #5:RELEASE_ACK_EV:(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( lgr_flow)(1245 ) | | | (#5)SIPStackSession <- (#0)ENDPOINT: RELEASE_ACK_EV (399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( sip_stack)(1246 ) New SIPMessage created - #0 1d:11h:21m:28s ( sip_stack)(1247 ) AcSIPStackAPI::FreeCallAPI - #5 1d:11h:21m:28s ( sip_stack)(1248 ) Setting ApplicationCall of AcSIPCall#11 to NULL 1d:11h:21m:28s ( sip_stack)(1249 ) SDPBodyMedia::Delete - id = 28 1d:11h:21m:28s ( lgr_stk_mngr)(1250 ) Resource StackSession <#5> Deleted 1d:11h:21m:28s ( sip_stack)(1251 ) Resource SIPMessage deleted - #0 1d:11h:21m:28s ( lgr_call)(1252 ) (#5) CALL Deallocated. 1d:11h:21m:28s ( lgr_call)(1253 ) delete GW call current active is: 1 1d:11h:21m:28s ( lgr_psbrdif)(1254 ) #2:cpDigitMapHndlr_Stop - Stoped (0) 1d:11h:21m:28s ( lgr_psbrdif)(1255 ) #2:CloseChannel: ChannelNum=2 1d:11h:21m:28s ( lgr_psbrdif)(1256 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 1d:11h:21m:28s ( lgr_psbrdif)(1257 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g723130 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED 1d:11h:21m:28s ( lgr_psbrdif)(1258 ) #2:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 1d:11h:21m:28s ( lgr_psbrdif)(1259 ) RFC2833RTPPayloadType: Rx=101 Tx=101 1d:11h:21m:28s ( lgr_psbrdif)(1260 ) OpenChannel, CoderType = 16, Interval = 0, M = 1 1d:11h:21m:28s ( lgr_psbrdif)(1261 ) ConfigureVbdAndT38:FAXTransportType:0 T38Version:0 FaxBypassPT:102 1d:11h:21m:28s ( lgr_psbrdif)(1262 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=0, VxxTranType=0, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1,ECEType=0 SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) 1d:11h:21m:28s ( lgr_psbrdif)(1263 ) #2:FAXTransportType = 0 1d:11h:21m:28s SWWD : Run Task DSPD Ticks 5 (5), mode 1, priority 170 [Code:0x20019] [CID:0] 1d:11h:21m:28s IP e375d8 , LR e3dac4 [Code:0x20019] [CID:0] 1d:11h:21m:28s Trace 0-4: e3dac4 e27528 e323f0 e35ef8 e5b9c8 1d:11h:21m:28s Trace 5-9: 434fe0 5f3b8 6032c e108a0 15b0aac 1d:11h:21m:28s Trace 10-14: 499 1992a50 4f1048 1d:11h:21m:28s Trace 15-19: 499 15909f7 15b0aac 1d:11h:21m:28s SWWD : Wait Task GWAP, status 0, mode 0, priority 50, event 0 [Code:0x20019] [CID:0] 1d:11h:21m:28s TBack = e11784 ddf5fc 78a4c0 820d8c 8147cc [Code:0x20019] [CID:0] 1d:11h:21m:28s ( lgr_psbrdif)(1264 ) Detectors: Amd:On=0,Direction=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 1d:11h:21m:28s ( lgr_psbrdif)(1265 ) #2:Channel will be open WITH DSP 1d:11h:21m:28s ( sip_stack)(1266 ) Resource SIPMessage deleted - #2 1d:11h:21m:28s ( sip_stack)(1267 ) Resource SIPMessage deleted - #3 1d:11h:21m:28s ( sip_stack)(1268 ) SDPBodyMedia::Delete - id = 30 1d:11h:21m:28s ( sip_stack)(1269 ) SDPBody::Delete - id = 0 1d:11h:21m:28s ( lgr_flow)(1270 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:28s ACK sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac399189380 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c399179360 To: <sip:100@192.168.1.221;user=phone>;tag=1c179153607 Call-ID: 399178768182019112128@192.168.1.222 CSeq: 1 ACK Contact: <sip:112@192.168.1.222:5060> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Length: 0 1d:11h:21m:28s ( sip_stack)(1272 ) New SIPMessage created - #33 1d:11h:21m:28s ( sip_stack)(1273 ) UdpRtxMngr::Remove 302 Response 1 INVITE 1d:11h:21m:28s ( lgr_flow)(1274 ) | |(SIPTU#11)ACK State:Disconnected(399178768182019112128@192.168.1.222) 1d:11h:21m:28s ( sip_stack)(1275 ) Resource SIPMessage deleted - #33 1d:11h:21m:33s ( sip_stack)(1276 ) New SIPMessage created - #32 1d:11h:21m:33s ( sip_stack)(1277 ) Resource SIPMessage deleted - #32 1d:11h:21m:33s ( lgr_flow)(1278 ) | | TransactionUserMngr::ReturnTransactionUser - #11 1d:11h:21m:33s ( sip_stack)(1279 ) SIPCall(#11) changes state from Disconnected to Idle 1d:11h:21m:38s ( lgr_flow)(1280 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:38s INVITE sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac424690477 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c424669693 To: <sip:100@192.168.1.221;user=phone> Call-ID: 424669120182019112138@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222:5060> Supported: em,100rel,timer,replaces,path,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Type: application/sdp Content-Length: 254 v=0 o=AudiocodesGW 424644944 424644943 IN IP4 192.168.1.222 s=Phone-Call c=IN IP4 192.168.1.222 t=0 0 m=audio 6030 RTP/AVP 8 0 96 a=ptime:20 a=sendrecv a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 1d:11h:21m:38s ( sip_stack)(1282 ) New SIPMessage created - #34 1d:11h:21m:38s ( sip_stack)(1283 ) New AcSIPCallAPI created - #4 1d:11h:21m:38s ( sip_stack)(1284 ) New SIPStackSession created - #4 1d:11h:21m:38s ( sip_stack)(1285 ) New AcSIPCall created - #10 1d:11h:21m:38s ( lgr_flow)(1286 ) | |(SIPTU#10)INVITE State:Idle(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( sip_stack)(1287 ) SIPSessionTimer<TU#10>::HandleSTRequest - Session-Timer mode: SUPPORTED 1d:11h:21m:38s ( sip_stack)(1288 ) SIPCall(#10) changes state from Idle to Invited 1d:11h:21m:38s ( sip_stack)(1289 ) SDPBody::New - id = 15 1d:11h:21m:38s ( sip_stack)(1290 ) SDPBodyMedia::New - id = 27 1d:11h:21m:38s ( lgr_flow)(1291 ) | | | #4:SIP_SETUP_EV(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_call)(1292 ) (#4) CALL Allocated. 1d:11h:21m:38s ( lgr_stk_ses)(1293 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 1d:11h:21m:38s ( lgr_stk_ses)(1294 ) <SESSION #4> SendToCall - event: NEW_CALL_EV m_Call#4 1d:11h:21m:38s ( lgr_flow)(1295 ) | | #4:NEW_CALL_EV:(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_flow)(1296 ) | | #4:Call changing states from:IdleState to:NewCallState_IP2Tel 1d:11h:21m:38s ( lgr_flow)(1297 ) ServicesMngr::GetEndPoint PhoneNum = 100 1d:11h:21m:38s ( lgr_psbrdif)(1298 ) GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:100 DstPfx:* SrcNum:112 SrcPfx:* SrcIp:c0a801de SrcIpPfx:192.168.1.222 1d:11h:21m:38s ( lgr_psbrdif)(1299 ) QueryOnHookPortStatus (ChannelNum=0), status = 1 Polarity = 0 Validate = 0 1d:11h:21m:38s ( lgr_psbrdif)(1300 ) QueryOnHookPortStatus (ChannelNum=1), status = 1 Polarity = 0 Validate = 0 1d:11h:21m:38s ( lgr_psbrdif)(1301 ) QueryOnHookPortStatus (ChannelNum=2), status = 1 Polarity = 0 Validate = 0 1d:11h:21m:38s ( lgr_call)(1302 ) Call::SetCoderListForCall #4 Found 2 Common Coders For Call 1d:11h:21m:38s ( lgr_call)(1303 ) <Call #4> Coder g711Alaw64k20 : 20 1d:11h:21m:38s ( lgr_call)(1304 ) <Call #4> Coder g711Ulaw64k20 : 20 1d:11h:21m:38s ( lgr_profiling)(1305 ) <Call 4> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 IPDS=46 SigIPDF=40 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 EEarlyM=0 FaxTM=0 IsFaxU=0 PI2IP=-1 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 SCE=0 VxxTT=0 Dst2Rdrt=0 DTMFVol=20 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 MWIA=0 MWID=0 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DPInd=255 AGC=0 NLP=0 1d:11h:21m:38s ( lgr_call)(1306 ) | | #4GetNextUI:GlobalUI=466533579, mACAddrLsb=11400015 1d:11h:21m:38s ( lgr_call)(1307 ) | | #4GetNextUI:GlobalUI=466533580 1d:11h:21m:38s ( lgr_flow)(1308 ) | #2:NEW_CALL_EV : (424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_flow)(1309 ) EndPoint::MediaResourceList::AllocateMediaIpPortsByMediaRealmID Perform NEW allocation of Media ports for RealmIndex(0) port(6000) current allocations are:(1) Owner:(0) 1d:11h:21m:38s ( sip_stack)(1310 ) SDPBodyMedia::New - id = 26 1d:11h:21m:38s ( sip_stack)(1311 ) SIPSDPSession#4 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 1d:11h:21m:38s ( sip_stack)(1312 ) <BaseSIP SDPSESSION #4> UpdateChosenMediaByCN - CN as Remote -1 1d:11h:21m:38s ( sip_stack)(1313 ) SDPBodyMedia::Delete - id = 26 1d:11h:21m:38s ( sip_stack)(1314 ) SDPBodyMedia::New - id = 25 1d:11h:21m:38s ( lgr_flow)(1315 ) | |(SIPTU#10)TRYING_REQ State:Invited(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( sip_stack)(1316 ) New SIPMessage created - #31 1d:11h:21m:38s ( lgr_flow)(1317 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:38s SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac424690477 From: <sip:112@192.168.1.222>;tag=1c424669693 To: <sip:100@192.168.1.221;user=phone>;tag=1c204661014 Call-ID: 424669120182019112138@192.168.1.222 CSeq: 1 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: MP-114 FXO/v.6.60A.350.001 Content-Length: 0 1d:11h:21m:38s ( sip_stack)(1319 ) Resource SIPMessage deleted - #31 1d:11h:21m:38s ( lgr_stk_ses)(1320 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 1d:11h:21m:38s ( lgr_stk_ses)(1321 ) <SESSION #4> SendToCall - event: SETUP_EV m_Call#4 1d:11h:21m:38s ( lgr_flow)(1322 ) | | #4:SETUP (TO:100, FROM:112):(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_flow)(1323 ) | | #4:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel 1d:11h:21m:38s ( lgr_flow)(1324 ) | #2:SETUP_EV : (424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_flow)(1325 ) | #2:FWD_CALL_EV (send) : (424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_flow)(1326 ) | | #4:FWD_CALL_EV:(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_flow)(1327 ) | | | (#4)SIPStackSession <- (#0)ENDPOINT: FWD_CALL_EV (424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( sip_stack)(1328 ) New SIPMessage created - #29 1d:11h:21m:38s ( lgr_flow)(1329 ) | |(SIPTU#10)FORWARD_REQ State:Invited(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( sip_stack)(1330 ) SIPCall(#10) changes state from Invited to Disconnected 1d:11h:21m:38s ( lgr_flow)(1331 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:38s SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac424690477 From: <sip:112@192.168.1.222>;tag=1c424669693 To: <sip:100@192.168.1.221;user=phone>;tag=1c204661014 Call-ID: 424669120182019112138@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222;user=phone> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Diversion: <tel:112>;reason=unconditional;counter=1 Server: MP-114 FXO/v.6.60A.350.001 Reason: SIP ;cause=302 ;text="302 Moved Temporarily" Content-Length: 0 1d:11h:21m:38s ( sip_stack)(1333 ) UdpRtxMngr::Transmit 302 Response 1 INVITE Rtx Left: 6 Dest: 192.168.1.222:5060 CallID: (424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_stk_ses)(1334 ) <SESSION #4> SendToCall - event: RELEASE_EV m_Call#4 1d:11h:21m:38s ( lgr_flow)(1335 ) | | #4:RELEASE_EV:(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_flow)(1336 ) | | #4:Call changing states from:InitiatedState_IP2Tel to:DisconnectingState 1d:11h:21m:38s |CALL_END |2 |312822474 |-1 |2 |0 |1 |FXO |RMT |192.168.1.222 |192.168.1.221 |0 |0 |112 |112 |0 |0 |100 |100 |0 |N/A |255 | |0 |RMT |RELEASE_BECAUSE_FORWARD |0 |-1 |-1 |0 |0 |424669120182019112138@192.168.1.222 |11:21:38.825 UTC Thu Aug 01 2019 | |11:21:38.850 UTC Thu Aug 01 2019 |-1 |-1 |-1 |-1 |-1 |0 |0 | |0 |192.168.1.222 |192.168.1.222 |192.168.1.221 |192.168.1.221 |0 () |192.168.1.221 |6000 | | |FORWARDED | |0 (DefaultSRD) |0 |0 |0 () |0 (DefaultRealm) |UDP |46 |40 |127 |127 |127 |127 |5060 |5060 |AUDIO | | |302 | |0 | |0 1d:11h:21m:38s ( lgr_flow)(1338 ) | | #4:RELEASE_ACK_EV:(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_flow)(1339 ) EndPoint::MediaResourceList::FreeMediaIpPorts Perform dellocation of Media ports for RealmIndex(0) port(6000) current allocations are:(0) Owner:(0) 1d:11h:21m:38s ( lgr_flow)(1340 ) | #2:RELEASE_EV RELEASE_BECAUSE_FORWARD : (424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_flow)(1341 ) | | #4:RELEASE_ACK_EV:(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( lgr_flow)(1342 ) | | | (#4)SIPStackSession <- (#0)ENDPOINT: RELEASE_ACK_EV (424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( sip_stack)(1343 ) New SIPMessage created - #28 1d:11h:21m:38s ( sip_stack)(1344 ) AcSIPStackAPI::FreeCallAPI - #4 1d:11h:21m:38s ( sip_stack)(1345 ) Setting ApplicationCall of AcSIPCall#10 to NULL 1d:11h:21m:38s ( sip_stack)(1346 ) SDPBodyMedia::Delete - id = 25 1d:11h:21m:38s ( lgr_stk_mngr)(1347 ) Resource StackSession <#4> Deleted 1d:11h:21m:38s ( sip_stack)(1348 ) Resource SIPMessage deleted - #28 1d:11h:21m:38s ( lgr_call)(1349 ) (#4) CALL Deallocated. 1d:11h:21m:38s ( lgr_call)(1350 ) delete GW call current active is: 1 1d:11h:21m:38s ( lgr_psbrdif)(1351 ) #2:cpDigitMapHndlr_Stop - Stoped (0) 1d:11h:21m:38s ( lgr_psbrdif)(1352 ) #2:CloseChannel: ChannelNum=2 1d:11h:21m:38s ( lgr_psbrdif)(1353 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 1d:11h:21m:38s ( lgr_psbrdif)(1354 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g723130 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED 1d:11h:21m:38s ( lgr_psbrdif)(1355 ) #2:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 1d:11h:21m:38s ( lgr_psbrdif)(1356 ) RFC2833RTPPayloadType: Rx=101 Tx=101 1d:11h:21m:38s ( lgr_psbrdif)(1357 ) OpenChannel, CoderType = 16, Interval = 0, M = 1 1d:11h:21m:38s ( lgr_psbrdif)(1358 ) ConfigureVbdAndT38:FAXTransportType:0 T38Version:0 FaxBypassPT:102 1d:11h:21m:38s ( lgr_psbrdif)(1359 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=0, VxxTranType=0, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1,ECEType=0 SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) 1d:11h:21m:38s ( lgr_psbrdif)(1360 ) #2:FAXTransportType = 0 1d:11h:21m:38s ( lgr_psbrdif)(1361 ) Detectors: Amd:On=0,Direction=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 1d:11h:21m:38s ( lgr_psbrdif)(1362 ) #2:Channel will be open WITH DSP 1d:11h:21m:38s SWWD : Run Task GWAP Ticks 5 (5), mode 1, priority 50 [Code:0x20019] [CID:0] 1d:11h:21m:38s IP e5acf8 , LR e3b44c [Code:0x20019] [CID:0] 1d:11h:21m:38s Trace 0-4: e3b44c 3c6d44 e44930 43ce64 35ffb4 1d:11h:21m:38s Trace 5-9: 35ff30 360948 329d8 3315c 798764 1d:11h:21m:38s Trace 10-14: c59660 7c7dd8 7c7d64 7c7be4 7a06bc 1d:11h:21m:38s Trace 15-19: 78a3f8 820d8c 8147cc 81f96c 81cd9c 1d:11h:21m:38s SWWD : Wait Task GWAP, status 0, mode 1, priority 50, event 0 [Code:0x20019] [CID:0] 1d:11h:21m:38s TBack = 220 [Code:0x20019] [CID:0] 1d:11h:21m:38s ( sip_stack)(1363 ) Resource SIPMessage deleted - #29 1d:11h:21m:38s ( sip_stack)(1364 ) Resource SIPMessage deleted - #34 1d:11h:21m:38s ( sip_stack)(1365 ) SDPBodyMedia::Delete - id = 27 1d:11h:21m:38s ( sip_stack)(1366 ) SDPBody::Delete - id = 15 1d:11h:21m:38s ( lgr_flow)(1367 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:38s ACK sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac424690477 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c424669693 To: <sip:100@192.168.1.221;user=phone>;tag=1c204661014 Call-ID: 424669120182019112138@192.168.1.222 CSeq: 1 ACK Contact: <sip:112@192.168.1.222:5060> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Length: 0 1d:11h:21m:38s ( sip_stack)(1369 ) New SIPMessage created - #30 1d:11h:21m:38s ( sip_stack)(1370 ) UdpRtxMngr::Remove 302 Response 1 INVITE 1d:11h:21m:38s ( lgr_flow)(1371 ) | |(SIPTU#10)ACK State:Disconnected(424669120182019112138@192.168.1.222) 1d:11h:21m:38s ( sip_stack)(1372 ) Resource SIPMessage deleted - #30 1d:11h:21m:43s ( sip_stack)(1373 ) New SIPMessage created - #27 1d:11h:21m:43s ( sip_stack)(1374 ) Resource SIPMessage deleted - #27 1d:11h:21m:43s ( lgr_flow)(1375 ) | | TransactionUserMngr::ReturnTransactionUser - #10 1d:11h:21m:43s ( sip_stack)(1376 ) SIPCall(#10) changes state from Disconnected to Idle 1d:11h:21m:51s ( lgr_flow)(1377 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:51s INVITE sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac456564039 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c456553968 To: <sip:100@192.168.1.221;user=phone> Call-ID: 456543071182019112151@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222:5060> Supported: em,100rel,timer,replaces,path,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Type: application/sdp Content-Length: 254 v=0 o=AudiocodesGW 456517939 456517937 IN IP4 192.168.1.222 s=Phone-Call c=IN IP4 192.168.1.222 t=0 0 m=audio 6000 RTP/AVP 8 0 96 a=ptime:20 a=sendrecv a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 1d:11h:21m:51s ( sip_stack)(1379 ) New SIPMessage created - #25 1d:11h:21m:51s ( sip_stack)(1380 ) New AcSIPCallAPI created - #3 1d:11h:21m:51s ( sip_stack)(1381 ) New SIPStackSession created - #3 1d:11h:21m:51s ( sip_stack)(1382 ) New AcSIPCall created - #9 1d:11h:21m:51s ( lgr_flow)(1383 ) | |(SIPTU#9)INVITE State:Idle(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( sip_stack)(1384 ) SIPSessionTimer<TU#9>::HandleSTRequest - Session-Timer mode: SUPPORTED 1d:11h:21m:51s ( sip_stack)(1385 ) SIPCall(#9) changes state from Idle to Invited 1d:11h:21m:51s ( sip_stack)(1386 ) SDPBody::New - id = 14 1d:11h:21m:51s ( sip_stack)(1387 ) SDPBodyMedia::New - id = 24 1d:11h:21m:51s ( lgr_flow)(1388 ) | | | #3:SIP_SETUP_EV(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_call)(1389 ) (#3) CALL Allocated. 1d:11h:21m:51s ( lgr_stk_ses)(1390 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 1d:11h:21m:51s ( lgr_stk_ses)(1391 ) <SESSION #3> SendToCall - event: NEW_CALL_EV m_Call#3 1d:11h:21m:51s ( lgr_flow)(1392 ) | | #3:NEW_CALL_EV:(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_flow)(1393 ) | | #3:Call changing states from:IdleState to:NewCallState_IP2Tel 1d:11h:21m:51s ( lgr_flow)(1394 ) ServicesMngr::GetEndPoint PhoneNum = 100 1d:11h:21m:51s ( lgr_psbrdif)(1395 ) GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:100 DstPfx:* SrcNum:112 SrcPfx:* SrcIp:c0a801de SrcIpPfx:192.168.1.222 1d:11h:21m:51s ( lgr_psbrdif)(1396 ) QueryOnHookPortStatus (ChannelNum=0), status = 1 Polarity = 0 Validate = 0 1d:11h:21m:51s ( lgr_psbrdif)(1397 ) QueryOnHookPortStatus (ChannelNum=1), status = 1 Polarity = 0 Validate = 0 1d:11h:21m:51s ( lgr_psbrdif)(1398 ) QueryOnHookPortStatus (ChannelNum=2), status = 1 Polarity = 0 Validate = 0 1d:11h:21m:51s ( lgr_call)(1399 ) Call::SetCoderListForCall #3 Found 2 Common Coders For Call 1d:11h:21m:51s ( lgr_call)(1400 ) <Call #3> Coder g711Alaw64k20 : 20 1d:11h:21m:51s ( lgr_call)(1401 ) <Call #3> Coder g711Ulaw64k20 : 20 1d:11h:21m:51s ( lgr_profiling)(1402 ) <Call 3> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 IPDS=46 SigIPDF=40 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 EEarlyM=0 FaxTM=0 IsFaxU=0 PI2IP=-1 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 SCE=0 VxxTT=0 Dst2Rdrt=0 DTMFVol=20 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 MWIA=0 MWID=0 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DPInd=255 AGC=0 NLP=0 1d:11h:21m:51s ( lgr_call)(1403 ) | | #3GetNextUI:GlobalUI=466533580, mACAddrLsb=11400015 1d:11h:21m:51s ( lgr_call)(1404 ) | | #3GetNextUI:GlobalUI=466533581 1d:11h:21m:51s ( lgr_flow)(1405 ) | #2:NEW_CALL_EV : (456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_flow)(1406 ) EndPoint::MediaResourceList::AllocateMediaIpPortsByMediaRealmID Perform NEW allocation of Media ports for RealmIndex(0) port(6010) current allocations are:(1) Owner:(0) 1d:11h:21m:51s ( sip_stack)(1407 ) SDPBodyMedia::New - id = 23 1d:11h:21m:51s ( sip_stack)(1408 ) SIPSDPSession#3 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 1d:11h:21m:51s ( sip_stack)(1409 ) <BaseSIP SDPSESSION #3> UpdateChosenMediaByCN - CN as Remote -1 1d:11h:21m:51s ( sip_stack)(1410 ) SDPBodyMedia::Delete - id = 23 1d:11h:21m:51s ( sip_stack)(1411 ) SDPBodyMedia::New - id = 22 1d:11h:21m:51s ( lgr_flow)(1412 ) | |(SIPTU#9)TRYING_REQ State:Invited(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( sip_stack)(1413 ) New SIPMessage created - #26 1d:11h:21m:51s ( lgr_flow)(1414 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:51s SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac456564039 From: <sip:112@192.168.1.222>;tag=1c456553968 To: <sip:100@192.168.1.221;user=phone>;tag=1c236525175 Call-ID: 456543071182019112151@192.168.1.222 CSeq: 1 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: MP-114 FXO/v.6.60A.350.001 Content-Length: 0 1d:11h:21m:51s ( sip_stack)(1416 ) Resource SIPMessage deleted - #26 1d:11h:21m:51s ( lgr_stk_ses)(1417 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 1d:11h:21m:51s ( lgr_stk_ses)(1418 ) <SESSION #3> SendToCall - event: SETUP_EV m_Call#3 1d:11h:21m:51s ( lgr_flow)(1419 ) | | #3:SETUP (TO:100, FROM:112):(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_flow)(1420 ) | | #3:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel 1d:11h:21m:51s ( lgr_flow)(1421 ) | #2:SETUP_EV : (456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_flow)(1422 ) | #2:FWD_CALL_EV (send) : (456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_flow)(1423 ) | | #3:FWD_CALL_EV:(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_flow)(1424 ) | | | (#3)SIPStackSession <- (#0)ENDPOINT: FWD_CALL_EV (456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( sip_stack)(1425 ) New SIPMessage created - #24 1d:11h:21m:51s ( lgr_flow)(1426 ) | |(SIPTU#9)FORWARD_REQ State:Invited(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( sip_stack)(1427 ) SIPCall(#9) changes state from Invited to Disconnected 1d:11h:21m:51s ( lgr_flow)(1428 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:51s SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac456564039 From: <sip:112@192.168.1.222>;tag=1c456553968 To: <sip:100@192.168.1.221;user=phone>;tag=1c236525175 Call-ID: 456543071182019112151@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222;user=phone> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Diversion: <tel:112>;reason=unconditional;counter=1 Server: MP-114 FXO/v.6.60A.350.001 Reason: SIP ;cause=302 ;text="302 Moved Temporarily" Content-Length: 0 1d:11h:21m:51s ( sip_stack)(1430 ) UdpRtxMngr::Transmit 302 Response 1 INVITE Rtx Left: 6 Dest: 192.168.1.222:5060 CallID: (456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_stk_ses)(1431 ) <SESSION #3> SendToCall - event: RELEASE_EV m_Call#3 1d:11h:21m:51s ( lgr_flow)(1432 ) | | #3:RELEASE_EV:(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_flow)(1433 ) | | #3:Call changing states from:InitiatedState_IP2Tel to:DisconnectingState 1d:11h:21m:51s |CALL_END |2 |312822476 |-1 |2 |0 |1 |FXO |RMT |192.168.1.222 |192.168.1.221 |0 |0 |112 |112 |0 |0 |100 |100 |0 |N/A |255 | |0 |RMT |RELEASE_BECAUSE_FORWARD |0 |-1 |-1 |0 |0 |456543071182019112151@192.168.1.222 |11:21:51.575 UTC Thu Aug 01 2019 | |11:21:51.600 UTC Thu Aug 01 2019 |-1 |-1 |-1 |-1 |-1 |0 |0 | |0 |192.168.1.222 |192.168.1.222 |192.168.1.221 |192.168.1.221 |0 () |192.168.1.221 |6010 | | |FORWARDED | |0 (DefaultSRD) |0 |0 |0 () |0 (DefaultRealm) |UDP |46 |40 |127 |127 |127 |127 |5060 |5060 |AUDIO | | |302 | |0 | |0 1d:11h:21m:51s ( lgr_flow)(1435 ) | | #3:RELEASE_ACK_EV:(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_flow)(1436 ) EndPoint::MediaResourceList::FreeMediaIpPorts Perform dellocation of Media ports for RealmIndex(0) port(6010) current allocations are:(0) Owner:(0) 1d:11h:21m:51s ( lgr_flow)(1437 ) | #2:RELEASE_EV RELEASE_BECAUSE_FORWARD : (456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_flow)(1438 ) | | #3:RELEASE_ACK_EV:(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( lgr_flow)(1439 ) | | | (#3)SIPStackSession <- (#0)ENDPOINT: RELEASE_ACK_EV (456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( sip_stack)(1440 ) New SIPMessage created - #21 1d:11h:21m:51s ( sip_stack)(1441 ) AcSIPStackAPI::FreeCallAPI - #3 1d:11h:21m:51s ( sip_stack)(1442 ) Setting ApplicationCall of AcSIPCall#9 to NULL 1d:11h:21m:51s ( sip_stack)(1443 ) SDPBodyMedia::Delete - id = 22 1d:11h:21m:51s ( lgr_stk_mngr)(1444 ) Resource StackSession <#3> Deleted 1d:11h:21m:51s ( sip_stack)(1445 ) Resource SIPMessage deleted - #21 1d:11h:21m:51s ( lgr_call)(1446 ) (#3) CALL Deallocated. 1d:11h:21m:51s ( lgr_call)(1447 ) delete GW call current active is: 1 1d:11h:21m:51s ( lgr_psbrdif)(1448 ) #2:cpDigitMapHndlr_Stop - Stoped (0) 1d:11h:21m:51s ( lgr_psbrdif)(1449 ) #2:CloseChannel: ChannelNum=2 1d:11h:21m:51s ( lgr_psbrdif)(1450 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 1d:11h:21m:51s ( lgr_psbrdif)(1451 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g723130 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED 1d:11h:21m:51s ( lgr_psbrdif)(1452 ) #2:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 1d:11h:21m:51s ( lgr_psbrdif)(1453 ) RFC2833RTPPayloadType: Rx=101 Tx=101 1d:11h:21m:51s ( lgr_psbrdif)(1454 ) OpenChannel, CoderType = 16, Interval = 0, M = 1 1d:11h:21m:51s ( lgr_psbrdif)(1455 ) ConfigureVbdAndT38:FAXTransportType:0 T38Version:0 FaxBypassPT:102 1d:11h:21m:51s ( lgr_psbrdif)(1456 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=0, VxxTranType=0, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1,ECEType=0 SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) 1d:11h:21m:51s ( lgr_psbrdif)(1457 ) #2:FAXTransportType = 0 1d:11h:21m:51s ( lgr_psbrdif)(1458 ) Detectors: Amd:On=0,Direction=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 1d:11h:21m:51s ( lgr_psbrdif)(1459 ) #2:Channel will be open WITH DSP 1d:11h:21m:51s SWWD : Run Task GWAP Ticks 5 (5), mode 1, priority 50 [Code:0x20019] [CID:0] 1d:11h:21m:51s IP 3e910 , LR 3e694 [Code:0x20019] [CID:0] 1d:11h:21m:51s Trace 0-4: 39c4c 39cb0 78ac9c 820d8c 8147cc 1d:11h:21m:51s Trace 5-9: 81f96c 81cd9c 8132ec c65668 c8e124 1d:11h:21m:51s Trace 10-14: c8d09c 89c2bc 81c8d4 868474 85e2d0 1d:11h:21m:51s Trace 15-19: 85de34 85de34 868d9c 854b54 840128 1d:11h:21m:51s SWWD : Wait Task GWAP, status 0, mode 1, priority 50, event 0 [Code:0x20019] [CID:0] 1d:11h:21m:51s TBack = 1bfce30 [Code:0x20019] [CID:0] 1d:11h:21m:51s ( sip_stack)(1460 ) Resource SIPMessage deleted - #24 1d:11h:21m:51s ( sip_stack)(1461 ) Resource SIPMessage deleted - #25 1d:11h:21m:51s ( sip_stack)(1462 ) SDPBodyMedia::Delete - id = 24 1d:11h:21m:51s ( sip_stack)(1463 ) SDPBody::Delete - id = 14 1d:11h:21m:51s ( lgr_flow)(1464 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:21m:51s ACK sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac456564039 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c456553968 To: <sip:100@192.168.1.221;user=phone>;tag=1c236525175 Call-ID: 456543071182019112151@192.168.1.222 CSeq: 1 ACK Contact: <sip:112@192.168.1.222:5060> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Length: 0 1d:11h:21m:51s ( sip_stack)(1466 ) New SIPMessage created - #23 1d:11h:21m:51s ( sip_stack)(1467 ) UdpRtxMngr::Remove 302 Response 1 INVITE 1d:11h:21m:51s ( lgr_flow)(1468 ) | |(SIPTU#9)ACK State:Disconnected(456543071182019112151@192.168.1.222) 1d:11h:21m:51s ( sip_stack)(1469 ) Resource SIPMessage deleted - #23 1d:11h:21m:56s ( sip_stack)(1470 ) New SIPMessage created - #22 1d:11h:21m:56s ( sip_stack)(1471 ) Resource SIPMessage deleted - #22 1d:11h:21m:56s ( lgr_flow)(1472 ) | | TransactionUserMngr::ReturnTransactionUser - #9 1d:11h:21m:56s ( sip_stack)(1473 ) SIPCall(#9) changes state from Disconnected to Idle 1d:11h:22m:0s ( lgr_flow)(1474 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:0s INVITE sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac478565752 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c478555366 To: <sip:100@192.168.1.221;user=phone> Call-ID: 47855478118201911220@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222:5060> Supported: em,100rel,timer,replaces,path,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Type: application/sdp Content-Length: 254 v=0 o=AudiocodesGW 478519924 478519922 IN IP4 192.168.1.222 s=Phone-Call c=IN IP4 192.168.1.222 t=0 0 m=audio 6010 RTP/AVP 8 0 96 a=ptime:20 a=sendrecv a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 1d:11h:22m:0s ( sip_stack)(1476 ) New SIPMessage created - #19 1d:11h:22m:0s ( sip_stack)(1477 ) New AcSIPCallAPI created - #2 1d:11h:22m:0s ( sip_stack)(1478 ) New SIPStackSession created - #2 1d:11h:22m:0s ( sip_stack)(1479 ) New AcSIPCall created - #8 1d:11h:22m:0s ( lgr_flow)(1480 ) | |(SIPTU#8)INVITE State:Idle(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( sip_stack)(1481 ) SIPSessionTimer<TU#8>::HandleSTRequest - Session-Timer mode: SUPPORTED 1d:11h:22m:0s ( sip_stack)(1482 ) SIPCall(#8) changes state from Idle to Invited 1d:11h:22m:0s ( sip_stack)(1483 ) SDPBody::New - id = 13 1d:11h:22m:0s ( sip_stack)(1484 ) SDPBodyMedia::New - id = 21 1d:11h:22m:0s ( lgr_flow)(1485 ) | | | #2:SIP_SETUP_EV(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_call)(1486 ) (#2) CALL Allocated. 1d:11h:22m:0s ( lgr_stk_ses)(1487 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 1d:11h:22m:0s ( lgr_stk_ses)(1488 ) <SESSION #2> SendToCall - event: NEW_CALL_EV m_Call#2 1d:11h:22m:0s ( lgr_flow)(1489 ) | | #2:NEW_CALL_EV:(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_flow)(1490 ) | | #2:Call changing states from:IdleState to:NewCallState_IP2Tel 1d:11h:22m:0s ( lgr_flow)(1491 ) ServicesMngr::GetEndPoint PhoneNum = 100 1d:11h:22m:0s ( lgr_psbrdif)(1492 ) GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:100 DstPfx:* SrcNum:112 SrcPfx:* SrcIp:c0a801de SrcIpPfx:192.168.1.222 1d:11h:22m:0s ( lgr_psbrdif)(1493 ) QueryOnHookPortStatus (ChannelNum=0), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:0s ( lgr_psbrdif)(1494 ) QueryOnHookPortStatus (ChannelNum=1), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:0s ( lgr_psbrdif)(1495 ) QueryOnHookPortStatus (ChannelNum=2), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:0s ( lgr_call)(1496 ) Call::SetCoderListForCall #2 Found 2 Common Coders For Call 1d:11h:22m:0s ( lgr_call)(1497 ) <Call #2> Coder g711Alaw64k20 : 20 1d:11h:22m:0s ( lgr_call)(1498 ) <Call #2> Coder g711Ulaw64k20 : 20 1d:11h:22m:0s ( lgr_profiling)(1499 ) <Call 2> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 IPDS=46 SigIPDF=40 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 EEarlyM=0 FaxTM=0 IsFaxU=0 PI2IP=-1 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 SCE=0 VxxTT=0 Dst2Rdrt=0 DTMFVol=20 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 MWIA=0 MWID=0 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DPInd=255 AGC=0 NLP=0 1d:11h:22m:0s ( lgr_call)(1500 ) | | #2GetNextUI:GlobalUI=466533581, mACAddrLsb=11400015 1d:11h:22m:0s ( lgr_call)(1501 ) | | #2GetNextUI:GlobalUI=466533582 1d:11h:22m:0s ( lgr_flow)(1502 ) | #2:NEW_CALL_EV : (47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_flow)(1503 ) EndPoint::MediaResourceList::AllocateMediaIpPortsByMediaRealmID Perform NEW allocation of Media ports for RealmIndex(0) port(6020) current allocations are:(1) Owner:(0) 1d:11h:22m:0s ( sip_stack)(1504 ) SDPBodyMedia::New - id = 20 1d:11h:22m:0s ( sip_stack)(1505 ) SIPSDPSession#2 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 1d:11h:22m:0s ( sip_stack)(1506 ) <BaseSIP SDPSESSION #2> UpdateChosenMediaByCN - CN as Remote -1 1d:11h:22m:0s ( sip_stack)(1507 ) SDPBodyMedia::Delete - id = 20 1d:11h:22m:0s ( sip_stack)(1508 ) SDPBodyMedia::New - id = 19 1d:11h:22m:0s ( lgr_flow)(1509 ) | |(SIPTU#8)TRYING_REQ State:Invited(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( sip_stack)(1510 ) New SIPMessage created - #20 1d:11h:22m:0s ( lgr_flow)(1511 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:0s SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac478565752 From: <sip:112@192.168.1.222>;tag=1c478555366 To: <sip:100@192.168.1.221;user=phone>;tag=1c258525185 Call-ID: 47855478118201911220@192.168.1.222 CSeq: 1 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: MP-114 FXO/v.6.60A.350.001 Content-Length: 0 1d:11h:22m:0s ( sip_stack)(1513 ) Resource SIPMessage deleted - #20 1d:11h:22m:0s ( lgr_stk_ses)(1514 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 1d:11h:22m:0s ( lgr_stk_ses)(1515 ) <SESSION #2> SendToCall - event: SETUP_EV m_Call#2 1d:11h:22m:0s ( lgr_flow)(1516 ) | | #2:SETUP (TO:100, FROM:112):(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_flow)(1517 ) | | #2:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel 1d:11h:22m:0s ( lgr_flow)(1518 ) | #2:SETUP_EV : (47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_flow)(1519 ) | #2:FWD_CALL_EV (send) : (47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_flow)(1520 ) | | #2:FWD_CALL_EV:(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_flow)(1521 ) | | | (#2)SIPStackSession <- (#0)ENDPOINT: FWD_CALL_EV (47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( sip_stack)(1522 ) New SIPMessage created - #18 1d:11h:22m:0s ( lgr_flow)(1523 ) | |(SIPTU#8)FORWARD_REQ State:Invited(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( sip_stack)(1524 ) SIPCall(#8) changes state from Invited to Disconnected 1d:11h:22m:0s ( lgr_flow)(1525 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:0s SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac478565752 From: <sip:112@192.168.1.222>;tag=1c478555366 To: <sip:100@192.168.1.221;user=phone>;tag=1c258525185 Call-ID: 47855478118201911220@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222;user=phone> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Diversion: <tel:112>;reason=unconditional;counter=1 Server: MP-114 FXO/v.6.60A.350.001 Reason: SIP ;cause=302 ;text="302 Moved Temporarily" Content-Length: 0 1d:11h:22m:0s ( sip_stack)(1527 ) UdpRtxMngr::Transmit 302 Response 1 INVITE Rtx Left: 6 Dest: 192.168.1.222:5060 CallID: (47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_stk_ses)(1528 ) <SESSION #2> SendToCall - event: RELEASE_EV m_Call#2 1d:11h:22m:0s ( lgr_flow)(1529 ) | | #2:RELEASE_EV:(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_flow)(1530 ) | | #2:Call changing states from:InitiatedState_IP2Tel to:DisconnectingState 1d:11h:22m:0s |CALL_END |2 |312822478 |-1 |2 |0 |1 |FXO |RMT |192.168.1.222 |192.168.1.221 |0 |0 |112 |112 |0 |0 |100 |100 |0 |N/A |255 | |0 |RMT |RELEASE_BECAUSE_FORWARD |0 |-1 |-1 |0 |0 |47855478118201911220@192.168.1.222 |11:22:00.375 UTC Thu Aug 01 2019 | |11:22:00.400 UTC Thu Aug 01 2019 |-1 |-1 |-1 |-1 |-1 |0 |0 | |0 |192.168.1.222 |192.168.1.222 |192.168.1.221 |192.168.1.221 |0 () |192.168.1.221 |6020 | | |FORWARDED | |0 (DefaultSRD) |0 |0 |0 () |0 (DefaultRealm) |UDP |46 |40 |127 |127 |127 |127 |5060 |5060 |AUDIO | | |302 | |0 | |0 1d:11h:22m:0s ( lgr_flow)(1532 ) | | #2:RELEASE_ACK_EV:(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_flow)(1533 ) EndPoint::MediaResourceList::FreeMediaIpPorts Perform dellocation of Media ports for RealmIndex(0) port(6020) current allocations are:(0) Owner:(0) 1d:11h:22m:0s ( lgr_flow)(1534 ) | #2:RELEASE_EV RELEASE_BECAUSE_FORWARD : (47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_flow)(1535 ) | | #2:RELEASE_ACK_EV:(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( lgr_flow)(1536 ) | | | (#2)SIPStackSession <- (#0)ENDPOINT: RELEASE_ACK_EV (47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( sip_stack)(1537 ) New SIPMessage created - #17 1d:11h:22m:0s ( sip_stack)(1538 ) AcSIPStackAPI::FreeCallAPI - #2 1d:11h:22m:0s ( sip_stack)(1539 ) Setting ApplicationCall of AcSIPCall#8 to NULL 1d:11h:22m:0s ( sip_stack)(1540 ) SDPBodyMedia::Delete - id = 19 1d:11h:22m:0s ( lgr_stk_mngr)(1541 ) Resource StackSession <#2> Deleted 1d:11h:22m:0s ( sip_stack)(1542 ) Resource SIPMessage deleted - #17 1d:11h:22m:0s ( lgr_call)(1543 ) (#2) CALL Deallocated. 1d:11h:22m:0s ( lgr_call)(1544 ) delete GW call current active is: 1 1d:11h:22m:0s ( lgr_psbrdif)(1545 ) #2:cpDigitMapHndlr_Stop - Stoped (0) 1d:11h:22m:0s ( lgr_psbrdif)(1546 ) #2:CloseChannel: ChannelNum=2 1d:11h:22m:0s ( lgr_psbrdif)(1547 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 1d:11h:22m:0s ( lgr_psbrdif)(1548 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g723130 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED 1d:11h:22m:0s ( lgr_psbrdif)(1549 ) #2:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 1d:11h:22m:0s ( lgr_psbrdif)(1550 ) RFC2833RTPPayloadType: Rx=101 Tx=101 1d:11h:22m:0s ( lgr_psbrdif)(1551 ) OpenChannel, CoderType = 16, Interval = 0, M = 1 1d:11h:22m:0s ( lgr_psbrdif)(1552 ) ConfigureVbdAndT38:FAXTransportType:0 T38Version:0 FaxBypassPT:102 1d:11h:22m:0s ( lgr_psbrdif)(1553 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=0, VxxTranType=0, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1,ECEType=0 SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) 1d:11h:22m:0s ( lgr_psbrdif)(1554 ) #2:FAXTransportType = 0 1d:11h:22m:0s SWWD : Run Task DSPD Ticks 5 (5), mode 1, priority 170 [Code:0x20019] [CID:0] 1d:11h:22m:0s IP 3c99d4 , LR e621a4 [Code:0x20019] [CID:0] 1d:11h:22m:0s !!! Repeated 10 times : Trace 0-4: e621a4 e375fc e3dac4 e27528 e323f0 1d:11h:22m:0s SWWD : Wait Task GWAP, status 0, mode 0, priority 50, event 0 [Code:0x20019] [CID:0] 1d:11h:22m:0s TBack = e11784 ddf5fc 78a4c0 820d8c 8147cc [Code:0x20019] [CID:0] 1d:11h:22m:0s ( lgr_psbrdif)(1555 ) Detectors: Amd:On=0,Direction=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 1d:11h:22m:0s ( lgr_psbrdif)(1556 ) #2:Channel will be open WITH DSP 1d:11h:22m:0s ( sip_stack)(1557 ) Resource SIPMessage deleted - #18 1d:11h:22m:0s ( sip_stack)(1558 ) Resource SIPMessage deleted - #19 1d:11h:22m:0s ( sip_stack)(1559 ) SDPBodyMedia::Delete - id = 21 1d:11h:22m:0s ( sip_stack)(1560 ) SDPBody::Delete - id = 13 1d:11h:22m:0s ( lgr_flow)(1561 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:0s ACK sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac478565752 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c478555366 To: <sip:100@192.168.1.221;user=phone>;tag=1c258525185 Call-ID: 47855478118201911220@192.168.1.222 CSeq: 1 ACK Contact: <sip:112@192.168.1.222:5060> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Length: 0 1d:11h:22m:0s ( sip_stack)(1563 ) New SIPMessage created - #16 1d:11h:22m:0s ( sip_stack)(1564 ) UdpRtxMngr::Remove 302 Response 1 INVITE 1d:11h:22m:0s ( lgr_flow)(1565 ) | |(SIPTU#8)ACK State:Disconnected(47855478118201911220@192.168.1.222) 1d:11h:22m:0s ( sip_stack)(1566 ) Resource SIPMessage deleted - #16 1d:11h:22m:5s ( sip_stack)(1567 ) New SIPMessage created - #13 1d:11h:22m:5s ( sip_stack)(1568 ) Resource SIPMessage deleted - #13 1d:11h:22m:5s ( lgr_flow)(1569 ) | | TransactionUserMngr::ReturnTransactionUser - #8 1d:11h:22m:5s ( sip_stack)(1570 ) SIPCall(#8) changes state from Disconnected to Idle 1d:11h:22m:11s ( lgr_flow)(1571 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:11s INVITE sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac506067864 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c506049436 To: <sip:100@192.168.1.221;user=phone> Call-ID: 506048865182019112211@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222:5060> Supported: em,100rel,timer,replaces,path,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Type: application/sdp Content-Length: 254 v=0 o=AudiocodesGW 506023256 506023255 IN IP4 192.168.1.222 s=Phone-Call c=IN IP4 192.168.1.222 t=0 0 m=audio 6020 RTP/AVP 8 0 96 a=ptime:20 a=sendrecv a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 1d:11h:22m:11s ( sip_stack)(1573 ) New SIPMessage created - #12 1d:11h:22m:11s ( sip_stack)(1574 ) New AcSIPCallAPI created - #1 1d:11h:22m:11s ( sip_stack)(1575 ) New SIPStackSession created - #1 1d:11h:22m:11s ( sip_stack)(1576 ) New AcSIPCall created - #7 1d:11h:22m:11s ( lgr_flow)(1577 ) | |(SIPTU#7)INVITE State:Idle(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( sip_stack)(1578 ) SIPSessionTimer<TU#7>::HandleSTRequest - Session-Timer mode: SUPPORTED 1d:11h:22m:11s ( sip_stack)(1579 ) SIPCall(#7) changes state from Idle to Invited 1d:11h:22m:11s ( sip_stack)(1580 ) SDPBody::New - id = 12 1d:11h:22m:11s ( sip_stack)(1581 ) SDPBodyMedia::New - id = 18 1d:11h:22m:11s ( lgr_flow)(1582 ) | | | #1:SIP_SETUP_EV(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_call)(1583 ) (#1) CALL Allocated. 1d:11h:22m:11s ( lgr_stk_ses)(1584 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 1d:11h:22m:11s ( lgr_stk_ses)(1585 ) <SESSION #1> SendToCall - event: NEW_CALL_EV m_Call#1 1d:11h:22m:11s ( lgr_flow)(1586 ) | | #1:NEW_CALL_EV:(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_flow)(1587 ) | | #1:Call changing states from:IdleState to:NewCallState_IP2Tel 1d:11h:22m:11s ( lgr_flow)(1588 ) ServicesMngr::GetEndPoint PhoneNum = 100 1d:11h:22m:11s ( lgr_psbrdif)(1589 ) GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:100 DstPfx:* SrcNum:112 SrcPfx:* SrcIp:c0a801de SrcIpPfx:192.168.1.222 1d:11h:22m:11s ( lgr_psbrdif)(1590 ) QueryOnHookPortStatus (ChannelNum=0), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:11s ( lgr_psbrdif)(1591 ) QueryOnHookPortStatus (ChannelNum=1), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:11s ( lgr_psbrdif)(1592 ) QueryOnHookPortStatus (ChannelNum=2), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:11s ( lgr_call)(1593 ) Call::SetCoderListForCall #1 Found 2 Common Coders For Call 1d:11h:22m:11s ( lgr_call)(1594 ) <Call #1> Coder g711Alaw64k20 : 20 1d:11h:22m:11s ( lgr_call)(1595 ) <Call #1> Coder g711Ulaw64k20 : 20 1d:11h:22m:11s ( lgr_profiling)(1596 ) <Call 1> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 IPDS=46 SigIPDF=40 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 EEarlyM=0 FaxTM=0 IsFaxU=0 PI2IP=-1 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 SCE=0 VxxTT=0 Dst2Rdrt=0 DTMFVol=20 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 MWIA=0 MWID=0 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DPInd=255 AGC=0 NLP=0 1d:11h:22m:11s ( lgr_call)(1597 ) | | #1GetNextUI:GlobalUI=466533582, mACAddrLsb=11400015 1d:11h:22m:11s ( lgr_call)(1598 ) | | #1GetNextUI:GlobalUI=466533583 1d:11h:22m:11s ( lgr_flow)(1599 ) | #2:NEW_CALL_EV : (506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_flow)(1600 ) EndPoint::MediaResourceList::AllocateMediaIpPortsByMediaRealmID Perform NEW allocation of Media ports for RealmIndex(0) port(6030) current allocations are:(1) Owner:(0) 1d:11h:22m:11s ( sip_stack)(1601 ) SDPBodyMedia::New - id = 17 1d:11h:22m:11s ( sip_stack)(1602 ) SIPSDPSession#1 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 1d:11h:22m:11s ( sip_stack)(1603 ) <BaseSIP SDPSESSION #1> UpdateChosenMediaByCN - CN as Remote -1 1d:11h:22m:11s ( sip_stack)(1604 ) SDPBodyMedia::Delete - id = 17 1d:11h:22m:11s ( sip_stack)(1605 ) SDPBodyMedia::New - id = 16 1d:11h:22m:11s ( lgr_flow)(1606 ) | |(SIPTU#7)TRYING_REQ State:Invited(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( sip_stack)(1607 ) New SIPMessage created - #14 1d:11h:22m:11s ( lgr_flow)(1608 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:11s SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac506067864 From: <sip:112@192.168.1.222>;tag=1c506049436 To: <sip:100@192.168.1.221;user=phone>;tag=1c286030120 Call-ID: 506048865182019112211@192.168.1.222 CSeq: 1 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: MP-114 FXO/v.6.60A.350.001 Content-Length: 0 1d:11h:22m:11s ( sip_stack)(1610 ) Resource SIPMessage deleted - #14 1d:11h:22m:11s ( lgr_stk_ses)(1611 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 1d:11h:22m:11s ( lgr_stk_ses)(1612 ) <SESSION #1> SendToCall - event: SETUP_EV m_Call#1 1d:11h:22m:11s ( lgr_flow)(1613 ) | | #1:SETUP (TO:100, FROM:112):(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_flow)(1614 ) | | #1:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel 1d:11h:22m:11s ( lgr_flow)(1615 ) | #2:SETUP_EV : (506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_flow)(1616 ) | #2:FWD_CALL_EV (send) : (506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_flow)(1617 ) | | #1:FWD_CALL_EV:(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_flow)(1618 ) | | | (#1)SIPStackSession <- (#0)ENDPOINT: FWD_CALL_EV (506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( sip_stack)(1619 ) New SIPMessage created - #15 1d:11h:22m:11s ( lgr_flow)(1620 ) | |(SIPTU#7)FORWARD_REQ State:Invited(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( sip_stack)(1621 ) SIPCall(#7) changes state from Invited to Disconnected 1d:11h:22m:11s ( lgr_flow)(1622 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:11s SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac506067864 From: <sip:112@192.168.1.222>;tag=1c506049436 To: <sip:100@192.168.1.221;user=phone>;tag=1c286030120 Call-ID: 506048865182019112211@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222;user=phone> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Diversion: <tel:112>;reason=unconditional;counter=1 Server: MP-114 FXO/v.6.60A.350.001 Reason: SIP ;cause=302 ;text="302 Moved Temporarily" Content-Length: 0 1d:11h:22m:11s ( sip_stack)(1624 ) UdpRtxMngr::Transmit 302 Response 1 INVITE Rtx Left: 6 Dest: 192.168.1.222:5060 CallID: (506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_stk_ses)(1625 ) <SESSION #1> SendToCall - event: RELEASE_EV m_Call#1 1d:11h:22m:11s ( lgr_flow)(1626 ) | | #1:RELEASE_EV:(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_flow)(1627 ) | | #1:Call changing states from:InitiatedState_IP2Tel to:DisconnectingState 1d:11h:22m:11s |CALL_END |2 |312822480 |-1 |2 |0 |1 |FXO |RMT |192.168.1.222 |192.168.1.221 |0 |0 |112 |112 |0 |0 |100 |100 |0 |N/A |255 | |0 |RMT |RELEASE_BECAUSE_FORWARD |0 |-1 |-1 |0 |0 |506048865182019112211@192.168.1.222 |11:22:11.375 UTC Thu Aug 01 2019 | |11:22:11.400 UTC Thu Aug 01 2019 |-1 |-1 |-1 |-1 |-1 |0 |0 | |0 |192.168.1.222 |192.168.1.222 |192.168.1.221 |192.168.1.221 |0 () |192.168.1.221 |6030 | | |FORWARDED | |0 (DefaultSRD) |0 |0 |0 () |0 (DefaultRealm) |UDP |46 |40 |127 |127 |127 |127 |5060 |5060 |AUDIO | | |302 | |0 | |0 1d:11h:22m:11s ( lgr_flow)(1629 ) | | #1:RELEASE_ACK_EV:(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_flow)(1630 ) EndPoint::MediaResourceList::FreeMediaIpPorts Perform dellocation of Media ports for RealmIndex(0) port(6030) current allocations are:(0) Owner:(0) 1d:11h:22m:11s ( lgr_flow)(1631 ) | #2:RELEASE_EV RELEASE_BECAUSE_FORWARD : (506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_flow)(1632 ) | | #1:RELEASE_ACK_EV:(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( lgr_flow)(1633 ) | | | (#1)SIPStackSession <- (#0)ENDPOINT: RELEASE_ACK_EV (506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( sip_stack)(1634 ) New SIPMessage created - #11 1d:11h:22m:11s ( sip_stack)(1635 ) AcSIPStackAPI::FreeCallAPI - #1 1d:11h:22m:11s ( sip_stack)(1636 ) Setting ApplicationCall of AcSIPCall#7 to NULL 1d:11h:22m:11s ( sip_stack)(1637 ) SDPBodyMedia::Delete - id = 16 1d:11h:22m:11s ( lgr_stk_mngr)(1638 ) Resource StackSession <#1> Deleted 1d:11h:22m:11s ( sip_stack)(1639 ) Resource SIPMessage deleted - #11 1d:11h:22m:11s ( lgr_call)(1640 ) (#1) CALL Deallocated. 1d:11h:22m:11s ( lgr_call)(1641 ) delete GW call current active is: 1 1d:11h:22m:11s ( lgr_psbrdif)(1642 ) #2:cpDigitMapHndlr_Stop - Stoped (0) 1d:11h:22m:11s ( lgr_psbrdif)(1643 ) #2:CloseChannel: ChannelNum=2 1d:11h:22m:11s ( lgr_psbrdif)(1644 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 1d:11h:22m:11s ( lgr_psbrdif)(1645 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g723130 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED 1d:11h:22m:11s ( lgr_psbrdif)(1646 ) #2:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 1d:11h:22m:11s ( lgr_psbrdif)(1647 ) RFC2833RTPPayloadType: Rx=101 Tx=101 1d:11h:22m:11s ( lgr_psbrdif)(1648 ) OpenChannel, CoderType = 16, Interval = 0, M = 1 1d:11h:22m:11s ( lgr_psbrdif)(1649 ) ConfigureVbdAndT38:FAXTransportType:0 T38Version:0 FaxBypassPT:102 1d:11h:22m:11s ( lgr_psbrdif)(1650 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=0, VxxTranType=0, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1,ECEType=0 SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) 1d:11h:22m:11s ( lgr_psbrdif)(1651 ) #2:FAXTransportType = 0 1d:11h:22m:11s ( lgr_psbrdif)(1652 ) Detectors: Amd:On=0,Direction=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 1d:11h:22m:11s ( lgr_psbrdif)(1653 ) #2:Channel will be open WITH DSP 1d:11h:22m:11s SWWD : Run Task GWAP Ticks 5 (5), mode 1, priority 50 [Code:0x20019] [CID:0] 1d:11h:22m:11s IP 18e2f0 , LR 18e294 [Code:0x20019] [CID:0] 1d:11h:22m:11s !!! Repeated 13 times : Trace 0-4: 3294c 3315c 798764 c59660 7c7dd8 1d:11h:22m:11s SWWD : Wait Task GWAP, status 0, mode 1, priority 50, event 0 [Code:0x20019] [CID:0] 1d:11h:22m:11s TBack = 4 [Code:0x20019] [CID:0] 1d:11h:22m:11s ( sip_stack)(1654 ) Resource SIPMessage deleted - #15 1d:11h:22m:11s ( sip_stack)(1655 ) Resource SIPMessage deleted - #12 1d:11h:22m:11s ( sip_stack)(1656 ) SDPBodyMedia::Delete - id = 18 1d:11h:22m:11s ( sip_stack)(1657 ) SDPBody::Delete - id = 12 1d:11h:22m:11s ( lgr_flow)(1658 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:11s ACK sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac506067864 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c506049436 To: <sip:100@192.168.1.221;user=phone>;tag=1c286030120 Call-ID: 506048865182019112211@192.168.1.222 CSeq: 1 ACK Contact: <sip:112@192.168.1.222:5060> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Length: 0 1d:11h:22m:11s ( sip_stack)(1660 ) New SIPMessage created - #9 1d:11h:22m:11s ( sip_stack)(1661 ) UdpRtxMngr::Remove 302 Response 1 INVITE 1d:11h:22m:11s ( lgr_flow)(1662 ) | |(SIPTU#7)ACK State:Disconnected(506048865182019112211@192.168.1.222) 1d:11h:22m:11s ( sip_stack)(1663 ) Resource SIPMessage deleted - #9 1d:11h:22m:16s ( sip_stack)(1664 ) New SIPMessage created - #8 1d:11h:22m:16s ( sip_stack)(1665 ) Resource SIPMessage deleted - #8 1d:11h:22m:16s ( lgr_flow)(1666 ) | | TransactionUserMngr::ReturnTransactionUser - #7 1d:11h:22m:16s ( sip_stack)(1667 ) SIPCall(#7) changes state from Disconnected to Idle 1d:11h:22m:28s ( lgr_flow)(1668 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:28s INVITE sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac549318031 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c549303254 To: <sip:100@192.168.1.221;user=phone> Call-ID: 549302677182019112228@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222:5060> Supported: em,100rel,timer,replaces,path,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Type: application/sdp Content-Length: 254 v=0 o=AudiocodesGW 549274326 549274325 IN IP4 192.168.1.222 s=Phone-Call c=IN IP4 192.168.1.222 t=0 0 m=audio 6030 RTP/AVP 8 0 96 a=ptime:20 a=sendrecv a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 1d:11h:22m:28s ( sip_stack)(1670 ) New SIPMessage created - #5 1d:11h:22m:28s ( sip_stack)(1671 ) New AcSIPCallAPI created - #0 1d:11h:22m:28s ( sip_stack)(1672 ) New SIPStackSession created - #0 1d:11h:22m:28s ( sip_stack)(1673 ) New AcSIPCall created - #6 1d:11h:22m:28s ( lgr_flow)(1674 ) | |(SIPTU#6)INVITE State:Idle(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( sip_stack)(1675 ) SIPSessionTimer<TU#6>::HandleSTRequest - Session-Timer mode: SUPPORTED 1d:11h:22m:28s ( sip_stack)(1676 ) SIPCall(#6) changes state from Idle to Invited 1d:11h:22m:28s ( sip_stack)(1677 ) SDPBody::New - id = 11 1d:11h:22m:28s ( sip_stack)(1678 ) SDPBodyMedia::New - id = 15 1d:11h:22m:28s ( lgr_flow)(1679 ) | | | #0:SIP_SETUP_EV(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_call)(1680 ) (#0) CALL Allocated. 1d:11h:22m:28s ( lgr_stk_ses)(1681 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 1d:11h:22m:28s ( lgr_stk_ses)(1682 ) <SESSION #0> SendToCall - event: NEW_CALL_EV m_Call#0 1d:11h:22m:28s ( lgr_flow)(1683 ) | | #0:NEW_CALL_EV:(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_flow)(1684 ) | | #0:Call changing states from:IdleState to:NewCallState_IP2Tel 1d:11h:22m:28s ( lgr_flow)(1685 ) ServicesMngr::GetEndPoint PhoneNum = 100 1d:11h:22m:28s ( lgr_psbrdif)(1686 ) GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:100 DstPfx:* SrcNum:112 SrcPfx:* SrcIp:c0a801de SrcIpPfx:192.168.1.222 1d:11h:22m:28s ( lgr_psbrdif)(1687 ) QueryOnHookPortStatus (ChannelNum=0), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:28s ( lgr_psbrdif)(1688 ) QueryOnHookPortStatus (ChannelNum=1), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:28s ( lgr_psbrdif)(1689 ) QueryOnHookPortStatus (ChannelNum=2), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:28s ( lgr_call)(1690 ) Call::SetCoderListForCall #0 Found 2 Common Coders For Call 1d:11h:22m:28s ( lgr_call)(1691 ) <Call #0> Coder g711Alaw64k20 : 20 1d:11h:22m:28s ( lgr_call)(1692 ) <Call #0> Coder g711Ulaw64k20 : 20 1d:11h:22m:28s ( lgr_profiling)(1693 ) <Call 0> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 IPDS=46 SigIPDF=40 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 EEarlyM=0 FaxTM=0 IsFaxU=0 PI2IP=-1 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 SCE=0 VxxTT=0 Dst2Rdrt=0 DTMFVol=20 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 MWIA=0 MWID=0 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DPInd=255 AGC=0 NLP=0 1d:11h:22m:28s ( lgr_call)(1694 ) | | #0GetNextUI:GlobalUI=466533583, mACAddrLsb=11400015 1d:11h:22m:28s ( lgr_call)(1695 ) | | #0GetNextUI:GlobalUI=466533584 1d:11h:22m:28s ( lgr_flow)(1696 ) | #2:NEW_CALL_EV : (549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_flow)(1697 ) EndPoint::MediaResourceList::AllocateMediaIpPortsByMediaRealmID Perform NEW allocation of Media ports for RealmIndex(0) port(6000) current allocations are:(1) Owner:(0) 1d:11h:22m:28s ( sip_stack)(1698 ) SDPBodyMedia::New - id = 14 1d:11h:22m:28s ( sip_stack)(1699 ) SIPSDPSession#0 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 1d:11h:22m:28s ( sip_stack)(1700 ) <BaseSIP SDPSESSION #0> UpdateChosenMediaByCN - CN as Remote -1 1d:11h:22m:28s ( sip_stack)(1701 ) SDPBodyMedia::Delete - id = 14 1d:11h:22m:28s ( sip_stack)(1702 ) SDPBodyMedia::New - id = 13 1d:11h:22m:28s ( lgr_flow)(1703 ) | |(SIPTU#6)TRYING_REQ State:Invited(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( sip_stack)(1704 ) New SIPMessage created - #7 1d:11h:22m:28s ( lgr_flow)(1705 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:28s SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac549318031 From: <sip:112@192.168.1.222>;tag=1c549303254 To: <sip:100@192.168.1.221;user=phone>;tag=1c329279999 Call-ID: 549302677182019112228@192.168.1.222 CSeq: 1 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: MP-114 FXO/v.6.60A.350.001 Content-Length: 0 1d:11h:22m:28s ( sip_stack)(1707 ) Resource SIPMessage deleted - #7 1d:11h:22m:28s ( lgr_stk_ses)(1708 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 1d:11h:22m:28s ( lgr_stk_ses)(1709 ) <SESSION #0> SendToCall - event: SETUP_EV m_Call#0 1d:11h:22m:28s ( lgr_flow)(1710 ) | | #0:SETUP (TO:100, FROM:112):(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_flow)(1711 ) | | #0:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel 1d:11h:22m:28s ( lgr_flow)(1712 ) | #2:SETUP_EV : (549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_flow)(1713 ) | #2:FWD_CALL_EV (send) : (549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_flow)(1714 ) | | #0:FWD_CALL_EV:(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_flow)(1715 ) | | | (#0)SIPStackSession <- (#0)ENDPOINT: FWD_CALL_EV (549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( sip_stack)(1716 ) New SIPMessage created - #10 1d:11h:22m:28s ( lgr_flow)(1717 ) | |(SIPTU#6)FORWARD_REQ State:Invited(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( sip_stack)(1718 ) SIPCall(#6) changes state from Invited to Disconnected 1d:11h:22m:28s ( lgr_flow)(1719 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:28s SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac549318031 From: <sip:112@192.168.1.222>;tag=1c549303254 To: <sip:100@192.168.1.221;user=phone>;tag=1c329279999 Call-ID: 549302677182019112228@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222;user=phone> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Diversion: <tel:112>;reason=unconditional;counter=1 Server: MP-114 FXO/v.6.60A.350.001 Reason: SIP ;cause=302 ;text="302 Moved Temporarily" Content-Length: 0 1d:11h:22m:28s ( sip_stack)(1721 ) UdpRtxMngr::Transmit 302 Response 1 INVITE Rtx Left: 6 Dest: 192.168.1.222:5060 CallID: (549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_stk_ses)(1722 ) <SESSION #0> SendToCall - event: RELEASE_EV m_Call#0 1d:11h:22m:28s ( lgr_flow)(1723 ) | | #0:RELEASE_EV:(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_flow)(1724 ) | | #0:Call changing states from:InitiatedState_IP2Tel to:DisconnectingState 1d:11h:22m:28s |CALL_END |2 |312822482 |-1 |2 |0 |1 |FXO |RMT |192.168.1.222 |192.168.1.221 |0 |0 |112 |112 |0 |0 |100 |100 |0 |N/A |255 | |0 |RMT |RELEASE_BECAUSE_FORWARD |0 |-1 |-1 |0 |0 |549302677182019112228@192.168.1.222 |11:22:28.675 UTC Thu Aug 01 2019 | |11:22:28.700 UTC Thu Aug 01 2019 |-1 |-1 |-1 |-1 |-1 |0 |0 | |0 |192.168.1.222 |192.168.1.222 |192.168.1.221 |192.168.1.221 |0 () |192.168.1.221 |6000 | | |FORWARDED | |0 (DefaultSRD) |0 |0 |0 () |0 (DefaultRealm) |UDP |46 |40 |127 |127 |127 |127 |5060 |5060 |AUDIO | | |302 | |0 | |0 1d:11h:22m:28s ( lgr_flow)(1726 ) | | #0:RELEASE_ACK_EV:(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_flow)(1727 ) EndPoint::MediaResourceList::FreeMediaIpPorts Perform dellocation of Media ports for RealmIndex(0) port(6000) current allocations are:(0) Owner:(0) 1d:11h:22m:28s ( lgr_flow)(1728 ) | #2:RELEASE_EV RELEASE_BECAUSE_FORWARD : (549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_flow)(1729 ) | | #0:RELEASE_ACK_EV:(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( lgr_flow)(1730 ) | | | (#0)SIPStackSession <- (#0)ENDPOINT: RELEASE_ACK_EV (549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( sip_stack)(1731 ) New SIPMessage created - #6 1d:11h:22m:28s ( sip_stack)(1732 ) AcSIPStackAPI::FreeCallAPI - #0 1d:11h:22m:28s ( sip_stack)(1733 ) Setting ApplicationCall of AcSIPCall#6 to NULL 1d:11h:22m:28s ( sip_stack)(1734 ) SDPBodyMedia::Delete - id = 13 1d:11h:22m:28s ( lgr_stk_mngr)(1735 ) Resource StackSession <#0> Deleted 1d:11h:22m:28s ( sip_stack)(1736 ) Resource SIPMessage deleted - #6 1d:11h:22m:28s ( lgr_call)(1737 ) (#0) CALL Deallocated. 1d:11h:22m:28s ( lgr_call)(1738 ) delete GW call current active is: 1 1d:11h:22m:28s ( lgr_psbrdif)(1739 ) #2:cpDigitMapHndlr_Stop - Stoped (0) 1d:11h:22m:28s ( lgr_psbrdif)(1740 ) #2:CloseChannel: ChannelNum=2 1d:11h:22m:28s ( lgr_psbrdif)(1741 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 1d:11h:22m:28s ( lgr_psbrdif)(1742 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g723130 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED 1d:11h:22m:28s ( lgr_psbrdif)(1743 ) #2:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 1d:11h:22m:28s ( lgr_psbrdif)(1744 ) RFC2833RTPPayloadType: Rx=101 Tx=101 1d:11h:22m:28s ( lgr_psbrdif)(1745 ) OpenChannel, CoderType = 16, Interval = 0, M = 1 1d:11h:22m:28s ( lgr_psbrdif)(1746 ) ConfigureVbdAndT38:FAXTransportType:0 T38Version:0 FaxBypassPT:102 1d:11h:22m:28s ( lgr_psbrdif)(1747 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=0, VxxTranType=0, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1,ECEType=0 SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) 1d:11h:22m:28s ( lgr_psbrdif)(1748 ) #2:FAXTransportType = 0 1d:11h:22m:28s ( lgr_psbrdif)(1749 ) Detectors: Amd:On=0,Direction=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 1d:11h:22m:28s ( lgr_psbrdif)(1750 ) #2:Channel will be open WITH DSP 1d:11h:22m:28s SWWD : Run Task GWAP Ticks 5 (5), mode 1, priority 50 [Code:0x20019] [CID:0] 1d:11h:22m:28s IP 33190 , LR 3315c [Code:0x20019] [CID:0] 1d:11h:22m:28s Trace 0-4: 798764 c59660 7c7dd8 7c7d64 7c7be4 1d:11h:22m:28s Trace 5-9: 788430 78a4e4 820d8c 8147cc 81f96c 1d:11h:22m:28s Trace 10-14: 81cd9c 8132ec c65668 c8e124 c8d09c 1d:11h:22m:28s Trace 15-19: 89c2bc 81c8d4 868474 85e2d0 85de34 1d:11h:22m:28s SWWD : Wait Task GWAP, status 0, mode 1, priority 50, event 0 [Code:0x20019] [CID:0] 1d:11h:22m:28s TBack = [Code:0x20019] [CID:0] 1d:11h:22m:28s ( sip_stack)(1751 ) Resource SIPMessage deleted - #10 1d:11h:22m:28s ( sip_stack)(1752 ) Resource SIPMessage deleted - #5 1d:11h:22m:28s ( sip_stack)(1753 ) SDPBodyMedia::Delete - id = 15 1d:11h:22m:28s ( sip_stack)(1754 ) SDPBody::Delete - id = 11 1d:11h:22m:28s ( lgr_flow)(1755 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:28s ACK sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac549318031 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c549303254 To: <sip:100@192.168.1.221;user=phone>;tag=1c329279999 Call-ID: 549302677182019112228@192.168.1.222 CSeq: 1 ACK Contact: <sip:112@192.168.1.222:5060> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Length: 0 1d:11h:22m:28s ( sip_stack)(1757 ) New SIPMessage created - #4 1d:11h:22m:28s ( sip_stack)(1758 ) UdpRtxMngr::Remove 302 Response 1 INVITE 1d:11h:22m:28s ( lgr_flow)(1759 ) | |(SIPTU#6)ACK State:Disconnected(549302677182019112228@192.168.1.222) 1d:11h:22m:28s ( sip_stack)(1760 ) Resource SIPMessage deleted - #4 1d:11h:22m:33s ( sip_stack)(1761 ) New SIPMessage created - #1 1d:11h:22m:33s ( sip_stack)(1762 ) Resource SIPMessage deleted - #1 1d:11h:22m:33s ( lgr_flow)(1763 ) | | TransactionUserMngr::ReturnTransactionUser - #6 1d:11h:22m:33s ( sip_stack)(1764 ) SIPCall(#6) changes state from Disconnected to Idle 1d:11h:22m:45s ( lgr_flow)(1765 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:45s INVITE sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac591251416 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c591241193 To: <sip:100@192.168.1.221;user=phone> Call-ID: 591240619182019112245@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222:5060> Supported: em,100rel,timer,replaces,path,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Type: application/sdp Content-Length: 254 v=0 o=AudiocodesGW 591208735 591208734 IN IP4 192.168.1.222 s=Phone-Call c=IN IP4 192.168.1.222 t=0 0 m=audio 6000 RTP/AVP 8 0 96 a=ptime:20 a=sendrecv a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 1d:11h:22m:45s ( sip_stack)(1767 ) New SIPMessage created - #0 1d:11h:22m:45s ( sip_stack)(1768 ) New AcSIPCallAPI created - #9 1d:11h:22m:45s ( sip_stack)(1769 ) New SIPStackSession created - #9 1d:11h:22m:45s ( sip_stack)(1770 ) New AcSIPCall created - #5 1d:11h:22m:45s ( lgr_flow)(1771 ) | |(SIPTU#5)INVITE State:Idle(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( sip_stack)(1772 ) SIPSessionTimer<TU#5>::HandleSTRequest - Session-Timer mode: SUPPORTED 1d:11h:22m:45s ( sip_stack)(1773 ) SIPCall(#5) changes state from Idle to Invited 1d:11h:22m:45s ( sip_stack)(1774 ) SDPBody::New - id = 10 1d:11h:22m:45s ( sip_stack)(1775 ) SDPBodyMedia::New - id = 12 1d:11h:22m:45s ( lgr_flow)(1776 ) | | | #9:SIP_SETUP_EV(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_call)(1777 ) (#9) CALL Allocated. 1d:11h:22m:45s ( lgr_stk_ses)(1778 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 1d:11h:22m:45s ( lgr_stk_ses)(1779 ) <SESSION #9> SendToCall - event: NEW_CALL_EV m_Call#9 1d:11h:22m:45s ( lgr_flow)(1780 ) | | #9:NEW_CALL_EV:(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_flow)(1781 ) | | #9:Call changing states from:IdleState to:NewCallState_IP2Tel 1d:11h:22m:45s ( lgr_flow)(1782 ) ServicesMngr::GetEndPoint PhoneNum = 100 1d:11h:22m:45s ( lgr_psbrdif)(1783 ) GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:100 DstPfx:* SrcNum:112 SrcPfx:* SrcIp:c0a801de SrcIpPfx:192.168.1.222 1d:11h:22m:45s ( lgr_psbrdif)(1784 ) QueryOnHookPortStatus (ChannelNum=0), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:45s ( lgr_psbrdif)(1785 ) QueryOnHookPortStatus (ChannelNum=1), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:45s ( lgr_psbrdif)(1786 ) QueryOnHookPortStatus (ChannelNum=2), status = 1 Polarity = 0 Validate = 0 1d:11h:22m:45s ( lgr_call)(1787 ) Call::SetCoderListForCall #9 Found 2 Common Coders For Call 1d:11h:22m:45s ( lgr_call)(1788 ) <Call #9> Coder g711Alaw64k20 : 20 1d:11h:22m:45s ( lgr_call)(1789 ) <Call #9> Coder g711Ulaw64k20 : 20 1d:11h:22m:45s ( lgr_profiling)(1790 ) <Call 9> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 IPDS=46 SigIPDF=40 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 EEarlyM=0 FaxTM=0 IsFaxU=0 PI2IP=-1 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 SCE=0 VxxTT=0 Dst2Rdrt=0 DTMFVol=20 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 MWIA=0 MWID=0 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DPInd=255 AGC=0 NLP=0 1d:11h:22m:45s ( lgr_call)(1791 ) | | #9GetNextUI:GlobalUI=466533584, mACAddrLsb=11400015 1d:11h:22m:45s ( lgr_call)(1792 ) | | #9GetNextUI:GlobalUI=466533585 1d:11h:22m:45s ( lgr_flow)(1793 ) | #2:NEW_CALL_EV : (591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_flow)(1794 ) EndPoint::MediaResourceList::AllocateMediaIpPortsByMediaRealmID Perform NEW allocation of Media ports for RealmIndex(0) port(6010) current allocations are:(1) Owner:(0) 1d:11h:22m:45s ( sip_stack)(1795 ) SDPBodyMedia::New - id = 11 1d:11h:22m:45s ( sip_stack)(1796 ) SIPSDPSession#9 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 1d:11h:22m:45s ( sip_stack)(1797 ) <BaseSIP SDPSESSION #9> UpdateChosenMediaByCN - CN as Remote -1 1d:11h:22m:45s ( sip_stack)(1798 ) SDPBodyMedia::Delete - id = 11 1d:11h:22m:45s ( sip_stack)(1799 ) SDPBodyMedia::New - id = 10 1d:11h:22m:45s ( lgr_flow)(1800 ) | |(SIPTU#5)TRYING_REQ State:Invited(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( sip_stack)(1801 ) New SIPMessage created - #2 1d:11h:22m:45s ( lgr_flow)(1802 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:45s SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac591251416 From: <sip:112@192.168.1.222>;tag=1c591241193 To: <sip:100@192.168.1.221;user=phone>;tag=1c371211832 Call-ID: 591240619182019112245@192.168.1.222 CSeq: 1 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: MP-114 FXO/v.6.60A.350.001 Content-Length: 0 1d:11h:22m:45s ( sip_stack)(1804 ) Resource SIPMessage deleted - #2 1d:11h:22m:45s ( lgr_stk_ses)(1805 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 1d:11h:22m:45s ( lgr_stk_ses)(1806 ) <SESSION #9> SendToCall - event: SETUP_EV m_Call#9 1d:11h:22m:45s ( lgr_flow)(1807 ) | | #9:SETUP (TO:100, FROM:112):(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_flow)(1808 ) | | #9:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel 1d:11h:22m:45s ( lgr_flow)(1809 ) | #2:SETUP_EV : (591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_flow)(1810 ) | #2:FWD_CALL_EV (send) : (591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_flow)(1811 ) | | #9:FWD_CALL_EV:(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_flow)(1812 ) | | | (#9)SIPStackSession <- (#0)ENDPOINT: FWD_CALL_EV (591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( sip_stack)(1813 ) New SIPMessage created - #3 1d:11h:22m:45s ( lgr_flow)(1814 ) | |(SIPTU#5)FORWARD_REQ State:Invited(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( sip_stack)(1815 ) SIPCall(#5) changes state from Invited to Disconnected 1d:11h:22m:45s ( lgr_flow)(1816 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:45s SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac591251416 From: <sip:112@192.168.1.222>;tag=1c591241193 To: <sip:100@192.168.1.221;user=phone>;tag=1c371211832 Call-ID: 591240619182019112245@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222;user=phone> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Diversion: <tel:112>;reason=unconditional;counter=1 Server: MP-114 FXO/v.6.60A.350.001 Reason: SIP ;cause=302 ;text="302 Moved Temporarily" Content-Length: 0 1d:11h:22m:45s ( sip_stack)(1818 ) UdpRtxMngr::Transmit 302 Response 1 INVITE Rtx Left: 6 Dest: 192.168.1.222:5060 CallID: (591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_stk_ses)(1819 ) <SESSION #9> SendToCall - event: RELEASE_EV m_Call#9 1d:11h:22m:45s ( lgr_flow)(1820 ) | | #9:RELEASE_EV:(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_flow)(1821 ) | | #9:Call changing states from:InitiatedState_IP2Tel to:DisconnectingState 1d:11h:22m:45s |GWReportType|Cid |SessionId |Trunk|BChan|ConId|TG |EPTyp |Orig |SourceIp |DestIp |TON |NPI |SrcPhoneNum |SrcNumBeforeMap |TON |NPI |DstPhoneNum |DstNumBeforeMap |Durat|Coder |Intrv|RtpIp |Port |TrmSd|TrmReason |Fax |InPackets |OutPackets|PackLoss |RemotePackLoss|SIPCallId |SetupTime |ConnectTime |ReleaseTime |RTPdelay |RTPjitter|RTPssrc |RemoteRTPssrc |RedirectReason |TON |NPI |RedirectPhonNum |MeteringPulses |SrcHost |SrcHostBeforeMap |DstHost |DstHostBeforeMap |IPG (description) |LocalRtpIp |LocalRtpPort |Amount |Mult |TrmReasonCategory|RedirectNumBeforeMap|SrdId (name) |SIPInterfaceId |ProxySetId |IpProfileId (name) |MediaRealmId (name) |SigTransportType|TxRTPIPDiffServ|TxSigIPDiffServ|LocalRFactor|RemoteRFactor|LocalMosCQ|RemoteMosCQ|SigSourcePort|SigDestPort|MediaType |AMD| % |SIPTrmReason|SIPTermDesc |PstnTermReason|LatchedRtpIp |LatchedRtpPort 1d:11h:22m:45s |CALL_END |2 |312822484 |-1 |2 |0 |1 |FXO |RMT |192.168.1.222 |192.168.1.221 |0 |0 |112 |112 |0 |0 |100 |100 |0 |N/A |255 | |0 |RMT |RELEASE_BECAUSE_FORWARD |0 |-1 |-1 |0 |0 |591240619182019112245@192.168.1.222 |11:22:45.450 UTC Thu Aug 01 2019 | |11:22:45.475 UTC Thu Aug 01 2019 |-1 |-1 |-1 |-1 |-1 |0 |0 | |0 |192.168.1.222 |192.168.1.222 |192.168.1.221 |192.168.1.221 |0 () |192.168.1.221 |6010 | | |FORWARDED | |0 (DefaultSRD) |0 |0 |0 () |0 (DefaultRealm) |UDP |46 |40 |127 |127 |127 |127 |5060 |5060 |AUDIO | | |302 | |0 | |0 1d:11h:22m:45s ( lgr_flow)(1824 ) | | #9:RELEASE_ACK_EV:(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_flow)(1825 ) EndPoint::MediaResourceList::FreeMediaIpPorts Perform dellocation of Media ports for RealmIndex(0) port(6010) current allocations are:(0) Owner:(0) 1d:11h:22m:45s ( lgr_flow)(1826 ) | #2:RELEASE_EV RELEASE_BECAUSE_FORWARD : (591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_flow)(1827 ) | | #9:RELEASE_ACK_EV:(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( lgr_flow)(1828 ) | | | (#9)SIPStackSession <- (#0)ENDPOINT: RELEASE_ACK_EV (591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( sip_stack)(1829 ) New SIPMessage created - #33 1d:11h:22m:45s ( sip_stack)(1830 ) AcSIPStackAPI::FreeCallAPI - #9 1d:11h:22m:45s ( sip_stack)(1831 ) Setting ApplicationCall of AcSIPCall#5 to NULL 1d:11h:22m:45s ( sip_stack)(1832 ) SDPBodyMedia::Delete - id = 10 1d:11h:22m:45s ( lgr_stk_mngr)(1833 ) Resource StackSession <#9> Deleted 1d:11h:22m:45s ( sip_stack)(1834 ) Resource SIPMessage deleted - #33 1d:11h:22m:45s ( lgr_call)(1835 ) (#9) CALL Deallocated. 1d:11h:22m:45s ( lgr_call)(1836 ) delete GW call current active is: 1 1d:11h:22m:45s ( lgr_psbrdif)(1837 ) #2:cpDigitMapHndlr_Stop - Stoped (0) 1d:11h:22m:45s ( lgr_psbrdif)(1838 ) #2:CloseChannel: ChannelNum=2 1d:11h:22m:45s ( lgr_psbrdif)(1839 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 1d:11h:22m:45s ( lgr_psbrdif)(1840 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g723130 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED 1d:11h:22m:45s ( lgr_psbrdif)(1841 ) #2:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 1d:11h:22m:45s ( lgr_psbrdif)(1842 ) RFC2833RTPPayloadType: Rx=101 Tx=101 1d:11h:22m:45s ( lgr_psbrdif)(1843 ) OpenChannel, CoderType = 16, Interval = 0, M = 1 1d:11h:22m:45s ( lgr_psbrdif)(1844 ) ConfigureVbdAndT38:FAXTransportType:0 T38Version:0 FaxBypassPT:102 1d:11h:22m:45s ( lgr_psbrdif)(1845 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=0, VxxTranType=0, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1,ECEType=0 SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) 1d:11h:22m:45s ( lgr_psbrdif)(1846 ) #2:FAXTransportType = 0 1d:11h:22m:45s ( lgr_psbrdif)(1847 ) Detectors: Amd:On=0,Direction=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 1d:11h:22m:45s ( lgr_psbrdif)(1848 ) #2:Channel will be open WITH DSP 1d:11h:22m:45s SWWD : Run Task GWAP Ticks 5 (5), mode 1, priority 50 [Code:0x20019] [CID:0] 1d:11h:22m:45s IP 7c78dc , LR 7c78b8 [Code:0x20019] [CID:0] 1d:11h:22m:45s Trace 0-4: 7a05ec 78a3f8 820d8c 8147cc 81f96c 1d:11h:22m:45s Trace 5-9: 81cd9c 8132ec c65668 c8e124 c8d09c 1d:11h:22m:45s Trace 10-14: 89c2bc 81c8d4 868474 85e2d0 85de34 1d:11h:22m:45s Trace 15-19: 868d9c 854b54 840128 b38968 b38968 1d:11h:22m:45s SWWD : Wait Task GWAP, status 0, mode 1, priority 50, event 0 [Code:0x20019] [CID:0] 1d:11h:22m:45s TBack = [Code:0x20019] [CID:0] 1d:11h:22m:45s ( sip_stack)(1849 ) Resource SIPMessage deleted - #3 1d:11h:22m:45s ( sip_stack)(1850 ) Resource SIPMessage deleted - #0 1d:11h:22m:45s ( sip_stack)(1851 ) SDPBodyMedia::Delete - id = 12 1d:11h:22m:45s ( sip_stack)(1852 ) SDPBody::Delete - id = 10 1d:11h:22m:45s ( lgr_flow)(1853 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:22m:45s ACK sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac591251416 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c591241193 To: <sip:100@192.168.1.221;user=phone>;tag=1c371211832 Call-ID: 591240619182019112245@192.168.1.222 CSeq: 1 ACK Contact: <sip:112@192.168.1.222:5060> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Length: 0 1d:11h:22m:45s ( sip_stack)(1855 ) New SIPMessage created - #32 1d:11h:22m:45s ( sip_stack)(1856 ) UdpRtxMngr::Remove 302 Response 1 INVITE 1d:11h:22m:45s ( lgr_flow)(1857 ) | |(SIPTU#5)ACK State:Disconnected(591240619182019112245@192.168.1.222) 1d:11h:22m:45s ( sip_stack)(1858 ) Resource SIPMessage deleted - #32 1d:11h:22m:50s ( sip_stack)(1859 ) New SIPMessage created - #31 1d:11h:22m:50s ( sip_stack)(1860 ) Resource SIPMessage deleted - #31 1d:11h:22m:50s ( lgr_flow)(1861 ) | | TransactionUserMngr::ReturnTransactionUser - #5 1d:11h:22m:50s ( sip_stack)(1862 ) SIPCall(#5) changes state from Disconnected to Idle 1d:11h:23m:26s ( lgr_flow)(1863 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:23m:26s INVITE sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac692944093 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c692929292 To: <sip:100@192.168.1.221;user=phone> Call-ID: 692928719182019112326@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222:5060> Supported: em,100rel,timer,replaces,path,resource-priority,sdp-anat Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Type: application/sdp Content-Length: 254 v=0 o=AudiocodesGW 692900096 692900094 IN IP4 192.168.1.222 s=Phone-Call c=IN IP4 192.168.1.222 t=0 0 m=audio 6010 RTP/AVP 8 0 96 a=ptime:20 a=sendrecv a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 1d:11h:23m:26s ( sip_stack)(1865 ) New SIPMessage created - #28 1d:11h:23m:26s ( sip_stack)(1866 ) New AcSIPCallAPI created - #8 1d:11h:23m:26s ( sip_stack)(1867 ) New SIPStackSession created - #8 1d:11h:23m:26s ( sip_stack)(1868 ) New AcSIPCall created - #4 1d:11h:23m:26s ( lgr_flow)(1869 ) | |(SIPTU#4)INVITE State:Idle(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( sip_stack)(1870 ) SIPSessionTimer<TU#4>::HandleSTRequest - Session-Timer mode: SUPPORTED 1d:11h:23m:26s ( sip_stack)(1871 ) SIPCall(#4) changes state from Idle to Invited 1d:11h:23m:26s ( sip_stack)(1872 ) SDPBody::New - id = 9 1d:11h:23m:26s ( sip_stack)(1873 ) SDPBodyMedia::New - id = 9 1d:11h:23m:26s ( lgr_flow)(1874 ) | | | #8:SIP_SETUP_EV(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_call)(1875 ) (#8) CALL Allocated. 1d:11h:23m:26s ( lgr_stk_ses)(1876 ) SIPStackSession::HandleStackSetupEV - NEWCALL: SrcPN=0 1d:11h:23m:26s ( lgr_stk_ses)(1877 ) <SESSION #8> SendToCall - event: NEW_CALL_EV m_Call#8 1d:11h:23m:26s ( lgr_flow)(1878 ) | | #8:NEW_CALL_EV:(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_flow)(1879 ) | | #8:Call changing states from:IdleState to:NewCallState_IP2Tel 1d:11h:23m:26s ( lgr_flow)(1880 ) ServicesMngr::GetEndPoint PhoneNum = 100 1d:11h:23m:26s ( lgr_psbrdif)(1881 ) GetTrunkGroupId- TrunkGroup:1 Trunk:-1 found DstNum:100 DstPfx:* SrcNum:112 SrcPfx:* SrcIp:c0a801de SrcIpPfx:192.168.1.222 1d:11h:23m:26s ( lgr_psbrdif)(1882 ) QueryOnHookPortStatus (ChannelNum=0), status = 1 Polarity = 0 Validate = 0 1d:11h:23m:26s ( lgr_psbrdif)(1883 ) QueryOnHookPortStatus (ChannelNum=1), status = 1 Polarity = 0 Validate = 0 1d:11h:23m:26s ( lgr_psbrdif)(1884 ) QueryOnHookPortStatus (ChannelNum=2), status = 1 Polarity = 0 Validate = 0 1d:11h:23m:26s ( lgr_call)(1885 ) Call::SetCoderListForCall #8 Found 2 Common Coders For Call 1d:11h:23m:26s ( lgr_call)(1886 ) <Call #8> Coder g711Alaw64k20 : 20 1d:11h:23m:26s ( lgr_call)(1887 ) <Call #8> Coder g711Ulaw64k20 : 20 1d:11h:23m:26s ( lgr_profiling)(1888 ) <Call 8> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 IPDS=46 SigIPDF=40 RTPRD=0 ECE=1 InG=32 VVol=32 DiscOnBrok=1 MIPVP=0 EEarlyM=0 FaxTM=0 IsFaxU=0 PI2IP=-1 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 SCE=0 VxxTT=0 Dst2Rdrt=0 DTMFVol=20 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 MWIA=0 MWID=0 ReorderTime=255 DIDWink=0 2StageDial=0 DiscOnBusyT=1 DPInd=255 AGC=0 NLP=0 1d:11h:23m:26s ( lgr_call)(1889 ) | | #8GetNextUI:GlobalUI=466533585, mACAddrLsb=11400015 1d:11h:23m:26s ( lgr_call)(1890 ) | | #8GetNextUI:GlobalUI=466533586 1d:11h:23m:26s ( lgr_flow)(1891 ) | #2:NEW_CALL_EV : (692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_flow)(1892 ) EndPoint::MediaResourceList::AllocateMediaIpPortsByMediaRealmID Perform NEW allocation of Media ports for RealmIndex(0) port(6020) current allocations are:(1) Owner:(0) 1d:11h:23m:26s ( sip_stack)(1893 ) SDPBodyMedia::New - id = 8 1d:11h:23m:26s ( sip_stack)(1894 ) SIPSDPSession#8 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERED 1d:11h:23m:26s ( sip_stack)(1895 ) <BaseSIP SDPSESSION #8> UpdateChosenMediaByCN - CN as Remote -1 1d:11h:23m:26s ( sip_stack)(1896 ) SDPBodyMedia::Delete - id = 8 1d:11h:23m:26s ( sip_stack)(1897 ) SDPBodyMedia::New - id = 7 1d:11h:23m:26s ( lgr_flow)(1898 ) | |(SIPTU#4)TRYING_REQ State:Invited(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( sip_stack)(1899 ) New SIPMessage created - #29 1d:11h:23m:26s ( lgr_flow)(1900 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:23m:26s SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac692944093 From: <sip:112@192.168.1.222>;tag=1c692929292 To: <sip:100@192.168.1.221;user=phone>;tag=1c472905992 Call-ID: 692928719182019112326@192.168.1.222 CSeq: 1 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: MP-114 FXO/v.6.60A.350.001 Content-Length: 0 1d:11h:23m:26s ( sip_stack)(1902 ) Resource SIPMessage deleted - #29 1d:11h:23m:26s ( lgr_stk_ses)(1903 ) SIPStackSession::HandleStackSetupEV - SETUP: SrcPN=0 1d:11h:23m:26s ( lgr_stk_ses)(1904 ) <SESSION #8> SendToCall - event: SETUP_EV m_Call#8 1d:11h:23m:26s ( lgr_flow)(1905 ) | | #8:SETUP (TO:100, FROM:112):(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_flow)(1906 ) | | #8:Call changing states from:NewCallState_IP2Tel to:InitiatedState_IP2Tel 1d:11h:23m:26s ( lgr_flow)(1907 ) | #2:SETUP_EV : (692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_flow)(1908 ) | #2:FWD_CALL_EV (send) : (692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_flow)(1909 ) | | #8:FWD_CALL_EV:(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_flow)(1910 ) | | | (#8)SIPStackSession <- (#0)ENDPOINT: FWD_CALL_EV (692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( sip_stack)(1911 ) New SIPMessage created - #34 1d:11h:23m:26s ( lgr_flow)(1912 ) | |(SIPTU#4)FORWARD_REQ State:Invited(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( sip_stack)(1913 ) SIPCall(#4) changes state from Invited to Disconnected 1d:11h:23m:26s ( lgr_flow)(1914 ) ---- Outgoing SIP Message to 192.168.1.222:5060 from SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:23m:26s SIP/2.0 302 Moved Temporarily Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac692944093 From: <sip:112@192.168.1.222>;tag=1c692929292 To: <sip:100@192.168.1.221;user=phone>;tag=1c472905992 Call-ID: 692928719182019112326@192.168.1.222 CSeq: 1 INVITE Contact: <sip:112@192.168.1.222;user=phone> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Diversion: <tel:112>;reason=unconditional;counter=1 Server: MP-114 FXO/v.6.60A.350.001 Reason: SIP ;cause=302 ;text="302 Moved Temporarily" Content-Length: 0 1d:11h:23m:26s ( sip_stack)(1916 ) UdpRtxMngr::Transmit 302 Response 1 INVITE Rtx Left: 6 Dest: 192.168.1.222:5060 CallID: (692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_stk_ses)(1917 ) <SESSION #8> SendToCall - event: RELEASE_EV m_Call#8 1d:11h:23m:26s ( lgr_flow)(1918 ) | | #8:RELEASE_EV:(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_flow)(1919 ) | | #8:Call changing states from:InitiatedState_IP2Tel to:DisconnectingState 1d:11h:23m:26s |CALL_END |2 |312822486 |-1 |2 |0 |1 |FXO |RMT |192.168.1.222 |192.168.1.221 |0 |0 |112 |112 |0 |0 |100 |100 |0 |N/A |255 | |0 |RMT |RELEASE_BECAUSE_FORWARD |0 |-1 |-1 |0 |0 |692928719182019112326@192.168.1.222 |11:23:26.125 UTC Thu Aug 01 2019 | |11:23:26.150 UTC Thu Aug 01 2019 |-1 |-1 |-1 |-1 |-1 |0 |0 | |0 |192.168.1.222 |192.168.1.222 |192.168.1.221 |192.168.1.221 |0 () |192.168.1.221 |6020 | | |FORWARDED | |0 (DefaultSRD) |0 |0 |0 () |0 (DefaultRealm) |UDP |46 |40 |127 |127 |127 |127 |5060 |5060 |AUDIO | | |302 | |0 | |0 1d:11h:23m:26s ( lgr_flow)(1921 ) | | #8:RELEASE_ACK_EV:(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_flow)(1922 ) EndPoint::MediaResourceList::FreeMediaIpPorts Perform dellocation of Media ports for RealmIndex(0) port(6020) current allocations are:(0) Owner:(0) 1d:11h:23m:26s ( lgr_flow)(1923 ) | #2:RELEASE_EV RELEASE_BECAUSE_FORWARD : (692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_flow)(1924 ) | | #8:RELEASE_ACK_EV:(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( lgr_flow)(1925 ) | | | (#8)SIPStackSession <- (#0)ENDPOINT: RELEASE_ACK_EV (692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( sip_stack)(1926 ) New SIPMessage created - #30 1d:11h:23m:26s ( sip_stack)(1927 ) AcSIPStackAPI::FreeCallAPI - #8 1d:11h:23m:26s ( sip_stack)(1928 ) Setting ApplicationCall of AcSIPCall#4 to NULL 1d:11h:23m:26s ( sip_stack)(1929 ) SDPBodyMedia::Delete - id = 7 1d:11h:23m:26s ( lgr_stk_mngr)(1930 ) Resource StackSession <#8> Deleted 1d:11h:23m:26s ( sip_stack)(1931 ) Resource SIPMessage deleted - #30 1d:11h:23m:26s ( lgr_call)(1932 ) (#8) CALL Deallocated. 1d:11h:23m:26s ( lgr_call)(1933 ) delete GW call current active is: 1 1d:11h:23m:26s ( lgr_psbrdif)(1934 ) #2:cpDigitMapHndlr_Stop - Stoped (0) 1d:11h:23m:26s ( lgr_psbrdif)(1935 ) #2:CloseChannel: ChannelNum=2 1d:11h:23m:26s ( lgr_psbrdif)(1936 ) Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 1d:11h:23m:26s ( lgr_psbrdif)(1937 ) #2:OpenChannel:on Trunk -1 BChannel:2 CID=2 with VoiceCoder: g723130 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED 1d:11h:23m:26s ( lgr_psbrdif)(1938 ) #2:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000 1d:11h:23m:26s ( lgr_psbrdif)(1939 ) RFC2833RTPPayloadType: Rx=101 Tx=101 1d:11h:23m:26s ( lgr_psbrdif)(1940 ) OpenChannel, CoderType = 16, Interval = 0, M = 1 1d:11h:23m:26s ( lgr_psbrdif)(1941 ) ConfigureVbdAndT38:FAXTransportType:0 T38Version:0 FaxBypassPT:102 1d:11h:23m:26s ( lgr_psbrdif)(1942 ) #2:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=0, VxxTranType=0, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1,ECEType=0 SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) 1d:11h:23m:26s SWWD : Run Task DSPD Ticks 5 (5), mode 1, priority 170 [Code:0x20019] [CID:0] 1d:11h:23m:26s IP dba44c , LR 4348b4 [Code:0x20019] [CID:0] 1d:11h:23m:26s Trace 0-4: 3c8348 e6238c 3c83d4 e29484 e27534 1d:11h:23m:26s Trace 5-9: e323f0 e35ef8 e5b9c8 434fe0 5f3b8 1d:11h:23m:26s Trace 10-14: 6032c e108a0 4f1048 1d:11h:23m:26s Trace 15-19: 15a81b8 e08 159f0c4 1d:11h:23m:26s SWWD : Wait Task GWAP, status 0, mode 0, priority 50, event 0 [Code:0x20019] [CID:0] 1d:11h:23m:26s TBack = e11784 ddf5fc 7a06c0 78a3f8 820d8c [Code:0x20019] [CID:0] 1d:11h:23m:26s ( lgr_psbrdif)(1943 ) #2:FAXTransportType = 0 1d:11h:23m:26s ( lgr_psbrdif)(1944 ) Detectors: Amd:On=0,Direction=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 1d:11h:23m:26s ( lgr_psbrdif)(1945 ) #2:Channel will be open WITH DSP 1d:11h:23m:26s ( sip_stack)(1946 ) Resource SIPMessage deleted - #34 1d:11h:23m:26s ( sip_stack)(1947 ) Resource SIPMessage deleted - #28 1d:11h:23m:26s ( sip_stack)(1948 ) SDPBodyMedia::Delete - id = 9 1d:11h:23m:26s ( sip_stack)(1949 ) SDPBody::Delete - id = 9 1d:11h:23m:26s ( lgr_flow)(1950 ) ---- Incoming SIP Message from 192.168.1.222:5060 to SIPInterface #0 UdpTransportObject[#0] ---- 1d:11h:23m:26s ACK sip:100@192.168.1.221;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.222:5060;branch=z9hG4bKac692944093 Max-Forwards: 70 From: <sip:112@192.168.1.222>;tag=1c692929292 To: <sip:100@192.168.1.221;user=phone>;tag=1c472905992 Call-ID: 692928719182019112326@192.168.1.222 CSeq: 1 ACK Contact: <sip:112@192.168.1.222:5060> Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXS/v.6.60A.241.010 Content-Length: 0 1d:11h:23m:26s ( sip_stack)(1952 ) New SIPMessage created - #27 1d:11h:23m:26s ( sip_stack)(1953 ) UdpRtxMngr::Remove 302 Response 1 INVITE 1d:11h:23m:26s ( lgr_flow)(1954 ) | |(SIPTU#4)ACK State:Disconnected(692928719182019112326@192.168.1.222) 1d:11h:23m:26s ( sip_stack)(1955 ) Resource SIPMessage deleted - #27 1d:11h:23m:31s ( sip_stack)(1956 ) New SIPMessage created - #26 1d:11h:23m:31s ( sip_stack)(1957 ) Resource SIPMessage deleted - #26 1d:11h:23m:31s ( lgr_flow)(1958 ) | | TransactionUserMngr::ReturnTransactionUser - #4 1d:11h:23m:31s ( sip_stack)(1959 ) SIPCall(#4) changes state from Disconnected to Idle Сейчас с настройкой шлюзов помогает ТП Нага, но помогает очень неторопливо. А я думаю, что дело в какой-то мелочи и я что-то недоделал. Может быть кто-то с таким уже сталкивался? Вставить ник Quote
MobileOneWiFi Posted August 1, 2019 Posted August 1, 2019 В 15.07.2019 в 19:09, alibek сказал: Это второй сезон. 1 час назад, alibek сказал: Сейчас с настройкой шлюзов помогает ТП Нага, но помогает очень неторопливо. Третий сезон начался?;) Как раз 1 августа.. Вставить ник Quote
alibek Posted August 1, 2019 Author Posted August 1, 2019 Нет, это уже финал. Почти все работает, кроме исходящей связи с FXS. Вставить ник Quote
Victor_AC Posted August 1, 2019 Posted August 1, 2019 1 hour ago, alibek said: Нет, это уже финал. Почти все работает, кроме исходящей связи с FXS. Я думаю проблема в One stage/two stage dialing. 1. Он получает INVITE и набирает номер, который есть в INVITE. 2. Он получает INVITE и открывает канал FXO, а далее надо на телефоне набрать номер в виде DTMF. Второй вариант используется по умолчанию. Так же, для FXO важно использовать русские тоны. Файлы с тонами прикладываю. Если не получится, то надо уже смотреть логи и конфиг. rus_tones.dat rus_tones_v2.dat Вставить ник Quote
alibek Posted August 1, 2019 Author Posted August 1, 2019 В соответствии с примером я настраивал на FXO One stage dialing. Да и с FXO дозвон как раз идет нормально, проблема при звонке с FXS, а там такой настройки нет. Тоны нужно загружать на оба устройства или только на FXS? Вставить ник Quote
Victor_AC Posted August 1, 2019 Posted August 1, 2019 8 hours ago, alibek said: В соответствии с примером я настраивал на FXO One stage dialing. Да и с FXO дозвон как раз идет нормально, проблема при звонке с FXS, а там такой настройки нет. Тоны нужно загружать на оба устройства или только на FXS? Тоны надо в первую очередь заливать на FXO, чтобы он корректно сигналы в линии понимал. Присылай в личку конфиг и лог, который есть. Лог лучше собрать утилитой: http://redirect.audiocodes.com/install/syslogViewer/syslogViewer-setup.exe Вставить ник Quote
alibek Posted August 2, 2019 Author Posted August 2, 2019 Отправил. А какие именно тоны нужно загрузить на FXO? call progress или prerecorded? Вставить ник Quote
YuryD Posted August 2, 2019 Posted August 2, 2019 13 минут назад, alibek сказал: Отправил. А какие именно тоны нужно загрузить на FXO? call progress или prerecorded? cp tones. Но лучше прописать все ответы станции под свою. Вставить ник Quote
alibek Posted August 2, 2019 Author Posted August 2, 2019 Сейчас в Call Progress Tone загружен файл usa_tones_13.dat. Можно ли его как-то сохранить, прежде чем заменять, на всякий случай? Вставить ник Quote
YuryD Posted August 2, 2019 Posted August 2, 2019 Там же всё просто, компиллятор cptones у меня на mp124fxo был. Запихиваешь ему текст с описаниями тонов станции(пишешь ответы станции ready,busy И fastbusy и анализируешь частоты и длительности), он генерит dat. Давно правда было... Вставить ник Quote
alibek Posted August 2, 2019 Author Posted August 2, 2019 Если компилятор это утилита DConvert, то я ее уже скачал. Но у меня нет исходного тона (в виде ini или dat). Можно ли его сохранить, прежде чем перезаписать? Вставить ник Quote
YuryD Posted August 2, 2019 Posted August 2, 2019 В документации описание было, поискал - ничего не нашел, только то, что писал давно в 2004 году. Борьба с тонами BUSY и иными для шлюзов FXO , умеющих это делать... Суть методики - запись сигналов ответа станции во всех режимах, затем ее анализ. Ответов станций в виде тональных сигналов множество, рассмотрим пока только сигнал "ЗанятоБ/Отбой". В советских стандартах он представляет собой посылку сигнала частотой 425+/-5 Гц и интервалом 350 Мс. В буржуйских миниАТС этот сигнал может быть совершенно иным и отбойА получить очень трудно без соотв. настроек cptones шлюза. Сделать это можно так: Берем компьютер со звуковой картой, микрофоном и установленной программой WaveLab Demo 4.0 Пишем с помощью стандартной программы "Звукозапись" с микрофона сигнал "Занято" вашей АТС прямо с трубки телефона, качества хватит. Далее скармливаем этот Wav прогамме Wavelab и по картинке изучаем длительности посылок/пауз, благо там есть временные метки. Затем выделяем сигнал посылки, загоняем его в режим непрерывного воспроизведения и используя функцию FFT Spectrum Analise видим спектральные составляющие тона , те что нам интересны лежат в диапазоне 300-600 Гц. Выделяем пики спектрограммы и видим частоты. Вот и все, осталось только уровни подобрать, но это уже вручную. Некоторые BUSY ГТС 425 Гц 350 Мс Panasonic 1232 (2 шт) 400 Гц 250 Мс Panasonic KX TA 616 двутональный 350/440 250 Мс SY, YDB Вдогон https://www.voipinfo.ru/forum/viewtopic.php?p=45978 Вставить ник Quote
alibek Posted August 2, 2019 Author Posted August 2, 2019 У меня FXO включен не в старую советскую АТС, а в офисную АТС ARIA SOHO, на которой все эти тоновые сигналы конфигурируются и выставлены именно в 425 Гц и прочие значения, соответствующие РФ. Обновил тоны, не помогло. Вставить ник Quote
YuryD Posted August 2, 2019 Posted August 2, 2019 Мне помогало и с гтс и с порнослониками... Вставить ник Quote
YuryD Posted August 2, 2019 Posted August 2, 2019 14 минут назад, alibek сказал: У меня FXO включен не в старую советскую АТС, а в офисную АТС ARIA SOHO, на которой все эти тоновые сигналы конфигурируются и выставлены именно в 425 Гц и прочие значения, соответствующие РФ. Обновил тоны, не помогло. Вот именно, аудиокодес про советские ответы с usa_cptone ничего не знает Вставить ник Quote
MobileOneWiFi Posted August 2, 2019 Posted August 2, 2019 А какой режим включен? Если two stage dialing то по идее при исходящем звонке в станцию, сами тоны станции должны быть непричем - просто устанавливается соединение, поднимается труба на FXO стороне и все, далее ждется dtmf донабор со стороны FXS шлюза. Ну может у них анализ есть дайлтона и он проверяется, но это не тоны отбоя, не бизи. А one stage dialing при таком режиме когда шлюзы без сервера - он точно работает? Вставить ник Quote
alibek Posted August 2, 2019 Author Posted August 2, 2019 Включен режим One Stage. В примере по ссылке утверждается, что ему сервер не нужен. Да и ТП Нага так же считает. Замена тонов, кстати, не помогла. Никаких изменений не заметил. А ТП Нага изучила логи и предположила неисправность АТС или несогласованность каких-то параметров: Цитата Проанализировав лог вижу, что два шлюза связываются, обмениваются SIP сообщениями, и в них нет сообщений об ошибках или прерваном сеансе связи. Наоборот, вижу, что все хорошо, шлюз FXO сообщает шлюзу FXS 200 OK, но после этого сообщает CANCEL 1d:12h:51m:32s CANCEL sip:110@192.168.1.222;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.221:5060;branch=z9hG4bKac784408073 Max-Forwards: 70 From: <sip:110@192.168.1.221>;tag=1c784398414 To: <sip:110@192.168.1.222;user=phone> Call-ID: 784397856182019125124@192.168.1.221 CSeq: 1 CANCEL Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE User-Agent: MP-114 FXO/v.6.60A.350.001 Reason: Q.850 ;cause=16 ;text="local" Content-Length: 0 Прошу проверить порт АТС, куда подключен шлюз FXO. Можно воткнуть в него аналоговый телефон и проверить исходящюю связь на номер 110 Возможно между шлюзом FXO и АТС не согласованы какие-то параметры. Но порты АТС точно рабочие. А если под несогласованностью предполагать неподходящие тоны, то это не помогло. Вставить ник Quote
alibek Posted August 2, 2019 Author Posted August 2, 2019 ТП видимо читает этот форум. Попробовали переключить на двухшаговый режим. То же самое: - при снятии трубки длинный гудок (готовность линии) - при начале набора цифр тишина - по завершению набора цифр тишина в течение 3-4 секунд, затем короткие гудки Вставить ник Quote
MobileOneWiFi Posted August 2, 2019 Posted August 2, 2019 30 минут назад, alibek сказал: - при снятии трубки длинный гудок (готовность линии) Ну это уже хорошо, если длинный гудок идет уже от станции со шлюза FXO - я правильно понимаю? Т.е. коннект уже есть от FXS до FXO и порт на FXO снял трубку.. 30 минут назад, alibek сказал: - при начале набора цифр тишина - по завершению набора цифр тишина в течение 3-4 секунд, затем короткие гудки дтмф режимами игрались на обоих шлюзах? наверное inband надо поставить, т.к. набирать по дтмф должна уже ваша станция... Вставить ник Quote
alibek Posted August 2, 2019 Author Posted August 2, 2019 26 минут назад, MobileOneWiFi сказал: Ну это уже хорошо, если длинный гудок идет уже от станции со шлюза FXO - я правильно понимаю? Это трубка, подключенная в FXS. Я не знаю, как именно он устроен, но предполагал, что гудок в линию дает сам FXS, а не АТС. Если же это гудок от АТС или FXO, то тогда это должно быть действительно хорошо. 27 минут назад, MobileOneWiFi сказал: дтмф режимами игрались на обоих шлюзах? Нет, делал только по инструкции. Но это навело меня на мысль. На АТС я могу поэкспериментировать с настройками внутренних линий. И сейчас там по умолчанию "CID SLT Type(FSK)" — а это помоему импульсный набор. Попробую поставить тоновый набор. Вставить ник Quote
MobileOneWiFi Posted August 2, 2019 Posted August 2, 2019 9 минут назад, alibek сказал: "CID SLT Type(FSK)" — а это помоему импульсный набор. Это вообще не то. Это определитель номера со станции по протоколу FSK. 9 минут назад, alibek сказал: Это трубка, подключенная в FXS. Я не знаю, как именно он устроен, но предполагал, что гудок в линию дает сам FXS, а не АТС. Подождите. Когда вы снимаете трубку, если у вас стоит типа хотлайна с FXS на FXo, то вам гудок должен идти уже с удаленной стороны. И это по идее видно по логу что коннект успешный 200, т.е. шлюзы связались и подружились. А вот дальше, имхо, виноват или дтмф или может быть импульсный набор. Но я сомневаюсь что в аудиокодесе есть импульсный набор. Может быть в вашей станции он стоит, а тоновый не понимает? Проверьте сами телефоном подключителсь к этой линии на АТС и в тоне наберите. Вставить ник Quote
alibek Posted August 2, 2019 Author Posted August 2, 2019 Нет, это уже я что-то напутал. Трубки с тоновым набором, и на АТС, когда они ранее подключались прямо в нее, тоже использовали тоновый набор. На АТС для порта есть режим "SLT (PULSE)", я его с FSK спутал. Вставить ник Quote
YuryD Posted August 2, 2019 Posted August 2, 2019 Как я понял задачу - вынести fxs аналоговый к порту атс, с выходом туда через fxo на станцию. Сип тут не при делах, типовой конфиг есть во всех мануалах. Сервер сигнализации вообще не нужен. Там пир-ту-пир везде нарисована в мануалах, неоднократно делал и на аудиокодесах, аддпаках, аташках и прочих блинках, и на элтексах. Единственные засады - нету маршрута или фаер по дороге. Ну и пиров надо правильно прописывать. Нормальное поведение fxs - Ответ станции, а затем или отбой, или тишина при несовпадении кодеков. Отбой - не достучался до пира, или сервер отлупил, по неоплате например :) А там именно проблема с отбоями была. Вставить ник Quote
YuryD Posted August 2, 2019 Posted August 2, 2019 2 часа назад, MobileOneWiFi сказал: Это вообще не то. Но я сомневаюсь что в аудиокодесе есть импульсный набор. Может быть в вашей станции он стоит, а тоновый не понимает? Проверьте сами телефоном подключителсь к этой линии на АТС и в тоне наберите. Это один из моих последних приколов, сделали на элтексах выносы, всё гудит, к ним вызовы приходят, а они никуда позвонить не могут :) На елефонах в выносе был включен пульс, который станция понимала, но не элтекс :) Нервов при сдаче было :) Пульса в моем аудиокодесе mp124fxo нету точно, как и в стране-производителе Израиль. Ходит древняя байка, что когда елефония в Израиле начала развиваться, там просто бесплатно заменили всем пульсовые елефоны на dtmf. Проверить ухом просто, dtmf у вас или пульс. Снимите трубу, наберите любой длинный номер. Если после набора услышите туту-пипи - тональный, если тррр и треск - пульсовый. И вдогон - дтмф тоны стандартны, и легко декодируются любым аудиоредактором в цифры. Вставить ник Quote
.png.5d2afa2996cc6a85d0f2c09b92dd0a28.png)
Recommended Posts
Join the conversation
You can post now and register later. If you have an account, sign in now to post with your account.