[Freeswitch-users] MOH while running bind_digit_action or bind_meta_app

[Dmitry Sytchev]

Hi!

I faced strange problem. After running bind_meta_app or
bind_digit_action with att_xfer transferee listens to MOH, but when
transferer cancels xfer with #, there are no music on hold for
transferee on second run of att_xfer . It doesn't even try to launch
playback of MOH.

I use local channel in att_xfer to allow attended transfer to
arbitrary extensions, so I had to add
loopback_bowout/bowout_on_execute=false variables, app_xfer did'nt
work as expected at all without them. Could this cause such a strange
MOH behavior?

first try:

2011-01-17 15:09:34.044500 [DEBUG] switch_rtp.c:3098 RTP RECV DTMF *:884
2011-01-17 15:09:34.264674 [DEBUG] switch_rtp.c:3098 RTP RECV DTMF 7:804
2011-01-17 15:09:34.264674 [DEBUG] switch_ivr_async.c:2866
sofia/local/sytchev2 at 85.114.2.200 Processing meta digit '7'
[execute_extension::att_xfer XML features]
2011-01-17 15:09:34.264674 [DEBUG] switch_core_session.c:954 Send
signal sofia/local/sytchev2 at 85.114.2.200 [BREAK]
2011-01-17 15:09:34.264674 [DEBUG] switch_core_session.c:709 Send
signal sofia/local/sytchev2 at 85.114.2.200 [BREAK]
2011-01-17 15:09:34.404746 [DEBUG] switch_core_session.c:954 Send
signal sofia/local/sip:sytchev3 at 192.168.4.130 [BREAK]
2011-01-17 15:09:34.424718 [DEBUG] switch_core_session.c:709 Send
signal sofia/local/sip:sytchev3 at 192.168.4.130 [BREAK]
2011-01-17 15:09:34.564684 [DEBUG] switch_ivr.c:563
sofia/local/sip:sytchev3 at 192.168.4.130 Command Execute
playback(local_stream://moh)
EXECUTE sofia/local/sip:sytchev3 at 192.168.4.130 playback(local_stream://moh)
2011-01-17 15:09:34.564684 [WARNING] mod_local_stream.c:393 Unknown
source moh, trying 'default'
2011-01-17 15:09:34.564684 [DEBUG] mod_local_stream.c:421 Opening
Stream [default] 8000hz
2011-01-17 15:09:34.564684 [DEBUG] switch_ivr_play_say.c:1236 Codec
Activated L16 at 8000hz 1 channels 20ms
2011-01-17 15:09:34.564684 [DEBUG] switch_ivr.c:563
sofia/local/sytchev2 at 85.114.2.200 Command Execute
execute_extension(att_xfer XML features)
EXECUTE sofia/local/sytchev2 at 85.114.2.200 execute_extension(att_xfer
XML features)
2011-01-17 15:09:34.565718 [INFO] mod_dialplan_xml.c:331 Processing
8126778008 <sytchev2>->att_xfer in context features
Dialplan: sofia/local/sytchev2 at 85.114.2.200 parsing [features->dx]
continue=false
Dialplan: sofia/local/sytchev2 at 85.114.2.200 Regex (FAIL) [dx]
destination_number(att_xfer) =~ /^dx$/ break=on-false
Dialplan: sofia/local/sytchev2 at 85.114.2.200 parsing
[features->att_xfer] continue=false
Dialplan: sofia/local/sytchev2 at 85.114.2.200 Regex (PASS) [att_xfer]
destination_number(att_xfer) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/local/sytchev2 at 85.114.2.200 Action read(3 11
'tone_stream://%(10000,0,350,440)' digits 30000 #)
Dialplan: sofia/local/sytchev2 at 85.114.2.200 Action
set(origination_cancel_key=#)
Dialplan: sofia/local/sytchev2 at 85.114.2.200 Action
att_xfer(loopback/${digits}/route_customer_global)
2011-01-17 15:09:34.565718 [NOTICE] switch_core_session.c:2137 Execute
read(3 11 'tone_stream://%(10000,0,350,440)' digits 30000 #)
EXECUTE sofia/local/sytchev2 at 85.114.2.200 read(3 11
'tone_stream://%(10000,0,350,440)' digits 30000 #)
2011-01-17 15:09:34.567713 [DEBUG] switch_ivr_play_say.c:1236 Codec
Activated L16 at 8000hz 1 channels 20ms
2011-01-17 15:09:44.564756 [DEBUG] switch_ivr_play_say.c:1573 done playing file


Second try:

2011-01-17 15:10:10.643923 [DEBUG] switch_rtp.c:3098 RTP RECV DTMF *:804
2011-01-17 15:10:10.964291 [DEBUG] switch_rtp.c:3098 RTP RECV DTMF 7:804
2011-01-17 15:10:10.964291 [DEBUG] switch_ivr_async.c:2866
sofia/local/sytchev2 at 85.114.2.200 Processing meta digit '7'
[execute_extension::att_xfer XML features]
2011-01-17 15:10:10.964291 [DEBUG] switch_core_session.c:954 Send
signal sofia/local/sytchev2 at 85.114.2.200 [BREAK]
2011-01-17 15:10:10.964291 [DEBUG] switch_core_session.c:709 Send
signal sofia/local/sytchev2 at 85.114.2.200 [BREAK]
2011-01-17 15:10:11.104279 [DEBUG] switch_ivr.c:563
sofia/local/sytchev2 at 85.114.2.200 Command Execute
execute_extension(att_xfer XML features)
EXECUTE sofia/local/sytchev2 at 85.114.2.200 execute_extension(att_xfer
XML features)
2011-01-17 15:10:11.104279 [INFO] mod_dialplan_xml.c:331 Processing
8126778008 <sytchev2>->att_xfer in context features
Dialplan: sofia/local/sytchev2 at 85.114.2.200 parsing [features->dx]
continue=false
Dialplan: sofia/local/sytchev2 at 85.114.2.200 Regex (FAIL) [dx]
destination_number(att_xfer) =~ /^dx$/ break=on-false
Dialplan: sofia/local/sytchev2 at 85.114.2.200 parsing
[features->att_xfer] continue=false
Dialplan: sofia/local/sytchev2 at 85.114.2.200 Regex (PASS) [att_xfer]
destination_number(att_xfer) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/local/sytchev2 at 85.114.2.200 Action read(3 11
'tone_stream://%(10000,0,350,440)' digits 30000 #)
Dialplan: sofia/local/sytchev2 at 85.114.2.200 Action
set(origination_cancel_key=#)
Dialplan: sofia/local/sytchev2 at 85.114.2.200 Action
att_xfer(loopback/${digits}/route_customer_global)
2011-01-17 15:10:11.104279 [NOTICE] switch_core_session.c:2137 Execute
read(3 11 'tone_stream://%(10000,0,350,440)' digits 30000 #)
EXECUTE sofia/local/sytchev2 at 85.114.2.200 read(3 11
'tone_stream://%(10000,0,350,440)' digits 30000 #)
2011-01-17 15:10:11.106278 [DEBUG] switch_ivr_play_say.c:1236 Codec
Activated L16 at 8000hz 1 channels 20ms
2011-01-17 15:10:21.104543 [DEBUG] switch_ivr_play_say.c:1573 done playing file


-- 
Best regards,

Dmitry Sytchev,
IT Engineer