pjsua: no audio is heard

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

 



Hello,
Can me somebody help with this problem? :

I have compiled aplication pjsua on ARM without customizations file 
pjlib/include/pj/config_site.h. Is empty.

But when i run application no audio is heard on speaker during the call...
I run pjsua as...

pjsua-svn --id sip:pc3 at 192.168.10.123 --registrar sip:192.168.10.123 --realm 
asterisk --username pc3 --password heslo  --auto-answer 200 --rec-file rec.wav 
--auto-rec --log-level=6 --app-log-level=6 --log-file=tmp.log 


here is log:

################################################
################################################

 13:13:02.383 sip_endpoint.c  Module "mod-pjsua-log" registered
 13:13:02.386 sip_endpoint.c  Module "mod-tsx-layer" registered
 13:13:02.388 sip_endpoint.c  Module "mod-stateful-util" registered
 13:13:02.391 sip_endpoint.c  Module "mod-ua" registered
 13:13:02.394 sip_endpoint.c  Module "mod-100rel" registered
 13:13:02.396 sip_endpoint.c  Module "mod-pjsua" registered
 13:13:02.399 sip_endpoint.c  Module "mod-invite" registered
 13:13:02.908       pa_dev.c  PortAudio sound library initialized, status=0
 13:13:02.911       pa_dev.c  PortAudio host api count=2
 13:13:02.913       pa_dev.c  Sound device count=3
 13:13:02.921          pjlib  select() I/O Queue created (0x23e6cc)
 13:13:03.060   conference.c  Creating conference bridge with 254 ports
 13:13:03.547 sip_endpoint.c  Module "mod-evsub" registered
 13:13:03.549 sip_endpoint.c  Module "mod-presence" registered
 13:13:03.551        evsub.c  Event pkg "presence" registered by mod-presence
 13:13:03.554 sip_endpoint.c  Module "mod-mwi" registered
 13:13:03.556        evsub.c  Event pkg "message-summary" registered by mod-
mwi
 13:13:03.558 sip_endpoint.c  Module "mod-refer" registered
 13:13:03.560        evsub.c  Event pkg "refer" registered by mod-refer
 13:13:03.605 sip_endpoint.c  Module "mod-pjsua-pres" registered
 13:13:03.607 sip_endpoint.c  Module "mod-pjsua-im" registered
 13:13:03.607 sip_endpoint.c  Module "mod-pjsua-options" registered
 13:13:03.610   pjsua_core.c  1 SIP worker threads created
 13:13:03.611   pjsua_core.c  pjsua version 1.4-trunk for arm-unknown-linux-
gnu initialized
 13:13:03.612 sip_endpoint.c  Module "mod-default-handler" registered
 13:13:03.614   wav_writer.c  File writer 'recc.wav' created: samp.rate=16000, 
bufsize=4KB
 13:13:03.621   pjsua_core.c  SIP UDP socket reachable at 192.168.32.17:5060
 13:13:03.625    udp0x259118  SIP UDP transport started, published address is 
192.168.32.17:5060
 13:13:03.627    pjsua_acc.c  Account <sip:192.168.32.17:5060> added with id 0
 13:13:03.633    tcplis:5060  SIP TCP listener ready for incoming connections 
at 192.168.32.17:5060
 13:13:03.635    pjsua_acc.c  Account <sip:192.168.32.17:5060;transport=TCP> 
added with id 1
 13:13:03.653    pjsua_acc.c  Account sip:pc3 at 192.168.10.123 added with id 2
 13:13:03.658       endpoint  Request msg REGISTER/cseq=32540 (tdta0x25b970) 
created.
 13:13:03.674    tsx0x25c9dc  Transaction created for Request msg 
REGISTER/cseq=32541 (tdta0x25b970)
 13:13:03.693    tsx0x25c9dc  Sending Request msg REGISTER/cseq=32541 
(tdta0x25b970) in state Null
 13:13:03.703  sip_resolve.c  Target '192.168.10.123:0' type=Unspecified 
resolved to '192.168.10.123:5060' type=UDP (UDP transport)
 13:13:03.707   pjsua_core.c  TX 438 bytes Request msg REGISTER/cseq=32541 
(tdta0x25b970) to UDP 192.168.10.123:5060:
REGISTER sip:192.168.10.123 SIP/2.0
Via: SIP/2.0/UDP 
192.168.32.17:5060;rport;branch=z9hG4bKPjFddwCvK7f8bGykeG9Tjxl4QysRTthWsb
Max-Forwards: 70
From: <sip:pc3@192.168.10.123>;tag=0KeNmGnF.sDUtECKoEhLa6s1iR8tOR2o
To: <sip:pc3 at 192.168.10.123>
Call-ID: xrOXblP8bc9W3eZMIG-cziLHBRm8DVDr
CSeq: 32541 REGISTER
User-Agent: PJSUA v1.4-trunk/arm-unknown-linux-gnu
Contact: <sip:pc3 at 192.168.32.17:5060>
Expires: 300
Content-Length:  0


--end msg--
 13:13:03.733    tsx0x25c9dc  State changed from Null to Calling, event=TX_MSG
 13:13:03.738 sip_endpoint.c  Processing incoming message: Response msg 
100/REGISTER/cseq=32541 (rdata0x25958c)
 13:13:03.743    pjsua_acc.c  Registration sent
 13:13:03.755  pjsua_media.c  RTP socket reachable at 192.168.32.17:4000
 13:13:03.769  pjsua_media.c  RTCP socket reachable at 192.168.32.17:4001
 13:13:03.772   pjsua_core.c  RX 475 bytes Response msg 
100/REGISTER/cseq=32541 (rdata0x25958c) from UDP 192.168.32.123:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 
192.168.32.17:5060;branch=z9hG4bKPjFddwCvK7f8bGykeG9Tjxl4QysRTthWsb;received=192.168.32.17;rport=5060
From: <sip:pc3@192.168.10.123>;tag=0KeNmGnF.sDUtECKoEhLa6s1iR8tOR2o
To: <sip:pc3 at 192.168.10.123>
Call-ID: xrOXblP8bc9W3eZMIG-cziLHBRm8DVDr
CSeq: 32541 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:pc3 at 192.168.32.123>
Content-Length: 0


--end msg--
 13:13:03.774    tsx0x25c9dc  Incoming Response msg 100/REGISTER/cseq=32541 
(rdata0x25958c) in state Calling
 13:13:03.775    tsx0x25c9dc  State changed from Calling to Proceeding, 
event=RX_MSG
 13:13:03.796  pjsua_media.c  RTP socket reachable at 192.168.32.17:4002
 13:13:03.803 sip_endpoint.c  Processing incoming message: Response msg 
401/REGISTER/cseq=32541 (rdata0x25958c)
 13:13:03.833  pjsua_media.c  RTCP socket reachable at 192.168.32.17:4003
 13:13:03.852   pjsua_core.c  RX 537 bytes Response msg 
401/REGISTER/cseq=32541 (rdata0x25958c) from UDP 192.168.32.123:5060:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 
192.168.32.17:5060;branch=z9hG4bKPjFddwCvK7f8bGykeG9Tjxl4QysRTthWsb;received=192.168.32.17;rport=5060
From: <sip:pc3@192.168.10.123>;tag=0KeNmGnF.sDUtECKoEhLa6s1iR8tOR2o
To: <sip:pc3 at 192.168.10.123>;tag=as237c06cf
Call-ID: xrOXblP8bc9W3eZMIG-cziLHBRm8DVDr
CSeq: 32541 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="19856f1f"
Content-Length: 0


--end msg--
 13:13:03.853    tsx0x25c9dc  Incoming Response msg 401/REGISTER/cseq=32541 
(rdata0x25958c) in state Proceeding
 13:13:03.854    tsx0x25c9dc  State changed from Proceeding to Completed, 
event=RX_MSG
 13:13:03.855    tsx0x25e394  Transaction created for Request msg 
REGISTER/cseq=32542 (tdta0x25b970)
 13:13:03.856    tsx0x25e394  Sending Request msg REGISTER/cseq=32542 
(tdta0x25b970) in state Null
 13:13:03.856       endpoint  Request msg REGISTER/cseq=32542 (tdta0x25b970): 
skipping target resolution because address is already set
 13:13:03.858   pjsua_core.c  TX 598 bytes Request msg REGISTER/cseq=32542 
