Re: [pjsip] Cannot register with SIP server

JS
Jason Stäuble
Thu, Dec 8, 2016 6:26 PM

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.

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.
EL
Eric Le Bras
Fri, Dec 9, 2016 8:54 AM

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@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@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@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@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@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: sip:0442419862@freephonie.net;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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: sip:0442419862@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: <sip:0442419862@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@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: sip:0442419862@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@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@78.214.178.85:5060;ob;expires=1800
CSeq: 24439 REGISTER
From: sip:0954333845@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@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@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@78.214.178.85:5060;ob;expires=0
CSeq: 24441 REGISTER
From: sip:0954333845@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@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@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@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@lists.pjsip.org
http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org

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@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S To: <sip:0954333845@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@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@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@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@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S To: <sip:0954333845@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S To: <sip:0954333845@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@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5 To: sip:0442419862@freephonie.net;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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S To: <sip:0954333845@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@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5 To: <sip:0442419862@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5 To: <sip:0442419862@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@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5 To: sip:0442419862@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@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S To: <sip:0954333845@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@78.214.178.85:5060;ob>;expires=1800 CSeq: 24439 REGISTER From: <sip:0954333845@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S To: <sip:0954333845@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@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@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH To: <sip:0954333845@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@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH To: <sip:0954333845@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH To: <sip:0954333845@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH To: <sip:0954333845@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@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH To: <sip:0954333845@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@78.214.178.85:5060;ob>;expires=0 CSeq: 24441 REGISTER From: <sip:0954333845@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH To: <sip:0954333845@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@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@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@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@lists.pjsip.org http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org
DV
David Villasmil Govea
Fri, Dec 9, 2016 11:06 AM

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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@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@gmail.com 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@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@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@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@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@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: sip:0442419862@freephonie.net;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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: sip:0442419862@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: sip:0442419862@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@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5
To: sip:0442419862@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@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@78.214.178.85:5060;ob;expires=1800
CSeq: 24439 REGISTER
From: sip:0954333845@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@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@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@78.214.178.85:5060;ob;expires=0
CSeq: 24441 REGISTER
From: sip:0954333845@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH
To: sip:0954333845@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@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@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@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@lists.pjsip.org
http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org


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

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

--
DVG

--
Imagination is more important than knowledge
Albert Einstein

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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S To: <sip:0954333845@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@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@gmail.com> 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@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S > To: <sip:0954333845@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@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@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@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@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S > To: <sip:0954333845@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S > To: <sip:0954333845@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@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5 > To: sip:0442419862@freephonie.net;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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S > To: <sip:0954333845@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@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5 > To: <sip:0442419862@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5 > To: <sip:0442419862@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@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOwDL6kzdK5 > To: sip:0442419862@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@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S > To: <sip:0954333845@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@78.214.178.85:5060;ob>;expires=1800 > CSeq: 24439 REGISTER > From: <sip:0954333845@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S > To: <sip:0954333845@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@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@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH > To: <sip:0954333845@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@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH > To: <sip:0954333845@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH > To: <sip:0954333845@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH > To: <sip:0954333845@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@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH > To: <sip:0954333845@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@78.214.178.85:5060;ob>;expires=0 > CSeq: 24441 REGISTER > From: <sip:0954333845@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnSMz4UZJwH > To: <sip:0954333845@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@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@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@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@lists.pjsip.org > http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org > > > _______________________________________________ > Visit our blog: http://blog.pjsip.org > > pjsip mailing list > pjsip@lists.pjsip.org > http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org > > -- DVG -- Imagination is more important than knowledge Albert Einstein
DV
David Villasmil Govea
Fri, Dec 9, 2016 11:16 AM

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@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@78.214.178.85:5060;ob;expires=1800
CSeq: 24439 REGISTER
From: sip:0954333845@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S
To: sip:0954333845@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@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@gmail.com
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@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqqu
AMg1wj9S
To: sip:0954333845@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@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@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@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@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqqu
AMg1wj9S
To: sip:0954333845@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqqu
AMg1wj9S
To: sip:0954333845@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@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOw
DL6kzdK5
To: sip:0442419862@freephonie.net;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;branc
h=z9hG4bKPjYcKUUuh8Q4G6bsXz7sLuv72qp2JhP7Nm
Max-Forwards: 70
From: sip:0954333845@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqqu
AMg1wj9S
To: sip:0954333845@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@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOw
DL6kzdK5
To: sip:0442419862@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOw
DL6kzdK5
To: sip:0442419862@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@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@freephonie.net;tag=jfMImH3S8iDvi4ZS34smEZOw
DL6kzdK5
To: sip:0442419862@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@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@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqqu
AMg1wj9S
To: sip:0954333845@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@78.214.178.85:5060;ob;expires=1800
CSeq: 24439 REGISTER
From: sip:0954333845@freephonie.net;tag=V6y7Av9eBkY5-GoC-SHfLqqu
AMg1wj9S
To: sip:0954333845@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@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@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnS
Mz4UZJwH
To: sip:0954333845@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@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnS
Mz4UZJwH
To: sip:0954333845@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnS
Mz4UZJwH
To: sip:0954333845@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnS
Mz4UZJwH
To: sip:0954333845@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@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@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnS
Mz4UZJwH
To: sip:0954333845@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@78.214.178.85:5060;ob;expires=0
CSeq: 24441 REGISTER
From: sip:0954333845@freephonie.net;tag=ldvYE7lBLB8DBUka2cGoPnnS
Mz4UZJwH
To: sip:0954333845@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@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@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@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@lists.pjsip.org
http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org


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

