[Linphone-developers] one way voice problem

[0.0]

[0.0] 1316584513846 :: Getting dialog for address@hidden:4000@00.00.00.00[0.0] :wsh8d9s0h:

[0.0] 1316584513859 :: could not find existing dialog for INVITE sip:address@hidden/2.0

[0.0] and dialogID: address@hidden:address@hidden:wsh8d9s0h:address@hidden 1.119.213

[0.0] 1316584513879 :: create hop for :5060/udp

[0.0] 1316584513885 :: NextHop based on Route:00.00.00.00:5060/udp

[0.0] 1316584513892 :: createMessageChannel 00.00.00.00:5060/udp processor sip4me.gov.nist.siplite.stac[0.0] address@hidden

[0.0] 1316584513902 :: Creating clientTransaction address@hidden

[0.0] 1316584513916 :: hop = 00.00.00.00:5060/udp ; ct address@hidden fa65a60a for topmostVia: Via: SIP/2.0/UDP 192.168.2.2:5060;rport

[0.0]

[0.0] 1316584513950 :: Setting Branch id : z9hG4bKbbcd482f8d26544f24d5cc262a77ec2f

[0.0] 1316584513956 :: Creating Client Transaction For Dialog-Initiating request (INVITE)

[0.0] 1316584513964 :: [SIPTransactionStack] Creating dialog for transaction sip4me.gov.nist.siplite.stack[0.0] address@hidden

[0.0] 1316584513974 :: Transaction Added address@hidden to dialo[0.0] g address@hidden tags: wsH8D9s0h / null

[0.0] 1316584513985 :: TID = z9hg4bkbbcd482f8d26544f24d5cc262a77ec2f/false

[0.0] 1316584513992 :: Sending request directly through Client Transaction

[0.0] 1316584513999 :: Setting Branch id : z9hG4bKbbcd482f8d26544f24d5cc262a77ec2f

[0.0] 1316584514005 :: setState address@hidden 2

[0.0] 1316584514016 :: Sending message to [00.00.00.00:5060/UDP]

[0.0] INVITE sip:address@hidden SIP/2.0

[0.0] Call-Id: address@hidden

[0.0] CSeq: 1 INVITE

[0.0] To: <sip:address@hidden>

[0.0] Via: SIP/2.0/UDP 192.168.2.2:5060;rport;branch=z9hG4bKbbcd482f8d26544f24d5cc262a77ec2f

[0.0] Max-Forwards: 70

[0.0] From: <sip:address@hidden>;tag=wsH8D9s0h

[0.0] Route: <sip:00.00.00.00;lr>

[0.0] Contact: <sip:address@hidden:32807>

[0.0] Content-Type: application/sdp

[0.0] Content-Length: 195

[0.0]

[0.0] v=0

[0.0] o=blackberry 1316584513822 1316584513822 IN IP4 192.168.1.99

[0.0] s=Phone call

[0.0] c=IN IP4 192.168.1.99

[0.0] t=0 0

[0.0] m=audio 7078 RTP/AVP 114

[0.0] a=rtpmap:114 AMR/8000/1

[0.0] a=fmtp:114 octet-align=1

[0.0] to be sent to

[0.0] 1316584514241 :: SIPTransactionStack: creating new Response SIP/2.0 100 Giving a try

[0.0]

[0.0] 1316584514258 :: Found Transaction address@hidden for SIP/[0.0] 2.0 100 Giving a try

[0.0] Call-Id: address@hidden

[0.0] CSeq: 1 INVITE

[0.0] To: <sip:address@hidden>

[0.0] Via: SIP/2.0/UDP 192.168.2.2:5060;rport=32807;branch=z9hG4bKbbcd482f8d26544f24d5cc262a77ec2f;receive[0.0] d=192.168.1.99

[0.0] From: <sip:address@hidden>;tag=wsH8D9s0h

[0.0] Server: OpenSIPS (1.6.2-notls (i386/linux))

[0.0] Content-Length: 0

[0.0]

[0.0] 1316584514408 :: MessageProcessor = address@hidden/sip4m[0.0] address@hidden

[0.0] 1316584514422 :: processing SIP/2.0 100 Giving a try

[0.0] current state = 2

[0.0] 1316584514434 :: addRoute: dialogState: address@hidden = -1

[0.0] 1316584514506 :: setState address@hidden 3

[0.0] 1316584514516 :: PROCESSING INCOMING RESPONSESIP/2.0 100 Giving a try

[0.0] Call-Id: address@hidden

[0.0] CSeq: 1 INVITE

[0.0] To: <sip:address@hidden>

[0.0] Via: SIP/2.0/UDP 192.168.2.2:5060;rport=32807;branch=z9hG4bKbbcd482f8d26544f24d5cc262a77ec2f;receive[0.0] d=192.168.1.99

[0.0] From: <sip:address@hidden>;tag=wsH8D9s0h

[0.0] Server: OpenSIPS (1.6.2-notls (i386/linux))

[0.0] Content-Length: 0

[0.0]

[0.0] 1316584514654 :: Transaction = address@hidden

[0.0] 1316584514663 :: handleEvent address@hidden

[0.0] currentTransaction = address@hidden

[0.0] this.sipListener = address@hidden

[0.0] 1316584514691 :: Adding event to EventScanner queue: sip4me.gov.nist.siplite.stack.ClientTransaction[0.0] @fa65a60a

[0.0] 1316584514702 :: EventScanner waking up!

[0.0] 1316584514709 :: Processing address@hidden nevents 1

[0.0] Sal-Warn:Unexpected answer [100 sip:address@hidden]

[0.0] 1316584514723 :: EventScanner going to sleep!

[0.0] 1316584514762 :: SIPTransactionStack: creating new Response SIP/2.0 180 Ringing

[0.0]

[0.0] 1316584514783 :: Found Transaction address@hidden for SIP/[0.0] 2.0 180 Ringing

[0.0] From: <sip:address@hidden>;tag=wsH8D9s0h

[0.0] To: <sip:address@hidden>;tag=3074222

