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

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

 



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



 
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.pjsip.org/pipermail/pjsip_lists.pjsip.org/attachments/20101213/5c4ba7ad/attachment-0001.html>


[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