This is a call from an internal registered user to another one.
2016-08-12 18:24:47.965022 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/401942957943@sip.cvraptzg6qbq5lmj) Running State Change CS_NEW
2016-08-12 18:24:47.965022 [DEBUG] sofia.c:9099 sofia/internal/401942957943@sip.cvraptzg6qbq5lmj receiving invite from
10.10.250.3:5060 version: 1.7.0 git bd371f4 2015-08-24 13:36:23Z 64bit
2016-08-12 18:24:47.965022 [DEBUG] sofia.c:9266 IP 10.10.250.3 Rejected by acl "domains". Falling back to Digest auth.
2016-08-12 18:24:47.965022 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/401942957943@sip.cvraptzg6qbq5lmj) State NEW
2016-08-12 18:24:47.965022 [DEBUG] sofia.c:2147 detaching session 6614a55f-bbf1-4b9e-8a25-d5e31e576bb4
2016-08-12 18:24:48.025008 [DEBUG] sofia.c:2255 Re-attaching to session 6614a55f-bbf1-4b9e-8a25-d5e31e576bb4
2016-08-12 18:24:48.045005 [DEBUG] sofia.c:9099 sofia/internal/401942957943@sip.cvraptzg6qbq5lmj receiving invite from
10.10.250.3:5060 version: 1.7.0 git bd371f4 2015-08-24 13:36:23Z 64bit
2016-08-12 18:24:48.045005 [DEBUG] sofia.c:9266 IP 10.10.250.3 Rejected by acl "domains". Falling back to Digest auth.
2016-08-12 18:24:48.045005 [NOTICE] mod_python.c:212 Invoking py module: switch_connector.handler
2016-08-12 18:24:49.124969 [DEBUG] mod_python.c:283 Call python script
.........
2016-08-12 18:24:49.304970 [DEBUG] mod_sofia.c:141 sofia/internal/401942957943@sip.cvraptzg6qbq5lmj SOFIA ROUTING
2016-08-12 18:24:49.304970 [DEBUG] switch_core_state_machine.c:166 sofia/internal/401942957943@sip.cvraptzg6qbq5lmj Standard ROUTING
2016-08-12 18:24:49.304970 [INFO] mod_dialplan_xml.c:637 Processing 401942957943 <401942957943>->2320 in context public
From there the extension will not be found and the call will be dropped. The next call I try will be successful.
Below is a successful one.
-------------------------------------------------------------------------------------------------------------------
2016-08-12 18:31:34.205008 [NOTICE] switch_channel.c:1089 New Channel sofia/internal/401942957943@sip.cvraptzg6qbq5lmj [d3eeae13-a4e6-4cf1-b311-4cff5bff4523]
2016-08-12 18:31:34.205008 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/401942957943@sip.cvraptzg6qbq5lmj) Running State Change CS_NEW
2016-08-12 18:31:34.205008 [DEBUG] sofia.c:9099 sofia/internal/401942957943@sip.cvraptzg6qbq5lmj receiving invite from
10.10.250.3:5060 version: 1.7.0 git bd371f4 2015-08-24 13:36:23Z 64bit
2016-08-12 18:31:34.205008 [DEBUG] sofia.c:9266 IP 10.10.250.3 Rejected by acl "domains". Falling back to Digest auth.
2016-08-12 18:31:34.205008 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/401942957943@sip.cvraptzg6qbq5lmj) State NEW
2016-08-12 18:31:34.205008 [DEBUG] sofia.c:2147 detaching session d3eeae13-a4e6-4cf1-b311-4cff5bff4523
2016-08-12 18:31:34.804982 [DEBUG] sofia.c:2255 Re-attaching to session d3eeae13-a4e6-4cf1-b311-4cff5bff4523
2016-08-12 18:31:34.804982 [DEBUG] sofia.c:9099 sofia/internal/401942957943@sip.cvraptzg6qbq5lmj receiving invite from
10.10.250.3:5060 version: 1.7.0 git bd371f4 2015-08-24 13:36:23Z 64bit
2016-08-12 18:31:34.804982 [DEBUG] sofia.c:9266 IP 10.10.250.3 Rejected by acl "domains". Falling back to Digest auth.
2016-08-12 18:31:34.824970 [NOTICE] mod_python.c:212 Invoking py module: switch_connector.handler
....
2016-08-12 18:31:34.944971 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/401942957943@sip.cvraptzg6qbq5lmj) Running State Change CS_ROUTING
2016-08-12 18:31:34.944971 [DEBUG] switch_channel.c:2239 (sofia/internal/401942957943@sip.cvraptzg6qbq5lmj) Callstate Change DOWN -> RINGING
2016-08-12 18:31:34.944971 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/401942957943@sip.cvraptzg6qbq5lmj) State ROUTING
2016-08-12 18:31:34.944971 [DEBUG] mod_sofia.c:141 sofia/internal/401942957943@sip.cvraptzg6qbq5lmj SOFIA ROUTING
2016-08-12 18:31:34.944971 [DEBUG] switch_core_state_machine.c:166 sofia/internal/401942957943@sip.cvraptzg6qbq5lmj Standard ROUTING
2016-08-12 18:31:34.944971 [INFO] mod_dialplan_xml.c:637 Processing 401942957943 <401942957943>->2320 in context Local_d7082761-3178-4cab-955a-bddaee110767
2016-08-12 18:31:34.944971 [NOTICE] mod_python.c:212 Invoking py module: switch_connector.handler