[0.0] Contact: <sip:address@hidden:1738;transport=udp>

[0.0] Call-Id: address@hidden

[0.0] CSeq: 1 INVITE

[0.0] Via: SIP/2.0/UDP 192.168.2.2:5060;rport=32807;received=192.168.1.99;branch=z9hG4bKbbcd482f8d26544f24[0.0] d5cc262a77ec2f

[0.0] Record-Route: <sip:00.00.00.00;lr=on>

[0.0] Allow: ACK, BYE, CANCEL, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE

[0.0] Content-Length: 0

[0.0]

[0.0] 1316584514881 :: MessageProcessor = address@hidden/sip4m[0.0] address@hidden

[0.0] 1316584514893 :: processing SIP/2.0 180 Ringing

[0.0] current state = 3

[0.0] 1316584514909 :: addRoute: dialogState: address@hidden = -1

[0.0] 1316584514919 :: Dialog.setRemoteTarget: <sip:address@hidden:1738;transport=udp>

[0.0] 1316584514930 :: putDialog address@hidden with dialogId=18276a47d7109[0.0] address@hidden:address@hidden:wsh8d9s0h:address@hidden:3074222

[0.0] 1316584514943 :: Setting dialog state for address@hidden to 1

[0.0] 1316584514952 :: New dialog state is 1dialogId = address@hidden:address@hidden 82.71.119.213:wsh8d9s0h:address@hidden:3074222

[0.0] 1316584514966 :: PROCESSING INCOMING RESPONSESIP/2.0 180 Ringing

[0.0] From: <sip:address@hidden>;tag=wsH8D9s0h

[0.0] To: <sip:address@hidden>;tag=3074222

[0.0] Contact: <sip:address@hidden:1738;transport=udp>

[0.0] Call-Id: address@hidden

[0.0] CSeq: 1 INVITE

[0.0] Via: SIP/2.0/UDP 192.168.2.2:5060;rport=32807;received=192.168.1.99;branch=z9hG4bKbbcd482f8d26544f24[0.0] d5cc262a77ec2f

[0.0] Record-Route: <sip:00.00.00.00;lr=on>

[0.0] Allow: ACK, BYE, CANCEL, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE

[0.0] Content-Length: 0

[0.0]

[0.0] 1316584515052 :: Transaction = address@hidden

[0.0] 1316584515059 :: handleEvent address@hidden

[0.0] currentTransaction = address@hidden

[0.0] this.sipListener = address@hidden

[0.0] 1316584515077 :: Adding event to EventScanner queue: sip4me.gov.nist.siplite.stack.ClientTransaction[0.0] @fa65a60a

[0.0] 1316584515098 :: EventScanner waking up!

[0.0] 1316584515108 :: Processing address@hidden nevents 1

[0.0] MSM[0]: created session

[0.0] MSM[0]: ----- initializeStream

[0.0] [STREAM] allocating buffer [index: 0] [size: 65536]

[0.0] [STREAM] allocating stream [index: 0] [size: 65536]

[0.0] [STREAM] allocated stream [index: 0] [handle: 3339] [size: 65536]

[0.0] null:_ss=3339 PID=294

[0.0] MN: load 0

[0.0] MN: init0(33554432, 0, 1, 65535)=0

[0.0] MSM[0]: Stream 3339 watermark remain=0 avail=65536

[0.0] [STREAM] write [handle: 3339] 53126 bytes written

[0.0] Linphone-Info:Call button pressed.

[0.0] MSM[0]: Stream Streaming session: 3339 wrote 53126

[0.0] MP: h=33554432,s=LG: +mLod(294):120

[0.0] MP: h=33554432,s=LG: -> LD

[0.0] MN: getPlayableStreams0(33554432)=65537

[0.0] MP: h=33554432,s=LD: -mLod

[0.0] MP: h=33554432,s=LD: +sNMD

[0.0] MN: signifyNoMoreData0(33554432)=0

[0.0] MP: h=33554432,s=LD: -sNMD

[0.0] MN: getLength0(33554432)=3317

[0.0] MN: getPlayableStreams0(33554432)=65537

[0.0] MN: isSeekable0(33554432)=1

[0.0] MP: h=33554432,s=LD: statUpdEn true

[0.0] MN: slewVolume(33554432, 70, 1)=0

[0.0] MP: h=33554432,s=LD: volChange 70

[0.0] MN: slewVolume(33554432, 70, 1)=0

[0.0] AR: add source 14

[0.0] AR: setAudioMode 34

[0.0] 3rd Party explicitly set sink.

[0.0] MP: h=33554432,s=LD: +play(294):294

[0.0] MN: play0(33554432) is audio, NO check for active pause

[0.0] MN: play0(33554432)=0

[0.0] MP: h=33554432,s=LD: -> PL

[0.0] MP: h=33554432,s=PL: -play

[0.0] 1316584515492 :: EventScanner going to sleep!

[0.0] MP: h=33554432,s=PL: volChange 70

[0.0] MP: h=33554432,s=PL: mStat: 0

[0.0] MN: slewVolume(33554432, 70, 1)=0

[0.0] SMP[-1216270423,MP: h=33554432]: BUFFERING = 0

[0.0] SMP[-1216270423,MP: h=33554432]: BUFFERING = 100

[0.0] MP: h=33554432,s=PL: volChange 70

[0.0] MP: h=33554432,s=PL: mStat: -1

[0.0] MP: h=33554432,s=PL: -> LD

[0.0] MP: h=33554432,s=LD: +unld(294):120

[0.0] MP: h=33554432,s=LD: -> UG

[0.0] MN: unload0(33554432)=0 pauseHandle=0

[0.0] MN: MEDIA_STOPPED received

[0.0] MN: handle=33554432 bitmapHandle=0 thumbnailHandle=2147483647

[0.0] MP: h=33554432,s=UG: +mStp(294):120

[0.0] MP: h=33554432,s=UG: +unlC

[0.0] MN: finalize0(33554432)

[0.0] MN: unload 1

[0.0] MP: h=33554432,s=UG: -> UD

