Re: Cannot register with SIP server

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 

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@78.214.178.85:5060;ob>;expires=1800
CSeq: 24439 REGISTER
From: <sip:0954333845@xxxxxxxxxxxxxx>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: <sip:0954333845@xxxxxxxxxxxxxx>;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@xxxxxxxxxxxxxx>
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@xxxxxxxxx> wrote:
I just tried. Here are my parameters :
--id sip:0954xxxxxx@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@freephonie.net>
09:40:15.242    pjsua_acc.c  .Account <sip:0954333845@xxxxxxxxxxxxxx> 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@xxxxxxxxxxxxxx>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: <sip:0954333845@xxxxxxxxxxxxxx>
Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
CSeq: 24438 REGISTER
User-Agent: PJSUA v2.5.5 Linux-3.4.113/armv7l/glibc-2.19
Contact: <sip:0954333845@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@xxxxxxxxxxxxxx;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@xxxxxxxxxxxxxx;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@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@xxxxxxxxxxxxxx>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: <sip:0954333845@xxxxxxxxxxxxxx>
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@xxxxxxxxxxxxxx>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: <sip:0954333845@xxxxxxxxxxxxxx>;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="">
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="">
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@xxxxxxxxxxxxxx;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.0.1:5060;rport;branch=z9hG4bKPjgcMx3CGYqIKUOJV6RmPcS6wh8GIkg9oP
Max-Forwards: 70
From: <sip:0954333845@xxxxxxxxxxxxxx>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: sip:0442419862@xxxxxxxxxxxxxx;user=phone
Contact: <sip:0954333845@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@xxxxxxxxxxxxxx>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: <sip:0954333845@xxxxxxxxxxxxxx>
Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
CSeq: 24439 REGISTER
User-Agent: PJSUA v2.5.5 Linux-3.4.113/armv7l/glibc-2.19
Contact: <sip:0954333845@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@xxxxxxxxxxxxxx>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: <sip:0442419862@xxxxxxxxxxxxxx>;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@xxxxxxxxxxxxxx>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: <sip:0442419862@xxxxxxxxxxxxxx>;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@xxxxxxxxxxxxxx;user=phone SIP/2.0
Via: SIP/2.0/UDP 192.168.0.1:5060;rport;branch=z9hG4bKPjgcMx3CGYqIKUOJV6RmPcS6wh8GIkg9oP
Max-Forwards: 70
From: <sip:0954333845@xxxxxxxxxxxxxx>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: sip:0442419862@xxxxxxxxxxxxxx;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@xxxxxxxxxxxxxx;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@xxxxxxxxxxxxxx>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: <sip:0954333845@xxxxxxxxxxxxxx>
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@78.214.178.85:5060;ob>;expires=1800
CSeq: 24439 REGISTER
From: <sip:0954333845@xxxxxxxxxxxxxx>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: <sip:0954333845@xxxxxxxxxxxxxx>;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@xxxxxxxxxxxxxx>
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@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@xxxxxxxxxxxxxx>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: <sip:0954333845@xxxxxxxxxxxxxx>
Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
CSeq: 24440 REGISTER
User-Agent: PJSUA v2.5.5 Linux-3.4.113/armv7l/glibc-2.19
Contact: <sip:0954333845@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@xxxxxxxxxxxxxx>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: <sip:0954333845@xxxxxxxxxxxxxx>
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@xxxxxxxxxxxxxx>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: <sip:0954333845@xxxxxxxxxxxxxx>;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@xxxxxxxxxxxxxx>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: <sip:0954333845@xxxxxxxxxxxxxx>
Call-ID: N9BdiwU1CqEa.Eq87jdW9mGDQfU25huR
CSeq: 24441 REGISTER
User-Agent: PJSUA v2.5.5 Linux-3.4.113/armv7l/glibc-2.19
Contact: <sip:0954333845@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@xxxxxxxxxxxxxx>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: <sip:0954333845@xxxxxxxxxxxxxx>
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@78.214.178.85:5060;ob>;expires=0
CSeq: 24441 REGISTER
From: <sip:0954333845@xxxxxxxxxxxxxx>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: <sip:0954333845@xxxxxxxxxxxxxx>;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@xxxxxxxxxxxxxx>
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@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@xxxxxxxxxxx> 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@xxxxxxxxxxxxxxx
http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org

_______________________________________________
Visit our blog: http://blog.pjsip.org

pjsip mailing list
pjsip@xxxxxxxxxxxxxxx
http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org




--
DVG

--
Imagination is more important than knowledge
Albert Einstein
_______________________________________________
Visit our blog: http://blog.pjsip.org

pjsip mailing list
pjsip@xxxxxxxxxxxxxxx
http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org
[Index of Archives]     [Asterisk Users]     [Asterisk App Development]     [Linux ARM Kernel]     [Linux ARM]     [Linux Omap]     [Fedora ARM]     [IETF Annouce]     [Security]     [Bugtraq]     [Linux]     [Linux OMAP]     [Linux MIPS]     [Linux API]
  Powered by Linux