on_reg_state not working with rev.1504

OB
Olivier Beytrison
Wed, Oct 17, 2007 2:25 PM

Hello,

This morning I've updated my source-tree to the last SVN-trunk (rev1504).

Since then, my on_reg_state callback isn't triggered at all, in a
scenario where it was always triggered in the past.

Anyone else experiencing the same issue ?

Cheers,
Olivier B.

--

Olivier Beytrison
Telecommunication Engineer
Mobile: +41 (0)78 619 73 53
Mail: olivier@heliosnet.org
GPG: 0x4FB83528 http://pgp.mit.edu/

Hello, This morning I've updated my source-tree to the last SVN-trunk (rev1504). Since then, my on_reg_state callback isn't triggered at all, in a scenario where it was always triggered in the past. Anyone else experiencing the same issue ? Cheers, Olivier B. -- Olivier Beytrison Telecommunication Engineer Mobile: +41 (0)78 619 73 53 Mail: olivier@heliosnet.org GPG: 0x4FB83528 http://pgp.mit.edu/
BP
Benny Prijono
Thu, Oct 18, 2007 2:34 AM

Olivier Beytrison wrote:

Hello,

This morning I've updated my source-tree to the last SVN-trunk (rev1504).

Since then, my on_reg_state callback isn't triggered at all, in a
scenario where it was always triggered in the past.

Anyone else experiencing the same issue ?

What particular scenario? I'm also interested to hear if anyone is
experiencing any problems with the svn trunk code.

cheers,
-benny

Cheers,
Olivier B.

--
Benny Prijono
http://www.pjsip.org

Olivier Beytrison wrote: > Hello, > > This morning I've updated my source-tree to the last SVN-trunk (rev1504). > > Since then, my on_reg_state callback isn't triggered at all, in a > scenario where it was always triggered in the past. > > Anyone else experiencing the same issue ? What particular scenario? I'm also interested to hear if anyone is experiencing any problems with the svn trunk code. cheers, -benny > Cheers, > Olivier B. > -- Benny Prijono http://www.pjsip.org
OB
Olivier Beytrison
Thu, Oct 18, 2007 8:26 AM

Alright, we made another test right now.

Used : our application developed in C# for windows mobile, using a
wrapper to pjsip. (all thanks to Sasa ;)) which connect to a dummy sip
server which accept any REGISTER and send 200 OK. (SipServer from the
symbian SDK)

ok, here's some piece of code

the call back method itself

