Isaac Xiao
2006-Jun-26  07:32 UTC
[Asterisk-Users] 1.2.9.1 SIP/Local/Queue behaviours weird
Hi,
 
Does any one experience that SIP phone to SIP phone (Polycom phone)
calls can't hear each other, but Monitor application records both end's
voices. It also happens in group pickup calls. Zap calls to queue (Local
channel) also experience this problem (sometimes, our SIP phone can't
hear any voice from incoming Zap calls when pickup, sometimes this
happens after 10-50 seconds' talk). It is weird.
 
Jun 26 16:53:35 VERBOSE[8290] logger.c: -- Executing
Dial("Local/7188@from-internal-7036,2", "SIP/7188|30|trWwT")
in new
stack
Jun 26 16:53:35 DEBUG[8290] chan_sip.c: Setting NAT on RTP to 0
Jun 26 16:53:35 DEBUG[8290] chan_sip.c: Setting NAT on VRTP to 0
Jun 26 16:53:35 DEBUG[8290] chan_sip.c: Outgoing Call for 7188
Jun 26 16:53:35 VERBOSE[8290] logger.c: -- Called 7188
Jun 26 16:53:35 VERBOSE[8287] logger.c: --
Local/7188@from-internal-7036,1 is ringing
Jun 26 16:53:35 DEBUG[2966] chan_sip.c: (Provisional) Stopping
retransmission (but retaining packet) on
'2978d85271fdaf3d0ac2e5b244e78773@192.168.2.66' Request 102: Found
Jun 26 16:53:35 DEBUG[2966] chan_sip.c: (Provisional) Stopping
retransmission (but retaining packet) on
'2978d85271fdaf3d0ac2e5b244e78773@192.168.2.66' Request 102: Found
Jun 26 16:53:35 DEBUG[2957] channel.c: Avoiding initial deadlock for
'SIP/7188-6b1f'
Jun 26 16:53:35 VERBOSE[8290] logger.c: -- SIP/7188-6b1f is ringing
Jun 26 16:53:37 DEBUG[2966] chan_sip.c: Acked pending invite 102
Jun 26 16:53:37 DEBUG[2966] chan_sip.c: Stopping retransmission on
'2978d85271fdaf3d0ac2e5b244e78773@192.168.2.66' of Request 102: Match
Found
Jun 26 16:53:37 DEBUG[2966] chan_sip.c: build_route: Contact hop: 
Jun 26 16:53:37 VERBOSE[8290] logger.c: -- SIP/7188-6b1f answered
Local/7188@from-internal-7036,2
Jun 26 16:53:37 DEBUG[8287] app_queue.c: Dunno what to do with control
type -1
Jun 26 16:53:37 VERBOSE[8287] logger.c: --
Local/7188@from-internal-7036,1 answered Zap/13-1
Jun 26 16:53:37 DEBUG[8287] chan_zap.c: Set option TONE VERIFY, mode:
MUTECONF(1) on Zap/13-1
Jun 26 16:53:37 VERBOSE[8287] logger.c: -- Stopped music on hold on
Zap/13-1
Jun 26 16:53:37 DEBUG[8287] channel.c: Scheduling timer at 0 sample
intervals
Jun 26 16:54:02 DEBUG[8290] channel.c: Didn't get a frame from channel:
SIP/7188-6b1f
Jun 26 16:54:02 DEBUG[8290] channel.c: Bridge stops bridging channels
Local/7188@from-internal-7036,2 and SIP/7188-6b1f
Jun 26 16:54:02 DEBUG[8290] chan_sip.c: update_call_counter(7188) -
decrement call limit counter
Jun 26 16:54:02 DEBUG[8290] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Jun 26 16:54:02 VERBOSE[8290] logger.c: == Spawn extension (macro-dial,
s, 10) exited non-zero on 'Local/7188@from-internal-7036,2' in macro
'dial'
Jun 26 16:54:02 VERBOSE[8290] logger.c: == Spawn extension (macro-dial,
s, 10) exited non-zero on 'Local/7188@from-internal-7036,2' in macro
'exten-vm'
Jun 26 16:54:02 VERBOSE[8290] logger.c: == Spawn extension (macro-dial,
s, 10) exited non-zero on 'Local/7188@from-internal-7036,2'
Jun 26 16:54:02 DEBUG[8290] res_monitor.c: monitor executing ( nice -n
19 soxmix
"/var/spool/asterisk/monitor/20060626-165333-1151304813.901-in.gsm"
"/var/spool/asterisk/monitor/20060626-165333-1151304813.901-out.gsm"
"/var/spool/asterisk/monitor/20060626-165333-1151304813.901.gsm"
&& rm
-f "/var/spool/asterisk/monitor/20060626-165333-1151304813.901-"* )
&
Jun 26 16:54:02 DEBUG[8287] channel.c: Didn't get a frame from channel:
Local/7188@from-internal-7036,1
Jun 26 16:54:02 DEBUG[8287] channel.c: Bridge stops bridging channels
Zap/13-1 and Local/7188@from-internal-7036,1
Jun 26 16:54:02 VERBOSE[8287] logger.c: == Spawn extension (ext-queues,
7141, 6) exited non-zero on 'Zap/13-1'
Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Set option AUDIO MODE, value:
ON(1) on Zap/13-1
Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Hangup: channel: 13 index = 0,
normal = 27, callwait = -1, thirdcall = -1
Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Not yet hungup... Calling hangup
once with icause, and clearing call
Jun 26 16:54:02 DEBUG[8287] chan_zap.c: disabled echo cancellation on
channel 13
Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Set option TDD MODE, value:
OFF(0) on Zap/13-1
Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Updated conferencing on 13, with
0 conference users
Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Set option AUDIO MODE, value:
OFF(0) on Zap/13-1
Jun 26 16:54:02 DEBUG[8287] chan_zap.c: disabled echo cancellation on
channel 13
Jun 26 16:54:02 VERBOSE[8287] logger.c: -- Hungup 'Zap/13-1'
 
Isaac Xiao
 
-------------- next part --------------
An HTML attachment was scrubbed...
URL:
http://lists.digium.com/pipermail/asterisk-users/attachments/20060626/092a4a5f/attachment.htm
I have seen this when Polycom has to communicate with none polycom phones and a transfer is initiated to a polycom, unless the Polycom presses Hold and then unhold, there is only one way audio, this is without NAT involved. There might also be other cases when this happens. My workaround is to add canreinvite=no On 6/26/06, Isaac Xiao <isaac.x@kvbkunlun.com> wrote:> > > > > Hi, > > > > Does any one experience that SIP phone to SIP phone (Polycom phone) calls > can't hear each other, but Monitor application records both end's voices. It > also happens in group pickup calls. Zap calls to queue (Local channel) also > experience this problem (sometimes, our SIP phone can't hear any voice from > incoming Zap calls when pickup, sometimes this happens after 10-50 seconds' > talk). It is weird. > > > > Jun 26 16:53:35 VERBOSE[8290] logger.c: -- Executing > Dial("Local/7188@from-internal-7036,2", "SIP/7188|30|trWwT") in new stack > Jun 26 16:53:35 DEBUG[8290] chan_sip.c: Setting NAT on RTP to 0 > Jun 26 16:53:35 DEBUG[8290] chan_sip.c: Setting NAT on VRTP to 0 > Jun 26 16:53:35 DEBUG[8290] chan_sip.c: Outgoing Call for 7188 > Jun 26 16:53:35 VERBOSE[8290] logger.c: -- Called 7188 > Jun 26 16:53:35 VERBOSE[8287] logger.c: -- Local/7188@from-internal-7036,1 > is ringing > Jun 26 16:53:35 DEBUG[2966] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '2978d85271fdaf3d0ac2e5b244e78773@192.168.2.66' Request > 102: Found > Jun 26 16:53:35 DEBUG[2966] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '2978d85271fdaf3d0ac2e5b244e78773@192.168.2.66' Request > 102: Found > Jun 26 16:53:35 DEBUG[2957] channel.c: Avoiding initial deadlock for > 'SIP/7188-6b1f' > Jun 26 16:53:35 VERBOSE[8290] logger.c: -- SIP/7188-6b1f is ringing > Jun 26 16:53:37 DEBUG[2966] chan_sip.c: Acked pending invite 102 > Jun 26 16:53:37 DEBUG[2966] chan_sip.c: Stopping retransmission on > '2978d85271fdaf3d0ac2e5b244e78773@192.168.2.66' of Request > 102: Match Found > Jun 26 16:53:37 DEBUG[2966] chan_sip.c: build_route: Contact hop: > Jun 26 16:53:37 VERBOSE[8290] logger.c: -- SIP/7188-6b1f answered > Local/7188@from-internal-7036,2 > Jun 26 16:53:37 DEBUG[8287] app_queue.c: Dunno what to do with control type > -1 > Jun 26 16:53:37 VERBOSE[8287] logger.c: -- Local/7188@from-internal-7036,1 > answered Zap/13-1 > Jun 26 16:53:37 DEBUG[8287] chan_zap.c: Set option TONE VERIFY, mode: > MUTECONF(1) on Zap/13-1 > Jun 26 16:53:37 VERBOSE[8287] logger.c: -- Stopped music on hold on > Zap/13-1 > Jun 26 16:53:37 DEBUG[8287] channel.c: Scheduling timer at 0 sample > intervals > Jun 26 16:54:02 DEBUG[8290] channel.c: Didn't get a frame from channel: > SIP/7188-6b1f > Jun 26 16:54:02 DEBUG[8290] channel.c: Bridge stops bridging channels > Local/7188@from-internal-7036,2 and SIP/7188-6b1f > Jun 26 16:54:02 DEBUG[8290] chan_sip.c: update_call_counter(7188) - > decrement call limit counter > Jun 26 16:54:02 DEBUG[8290] app_dial.c: Exiting with DIALSTATUS=ANSWER. > Jun 26 16:54:02 VERBOSE[8290] logger.c: == Spawn extension (macro-dial, s, > 10) exited non-zero on 'Local/7188@from-internal-7036,2' in macro 'dial' > Jun 26 16:54:02 VERBOSE[8290] logger.c: == Spawn extension (macro-dial, s, > 10) exited non-zero on 'Local/7188@from-internal-7036,2' in macro 'exten-vm' > Jun 26 16:54:02 VERBOSE[8290] logger.c: == Spawn extension (macro-dial, s, > 10) exited non-zero on 'Local/7188@from-internal-7036,2' > Jun 26 16:54:02 DEBUG[8290] res_monitor.c: monitor executing ( nice -n 19 > soxmix > "/var/spool/asterisk/monitor/20060626-165333-1151304813.901-in.gsm" > "/var/spool/asterisk/monitor/20060626-165333-1151304813.901-out.gsm" > "/var/spool/asterisk/monitor/20060626-165333-1151304813.901.gsm" > && rm -f > "/var/spool/asterisk/monitor/20060626-165333-1151304813.901-"* > ) & > Jun 26 16:54:02 DEBUG[8287] channel.c: Didn't get a frame from channel: > Local/7188@from-internal-7036,1 > Jun 26 16:54:02 DEBUG[8287] channel.c: Bridge stops bridging channels > Zap/13-1 and Local/7188@from-internal-7036,1 > Jun 26 16:54:02 VERBOSE[8287] logger.c: == Spawn extension (ext-queues, > 7141, 6) exited non-zero on 'Zap/13-1' > Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Set option AUDIO MODE, value: ON(1) > on Zap/13-1 > Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Hangup: channel: 13 index = 0, > normal = 27, callwait = -1, thirdcall = -1 > Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Not yet hungup... Calling hangup > once with icause, and clearing call > Jun 26 16:54:02 DEBUG[8287] chan_zap.c: disabled echo cancellation on > channel 13 > Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Set option TDD MODE, value: OFF(0) > on Zap/13-1 > Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Updated conferencing on 13, with 0 > conference users > Jun 26 16:54:02 DEBUG[8287] chan_zap.c: Set option AUDIO MODE, value: > OFF(0) on Zap/13-1 > Jun 26 16:54:02 DEBUG[8287] chan_zap.c: disabled echo cancellation on > channel 13 > Jun 26 16:54:02 VERBOSE[8287] logger.c: -- Hungup 'Zap/13-1' > > > > Isaac Xiao > > > _______________________________________________ > --Bandwidth and Colocation provided by Easynews.com -- > > Asterisk-Users mailing list > To UNSUBSCRIBE or update options visit: > > http://lists.digium.com/mailman/listinfo/asterisk-users > > >
Isaac Xiao
2006-Jun-26  16:37 UTC
[Asterisk-Users] 1.2.9.1 SIP/Local/Queue behaviours weird
Our phone all Polycom phone and we use *'s transfer function rather than phone's one. We also has canreinvite=no. I believe that it is something wrong with Call Bridge between two channels(ZAP/SIP/Local). Before we didn't disable autofallthrough (default is yes), we also experienced call drop.>I have seen this when Polycom has to communicate with none polycom >phones and a transfer is initiated to a polycom, unless the Polycom >presses Hold and then unhold, there is only one way audio, this is >without NAT involved. There might also be other cases when this >happens. My workaround is to add canreinvite=noIsaac Xiao