(tdta0x25b970) to UDP 192.168.10.123:5060:
REGISTER sip:192.168.10.123 SIP/2.0
Via: SIP/2.0/UDP 
192.168.32.17:5060;rport;branch=z9hG4bKPj3FYcem33B13VEMUfjGJPmFW4oHa3XQYP
Max-Forwards: 70
From: <sip:pc3@192.168.10.123>;tag=0KeNmGnF.sDUtECKoEhLa6s1iR8tOR2o
To: <sip:pc3 at 192.168.10.123>
Call-ID: xrOXblP8bc9W3eZMIG-cziLHBRm8DVDr
CSeq: 32542 REGISTER
User-Agent: PJSUA v1.4-trunk/arm-unknown-linux-gnu
Contact: <sip:pc3 at 192.168.32.17:5060>
Expires: 300
Authorization: Digest username="pc3", realm="asterisk", nonce="19856f1f", 
uri="sip:192.168.10.123", response="f2770566d802cebe33f97c31141711fe", 
algorithm=MD5
Content-Length:  0


--end msg--
 13:13:03.859    tsx0x25e394  State changed from Null to Calling, event=TX_MSG
 13:13:03.864 sip_endpoint.c  Processing incoming message: Response msg 
100/REGISTER/cseq=32542 (rdata0x25958c)
 13:13:03.867   pjsua_core.c  RX 475 bytes Response msg 
100/REGISTER/cseq=32542 (rdata0x25958c) from UDP 192.168.32.123:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 
192.168.32.17:5060;branch=z9hG4bKPj3FYcem33B13VEMUfjGJPmFW4oHa3XQYP;received=192.168.32.17;rport=5060
From: <sip:pc3@192.168.10.123>;tag=0KeNmGnF.sDUtECKoEhLa6s1iR8tOR2o
To: <sip:pc3 at 192.168.10.123>
Call-ID: xrOXblP8bc9W3eZMIG-cziLHBRm8DVDr
CSeq: 32542 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:pc3 at 192.168.32.123>
Content-Length: 0


--end msg--
 13:13:03.869    tsx0x25e394  Incoming Response msg 100/REGISTER/cseq=32542 
(rdata0x25958c) in state Calling
 13:13:03.872    tsx0x25e394  State changed from Calling to Proceeding, 
event=RX_MSG
 13:13:03.875 sip_endpoint.c  Processing incoming message: Response msg 
200/REGISTER/cseq=32542 (rdata0x25958c)
 13:13:03.881  pjsua_media.c  RTP socket reachable at 192.168.32.17:4004
 13:13:03.935   pjsua_core.c  RX 553 bytes Response msg 
200/REGISTER/cseq=32542 (rdata0x25958c) from UDP 192.168.32.123:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 
192.168.32.17:5060;branch=z9hG4bKPj3FYcem33B13VEMUfjGJPmFW4oHa3XQYP;received=192.168.32.17;rport=5060
From: <sip:pc3@192.168.10.123>;tag=0KeNmGnF.sDUtECKoEhLa6s1iR8tOR2o
To: <sip:pc3 at 192.168.10.123>;tag=as237c06cf
Call-ID: xrOXblP8bc9W3eZMIG-cziLHBRm8DVDr
CSeq: 32542 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Expires: 300
Contact: <sip:pc3 at 192.168.32.17:5060>;expires=300
Date: Fri, 20 Nov 2009 12:15:29 GMT
Content-Length: 0


--end msg--
 13:13:03.946    tsx0x25e394  Incoming Response msg 200/REGISTER/cseq=32542 
(rdata0x25958c) in state Proceeding
 13:13:03.977    tsx0x25e394  State changed from Proceeding to Completed, 
event=RX_MSG
 13:13:03.994  pjsua_media.c  RTCP socket reachable at 192.168.32.17:4005
 13:13:04.001  pjsua_media.c  RTP socket reachable at 192.168.32.17:4006
 13:13:04.013  pjsua_media.c  RTCP socket reachable at 192.168.32.17:4007
 13:13:04.034    pjsua_acc.c  sip:pc3 at 192.168.10.123: registration success, 
status=200 (OK), will re-register in 300 seconds
 13:13:04.044    pjsua_acc.c  Keep-alive timer started for acc 2, 
destination:192.168.32.123:5060, interval:15s


################################################
################################################



application is running....  receiveing call...



################################################
################################################



 13:13:08.862    tsx0x25c9dc  Timeout timer event
 13:13:08.864    tsx0x25c9dc  State changed from Completed to Terminated, 
event=TIMER
 13:13:08.892    tsx0x25c9dc  Timeout timer event
 13:13:08.894    tsx0x25c9dc  State changed from Terminated to Destroyed, 
event=TIMER
 13:13:08.896    tsx0x25c9dc  Transaction destroyed!
 13:13:08.982    tsx0x25e394  Timeout timer event
 13:13:08.984    tsx0x25e394  State changed from Completed to Terminated, 
event=TIMER
 13:13:08.992    tsx0x25e394  Timeout timer event
 13:13:08.994    tsx0x25e394  State changed from Terminated to Destroyed, 
event=TIMER
 13:13:08.996   tdta0x25b970  Destroying txdata Request msg 
REGISTER/cseq=32542 (tdta0x25b970)
 13:13:08.999    tsx0x25e394  Transaction destroyed!
 13:13:19.052    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 
192.168.32.123:5060
 13:13:19.055   tdta0x25b970  Destroying txdata raw
 13:13:34.062    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 
192.168.32.123:5060
 13:13:34.063   tdta0x25b970  Destroying txdata raw
 13:13:49.072    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 
192.168.32.123:5060
 13:13:49.075   tdta0x25b970  Destroying txdata raw
 13:14:04.082    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 
192.168.32.123:5060
 13:14:04.084   tdta0x25b970  Destroying txdata raw
 13:14:06.673 sip_endpoint.c  Processing incoming message: Request msg 
INVITE/cseq=102 (rdata0x25958c)
 13:14:06.675   pjsua_core.c  RX 852 bytes Request msg INVITE/cseq=102 
(rdata0x25958c) from UDP 192.168.32.123:5060:
INVITE sip:pc3 at 192.168.32.17:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.32.123:5060;branch=z9hG4bK11341831;rport
From: "pc1" <sip:pc1@192.168.32.123>;tag=as67dbe988
To: <sip:pc3 at 192.168.32.17:5060>
Contact: <sip:pc1 at 192.168.32.123>
Call-ID: 450c115729d991851c21b5d3130cee28 at 192.168.32.123
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Fri, 20 Nov 2009 12:16:32 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 320

v=0
o=root 3336 3336 IN IP4 192.168.32.123
s=session
c=IN IP4 192.168.32.123
t=0 0
m=audio 15286 RTP/AVP 0 111 3 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

--end msg--
 13:14:06.680  pjsua_media.c  Media index 0 selected for call 0
 13:14:06.688    tsx0x26a8a4  Transaction created for Request msg 
INVITE/cseq=102 (rdata0x25958c)
 13:14:06.690    tsx0x26a8a4  Incoming Request msg INVITE/cseq=102 
(rdata0x25958c) in state Null
 13:14:06.693    tsx0x26a8a4  State changed from Null to Trying, event=RX_MSG
 13:14:06.694    dlg0x25d924  Transaction tsx0x26a8a4 state changed to Trying
 13:14:06.697    dlg0x25d924  UAS dialog created
 13:14:06.701    dlg0x25d924  Module mod-invite added as dialog usage, 
data=0x25dfd8
 13:14:06.703    dlg0x25d924  Session count inc to 2 by mod-invite
 13:14:06.706    inv0x25d924  UAS invite session created for dialog 
dlg0x25d924
 13:14:06.708   pjsua_call.c  Call 0: remote NAT type is 0 (Unknown)
 13:14:06.712       endpoint  Response msg 100/INVITE/cseq=102 (tdta0x26c680) 
created
 13:14:06.714    dlg0x25d924  Initial answer Response msg 100/INVITE/cseq=102 
(tdta0x26c680)
 13:14:06.716    inv0x25d924  Sending Response msg 100/INVITE/cseq=102 
(tdta0x26c680)
 13:14:06.719    dlg0x25d924  Sending Response msg 100/INVITE/cseq=102 
(tdta0x26c680)
 13:14:06.721    tsx0x26a8a4  Sending Response msg 100/INVITE/cseq=102 
(tdta0x26c680) in state Trying
 13:14:06.724   pjsua_core.c  TX 296 bytes Response msg 100/INVITE/cseq=102 
(tdta0x26c680) to UDP 192.168.32.123:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 
192.168.32.123:5060;rport=5060;received=192.168.32.123;branch=z9hG4bK11341831
Call-ID: 450c115729d991851c21b5d3130cee28 at 192.168.32.123
From: "pc1" <sip:pc1@192.168.32.123>;tag=as67dbe988
To: <sip:pc3 at 192.168.32.17>
CSeq: 102 INVITE
Content-Length:  0


--end msg--
 13:14:06.727    tsx0x26a8a4  State changed from Trying to Proceeding, 
event=TX_MSG
 13:14:06.729    dlg0x25d924  Transaction tsx0x26a8a4 state changed to 
