==> OpenSBC_data/logs/b2bua-2010-01-15-13631.log <== 2010/01/15 03:20:34.237 DBG: [CID=0x4e67c6a7] GC: First Stale Object SIPTransaction 2010/01/15 03:20:34.237 DBG: [CID=0xce0426af] TRANSACTION: (NIST) DESTROYED 2010/01/15 03:20:34.237 DTL: [CID=0xce0426af] NIST(1263543196201) *** DESTROYED *** - NIST|NGEyNmY5MDQ3YTc3ZWRiMzVmNzEwYjYyZmNmZTVkYzg.|z9hG4bK-d8754z-aa7c41567d1ee241-1---d8754z-|REGISTER 2010/01/15 03:20:34.457 DTL: [CID=0xce0426af] NIST(1263543196202) Event( Timer-J ) Interval: 32000 2010/01/15 03:20:34.457 DTL: [CID=0xce0426af] NIST(1263543196202) StateCompleted->StateTerminated 2010/01/15 03:20:34.457 DTL: [CID=0xce0426af] NIST(1263543196202) Event(Final) 2010/01/15 03:20:34.457 DTL: [CID=0x4e67c6a7] *** REMOVED TRANSACTION *** NIST|NGEyNmY5MDQ3YTc3ZWRiMzVmNzEwYjYyZmNmZTVkYzg.|z9hG4bK-d8754z-47591409e5361216-1---d8754z-|REGISTER 2010/01/15 03:20:34.457 DBG: [CID=0x4e67c6a7] GC: First Stale Object SIPTransaction 2010/01/15 03:20:34.457 DBG: [CID=0xce0426af] TRANSACTION: (NIST) DESTROYED 2010/01/15 03:20:34.457 DTL: [CID=0xce0426af] NIST(1263543196202) *** DESTROYED *** - NIST|NGEyNmY5MDQ3YTc3ZWRiMzVmNzEwYjYyZmNmZTVkYzg.|z9hG4bK-d8754z-47591409e5361216-1---d8754z-|REGISTER ==> OpenSBC_data/logs/b2bua-2010-01-15-13804.log <== 2010/01/15 03:20:45.649 DTL: [CID=0x4e67c6a7] Starting UDP Transport on 204.x.x.56:5064 2010/01/15 03:20:45.649 PWL: [CID=0x00000000] *** LISTENER STARTED *** [SIP Trunk] 204.x.x.56:5064 [*** DEFAULT LISTENER ***] 2010/01/15 03:20:45.649 DTL: [CID=0x4e67c6a7] *** TRANSPORT STARTED *** Listener: 204.x.x.56:5064 2010/01/15 03:20:45.649 PWL: [CID=0x00000000] *** CREATED *** SIP Trunk Registration Manager 2010/01/15 03:20:45.649 DTL: [CID=0x4e67c6a7] Starting UDP Transport on 204.x.x.56:5068 2010/01/15 03:20:45.650 PWL: [CID=0x00000000] *** LISTENER STARTED *** [CALEA Trunk] 204.x.x.56:5068 [*** DEFAULT LISTENER ***] 2010/01/15 03:20:45.650 DTL: [CID=0x4e67c6a7] *** TRANSPORT STARTED *** Listener: 204.x.x.56:5068 2010/01/15 03:21:09.640 PWL: [CID=0x00000000] Configuration change detected 2010/01/15 03:21:09.640 PWL: [CID=0x00000000] Setting SIP Log Level to 3 2010/01/15 03:21:09.640 PWL: [CID=0x00000000] Setting PTRACE Log Level to 1 ==> OpenSBC_data/logs/b2bua-2010-01-15-13911.log <== 2010/01/15 03:26:50.013 DTL: [CID=0xe5c89e13] NIST(1263543718846) Event( Timer-J ) Interval: 32000 2010/01/15 03:26:50.013 DTL: [CID=0xe5c89e13] NIST(1263543718846) StateCompleted->StateTerminated 2010/01/15 03:26:50.013 DTL: [CID=0xe5c89e13] NIST(1263543718846) Event(Final) 2010/01/15 03:26:50.013 DTL: [CID=0x4e67c6a7] *** REMOVED TRANSACTION *** NIST|OTQ0ZTQwODQxYzc2NDA4NDdiZWM4MmM5YzYzMjZkNmE.|z9hG4bK-d8754z-f9e93e6d168f3a59-1---d8754z-|REGISTER 2010/01/15 03:26:50.013 DBG: [CID=0x4e67c6a7] GC: First Stale Object SIPTransaction 2010/01/15 03:26:50.013 DBG: [CID=0xe5c89e13] TRANSACTION: (NIST) DESTROYED 2010/01/15 03:26:50.013 DTL: [CID=0xe5c89e13] NIST(1263543718846) *** DESTROYED *** - NIST|OTQ0ZTQwODQxYzc2NDA4NDdiZWM4MmM5YzYzMjZkNmE.|z9hG4bK-d8754z-f9e93e6d168f3a59-1---d8754z-|REGISTER 2010/01/15 03:31:23.078 PWL: [CID=0x00000000] Configuration change detected 2010/01/15 03:31:23.078 PWL: [CID=0x00000000] Setting SIP Log Level to 3 2010/01/15 03:31:23.078 PWL: [CID=0x00000000] Setting PTRACE Log Level to 1 ==> OpenSBC_data/logs/b2bua-2010-01-15-14060.log <== 2010/01/15 04:25:17.398 DBG: [CID=0x935a93cf] TRANSACTION: (NIST) DESTROYED 2010/01/15 04:25:17.398 DTL: [CID=0x935a93cf] NIST(1263545050926) *** DESTROYED *** - NIST|YWFmMGJmNTExZDFmYTJkY2M0ODU5ZDRlNThjZjExYWQ.|z9hG4bK-d8754z-5baaa4653b4bc94e-1---d8754z-|REGISTER 2010/01/15 04:25:17.609 DTL: [CID=0x935a93cf] NIST(1263545050927) Event( Timer-J ) Interval: 32000 2010/01/15 04:25:17.609 DTL: [CID=0x935a93cf] NIST(1263545050927) StateCompleted->StateTerminated 2010/01/15 04:25:17.609 DTL: [CID=0x935a93cf] NIST(1263545050927) Event(Final) 2010/01/15 04:25:17.609 DTL: [CID=0x4e67c6a7] *** REMOVED TRANSACTION *** NIST|YWFmMGJmNTExZDFmYTJkY2M0ODU5ZDRlNThjZjExYWQ.|z9hG4bK-d8754z-5d9b9b6cee1e4e38-1---d8754z-|REGISTER 2010/01/15 04:25:17.609 DBG: [CID=0x4e67c6a7] GC: First Stale Object SIPTransaction 2010/01/15 04:25:17.609 DBG: [CID=0x935a93cf] TRANSACTION: (NIST) DESTROYED 2010/01/15 04:25:17.609 DTL: [CID=0x935a93cf] NIST(1263545050927) *** DESTROYED *** - NIST|YWFmMGJmNTExZDFmYTJkY2M0ODU5ZDRlNThjZjExYWQ.|z9hG4bK-d8754z-5d9b9b6cee1e4e38-1---d8754z-|REGISTER 2010/01/15 04:25:38.198 PWL: [CID=0x00000000] Configuration change detected ==> OpenSBC_data/logs/b2bua-2010-01-15-16347.log <== 2010/01/15 04:29:00.608 DBG: [CID=0x4e67c6a7] GC: First Stale Object SIPTransaction 2010/01/15 04:29:00.608 DBG: [CID=0x3a9807fa] TRANSACTION: (NIST) DESTROYED 2010/01/15 04:29:00.608 DTL: [CID=0x3a9807fa] NIST(1263547708618) *** DESTROYED *** - NIST|MmQwY2RlOTBlMjhkYmM4OGZlMTcxNjcxZDlhNTNiM2Y.|z9hG4bK-d8754z-6239022e5cec9e63-1---d8754z-|REGISTER 2010/01/15 04:29:00.828 DTL: [CID=0x3a9807fa] NIST(1263547708619) Event( Timer-J ) Interval: 32000 2010/01/15 04:29:00.828 DTL: [CID=0x3a9807fa] NIST(1263547708619) StateCompleted->StateTerminated 2010/01/15 04:29:00.828 DTL: [CID=0x3a9807fa] NIST(1263547708619) Event(Final) 2010/01/15 04:29:00.828 DTL: [CID=0x4e67c6a7] *** REMOVED TRANSACTION *** NIST|MmQwY2RlOTBlMjhkYmM4OGZlMTcxNjcxZDlhNTNiM2Y.|z9hG4bK-d8754z-bf708304b82bc937-1---d8754z-|REGISTER 2010/01/15 04:29:00.828 DBG: [CID=0x4e67c6a7] GC: First Stale Object SIPTransaction 2010/01/15 04:29:00.828 DBG: [CID=0x3a9807fa] TRANSACTION: (NIST) DESTROYED 2010/01/15 04:29:00.828 DTL: [CID=0x3a9807fa] NIST(1263547708619) *** DESTROYED *** - NIST|MmQwY2RlOTBlMjhkYmM4OGZlMTcxNjcxZDlhNTNiM2Y.|z9hG4bK-d8754z-bf708304b82bc937-1---d8754z-|REGISTER ==> OpenSBC_data/logs/b2bua-2010-01-15-16455.log <== 2010/01/15 09:41:04.241 DTL: [CID=0x99b5a101] *** QUEUED FOR DELETION *** SIPSession: c6adc532-7cc9-122d-e1ad-0030489584c6-0x0010 2010/01/15 09:41:10.254 DBG: [CID=0x4e67c6a7] GC: First Stale Object B2BUACall 2010/01/15 09:41:10.254 DBG: [CID=0x4e67c6a7] GC: First Stale Object B2BUACall Leg 2 2010/01/15 09:41:10.254 INF: [CID=0x75440f0f] *** DESTROYED B2BUA CONNECTION *** 2010/01/15 09:41:10.254 INF: [CID=0x75440f0f] *** COUNTERS *** ICT=0 NICT=0 IST=0 NIST=0 TIMERS=0 CALL=0 CONN=0 REG=0 RTP=0 QUEUE=1 CACHE=0 GC=3 TOTALCONN=29 TOTALREG=0 TOTALSZR=9 AVGDUR=8 HIGHDUR=29 IDLETIME=38 2010/01/15 09:41:10.254 DBG: [CID=0x75440f0f] CONNECTION: Session DESTROYED 2010/01/15 09:41:10.254 INF: [CID=0x75440f0f] *** DESTROYED CALL *** c6adc532-7cc9-122d-e1ad-0030489584c6 2010/01/15 09:41:10.254 DBG: [CID=0x75440f0f] CALL: (inbound) : Session DESTROYED 2010/01/15 09:41:10.254 INF: [CID=0x75440f0f] *** DESTROYED CALL *** c6adc532-7cc9-122d-e1ad-0030489584c6-0x0010 2010/01/15 09:41:10.254 DBG: [CID=0x75440f0f] CALL: (outbound) : Session DESTROYED ==> OpenSBC_data/logs/b2bua.current.log <== 2010/01/15 03:20:34.237 DBG: [CID=0x4e67c6a7] GC: First Stale Object SIPTransaction 2010/01/15 03:20:34.237 DBG: [CID=0xce0426af] TRANSACTION: (NIST) DESTROYED 2010/01/15 03:20:34.237 DTL: [CID=0xce0426af] NIST(1263543196201) *** DESTROYED *** - NIST|NGEyNmY5MDQ3YTc3ZWRiMzVmNzEwYjYyZmNmZTVkYzg.|z9hG4bK-d8754z-aa7c41567d1ee241-1---d8754z-|REGISTER 2010/01/15 03:20:34.457 DTL: [CID=0xce0426af] NIST(1263543196202) Event( Timer-J ) Interval: 32000 2010/01/15 03:20:34.457 DTL: [CID=0xce0426af] NIST(1263543196202) StateCompleted->StateTerminated 2010/01/15 03:20:34.457 DTL: [CID=0xce0426af] NIST(1263543196202) Event(Final) 2010/01/15 03:20:34.457 DTL: [CID=0x4e67c6a7] *** REMOVED TRANSACTION *** NIST|NGEyNmY5MDQ3YTc3ZWRiMzVmNzEwYjYyZmNmZTVkYzg.|z9hG4bK-d8754z-47591409e5361216-1---d8754z-|REGISTER 2010/01/15 03:20:34.457 DBG: [CID=0x4e67c6a7] GC: First Stale Object SIPTransaction 2010/01/15 03:20:34.457 DBG: [CID=0xce0426af] TRANSACTION: (NIST) DESTROYED 2010/01/15 03:20:34.457 DTL: [CID=0xce0426af] NIST(1263543196202) *** DESTROYED *** - NIST|NGEyNmY5MDQ3YTc3ZWRiMzVmNzEwYjYyZmNmZTVkYzg.|z9hG4bK-d8754z-47591409e5361216-1---d8754z-|REGISTER ==> OpenSBC_data/logs/b2bua-2010-01-15-16455.log <== 2010/01/15 10:09:30.854 INF: [CID=0xdedc4290] <<< INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5062;rinstance=1177004d3948194b SIP/2.0 Method(INVITE) SRC: 72.x.x.154:5060:UDP enc=0 bytes=1441 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5062;rinstance=1177004d3948194b SIP/2.0 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] From: "+16782342838" ;tag=74jDStc6NDj7H 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] To: 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Via: SIP/2.0/UDP 72.x.x.154;branch=z9hG4bKQrD1H80rDyZpj;rport=5060;received=72.x.x.154 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] CSeq: 125680137 INVITE 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Call-ID: d2ff8ec0-7ccd-122d-e1ad-0030489584c6 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Contact: 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Content-Disposition: session 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-13081M 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Max-Forwards: 65 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Supported: timer, precondition, path, replaces 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Session-Expires: 600 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Min-SE: 120 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] X-Master_UUID: fb57da6a-022a-11df-98a3-e7ca8bfc9c72 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] X-MASTER_ACCTCODE: 55195 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Remote-Party-ID: "+16782342838" ;party=calling;screen=yes;privacy=off 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Content-Type: application/sdp 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] Content-Length: 259 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] v=0 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] o=FreeSWITCH 277809115536628870 1650001856561521284 IN IP4 72.x.x.154 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] s=FreeSWITCH 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] c=IN IP4 72.x.x.154 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] t=0 0 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] m=audio 26680 RTP/AVP 0 101 13 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] a=rtpmap:0 PCMU/8000 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] a=rtpmap:101 telephone-event/8000 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] a=fmtp:101 0-16 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] a=rtpmap:13 CN/8000 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] a=ptime:20 2010/01/15 10:09:30.854 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.855 DTL: [CID=0xdedc4290] IST(1263547786946) *** CREATED *** - IST|d2ff8ec0-7ccd-122d-e1ad-0030489584c6|z9hG4bKQrD1H80rDyZpj|INVITE 2010/01/15 10:09:30.855 DTL: [CID=0xdedc4290] IST(1263547786946) Event(SIPMessage) - INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5062;rinstance=1177004d3948194b SIP/2.0 2010/01/15 10:09:30.855 DBG: [CID=0xdedc4290] TRANSACTION: (IST) INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5062;rinstance=1177004d3948194b SIP/2.0 State: 0 2010/01/15 10:09:30.855 DBG: [CID=0xdedc4290] Event: SIPStack::Enqueue(INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5062;rinstance=1177004d3948194b SIP/2.0) 2010/01/15 10:09:30.855 DTL: [CID=0xdedc4290] IST(1263547786946) StateIdle->StateProceeding 2010/01/15 10:09:30.856 DTL: [CID=0xdedc4290] Event: ---> Inbound - INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5062;rinstance=1177004d3948194b SIP/2.0 2010/01/15 10:09:30.856 INF: [CID=0xdedc4290] >>> SIP/2.0 100 Trying Method(INVITE) DST: 72.x.x.154:5060:UDP SRC: 204.x.x.56:5062 enc=0 bytes=381 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] SIP/2.0 100 Trying 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] From: "+16782342838" ;tag=74jDStc6NDj7H 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] To: 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] Via: SIP/2.0/UDP 72.x.x.154;branch=z9hG4bKQrD1H80rDyZpj;rport=5060;received=72.x.x.154 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] CSeq: 125680137 INVITE 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] Call-ID: d2ff8ec0-7ccd-122d-e1ad-0030489584c6 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] Content-Length: 0 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] Session CREATED 2010/01/15 10:09:30.856 INF: [CID=0xdedc4290] *** CREATED (UAS) CALL *** Call-ID: d2ff8ec0-7ccd-122d-e1ad-0030489584c6 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] Multidirectional Session CREATED 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] B2BUAConnection Created 0x0x1d690ed0 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] *** COUNTERS *** (Constructor)ICT=0 NICT=0 IST=1 NIST=0 TIMERS=1 CALL=1 CONN=1 REG=0 RTP=0 QUEUE=0 CACHE=0 GC=1 TOTALCONN=30 TOTALREG=0 TOTALSZR=9 AVGDUR=8 HIGHDUR=29 IDLETIME=0 2010/01/15 10:09:30.856 DBG: [CID=0xdedc4290] *** MESSAGE ARRIVAL *** for SIP Session d2ff8ec0-7ccd-122d-e1ad-0030489584c6 2010/01/15 10:09:30.857 DBG: [CID=0x4e67c6a7] CREATED via=204.x.x.56:5062/ for target=204.x.x.56 protocol=UDP 2010/01/15 10:09:30.857 DBG: [CID=0xdedc4290] Session CREATED 2010/01/15 10:09:30.857 INF: [CID=0xdedc4290] *** CREATED (UAC) CALL *** d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011 2010/01/15 10:09:30.857 DBG: [CID=0x4e67c6a7] CREATED via=204.x.x.56:5062/ for target=72.x.x.154 protocol=UDP 2010/01/15 10:09:30.858 DTL: [CID=0xdedc4290] ICT(1263547786947) *** CREATED *** - ICT|d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011|z9hG4bKc4db69a65500df1180c4ef81de19c4a0|INVITE 2010/01/15 10:09:30.858 DTL: [CID=0xdedc4290] ICT(1263547786947) Event(SIPMessage) - INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5060;rinstance=1177004d3948194b SIP/2.0 2010/01/15 10:09:30.858 DBG: [CID=0xdedc4290] TRANSACTION: (ICT) INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5060;rinstance=1177004d3948194b SIP/2.0 State: 0 2010/01/15 10:09:30.859 INF: [CID=0xdedc4290] >>> INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5060;rinstance=1177004d3948194b SIP/2.0 Method(INVITE) DST: 72.x.x.154:5060:UDP SRC: 204.x.x.56:5062 enc=0 bytes=1429 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5060;rinstance=1177004d3948194b SIP/2.0 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] From: "+16782342838" ;tag=74jDStc6NDj7H 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] To: 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Via: SIP/2.0/UDP 204.x.x.56:5062;iid=16458;branch=z9hG4bKc4db69a65500df1180c4ef81de19c4a0;uas-addr=72.x.x.154;rport 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] CSeq: 125680137 INVITE 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Call-ID: d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Contact: 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Content-Disposition: session 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] User-Agent: Backdoor Trunk 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Max-Forwards: 64 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Supported: timer, precondition, path, replaces 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Session-Expires: 600 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Min-SE: 120 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] X-Master_UUID: fb57da6a-022a-11df-98a3-e7ca8bfc9c72 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] X-MASTER_ACCTCODE: 55195 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Remote-Party-ID: "+16782342838" ;party=calling;screen=yes;privacy=off 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Content-Type: application/sdp 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] Content-Length: 259 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] v=0 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] o=FreeSWITCH 277809115536628870 1650001856561521284 IN IP4 72.x.x.154 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] s=FreeSWITCH 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] c=IN IP4 72.x.x.154 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] t=0 0 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] m=audio 26680 RTP/AVP 0 101 13 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] a=rtpmap:0 PCMU/8000 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] a=rtpmap:101 telephone-event/8000 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] a=fmtp:101 0-16 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] a=rtpmap:13 CN/8000 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] a=ptime:20 2010/01/15 10:09:30.859 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.859 DTL: [CID=0xdedc4290] Found ICT|d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011|z9hG4bKc4db69a65500df1180c4ef81de19c4a0|INVITE for INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5060;rinstance=1177004d3948194b SIP/2.0 2010/01/15 10:09:30.859 DTL: [CID=0xdedc4290] Event: ---> TransportWrite - INVITE 2010/01/15 10:09:30.860 DTL: [CID=0xdedc4290] ICT(1263547786947) Event(SIPMessage) - INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5060;rinstance=1177004d3948194b SIP/2.0 2010/01/15 10:09:30.860 DBG: [CID=0xdedc4290] TRANSACTION: (ICT) INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5060;rinstance=1177004d3948194b SIP/2.0 State: 0 2010/01/15 10:09:30.860 DTL: [CID=0xdedc4290] ICT(1263547786947) StateIdle->StateCalling(INVITE sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5060;rinstance=1177004d3948194b SIP/2.0) 2010/01/15 10:09:30.871 INF: [CID=0xdedc4290] <<< SIP/2.0 100 Trying Method(INVITE) SRC: 72.x.x.154:5060:UDP enc=0 bytes=473 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] SIP/2.0 100 Trying 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] From: "+16782342838" ;tag=74jDStc6NDj7H 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] To: 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] Via: SIP/2.0/UDP 204.x.x.56:5062;iid=16458;branch=z9hG4bKc4db69a65500df1180c4ef81de19c4a0;uas-addr=72.x.x.154;rport=5062 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] CSeq: 125680137 INVITE 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] Call-ID: d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-13081M 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] Content-Length: 0 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.871 DTL: [CID=0xdedc4290] Found ICT|d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011|z9hG4bKc4db69a65500df1180c4ef81de19c4a0|INVITE for SIP/2.0 100 Trying 2010/01/15 10:09:30.871 DTL: [CID=0xdedc4290] ICT(1263547786947) Event(SIPMessage) - SIP/2.0 100 Trying 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] TRANSACTION: (ICT) SIP/2.0 100 Trying State: 1 2010/01/15 10:09:30.871 DTL: [CID=0xdedc4290] ICT(1263547786947) StateCalling->StateProceeding(SIP/2.0 100 Trying) 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] Event: SIPStack::Enqueue(SIP/2.0 100 Trying) 2010/01/15 10:09:30.871 DTL: [CID=0xdedc4290] Event: ---> Inbound - SIP/2.0 100 Trying 2010/01/15 10:09:30.871 DBG: [CID=0xdedc4290] *** MESSAGE ARRIVAL *** for SIP Session d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011 2010/01/15 10:09:30.872 INF: [CID=0xdedc4290] <<< SIP/2.0 407 Proxy Authentication Required Method(INVITE) SRC: 72.x.x.154:5060:UDP enc=0 bytes=937 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] SIP/2.0 407 Proxy Authentication Required 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] From: "+16782342838" ;tag=74jDStc6NDj7H 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] To: ;tag=9p5yvgeDgZycS 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] Via: SIP/2.0/UDP 204.x.x.56:5062;iid=16458;branch=z9hG4bKc4db69a65500df1180c4ef81de19c4a0;uas-addr=72.x.x.154;rport=5062 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] CSeq: 125680137 INVITE 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] Call-ID: d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-13081M 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] Proxy-Authenticate: Digest realm="204.x.x.56", nonce="fba3fd46-022a-11df-b090-c13a12ca93df", algorithm=MD5 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] Accept: application/sdp 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] Supported: timer, precondition, path, replaces 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] Content-Length: 0 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.872 DTL: [CID=0xdedc4290] Found ICT|d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011|z9hG4bKc4db69a65500df1180c4ef81de19c4a0|INVITE for SIP/2.0 407 Proxy Authentication Required 2010/01/15 10:09:30.872 DTL: [CID=0xdedc4290] ICT(1263547786947) Event(SIPMessage) - SIP/2.0 407 Proxy Authentication Required 2010/01/15 10:09:30.872 DBG: [CID=0xdedc4290] TRANSACTION: (ICT) SIP/2.0 407 Proxy Authentication Required State: 2 2010/01/15 10:09:30.873 DTL: [CID=0xdedc4290] ICT(1263547786947) StateProceeding->StateCompleted(SIP/2.0 407 Proxy Authentication Required) 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Event: SIPStack::Enqueue(SIP/2.0 407 Proxy Authentication Required) 2010/01/15 10:09:30.873 DTL: [CID=0xdedc4290] Event: ---> Inbound - SIP/2.0 407 Proxy Authentication Required 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] *** MESSAGE ARRIVAL *** for SIP Session d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011 2010/01/15 10:09:30.873 INF: [CID=0xdedc4290] >>> ACK sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5060;rinstance=1177004d3948194b SIP/2.0 Method(ACK) DST: 72.x.x.154:5060:UDP SRC: 204.x.x.56:5062 enc=0 bytes=1149 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] ACK sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5060;rinstance=1177004d3948194b SIP/2.0 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] From: "+16782342838" ;tag=74jDStc6NDj7H 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] To: ;tag=9p5yvgeDgZycS 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Via: SIP/2.0/UDP 204.x.x.56:5062;iid=16458;branch=z9hG4bKc4db69a65500df1180c4ef81de19c4a0;uas-addr=72.x.x.154;rport 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] CSeq: 125680137 ACK 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Call-ID: d2ff8ec0-7ccd-122d-e1ad-0030489584c6-0x0011 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Contact: 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Content-Disposition: session 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] User-Agent: Backdoor Trunk 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Max-Forwards: 70 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Supported: timer, precondition, path, replaces 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Session-Expires: 600 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Min-SE: 120 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] X-Master_UUID: fb57da6a-022a-11df-98a3-e7ca8bfc9c72 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] X-MASTER_ACCTCODE: 55195 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Remote-Party-ID: "+16782342838" ;party=calling;screen=yes;privacy=off 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] Content-Length: 0 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.873 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.874 DTL: [CID=0xdedc4290] Found IST|d2ff8ec0-7ccd-122d-e1ad-0030489584c6|z9hG4bKQrD1H80rDyZpj|INVITE for SIP/2.0 407 Proxy Authentication Required 2010/01/15 10:09:30.874 DTL: [CID=0xdedc4290] IST(1263547786946) Event(SIPMessage) - SIP/2.0 407 Proxy Authentication Required 2010/01/15 10:09:30.874 DBG: [CID=0xdedc4290] TRANSACTION: (IST) SIP/2.0 407 Proxy Authentication Required State: 3 2010/01/15 10:09:30.874 DTL: [CID=0xdedc4290] IST(1263547786946) StateProceeding->StateCompleted(SIP/2.0 407 Proxy Authentication Required) 2010/01/15 10:09:30.874 DBG: [CID=0xdedc4290] Added ACK Transaction d2ff8ec0-7ccd-122d-e1ad-0030489584c6|z9hG4bKQrD1H80rDyZpj|ACK 2010/01/15 10:09:30.874 INF: [CID=0xdedc4290] >>> SIP/2.0 407 Proxy Authentication Required Method(INVITE) DST: 72.x.x.154:5060:UDP SRC: 204.x.x.56:5062 enc=0 bytes=548 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] SIP/2.0 407 Proxy Authentication Required 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] From: "+16782342838" ;tag=74jDStc6NDj7H 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] To: 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] Via: SIP/2.0/UDP 72.x.x.154;iid=16456;branch=z9hG4bKQrD1H80rDyZpj;rport=5060;received=72.x.x.154 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] CSeq: 125680137 INVITE 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] Call-ID: d2ff8ec0-7ccd-122d-e1ad-0030489584c6 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] Server: Backdoor Trunk 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] Proxy-Authenticate: Digest realm="204.x.x.56", nonce="fba3fd46-022a-11df-b090-c13a12ca93df", algorithm=MD5 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] Content-Length: 0 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.875 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.886 INF: [CID=0xdedc4290] <<< ACK sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5062;rinstance=1177004d3948194b SIP/2.0 Method(ACK) SRC: 72.x.x.154:5060:UDP enc=0 bytes=489 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] ACK sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5062;rinstance=1177004d3948194b SIP/2.0 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] From: "+16782342838" ;tag=74jDStc6NDj7H 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] To: 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] Via: SIP/2.0/UDP 72.x.x.154;branch=z9hG4bKQrD1H80rDyZpj;rport=5060;received=72.x.x.154 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] CSeq: 125680137 ACK 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] Call-ID: d2ff8ec0-7ccd-122d-e1ad-0030489584c6 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] Max-Forwards: 65 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] Content-Length: 0 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] Found ACK Transaction d2ff8ec0-7ccd-122d-e1ad-0030489584c6|z9hG4bKQrD1H80rDyZpj|ACK 2010/01/15 10:09:30.886 DTL: [CID=0xdedc4290] IST(1263547786946) Event(SIPMessage) - ACK sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5062;rinstance=1177004d3948194b SIP/2.0 2010/01/15 10:09:30.886 DBG: [CID=0xdedc4290] TRANSACTION: (IST) ACK sip:x-reg-id-sip%3A16785348167%4024.196.234.39%3A26353@204.x.x.56:5062;rinstance=1177004d3948194b SIP/2.0 State: 4 2010/01/15 10:09:30.886 DTL: [CID=0xdedc4290] IST(1263547786946) StateProceeding->StateConfirmed