13:51:59.293 sip_endpoint.c Module "mod-pjsua-log" registered 13:51:59.293 sip_endpoint.c Module "mod-tsx-layer" registered 13:51:59.293 sip_endpoint.c Module "mod-stateful-util" registered 13:51:59.299 sip_endpoint.c Module "mod-ua" registered 13:51:59.299 sip_endpoint.c Module "mod-100rel" registered 13:51:59.299 sip_endpoint.c Module "mod-pjsua" registered 13:51:59.299 sip_endpoint.c Module "mod-invite" registered 13:51:59.430 pa_dev.c PortAudio sound library initialized, status=0 13:51:59.430 pa_dev.c PortAudio host api count=1 13:51:59.430 pa_dev.c Sound device count=7 13:51:59.430 pjlib select() I/O Queue created (0x1818814) 13:51:59.430 conference.c Creating conference bridge with 254 ports 13:51:59.447 sip_endpoint.c Module "mod-evsub" registered 13:51:59.447 sip_endpoint.c Module "mod-presence" registered 13:51:59.447 evsub.c Event pkg "presence" registered by mod-presence 13:51:59.447 sip_endpoint.c Module "mod-mwi" registered 13:51:59.447 evsub.c Event pkg "message-summary" registered by mod-mwi 13:51:59.447 sip_endpoint.c Module "mod-refer" registered 13:51:59.447 evsub.c Event pkg "refer" registered by mod-refer 13:51:59.447 sip_endpoint.c Module "mod-pjsua-pres" registered 13:51:59.447 sip_endpoint.c Module "mod-pjsua-im" registered 13:51:59.447 sip_endpoint.c Module "mod-pjsua-options" registered 13:51:59.447 pjsua_core.c 1 SIP worker threads created 13:51:59.447 pjsua_core.c pjsua version 1.5 for i386-apple-darwin8.11.1 initialized 13:51:59.447 sip_endpoint.c Module "mod-default-handler" registered 13:51:59.448 pjsua_core.c SIP UDP socket reachable at 10.127.1.99:5060 13:51:59.448 sip_transport_ Error setting SO_RCVBUF: No buffer space available [120055] 13:51:59.448 sip_transport_ Error setting SO_SNDBUF: No buffer space available [120055] 13:51:59.448 udp0x181f400 SIP UDP transport started, published address is 10.127.1.99:5060 13:51:59.448 pjsua_acc.c Account added with id 0 13:51:59.448 tcplis:5060 SIP TCP listener ready for incoming connections at 10.127.1.99:5060 13:51:59.448 pjsua_acc.c Account added with id 1 13:51:59.448 pjsua_acc.c Account sip:4414@sip.example.com added with id 2 13:51:59.448 endpoint Request msg REGISTER/cseq=20721 (tdta0x182d400) created. 13:51:59.448 tsx0x182aa64 Transaction created for Request msg REGISTER/cseq=20722 (tdta0x182d400) 13:51:59.448 tsx0x182aa64 Sending Request msg REGISTER/cseq=20722 (tdta0x182d400) in state Null 13:51:59.448 sip_resolve.c DNS resolver not available, target 'sip.example.com:0' type=Unspecified will be resolved with gethostbyname() 13:51:59.449 sip_resolve.c Target 'sip.example.com:0' type=Unspecified resolved to '173.8.19.148:5060' type=UDP (UDP transport) 13:51:59.449 pjsua_core.c TX 460 bytes Request msg REGISTER/cseq=20722 (tdta0x182d400) to UDP 173.8.19.148:5060: REGISTER sip:sip.example.com SIP/2.0 Via: SIP/2.0/UDP 10.127.1.99:5060;rport;branch=z9hG4bKPj9129820D-75BA-404C-A8F4-0CD951872DF2 Max-Forwards: 70 From: ;tag=839BF07A-93EE-47D4-8008-4F019ACEB257 To: Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20722 REGISTER User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Contact: Expires: 300 Content-Length: 0 --end msg-- 13:51:59.449 tsx0x182aa64 State changed from Null to Calling, event=TX_MSG 13:51:59.449 pjsua_acc.c Registration sent 13:51:59.449 _krtp.c pjmedia_transport_krtp_create 13:51:59.449 _krtp.c Coalesce Count 3 3 13:51:59.450 _krtp.c pjmedia_transport_krtp_create 13:51:59.450 _krtp.c Coalesce Count 3 3 13:51:59.450 _krtp.c pjmedia_transport_krtp_create 13:51:59.450 _krtp.c Coalesce Count 3 3 13:51:59.450 _krtp.c pjmedia_transport_krtp_create 13:51:59.450 _krtp.c Coalesce Count 3 3 13:51:59.451 sip_endpoint.c Module "mod-unsolicited-mwi" registered 13:51:59.466 sip_endpoint.c Processing incoming message: Request msg OPTIONS/cseq=102 (rdata0x182b664) 13:51:59.466 pjsua_core.c RX 535 bytes Request msg OPTIONS/cseq=102 (rdata0x182b664) from UDP 173.8.19.148:5060: OPTIONS sip:4414@66.219.174.73:5060;transport=UDP SIP/2.0 Via: SIP/2.0/UDP 173.8.19.148:5060;branch=z9hG4bK568a3c49;rport From: "Unknown" ;tag=as2b1f0eea To: Contact: Call-ID: 7a08c2d543456a5b22e920cf1ebef829@173.8.19.148 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 30 Nov 2009 20:05:32 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --end msg-- 13:51:59.468 endpoint Response msg 200/OPTIONS/cseq=102 (tdta0x184e600) created 13:51:59.468 pjsua_core.c TX 1188 bytes Response msg 200/OPTIONS/cseq=102 (tdta0x184e600) to UDP 173.8.19.148:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 173.8.19.148:5060;rport=5060;received=173.8.19.148;branch=z9hG4bK568a3c49 Call-ID: 7a08c2d543456a5b22e920cf1ebef829@173.8.19.148 From: "Unknown" ;tag=as2b1f0eea To: CSeq: 102 OPTIONS Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Accept: application/sdp, application/pidf+xml, application/xpidf+xml, application/simple-message-summary, message/sipfrag;version=2.0, application/im-iscomposing+xml, text/plain Supported: replaces, 100rel, timer, norefersub Allow-Events: presence, message-summary, refer User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Content-Type: application/sdp Content-Length: 445 v=0 o=- 3468595919 3468595919 IN IP4 10.127.1.99 s=pjmedia c=IN IP4 10.127.1.99 t=0 0 m=audio 7000 RTP/AVP 103 102 104 113 3 0 8 9 101 a=rtcp:7001 IN IP4 10.127.1.99 a=rtpmap:103 speex/16000 a=rtpmap:102 speex/8000 a=rtpmap:104 speex/32000 a=rtpmap:113 iLBC/8000 a=fmtp:113 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=sendrecv a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 --end msg-- 13:51:59.469 tdta0x184e600 Destroying txdata Response msg 200/OPTIONS/cseq=102 (tdta0x184e600) 13:51:59.530 sip_endpoint.c Processing incoming message: Response msg 100/REGISTER/cseq=20722 (rdata0x182b664) 13:51:59.530 pjsua_core.c RX 496 bytes Response msg 100/REGISTER/cseq=20722 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.127.1.99:5060;branch=z9hG4bKPj9129820D-75BA-404C-A8F4-0CD951872DF2;received=66.219.174.73;rport=5060 From: ;tag=839BF07A-93EE-47D4-8008-4F019ACEB257 To: Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20722 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --end msg-- 13:51:59.530 tsx0x182aa64 Incoming Response msg 100/REGISTER/cseq=20722 (rdata0x182b664) in state Calling 13:51:59.530 tsx0x182aa64 State changed from Calling to Proceeding, event=RX_MSG 13:51:59.543 sip_endpoint.c Processing incoming message: Response msg 401/REGISTER/cseq=20722 (rdata0x182b664) 13:51:59.543 pjsua_core.c RX 559 bytes Response msg 401/REGISTER/cseq=20722 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.127.1.99:5060;branch=z9hG4bKPj9129820D-75BA-404C-A8F4-0CD951872DF2;received=66.219.174.73;rport=5060 From: ;tag=839BF07A-93EE-47D4-8008-4F019ACEB257 To: ;tag=as162917f2 Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20722 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7b9ec77c" Content-Length: 0 --end msg-- 13:51:59.543 tsx0x182aa64 Incoming Response msg 401/REGISTER/cseq=20722 (rdata0x182b664) in state Proceeding 13:51:59.543 tsx0x182aa64 State changed from Proceeding to Completed, event=RX_MSG 13:51:59.543 tsx0x184cc64 Transaction created for Request msg REGISTER/cseq=20723 (tdta0x182d400) 13:51:59.543 tsx0x184cc64 Sending Request msg REGISTER/cseq=20723 (tdta0x182d400) in state Null 13:51:59.543 endpoint Request msg REGISTER/cseq=20723 (tdta0x182d400): skipping target resolution because address is already set 13:51:59.543 pjsua_core.c TX 626 bytes Request msg REGISTER/cseq=20723 (tdta0x182d400) to UDP 173.8.19.148:5060: REGISTER sip:sip.example.com SIP/2.0 Via: SIP/2.0/UDP 10.127.1.99:5060;rport;branch=z9hG4bKPjACB650C5-94DC-49C5-BF67-DAC5015EA1DC Max-Forwards: 70 From: ;tag=839BF07A-93EE-47D4-8008-4F019ACEB257 To: Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20723 REGISTER User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Contact: Expires: 300 Authorization: Digest username="4414", realm="asterisk", nonce="7b9ec77c", uri="sip:sip.example.com", response="764f37e116d58c46c186b642f6ae4214", algorithm=MD5 Content-Length: 0 --end msg-- 13:51:59.545 tsx0x184cc64 State changed from Null to Calling, event=TX_MSG 13:51:59.628 sip_endpoint.c Processing incoming message: Response msg 100/REGISTER/cseq=20723 (rdata0x182b664) 13:51:59.628 pjsua_core.c RX 496 bytes Response msg 100/REGISTER/cseq=20723 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.127.1.99:5060;branch=z9hG4bKPjACB650C5-94DC-49C5-BF67-DAC5015EA1DC;received=66.219.174.73;rport=5060 From: ;tag=839BF07A-93EE-47D4-8008-4F019ACEB257 To: Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20723 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --end msg-- 13:51:59.628 tsx0x184cc64 Incoming Response msg 100/REGISTER/cseq=20723 (rdata0x182b664) in state Calling 13:51:59.628 tsx0x184cc64 State changed from Calling to Proceeding, event=RX_MSG 13:51:59.636 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=20723 (rdata0x182b664) 13:51:59.636 pjsua_core.c RX 574 bytes Response msg 200/REGISTER/cseq=20723 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.127.1.99:5060;branch=z9hG4bKPjACB650C5-94DC-49C5-BF67-DAC5015EA1DC;received=66.219.174.73;rport=5060 From: ;tag=839BF07A-93EE-47D4-8008-4F019ACEB257 To: ;tag=as162917f2 Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20723 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Expires: 300 Contact: ;expires=300 Date: Mon, 30 Nov 2009 20:05:36 GMT Content-Length: 0 --end msg-- 13:51:59.636 tsx0x184cc64 Incoming Response msg 200/REGISTER/cseq=20723 (rdata0x182b664) in state Proceeding 13:51:59.636 tsx0x184cc64 State changed from Proceeding to Completed, event=RX_MSG 13:51:59.636 pjsua_acc.c IP address change detected for account 2 (10.127.1.99:5060 --> 66.219.174.73:5060). Updating registration.. 13:51:59.636 endpoint Request msg REGISTER/cseq=20723 (tdta0x184e600) created. 13:51:59.636 tsx0x1832464 Transaction created for Request msg REGISTER/cseq=20724 (tdta0x184e600) 13:51:59.636 tsx0x1832464 Sending Request msg REGISTER/cseq=20724 (tdta0x184e600) in state Null 13:51:59.636 sip_resolve.c DNS resolver not available, target 'sip.example.com:0' type=Unspecified will be resolved with gethostbyname() 13:51:59.637 sip_resolve.c Target 'sip.example.com:0' type=Unspecified resolved to '173.8.19.148:5060' type=UDP (UDP transport) 13:51:59.637 pjsua_core.c TX 458 bytes Request msg REGISTER/cseq=20724 (tdta0x184e600) to UDP 173.8.19.148:5060: REGISTER sip:sip.example.com SIP/2.0 Via: SIP/2.0/UDP 10.127.1.99:5060;rport;branch=z9hG4bKPj09C90333-6431-4828-A85C-E98C601E7EE8 Max-Forwards: 70 From: ;tag=176FAB28-04BC-47DE-B0BF-3621A4D4DD06 To: Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20724 REGISTER User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Contact: Expires: 0 Content-Length: 0 --end msg-- 13:51:59.637 tsx0x1832464 State changed from Null to Calling, event=TX_MSG 13:51:59.637 pjsua_acc.c Unregistration sent 13:51:59.637 endpoint Request msg REGISTER/cseq=10541 (tdta0x1851200) created. 13:51:59.637 tsx0x1852264 Transaction created for Request msg REGISTER/cseq=10542 (tdta0x1851200) 13:51:59.637 tsx0x1852264 Sending Request msg REGISTER/cseq=10542 (tdta0x1851200) in state Null 13:51:59.637 sip_resolve.c DNS resolver not available, target 'sip.example.com:0' type=Unspecified will be resolved with gethostbyname() 13:51:59.638 sip_resolve.c Target 'sip.example.com:0' type=Unspecified resolved to '173.8.19.148:5060' type=UDP (UDP transport) 13:51:59.638 pjsua_core.c TX 478 bytes Request msg REGISTER/cseq=10542 (tdta0x1851200) to UDP 173.8.19.148:5060: REGISTER sip:sip.example.com SIP/2.0 Via: SIP/2.0/UDP 66.219.174.73:5060;rport;branch=z9hG4bKPjD761F86B-AB0F-4D8D-B60F-A88FD7451411 Max-Forwards: 70 From: ;tag=A92223E5-41C4-432F-8607-021E6F4E5545 To: Call-ID: A26ED88D-5038-4611-9079-6D79B6D67AC7 CSeq: 10542 REGISTER User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Contact: Expires: 300 Content-Length: 0 --end msg-- 13:51:59.639 tsx0x1852264 State changed from Null to Calling, event=TX_MSG 13:51:59.639 pjsua_acc.c Registration sent 13:51:59.718 sip_endpoint.c Processing incoming message: Response msg 100/REGISTER/cseq=20724 (rdata0x182b664) 13:51:59.718 pjsua_core.c RX 496 bytes Response msg 100/REGISTER/cseq=20724 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.127.1.99:5060;branch=z9hG4bKPj09C90333-6431-4828-A85C-E98C601E7EE8;received=66.219.174.73;rport=5060 From: ;tag=176FAB28-04BC-47DE-B0BF-3621A4D4DD06 To: Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20724 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --end msg-- 13:51:59.718 tsx0x1832464 Incoming Response msg 100/REGISTER/cseq=20724 (rdata0x182b664) in state Calling 13:51:59.718 tsx0x1832464 State changed from Calling to Proceeding, event=RX_MSG 13:51:59.741 sip_endpoint.c Processing incoming message: Response msg 401/REGISTER/cseq=20724 (rdata0x182b664) 13:51:59.741 pjsua_core.c RX 559 bytes Response msg 401/REGISTER/cseq=20724 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.127.1.99:5060;branch=z9hG4bKPj09C90333-6431-4828-A85C-E98C601E7EE8;received=66.219.174.73;rport=5060 From: ;tag=176FAB28-04BC-47DE-B0BF-3621A4D4DD06 To: ;tag=as162917f2 Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20724 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="3b0ae503" Content-Length: 0 --end msg-- 13:51:59.742 tsx0x1832464 Incoming Response msg 401/REGISTER/cseq=20724 (rdata0x182b664) in state Proceeding 13:51:59.742 tsx0x1832464 State changed from Proceeding to Completed, event=RX_MSG 13:51:59.742 tsx0x1853a64 Transaction created for Request msg REGISTER/cseq=20725 (tdta0x184e600) 13:51:59.742 tsx0x1853a64 Sending Request msg REGISTER/cseq=20725 (tdta0x184e600) in state Null 13:51:59.742 endpoint Request msg REGISTER/cseq=20725 (tdta0x184e600): skipping target resolution because address is already set 13:51:59.742 pjsua_core.c TX 626 bytes Request msg REGISTER/cseq=20725 (tdta0x184e600) to UDP 173.8.19.148:5060: REGISTER sip:sip.example.com SIP/2.0 Via: SIP/2.0/UDP 66.219.174.73:5060;rport;branch=z9hG4bKPj9080803E-6446-4AC4-AAFF-4A9C62864BCE Max-Forwards: 70 From: ;tag=176FAB28-04BC-47DE-B0BF-3621A4D4DD06 To: Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20725 REGISTER User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Contact: Expires: 0 Authorization: Digest username="4414", realm="asterisk", nonce="3b0ae503", uri="sip:sip.example.com", response="c93d5de22b04304d1c7c9debdd44fcae", algorithm=MD5 Content-Length: 0 --end msg-- 13:51:59.743 tsx0x1853a64 State changed from Null to Calling, event=TX_MSG 13:51:59.755 sip_endpoint.c Processing incoming message: Response msg 100/REGISTER/cseq=10542 (rdata0x182b664) 13:51:59.755 pjsua_core.c RX 498 bytes Response msg 100/REGISTER/cseq=10542 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 66.219.174.73:5060;branch=z9hG4bKPjD761F86B-AB0F-4D8D-B60F-A88FD7451411;received=66.219.174.73;rport=5060 From: ;tag=A92223E5-41C4-432F-8607-021E6F4E5545 To: Call-ID: A26ED88D-5038-4611-9079-6D79B6D67AC7 CSeq: 10542 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --end msg-- 13:51:59.755 tsx0x1852264 Incoming Response msg 100/REGISTER/cseq=10542 (rdata0x182b664) in state Calling 13:51:59.755 tsx0x1852264 State changed from Calling to Proceeding, event=RX_MSG 13:51:59.755 sip_endpoint.c Processing incoming message: Response msg 401/REGISTER/cseq=10542 (rdata0x182b664) 13:51:59.755 pjsua_core.c RX 561 bytes Response msg 401/REGISTER/cseq=10542 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 66.219.174.73:5060;branch=z9hG4bKPjD761F86B-AB0F-4D8D-B60F-A88FD7451411;received=66.219.174.73;rport=5060 From: ;tag=A92223E5-41C4-432F-8607-021E6F4E5545 To: ;tag=as5f1b651c Call-ID: A26ED88D-5038-4611-9079-6D79B6D67AC7 CSeq: 10542 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="45b32568" Content-Length: 0 --end msg-- 13:51:59.756 tsx0x1852264 Incoming Response msg 401/REGISTER/cseq=10542 (rdata0x182b664) in state Proceeding 13:51:59.756 tsx0x1852264 State changed from Proceeding to Completed, event=RX_MSG 13:51:59.756 tsx0x1854264 Transaction created for Request msg REGISTER/cseq=10543 (tdta0x1851200) 13:51:59.756 tsx0x1854264 Sending Request msg REGISTER/cseq=10543 (tdta0x1851200) in state Null 13:51:59.756 endpoint Request msg REGISTER/cseq=10543 (tdta0x1851200): skipping target resolution because address is already set 13:51:59.756 pjsua_core.c TX 644 bytes Request msg REGISTER/cseq=10543 (tdta0x1851200) to UDP 173.8.19.148:5060: REGISTER sip:sip.example.com SIP/2.0 Via: SIP/2.0/UDP 66.219.174.73:5060;rport;branch=z9hG4bKPj4DF6E5BA-2B4D-4514-B296-C04172DB98C1 Max-Forwards: 70 From: ;tag=A92223E5-41C4-432F-8607-021E6F4E5545 To: Call-ID: A26ED88D-5038-4611-9079-6D79B6D67AC7 CSeq: 10543 REGISTER User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Contact: Expires: 300 Authorization: Digest username="4414", realm="asterisk", nonce="45b32568", uri="sip:sip.example.com", response="9d376d7aeb00a86d1576d5ba58bb27b0", algorithm=MD5 Content-Length: 0 --end msg-- 13:51:59.761 tsx0x1854264 State changed from Null to Calling, event=TX_MSG 13:51:59.838 sip_endpoint.c Processing incoming message: Response msg 100/REGISTER/cseq=20725 (rdata0x182b664) 13:51:59.838 pjsua_core.c RX 498 bytes Response msg 100/REGISTER/cseq=20725 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 66.219.174.73:5060;branch=z9hG4bKPj9080803E-6446-4AC4-AAFF-4A9C62864BCE;received=66.219.174.73;rport=5060 From: ;tag=176FAB28-04BC-47DE-B0BF-3621A4D4DD06 To: Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20725 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --end msg-- 13:51:59.839 tsx0x1853a64 Incoming Response msg 100/REGISTER/cseq=20725 (rdata0x182b664) in state Calling 13:51:59.839 tsx0x1853a64 State changed from Calling to Proceeding, event=RX_MSG 13:51:59.844 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=20725 (rdata0x182b664) 13:51:59.844 pjsua_core.c RX 572 bytes Response msg 200/REGISTER/cseq=20725 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 66.219.174.73:5060;branch=z9hG4bKPj9080803E-6446-4AC4-AAFF-4A9C62864BCE;received=66.219.174.73;rport=5060 From: ;tag=176FAB28-04BC-47DE-B0BF-3621A4D4DD06 To: ;tag=as162917f2 Call-ID: 7705D8EA-1E47-4A25-9D45-3C90A3A92121 CSeq: 20725 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Expires: 300 Contact: ;expires=300 Date: Mon, 30 Nov 2009 20:05:37 GMT Content-Length: 0 --end msg-- 13:51:59.844 tsx0x1853a64 Incoming Response msg 200/REGISTER/cseq=20725 (rdata0x182b664) in state Proceeding 13:51:59.844 tsx0x1853a64 State changed from Proceeding to Completed, event=RX_MSG 13:51:59.849 sip_endpoint.c Processing incoming message: Response msg 100/REGISTER/cseq=10543 (rdata0x182b664) 13:51:59.849 pjsua_core.c RX 498 bytes Response msg 100/REGISTER/cseq=10543 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 66.219.174.73:5060;branch=z9hG4bKPj4DF6E5BA-2B4D-4514-B296-C04172DB98C1;received=66.219.174.73;rport=5060 From: ;tag=A92223E5-41C4-432F-8607-021E6F4E5545 To: Call-ID: A26ED88D-5038-4611-9079-6D79B6D67AC7 CSeq: 10543 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --end msg-- 13:51:59.850 tsx0x1854264 Incoming Response msg 100/REGISTER/cseq=10543 (rdata0x182b664) in state Calling 13:51:59.850 tsx0x1854264 State changed from Calling to Proceeding, event=RX_MSG 13:51:59.853 sip_endpoint.c Processing incoming message: Request msg OPTIONS/cseq=102 (rdata0x182b664) 13:51:59.853 pjsua_core.c RX 535 bytes Request msg OPTIONS/cseq=102 (rdata0x182b664) from UDP 173.8.19.148:5060: OPTIONS sip:4414@66.219.174.73:5060;transport=UDP SIP/2.0 Via: SIP/2.0/UDP 173.8.19.148:5060;branch=z9hG4bK4a5f7940;rport From: "Unknown" ;tag=as5015fc6b To: Contact: Call-ID: 6c2e04cb05fa73f517d318c751ec5668@173.8.19.148 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 30 Nov 2009 20:05:37 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --end msg-- 13:51:59.855 endpoint Response msg 200/OPTIONS/cseq=102 (tdta0x1854a00) created 13:51:59.855 pjsua_core.c TX 1188 bytes Response msg 200/OPTIONS/cseq=102 (tdta0x1854a00) to UDP 173.8.19.148:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 173.8.19.148:5060;rport=5060;received=173.8.19.148;branch=z9hG4bK4a5f7940 Call-ID: 6c2e04cb05fa73f517d318c751ec5668@173.8.19.148 From: "Unknown" ;tag=as5015fc6b To: CSeq: 102 OPTIONS Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Accept: application/sdp, application/pidf+xml, application/xpidf+xml, application/simple-message-summary, message/sipfrag;version=2.0, application/im-iscomposing+xml, text/plain Supported: replaces, 100rel, timer, norefersub Allow-Events: presence, message-summary, refer User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Content-Type: application/sdp Content-Length: 445 v=0 o=- 3468595919 3468595919 IN IP4 10.127.1.99 s=pjmedia c=IN IP4 10.127.1.99 t=0 0 m=audio 7000 RTP/AVP 103 102 104 113 3 0 8 9 101 a=rtcp:7001 IN IP4 10.127.1.99 a=rtpmap:103 speex/16000 a=rtpmap:102 speex/8000 a=rtpmap:104 speex/32000 a=rtpmap:113 iLBC/8000 a=fmtp:113 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=sendrecv a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 --end msg-- 13:51:59.856 tdta0x1854a00 Destroying txdata Response msg 200/OPTIONS/cseq=102 (tdta0x1854a00) 13:51:59.859 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=10543 (rdata0x182b664) 13:51:59.859 pjsua_core.c RX 592 bytes Response msg 200/REGISTER/cseq=10543 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 66.219.174.73:5060;branch=z9hG4bKPj4DF6E5BA-2B4D-4514-B296-C04172DB98C1;received=66.219.174.73;rport=5060 From: ;tag=A92223E5-41C4-432F-8607-021E6F4E5545 To: ;tag=as5f1b651c Call-ID: A26ED88D-5038-4611-9079-6D79B6D67AC7 CSeq: 10543 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Expires: 300 Contact: ;expires=300 Date: Mon, 30 Nov 2009 20:05:37 GMT Content-Length: 0 --end msg-- 13:51:59.860 tsx0x1854264 Incoming Response msg 200/REGISTER/cseq=10543 (rdata0x182b664) in state Proceeding 13:51:59.860 tsx0x1854264 State changed from Proceeding to Completed, event=RX_MSG 13:51:59.860 pjsua_acc.c sip:4414@sip.example.com: registration success, status=200 (OK), will re-register in 300 seconds 13:51:59.860 pjsua_acc.c Keep-alive timer started for acc 2, destination:173.8.19.148:5060, interval:15s 13:52:04.548 tsx0x182aa64 Timeout timer event 13:52:04.548 tsx0x182aa64 State changed from Completed to Terminated, event=TIMER 13:52:04.548 tsx0x182aa64 Timeout timer event 13:52:04.548 tsx0x182aa64 State changed from Terminated to Destroyed, event=TIMER 13:52:04.548 tsx0x182aa64 Transaction destroyed! 13:52:04.639 tsx0x184cc64 Timeout timer event 13:52:04.639 tsx0x184cc64 State changed from Completed to Terminated, event=TIMER 13:52:04.639 tsx0x184cc64 Timeout timer event 13:52:04.639 tsx0x184cc64 State changed from Terminated to Destroyed, event=TIMER 13:52:04.639 tdta0x182d400 Destroying txdata Request msg REGISTER/cseq=20723 (tdta0x182d400) 13:52:04.639 tsx0x184cc64 Transaction destroyed! 13:52:04.750 tsx0x1832464 Timeout timer event 13:52:04.750 tsx0x1832464 State changed from Completed to Terminated, event=TIMER 13:52:04.750 tsx0x1832464 Timeout timer event 13:52:04.750 tsx0x1832464 State changed from Terminated to Destroyed, event=TIMER 13:52:04.750 tsx0x1832464 Transaction destroyed! 13:52:04.770 tsx0x1852264 Timeout timer event 13:52:04.770 tsx0x1852264 State changed from Completed to Terminated, event=TIMER 13:52:04.770 tsx0x1852264 Timeout timer event 13:52:04.770 tsx0x1852264 State changed from Terminated to Destroyed, event=TIMER 13:52:04.770 tsx0x1852264 Transaction destroyed! 13:52:04.851 tsx0x1853a64 Timeout timer event 13:52:04.851 tsx0x1853a64 State changed from Completed to Terminated, event=TIMER 13:52:04.851 tsx0x1853a64 Timeout timer event 13:52:04.851 tsx0x1853a64 State changed from Terminated to Destroyed, event=TIMER 13:52:04.851 tdta0x184e600 Destroying txdata Request msg REGISTER/cseq=20725 (tdta0x184e600) 13:52:04.851 tsx0x1853a64 Transaction destroyed! 13:52:04.861 tsx0x1854264 Timeout timer event 13:52:04.861 tsx0x1854264 State changed from Completed to Terminated, event=TIMER 13:52:04.861 tsx0x1854264 Timeout timer event 13:52:04.861 tsx0x1854264 State changed from Terminated to Destroyed, event=TIMER 13:52:04.861 tdta0x1851200 Destroying txdata Request msg REGISTER/cseq=10543 (tdta0x1851200) 13:52:04.861 tsx0x1854264 Transaction destroyed! 13:52:08.563 sip_endpoint.c Processing incoming message: Request msg NOTIFY/cseq=102 (rdata0x182b664) 13:52:08.563 pjsua_core.c RX 570 bytes Request msg NOTIFY/cseq=102 (rdata0x182b664) from UDP 173.8.19.148:5060: NOTIFY sip:4414@66.219.174.73:5060;transport=UDP SIP/2.0 Via: SIP/2.0/UDP 173.8.19.148:5060;branch=z9hG4bK1f9c067f;rport From: "Unknown" ;tag=as384a6eed To: Contact: Call-ID: 5016fcaa35e173d35a6a287148031607@173.8.19.148 CSeq: 102 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: message-summary Content-Type: application/simple-message-summary Content-Length: 88 Messages-Waiting: yes Message-Account: sip:*86@173.8.19.148 Voice-Message: 6/0 (0/0) --end msg-- 13:52:08.563 endpoint Response msg 200/NOTIFY/cseq=102 (tdta0x1854a00) created 13:52:08.563 tsx0x1854264 Transaction created for Request msg NOTIFY/cseq=102 (rdata0x182b664) 13:52:08.563 tsx0x1854264 Incoming Request msg NOTIFY/cseq=102 (rdata0x182b664) in state Null 13:52:08.563 tsx0x1854264 State changed from Null to Trying, event=RX_MSG 13:52:08.563 tsx0x1854264 Sending Response msg 200/NOTIFY/cseq=102 (tdta0x1854a00) in state Trying 13:52:08.563 pjsua_core.c TX 293 bytes Response msg 200/NOTIFY/cseq=102 (tdta0x1854a00) to UDP 173.8.19.148:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 173.8.19.148:5060;rport=5060;received=173.8.19.148;branch=z9hG4bK1f9c067f Call-ID: 5016fcaa35e173d35a6a287148031607@173.8.19.148 From: "Unknown" ;tag=as384a6eed To: CSeq: 102 NOTIFY Content-Length: 0 --end msg-- 13:52:08.563 tsx0x1854264 State changed from Trying to Completed, event=TX_MSG 13:52:08.563 pjsua_app.c Received MWI for acc 2: 13:52:08.563 pjsua_app.c Content-Type: application/simple-message-summary 13:52:08.563 pjsua_app.c Body: Messages-Waiting: yes Message-Account: sip:*86@173.8.19.148 Voice-Message: 6/0 (0/0) 13:52:14.862 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 173.8.19.148:5060 13:52:14.862 tdta0x1855a00 Destroying txdata raw 13:52:29.864 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 173.8.19.148:5060 13:52:29.864 tdta0x1855a00 Destroying txdata raw 13:52:40.571 tsx0x1854264 Timeout timer event 13:52:40.571 tsx0x1854264 State changed from Completed to Terminated, event=TIMER 13:52:40.571 tsx0x1854264 Timeout timer event 13:52:40.571 tsx0x1854264 State changed from Terminated to Destroyed, event=TIMER 13:52:40.571 tdta0x1854a00 Destroying txdata Response msg 200/NOTIFY/cseq=102 (tdta0x1854a00) 13:52:40.571 tsx0x1854264 Transaction destroyed! 13:52:44.871 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 173.8.19.148:5060 13:52:44.871 tdta0x1854a00 Destroying txdata raw 13:52:59.878 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 173.8.19.148:5060 13:52:59.878 tdta0x1854a00 Destroying txdata raw 13:52:59.938 sip_endpoint.c Processing incoming message: Request msg OPTIONS/cseq=102 (rdata0x182b664) 13:52:59.938 pjsua_core.c RX 535 bytes Request msg OPTIONS/cseq=102 (rdata0x182b664) from UDP 173.8.19.148:5060: OPTIONS sip:4414@66.219.174.73:5060;transport=UDP SIP/2.0 Via: SIP/2.0/UDP 173.8.19.148:5060;branch=z9hG4bK5ec1d028;rport From: "Unknown" ;tag=as4291dcf9 To: Contact: Call-ID: 69f0bc3119e0ebd3607fe77112917f5b@173.8.19.148 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 30 Nov 2009 20:06:37 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --end msg-- 13:52:59.939 endpoint Response msg 200/OPTIONS/cseq=102 (tdta0x1855a00) created 13:52:59.939 pjsua_core.c TX 1188 bytes Response msg 200/OPTIONS/cseq=102 (tdta0x1855a00) to UDP 173.8.19.148:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 173.8.19.148:5060;rport=5060;received=173.8.19.148;branch=z9hG4bK5ec1d028 Call-ID: 69f0bc3119e0ebd3607fe77112917f5b@173.8.19.148 From: "Unknown" ;tag=as4291dcf9 To: CSeq: 102 OPTIONS Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Accept: application/sdp, application/pidf+xml, application/xpidf+xml, application/simple-message-summary, message/sipfrag;version=2.0, application/im-iscomposing+xml, text/plain Supported: replaces, 100rel, timer, norefersub Allow-Events: presence, message-summary, refer User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Content-Type: application/sdp Content-Length: 445 v=0 o=- 3468595979 3468595979 IN IP4 10.127.1.99 s=pjmedia c=IN IP4 10.127.1.99 t=0 0 m=audio 7000 RTP/AVP 103 102 104 113 3 0 8 9 101 a=rtcp:7001 IN IP4 10.127.1.99 a=rtpmap:103 speex/16000 a=rtpmap:102 speex/8000 a=rtpmap:104 speex/32000 a=rtpmap:113 iLBC/8000 a=fmtp:113 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=sendrecv a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 --end msg-- 13:52:59.940 tdta0x1855a00 Destroying txdata Response msg 200/OPTIONS/cseq=102 (tdta0x1855a00) 13:53:10.153 pjsua_media.c Opening sound device PCM@16000/1/20ms 13:53:10.882 pa_dev.c Opened device Built-in Microphone(Core Audio)/Built-in Output(Core Audio) for recording and playback, sample rate=16000, ch=1, bits=16, 320 samples per frame, input latency=0 ms, output latency=0 ms 13:53:10.882 ec0x4c2880 Creating AEC 13:53:10.883 ec0x4c2880 AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=20 ms 13:53:10.883 pa_dev.c Starting Built-in Microphone stream.. 13:53:10.884 pa_dev.c Done, status=0 13:53:10.884 pjsua_call.c Making call with acc #2 to sip:3304128820@sip.example.com 13:53:10.884 dlg0x187e064 UAC dialog created 13:53:10.884 pjsua_media.c Media index 0 selected for call 0 13:53:10.884 _krtp.c transport_media_create: offerer=1 use=1 13:53:10.884 _krtp.c media_create krtp: 0x182b264 handle: 10000 13:53:10.891 pa_dev.c Player thread started 13:53:10.905 ec0x4c2880 Latency bufferring complete 13:53:10.906 pa_dev.c Recorder thread started 13:53:11.190 _krtp.c TC Card Connected 13:53:11.312 _krtp.c TC Profiles found: 1 13:53:11.352 _krtp.c TCB Session Created 13:53:11.353 _krtp.c TC Session Created 13:53:11.353 dlg0x187e064 Module mod-invite added as dialog usage, data=0x1885414 13:53:11.353 dlg0x187e064 Session count inc to 3 by mod-invite 13:53:11.353 dlg0x187e064 Module mod-100rel added as dialog usage, data=0x187e7f4 13:53:11.353 dlg0x187e064 100rel module attached 13:53:11.353 inv0x187e064 UAC invite session created for dialog dlg0x187e064 13:53:11.353 endpoint Request msg INVITE/cseq=4313 (tdta0x1886400) created. 13:53:11.353 inv0x187e064 Sending Request msg INVITE/cseq=4313 (tdta0x1886400) 13:53:11.353 dlg0x187e064 Sending Request msg INVITE/cseq=4313 (tdta0x1886400) 13:53:11.353 tsx0x1888464 Transaction created for Request msg INVITE/cseq=4312 (tdta0x1886400) 13:53:11.353 tsx0x1888464 Sending Request msg INVITE/cseq=4312 (tdta0x1886400) in state Null 13:53:11.353 sip_resolve.c DNS resolver not available, target 'sip.example.com:0' type=Unspecified will be resolved with gethostbyname() 13:53:11.353 sip_resolve.c Target 'sip.example.com:0' type=Unspecified resolved to '173.8.19.148:5060' type=UDP (UDP transport) 13:53:11.354 pjsua_core.c TX 1137 bytes Request msg INVITE/cseq=4312 (tdta0x1886400) to UDP 173.8.19.148:5060: INVITE sip:3304128820@sip.example.com SIP/2.0 Via: SIP/2.0/UDP 66.219.174.73:5060;rport;branch=z9hG4bKPj5C9AD436-16CC-4DFE-9C53-21CA51F84ECD Max-Forwards: 70 From: sip:4414@sip.example.com;tag=D1F45773-0B5E-4155-B6C0-AF7E0DBDA1B3 To: sip:3304128820@sip.example.com Contact: Call-ID: 3B1E22FB-3601-4D79-82F1-68C22970B95C CSeq: 4312 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub Session-Expires: 1800 Min-SE: 90 User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Content-Type: application/sdp Content-Length: 456 v=0 o=- 3468595991 3468595991 IN IP4 10.127.1.99 s=pjmedia c=IN IP4 10.127.1.99 t=0 0 a=X-nat:0 m=audio 7000 RTP/AVP 103 102 104 113 3 0 8 9 101 a=rtcp:7001 IN IP4 10.127.1.99 a=rtpmap:103 speex/16000 a=rtpmap:102 speex/8000 a=rtpmap:104 speex/32000 a=rtpmap:113 iLBC/8000 a=fmtp:113 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=sendrecv a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 --end msg-- 13:53:11.355 tsx0x1888464 State changed from Null to Calling, event=TX_MSG 13:53:11.355 dlg0x187e064 Transaction tsx0x1888464 state changed to Calling 13:53:11.355 pjsua_app.c Call 0 state changed to CALLING 13:53:11.453 sip_endpoint.c Processing incoming message: Response msg 407/INVITE/cseq=4312 (rdata0x182b664) 13:53:11.453 pjsua_core.c RX 579 bytes Response msg 407/INVITE/cseq=4312 (rdata0x182b664) from UDP 173.8.19.148:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 66.219.174.73:5060;branch=z9hG4bKPj5C9AD436-16CC-4DFE-9C53-21CA51F84ECD;received=66.219.174.73;rport=5060 From: sip:4414@sip.example.com;tag=D1F45773-0B5E-4155-B6C0-AF7E0DBDA1B3 To: sip:3304128820@sip.example.com;tag=as344909d4 Call-ID: 3B1E22FB-3601-4D79-82F1-68C22970B95C CSeq: 4312 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="0f1f398a" Content-Length: 0 --end msg-- 13:53:11.453 tsx0x1888464 Incoming Response msg 407/INVITE/cseq=4312 (rdata0x182b664) in state Calling 13:53:11.453 endpoint Request msg ACK/cseq=4312 (tdta0x1889c00) created. 13:53:11.453 pjsua_core.c TX 379 bytes Request msg ACK/cseq=4312 (tdta0x1889c00) to UDP 173.8.19.148:5060: ACK sip:3304128820@sip.example.com SIP/2.0 Via: SIP/2.0/UDP 66.219.174.73:5060;rport;branch=z9hG4bKPj5C9AD436-16CC-4DFE-9C53-21CA51F84ECD Max-Forwards: 70 From: sip:4414@sip.example.com;tag=D1F45773-0B5E-4155-B6C0-AF7E0DBDA1B3 To: sip:3304128820@sip.example.com;tag=as344909d4 Call-ID: 3B1E22FB-3601-4D79-82F1-68C22970B95C CSeq: 4312 ACK Content-Length: 0 --end msg-- 13:53:11.453 tsx0x1888464 State changed from Calling to Completed, event=RX_MSG 13:53:11.453 dlg0x187e064 Received Response msg 407/INVITE/cseq=4312 (rdata0x182b664) 13:53:11.453 dlg0x187e064 Transaction tsx0x1888464 state changed to Completed 13:53:11.453 inv0x187e064 Sending Request msg INVITE/cseq=4312 (tdta0x1886400) 13:53:11.453 dlg0x187e064 Sending Request msg INVITE/cseq=4312 (tdta0x1886400) 13:53:11.454 tsx0x188bc64 Transaction created for Request msg INVITE/cseq=4313 (tdta0x1886400) 13:53:11.454 tsx0x188bc64 Sending Request msg INVITE/cseq=4313 (tdta0x1886400) in state Null 13:53:11.454 endpoint Request msg INVITE/cseq=4313 (tdta0x1886400): skipping target resolution because address is already set 13:53:11.454 endpoint Request msg INVITE/cseq=4313 (tdta0x1886400) exceeds UDP size threshold (1300), sending with TCP 13:53:11.454 tcpc0x188c414 TCP client transport created 13:53:11.454 tcpc0x188c414 TCP transport 10.127.1.99:49241 is connecting to 173.8.19.148:5060... 13:53:11.454 pjsua_core.c TX 1319 bytes Request msg INVITE/cseq=4313 (tdta0x1886400) to tcp 173.8.19.148:5060: INVITE sip:3304128820@sip.example.com SIP/2.0 Via: SIP/2.0/TCP 10.127.1.99:49241;rport;branch=z9hG4bKPj576EEE7D-0CB6-4BF5-8B44-01ED3D1755A8 Max-Forwards: 70 From: sip:4414@sip.example.com;tag=D1F45773-0B5E-4155-B6C0-AF7E0DBDA1B3 To: sip:3304128820@sip.example.com Contact: Call-ID: 3B1E22FB-3601-4D79-82F1-68C22970B95C CSeq: 4313 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub Session-Expires: 1800 Min-SE: 90 User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Proxy-Authorization: Digest username="4414", realm="asterisk", nonce="0f1f398a", uri="sip:3304128820@sip.example.com", response="1e53a05dc75c8578029616a0fc288138", algorithm=MD5 Content-Type: application/sdp Content-Length: 456 v=0 o=- 3468595991 3468595991 IN IP4 10.127.1.99 s=pjmedia c=IN IP4 10.127.1.99 t=0 0 a=X-nat:0 m=audio 7000 RTP/AVP 103 102 104 113 3 0 8 9 101 a=rtcp:7001 IN IP4 10.127.1.99 a=rtpmap:103 speex/16000 a=rtpmap:102 speex/8000 a=rtpmap:104 speex/32000 a=rtpmap:113 iLBC/8000 a=fmtp:113 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=sendrecv a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 --end msg-- 13:53:11.455 tsx0x188bc64 State changed from Null to Calling, event=TX_MSG 13:53:11.455 dlg0x187e064 Transaction tsx0x188bc64 state changed to Calling 13:53:11.455 pjsua_app.c Call 0 state changed to CALLING 13:53:14.881 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 173.8.19.148:5060 13:53:14.881 tdta0x1888c00 Destroying txdata raw 13:53:15.890 sound_port.c EC suspended because of inactivity 13:53:29.883 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 173.8.19.148:5060 13:53:29.883 tdta0x1888c00 Destroying txdata raw 13:53:43.461 tsx0x188bc64 Timeout timer event 13:53:43.461 tsx0x188bc64 State changed from Calling to Terminated, event=TIMER 13:53:43.461 dlg0x187e064 Transaction tsx0x188bc64 state changed to Terminated 13:53:43.461 pjsua_app.c Call 0 is DISCONNECTED [reason=408 (Request Timeout)] 13:53:43.462 pjsua_app.c Call 0 disconnected, dumping media stats.. 13:53:43.462 pjsua_app.c [DISCONNCTD] To: sip:3304128820@sip.example.com Call time: 00h:00m:00s, 1st res in 32577 ms, conn in 0ms SRTP status: Not active Crypto-suite: (null) 13:53:43.462 _krtp.c transport_media_stop 13:53:43.508 _krtp.c TCB Session Destroyed 13:53:43.509 _krtp.c Last RTP packet Rx 33 seconds ago 13:53:43.510 _krtp.c Last RTP packet Tx 33 seconds ago 13:53:43.510 _krtp.c Stats - Pkts Rx: 0 Tx: 0 Dscrd Rx: 0 Tx: 0 13:53:43.510 _krtp.c Pkts Delivered: 0 Probes: 0 DupRx: 0 13:53:43.510 _krtp.c Cipher Rx: 0 Tx: 0 Error Rx: 0 Tx: 0 13:53:43.511 _krtp.c OOO Pkts: 0 Seg: 0 Discrd: 0 Lost: 0 13:53:43.511 _krtp.c RX Net Interval min/max/avg: 60 0 0 13:53:43.511 _krtp.c RX Dlvr Interval min/max/avg: 60 0 0 13:53:43.511 _krtp.c TX Interval min/max/avg: 20 0 0 13:53:43.511 _krtp.c Audio Underrun c/m/m/a: 0 -1 0 0 13:53:43.511 _krtp.c Audio Buffered max/avg: 0 -1 13:53:43.511 _krtp.c Tx Audio Frames req/pro: 0 0 13:53:43.511 _krtp.c Coalesce Factor: 3 13:53:43.512 dlg0x187e064 Session count dec to 1 by mod-invite 13:53:43.512 tsx0x1888464 Timeout timer event 13:53:43.512 tsx0x1888464 State changed from Completed to Terminated, event=TIMER 13:53:43.512 dlg0x187e064 Transaction tsx0x1888464 state changed to Terminated 13:53:43.512 dlg0x187e064 Dialog destroyed 13:53:43.522 tsx0x1888464 Timeout timer event 13:53:43.522 tsx0x1888464 State changed from Terminated to Destroyed, event=TIMER 13:53:43.522 tdta0x1889c00 Destroying txdata Request msg ACK/cseq=4312 (tdta0x1889c00) 13:53:43.522 tsx0x1888464 Transaction destroyed! 13:53:44.514 pjsua_media.c Closing sound device after idle for 1 seconds 13:53:44.514 pjsua_media.c Closing Built-in Output sound playback device and Built-in Microphone sound capture device 13:53:44.535 pa_dev.c Stopping stream.. 13:53:44.761 pa_dev.c Done, status=0 13:53:44.761 pa_dev.c Closing Built-in Microphone: 0 underflow, 0 overflow 13:53:44.883 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 173.8.19.148:5060 13:53:44.883 tdta0x182d000 Destroying txdata raw 13:53:59.892 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 173.8.19.148:5060 13:53:59.892 tdta0x182d000 Destroying txdata raw 13:54:00.030 sip_endpoint.c Processing incoming message: Request msg OPTIONS/cseq=102 (rdata0x182b664) 13:54:00.030 pjsua_core.c RX 535 bytes Request msg OPTIONS/cseq=102 (rdata0x182b664) from UDP 173.8.19.148:5060: OPTIONS sip:4414@66.219.174.73:5060;transport=UDP SIP/2.0 Via: SIP/2.0/UDP 173.8.19.148:5060;branch=z9hG4bK6e69bf4b;rport From: "Unknown" ;tag=as11d5bf32 To: Contact: Call-ID: 725c2f5f6bbdf1ca27b4974e6d62bd72@173.8.19.148 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 30 Nov 2009 20:07:37 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --end msg-- 13:54:00.030 endpoint Response msg 200/OPTIONS/cseq=102 (tdta0x185ac00) created 13:54:00.030 pjsua_core.c TX 1188 bytes Response msg 200/OPTIONS/cseq=102 (tdta0x185ac00) to UDP 173.8.19.148:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 173.8.19.148:5060;rport=5060;received=173.8.19.148;branch=z9hG4bK6e69bf4b Call-ID: 725c2f5f6bbdf1ca27b4974e6d62bd72@173.8.19.148 From: "Unknown" ;tag=as11d5bf32 To: CSeq: 102 OPTIONS Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Accept: application/sdp, application/pidf+xml, application/xpidf+xml, application/simple-message-summary, message/sipfrag;version=2.0, application/im-iscomposing+xml, text/plain Supported: replaces, 100rel, timer, norefersub Allow-Events: presence, message-summary, refer User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Content-Type: application/sdp Content-Length: 445 v=0 o=- 3468596040 3468596040 IN IP4 10.127.1.99 s=pjmedia c=IN IP4 10.127.1.99 t=0 0 m=audio 7000 RTP/AVP 103 102 104 113 3 0 8 9 101 a=rtcp:7001 IN IP4 10.127.1.99 a=rtpmap:103 speex/16000 a=rtpmap:102 speex/8000 a=rtpmap:104 speex/32000 a=rtpmap:113 iLBC/8000 a=fmtp:113 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=sendrecv a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 --end msg-- 13:54:00.031 tdta0x185ac00 Destroying txdata Response msg 200/OPTIONS/cseq=102 (tdta0x185ac00) 13:54:14.894 pjsua_acc.c Sending 2 bytes keep-alive packet for acc 2 to 173.8.19.148:5060 13:54:14.894 tdta0x182d000 Destroying txdata raw 13:54:26.231 tcpc0x188c414 TCP connect() error: Unknown error 60 [code=60] 13:54:26.231 tsx0x188bc64 Failed to send Request msg INVITE/cseq=4313 (tdta0x1886400)! err=60 (Unknown error 60) 13:54:26.231 tsx0x188bc64 State changed from Terminated to Destroyed, event=TRANSPORT_ERROR 13:54:26.231 tsx0x188bc64 Transaction destroyed! 13:54:26.231 pjsua_core.c TX 1320 bytes Request msg INVITE/cseq=4313 (tdta0x1886400) to UDP 173.8.19.148:5060: INVITE sip:3304128820@sip.example.com SIP/2.0 Via: SIP/2.0/UDP 66.219.174.73:5060;rport;branch=z9hG4bKPj576EEE7D-0CB6-4BF5-8B44-01ED3D1755A8 Max-Forwards: 70 From: sip:4414@sip.example.com;tag=D1F45773-0B5E-4155-B6C0-AF7E0DBDA1B3 To: sip:3304128820@sip.example.com Contact: Call-ID: 3B1E22FB-3601-4D79-82F1-68C22970B95C CSeq: 4313 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub Session-Expires: 1800 Min-SE: 90 User-Agent: PJSUA v1.5/i386-apple-darwin8.11.1 Proxy-Authorization: Digest username="4414", realm="asterisk", nonce="0f1f398a", uri="sip:3304128820@sip.example.com", response="1e53a05dc75c8578029616a0fc288138", algorithm=MD5 Content-Type: application/sdp Content-Length: 456 v=0 o=- 3468595991 3468595991 IN IP4 10.127.1.99 s=pjmedia c=IN IP4 10.127.1.99 t=0 0 a=X-nat:0 m=audio 7000 RTP/AVP 103 102 104 113 3 0 8 9 101 a=rtcp:7001 IN IP4 10.127.1.99 a=rtpmap:103 speex/16000 a=rtpmap:102 speex/8000 a=rtpmap:104 speex/32000 a=rtpmap:113 iLBC/8000 a=fmtp:113 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=sendrecv a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 --end msg-- 13:54:26.233 tsx0x188bc64 State changed from Destroyed to Destroyed, event=UNIDENTIFIED