Proceeding
 13:14:06.733  pjsua_media.c  Opening sound device PCM at 16000/1/20ms
 13:14:06.753       pa_dev.c  Opened device UCB1400:  (hw:0,0)(ALSA)/UCB1400:  
(hw:0,0)(ALSA) for recording and playback, sample rate=16000, ch=1, bits=16, 
320 samples per frame, input latency=100 ms, output latency=140 ms
 13:14:06.756     ec0x25ea18  Creating AEC
 13:14:06.828     ec0x25ea18  AEC created, clock_rate=16000, channel=1, 
samples per frame=320, tail length=200 ms, latency=140 ms
 13:14:06.830       pa_dev.c  Starting UCB1400:  (hw:0,0) stream..
 13:14:06.835       pa_dev.c  Done, status=0
 13:14:06.837   conference.c  Port 3 (ring) transmitting to port 0 (UCB1400:  
(hw:0,0))
 13:14:06.843    inv0x25d924  SDP negotiation done, status=0
 13:14:06.846   pjsua_call.c  Call 0: remote NAT type is 0 (Unknown)
 13:14:06.851   strm0x2a034c  VAD temporarily disabled
 13:14:06.854       pa_dev.c  Recorder thread started
 13:14:06.855     ec0x25ea18  Prefetching..
 13:14:06.855 os_core_unix.c  Info: possibly re-registering existing thread
 13:14:06.856       pa_dev.c  Player thread started
 13:14:06.860          rtp.c  pjmedia_rtp_session_init: ses=0x2a1be4, 
default_pt=0, ssrc=0x3799db39
 13:14:06.862          rtp.c  pjmedia_rtp_session_init: ses=0x2a2208, 
default_pt=0, ssrc=0x3799db39
 13:14:06.865       stream.c  Stream strm0x2a034c created
 13:14:06.867   strm0x2a034c  Encoder stream started
 13:14:06.869   strm0x2a034c  Decoder stream started
 13:14:06.874     ec0x25ea18  Prefetching..
 13:14:06.873     resample.c  resample created: high qualiy, large filter, 
in/out rate=8000/16000
 13:14:06.878     resample.c  resample created: high qualiy, large filter, 
in/out rate=16000/8000
 13:14:06.881  pjsua_media.c  Media updates, stream #0: PCMU (sendrecv)
 13:14:06.884   strm0x2a034c  Start talksprut..
 13:14:06.895     ec0x25ea18  Prefetching..
 13:14:06.900   conference.c  Port 3 (ring) stop transmitting to port 0 
(UCB1400:  (hw:0,0))
 13:14:06.902   conference.c  Port 4 (sip:pc1 at 192.168.32.123) transmitting to 
port 1 (recc.wav)
 13:14:06.904   conference.c  Port 4 (sip:pc1 at 192.168.32.123) transmitting to 
port 0 (UCB1400:  (hw:0,0))
 13:14:06.906   conference.c  Port 0 (UCB1400:  (hw:0,0)) transmitting to port 
4 (sip:pc1 at 192.168.32.123)
 13:14:06.908   conference.c  Port 0 (UCB1400:  (hw:0,0)) transmitting to port 
1 (recc.wav)
 13:14:06.910    pjsua_app.c  Media for call 0 is active
 13:14:06.913    inv0x25d924  Sending Response msg 200/INVITE/cseq=102 
(tdta0x26c680)
 13:14:06.916    dlg0x25d924  Sending Response msg 200/INVITE/cseq=102 
(tdta0x26c680)
 13:14:06.918    tsx0x26a8a4  Sending Response msg 200/INVITE/cseq=102 
(tdta0x26c680) in state Proceeding
 13:14:06.921   pjsua_core.c  TX 792 bytes Response msg 200/INVITE/cseq=102 
(tdta0x26c680) to UDP 192.168.32.123:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 
192.168.32.123:5060;rport=5060;received=192.168.32.123;branch=z9hG4bK11341831
Call-ID: 450c115729d991851c21b5d3130cee28 at 192.168.32.123
From: "pc1" <sip:pc1@192.168.32.123>;tag=as67dbe988
To: <sip:pc3 at 192.168.32.17>;tag=9KiTS1FWFC2hKpT7mJamKvfBMic1GMkp
CSeq: 102 INVITE
Contact: <sip:192.168.32.17:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, 
MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   254

v=0
o=- 3467708046 3467708047 IN IP4 192.168.32.17
s=pjmedia
c=IN IP4 192.168.32.17
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 0 101
a=rtcp:4001 IN IP4 192.168.32.17
a=rtpmap:0 PCMU/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

--end msg--
 13:14:06.926    tsx0x26a8a4  State changed from Proceeding to Completed, 
event=TX_MSG
 13:14:06.929    udp0x25b360  Remote RTCP address switched to 
192.168.32.123:4001
 13:14:06.931    dlg0x25d924  Transaction tsx0x26a8a4 state changed to 
Completed
 13:14:06.914     ec0x25ea18  Prefetching..
 13:14:06.933    pjsua_app.c  Call 0 state changed to CONNECTING
 13:14:06.937 sip_endpoint.c  Processing incoming message: Request msg 
ACK/cseq=102 (rdata0x25958c)
 13:14:06.939   pjsua_core.c  RX 400 bytes Request msg ACK/cseq=102 
(rdata0x25958c) from UDP 192.168.32.123:5060:
ACK sip:192.168.32.17:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.32.123:5060;branch=z9hG4bK746c0438;rport
From: "pc1" <sip:pc1@192.168.32.123>;tag=as67dbe988
To: <sip:pc3 at 192.168.32.17:5060>;tag=9KiTS1FWFC2hKpT7mJamKvfBMic1GMkp
Contact: <sip:pc1 at 192.168.32.123>
Call-ID: 450c115729d991851c21b5d3130cee28 at 192.168.32.123
CSeq: 102 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0


--end msg--
 13:14:06.946   strm0x2a034c  Jitter buffer empty (prefetch=0), plc invoked
 13:14:06.953   strm0x2a034c  RTP status: badpt=0, badssrc=0, dup=0, 
outorder=0, probation=-1, restart=0
 13:14:06.941    dlg0x25d924  Received Request msg ACK/cseq=102 
(rdata0x25958c)
 13:14:06.956    tsx0x26a8a4  Request to terminate transaction
 13:14:06.956    tsx0x26a8a4  State changed from Completed to Terminated, 
event=USER
 13:14:06.957    dlg0x25d924  Transaction tsx0x26a8a4 state changed to 
Terminated
 13:14:06.958    pjsua_app.c  Call 0 state changed to CONFIRMED
 13:14:06.960 sip_endpoint.c  Processing incoming message: Request msg 
INVITE/cseq=103 (rdata0x25958c)
 13:14:06.960   pjsua_core.c  RX 769 bytes Request msg INVITE/cseq=103 
(rdata0x25958c) from UDP 192.168.32.123:5060:
INVITE sip:192.168.32.17:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.32.123:5060;branch=z9hG4bK3f61f139;rport
From: "pc1" <sip:pc1@192.168.32.123>;tag=as67dbe988
To: <sip:pc3 at 192.168.32.17:5060>;tag=9KiTS1FWFC2hKpT7mJamKvfBMic1GMkp
Contact: <sip:pc1 at 192.168.32.123>
Call-ID: 450c115729d991851c21b5d3130cee28 at 192.168.32.123
CSeq: 103 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 241

v=0
o=root 3336 3337 IN IP4 192.168.10.123
s=session
c=IN IP4 192.168.10.123
t=0 0
m=audio 4000 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

--end msg--
 13:14:06.961    dlg0x25d924  Received Request msg INVITE/cseq=103 
(rdata0x25958c)
 13:14:06.972    tsx0x2698fc  Transaction created for Request msg 
INVITE/cseq=103 (rdata0x25958c)
 13:14:06.974    tsx0x2698fc  Incoming Request msg INVITE/cseq=103 
(rdata0x25958c) in state Null
 13:14:06.976    tsx0x2698fc  State changed from Null to Trying, event=RX_MSG
 13:14:06.986    dlg0x25d924  Transaction tsx0x2698fc state changed to Trying
 13:14:06.989    inv0x25d924  Got SDP offer in Request msg INVITE/cseq=103 
(rdata0x25958c)
 13:14:06.991   pjsua_call.c  Call 0: received updated media offer
 13:14:07.016       endpoint  Response msg 200/INVITE/cseq=103 (tdta0x2a3ed0) 
created
 13:14:07.020    inv0x25d924  SDP negotiation done, status=0
 13:14:07.023   pjsua_call.c  Call 0: remote NAT type is 0 (Unknown)
 13:14:07.025   strm0x2a034c  JB summary: size=10 prefetch=0, delay 
