On 28.01.2009, at 15:02, Benny Prijono wrote: > On Wed, Jan 28, 2009 at 11:47 AM, Alexei Kuznetsov > <eofster at gmail.com> wrote: > On Mon, Jan 26, 2009 at 11:53 PM, Benny Prijono <bennylp at teluu.com> > wrote: > > On Mon, Jan 26, 2009 at 5:54 PM, Alexei Kuznetsov > <eofster at gmail.com> wrote: > >> > >> > Hi, this would have been taken care of automatically if you use > the ICE > >> > media transport. With the default UDP media transport, STUN > discovery is > >> > only done once during startup, and public IP change detection > is left to > >> > application to detect and handle. > >> > >> So if I configure media with enable_ice = PJ_TRUE and configure > STUN > >> server, that will do the trick? > >> > > > > Yes. > > That didn't work for me. I've tested pjsua from the 1.0 branch running > with arguments --stun-srv=stun.sipgate.net:10000 --use-ice. > > I registered an account and made a successfull call. Then changed > external NAT IP address (with DSL modem reconnect). pjsua noticed the > change. > > 14:37:36.731 stuntp0x216ca0 STUN mapped address found/changed: > 91.78.83.150:53705 > 14:37:36.732 stuntp0x216ba0 STUN mapped address found/changed: > 91.78.83.150:53704 > 14:37:36.813 stuntp0x216da0 STUN mapped address found/changed: > 91.78.83.150:53706 > 14:37:36.819 stuntp0x216ea0 STUN mapped address found/changed: > 91.78.83.150:53707 > 14:37:36.924 stuntp0x216fa0 STUN mapped address found/changed: > 91.78.83.150:53708 > 14:37:36.926 stuntp0x2170a0 STUN mapped address found/changed: > 91.78.83.150:53709 > 14:37:37.013 stuntp0x2171a0 STUN mapped address found/changed: > 91.78.83.150:53710 > 14:37:37.015 stuntp0x2172a0 STUN mapped address found/changed: > 91.78.83.150:53711 > > Then I re-registered an account. > > 14:38:08.905 pjsua_acc.c IP address change detected for account 2 > (91.77.38.3:5060 --> 91.78.83.150:5060). Updating registration.. > > Then I tried to make a call. > > INVITE sip:612 at fwd.pulver.com SIP/2.0 > Via: SIP/2.0/UDP > 91.78.83.150 > :5060;rport;branch=z9hG4bKPjqGtF76H6KHTHyXHXHQmzWw3IsN6FBkZ9 > Max-Forwards: 70 > From: sip:902984@xxxxxxxxxxxxxx;tag=nulHw0zLPId9Lmda.GfWDIY-z85vetb- > To: sip:612 at fwd.pulver.com > Contact: <sip:902984 at 91.78.83.150:5060;transport=UDP> > Call-ID: Orvyt0DCa48yjjlVMvO24KxYTU.AOwxM > CSeq: 30193 INVITE > Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, > REFER, MESSAGE, OPTIONS > Supported: replaces, 100rel, norefersub > User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 > Content-Type: application/sdp > Content-Length: 601 > > v=0 > o=- 3442131714 3442131714 IN IP4 91.77.38.3 > s=pjmedia > c=IN IP4 91.77.38.3 > t=0 0 > a=X-nat:8 > m=audio 53706 RTP/AVP 103 102 104 117 3 0 8 9 101 > a=rtcp:53707 IN IP4 91.77.38.3 > a=rtpmap:103 speex/16000 > a=rtpmap:102 speex/8000 > a=rtpmap:104 speex/32000 > a=rtpmap:117 iLBC/8000 > a=fmtp:117 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 > a=ice-ufrag:2147b80b > a=ice-pwd:6b83eb3e > a=candidate:H 1 UDP 23 192.168.1.10 53706 typ host > a=candidate:H 2 UDP 22 192.168.1.10 53707 typ host > > --end msg-- > > The media didn't succeed here and there was an old IP address > 91.77.38.3 in SDP. > > > That's peculiar. For a start, the SDP c= and a=rtcp addresses are > not listed as candidates, and that's not valid in ICE and we should > never emit this message. And then the STUN mapped addresses are also > not listed as candidates, which is strange. > > Are you capturing the INVITE message in the client or server side? > If it's in the server then there is a possibility that ALG in the > middle might have messed the message up. That was from the client side. I definitely don't have ALG. > If you have the full file log in the client that would be great. No problem. If I shouldn't post such big logs in the mailing list, tell me. $ ./pjsua-i386-apple-darwin9.6.0 --stun-srv=stun.sipgate.net:10000 -- use-ice 14:35:43.098 os_core_unix.c pjlib 1.0.1 for POSIX initialized 14:35:43.098 sip_endpoint.c Creating endpoint instance... 14:35:43.099 pjlib select() I/O Queue created (0x18f094) 14:35:43.099 sip_endpoint.c Module "mod-msg-print" registered 14:35:43.099 sip_transport. Transport manager created. 14:35:43.099 sip_endpoint.c Module "mod-pjsua-log" registered 14:35:43.099 sip_endpoint.c Module "mod-tsx-layer" registered 14:35:43.099 sip_endpoint.c Module "mod-stateful-util" registered 14:35:43.099 sip_endpoint.c Module "mod-ua" registered 14:35:43.099 sip_endpoint.c Module "mod-100rel" registered 14:35:43.099 sip_endpoint.c Module "mod-pjsua" registered 14:35:43.099 sip_endpoint.c Module "mod-invite" registered 14:35:43.204 pjsua_core.c STUN server stun.sipgate.net:10000 resolved, address is 217.10.79.2:10000 14:35:43.305 pasound.c PortAudio sound library initialized, status=0 14:35:43.305 pasound.c PortAudio host api count=1 14:35:43.305 pasound.c Sound device count=3 14:35:43.305 pjlib select() I/O Queue created (0x800814) 14:35:43.305 sip_endpoint.c Module "mod-evsub" registered 14:35:43.305 sip_endpoint.c Module "mod-presence" registered 14:35:43.306 sip_endpoint.c Module "mod-refer" registered 14:35:43.306 sip_endpoint.c Module "mod-pjsua-pres" registered 14:35:43.306 sip_endpoint.c Module "mod-pjsua-im" registered 14:35:43.306 sip_endpoint.c Module "mod-pjsua-options" registered 14:35:43.306 pjsua_core.c 1 SIP worker threads created 14:35:43.306 pjsua_core.c pjsua version 1.0.1 for i386-apple- darwin9.6.0 initialized 14:35:43.373 pjsua_core.c SIP UDP socket reachable at 91.77.38.3:5060 14:35:43.373 sip_transport_ Error setting SO_RCVBUF: No buffer space available [120055] 14:35:43.373 sip_transport_ Error setting SO_SNDBUF: No buffer space available [120055] 14:35:43.373 udp0x829000 SIP UDP transport started, published address is 91.77.38.3:5060 14:35:43.373 pjsua_acc.c Account <sip:91.77.38.3:5060> added with id 0 14:35:43.373 tcplis:5060 SIP TCP listener ready for incoming connections at 192.168.1.10:5060 14:35:43.373 pjsua_acc.c Account <sip:192.168.1.10:5060;transport=TCP > added with id 1 14:35:43.373 icetp00 Creating ICE stream transport with 2 component(s) 14:35:43.373 icetp00 Comp 1: srflx candidate starts Binding discovery 14:35:43.373 icetp00 Comp 1: host candidate 192.168.1.10:53704 added 14:35:43.373 icetp00 Comp 2: srflx candidate starts Binding discovery 14:35:43.374 icetp00 Comp 2: host candidate 192.168.1.10:53705 added 14:35:43.374 icetp00 ICE stream transport created 14:35:43.439 stuntp0x216ba0 STUN mapped address found/changed: 91.77.38.3:53704 14:35:43.439 icetp00 Comp 1: Binding discovery complete, srflx address is 91.77.38.3:53704 14:35:43.440 stuntp0x216ca0 STUN mapped address found/changed: 91.77.38.3:53705 14:35:43.440 icetp00 Comp 2: Binding discovery complete, srflx address is 91.77.38.3:53705 14:35:43.455 icetp01 Creating ICE stream transport with 2 component(s) 14:35:43.455 icetp01 Comp 1: srflx candidate starts Binding discovery 14:35:43.455 icetp01 Comp 1: host candidate 192.168.1.10:53706 added 14:35:43.455 icetp01 Comp 2: srflx candidate starts Binding discovery 14:35:43.455 icetp01 Comp 2: host candidate 192.168.1.10:53707 added 14:35:43.456 icetp01 ICE stream transport created 14:35:43.521 stuntp0x216da0 STUN mapped address found/changed: 91.77.38.3:53706 14:35:43.521 icetp01 Comp 1: Binding discovery complete, srflx address is 91.77.38.3:53706 14:35:43.522 stuntp0x216ea0 STUN mapped address found/changed: 91.77.38.3:53707 14:35:43.523 icetp01 Comp 2: Binding discovery complete, srflx address is 91.77.38.3:53707 14:35:43.556 icetp02 Creating ICE stream transport with 2 component(s) 14:35:43.556 icetp02 Comp 1: srflx candidate starts Binding discovery 14:35:43.565 icetp02 Comp 1: host candidate 192.168.1.10:53708 added 14:35:43.565 icetp02 Comp 2: srflx candidate starts Binding discovery 14:35:43.565 icetp02 Comp 2: host candidate 192.168.1.10:53709 added 14:35:43.565 icetp02 ICE stream transport created 14:35:43.630 stuntp0x216fa0 STUN mapped address found/changed: 91.77.38.3:53708 14:35:43.630 icetp02 Comp 1: Binding discovery complete, srflx address is 91.77.38.3:53708 14:35:43.631 stuntp0x2170a0 STUN mapped address found/changed: 91.77.38.3:53709 14:35:43.631 icetp02 Comp 2: Binding discovery complete, srflx address is 91.77.38.3:53709 14:35:43.655 icetp03 Creating ICE stream transport with 2 component(s) 14:35:43.655 icetp03 Comp 1: srflx candidate starts Binding discovery 14:35:43.655 icetp03 Comp 1: host candidate 192.168.1.10:53710 added 14:35:43.655 icetp03 Comp 2: srflx candidate starts Binding discovery 14:35:43.656 icetp03 Comp 2: host candidate 192.168.1.10:53711 added 14:35:43.656 icetp03 ICE stream transport created 14:35:43.721 stuntp0x2171a0 STUN mapped address found/changed: 91.77.38.3:53710 14:35:43.721 icetp03 Comp 1: Binding discovery complete, srflx address is 91.77.38.3:53710 14:35:43.722 stuntp0x2172a0 STUN mapped address found/changed: 91.77.38.3:53711 14:35:43.723 icetp03 Comp 2: Binding discovery complete, srflx address is 91.77.38.3:53711 >>>> Account list: [ 0] <sip:91.77.38.3:5060>: does not register Online status: Online *[ 1] <sip:192.168.1.10:5060;transport=TCP>: does not register Online status: Online Buddy list: -none- + = = = = = = =======================================================================+ | Call Commands: | Buddy, IM & Presence: | Account: | | | | | | m Make new call | +b Add new buddy .| +a Add new accnt | | M Make multiple calls | -b Delete buddy | -a Delete accnt. | | a Answer call | i Send IM | !a Modify accnt. | | h Hangup call (ha=all) | s Subscribe presence | rr (Re-)register | | H Hold call | u Unsubscribe presence | ru Unregister | | v re-inVite (release hold) | t ToGgle Online status | > Cycle next ac.| | U send UPDATE | T Set online status | < Cycle prev ac.| | ],[ Select next/prev call +-------------------------- +-------------------+ | x Xfer call | Media Commands: | Status & Config: | | X Xfer with Replaces | | | | # Send RFC 2833 DTMF | cl List ports | d Dump status | | * Send DTMF with INFO | cc Connect port | dd Dump detailed | | dq Dump curr. call quality | cd Disconnect port | dc Dump config | | | V Adjust audio Volume | f Save config | | S Send arbitrary REQUEST | Cp Codec priorities | f Save config | +------------------------------+-------------------------- +-------------------+ | q QUIT sleep MS echo [0|1|txt] n: detect NAT type | + = = = = = = =======================================================================+ You have 0 active call >>> +a Your SIP URL: (empty to cancel): 14:35:51.255 stuntsx0x828f3 STUN timeout waiting for response 14:35:51.305 stuntsx0x830b3 STUN timeout waiting for response 14:35:51.329 pjsua_app.c NAT detected as Port Restricted >>> >>>> Account list: [ 0] <sip:91.77.38.3:5060>: does not register Online status: Online *[ 1] <sip:192.168.1.10:5060;transport=TCP>: does not register Online status: Online Buddy list: -none- + = = = = = = =======================================================================+ | Call Commands: | Buddy, IM & Presence: | Account: | | | | | | m Make new call | +b Add new buddy .| +a Add new accnt | | M Make multiple calls | -b Delete buddy | -a Delete accnt. | | a Answer call | i Send IM | !a Modify accnt. | | h Hangup call (ha=all) | s Subscribe presence | rr (Re-)register | | H Hold call | u Unsubscribe presence | ru Unregister | | v re-inVite (release hold) | t ToGgle Online status | > Cycle next ac.| | U send UPDATE | T Set online status | < Cycle prev ac.| | ],[ Select next/prev call +-------------------------- +-------------------+ | x Xfer call | Media Commands: | Status & Config: | | X Xfer with Replaces | | | | # Send RFC 2833 DTMF | cl List ports | d Dump status | | * Send DTMF with INFO | cc Connect port | dd Dump detailed | | dq Dump curr. call quality | cd Disconnect port | dc Dump config | | | V Adjust audio Volume | f Save config | | S Send arbitrary REQUEST | Cp Codec priorities | f Save config | +------------------------------+-------------------------- +-------------------+ | q QUIT sleep MS echo [0|1|txt] n: detect NAT type | + = = = = = = =======================================================================+ You have 0 active call >>> +a Your SIP URL: (empty to cancel): sip:902984 at fwd.pulver.com URL of the registrar: (empty to cancel): sip:fwd.pulver.com Auth Realm: (empty to cancel): * Auth Username: (empty to cancel): 902984 Auth Password: (empty to cancel): ************* 14:36:24.463 pjsua_acc.c Account sip:902984 at fwd.pulver.com added with id 2 14:36:24.625 pjsua_core.c TX 438 bytes Request msg REGISTER/ cseq=53011 (tdta0x82fc00) to UDP 69.90.155.70:5060: REGISTER sip:fwd.pulver.com SIP/2.0 Via: SIP/2.0/UDP 91.77.38.3:5060;rport;branch=z9hG4bKPjVyUbldmMCX1RLVBaEC03tFADdvBhaBvQ Max-Forwards: 70 From: <sip:902984@xxxxxxxxxxxxxx>;tag=Dij4NWJUpPD7qzsqlcowBgebWxb.5-hE To: <sip:902984 at fwd.pulver.com> Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53011 REGISTER User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 Contact: <sip:902984 at 91.77.38.3:5060> Expires: 300 Content-Length: 0 --end msg-- 14:36:24.626 pjsua_acc.c Registration sent >>> 14:36:24.787 pjsua_core.c RX 452 bytes Response msg 401/ REGISTER/cseq=53011 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 91.77.38.3 :5060;rport=5060;branch=z9hG4bKPjVyUbldmMCX1RLVBaEC03tFADdvBhaBvQ From: <sip:902984@xxxxxxxxxxxxxx>;tag=Dij4NWJUpPD7qzsqlcowBgebWxb.5-hE To: <sip: 902984 at fwd.pulver.com>;tag=cb2000b247d89723001a836145f3b053.d67d Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53011 REGISTER WWW-Authenticate: Digest realm="fwd.pulver.com", nonce="498047dde3077d60e4acfe01bc03338b3196fed1" Content-Length: 0 --end msg-- 14:36:24.787 pjsua_core.c TX 624 bytes Request msg REGISTER/ cseq=53012 (tdta0x82fc00) to UDP 69.90.155.70:5060: REGISTER sip:fwd.pulver.com SIP/2.0 Via: SIP/2.0/UDP 91.77.38.3:5060;rport;branch=z9hG4bKPj3AOCkRMdDgo6yBaZzfKycbatapLTlGEM Max-Forwards: 70 From: <sip:902984@xxxxxxxxxxxxxx>;tag=Dij4NWJUpPD7qzsqlcowBgebWxb.5-hE To: <sip:902984 at fwd.pulver.com> Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53012 REGISTER User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 Contact: <sip:902984 at 91.77.38.3:5060> Expires: 300 Authorization: Digest username="902984", realm="fwd.pulver.com", nonce="498047dde3077d60e4acfe01bc03338b3196fed1", uri="sip:fwd.pulver.com ", response="f1c29e6c8e23fef0398140b49aff0be5" Content-Length: 0 --end msg-- 14:36:24.951 pjsua_core.c RX 437 bytes Response msg 200/REGISTER/ cseq=53012 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 91.77.38.3 :5060;rport=5060;branch=z9hG4bKPj3AOCkRMdDgo6yBaZzfKycbatapLTlGEM From: <sip:902984@xxxxxxxxxxxxxx>;tag=Dij4NWJUpPD7qzsqlcowBgebWxb.5-hE To: <sip: 902984 at fwd.pulver.com>;tag=cb2000b247d89723001a836145f3b053.f001 Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53012 REGISTER Date: Wed, 28 Jan 2009 11:51:14 GMT Contact: <sip:902984 at 91.77.38.3:5060>;q=0.1;expires=300 Content-Length: 0 --end msg-- 14:36:24.951 pjsua_acc.c sip:902984 at fwd.pulver.com: registration success, status=200 (OK), will re-register in 300 seconds 14:36:24.951 pjsua_acc.c Keep-alive timer started for acc 2, destination:69.90.155.70:5060, interval:15s >>>> Account list: [ 0] <sip:91.77.38.3:5060>: does not register Online status: Online [ 1] <sip:192.168.1.10:5060;transport=TCP>: does not register Online status: Online *[ 2] sip:902984 at fwd.pulver.com: 200/OK (expires=287) Online status: Offline Buddy list: -none- + = = = = = = =======================================================================+ | Call Commands: | Buddy, IM & Presence: | Account: | | | | | | m Make new call | +b Add new buddy .| +a Add new accnt | | M Make multiple calls | -b Delete buddy | -a Delete accnt. | | a Answer call | i Send IM | !a Modify accnt. | | h Hangup call (ha=all) | s Subscribe presence | rr (Re-)register | | H Hold call | u Unsubscribe presence | ru Unregister | | v re-inVite (release hold) | t ToGgle Online status | > Cycle next ac.| | U send UPDATE | T Set online status | < Cycle prev ac.| | ],[ Select next/prev call +-------------------------- +-------------------+ | x Xfer call | Media Commands: | Status & Config: | | X Xfer with Replaces | | | | # Send RFC 2833 DTMF | cl List ports | d Dump status | | * Send DTMF with INFO | cc Connect port | dd Dump detailed | | dq Dump curr. call quality | cd Disconnect port | dc Dump config | | | V Adjust audio Volume | f Save config | | S Send arbitrary REQUEST | Cp Codec priorities | f Save config | +------------------------------+-------------------------- +-------------------+ | q QUIT sleep MS echo [0|1|txt] n: detect NAT type | + = = = = = = =======================================================================+ You have 0 active call >>> m (You currently have 0 calls) Buddy list: -none- Choices: 0 For current dialog. -1 All 0 buddies in buddy list [1 - 0] Select from buddy list URL An URL <Enter> Empty input (or 'q') to cancel Make call: sip:612 at fwd.pulver.com 14:36:40.839 pjsua_media.c pjsua_set_snd_dev(): attempting to open devices @16000 Hz 14:36:41.879 ec0x223fd0 AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=20 ms 14:36:41.879 pjsua_call.c Making call with acc #2 to sip:612 at fwd.pulver.com 14:36:41.889 pjsua_media.c Media index 0 selected for call 0 14:36:41.889 icetp00 ICE session created, comp_cnt=2, role is Controlling agent 14:36:41.889 icetp00 Candidate 0 added: comp_id=1, type=srflx, foundation=S, addr=91.77.38.3:53704, base=192.168.1.10:53704, prio=0x1f (31) 14:36:41.889 icetp00 Candidate 1 added: comp_id=1, type=host, foundation=H, addr=192.168.1.10:53704, base=192.168.1.10:53704, prio=0x17 (23) 14:36:41.889 icetp00 Candidate 2 added: comp_id=2, type=srflx, foundation=S, addr=91.77.38.3:53705, base=192.168.1.10:53705, prio=0x1e (30) 14:36:41.889 icetp00 Candidate 3 added: comp_id=2, type=host, foundation=H, addr=192.168.1.10:53705, base=192.168.1.10:53705, prio=0x16 (22) 14:36:41.890 pjsua_core.c TX 1349 bytes Request msg INVITE/ cseq=12795 (tdta0x869000) to UDP 69.90.155.70:5060: INVITE sip:612 at fwd.pulver.com SIP/2.0 Via: SIP/2.0/UDP 91.77.38.3:5060;rport;branch=z9hG4bKPjJ6RuENrD4EfPJKVJcOfgXU1xgS4L2.5. Max-Forwards: 70 From: sip:902984@xxxxxxxxxxxxxx;tag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe To: sip:612 at fwd.pulver.com Contact: <sip:902984 at 91.77.38.3:5060> Call-ID: Wu-A1WBYGTYb1YpkJze.mi2vSkroeri0 CSeq: 12795 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, norefersub User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 Content-Type: application/sdp Content-Length: 765 v=0 o=- 3442131401 3442131401 IN IP4 91.77.38.3 s=pjmedia c=IN IP4 91.77.38.3 t=0 0 a=X-nat:8 m=audio 53704 RTP/AVP 103 102 104 117 3 0 8 9 101 a=rtcp:53705 IN IP4 91.77.38.3 a=rtpmap:103 speex/16000 a=rtpmap:102 speex/8000 a=rtpmap:104 speex/32000 a=rtpmap:117 iLBC/8000 a=fmtp:117 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 a=ice-ufrag:753f1ed7 a=ice-pwd:0301f164 a=candidate:S 1 UDP 31 91.77.38.3 53704 typ srflx raddr 192.168.1.10 rport 53704 a=candidate:H 1 UDP 23 192.168.1.10 53704 typ host a=candidate:S 2 UDP 30 91.77.38.3 53705 typ srflx raddr 192.168.1.10 rport 53705 a=candidate:H 2 UDP 22 192.168.1.10 53705 typ host --end msg-- 14:36:41.890 pjsua_app.c Call 0 state changed to CALLING >>> 14:36:42.061 pjsua_core.c RX 296 bytes Response msg 100/ INVITE/cseq=12795 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 91.77.38.3 :5060;rport=5060;branch=z9hG4bKPjJ6RuENrD4EfPJKVJcOfgXU1xgS4L2.5. From: sip:902984@xxxxxxxxxxxxxx;tag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe To: sip:612 at fwd.pulver.com Call-ID: Wu-A1WBYGTYb1YpkJze.mi2vSkroeri0 CSeq: 12795 INVITE Content-Length: 0 --end msg-- 14:36:42.062 pjsua_core.c RX 328 bytes Response msg 100/INVITE/ cseq=12795 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 91.77.38.3 :5060;rport=5060;branch=z9hG4bKPjJ6RuENrD4EfPJKVJcOfgXU1xgS4L2.5. From: sip:902984@xxxxxxxxxxxxxx;tag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe To: sip:612 at fwd.pulver.com Call-ID: Wu-A1WBYGTYb1YpkJze.mi2vSkroeri0 CSeq: 12795 INVITE Content-Length: 0 --end msg-- 14:36:42.152 pjsua_core.c RX 425 bytes Response msg 180/INVITE/ cseq=12795 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 91.77.38.3 :5060;rport=5060;branch=z9hG4bKPjJ6RuENrD4EfPJKVJcOfgXU1xgS4L2.5. From: sip:902984@xxxxxxxxxxxxxx;tag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe To: sip:612 at fwd.pulver.com;tag=as7ffc4c37 Call-ID: Wu-A1WBYGTYb1YpkJze.mi2vSkroeri0 CSeq: 12795 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Contact: <sip:612 at 69.90.168.13:5028> Content-Length: 0 --end msg-- 14:36:42.152 conference.c Port 1 (ringback) transmitting to port 0 (Built-in Microphone) 14:36:42.152 pjsua_app.c Call 0 state changed to EARLY (180 Ringing) 14:36:45.149 pjsua_core.c RX 796 bytes Response msg 200/INVITE/ cseq=12795 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 91.77.38.3 :5060;rport=5060;branch=z9hG4bKPjJ6RuENrD4EfPJKVJcOfgXU1xgS4L2.5. Record-Route: <sip:69.90.155.70;ftag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe;lr=on > From: sip:902984@xxxxxxxxxxxxxx;tag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe To: sip:612 at fwd.pulver.com;tag=as7ffc4c37 Call-ID: Wu-A1WBYGTYb1YpkJze.mi2vSkroeri0 CSeq: 12795 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Contact: <sip:612 at 69.90.168.13:5028> Content-Type: application/sdp Content-Length: 265 v=0 o=root 20509 20509 IN IP4 69.90.168.13 s=session c=IN IP4 69.90.168.13 t=0 0 m=audio 14650 RTP/AVP 0 3 97 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:97 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --end msg-- 14:36:45.149 pjsua_app.c Call 0 state changed to CONNECTING 14:36:45.150 icetp00 Stopping ICE, reason=Remote answer doesn't support ICE 14:36:45.150 icetp00 Destroying ICE session 14:36:45.150 strm0x864974 VAD temporarily disabled 14:36:45.150 strm0x864974 Encoder stream started 14:36:45.150 strm0x864974 Decoder stream started 14:36:45.150 pjsua_media.c Media updates, stream #0: PCMU (sendrecv) 14:36:45.150 conference.c Port 1 (ringback) stop transmitting to port 0 (Built-in Microphone) 14:36:45.150 conference.c Port 3 (sip:612 at fwd.pulver.com) transmitting to port 0 (Built-in Microphone) 14:36:45.150 conference.c Port 0 (Built-in Microphone) transmitting to port 3 (sip:612 at fwd.pulver.com) 14:36:45.150 pjsua_app.c Media for call 0 is active 14:36:45.150 pjsua_core.c TX 409 bytes Request msg ACK/cseq=12795 (tdta0x872800) to UDP 69.90.155.70:5060: ACK sip:612 at 69.90.168.13:5028 SIP/2.0 Via: SIP/2.0/UDP 91.77.38.3:5060;rport;branch=z9hG4bKPjfKE66sJZElqn2PAYlrpcxV2oxKCoLQ3A Max-Forwards: 70 From: sip:902984@xxxxxxxxxxxxxx;tag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe To: sip:612 at fwd.pulver.com;tag=as7ffc4c37 Call-ID: Wu-A1WBYGTYb1YpkJze.mi2vSkroeri0 CSeq: 12795 ACK Route: <sip:69.90.155.70;lr;ftag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe> Content-Length: 0 --end msg-- 14:36:45.150 pjsua_app.c Call 0 state changed to CONFIRMED 14:36:45.153 Master/sound Underflow, buf_cnt=0, will generate 1 frame 14:36:45.777 strm0x864974 VAD re-enabled 14:36:53.528 pjsua_core.c RX 545 bytes Request msg BYE/cseq=102 (rdata0x829464) from UDP 69.90.155.70:5060: BYE sip:902984 at 91.77.38.3:5060 SIP/2.0 Max-Forwards: 10 Record-Route: <sip:69.90.155.70;ftag=as7ffc4c37;lr=on> Via: SIP/2.0/UDP 69.90.155.70;branch=z9hG4bK96f7.8059c24.0 Via: SIP/2.0/UDP 69.90.168.13:5028;branch=z9hG4bK1417ba1e Route: <sip:902984 at 91.77.38.3:5060> From: sip:612@xxxxxxxxxxxxxx;tag=as7ffc4c37 To: sip:902984 at fwd.pulver.com;tag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe Contact: <sip:612 at 69.90.168.13:5028> Call-ID: Wu-A1WBYGTYb1YpkJze.mi2vSkroeri0 CSeq: 102 BYE User-Agent: Asterisk PBX Content-Length: 0 P-hint: NON-LOCAL --end msg-- 14:36:53.528 pjsua_core.c TX 407 bytes Response msg 200/BYE/ cseq=102 (tdta0x86b800) to UDP 69.90.155.70:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 69.90.155.70;received=69.90.155.70;branch=z9hG4bK96f7.8059c24.0 Via: SIP/2.0/UDP 69.90.168.13:5028;branch=z9hG4bK1417ba1e Record-Route: <sip:69.90.155.70;lr;ftag=as7ffc4c37> Call-ID: Wu-A1WBYGTYb1YpkJze.mi2vSkroeri0 From: <sip:612@xxxxxxxxxxxxxx>;tag=as7ffc4c37 To: <sip:902984 at fwd.pulver.com>;tag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe CSeq: 102 BYE Content-Length: 0 --end msg-- 14:36:53.528 pjsua_app.c Call 0 is DISCONNECTED [reason=200 (Normal call clearing)] 14:36:53.528 pjsua_app.c [DISCONNCTD] To: sip:612 at fwd.pulver.com;tag=as7ffc4c37 Call time: 00h:00m:08s, 1st res in 273 ms, conn in 3271ms SRTP status: Not active Crypto-suite: (null) #0 PCMU @8KHz, sendrecv, peer=69.90.168.13:14650 RX pt=0, stat last update: 00h:00m:03.412s ago total 418pkt 66.8KB (83.6KB +IP hdr) @avg=63.8Kbps/79.8Kbps pkt loss=0 (0.0%), discrd=0 (0.0%), dup=0 (0.0%), reord=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.000 3.642 79.500 0.250 6.340 TX pt=0, ptime=20ms, stat last update: never total 183pkt 29.2KB (36.6KB +IP hdr) @avg 27.9Kbps/34.9Kbps pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.000 0.000 0.000 0.000 0.000 RTT msec : 0.000 0.000 0.000 0.000 0.000 14:36:53.529 pjsua_media.c Media session for call 0 is destroyed 14:36:53.529 icetp00 Stopping ICE, reason=media stop requested 14:36:53.545 pjsua_core.c RX 545 bytes Request msg BYE/cseq=102 (rdata0x829464) from UDP 69.90.155.70:5060: BYE sip:902984 at 91.77.38.3:5060 SIP/2.0 Max-Forwards: 10 Record-Route: <sip:69.90.155.70;ftag=as7ffc4c37;lr=on> Via: SIP/2.0/UDP 69.90.155.70;branch=z9hG4bK96f7.8059c24.0 Via: SIP/2.0/UDP 69.90.168.13:5028;branch=z9hG4bK1417ba1e Route: <sip:902984 at 91.77.38.3:5060> From: sip:612@xxxxxxxxxxxxxx;tag=as7ffc4c37 To: sip:902984 at fwd.pulver.com;tag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe Contact: <sip:612 at 69.90.168.13:5028> Call-ID: Wu-A1WBYGTYb1YpkJze.mi2vSkroeri0 CSeq: 102 BYE User-Agent: Asterisk PBX Content-Length: 0 P-hint: NON-LOCAL --end msg-- 14:36:53.545 pjsua_core.c TX 407 bytes Response msg 200/BYE/ cseq=102 (tdta0x86b800) to UDP 69.90.155.70:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 69.90.155.70;received=69.90.155.70;branch=z9hG4bK96f7.8059c24.0 Via: SIP/2.0/UDP 69.90.168.13:5028;branch=z9hG4bK1417ba1e Record-Route: <sip:69.90.155.70;lr;ftag=as7ffc4c37> Call-ID: Wu-A1WBYGTYb1YpkJze.mi2vSkroeri0 From: <sip:612@xxxxxxxxxxxxxx>;tag=as7ffc4c37 To: <sip:902984 at fwd.pulver.com>;tag=lFHrQ3ZFZ82yTbxRvmgMQJxTRmRr13qe CSeq: 102 BYE Content-Length: 0 --end msg-- >>>> Account list: [ 0] <sip:91.77.38.3:5060>: does not register Online status: Online [ 1] <sip:192.168.1.10:5060;transport=TCP>: does not register Online status: Online *[ 2] sip:902984 at fwd.pulver.com: 200/OK (expires=264) Online status: Offline Buddy list: -none- + = = = = = = =======================================================================+ | Call Commands: | Buddy, IM & Presence: | Account: | | | | | | m Make new call | +b Add new buddy .| +a Add new accnt | | M Make multiple calls | -b Delete buddy | -a Delete accnt. | | a Answer call | i Send IM | !a Modify accnt. | | h Hangup call (ha=all) | s Subscribe presence | rr (Re-)register | | H Hold call | u Unsubscribe presence | ru Unregister | | v re-inVite (release hold) | t ToGgle Online status | > Cycle next ac.| | U send UPDATE | T Set online status | < Cycle prev ac.| | ],[ Select next/prev call +-------------------------- +-------------------+ | x Xfer call | Media Commands: | Status & Config: | | X Xfer with Replaces | | | | # Send RFC 2833 DTMF | cl List ports | d Dump status | | * Send DTMF with INFO | cc Connect port | dd Dump detailed | | dq Dump curr. call quality | cd Disconnect port | dc Dump config | | | V Adjust audio Volume | f Save config | | S Send arbitrary REQUEST | Cp Codec priorities | f Save config | +------------------------------+-------------------------- +-------------------+ | q QUIT sleep MS echo [0|1|txt] n: detect NAT type | + = = = = = = =======================================================================+ You have 0 active call >>> 14:36:58.533 sound_port.c EC suspended because of inactivity 14:37:21.666 stuntsx0x87173 STUN timeout waiting for response 14:37:21.666 stuntp0x216ba0 Session failed because Keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.666 icetp00 STUN keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.666 stuntsx0x86973 STUN timeout waiting for response 14:37:21.666 stuntp0x216ca0 Session failed because Keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.666 icetp00 STUN keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.748 stuntsx0x869f3 STUN timeout waiting for response 14:37:21.748 stuntp0x216da0 Session failed because Keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.748 icetp01 STUN keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.753 stuntsx0x86a73 STUN timeout waiting for response 14:37:21.753 stuntp0x216ea0 Session failed because Keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.753 icetp01 STUN keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.858 stuntsx0x86af3 STUN timeout waiting for response 14:37:21.858 stuntp0x216fa0 Session failed because Keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.858 icetp02 STUN keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.861 stuntsx0x86df3 STUN timeout waiting for response 14:37:21.861 stuntp0x2170a0 Session failed because Keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.861 icetp02 STUN keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.947 stuntsx0x86e73 STUN timeout waiting for response 14:37:21.947 stuntp0x2171a0 Session failed because Keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.947 icetp03 STUN keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.949 stuntsx0x86ef3 STUN timeout waiting for response 14:37:21.949 stuntp0x2172a0 Session failed because Keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:21.949 icetp03 STUN keep-alive failed: STUN transaction has timed out (PJNATH_ESTUNTIMEDOUT) 14:37:36.731 stuntp0x216ca0 STUN mapped address found/changed: 91.78.83.150:53705 14:37:36.732 stuntp0x216ba0 STUN mapped address found/changed: 91.78.83.150:53704 14:37:36.813 stuntp0x216da0 STUN mapped address found/changed: 91.78.83.150:53706 14:37:36.819 stuntp0x216ea0 STUN mapped address found/changed: 91.78.83.150:53707 14:37:36.924 stuntp0x216fa0 STUN mapped address found/changed: 91.78.83.150:53708 14:37:36.926 stuntp0x2170a0 STUN mapped address found/changed: 91.78.83.150:53709 14:37:37.013 stuntp0x2171a0 STUN mapped address found/changed: 91.78.83.150:53710 14:37:37.015 stuntp0x2172a0 STUN mapped address found/changed: 91.78.83.150:53711 >>>> Account list: [ 0] <sip:91.77.38.3:5060>: does not register Online status: Online [ 1] <sip:192.168.1.10:5060;transport=TCP>: does not register Online status: Online *[ 2] sip:902984 at fwd.pulver.com: 200/OK (expires=198) Online status: Offline Buddy list: -none- + = = = = = = =======================================================================+ | Call Commands: | Buddy, IM & Presence: | Account: | | | | | | m Make new call | +b Add new buddy .| +a Add new accnt | | M Make multiple calls | -b Delete buddy | -a Delete accnt. | | a Answer call | i Send IM | !a Modify accnt. | | h Hangup call (ha=all) | s Subscribe presence | rr (Re-)register | | H Hold call | u Unsubscribe presence | ru Unregister | | v re-inVite (release hold) | t ToGgle Online status | > Cycle next ac.| | U send UPDATE | T Set online status | < Cycle prev ac.| | ],[ Select next/prev call +-------------------------- +-------------------+ | x Xfer call | Media Commands: | Status & Config: | | X Xfer with Replaces | | | | # Send RFC 2833 DTMF | cl List ports | d Dump status | | * Send DTMF with INFO | cc Connect port | dd Dump detailed | | dq Dump curr. call quality | cd Disconnect port | dc Dump config | | | V Adjust audio Volume | f Save config | | S Send arbitrary REQUEST | Cp Codec priorities | f Save config | +------------------------------+-------------------------- +-------------------+ | q QUIT sleep MS echo [0|1|txt] n: detect NAT type | + = = = = = = =======================================================================+ You have 0 active call >>> rr 14:38:08.568 pjsua_core.c TX 438 bytes Request msg REGISTER/ cseq=53013 (tdta0x862c00) to UDP 69.90.155.70:5060: REGISTER sip:fwd.pulver.com SIP/2.0 Via: SIP/2.0/UDP 91.77.38.3:5060;rport;branch=z9hG4bKPjl- GQJphTm15ASXgtNJDrAF2kMvquevyQ Max-Forwards: 70 From: <sip:902984@xxxxxxxxxxxxxx>;tag=Kmb-O4wNiSJ-rl0.jmWvkX8aepZgiLCz To: <sip:902984 at fwd.pulver.com> Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53013 REGISTER User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 Contact: <sip:902984 at 91.77.38.3:5060> Expires: 300 Content-Length: 0 --end msg-- 14:38:08.569 pjsua_acc.c Registration sent >>> 14:38:08.735 pjsua_core.c RX 474 bytes Response msg 401/ REGISTER/cseq=53013 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 91.77.38.3:5060;rport=5060;branch=z9hG4bKPjl- GQJphTm15ASXgtNJDrAF2kMvquevyQ;received=91.78.83.150 From: <sip:902984@xxxxxxxxxxxxxx>;tag=Kmb-O4wNiSJ-rl0.jmWvkX8aepZgiLCz To: <sip: 902984 at fwd.pulver.com>;tag=cb2000b247d89723001a836145f3b053.174e Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53013 REGISTER WWW-Authenticate: Digest realm="fwd.pulver.com", nonce="498048453446637e1fac55a1ab535e67775650ed" Content-Length: 0 --end msg-- 14:38:08.735 pjsua_core.c TX 624 bytes Request msg REGISTER/ cseq=53014 (tdta0x862c00) to UDP 69.90.155.70:5060: REGISTER sip:fwd.pulver.com SIP/2.0 Via: SIP/2.0/UDP 91.77.38.3:5060;rport;branch=z9hG4bKPjzrOpJtYvxnheKgUR5Fs7uObI7MWAzr9- Max-Forwards: 70 From: <sip:902984@xxxxxxxxxxxxxx>;tag=Kmb-O4wNiSJ-rl0.jmWvkX8aepZgiLCz To: <sip:902984 at fwd.pulver.com> Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53014 REGISTER User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 Contact: <sip:902984 at 91.77.38.3:5060> Expires: 300 Authorization: Digest username="902984", realm="fwd.pulver.com", nonce="498048453446637e1fac55a1ab535e67775650ed", uri="sip:fwd.pulver.com ", response="c316d4ad86eea04944e4b0bb581e123f" Content-Length: 0 --end msg-- 14:38:08.905 pjsua_core.c RX 459 bytes Response msg 200/REGISTER/ cseq=53014 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 91.77.38.3 : 5060 ;rport = 5060 ;branch=z9hG4bKPjzrOpJtYvxnheKgUR5Fs7uObI7MWAzr9-;received=91.78.83.150 From: <sip:902984@xxxxxxxxxxxxxx>;tag=Kmb-O4wNiSJ-rl0.jmWvkX8aepZgiLCz To: <sip: 902984 at fwd.pulver.com>;tag=cb2000b247d89723001a836145f3b053.f35b Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53014 REGISTER Date: Wed, 28 Jan 2009 11:52:58 GMT Contact: <sip:902984 at 91.77.38.3:5060>;q=0.1;expires=300 Content-Length: 0 --end msg-- 14:38:08.905 pjsua_acc.c IP address change detected for account 2 (91.77.38.3:5060 --> 91.78.83.150:5060). Updating registration.. 14:38:08.906 pjsua_core.c TX 436 bytes Request msg REGISTER/ cseq=53015 (tdta0x869400) to UDP 69.90.155.70:5060: REGISTER sip:fwd.pulver.com SIP/2.0 Via: SIP/2.0/UDP 91.77.38.3:5060;rport;branch=z9hG4bKPjaI44fFEUx2GfpSNiV.L10nRCO2r4xL3- Max-Forwards: 70 From: <sip:902984@xxxxxxxxxxxxxx>;tag=h7Arhy.nXgWRTWXqaRJwR2qoe94kc56. To: <sip:902984 at fwd.pulver.com> Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53015 REGISTER User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 Contact: <sip:902984 at 91.77.38.3:5060> Expires: 0 Content-Length: 0 --end msg-- 14:38:08.906 pjsua_acc.c Unregistration sent 14:38:08.906 pjsua_core.c TX 456 bytes Request msg REGISTER/ cseq=22497 (tdta0x86b800) to UDP 69.90.155.70:5060: REGISTER sip:fwd.pulver.com SIP/2.0 Via: SIP/2.0/UDP 91.78.83.150:5060;rport;branch=z9hG4bKPjdcdKu4a- IBVlLr-x21lKjH7GAZuE-mId Max-Forwards: 70 From: <sip:902984@xxxxxxxxxxxxxx>;tag=IgtDgCFV8DWYBdKFhvlb3E7a7.hsGzJ- To: <sip:902984 at fwd.pulver.com> Call-ID: z7j94zGzsLqub4wFk24rtmaHrcSgSsrj CSeq: 22497 REGISTER User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 Contact: <sip:902984 at 91.78.83.150:5060;transport=UDP> Expires: 300 Content-Length: 0 --end msg-- 14:38:08.906 pjsua_acc.c Registration sent 14:38:09.072 pjsua_core.c RX 474 bytes Response msg 401/REGISTER/ cseq=53015 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 91.77.38.3 : 5060 ;rport = 5060 ;branch=z9hG4bKPjaI44fFEUx2GfpSNiV.L10nRCO2r4xL3-;received=91.78.83.150 From: <sip:902984@xxxxxxxxxxxxxx>;tag=h7Arhy.nXgWRTWXqaRJwR2qoe94kc56. To: <sip: 902984 at fwd.pulver.com>;tag=cb2000b247d89723001a836145f3b053.6b8d Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53015 REGISTER WWW-Authenticate: Digest realm="fwd.pulver.com", nonce="4980484656e705d557f15d2b6e8ce92ecd6147ae" Content-Length: 0 --end msg-- 14:38:09.073 pjsua_core.c TX 624 bytes Request msg REGISTER/ cseq=53016 (tdta0x869400) to UDP 69.90.155.70:5060: REGISTER sip:fwd.pulver.com SIP/2.0 Via: SIP/2.0/UDP 91.78.83.150:5060;rport;branch=z9hG4bKPj-48eiTBITT- wNWnCM1zTLI.w6Rsh4L.E Max-Forwards: 70 From: <sip:902984@xxxxxxxxxxxxxx>;tag=h7Arhy.nXgWRTWXqaRJwR2qoe94kc56. To: <sip:902984 at fwd.pulver.com> Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53016 REGISTER User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 Contact: <sip:902984 at 91.77.38.3:5060> Expires: 0 Authorization: Digest username="902984", realm="fwd.pulver.com", nonce="4980484656e705d557f15d2b6e8ce92ecd6147ae", uri="sip:fwd.pulver.com ", response="17f967a736630f19647e0530dc343139" Content-Length: 0 --end msg-- 14:38:09.078 pjsua_core.c RX 454 bytes Response msg 401/REGISTER/ cseq=22497 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 91.78.83.150:5060;rport=5060;branch=z9hG4bKPjdcdKu4a- IBVlLr-x21lKjH7GAZuE-mId From: <sip:902984@xxxxxxxxxxxxxx>;tag=IgtDgCFV8DWYBdKFhvlb3E7a7.hsGzJ- To: <sip: 902984 at fwd.pulver.com>;tag=cb2000b247d89723001a836145f3b053.2ad0 Call-ID: z7j94zGzsLqub4wFk24rtmaHrcSgSsrj CSeq: 22497 REGISTER WWW-Authenticate: Digest realm="fwd.pulver.com", nonce="4980484656e705d557f15d2b6e8ce92ecd6147ae" Content-Length: 0 --end msg-- 14:38:09.079 pjsua_core.c TX 642 bytes Request msg REGISTER/ cseq=22498 (tdta0x86b800) to UDP 69.90.155.70:5060: REGISTER sip:fwd.pulver.com SIP/2.0 Via: SIP/2.0/UDP 91.78.83.150:5060;rport;branch=z9hG4bKPjbeEHBuFCE11IVrRa6ErYf2r7k4MkazAw Max-Forwards: 70 From: <sip:902984@xxxxxxxxxxxxxx>;tag=IgtDgCFV8DWYBdKFhvlb3E7a7.hsGzJ- To: <sip:902984 at fwd.pulver.com> Call-ID: z7j94zGzsLqub4wFk24rtmaHrcSgSsrj CSeq: 22498 REGISTER User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 Contact: <sip:902984 at 91.78.83.150:5060;transport=UDP> Expires: 300 Authorization: Digest username="902984", realm="fwd.pulver.com", nonce="4980484656e705d557f15d2b6e8ce92ecd6147ae", uri="sip:fwd.pulver.com ", response="17f967a736630f19647e0530dc343139" Content-Length: 0 --end msg-- 14:38:09.242 pjsua_core.c RX 382 bytes Response msg 200/REGISTER/ cseq=53016 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 91.78.83.150:5060;rport=5060;branch=z9hG4bKPj-48eiTBITT- wNWnCM1zTLI.w6Rsh4L.E From: <sip:902984@xxxxxxxxxxxxxx>;tag=h7Arhy.nXgWRTWXqaRJwR2qoe94kc56. To: <sip: 902984 at fwd.pulver.com>;tag=cb2000b247d89723001a836145f3b053.161b Call-ID: gCHWO3mIKNSCYMFTfUFRSacnrYGYhXtN CSeq: 53016 REGISTER Date: Wed, 28 Jan 2009 11:52:58 GMT Content-Length: 0 --end msg-- 14:38:09.251 pjsua_core.c RX 455 bytes Response msg 200/REGISTER/ cseq=22498 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 91.78.83.150 :5060;rport=5060;branch=z9hG4bKPjbeEHBuFCE11IVrRa6ErYf2r7k4MkazAw From: <sip:902984@xxxxxxxxxxxxxx>;tag=IgtDgCFV8DWYBdKFhvlb3E7a7.hsGzJ- To: <sip: 902984 at fwd.pulver.com>;tag=cb2000b247d89723001a836145f3b053.73ba Call-ID: z7j94zGzsLqub4wFk24rtmaHrcSgSsrj CSeq: 22498 REGISTER Date: Wed, 28 Jan 2009 11:52:58 GMT Contact: <sip:902984 at 91.78.83.150:5060;transport=UDP>;q=0.1;expires=300 Content-Length: 0 --end msg-- 14:38:09.251 pjsua_acc.c sip:902984 at fwd.pulver.com: registration success, status=200 (OK), will re-register in 300 seconds 14:38:09.251 pjsua_acc.c Keep-alive timer started for acc 2, destination:69.90.155.70:5060, interval:15s >>>> Account list: [ 0] <sip:91.77.38.3:5060>: does not register Online status: Online [ 1] <sip:192.168.1.10:5060;transport=TCP>: does not register Online status: Online *[ 2] sip:902984 at fwd.pulver.com: 200/OK (expires=81) Online status: Offline Buddy list: -none- + = = = = = = =======================================================================+ | Call Commands: | Buddy, IM & Presence: | Account: | | | | | | m Make new call | +b Add new buddy .| +a Add new accnt | | M Make multiple calls | -b Delete buddy | -a Delete accnt. | | a Answer call | i Send IM | !a Modify accnt. | | h Hangup call (ha=all) | s Subscribe presence | rr (Re-)register | | H Hold call | u Unsubscribe presence | ru Unregister | | v re-inVite (release hold) | t ToGgle Online status | > Cycle next ac.| | U send UPDATE | T Set online status | < Cycle prev ac.| | ],[ Select next/prev call +-------------------------- +-------------------+ | x Xfer call | Media Commands: | Status & Config: | | X Xfer with Replaces | | | | # Send RFC 2833 DTMF | cl List ports | d Dump status | | * Send DTMF with INFO | cc Connect port | dd Dump detailed | | dq Dump curr. call quality | cd Disconnect port | dc Dump config | | | V Adjust audio Volume | f Save config | | S Send arbitrary REQUEST | Cp Codec priorities | f Save config | +------------------------------+-------------------------- +-------------------+ | q QUIT sleep MS echo [0|1|txt] n: detect NAT type | + = = = = = = =======================================================================+ You have 0 active call >>> m (You currently have 0 calls) Buddy list: -none- Choices: 0 For current dialog. -1 All 0 buddies in buddy list [1 - 0] Select from buddy list URL An URL <Enter> Empty input (or 'q') to cancel Make call: sip:612 at fwd.pulver.com 14:41:54.372 pjsua_call.c Making call with acc #2 to sip:612 at fwd.pulver.com 14:41:54.372 pjsua_media.c Media index 0 selected for call 1 14:41:54.372 icetp01 ICE session created, comp_cnt=2, role is Controlling agent 14:41:54.372 icetp01 Candidate 0 added: comp_id=1, type=host, foundation=H, addr=192.168.1.10:53706, base=192.168.1.10:53706, prio=0x17 (23) 14:41:54.372 icetp01 Candidate 1 added: comp_id=2, type=host, foundation=H, addr=192.168.1.10:53707, base=192.168.1.10:53707, prio=0x16 (22) 14:41:54.372 pjsua_core.c TX 1203 bytes Request msg INVITE/ cseq=30193 (tdta0x864a00) to UDP 69.90.155.70:5060: INVITE sip:612 at fwd.pulver.com SIP/2.0 Via: SIP/2.0/UDP 91.78.83.150:5060;rport;branch=z9hG4bKPjqGtF76H6KHTHyXHXHQmzWw3IsN6FBkZ9 Max-Forwards: 70 From: sip:902984@xxxxxxxxxxxxxx;tag=nulHw0zLPId9Lmda.GfWDIY-z85vetb- To: sip:612 at fwd.pulver.com Contact: <sip:902984 at 91.78.83.150:5060;transport=UDP> Call-ID: Orvyt0DCa48yjjlVMvO24KxYTU.AOwxM CSeq: 30193 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, norefersub User-Agent: PJSUA v1.0.1/i386-apple-darwin9.6.0 Content-Type: application/sdp Content-Length: 601 v=0 o=- 3442131714 3442131714 IN IP4 91.77.38.3 s=pjmedia c=IN IP4 91.77.38.3 t=0 0 a=X-nat:8 m=audio 53706 RTP/AVP 103 102 104 117 3 0 8 9 101 a=rtcp:53707 IN IP4 91.77.38.3 a=rtpmap:103 speex/16000 a=rtpmap:102 speex/8000 a=rtpmap:104 speex/32000 a=rtpmap:117 iLBC/8000 a=fmtp:117 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 a=ice-ufrag:2147b80b a=ice-pwd:6b83eb3e a=candidate:H 1 UDP 23 192.168.1.10 53706 typ host a=candidate:H 2 UDP 22 192.168.1.10 53707 typ host --end msg-- 14:41:54.373 pjsua_app.c Call 1 state changed to CALLING >>> 14:41:54.547 pjsua_core.c RX 298 bytes Response msg 100/ INVITE/cseq=30193 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 91.78.83.150 :5060;rport=5060;branch=z9hG4bKPjqGtF76H6KHTHyXHXHQmzWw3IsN6FBkZ9 From: sip:902984@xxxxxxxxxxxxxx;tag=nulHw0zLPId9Lmda.GfWDIY-z85vetb- To: sip:612 at fwd.pulver.com Call-ID: Orvyt0DCa48yjjlVMvO24KxYTU.AOwxM CSeq: 30193 INVITE Content-Length: 0 --end msg-- 14:41:54.548 pjsua_core.c RX 330 bytes Response msg 100/INVITE/ cseq=30193 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 91.78.83.150 :5060;rport=5060;branch=z9hG4bKPjqGtF76H6KHTHyXHXHQmzWw3IsN6FBkZ9 From: sip:902984@xxxxxxxxxxxxxx;tag=nulHw0zLPId9Lmda.GfWDIY-z85vetb- To: sip:612 at fwd.pulver.com Call-ID: Orvyt0DCa48yjjlVMvO24KxYTU.AOwxM CSeq: 30193 INVITE Content-Length: 0 --end msg-- 14:41:54.549 pjsua_core.c RX 427 bytes Response msg 180/INVITE/ cseq=30193 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 91.78.83.150 :5060;rport=5060;branch=z9hG4bKPjqGtF76H6KHTHyXHXHQmzWw3IsN6FBkZ9 From: sip:902984@xxxxxxxxxxxxxx;tag=nulHw0zLPId9Lmda.GfWDIY-z85vetb- To: sip:612 at fwd.pulver.com;tag=as2a8b582a Call-ID: Orvyt0DCa48yjjlVMvO24KxYTU.AOwxM CSeq: 30193 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Contact: <sip:612 at 69.90.168.13:5028> Content-Length: 0 --end msg-- 14:41:54.549 conference.c Port 1 (ringback) transmitting to port 0 (Built-in Microphone) 14:41:54.549 pjsua_app.c Call 1 state changed to EARLY (180 Ringing) 14:41:54.563 sound_port.c EC activated 14:41:57.541 pjsua_core.c RX 798 bytes Response msg 200/INVITE/ cseq=30193 (rdata0x829464) from UDP 69.90.155.70:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 91.78.83.150 :5060;rport=5060;branch=z9hG4bKPjqGtF76H6KHTHyXHXHQmzWw3IsN6FBkZ9 Record-Route: <sip:69.90.155.70;ftag=nulHw0zLPId9Lmda.GfWDIY-z85vetb-;lr=on > From: sip:902984@xxxxxxxxxxxxxx;tag=nulHw0zLPId9Lmda.GfWDIY-z85vetb- To: sip:612 at fwd.pulver.com;tag=as2a8b582a Call-ID: Orvyt0DCa48yjjlVMvO24KxYTU.AOwxM CSeq: 30193 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER Contact: <sip:612 at 69.90.168.13:5028> Content-Type: application/sdp Content-Length: 265 v=0 o=root 20571 20571 IN IP4 69.90.168.13 s=session c=IN IP4 69.90.168.13 t=0 0 m=audio 11954 RTP/AVP 0 3 97 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:97 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --end msg-- 14:41:57.541 pjsua_app.c Call 1 state changed to CONNECTING 14:41:57.541 icetp01 Stopping ICE, reason=Remote answer doesn't support ICE 14:41:57.541 icetp01 Destroying ICE session 14:41:57.541 strm0x870174 VAD temporarily disabled 14:41:57.541 strm0x870174 Encoder stream started 14:41:57.541 strm0x870174 Decoder stream started 14:41:57.541 pjsua_media.c Media updates, stream #0: PCMU (sendrecv) 14:41:57.541 conference.c Port 1 (ringback) stop transmitting to port 0 (Built-in Microphone) 14:41:57.542 conference.c Port 3 (sip:612 at fwd.pulver.com) transmitting to port 0 (Built-in Microphone) 14:41:57.542 conference.c Port 0 (Built-in Microphone) transmitting to port 3 (sip:612 at fwd.pulver.com) 14:41:57.542 pjsua_app.c Media for call 1 is active 14:41:57.542 pjsua_core.c TX 411 bytes Request msg ACK/cseq=30193 (tdta0x867200) to UDP 69.90.155.70:5060: ACK sip:612 at 69.90.168.13:5028 SIP/2.0 Via: SIP/2.0/UDP 91.78.83.150:5060;rport;branch=z9hG4bKPjss7EnqBpJBNQVO58O2gtf8YJq-weus6X Max-Forwards: 70 From: sip:902984@xxxxxxxxxxxxxx;tag=nulHw0zLPId9Lmda.GfWDIY-z85vetb- To: sip:612 at fwd.pulver.com;tag=as2a8b582a Call-ID: Orvyt0DCa48yjjlVMvO24KxYTU.AOwxM CSeq: 30193 ACK Route: <sip:69.90.155.70;lr;ftag=nulHw0zLPId9Lmda.GfWDIY-z85vetb-> Content-Length: 0 --end msg-- 14:41:57.542 pjsua_app.c Call 1 state changed to CONFIRMED 14:41:58.177 strm0x870174 VAD re-enabled 14:42:01.559 sound_port.c EC suspended because of inactivity 14:42:05.990 pjsua_core.c RX 564 bytes Request msg BYE/cseq=102 (rdata0x829464) from UDP 69.90.155.70:5060: BYE sip:902984 at 91.78.83.150:5060 SIP/2.0 Max-Forwards: 10 Record-Route: <sip:69.90.155.70;ftag=as2a8b582a;lr=on> Via: SIP/2.0/UDP 69.90.155.70;branch=z9hG4bKff03.f22af995.0 Via: SIP/2.0/UDP 69.90.168.13:5028;branch=z9hG4bK17cc521b Route: <sip:902984 at 91.78.83.150:5060;transport=UDP> From: sip:612@xxxxxxxxxxxxxx;tag=as2a8b582a To: sip:902984 at fwd.pulver.com;tag=nulHw0zLPId9Lmda.GfWDIY-z85vetb- Contact: <sip:612 at 69.90.168.13:5028> Call-ID: Orvyt0DCa48yjjlVMvO24KxYTU.AOwxM CSeq: 102 BYE User-Agent: Asterisk PBX Content-Length: 0 P-hint: NON-LOCAL --end msg-- 14:42:05.990 pjsua_core.c TX 408 bytes Response msg 200/BYE/ cseq=102 (tdta0x868200) to UDP 69.90.155.70:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 69.90.155.70;received=69.90.155.70;branch=z9hG4bKff03.f22af995.0 Via: SIP/2.0/UDP 69.90.168.13:5028;branch=z9hG4bK17cc521b Record-Route: <sip:69.90.155.70;lr;ftag=as2a8b582a> Call-ID: Orvyt0DCa48yjjlVMvO24KxYTU.AOwxM From: <sip:612@xxxxxxxxxxxxxx>;tag=as2a8b582a To: <sip:902984 at fwd.pulver.com>;tag=nulHw0zLPId9Lmda.GfWDIY-z85vetb- CSeq: 102 BYE Content-Length: 0 --end msg-- 14:42:05.990 pjsua_app.c Call 1 is DISCONNECTED [reason=200 (Normal call clearing)] 14:42:05.991 pjsua_app.c [DISCONNCTD] To: sip:612 at fwd.pulver.com;tag=as2a8b582a Call time: 00h:00m:08s, 1st res in 177 ms, conn in 3170ms SRTP status: Not active Crypto-suite: (null) #0 PCMU @8KHz, sendrecv, peer=- RX pt=0, stat last update: 00h:00m:03.728s ago total 1pkt 0B (40B +IP hdr) @avg=0bps/37bps pkt loss=0 (0.0%), discrd=0 (0.0%), dup=0 (0.0%), reord=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.000 0.000 0.000 0.000 0.000 TX pt=0, ptime=20ms, stat last update: never total 262pkt 41.9KB (52.4KB +IP hdr) @avg 39.6Kbps/49.6Kbps pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%) (msec) min avg max last dev loss period: 0.000 0.000 0.000 0.000 0.000 jitter : 0.000 0.000 0.000 0.000 0.000 RTT msec : 0.000 0.000 0.000 0.000 0.000 14:42:05.991 pjsua_media.c Media session for call 1 is destroyed 14:42:05.991 icetp01 Stopping ICE, reason=media stop requested Regards, Alexei > > > cheers > Benny > > > Is there anything I've missed? > > Alexei