[0.0] MSM[0]: release

[0.0] MSM[0]: Stream 3339 sink done

[0.0] MSM[0]: Stream Streaming session: 3339 sendSourceEnded called

[0.0] MP: h=33554432,s=UD: -unld

[0.0] AR: remove source 14

[0.0] AR: setAudioMode 32

[0.0] MSM[0]: RT exit

[0.0] [STREAM] releasing buffer [index: 0] [handle: 3339]

[0.0] MSM[0]: already released

[0.0] MP: h=33554432,s=UD: -unlC

[0.0] MP: h=33554432,s=UD: -mStp

[0.0] MSM[0]: created session

[0.0] MSM[0]: ----- initializeStream

[0.0] [STREAM] allocating buffer [index: 0] [size: 65536]

[0.0] [STREAM] allocating stream [index: 0] [size: 65536]

[0.0] [STREAM] allocated stream [index: 0] [handle: 3595] [size: 65536]

[0.0] null:_ss=3595 PID=120

[0.0] MN: load 0

[0.0] MN: init0(50331648, 0, 1, 65535)=0

[0.0] [STREAM] write [handle: 3595] 53126 bytes written

[0.0] MSM[0]: Stream Streaming session: 3595 wrote 53126

[0.0] MP: h=50331648,s=LG: +sNMD

[0.0] MN: signifyNoMoreData0(50331648)=0

[0.0] MP: h=50331648,s=LG: -sNMD

[0.0] MSM[0]: Stream 3595 watermark remain=0 avail=65536

[0.0] MP: h=50331648,s=LG: +mLod(120):120

[0.0] MP: h=50331648,s=LG: -> LD

[0.0] MN: getPlayableStreams0(50331648)=65537

[0.0] MP: h=50331648,s=LD: -mLod

[0.0] MN: getLength0(50331648)=3317

[0.0] MN: getPlayableStreams0(50331648)=65537

[0.0] MN: isSeekable0(50331648)=1

[0.0] MP: h=50331648,s=LD: statUpdEn true

[0.0] MN: slewVolume(50331648, 70, 1)=0

[0.0] AR: add source 14

[0.0] AR: setAudioMode 34

[0.0] 3rd Party explicitly set sink.

[0.0] MP: h=50331648,s=LD: +play(120):120

[0.0] MN: play0(50331648) is audio, NO check for active pause

[0.0] MN: play0(50331648)=0

[0.0] MP: h=50331648,s=LD: -> PL

[0.0] MP: h=50331648,s=PL: -play

[0.0] MP: h=50331648,s=PL: volChange 70

[0.0] MN: slewVolume(50331648, 70, 1)=0

[0.0] MP: h=50331648,s=PL: mStat: 0

[0.0] SMP[-1216270423,MP: h=50331648]: BUFFERING = 0

[0.0] SMP[-1216270423,MP: h=50331648]: BUFFERING = 100

[0.0] MP: h=50331648,s=PL: volChange 70

[0.0] MP: h=50331648,s=PL: mStat: -1

[0.0] MP: h=50331648,s=PL: -> LD

[0.0] MP: h=50331648,s=LD: +unld(120):120

[0.0] MP: h=50331648,s=LD: -> UG

[0.0] MN: unload0(50331648)=0 pauseHandle=0

[0.0] MN: MEDIA_STOPPED received

[0.0] MN: handle=50331648 bitmapHandle=0 thumbnailHandle=2147483647

[0.0] MP: h=50331648,s=UG: +mStp(120):120

[0.0] MP: h=50331648,s=UG: +unlC

[0.0] MN: finalize0(50331648)

[0.0] MN: unload 1

[0.0] MP: h=50331648,s=UG: -> UD

[0.0] MSM[0]: release

[0.0] MSM[0]: Stream 3595 sink done

[0.0] MSM[0]: Stream Streaming session: 3595 sendSourceEnded called

[0.0] MP: h=50331648,s=UD: -unld

[0.0] AR: remove source 14

[0.0] AR: setAudioMode 32

[0.0] MSM[0]: RT exit

[0.0] [STREAM] releasing buffer [index: 0] [handle: 3595]

[0.0] MSM[0]: already released

[0.0] MP: h=50331648,s=UD: -unlC

[0.0] MP: h=50331648,s=UD: -mStp

[0.0] MSM[1]: created session

[0.0] MSM[1]: ----- initializeStream

[0.0] [STREAM] allocating buffer [index: 0] [size: 65536]

[0.0] [STREAM] allocating stream [index: 0] [size: 65536]

[0.0] [STREAM] allocated stream [index: 0] [handle: 3851] [size: 65536]

[0.0] null:_ss=3851 PID=120

[0.0] MN: load 0

[0.0] MN: init0(67108864, 0, 1, 65535)=0

[0.0] [STREAM] write [handle: 3851] 53126 bytes written

[0.0] MSM[1]: Stream Streaming session: 3851 wrote 53126

[0.0] MP: h=67108864,s=LG: +sNMD

[0.0] MN: signifyNoMoreData0(67108864)=0

[0.0] MP: h=67108864,s=LG: -sNMD

[0.0] MSM[1]: Stream 3851 watermark remain=0 avail=65536

[0.0] MP: h=67108864,s=LG: +mLod(120):120

[0.0] MP: h=67108864,s=LG: -> LD

[0.0] MN: getPlayableStreams0(67108864)=65537

[0.0] MP: h=67108864,s=LD: -mLod

[0.0] MN: getLength0(67108864)=3317

[0.0] MN: getPlayableStreams0(67108864)=65537

[0.0] MN: isSeekable0(67108864)=1

[0.0] MP: h=67108864,s=LD: statUpdEn true

[0.0] MN: slewVolume(67108864, 70, 1)=0

[0.0] AR: add source 14

[0.0] AR: setAudioMode 34

[0.0] 3rd Party explicitly set sink.

[0.0] MP: h=67108864,s=LD: +play(120):120

[0.0] MN: play0(67108864) is audio, NO check for active pause

[0.0] MN: play0(67108864)=0

[0.0] MP: h=67108864,s=LD: -> PL

[0.0] MP: h=67108864,s=PL: -play

[0.0] MP: h=67108864,s=PL: volChange 70

[0.0] MN: slewVolume(67108864, 70, 1)=0

[0.0] MP: h=67108864,s=PL: mStat: 0

[0.0] SMP[-1216270423,MP: h=67108864]: BUFFERING = 0

[0.0] SMP[-1216270423,MP: h=67108864]: BUFFERING = 100

[0.0] MP: h=67108864,s=PL: volChange 70

[0.0] MP: h=67108864,s=PL: mStat: -1

[0.0] MP: h=67108864,s=PL: -> LD

[0.0] MP: h=67108864,s=LD: +unld(120):120

[0.0] MP: h=67108864,s=LD: -> UG

[0.0] MN: unload0(67108864)=0 pauseHandle=0

[0.0] MN: MEDIA_STOPPED received

[0.0] MN: handle=67108864 bitmapHandle=0 thumbnailHandle=2147483647

[0.0] MP: h=67108864,s=UG: +mStp(120):120

[0.0] MP: h=67108864,s=UG: +unlC

[0.0] MN: finalize0(67108864)

[0.0] MN: unload 1

[0.0] MP: h=67108864,s=UG: -> UD

[0.0] MSM[1]: release

[0.0] MSM[1]: Stream 3851 sink done

[0.0] MSM[1]: Stream Streaming session: 3851 sendSourceEnded called

[0.0] MP: h=67108864,s=UD: -unld

[0.0] AR: remove source 14

[0.0] AR: setAudioMode 32

[0.0] MSM[1]: RT exit

[0.0] [STREAM] releasing buffer [index: 0] [handle: 3851]

[0.0] MSM[1]: already released

[0.0] MP: h=67108864,s=UD: -unlC

[0.0] MP: h=67108864,s=UD: -mStp

[0.0] MSM[2]: created session

[0.0] MSM[2]: ----- initializeStream

[0.0] [STREAM] allocating buffer [index: 0] [size: 65536]

[0.0] [STREAM] allocating stream [index: 0] [size: 65536]

[0.0] [STREAM] allocated stream [index: 0] [handle: 4107] [size: 65536]

[0.0] null:_ss=4107 PID=120

[0.0] MN: load 0

[0.0] MN: init0(83886080, 0, 1, 65535)=0

[0.0] [STREAM] write [handle: 4107] 53126 bytes written

[0.0] MSM[2]: Stream Streaming session: 4107 wrote 53126

[0.0] MP: h=83886080,s=LG: +sNMD

[0.0] MN: signifyNoMoreData0(83886080)=0

[0.0] MP: h=83886080,s=LG: -sNMD

[0.0] MSM[2]: Stream 4107 watermark remain=0 avail=65536

[0.0] MP: h=83886080,s=LG: +mLod(120):120

[0.0] MP: h=83886080,s=LG: -> LD

[0.0] MN: getPlayableStreams0(83886080)=65537

[0.0] MP: h=83886080,s=LD: -mLod

[0.0] MN: getLength0(83886080)=3317

[0.0] MN: getPlayableStreams0(83886080)=65537

[0.0] MN: isSeekable0(83886080)=1

[0.0] MP: h=83886080,s=LD: statUpdEn true

[0.0] MN: slewVolume(83886080, 70, 1)=0

[0.0] AR: add source 14

[0.0] AR: setAudioMode 34

[0.0] 3rd Party explicitly set sink.

[0.0] MP: h=83886080,s=LD: +play(120):120

[0.0] MN: play0(83886080) is audio, NO check for active pause

[0.0] MN: play0(83886080)=0

[0.0] MP: h=83886080,s=LD: -> PL

[0.0] MP: h=83886080,s=PL: -play

[0.0] MP: h=83886080,s=PL: volChange 70

[0.0] MN: slewVolume(83886080, 70, 1)=0

[0.0] MP: h=83886080,s=PL: mStat: 0

[0.0] SMP[-1216270423,MP: h=83886080]: BUFFERING = 0

[0.0] SMP[-1216270423,MP: h=83886080]: BUFFERING = 100

[0.0] MP: h=83886080,s=PL: volChange 70

[0.0] JVM: bklt @987710: timer

[0.0] JVM: bklt[1] @987710: usrIdle 14, usrTime 30, usrAct 1

[0.0] JVM: bklt[1] @987710: chkIdle 24, currTime 30

[0.0] JVM: bklt @987710: setTimer 16

[0.0] 1316584529249 :: SIPTransactionStack: creating new Response SIP/2.0 200 OK

[0.0]

[0.0] 1316584529269 :: Found Transaction address@hidden for SIP/[0.0] 2.0 200 OK

[0.0] From: <sip:address@hidden>;tag=wsH8D9s0h

[0.0] To: <sip:address@hidden>;tag=3074222

[0.0] Contact: <sip:address@hidden:1738;transport=udp>

[0.0] Call-Id: address@hidden

[0.0] CSeq: 1 INVITE

[0.0] Content-Type: application/sdp

[0.0] Via: SIP/2.0/UDP 192.168.2.2:5060;rport=32807;received=192.168.1.99;branch=z9hG4bKbbcd482f8d26544f24[0.0] d5cc262a77ec2f

[0.0] Record-Route: <sip:00.00.00.00;lr=on>

[0.0] Allow: ACK, BYE, CANCEL, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE

[0.0] Content-Length: 176

[0.0]

[0.0] v=0

[0.0] o=doubango 1983 678902 IN IP4 192.168.1.106

[0.0] s=-

[0.0] c=IN IP4 192.168.1.106

[0.0] t=0 0

[0.0] m=audio 25488 RTP/AVP 114

[0.0] a=ptime:20

[0.0] a=rtpmap:114 AMR/8000/1

[0.0] a=fmtp:114 octet-align=1

[0.0]

[0.0] 1316584529426 :: MessageProcessor = address@hidden/sip4m[0.0] address@hidden

[0.0] 1316584529437 :: processing SIP/2.0 200 OK

[0.0] current state = 3

[0.0] 1316584529453 :: addRoute: dialogState: address@hidden = 1

[0.0] 1316584529463 :: Dialog.setRemoteTarget: <sip:address@hidden:1738;transport=udp>

[0.0] 1316584529472 :: Setting dialog state for address@hidden to 2

[0.0] 1316584529483 :: New dialog state is 2dialogId = address@hidden:address@hidden 82.71.119.213:wsh8d9s0h:address@hidden:3074222

[0.0] 1316584529495 :: setState address@hidden 6

[0.0] 1316584529504 :: Terminating transaction

[0.0] 1316584529524 :: PROCESSING INCOMING RESPONSESIP/2.0 200 OK

[0.0] From: <sip:address@hidden>;tag=wsH8D9s0h

[0.0] To: <sip:address@hidden>;tag=3074222

[0.0] Contact: <sip:address@hidden:1738;transport=udp>

[0.0] Call-Id: address@hidden

[0.0] CSeq: 1 INVITE

[0.0] Content-Type: application/sdp

[0.0] Via: SIP/2.0/UDP 192.168.2.2:5060;rport=32807;received=192.168.1.99;branch=z9hG4bKbbcd482f8d26544f24[0.0] d5cc262a77ec2f

[0.0] Record-Route: <sip:00.00.00.00;lr=on>

[0.0] Allow: ACK, BYE, CANCEL, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE

[0.0] Content-Length: 176

[0.0]

[0.0] v=0

[0.0] o=doubango 1983 678902 IN IP4 192.168.1.106

[0.0] s=-

[0.0] c=IN IP4 192.168.1.106

[0.0] t=0 0

[0.0] m=audio 25488 RTP/AVP 114

[0.0] a=ptime:20

[0.0] a=rtpmap:114 AMR/8000/1

[0.0] a=fmtp:114 octet-align=1

[0.0]

[0.0] 1316584529672 :: Transaction = address@hidden

[0.0] 1316584529680 :: handleEvent address@hidden

[0.0] currentTransaction = address@hidden

[0.0] this.sipListener = address@hidden

[0.0] 1316584529712 :: Adding event to EventScanner queue: sip4me.gov.nist.siplite.stack.ClientTransaction[0.0] @fa65a60a

[0.0] 1316584529722 :: EventScanner waking up!

[0.0] 1316584529730 :: Processing address@hidden nevents 1

[0.0] 1316584529737 :: setting state of SipDialog to 2

[0.0] 1316584529773 :: Removing clientTransaction address@hidden

[0.0] 1316584529793 :: [SipClientConnection] Sending request ACK

[0.0] 1316584529805 :: could not find existing transaction for ACK sip:address@hidden:1738;transport=u[0.0] dp SIP/2.0

[0.0]

[0.0] 1316584529832 :: Getting dialog for address@hidden:4000@00.00.00.00[0.0] :wsh8d9s0h:address@hidden:3074222

[0.0] 1316584529844 :: getRouteList address@hidden:address@hidden:wsh8d[0.0] 9s0h:address@hidden:3074222

[0.0] 1316584529857 :: ----->>>

[0.0] 1316584529865 :: getRouteList for address@hidden

[0.0] 1316584529874 :: RouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584529891 :: myRouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584529906 :: ----->>>

[0.0] 1316584529914 :: getRouteList address@hidden:address@hidden:wsh8d[0.0] 9s0h:address@hidden:3074222

[0.0] 1316584529927 :: ----->>>

[0.0] 1316584529935 :: getRouteList for address@hidden

[0.0] 1316584529944 :: RouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584529960 :: myRouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584529976 :: ----->>>

[0.0] 1316584529984 :: create hop for 00.00.00.00:5060/udp

[0.0] 1316584529992 :: createMessageChannel 00.00.00.00:5060/udp processor sip4me.gov.nist.siplite.stac[0.0] address@hidden

[0.0] 1316584530005 :: Creating clientTransaction address@hidden

[0.0] 1316584530016 :: Setting Branch id : z9hG4bK94779dba6b8ef9472296e2fa9d551ba9

[0.0] 1316584530025 :: address@hidden

[0.0] 1316584530034 :: setting from tag For outgoing ACK= wsH8D9s0h

[0.0] 1316584530042 :: setting ToHeader tag for outgoing ACK = 3074222

[0.0] 1316584530053 :: getRouteList address@hidden:address@hidden:wsh8d[0.0] 9s0h:address@hidden:3074222

[0.0] 1316584530064 :: ----->>>

[0.0] 1316584530072 :: getRouteList for address@hidden

[0.0] 1316584530083 :: RouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584530097 :: myRouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584530118 :: ----->>>

[0.0] 1316584530127 :: getRouteList address@hidden:address@hidden:wsh8d[0.0] 9s0h:address@hidden:3074222

[0.0] 1316584530138 :: ----->>>

[0.0] 1316584530147 :: getRouteList for address@hidden

[0.0] 1316584530156 :: RouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584530172 :: myRouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584530189 :: ----->>>

[0.0] 1316584530196 :: create hop for 00.00.00.00:5060/udp

[0.0] 1316584530204 :: createMessageChannel 00.00.00.00:5060/udp processor sip4me.gov.nist.siplite.stac[0.0] address@hidden

[0.0] 1316584530217 :: Creating clientTransaction address@hidden

[0.0] 1316584530228 :: Setting Branch id : z9hG4bK94779dba6b8ef9472296e2fa9d551ba9

[0.0] 1316584530236 :: Setting Branch id : z9hG4bK94779dba6b8ef9472296e2fa9d551ba9

[0.0] 1316584530244 :: setState address@hidden 6

[0.0] 1316584530253 :: Terminating transaction

[0.0] 1316584530267 :: Sending message to [00.00.00.00:5060/UDP]

[0.0] ACK sip:address@hidden:1738;transport=udp SIP/2.0

[0.0] Call-Id: address@hidden

[0.0] CSeq: 1 ACK