(min/max/avg/dev)=0/0/0/0 ms, burst (min/max/avg/dev)=0/0/0/0 frames, lost=0 
discard=0 empty=1
 13:14:07.028  pjsua_media.c  Media session for call 0 is destroyed
 13:14:07.033   strm0x2a602c  VAD temporarily disabled
 13:14:07.035          rtp.c  pjmedia_rtp_session_init: ses=0x2a78c4, 
default_pt=0, ssrc=0x3799db39
 13:14:07.038          rtp.c  pjmedia_rtp_session_init: ses=0x2a7ee8, 
default_pt=0, ssrc=0x3799db39
 13:14:07.041       stream.c  Stream strm0x2a602c created
 13:14:07.043   strm0x2a602c  Encoder stream started
 13:14:07.045   strm0x2a602c  Decoder stream started
 13:14:07.048     resample.c  resample created: high qualiy, large filter, 
in/out rate=8000/16000
 13:14:07.051     resample.c  resample created: high qualiy, large filter, 
in/out rate=16000/8000
 13:14:07.054  pjsua_media.c  Media updates, stream #0: PCMU (sendrecv)
 13:14:07.057   conference.c  Port 4 (sip:pc1 at 192.168.32.123) transmitting to 
port 1 (recc.wav)
 13:14:07.058   conference.c  Port 4 (sip:pc1 at 192.168.32.123) transmitting to 
port 0 (UCB1400:  (hw:0,0))
 13:14:07.061   conference.c  Port 0 (UCB1400:  (hw:0,0)) transmitting to port 
4 (sip:pc1 at 192.168.32.123)
 13:14:07.063    pjsua_app.c  Media for call 0 is active
 13:14:07.066    inv0x25d924  Sending Response msg 200/INVITE/cseq=103 
(tdta0x2a3ed0)
 13:14:07.068   strm0x2a602c  RTP status: badpt=0, badssrc=0, dup=0, 
outorder=0, probation=-1, restart=0
 13:14:07.069    dlg0x25d924  Sending Response msg 200/INVITE/cseq=103 
(tdta0x2a3ed0)
 13:14:07.072    tsx0x2698fc  Sending Response msg 200/INVITE/cseq=103 
(tdta0x2a3ed0) in state Trying
 13:14:07.078   pjsua_core.c  TX 792 bytes Response msg 200/INVITE/cseq=103 
(tdta0x2a3ed0) to UDP 192.168.32.123:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 
192.168.32.123:5060;rport=5060;received=192.168.32.123;branch=z9hG4bK3f61f139
Call-ID: 450c115729d991851c21b5d3130cee28 at 192.168.32.123
From: "pc1" <sip:pc1@192.168.32.123>;tag=as67dbe988
To: <sip:pc3 at 192.168.32.17>;tag=9KiTS1FWFC2hKpT7mJamKvfBMic1GMkp
CSeq: 103 INVITE
Contact: <sip:192.168.32.17:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, 
MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   254

v=0
o=- 3467708046 3467708048 IN IP4 192.168.32.17
s=pjmedia
c=IN IP4 192.168.32.17
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 0 101
a=rtcp:4001 IN IP4 192.168.32.17
a=rtpmap:0 PCMU/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

--end msg--
 13:14:07.082    tsx0x2698fc  State changed from Trying to Completed, 
event=TX_MSG
 13:14:07.084    dlg0x25d924  Transaction tsx0x2698fc state changed to 
Completed
 13:14:07.088 sip_endpoint.c  Processing incoming message: Request msg 
ACK/cseq=103 (rdata0x25958c)
 13:14:07.090   pjsua_core.c  RX 400 bytes Request msg ACK/cseq=103 
(rdata0x25958c) from UDP 192.168.32.123:5060:
ACK sip:192.168.32.17:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.32.123:5060;branch=z9hG4bK2bbdf999;rport
From: "pc1" <sip:pc1@192.168.32.123>;tag=as67dbe988
To: <sip:pc3 at 192.168.32.17:5060>;tag=9KiTS1FWFC2hKpT7mJamKvfBMic1GMkp
Contact: <sip:pc1 at 192.168.32.123>
Call-ID: 450c115729d991851c21b5d3130cee28 at 192.168.32.123
CSeq: 103 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0


--end msg--
 13:14:07.092    dlg0x25d924  Received Request msg ACK/cseq=103 
(rdata0x25958c)
 13:14:07.097    tsx0x2698fc  Request to terminate transaction
 13:14:07.098    tsx0x2698fc  State changed from Completed to Terminated, 
event=USER
 13:14:07.102    dlg0x25d924  Transaction tsx0x2698fc state changed to 
Terminated
 13:14:07.105    tsx0x26a8a4  Timeout timer event
 13:14:07.107    tsx0x26a8a4  State changed from Terminated to Destroyed, 
event=TIMER
 13:14:07.109   tdta0x26c680  Destroying txdata Response msg 
200/INVITE/cseq=102 (tdta0x26c680)
 13:14:07.111    tsx0x26a8a4  Transaction destroyed!
 13:14:07.114    tsx0x2698fc  Timeout timer event
 13:14:07.116    tsx0x2698fc  State changed from Terminated to Destroyed, 
event=TIMER
 13:14:07.119   tdta0x2a3ed0  Destroying txdata Response msg 
200/INVITE/cseq=103 (tdta0x2a3ed0)
 13:14:07.121    tsx0x2698fc  Transaction destroyed!
 13:14:07.228    udp0x25b360  Remote RTP address switched to 
192.168.32.123:4000
 13:14:07.229    udp0x25b360  Remote RTCP address switched to 
192.168.32.123:4001
 13:14:11.987    udp0x25b360  Remote RTCP address switched to 
192.168.32.123:15287
 13:14:19.092    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 
192.168.32.123:5060
 13:14:19.095   tdta0x26c680  Destroying txdata raw
 13:14:28.202     ec0x25ea18  Prefetching..
 13:14:28.286   strm0x2a602c  Start talksprut..
 13:14:34.102    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 
192.168.32.123:5060
 13:14:34.105   tdta0x26c680  Destroying txdata raw


################################################
################################################

call is CONFIRMED

File rec.wav is empty. When call is incoming i hear ring tone which is not 
clear. Is intermittent.  As if sample rate was wrong.

when i run sampe aplication "auddemo" and list device i got

  0: PA [UCB1400:  (hw:0,0)] (2/2)
  1: PA [default] (128/128)


commands
p 0 test.wav
and 
p 1 test.wav 
works..i hear sound very well

log from program :

File player 'test.wav' created: samp.rate=44100, ch=2, bufsize=4KB, 
filesize=2741KB
Opened device 0: UCB1400:  (hw:0,0)(ALSA) for playing, sample rate=44100, 
ch=2, bits=16, 1764 samples per frame, latency=99 ms
Starting UCB1400:  (hw:0,0) stream.. 


so i try run pjsua with parameter --playback-dev=1

../pjsua-svn --id sip:pc3 at 192.168.10.123 --registrar sip:192.168.10.123 --
realm asterisk --username pc3 --password heslo  --auto-answer 200 --rec-file 
recc.wav --auto-rec --log-level=6 --app-log-level=6 --playback-dev=1 --log-
file=tmp2.log

and i got

################################################
################################################


 13:41:17.759 sip_endpoint.c  Module "mod-pjsua-log" registered
 13:41:17.762 sip_endpoint.c  Module "mod-tsx-layer" registered
 13:41:17.765 sip_endpoint.c  Module "mod-stateful-util" registered
 13:41:17.767 sip_endpoint.c  Module "mod-ua" registered
 13:41:17.770 sip_endpoint.c  Module "mod-100rel" registered
 13:41:17.772 sip_endpoint.c  Module "mod-pjsua" registered
 13:41:17.775 sip_endpoint.c  Module "mod-invite" registered
 13:41:18.289       pa_dev.c  PortAudio sound library initialized, status=0
 13:41:18.291       pa_dev.c  PortAudio host api count=2
 13:41:18.293       pa_dev.c  Sound device count=3
 13:41:18.301          pjlib  select() I/O Queue created (0x23e6cc)
 13:41:18.313   conference.c  Creating conference bridge with 254 ports
 13:41:18.701 sip_endpoint.c  Module "mod-evsub" registered
 13:41:18.703 sip_endpoint.c  Module "mod-presence" registered
 13:41:18.705        evsub.c  Event pkg "presence" registered by mod-presence
 13:41:18.707 sip_endpoint.c  Module "mod-mwi" registered
 13:41:18.709        evsub.c  Event pkg "message-summary" registered by mod-
