[pjsip] Cannot register with SIP server

David Villasmil Govea david.villasmil at gmail.com
Fri Dec 9 06:16:54 EST 2016


Just tested on my side, i can register with no problems via tcp on my test
server, difference is you use "register" whereas I use --"registrar"... but
my version is 2.4
ᐧ

On Fri, Dec 9, 2016 at 12:06 PM, David Villasmil Govea <
david.villasmil at gmail.com> wrote:

> It seems to be sending n INVITE before completing the registration, is
> this what you're trying to do?
>
> the registration is indeed succesful:
>
> SIP/2.0 200 OK
> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
> Contact: <sip:0954333845 at 78.214.178.85:5060;ob>;expires=1800
> CSeq: 24439 REGISTER
> From: <sip:0954333845 at freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
> To: <sip:0954333845 at freephonie.net>;tag=00-08185-0143a177-4f6cbbc92
> Via: SIP/2.0/UDP 78.214.178.85:5060;received=78
> .214.178.85;rport=5060;branch=z9hG4bKPjYcKUUuh8Q4G6bsXz7sLuv72qp2JhP7Nm
> P-Associated-URI: <sip:0954333845 at freephonie.net>
> Server: Cirpack/v4.42q (gw_sip)
> Content-Length: 0
>
> but it sends an invite before that, to which you get a "not registered"...
>>
> On Fri, Dec 9, 2016 at 9:54 AM, Eric Le Bras <eric.lebras at gmail.com>
> wrote:
>
>> I just tried. Here are my parameters :
>> --id sip:0954xxxxxx at freephonie.net
>> --register sip:freephonie.net
>> --realm *
>> --username 0954xxxxxx
>> --password xxxxxxxx
>> --reg-timeout=1800
>> --log-file log.txt
>>
>> And below is the complete log.txt file obtained. I still receive the
>> message :
>> SIP/2.0 401 Unauthorized
>>
>> I doubled checked the username and password.
>>
>>
>> 09:40:14.852 sip_endpoint.c  .Module "mod-pjsua-log" registered
>> 09:40:14.852 sip_endpoint.c  .Module "mod-tsx-layer" registered
>> 09:40:14.852 sip_endpoint.c  .Module "mod-stateful-util" registered
>> 09:40:14.853 sip_endpoint.c  .Module "mod-ua" registered
>> 09:40:14.853 sip_endpoint.c  .Module "mod-100rel" registered
>> 09:40:14.853 sip_endpoint.c  .Module "mod-pjsua" registered
>> 09:40:14.854 sip_endpoint.c  .Module "mod-invite" registered
>> 09:40:14.895     alsa_dev.c  ..Added sound device default:CARD=audiocodec
>> 09:40:14.897     alsa_dev.c  ..Added sound device
>> sysdefault:CARD=audiocodec
>> 09:40:14.918     alsa_dev.c  ..Added sound device
>> dmix:CARD=audiocodec,DEV=0
>> 09:40:15.128     alsa_dev.c  ..Added sound device
>> dsnoop:CARD=audiocodec,DEV=0
>> 09:40:15.132     alsa_dev.c  ..Added sound device hw:CARD=audiocodec,DEV=0
>> 09:40:15.136     alsa_dev.c  ..Added sound device
>> plughw:CARD=audiocodec,DEV=0
>> 09:40:15.143     alsa_dev.c  ..Added sound device default:CARD=sndhdmi
>> 09:40:15.151     alsa_dev.c  ..Added sound device sysdefault:CARD=sndhdmi
>> 09:40:15.184     alsa_dev.c  ..Added sound device dmix:CARD=sndhdmi,DEV=0
>> 09:40:15.203     alsa_dev.c  ..Added sound device
>> dsnoop:CARD=sndhdmi,DEV=0
>> 09:40:15.207     alsa_dev.c  ..Added sound device hw:CARD=sndhdmi,DEV=0
>> 09:40:15.212     alsa_dev.c  ..Added sound device
>> plughw:CARD=sndhdmi,DEV=0
>> 09:40:15.212     alsa_dev.c  ..ALSA driver found 12 devices
>> 09:40:15.212     alsa_dev.c  ..ALSA initialized
>> 09:40:15.213          pjlib  ..select() I/O Queue created (0x1b2cbfc)
>> 09:40:15.216   conference.c  ..Creating conference bridge with 254 ports
>> 09:40:15.217   Master/sound  ..Using delay buffer with WSOLA.
>> 09:40:15.235 sip_endpoint.c  .Module "mod-evsub" registered
>> 09:40:15.235 sip_endpoint.c  .Module "mod-presence" registered
>> 09:40:15.235        evsub.c  .Event pkg "presence" registered by
>> mod-presence
>> 09:40:15.236 sip_endpoint.c  .Module "mod-mwi" registered
>> 09:40:15.236        evsub.c  .Event pkg "message-summary" registered by
>> mod-mwi
>> 09:40:15.236 sip_endpoint.c  .Module "mod-refer" registered
>> 09:40:15.236        evsub.c  .Event pkg "refer" registered by mod-refer
>> 09:40:15.236 sip_endpoint.c  .Module "mod-pjsua-pres" registered
>> 09:40:15.236 sip_endpoint.c  .Module "mod-pjsua-im" registered
>> 09:40:15.236 sip_endpoint.c  .Module "mod-pjsua-options" registered
>> 09:40:15.237   pjsua_core.c  .1 SIP worker threads created
>> 09:40:15.237   pjsua_core.c  .pjsua version 2.5.5 for
>> Linux-3.4.113/armv7l/glibc-2.19 initialized
>> 09:40:15.237   pjsua_core.c  .PJSUA state changed: CREATED --> INIT
>> 09:40:15.237 sip_endpoint.c  Module "mod-default-handler" registered
>> 09:40:15.238   pjsua_core.c  SIP UDP socket reachable at 192.168.0.1:5060
>> 09:40:15.239   udp0x1b3d9a0  SIP UDP transport started, published address
>> is 192.168.0.1:5060
>> 09:40:15.239    pjsua_acc.c  Adding account: id=<sip:192.168.0.1:5060>
>> 09:40:15.239    pjsua_acc.c  .Account <sip:192.168.0.1:5060> added with
>> id 0
>> 09:40:15.239    pjsua_acc.c  Modifying account 0
>> 09:40:15.240    pjsua_acc.c  Acc 0: setting online status to 1..
>> 09:40:15.241     tcptp:5060  SIP TCP listener ready for incoming
>> connections at 192.168.0.1:5060
>> 09:40:15.241    pjsua_acc.c  Adding account: id=<sip:192.168.0.1:5060
>> ;transport=TCP>
>> 09:40:15.241    pjsua_acc.c  .Account <sip:192.168.0.1:5060;transport=TCP>
>> added with id 1
>> 09:40:15.241    pjsua_acc.c  Modifying account 1
>> 09:40:15.241    pjsua_acc.c  Acc 1: setting online status to 1..
>> 09:40:15.241    pjsua_acc.c  Adding account: id=<
>> sip:0954333845 at freephonie.net>
>> 09:40:15.242    pjsua_acc.c  .Account <sip:0954333845 at freephonie.net>
>> added with id 2
>> 09:40:15.242    pjsua_acc.c  .Acc 2: setting registration..
>> 09:40:15.242       endpoint  ..Request msg REGISTER/cseq=24437
>> (tdta0x1b42b60) created.
>> 09:40:15.242   tsx0x1b43bcc  ...Transaction created for Request msg
>> REGISTER/cseq=24438 (tdta0x1b42b60)
>> 09:40:15.242   tsx0x1b43bcc  ..Sending Request msg REGISTER/cseq=24438
>> (tdta0x1b42b60) in state Null
>> 09:40:15.242  sip_resolve.c  ...DNS resolver not available, target '
>> freephonie.net:0' type=Unspecified will be resolved with getaddrinfo()
>> 09:40:15.245  sip_resolve.c  ...Target 'freephonie.net:0'
>> type=Unspecified resolved to '212.27.52.5:5060' type=UDP (UDP transport)
>> 09:40:15.245   pjsua_core.c  ...TX 563 bytes Request msg
>> REGISTER/cseq=24438 (tdta0x1b42b60) to UDP 212.27.52.5:5060:
>> REGISTER sip:freephonie.net SIP/2.0
>> Via: SIP/2.0/UDP 192.168.0.1:5060;rport;branch=
>> z9hG4bKPjxbTmp0917JzK7dkn6RcoIGNMXOGqFs39
>> Max-Forwards: 70
>> From: <sip:0954333845 at freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu
>> AMg1wj9S
>> To: <sip:0954333845 at freephonie.net>
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> CSeq: 24438 REGISTER
>> User-Agent: PJSUA v2.5.5 Linux-3.4.113/armv7l/glibc-2.19
>> Contact: <sip:0954333845 at 192.168.0.1:5060;ob>
>> Expires: 1800
>> Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY,
>> REFER, MESSAGE, OPTIONS
>> Content-Length:  0
>>
>>
>> --end msg--
>> 09:40:15.246   tsx0x1b43bcc  ...State changed from Null to Calling,
>> event=TX_MSG
>> 09:40:15.246    pjsua_acc.c  ..Acc 2: Registration sent
>> 09:40:15.246    pjsua_acc.c  Acc 2: setting online status to 1..
>> 09:40:15.246   pjsua_pres.c  Adding buddy: sip:0442419862 at freephonie.net;
>> user=phone
>> 09:40:15.247   pjsua_pres.c  .Buddy 0 added.
>> 09:40:15.247   pjsua_pres.c  ..Buddy 0: updating presence..
>> 09:40:15.247   pjsua_core.c  PJSUA state changed: INIT --> STARTING
>> 09:40:15.247 sip_endpoint.c  .Module "mod-unsolicited-mwi" registered
>> 09:40:15.247   pjsua_core.c  .PJSUA state changed: STARTING --> RUNNING
>> 09:40:15.247         main.c  Ready: Success
>> 09:40:15.248   pjsua_call.c  Making call with acc #2 to
>> sip:0442419862 at freephonie.net;user=phone
>> 09:40:15.248    pjsua_aud.c  .Set sound device: capture=-1, playback=-2
>> 09:40:15.248    pjsua_app.c  ..Turning sound device ON
>> 09:40:15.248    pjsua_aud.c  ..Opening sound device (speaker + mic)
>> PCM at 16000/1/20ms
>> 09:40:15.248     alsa_dev.c  ...open_playback: Open playback device
>> 'default:CARD=audiocodec'
>> 09:40:15.265 sip_endpoint.c !Processing incoming message: Response msg
>> 100/REGISTER/cseq=24438 (rdata0x1b3efd4)
>> 09:40:15.265   pjsua_core.c  .RX 337 bytes Response msg
>> 100/REGISTER/cseq=24438 (rdata0x1b3efd4) from UDP 212.27.52.5:5060:
>> SIP/2.0 100 Trying
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> CSeq: 24438 REGISTER
>> From: <sip:0954333845 at freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu
>> AMg1wj9S
>> To: <sip:0954333845 at freephonie.net>
>> Via: SIP/2.0/UDP 192.168.0.1:5060;received=78.2
>> 14.178.85;rport=5060;branch=z9hG4bKPjxbTmp0917JzK7dkn6RcoIGNMXOGqFs39
>> Content-Length: 0
>>
>>
>> --end msg--
>> 09:40:15.265     alsa_dev.c !...Opened device
>> alsa(default:CARD=audiocodec) for playing, sample rate=16000, ch=1,
>> bits=16, period size=320 frames, latency=140 ms
>> 09:40:15.265     alsa_dev.c  ...open_capture: Open capture device
>> 'default:CARD=audiocodec'
>> 09:40:15.266   tsx0x1b43bcc !.Incoming Response msg
>> 100/REGISTER/cseq=24438 (rdata0x1b3efd4) in state Calling
>> 09:40:15.266   tsx0x1b43bcc  ..State changed from Calling to Proceeding,
>> event=RX_MSG
>> 09:40:15.266 sip_endpoint.c  Processing incoming message: Response msg
>> 401/REGISTER/cseq=24438 (rdata0xb5900494)
>> 09:40:15.266   pjsua_core.c  .RX 549 bytes Response msg
>> 401/REGISTER/cseq=24438 (rdata0xb5900494) from UDP 212.27.52.5:5060:
>> SIP/2.0 401 Unauthorized
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> CSeq: 24438 REGISTER
>> From: <sip:0954333845 at freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu
>> AMg1wj9S
>> To: <sip:0954333845 at freephonie.net>;tag=00-08185-0143a174-789f83805
>> Via: SIP/2.0/UDP 192.168.0.1:5060;received=78.2
>> 14.178.85;rport=5060;branch=z9hG4bKPjxbTmp0917JzK7dkn6RcoIGNMXOGqFs39
>> WWW-Authenticate: Digest realm="freephonie.net",nonce="
>> 0143a1405e59eb476fdae42f742a7877",opaque="014304f23e5124b",s
>> tale=false,algorithm=MD5
>> Server: Cirpack/v4.42q (gw_sip)
>> Content-Length: 0
>>
>>
>> --end msg--
>> 09:40:15.267   tsx0x1b43bcc  .Incoming Response msg
>> 401/REGISTER/cseq=24438 (rdata0xb5900494) in state Proceeding
>> 09:40:15.267   tsx0x1b43bcc  ..State changed from Proceeding to
>> Completed, event=RX_MSG
>> 09:40:15.475     alsa_dev.c !...Opened device
>> alsa(default:CARD=audiocodec) for capture, sample rate=16000, ch=1,
>> bits=16, period size=320 frames, latency=100 ms
>> 09:40:15.475    ec0x1b32490  ...Creating AEC
>> 09:40:15.478    ec0x1b32490  ...AEC created, clock_rate=16000, channel=1,
>> samples per frame=320, tail length=200 ms, latency=0 ms
>> 09:40:15.479     alsa_dev.c !ca_thread_func(6196): Set thread priority
>> for audio capture thread.
>> 09:40:15.479   dlg0x1b6fb2c !.UAC dialog created
>> 09:40:15.479     alsa_dev.c !Unable to increase thread priority, root
>> access needed.
>> 09:40:15.479   dlg0x1b6fb2c !..Session count inc to 2 by mod-pjsua
>> 09:40:15.479  pjsua_media.c  .Call 0: initializing media..
>> 09:40:15.480  pjsua_media.c  ..RTP socket reachable at 192.168.0.1:4000
>> 09:40:15.481  pjsua_media.c  ..RTCP socket reachable at 192.168.0.1:4001
>> 09:40:15.481  pjsua_media.c  ..Media index 0 selected for audio call 0
>> 09:40:15.481   dlg0x1b6fb2c  ..Session count dec to 2 by mod-pjsua
>> 09:40:15.481   dlg0x1b6fb2c  .Module mod-invite added as dialog usage,
>> data=0x1b748bc
>> 09:40:15.481   dlg0x1b6fb2c  ..Session count inc to 4 by mod-invite
>> 09:40:15.481   dlg0x1b6fb2c  .Module mod-100rel added as dialog usage,
>> data=0x1b75754
>> 09:40:15.481   dlg0x1b6fb2c  .100rel module attached
>> 09:40:15.481   inv0x1b6fb2c  .UAC invite session created for dialog
>> dlg0x1b6fb2c
>> 09:40:15.482       endpoint  .Request msg INVITE/cseq=15740
>> (tdta0x1b758d8) created.
>> 09:40:15.482   inv0x1b6fb2c  ..Sending Request msg INVITE/cseq=15740
>> (tdta0x1b758d8)
>> 09:40:15.482   dlg0x1b6fb2c  ...Sending Request msg INVITE/cseq=15740
>> (tdta0x1b758d8)
>> 09:40:15.482   tsx0x1b778ec  ....Transaction created for Request msg
>> INVITE/cseq=15739 (tdta0x1b758d8)
>> 09:40:15.482   tsx0x1b778ec  ...Sending Request msg INVITE/cseq=15739
>> (tdta0x1b758d8) in state Null
>> 09:40:15.482  sip_resolve.c  ....DNS resolver not available, target '
>> freephonie.net:0' type=Unspecified will be resolved with getaddrinfo()
>> 09:40:15.483  sip_resolve.c  ....Target 'freephonie.net:0'
>> type=Unspecified resolved to '212.27.52.5:5060' type=UDP (UDP transport)
>> 09:40:15.483   pjsua_core.c  ....TX 1162 bytes Request msg
>> INVITE/cseq=15739 (tdta0x1b758d8) to UDP 212.27.52.5:5060:
>> INVITE sip:0442419862 at freephonie.net;user=phone SIP/2.0
>> Via: SIP/2.0/UDP 192.168.0.1:5060;rport;branch=
>> z9hG4bKPjgcMx3CGYqIKUOJV6RmPcS6wh8GIkg9oP
>> Max-Forwards: 70
>> From: <sip:0954333845 at freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOw
>> DL6kzdK5
>> To: sip:0442419862 at freephonie.net;user=phone
>> Contact: <sip:0954333845 at 192.168.0.1:5060;ob>
>> Call-ID: h-2-3lZq1Q.JUFOmz7FfgH3O-5eX0OYd
>> CSeq: 15739 INVITE
>> Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY,
>> REFER, MESSAGE, OPTIONS
>> Supported: replaces, 100rel, timer, norefersub
>> Session-Expires: 1800
>> Min-SE: 90
>> User-Agent: PJSUA v2.5.5 Linux-3.4.113/armv7l/glibc-2.19
>> Content-Type: application/sdp
>> Content-Length:   470
>>
>> v=0
>> o=- 3690261615 3690261615 IN IP4 192.168.0.1
>> s=pjmedia
>> b=AS:84
>> t=0 0
>> a=X-nat:0
>> m=audio 4000 RTP/AVP 98 97 99 104 3 0 8 9 96
>> c=IN IP4 192.168.0.1
>> b=TIAS:64000
>> a=rtcp:4001 IN IP4 192.168.0.1
>> a=sendrecv
>> a=rtpmap:98 speex/16000
>> a=rtpmap:97 speex/8000
>> a=rtpmap:99 speex/32000
>> a=rtpmap:104 iLBC/8000
>> a=fmtp:104 mode=30
>> a=rtpmap:3 GSM/8000
>> a=rtpmap:0 PCMU/8000
>> a=rtpmap:8 PCMA/8000
>> a=rtpmap:9 G722/8000
>> a=rtpmap:96 telephone-event/8000
>> a=fmtp:96 0-16
>>
>> --end msg--
>> 09:40:15.486   tsx0x1b778ec  ....State changed from Null to Calling,
>> event=TX_MSG
>> 09:40:15.486   dlg0x1b6fb2c  .....Transaction tsx0x1b778ec state changed
>> to Calling
>> 09:40:15.486    pjsua_app.c  .......Call 0 state changed to CALLING
>> 09:40:15.486    pjsua_acc.c !....IP address change detected for account 2
>> (192.168.0.1:5060 --> 78.214.178.85:5060). Updating registration (using
>> method 4)
>> 09:40:15.486  tsx0xb5902834  ....Transaction created for Request msg
>> REGISTER/cseq=24439 (tdta0x1b42b60)
>> 09:40:15.486  tsx0xb5902834  ...Sending Request msg REGISTER/cseq=24439
>> (tdta0x1b42b60) in state Null
>> 09:40:15.486       endpoint  ....Request msg REGISTER/cseq=24439
>> (tdta0x1b42b60): skipping target resolution because address is already set
>> 09:40:15.487   pjsua_core.c  ....TX 790 bytes Request msg
>> REGISTER/cseq=24439 (tdta0x1b42b60) to UDP 212.27.52.5:5060:
>> REGISTER sip:freephonie.net SIP/2.0
>> Via: SIP/2.0/UDP 78.214.178.85:5060;rport;branc
>> h=z9hG4bKPjYcKUUuh8Q4G6bsXz7sLuv72qp2JhP7Nm
>> Max-Forwards: 70
>> From: <sip:0954333845 at freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu
>> AMg1wj9S
>> To: <sip:0954333845 at freephonie.net>
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> CSeq: 24439 REGISTER
>> User-Agent: PJSUA v2.5.5 Linux-3.4.113/armv7l/glibc-2.19
>> Contact: <sip:0954333845 at 78.214.178.85:5060;ob>
>> Expires: 1800
>> Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY,
>> REFER, MESSAGE, OPTIONS
>> Authorization: Digest username="0954333845", realm="freephonie.net",
>> nonce="0143a1405e59eb476fdae42f742a7877", uri="sip:freephonie.net",
>> response="5d1a41eee573768c50f5db82f8b9fc51", algorithm=MD5,
>> opaque="014304f23e5124b"
>> Content-Length:  0
>>
>>
>> --end msg--
>> 09:40:15.488  tsx0xb5902834  ....State changed from Null to Calling,
>> event=TX_MSG
>> 09:40:15.495          speex !warning: discarded first playback frame
>> 09:40:15.496          speex  warning: discarded first playback frame
>> 09:40:15.496          speex  warning: discarded first playback frame
>> 09:40:15.496          speex  warning: discarded first playback frame
>> 09:40:15.496          speex  warning: discarded first playback frame
>> 09:40:15.496          speex  warning: discarded first playback frame
>> 09:40:15.496          speex  warning: discarded first playback frame
>> 09:40:15.496          speex  warning: discarded first playback frame
>> 09:40:15.507 sip_endpoint.c !Processing incoming message: Response msg
>> 100/INVITE/cseq=15739 (rdata0xb5900494)
>> 09:40:15.507   pjsua_core.c  .RX 346 bytes Response msg
>> 100/INVITE/cseq=15739 (rdata0xb5900494) from UDP 212.27.52.5:5060:
>> SIP/2.0 100 Trying
>> Call-ID: h-2-3lZq1Q.JUFOmz7FfgH3O-5eX0OYd
>> CSeq: 15739 INVITE
>> From: <sip:0954333845 at freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOw
>> DL6kzdK5
>> To: <sip:0442419862 at freephonie.net>;user=phone
>> Via: SIP/2.0/UDP 192.168.0.1:5060;received=78.2
>> 14.178.85;rport=5060;branch=z9hG4bKPjgcMx3CGYqIKUOJV6RmPcS6wh8GIkg9oP
>> Content-Length: 0
>>
>>
>> --end msg--
>> 09:40:15.507   tsx0x1b778ec  .Incoming Response msg 100/INVITE/cseq=15739
>> (rdata0xb5900494) in state Calling
>> 09:40:15.507   tsx0x1b778ec  ..State changed from Calling to Proceeding,
>> event=RX_MSG
>> 09:40:15.508   dlg0x1b6fb2c  ...Received Response msg
>> 100/INVITE/cseq=15739 (rdata0xb5900494)
>> 09:40:15.508   dlg0x1b6fb2c  ...Transaction tsx0x1b778ec state changed to
>> Proceeding
>> 09:40:15.508 sip_endpoint.c  Processing incoming message: Response msg
>> 403/INVITE/cseq=15739 (rdata0xb5900494)
>> 09:40:15.508   pjsua_core.c  .RX 386 bytes Response msg
>> 403/INVITE/cseq=15739 (rdata0xb5900494) from UDP 212.27.52.5:5060:
>> SIP/2.0 403 not registered
>> Call-ID: h-2-3lZq1Q.JUFOmz7FfgH3O-5eX0OYd
>> CSeq: 15739 INVITE
>> From: <sip:0954333845 at freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOw
>> DL6kzdK5
>> To: <sip:0442419862 at freephonie.net>;tag=00-32588-150300fb-596217
>> 0f6;user=phone
>> Via: SIP/2.0/UDP 192.168.0.1:5060;received=78.2
>> 14.178.85;rport=5060;branch=z9hG4bKPjgcMx3CGYqIKUOJV6RmPcS6wh8GIkg9oP
>> Content-Length: 0
>>
>>
>> --end msg--
>> 09:40:15.508   tsx0x1b778ec  .Incoming Response msg 403/INVITE/cseq=15739
>> (rdata0xb5900494) in state Proceeding
>> 09:40:15.509       endpoint  ..Request msg ACK/cseq=15739
>> (tdta0xb59031e0) created.
>> 09:40:15.509   pjsua_core.c  ..TX 398 bytes Request msg ACK/cseq=15739
>> (tdta0xb59031e0) to UDP 212.27.52.5:5060:
>> ACK sip:0442419862 at freephonie.net;user=phone SIP/2.0
>> Via: SIP/2.0/UDP 192.168.0.1:5060;rport;branch=
>> z9hG4bKPjgcMx3CGYqIKUOJV6RmPcS6wh8GIkg9oP
>> Max-Forwards: 70
>> From: <sip:0954333845 at freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOw
>> DL6kzdK5
>> To: sip:0442419862 at freephonie.net;user=phone;tag=00-32588-150300
>> fb-5962170f6
>> Call-ID: h-2-3lZq1Q.JUFOmz7FfgH3O-5eX0OYd
>> CSeq: 15739 ACK
>> Content-Length:  0
>>
>>
>> --end msg--
>> 09:40:15.509   tsx0x1b778ec  ..State changed from Proceeding to
>> Completed, event=RX_MSG
>> 09:40:15.509   dlg0x1b6fb2c  ...Received Response msg
>> 403/INVITE/cseq=15739 (rdata0xb5900494)
>> 09:40:15.509   dlg0x1b6fb2c  ...Transaction tsx0x1b778ec state changed to
>> Completed
>> 09:40:15.510    pjsua_app.c  .....Call 0 is DISCONNECTED [reason=403 (not
>> registered)]
>> 09:40:15.510    pjsua_app.c  .....Call 0 disconnected, dumping media
>> stats..
>> 09:40:15.510 pjsua_app_comm  .....
>>   [DISCONNCTD] To: sip:0442419862 at freephonie.net;user=phone
>>     Call time: 00h:00m:00s, 1st res in 31 ms, conn in 0ms
>> 09:40:15.510  pjsua_media.c  .....Call 0: deinitializing media..
>> 09:40:15.510  pjsua_media.c  ......Call 0: cleaning up provisional media,
>> prov_med_cnt=1, med_cnt=0
>> 09:40:15.510   dlg0x1b6fb2c  .....Session count dec to 1 by mod-invite
>> 09:40:15.511  tdta0x1b758d8  ..Destroying txdata Request msg
>> INVITE/cseq=15739 (tdta0x1b758d8)
>> 09:40:15.511 sip_endpoint.c  Processing incoming message: Response msg
>> 100/REGISTER/cseq=24439 (rdata0xb5900494)
>> 09:40:15.511   pjsua_core.c  .RX 339 bytes Response msg
>> 100/REGISTER/cseq=24439 (rdata0xb5900494) from UDP 212.27.52.5:5060:
>> SIP/2.0 100 Trying
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> CSeq: 24439 REGISTER
>> From: <sip:0954333845 at freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu
>> AMg1wj9S
>> To: <sip:0954333845 at freephonie.net>
>> Via: SIP/2.0/UDP 78.214.178.85:5060;received=78
>> .214.178.85;rport=5060;branch=z9hG4bKPjYcKUUuh8Q4G6bsXz7sLuv72qp2JhP7Nm
>> Content-Length: 0
>>
>>
>> --end msg--
>> 09:40:15.511          speex !warning: discarded first playback frame
>> 09:40:15.511  tsx0xb5902834 !.Incoming Response msg
>> 100/REGISTER/cseq=24439 (rdata0xb5900494) in state Calling
>> 09:40:15.511  tsx0xb5902834  ..State changed from Calling to Proceeding,
>> event=RX_MSG
>> 09:40:15.517 sip_endpoint.c  Processing incoming message: Response msg
>> 200/REGISTER/cseq=24439 (rdata0xb5900494)
>> 09:40:15.517   pjsua_core.c  .RX 513 bytes Response msg
>> 200/REGISTER/cseq=24439 (rdata0xb5900494) from UDP 212.27.52.5:5060:
>> SIP/2.0 200 OK
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> Contact: <sip:0954333845 at 78.214.178.85:5060;ob>;expires=1800
>> CSeq: 24439 REGISTER
>> From: <sip:0954333845 at freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu
>> AMg1wj9S
>> To: <sip:0954333845 at freephonie.net>;tag=00-08185-0143a177-4f6cbbc92
>> Via: SIP/2.0/UDP 78.214.178.85:5060;received=78
>> .214.178.85;rport=5060;branch=z9hG4bKPjYcKUUuh8Q4G6bsXz7sLuv72qp2JhP7Nm
>> P-Associated-URI: <sip:0954333845 at freephonie.net>
>> Server: Cirpack/v4.42q (gw_sip)
>> Content-Length: 0
>>
>>
>> --end msg--
>> 09:40:15.518  tsx0xb5902834  .Incoming Response msg
>> 200/REGISTER/cseq=24439 (rdata0xb5900494) in state Proceeding
>> 09:40:15.518  tsx0xb5902834  ..State changed from Proceeding to
>> Completed, event=RX_MSG
>> 09:40:15.518    pjsua_acc.c  ....SIP outbound status for acc 2 is not
>> active
>> 09:40:15.518    pjsua_acc.c  ....<sip:0954333845 at freephonie.net>:
>> registration success, status=200 (OK), will re-register in 1800 seconds
>> 09:40:15.518    pjsua_acc.c  ....Keep-alive timer started for acc 2,
>> destination:212.27.52.5:5060, interval:15s
>> 09:40:15.531          speex !warning: discarded first playback frame
>> 09:40:15.551          speex  warning: discarded first playback frame
>> 09:40:15.571          speex  warning: discarded first playback frame
>> 09:40:15.591          speex  warning: discarded first playback frame
>> 09:40:15.611          speex  warning: discarded first playback frame
>> 09:40:15.631          speex  warning: discarded first playback frame
>> 09:40:15.651          speex  warning: discarded first playback frame
>> 09:40:15.671          speex  warning: discarded first playback frame
>> 09:40:15.691          speex  warning: discarded first playback frame
>> 09:40:15.711          speex  warning: discarded first playback frame
>> 09:40:16.511    pjsua_aud.c !Closing sound device after idle for 1
>> second(s)
>> 09:40:16.511    pjsua_app.c  .Turning sound device OFF
>> 09:40:16.512    pjsua_aud.c  .Closing default:CARD=audiocodec sound
>> playback device and default:CARD=audiocodec sound capture device
>> 09:40:20.487   tsx0x1b43bcc  Timeout timer event
>> 09:40:20.487   tsx0x1b43bcc  .State changed from Completed to Terminated,
>> event=TIMER
>> 09:40:20.487   tsx0x1b43bcc  Timeout timer event
>> 09:40:20.488   tsx0x1b43bcc  .State changed from Terminated to Destroyed,
>> event=TIMER
>> 09:40:20.488   tsx0x1b43bcc  Transaction destroyed!
>> 09:40:20.518  tsx0xb5902834  Timeout timer event
>> 09:40:20.518  tsx0xb5902834  .State changed from Completed to Terminated,
>> event=TIMER
>> 09:40:20.518  tsx0xb5902834  Timeout timer event
>> 09:40:20.519  tsx0xb5902834  .State changed from Terminated to Destroyed,
>> event=TIMER
>> 09:40:20.519  tdta0x1b42b60  ..Destroying txdata Request msg
>> REGISTER/cseq=24439 (tdta0x1b42b60)
>> 09:40:20.519  tsx0xb5902834  Transaction destroyed!
>> 09:40:30.519    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2
>> to 212.27.52.5:5060
>> 09:40:30.520 tdta0xb5906130  Destroying txdata raw
>> 09:40:31.640   pjsua_core.c !Shutting down, flags=0...
>> 09:40:31.640   pjsua_core.c  PJSUA state changed: RUNNING --> CLOSING
>> 09:40:31.643   pjsua_call.c  .Hangup all calls..
>> 09:40:31.643  pjsua_media.c  .Call 0: deinitializing media..
>> 09:40:31.643  pjsua_media.c  .Call 1: deinitializing media..
>> 09:40:31.643  pjsua_media.c  .Call 2: deinitializing media..
>> 09:40:31.643  pjsua_media.c  .Call 3: deinitializing media..
>> 09:40:31.643   pjsua_pres.c  .Shutting down presence..
>> 09:40:31.644    pjsua_acc.c  .Acc 2: setting unregistration..
>> 09:40:31.644       endpoint  ..Request msg REGISTER/cseq=24439
>> (tdta0x1b42b60) created.
>> 09:40:31.644   tsx0x1b43bcc  ...Transaction created for Request msg
>> REGISTER/cseq=24440 (tdta0x1b42b60)
>> 09:40:31.644   tsx0x1b43bcc  ..Sending Request msg REGISTER/cseq=24440
>> (tdta0x1b42b60) in state Null
>> 09:40:31.644  sip_resolve.c  ...DNS resolver not available, target '
>> freephonie.net:0' type=Unspecified will be resolved with getaddrinfo()
>> 09:40:31.647  sip_resolve.c  ...Target 'freephonie.net:0'
>> type=Unspecified resolved to '212.27.52.5:5060' type=UDP (UDP transport)
>> 09:40:31.647   pjsua_core.c  ...TX 466 bytes Request msg
>> REGISTER/cseq=24440 (tdta0x1b42b60) to UDP 212.27.52.5:5060:
>> REGISTER sip:freephonie.net SIP/2.0
>> Via: SIP/2.0/UDP 78.214.178.85:5060;rport;branc
>> h=z9hG4bKPjjGZWc1jX9btgn5kq39knTcBsBB3b.qu3
>> Max-Forwards: 70
>> From: <sip:0954333845 at freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS
>> Mz4UZJwH
>> To: <sip:0954333845 at freephonie.net>
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> CSeq: 24440 REGISTER
>> User-Agent: PJSUA v2.5.5 Linux-3.4.113/armv7l/glibc-2.19
>> Contact: <sip:0954333845 at 78.214.178.85:5060;ob>
>> Expires: 0
>> Content-Length:  0
>>
>>
>> --end msg--
>> 09:40:31.648   tsx0x1b43bcc  ...State changed from Null to Calling,
>> event=TX_MSG
>> 09:40:31.648    pjsua_acc.c  ..Acc 2: Unregistration sent
>> 09:40:31.667 sip_endpoint.c  .Processing incoming message: Response msg
>> 100/REGISTER/cseq=24440 (rdata0xb5900494)
>> 09:40:31.667   pjsua_core.c  ..RX 339 bytes Response msg
>> 100/REGISTER/cseq=24440 (rdata0xb5900494) from UDP 212.27.52.5:5060:
>> SIP/2.0 100 Trying
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> CSeq: 24440 REGISTER
>> From: <sip:0954333845 at freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS
>> Mz4UZJwH
>> To: <sip:0954333845 at freephonie.net>
>> Via: SIP/2.0/UDP 78.214.178.85:5060;received=78
>> .214.178.85;rport=5060;branch=z9hG4bKPjjGZWc1jX9btgn5kq39knTcBsBB3b.qu3
>> Content-Length: 0
>>
>>
>> --end msg--
>> 09:40:31.667   tsx0x1b43bcc  ..Incoming Response msg
>> 100/REGISTER/cseq=24440 (rdata0xb5900494) in state Calling
>> 09:40:31.668   tsx0x1b43bcc  ...State changed from Calling to Proceeding,
>> event=RX_MSG
>> 09:40:31.668 sip_endpoint.c  .Processing incoming message: Response msg
>> 401/REGISTER/cseq=24440 (rdata0x1b758ec)
>> 09:40:31.668   pjsua_core.c  ..RX 551 bytes Response msg
>> 401/REGISTER/cseq=24440 (rdata0x1b758ec) from UDP 212.27.52.5:5060:
>> SIP/2.0 401 Unauthorized
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> CSeq: 24440 REGISTER
>> From: <sip:0954333845 at freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS
>> Mz4UZJwH
>> To: <sip:0954333845 at freephonie.net>;tag=00-08174-0143a25d-2f30fb491
>> Via: SIP/2.0/UDP 78.214.178.85:5060;received=78
>> .214.178.85;rport=5060;branch=z9hG4bKPjjGZWc1jX9btgn5kq39knTcBsBB3b.qu3
>> WWW-Authenticate: Digest realm="freephonie.net",nonce="
>> 0143a1e55e2ab7514990163420a562e4",opaque="014304f23e5124b",s
>> tale=false,algorithm=MD5
>> Server: Cirpack/v4.42q (gw_sip)
>> Content-Length: 0
>>
>>
>> --end msg--
>> 09:40:31.668   tsx0x1b43bcc  ..Incoming Response msg
>> 401/REGISTER/cseq=24440 (rdata0x1b758ec) in state Proceeding
>> 09:40:31.668   tsx0x1b43bcc  ...State changed from Proceeding to
>> Completed, event=RX_MSG
>> 09:40:31.669   tsx0x1b13d5c  .....Transaction created for Request msg
>> REGISTER/cseq=24441 (tdta0x1b42b60)
>> 09:40:31.669   tsx0x1b13d5c  ....Sending Request msg REGISTER/cseq=24441
>> (tdta0x1b42b60) in state Null
>> 09:40:31.669       endpoint  .....Request msg REGISTER/cseq=24441
>> (tdta0x1b42b60): skipping target resolution because address is already set
>> 09:40:31.669   pjsua_core.c  .....TX 689 bytes Request msg
>> REGISTER/cseq=24441 (tdta0x1b42b60) to UDP 212.27.52.5:5060:
>> REGISTER sip:freephonie.net SIP/2.0
>> Via: SIP/2.0/UDP 78.214.178.85:5060;rport;branc
>> h=z9hG4bKPjUFpwyhgDC9Rh7-9jMv9IINHNunR0CTXC
>> Max-Forwards: 70
>> From: <sip:0954333845 at freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS
>> Mz4UZJwH
>> To: <sip:0954333845 at freephonie.net>
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> CSeq: 24441 REGISTER
>> User-Agent: PJSUA v2.5.5 Linux-3.4.113/armv7l/glibc-2.19
>> Contact: <sip:0954333845 at 78.214.178.85:5060;ob>
>> Expires: 0
>> Authorization: Digest username="0954333845", realm="freephonie.net",
>> nonce="0143a1e55e2ab7514990163420a562e4", uri="sip:freephonie.net",
>> response="2c14bb438e47d3fa197828c2d703210b", algorithm=MD5,
>> opaque="014304f23e5124b"
>> Content-Length:  0
>>
>>
>> --end msg--
>> 09:40:31.670   tsx0x1b13d5c  .....State changed from Null to Calling,
>> event=TX_MSG
>> 09:40:31.690 sip_endpoint.c  .Processing incoming message: Response msg
>> 100/REGISTER/cseq=24441 (rdata0x1b758ec)
>> 09:40:31.690   pjsua_core.c  ..RX 339 bytes Response msg
>> 100/REGISTER/cseq=24441 (rdata0x1b758ec) from UDP 212.27.52.5:5060:
>> SIP/2.0 100 Trying
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> CSeq: 24441 REGISTER
>> From: <sip:0954333845 at freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS
>> Mz4UZJwH
>> To: <sip:0954333845 at freephonie.net>
>> Via: SIP/2.0/UDP 78.214.178.85:5060;received=78
>> .214.178.85;rport=5060;branch=z9hG4bKPjUFpwyhgDC9Rh7-9jMv9IINHNunR0CTXC
>> Content-Length: 0
>>
>>
>> --end msg--
>> 09:40:31.690   tsx0x1b13d5c  ..Incoming Response msg
>> 100/REGISTER/cseq=24441 (rdata0x1b758ec) in state Calling
>> 09:40:31.691   tsx0x1b13d5c  ...State changed from Calling to Proceeding,
>> event=RX_MSG
>> 09:40:31.718 sip_endpoint.c  .Processing incoming message: Response msg
>> 200/REGISTER/cseq=24441 (rdata0x1b758ec)
>> 09:40:31.718   pjsua_core.c  ..RX 510 bytes Response msg
>> 200/REGISTER/cseq=24441 (rdata0x1b758ec) from UDP 212.27.52.5:5060:
>> SIP/2.0 200 OK
>> Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
>> Contact: <sip:0954333845 at 78.214.178.85:5060;ob>;expires=0
>> CSeq: 24441 REGISTER
>> From: <sip:0954333845 at freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS
>> Mz4UZJwH
>> To: <sip:0954333845 at freephonie.net>;tag=00-08174-0143a25e-012b24bc3
>> Via: SIP/2.0/UDP 78.214.178.85:5060;received=78
>> .214.178.85;rport=5060;branch=z9hG4bKPjUFpwyhgDC9Rh7-9jMv9IINHNunR0CTXC
>> P-Associated-URI: <sip:0954333845 at freephonie.net>
>> Server: Cirpack/v4.42q (gw_sip)
>> Content-Length: 0
>>
>>
>> --end msg--
>> 09:40:31.719   tsx0x1b13d5c  ..Incoming Response msg
>> 200/REGISTER/cseq=24441 (rdata0x1b758ec) in state Proceeding
>> 09:40:31.719   tsx0x1b13d5c  ...State changed from Proceeding to
>> Completed, event=RX_MSG
>> 09:40:31.719    pjsua_acc.c  .....<sip:0954333845 at freephonie.net>:
>> unregistration success
>> 09:40:32.669   pjsua_core.c  .Destroying...
>> 09:40:32.670  pjsua_media.c  .Shutting down media..
>> 09:40:32.722 sip_endpoint.c  .Destroying endpoing instance..
>> 09:40:32.722 sip_transactio  .Stopping transaction layer module
>> 09:40:32.722   tsx0x1b13d5c  .Request to terminate transaction
>> 09:40:32.723   tsx0x1b13d5c  ..State changed from Completed to
>> Terminated, event=USER
>> 09:40:32.723   tsx0x1b13d5c  .Transaction destroyed!
>> 09:40:32.723   tsx0x1b778ec  .Request to terminate transaction
>> 09:40:32.723   tsx0x1b778ec  ..State changed from Completed to
>> Terminated, event=USER
>> 09:40:32.723   dlg0x1b6fb2c  ...Transaction tsx0x1b778ec state changed to
>> Terminated
>> 09:40:32.723   dlg0x1b6fb2c  ....Dialog destroyed
>> 09:40:32.723 tdta0xb59031e0  .Destroying txdata Request msg
>> ACK/cseq=15739 (tdta0xb59031e0)
>> 09:40:32.723   tsx0x1b778ec  .Transaction destroyed!
>> 09:40:32.723   tsx0x1b43bcc  .Request to terminate transaction
>> 09:40:32.723   tsx0x1b43bcc  ..State changed from Completed to
>> Terminated, event=USER
>> 09:40:32.724  tdta0x1b42b60  .Destroying txdata Request msg
>> REGISTER/cseq=24441 (tdta0x1b42b60)
>> 09:40:32.724   tsx0x1b43bcc  .Transaction destroyed!
>> 09:40:32.724 sip_transactio  .Stopped transaction layer module
>> 09:40:32.725 sip_endpoint.c  .Module "mod-default-handler" unregistered
>> 09:40:32.725 sip_endpoint.c  .Module "mod-unsolicited-mwi" unregistered
>> 09:40:32.726 sip_endpoint.c  .Module "mod-pjsua-options" unregistered
>> 09:40:32.726 sip_endpoint.c  .Module "mod-pjsua-im" unregistered
>> 09:40:32.726 sip_endpoint.c  .Module "mod-pjsua-pres" unregistered
>> 09:40:32.727 sip_endpoint.c  .Module "mod-pjsua" unregistered
>> 09:40:32.727 sip_endpoint.c  .Module "mod-stateful-util" unregistered
>> 09:40:32.727 sip_endpoint.c  .Module "mod-refer" unregistered
>> 09:40:32.728 sip_endpoint.c  .Module "mod-mwi" unregistered
>> 09:40:32.728 sip_endpoint.c  .Module "mod-presence" unregistered
>> 09:40:32.728 sip_endpoint.c  .Module "mod-evsub" unregistered
>> 09:40:32.729 sip_endpoint.c  .Module "mod-invite" unregistered
>> 09:40:32.729 sip_endpoint.c  .Module "mod-100rel" unregistered
>> 09:40:32.729 sip_endpoint.c  .Module "mod-ua" unregistered
>> 09:40:32.730 sip_transactio  .Transaction layer module destroyed
>> 09:40:32.730 sip_endpoint.c  .Module "mod-tsx-layer" unregistered
>> 09:40:32.731 sip_endpoint.c  .Module "mod-msg-print" unregistered
>> 09:40:32.731 sip_endpoint.c  .Module "mod-pjsua-log" unregistered
>> 09:40:32.731 sip_transport.  .Destroying transport manager
>> 09:40:32.733   udp0x1b3d9a0  .SIP UDP transport destroyed
>> 09:40:32.734     tcptp:5060  .SIP TCP transport destroyed
>> 09:40:32.734 sip_endpoint.c  .Endpoint 0x1b0814c destroyed
>> 09:40:32.735   pjsua_core.c  .PJSUA state changed: CLOSING --> NULL
>> 09:40:32.735   pjsua_core.c  .PJSUA destroyed...
>>
>>
>> Le jeu. 8 déc. 2016 à 19:27, Jason Stäuble <mokitto at hotmail.com> a
>> écrit :
>>
>> Hi,
>>
>> Try to change realm to "*" instead of "freephonie.net"
>> Does this work?
>>
>> This would send another REGISTER request to your registrar with different
>> challenge as the first REGISTER request simply does not match the requested
>> challange.
>> _______________________________________________
>> Visit our blog: http://blog.pjsip.org
>>
>> pjsip mailing list
>> pjsip at lists.pjsip.org
>> http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org
>>
>>
>> _______________________________________________
>> Visit our blog: http://blog.pjsip.org
>>
>> pjsip mailing list
>> pjsip at lists.pjsip.org
>> http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org
>>
>>
>
>
> --
> DVG
>
> --
> Imagination is more important than knowledge
> Albert Einstein
>



-- 
DVG

-- 
Imagination is more important than knowledge
Albert Einstein
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.pjsip.org/pipermail/pjsip_lists.pjsip.org/attachments/20161209/c9869476/attachment-0002.html>


More information about the pjsip mailing list