static void on_reg_state(pjsua_acc_id acc_id)
{
pjsua_acc_info accinfo;

pjsua_acc_get_info(acc_id, &accinfo);
PJ_LOG(3,(THIS_FILE,"Reg state changed for account %d :
%d",acc_id,accinfo.status));

// callback
if (cb_regstate != 0) cb_regstate(acc_id, accinfo.status);

}

call back assignement in the dll_init() code :

app_config.cfg.cb.on_reg_state = &on_reg_state;

Attached you'll find the pjsip.log file which contains everything.

For your information, the code of the wrapper hasn't changed since I've
upgraded to the last SVN trunk. and before the update, it was working
like a charm.

Ask me if you need any more informations.

Regards,

Olivier B.

Benny Prijono a écrit :

Olivier Beytrison wrote:

Hello,

This morning I've updated my source-tree to the last SVN-trunk (rev1504).

Since then, my on_reg_state callback isn't triggered at all, in a
scenario where it was always triggered in the past.

Anyone else experiencing the same issue ?

What particular scenario? I'm also interested to hear if anyone is
experiencing any problems with the svn trunk code.

cheers,
-benny

Cheers,
Olivier B.

--

Olivier Beytrison
Telecommunication Engineer
Mobile: +41 (0)78 619 73 53
Mail: olivier@heliosnet.org
GPG: 0x4FB83528 http://pgp.mit.edu/

09:04:45.000 sip_endpoint.c Module "mod-pjsua-log" registered
09:04:45.000 sip_endpoint.c Module "mod-tsx-layer" registered
09:04:45.000 sip_endpoint.c Module "mod-stateful-util" registered
09:04:45.000 sip_endpoint.c Module "mod-ua" registered
09:04:45.000 sip_endpoint.c Module "mod-100rel" registered
09:04:45.000 sip_endpoint.c Module "mod-pjsua" registered
09:04:45.000 sip_endpoint.c Module "mod-invite" registered
09:04:45.000      pasound.c PortAudio sound library initialized, status=0
09:04:45.000      pasound.c PortAudio host api count=2
09:04:45.000      pasound.c Sound device count=4
09:04:45.000          pjlib select() I/O Queue created (0007DFC4)
09:04:45.000  speex_codec.c Adjusting quality to 5 for uwb
09:04:45.000  conference.c Creating conference bridge with 32 ports
09:04:45.000  conference.c Sound device successfully created for port 0
09:04:45.000 sip_endpoint.c Module "mod-evsub" registered
09:04:45.000 sip_endpoint.c Module "mod-presence" registered
09:04:45.000        evsub.c Event pkg "presence" registered by mod-presence
09:04:45.000 sip_endpoint.c Module "mod-refer" registered
09:04:45.000        evsub.c Event pkg "refer" registered by mod-refer
09:04:45.000 sip_endpoint.c Module "mod-pjsua-pres" registered
09:04:45.000 sip_endpoint.c Module "mod-pjsua-im" registered
09:04:45.000 sip_endpoint.c Module "mod-pjsua-options" registered
09:04:45.000  pjsua_core.c 1 SIP worker threads created
09:04:45.000  pjsua_core.c pjsua version 0.7.0-trunk for win32-wince initialized
09:04:45.000  pjsua_core.c SIP UDP socket reachable at 169.254.2.1:5060
09:04:45.000    udp0020A794 SIP UDP transport started, published address is 169.254.2.1:5060
09:04:45.000    tcplis:5060 SIP TCP listener ready for incoming connections at 169.254.2.1:5060
09:04:45.000  pjsua_media.c RTP socket reachable at 169.254.2.1:4000
09:04:45.000  pjsua_media.c RTCP socket reachable at 169.254.2.1:4001
09:04:45.000  pjsua_media.c RTP socket reachable at 169.254.2.1:4002
09:04:45.000  pjsua_media.c RTCP socket reachable at 169.254.2.1:4003
09:04:45.000  pjsua_media.c RTP socket reachable at 169.254.2.1:4004
09:04:45.000  pjsua_media.c RTCP socket reachable at 169.254.2.1:4005
09:04:45.000  pjsua_media.c RTP socket reachable at 169.254.2.1:4006
09:04:45.000  pjsua_media.c RTCP socket reachable at 169.254.2.1:4007
09:04:45.000  pjsua_media.c pjsua_set_snd_dev(): attempting to open devices @8000 Hz
09:04:45.000      pasound.c Opened device M - Input(MME)/M - Output(MME) for recording and playback, sample rate=8000, ch=1, bits=16, 80 samples per frame, input latency=60 ms, output latency=60 ms
09:04:45.000      pasound.c Starting M - Input stream..
09:04:46.000      pasound.c Player thread started
09:04:46.000      pasound.c Done, status=0
09:04:46.000      pasound.c Recorder thread started
09:04:49.000  ecsu00054954 Vad cur_threshold updated 4-->3. Signal lo=-1
09:04:51.000  sound_port.c EC suspended because of inactivity
09:05:25.000    pjsua_acc.c Account sip:filou@169.254.2.2 added with id 0
09:05:25.000      endpoint Request msg REGISTER/cseq=22329 (tdta00284A50) created.
09:05:25.000    tsx00285AC4 Transaction created for Request msg REGISTER/cseq=22330 (tdta00284A50)
09:05:25.000    tsx00285AC4 Sending Request msg REGISTER/cseq=22330 (tdta00284A50) in state Null
09:05:25.000  sip_resolve.c Target '169.254.2.2:0' type=Unspecified resolved to '169.254.2.2:5060' type=UDP
09:05:25.000  pjsua_core.c TX 358 bytes Request msg REGISTER/cseq=22330 (tdta00284A50) to UDP 169.254.2.2:5060:
REGISTER sip:169.254.2.2 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.1:5060;rport;branch=z9hG4bKPj7259000000033b70b87a
Max-Forwards: 70
From: sip:filou@169.254.2.2;tag=7259000000023b70b87a
To: sip:filou@169.254.2.2
Call-ID: 7259000000013b70b87a
CSeq: 22330 REGISTER
Contact: sip:filou@169.254.2.1:5060;transport=UDP
Expires: 3600
Content-Length:  0

--end msg--
09:05:25.000    tsx00285AC4 State changed from Null to Calling, event=TX_MSG
09:05:25.000    pjsua_acc.c Registration sent
09:05:25.000 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=22330 (rdata00217EE4)
09:05:25.000  pjsua_core.c RX 332 bytes Response msg 200/REGISTER/cseq=22330 (rdata00217EE4) from UDP 169.254.2.2:1320:
SIP/2.0 200 OK
Contact: sip:filou@169.254.2.1:5060;transport=UDP;expires=3600
Call-ID: 7259000000013b70b87a
CSeq: 22330 REGISTER
From: sip:filou@169.254.2.2;tag=7259000000023b70b87a
To: sip:filou@169.254.2.2;tag=52761423
Via: SIP/2.0/UDP 169.254.2.1:5060;rport;branch=z9hG4bKPj7259000000033b70b87a
Content-Length: 0

--end msg--
09:05:25.000    tsx00285AC4 Incoming Response msg 200/REGISTER/cseq=22330 (rdata00217EE4) in state Calling
09:05:25.000    tsx00285AC4 State changed from Calling to Completed, event=RX_MSG
09:05:25.000    pjsua_acc.c sip:filou@169.254.2.2: registration success, status=200 (OK), will re-register in 3600 seconds
09:05:30.000    tsx00285AC4 Timeout timer event
09:05:30.000    tsx00285AC4 State changed from Completed to Terminated, event=TIMER
09:05:30.000    tsx00285AC4 Timeout timer event
09:05:30.000    tsx00285AC4 State changed from Terminated to Destroyed, event=TIMER
09:05:30.000  tdta00284A50 Destroying txdata Request msg REGISTER/cseq=22330 (tdta00284A50)
09:05:30.000    tsx00285AC4 Transaction destroyed!
09:08:23.000      endpoint Request msg REGISTER/cseq=22330 (tdta00284A50) created.
09:08:23.000    tsx00285AC4 Transaction created for Request msg REGISTER/cseq=22331 (tdta00284A50)
09:08:23.000    tsx00285AC4 Sending Request msg REGISTER/cseq=22331 (tdta00284A50) in state Null
09:08:23.000  sip_resolve.c Target '169.254.2.2:0' type=Unspecified resolved to '169.254.2.2:5060' type=UDP
09:08:23.000  pjsua_core.c TX 355 bytes Request msg REGISTER/cseq=22331 (tdta00284A50) to UDP 169.254.2.2:5060:
REGISTER sip:169.254.2.2 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.1:5060;rport;branch=z9hG4bKPj7259000000053b70b87a
Max-Forwards: 70
From: sip:filou@169.254.2.2;tag=7259000000043b70b87a
To: sip:filou@169.254.2.2
Call-ID: 7259000000013b70b87a
CSeq: 22331 REGISTER
Contact: sip:filou@169.254.2.1:5060;transport=UDP
Expires: 0
Content-Length:  0

--end msg--
09:08:23.000    tsx00285AC4 State changed from Null to Calling, event=TX_MSG
09:08:23.000    pjsua_acc.c Unregistration sent
09:08:23.000  pjsua_core.c Shutting down...
09:08:23.000 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=22331 (rdata00217EE4)
09:08:23.000  pjsua_core.c RX 266 bytes Response msg 200/REGISTER/cseq=22331 (rdata00217EE4) from UDP 169.254.2.2:1322:
SIP/2.0 200 OK
Call-ID: 7259000000013b70b87a
CSeq: 22331 REGISTER
From: sip:filou@169.254.2.2;tag=7259000000043b70b87a
To: sip:filou@169.254.2.2;tag=22168576
Via: SIP/2.0/UDP 169.254.2.1:5060;rport;branch=z9hG4bKPj7259000000053b70b87a
Content-Length: 0

--end msg--
09:08:23.000    tsx00285AC4 Incoming Response msg 200/REGISTER/cseq=22331 (rdata00217EE4) in state Calling
09:08:23.000    tsx00285AC4 State changed from Calling to Completed, event=RX_MSG
09:08:23.000    pjsua_acc.c sip:filou@169.254.2.2: unregistration success
09:08:24.000  pjsua_media.c Closing (null) sound playback device and (null) sound capture device
09:08:24.000      pasound.c Stopping stream..
09:08:24.000      pasound.c Done, status=0
09:08:24.000      pasound.c Closing M - Input: 1 underflow, 0 overflow
09:08:24.000      pasound.c PortAudio sound library shutting down..
09:08:24.000 sip_endpoint.c Destroying endpoing instance..
09:08:24.000 sip_endpoint.c Module "mod-pjsua-options" unregistered
09:08:24.000 sip_endpoint.c Module "mod-pjsua-im" unregistered
09:08:24.000 sip_endpoint.c Module "mod-pjsua-pres" unregistered
09:08:24.000 sip_endpoint.c Module "mod-pjsua" unregistered
09:08:24.000 sip_endpoint.c Module "mod-stateful-util" unregistered
09:08:24.000 sip_endpoint.c Module "mod-refer" unregistered
09:08:24.000 sip_endpoint.c Module "mod-presence" unregistered
09:08:24.000 sip_endpoint.c Module "mod-evsub" unregistered
09:08:24.000 sip_endpoint.c Module "mod-invite" unregistered
09:08:24.000 sip_endpoint.c Module "mod-100rel" unregistered
09:08:24.000 sip_endpoint.c Module "mod-ua" unregistered
09:08:24.000 sip_transactio Stopping transaction layer module
09:08:24.000  tdta00284A50 Destroying txdata Request msg REGISTER/cseq=22331 (tdta00284A50)
09:08:24.000    tsx00285AC4 Transaction destroyed!
09:08:24.000 sip_transactio Transaction layer module destroyed
09:08:24.000 sip_endpoint.c Module "mod-tsx-layer" unregistered
09:08:24.000 sip_endpoint.c Module "mod-msg-print" unregistered
09:08:24.000 sip_endpoint.c Module "mod-pjsua-log" unregistered
09:08:24.000 sip_transport. Destroying transport manager
09:08:24.000    tcplis:5060 SIP TCP listener destroyed
09:08:24.000 sip_endpoint.c Endpoint 00054FB4 destroyed
09:08:24.000          pjsua Pool is not released by application, releasing now
09:08:24.000  pjsua_core.c PJSUA destroyed...

Alright, we made another test right now. Used : our application developed in C# for windows mobile, using a wrapper to pjsip. (all thanks to Sasa ;)) which connect to a dummy sip server which accept any REGISTER and send 200 OK. (SipServer from the symbian SDK) ok, here's some piece of code the call back method itself static void on_reg_state(pjsua_acc_id acc_id) { pjsua_acc_info accinfo; pjsua_acc_get_info(acc_id, &accinfo); PJ_LOG(3,(THIS_FILE,"Reg state changed for account %d : %d",acc_id,accinfo.status)); // callback if (cb_regstate != 0) cb_regstate(acc_id, accinfo.status); } call back assignement in the dll_init() code : app_config.cfg.cb.on_reg_state = &on_reg_state; Attached you'll find the pjsip.log file which contains everything. For your information, the code of the wrapper hasn't changed since I've upgraded to the last SVN trunk. and before the update, it was working like a charm. Ask me if you need any more informations. Regards, Olivier B. Benny Prijono a écrit : > Olivier Beytrison wrote: >> Hello, >> >> This morning I've updated my source-tree to the last SVN-trunk (rev1504). >> >> Since then, my on_reg_state callback isn't triggered at all, in a >> scenario where it was always triggered in the past. >> >> Anyone else experiencing the same issue ? > > What particular scenario? I'm also interested to hear if anyone is > experiencing any problems with the svn trunk code. > > cheers, > -benny > >> Cheers, >> Olivier B. >> > > -- Olivier Beytrison Telecommunication Engineer Mobile: +41 (0)78 619 73 53 Mail: olivier@heliosnet.org GPG: 0x4FB83528 http://pgp.mit.edu/ 09:04:45.000 sip_endpoint.c Module "mod-pjsua-log" registered 09:04:45.000 sip_endpoint.c Module "mod-tsx-layer" registered 09:04:45.000 sip_endpoint.c Module "mod-stateful-util" registered 09:04:45.000 sip_endpoint.c Module "mod-ua" registered 09:04:45.000 sip_endpoint.c Module "mod-100rel" registered 09:04:45.000 sip_endpoint.c Module "mod-pjsua" registered 09:04:45.000 sip_endpoint.c Module "mod-invite" registered 09:04:45.000 pasound.c PortAudio sound library initialized, status=0 09:04:45.000 pasound.c PortAudio host api count=2 09:04:45.000 pasound.c Sound device count=4 09:04:45.000 pjlib select() I/O Queue created (0007DFC4) 09:04:45.000 speex_codec.c Adjusting quality to 5 for uwb 09:04:45.000 conference.c Creating conference bridge with 32 ports 09:04:45.000 conference.c Sound device successfully created for port 0 09:04:45.000 sip_endpoint.c Module "mod-evsub" registered 09:04:45.000 sip_endpoint.c Module "mod-presence" registered 09:04:45.000 evsub.c Event pkg "presence" registered by mod-presence 09:04:45.000 sip_endpoint.c Module "mod-refer" registered 09:04:45.000 evsub.c Event pkg "refer" registered by mod-refer 09:04:45.000 sip_endpoint.c Module "mod-pjsua-pres" registered 09:04:45.000 sip_endpoint.c Module "mod-pjsua-im" registered 09:04:45.000 sip_endpoint.c Module "mod-pjsua-options" registered 09:04:45.000 pjsua_core.c 1 SIP worker threads created 09:04:45.000 pjsua_core.c pjsua version 0.7.0-trunk for win32-wince initialized 09:04:45.000 pjsua_core.c SIP UDP socket reachable at 169.254.2.1:5060 09:04:45.000 udp0020A794 SIP UDP transport started, published address is 169.254.2.1:5060 09:04:45.000 tcplis:5060 SIP TCP listener ready for incoming connections at 169.254.2.1:5060 09:04:45.000 pjsua_media.c RTP socket reachable at 169.254.2.1:4000 09:04:45.000 pjsua_media.c RTCP socket reachable at 169.254.2.1:4001 09:04:45.000 pjsua_media.c RTP socket reachable at 169.254.2.1:4002 09:04:45.000 pjsua_media.c RTCP socket reachable at 169.254.2.1:4003 09:04:45.000 pjsua_media.c RTP socket reachable at 169.254.2.1:4004 09:04:45.000 pjsua_media.c RTCP socket reachable at 169.254.2.1:4005 09:04:45.000 pjsua_media.c RTP socket reachable at 169.254.2.1:4006 09:04:45.000 pjsua_media.c RTCP socket reachable at 169.254.2.1:4007 09:04:45.000 pjsua_media.c pjsua_set_snd_dev(): attempting to open devices @8000 Hz 09:04:45.000 pasound.c Opened device M - Input(MME)/M - Output(MME) for recording and playback, sample rate=8000, ch=1, bits=16, 80 samples per frame, input latency=60 ms, output latency=60 ms 09:04:45.000 pasound.c Starting M - Input stream.. 09:04:46.000 pasound.c Player thread started 09:04:46.000 pasound.c Done, status=0 09:04:46.000 pasound.c Recorder thread started 09:04:49.000 ecsu00054954 Vad cur_threshold updated 4-->3. Signal lo=-1 09:04:51.000 sound_port.c EC suspended because of inactivity 09:05:25.000 pjsua_acc.c Account sip:filou@169.254.2.2 added with id 0 09:05:25.000 endpoint Request msg REGISTER/cseq=22329 (tdta00284A50) created. 09:05:25.000 tsx00285AC4 Transaction created for Request msg REGISTER/cseq=22330 (tdta00284A50) 09:05:25.000 tsx00285AC4 Sending Request msg REGISTER/cseq=22330 (tdta00284A50) in state Null 09:05:25.000 sip_resolve.c Target '169.254.2.2:0' type=Unspecified resolved to '169.254.2.2:5060' type=UDP 09:05:25.000 pjsua_core.c TX 358 bytes Request msg REGISTER/cseq=22330 (tdta00284A50) to UDP 169.254.2.2:5060: REGISTER sip:169.254.2.2 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.1:5060;rport;branch=z9hG4bKPj7259000000033b70b87a Max-Forwards: 70 From: <sip:filou@169.254.2.2>;tag=7259000000023b70b87a To: <sip:filou@169.254.2.2> Call-ID: 7259000000013b70b87a CSeq: 22330 REGISTER Contact: <sip:filou@169.254.2.1:5060;transport=UDP> Expires: 3600 Content-Length: 0 --end msg-- 09:05:25.000 tsx00285AC4 State changed from Null to Calling, event=TX_MSG 09:05:25.000 pjsua_acc.c Registration sent 09:05:25.000 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=22330 (rdata00217EE4) 09:05:25.000 pjsua_core.c RX 332 bytes Response msg 200/REGISTER/cseq=22330 (rdata00217EE4) from UDP 169.254.2.2:1320: SIP/2.0 200 OK Contact: <sip:filou@169.254.2.1:5060;transport=UDP>;expires=3600 Call-ID: 7259000000013b70b87a CSeq: 22330 REGISTER From: <sip:filou@169.254.2.2>;tag=7259000000023b70b87a To: <sip:filou@169.254.2.2>;tag=52761423 Via: SIP/2.0/UDP 169.254.2.1:5060;rport;branch=z9hG4bKPj7259000000033b70b87a Content-Length: 0 --end msg-- 09:05:25.000 tsx00285AC4 Incoming Response msg 200/REGISTER/cseq=22330 (rdata00217EE4) in state Calling 09:05:25.000 tsx00285AC4 State changed from Calling to Completed, event=RX_MSG 09:05:25.000 pjsua_acc.c sip:filou@169.254.2.2: registration success, status=200 (OK), will re-register in 3600 seconds 09:05:30.000 tsx00285AC4 Timeout timer event 09:05:30.000 tsx00285AC4 State changed from Completed to Terminated, event=TIMER 09:05:30.000 tsx00285AC4 Timeout timer event 09:05:30.000 tsx00285AC4 State changed from Terminated to Destroyed, event=TIMER 09:05:30.000 tdta00284A50 Destroying txdata Request msg REGISTER/cseq=22330 (tdta00284A50) 09:05:30.000 tsx00285AC4 Transaction destroyed! 09:08:23.000 endpoint Request msg REGISTER/cseq=22330 (tdta00284A50) created. 09:08:23.000 tsx00285AC4 Transaction created for Request msg REGISTER/cseq=22331 (tdta00284A50) 09:08:23.000 tsx00285AC4 Sending Request msg REGISTER/cseq=22331 (tdta00284A50) in state Null 09:08:23.000 sip_resolve.c Target '169.254.2.2:0' type=Unspecified resolved to '169.254.2.2:5060' type=UDP 09:08:23.000 pjsua_core.c TX 355 bytes Request msg REGISTER/cseq=22331 (tdta00284A50) to UDP 169.254.2.2:5060: REGISTER sip:169.254.2.2 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.1:5060;rport;branch=z9hG4bKPj7259000000053b70b87a Max-Forwards: 70 From: <sip:filou@169.254.2.2>;tag=7259000000043b70b87a To: <sip:filou@169.254.2.2> Call-ID: 7259000000013b70b87a CSeq: 22331 REGISTER Contact: <sip:filou@169.254.2.1:5060;transport=UDP> Expires: 0 Content-Length: 0 --end msg-- 09:08:23.000 tsx00285AC4 State changed from Null to Calling, event=TX_MSG 09:08:23.000 pjsua_acc.c Unregistration sent 09:08:23.000 pjsua_core.c Shutting down... 09:08:23.000 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=22331 (rdata00217EE4) 09:08:23.000 pjsua_core.c RX 266 bytes Response msg 200/REGISTER/cseq=22331 (rdata00217EE4) from UDP 169.254.2.2:1322: SIP/2.0 200 OK Call-ID: 7259000000013b70b87a CSeq: 22331 REGISTER From: <sip:filou@169.254.2.2>;tag=7259000000043b70b87a To: <sip:filou@169.254.2.2>;tag=22168576 Via: SIP/2.0/UDP 169.254.2.1:5060;rport;branch=z9hG4bKPj7259000000053b70b87a Content-Length: 0 --end msg-- 09:08:23.000 tsx00285AC4 Incoming Response msg 200/REGISTER/cseq=22331 (rdata00217EE4) in state Calling 09:08:23.000 tsx00285AC4 State changed from Calling to Completed, event=RX_MSG 09:08:23.000 pjsua_acc.c sip:filou@169.254.2.2: unregistration success 09:08:24.000 pjsua_media.c Closing (null) sound playback device and (null) sound capture device 09:08:24.000 pasound.c Stopping stream.. 09:08:24.000 pasound.c Done, status=0 09:08:24.000 pasound.c Closing M - Input: 1 underflow, 0 overflow 09:08:24.000 pasound.c PortAudio sound library shutting down.. 09:08:24.000 sip_endpoint.c Destroying endpoing instance.. 09:08:24.000 sip_endpoint.c Module "mod-pjsua-options" unregistered 09:08:24.000 sip_endpoint.c Module "mod-pjsua-im" unregistered 09:08:24.000 sip_endpoint.c Module "mod-pjsua-pres" unregistered 09:08:24.000 sip_endpoint.c Module "mod-pjsua" unregistered 09:08:24.000 sip_endpoint.c Module "mod-stateful-util" unregistered 09:08:24.000 sip_endpoint.c Module "mod-refer" unregistered 09:08:24.000 sip_endpoint.c Module "mod-presence" unregistered 09:08:24.000 sip_endpoint.c Module "mod-evsub" unregistered 09:08:24.000 sip_endpoint.c Module "mod-invite" unregistered 09:08:24.000 sip_endpoint.c Module "mod-100rel" unregistered 09:08:24.000 sip_endpoint.c Module "mod-ua" unregistered 09:08:24.000 sip_transactio Stopping transaction layer module 09:08:24.000 tdta00284A50 Destroying txdata Request msg REGISTER/cseq=22331 (tdta00284A50) 09:08:24.000 tsx00285AC4 Transaction destroyed! 09:08:24.000 sip_transactio Transaction layer module destroyed 09:08:24.000 sip_endpoint.c Module "mod-tsx-layer" unregistered 09:08:24.000 sip_endpoint.c Module "mod-msg-print" unregistered 09:08:24.000 sip_endpoint.c Module "mod-pjsua-log" unregistered 09:08:24.000 sip_transport. Destroying transport manager 09:08:24.000 tcplis:5060 SIP TCP listener destroyed 09:08:24.000 sip_endpoint.c Endpoint 00054FB4 destroyed 09:08:24.000 pjsua Pool is not released by application, releasing now 09:08:24.000 pjsua_core.c PJSUA destroyed...
OB
Olivier Beytrison
Thu, Oct 18, 2007 9:23 AM

Some more infos ..

We made some other tests here, and when an incoming call comes, it
reaches the UA and is handled by pjsip, it answers with a 100 Trying
(weird) and immediately send a 480 Unavailable, this without calling the
on_call_state callback nor the on_incoming_call.

You'll find the log file attached to this mail.

Regards,
Olivier B.

Olivier Beytrison a écrit :

Alright, we made another test right now.

Used : our application developed in C# for windows mobile, using a
wrapper to pjsip. (all thanks to Sasa ;)) which connect to a dummy sip
server which accept any REGISTER and send 200 OK. (SipServer from the
symbian SDK)

ok, here's some piece of code

the call back method itself

static void on_reg_state(pjsua_acc_id acc_id)
{
pjsua_acc_info accinfo;

pjsua_acc_get_info(acc_id, &accinfo);
PJ_LOG(3,(THIS_FILE,"Reg state changed for account %d :
%d",acc_id,accinfo.status));

// callback
if (cb_regstate != 0) cb_regstate(acc_id, accinfo.status);

}

call back assignement in the dll_init() code :

app_config.cfg.cb.on_reg_state = &on_reg_state;

Attached you'll find the pjsip.log file which contains everything.

For your information, the code of the wrapper hasn't changed since I've
upgraded to the last SVN trunk. and before the update, it was working
like a charm.

Ask me if you need any more informations.

Regards,

Olivier B.

Benny Prijono a écrit :

Olivier Beytrison wrote:

Hello,

This morning I've updated my source-tree to the last SVN-trunk (rev1504).

Since then, my on_reg_state callback isn't triggered at all, in a
scenario where it was always triggered in the past.

Anyone else experiencing the same issue ?

What particular scenario? I'm also interested to hear if anyone is
experiencing any problems with the svn trunk code.

cheers,
-benny

Cheers,
Olivier B.

--

Olivier Beytrison
Telecommunication Engineer
Mobile: +41 (0)78 619 73 53
Mail: olivier@heliosnet.org
GPG: 0x4FB83528 http://pgp.mit.edu/

10:16:40.000 sip_endpoint.c Module "mod-pjsua-log" registered
10:16:40.000 sip_endpoint.c Module "mod-tsx-layer" registered
10:16:40.000 sip_endpoint.c Module "mod-stateful-util" registered
10:16:40.000 sip_endpoint.c Module "mod-ua" registered
10:16:40.000 sip_endpoint.c Module "mod-100rel" registered
10:16:40.000 sip_endpoint.c Module "mod-pjsua" registered
10:16:40.000 sip_endpoint.c Module "mod-invite" registered
10:16:40.000      pasound.c PortAudio sound library initialized, status=0
10:16:40.000      pasound.c PortAudio host api count=2
10:16:40.000      pasound.c Sound device count=4
10:16:40.000          pjlib select() I/O Queue created (00060F54)
10:16:40.000  speex_codec.c Adjusting quality to 5 for uwb
10:16:40.000  conference.c Creating conference bridge with 32 ports
10:16:40.000  conference.c Sound device successfully created for port 0
10:16:40.000 sip_endpoint.c Module "mod-evsub" registered
10:16:40.000 sip_endpoint.c Module "mod-presence" registered
10:16:40.000        evsub.c Event pkg "presence" registered by mod-presence
10:16:40.000 sip_endpoint.c Module "mod-refer" registered
10:16:40.000        evsub.c Event pkg "refer" registered by mod-refer
10:16:40.000 sip_endpoint.c Module "mod-pjsua-pres" registered
10:16:40.000 sip_endpoint.c Module "mod-pjsua-im" registered
10:16:40.000 sip_endpoint.c Module "mod-pjsua-options" registered
10:16:40.000  pjsua_core.c 1 SIP worker threads created
10:16:40.000  pjsua_core.c pjsua version 0.7.0-trunk for win32-wince initialized
10:16:40.000  pjsua_core.c SIP UDP socket reachable at 169.254.2.1:5061
10:16:40.000    udp0006ABD4 SIP UDP transport started, published address is 169.254.2.1:5061
10:16:40.000    tcplis:5061 SIP TCP listener ready for incoming connections at 169.254.2.1:5061
10:16:40.000  pjsua_media.c RTP socket reachable at 169.254.2.1:4000
10:16:40.000  pjsua_media.c RTCP socket reachable at 169.254.2.1:4001
10:16:40.000  pjsua_media.c RTP socket reachable at 169.254.2.1:4002
10:16:40.000  pjsua_media.c RTCP socket reachable at 169.254.2.1:4003
10:16:40.000  pjsua_media.c RTP socket reachable at 169.254.2.1:4004
10:16:40.000  pjsua_media.c RTCP socket reachable at 169.254.2.1:4005
10:16:40.000  pjsua_media.c RTP socket reachable at 169.254.2.1:4006
10:16:40.000  pjsua_media.c RTCP socket reachable at 169.254.2.1:4007
10:16:40.000  pjsua_media.c pjsua_set_snd_dev(): attempting to open devices @8000 Hz
10:16:40.000      pasound.c Opened device M - Input(MME)/M - Output(MME) for recording and playback, sample rate=8000, ch=1, bits=16, 80 samples per frame, input latency=60 ms, output latency=60 ms
10:16:40.000      pasound.c Starting M - Input stream..
10:16:41.000      pasound.c Player thread started
10:16:41.000      pasound.c Done, status=0
10:16:41.000      pasound.c Recorder thread started
10:16:44.000  ecsu0005AA04 Vad cur_threshold updated 4-->3. Signal lo=-1
10:16:46.000  sound_port.c EC suspended because of inactivity
10:16:47.000    pjsua_acc.c Account sip:spv@169.254.2.2 added with id 0
10:16:47.000      endpoint Request msg REGISTER/cseq=29295 (tdta00257600) created.
10:16:47.000    tsx00258674 Transaction created for Request msg REGISTER/cseq=29296 (tdta00257600)
10:16:47.000    tsx00258674 Sending Request msg REGISTER/cseq=29296 (tdta00257600) in state Null
10:16:47.000  sip_resolve.c Target '169.254.2.2:0' type=Unspecified resolved to '169.254.2.2:5060' type=UDP
10:16:47.000  pjsua_core.c TX 352 bytes Request msg REGISTER/cseq=29296 (tdta00257600) to UDP 169.254.2.2:5060:
REGISTER sip:169.254.2.2 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.1:5061;rport;branch=z9hG4bKPj12cb000000032417fb35
Max-Forwards: 70
From: sip:spv@169.254.2.2;tag=12cb000000022417fb35
To: sip:spv@169.254.2.2
Call-ID: 12cb000000012417fb35
CSeq: 29296 REGISTER
Contact: sip:spv@169.254.2.1:5061;transport=UDP
Expires: 3600
Content-Length:  0

--end msg--
10:16:47.000    tsx00258674 State changed from Null to Calling, event=TX_MSG
10:16:47.000    pjsua_acc.c Registration sent
10:16:47.000 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=29296 (rdata0006AFF4)
10:16:47.000  pjsua_core.c RX 326 bytes Response msg 200/REGISTER/cseq=29296 (rdata0006AFF4) from UDP 169.254.2.2:1458:
SIP/2.0 200 OK
Contact: sip:spv@169.254.2.1:5061;transport=UDP;expires=3600
Call-ID: 12cb000000012417fb35
CSeq: 29296 REGISTER
From: sip:spv@169.254.2.2;tag=12cb000000022417fb35
To: sip:spv@169.254.2.2;tag=93446066
Via: SIP/2.0/UDP 169.254.2.1:5061;rport;branch=z9hG4bKPj12cb000000032417fb35
Content-Length: 0

--end msg--
10:16:47.000    tsx00258674 Incoming Response msg 200/REGISTER/cseq=29296 (rdata0006AFF4) in state Calling
10:16:47.000    tsx00258674 State changed from Calling to Completed, event=RX_MSG
10:16:47.000    pjsua_acc.c sip:spv@169.254.2.2: registration success, status=200 (OK), will re-register in 3600 seconds
10:16:52.000    tsx00258674 Timeout timer event
10:16:52.000    tsx00258674 State changed from Completed to Terminated, event=TIMER
10:16:52.000    tsx00258674 Timeout timer event
10:16:52.000    tsx00258674 State changed from Terminated to Destroyed, event=TIMER
10:16:52.000  tdta00257600 Destroying txdata Request msg REGISTER/cseq=29296 (tdta00257600)
10:16:52.000    tsx00258674 Transaction destroyed!
10:16:52.000 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=1 (rdata0006AFF4)
10:16:52.000  pjsua_core.c RX 1209 bytes Request msg INVITE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1461:
INVITE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK120f98ee544cf0bd8eb4ad02f89a6065
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-5806a17a47666952-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv (Softphone)" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=1d33484e
Call-ID: MTgyYjNkY2I4ZDhiNzU4YjY0YzcwZTdhZDY0ZjhmMmQ.
CSeq: 1 INVITE
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
Content-Type: application/sdp
User-Agent: X-Lite release 1011s stamp 41150
Content-Length: 516

v=0
o=- 7 2 IN IP4 169.254.2.2
s=CounterPath X-Lite 3.0
c=IN IP4 169.254.2.2
t=0 0
m=audio 37496 RTP/AVP 107 119 100 106 0 105 98 8 101
a=alt:1 3 : PKhxNgPO nFCjQcb6 169.254.2.2 37496
a=alt:2 2 : UVrzrnFW TVk13hcX 10.8.0.10 37496
a=alt:3 1 : r2vRC2Wn eygPbsT6 193.63.130.185 37496
a=fmtp:101 0-15
a=rtpmap:107 BV32/16000
a=rtpmap:119 BV32-FEC/16000
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:98 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv

--end msg--
10:16:52.000    tsx0025C1F4 Transaction created for Request msg INVITE/cseq=1 (rdata0006AFF4)
10:16:52.000    tsx0025C1F4 Incoming Request msg INVITE/cseq=1 (rdata0006AFF4) in state Null
10:16:52.000    tsx0025C1F4 State changed from Null to Trying, event=RX_MSG
10:16:52.000    dlg00258614 Transaction tsx0025C1F4 state changed to Trying
10:16:52.000    dlg00258614 UAS dialog created
10:16:52.000    dlg00258614 Module mod-invite added as dialog usage, data=00258CD4
10:16:52.000    dlg00258614 Session count inc to 2 by mod-invite
10:16:52.000    inv00258614 UAS invite session created for dialog dlg00258614
10:16:52.000      endpoint Response msg 100/INVITE/cseq=1 (tdta0025DC00) created
10:16:52.000    dlg00258614 Initial answer Response msg 100/INVITE/cseq=1 (tdta0025DC00)
10:16:52.000    inv00258614 Sending Response msg 100/INVITE/cseq=1 (tdta0025DC00)
10:16:52.000    dlg00258614 Sending Response msg 100/INVITE/cseq=1 (tdta0025DC00)
10:16:52.000    tsx0025C1F4 Sending Response msg 100/INVITE/cseq=1 (tdta0025DC00) in state Trying
10:16:52.000  sip_resolve.c Target '169.254.2.2:5060' type=UDP resolved to '169.254.2.2:5060' type=UDP
10:16:52.000  pjsua_core.c TX 408 bytes Response msg 100/INVITE/cseq=1 (tdta0025DC00) to UDP 169.254.2.2:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 169.254.2.2;received=169.254.2.2;branch=z9hG4bK120f98ee544cf0bd8eb4ad02f89a6065
Via: SIP/2.0/UDP 127.0.0.1:49602;rport;branch=z9hG4bK-d87543-5806a17a47666952-1--d87543-
Call-ID: MTgyYjNkY2I4ZDhiNzU4YjY0YzcwZTdhZDY0ZjhmMmQ.
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=1d33484e
To: "spv (Softphone)" sip:spv@169.254.2.2
CSeq: 1 INVITE
Content-Length:  0

--end msg--
10:16:52.000    tsx0025C1F4 State changed from Trying to Proceeding, event=TX_MSG
10:16:52.000    dlg00258614 Transaction tsx0025C1F4 state changed to Proceeding
10:16:52.000    inv00258614 Sending Response msg 480/INVITE/cseq=1 (tdta0025DC00)
10:16:52.000    dlg00258614 Sending Response msg 480/INVITE/cseq=1 (tdta0025DC00)
10:16:52.000    tsx0025C1F4 Sending Response msg 480/INVITE/cseq=1 (tdta0025DC00) in state Proceeding
10:16:52.000  pjsua_core.c TX 450 bytes Response msg 480/INVITE/cseq=1 (tdta0025DC00) to UDP 169.254.2.2:5060:
SIP/2.0 480 Temporarily Unavailable
Via: SIP/2.0/UDP 169.254.2.2;received=169.254.2.2;branch=z9hG4bK120f98ee544cf0bd8eb4ad02f89a6065
Via: SIP/2.0/UDP 127.0.0.1:49602;rport;branch=z9hG4bK-d87543-5806a17a47666952-1--d87543-
Call-ID: MTgyYjNkY2I4ZDhiNzU4YjY0YzcwZTdhZDY0ZjhmMmQ.
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=1d33484e
To: "spv (Softphone)" sip:spv@169.254.2.2;tag=12cb000000042417fb35
CSeq: 1 INVITE
Content-Length:  0

--end msg--
10:16:52.000    tsx0025C1F4 State changed from Proceeding to Completed, event=TX_MSG
10:16:52.000    dlg00258614 Transaction tsx0025C1F4 state changed to Completed
10:16:52.000    dlg00258614 Session count dec to 3 by mod-invite
10:16:52.000 sip_endpoint.c Processing incoming message: Request msg ACK/cseq=1 (rdata0006AFF4)
10:16:52.000  pjsua_core.c RX 426 bytes Request msg ACK/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1465:
ACK sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK120f98ee544cf0bd8eb4ad02f89a6065
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-5806a17a47666952-1--d87543-;rport
To: "spv (Softphone)" sip:spv@169.254.2.2;tag=12cb000000042417fb35
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=1d33484e
Call-ID: MTgyYjNkY2I4ZDhiNzU4YjY0YzcwZTdhZDY0ZjhmMmQ.
CSeq: 1 ACK
Content-Length: 0

--end msg--
10:16:52.000    tsx0025C1F4 Incoming Request msg ACK/cseq=1 (rdata0006AFF4) in state Completed
10:16:52.000    tsx0025C1F4 State changed from Completed to Confirmed, event=RX_MSG
10:16:52.000    dlg00258614 Transaction tsx0025C1F4 state changed to Confirmed
10:16:52.000    tsx0025C1F4 Timeout timer event
10:16:52.000    tsx0025C1F4 State changed from Confirmed to Terminated, event=TIMER
10:16:52.000    dlg00258614 Transaction tsx0025C1F4 state changed to Terminated
10:16:52.000    dlg00258614 Dialog destroyed
10:16:52.000    tsx0025C1F4 Timeout timer event
10:16:52.000    tsx0025C1F4 State changed from Terminated to Destroyed, event=TIMER
10:16:52.000  tdta0025DC00 Destroying txdata Response msg 480/INVITE/cseq=1 (tdta0025DC00)
10:16:52.000    tsx0025C1F4 Transaction destroyed!
10:19:10.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:10.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1467:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:10.000  pjsua_pres.c Creating server subscription, using account 0
10:19:10.000    tsx0025F224 Transaction created for Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:10.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Null
10:19:10.000    tsx0025F224 State changed from Null to Trying, event=RX_MSG
10:19:10.000    dlg0025EA14 Transaction tsx0025F224 state changed to Trying
10:19:10.000    dlg0025EA14 UAS dialog created
10:19:10.000  pjsua_pres.c Unable to create server subscription: Not Acceptable [status=170406]
10:19:10.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:10.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1469:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:10.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying
10:19:11.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:11.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1471:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:11.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying
10:19:13.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:13.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1473:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:13.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying
10:19:17.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:17.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1475:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:17.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying
10:19:22.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:22.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1477:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:22.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying
10:19:26.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:26.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1479:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:26.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying
10:19:30.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:30.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1481:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:30.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying
10:19:34.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:34.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1483:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:34.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying
10:19:38.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:38.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1485:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:38.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying
10:19:42.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4)
10:19:42.000  pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1487:
SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f
Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport
Max-Forwards: 69
Contact: sip:fil_x_lite@127.0.0.1:49602
To: "spv" sip:spv@169.254.2.2
From: "fil_x_lite" sip:fil_x_lite@169.254.2.2;tag=db2e7031
Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ.
CSeq: 1 SUBSCRIBE
Subject:
Expires: 3600
Accept: multipart/related
Accept: application/rlmi+xml
Accept: application/pidf+xml
Allow: INVITE
Allow: ACK
Allow: CANCEL
Allow: OPTIONS
Allow: BYE
Allow: REFER
Allow: NOTIFY
Allow: MESSAGE
Allow: SUBSCRIBE
Allow: INFO
User-Agent: X-Lite release 1011s stamp 41150
Event: presence
Content-Length: 0

--end msg--
10:19:42.000    tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying
10:19:51.000      endpoint Request msg REGISTER/cseq=29296 (tdta00258E20) created.
10:19:51.000    tsx00259E94 Transaction created for Request msg REGISTER/cseq=29297 (tdta00258E20)
10:19:51.000    tsx00259E94 Sending Request msg REGISTER/cseq=29297 (tdta00258E20) in state Null
10:19:51.000  sip_resolve.c Target '169.254.2.2:0' type=Unspecified resolved to '169.254.2.2:5060' type=UDP
10:19:51.000  pjsua_core.c TX 349 bytes Request msg REGISTER/cseq=29297 (tdta00258E20) to UDP 169.254.2.2:5060:
REGISTER sip:169.254.2.2 SIP/2.0
Via: SIP/2.0/UDP 169.254.2.1:5061;rport;branch=z9hG4bKPj12cb000000072417fb35
Max-Forwards: 70
From: sip:spv@169.254.2.2;tag=12cb000000062417fb35
To: sip:spv@169.254.2.2
Call-ID: 12cb000000012417fb35
CSeq: 29297 REGISTER
Contact: sip:spv@169.254.2.1:5061;transport=UDP
Expires: 0
Content-Length:  0

--end msg--
10:19:51.000    tsx00259E94 State changed from Null to Calling, event=TX_MSG
10:19:51.000    pjsua_acc.c Unregistration sent
10:19:51.000  pjsua_core.c Shutting down...
10:19:51.000 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=29297 (rdata0006AFF4)
10:19:51.000  pjsua_core.c RX 262 bytes Response msg 200/REGISTER/cseq=29297 (rdata0006AFF4) from UDP 169.254.2.2:1488:
SIP/2.0 200 OK
Call-ID: 12cb000000012417fb35
CSeq: 29297 REGISTER
From: sip:spv@169.254.2.2;tag=12cb000000062417fb35
To: sip:spv@169.254.2.2;tag=18496378
Via: SIP/2.0/UDP 169.254.2.1:5061;rport;branch=z9hG4bKPj12cb000000072417fb35
Content-Length: 0

--end msg--
10:19:51.000    tsx00259E94 Incoming Response msg 200/REGISTER/cseq=29297 (rdata0006AFF4) in state Calling
10:19:51.000    tsx00259E94 State changed from Calling to Completed, event=RX_MSG
10:19:51.000    pjsua_acc.c sip:spv@169.254.2.2: unregistration success
10:19:52.000  pjsua_media.c Closing (null) sound playback device and (null) sound capture device
10:19:52.000      pasound.c Stopping stream..
10:19:52.000      pasound.c Done, status=0
10:19:52.000      pasound.c Closing M - Input: 2 underflow, 0 overflow
10:19:52.000      pasound.c PortAudio sound library shutting down..
10:19:52.000 sip_endpoint.c Destroying endpoing instance..
10:19:52.000 sip_endpoint.c Module "mod-pjsua-options" unregistered
10:19:52.000 sip_endpoint.c Module "mod-pjsua-im" unregistered
10:19:52.000 sip_endpoint.c Module "mod-pjsua-pres" unregistered
10:19:52.000 sip_endpoint.c Module "mod-pjsua" unregistered
10:19:52.000 sip_endpoint.c Module "mod-stateful-util" unregistered
10:19:52.000 sip_endpoint.c Module "mod-refer" unregistered
10:19:52.000 sip_endpoint.c Module "mod-presence" unregistered
10:19:52.000 sip_endpoint.c Module "mod-evsub" unregistered
10:19:52.000 sip_endpoint.c Module "mod-invite" unregistered
10:19:52.000 sip_endpoint.c Module "mod-100rel" unregistered
10:19:52.000 sip_endpoint.c Module "mod-ua" unregistered
10:19:52.000 sip_transactio Stopping transaction layer module
10:19:52.000    tsx0025F224 Transaction destroyed!
10:19:52.000  tdta00258E20 Destroying txdata Request msg REGISTER/cseq=29297 (tdta00258E20)
10:19:52.000    tsx00259E94 Transaction destroyed!
10:19:52.000 sip_transactio Transaction layer module destroyed
10:19:52.000 sip_endpoint.c Module "mod-tsx-layer" unregistered
10:19:52.000 sip_endpoint.c Module "mod-msg-print" unregistered
10:19:52.000 sip_endpoint.c Module "mod-pjsua-log" unregistered
10:19:52.000 sip_transport. Destroying transport manager
10:19:52.000    tcplis:5061 SIP TCP listener destroyed
10:19:52.000 sip_endpoint.c Endpoint 0005B064 destroyed
10:19:52.000          pjsua Pool is not released by application, releasing now
10:19:52.000    dlg0025E9B0 Pool is not released by application, releasing now
10:19:52.000  pjsua_core.c PJSUA destroyed...

Some more infos .. We made some other tests here, and when an incoming call comes, it reaches the UA and is handled by pjsip, it answers with a 100 Trying (weird) and immediately send a 480 Unavailable, this without calling the on_call_state callback nor the on_incoming_call. You'll find the log file attached to this mail. Regards, Olivier B. Olivier Beytrison a écrit : > Alright, we made another test right now. > > Used : our application developed in C# for windows mobile, using a > wrapper to pjsip. (all thanks to Sasa ;)) which connect to a dummy sip > server which accept any REGISTER and send 200 OK. (SipServer from the > symbian SDK) > > ok, here's some piece of code > > the call back method itself > > static void on_reg_state(pjsua_acc_id acc_id) > { > pjsua_acc_info accinfo; > > pjsua_acc_get_info(acc_id, &accinfo); > PJ_LOG(3,(THIS_FILE,"Reg state changed for account %d : > %d",acc_id,accinfo.status)); > > // callback > if (cb_regstate != 0) cb_regstate(acc_id, accinfo.status); > } > > call back assignement in the dll_init() code : > > app_config.cfg.cb.on_reg_state = &on_reg_state; > > Attached you'll find the pjsip.log file which contains everything. > > For your information, the code of the wrapper hasn't changed since I've > upgraded to the last SVN trunk. and before the update, it was working > like a charm. > > Ask me if you need any more informations. > > Regards, > > Olivier B. > > Benny Prijono a écrit : >> Olivier Beytrison wrote: >>> Hello, >>> >>> This morning I've updated my source-tree to the last SVN-trunk (rev1504). >>> >>> Since then, my on_reg_state callback isn't triggered at all, in a >>> scenario where it was always triggered in the past. >>> >>> Anyone else experiencing the same issue ? >> What particular scenario? I'm also interested to hear if anyone is >> experiencing any problems with the svn trunk code. >> >> cheers, >> -benny >> >>> Cheers, >>> Olivier B. >>> >> > > > ------------------------------------------------------------------------ > > _______________________________________________ > Visit our blog: http://blog.pjsip.org > > pjsip mailing list > pjsip@lists.pjsip.org > http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org -- Olivier Beytrison Telecommunication Engineer Mobile: +41 (0)78 619 73 53 Mail: olivier@heliosnet.org GPG: 0x4FB83528 http://pgp.mit.edu/ 10:16:40.000 sip_endpoint.c Module "mod-pjsua-log" registered 10:16:40.000 sip_endpoint.c Module "mod-tsx-layer" registered 10:16:40.000 sip_endpoint.c Module "mod-stateful-util" registered 10:16:40.000 sip_endpoint.c Module "mod-ua" registered 10:16:40.000 sip_endpoint.c Module "mod-100rel" registered 10:16:40.000 sip_endpoint.c Module "mod-pjsua" registered 10:16:40.000 sip_endpoint.c Module "mod-invite" registered 10:16:40.000 pasound.c PortAudio sound library initialized, status=0 10:16:40.000 pasound.c PortAudio host api count=2 10:16:40.000 pasound.c Sound device count=4 10:16:40.000 pjlib select() I/O Queue created (00060F54) 10:16:40.000 speex_codec.c Adjusting quality to 5 for uwb 10:16:40.000 conference.c Creating conference bridge with 32 ports 10:16:40.000 conference.c Sound device successfully created for port 0 10:16:40.000 sip_endpoint.c Module "mod-evsub" registered 10:16:40.000 sip_endpoint.c Module "mod-presence" registered 10:16:40.000 evsub.c Event pkg "presence" registered by mod-presence 10:16:40.000 sip_endpoint.c Module "mod-refer" registered 10:16:40.000 evsub.c Event pkg "refer" registered by mod-refer 10:16:40.000 sip_endpoint.c Module "mod-pjsua-pres" registered 10:16:40.000 sip_endpoint.c Module "mod-pjsua-im" registered 10:16:40.000 sip_endpoint.c Module "mod-pjsua-options" registered 10:16:40.000 pjsua_core.c 1 SIP worker threads created 10:16:40.000 pjsua_core.c pjsua version 0.7.0-trunk for win32-wince initialized 10:16:40.000 pjsua_core.c SIP UDP socket reachable at 169.254.2.1:5061 10:16:40.000 udp0006ABD4 SIP UDP transport started, published address is 169.254.2.1:5061 10:16:40.000 tcplis:5061 SIP TCP listener ready for incoming connections at 169.254.2.1:5061 10:16:40.000 pjsua_media.c RTP socket reachable at 169.254.2.1:4000 10:16:40.000 pjsua_media.c RTCP socket reachable at 169.254.2.1:4001 10:16:40.000 pjsua_media.c RTP socket reachable at 169.254.2.1:4002 10:16:40.000 pjsua_media.c RTCP socket reachable at 169.254.2.1:4003 10:16:40.000 pjsua_media.c RTP socket reachable at 169.254.2.1:4004 10:16:40.000 pjsua_media.c RTCP socket reachable at 169.254.2.1:4005 10:16:40.000 pjsua_media.c RTP socket reachable at 169.254.2.1:4006 10:16:40.000 pjsua_media.c RTCP socket reachable at 169.254.2.1:4007 10:16:40.000 pjsua_media.c pjsua_set_snd_dev(): attempting to open devices @8000 Hz 10:16:40.000 pasound.c Opened device M - Input(MME)/M - Output(MME) for recording and playback, sample rate=8000, ch=1, bits=16, 80 samples per frame, input latency=60 ms, output latency=60 ms 10:16:40.000 pasound.c Starting M - Input stream.. 10:16:41.000 pasound.c Player thread started 10:16:41.000 pasound.c Done, status=0 10:16:41.000 pasound.c Recorder thread started 10:16:44.000 ecsu0005AA04 Vad cur_threshold updated 4-->3. Signal lo=-1 10:16:46.000 sound_port.c EC suspended because of inactivity 10:16:47.000 pjsua_acc.c Account sip:spv@169.254.2.2 added with id 0 10:16:47.000 endpoint Request msg REGISTER/cseq=29295 (tdta00257600) created. 10:16:47.000 tsx00258674 Transaction created for Request msg REGISTER/cseq=29296 (tdta00257600) 10:16:47.000 tsx00258674 Sending Request msg REGISTER/cseq=29296 (tdta00257600) in state Null 10:16:47.000 sip_resolve.c Target '169.254.2.2:0' type=Unspecified resolved to '169.254.2.2:5060' type=UDP 10:16:47.000 pjsua_core.c TX 352 bytes Request msg REGISTER/cseq=29296 (tdta00257600) to UDP 169.254.2.2:5060: REGISTER sip:169.254.2.2 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.1:5061;rport;branch=z9hG4bKPj12cb000000032417fb35 Max-Forwards: 70 From: <sip:spv@169.254.2.2>;tag=12cb000000022417fb35 To: <sip:spv@169.254.2.2> Call-ID: 12cb000000012417fb35 CSeq: 29296 REGISTER Contact: <sip:spv@169.254.2.1:5061;transport=UDP> Expires: 3600 Content-Length: 0 --end msg-- 10:16:47.000 tsx00258674 State changed from Null to Calling, event=TX_MSG 10:16:47.000 pjsua_acc.c Registration sent 10:16:47.000 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=29296 (rdata0006AFF4) 10:16:47.000 pjsua_core.c RX 326 bytes Response msg 200/REGISTER/cseq=29296 (rdata0006AFF4) from UDP 169.254.2.2:1458: SIP/2.0 200 OK Contact: <sip:spv@169.254.2.1:5061;transport=UDP>;expires=3600 Call-ID: 12cb000000012417fb35 CSeq: 29296 REGISTER From: <sip:spv@169.254.2.2>;tag=12cb000000022417fb35 To: <sip:spv@169.254.2.2>;tag=93446066 Via: SIP/2.0/UDP 169.254.2.1:5061;rport;branch=z9hG4bKPj12cb000000032417fb35 Content-Length: 0 --end msg-- 10:16:47.000 tsx00258674 Incoming Response msg 200/REGISTER/cseq=29296 (rdata0006AFF4) in state Calling 10:16:47.000 tsx00258674 State changed from Calling to Completed, event=RX_MSG 10:16:47.000 pjsua_acc.c sip:spv@169.254.2.2: registration success, status=200 (OK), will re-register in 3600 seconds 10:16:52.000 tsx00258674 Timeout timer event 10:16:52.000 tsx00258674 State changed from Completed to Terminated, event=TIMER 10:16:52.000 tsx00258674 Timeout timer event 10:16:52.000 tsx00258674 State changed from Terminated to Destroyed, event=TIMER 10:16:52.000 tdta00257600 Destroying txdata Request msg REGISTER/cseq=29296 (tdta00257600) 10:16:52.000 tsx00258674 Transaction destroyed! 10:16:52.000 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=1 (rdata0006AFF4) 10:16:52.000 pjsua_core.c RX 1209 bytes Request msg INVITE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1461: INVITE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK120f98ee544cf0bd8eb4ad02f89a6065 Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-5806a17a47666952-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv (Softphone)" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=1d33484e Call-ID: MTgyYjNkY2I4ZDhiNzU4YjY0YzcwZTdhZDY0ZjhmMmQ. CSeq: 1 INVITE Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO Content-Type: application/sdp User-Agent: X-Lite release 1011s stamp 41150 Content-Length: 516 v=0 o=- 7 2 IN IP4 169.254.2.2 s=CounterPath X-Lite 3.0 c=IN IP4 169.254.2.2 t=0 0 m=audio 37496 RTP/AVP 107 119 100 106 0 105 98 8 101 a=alt:1 3 : PKhxNgPO nFCjQcb6 169.254.2.2 37496 a=alt:2 2 : UVrzrnFW TVk13hcX 10.8.0.10 37496 a=alt:3 1 : r2vRC2Wn eygPbsT6 193.63.130.185 37496 a=fmtp:101 0-15 a=rtpmap:107 BV32/16000 a=rtpmap:119 BV32-FEC/16000 a=rtpmap:100 SPEEX/16000 a=rtpmap:106 SPEEX-FEC/16000 a=rtpmap:105 SPEEX-FEC/8000 a=rtpmap:98 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv --end msg-- 10:16:52.000 tsx0025C1F4 Transaction created for Request msg INVITE/cseq=1 (rdata0006AFF4) 10:16:52.000 tsx0025C1F4 Incoming Request msg INVITE/cseq=1 (rdata0006AFF4) in state Null 10:16:52.000 tsx0025C1F4 State changed from Null to Trying, event=RX_MSG 10:16:52.000 dlg00258614 Transaction tsx0025C1F4 state changed to Trying 10:16:52.000 dlg00258614 UAS dialog created 10:16:52.000 dlg00258614 Module mod-invite added as dialog usage, data=00258CD4 10:16:52.000 dlg00258614 Session count inc to 2 by mod-invite 10:16:52.000 inv00258614 UAS invite session created for dialog dlg00258614 10:16:52.000 endpoint Response msg 100/INVITE/cseq=1 (tdta0025DC00) created 10:16:52.000 dlg00258614 Initial answer Response msg 100/INVITE/cseq=1 (tdta0025DC00) 10:16:52.000 inv00258614 Sending Response msg 100/INVITE/cseq=1 (tdta0025DC00) 10:16:52.000 dlg00258614 Sending Response msg 100/INVITE/cseq=1 (tdta0025DC00) 10:16:52.000 tsx0025C1F4 Sending Response msg 100/INVITE/cseq=1 (tdta0025DC00) in state Trying 10:16:52.000 sip_resolve.c Target '169.254.2.2:5060' type=UDP resolved to '169.254.2.2:5060' type=UDP 10:16:52.000 pjsua_core.c TX 408 bytes Response msg 100/INVITE/cseq=1 (tdta0025DC00) to UDP 169.254.2.2:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 169.254.2.2;received=169.254.2.2;branch=z9hG4bK120f98ee544cf0bd8eb4ad02f89a6065 Via: SIP/2.0/UDP 127.0.0.1:49602;rport;branch=z9hG4bK-d87543-5806a17a47666952-1--d87543- Call-ID: MTgyYjNkY2I4ZDhiNzU4YjY0YzcwZTdhZDY0ZjhmMmQ. From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=1d33484e To: "spv (Softphone)" <sip:spv@169.254.2.2> CSeq: 1 INVITE Content-Length: 0 --end msg-- 10:16:52.000 tsx0025C1F4 State changed from Trying to Proceeding, event=TX_MSG 10:16:52.000 dlg00258614 Transaction tsx0025C1F4 state changed to Proceeding 10:16:52.000 inv00258614 Sending Response msg 480/INVITE/cseq=1 (tdta0025DC00) 10:16:52.000 dlg00258614 Sending Response msg 480/INVITE/cseq=1 (tdta0025DC00) 10:16:52.000 tsx0025C1F4 Sending Response msg 480/INVITE/cseq=1 (tdta0025DC00) in state Proceeding 10:16:52.000 pjsua_core.c TX 450 bytes Response msg 480/INVITE/cseq=1 (tdta0025DC00) to UDP 169.254.2.2:5060: SIP/2.0 480 Temporarily Unavailable Via: SIP/2.0/UDP 169.254.2.2;received=169.254.2.2;branch=z9hG4bK120f98ee544cf0bd8eb4ad02f89a6065 Via: SIP/2.0/UDP 127.0.0.1:49602;rport;branch=z9hG4bK-d87543-5806a17a47666952-1--d87543- Call-ID: MTgyYjNkY2I4ZDhiNzU4YjY0YzcwZTdhZDY0ZjhmMmQ. From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=1d33484e To: "spv (Softphone)" <sip:spv@169.254.2.2>;tag=12cb000000042417fb35 CSeq: 1 INVITE Content-Length: 0 --end msg-- 10:16:52.000 tsx0025C1F4 State changed from Proceeding to Completed, event=TX_MSG 10:16:52.000 dlg00258614 Transaction tsx0025C1F4 state changed to Completed 10:16:52.000 dlg00258614 Session count dec to 3 by mod-invite 10:16:52.000 sip_endpoint.c Processing incoming message: Request msg ACK/cseq=1 (rdata0006AFF4) 10:16:52.000 pjsua_core.c RX 426 bytes Request msg ACK/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1465: ACK sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK120f98ee544cf0bd8eb4ad02f89a6065 Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-5806a17a47666952-1--d87543-;rport To: "spv (Softphone)" <sip:spv@169.254.2.2>;tag=12cb000000042417fb35 From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=1d33484e Call-ID: MTgyYjNkY2I4ZDhiNzU4YjY0YzcwZTdhZDY0ZjhmMmQ. CSeq: 1 ACK Content-Length: 0 --end msg-- 10:16:52.000 tsx0025C1F4 Incoming Request msg ACK/cseq=1 (rdata0006AFF4) in state Completed 10:16:52.000 tsx0025C1F4 State changed from Completed to Confirmed, event=RX_MSG 10:16:52.000 dlg00258614 Transaction tsx0025C1F4 state changed to Confirmed 10:16:52.000 tsx0025C1F4 Timeout timer event 10:16:52.000 tsx0025C1F4 State changed from Confirmed to Terminated, event=TIMER 10:16:52.000 dlg00258614 Transaction tsx0025C1F4 state changed to Terminated 10:16:52.000 dlg00258614 Dialog destroyed 10:16:52.000 tsx0025C1F4 Timeout timer event 10:16:52.000 tsx0025C1F4 State changed from Terminated to Destroyed, event=TIMER 10:16:52.000 tdta0025DC00 Destroying txdata Response msg 480/INVITE/cseq=1 (tdta0025DC00) 10:16:52.000 tsx0025C1F4 Transaction destroyed! 10:19:10.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:10.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1467: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:10.000 pjsua_pres.c Creating server subscription, using account 0 10:19:10.000 tsx0025F224 Transaction created for Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:10.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Null 10:19:10.000 tsx0025F224 State changed from Null to Trying, event=RX_MSG 10:19:10.000 dlg0025EA14 Transaction tsx0025F224 state changed to Trying 10:19:10.000 dlg0025EA14 UAS dialog created 10:19:10.000 pjsua_pres.c Unable to create server subscription: Not Acceptable [status=170406] 10:19:10.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:10.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1469: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:10.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying 10:19:11.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:11.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1471: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:11.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying 10:19:13.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:13.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1473: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:13.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying 10:19:17.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:17.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1475: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:17.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying 10:19:22.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:22.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1477: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:22.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying 10:19:26.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:26.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1479: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:26.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying 10:19:30.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:30.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1481: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:30.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying 10:19:34.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:34.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1483: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:34.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying 10:19:38.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:38.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1485: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:38.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying 10:19:42.000 sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) 10:19:42.000 pjsua_core.c RX 784 bytes Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) from UDP 169.254.2.2:1487: SUBSCRIBE sip:spv@169.254.2.1:5061 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.2;branch=z9hG4bK3047df78aff3b9cdd4f988256148d97f Via: SIP/2.0/UDP 127.0.0.1:49602;branch=z9hG4bK-d87543-a16ffd28fd0bd86a-1--d87543-;rport Max-Forwards: 69 Contact: <sip:fil_x_lite@127.0.0.1:49602> To: "spv" <sip:spv@169.254.2.2> From: "fil_x_lite" <sip:fil_x_lite@169.254.2.2>;tag=db2e7031 Call-ID: NzczNzY3ZTkxY2EyMzk1MDRjNGRjZDViOWViODQxMzQ. CSeq: 1 SUBSCRIBE Subject: Expires: 3600 Accept: multipart/related Accept: application/rlmi+xml Accept: application/pidf+xml Allow: INVITE Allow: ACK Allow: CANCEL Allow: OPTIONS Allow: BYE Allow: REFER Allow: NOTIFY Allow: MESSAGE Allow: SUBSCRIBE Allow: INFO User-Agent: X-Lite release 1011s stamp 41150 Event: presence Content-Length: 0 --end msg-- 10:19:42.000 tsx0025F224 Incoming Request msg SUBSCRIBE/cseq=1 (rdata0006AFF4) in state Trying 10:19:51.000 endpoint Request msg REGISTER/cseq=29296 (tdta00258E20) created. 10:19:51.000 tsx00259E94 Transaction created for Request msg REGISTER/cseq=29297 (tdta00258E20) 10:19:51.000 tsx00259E94 Sending Request msg REGISTER/cseq=29297 (tdta00258E20) in state Null 10:19:51.000 sip_resolve.c Target '169.254.2.2:0' type=Unspecified resolved to '169.254.2.2:5060' type=UDP 10:19:51.000 pjsua_core.c TX 349 bytes Request msg REGISTER/cseq=29297 (tdta00258E20) to UDP 169.254.2.2:5060: REGISTER sip:169.254.2.2 SIP/2.0 Via: SIP/2.0/UDP 169.254.2.1:5061;rport;branch=z9hG4bKPj12cb000000072417fb35 Max-Forwards: 70 From: <sip:spv@169.254.2.2>;tag=12cb000000062417fb35 To: <sip:spv@169.254.2.2> Call-ID: 12cb000000012417fb35 CSeq: 29297 REGISTER Contact: <sip:spv@169.254.2.1:5061;transport=UDP> Expires: 0 Content-Length: 0 --end msg-- 10:19:51.000 tsx00259E94 State changed from Null to Calling, event=TX_MSG 10:19:51.000 pjsua_acc.c Unregistration sent 10:19:51.000 pjsua_core.c Shutting down... 10:19:51.000 sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=29297 (rdata0006AFF4) 10:19:51.000 pjsua_core.c RX 262 bytes Response msg 200/REGISTER/cseq=29297 (rdata0006AFF4) from UDP 169.254.2.2:1488: SIP/2.0 200 OK Call-ID: 12cb000000012417fb35 CSeq: 29297 REGISTER From: <sip:spv@169.254.2.2>;tag=12cb000000062417fb35 To: <sip:spv@169.254.2.2>;tag=18496378 Via: SIP/2.0/UDP 169.254.2.1:5061;rport;branch=z9hG4bKPj12cb000000072417fb35 Content-Length: 0 --end msg-- 10:19:51.000 tsx00259E94 Incoming Response msg 200/REGISTER/cseq=29297 (rdata0006AFF4) in state Calling 10:19:51.000 tsx00259E94 State changed from Calling to Completed, event=RX_MSG 10:19:51.000 pjsua_acc.c sip:spv@169.254.2.2: unregistration success 10:19:52.000 pjsua_media.c Closing (null) sound playback device and (null) sound capture device 10:19:52.000 pasound.c Stopping stream.. 10:19:52.000 pasound.c Done, status=0 10:19:52.000 pasound.c Closing M - Input: 2 underflow, 0 overflow 10:19:52.000 pasound.c PortAudio sound library shutting down.. 10:19:52.000 sip_endpoint.c Destroying endpoing instance.. 10:19:52.000 sip_endpoint.c Module "mod-pjsua-options" unregistered 10:19:52.000 sip_endpoint.c Module "mod-pjsua-im" unregistered 10:19:52.000 sip_endpoint.c Module "mod-pjsua-pres" unregistered 10:19:52.000 sip_endpoint.c Module "mod-pjsua" unregistered 10:19:52.000 sip_endpoint.c Module "mod-stateful-util" unregistered 10:19:52.000 sip_endpoint.c Module "mod-refer" unregistered 10:19:52.000 sip_endpoint.c Module "mod-presence" unregistered 10:19:52.000 sip_endpoint.c Module "mod-evsub" unregistered 10:19:52.000 sip_endpoint.c Module "mod-invite" unregistered 10:19:52.000 sip_endpoint.c Module "mod-100rel" unregistered 10:19:52.000 sip_endpoint.c Module "mod-ua" unregistered 10:19:52.000 sip_transactio Stopping transaction layer module 10:19:52.000 tsx0025F224 Transaction destroyed! 10:19:52.000 tdta00258E20 Destroying txdata Request msg REGISTER/cseq=29297 (tdta00258E20) 10:19:52.000 tsx00259E94 Transaction destroyed! 10:19:52.000 sip_transactio Transaction layer module destroyed 10:19:52.000 sip_endpoint.c Module "mod-tsx-layer" unregistered 10:19:52.000 sip_endpoint.c Module "mod-msg-print" unregistered 10:19:52.000 sip_endpoint.c Module "mod-pjsua-log" unregistered 10:19:52.000 sip_transport. Destroying transport manager 10:19:52.000 tcplis:5061 SIP TCP listener destroyed 10:19:52.000 sip_endpoint.c Endpoint 0005B064 destroyed 10:19:52.000 pjsua Pool is not released by application, releasing now 10:19:52.000 dlg0025E9B0 Pool is not released by application, releasing now 10:19:52.000 pjsua_core.c PJSUA destroyed...
OB
Olivier Beytrison
Thu, Oct 18, 2007 10:06 AM

Alright ...

nevermind all my mails ...

I should really stop working early in the morning with less than 1L of
coffee in my veins...

The problem was that after upgrading the pjsip library, i had to
recreate the whole project, and I forgot to set the target to Windows
Mobile 5. So it was compiled for PocketPC 2003. Since I changed it, it
works fine

Sorry for the inconvenience Benny

(as the saying says : Put your own work in question before the one of
the others)

Cheers,
Oliver

Olivier Beytrison a écrit :

Some more infos ..

We made some other tests here, and when an incoming call comes, it
reaches the UA and is handled by pjsip, it answers with a 100 Trying
(weird) and immediately send a 480 Unavailable, this without calling the
on_call_state callback nor the on_incoming_call.

You'll find the log file attached to this mail.

Regards,
Olivier B.

Olivier Beytrison a écrit :

Alright, we made another test right now.

Used : our application developed in C# for windows mobile, using a
wrapper to pjsip. (all thanks to Sasa ;)) which connect to a dummy sip
server which accept any REGISTER and send 200 OK. (SipServer from the
symbian SDK)

ok, here's some piece of code

the call back method itself

static void on_reg_state(pjsua_acc_id acc_id)
{
pjsua_acc_info accinfo;

pjsua_acc_get_info(acc_id, &accinfo);
PJ_LOG(3,(THIS_FILE,"Reg state changed for account %d :
%d",acc_id,accinfo.status));

// callback
if (cb_regstate != 0) cb_regstate(acc_id, accinfo.status);

}

call back assignement in the dll_init() code :

app_config.cfg.cb.on_reg_state = &on_reg_state;

Attached you'll find the pjsip.log file which contains everything.

For your information, the code of the wrapper hasn't changed since I've
upgraded to the last SVN trunk. and before the update, it was working
like a charm.

Ask me if you need any more informations.

Regards,

Olivier B.

Benny Prijono a écrit :

Olivier Beytrison wrote:

Hello,

This morning I've updated my source-tree to the last SVN-trunk (rev1504).

Since then, my on_reg_state callback isn't triggered at all, in a
scenario where it was always triggered in the past.

Anyone else experiencing the same issue ?

What particular scenario? I'm also interested to hear if anyone is
experiencing any problems with the svn trunk code.

cheers,
-benny

Cheers,
Olivier B.

--

Olivier Beytrison
Telecommunication Engineer
Mobile: +41 (0)78 619 73 53
Mail: olivier@heliosnet.org
GPG: 0x4FB83528 http://pgp.mit.edu/

Alright ... nevermind all my mails ... I should really stop working early in the morning with less than 1L of coffee in my veins... The problem was that after upgrading the pjsip library, i had to recreate the whole project, and I forgot to set the target to Windows Mobile 5. So it was compiled for PocketPC 2003. Since I changed it, it works fine Sorry for the inconvenience Benny (as the saying says : Put your own work in question before the one of the others) Cheers, Oliver Olivier Beytrison a écrit : > Some more infos .. > > We made some other tests here, and when an incoming call comes, it > reaches the UA and is handled by pjsip, it answers with a 100 Trying > (weird) and immediately send a 480 Unavailable, this without calling the > on_call_state callback nor the on_incoming_call. > > You'll find the log file attached to this mail. > > Regards, > Olivier B. > > Olivier Beytrison a écrit : >> Alright, we made another test right now. >> >> Used : our application developed in C# for windows mobile, using a >> wrapper to pjsip. (all thanks to Sasa ;)) which connect to a dummy sip >> server which accept any REGISTER and send 200 OK. (SipServer from the >> symbian SDK) >> >> ok, here's some piece of code >> >> the call back method itself >> >> static void on_reg_state(pjsua_acc_id acc_id) >> { >> pjsua_acc_info accinfo; >> >> pjsua_acc_get_info(acc_id, &accinfo); >> PJ_LOG(3,(THIS_FILE,"Reg state changed for account %d : >> %d",acc_id,accinfo.status)); >> >> // callback >> if (cb_regstate != 0) cb_regstate(acc_id, accinfo.status); >> } >> >> call back assignement in the dll_init() code : >> >> app_config.cfg.cb.on_reg_state = &on_reg_state; >> >> Attached you'll find the pjsip.log file which contains everything. >> >> For your information, the code of the wrapper hasn't changed since I've >> upgraded to the last SVN trunk. and before the update, it was working >> like a charm. >> >> Ask me if you need any more informations. >> >> Regards, >> >> Olivier B. >> >> Benny Prijono a écrit : >>> Olivier Beytrison wrote: >>>> Hello, >>>> >>>> This morning I've updated my source-tree to the last SVN-trunk (rev1504). >>>> >>>> Since then, my on_reg_state callback isn't triggered at all, in a >>>> scenario where it was always triggered in the past. >>>> >>>> Anyone else experiencing the same issue ? >>> What particular scenario? I'm also interested to hear if anyone is >>> experiencing any problems with the svn trunk code. >>> >>> cheers, >>> -benny >>> >>>> Cheers, >>>> Olivier B. >>>> >> >> ------------------------------------------------------------------------ >> >> _______________________________________________ >> 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 -- Olivier Beytrison Telecommunication Engineer Mobile: +41 (0)78 619 73 53 Mail: olivier@heliosnet.org GPG: 0x4FB83528 http://pgp.mit.edu/