mwi
 13:41:18.712 sip_endpoint.c  Module "mod-refer" registered
 13:41:18.714        evsub.c  Event pkg "refer" registered by mod-refer
 13:41:18.716 sip_endpoint.c  Module "mod-pjsua-pres" registered
 13:41:18.719 sip_endpoint.c  Module "mod-pjsua-im" registered
 13:41:18.721 sip_endpoint.c  Module "mod-pjsua-options" registered
 13:41:18.724   pjsua_core.c  1 SIP worker threads created
 13:41:18.726   pjsua_core.c  pjsua version 1.4-trunk for arm-unknown-linux-
gnu initialized
 13:41:18.728 sip_endpoint.c  Module "mod-default-handler" registered
 13:41:18.732   wav_writer.c  File writer 'recc.wav' created: samp.rate=16000, 
bufsize=4KB
 13:41:18.758   pjsua_core.c  SIP UDP socket reachable at 192.168.32.17:5060
 13:41:18.816    udp0x259118  SIP UDP transport started, published address is 
192.168.32.17:5060
 13:41:18.823    pjsua_acc.c  Account <sip:192.168.32.17:5060> added with id 0
 13:41:18.828    tcplis:5060  SIP TCP listener ready for incoming connections 
at 192.168.32.17:5060
 13:41:18.843    pjsua_acc.c  Account <sip:192.168.32.17:5060;transport=TCP> 
added with id 1
 13:41:18.848    pjsua_acc.c  Account sip:pc3 at 192.168.10.123 added with id 2
 13:41:18.852       endpoint  Request msg REGISTER/cseq=45802 (tdta0x25b970) 
created.
 13:41:18.857    tsx0x25c9dc  Transaction created for Request msg 
REGISTER/cseq=45803 (tdta0x25b970)
 13:41:18.859    tsx0x25c9dc  Sending Request msg REGISTER/cseq=45803 
(tdta0x25b970) in state Null
 13:41:18.862  sip_resolve.c  Target '192.168.10.123:0' type=Unspecified 
resolved to '192.168.10.123:5060' type=UDP (UDP transport)
 13:41:18.866   pjsua_core.c  TX 438 bytes Request msg REGISTER/cseq=45803 
(tdta0x25b970) to UDP 192.168.10.123:5060:
REGISTER sip:192.168.10.123 SIP/2.0
Via: SIP/2.0/UDP 
192.168.32.17:5060;rport;branch=z9hG4bKPjY4e.HwGpo3qUsL4u81ZHTcXQxbORF7hK
Max-Forwards: 70
From: <sip:pc3@192.168.10.123>;tag=leUodnI.2q1SpKlz2DEdUm0-2m762bs8
To: <sip:pc3 at 192.168.10.123>
Call-ID: 2crov-CUQsInf3dZGxkSJBcxrjtqyUwk
CSeq: 45803 REGISTER
User-Agent: PJSUA v1.4-trunk/arm-unknown-linux-gnu
Contact: <sip:pc3 at 192.168.32.17:5060>
Expires: 300
Content-Length:  0


--end msg--
 13:41:18.869    tsx0x25c9dc  State changed from Null to Calling, event=TX_MSG
 13:41:18.874 sip_endpoint.c  Processing incoming message: Response msg 
100/REGISTER/cseq=45803 (rdata0x25958c)
 13:41:18.875    pjsua_acc.c  Registration sent
 13:41:18.880  pjsua_media.c  RTP socket reachable at 192.168.32.17:4000
 13:41:18.882  pjsua_media.c  RTCP socket reachable at 192.168.32.17:4001
 13:41:18.890  pjsua_media.c  RTP socket reachable at 192.168.32.17:4002
 13:41:18.882   pjsua_core.c  RX 475 bytes Response msg 
100/REGISTER/cseq=45803 (rdata0x25958c) from UDP 192.168.32.123:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 
192.168.32.17:5060;branch=z9hG4bKPjY4e.HwGpo3qUsL4u81ZHTcXQxbORF7hK;received=192.168.32.17;rport=5060
From: <sip:pc3@192.168.10.123>;tag=leUodnI.2q1SpKlz2DEdUm0-2m762bs8
To: <sip:pc3 at 192.168.10.123>
Call-ID: 2crov-CUQsInf3dZGxkSJBcxrjtqyUwk
CSeq: 45803 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:pc3 at 192.168.32.123>
Content-Length: 0


--end msg--
 13:41:18.898  pjsua_media.c  RTCP socket reachable at 192.168.32.17:4003
 13:41:18.903    tsx0x25c9dc  Incoming Response msg 100/REGISTER/cseq=45803 
(rdata0x25958c) in state Calling
 13:41:18.903    tsx0x25c9dc  State changed from Calling to Proceeding, 
event=RX_MSG
 13:41:18.905 sip_endpoint.c  Processing incoming message: Response msg 
401/REGISTER/cseq=45803 (rdata0x25958c)
 13:41:18.905   pjsua_core.c  RX 537 bytes Response msg 
401/REGISTER/cseq=45803 (rdata0x25958c) from UDP 192.168.32.123:5060:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 
192.168.32.17:5060;branch=z9hG4bKPjY4e.HwGpo3qUsL4u81ZHTcXQxbORF7hK;received=192.168.32.17;rport=5060
From: <sip:pc3@192.168.10.123>;tag=leUodnI.2q1SpKlz2DEdUm0-2m762bs8
To: <sip:pc3 at 192.168.10.123>;tag=as65cc45a1
Call-ID: 2crov-CUQsInf3dZGxkSJBcxrjtqyUwk
CSeq: 45803 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="04bb7d75"
Content-Length: 0


--end msg--
 13:41:18.906    tsx0x25c9dc  Incoming Response msg 401/REGISTER/cseq=45803 
(rdata0x25958c) in state Proceeding
 13:41:18.907    tsx0x25c9dc  State changed from Proceeding to Completed, 
event=RX_MSG
 13:41:18.908    tsx0x25e18c  Transaction created for Request msg 
REGISTER/cseq=45804 (tdta0x25b970)
 13:41:18.909    tsx0x25e18c  Sending Request msg REGISTER/cseq=45804 
(tdta0x25b970) in state Null
 13:41:18.909       endpoint  Request msg REGISTER/cseq=45804 (tdta0x25b970): 
skipping target resolution because address is already set
 13:41:18.911   pjsua_core.c  TX 598 bytes Request msg REGISTER/cseq=45804 
(tdta0x25b970) to UDP 192.168.10.123:5060:
REGISTER sip:192.168.10.123 SIP/2.0
Via: SIP/2.0/UDP 
192.168.32.17:5060;rport;branch=z9hG4bKPjiEoQvkjZOIjZZyqrScTjQqutxmsWlXUo
Max-Forwards: 70
From: <sip:pc3@192.168.10.123>;tag=leUodnI.2q1SpKlz2DEdUm0-2m762bs8
To: <sip:pc3 at 192.168.10.123>
Call-ID: 2crov-CUQsInf3dZGxkSJBcxrjtqyUwk
CSeq: 45804 REGISTER
User-Agent: PJSUA v1.4-trunk/arm-unknown-linux-gnu
Contact: <sip:pc3 at 192.168.32.17:5060>
Expires: 300
Authorization: Digest username="pc3", realm="asterisk", nonce="04bb7d75", 
uri="sip:192.168.10.123", response="adcc8fb6226e3868c01295df00672f2b", 
algorithm=MD5
Content-Length:  0


--end msg--
 13:41:18.915    tsx0x25e18c  State changed from Null to Calling, event=TX_MSG
 13:41:18.919 sip_endpoint.c  Processing incoming message: Response msg 
100/REGISTER/cseq=45804 (rdata0x25958c)
 13:41:18.919   pjsua_core.c  RX 475 bytes Response msg 
100/REGISTER/cseq=45804 (rdata0x25958c) from UDP 192.168.32.123:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 
192.168.32.17:5060;branch=z9hG4bKPjiEoQvkjZOIjZZyqrScTjQqutxmsWlXUo;received=192.168.32.17;rport=5060
From: <sip:pc3@192.168.10.123>;tag=leUodnI.2q1SpKlz2DEdUm0-2m762bs8
To: <sip:pc3 at 192.168.10.123>
Call-ID: 2crov-CUQsInf3dZGxkSJBcxrjtqyUwk
CSeq: 45804 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:pc3 at 192.168.32.123>
Content-Length: 0


--end msg--
 13:41:18.920    tsx0x25e18c  Incoming Response msg 100/REGISTER/cseq=45804 
(rdata0x25958c) in state Calling
 13:41:18.921    tsx0x25e18c  State changed from Calling to Proceeding, 
event=RX_MSG
 13:41:18.925  pjsua_media.c  RTP socket reachable at 192.168.32.17:4004
 13:41:18.927  pjsua_media.c  RTCP socket reachable at 192.168.32.17:4005
 13:41:18.933  pjsua_media.c  RTP socket reachable at 192.168.32.17:4006
 13:41:18.936 sip_endpoint.c  Processing incoming message: Response msg 
