Skip to content

Instantly share code, notes, and snippets.

@mattvv

mattvv/rtp.log Secret

Last active December 11, 2015 11:08
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save mattvv/2eb4c680055510ac2f84 to your computer and use it in GitHub Desktop.
Save mattvv/2eb4c680055510ac2f84 to your computer and use it in GitHub Desktop.
Download ZIP
Sems Log
Raw
rtp.log
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: Got valid command from udp:127.0.0.1:54747: 13627_25 USII b9df66ae-22b6-dfbe-70cc-eefbaebefab7;z9hG4bKf347.823b601d155c6641997f620429c486e7.0 208.57.127.186 64850 893046186;1
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7] Creating new call
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7 - z9hG4bKf347.823b601d155c6641997f620429c486e7.0] Opened ports 31920/31921 for RTP
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7 - z9hG4bKf347.823b601d155c6641997f620429c486e7.0] Opened ports 31922/31923 for RTP
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7 - z9hG4bKf347.823b601d155c6641997f620429c486e7.0] Returning to SIP proxy: 13627_25 31920 108.166.121.76 4
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: Got valid command from udp:127.0.0.1:54747: 13627_26 USII b9df66ae-22b6-dfbe-70cc-eefbaebefab7;z9hG4bKf347.823b601d155c6641997f620429c486e7.0 208.57.127.186 60042 893046186;2
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7 - z9hG4bKf347.823b601d155c6641997f620429c486e7.0] Opened ports 31924/31925 for RTP
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7 - z9hG4bKf347.823b601d155c6641997f620429c486e7.0] Opened ports 31926/31927 for RTP
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7 - z9hG4bKf347.823b601d155c6641997f620429c486e7.0] Returning to SIP proxy: 13627_26 31924 108.166.121.76 4
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: Got valid command from udp:127.0.0.1:45543: 13632_17 LS b9df66ae-22b6-dfbe-70cc-eefbaebefab7;z9hG4bKf347.823b601d155c6641997f620429c486e7.0 208.57.127.186 62510 893046186;1 7E1B359E-50FDF2A60007EA61-DA6E9700;1
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7 - z9hG4bKf347.823b601d155c6641997f620429c486e7.0] Returning to SIP proxy: 13632_17 31922 108.166.121.76 4
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: Got valid command from udp:127.0.0.1:45543: 13632_18 LS b9df66ae-22b6-dfbe-70cc-eefbaebefab7;z9hG4bKf347.823b601d155c6641997f620429c486e7.0 208.57.127.186 56694 893046186;2 7E1B359E-50FDF2A60007EA61-DA6E9700;2
Jan 21 20:00:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7 - z9hG4bKf347.823b601d155c6641997f620429c486e7.0] Returning to SIP proxy: 13632_18 31926 108.166.121.76 4
Jan 21 20:01:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7] Closing call branch due to timeout
Jan 21 20:01:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7] Final packet stats:
Jan 21 20:01:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7] --- side A: RTP[31924] 0 p, 0 b, 0 e; RTCP[31925] 0 p, 0 b, 0 e; side B: RTP[31926] 0 p, 0 b, 0 e; RTCP[31927] 0 p, 0 b, 0 e
Jan 21 20:01:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7] --- side A: RTP[31920] 0 p, 0 b, 0 e; RTCP[31921] 0 p, 0 b, 0 e; side B: RTP[31922] 0 p, 0 b, 0 e; RTCP[31923] 0 p, 0 b, 0 e
Jan 21 20:01:06 sipwise01-prod mediaproxy-ng[898]: Forking child to close call with tag 7E1B359E-50FDF2A60007EA61-DA6E9700 via XMLRPC
Jan 21 20:01:06 sipwise01-prod mediaproxy-ng/child[14228]: Initiating XMLRPC call for tag 7E1B359E-50FDF2A60007EA61-DA6E9700
Jan 21 20:01:06 sipwise01-prod mediaproxy-ng[898]: Got valid command from udp:127.0.0.1:35192: 13633_14 D b9df66ae-22b6-dfbe-70cc-eefbaebefab7 7E1B359E-50FDF2A60007EA61-DA6E9700 893046186
Jan 21 20:01:06 sipwise01-prod mediaproxy-ng[898]: [b9df66ae-22b6-dfbe-70cc-eefbaebefab7] Call-ID to delete not found
Jan 21 20:01:06 sipwise01-prod mediaproxy-ng[898]: Forking child to close call with tag 7E1B359E-50FDF2A60007EA61-DA6E9700 via XMLRPC
Jan 21 20:01:06 sipwise01-prod mediaproxy-ng/child[14229]: Initiating XMLRPC call for tag 7E1B359E-50FDF2A60007EA61-DA6E9700
Raw
sems.log
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg via UDP vv#012--++--#012INVITE sip:10.jamesh@192.168.2.11:54852;transport=udp SIP/2.0#015#012Record-Route: <sip:127.0.0.1:5062;lr=on;ftag=893046186;did=d07.90b2;mpd=ii;rtpprx=yes;vsf=YWhGcUo3Q3BkVGtnRnZ3SkVXOWlhaEZxSjdDcGQ->#015#012Record-Route: <sip:108.166.121.76:80;lr=on;ftag=893046186;nat=yes;ngcplb=yes>#015#012Via: SIP/2.0/UDP 127.0.0.1:5062;branch=z9hG4bKf347.cf9b31dab52f90c00fdd9ca0ff47ddea.0#015#012Route: <sip:lb@108.166.121.76:80;lr;received='sip:208.57.127.186:2664'>#015#012Via: SIP/2.0/UDP 108.166.121.76:80;branch=z9hG4bKf347.823b601d155c6641997f620429c486e7.0#015#012Via: SIP/2.0/UDP 192.168.2.9:60658;received=208.57.127.186;branch=z9hG4bK1286053818;rport=60570#015#012From: <sip:10.jameshe@108.166.121.76>;tag=893046186#015#012To: <sip:10.jamesh@108.166.121.76>#015#012Contact: <sip:10.jameshe@192.168.2.9:60658;alias=208.57.127.186~60570~1;transport=udp>;+g.oma.sip-im;language="en,fr";+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7#015#012CSeq: 1998907299 INVITE#015#012Content-Type: application/sdp#015#012Content-Length: 1593#015#012Max-Forwards: 68#015#012Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"#015#012P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel#015#012Allow: INVITE, ACK, CANCEL, BYE, MESSAGE, OPTIONS, NOTIFY, PRACK, UPDATE, REFER#015#012P-Access-Network-Info: ADSL;utran-cell-id-3gpp=00000000#015#012User-Agent: IM-client/cm-sip/v01#015#012P-Asserted-Identity: <sip:10.jameshe@108.166.121.76>#015#012P-Called-Party-ID: <sip:10.jamesh@108.166.121.76>#015#012P-App-Param: ;aleg_sst_enable=no;sst_enable=no#015#012P-App-Name: sbc#015#012P-D-Uri: sip:lb@108.166.121.76:80;lr;received='sip:208.57.127.186:2664'#012P-Caller-UUID: ed52ed36-7640-4d4f-9a76-b350ea5fe544#015#012P-Callee-UUID: f02e5c01-9142-4d11-b0e8-5ea9bb8b5fc0#015#012#015#012v=0#015#012o=ShowKit 1983 678901 IN IP4 108.166.121.76#015#012s=-#015#012c=IN IP4 108.166.121.76#015#012t=0 0#015#012a=tcap:1 RTP/AVPF#015#012m=audio 31920 RTP/AVP 0 8 101#015#012c=IN IP4 108.166.121.76#015#012a=ptime:20#015#012a=silenceSupp:off - - - -#015#012a=rtpmap:0 PCMU/8000/1#015#012a=rtpmap:8 PCMA/8000/1#015#012a=rtpmap:101 telephone-event/8000/1#015#012a=fmtp:101 0-16#015#012a=pcfg:1
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (54852) to int (54852)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKf347.cf9b31dab52f90c00fdd9ca0ff47ddea.0
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 0
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [match_request, trans_table.cpp:68] DEBUG: Matching INVITE request
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (60658) to int (60658)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:592] DEBUG: Received new request
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:595] DEBUG: req.method = <INVITE>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:598] DEBUG: req.r_uri = <sip:10.jamesh@192.168.2.11:54852;transport=udp>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:599] DEBUG: req.from_uri = <sip:10.jameshe@192.168.2.9:60658;alias=208.57.127.186~60570~1;transport=udp>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:600] DEBUG: req.from = <<sip:10.jameshe@108.166.121.76>>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:601] DEBUG: req.to = <<sip:10.jamesh@108.166.121.76>>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:602] DEBUG: req.callid = <b9df66ae-22b6-dfbe-70cc-eefbaebefab7>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:603] DEBUG: req.from_tag = <893046186>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:604] DEBUG: req.to_tag = <>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:605] DEBUG: cseq = <1998907299>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:606] DEBUG: req.route = <<sip:127.0.0.1:5062;lr=on;ftag=893046186;did=d07.90b2;mpd=ii;rtpprx=yes;vsf=YWhGcUo3Q3BkVGtnRnZ3SkVXOWlhaEZxSjdDcGQ->, <sip:108.166.121.76:80;lr=on;ftag=893046186;nat=yes;ngcplb=yes>>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:607] DEBUG: hdrs = <Max-Forwards: 68#015#012Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"#015#012P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel#015#012Allow: INVITE, ACK, CANCEL, BYE, MESSAGE, OPTIONS, NOTIFY, PRACK, UPDATE, REFER#015#012P-Access-Network-Info: ADSL;utran-cell-id-3gpp=00000000#015#012User-Agent: IM-client/cm-sip/v01#015#012P-Asserted-Identity: <sip:10.jameshe@108.166.121.76>#015#012P-Called-Party-ID: <sip:10.jamesh@108.166.121.76>#015#012P-App-Param: ;aleg_sst_enable=no;sst_enable=no#015#012P-App-Name: sbc#015#012P-D-Uri: sip:lb@108.166.121.76:80;lr;received='sip:208.57.127.186:2664'#015#012P-Caller-UUID: ed52ed36-7640-4d4f-9a76-b350ea5fe544#015#012P-Callee-UUID: f02e5c01-9142-4d11-b0e8-5ea9bb8b5fc0#015#012>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:608] DEBUG: body = <v=0#015#012o=ShowKit 1983 678901 IN IP4 108.166.121.76#015#012s=-#015#012c=IN IP4 108.166.121.76#015#012t=0 0#015#012a=tcap:1 RTP/AVPF#015#012m=audio 31920 RTP/AVP 0 8 101#015#012c=IN IP4 108.166.121.76#015#012a=ptime:20#015#012a=silenceSupp:off - - - -#015#012a=rtpmap:0 PCMU/8000/1#015#012a=rtpmap:8 PCMA/8000/1#015#012a=rtpmap:101 telephone-event/8000/1#015#012a=fmtp:101 0-16#015#012a=pcfg:1 t=1#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=ssrc:1527048825 cname:ldjWoB60jbyQlR6e#015#012a=ssrc:1527048825 mslabel:6994f7d1-6ce9-4fbd-acfd-84e5131ca2e2#015#012a=ssrc:1527048825 label:ShowKit#015#012a=ice-ufrag:UU4Bs1gRYzHXyB4#015#012a=ice-pwd:TvC8svmleOF8Tu5nt7Msu#015#012a=candidate:MPzeW1hRp 1 udp 2130706431 192.168.2.9 64850 typ host#015#012a=candidate:MPzeW1hRp 2 udp 2130706430 192.168.2.9 64851 typ host#015#012a=candidate:srflxMPze 1 udp 1694498815 208.57.127.186 6259 typ srflx#015#012a=direction:active#015#012m=video 31924 RTP/AVP 104#015#012c=IN IP4 108.166.121.76#015#012a=rtpmap:104 H264/90000#015#012a=imageattr:104 recv [x=[128:16:320],y=[96:16:240]] send [x=[128:16:320],y=[96:16:240]]#015#012a=framerate:104 15#015#012a=fmtp:104 profile-level-id=42000c; packetization-mode=1; QVGA=2; MaxBR=343984 #015#012a=pcfg:1 t=1#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=ssrc:1028635698 cname:ldjWoB60jbyQlR6e#015#012a=ssrc:1028635698 mslabel:6994f7d1-6ce9-4fbd-acfd-84e5131ca2e2#015#012a=ssrc:1028635698 label:ShowKit#015#012a=ice-ufrag:hi9rWtXjYkIyeNR#015#012a=ice-pwd:k3kEiYNDrkk66fg8Np4bW#015#012a=candidate:0uvRMGgW4 1 udp 2130706431 192.168.2.9 60042 typ host#015#012a=candidate:0uvRMGgW4 2 udp 2130706430 192.168.2.9 60043 typ host#015#012a=candidate:srflx0uvR 1 udp 1694498815 208.57.127.186 36612 typ srflx#015#012a=direction:active#015#012a=oldmediaip:192.168.2.9#015#012a=oldmediaip:192.168.2.9#015#012a=oldmediaip:192.168.2.9#015#012a=nortpproxy:yes#015#012>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handleSipMsg, AmSipDispatcher.cpp:88] DEBUG: method: `INVITE' [6].
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [onInvite, SBC.cpp:190] DEBUG: using call profile 'ngcp' (from matching active_profile rule 'ngcp')
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [setInbandDetector, AmDtmfDetector.cpp:209] DEBUG: Setting internal DTMF detector
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [setLocalTag, AmSession.cpp:230] DEBUG: AmSession::setLocalTag() - session id set to 7E1B359E-50FDF2A60007EA61-DA6E9700
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:614] DEBUG: ^^ M [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|] Ru SIP request INVITE handled ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:112] DEBUG: starting up 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:119] DEBUG: starting up [|7E1B359E-50FDF2A60007EA61-DA6E9700]: [0x7f0ccc003cf0]
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [|7E1B359E-50FDF2A60007EA61-DA6E9700] Disconnected, running, 0 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [process, AmSession.cpp:790] DEBUG: AmSession processing event
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [updateStatus, AmSipDialog.cpp:90] DEBUG: AmSipDialog::updateStatus(req = INVITE)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipRequest, AmSession.cpp:840] DEBUG: onSipRequest: method = INVITE
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [updateRefreshMethod, AmSession.cpp:1140] DEBUG: remote allows UPDATE, using UPDATE for session refresh.
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onInvite, SBC.cpp:522] DEBUG: processing initial INVITE
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [updateStatusReply, AmSipDialog.cpp:274] DEBUG: reply: transaction found!
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send_reply, trans_layer.cpp:215] DEBUG: reply_len = 832
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send_reply, trans_layer.cpp:383] DEBUG: Sending to 127.0.0.1:5062 <SIP/2.0 100 Connecting#015#012Record-Route: <sip:127.0.0...>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send, transport.cpp:98] DEBUG: send msg#012--++--#012SIP/2.0 100 Connecting#015#012Record-Route: <sip:127.0.0.1:5062;lr=on;ftag=893046186;did=d07.90b2;mpd=ii;rtpprx=yes;vsf=YWhGcUo3Q3BkVGtnRnZ3SkVXOWlhaEZxSjdDcGQ->#015#012Record-Route: <sip:108.166.121.76:80;lr=on;ftag=893046186;nat=yes;ngcplb=yes>#015#012Via: SIP/2.0/UDP 127.0.0.1:5062;branch=z9hG4bKf347.cf9b31dab52f90c00fdd9ca0ff47ddea.0;received=127.0.0.1#015#012Via: SIP/2.0/UDP 108.166.121.76:80;branch=z9hG4bKf347.823b601d155c6641997f620429c486e7.0#015#012Via: SIP/2.0/UDP 192.168.2.9:60658;received=208.57.127.186;branch=z9hG4bK1286053818;rport=60570#015#012From: <sip:10.jameshe@108.166.121.76>;tag=893046186#015#012To: <sip:10.jamesh@108.166.121.76>;tag=7E1B359E-50FDF2A60007EA61-DA6E9700#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7#015#012CSeq: 1998907299 INVITE#015#012Server: Sipwise NGCP Application Server#015#012Contact: <sip:10.jamesh@127.0.0.1:5080>#015#012Content-Length: 0#015#012#015#012--++--
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [update_uas_reply, trans_layer.cpp:1522] DEBUG: update_uas_reply(t=0x7f0ccc002630)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [replaceParameters, ParamReplacer.cpp:341] DEBUG: RURI pattern replace: '$r' -> 'sip:10.jamesh@192.168.2.11:54852;transport=udp'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [replaceParameters, ParamReplacer.cpp:341] DEBUG: From pattern replace: '$f' -> '<sip:10.jameshe@108.166.121.76>'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [replaceParameters, ParamReplacer.cpp:341] DEBUG: To pattern replace: '$H(P-Called-Party-ID)' -> '<sip:10.jamesh@108.166.121.76>'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [replaceParameters, ParamReplacer.cpp:341] DEBUG: Call-ID pattern replace: '$ci_b2b-1' -> 'b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [replaceParameters, ParamReplacer.cpp:341] DEBUG: outbound_proxy pattern replace: '$H(P-D-Uri)' -> 'sip:lb@108.166.121.76:80;lr;received='sip:208.57.127.186:2664''
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onInvite, SBC.cpp:545] DEBUG: set outbound proxy to 'sip:lb@108.166.121.76:80;lr;received='sip:208.57.127.186:2664''
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'Max-Forwards'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'Accept-Contact'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Preferred-Service'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Access-Network-Info'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'User-Agent'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Called-Party-ID'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Caller-UUID'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Callee-UUID'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [replaceParameters, ParamReplacer.cpp:341] DEBUG: auth_user pattern replace: '$P(u)' -> ''
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [replaceParameters, ParamReplacer.cpp:341] DEBUG: auth_pwd pattern replace: '$P(p)' -> ''
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onInvite, SBC.cpp:723] DEBUG: SBC: connecting to 'sip:10.jamesh@192.168.2.11:54852;transport=udp'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onInvite, SBC.cpp:724] DEBUG: From: '<sip:10.jameshe@108.166.121.76>'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onInvite, SBC.cpp:725] DEBUG: To: '<sip:10.jamesh@108.166.121.76>'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [setInbandDetector, AmDtmfDetector.cpp:209] DEBUG: Setting internal DTMF detector
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [createCalleeSession, SBC.cpp:1043] DEBUG: uac auth enabled for callee session.
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [createCalleeSession, SBC.cpp:1101] DEBUG: Created B2BUA callee leg, From: <sip:10.jameshe@108.166.121.76>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [relayEvent, AmB2BSession.cpp:474] DEBUG: AmB2BSession::relayEvent: to other_id='30A30B3D-50FDF2A60007EE16-DACEF700'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:112] DEBUG: starting up 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:119] DEBUG: starting up [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700]: [0x7f0cd40016d0]
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Disconnected, running, 0 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSendRequest, SBC.cpp:1265] DEBUG: auth->onSendRequest cseq = 10
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSendRequest, UACAuth.cpp:244] DEBUG: adding 10 to list of sent requests.
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipRequest, AmSession.cpp:868] DEBUG: no audio input and output set. Session will not be attached to MediaProcessor.
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (80) to int (80)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [set_next_hop, trans_layer.cpp:672] DEBUG: next_hop:next_port is <108.166.121.76:80>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [set_destination_ip, trans_layer.cpp:683] DEBUG: checking whether '108.166.121.76' is IP address...
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [set_destination_ip, trans_layer.cpp:725] DEBUG: set destination to 108.166.121.76:80
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send_request, trans_layer.cpp:851] DEBUG: send_request to R-URI <sip:10.jamesh@192.168.2.11:54852;transport=udp>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (54852) to int (54852)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKweIXAaoJ
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send_request, trans_layer.cpp:920] DEBUG: Sending to 108.166.121.76:80 <INVITE sip:10.jamesh@192.168.2.11:54852;transport=...>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send, transport.cpp:98] DEBUG: send msg#012--++--#012INVITE sip:10.jamesh@192.168.2.11:54852;transport=udp SIP/2.0#015#012Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKweIXAaoJ;rport#015#012From: <sip:10.jameshe@108.166.121.76>;tag=30A30B3D-50FDF2A60007EE16-DACEF700#015#012To: <sip:10.jamesh@108.166.121.76>#015#012CSeq: 10 INVITE#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1#015#012Contact: <sip:127.0.0.1:5080>#015#012Route: <sip:lb@108.166.121.76:80;lr;received='sip:208.57.127.186:2664';lr>#015#012Allow: INVITE, ACK, CANCEL, BYE, MESSAGE, OPTIONS, NOTIFY, PRACK, UPDATE, REFER#015#012P-Asserted-Identity: <sip:10.jameshe@108.166.121.76>#015#012P-D-Uri: sip:lb@108.166.121.76:80;lr;received='sip:208.57.127.186:2664'#015#012Content-Type: application/sdp#015#012Content-Length: 1593#015#012#015#012v=0#015#012o=ShowKit 1983 678901 IN IP4 108.166.121.76#015#012s=-#015#012c=IN IP4 108.166.121.76#015#012t=0 0#015#012a=tcap:1 RTP/AVPF#015#012m=audio 31920 RTP/AVP 0 8 101#015#012c=IN IP4 108.166.121.76#015#012a=ptime:20#015#012a=silenceSupp:off - - - -#015#012a=rtpmap:0 PCMU/8000/1#015#012a=rtpmap:8 PCMA/8000/1#015#012a=rtpmap:101 telephone-event/8000/1#015#012a=fmtp:101 0-16#015#012a=pcfg:1 t=1#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=ssrc:1527048825 cname:ldjWoB60jbyQlR6e#015#012a=ssrc:1527048825 mslabel:6994f7d1-6ce9-4fbd-acfd-84e5131ca2e2#015#012a=ssrc:1527048825 label:ShowKit#015#012a=ice-ufrag:UU4Bs1gRYzHXyB4#015#012a=ice-pwd:TvC8svmleOF8Tu5nt7Msu#015#012a=candidate:MPzeW1hRp 1 udp 2130706431 192.168.2.9 64850 typ host#015#012a=candidate:MPzeW1hRp 2 udp 2130706430 192.168.2.9 64851 typ host#015#012a=candidate:srflxMPze 1 udp 1694498815 208.57.127.186 6259 typ srflx#015#012a=direction:active#015#012m=video 31924 RTP/AVP 104#015#012c=IN IP4 108.166.121.76#015#012a=rtpmap:104 H264/90000#015#012a=imageattr:104 recv [x=[128:16:320],y=[96:16:240]] send [x=[128:16:320],y=[96:16:240]]#015#012a=framerate:104 15#015#012a=fmtp:104 profile-level-id=42000c; packetization-mode=1; QVGA=2; MaxBR=343984 #015#012a=pcfg:1 t=1#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=ssrc:1028635698 cname:ldjWoB60jbyQlR6e#015#012a=ssrc:1028635698 mslabel:6994f7d1-6ce9-4fbd-acfd-84e5131ca2e2#015#012a=ssrc:1028635698 label:ShowKit#015#012a=ice-ufrag:hi9rWtXjYkIyeNR#015#012a=ice-pwd:k3kEiYNDrkk66fg8Np4bW#015#012a=candidate:0uvRMGgW4 1 udp 2130706431 192.168.2.9 60042 typ host#015#012a=candidate:
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'Max-Forwards'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'Accept-Contact'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Preferred-Service'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Access-Network-Info'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'User-Agent'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Called-Party-ID'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-App-Param'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-App-Name'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Caller-UUID'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Callee-UUID'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [relayEvent, AmB2BSession.cpp:474] DEBUG: AmB2BSession::relayEvent: to other_id='30A30B3D-50FDF2A60007EE16-DACEF700'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Pending, running, 0 UACTransPending ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send_request, trans_layer.cpp:932] DEBUG: update_uac_request tt->_t =(nil)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [update_uac_request, trans_layer.cpp:1466] DEBUG: update_uac_request(t=0x7f0ccc006770)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type A at time=22364463 (repeated=0)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type B at time=22394438 (repeated=0)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type M at time=22364838 (repeated=0)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [saveSessionDescription, AmB2BSession.cpp:538] DEBUG: saving session description (application/sdp, v=0#015#012o=ShowKit 1983 678901 IN IP4 108.166.121.76#015#012...)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Disconnected, running, 1 UACTransPending ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Disconnected, running, 1 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Disconnected, running, 1 UACTransPending ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg via UDP vv#012--++--#012SIP/2.0 100 Trying (sent from the Transaction Layer)#015#012From: <sip:10.jameshe@108.166.121.76>;tag=30A30B3D-50FDF2A60007EE16-DACEF700#015#012To: <sip:10.jamesh@108.166.121.76>#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1#015#012CSeq: 10 INVITE#015#012Content-Length: 0#015#012Via: SIP/2.0/UDP 127.0.0.1:5080;rport=5080;branch=z9hG4bKweIXAaoJ#015#012Record-Route: <sip:108.166.121.76:80;lr=on;ftag=30A30B3D-50FDF2A60007EE16-DACEF700;ngcplb=yes>#015#012P-NGCP-Src-Ip: 208.57.127.186#015#012P-NGCP-Src-Port: 2664#015#012P-NGCP-Src-Proto: udp#015#012P-NGCP-Src-Af: 4#015#012#015#012--++--
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKweIXAaoJ
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [received_msg, trans_layer.cpp:1235] DEBUG: Reply matched an existing transaction
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [update_uac_reply, trans_layer.cpp:1286] DEBUG: update_uac_reply(reply code = 100, trans=0x7f0ccc006770)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type A (this=0x7f0ccc0087e0)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type M (this=0x7f0ccc008860)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type B (this=0x7f0ccc008820)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type C at time=22373453 (repeated=0)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [prepare_routes_uac, SipCtrlInterface.cpp:721] DEBUG: route_field = [<sip:108.166.121.76:80;lr=on;ftag=30A30B3D-50FDF2A60007EE16-DACEF700;ngcplb=yes>]
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:627] DEBUG: Received reply: 100 Trying (sent from the Transaction Layer)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:628] DEBUG: reply.callid = <b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:629] DEBUG: reply.local_tag = <30A30B3D-50FDF2A60007EE16-DACEF700>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:630] DEBUG: reply.remote_tag = <>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:631] DEBUG: cseq = <10>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:637] DEBUG: ^^ M [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] ru SIP reply 100 Trying (sent from the Transaction Layer) handled ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Disconnected, running, 1 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [process, AmSession.cpp:790] DEBUG: AmSession processing event
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [updateStatus, AmSipDialog.cpp:331] DEBUG: updateStatus(rep = 100 Trying (sent from the Transaction Layer)): transaction found!
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, SBC.cpp:1233] DEBUG: onSipReply: 100 Trying (sent from the Transaction Layer) (fwd=1)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, SBC.cpp:1234] DEBUG: onSipReply: content-type =
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, AmB2BSession.cpp:411] DEBUG: onSipReply: INVITE -> 100 Trying (sent from the Transaction Layer) (fwd=false), c-t=
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, AmSession.cpp:908] DEBUG: Dialog status changed Disconnected -> Pending (stopped=false)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, AmSession.cpp:914] DEBUG: negotiate_onreply = false
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Ip'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Port'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Proto'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Af'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [relayEvent, AmB2BSession.cpp:474] DEBUG: AmB2BSession::relayEvent: to other_id='7E1B359E-50FDF2A60007EA61-DA6E9700'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Pending, running, 1 UACTransPending ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Pending, running, 0 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:889] DEBUG: 100 reply received from other leg
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onOtherReply, SBC.cpp:885] DEBUG: Callee is trying... code 100
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:148] DEBUG: B2BSipReply: 100 Trying (sent from the Transaction Layer) (fwd=false)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:149] DEBUG: B2BSipReply: content-type =
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Pending, running, 0 UACTransPending ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg via UDP vv#012--++--#012SIP/2.0 180 Ringing#015#012From: <sip:10.jameshe@108.166.121.76>;tag=30A30B3D-50FDF2A60007EE16-DACEF700#015#012To: <sip:10.jamesh@108.166.121.76>;tag=1951542397#015#012Contact: <sip:10.jamesh@192.168.2.11:54852;alias=208.57.127.186~2664~1;transport=udp>#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1#015#012CSeq: 10 INVITE#015#012Content-Length: 0#015#012Via: SIP/2.0/UDP 127.0.0.1:5080;rport=5080;branch=z9hG4bKweIXAaoJ#015#012Record-Route: <sip:108.166.121.76:80;lr=on;ftag=30A30B3D-50FDF2A60007EE16-DACEF700;ngcplb=yes>#015#012Allow: ACK, BYE, CANCEL, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE#015#012P-NGCP-Src-Ip: 208.57.127.186#015#012P-NGCP-Src-Port: 2664#015#012P-NGCP-Src-Proto: udp#015#012P-NGCP-Src-Af: 4#015#012#015#012--++--
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKweIXAaoJ
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [received_msg, trans_layer.cpp:1235] DEBUG: Reply matched an existing transaction
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [update_uac_reply, trans_layer.cpp:1286] DEBUG: update_uac_reply(reply code = 180, trans=0x7f0ccc006770)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type C at time=22373456 (repeated=0)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type C (this=0x23c1700)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [prepare_routes_uac, SipCtrlInterface.cpp:721] DEBUG: route_field = [<sip:108.166.121.76:80;lr=on;ftag=30A30B3D-50FDF2A60007EE16-DACEF700;ngcplb=yes>]
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:627] DEBUG: Received reply: 180 Ringing
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:628] DEBUG: reply.callid = <b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:629] DEBUG: reply.local_tag = <30A30B3D-50FDF2A60007EE16-DACEF700>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:630] DEBUG: reply.remote_tag = <1951542397>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:631] DEBUG: cseq = <10>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:637] DEBUG: ^^ M [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] ru SIP reply 180 Ringing handled ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Pending, running, 1 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [process, AmSession.cpp:790] DEBUG: AmSession processing event
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [updateStatus, AmSipDialog.cpp:331] DEBUG: updateStatus(rep = 180 Ringing): transaction found!
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, SBC.cpp:1233] DEBUG: onSipReply: 180 Ringing (fwd=1)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, SBC.cpp:1234] DEBUG: onSipReply: content-type =
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, AmB2BSession.cpp:411] DEBUG: onSipReply: INVITE -> 180 Ringing (fwd=true), c-t=
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [updateRefreshMethod, AmSession.cpp:1140] DEBUG: remote allows UPDATE, using UPDATE for session refresh.
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Ip'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Port'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Proto'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Af'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [relayEvent, AmB2BSession.cpp:474] DEBUG: AmB2BSession::relayEvent: to other_id='7E1B359E-50FDF2A60007EA61-DA6E9700'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Pending, running, 1 UACTransPending ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Pending, running, 0 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:889] DEBUG: 180 reply received from other leg
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onOtherReply, SBC.cpp:885] DEBUG: Callee is trying... code 180
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:148] DEBUG: B2BSipReply: 180 Ringing (fwd=true)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:149] DEBUG: B2BSipReply: content-type =
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [updateStatusReply, AmSipDialog.cpp:274] DEBUG: reply: transaction found!
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send, SipCtrlInterface.cpp:354] DEBUG: HT:14, ext:0, rseq:0.
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send, SipCtrlInterface.cpp:354] DEBUG: HT:9, ext:0, rseq:0.
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send_reply, trans_layer.cpp:215] DEBUG: reply_len = 869
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send_reply, trans_layer.cpp:383] DEBUG: Sending to 127.0.0.1:5062 <SIP/2.0 180 Ringing#015#012Record-Route: <sip:127.0.0.1:...>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send, transport.cpp:98] DEBUG: send msg#012--++--#012SIP/2.0 180 Ringing#015#012Record-Route: <sip:127.0.0.1:5062;lr=on;ftag=893046186;did=d07.90b2;mpd=ii;rtpprx=yes;vsf=YWhGcUo3Q3BkVGtnRnZ3SkVXOWlhaEZxSjdDcGQ->#015#012Record-Route: <sip:108.166.121.76:80;lr=on;ftag=893046186;nat=yes;ngcplb=yes>#015#012Via: SIP/2.0/UDP 127.0.0.1:5062;branch=z9hG4bKf347.cf9b31dab52f90c00fdd9ca0ff47ddea.0;received=127.0.0.1#015#012Via: SIP/2.0/UDP 108.166.121.76:80;branch=z9hG4bKf347.823b601d155c6641997f620429c486e7.0#015#012Via: SIP/2.0/UDP 192.168.2.9:60658;received=208.57.127.186;branch=z9hG4bK1286053818;rport=60570#015#012From: <sip:10.jameshe@108.166.121.76>;tag=893046186#015#012To: <sip:10.jamesh@108.166.121.76>;tag=7E1B359E-50FDF2A60007EA61-DA6E9700#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7#015#012CSeq: 1998907299 INVITE#015#012Allow: ACK, BYE, CANCEL, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE#015#012Contact: <sip:10.jamesh@127.0.0.1:5080>#015#012Content-Length: 0#015#012#015#012--++--
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [update_uas_reply, trans_layer.cpp:1522] DEBUG: update_uas_reply(t=0x7f0ccc002630)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Pending, running, 0 UACTransPending ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg via UDP vv#012--++--#012SIP/2.0 200 OK#015#012From: <sip:10.jameshe@108.166.121.76>;tag=30A30B3D-50FDF2A60007EE16-DACEF700#015#012To: <sip:10.jamesh@108.166.121.76>;tag=1951542397#015#012Contact: <sip:10.jamesh@192.168.2.11:54852;alias=208.57.127.186~2664~1;transport=udp>#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1#015#012CSeq: 10 INVITE#015#012Content-Type: application/sdp#015#012Content-Length: 1505#015#012Via: SIP/2.0/UDP 127.0.0.1:5080;rport=5080;branch=z9hG4bKweIXAaoJ#015#012Record-Route: <sip:108.166.121.76:80;lr=on;ftag=30A30B3D-50FDF2A60007EE16-DACEF700;ngcplb=yes>#015#012Allow: ACK, BYE, CANCEL, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE#015#012P-NGCP-Src-Ip: 208.57.127.186#015#012P-NGCP-Src-Port: 2664#015#012P-NGCP-Src-Proto: udp#015#012P-NGCP-Src-Af: 4#015#012#015#012v=0#015#012o=ShowKit 1983 678901 IN IP4 192.168.2.11#015#012s=-#015#012c=IN IP4 208.57.127.186#015#012t=0 0#015#012m=audio 62510 RTP/AVPF 0 8#015#012c=IN IP4 208.57.127.186#015#012a=ptime:20#015#012a=silenceSupp:off - - - -#015#012a=rtpmap:0 PCMU/8000/1#015#012a=rtpmap:8 PCMA/8000/1#015#012a=acfg:1 t=1#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=ssrc:1841127248 cname:ldjWoB60jbyQlR6e#015#012a=ssrc:1841127248 mslabel:6994f7d1-6ce9-4fbd-acfd-84e5131ca2e2#015#012a=ssrc:1841127248 label:ShowKit#015#012a=ice-ufrag:W3CMJbnxfKKwtzZ#015#012a=ice-pwd:hgtHHv3gyRFjJvguYwiDG#015#012a=candidate:1J2d0nUWz 1 udp 2130706431 192.168.2.11 62510 typ host#015#012a=candidate:eKXrIdFlD 1 udp 2130706175 10.169.149.37 60092 typ host#015#012a=candidate:srflx1J2d 1 udp 1694498815 208.57.127.186 14346 typ srflx#015#012a=direction:active#015#012m=video 56694 RTP/AVPF 104#015#012c=IN IP4 208.57.127.186#015#012a=rtpmap:104 H264/90000#015#012a=imageattr:104 recv [x=[128:16:320],y=[96:16:240]] send [x=[128:16:320],y=[96:16:240]]#015#012a=framerate:104 15#015#012a=fmtp:104 profile-level-id=42001e; packetization-mode=1; HVGA=2; MaxBR=418670 #015#012a=acfg:1 t=1#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=ssrc:610671527 cname:ldjWoB60jbyQlR6e#015#012a=ssrc:610671527 mslabel:6994f7d1-6ce9-4fbd-acfd-84e5131ca2e2#015#012a=ssrc:610671527 label:ShowKit#015#012a=ice-ufrag:Ru8lTvv4ECufpZd#015#012a=ice-pwd:XenxTtuoz0Kf0KwRz4ZLf#015#012a=candidate:zLbYnJOpI 1 udp 2130706431 192.168.2.11 56694 typ host#015#012a=candidate:rHS8cwJjG 1 udp 2130706175 10.169.14
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKweIXAaoJ
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [received_msg, trans_layer.cpp:1235] DEBUG: Reply matched an existing transaction
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [update_uac_reply, trans_layer.cpp:1286] DEBUG: update_uac_reply(reply code = 200, trans=0x7f0ccc006770)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [update_uac_reply, trans_layer.cpp:1361] DEBUG: Positive final reply to INVITE transaction (state=3)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type C (this=0x7f0cd4005120)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type L at time=22366056 (repeated=0)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [prepare_routes_uac, SipCtrlInterface.cpp:721] DEBUG: route_field = [<sip:108.166.121.76:80;lr=on;ftag=30A30B3D-50FDF2A60007EE16-DACEF700;ngcplb=yes>]
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:627] DEBUG: Received reply: 200 OK
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:628] DEBUG: reply.callid = <b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:629] DEBUG: reply.local_tag = <30A30B3D-50FDF2A60007EE16-DACEF700>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:630] DEBUG: reply.remote_tag = <1951542397>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:631] DEBUG: cseq = <10>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:637] DEBUG: ^^ M [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] ru SIP reply 200 OK handled ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Pending, running, 1 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [process, AmSession.cpp:790] DEBUG: AmSession processing event
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [updateStatus, AmSipDialog.cpp:331] DEBUG: updateStatus(rep = 200 OK): transaction found!
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onInvite2xx, AmB2BSession.cpp:463] DEBUG: no 200 ACK now: waiting for the 200 ACK from the other side...
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, SBC.cpp:1233] DEBUG: onSipReply: 200 OK (fwd=1)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, SBC.cpp:1234] DEBUG: onSipReply: content-type = application/sdp
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, AmB2BSession.cpp:411] DEBUG: onSipReply: INVITE -> 200 OK (fwd=true), c-t=application/sdp
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Ip'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Port'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Proto'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Af'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [relayEvent, AmB2BSession.cpp:474] DEBUG: AmB2BSession::relayEvent: to other_id='7E1B359E-50FDF2A60007EA61-DA6E9700'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, AmB2BSession.cpp:437] DEBUG: not removing relayed INVITE transaction yet...
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Connected, running, 1 UACTransPending ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Pending, running, 0 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:889] DEBUG: 200 reply received from other leg
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:148] DEBUG: B2BSipReply: 200 OK (fwd=true)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:149] DEBUG: B2BSipReply: content-type = application/sdp
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [updateStatusReply, AmSipDialog.cpp:274] DEBUG: reply: transaction found!
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [updateStatusReply, AmSipDialog.cpp:311] DEBUG: req.method = INVITE; t.method = INVITE
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send_reply, trans_layer.cpp:215] DEBUG: reply_len = 2403
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send_reply, trans_layer.cpp:383] DEBUG: Sending to 127.0.0.1:5062 <SIP/2.0 200 OK#015#012Record-Route: <sip:127.0.0.1:5062;...>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send, transport.cpp:98] DEBUG: send msg#012--++--#012SIP/2.0 200 OK#015#012Record-Route: <sip:127.0.0.1:5062;lr=on;ftag=893046186;did=d07.90b2;mpd=ii;rtpprx=yes;vsf=YWhGcUo3Q3BkVGtnRnZ3SkVXOWlhaEZxSjdDcGQ->#015#012Record-Route: <sip:108.166.121.76:80;lr=on;ftag=893046186;nat=yes;ngcplb=yes>#015#012Via: SIP/2.0/UDP 127.0.0.1:5062;branch=z9hG4bKf347.cf9b31dab52f90c00fdd9ca0ff47ddea.0;received=127.0.0.1#015#012Via: SIP/2.0/UDP 108.166.121.76:80;branch=z9hG4bKf347.823b601d155c6641997f620429c486e7.0#015#012Via: SIP/2.0/UDP 192.168.2.9:60658;received=208.57.127.186;branch=z9hG4bK1286053818;rport=60570#015#012From: <sip:10.jameshe@108.166.121.76>;tag=893046186#015#012To: <sip:10.jamesh@108.166.121.76>;tag=7E1B359E-50FDF2A60007EA61-DA6E9700#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7#015#012CSeq: 1998907299 INVITE#015#012Allow: ACK, BYE, CANCEL, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE#015#012Contact: <sip:10.jamesh@127.0.0.1:5080>#015#012Content-Type: application/sdp#015#012Content-Length: 1505#015#012#015#012v=0#015#012o=ShowKit 1983 678901 IN IP4 192.168.2.11#015#012s=-#015#012c=IN IP4 208.57.127.186#015#012t=0 0#015#012m=audio 62510 RTP/AVPF 0 8#015#012c=IN IP4 208.57.127.186#015#012a=ptime:20#015#012a=silenceSupp:off - - - -#015#012a=rtpmap:0 PCMU/8000/1#015#012a=rtpmap:8 PCMA/8000/1#015#012a=acfg:1 t=1#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=ssrc:1841127248 cname:ldjWoB60jbyQlR6e#015#012a=ssrc:1841127248 mslabel:6994f7d1-6ce9-4fbd-acfd-84e5131ca2e2#015#012a=ssrc:1841127248 label:ShowKit#015#012a=ice-ufrag:W3CMJbnxfKKwtzZ#015#012a=ice-pwd:hgtHHv3gyRFjJvguYwiDG#015#012a=candidate:1J2d0nUWz 1 udp 2130706431 192.168.2.11 62510 typ host#015#012a=candidate:eKXrIdFlD 1 udp 2130706175 10.169.149.37 60092 typ host#015#012a=candidate:srflx1J2d 1 udp 1694498815 208.57.127.186 14346 typ srflx#015#012a=direction:active#015#012m=video 56694 RTP/AVPF 104#015#012c=IN IP4 208.57.127.186#015#012a=rtpmap:104 H264/90000#015#012a=imageattr:104 recv [x=[128:16:320],y=[96:16:240]] send [x=[128:16:320],y=[96:16:240]]#015#012a=framerate:104 15#015#012a=fmtp:104 profile-level-id=42001e; packetization-mode=1; HVGA=2; MaxBR=418670 #015#012a=acfg:1 t=1#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=ssrc:610671527 cname:ldjWoB60jbyQlR6e#015#012a=ssrc:610671527 mslabel:6994f7d1-6ce9-4fbd-acfd-84e5131ca2e2#015#012a=ssrc:610671527 label:ShowKit
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [update_uas_reply, trans_layer.cpp:1522] DEBUG: update_uas_reply(t=0x7f0ccc002630)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type G at time=22364481 (repeated=0)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type H at time=22366056 (repeated=0)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Connected, running, 0 UACTransPending ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg via UDP vv#012--++--#012ACK sip:127.0.0.1:5080 SIP/2.0#015#012Record-Route: <sip:127.0.0.1:5062;lr=on;ftag=893046186;rtpprx=yes>#015#012Record-Route: <sip:108.166.121.76:80;lr=on;ftag=893046186;nat=yes;ngcplb=yes>#015#012Via: SIP/2.0/UDP 127.0.0.1:5062;branch=z9hG4bKf347.77722cbcf75d5727d1352167fa49220e.0#015#012Via: SIP/2.0/UDP 108.166.121.76:80;branch=z9hG4bKf347.1d737562c9ab2ac95daaa4f6aa1eeb44.0#015#012Via: SIP/2.0/UDP 192.168.2.9:60658;received=208.57.127.186;branch=z9hG4bK1075824527;rport=60570#015#012From: <sip:10.jameshe@108.166.121.76>;tag=893046186#015#012To: <sip:10.jamesh@108.166.121.76>;tag=7E1B359E-50FDF2A60007EA61-DA6E9700#015#012Contact: <sip:10.jameshe@192.168.2.9:60658;alias=208.57.127.186~60570~1;transport=udp>;+g.oma.sip-im;language="en,fr";+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7#015#012CSeq: 1998907299 ACK#015#012Content-Length: 0#015#012Max-Forwards: 68#015#012Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"#015#012P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel#015#012Allow: INVITE, ACK, CANCEL, BYE, MESSAGE, OPTIONS, NOTIFY, PRACK, UPDATE, REFER#015#012P-Access-Network-Info: ADSL;utran-cell-id-3gpp=00000000#015#012User-Agent: IM-client/cm-sip/v01#015#012#015#012--++--
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (5080) to int (5080)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKf347.77722cbcf75d5727d1352167fa49220e.0
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 0
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [match_request, trans_table.cpp:68] DEBUG: Matching ACK request
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [match_request, trans_table.cpp:87] DEBUG: do_3261_match = 1
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [received_msg, trans_layer.cpp:1143] DEBUG: ACK matched INVITE transaction 0x7f0ccc002630
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [update_uas_request, trans_layer.cpp:1590] DEBUG: update_uas_request(t=0x7f0ccc002630)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [received_msg, trans_layer.cpp:1146] DEBUG: update_uas_request(bucket,t=0x7f0ccc002630,msg) = 9
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [received_msg, trans_layer.cpp:1163] DEBUG: Passing ACK to the UA.
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (60658) to int (60658)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:592] DEBUG: Received new request
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:595] DEBUG: req.method = <ACK>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:598] DEBUG: req.r_uri = <sip:127.0.0.1:5080>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:599] DEBUG: req.from_uri = <sip:10.jameshe@192.168.2.9:60658;alias=208.57.127.186~60570~1;transport=udp>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:600] DEBUG: req.from = <<sip:10.jameshe@108.166.121.76>>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:601] DEBUG: req.to = <<sip:10.jamesh@108.166.121.76>;tag=7E1B359E-50FDF2A60007EA61-DA6E9700>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:602] DEBUG: req.callid = <b9df66ae-22b6-dfbe-70cc-eefbaebefab7>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:603] DEBUG: req.from_tag = <893046186>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:604] DEBUG: req.to_tag = <7E1B359E-50FDF2A60007EA61-DA6E9700>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:605] DEBUG: cseq = <1998907299>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:606] DEBUG: req.route = <<sip:127.0.0.1:5062;lr=on;ftag=893046186;rtpprx=yes>, <sip:108.166.121.76:80;lr=on;ftag=893046186;nat=yes;ngcplb=yes>>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:607] DEBUG: hdrs = <Max-Forwards: 68#015#012Accept-Contact: *;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"#015#012P-Preferred-Service: urn:urn-7:3gpp-service.ims.icsi.mmtel#015#012Allow: INVITE, ACK, CANCEL, BYE, MESSAGE, OPTIONS, NOTIFY, PRACK, UPDATE, REFER#015#012P-Access-Network-Info: ADSL;utran-cell-id-3gpp=00000000#015#012User-Agent: IM-client/cm-sip/v01#015#012>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:608] DEBUG: body = <>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cda6e9700] [handle_sip_request, SipCtrlInterface.cpp:614] DEBUG: ^^ M [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Ru SIP request ACK handled ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Connected, running, 0 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [process, AmSession.cpp:790] DEBUG: AmSession processing event
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [updateStatus, AmSipDialog.cpp:90] DEBUG: AmSipDialog::updateStatus(req = ACK)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'Max-Forwards'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'Accept-Contact'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Preferred-Service'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-Access-Network-Info'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'User-Agent'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [relayEvent, AmB2BSession.cpp:474] DEBUG: AmB2BSession::relayEvent: to other_id='30A30B3D-50FDF2A60007EE16-DACEF700'
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Connected, running, 0 UACTransPending ^^
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Connected, running, 1 UACTransPending vv
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [relaySip, AmB2BSession.cpp:697] DEBUG: sending relayed ACK
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSendRequest, SBC.cpp:1265] DEBUG: auth->onSendRequest cseq = 10
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSendRequest, UACAuth.cpp:244] DEBUG: adding 10 to list of sent requests.
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (80) to int (80)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [set_next_hop, trans_layer.cpp:672] DEBUG: next_hop:next_port is <108.166.121.76:80>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [set_destination_ip, trans_layer.cpp:683] DEBUG: checking whether '108.166.121.76' is IP address...
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [set_destination_ip, trans_layer.cpp:725] DEBUG: set destination to 108.166.121.76:80
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send_request, trans_layer.cpp:851] DEBUG: send_request to R-URI <sip:10.jamesh@192.168.2.11:54852;alias=208.57.127.186~2664~1;transport=udp>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (54852) to int (54852)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKMBRXvabM
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send_request, trans_layer.cpp:920] DEBUG: Sending to 108.166.121.76:80 <ACK sip:10.jamesh@192.168.2.11:54852;alias=208.57....>
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send, transport.cpp:98] DEBUG: send msg#012--++--#012ACK sip:10.jamesh@192.168.2.11:54852;alias=208.57.127.186~2664~1;transport=udp SIP/2.0#015#012Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKMBRXvabM;rport#015#012From: <sip:10.jameshe@108.166.121.76>;tag=30A30B3D-50FDF2A60007EE16-DACEF700#015#012To: <sip:10.jamesh@108.166.121.76>;tag=1951542397#015#012CSeq: 10 ACK#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1#015#012Contact: <sip:127.0.0.1:5080>#015#012Route: <sip:108.166.121.76:80;lr=on;ftag=30A30B3D-50FDF2A60007EE16-DACEF700;ngcplb=yes>#015#012Allow: INVITE, ACK, CANCEL, BYE, MESSAGE, OPTIONS, NOTIFY, PRACK, UPDATE, REFER#015#012Content-Length: 0#015#012#015#012--++--
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send_request, trans_layer.cpp:932] DEBUG: update_uac_request tt->_t =(nil)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [match_request, trans_table.cpp:68] DEBUG: Matching ACK request
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [match_request, trans_table.cpp:87] DEBUG: do_3261_match = 1
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [update_uac_request, trans_layer.cpp:1475] DEBUG: update_uac_request(200 ACK, t=0x7f0ccc006770)
Jan 21 20:00:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Connected, running, 0 UACTransPending ^^
Jan 21 20:00:38 sipwise01-prod sems[17785]: [#7f0cda8eb700] [trans_timer_cb, sip_trans.cpp:157] DEBUG: Transaction timer expired: type=L, trans=0x7f0ccc006770, eta=22366056, t=22366056
Jan 21 20:00:38 sipwise01-prod sems[17785]: [#7f0cda8eb700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type L (this=0x7f0ccc0093d0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdbbfe700] [execute, XMLRPC2DI.cpp:542] DEBUG: XMLRPC2DI: factory 'sbc' function 'postControlCmd'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdbbfe700] [execute, XMLRPC2DI.cpp:549] DEBUG: params: <['7E1B359E-50FDF2A60007EA61-DA6E9700', 'teardown']>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdbbfe700] [execute, XMLRPC2DI.cpp:565] DEBUG: result: <[202, 'Accepted']>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Connected, running, 0 UACTransPending vv
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onControlCmd, SBC.cpp:781] DEBUG: teardown requested from control cmd
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [relayEvent, AmB2BSession.cpp:474] DEBUG: AmB2BSession::relayEvent: to other_id='30A30B3D-50FDF2A60007EE16-DACEF700'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (5062) to int (5062)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [set_next_hop, trans_layer.cpp:672] DEBUG: next_hop:next_port is <127.0.0.1:5062>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [set_destination_ip, trans_layer.cpp:683] DEBUG: checking whether '127.0.0.1' is IP address...
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [set_destination_ip, trans_layer.cpp:725] DEBUG: set destination to 127.0.0.1:5062
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send_request, trans_layer.cpp:851] DEBUG: send_request to R-URI <sip:10.jameshe@192.168.2.9:60658;alias=208.57.127.186~60570~1;transport=udp>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (60658) to int (60658)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKtcZFraO2
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send_request, trans_layer.cpp:920] DEBUG: Sending to 127.0.0.1:5062 <BYE sip:10.jameshe@192.168.2.9:60658;alias=208.57....>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send, transport.cpp:98] DEBUG: send msg#012--++--#012BYE sip:10.jameshe@192.168.2.9:60658;alias=208.57.127.186~60570~1;transport=udp SIP/2.0#015#012Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKtcZFraO2;rport#015#012From: <sip:10.jamesh@108.166.121.76>;tag=7E1B359E-50FDF2A60007EA61-DA6E9700#015#012To: <sip:10.jameshe@108.166.121.76>;tag=893046186#015#012CSeq: 10 BYE#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7#015#012Route: <sip:127.0.0.1:5062;lr=on;ftag=893046186;did=d07.90b2;mpd=ii;rtpprx=yes;vsf=YWhGcUo3Q3BkVGtnRnZ3SkVXOWlhaEZxSjdDcGQ->, <sip:108.166.121.76:80;lr=on;ftag=893046186;nat=yes;ngcplb=yes>#015#012Content-Length: 0#015#012#015#012--++--
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [send_request, trans_layer.cpp:932] DEBUG: update_uac_request tt->_t =(nil)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [update_uac_request, trans_layer.cpp:1466] DEBUG: update_uac_request(t=0x7f0cd40055f0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type E at time=22367438 (repeated=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type F at time=22397413 (repeated=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type M at time=22367813 (repeated=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Disconnecting, stopped, 1 UACTransPending ^^
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Connected, running, 0 UACTransPending vv
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSendRequest, SBC.cpp:1265] DEBUG: auth->onSendRequest cseq = 11
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSendRequest, UACAuth.cpp:244] DEBUG: adding 11 to list of sent requests.
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (80) to int (80)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [set_next_hop, trans_layer.cpp:672] DEBUG: next_hop:next_port is <108.166.121.76:80>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [set_destination_ip, trans_layer.cpp:683] DEBUG: checking whether '108.166.121.76' is IP address...
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [set_destination_ip, trans_layer.cpp:725] DEBUG: set destination to 108.166.121.76:80
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send_request, trans_layer.cpp:851] DEBUG: send_request to R-URI <sip:10.jamesh@192.168.2.11:54852;alias=208.57.127.186~2664~1;transport=udp>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_sip_uri, parse_uri.cpp:332] DEBUG: Converted URI port (54852) to int (54852)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKTFH4xaoZ
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send_request, trans_layer.cpp:920] DEBUG: Sending to 108.166.121.76:80 <BYE sip:10.jamesh@192.168.2.11:54852;alias=208.57....>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send, transport.cpp:98] DEBUG: send msg#012--++--#012BYE sip:10.jamesh@192.168.2.11:54852;alias=208.57.127.186~2664~1;transport=udp SIP/2.0#015#012Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKTFH4xaoZ;rport#015#012From: <sip:10.jameshe@108.166.121.76>;tag=30A30B3D-50FDF2A60007EE16-DACEF700#015#012To: <sip:10.jamesh@108.166.121.76>;tag=1951542397#015#012CSeq: 11 BYE#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1#015#012Route: <sip:108.166.121.76:80;lr=on;ftag=30A30B3D-50FDF2A60007EE16-DACEF700;ngcplb=yes>#015#012Content-Length: 0#015#012#015#012--++--
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [send_request, trans_layer.cpp:932] DEBUG: update_uac_request tt->_t =(nil)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [update_uac_request, trans_layer.cpp:1466] DEBUG: update_uac_request(t=0x7f0ccc0098d0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type E at time=22367438 (repeated=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type F at time=22397413 (repeated=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type M at time=22367813 (repeated=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Disconnecting, stopped, 1 UACTransPending ^^
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg via UDP vv#012--++--#012SIP/2.0 100 Trying#015#012Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKtcZFraO2;rport=5080#015#012From: <sip:10.jamesh@108.166.121.76>;tag=7E1B359E-50FDF2A60007EA61-DA6E9700#015#012To: <sip:10.jameshe@108.166.121.76>;tag=893046186#015#012CSeq: 10 BYE#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7#015#012Server: Sipwise NGCP Proxy 2.X#015#012Content-Length: 0#015#012#015#012--++--
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKtcZFraO2
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [received_msg, trans_layer.cpp:1235] DEBUG: Reply matched an existing transaction
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [update_uac_reply, trans_layer.cpp:1286] DEBUG: update_uac_reply(reply code = 100, trans=0x7f0cd40055f0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:627] DEBUG: Received reply: 100 Trying
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:628] DEBUG: reply.callid = <b9df66ae-22b6-dfbe-70cc-eefbaebefab7>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:629] DEBUG: reply.local_tag = <7E1B359E-50FDF2A60007EA61-DA6E9700>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:630] DEBUG: reply.remote_tag = <893046186>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:631] DEBUG: cseq = <10>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda7ea700] [handle_sip_reply, SipCtrlInterface.cpp:637] DEBUG: ^^ M [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] ru SIP reply 100 Trying handled ^^
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Disconnecting, stopped, 1 UACTransPending vv
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [process, AmSession.cpp:790] DEBUG: AmSession processing event
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [updateStatus, AmSipDialog.cpp:331] DEBUG: updateStatus(rep = 100 Trying): transaction found!
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipReply, SBC.cpp:870] DEBUG: onSipReply: 100 Trying (fwd=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipReply, SBC.cpp:871] DEBUG: onSipReply: content-type =
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipReply, AmB2BSession.cpp:411] DEBUG: onSipReply: BYE -> 100 Trying (fwd=false), c-t=
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipReply, AmSession.cpp:912] DEBUG: Dialog status stays Disconnecting (stopped=true)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipReply, AmSession.cpp:914] DEBUG: negotiate_onreply = false
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'Server'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [relayEvent, AmB2BSession.cpp:474] DEBUG: AmB2BSession::relayEvent: to other_id=''
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Disconnecting, stopped, 1 UACTransPending ^^
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdbafd700] [execute, XMLRPC2DI.cpp:542] DEBUG: XMLRPC2DI: factory 'sbc' function 'postControlCmd'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdbafd700] [execute, XMLRPC2DI.cpp:549] DEBUG: params: <['7E1B359E-50FDF2A60007EA61-DA6E9700', 'teardown']>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdbafd700] [execute, XMLRPC2DI.cpp:565] DEBUG: result: <[202, 'Accepted']>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Disconnecting, stopped, 1 UACTransPending vv
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onControlCmd, SBC.cpp:781] DEBUG: teardown requested from control cmd
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Disconnecting, stopped, 1 UACTransPending ^^
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg via UDP vv#012--++--#012SIP/2.0 200 OK#015#012From: <sip:10.jameshe@108.166.121.76>;tag=30A30B3D-50FDF2A60007EE16-DACEF700#015#012To: <sip:10.jamesh@108.166.121.76>;tag=1951542397#015#012Contact: <sip:10.jamesh@192.168.2.11:54852;alias=208.57.127.186~2664~1;transport=udp>#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1#015#012CSeq: 11 BYE#015#012Content-Length: 0#015#012Via: SIP/2.0/UDP 127.0.0.1:5080;rport=5080;branch=z9hG4bKTFH4xaoZ#015#012Record-Route: <sip:108.166.121.76:80;lr=on;ftag=30A30B3D-50FDF2A60007EE16-DACEF700;ngcplb=yes>#015#012P-NGCP-Src-Ip: 208.57.127.186#015#012P-NGCP-Src-Port: 2664#015#012P-NGCP-Src-Proto: udp#015#012P-NGCP-Src-Af: 4#015#012#015#012--++--
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKTFH4xaoZ
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [received_msg, trans_layer.cpp:1235] DEBUG: Reply matched an existing transaction
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [update_uac_reply, trans_layer.cpp:1286] DEBUG: update_uac_reply(reply code = 200, trans=0x7f0ccc0098d0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type E (this=0x7f0ccc0099e0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type M (this=0x7f0ccc009a60)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type F (this=0x7f0ccc009a20)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type K at time=22367666 (repeated=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [prepare_routes_uac, SipCtrlInterface.cpp:721] DEBUG: route_field = [<sip:108.166.121.76:80;lr=on;ftag=30A30B3D-50FDF2A60007EE16-DACEF700;ngcplb=yes>]
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:627] DEBUG: Received reply: 200 OK
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:628] DEBUG: reply.callid = <b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:629] DEBUG: reply.local_tag = <30A30B3D-50FDF2A60007EE16-DACEF700>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:630] DEBUG: reply.remote_tag = <1951542397>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:631] DEBUG: cseq = <11>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda5e8700] [handle_sip_reply, SipCtrlInterface.cpp:637] DEBUG: ^^ M [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] ru SIP reply 200 OK handled ^^
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Disconnecting, stopped, 1 UACTransPending vv
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [process, AmSession.cpp:790] DEBUG: AmSession processing event
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [updateStatus, AmSipDialog.cpp:331] DEBUG: updateStatus(rep = 200 OK): transaction found!
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, SBC.cpp:1233] DEBUG: onSipReply: 200 OK (fwd=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, SBC.cpp:1234] DEBUG: onSipReply: content-type =
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, AmB2BSession.cpp:411] DEBUG: onSipReply: BYE -> 200 OK (fwd=false), c-t=
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, AmSession.cpp:908] DEBUG: Dialog status changed Disconnecting -> Disconnected (stopped=true)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [onSipReply, AmSession.cpp:914] DEBUG: negotiate_onreply = false
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Ip'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Port'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Proto'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Af'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [relayEvent, AmB2BSession.cpp:474] DEBUG: AmB2BSession::relayEvent: to other_id='7E1B359E-50FDF2A60007EA61-DA6E9700'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1|30A30B3D-50FDF2A60007EE16-DACEF700] Disconnected, stopped, 0 UACTransPending ^^
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:150] DEBUG: finalizing 1 sessions
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:154] DEBUG: finalizing session [0x7f0cd40016d0/b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1/30A30B3D-50FDF2A60007EE16-DACEF700]
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [finalize, AmSession.cpp:528] DEBUG: running finalize sequence...
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [destroy, AmSession.cpp:558] DEBUG: AmSession::destroy()
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [stop, AmSession.cpp:542] DEBUG: AmSession::stop()
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [clearAudio, AmSession.cpp:782] DEBUG: Audio cleared !!!
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [run, udp_trsp.cpp:213] DEBUG: vv M [|] u recvd msg via UDP vv#012--++--#012SIP/2.0 200 OK#015#012From: <sip:10.jamesh@108.166.121.76>;tag=7E1B359E-50FDF2A60007EA61-DA6E9700#015#012To: <sip:10.jameshe@108.166.121.76>;tag=893046186#015#012Contact: <sip:10.jameshe@192.168.2.9:60658;alias=208.57.127.186~60570~1;transport=udp>#015#012Call-ID: b9df66ae-22b6-dfbe-70cc-eefbaebefab7#015#012CSeq: 10 BYE#015#012Content-Length: 0#015#012Via: SIP/2.0/UDP 127.0.0.1:5080;rport=5080;branch=z9hG4bKtcZFraO2#015#012Record-Route: <sip:108.166.121.76:80;lr=on;ftag=7E1B359E-50FDF2A60007EA61-DA6E9700;ngcplb=yes>#015#012Record-Route: <sip:127.0.0.1:5062;lr=on;ftag=7E1B359E-50FDF2A60007EA61-DA6E9700;rtpprx=yes>#015#012P-NGCP-Src-Ip: 208.57.127.186#015#012P-NGCP-Src-Port: 60570#015#012P-NGCP-Src-Proto: udp#015#012P-NGCP-Src-Af: 4#015#012#015#012--++--
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [parse_via_params, parse_via.cpp:525] DEBUG: parsed branch: z9hG4bKtcZFraO2
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [parse_via_params, parse_via.cpp:548] DEBUG: has_rport: 1
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [received_msg, trans_layer.cpp:1235] DEBUG: Reply matched an existing transaction
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [update_uac_reply, trans_layer.cpp:1286] DEBUG: update_uac_reply(reply code = 200, trans=0x7f0cd40055f0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type E (this=0x7f0cd4005700)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type M (this=0x7f0cd4005780)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type F (this=0x7f0cd4005740)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [reset_timer, sip_trans.cpp:188] DEBUG: New timer of type K at time=22367666 (repeated=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [prepare_routes_uac, SipCtrlInterface.cpp:721] DEBUG: route_field = [<sip:127.0.0.1:5062;lr=on;ftag=7E1B359E-50FDF2A60007EA61-DA6E9700;rtpprx=yes>, <sip:108.166.121.76:80;lr=on;ftag=7E1B359E-50FDF2A60007EA61-DA6E9700;ngcplb=yes>]
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:627] DEBUG: Received reply: 200 OK
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:628] DEBUG: reply.callid = <b9df66ae-22b6-dfbe-70cc-eefbaebefab7>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:629] DEBUG: reply.local_tag = <7E1B359E-50FDF2A60007EA61-DA6E9700>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:630] DEBUG: reply.remote_tag = <893046186>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:631] DEBUG: cseq = <10>
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cda4e7700] [handle_sip_reply, SipCtrlInterface.cpp:637] DEBUG: ^^ M [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] ru SIP reply 200 OK handled ^^
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:450] DEBUG: vv S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Disconnecting, stopped, 1 UACTransPending vv
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:875] DEBUG: Discarding B2BSipReply from other leg (other_id empty)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onB2BEvent, AmB2BSession.cpp:879] DEBUG: reply code=200; method=BYE; callid=b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1; local_tag=30A30B3D-50FDF2A60007EE16-DACEF700; remote_tag=1951542397; cseq=11
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [process, AmSession.cpp:790] DEBUG: AmSession processing event
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [updateStatus, AmSipDialog.cpp:331] DEBUG: updateStatus(rep = 200 OK): transaction found!
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipReply, SBC.cpp:870] DEBUG: onSipReply: 200 OK (fwd=0)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipReply, SBC.cpp:871] DEBUG: onSipReply: content-type =
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipReply, AmB2BSession.cpp:411] DEBUG: onSipReply: BYE -> 200 OK (fwd=false), c-t=
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipReply, AmSession.cpp:908] DEBUG: Dialog status changed Disconnecting -> Disconnected (stopped=true)
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [onSipReply, AmSession.cpp:914] DEBUG: negotiate_onreply = false
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [finalize, AmSession.cpp:534] DEBUG: session is stopped.
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Ip'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Port'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Proto'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [inplaceHeaderFilter, HeaderFilter.cpp:161] DEBUG: erasing header 'P-NGCP-Src-Af'
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [relayEvent, AmB2BSession.cpp:474] DEBUG: AmB2BSession::relayEvent: to other_id=''
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [processingCycle, AmSession.cpp:468] DEBUG: ^^ S [b9df66ae-22b6-dfbe-70cc-eefbaebefab7|7E1B359E-50FDF2A60007EA61-DA6E9700] Disconnected, stopped, 0 UACTransPending ^^
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:150] DEBUG: finalizing 1 sessions
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:154] DEBUG: finalizing session [0x7f0ccc003cf0/b9df66ae-22b6-dfbe-70cc-eefbaebefab7/7E1B359E-50FDF2A60007EA61-DA6E9700]
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [finalize, AmSession.cpp:528] DEBUG: running finalize sequence...
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [destroy, AmSession.cpp:558] DEBUG: AmSession::destroy()
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [stop, AmSession.cpp:542] DEBUG: AmSession::stop()
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [clearAudio, AmSession.cpp:782] DEBUG: Audio cleared !!!
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [finalize, AmSession.cpp:534] DEBUG: session is stopped.
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:97] DEBUG: running processing loop
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdacef700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:01:06 sipwise01-prod sems[17785]: [#7f0cdabee700] [run, AmSessionProcessor.cpp:133] DEBUG: processing events for up to 1 sessions
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cda8eb700] [trans_timer_cb, sip_trans.cpp:157] DEBUG: Transaction timer expired: type=K, trans=0x7f0ccc0098d0, eta=22367666, t=22367666
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cda8eb700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type K (this=0x7f0cd40059d0)
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cda8eb700] [trans_timer_cb, sip_trans.cpp:157] DEBUG: Transaction timer expired: type=K, trans=0x7f0cd40055f0, eta=22367666, t=22367666
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cda8eb700] [reset_timer, sip_trans.cpp:140] DEBUG: Clearing old timer of type K (this=0x7f0ccc007980)
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [clean_sessions, AmSessionContainer.cpp:77] DEBUG: Session cleaner starting its work
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [clean_sessions, AmSessionContainer.cpp:93] DEBUG: session [0x7f0cd40016d0] has been destroyed
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmB2BSession, AmB2BSession.cpp:76] DEBUG: relayed_req.size() = 0
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmB2BSession, AmB2BSession.cpp:77] DEBUG: recvd_req.size() = 0
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmSession, AmSession.cpp:112] DEBUG: AmSession destructor finished
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmSipDialog, AmSipDialog.cpp:61] DEBUG: callid = b9df66ae-22b6-dfbe-70cc-eefbaebefab7_b2b-1
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmSipDialog, AmSipDialog.cpp:62] DEBUG: local_tag = 30A30B3D-50FDF2A60007EE16-DACEF700
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmSipDialog, AmSipDialog.cpp:63] DEBUG: uac_trans.size() = 0
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmSipDialog, AmSipDialog.cpp:71] DEBUG: uas_trans.size() = 0
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [clean_sessions, AmSessionContainer.cpp:93] DEBUG: session [0x7f0ccc003cf0] has been destroyed
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmB2BSession, AmB2BSession.cpp:76] DEBUG: relayed_req.size() = 0
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmB2BSession, AmB2BSession.cpp:77] DEBUG: recvd_req.size() = 1
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmSession, AmSession.cpp:112] DEBUG: AmSession destructor finished
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmSipDialog, AmSipDialog.cpp:61] DEBUG: callid = b9df66ae-22b6-dfbe-70cc-eefbaebefab7
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmSipDialog, AmSipDialog.cpp:62] DEBUG: local_tag = 7E1B359E-50FDF2A60007EA61-DA6E9700
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmSipDialog, AmSipDialog.cpp:63] DEBUG: uac_trans.size() = 0
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [~AmSipDialog, AmSipDialog.cpp:71] DEBUG: uas_trans.size() = 0
Jan 21 20:01:11 sipwise01-prod sems[17785]: [#7f0cdb5f8700] [run, AmSessionContainer.cpp:134] DEBUG: Session cleaner finished
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment