iOS4: no local-audio on incoming call - depending on provider

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

 



Problem solved:

Thanks to Klaus, I disabled the contact_rewrite by setting cfg.allow_contact_rewrite to 'NO' (0), which avoids the described problem.

If I (as a non-expert) interpret the situation correctly, it appears that on a contact_rewrite not all audio relevant connections/ports are updated. I am uncertain, whether this would be within the responsibility of the application and how the application would properly achieve this. (Maybe one of the experts can shed some more light on this ...?)

For all practical purposes, however, it seem that disabling the contact_rewrite all-together is a viable route to go, since many (if not all) 'SIP providers support NAT traversal for their clients (at least with UDP)' as Klaus points out.

Thanks again!

-Thomas


-------- Original-Nachricht --------
> Datum: Mon, 13 Dec 2010 00:38:46 +0100
> Von: Thomas Martin <tmemail at gmx.de>
> An: pjsip list <pjsip at lists.pjsip.org>
> Betreff: [pjsip] iOS4: no local-audio on incoming call - depending on	provider

> Hello Everybody,
> 
> still working on my iOS4 project, I am encountering the following problem:
> 
> Depending on the provider (e.g. sipgate.de) of the Called-SIP-Account,
> there is no sound to be heard on the local side, while the remote side can
> hear everything well (except for the usual(?) echo - however, that is not the
> current topic...). - There is one exception to this "rule": If the Caller
> uses a SIP-Account (for the outbound call), belonging to the same provider
> as the Called-Account (in this case sipgate.de). 
> 
> If the Called-SIP-Account is with a different provider (e.g. placetel.de)
> the audio is fine on both ends no matter what kind of outbound account or
> technology (PSTN, GSM) the caller uses.
> 
> The symptoms are reproducible in different WiFi environments and with 3G.
> 
> I checked many posts in this mailing list and followed the
> trouble-shooting guide for audio problems with no progress.
> 
> Attached, are two "level-5" console logs, the first one logs the call with
> NO local audio on the callee side, the second one logs a call with fine
> audio on both sides. Unfortunately, I can not identify any suspicious events
> (I just hung -up on the first call a little earlier than on the second
> one).
> 
> I hope, some of the more experienced colleges can make heads and tails
> from the logs and push me in the right direction.
> 
> Thank you very much in advance for your efforts!
> 
> -Thomas
> 
> LOG I:
> 
>  23:56:49.736    inv0xb10c64  SDP negotiation done, status=0
>  23:56:49.736   pjsua_call.c  Call 2: remote NAT type is 0 (Unknown)
>  23:56:49.736   strm0xb56774  VAD temporarily disabled
>  23:56:49.736          rtp.c  pjmedia_rtp_session_init: ses=0xb58008,
> default_pt=8, ssrc=0x5b5c5965
>  23:56:49.736          rtp.c  pjmedia_rtp_session_init: ses=0xb5862c,
> default_pt=8, ssrc=0x5b5c5965
>  23:56:49.737       stream.c  Stream strm0xb56774 created
>  23:56:49.737   strm0xb56774  Encoder stream started
>  23:56:49.737   strm0xb56774  Decoder stream started
>  23:56:49.737  pjsua_media.c  Media updates, stream #0: PCMA (sendrecv)
>  23:56:49.737  pjsua_media.c  Opening sound device PCM at 8000/1/20ms
>  23:56:49.801 coreaudio_dev.  core audio stream started
>  23:56:49.804   conference.c  Port 3 (sip:098765432100 at sipgate.de)
> transmitting to port 0 (iPhone IO device)
>  23:56:49.804   conference.c  Port 0 (iPhone IO device) transmitting to
> port 3 (sip:098765432100 at sipgate.de)
>  23:56:49.804    inv0xb10c64  Sending Response msg 200/INVITE/cseq=102
> (tdta0xb14400)
>  23:56:49.804    dlg0xb10c64  Sending Response msg 200/INVITE/cseq=102
> (tdta0xb14400)
>  23:56:49.804    tsx0xb11664  Sending Response msg 200/INVITE/cseq=102
> (tdta0xb14400) in state Proceeding
>  23:56:49.804   pjsua_core.c  TX 1163 bytes Response msg
> 200/INVITE/cseq=102 (tdta0xb14400) to UDP 217.10.79.9:5060:
> SIP/2.0 200 OK
> Via: SIP/2.0/UDP
> 217.10.79.9:5060;received=217.10.79.9;branch=z9hG4bK9bd3.b4cd1443.1
> Via: SIP/2.0/UDP 172.20.40.3;branch=z9hG4bK9bd3.b4cd1443.1
> Via: SIP/2.0/UDP
> 217.10.79.9:5060;received=217.10.68.222;branch=z9hG4bK571036c7
> Via: SIP/2.0/UDP 217.10.67.141:5060;rport=5060;branch=z9hG4bK571036c7
> Record-Route: <sip:217.10.79.9;lr;ftag=as34fc627f>
> Record-Route: <sip:172.20.40.3;lr>
> Record-Route: <sip:217.10.79.9;lr;ftag=as34fc627f>
> Call-ID: 2ac7e43461b8536d203bf0bf2fafbc3e at sipgate.de
> From: "098765432100" <sip:098765432100@xxxxxxxxxx>;tag=as34fc627f
> To: <sip:00497654321e10 at sipgate.de>;tag=.nTAXZM4d.e9HxODJnc9utnzx-6tl5M8
> CSeq: 102 INVITE
> Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER,
> MESSAGE, OPTIONS
> Contact: <sip:7654321e1 at 89.204.155.99:28926;transport=UDP;ob>
> Supported: replaces, 100rel, timer, norefersub
> Content-Type: application/sdp
> Content-Length:   236
> 
> v=0
> o=- 3501183403 3501183404 IN IP4 10.0.2.7
> s=pjmedia
> c=IN IP4 10.0.2.7
> t=0 0
> a=X-nat:0
> m=audio 4004 RTP/AVP 8 96
> a=rtcp:4005 IN IP4 10.0.2.7
> a=rtpmap:8 PCMA/8000
> a=sendrecv
> a=rtpmap:96 telephone-event/8000
> a=fmtp:96 0-15
> 
> --end msg--
>  23:56:49.824 coreaudio_dev.  Recorder thread started
>  23:56:49.824 os_core_unix.c  Info: possibly re-registering existing
> thread
>  23:56:49.824 coreaudio_dev.  Player thread started
>  23:56:49.824    tsx0xb11664  State changed from Proceeding to Completed,
> event=TX_MSG
>  23:56:49.834   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  23:56:49.834   strm0xb56774  Start talksprut..
>  23:56:49.834    dlg0xb10c64  Transaction tsx0xb11664 state changed to
> Completed
>  23:56:49.834   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
> 2010-12-12 23:56:49.836 SIP05[2863:307] 
> 
>  23:56:49.839 /Users/tmartin  
> 
> __callStateChanged: Call 2 state=CONNECTING - AccountID = 0
> 
> 
>  23:56:49.934 sip_endpoint.c  Processing incoming message: Request msg
> ACK/cseq=102 (rdata0xaf2814)
>  23:56:49.934   pjsua_core.c  RX 655 bytes Request msg ACK/cseq=102
> (rdata0xaf2814) from UDP 217.10.79.9:5060:
> ACK sip:7654321e1 at 89.204.155.99:28926;transport=UDP;ob SIP/2.0
> Via: SIP/2.0/UDP 217.10.79.9:5060;branch=z9hG4bK9bd3.b4cd1443.3
> Via: SIP/2.0/UDP 172.20.40.3;branch=z9hG4bK9bd3.b4cd1443.3
> Via: SIP/2.0/UDP
> 217.10.79.9:5060;received=217.10.68.222;branch=z9hG4bK5a026c96
> Via: SIP/2.0/UDP 217.10.67.141:5060;branch=z9hG4bK5a026c96;rport=5060
> From: "098765432100" <sip:098765432100@xxxxxxxxxx>;tag=as34fc627f
> To: <sip:00497654321e10 at sipgate.de>;tag=.nTAXZM4d.e9HxODJnc9utnzx-6tl5M8
> Contact: <sip:098765432100 at 217.10.67.141>
> Call-ID: 2ac7e43461b8536d203bf0bf2fafbc3e at sipgate.de
> CSeq: 102 ACK
> Max-Forwards: 67
> Content-Length: 0
> X-hint: rr-enforced
> 
> 
> --end msg--
>  23:56:49.934    dlg0xb10c64  Received Request msg ACK/cseq=102
> (rdata0xaf2814)
>  23:56:49.934    tsx0xb11664  Request to terminate transaction
>  23:56:49.934    tsx0xb11664  State changed from Completed to Terminated,
> event=USER
>  23:56:49.935    dlg0xb10c64  Transaction tsx0xb11664 state changed to
> Terminated
> 2010-12-12 23:56:49.935 SIP05[2863:7003] 
> 
>  23:56:49.936 /Users/tmartin  
> 
> __callStateChanged: Call 2 state=CONFIRMED - AccountID = 0
> 
> 
>  23:56:49.936    tsx0xb11664  Timeout timer event
>  23:56:49.936    tsx0xb11664  State changed from Terminated to Destroyed,
> event=TIMER
>  23:56:49.936   tdta0xb14400  Destroying txdata Response msg
> 200/INVITE/cseq=102 (tdta0xb14400)
>  23:56:49.936    tsx0xb11664  Transaction destroyed!
>  23:56:50.427   strm0xb56774  VAD re-enabled
>  23:56:50.428   strm0xb56774  Starting silence
>  23:56:50.543   silencedet.c  Starting talk burst (level=45 threshold=44)
>  23:56:50.543   strm0xb56774  Start talksprut..
>  23:56:51.356   silencedet.c  Starting silence (level=19 threshold=42)
>  23:56:51.356   strm0xb56774  Starting silence
>  23:56:52.546       endpoint  Request msg BYE/cseq=3981 (tdta0xb14400)
> created.
>  23:56:52.546    inv0xb10c64  Sending Request msg BYE/cseq=3981
> (tdta0xb14400)
>  23:56:52.546    dlg0xb10c64  Sending Request msg BYE/cseq=3981
> (tdta0xb14400)
>  23:56:52.546    tsx0xb11664  Transaction created for Request msg
> BYE/cseq=3980 (tdta0xb14400)
>  23:56:52.546    tsx0xb11664  Sending Request msg BYE/cseq=3980
> (tdta0xb14400) in state Null
>  23:56:52.547  sip_resolve.c  Target '217.10.79.9:0' type=Unspecified
> resolved to '217.10.79.9:5060' type=UDP (UDP transport)
>  23:56:52.547   pjsua_core.c  TX 527 bytes Request msg BYE/cseq=3980
> (tdta0xb14400) to UDP 217.10.79.9:5060:
> BYE sip:098765432100 at 217.10.67.141 SIP/2.0
> Via: SIP/2.0/UDP
> 89.204.155.99:28926;rport;branch=z9hG4bKPjEORttaJPoq5mu1m-TyX56EPAM80VOBE3
> Max-Forwards: 70
> From: <sip:00497654321e10@xxxxxxxxxx>;tag=.nTAXZM4d.e9HxODJnc9utnzx-6tl5M8
> To: "098765432100" <sip:098765432100 at sipgate.de>;tag=as34fc627f
> Call-ID: 2ac7e43461b8536d203bf0bf2fafbc3e at sipgate.de
> CSeq: 3980 BYE
> Route: <sip:217.10.79.9;lr;ftag=as34fc627f>
> Route: <sip:172.20.40.3;lr>
> Route: <sip:217.10.79.9;lr;ftag=as34fc627f>
> User-Agent: SIP01
> Content-Length:  0
> 
> 
> --end msg--
>  23:56:52.548    tsx0xb11664  State changed from Null to Calling,
> event=TX_MSG
>  23:56:52.548    dlg0xb10c64  Transaction tsx0xb11664 state changed to
> Calling
>  23:56:52.757 sip_endpoint.c  Processing incoming message: Response msg
> 200/BYE/cseq=3980 (rdata0xaf2814)
>  23:56:52.757   pjsua_core.c  RX 480 bytes Response msg 200/BYE/cseq=3980
> (rdata0xaf2814) from UDP 217.10.79.9:5060:
> SIP/2.0 200 OK
> Via: SIP/2.0/UDP
> 89.204.155.99:28926;rport=28926;branch=z9hG4bKPjEORttaJPoq5mu1m-TyX56EPAM80VOBE3
> From: <sip:00497654321e10@xxxxxxxxxx>;tag=.nTAXZM4d.e9HxODJnc9utnzx-6tl5M8
> To: "098765432100" <sip:098765432100 at sipgate.de>;tag=as34fc627f
> Call-ID: 2ac7e43461b8536d203bf0bf2fafbc3e at sipgate.de
> CSeq: 3980 BYE
> Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
> Supported: replaces
> Contact: <sip:098765432100 at 217.10.67.141>
> Content-Length: 0
> 
> 
> --end msg--
>  23:56:52.757    tsx0xb11664  Incoming Response msg 200/BYE/cseq=3980
> (rdata0xaf2814) in state Calling
>  23:56:52.757    tsx0xb11664  State changed from Calling to Completed,
> event=RX_MSG
>  23:56:52.757    dlg0xb10c64  Received Response msg 200/BYE/cseq=3980
> (rdata0xaf2814)
>  23:56:52.758    dlg0xb10c64  Transaction tsx0xb11664 state changed to
> Completed
> 2010-12-12 23:56:52.758 SIP05[2863:7003] 
> 
>  23:56:52.768 /Users/tmartin  
> 
> __callStateChanged: Call 2 state=DISCONNCTD - AccountID = 0
> 
>  23:56:52.774   strm0xb56774  JB summary:
>   size=0 prefetch=0 level=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=148
>  23:56:52.774  pjsua_media.c  Media session for call 2 is destroyed
>  23:56:52.774    dlg0xb10c64  Session count dec to 1 by mod-invite
>  23:56:53.768  pjsua_media.c  Closing sound device after idle for 1
> seconds
>  23:56:53.778  pjsua_media.c  Closing iPhone IO device sound playback
> device and iPhone IO device sound capture device
>  23:56:53.824 coreaudio_dev.  core audio stream stopped
>  23:56:53.849 coreaudio_dev.  core audio stream stopped
>  23:56:57.757    tsx0xb11664  Timeout timer event
>  23:56:57.757    tsx0xb11664  State changed from Completed to Terminated,
> event=TIMER
>  23:56:57.758    dlg0xb10c64  Transaction tsx0xb11664 state changed to
> Terminated
>  23:56:57.758    dlg0xb10c64  Dialog destroyed
>  23:56:57.760    tsx0xb11664  Timeout timer event
>  23:56:57.761    tsx0xb11664  State changed from Terminated to Destroyed,
> event=TIMER
>  23:56:57.761   tdta0xb14400  Destroying txdata Request msg BYE/cseq=3980
> (tdta0xb14400)
>  23:56:57.761    tsx0xb11664  Transaction destroyed!
>  23:57:02.504    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 0
> to 217.10.79.9:5060
>  23:57:02.513   tdta0xb40800  Destroying txdata raw
> 
> 
> LOG II:
> 
>  00:03:50.458    inv0xb10c64  SDP negotiation done, status=0
>  00:03:50.458   pjsua_call.c  Call 3: remote NAT type is 0 (Unknown)
>  00:03:50.469   strm0xb56774  VAD temporarily disabled
>  00:03:50.469          rtp.c  pjmedia_rtp_session_init: ses=0xb5799c,
> default_pt=98, ssrc=0x4f05a10a
>  00:03:50.469          rtp.c  pjmedia_rtp_session_init: ses=0xb57e10,
> default_pt=98, ssrc=0x4f05a10a
>  00:03:50.475       stream.c  Stream strm0xb56774 created
>  00:03:50.475   strm0xb56774  Encoder stream started
>  00:03:50.475   strm0xb56774  Decoder stream started
>  00:03:50.475     resample.c  resample created: high qualiy, small filter,
> in/out rate=16000/8000
>  00:03:50.475     resample.c  resample created: high qualiy, small filter,
> in/out rate=8000/16000
>  00:03:50.475  pjsua_media.c  Media updates, stream #0: speex (sendrecv)
>  00:03:50.475  pjsua_media.c  Opening sound device PCM at 8000/1/20ms
>  00:03:50.537 coreaudio_dev.  core audio stream started
>  00:03:50.539   conference.c  Port 3 (sip:7654321e0 at sipgate.de)
> transmitting to port 0 (iPhone IO device)
>  00:03:50.539   conference.c  Port 0 (iPhone IO device) transmitting to
> port 3 (sip:7654321e0 at sipgate.de)
>  00:03:50.539    inv0xb10c64  Sending Response msg 200/INVITE/cseq=27226
> (tdta0xb52c00)
>  00:03:50.540    dlg0xb10c64  Sending Response msg 200/INVITE/cseq=27226
> (tdta0xb52c00)
>  00:03:50.540    tsx0xb11664  Sending Response msg 200/INVITE/cseq=27226
> (tdta0xb52c00) in state Proceeding
>  00:03:50.540   pjsua_core.c  TX 1305 bytes Response msg
> 200/INVITE/cseq=27226 (tdta0xb52c00) to UDP 217.10.79.9:5060:
> SIP/2.0 200 OK
> Via: SIP/2.0/UDP
> 217.10.79.9:5060;received=217.10.79.9;branch=z9hG4bKc0b4.98902b73.1
> Via: SIP/2.0/UDP 172.20.40.3;branch=z9hG4bKc0b4.98902b73.1
> Via: SIP/2.0/UDP
> 217.10.79.9:5060;received=217.10.68.222;branch=z9hG4bKPj86PqbQoNMvf0E6QhJUBt6r8tbg2psw05
> Via: SIP/2.0/UDP
> 89.204.155.99:13581;rport=13581;branch=z9hG4bKPj86PqbQoNMvf0E6QhJUBt6r8tbg2psw05
> Record-Route: <sip:217.10.79.9;lr;ftag=8n-kcM01IWBRHxyHF962Z2YAsb4HbcNo>
> Record-Route: <sip:172.20.40.3;lr>
> Record-Route: <sip:217.10.79.9;lr;ftag=8n-kcM01IWBRHxyHF962Z2YAsb4HbcNo>
> Call-ID: Z39UrexKQW4nzSwsyn0V5W7B7D09SOIf
> From: <sip:7654321e0@xxxxxxxxxx>;tag=8n-kcM01IWBRHxyHF962Z2YAsb4HbcNo
> To:
> <sip:012345678900 at sipgate.de>;tag=B0CyGf9U3GEJfw9-EjZAkaDWnkZtjdgX;user=phone
> CSeq: 27226 INVITE
> Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER,
> MESSAGE, OPTIONS
> Contact: <sip:7654321e1 at 89.204.155.99:28926;transport=UDP;ob>
> Supported: replaces, 100rel, timer, norefersub
> Session-Expires: 1800;refresher=uac
> Content-Type: application/sdp
> Content-Length:   240
> 
> v=0
> o=- 3501183824 3501183825 IN IP4 10.0.2.7
> s=pjmedia
> c=IN IP4 10.0.2.7
> t=0 0
> a=X-nat:0
> m=audio 4006 RTP/AVP 98 96
> a=rtcp:4007 IN IP4 10.0.2.7
> a=rtpmap:98 speex/16000
> a=sendrecv
> a=rtpmap:96 telephone-event/8000
> a=fmtp:96 0-15
> 
> --end msg--
>  00:03:50.559 coreaudio_dev.  Recorder thread started
>  00:03:50.568    tsx0xb11664  State changed from Proceeding to Completed,
> event=TX_MSG
>  00:03:50.568 os_core_unix.c  Info: possibly re-registering existing
> thread
>  00:03:50.569 coreaudio_dev.  Player thread started
>  00:03:50.569   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:50.571   strm0xb56774  Start talksprut..
>  00:03:50.571    dlg0xb10c64  Transaction tsx0xb11664 state changed to
> Completed
>  00:03:50.571   Master/sound  Underflow, buf_cnt=0, will generate 1 frame
> 2010-12-13 00:03:50.577 SIP05[2863:307] 
> 
>  00:03:50.578 /Users/tmartin  
> 
> __callStateChanged: Call 3 state=CONNECTING - AccountID = 0
> 
> 
>  00:03:50.924    udp0xafc200  Remote RTCP address switched to
> 10.0.2.3:4005
>  00:03:50.940 sip_endpoint.c  Processing incoming message: Request msg
> ACK/cseq=27226 (rdata0xaf2814)
>  00:03:50.940   pjsua_core.c  RX 666 bytes Request msg ACK/cseq=27226
> (rdata0xaf2814) from UDP 217.10.79.9:5060:
> ACK sip:7654321e1 at 89.204.155.99:28926;transport=UDP;ob SIP/2.0
> Via: SIP/2.0/UDP 217.10.79.9:5060;branch=z9hG4bKc0b4.98902b73.3
> Via: SIP/2.0/UDP 172.20.40.3;branch=z9hG4bKc0b4.98902b73.3
> Via: SIP/2.0/UDP
> 217.10.79.9:5060;received=217.10.68.222;branch=z9hG4bKPjOi.H61.YBC6VCMgkAIjl6PGK-rdWnQoL
> Via: SIP/2.0/UDP
> 89.204.155.99:13581;rport=13581;branch=z9hG4bKPjOi.H61.YBC6VCMgkAIjl6PGK-rdWnQoL
> Max-Forwards: 67
> From: sip:7654321e0@xxxxxxxxxx;tag=8n-kcM01IWBRHxyHF962Z2YAsb4HbcNo
> To:
> sip:012345678900 at sipgate.de;user=phone;tag=B0CyGf9U3GEJfw9-EjZAkaDWnkZtjdgX
> Call-ID: Z39UrexKQW4nzSwsyn0V5W7B7D09SOIf
> CSeq: 27226 ACK
> Content-Length:  0
> X-hint: rr-enforced
> 
> 
> --end msg--
>  00:03:50.940    dlg0xb10c64  Received Request msg ACK/cseq=27226
> (rdata0xaf2814)
>  00:03:50.940    tsx0xb11664  Request to terminate transaction
>  00:03:50.940    tsx0xb11664  State changed from Completed to Terminated,
> event=USER
>  00:03:50.941    dlg0xb10c64  Transaction tsx0xb11664 state changed to
> Terminated
> 2010-12-13 00:03:50.941 SIP05[2863:7003] 
> 
>  00:03:50.943 /Users/tmartin  
> 
> __callStateChanged: Call 3 state=CONFIRMED - AccountID = 0
> 
> 
>  00:03:50.943    tsx0xb11664  Timeout timer event
>  00:03:50.943    tsx0xb11664  State changed from Terminated to Destroyed,
> event=TIMER
>  00:03:50.943   tdta0xb52c00  Destroying txdata Response msg
> 200/INVITE/cseq=27226 (tdta0xb52c00)
>  00:03:50.943    tsx0xb11664  Transaction destroyed!
>  00:03:50.943   strm0xb56774  RTP status: badpt=0, badssrc=0, dup=0,
> outorder=0, probation=-1, restart=0
>  00:03:50.954   strm0xb56774  Jitter buffer starts returning normal frames
> (after 20 empty/lost)
>  00:03:51.070   strm0xb56774  jb updated(2), lvl=1 pre=0, size=0
>  00:03:51.116   strm0xb56774  jb updated(2), lvl=2 pre=0, size=1
>  00:03:51.164   strm0xb56774  VAD re-enabled
>  00:03:51.279   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:51.302   strm0xb56774  Jitter buffer starts returning normal frames
> (after 2 empty/lost)
>  00:03:51.721   strm0xb56774  Starting silence
>  00:03:51.766   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:51.790   strm0xb56774  Jitter buffer starts returning normal frames
> (after 1 empty/lost)
>  00:03:51.813   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:51.952   strm0xb56774  jb updated(2), lvl=4 pre=0, size=3
>  00:03:51.953   strm0xb56774  Jitter buffer starts returning normal frames
> (after 7 empty/lost)
>  00:03:52.022   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:52.069   strm0xb56774  Jitter buffer starts returning normal frames
> (after 2 empty/lost)
>  00:03:52.092   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:52.095   strm0xb56774  Start talksprut..
>  00:03:52.140   strm0xb56774  Starting silence
>  00:03:52.280   strm0xb56774  Start talksprut..
>  00:03:52.302   strm0xb56774  Starting silence
>  00:03:52.349   strm0xb56774  Start talksprut..
>  00:03:52.371   strm0xb56774  Jitter buffer starts returning normal frames
> (after 14 empty/lost)
>  00:03:52.419   strm0xb56774  Starting silence
>  00:03:52.419   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:52.440   strm0xb56774  Jitter buffer starts returning normal frames
> (after 1 empty/lost)
>  00:03:52.489   strm0xb56774  Start talksprut..
>  00:03:52.512   strm0xb56774  Starting silence
>  00:03:52.536   strm0xb56774  Start talksprut..
>  00:03:52.558   strm0xb56774  Starting silence
>  00:03:52.719   strm0xb56774  Frame lost, recovered!
>  00:03:52.721   strm0xb56774  Jitter buffer starts returning normal frames
> (after 1 empty/lost)
>  00:03:52.745   strm0xb56774  Start talksprut..
>  00:03:52.765   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:52.835   strm0xb56774  Jitter buffer starts returning normal frames
> (after 3 empty/lost)
>  00:03:52.864   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:52.882   strm0xb56774  Jitter buffer starts returning normal frames
> (after 1 empty/lost)
>  00:03:52.904   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:52.928   strm0xb56774  Jitter buffer starts returning normal frames
> (after 1 empty/lost)
>  00:03:52.951   strm0xb56774  jb updated(1), lvl=3 pre=0, size=1
>  00:03:53.142   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:53.160   strm0xb56774  Jitter buffer starts returning normal frames
> (after 1 empty/lost)
>  00:03:53.797 sip_endpoint.c  Processing incoming message: Request msg
> BYE/cseq=27227 (rdata0xaf2814)
>  00:03:53.797   pjsua_core.c  RX 685 bytes Request msg BYE/cseq=27227
> (rdata0xaf2814) from UDP 217.10.79.9:5060:
> BYE sip:7654321e1 at 89.204.155.99:28926;transport=UDP;ob SIP/2.0
> Via: SIP/2.0/UDP 217.10.79.9:5060;branch=z9hG4bKd0b4.d024a612.0
> Via: SIP/2.0/UDP 172.20.40.3;branch=z9hG4bKd0b4.d024a612.0
> Via: SIP/2.0/UDP
> 217.10.79.9:5060;received=217.10.68.222;branch=z9hG4bKPjuyG.sZmhxsS3al3xVGoCXJrprTENXoAU
> Via: SIP/2.0/UDP
> 89.204.155.99:13581;rport=13581;branch=z9hG4bKPjuyG.sZmhxsS3al3xVGoCXJrprTENXoAU
> Max-Forwards: 67
> From: sip:7654321e0@xxxxxxxxxx;tag=8n-kcM01IWBRHxyHF962Z2YAsb4HbcNo
> To:
> sip:012345678900 at sipgate.de;user=phone;tag=B0CyGf9U3GEJfw9-EjZAkaDWnkZtjdgX
> Call-ID: Z39UrexKQW4nzSwsyn0V5W7B7D09SOIf
> CSeq: 27227 BYE
> User-Agent: SIP01
> Content-Length:  0
> X-hint: rr-enforced
> 
> 
> --end msg--
>  00:03:53.797    dlg0xb10c64  Received Request msg BYE/cseq=27227
> (rdata0xaf2814)
>  00:03:53.797    tsx0xb11664  Transaction created for Request msg
> BYE/cseq=27227 (rdata0xaf2814)
>  00:03:53.797    tsx0xb11664  Incoming Request msg BYE/cseq=27227
> (rdata0xaf2814) in state Null
>  00:03:53.797    tsx0xb11664  State changed from Null to Trying,
> event=RX_MSG
>  00:03:53.797    dlg0xb10c64  Transaction tsx0xb11664 state changed to
> Trying
>  00:03:53.797       endpoint  Response msg 200/BYE/cseq=27227
> (tdta0xb52c00) created
>  00:03:53.797    dlg0xb10c64  Sending Response msg 200/BYE/cseq=27227
> (tdta0xb52c00)
>  00:03:53.797    tsx0xb11664  Sending Response msg 200/BYE/cseq=27227
> (tdta0xb52c00) in state Trying
>  00:03:53.798  sip_resolve.c  Target '217.10.79.9:5060' type=UDP resolved
> to '217.10.79.9:5060' type=UDP (UDP transport)
>  00:03:53.798   pjsua_core.c  TX 604 bytes Response msg 200/BYE/cseq=27227
> (tdta0xb52c00) to UDP 217.10.79.9:5060:
> SIP/2.0 200 OK
> Via: SIP/2.0/UDP
> 217.10.79.9:5060;received=217.10.79.9;branch=z9hG4bKd0b4.d024a612.0
> Via: SIP/2.0/UDP 172.20.40.3;branch=z9hG4bKd0b4.d024a612.0
> Via: SIP/2.0/UDP
> 217.10.79.9:5060;received=217.10.68.222;branch=z9hG4bKPjuyG.sZmhxsS3al3xVGoCXJrprTENXoAU
> Via: SIP/2.0/UDP
> 89.204.155.99:13581;rport=13581;branch=z9hG4bKPjuyG.sZmhxsS3al3xVGoCXJrprTENXoAU
> Call-ID: Z39UrexKQW4nzSwsyn0V5W7B7D09SOIf
> From: <sip:7654321e0@xxxxxxxxxx>;tag=8n-kcM01IWBRHxyHF962Z2YAsb4HbcNo
> To:
> <sip:012345678900 at sipgate.de>;tag=B0CyGf9U3GEJfw9-EjZAkaDWnkZtjdgX;user=phone
> CSeq: 27227 BYE
> Content-Length:  0
> 
> 
> --end msg--
>  00:03:53.804    tsx0xb11664  State changed from Trying to Completed,
> event=TX_MSG
>  00:03:53.804    dlg0xb10c64  Transaction tsx0xb11664 state changed to
> Completed
> 2010-12-13 00:03:53.804 SIP05[2863:7003] 
>  00:03:53.810   strm0xb56774  Jitter buffer empty (prefetch=0), plc
> invoked
>  00:03:53.816 /Users/tmartin  
> 
> __callStateChanged: Call 3 state=DISCONNCTD - AccountID = 0
> 
> 
>  00:03:53.817   strm0xb56774  JB summary:
>   size=0 prefetch=0 level=3
>   delay (min/max/avg/dev)=20/80/42/18 ms
>   burst (min/max/avg/dev)=1/4/1/0 frames
>   lost=1 discard=0 empty=54
>  00:03:53.817  pjsua_media.c  Media session for call 3 is destroyed
>  00:03:53.817    dlg0xb10c64  Session count dec to 3 by mod-invite
>  00:03:54.817  pjsua_media.c  Closing sound device after idle for 1
> seconds
>  00:03:54.823  pjsua_media.c  Closing iPhone IO device sound playback
> device and iPhone IO device sound capture device
>  00:03:54.863 coreaudio_dev.  core audio stream stopped
>  00:03:54.886 coreaudio_dev.  core audio stream stopped
>  00:03:57.988    pjsua_acc.c  Sending 2 bytes keep-alive packet for acc 0
> to 217.10.79.9:5060
>  00:03:57.992   tdta0xb44400  Destroying txdata raw
> 
> 
> 
>  

-- 
GRATIS! Movie-FLAT mit ?ber 300 Videos. 
Jetzt freischalten unter http://portal.gmx.net/de/go/maxdome



[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