200/REGISTER/cseq=45804 (rdata0x25958c)
 13:41:18.937  pjsua_media.c  RTCP socket reachable at 192.168.32.17:4007
 13:41:18.942  pjsua_media.c  Opening sound device PCM at 16000/1/20ms
 13:41:18.943   pjsua_core.c  RX 553 bytes Response msg 
200/REGISTER/cseq=45804 (rdata0x25958c) from UDP 192.168.32.123:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 
192.168.32.17:5060;branch=z9hG4bKPjiEoQvkjZOIjZZyqrScTjQqutxmsWlXUo;received=192.168.32.17;rport=5060
From: <sip:pc3@192.168.10.123>;tag=leUodnI.2q1SpKlz2DEdUm0-2m762bs8
To: <sip:pc3 at 192.168.10.123>;tag=as65cc45a1
Call-ID: 2crov-CUQsInf3dZGxkSJBcxrjtqyUwk
CSeq: 45804 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Expires: 300
Contact: <sip:pc3 at 192.168.32.17:5060>;expires=300
Date: Fri, 20 Nov 2009 12:43:44 GMT
Content-Length: 0


--end msg--
 13:41:18.954    tsx0x25e18c  Incoming Response msg 200/REGISTER/cseq=45804 
(rdata0x25958c) in state Proceeding
 13:41:18.955    tsx0x25e18c  State changed from Proceeding to Completed, 
event=RX_MSG
 13:41:18.956    pjsua_acc.c  sip:pc3 at 192.168.10.123: registration success, 
status=200 (OK), will re-register in 300 seconds
 13:41:18.956    pjsua_acc.c  Keep-alive timer started for acc 2, 
destination:192.168.32.123:5060, interval:15s
 13:41:19.137       pa_dev.c  Opened device UCB1400:  (hw:0,0)
(ALSA)/default(ALSA) for recording and playback, sample rate=16000, ch=1, 
bits=16, 320 samples per frame, input latency=100 ms, output latency=140 ms
 13:41:19.139     ec0x23e488  Creating AEC
 13:41:19.219     ec0x23e488  AEC created, clock_rate=16000, channel=1, 
samples per frame=320, tail length=200 ms, latency=140 ms
 13:41:19.221       pa_dev.c  Starting UCB1400:  (hw:0,0) stream..
 13:41:19.232       pa_dev.c  Done, status=0
 13:41:19.246       pa_dev.c  Player thread started
 13:41:19.247       pa_dev.c  Recorder thread started
 13:41:19.265     ec0x23e488  Prefetching..
 13:41:19.267     ec0x23e488  Prefetching..
 13:41:19.287     ec0x23e488  Prefetching..
 13:41:19.307     ec0x23e488  Prefetching..
 13:41:19.327     ec0x23e488  Prefetching..
 13:41:19.347     ec0x23e488  Prefetching..
 13:41:19.367     ec0x23e488  Prefetching..
 13:41:19.385     ec0x23e488  Latency bufferring complete
 13:41:19.594     ec0x23e488  479 samples reduced, buf_cnt=1761

 13:41:20.074     ec0x23e488  481 samples reduced, buf_cnt=2241
 13:41:20.094     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:20.114     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:20.134     ec0x23e488  479 samples reduced, buf_cnt=1762
....
...
...
 13:41:22.835     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:22.853     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:22.870     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:22.887     ec0x23e488  320 samples reduced, buf_cnt=2241

 13:41:23.922    tsx0x25c9dc  Timeout timer event
 13:41:23.923    tsx0x25c9dc  State changed from Completed to Terminated, 
event=TIMER
 13:41:23.932    tsx0x25c9dc  Timeout timer event
 13:41:23.934    tsx0x25c9dc  State changed from Terminated to Destroyed, 
event=TIMER
 13:41:23.937    tsx0x25c9dc  Transaction destroyed!
 13:41:23.962    tsx0x25e18c  Timeout timer event
 13:41:23.964    tsx0x25e18c  State changed from Completed to Terminated, 
event=TIMER
 13:41:23.972    tsx0x25e18c  Timeout timer event
 13:41:23.974    tsx0x25e18c  State changed from Terminated to Destroyed, 
event=TIMER
 13:41:23.976   tdta0x25b970  Destroying txdata Request msg 
REGISTER/cseq=45804 (tdta0x25b970)
 13:41:23.978    tsx0x25e18c  Transaction destroyed!
 13:41:23.984     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.002     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.034     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.056     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.071     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.084     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.096     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.108     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.120     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.172     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.204     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.216     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.232     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.256     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:24.259   sound_port.c  EC suspended because of inactivity
 13:41:24.270     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:33.962    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 2 to 
192.168.32.123:5060
 13:41:33.965   tdta0x25b970  Destroying txdata raw
 13:41:44.069 sip_endpoint.c  Processing incoming message: Request msg 
INVITE/cseq=102 (rdata0x25958c)
 13:41:44.071   pjsua_core.c  RX 852 bytes Request msg INVITE/cseq=102 
(rdata0x25958c) from UDP 192.168.32.123:5060:
INVITE sip:pc3 at 192.168.32.17:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.32.123:5060;branch=z9hG4bK6b3b889f;rport
From: "pc1" <sip:pc1@192.168.32.123>;tag=as016d7557
To: <sip:pc3 at 192.168.32.17:5060>
Contact: <sip:pc1 at 192.168.32.123>
Call-ID: 29ecf63c2f06075312d889df1cf8d4a4 at 192.168.32.123
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Fri, 20 Nov 2009 12:44:09 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 320

v=0
o=root 3336 3336 IN IP4 192.168.32.123
s=session
c=IN IP4 192.168.32.123
t=0 0
m=audio 11398 RTP/AVP 0 111 3 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

--end msg--
 13:41:44.077  pjsua_media.c  Media index 0 selected for call 0
 13:41:44.086    tsx0x29d56c  Transaction created for Request msg 
INVITE/cseq=102 (rdata0x25958c)
 13:41:44.089    tsx0x29d56c  Incoming Request msg INVITE/cseq=102 
(rdata0x25958c) in state Null
 13:41:44.091    tsx0x29d56c  State changed from Null to Trying, event=RX_MSG
 13:41:44.093    dlg0x25dd2c  Transaction tsx0x29d56c state changed to Trying
 13:41:44.096    dlg0x25dd2c  UAS dialog created
 13:41:44.103    dlg0x25dd2c  Module mod-invite added as dialog usage, 
data=0x25e3e0
 13:41:44.105    dlg0x25dd2c  Session count inc to 2 by mod-invite
 13:41:44.108    inv0x25dd2c  UAS invite session created for dialog 
dlg0x25dd2c
 13:41:44.110   pjsua_call.c  Call 0: remote NAT type is 0 (Unknown)
 13:41:44.114       endpoint  Response msg 100/INVITE/cseq=102 (tdta0x29f348) 
created
 13:41:44.116    dlg0x25dd2c  Initial answer Response msg 100/INVITE/cseq=102 
(tdta0x29f348)
 13:41:44.118    inv0x25dd2c  Sending Response msg 100/INVITE/cseq=102 
(tdta0x29f348)
 13:41:44.122    dlg0x25dd2c  Sending Response msg 100/INVITE/cseq=102 
(tdta0x29f348)
 13:41:44.124    tsx0x29d56c  Sending Response msg 100/INVITE/cseq=102 
(tdta0x29f348) in state Trying
 13:41:44.127   pjsua_core.c  TX 296 bytes Response msg 100/INVITE/cseq=102 
(tdta0x29f348) to UDP 192.168.32.123:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 
192.168.32.123:5060;rport=5060;received=192.168.32.123;branch=z9hG4bK6b3b889f
Call-ID: 29ecf63c2f06075312d889df1cf8d4a4 at 192.168.32.123
From: "pc1" <sip:pc1@192.168.32.123>;tag=as016d7557
To: <sip:pc3 at 192.168.32.17>
CSeq: 102 INVITE
Content-Length:  0


--end msg--
 13:41:44.129    tsx0x29d56c  State changed from Trying to Proceeding, 
event=TX_MSG
 13:41:44.131    dlg0x25dd2c  Transaction tsx0x29d56c state changed to 
Proceeding
 13:41:44.134   conference.c  Port 3 (ring) transmitting to port 0 (UCB1400:  
(hw:0,0))
 13:41:44.139    inv0x25dd2c  SDP negotiation done, status=0
 13:41:44.141   pjsua_call.c  Call 0: remote NAT type is 0 (Unknown)
 13:41:44.150   strm0x2a2a6c  VAD temporarily disabled
 13:41:44.152          rtp.c  pjmedia_rtp_session_init: ses=0x2a4304, 
