[Freeswitch-users] Multiple gateways dial string and user busy

Fri Mar 12 09:30:34 PST 2010

2010/2/26 Kristian Kielhofner <kristian.kielhofner at gmail.com>:
> http://wiki.freeswitch.org/wiki/Channel_Variables#failure_causes

Doesn't help =( After getting first USER_BUSY it still makes call via
second part of dialstring.

originate {failure_causes=USER_BUSY}sofia/gateway/panas111/223|sofia/gateway/panas112/223
&playback(local_stream://moh)

Relevant log part is following:

2010-03-12 20:26:00.490396 [DEBUG] switch_ivr_originate.c:1176
variable string 0 = [failure_causes=USER_BUSY]
2010-03-12 20:26:00.494260 [NOTICE] switch_channel.c:613 New Channel
sofia/internal/223 [35e8382d-e3fd-4190-b625-47f61ae6be16]
2010-03-12 20:26:00.494260 [DEBUG] mod_sofia.c:3020
(sofia/internal/223) State Change CS_NEW -> CS_INIT
freeswitch at internal> 2010-03-12 20:26:00.494260 [DEBUG]
switch_core_session.c:1000 Send signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.494260 [DEBUG] switch_core_state_machine.c:306
(sofia/internal/223) Running State Change CS_INIT
2010-03-12 20:26:00.494260 [DEBUG] switch_core_state_machine.c:330
(sofia/internal/223) State INIT
2010-03-12 20:26:00.494260 [DEBUG] mod_sofia.c:83 sofia/internal/223
SOFIA INIT
2010-03-12 20:26:00.494260 [DEBUG] mod_sofia.c:111
(sofia/internal/223) State Change CS_INIT -> CS_ROUTING
2010-03-12 20:26:00.494260 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.494260 [DEBUG] switch_core_state_machine.c:330
(sofia/internal/223) State INIT going to sleep
2010-03-12 20:26:00.494260 [DEBUG] switch_core_state_machine.c:306
(sofia/internal/223) Running State Change CS_ROUTING
2010-03-12 20:26:00.494260 [DEBUG] switch_core_state_machine.c:333
(sofia/internal/223) State ROUTING
2010-03-12 20:26:00.494260 [DEBUG] mod_sofia.c:130 sofia/internal/223
SOFIA ROUTING
2010-03-12 20:26:00.494260 [DEBUG] switch_ivr_originate.c:66
(sofia/internal/223) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2010-03-12 20:26:00.494260 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.494260 [DEBUG] switch_core_state_machine.c:333
(sofia/internal/223) State ROUTING going to sleep
2010-03-12 20:26:00.494260 [DEBUG] switch_core_state_machine.c:306
(sofia/internal/223) Running State Change CS_CONSUME_MEDIA
2010-03-12 20:26:00.494260 [DEBUG] switch_core_state_machine.c:352
(sofia/internal/223) State CONSUME_MEDIA
2010-03-12 20:26:00.494260 [DEBUG] switch_core_state_machine.c:352
(sofia/internal/223) State CONSUME_MEDIA going to sleep
2010-03-12 20:26:00.494260 [DEBUG] sofia.c:3583 Channel
sofia/internal/223 entering state [calling][0]
2010-03-12 20:26:00.517975 [DEBUG] sofia.c:3583 Channel
sofia/internal/223 entering state [calling][0]
2010-03-12 20:26:00.550450 [DEBUG] sofia.c:3583 Channel
sofia/internal/223 entering state [terminated][486]
2010-03-12 20:26:00.550450 [NOTICE] sofia.c:4149 Hangup
sofia/internal/223 [CS_CONSUME_MEDIA] [USER_BUSY]
2010-03-12 20:26:00.550450 [DEBUG] switch_channel.c:1896 Send signal
sofia/internal/223 [KILL]
2010-03-12 20:26:00.550450 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:451
thread mismatch skipping state handler.
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:306
(sofia/internal/223) Running State Change CS_HANGUP
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:478
(sofia/internal/223) State HANGUP
2010-03-12 20:26:00.550450 [DEBUG] mod_sofia.c:318 sofia/internal/223
Overriding SIP cause 486 with 486 from the other leg
2010-03-12 20:26:00.550450 [DEBUG] mod_sofia.c:350 Channel
sofia/internal/223 hanging up, cause: USER_BUSY
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:46
sofia/internal/223 Standard HANGUP, cause: USER_BUSY
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:478
(sofia/internal/223) State HANGUP going to sleep
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:325
(sofia/internal/223) State Change CS_HANGUP -> CS_REPORTING
2010-03-12 20:26:00.550450 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:306
(sofia/internal/223) Running State Change CS_REPORTING
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:569
(sofia/internal/223) State REPORTING
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:53
sofia/internal/223 Standard REPORTING, cause: USER_BUSY
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:569
(sofia/internal/223) State REPORTING going to sleep
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:319
(sofia/internal/223) State Change CS_REPORTING -> CS_DESTROY
2010-03-12 20:26:00.550450 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.550450 [DEBUG] switch_core_session.c:1137 Session
44644 (sofia/internal/223) Locked, Waiting on external entities
2010-03-12 20:26:00.550450 [DEBUG] switch_ivr_originate.c:2467
Originate Resulted in Error Cause: 17 [USER_BUSY]
2010-03-12 20:26:00.550450 [NOTICE] switch_core_session.c:1155 Session
44644 (sofia/internal/223) Ended
2010-03-12 20:26:00.550450 [NOTICE] switch_core_session.c:1157 Close
Channel sofia/internal/223 [CS_DESTROY]
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:415
(sofia/internal/223) Running State Change CS_DESTROY
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:426
(sofia/internal/223) State DESTROY
2010-03-12 20:26:00.550450 [DEBUG] mod_sofia.c:267 sofia/internal/223
SOFIA DESTROY
2010-03-12 20:26:00.550450 [NOTICE] switch_channel.c:613 New Channel
sofia/internal/223 [c8fdd3e0-d905-4f02-8955-d21d0bddf925]
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:60
sofia/internal/223 Standard DESTROY
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:426
(sofia/internal/223) State DESTROY going to sleep
2010-03-12 20:26:00.550450 [DEBUG] mod_sofia.c:3020
(sofia/internal/223) State Change CS_NEW -> CS_INIT
2010-03-12 20:26:00.550450 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:306
(sofia/internal/223) Running State Change CS_INIT
2010-03-12 20:26:00.550450 [DEBUG] switch_core_state_machine.c:330
(sofia/internal/223) State INIT
2010-03-12 20:26:00.550450 [DEBUG] mod_sofia.c:83 sofia/internal/223
SOFIA INIT
2010-03-12 20:26:00.554707 [DEBUG] mod_sofia.c:111
(sofia/internal/223) State Change CS_INIT -> CS_ROUTING
2010-03-12 20:26:00.554707 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.554707 [DEBUG] sofia.c:3583 Channel
sofia/internal/223 entering state [calling][0]
2010-03-12 20:26:00.554707 [DEBUG] switch_core_state_machine.c:330
(sofia/internal/223) State INIT going to sleep
2010-03-12 20:26:00.554707 [DEBUG] switch_core_state_machine.c:306
(sofia/internal/223) Running State Change CS_ROUTING
2010-03-12 20:26:00.554707 [DEBUG] switch_core_state_machine.c:333
(sofia/internal/223) State ROUTING
2010-03-12 20:26:00.554707 [DEBUG] mod_sofia.c:130 sofia/internal/223
SOFIA ROUTING
2010-03-12 20:26:00.554707 [DEBUG] switch_ivr_originate.c:66
(sofia/internal/223) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2010-03-12 20:26:00.554707 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.554707 [DEBUG] switch_core_state_machine.c:333
(sofia/internal/223) State ROUTING going to sleep
2010-03-12 20:26:00.554707 [DEBUG] switch_core_state_machine.c:306
(sofia/internal/223) Running State Change CS_CONSUME_MEDIA
2010-03-12 20:26:00.554707 [DEBUG] switch_core_state_machine.c:352
(sofia/internal/223) State CONSUME_MEDIA
2010-03-12 20:26:00.554707 [DEBUG] switch_core_state_machine.c:352
(sofia/internal/223) State CONSUME_MEDIA going to sleep
2010-03-12 20:26:00.578400 [DEBUG] sofia.c:3583 Channel
sofia/internal/223 entering state [calling][0]
2010-03-12 20:26:00.614451 [DEBUG] sofia.c:3583 Channel
sofia/internal/223 entering state [terminated][486]
2010-03-12 20:26:00.614451 [NOTICE] sofia.c:4149 Hangup
sofia/internal/223 [CS_CONSUME_MEDIA] [USER_BUSY]
2010-03-12 20:26:00.614451 [DEBUG] switch_channel.c:1896 Send signal
sofia/internal/223 [KILL]
2010-03-12 20:26:00.614451 [DEBUG] switch_core_state_machine.c:306
(sofia/internal/223) Running State Change CS_HANGUP
2010-03-12 20:26:00.614451 [DEBUG] switch_core_state_machine.c:478
(sofia/internal/223) State HANGUP
2010-03-12 20:26:00.614451 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.614451 [DEBUG] switch_core_state_machine.c:451
thread mismatch skipping state handler.
2010-03-12 20:26:00.618419 [DEBUG] mod_sofia.c:318 sofia/internal/223
Overriding SIP cause 486 with 486 from the other leg
2010-03-12 20:26:00.618419 [DEBUG] mod_sofia.c:350 Channel
sofia/internal/223 hanging up, cause: USER_BUSY
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:46
sofia/internal/223 Standard HANGUP, cause: USER_BUSY
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:478
(sofia/internal/223) State HANGUP going to sleep
2010-03-12 20:26:00.618419 [DEBUG] switch_ivr_originate.c:2467
Originate Resulted in Error Cause: 17 [USER_BUSY]
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:325
(sofia/internal/223) State Change CS_HANGUP -> CS_REPORTING
2010-03-12 20:26:00.618419 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:306
(sofia/internal/223) Running State Change CS_REPORTING
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:569
(sofia/internal/223) State REPORTING
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:53
sofia/internal/223 Standard REPORTING, cause: USER_BUSY
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:569
(sofia/internal/223) State REPORTING going to sleep
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:319
(sofia/internal/223) State Change CS_REPORTING -> CS_DESTROY
2010-03-12 20:26:00.618419 [DEBUG] switch_core_session.c:1000 Send
signal sofia/internal/223 [BREAK]
2010-03-12 20:26:00.618419 [DEBUG] switch_core_session.c:1137 Session
44645 (sofia/internal/223) Locked, Waiting on external entities
2010-03-12 20:26:00.618419 [NOTICE] switch_core_session.c:1155 Session
44645 (sofia/internal/223) Ended
2010-03-12 20:26:00.618419 [NOTICE] switch_core_session.c:1157 Close
Channel sofia/internal/223 [CS_DESTROY]
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:415
(sofia/internal/223) Running State Change CS_DESTROY
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:426
(sofia/internal/223) State DESTROY
2010-03-12 20:26:00.618419 [DEBUG] mod_sofia.c:267 sofia/internal/223
SOFIA DESTROY
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:60
sofia/internal/223 Standard DESTROY
2010-03-12 20:26:00.618419 [DEBUG] switch_core_state_machine.c:426
(sofia/internal/223) State DESTROY going to sleep