alibek Опубликовано 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 Сейчас с настройкой шлюзов помогает ТП Нага, но помогает очень неторопливо. А я думаю, что дело в какой-то мелочи и я что-то недоделал. Может быть кто-то с таким уже сталкивался? Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
MobileOneWiFi Опубликовано 1 августа, 2019 · Жалоба В 15.07.2019 в 19:09, alibek сказал: Это второй сезон. 1 час назад, alibek сказал: Сейчас с настройкой шлюзов помогает ТП Нага, но помогает очень неторопливо. Третий сезон начался?;) Как раз 1 августа.. Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
alibek Опубликовано 1 августа, 2019 · Жалоба Нет, это уже финал. Почти все работает, кроме исходящей связи с FXS. Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
Victor_AC Опубликовано 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 Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
alibek Опубликовано 1 августа, 2019 · Жалоба В соответствии с примером я настраивал на FXO One stage dialing. Да и с FXO дозвон как раз идет нормально, проблема при звонке с FXS, а там такой настройки нет. Тоны нужно загружать на оба устройства или только на FXS? Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
Victor_AC Опубликовано 1 августа, 2019 · Жалоба 8 hours ago, alibek said: В соответствии с примером я настраивал на FXO One stage dialing. Да и с FXO дозвон как раз идет нормально, проблема при звонке с FXS, а там такой настройки нет. Тоны нужно загружать на оба устройства или только на FXS? Тоны надо в первую очередь заливать на FXO, чтобы он корректно сигналы в линии понимал. Присылай в личку конфиг и лог, который есть. Лог лучше собрать утилитой: http://redirect.audiocodes.com/install/syslogViewer/syslogViewer-setup.exe Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
alibek Опубликовано 2 августа, 2019 · Жалоба Отправил. А какие именно тоны нужно загрузить на FXO? call progress или prerecorded? Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
YuryD Опубликовано 2 августа, 2019 · Жалоба 13 минут назад, alibek сказал: Отправил. А какие именно тоны нужно загрузить на FXO? call progress или prerecorded? cp tones. Но лучше прописать все ответы станции под свою. Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
alibek Опубликовано 2 августа, 2019 · Жалоба Сейчас в Call Progress Tone загружен файл usa_tones_13.dat. Можно ли его как-то сохранить, прежде чем заменять, на всякий случай? Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
YuryD Опубликовано 2 августа, 2019 · Жалоба Там же всё просто, компиллятор cptones у меня на mp124fxo был. Запихиваешь ему текст с описаниями тонов станции(пишешь ответы станции ready,busy И fastbusy и анализируешь частоты и длительности), он генерит dat. Давно правда было... Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
alibek Опубликовано 2 августа, 2019 · Жалоба Если компилятор это утилита DConvert, то я ее уже скачал. Но у меня нет исходного тона (в виде ini или dat). Можно ли его сохранить, прежде чем перезаписать? Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
YuryD Опубликовано 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 Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
alibek Опубликовано 2 августа, 2019 · Жалоба У меня FXO включен не в старую советскую АТС, а в офисную АТС ARIA SOHO, на которой все эти тоновые сигналы конфигурируются и выставлены именно в 425 Гц и прочие значения, соответствующие РФ. Обновил тоны, не помогло. Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
YuryD Опубликовано 2 августа, 2019 · Жалоба Мне помогало и с гтс и с порнослониками... Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
YuryD Опубликовано 2 августа, 2019 · Жалоба 14 минут назад, alibek сказал: У меня FXO включен не в старую советскую АТС, а в офисную АТС ARIA SOHO, на которой все эти тоновые сигналы конфигурируются и выставлены именно в 425 Гц и прочие значения, соответствующие РФ. Обновил тоны, не помогло. Вот именно, аудиокодес про советские ответы с usa_cptone ничего не знает Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
MobileOneWiFi Опубликовано 2 августа, 2019 · Жалоба А какой режим включен? Если two stage dialing то по идее при исходящем звонке в станцию, сами тоны станции должны быть непричем - просто устанавливается соединение, поднимается труба на FXO стороне и все, далее ждется dtmf донабор со стороны FXS шлюза. Ну может у них анализ есть дайлтона и он проверяется, но это не тоны отбоя, не бизи. А one stage dialing при таком режиме когда шлюзы без сервера - он точно работает? Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
alibek Опубликовано 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 и АТС не согласованы какие-то параметры. Но порты АТС точно рабочие. А если под несогласованностью предполагать неподходящие тоны, то это не помогло. Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
alibek Опубликовано 2 августа, 2019 · Жалоба ТП видимо читает этот форум. Попробовали переключить на двухшаговый режим. То же самое: - при снятии трубки длинный гудок (готовность линии) - при начале набора цифр тишина - по завершению набора цифр тишина в течение 3-4 секунд, затем короткие гудки Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
MobileOneWiFi Опубликовано 2 августа, 2019 · Жалоба 30 минут назад, alibek сказал: - при снятии трубки длинный гудок (готовность линии) Ну это уже хорошо, если длинный гудок идет уже от станции со шлюза FXO - я правильно понимаю? Т.е. коннект уже есть от FXS до FXO и порт на FXO снял трубку.. 30 минут назад, alibek сказал: - при начале набора цифр тишина - по завершению набора цифр тишина в течение 3-4 секунд, затем короткие гудки дтмф режимами игрались на обоих шлюзах? наверное inband надо поставить, т.к. набирать по дтмф должна уже ваша станция... Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
YuryD Опубликовано 2 августа, 2019 · Жалоба С кодеками не баловались ? Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
alibek Опубликовано 2 августа, 2019 · Жалоба 26 минут назад, MobileOneWiFi сказал: Ну это уже хорошо, если длинный гудок идет уже от станции со шлюза FXO - я правильно понимаю? Это трубка, подключенная в FXS. Я не знаю, как именно он устроен, но предполагал, что гудок в линию дает сам FXS, а не АТС. Если же это гудок от АТС или FXO, то тогда это должно быть действительно хорошо. 27 минут назад, MobileOneWiFi сказал: дтмф режимами игрались на обоих шлюзах? Нет, делал только по инструкции. Но это навело меня на мысль. На АТС я могу поэкспериментировать с настройками внутренних линий. И сейчас там по умолчанию "CID SLT Type(FSK)" — а это помоему импульсный набор. Попробую поставить тоновый набор. Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
MobileOneWiFi Опубликовано 2 августа, 2019 · Жалоба 9 минут назад, alibek сказал: "CID SLT Type(FSK)" — а это помоему импульсный набор. Это вообще не то. Это определитель номера со станции по протоколу FSK. 9 минут назад, alibek сказал: Это трубка, подключенная в FXS. Я не знаю, как именно он устроен, но предполагал, что гудок в линию дает сам FXS, а не АТС. Подождите. Когда вы снимаете трубку, если у вас стоит типа хотлайна с FXS на FXo, то вам гудок должен идти уже с удаленной стороны. И это по идее видно по логу что коннект успешный 200, т.е. шлюзы связались и подружились. А вот дальше, имхо, виноват или дтмф или может быть импульсный набор. Но я сомневаюсь что в аудиокодесе есть импульсный набор. Может быть в вашей станции он стоит, а тоновый не понимает? Проверьте сами телефоном подключителсь к этой линии на АТС и в тоне наберите. Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
alibek Опубликовано 2 августа, 2019 · Жалоба Нет, это уже я что-то напутал. Трубки с тоновым набором, и на АТС, когда они ранее подключались прямо в нее, тоже использовали тоновый набор. На АТС для порта есть режим "SLT (PULSE)", я его с FSK спутал. Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
YuryD Опубликовано 2 августа, 2019 · Жалоба Как я понял задачу - вынести fxs аналоговый к порту атс, с выходом туда через fxo на станцию. Сип тут не при делах, типовой конфиг есть во всех мануалах. Сервер сигнализации вообще не нужен. Там пир-ту-пир везде нарисована в мануалах, неоднократно делал и на аудиокодесах, аддпаках, аташках и прочих блинках, и на элтексах. Единственные засады - нету маршрута или фаер по дороге. Ну и пиров надо правильно прописывать. Нормальное поведение fxs - Ответ станции, а затем или отбой, или тишина при несовпадении кодеков. Отбой - не достучался до пира, или сервер отлупил, по неоплате например :) А там именно проблема с отбоями была. Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...
YuryD Опубликовано 2 августа, 2019 · Жалоба 2 часа назад, MobileOneWiFi сказал: Это вообще не то. Но я сомневаюсь что в аудиокодесе есть импульсный набор. Может быть в вашей станции он стоит, а тоновый не понимает? Проверьте сами телефоном подключителсь к этой линии на АТС и в тоне наберите. Это один из моих последних приколов, сделали на элтексах выносы, всё гудит, к ним вызовы приходят, а они никуда позвонить не могут :) На елефонах в выносе был включен пульс, который станция понимала, но не элтекс :) Нервов при сдаче было :) Пульса в моем аудиокодесе mp124fxo нету точно, как и в стране-производителе Израиль. Ходит древняя байка, что когда елефония в Израиле начала развиваться, там просто бесплатно заменили всем пульсовые елефоны на dtmf. Проверить ухом просто, dtmf у вас или пульс. Снимите трубу, наберите любой длинный номер. Если после набора услышите туту-пипи - тональный, если тррр и треск - пульсовый. И вдогон - дтмф тоны стандартны, и легко декодируются любым аудиоредактором в цифры. Вставить ник Цитата Ответить с цитированием Поделиться сообщением Ссылка на сообщение Поделиться на других сайтах More sharing options...