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;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 > > > > > > > > > _______________________________________________ > 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 >