default_pt=0, ssrc=0x47a240b6
 13:41:44.154          rtp.c  pjmedia_rtp_session_init: ses=0x2a4928, 
default_pt=0, ssrc=0x47a240b6
 13:41:44.157       stream.c  Stream strm0x2a2a6c created
 13:41:44.159   strm0x2a2a6c  Encoder stream started
 13:41:44.184   strm0x2a2a6c  Decoder stream started
 13:41:44.187     resample.c  resample created: high qualiy, large filter, 
in/out rate=8000/16000
 13:41:44.189     resample.c  resample created: high qualiy, large filter, 
in/out rate=16000/8000
 13:41:44.143   sound_port.c  EC activated
 13:41:44.195  pjsua_media.c  Media updates, stream #0: PCMU (sendrecv)
 13:41:44.198   conference.c  Port 3 (ring) stop transmitting to port 0 
(UCB1400:  (hw:0,0))
 13:41:44.202   conference.c  Port 4 (sip:pc1 at 192.168.32.123) transmitting to 
port 1 (recc.wav)
 13:41:44.204   conference.c  Port 4 (sip:pc1 at 192.168.32.123) transmitting to 
port 0 (UCB1400:  (hw:0,0))
 13:41:44.207   conference.c  Port 0 (UCB1400:  (hw:0,0)) transmitting to port 
4 (sip:pc1 at 192.168.32.123)
 13:41:44.212   conference.c  Port 0 (UCB1400:  (hw:0,0)) transmitting to port 
1 (recc.wav)
 13:41:44.214    pjsua_app.c  Media for call 0 is active
 13:41:44.217    inv0x25dd2c  Sending Response msg 200/INVITE/cseq=102 
(tdta0x29f348)
 13:41:44.222   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
 13:41:44.223    dlg0x25dd2c  Sending Response msg 200/INVITE/cseq=102 
(tdta0x29f348)
 13:41:44.225    tsx0x29d56c  Sending Response msg 200/INVITE/cseq=102 
(tdta0x29f348) in state Proceeding
 13:41:44.227   pjsua_core.c  TX 792 bytes Response msg 200/INVITE/cseq=102 
(tdta0x29f348) to UDP 192.168.32.123:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 
192.168.32.123:5060;rport=5060;received=192.168.32.123;branch=z9hG4bK6b3b889f
Call-ID: 29ecf63c2f06075312d889df1cf8d4a4 at 192.168.32.123
From: "pc1" <sip:pc1@192.168.32.123>;tag=as016d7557
To: <sip:pc3 at 192.168.32.17>;tag=ySaCOXNMXtrrsnW9JyOCSg4OZg7VkYM1
CSeq: 102 INVITE
Contact: <sip:192.168.32.17:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, 
MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   254

v=0
o=- 3467709704 3467709705 IN IP4 192.168.32.17
s=pjmedia
c=IN IP4 192.168.32.17
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 0 101
a=rtcp:4001 IN IP4 192.168.32.17
a=rtpmap:0 PCMU/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

--end msg--
 13:41:44.233    tsx0x29d56c  State changed from Proceeding to Completed, 
event=TX_MSG
 13:41:44.238    udp0x25b360  Remote RTCP address switched to 
192.168.32.123:4001
 13:41:44.239    dlg0x25dd2c  Transaction tsx0x29d56c state changed to 
Completed
 13:41:44.241    pjsua_app.c  Call 0 state changed to CONNECTING
 13:41:44.254 sip_endpoint.c  Processing incoming message: Request msg 
ACK/cseq=102 (rdata0x25958c)
 13:41:44.256   pjsua_core.c  RX 400 bytes Request msg ACK/cseq=102 
(rdata0x25958c) from UDP 192.168.32.123:5060:
ACK sip:192.168.32.17:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.32.123:5060;branch=z9hG4bK437077a4;rport
From: "pc1" <sip:pc1@192.168.32.123>;tag=as016d7557
To: <sip:pc3 at 192.168.32.17:5060>;tag=ySaCOXNMXtrrsnW9JyOCSg4OZg7VkYM1
Contact: <sip:pc1 at 192.168.32.123>
Call-ID: 29ecf63c2f06075312d889df1cf8d4a4 at 192.168.32.123
CSeq: 102 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0


--end msg--
 13:41:44.258    dlg0x25dd2c  Received Request msg ACK/cseq=102 
(rdata0x25958c)
 13:41:44.260    tsx0x29d56c  Request to terminate transaction
 13:41:44.261    tsx0x29d56c  State changed from Completed to Terminated, 
event=USER
 13:41:44.262    dlg0x25dd2c  Transaction tsx0x29d56c state changed to 
Terminated
 13:41:44.277    pjsua_app.c  Call 0 state changed to CONFIRMED
 13:41:44.279 sip_endpoint.c  Processing incoming message: Request msg 
INVITE/cseq=103 (rdata0x25958c)
 13:41:44.279   pjsua_core.c  RX 769 bytes Request msg INVITE/cseq=103 
(rdata0x25958c) from UDP 192.168.32.123:5060:
INVITE sip:192.168.32.17:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.32.123:5060;branch=z9hG4bK0df46a26;rport
From: "pc1" <sip:pc1@192.168.32.123>;tag=as016d7557
To: <sip:pc3 at 192.168.32.17:5060>;tag=ySaCOXNMXtrrsnW9JyOCSg4OZg7VkYM1
Contact: <sip:pc1 at 192.168.32.123>
Call-ID: 29ecf63c2f06075312d889df1cf8d4a4 at 192.168.32.123
CSeq: 103 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 241

v=0
o=root 3336 3337 IN IP4 192.168.10.123
s=session
c=IN IP4 192.168.10.123
t=0 0
m=audio 4000 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

--end msg--
 13:41:44.280    dlg0x25dd2c  Received Request msg INVITE/cseq=103 
(rdata0x25958c)
 13:41:44.281    tsx0x29b61c  Transaction created for Request msg 
INVITE/cseq=103 (rdata0x25958c)
 13:41:44.282    tsx0x29b61c  Incoming Request msg INVITE/cseq=103 
(rdata0x25958c) in state Null
 13:41:44.300    tsx0x29b61c  State changed from Null to Trying, event=RX_MSG
 13:41:44.275   strm0x2a2a6c  Jitter buffer empty (prefetch=0), plc invoked
 13:41:44.263   strm0x2a2a6c  RTP status: badpt=0, badssrc=0, dup=0, 
outorder=0, probation=-1, restart=0
 13:41:44.304    dlg0x25dd2c  Transaction tsx0x29b61c state changed to Trying
 13:41:44.307    inv0x25dd2c  Got SDP offer in Request msg INVITE/cseq=103 
(rdata0x25958c)
 13:41:44.309   pjsua_call.c  Call 0: received updated media offer
 13:41:44.326       endpoint  Response msg 200/INVITE/cseq=103 (tdta0x2a6568) 
created
 13:41:44.343    inv0x25dd2c  SDP negotiation done, status=0
 13:41:44.352   pjsua_call.c  Call 0: remote NAT type is 0 (Unknown)
 13:41:44.363   strm0x2a2a6c  Start talksprut..
 13:41:44.373   strm0x2a2a6c  JB summary: size=12 prefetch=0, delay 
(min/max/avg/dev)=0/0/0/0 ms, burst (min/max/avg/dev)=0/0/0/0 frames, lost=0 
discard=0 empty=1
 13:41:44.375  pjsua_media.c  Media session for call 0 is destroyed
 13:41:44.379   strm0x2a86c4  VAD temporarily disabled
 13:41:44.382          rtp.c  pjmedia_rtp_session_init: ses=0x2a9f5c, 
default_pt=0, ssrc=0x47a240b6
 13:41:44.384          rtp.c  pjmedia_rtp_session_init: ses=0x2aa580, 
default_pt=0, ssrc=0x47a240b6
 13:41:44.389       stream.c  Stream strm0x2a86c4 created
 13:41:44.391   strm0x2a86c4  Encoder stream started
 13:41:44.393   strm0x2a86c4  Decoder stream started
 13:41:44.395     resample.c  resample created: high qualiy, large filter, 
in/out rate=8000/16000
 13:41:44.408     resample.c  resample created: high qualiy, large filter, 
in/out rate=16000/8000
 13:41:44.411  pjsua_media.c  Media updates, stream #0: PCMU (sendrecv)
 13:41:44.413   strm0x2a86c4  Start talksprut..
 13:41:44.419   strm0x2a86c4  RTP status: badpt=0, badssrc=0, dup=0, 
outorder=0, probation=-1, restart=0
 13:41:44.431     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:44.434   conference.c  Port 4 (sip:pc1 at 192.168.32.123) transmitting to 
port 1 (recc.wav)
 13:41:44.435   conference.c  Port 4 (sip:pc1 at 192.168.32.123) transmitting to 
