search

NG-Studio-Development / csipsimple

Automatically exported from code.google.com/p/csipsimple
0 stars 0 forks source link

Error "408 / Request Timeout" calling 9196 for testing. #2878

Closed GoogleCodeExporter closed 9 years ago

GoogleCodeExporter commented 9 years ago 
What steps will reproduce the problem?
1. I'n trying to make test call (9196). The service is registered ok with 
"ostel.co".

What is the expected output? What do you see instead?
I'm having a "408 / Request Timeout" message.

What version of the product are you using? On what device / operating
system?
I've installed trunk version 
(http://nightlies.csipsimple.com/stable/CSipSimple-latest-trunk.apk). My phone 
is Samsung Galaxy S3. I've Cyanogenmod 11 (cm-11-20150118-NIGHTLY).

Please provide any additional information below.
I've Orbot but this application isn't selected to be used with it.
I've AFWall+ but this application is enabled to be 
I've XPrivacy but it is enabled all permissions. I see "use" of this 
application and there isn't anything blocked.

Original issue reported on code.google.com by a...@orencio.org on 2 Feb 2015 at 2:38

GoogleCodeExporter commented 9 years ago 
--------- beginning of /dev/log/main
W/XPrivacy(15866): Get client 10137/SERIAL(null;null) identification=restricted 
4819 ms
D/ActivityThread(15866): handleBindApplication:com.csipsimple
D/ActivityThread(15866): setTargetHeapUtilization:0.75
D/ActivityThread(15866): setTargetHeapMinFree:2097152
I/dalvikvm(15866): DexOpt: illegal method access (call 
Landroid/content/res/TypedArray;.<init> (Landroid/content/res/Resources;[I[II)V 
from Landroid/content/res/XResources$XTypedArray;)
I/dalvikvm(15866): Could not find method android.content.res.TypedArray.<init>, 
referenced from method android.content.res.XResources$XTypedArray.<init>
W/dalvikvm(15866): VFY: unable to resolve direct method 82: 
Landroid/content/res/TypedArray;.<init> (Landroid/content/res/Resources;[I[II)V
D/dalvikvm(15866): VFY: replacing opcode 0x70 at 0x0002
D/ActivityThread(15884): handleBindApplication:com.csipsimple:sipStack
D/ActivityThread(15884): setTargetHeapUtilization:0.75
D/ActivityThread(15884): setTargetHeapMinFree:2097152
E/SIP SRV (15884): RESET SETTINGS !!!!
D/libEGL  (15866): loaded /system/lib/egl/libEGL_mali.so
D/libEGL  (15866): loaded /system/lib/egl/libGLESv1_CM_mali.so
D/libEGL  (15866): loaded /system/lib/egl/libGLESv2_mali.so
E/        (15866): Device driver API match
E/        (15866): Device driver API version: 23
E/        (15866): User space API version: 23 
E/        (15866): mali: REVISION=Linux-r3p2-01rel3 BUILD_DATE=Fri Mar 21 
13:52:50 KST 2014 
D/OpenGLRenderer(15866): Enabling debug mode 0
I/Choreographer(15866): Skipped 42 frames!  The application may be doing too 
much work on its main thread.
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@4212c320 
time:22983312
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@4212c320 
time:22983313
W/XPrivacy/XRuntime(15866): Get client 10137/exec(getprop init.svc.racoon;null) 
shell=!restricted 2829 ms
W/XPrivacy/XRuntime(15866): exec 2831 ms
W/XPrivacy/XNetworkInfo(15884): Get client 
10137/NetworkInfo.isConnected(null;null) internet=!restricted 3044 ms
W/XPrivacy/XNetworkInfo(15884): isConnected 3047 ms
I/dalvikvm(15884): DexOpt: illegal method access (call 
Landroid/content/res/TypedArray;.<init> (Landroid/content/res/Resources;[I[II)V 
from Landroid/content/res/XResources$XTypedArray;)
I/dalvikvm(15884): Could not find method android.content.res.TypedArray.<init>, 
referenced from method android.content.res.XResources$XTypedArray.<init>
W/dalvikvm(15884): VFY: unable to resolve direct method 82: 
Landroid/content/res/TypedArray;.<init> (Landroid/content/res/Resources;[I[II)V
D/dalvikvm(15884): VFY: replacing opcode 0x70 at 0x0002
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:22995041
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41e57b60 
time:22995784
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f0bca8 
time:22995788
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23000378
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41e6f5f0 
time:23001102
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23004496
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f913b8 
time:23005256
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41e57b60 
time:23005257
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): getExtractedText on inactive InputConnection
W/IInputConnectionWrapper(15866): getTextBeforeCursor on inactive 
InputConnection
W/IInputConnectionWrapper(15866): getTextAfterCursor on inactive InputConnection
W/IInputConnectionWrapper(15866): getSelectedText on inactive InputConnection
W/IInputConnectionWrapper(15866): getExtractedText on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): getExtractedText on inactive InputConnection
W/IInputConnectionWrapper(15866): getTextBeforeCursor on inactive 
InputConnection
W/IInputConnectionWrapper(15866): getTextAfterCursor on inactive InputConnection
W/IInputConnectionWrapper(15866): getSelectedText on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41e57b60 
time:23035617
E/        (15866): Device driver API match
E/        (15866): Device driver API version: 23
E/        (15866): User space API version: 23 
E/        (15866): mali: REVISION=Linux-r3p2-01rel3 BUILD_DATE=Fri Mar 21 
13:52:50 KST 2014 
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41e57b60 
time:23083249
D/        (15866): Checked 1 et 1
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23097410
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41e249d8 
time:23098142
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): getExtractedText on inactive InputConnection
W/IInputConnectionWrapper(15866): getTextBeforeCursor on inactive 
InputConnection
W/IInputConnectionWrapper(15866): getTextAfterCursor on inactive InputConnection
W/IInputConnectionWrapper(15866): getSelectedText on inactive InputConnection
W/IInputConnectionWrapper(15866): getExtractedText on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): beginBatchEdit on inactive InputConnection
W/IInputConnectionWrapper(15866): endBatchEdit on inactive InputConnection
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41e57b60 
time:23116953
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41e57b60 
time:23156360
W/InputEventReceiver(15866): Attempted to finish an input event but the input 
event receiver has already been disposed.
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f0bca8 
time:23164936
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23166738
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42282fa0 
time:23167194
D/        (15866): Checked 1 et 1
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23168642
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@4211fe78 
time:23169305
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@4211fe78 
time:23220001
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42282fa0 
time:23232225
W/InputEventReceiver(15866): Attempted to finish an input event but the input 
event receiver has already been disposed.
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f0bca8 
time:23235669
E/        (15866): Device driver API match
E/        (15866): Device driver API version: 23
E/        (15866): User space API version: 23 
E/        (15866): mali: REVISION=Linux-r3p2-01rel3 BUILD_DATE=Fri Mar 21 
13:52:50 KST 2014 
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f0bca8 
time:23310023
W/InputEventReceiver(15866): Attempted to finish an input event but the input 
event receiver has already been disposed.
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23314647
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@4242c1b8 
time:23315170
W/InputEventReceiver(15866): Attempted to finish an input event but the input 
event receiver has already been disposed.
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f0bca8 
time:23319176
I/Choreographer(15866): Skipped 34 frames!  The application may be doing too 
much work on its main thread.
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@4247c240 
time:23323160
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@421a7088 
time:23325701
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@4247c240 
time:23329841
D/dalvikvm(15884): Trying to load lib 
/data/app-lib/com.csipsimple-1/libstlport_shared.so 0x41f0f1f0
D/dalvikvm(15884): Added shared lib 
/data/app-lib/com.csipsimple-1/libstlport_shared.so 0x41f0f1f0
D/dalvikvm(15884): No JNI_OnLoad found in 
/data/app-lib/com.csipsimple-1/libstlport_shared.so 0x41f0f1f0, skipping init
D/dalvikvm(15884): Trying to load lib 
/data/app-lib/com.csipsimple-1/libpjsipjni.so 0x41f0f1f0
D/dalvikvm(15884): Added shared lib 
/data/app-lib/com.csipsimple-1/libpjsipjni.so 0x41f0f1f0
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f0bca8 
time:23331434
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23332494
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f3c348 
time:23332921
D/        (15866): Accounts status.onChange( false)
E/libpjsip(15884): 14:55:29.371    pjsua_acc.c  ..Unable to create/send 
REGISTER: Object is busy (PJSIP_EBUSY) [status=171001]
D/        (15866): Accounts status.onChange( false)
D/        (15866): Accounts status.onChange( false)
D/        (15866): Accounts status.onChange( false)
E/libpjsip(15884): 14:55:36.546    pjsua_acc.c !..Unable to create/send 
REGISTER: Object is busy (PJSIP_EBUSY) [status=171001]
D/        (15866): Accounts status.onChange( false)
D/        (15866): Accounts status.onChange( false)
D/        (15866): Accounts status.onChange( false)
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f0bca8 
time:23357019
W/AudioTrack(15884): AUDIO_OUTPUT_FLAG_FAST denied by client due to mismatching 
sample rate (16000 vs 44100)
W/AudioRecord(15884): AUDIO_INPUT_FLAG_FAST denied by client
W/dalvikvm(15884): method 
Lcom/actionbarsherlock/internal/widget/IcsLinearLayout;.drawDividersHorizontal 
incorrectly overrides package-private method with same name in 
Landroid/widget/LinearLayout;
W/dalvikvm(15884): method 
Lcom/actionbarsherlock/internal/widget/IcsLinearLayout;.drawDividersVertical 
incorrectly overrides package-private method with same name in 
Landroid/widget/LinearLayout;
W/dalvikvm(15884): method 
Lcom/actionbarsherlock/internal/widget/IcsLinearLayout;.drawHorizontalDivider 
incorrectly overrides package-private method with same name in 
Landroid/widget/LinearLayout;
W/dalvikvm(15884): method 
Lcom/actionbarsherlock/internal/widget/IcsLinearLayout;.drawVerticalDivider 
incorrectly overrides package-private method with same name in 
Landroid/widget/LinearLayout;
W/XPrivacy/XSensorManager(15884): Get client 10137/acceleration(null;null) 
sensors=!restricted 1819 ms
W/XPrivacy/XSensorManager(15884): getDefaultSensor 1825 ms
D/libEGL  (15884): loaded /system/lib/egl/libEGL_mali.so
D/libEGL  (15884): loaded /system/lib/egl/libGLESv1_CM_mali.so
D/libEGL  (15884): loaded /system/lib/egl/libGLESv2_mali.so
E/        (15884): Device driver API match
E/        (15884): Device driver API version: 23
E/        (15884): User space API version: 23 
E/        (15884): mali: REVISION=Linux-r3p2-01rel3 BUILD_DATE=Fri Mar 21 
13:52:50 KST 2014 
D/OpenGLRenderer(15884): Enabling debug mode 0
I/Timeline(15884): Timeline: Activity_idle id: android.os.BinderProxy@41ea15d8 
time:23371682
W/AudioTrack(15884): AUDIO_OUTPUT_FLAG_FAST denied by client due to mismatching 
sample rate (16000 vs 44100)
W/AudioRecord(15884): AUDIO_INPUT_FLAG_FAST denied by client
D/dalvikvm(15866): null clazz in OP_INSTANCE_OF, single-stepping
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f0bca8 
time:23404105
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f0bca8 
time:23438157
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23440072
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f913b8 
time:23440994
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f913b8 
time:23472196
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f0bca8 
time:23475953
W/IInputConnectionWrapper(15866): showStatusIcon on inactive InputConnection
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42506e00 
time:23533851
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23534225
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42131c78 
time:23534813
D/        (15866): Accounts status.onChange( false)
I/dalvikvm(15866): Jit: resizing JitTable from 4096 to 8192
D/        (15866): Accounts status.onChange( false)
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42506e00 
time:23546540
W/AudioTrack(15884): AUDIO_OUTPUT_FLAG_FAST denied by client due to mismatching 
sample rate (16000 vs 44100)
W/AudioRecord(15884): AUDIO_INPUT_FLAG_FAST denied by client
I/Timeline(15884): Timeline: Activity_idle id: android.os.BinderProxy@41e0fe98 
time:23551995
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42506e00 
time:23586931
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42506e00 
time:23623983
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f18870 
time:23627346
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23628841
W/Resources(15866): Converting to string: TypedValue{t=0x10/d=0x0 a=-1}
W/Resources(15866): Converting to string: TypedValue{t=0x10/d=0x0 a=-1}
W/Resources(15866): Converting to string: TypedValue{t=0x10/d=0x0 a=-1}
W/Resources(15866): Converting to string: TypedValue{t=0x10/d=0x0 a=-1}
W/Resources(15866): Converting to string: TypedValue{t=0x10/d=0xfa0 a=-1}
W/Resources(15866): Converting to string: TypedValue{t=0x10/d=0xfa0 a=-1}
W/Resources(15866): Converting to string: TypedValue{t=0x10/d=0x0 a=-1}
W/Resources(15866): Converting to string: TypedValue{t=0x10/d=0x1a a=-1}
W/Resources(15866): Converting to string: TypedValue{t=0x10/d=0x1a a=-1}
W/Resources(15866): Converting to string: TypedValue{t=0x10/d=0x0 a=-1}
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@4236fb50 
time:23629660
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41f18870 
time:23636748
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42506e00 
time:23638137
I/Timeline(15866): Timeline: Activity_launch_request id:com.csipsimple 
time:23639189
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@41eaf938 
time:23639630
D/        (15866): Accounts status.onChange( false)
D/        (15866): Accounts status.onChange( false)
D/        (15866): Accounts status.onChange( false)
D/        (15866): Accounts status.onChange( false)
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42506e00 
time:23650546
W/AudioTrack(15884): AUDIO_OUTPUT_FLAG_FAST denied by client due to mismatching 
sample rate (16000 vs 44100)
W/AudioRecord(15884): AUDIO_INPUT_FLAG_FAST denied by client
I/Timeline(15884): Timeline: Activity_idle id: android.os.BinderProxy@41e28e50 
time:23657095
W/AudioTrack(15884): AUDIO_OUTPUT_FLAG_FAST denied by client due to mismatching 
sample rate (16000 vs 44100)
W/AudioRecord(15884): AUDIO_INPUT_FLAG_FAST denied by client
E/        (15866): Device driver API match
E/        (15866): Device driver API version: 23
E/        (15866): User space API version: 23 
E/        (15866): mali: REVISION=Linux-r3p2-01rel3 BUILD_DATE=Fri Mar 21 
13:52:50 KST 2014 
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42506e00 
time:23697250
D/AccountChooserButton(15866): Accounts status.onChange( false)
D/PjService(15884): Starting sip stack
I/PjService(15884): Created 0
D/PjService(15884): create ua receiver
D/SIP UA Receiver(15884): Create async worker !!!
D/BT8     (15884): Register BT media receiver
D/BT8     (15884): >>> BT SCO state changed !!! 
D/BT8     (15884): BT SCO state changed : 0 target is false
D/PjService(15884): Attach is done to callback
D/PjService(15884): Use OpenSL-ES implementation
D/libpjsip(15884): 15:01:35.054 sip_endpoint.c !.Module "mod-pjsua-log" 
registered
D/libpjsip(15884): 15:01:35.054 sip_endpoint.c  .Module "mod-tsx-layer" 
registered
D/libpjsip(15884): 15:01:35.054 sip_endpoint.c  .Module "mod-stateful-util" 
registered
D/libpjsip(15884): 15:01:35.054 sip_endpoint.c  .Module "mod-ua" registered
D/libpjsip(15884): 15:01:35.055 sip_endpoint.c  .Module "mod-100rel" registered
D/libpjsip(15884): 15:01:35.055 sip_endpoint.c  .Module "mod-pjsua" registered
D/libpjsip(15884): 15:01:35.055 sip_endpoint.c  .Module "mod-invite" registered
D/libpjsip(15884): 15:01:35.062          pjlib  ..select() I/O Queue created 
(0x5f4ff9cc)
D/libpjsip(15884): 15:01:35.063 webrtc_codec.c  ..List of supported codec.
D/libpjsip(15884): 15:01:35.063 webrtc_codec.c  ..0 ISAC 103 16000 480 32000
D/libpjsip(15884): 15:01:35.063 webrtc_codec.c  ..5 ILBC 102 8000 240 13300
D/libpjsip(15884): 15:01:35.063         silk.c  ..SILK codec version 1.0.9 
initialized
D/libpjsip(15884): 15:01:35.064 sip_endpoint.c  ..Module 
"mod-opus-sdp-rewriter" registered
D/libpjsip(15884): 15:01:35.073    pjsua_vid.c  ..Initializing video subsystem..
D/libpjsip(15884): 15:01:35.073 sip_endpoint.c  .Module "mod-evsub" registered
D/libpjsip(15884): 15:01:35.073 sip_endpoint.c  .Module "mod-presence" 
registered
D/libpjsip(15884): 15:01:35.073 sip_endpoint.c  .Module "mod-mwi" registered
D/libpjsip(15884): 15:01:35.073 sip_endpoint.c  .Module "mod-refer" registered
D/libpjsip(15884): 15:01:35.073 sip_endpoint.c  .Module "mod-pjsua-pres" 
registered
D/libpjsip(15884): 15:01:35.073 sip_endpoint.c  .Module "mod-pjsua-im" 
registered
D/libpjsip(15884): 15:01:35.073 sip_endpoint.c  .Module "mod-pjsua-options" 
registered
D/libpjsip(15884): 15:01:35.073   pjsua_core.c  .1 SIP worker threads created
I/libpjsip(15884): 15:01:35.073   pjsua_core.c  .pjsua version 2.2.1-svn for  
initialized
D/libpjsip(15884): 15:01:35.073   pjsua_core.c  .PJSUA state changed: CREATED 
--> INIT
D/libpjsip(15884): 15:01:35.073   opensl_dev.c  OpenSL sound library initialized
D/libpjsip(15884): 15:01:35.073 pjsua_jni_addo  Loaded audio dev
D/libpjsip(15884): 15:01:35.201   pjsua_core.c  SIP UDP socket reachable at 
88.30.149.166:44783
D/libpjsip(15884): 15:01:35.201  udp0x5eed2288  SIP UDP transport started, 
published address is 88.30.149.166:44783
D/libpjsip(15884): 15:01:35.202    pjsua_acc.c  Adding account: 
id=<sip:88.30.149.166:44783>
D/libpjsip(15884): 15:01:35.202    pjsua_acc.c  .Account 
<sip:88.30.149.166:44783> added with id 0
D/libpjsip(15884): 15:01:35.332   tcplis:42082  SIP TCP listener ready for 
incoming connections at 88.30.149.166:42082
D/libpjsip(15884): 15:01:35.332    pjsua_acc.c  Adding account: 
id=<sip:88.30.149.166:42082;transport=TCP>
D/libpjsip(15884): 15:01:35.332    pjsua_acc.c  .Account 
<sip:88.30.149.166:42082;transport=TCP> added with id 1
D/libpjsip(15884): 15:01:35.504   tlslis:34761  SIP TLS listener is ready for 
incoming connections at 88.30.149.166:34761
D/libpjsip(15884): 15:01:35.504    pjsua_acc.c  Adding account: 
id=<sip:88.30.149.166:34761;transport=TLS>
D/libpjsip(15884): 15:01:35.504    pjsua_acc.c  .Account 
<sip:88.30.149.166:34761;transport=TLS> added with id 2
D/libpjsip(15884): 15:01:35.509 sip_endpoint.c  Module "mod-earlylock" 
registered
D/libpjsip(15884): 15:01:35.510 sip_endpoint.c  Module "mod-reg-tracker" 
registered
D/RegHandlerModule(15884): Reg handler module added with status 0
D/libpjsip(15884): 15:01:35.510   pjsua_core.c  PJSUA state changed: INIT --> 
STARTING
D/libpjsip(15884): 15:01:35.513 sip_endpoint.c  .Module "mod-unsolicited-mwi" 
registered
D/libpjsip(15884): 15:01:35.513   pjsua_core.c  .PJSUA state changed: STARTING 
--> RUNNING
D/PreferencesWrapper(15866): Invalid codec_speex_16000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_speex_8000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_speex_32000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_gsm_8000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_pcmu_8000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_pcma_8000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_g722_16000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_amr_8000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_amr-wb_16000_fpp format : expect a 
int
D/PreferencesWrapper(15866): Invalid codec_isac_16000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_ilbc_8000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_codec2_8000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_silk_8000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_silk_12000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_silk_16000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_silk_24000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_opus_16000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_g726-40_8000_fpp format : expect a 
int
D/PreferencesWrapper(15866): Invalid codec_g726-32_8000_fpp format : expect a 
int
D/PreferencesWrapper(15866): Invalid codec_g726-24_8000_fpp format : expect a 
int
D/PreferencesWrapper(15866): Invalid codec_g726-16_8000_fpp format : expect a 
int
D/PreferencesWrapper(15866): Invalid codec_mpeg4-generic_48000_fpp format : 
expect a int
D/PreferencesWrapper(15866): Invalid codec_g7221_16000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_g7221_16000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_g7221_32000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_g7221_32000_fpp format : expect a int
D/PreferencesWrapper(15866): Invalid codec_g7221_32000_fpp format : expect a int
D/PjService(15884): Audio codecs : GSM/8000/1 (230) - PCMU/8000/1 (60) - 
PCMA/8000/1 (50) - SILK/8000/1 (239) -

Original comment by a...@orencio.org on 2 Feb 2015 at 2:41

GoogleCodeExporter commented 9 years ago 
D/PjService(15884): Video codecs : 
D/DynamicReceiver(15884): Start monitoring of route file ? 0
D/SIP SRV (15884): Listen for phone state 
D/SIP SRV (15884): Add all accounts
D/SIP SRV (15884): We are adding all accounts right now....
D/PjSipAccount(15884): Create proxy 1
D/PjSipAccount(15884): Add proxy sips:ostel.co:5061
D/libpjsip(15884): 15:01:35.901    pjsua_acc.c  Adding account: 
id=<sip:ostel_user@ostel.co>
D/libpjsip(15884): 15:01:35.901    pjsua_acc.c  .Account 
<sip:ostel_user@ostel.co> added with id 3
D/libpjsip(15884): 15:01:35.902    pjsua_acc.c  Acc 3: setting registration..
D/libpjsip(15884): 15:01:36.009 tlsc0x5f35242c  .TLS client transport created
D/libpjsip(15884): 15:01:36.010 tlsc0x5f35242c  .TLS transport 
88.30.149.166:37381 is connecting to 66.151.32.200:5061...
D/libpjsip(15884): 15:01:36.013    pjsua_acc.c  .Contact for acc 3 updated for 
SIP outbound: 
<sip:ostel_user@88.30.149.166:37381;transport=TLS;ob>;reg-id=1;+sip.instance="<u
rn:uuid:69de3dfa-d575-44bf-9680-d748f6d0d4f6>"
D/libpjsip(15884): 15:01:36.225   pjsua_core.c  ..TX 656 bytes Request msg 
REGISTER/cseq=45644 (tdta0x5f3928a0) to TLS 66.151.32.200:5061:
D/libpjsip(15884): REGISTER sip:ostel.co SIP/2.0
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:37381;rport;branch=z9hG4bKPjyKph-0NrBRDG8xL2XFs6RqVbqDAREPpu;alias
D/libpjsip(15884): Route: <sips:ostel.co:5061;transport=tls;lr>
D/libpjsip(15884): Max-Forwards: 70
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=dpjB0RnSlA8cHCciP-PTZRoB-pbGo-5q
D/libpjsip(15884): t: <sip:ostel_user@ostel.co>
D/libpjsip(15884): i: SANhpnJZeZbUom14YG.x32WFbTRriAGh
D/libpjsip(15884): CSeq: 45644 REGISTER
D/libpjsip(15884): User-Agent: CSipSimple_m0-19/r2457
D/libpjsip(15884): k: outbound, path
D/libpjsip(15884): m: 
<sip:ostel_user@88.30.149.166:37381;transport=TLS;ob>;reg-id=1;+sip.instance="<u
rn:uuid:69de3dfa-d575-44bf-9680-d748f6d0d4f6>"
D/libpjsip(15884): Expires: 1800
D/libpjsip(15884): Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, 
SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
D/libpjsip(15884): l:  0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:36.227    pjsua_acc.c  .Acc 3: Registration sent
D/DBProvider(15884): Added status_text= status_code=-1 display_name=OSTN 
expires=0 account_id=1 added_status=0 priority=100 active=true wizard=OSTN 
reg_uri=sip:ostel.co pjsua_id=3
D/SIP SRV (15884): Accounts status.onChange( false)
D/SIP SRV (15884): Update registration state
D/libpjsip(15884): 15:01:36.233    pjsua_acc.c  Acc 3: setting online status to 
1..
D/AccountChooserButton(15866): Accounts status.onChange( false)
D/PjService(15884): Found pjsua 3 searching 3
D/PjService(15884): Update profile from service for 3 aka in db 1
D/DBProvider(15884): Updated status_text=In Progress status_code=100 
display_name=OSTN expires=0 account_id=1 added_status=0 priority=100 
active=true wizard=OSTN reg_uri=sip:ostel.co pjsua_id=3
D/SIP SRV (15884): Accounts status.onChange( false)
D/SIP SRV (15884): Update registration state
D/PjService(15884): Profile state UP : status_text=In Progress status_code=100 
expires=0
D/DynamicReceiver(15884): Internal receive android.net.conn.CONNECTIVITY_CHANGE
D/AccountChooserButton(15866): Accounts status.onChange( false)
D/Prefs   (15884): We are valid for MOBILE
D/Prefs   (15884): We are valid for MOBILE
D/DynamicReceiver(15884): onConnectivityChanged(): null -> mobile
E/libpjsip(15884): 15:01:38.072 tlsc0x5f35242c !TLS connect() error: Unknown 
error 495020 [code=495020]
W/libpjsip(15884): 15:01:38.072  tsx0x5f1bb9cc  Failed to send Request msg 
REGISTER/cseq=45644 (tdta0x5f3928a0)! err=495020 (Unknown error 495020)
W/libpjsip(15884): 15:01:38.072    pjsua_acc.c  ..SIP registration failed, 
status=503 (Unknown error 495020)
D/libpjsip(15884): 15:01:38.072    pjsua_acc.c  ..Scheduling re-registration 
retry for acc 3 in 2 seconds..
D/SIP UA Receiver(15884): < LOCK CPU
D/SIP UA Receiver(15884): > UNLOCK CPU 0
D/PjService(15884): Found pjsua 3 searching 3
D/PjService(15884): Update profile from service for 3 aka in db 1
D/DBProvider(15884): Updated status_text=Service Unavailable status_code=503 
display_name=OSTN expires=-1 account_id=1 added_status=0 priority=100 
active=true wizard=OSTN reg_uri=sip:ostel.co pjsua_id=3
D/SIP SRV (15884): Accounts status.onChange( false)
D/SIP SRV (15884): Update registration state
D/PjService(15884): Profile state UP : status_text=Service Unavailable 
status_code=503 expires=-1
D/AccountChooserButton(15866): Accounts status.onChange( false)
D/libpjsip(15884): 15:01:38.142    pjsua_acc.c  Disconnected notification for 
transport tlsc0x5f35242c
D/libpjsip(15884): 15:01:38.147    pjsua_acc.c  .Scheduling re-registration 
retry for acc 3 in 1 seconds..
D/libpjsip(15884): 15:01:38.206 tlsc0x5f35242c !TLS transport destroyed with 
reason 495020: Unknown error 495020
D/libpjsip(15884): 15:01:39.689    pjsua_acc.c  Acc 3: setting registration..
D/libpjsip(15884): 15:01:39.885 tlsc0x5f35242c  .TLS client transport created
D/libpjsip(15884): 15:01:39.886 tlsc0x5f35242c  .TLS transport 
88.30.149.166:46401 is connecting to 66.151.32.200:5061...
D/libpjsip(15884): 15:01:39.889    pjsua_acc.c  .Contact for acc 3 updated for 
SIP outbound: 
<sip:ostel_user@88.30.149.166:46401;transport=TLS;ob>;reg-id=1;+sip.instance="<u
rn:uuid:69de3dfa-d575-44bf-9680-d748f6d0d4f6>"
D/PjService(15884): Found pjsua 3 searching 3
D/RegHandlerReceiver(15884): We restore 
<sip:ostel_user@95.130.15.251:58781;transport=TLS;ob>
D/libpjsip(15884): 15:01:40.110 mobile_reg_han  ..Hook a RX request
D/libpjsip(15884): 15:01:40.110   pjsua_core.c  ..TX 724 bytes Request msg 
REGISTER/cseq=62583 (tdta0x5f343398) to TLS 66.151.32.200:5061:
D/libpjsip(15884): REGISTER sip:ostel.co SIP/2.0
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport;branch=z9hG4bKPjoSkTNcpKAAB-PE3OcmOfLbLTd4.bb-Th;alias
D/libpjsip(15884): Route: <sips:ostel.co:5061;transport=tls;lr>
D/libpjsip(15884): Max-Forwards: 70
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=y1oVpb5jr9MaCDwqaWTQw3VDNLYRpHWy
D/libpjsip(15884): t: <sip:ostel_user@ostel.co>
D/libpjsip(15884): i: Aa-mRTcxySdpMdsZPzqbOR5DIGYaCZfm
D/libpjsip(15884): CSeq: 62583 REGISTER
D/libpjsip(15884): User-Agent: CSipSimple_m0-19/r2457
D/libpjsip(15884): k: outbound, path
D/libpjsip(15884): m: 
<sip:ostel_user@88.30.149.166:46401;transport=TLS;ob>;reg-id=1;+sip.instance="<u
rn:uuid:69de3dfa-d575-44bf-9680-d748f6d0d4f6>"
D/libpjsip(15884): Expires: 1800
D/libpjsip(15884): Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, 
SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
D/libpjsip(15884): m: 
<sip:ostel_user@95.130.15.251:58781;transport=TLS;ob>;expires=0
D/libpjsip(15884): l:  0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:40.113    pjsua_acc.c  .Acc 3: Registration sent
D/libpjsip(15884): 15:01:41.615 tlsc0x5f35242c !TLS transport 
88.30.149.166:46401 is connected to 66.151.32.200:5061
D/libpjsip(15884): 15:01:42.412   pjsua_core.c  .RX 497 bytes Response msg 
401/REGISTER/cseq=62583 (rdata0x5f3525f8) from TLS 66.151.32.200:5061:
D/libpjsip(15884): SIP/2.0 401 Unauthorized
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport=33564;branch=z9hG4bKPjoSkTNcpKAAB-PE3OcmOfLbLTd4.bb-Th
;alias;received=95.130.15.251
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=y1oVpb5jr9MaCDwqaWTQw3VDNLYRpHWy
D/libpjsip(15884): t: 
<sip:ostel_user@ostel.co>;tag=e01f0de2cdfebbeefc5ff0c8eabbb8b3.80bd
D/libpjsip(15884): i: Aa-mRTcxySdpMdsZPzqbOR5DIGYaCZfm
D/libpjsip(15884): CSeq: 62583 REGISTER
D/libpjsip(15884): WWW-Authenticate: Digest realm="ostel.co", 
nonce="VM+EcVTPg0UAJIHKK8C32aKliX6GOYXr"
D/libpjsip(15884): Server: kamailio (4.1.3 (x86_64/linux))
D/libpjsip(15884): Content-Length: 0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:42.413 mobile_reg_han  .mod_reg_tracker_on_rx_response
D/libpjsip(15884): 15:01:42.413 mobile_reg_han  .mod_reg_tracker_on_rx_response 
done
D/PjService(15884): Found pjsua 3 searching 3
D/RegHandlerReceiver(15884): We restore 
<sip:ostel_user@95.130.15.251:58781;transport=TLS;ob>
D/libpjsip(15884): 15:01:42.434 mobile_reg_han  ....The register already has 
old contact in it, ignore
D/libpjsip(15884): 15:01:42.434   pjsua_core.c  ....TX 894 bytes Request msg 
REGISTER/cseq=62584 (tdta0x5f343398) to TLS 66.151.32.200:5061:
D/libpjsip(15884): REGISTER sip:ostel.co SIP/2.0
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport;branch=z9hG4bKPjDVzbA83LX6LEEAAZamoFDdUZ-CBRnJQ0;alias
D/libpjsip(15884): Route: <sips:ostel.co:5061;transport=tls;lr>
D/libpjsip(15884): Max-Forwards: 70
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=y1oVpb5jr9MaCDwqaWTQw3VDNLYRpHWy
D/libpjsip(15884): t: <sip:ostel_user@ostel.co>
D/libpjsip(15884): i: Aa-mRTcxySdpMdsZPzqbOR5DIGYaCZfm
D/libpjsip(15884): CSeq: 62584 REGISTER
D/libpjsip(15884): User-Agent: CSipSimple_m0-19/r2457
D/libpjsip(15884): k: outbound, path
D/libpjsip(15884): m: 
<sip:ostel_user@88.30.149.166:46401;transport=TLS;ob>;reg-id=1;+sip.instance="<u
rn:uuid:69de3dfa-d575-44bf-9680-d748f6d0d4f6>"
D/libpjsip(15884): Expires: 1800
D/libpjsip(15884): Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, 
SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
D/libpjsip(15884): m: 
<sip:ostel_user@95.130.15.251:58781;transport=TLS;ob>;expires=0
D/libpjsip(15884): Authorization: Digest username="ostel_user", 
realm="ostel.co", nonce="VM+EcVTPg0UAJIHKK8C32aKliX6GOYXr", uri="sip:ostel.co", 
response="1fd67a1722943a896fe52ba59f1ebc74"
D/libpjsip(15884): l:  0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:43.243   pjsua_core.c  .RX 741 bytes Response msg 
200/REGISTER/cseq=62584 (rdata0x5f3525f8) from TLS 66.151.32.200:5061:
D/libpjsip(15884): SIP/2.0 200 OK
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport=33564;branch=z9hG4bKPjDVzbA83LX6LEEAAZamoFDdUZ-CBRnJQ0
;alias;received=95.130.15.251
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=y1oVpb5jr9MaCDwqaWTQw3VDNLYRpHWy
D/libpjsip(15884): t: 
<sip:ostel_user@ostel.co>;tag=e01f0de2cdfebbeefc5ff0c8eabbb8b3.a8fc
D/libpjsip(15884): i: Aa-mRTcxySdpMdsZPzqbOR5DIGYaCZfm
D/libpjsip(15884): CSeq: 62584 REGISTER
D/libpjsip(15884): Contact: 
<sip:ostel_user@88.30.149.166:47657;transport=TLS;ob>;expires=740;+sip.instance=
"<urn:uuid:5c56f713-77c8-463e-9d11-1ac42458e291>";reg-id=1, 
<sip:ostel_user@88.30.149.166:46401;transport=TLS;ob>;expires=1800;received="sip
:95.130.15.251:33564;transport=TLS";+sip.instance="<urn:uuid:69de3dfa-d575-44bf-
9680-d748f6d0d4f6>";reg-id=1
D/libpjsip(15884): Server: kamailio (4.1.3 (x86_64/linux))
D/libpjsip(15884): Content-Length: 0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:43.243 mobile_reg_han  .mod_reg_tracker_on_rx_response
D/libpjsip(15884): 15:01:43.243 mobile_reg_han  .mod_reg_tracker_on_rx_response 
2
D/libpjsip(15884): 15:01:43.243 mobile_reg_han  .Hook a REGISTER RX response !!!
D/libpjsip(15884): 15:01:43.244 mobile_reg_han  . Hook should save contact : 
<sip:ostel_user@88.30.149.166:46401;transport=TLS;ob> > 1800
D/PjService(15884): Found pjsua 3 searching 3
D/libpjsip(15884): 15:01:43.266 mobile_reg_han  .mod_reg_tracker_on_rx_response 
done
D/libpjsip(15884): 15:01:43.295    pjsua_acc.c  ....SIP outbound status for acc 
3 is not active
I/libpjsip(15884): 15:01:43.300    pjsua_acc.c  ....IP address change detected 
for account 3 (88.30.149.166:46401 --> 95.130.15.251:33564). Updating 
registration (using method 2)
D/libpjsip(15884): 15:01:43.301    pjsua_acc.c  ....Acc 3: setting 
registration..
D/PjService(15884): Found pjsua 3 searching 3
D/RegHandlerReceiver(15884): We restore 
<sip:ostel_user@88.30.149.166:46401;transport=TLS;ob>
D/libpjsip(15884): 15:01:43.596 mobile_reg_han  ......The register already has 
old contact in it, ignore
D/libpjsip(15884): 15:01:43.596   pjsua_core.c  ......TX 894 bytes Request msg 
REGISTER/cseq=62585 (tdta0x5f1ebaf8) to TLS 66.151.32.200:5061:
D/libpjsip(15884): REGISTER sip:ostel.co SIP/2.0
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport;branch=z9hG4bKPj1hv.2jtQi.Du6p2cAlUUC9Xw9TSugDqL;alias
D/libpjsip(15884): Route: <sips:ostel.co:5061;transport=tls;lr>
D/libpjsip(15884): Max-Forwards: 70
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=XKJG.HWeyYdiR4IckVF6DWk2B4gb0cmM
D/libpjsip(15884): t: <sip:ostel_user@ostel.co>
D/libpjsip(15884): i: Aa-mRTcxySdpMdsZPzqbOR5DIGYaCZfm
D/libpjsip(15884): CSeq: 62585 REGISTER
D/libpjsip(15884): Authorization: Digest username="ostel_user", 
realm="ostel.co", nonce="VM+EcVTPg0UAJIHKK8C32aKliX6GOYXr", uri="sip:ostel.co", 
response="1fd67a1722943a896fe52ba59f1ebc74"
D/libpjsip(15884): User-Agent: CSipSimple_m0-19/r2457
D/libpjsip(15884): k: outbound, path
D/libpjsip(15884): m: <sip:ostel_user@95.130.15.251:33564;transport=TLS;ob>
D/libpjsip(15884): m: 
<sip:ostel_user@88.30.149.166:46401;transport=TLS;ob>;expires=0;reg-id=1;+sip.in
stance="<urn:uuid:69de3dfa-d575-44bf-9680-d748f6d0d4f6>"
D/libpjsip(15884): Expires: 1800
D/libpjsip(15884): Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, 
SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
D/libpjsip(15884): l:  0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:43.600    pjsua_acc.c  .....Acc 3: Registration sent
D/libpjsip(15884): 15:01:44.611   pjsua_core.c  .RX 668 bytes Response msg 
200/REGISTER/cseq=62585 (rdata0x5f3525f8) from TLS 66.151.32.200:5061:
D/libpjsip(15884): SIP/2.0 200 OK
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport=33564;branch=z9hG4bKPj1hv.2jtQi.Du6p2cAlUUC9Xw9TSugDqL
;alias;received=95.130.15.251
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=XKJG.HWeyYdiR4IckVF6DWk2B4gb0cmM
D/libpjsip(15884): t: 
<sip:ostel_user@ostel.co>;tag=e01f0de2cdfebbeefc5ff0c8eabbb8b3.4f07
D/libpjsip(15884): i: Aa-mRTcxySdpMdsZPzqbOR5DIGYaCZfm
D/libpjsip(15884): CSeq: 62585 REGISTER
D/libpjsip(15884): Contact: 
<sip:ostel_user@88.30.149.166:47657;transport=TLS;ob>;expires=739;+sip.instance=
"<urn:uuid:5c56f713-77c8-463e-9d11-1ac42458e291>";reg-id=1, 
<sip:ostel_user@95.130.15.251:33564;transport=TLS;ob>;expires=1800;received="sip
:95.130.15.251:33564;transport=TLS"
D/libpjsip(15884): Server: kamailio (4.1.3 (x86_64/linux))
D/libpjsip(15884): Content-Length: 0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:44.611 mobile_reg_han  .mod_reg_tracker_on_rx_response
D/libpjsip(15884): 15:01:44.611 mobile_reg_han  .mod_reg_tracker_on_rx_response 
2
D/libpjsip(15884): 15:01:44.611 mobile_reg_han  .Hook a REGISTER RX response !!!
D/libpjsip(15884): 15:01:44.611 mobile_reg_han  . Hook should save contact : 
<sip:ostel_user@95.130.15.251:33564;transport=TLS;ob> > 1800
D/PjService(15884): Found pjsua 3 searching 3
D/libpjsip(15884): 15:01:44.638 mobile_reg_han  .mod_reg_tracker_on_rx_response 
done
D/libpjsip(15884): 15:01:44.770    pjsua_acc.c  ....SIP outbound status for acc 
3 is not active
I/libpjsip(15884): 15:01:44.770    pjsua_acc.c  ....<sip:ostel_user@ostel.co>: 
registration success, status=200 (OK), will re-register in 1800 seconds
D/libpjsip(15884): 15:01:44.770   pjsua_pres.c  ....Starting MWI subscription..
D/libpjsip(15884): 15:01:45.005   pjsua_core.c  .......TX 624 bytes Request msg 
SUBSCRIBE/cseq=31245 (tdta0x5f343398) to TLS 66.151.32.200:5061:
D/libpjsip(15884): SUBSCRIBE sip:ostel_user@ostel.co SIP/2.0
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport;branch=z9hG4bKPjYs1pKiOJ2O4aSFJiZcp1BVaB.PnwRwIS;alias
D/libpjsip(15884): Max-Forwards: 70
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=ZeA.ModrzMapanJdyao46CHvLcGq7tPK
D/libpjsip(15884): t: <sip:ostel_user@ostel.co>
D/libpjsip(15884): m: <sip:ostel_user@95.130.15.251:33564;transport=TLS;ob>
D/libpjsip(15884): i: NqDXlsKHmp3uvHWJm5UpcWFQuGtXhd8F
D/libpjsip(15884): CSeq: 31245 SUBSCRIBE
D/libpjsip(15884): Route: <sips:ostel.co:5061;transport=tls;lr>
D/libpjsip(15884): Event: message-summary
D/libpjsip(15884): Expires: 3600
D/libpjsip(15884): k: replaces, 100rel, timer, norefersub
D/libpjsip(15884): Accept: application/simple-message-summary
D/libpjsip(15884): u: presence, message-summary, refer
D/libpjsip(15884): User-Agent: CSipSimple_m0-19/r2457
D/libpjsip(15884): l:  0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:45.010 evsub0x5f0df49  .........Subscription state 
changed NULL --> SENT
D/libpjsip(15884): 15:01:45.010   pjsua_pres.c  ..........MWI subscription for 
<sip:ostel_user@ostel.co> is SENT
D/SIP UA Receiver(15884): < LOCK CPU
D/SIP UA Receiver(15884): > UNLOCK CPU 0
D/PjService(15884): Found pjsua 3 searching 3
D/PjService(15884): Update profile from service for 3 aka in db 1
D/DBProvider(15884): Updated status_text=OK status_code=200 display_name=OSTN 
expires=1794 account_id=1 added_status=0 priority=100 active=true wizard=OSTN 
reg_uri=sip:ostel.co pjsua_id=3
D/SIP SRV (15884): Accounts status.onChange( false)
D/SIP SRV (15884): Update registration state
D/AccountChooserButton(15866): Accounts status.onChange( false)
D/PjService(15884): Profile state UP : status_text=OK status_code=200 
expires=1794
D/libpjsip(15884): 15:01:46.072   pjsua_core.c  .RX 517 bytes Response msg 
407/SUBSCRIBE/cseq=31245 (rdata0x5f3525f8) from TLS 66.151.32.200:5061:
D/libpjsip(15884): SIP/2.0 407 Proxy Authentication Required
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport=33564;branch=z9hG4bKPjYs1pKiOJ2O4aSFJiZcp1BVaB.PnwRwIS
;alias;received=95.130.15.251
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=ZeA.ModrzMapanJdyao46CHvLcGq7tPK
D/libpjsip(15884): t: 
<sip:ostel_user@ostel.co>;tag=e01f0de2cdfebbeefc5ff0c8eabbb8b3.75dd
D/libpjsip(15884): i: NqDXlsKHmp3uvHWJm5UpcWFQuGtXhd8F
D/libpjsip(15884): CSeq: 31245 SUBSCRIBE
D/libpjsip(15884): Proxy-Authenticate: Digest realm="ostel.co", 
nonce="VM+EdVTPg0mKUeTsb+DN+rYyD2EG8NQ1"
D/libpjsip(15884): Server: kamailio (4.1.3 (x86_64/linux))
D/libpjsip(15884): Content-Length: 0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:46.072 mobile_reg_han  .mod_reg_tracker_on_rx_response
D/libpjsip(15884): 15:01:46.072 mobile_reg_han  .mod_reg_tracker_on_rx_response 
done
D/libpjsip(15884): 15:01:46.078   pjsua_core.c  ......TX 811 bytes Request msg 
SUBSCRIBE/cseq=31246 (tdta0x5f343398) to TLS 66.151.32.200:5061:
D/libpjsip(15884): SUBSCRIBE sip:ostel_user@ostel.co SIP/2.0
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport;branch=z9hG4bKPjn.1dL4K.44BuwxNLpj1BywBcpB8xwp6p;alias
D/libpjsip(15884): Max-Forwards: 70
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=ZeA.ModrzMapanJdyao46CHvLcGq7tPK
D/libpjsip(15884): t: <sip:ostel_user@ostel.co>
D/libpjsip(15884): m: <sip:ostel_user@95.130.15.251:33564;transport=TLS;ob>
D/libpjsip(15884): i: NqDXlsKHmp3uvHWJm5UpcWFQuGtXhd8F
D/libpjsip(15884): CSeq: 31246 SUBSCRIBE
D/libpjsip(15884): Route: <sips:ostel.co:5061;transport=tls;lr>
D/libpjsip(15884): Event: message-summary
D/libpjsip(15884): Expires: 3600
D/libpjsip(15884): k: replaces, 100rel, timer, norefersub
D/libpjsip(15884): Accept: application/simple-message-summary
D/libpjsip(15884): u: presence, message-summary, refer
D/libpjsip(15884): User-Agent: CSipSimple_m0-19/r2457
D/libpjsip(15884): Proxy-Authorization: Digest username="ostel_user", 
realm="ostel.co", nonce="VM+EdVTPg0mKUeTsb+DN+rYyD2EG8NQ1", 
uri="sip:ostel_user@ostel.co", response="a005eae6d3765d3bae9d9f4f5928af9d"
D/libpjsip(15884): l:  0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:46.898   pjsua_core.c  .RX 421 bytes Response msg 
404/SUBSCRIBE/cseq=31246 (rdata0x5f3525f8) from TLS 66.151.32.200:5061:
D/libpjsip(15884): SIP/2.0 404 No voicemail service
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport=33564;branch=z9hG4bKPjn.1dL4K.44BuwxNLpj1BywBcpB8xwp6p
;alias;received=95.130.15.251
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=ZeA.ModrzMapanJdyao46CHvLcGq7tPK
D/libpjsip(15884): t: 
<sip:ostel_user@ostel.co>;tag=e01f0de2cdfebbeefc5ff0c8eabbb8b3.8e8e
D/libpjsip(15884): i: NqDXlsKHmp3uvHWJm5UpcWFQuGtXhd8F
D/libpjsip(15884): CSeq: 31246 SUBSCRIBE
D/libpjsip(15884): Server: kamailio (4.1.3 (x86_64/linux))
D/libpjsip(15884): Content-Length: 0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:46.898 mobile_reg_han  .mod_reg_tracker_on_rx_response
D/libpjsip(15884): 15:01:46.898 mobile_reg_han  .mod_reg_tracker_on_rx_response 
done
D/libpjsip(15884): 15:01:46.905 evsub0x5f0df49  ....Subscription state changed 
SENT --> TERMINATED
D/libpjsip(15884): 15:01:46.905   pjsua_pres.c  .....MWI subscription for 
<sip:ostel_user@ostel.co> is TERMINATED
D/libpjsip(15884): 15:01:46.929 evsub0x5f0df49 !...Subscription destroyed
D/Prefs   (15884): We are valid for MOBILE
D/Prefs   (15884): We are valid for MOBILE
D/SIP SRV (15884): Direct sip start
D/Prefs   (15884): We are valid for MOBILE
D/Prefs   (15884): We are valid for MOBILE
D/SIP SRV (15884): Start was asked and we should actually start now
D/SIP SRV (15884): Ask pjservice to start itself
D/PjService(15884): will call <sip:9196@ostel.co>
D/libpjsip(15884): 15:01:49.322   pjsua_call.c !Making call with acc #3 to 
<sip:9196@ostel.co>
D/libpjsip(15884): 15:01:49.322    pjsua_aud.c  .Set sound device: capture=-1, 
playback=-2
D/libpjsip(15884): 15:01:49.322    pjsua_aud.c  ..Opening sound device 
PCM@16000/1/20ms
D/libpjsip(15884): 15:01:49.322   opensl_dev.c  ...Creating OpenSL stream
W/AudioTrack(15884): AUDIO_OUTPUT_FLAG_FAST denied by client due to mismatching 
sample rate (16000 vs 44100)
D/libpjsip(15884): 15:01:49.325   opensl_dev.c  ...Recording stream type 4, SDK 
: 19
W/AudioRecord(15884): AUDIO_INPUT_FLAG_FAST denied by client
D/libpjsip(15884): 15:01:49.351 echo_webrtc_ae  ...Create webRTC AEC with clock 
rate 16000
D/libpjsip(15884): 15:01:49.351   ec0x5ef93318  ...WEBRTC AEC created, 
clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, 
latency=100 ms
D/libpjsip(15884): 15:01:49.351   opensl_dev.c  ...Starting OpenSL stream..
D/MediaManager(15884): Target mode... : 3
D/MediaManager(15884): Target mode... : 3
D/MediaManager(15884): Set mode audio in call to 3
D/AudioFocus 8(15884): Focus again false
D/MediaManager(15884): Initial volume level : 0.8
D/libpjsip(15884): 15:01:49.675   opensl_dev.c  ...OpenSL stream started
D/libpjsip(15884): 15:01:49.676  pjsua_media.c  .Call 0: initializing media..
D/libpjsip(15884): 15:01:49.834   ec0x5ef93318 !Buffer size adjusted from 1920 
to 1441 (eff_cnt=1440)
D/libpjsip(15884): 15:01:49.884  pjsua_media.c !..RTP socket reachable at 
88.30.149.166:4000
D/libpjsip(15884): 15:01:49.884  pjsua_media.c  ..RTCP socket reachable at 
88.30.149.166:4001
D/libpjsip(15884): 15:01:49.884  pjsua_media.c  ..Media index 0 selected for 
audio call 0
D/libpjsip(15884): 15:01:49.884 pjsua_jni_addo  ..Dispatch transport creation 
on ZRTP one
D/libpjsip(15884): 15:01:49.884 zrtp_android.c  ..ZRTP transport created
D/libpjsip(15884): 15:01:49.885 transport_zrtp  .attribute added: 
a=zrtp-hash:1.10 
46bd5349c9d5e379df63f78076e002ed4170d970bd479f7d7fbf250bec36f065
D/libpjsip(15884): 15:01:49.996   pjsua_core.c  ....TX 914 bytes Request msg 
INVITE/cseq=21664 (tdta0x5f36bfa8) to TLS 66.151.32.200:5061:
D/libpjsip(15884): INVITE sip:9196@ostel.co SIP/2.0
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport;branch=z9hG4bKPj6QiW14fu6AqImja.L5dR0ZBu54BTDLSs;alias
D/libpjsip(15884): Max-Forwards: 70
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=gm2RoQxtgGwBkN1YinO7hnGp-N8K-dM1
D/libpjsip(15884): t: <sip:9196@ostel.co>
D/libpjsip(15884): m: <sip:ostel_user@95.130.15.251:33564;transport=TLS;ob>
D/libpjsip(15884): i: 4607SgEyt0bZNTGzwQGjbUpGbvMMVJ18
D/libpjsip(15884): CSeq: 21664 INVITE
D/libpjsip(15884): Route: <sips:ostel.co:5061;transport=tls;lr>
D/libpjsip(15884): k: replaces, 100rel, timer, norefersub
D/libpjsip(15884): x: 1800
D/libpjsip(15884): Min-SE: 90
D/libpjsip(15884): User-Agent: CSipSimple_m0-19/r2457
D/libpjsip(15884): c: application/sdp
D/libpjsip(15884): l:   384
D/libpjsip(15884): 
D/libpjsip(15884): v=0
D/libpjsip(15884): o=- 3631874509 3631874509 IN IP4 88.30.149.166
D/libpjsip(15884): s=pjmedia
D/libpjsip(15884): c=IN IP4 88.30.149.166
D/libpjsip(15884): t=0 0
D/libpjsip(15884): m=audio 4000 RTP/AVP 96 3 0 8 101
D/libpjsip(15884): c=IN IP4 88.30.149.166
D/libpjsip(15884): a=rtcp:4001 IN IP4 88.30.149.166
D/libpjsip(15884): a=sendrecv
D/libpjsip(15884): a=rtpmap:96 SILK/8000
D/libpjsip(15884): a=fmtp:96 useinbandfec=0
D/libpjsip(15884): a=rtpmap:101 telephone-event/8000
D/libpjsip(15884): a=fmtp:101 0-16
D/libpjsip(15884): a=zrtp-hash:1.10 
46bd5349c9d5e379df63f78076e002ed4170d970bd479f7d7fbf250bec36f065
D/libpjsip(15884): --end msg--
I/libpjsip(15884): 15:01:50.000 pjsua_jni_addo  .......Call 0 state changed to 
CALLING
D/SIP UA Receiver(15884): < LOCK CPU
D/SIP UA Receiver(15884): Call state <<
D/SIP UA Receiver(15884): Updating call infos from the stack
D/PjSipCalls(15884): Update call 0
D/PjService(15884): Found pjsua 3 searching 3
D/PjSipCalls(15884): Last status code is 0
D/SIP UA Receiver(15884): Call state >>
D/SIP UA Receiver(15884): < LOCK CPU
D/SIP UA Receiver(15884): > UNLOCK CPU 0
D/SIP UA Receiver(15884): < LOCK CPU
D/SIP UA Receiver(15884): Call TSX state <<
D/SIP UA Receiver(15884): Updating call infos from the stack
D/PjSipCalls(15884): Update call 0
D/PjService(15884): Found pjsua 3 searching 3
D/PjSipCalls(15884): Last status code is 0
D/SIP UA Receiver(15884): Call TSX state >>
D/SIP UA Receiver(15884): > UNLOCK CPU 1
D/PjService(15884): DTMF - Store for 0 - null
D/SIP UA Receiver(15884): Anounce call activity
D/SIP_HOME(15866): On Pause SIPHOME
D/SIP UA Receiver(15884): > UNLOCK CPU 0
D/InCallActivity(15884): Create in call
D/libpjsip(15884): 15:01:50.209   ec0x5ef93318 !Underflow, buf_cnt=161, will 
generate 1 frame
D/InCallControls(15884): >> Speaker null
D/CallProximityManager(15884): Use 4.2 detection way for proximity sensor 
detection. Result is true
D/CallProximityManager(15884): We can use native screen locker !!
D/AccelerometerListener(15884): enable(true)

Original comment by a...@orencio.org on 2 Feb 2015 at 2:42

GoogleCodeExporter commented 9 years ago 
D/InCallControls(15884): Mode is : 1
D/InCallControls(15884): >> Speaker null
D/InCallActivity(15884): Start in call
D/InCallActivity(15884): We have a call 0 / 1/0
D/InCallControls(15884): Mode is : 1
D/InCallControls(15884): >> Speaker null
D/InCallActivity(15884): Active call is 0
D/InCallActivity(15884): Update ui from call 0 state Llamando...
D/InCallActivity(15884): Acquire wake up lock
D/InCallActivity(15884): we leave the update ui function
D/CallProximityManager(15884): Horizontal : false and activate for calls true
D/InCallActivity(15884): We have a call 0 / 1/0
D/InCallControls(15884): Mode is : 1
D/InCallControls(15884): >> Speaker null
D/InCallActivity(15884): Active call is 0
D/InCallActivity(15884): Update ui from call 0 state Llamando...
D/InCallActivity(15884): Acquire wake up lock
D/InCallActivity(15884): we leave the update ui function
D/CallProximityManager(15884): Horizontal : false and activate for calls true
D/InCallControls(15884): >> Speaker null
D/CallProximityManager(15884): Horizontal : false and activate for calls true
D/libpjsip(15884): 15:01:50.911   ec0x5ef93318 !Buffer size adjusted from 1761 
to 1282 (eff_cnt=1440)
I/Timeline(15884): Timeline: Activity_idle id: android.os.BinderProxy@41edc298 
time:23723386
D/InCallInfoGrid(15884): Populate 1 children
D/InCallCard(15884): Set call state : 1
W/InCallActivity(15884): No camera preview available to be shown
D/AccelerometerListener(15884): orientation: horizontal
D/CallProximityManager(15884): Horizontal : true and activate for calls true
D/InCallCard(15884): Current ratio is 0.68311197
D/libpjsip(15884): 15:01:53.060   pjsua_core.c !.RX 508 bytes Response msg 
407/INVITE/cseq=21664 (rdata0x5f3525f8) from TLS 66.151.32.200:5061:
D/libpjsip(15884): SIP/2.0 407 Proxy Authentication Required
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport=33564;branch=z9hG4bKPj6QiW14fu6AqImja.L5dR0ZBu54BTDLSs
;alias;received=95.130.15.251
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=gm2RoQxtgGwBkN1YinO7hnGp-N8K-dM1
D/libpjsip(15884): t: 
<sip:9196@ostel.co>;tag=e01f0de2cdfebbeefc5ff0c8eabbb8b3.918a
D/libpjsip(15884): i: 4607SgEyt0bZNTGzwQGjbUpGbvMMVJ18
D/libpjsip(15884): CSeq: 21664 INVITE
D/libpjsip(15884): Proxy-Authenticate: Digest realm="ostel.co", 
nonce="VM+Ee1TPg08gka0a94jlXiJ1hVCWg4h9"
D/libpjsip(15884): Server: kamailio (4.1.3 (x86_64/linux))
D/libpjsip(15884): Content-Length: 0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:53.060 mobile_reg_han  .mod_reg_tracker_on_rx_response
D/libpjsip(15884): 15:01:53.060 mobile_reg_han  .mod_reg_tracker_on_rx_response 
done
D/libpjsip(15884): 15:01:53.065   pjsua_core.c  ..TX 388 bytes Request msg 
ACK/cseq=21664 (tdta0x5f43e008) to TLS 66.151.32.200:5061:
D/libpjsip(15884): ACK sip:9196@ostel.co SIP/2.0
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport;branch=z9hG4bKPj6QiW14fu6AqImja.L5dR0ZBu54BTDLSs;alias
D/libpjsip(15884): Max-Forwards: 70
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=gm2RoQxtgGwBkN1YinO7hnGp-N8K-dM1
D/libpjsip(15884): t: 
<sip:9196@ostel.co>;tag=e01f0de2cdfebbeefc5ff0c8eabbb8b3.918a
D/libpjsip(15884): i: 4607SgEyt0bZNTGzwQGjbUpGbvMMVJ18
D/libpjsip(15884): CSeq: 21664 ACK
D/libpjsip(15884): Route: <sips:ostel.co:5061;transport=tls;lr>
D/libpjsip(15884): l:  0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:53.068   pjsua_core.c  .......TX 1095 bytes Request 
msg INVITE/cseq=21665 (tdta0x5f36bfa8) to TLS 66.151.32.200:5061:
D/libpjsip(15884): INVITE sip:9196@ostel.co SIP/2.0
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport;branch=z9hG4bKPj5q0AgdzNdiHkckGmUpcZ3FI5TU-qf0nx;alias
D/libpjsip(15884): Max-Forwards: 70
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=gm2RoQxtgGwBkN1YinO7hnGp-N8K-dM1
D/libpjsip(15884): t: <sip:9196@ostel.co>
D/libpjsip(15884): m: <sip:ostel_user@95.130.15.251:33564;transport=TLS;ob>
D/libpjsip(15884): i: 4607SgEyt0bZNTGzwQGjbUpGbvMMVJ18
D/libpjsip(15884): CSeq: 21665 INVITE
D/libpjsip(15884): Route: <sips:ostel.co:5061;transport=tls;lr>
D/libpjsip(15884): k: replaces, 100rel, timer, norefersub
D/libpjsip(15884): x: 1800
D/libpjsip(15884): Min-SE: 90
D/libpjsip(15884): User-Agent: CSipSimple_m0-19/r2457
D/libpjsip(15884): Proxy-Authorization: Digest username="ostel_user", 
realm="ostel.co", nonce="VM+Ee1TPg08gka0a94jlXiJ1hVCWg4h9", 
uri="sip:9196@ostel.co", response="aee9a6d768db8e2776e3b616b946f228"
D/libpjsip(15884): c: application/sdp
D/libpjsip(15884): l:   384
D/libpjsip(15884): 
D/libpjsip(15884): v=0
D/libpjsip(15884): o=- 3631874509 3631874509 IN IP4 88.30.149.166
D/libpjsip(15884): s=pjmedia
D/libpjsip(15884): c=IN IP4 88.30.149.166
D/libpjsip(15884): t=0 0
D/libpjsip(15884): m=audio 4000 RTP/AVP 96 3 0 8 101
D/libpjsip(15884): c=IN IP4 88.30.149.166
D/libpjsip(15884): a=rtcp:4001 IN IP4 88.30.149.166
D/libpjsip(15884): a=sendrecv
D/libpjsip(15884): a=rtpmap:96 SILK/8000
D/libpjsip(15884): a=fmtp:96 useinbandfec=0
D/libpjsip(15884): a=rtpmap:101 telephone-event/8000
D/libpjsip(15884): a=fmtp:101 0-16
D/libpjsip(15884): a=zrtp-hash:1.10 
46bd5349c9d5e379df63f78076e002ed4170d970bd479f7d7fbf250bec36f065
D/libpjsip(15884): --end msg--
D/SIP UA Receiver(15884): < LOCK CPU
D/SIP UA Receiver(15884): Call TSX state <<
D/SIP UA Receiver(15884): Updating call infos from the stack
D/PjSipCalls(15884): Update call 0
D/PjService(15884): Found pjsua 3 searching 3
D/PjSipCalls(15884): Last status code is 0
D/SIP UA Receiver(15884): Call TSX state >>
D/SIP UA Receiver(15884): > UNLOCK CPU 0
D/SIP UA Receiver(15884): < LOCK CPU
D/SIP UA Receiver(15884): Call TSX state <<
D/SIP UA Receiver(15884): Updating call infos from the stack
D/PjSipCalls(15884): Update call 0
D/PjService(15884): Found pjsua 3 searching 3
D/PjSipCalls(15884): Last status code is 407
D/SIP UA Receiver(15884): Call TSX state >>
D/SIP UA Receiver(15884): > UNLOCK CPU 0
D/libpjsip(15884): 15:01:54.018   pjsua_core.c  .RX 388 bytes Response msg 
100/INVITE/cseq=21665 (rdata0x5f3525f8) from TLS 66.151.32.200:5061:
D/libpjsip(15884): SIP/2.0 100 trying -- your call is important to us
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport=33564;branch=z9hG4bKPj5q0AgdzNdiHkckGmUpcZ3FI5TU-qf0nx
;alias;received=95.130.15.251
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=gm2RoQxtgGwBkN1YinO7hnGp-N8K-dM1
D/libpjsip(15884): t: <sip:9196@ostel.co>
D/libpjsip(15884): i: 4607SgEyt0bZNTGzwQGjbUpGbvMMVJ18
D/libpjsip(15884): CSeq: 21665 INVITE
D/libpjsip(15884): Server: kamailio (4.1.3 (x86_64/linux))
D/libpjsip(15884): Content-Length: 0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:01:54.018 mobile_reg_han  .mod_reg_tracker_on_rx_response
D/libpjsip(15884): 15:01:54.018 mobile_reg_han  .mod_reg_tracker_on_rx_response 
done
D/SIP UA Receiver(15884): < LOCK CPU
D/SIP UA Receiver(15884): Call TSX state <<
D/SIP UA Receiver(15884): Updating call infos from the stack
D/PjSipCalls(15884): Update call 0
D/PjService(15884): Found pjsua 3 searching 3
D/PjSipCalls(15884): Last status code is 100
D/SIP UA Receiver(15884): Call TSX state >>
D/SIP UA Receiver(15884): > UNLOCK CPU 0
D/libpjsip(15884): 15:01:54.504   sound_port.c !EC suspended because of 
inactivity
D/libpjsip(15884): 15:02:27.970   pjsua_core.c !.RX 407 bytes Response msg 
408/INVITE/cseq=21665 (rdata0x5f3525f8) from TLS 66.151.32.200:5061:
D/libpjsip(15884): SIP/2.0 408 Request Timeout
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport=33564;branch=z9hG4bKPj5q0AgdzNdiHkckGmUpcZ3FI5TU-qf0nx
;alias;received=95.130.15.251
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=gm2RoQxtgGwBkN1YinO7hnGp-N8K-dM1
D/libpjsip(15884): t: 
<sip:9196@ostel.co>;tag=1948a7b22eacdc1559fde4faedc490e1-ce19
D/libpjsip(15884): i: 4607SgEyt0bZNTGzwQGjbUpGbvMMVJ18
D/libpjsip(15884): CSeq: 21665 INVITE
D/libpjsip(15884): Server: kamailio (4.1.3 (x86_64/linux))
D/libpjsip(15884): Content-Length: 0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/libpjsip(15884): 15:02:27.971 mobile_reg_han  .mod_reg_tracker_on_rx_response
D/libpjsip(15884): 15:02:27.971 mobile_reg_han  .mod_reg_tracker_on_rx_response 
done
D/libpjsip(15884): 15:02:27.971   pjsua_core.c  ..TX 388 bytes Request msg 
ACK/cseq=21665 (tdta0x5f43e008) to TLS 66.151.32.200:5061:
D/libpjsip(15884): ACK sip:9196@ostel.co SIP/2.0
D/libpjsip(15884): v: SIP/2.0/TLS 
88.30.149.166:46401;rport;branch=z9hG4bKPj5q0AgdzNdiHkckGmUpcZ3FI5TU-qf0nx;alias
D/libpjsip(15884): Max-Forwards: 70
D/libpjsip(15884): f: 
<sip:ostel_user@ostel.co>;tag=gm2RoQxtgGwBkN1YinO7hnGp-N8K-dM1
D/libpjsip(15884): t: 
<sip:9196@ostel.co>;tag=1948a7b22eacdc1559fde4faedc490e1-ce19
D/libpjsip(15884): i: 4607SgEyt0bZNTGzwQGjbUpGbvMMVJ18
D/libpjsip(15884): CSeq: 21665 ACK
D/libpjsip(15884): Route: <sips:ostel.co:5061;transport=tls;lr>
D/libpjsip(15884): l:  0
D/libpjsip(15884): 
D/libpjsip(15884): --end msg--
D/SIP UA Receiver(15884): < LOCK CPU
D/SIP UA Receiver(15884): Call TSX state <<
D/SIP UA Receiver(15884): Updating call infos from the stack
D/PjSipCalls(15884): Update call 0
D/PjService(15884): Found pjsua 3 searching 3
D/PjSipCalls(15884): Last status code is 408
D/SIP UA Receiver(15884): Call TSX state >>
D/SIP UA Receiver(15884): > UNLOCK CPU 0
I/libpjsip(15884): 15:02:27.979 pjsua_jni_addo  .....Call 0 is DISCONNECTED 
[reason=408 (Request Timeout)]
D/SIP UA Receiver(15884): < LOCK CPU
D/SIP UA Receiver(15884): Call state <<
D/SIP UA Receiver(15884): Updating call infos from the stack
D/PjSipCalls(15884): Update call 0
D/PjService(15884): Found pjsua 3 searching 3
D/PjSipCalls(15884): Last status code is 408
D/SIP UA Receiver(15884): Call state >>
D/SIP UA Receiver(15884): > UNLOCK CPU 0
D/libpjsip(15884): 15:02:28.003  pjsua_media.c  .....Call 0: deinitializing 
media..
D/SIP UA Receiver(15884): < LOCK CPU
D/libpjsip(15884): 15:02:28.003 transport_zrtp  ......Media stop - encrypted 
packets: 0, decrypted packets: 0
D/libpjsip(15884): 15:02:28.004 transport_zrtp  ......Destroy - encrypted 
packets: 0, decrypted packets: 0
D/Prefs   (15884): We are valid for MOBILE
D/Prefs   (15884): We are valid for MOBILE
D/SIP UA Receiver(15884): > UNLOCK CPU 0
D/InCallActivity(15884): We have a call 0 / 6/0
D/InCallInfoGrid(15884): Populate 1 children
D/InCallCard(15884): Set call state : 6
W/InCallActivity(15884): No camera preview available to be shown
D/InCallInfoGrid(15884): Populate 0 children
D/InCallActivity(15884): Active call is 0
D/InCallActivity(15884): Update ui from call 0 state Colgado
D/InCallActivity(15884): Active call session is disconnected or null wait for 
quit...
W/InCallActivity(15884): No camera preview available to be shown
D/InCallActivity(15884): Releasing wake up lock
D/InCallActivity(15884): Start quit timer
D/InCallInfoGrid(15884): Populate 1 children
D/InCallCard(15884): Set call state : 6
D/InCallCard(15884): Nothing changed, ignore this update
W/InCallActivity(15884): No camera preview available to be shown
D/InCallCard(15884): Current ratio is 0.5930807
D/libpjsip(15884): 15:02:29.018    pjsua_aud.c !Closing sound device after idle 
for 1 second(s)
D/libpjsip(15884): 15:02:29.018    pjsua_aud.c  .Closing OpenSL ES Audio sound 
playback device and OpenSL ES Audio sound capture device
D/libpjsip(15884): 15:02:29.018   opensl_dev.c  .Stopping stream
D/libpjsip(15884): 15:02:29.059   opensl_dev.c  .OpenSL stream stopped
D/MediaManager(15884): Unset Audio In call
D/MediaManager(15884): Unset bt
D/BT8     (15884): Ask for false vs false
D/MediaManager(15884): Target mode... : 3
D/libpjsip(15884): 15:02:29.204   opensl_dev.c  .OpenSL stream destroyed
D/InCallActivity(15884): Run quit timer
D/SIP_HOME(15866): On Resume SIPHOME
D/SIP_HOME(15866): WE CAN NOW start SIP service
E/        (15866): Device driver API match
E/        (15866): Device driver API version: 23
E/        (15866): User space API version: 23 
E/        (15866): mali: REVISION=Linux-r3p2-01rel3 BUILD_DATE=Fri Mar 21 
13:52:50 KST 2014 
D/Prefs   (15884): We are valid for MOBILE
D/Prefs   (15884): We are valid for MOBILE
D/SIP SRV (15884): Direct sip start
D/Prefs   (15884): We are valid for MOBILE
I/Timeline(15866): Timeline: Activity_idle id: android.os.BinderProxy@42506e00 
time:23764176
D/Prefs   (15884): We are valid for MOBILE
D/SIP SRV (15884): Start was asked and we should actually start now
D/SIP SRV (15884): Ask pjservice to start itself
D/AccelerometerListener(15884): enable(false)
I/Help    (15866): Item clicked : 2 2
D/PreferencesWrapper(15866): Create directory /storage/emulated/0/CSipSimple

408 (1 of 5)

Original comment by a...@orencio.org on 2 Feb 2015 at 2:42

GoogleCodeExporter commented 9 years ago 
(this is the log, excuse me but Google says: "Issue attachment storage quota 
exceeded.").

Original comment by a...@orencio.org on 2 Feb 2015 at 2:43

GoogleCodeExporter commented 9 years ago 
I have the same problem! Wont let me make calls. Says 408 request timeout! How 
do I fix  it!!!!

Original comment by ryanl...@gmail.com on 5 Feb 2015 at 9:52

GoogleCodeExporter commented 9 years ago 
You need to disable SRTP completely and enable ZRTP (Settings -> Network -> 
Secure Transport).

Original comment by pej...@gmail.com on 6 May 2015 at 4:37

GoogleCodeExporter commented 9 years ago

Original comment by r3gis...@gmail.com on 22 Jun 2015 at 11:31