[0.0] To: <sip:address@hidden>;tag=3074222

[0.0] Via: SIP/2.0/UDP 192.168.2.2:5060;rport;branch=z9hG4bK94779dba6b8ef9472296e2fa9d551ba9

[0.0] Max-Forwards: 70

[0.0] From: <sip:address@hidden>;tag=wsH8D9s0h

[0.0] Contact: <sip:address@hidden:32807>

[0.0] Route: <sip:00.00.00.00;lr=on>

[0.0] Content-Length: 0

[0.0]

[0.0] to be sent to

[0.0] 1316584530361 :: setState address@hidden 6

[0.0] 1316584530371 :: Terminating transaction

[0.0] MN: slewVolume(83886080, 0, 1)=0

[0.0] MP: h=83886080,s=PL: +paus(120):294

[0.0] MN: pause0(83886080)=0

[0.0] MP: h=83886080,s=PL: -> PA

[0.0] MP: h=83886080,s=PA: volChange 0

[0.0] MP: h=83886080,s=PA: +mPC

[0.0] MP: h=83886080,s=PA: -> LD

[0.0] MP: h=83886080,s=LD: -mPC

[0.0] MP: h=83886080,s=LD: -paus

[0.0] AR: remove source 14

[0.0] AR: setAudioMode 32

[0.0] MP: h=83886080,s=LD: +unld(120):294

[0.0] MP: h=83886080,s=LD: -> UG

[0.0] MN: unload0(83886080)=0 pauseHandle=0

[0.0] MN: MEDIA_STOPPED received

[0.0] MN: handle=83886080 bitmapHandle=0 thumbnailHandle=2147483647

[0.0] MP: h=83886080,s=UG: +mStp(120):120

[0.0] MP: h=83886080,s=UG: +unlC

[0.0] MN: finalize0(83886080)

[0.0] MN: unload 1

[0.0] MP: h=83886080,s=UG: -> UD

[0.0] MSM[2]: release

[0.0] MSM[2]: Stream 4107 sink done

[0.0] MSM[2]: Stream Streaming session: 4107 sendSourceEnded called

[0.0] MP: h=83886080,s=UD: -unld

[0.0] MSM[2]: RT exit

[0.0] [STREAM] releasing buffer [index: 0] [handle: 4107]

[0.0] MSM[2]: already released

[0.0] MP: h=83886080,s=UD: -unlC

[0.0] MP: h=83886080,s=UD: -mStp

[0.0] AR: add source 12

[0.0] AR: setAudioMode 9

[0.0] MSM[1]: created session

[0.0] MSM[1]: ----- initializeStream

[0.0] [STREAM] allocating buffer [index: 0] [size: 65536]

[0.0] [STREAM] allocating stream [index: 0] [size: 65536]

[0.0] [STREAM] allocated stream [index: 0] [handle: 4363] [size: 65536]

[0.0] MN: setRecordParameters(1,20)=0

[0.0] MSM[1]: Starting streaming session 4363

[0.0] SendStream-Warn:Got event recordStarted[0]

[0.0] SendStream-Warn:Got event started[-1]

[0.0] 1316584530774 :: EventScanner going to sleep!

[0.0] RecvStream-Info:connect data source

[0.0] RecvStream-Info:start data source

[0.0] MSM[1]: Stream 4363 new data

[0.0] SendStream-Info:AMR header skipped

[0.0] MSM[1]: Stream 4363 new data

[0.0] RecvStream-Info:Got event com.rim.volume.control.type[volume.control.type.absolute]returned ms=0

[0.0] RecvStream-Info:Got event com.rim.seekableUpdate[true]returned ms=0

[0.0] RecvStream-Info:Got event com.rim.volume.control.type[volume.control.type.absolute]returned ms=0

[0.0] MSM[1]: Stream 4363 new data

[0.0] MSM[2]: created session

[0.0] MSM[2]: ----- initializeStream

[0.0] [STREAM] allocating buffer [index: 1] [size: 65536]

[0.0] [STREAM] allocating stream [index: 1] [size: 65536]

[0.0] [STREAM] allocated stream [index: 1] [handle: 4620] [size: 65536]

[0.0] null:_ss=4620 PID=294

[0.0] MN: load 0

[0.0] MN: init0(100663296, 0, 1, 65535)=0

[0.0] RecvStream-Info:Got event com.rim.loading[]returned ms=0

[0.0] MSM[1]: Stream 4363 new data

[0.0] RecvStream-Info:Initializing timestamp to [0]

[0.0] MSM[1]: Stream 4363 new data

[0.0] MSM[2]: Stream 4620 watermark remain=0 avail=65536

[0.0] MSM[1]: Stream 4363 new data

[0.0] 1316584531644 :: Removing clientTransaction address@hidden

[0.0] MSM[1]: Stream 4363 new data

[0.0] RecvStream-Warn:Too late, skipping 208 ms...

[0.0] MSM[1]: Stream 4363 new data

[0.0] AudioStream-Warn:received stats :Count=0

[0.0] Lost=1

[0.0] Late=0

[0.0] Played=0

[0.0] Player timestamp =61184

[0.0] AudioStream-Warn:send stats :Count=392

[0.0] Lost=0

[0.0] Late=0

[0.0] Played=0

[0.0] AudioStream-Warn:Jitter buffer statistics

[0.0] current size= 0.0 seconds

[0.0] delay= 0.0 seconds

[0.0] MSM[1]: Stream 4363 new data

[0.0] AudioStream-Warn:received stats :Count=0

[0.0] Lost=1

[0.0] Late=0

[0.0] Played=0

[0.0] Player timestamp =85184

[0.0] AudioStream-Warn:send stats :Count=541

[0.0] Lost=0

[0.0] Late=0

[0.0] Played=0

[0.0] AudioStream-Warn:Jitter buffer statistics

[0.0] current size= 0.0 seconds

[0.0] delay= 0.0 seconds

[0.0] MSM[1]: Stream 4363 new data

[0.0] JVM: bklt @997640: timer

[0.0] JVM: bklt[1] @997640: usrIdle 30, usrTime 30, usrAct 1

[0.0] JVM: bklt[1] @997640: chkIdle 34, currTime 30

[0.0] JVM: bklt[1] @997640: enableBacklight 0

[0.0] JVM: bklt[1]: setTimeout 30

[0.0] MSM[1]: Stream 4363 new data

[0.0] JVM: bklt[1] @997734: JBSC on=1

[0.0] JVM: bklt[1] @997734: SC 1

[0.0] JVM: bklt @997734: setTimer 30

[0.0] MSM[1]: Stream 4363 new data

[0.0] AudioStream-Warn:received stats :Count=0

[0.0] Lost=1

[0.0] Late=0

[0.0] Played=0

[0.0] Player timestamp =133024

[0.0] AudioStream-Warn:send stats :Count=840

[0.0] Lost=0

[0.0] Late=0

[0.0] Played=0

[0.0] AudioStream-Warn:Jitter buffer statistics

[0.0] current size= 0.0 seconds

[0.0] delay= 0.0 seconds

[0.0] MSM[1]: Stream 4363 new data

[0.0] RecvStream-Warn:Too late, skipping 35 ms...

[0.0] MSM[1]: Stream 4363 new data

[0.0] AudioStream-Warn:received stats :Count=0

[0.0] Lost=1

[0.0] Late=0

[0.0] Played=0

[0.0] Player timestamp =169464

[0.0] AudioStream-Warn:send stats :Count=1068

[0.0] Lost=0

[0.0] Late=0

[0.0] Played=0

[0.0] AudioStream-Warn:Jitter buffer statistics

[0.0] current size= 0.0 seconds

[0.0] delay= 0.0 seconds

[0.0] MSM[1]: Stream 4363 new data

[0.0] AudioStream-Warn:received stats :Count=0

[0.0] Lost=1

[0.0] Late=0

[0.0] Played=0

[0.0] Player timestamp =211544

[0.0] AudioStream-Warn:send stats :Count=1330

[0.0] Lost=0

[0.0] Late=0

[0.0] Played=0

[0.0] AudioStream-Warn:Jitter buffer statistics

[0.0] current size= 0.0 seconds

[0.0] delay= 0.0 seconds

[0.0] MSM[1]: Stream 4363 new data

[0.0] VM:+GC(f)w=11

[0.0] VM:-GCt=134,b=1,r=0,g=f,w=11

[0.0] VM:QUOT t=7

[0.0] VM:+CR

[0.0] VM:-CR=50

[0.0] RecvStream-Warn:Too late, skipping 1834 ms...

[0.0] AudioStream-Warn:received stats :Count=0

[0.0] Lost=1

[0.0] Late=0

[0.0] Played=0

[0.0] Player timestamp =247976

[0.0] AudioStream-Warn:send stats :Count=1460

[0.0] Lost=0

[0.0] Late=0

[0.0] Played=0

[0.0] AudioStream-Warn:Jitter buffer statistics

[0.0] current size= 0.0 seconds

[0.0] delay= 0.0 seconds

[0.0] RecvStream-Warn:Too late, skipping 108 ms...

[0.0] MSM[1]: Stream 4363 new data

[0.0] VM:NCICv=61

[0.0] VM:NMACv=13

[0.0] MSM[1]: Stream 4363 new data

[0.0] AudioStream-Warn:received stats :Count=0

[0.0] Lost=1

[0.0] Late=0

[0.0] Played=0

[0.0] Player timestamp =291400

[0.0] AudioStream-Warn:send stats :Count=1829

[0.0] Lost=0

[0.0] Late=0

[0.0] Played=0

[0.0] AudioStream-Warn:Jitter buffer statistics

[0.0] current size= 0.0 seconds

[0.0] delay= 0.0 seconds

[0.0] MSM[1]: Stream 4363 new data

[0.0] AudioStream-Warn:received stats :Count=0

[0.0] Lost=1

[0.0] Late=0

[0.0] Played=0

[0.0] Player timestamp =331080

[0.0] AudioStream-Warn:send stats :Count=2077

[0.0] Lost=0

[0.0] Late=0

[0.0] Played=0

[0.0] AudioStream-Warn:Jitter buffer statistics

[0.0] current size= 0.0 seconds

[0.0] delay= 0.0 seconds

[0.0] MSM[1]: Stream 4363 new data

[0.0] RecvStream-Warn:Too late, skipping 13 ms...

[0.0] MSM[1]: Stream 4363 new data

[0.0] JVM: bklt @1017843: timer

[0.0] JVM: bklt[1] @1017843: usrIdle 6, usrTime 30, usrAct 1

[0.0] JVM: bklt[1] @1017843: chkIdle 20, currTime 30

[0.0] JVM: bklt @1017843: setTimer 24

[0.0] MSM[1]: Stream 4363 new data

[0.0] AudioStream-Warn:received stats :Count=0

[0.0] Lost=1

[0.0] Late=0

[0.0] Played=0

[0.0] Player timestamp =371184

[0.0] AudioStream-Warn:send stats :Count=2327

[0.0] Lost=0

[0.0] Late=0

[0.0] Played=0

[0.0] AudioStream-Warn:Jitter buffer statistics

[0.0] current size= 0.0 seconds

[0.0] delay= 0.0 seconds

[0.0] MSM[1]: Stream 4363 new data

[0.0] Linphone-Info:Hangup button pressed

[0.0] MSM[1]: Stream 4363 new data

[0.0] MP: h=100663296,s=LG: +sNMD

[0.0] MN: signifyNoMoreData0(100663296)=0

[0.0] MP: h=100663296,s=LG: -sNMD

[0.0] MN: MEDIA_STOPPED received

[0.0] MN: handle=100663296 bitmapHandle=0 thumbnailHandle=2147483647

[0.0] MSM[1]: Stream 4363 new data

[0.0] MP: h=100663296,s=LG: +mErr

[0.0] MP: h=100663296,s=LG: +unlC

[0.0] MN: finalize0(100663296)

[0.0] MN: unload 1

