sip*CLI> sip*CLI> <-- SIP read from 195.250.90.227:8070: INVITE sip:10027@sip.arminco.com SIP/2.0 Via: SIP/2.0/UDP 195.250.90.227:8070;branch=z9hG4bK-d87543-527b7a00e577c511-1--d87543-;rport Max-Forwards: 70 Contact: To: From: "Vahan Yerkanian";tag=4d2cea31 Call-ID: b545507e05626b74@cGhvZW5peA.. CSeq: 1 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp Supported: eventlist User-Agent: CounterPath eyeBeam release 3013o stamp 23916 Content-Length: 250 v=0 o=- 120197834 120197853 IN IP4 195.250.90.227 s=CounterPath eyeBeam c=IN IP4 195.250.90.227 t=0 0 m=audio 8072 RTP/AVP 0 101 a=alt:1 1 : A9052CA2 00000035 195.250.90.227 8072 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=sendrecv Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 0: INVITE sip:10027@sip.arminco.com SIP/2.0 (40) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 1: Via: SIP/2.0/UDP 195.250.90.227:8070;branch=z9hG4bK-d87543-527b7a00e577c511-1--d87543-;rport (92) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 2: Max-Forwards: 70 (16) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 3: Contact: (40) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 4: To: (31) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 5: From: "Vahan Yerkanian";tag=4d2cea31 (63) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 6: Call-ID: b545507e05626b74@cGhvZW5peA.. (38) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 7: CSeq: 1 INVITE (14) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 9: Content-Type: application/sdp (29) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 10: Supported: eventlist (20) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 11: User-Agent: CounterPath eyeBeam release 3013o stamp 23916 (57) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 12: Content-Length: 250 (19) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 13: (0) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: v=0 (3) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: o=- 120197834 120197853 IN IP4 195.250.90.227 (45) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: s=CounterPath eyeBeam (21) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: c=IN IP4 195.250.90.227 (23) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: t=0 0 (5) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: m=audio 8072 RTP/AVP 0 101 (26) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: a=alt:1 1 : A9052CA2 00000035 195.250.90.227 8072 (49) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: a=fmtp:101 0-15 (15) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: a=sendrecv (10) --- (13 headers 10 lines)--- Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3174 sip_alloc: Allocating new SIP dialog for b545507e05626b74@cGhvZW5peA.. - INVITE (With RTP) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:11191 handle_request: **** Received INVITE (5) - Command in SIP INVITE Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:1005 parse_sip_options: Begin: parsing SIP "Supported: eventlist" Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:1017 parse_sip_options: Found SIP option: -eventlist- Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:1023 parse_sip_options: Matched SIP option: eventlist Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:1034 parse_sip_options: * SIP extension value: 2048 for call b545507e05626b74@cGhvZW5peA.. Using INVITE request as basis request - b545507e05626b74@cGhvZW5peA.. Sending to 195.250.90.227 : 8070 (NAT) Jul 20 20:03:12 DEBUG[7513]: res_config_mysql.c:125 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '10040' Jul 20 20:03:12 DEBUG[7513]: res_config_mysql.c:636 mysql_reconnect: MySQL RealTime: Everything is fine. Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:7225 check_user_full: Setting NAT on RTP to 524288 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:7229 check_user_full: Setting NAT on VRTP to 524288 Reliably Transmitting (NAT) to 195.250.90.227:8070: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 195.250.90.227:8070;branch=z9hG4bK-d87543-527b7a00e577c511-1--d87543-;received=195.250.90.227;rport=8070 From: "Vahan Yerkanian";tag=4d2cea31 To: ;tag=as513d10d1 Call-ID: b545507e05626b74@cGhvZW5peA.. CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Proxy-Authenticate: Digest realm="sip.arminco.com", nonce="28c1822d" Content-Length: 0 --- Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:1294 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #145 Scheduling destruction of call 'b545507e05626b74@cGhvZW5peA..' in 15000 ms Found user '10040' sip*CLI> <-- SIP read from 195.250.90.227:8070: ACK sip:10027@sip.arminco.com SIP/2.0 Via: SIP/2.0/UDP 195.250.90.227:8070;branch=z9hG4bK-d87543-527b7a00e577c511-1--d87543-;rport To: ;tag=as513d10d1 From: "Vahan Yerkanian";tag=4d2cea31 Call-ID: b545507e05626b74@cGhvZW5peA.. CSeq: 1 ACK Content-Length: 0 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 0: ACK sip:10027@sip.arminco.com SIP/2.0 (37) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 1: Via: SIP/2.0/UDP 195.250.90.227:8070;branch=z9hG4bK-d87543-527b7a00e577c511-1--d87543-;rport (92) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 2: To: ;tag=as513d10d1 (46) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 3: From: "Vahan Yerkanian";tag=4d2cea31 (63) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 4: Call-ID: b545507e05626b74@cGhvZW5peA.. (38) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 5: CSeq: 1 ACK (11) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 6: Content-Length: 0 (17) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 7: (0) --- (7 headers 0 lines)--- Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3222 find_call: = Found Their Call ID: b545507e05626b74@cGhvZW5peA.. Their Tag 4d2cea31 Our tag: as513d10d1 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:11191 handle_request: **** Received ACK (6) - Command in SIP ACK Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:1391 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #145 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:1402 __sip_ack: Stopping retransmission on 'b545507e05626b74@cGhvZW5peA..' of Response 1: Match Found sip*CLI> <-- SIP read from 195.250.90.227:8070: INVITE sip:10027@sip.arminco.com SIP/2.0 Via: SIP/2.0/UDP 195.250.90.227:8070;branch=z9hG4bK-d87543-8e11da512b647532-1--d87543-;rport Max-Forwards: 70 Contact: To: From: "Vahan Yerkanian";tag=4d2cea31 Call-ID: b545507e05626b74@cGhvZW5peA.. CSeq: 2 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp Proxy-Authorization: Digest username="10040",realm="sip.arminco.com",nonce="28c1822d",uri="sip:10027@sip.arminco.com",response="0f7af11cde0cf3094d885efbba8514c1",algorithm=MD5 Supported: eventlist User-Agent: CounterPath eyeBeam release 3013o stamp 23916 Content-Length: 250 v=0 o=- 120197834 120197853 IN IP4 195.250.90.227 s=CounterPath eyeBeam c=IN IP4 195.250.90.227 t=0 0 m=audio 8072 RTP/AVP 0 101 a=alt:1 1 : A9052CA2 00000035 195.250.90.227 8072 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=sendrecv Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 0: INVITE sip:10027@sip.arminco.com SIP/2.0 (40) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 1: Via: SIP/2.0/UDP 195.250.90.227:8070;branch=z9hG4bK-d87543-8e11da512b647532-1--d87543-;rport (92) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 2: Max-Forwards: 70 (16) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 3: Contact: (40) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 4: To: (31) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 5: From: "Vahan Yerkanian";tag=4d2cea31 (63) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 6: Call-ID: b545507e05626b74@cGhvZW5peA.. (38) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 7: CSeq: 2 INVITE (14) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 9: Content-Type: application/sdp (29) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 10: Proxy-Authorization: Digest username="10040",realm="sip.arminco.com",nonce="28c1822d",uri="sip:10027@sip.arminco.com",response="0f7af11cde0cf3094d885efbba8514c1",algorithm=MD5 (175) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 11: Supported: eventlist (20) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 12: User-Agent: CounterPath eyeBeam release 3013o stamp 23916 (57) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 13: Content-Length: 250 (19) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 14: (0) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: v=0 (3) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: o=- 120197834 120197853 IN IP4 195.250.90.227 (45) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: s=CounterPath eyeBeam (21) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: c=IN IP4 195.250.90.227 (23) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: t=0 0 (5) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: m=audio 8072 RTP/AVP 0 101 (26) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: a=alt:1 1 : A9052CA2 00000035 195.250.90.227 8072 (49) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: a=fmtp:101 0-15 (15) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3422 parse_request: Line: a=sendrecv (10) --- (14 headers 10 lines)--- Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3222 find_call: = Found Their Call ID: b545507e05626b74@cGhvZW5peA.. Their Tag 4d2cea31 Our tag: as513d10d1 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:11191 handle_request: **** Received INVITE (5) - Command in SIP INVITE Using INVITE request as basis request - b545507e05626b74@cGhvZW5peA.. Sending to 195.250.90.227 : 8070 (NAT) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:7225 check_user_full: Setting NAT on RTP to 524288 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:7229 check_user_full: Setting NAT on VRTP to 524288 Found user '10040' Found RTP audio format 0 Found RTP audio format 101 Peer audio RTP is at port 195.250.90.227:8072 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3641 process_sdp: Peer audio RTP is at port 195.250.90.227:8072 Peer video RTP is at port 195.250.90.227:65535 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3665 process_sdp: Peer video RTP is at port 195.250.90.227:65535 Found description format telephone-event Capabilities: us - 0x180104 (ulaw|g729|h263|h263p), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - (ulaw) Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:10542 handle_request_invite: Checking SIP call limits for device 10040 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:2208 update_call_counter: Updating call counter for incoming call Looking for 10027 in Arminco (domain sip.arminco.com) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:6205 build_route: build_route: Contact hop: list_route: hop: Transmitting (NAT) to 195.250.90.227:8070: SIP/2.0 100 Trying Via: SIP/2.0/UDP 195.250.90.227:8070;branch=z9hG4bK-d87543-8e11da512b647532-1--d87543-;received=195.250.90.227;rport=8070 From: "Vahan Yerkanian";tag=4d2cea31 To: Call-ID: b545507e05626b74@cGhvZW5peA.. CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:11722 sip_devicestate: Checking device state for peer 10040 Jul 20 20:03:12 DEBUG[7513]: res_config_mysql.c:125 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '10040' Jul 20 20:03:12 DEBUG[7513]: res_config_mysql.c:636 mysql_reconnect: MySQL RealTime: Everything is fine. -- SIP Seeding peer from astdb: '10040' at 10040@195.250.90.227:8070 for 3600 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3174 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 0: OPTIONS sip:10040@195.250.90.227:8070 SIP/2.0 (45) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 1: Via: SIP/2.0/UDP 195.250.74.130:5060;branch=z9hG4bK6f450d91;rport (65) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 2: From: "Unknown" ;tag=as105bcbe0 (59) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 3: To: (35) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 4: Contact: (37) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 5: Call-ID: 7bf1c6a63e28fbdc74af48651fba1abc@195.250.74.130 (56) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 6: CSeq: 102 OPTIONS (17) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 7: User-Agent: Asterisk PBX (24) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 8: Max-Forwards: 70 (16) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 9: Date: Thu, 20 Jul 2006 15:03:12 GMT (35) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 11: Content-Length: 0 (17) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 12: (0) 12 headers, 0 lines Reliably Transmitting (NAT) to 195.250.90.227:8070: OPTIONS sip:10040@195.250.90.227:8070 SIP/2.0 Via: SIP/2.0/UDP 195.250.74.130:5060;branch=z9hG4bK6f450d91;rport From: "Unknown" ;tag=as105bcbe0 To: Contact: Call-ID: 7bf1c6a63e28fbdc74af48651fba1abc@195.250.74.130 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Thu, 20 Jul 2006 15:03:12 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:1294 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #147 Jul 20 20:03:12 DEBUG[7513]: devicestate.c:187 do_state_change: Changing state for SIP/10040 - state 2 (In use) Jul 20 20:03:12 DEBUG[7513]: pbx.c:1677 pbx_extension_helper: Launching 'Macro' -- Executing Macro("SIP/10040-24ec", "call|10027") in new stack Jul 20 20:03:12 DEBUG[7513]: pbx.c:1677 pbx_extension_helper: Launching 'Set' -- Executing Set("SIP/10040-24ec", "ext=10027") in new stack Jul 20 20:03:12 DEBUG[7513]: pbx.c:1677 pbx_extension_helper: Launching 'Dial' -- Executing Dial("SIP/10040-24ec", "SIP/10027|20|o") in new stack Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3174 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Jul 20 20:03:12 DEBUG[7513]: res_config_mysql.c:125 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '10027' Jul 20 20:03:12 DEBUG[7513]: res_config_mysql.c:636 mysql_reconnect: MySQL RealTime: Everything is fine. -- SIP Seeding peer from astdb: '10027' at 10027@195.250.90.228:5060 for 3600 Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3174 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 0: OPTIONS sip:10027@195.250.90.228:5060 SIP/2.0 (45) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 1: Via: SIP/2.0/UDP 195.250.74.130:5060;branch=z9hG4bK41d889b5;rport (65) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 2: From: "Unknown" ;tag=as2ada9a06 (59) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 3: To: (35) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 4: Contact: (37) Jul 20 20:03:12 DEBUG[7513]: chan_sip.c:3390 parse_request: Header 5: Ca Disconnected from Asterisk server