port 0 (UCB1400:  (hw:0,0))
 13:41:44.435   conference.c  Port 0 (UCB1400:  (hw:0,0)) transmitting to port 
4 (sip:pc1 at 192.168.32.123)
 13:41:44.436    pjsua_app.c  Media for call 0 is active
 13:41:44.437    inv0x25dd2c  Sending Response msg 200/INVITE/cseq=103 
(tdta0x2a6568)
 13:41:44.437    dlg0x25dd2c  Sending Response msg 200/INVITE/cseq=103 
(tdta0x2a6568)
 13:41:44.438    tsx0x29b61c  Sending Response msg 200/INVITE/cseq=103 
(tdta0x2a6568) in state Trying
 13:41:44.439   pjsua_core.c  TX 792 bytes Response msg 200/INVITE/cseq=103 
(tdta0x2a6568) to UDP 192.168.32.123:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 
192.168.32.123:5060;rport=5060;received=192.168.32.123;branch=z9hG4bK0df46a26
Call-ID: 29ecf63c2f06075312d889df1cf8d4a4 at 192.168.32.123
From: "pc1" <sip:pc1@192.168.32.123>;tag=as016d7557
To: <sip:pc3 at 192.168.32.17>;tag=ySaCOXNMXtrrsnW9JyOCSg4OZg7VkYM1
CSeq: 103 INVITE
Contact: <sip:192.168.32.17:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, 
MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length:   254

v=0
o=- 3467709704 3467709706 IN IP4 192.168.32.17
s=pjmedia
c=IN IP4 192.168.32.17
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 0 101
a=rtcp:4001 IN IP4 192.168.32.17
a=rtpmap:0 PCMU/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

--end msg--
 13:41:44.440    tsx0x29b61c  State changed from Trying to Completed, 
event=TX_MSG
 13:41:44.441    dlg0x25dd2c  Transaction tsx0x29b61c state changed to 
Completed
 13:41:44.452    tsx0x29d56c  Timeout timer event
 13:41:44.454    tsx0x29d56c  State changed from Terminated to Destroyed, 
event=TIMER
 13:41:44.462   tdta0x29f348  Destroying txdata Response msg 
200/INVITE/cseq=102 (tdta0x29f348)
 13:41:44.465    tsx0x29d56c  Transaction destroyed!
 13:41:44.468 sip_endpoint.c  Processing incoming message: Request msg 
ACK/cseq=103 (rdata0x25958c)
 13:41:44.470   pjsua_core.c  RX 400 bytes Request msg ACK/cseq=103 
(rdata0x25958c) from UDP 192.168.32.123:5060:
ACK sip:192.168.32.17:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.32.123:5060;branch=z9hG4bK76df7f66;rport
From: "pc1" <sip:pc1@192.168.32.123>;tag=as016d7557
To: <sip:pc3 at 192.168.32.17:5060>;tag=ySaCOXNMXtrrsnW9JyOCSg4OZg7VkYM1
Contact: <sip:pc1 at 192.168.32.123>
Call-ID: 29ecf63c2f06075312d889df1cf8d4a4 at 192.168.32.123
CSeq: 103 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0


--end msg--
 13:41:44.472    dlg0x25dd2c  Received Request msg ACK/cseq=103 
(rdata0x25958c)
 13:41:44.475    tsx0x29b61c  Request to terminate transaction
 13:41:44.477    tsx0x29b61c  State changed from Completed to Terminated, 
event=USER
 13:41:44.479    dlg0x25dd2c  Transaction tsx0x29b61c state changed to 
Terminated
 13:41:44.484    tsx0x29b61c  Timeout timer event
 13:41:44.486    tsx0x29b61c  State changed from Terminated to Destroyed, 
event=TIMER
 13:41:44.492   tdta0x2a6568  Destroying txdata Response msg 
200/INVITE/cseq=103 (tdta0x2a6568)
 13:41:44.494    tsx0x29b61c  Transaction destroyed!
 13:41:44.503   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
 13:41:44.580    udp0x25b360  Remote RTP address switched to 
192.168.32.123:4000
 13:41:44.583    udp0x25b360  Remote RTCP address switched to 
192.168.32.123:4001
 13:41:44.662     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:44.665   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
 13:41:44.786     ec0x23e488  320 samples reduced, buf_cnt=2241
 13:41:44.823   strm0x2a86c4  jb updated(2), prefetch=10, size=33
 13:41:44.825   strm0x2a86c4  JB shrinking 1 frame(s), cur size=32
 13:41:44.875   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
 13:41:45.027     ec0x23e488  1 samples reduced, buf_cnt=2560
 13:41:45.108   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
 13:41:45.391     ec0x23e488  514 samples reduced, buf_cnt=2366
 13:41:45.503   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
 13:41:46.151     ec0x23e488  266 samples reduced, buf_cnt=2420
 13:41:46.413   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
 13:41:46.541     ec0x23e488  387 samples reduced, buf_cnt=2353
 13:41:46.893   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
 13:41:47.019     ec0x23e488  257 samples reduced, buf_cnt=2416
 13:41:47.052   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
 13:41:47.127     ec0x23e488  210 samples reduced, buf_cnt=2526
 13:41:47.203   Master/sound  Underflow, buf_cnt=0, will generate 1 frame


################################################
################################################

now i hear sound but is intermittent...
it's interesting because on 386 all works fine. But when i run sample 
application "streamutil" it works.
here is log:


################################################
################################################

[root at device samples]# ./streamutil 
 13:51:42.912 os_core_unix.c  pjlib 1.4.5 for POSIX initialized
 13:51:43.186       pa_dev.c  PortAudio sound library initialized, status=0
 13:51:43.188       pa_dev.c  PortAudio host api count=1                   
 13:51:43.189       pa_dev.c  Sound device count=2                         
 13:51:43.197          pjlib  select() I/O Queue created (0xfa214)         
 13:51:43.218   strm0x106144  VAD temporarily disabled                     
 13:51:43.220          rtp.c  pjmedia_rtp_session_init: ses=0x10ef54, 
default_pt=0, ssrc=0x515f007c
 13:51:43.223          rtp.c  pjmedia_rtp_session_init: ses=0x10f578, 
default_pt=0, ssrc=0x515f007c
 13:51:43.226       stream.c  Stream strm0x106144 created                                          
 13:51:43.237       pa_dev.c  Opened device 0: UCB1400:  (hw:0,0)(ALSA) for 
playing, sample rate=8000, ch=1, bits=16, 160 samples per frame, latency=100 
ms                                                                                                                           
 13:51:43.239       pa_dev.c  Starting UCB1400:  (hw:0,0) stream..                                                                         
 13:51:43.243       pa_dev.c  Done, status=0                                                                                               
 13:51:43.245   strm0x106144  Encoder stream paused                                                                                        
 13:51:43.247   strm0x106144  Decoder stream started                                                                                       
Stream is active, dir is recv-only, local port is 4000    
Command:  13:51:43.262       pa_dev.c  Player thread started
 13:51:43.267   strm0x106144  Jitter buffer empty (prefetch=0), plc invoked

################################################
################################################


I think something is wrong with open device or ??? i don't know... when i run 
streamutil in log is:

 13:51:43.237       pa_dev.c  Opened device 0: UCB1400:  (hw:0,0)(ALSA) for 
playing, sample rate=8000, ch=1, bits=16, 160 samples per frame, latency=100 
ms                                                                                                                           
 13:51:43.239       pa_dev.c  Starting UCB1400:  (hw:0,0) stream..                                                                         


but when i run pjsua in log is:

 13:14:06.733  pjsua_media.c  Opening sound device PCM at 16000/1/20ms
 13:14:06.753       pa_dev.c  Opened device UCB1400:  (hw:0,0)(ALSA)/UCB1400:  
(hw:0,0)(ALSA) for recording and playback, sample rate=16000, ch=1, bits=16, 
320 samples per frame, input latency=100 ms, output latency=140 ms
 13:14:06.756     ec0x25ea18  Creating AEC
 13:14:06.828     ec0x25ea18  AEC created, clock_rate=16000, channel=1, 
samples per frame=320, tail length=200 ms, latency=140 ms
 13:14:06.830       pa_dev.c  Starting UCB1400:  (hw:0,0) stream..
 13:14:06.835       pa_dev.c  Done, status=0


can me somebody help?

PS: simple_pjsua not work too...

thanks










[Index of Archives]     [Asterisk Users]     [Asterisk App Development]     [Linux ARM Kernel]     [Linux ARM]     [Linux Omap]     [Fedora ARM]     [IETF Annouce]     [Security]     [Bugtraq]     [Linux]     [Linux OMAP]     [Linux MIPS]     [Linux API]
  Powered by Linux