I registered an ALGO 8186 speaker with a sip extension in Fusionpbx, set the password, and it registered and shows registered
Any time I dial ext 440 (ext for ring) I get a NO RESPONSE error. When I view the logs it says USER/BUSY
I also have ext 441 registered with the same speaker (for page) and I get the same result NO RESPONSE
I set my domain abn14.XXXXX.us in the domain field in the algo speaker
I set the user id: 440
I set the auth id: 400
I set the password: XXXXXX
It registers / but NO RESPONSE whenever dialed
Any ideas where I should look?
In the attached logs I am calling from ext 499 to ring on the algo ext 440
Any time I dial ext 440 (ext for ring) I get a NO RESPONSE error. When I view the logs it says USER/BUSY
I also have ext 441 registered with the same speaker (for page) and I get the same result NO RESPONSE
I set my domain abn14.XXXXX.us in the domain field in the algo speaker
I set the user id: 440
I set the auth id: 400
I set the password: XXXXXX
It registers / but NO RESPONSE whenever dialed
Any ideas where I should look?
In the attached logs I am calling from ext 499 to ring on the algo ext 440
Code:
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us export(dialed_extension=440)
2022-08-06 11:19:18.355488 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [dialed_extension]=[440]
Dialplan: sofia/internal/499@abn14.npusa.us Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination})
Dialplan: sofia/internal/499@abn14.npusa.us Regex (PASS) [local_extension] () =~ // break=on-false
Dialplan: sofia/internal/499@abn14.npusa.us Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/499@abn14.npusa.us Action set(continue_on_fail=true)
Dialplan: sofia/internal/499@abn14.npusa.us Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
Dialplan: sofia/internal/499@abn14.npusa.us Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
Dialplan: sofia/internal/499@abn14.npusa.us Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)})
Dialplan: sofia/internal/499@abn14.npusa.us Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid})
Dialplan: sofia/internal/499@abn14.npusa.us Action set(api_hangup_hook=lua app.lua hangup)
Dialplan: sofia/internal/499@abn14.npusa.us Action export(domain_name=${domain_name})
Dialplan: sofia/internal/499@abn14.npusa.us Action bridge(user/${destination_number}@${domain_name})
Dialplan: sofia/internal/499@abn14.npusa.us Action lua(app.lua failure_handler)
Dialplan: sofia/internal/499@abn14.npusa.us parsing [abn14.npusa.us->voicemail] continue=false
Dialplan: sofia/internal/499@abn14.npusa.us Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false
Dialplan: sofia/internal/499@abn14.npusa.us Action set(record_append=false) INLINE
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(record_append=false)
2022-08-06 11:19:18.355488 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [record_append]=[false]
Dialplan: sofia/internal/499@abn14.npusa.us Action set(voicemail_action=save) INLINE
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(voicemail_action=save)
2022-08-06 11:19:18.355488 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [voicemail_action]=[save]
Dialplan: sofia/internal/499@abn14.npusa.us Action set(voicemail_id=${destination_number}) INLINE
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(voicemail_id=440)
2022-08-06 11:19:18.355488 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [voicemail_id]=[440]
Dialplan: sofia/internal/499@abn14.npusa.us Action set(voicemail_profile=default) INLINE
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(voicemail_profile=default)
2022-08-06 11:19:18.355488 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [voicemail_profile]=[default]
Dialplan: sofia/internal/499@abn14.npusa.us Action lua(app.lua voicemail)
2022-08-06 11:19:18.355488 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/499@abn14.npusa.us) State Change CS_ROUTING -> CS_EXECUTE
2022-08-06 11:19:18.355488 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/499@abn14.npusa.us) State ROUTING going to sleep
2022-08-06 11:19:18.355488 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/499@abn14.npusa.us) Running State Change CS_EXECUTE (Cur 2 Tot 509029)
2022-08-06 11:19:18.355488 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/499@abn14.npusa.us) State EXECUTE
2022-08-06 11:19:18.355488 [DEBUG] mod_sofia.c:209 sofia/internal/499@abn14.npusa.us SOFIA EXECUTE
2022-08-06 11:19:18.355488 [DEBUG] switch_core_state_machine.c:329 sofia/internal/499@abn14.npusa.us Standard EXECUTE
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(caller_id_number=499)
2022-08-06 11:19:18.355488 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [caller_id_number]=[499]
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(RFC2822_DATE=Sat, 06 Aug 2022 11:19:18 -0400)
2022-08-06 11:19:18.355488 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [RFC2822_DATE]=[Sat, 06 Aug 2022 11:19:18 -0400]
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us export(origination_callee_id_name=440)
2022-08-06 11:19:18.355488 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [origination_callee_id_name]=[440]
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us bind_digit_action(local,*5,api:uuid_record,11d51691-53a7-4503-aa68-ed9bd121439e mask /var/lib/freeswitch/recordings/abn14.npusa.us/archive/2022/Aug/06/11d51691-53a7-4503-aa68-ed9bd121439e.wav,both,self)
2022-08-06 11:19:18.355488 [INFO] switch_ivr_async.c:219 Digit parser DPTOOLS: Setting realm to 'local'
2022-08-06 11:19:18.355488 [DEBUG] switch_ivr_async.c:344 Digit parser DPTOOLS: binding *5/local/0 callback: 0x7f0e1f971ea0 data: 0x7f0d26b6c028
2022-08-06 11:19:18.355488 [INFO] switch_ivr_async.c:219 Digit parser DPTOOLS: Setting realm to 'local'
2022-08-06 11:19:18.355488 [DEBUG] switch_ivr_async.c:344 Digit parser DPTOOLS: binding *5/local/0 callback: 0x7f0e1f971ea0 data: 0x7f0d26b6c120
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us bind_digit_action(local,*6,api:uuid_record,11d51691-53a7-4503-aa68-ed9bd121439e unmask /var/lib/freeswitch/recordings/abn14.npusa.us/archive/2022/Aug/06/11d51691-53a7-4503-aa68-ed9bd121439e.wav,both,self)
2022-08-06 11:19:18.355488 [DEBUG] switch_ivr_async.c:344 Digit parser DPTOOLS: binding *6/local/0 callback: 0x7f0e1f971ea0 data: 0x7f0d26b6c458
2022-08-06 11:19:18.355488 [DEBUG] switch_ivr_async.c:344 Digit parser DPTOOLS: binding *6/local/0 callback: 0x7f0e1f971ea0 data: 0x7f0d26b6c550
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(api_on_answer=uuid_record 11d51691-53a7-4503-aa68-ed9bd121439e start /var/lib/freeswitch/recordings/abn14.npusa.us/archive/2022/Aug/06/11d51691-53a7-4503-aa68-ed9bd121439e.wav)
2022-08-06 11:19:18.355488 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [api_on_answer]=[uuid_record 11d51691-53a7-4503-aa68-ed9bd121439e start /var/lib/freeswitch/recordings/abn14.npusa.us/archive/2022/Aug/06/11d51691-53a7-4503-aa68-ed9bd121439e.wav]
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us hash(insert/abn14.npusa.us-last_dial/499/440)
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us eval(not_secure)
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us limit(hash abn14.npusa.us 440 5 error/user_busy)
2022-08-06 11:19:18.355488 [DEBUG] switch_limit.c:124 incr called: abn14.npusa.us_440 max:5, interval:0
2022-08-06 11:19:18.355488 [DEBUG] mod_hash.c:196 Usage for abn14.npusa.us_440 is now 1/5
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(hangup_after_bridge=true)
2022-08-06 11:19:18.355488 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(continue_on_fail=true)
2022-08-06 11:19:18.355488 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [continue_on_fail]=[true]
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us hash(insert/abn14.npusa.us-call_return/440/499)
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us hash(insert/abn14.npusa.us-last_dial_ext/440/11d51691-53a7-4503-aa68-ed9bd121439e)
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(called_party_call_group=)
2022-08-06 11:19:18.375482 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [called_party_call_group]=[UNDEF]
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us hash(insert/abn14.npusa.us-last_dial//11d51691-53a7-4503-aa68-ed9bd121439e)
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us set(api_hangup_hook=lua app.lua hangup)
2022-08-06 11:19:18.375482 [DEBUG] mod_dptools.c:1685 SET sofia/internal/499@abn14.npusa.us [api_hangup_hook]=[lua app.lua hangup]
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us export(domain_name=abn14.npusa.us)
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [domain_name]=[abn14.npusa.us]
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us bridge(user/440@abn14.npusa.us)
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1264 sofia/internal/499@abn14.npusa.us EXPORTING[export_vars] [domain_name]=[abn14.npusa.us] to event
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1264 sofia/internal/499@abn14.npusa.us EXPORTING[export_vars] [call_direction]=[local] to event
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1264 sofia/internal/499@abn14.npusa.us EXPORTING[export_vars] [dialed_extension]=[440] to event
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1264 sofia/internal/499@abn14.npusa.us EXPORTING[export_vars] [origination_callee_id_name]=[440] to event
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1264 sofia/internal/499@abn14.npusa.us EXPORTING[export_vars] [domain_name]=[abn14.npusa.us] to event
2022-08-06 11:19:18.375482 [DEBUG] switch_ivr_originate.c:2272 Parsing global variables
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1264 sofia/internal/499@abn14.npusa.us EXPORTING[export_vars] [domain_name]=[abn14.npusa.us] to event
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1264 sofia/internal/499@abn14.npusa.us EXPORTING[export_vars] [call_direction]=[local] to event
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1264 sofia/internal/499@abn14.npusa.us EXPORTING[export_vars] [dialed_extension]=[440] to event
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1264 sofia/internal/499@abn14.npusa.us EXPORTING[export_vars] [origination_callee_id_name]=[440] to event
2022-08-06 11:19:18.375482 [DEBUG] switch_channel.c:1264 sofia/internal/499@abn14.npusa.us EXPORTING[export_vars] [domain_name]=[abn14.npusa.us] to event
2022-08-06 11:19:18.375482 [DEBUG] switch_ivr_originate.c:2272 Parsing global variables
2022-08-06 11:19:18.375482 [NOTICE] switch_ivr_originate.c:3030 Cannot create outgoing channel of type [error] cause: [USER_BUSY]
2022-08-06 11:19:18.375482 [DEBUG] switch_ivr_originate.c:4026 Originate Resulted in Error Cause: 17 [USER_BUSY]
2022-08-06 11:19:18.375482 [NOTICE] switch_ivr_originate.c:3030 Cannot create outgoing channel of type [user] cause: [USER_BUSY]
2022-08-06 11:19:18.375482 [DEBUG] switch_ivr_originate.c:4026 Originate Resulted in Error Cause: 17 [USER_BUSY]
2022-08-06 11:19:18.375482 [INFO] mod_dptools.c:3643 Originate Failed. Cause: USER_BUSY
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us lua(app.lua failure_handler)
2022-08-06 11:19:18.375482 [DEBUG] switch_cpp.cpp:1209 sofia/internal/499@abn14.npusa.us destroy/unlink session from object
EXECUTE [depth=0] sofia/internal/499@abn14.npusa.us lua(app.lua voicemail)
2022-08-06 11:19:18.395492 [DEBUG] switch_cpp.cpp:773 CoreSession::setVariable('playback_terminators', '#')
2022-08-06 11:19:18.395492 [DEBUG] switch_cpp.cpp:773 CoreSession::setVariable('missed_call', 'true')
2022-08-06 11:19:18.395492 [DEBUG] switch_cpp.cpp:773 CoreSession::setVariable('voicemail_answer_stamp', '2022-08-06 11:19:18')
2022-08-06 11:19:18.395492 [DEBUG] switch_cpp.cpp:773 CoreSession::setVariable('voicemail_answer_epoch', '1659799158')
2022-08-06 11:19:18.395492 [DEBUG] switch_cpp.cpp:1209 sofia/internal/499@abn14.npusa.us destroy/unlink session from object
2022-08-06 11:19:18.395492 [NOTICE] switch_core_state_machine.c:386 sofia/internal/499@abn14.npusa.us has executed the last dialplan instruction, hanging up.
2022-08-06 11:19:18.395492 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/499@abn14.npusa.us [CS_EXECUTE] [NORMAL_CLEARING]
2022-08-06 11:19:18.395492 [DEBUG] mod_hash.c:296 Usage for abn14.npusa.us_440 is now 0
2022-08-06 11:19:18.395492 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/499@abn14.npusa.us) State EXECUTE going to sleep
2022-08-06 11:19:18.395492 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/499@abn14.npusa.us) Running State Change CS_HANGUP (Cur 2 Tot 509029)
2022-08-06 11:19:18.395492 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/499@abn14.npusa.us) Callstate Change RINGING -> HANGUP
2022-08-06 11:19:18.395492 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/499@abn14.npusa.us) State HANGUP
2022-08-06 11:19:18.395492 [DEBUG] mod_sofia.c:453 Channel sofia/internal/499@abn14.npusa.us hanging up, cause: NORMAL_CLEARING
2022-08-06 11:19:18.395492 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
2022-08-06 11:19:18.395492 [DEBUG] switch_core_state_machine.c:60 sofia/internal/499@abn14.npusa.us Standard HANGUP, cause: NORMAL_CLEARING
2022-08-06 11:19:18.395492 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/499@abn14.npusa.us) State HANGUP going to sleep
2022-08-06 11:19:18.395492 [DEBUG] switch_core_state_machine.c:781 Hangup Command with no Session lua(app.lua hangup):
2022-08-06 11:19:18.395492 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/499@abn14.npusa.us) State Change CS_HANGUP -> CS_REPORTING
2022-08-06 11:19:18.395492 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/499@abn14.npusa.us) Running State Change CS_REPORTING (Cur 2 Tot 509029)
2022-08-06 11:19:18.395492 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/499@abn14.npusa.us) State REPORTING
2022-08-06 11:19:18.455493 [DEBUG] switch_core_state_machine.c:174 sofia/internal/499@abn14.npusa.us Standard REPORTING, cause: NORMAL_CLEARING
2022-08-06 11:19:18.455493 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/499@abn14.npusa.us) State REPORTING going to sleep
2022-08-06 11:19:18.455493 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/499@abn14.npusa.us) State Change CS_REPORTING -> CS_DESTROY
2022-08-06 11:19:18.455493 [DEBUG] switch_core_session.c:1736 Session 509029 (sofia/internal/499@abn14.npusa.us) Locked, Waiting on external entities
2022-08-06 11:19:18.455493 [NOTICE] switch_core_session.c:1754 Session 509029 (sofia/internal/499@abn14.npusa.us) Ended
2022-08-06 11:19:18.455493 [NOTICE] switch_core_session.c:1758 Close Channel sofia/internal/499@abn14.npusa.us [CS_DESTROY]