[pjsip] Cannot register with SIP server

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


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-SHfLqquAMg1wj9S
> 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-SHfLqquAMg1wj9S
> To: <sip:0954333845 at freephonie.net>
> Via: SIP/2.0/UDP 192.168.0.1:5060;received=78.
> 214.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-SHfLqquAMg1wj9S
> To: <sip:0954333845 at freephonie.net>;tag=00-08185-0143a174-789f83805
> Via: SIP/2.0/UDP 192.168.0.1:5060;received=78.
> 214.178.85;rport=5060;branch=z9hG4bKPjxbTmp0917JzK7dkn6RcoIGNMXOGqFs39
> WWW-Authenticate: Digest realm="freephonie.net",nonce="
> 0143a1405e59eb476fdae42f742a7877",opaque="014304f23e5124b",
> stale=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=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
> 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;branch=
> z9hG4bKPjYcKUUuh8Q4G6bsXz7sLuv72qp2JhP7Nm
> Max-Forwards: 70
> From: <sip:0954333845 at freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
> 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=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
> To: <sip:0442419862 at freephonie.net>;user=phone
> Via: SIP/2.0/UDP 192.168.0.1:5060;received=78.
> 214.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=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
> To: <sip:0442419862 at freephonie.net>;tag=00-32588-150300fb-
> 5962170f6;user=phone
> Via: SIP/2.0/UDP 192.168.0.1:5060;received=78.
> 214.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=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
> To: sip:0442419862 at freephonie.net;user=phone;tag=00-32588-
> 150300fb-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-SHfLqquAMg1wj9S
> 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-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
>
>
> --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;branch=
> z9hG4bKPjjGZWc1jX9btgn5kq39knTcBsBB3b.qu3
> Max-Forwards: 70
> From: <sip:0954333845 at freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
> 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=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
> 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=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
> 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",
> stale=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;branch=z9hG4bKPjUFpwyhgDC9Rh7-
> 9jMv9IINHNunR0CTXC
> Max-Forwards: 70
> From: <sip:0954333845 at freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
> 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=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
> 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=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
> 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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.pjsip.org/pipermail/pjsip_lists.pjsip.org/attachments/20161209/2d813e8f/attachment.html>


More information about the pjsip mailing list