[0.0] MP: h=100663296,s=LG: -> UD

[0.0] MSM[2]: release

[0.0] MP: h=100663296,s=UD: +unld(294):294

[0.0] MSM[2]: RT exit

[0.0] MP: h=100663296,s=UD: +unlC

[0.0] MP: h=100663296,s=UD: -> UD

[0.0] MP: h=100663296,s=UD: -unlC

[0.0] MP: h=100663296,s=UD: LOAD ERR

[0.0] [STREAM] releasing buffer [index: 1] [handle: 4620]

[0.0] MSM[2]: already released

[0.0] MP: h=100663296,s=UD: -unlC

[0.0] MP: h=100663296,s=UD: -mErr

[0.0] RecvStream-Error:player error: [null]

[0.0] UnloadedMediaException

[0.0] No detail message

[0.0] net_rim_media(4D601F70)

[0.0] StreamingMediaPlayer

[0.0] <private>

[0.0] 0xA2D5

[0.0] net_rim_media(4D601F70)

[0.0] StreamingMediaPlayer

[0.0] <private>

[0.0] 0xA1F5

[0.0] net_rim_media(4D601F70)

[0.0] StreamingMediaPlayer

[0.0] doPrefetch

[0.0] 0x96A3

[0.0] net_rim_cldc-26(4D601F17)

[0.0] BasicPlayerImpl

[0.0] prefetch

[0.0] 0x1473

[0.0] jlinphone-4(4EA10847)

[0.0] RecvStream

[0.0] start

[0.0] 0x430

[0.0] jlinphone-4(4EA10847)

[0.0] AudioStreamImpl$2

[0.0] run

[0.0] 0x313

[0.0] net_rim_cldc-2(4D601F17)

[0.0] Thread

[0.0] run

[0.0] 0xAADB

[0.0] RecvStream-Info:Got event error[6]returned ms=0

[0.0] MSM[1]: Stream 4363 new data

[0.0] MP: h=100663296,s=UD: +unld(294):294

[0.0] MSM[1]: Stream 4363 new data

[0.0] MP: h=100663296,s=UD: +unlC

[0.0] MP: h=100663296,s=UD: -> UD

[0.0] MP: h=100663296,s=UD: -unlC

[0.0] MSM[1]: Stream 4363 new data

[0.0] RecvStream-Info:Got event closed[]returned ms=0

[0.0] MSM[1]: Stream 4363 new data

[0.0] SendStream-Warn:Got event recordStopped[49095000]

[0.0] MSM[1]: Stream stopped

[0.0] MSM[1]: Stream 4363 new data

[0.0] AR: remove source 12

[0.0] AR: setAudioMode 32

[0.0] MSM[1]: Stream 4363 new data

[0.0] SendStream-Info:close()

[0.0] RtpBBTransport-Info:Closing rtp connection[192.168.2.2:7078]

[0.0] MSM[1]: Stream 4363 new data

[0.0] SendStream-Warn:Got event stopped[-1]

[0.0] SendStream-Warn:Got event closed[]

[0.0] AudioStream-Warn:received stats :Count=0

[0.0] Lost=1

[0.0] Late=0

[0.0] Played=0

[0.0] AudioStream-Warn:send stats :Count=2454

[0.0] Lost=0

[0.0] Late=0

[0.0] Played=0

[0.0] 1316584580041 :: Initializing Request with Method: BYEand SCN: sip4me.gov.nist.microedition.sip.SipC[0.0] address@hidden

[0.0] 1316584580057 :: getRouteList address@hidden:address@hidden:wsh8d[0.0] 9s0h:address@hidden:3074222

[0.0] 1316584580069 :: ----->>>

[0.0] 1316584580077 :: getRouteList for address@hidden

[0.0] 1316584580086 :: RouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584580101 :: myRouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584580121 :: ----->>>

[0.0] 1316584580129 :: [SipClientConnection] Sending request BYE

[0.0] 1316584580138 :: could not find existing transaction for BYE sip:address@hidden:1738;transport=u[0.0] dp SIP/2.0

[0.0]

[0.0] 1316584580158 :: Getting dialog for address@hidden:4000@00.00.00.00[0.0] :wsh8d9s0h:address@hidden:3074222

[0.0] 1316584580171 :: getRouteList address@hidden:address@hidden:wsh8d[0.0] 9s0h:address@hidden:3074222

[0.0] 1316584580187 :: ----->>>

[0.0] 1316584580195 :: getRouteList for address@hidden

[0.0] 1316584580204 :: RouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584580218 :: myRouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584580232 :: ----->>>

[0.0] 1316584580242 :: getRouteList address@hidden:address@hidden:wsh8d[0.0] 9s0h:address@hidden:3074222

[0.0] 1316584580256 :: ----->>>

[0.0] 1316584580267 :: getRouteList for address@hidden

[0.0] 1316584580277 :: RouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584580295 :: myRouteList = Route: <sip:00.00.00.00;lr=on>

[0.0]

[0.0] 1316584580313 :: ----->>>

[0.0] 1316584580322 :: create hop for 00.00.00.00:5060/udp

[0.0] 1316584580333 :: createMessageChannel 00.00.00.00:5060/udp processor sip4me.gov.nist.siplite.stac[0.0] address@hidden

[0.0] 1316584580353 :: Creating clientTransaction address@hidden

[0.0] 1316584580369 :: Setting Branch id : z9hG4bK383c626397d814a707d79276856c6af0

[0.0] 1316584580383 :: dialog.sendRequest dialog = address@hidden

[0.0] dialogRequest =

[0.0] BYE sip:address@hidden:1738;transport=udp SIP/2.0

[0.0] Call-Id: address@hidden

[0.0] To: <sip:address@hidden>;tag=3074222

[0.0] Via: SIP/2.0/UDP 192.168.2.2:5060;rport;branch=z9hG4bK383c626397d814a707d79276856c6af0

From:	Anurag Mishra
Subject:	[Linphone-developers] one way voice problem
Date:	Wed, 21 Sep 2011 12:06:21 +0530