pjsip mailing list
pjsip@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

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@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@78.214.178.85:5060;ob>;expires=1800 > CSeq: 24439 REGISTER > From: <sip:0954333845@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqquAMg1wj9S > To: <sip:0954333845@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@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@gmail.com> > 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@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu >> AMg1wj9S >> To: <sip:0954333845@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@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@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@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@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu >> AMg1wj9S >> To: <sip:0954333845@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu >> AMg1wj9S >> To: <sip:0954333845@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@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOw >> DL6kzdK5 >> To: sip:0442419862@freephonie.net;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;branc >> h=z9hG4bKPjYcKUUuh8Q4G6bsXz7sLuv72qp2JhP7Nm >> Max-Forwards: 70 >> From: <sip:0954333845@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu >> AMg1wj9S >> To: <sip:0954333845@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@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOw >> DL6kzdK5 >> To: <sip:0442419862@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOw >> DL6kzdK5 >> To: <sip:0442419862@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@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@freephonie.net>;tag=jfMImH3S8iDvi4ZS34smEZOw >> DL6kzdK5 >> To: sip:0442419862@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@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@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu >> AMg1wj9S >> To: <sip:0954333845@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@78.214.178.85:5060;ob>;expires=1800 >> CSeq: 24439 REGISTER >> From: <sip:0954333845@freephonie.net>;tag=V6y7Av9eBkY5-GoC-SHfLqqu >> AMg1wj9S >> To: <sip:0954333845@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@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@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS >> Mz4UZJwH >> To: <sip:0954333845@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@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS >> Mz4UZJwH >> To: <sip:0954333845@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS >> Mz4UZJwH >> To: <sip:0954333845@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS >> Mz4UZJwH >> To: <sip:0954333845@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@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@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS >> Mz4UZJwH >> To: <sip:0954333845@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@78.214.178.85:5060;ob>;expires=0 >> CSeq: 24441 REGISTER >> From: <sip:0954333845@freephonie.net>;tag=ldvYE7lBLB8DBUka2cGoPnnS >> Mz4UZJwH >> To: <sip:0954333845@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@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@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@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@lists.pjsip.org >> http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org >> >> >> _______________________________________________ >> Visit our blog: http://blog.pjsip.org >> >> pjsip mailing list >> pjsip@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
EL
Eric Le Bras
Mon, Dec 12, 2016 10:48 AM

I've finaly found the issue. Indeed, there was 2 issues:
1: I had to add option --no-tcp. My configuration is now as follows:
--id sip:0954xxxxxx@freephonie.net
--registrar sip:freephonie.net
--realm freephonie.net
--username 0954xxxxxx
--password xxxxxxxx
--reg-timeout=1800
--no-tcp
==> registration problem solved.

2: I cannot register and make the call with a single command line, because
the INVITE message is sent by pjsua before we receive the REGISTERED
message from the server, so the server rejects the call. Is it possible to
add a delay before sending the INVITE message?

I've finaly found the issue. Indeed, there was 2 issues: 1: I had to add option --no-tcp. My configuration is now as follows: --id sip:0954xxxxxx@freephonie.net --registrar sip:freephonie.net --realm freephonie.net --username 0954xxxxxx --password xxxxxxxx --reg-timeout=1800 --no-tcp ==> registration problem solved. 2: I cannot register and make the call with a single command line, because the INVITE message is sent by pjsua before we receive the REGISTERED message from the server, so the server rejects the call. Is it possible to add a delay before sending the INVITE message?