pjsua: no audio is heard

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

 



oh thanks 
i use this option but without option --playback-dev=1 :) it work now..
but i get lot of logs when run pjsua:

 15:32:50.407     ec0x257c50  Buffer size adjusted from 984 to 745 (eff_cnt=720)
 15:32:50.467     ec0x257c50  Buffer size adjusted from 905 to 666 (eff_cnt=720)

and when i calling i have lot of logs :

 15:33:52.130   Master/sound  Buffer size adjusted from 804 to 656 (eff_cnt=640)  
 15:33:52.521     ec0x257c50  Underflow, buf_cnt=84, will generate 1 frame        
 15:33:52.531   Master/sound  Buffer size adjusted from 816 to 658 (eff_cnt=640)  
 15:33:52.746     ec0x257c50  Underflow, buf_cnt=84, will generate 1 frame        
 15:33:52.841   Master/sound  Buffer size adjusted from 818 to 645 (eff_cnt=640)  

when i calling pc - pc i have nothing....

and for speex codec (speex/8000/1) it not works..i have intermittent sound 
still


thanks





> Hi
> you can try to add a option --clock-rate=8000 in pjsua command
>
>
> --
> Web: http://jackytw.blogspot.com
> GV: +1-408-512-3803
>
> On Fri, Nov 20, 2009 at 9:06 PM, Peter Lukac <p.lukac at emtest.sk> wrote:
> > 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;recei
> >ved=192.168.32.17;rport=5060 From:
> > <sip:pc3 at 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;recei
> >ved=192.168.32.17;rport=5060 From:
> > <sip:pc3 at 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;recei
> >ved=192.168.32.17;rport=5060 From:
> > <sip:pc3 at 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;recei
> >ved=192.168.32.17;rport=5060 From:
> > <sip:pc3 at 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=z9hG4bK1134
> >1831 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=z9hG4bK1134
> >1831 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=z9hG4bK3f61
> >f139 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;recei
> >ved=192.168.32.17;rport=5060 From:
> > <sip:pc3 at 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;recei
> >ved=192.168.32.17;rport=5060 From:
> > <sip:pc3 at 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;recei
> >ved=192.168.32.17;rport=5060 From:
> > <sip:pc3 at 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;recei
> >ved=192.168.32.17;rport=5060 From:
> > <sip:pc3 at 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=z9hG4bK6b3b
> >889f 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=z9hG4bK6b3b
> >889f 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=z9hG4bK0df4
> >6a26 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
> >
> >
> >
> >
> >
> >
> >
> >
> > _______________________________________________
> > Visit our blog: http://blog.pjsip.org
> >
> > pjsip mailing list
> > pjsip at lists.pjsip.org
> > http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org
>
> _______________________________________________
> Visit our blog: http://blog.pjsip.org
>
> pjsip mailing list
> pjsip at lists.pjsip.org
> http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org
>
> !DSPAM:153,4b0698f5194617730779061!




[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