df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/106@66.228.34.184) State DESTROY going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:181 sofia/internal/106@66.228.34.184 Standard DESTROY df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] mod_sofia.c:343 sofia/internal/106@66.228.34.184 SOFIA DESTROY df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/106@66.228.34.184) State DESTROY df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/106@66.228.34.184) Running State Change CS_DESTROY (Cur 0 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/106@66.228.34.184 [CS_DESTROY] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [NOTICE] switch_core_session.c:1683 Session 84 (sofia/internal/106@66.228.34.184) Ended df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_session.c:1665 Session 84 (sofia/internal/106@66.228.34.184) Locked, Waiting on external entities df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/106@66.228.34.184) State Change CS_REPORTING -> CS_DESTROY df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/106@66.228.34.184) State REPORTING going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:174 sofia/internal/106@66.228.34.184 Standard REPORTING, cause: NORMAL_CLEARING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.614120 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/106@66.228.34.184) State REPORTING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.614120 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@66.228.34.184) Running State Change CS_REPORTING (Cur 1 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/106@66.228.34.184) State Change CS_HANGUP -> CS_REPORTING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/106@66.228.34.184) State HANGUP going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:60 sofia/internal/106@66.228.34.184 Standard HANGUP, cause: NORMAL_CLEARING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480 df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_sofia.c:438 Channel sofia/internal/106@66.228.34.184 hanging up, cause: NORMAL_CLEARING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/106@66.228.34.184) State HANGUP df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/106@66.228.34.184) Callstate Change RINGING -> HANGUP df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@66.228.34.184) Running State Change CS_HANGUP (Cur 1 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/106@66.228.34.184) State EXECUTE going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/106@66.228.34.184 [CS_EXECUTE] [NORMAL_CLEARING] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [NOTICE] switch_core_state_machine.c:385 sofia/internal/106@66.228.34.184 has executed the last dialplan instruction, hanging up. df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:328 sofia/internal/106@66.228.34.184 Standard EXECUTE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_sofia.c:198 sofia/internal/106@66.228.34.184 SOFIA EXECUTE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/106@66.228.34.184) State EXECUTE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@66.228.34.184) Running State Change CS_EXECUTE (Cur 1 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/106@66.228.34.184) State ROUTING going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/106@66.228.34.184) State Change CS_ROUTING -> CS_EXECUTE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_dptools.c:1742 routes] 404 not found 173.29.209.241 df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 log([inbound routes] 404 not found 173.29.209.241) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action log([inbound routes] 404 not found ${sip_network_ip}) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_dptools.c:1548 SET sofia/internal/106@66.228.34.184 [call_direction]=[inbound] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 set(call_direction=inbound) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action set(call_direction=inbound) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[inbound] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 export(call_direction=inbound) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action export(call_direction=inbound) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Regex (PASS) [not-found] () =~ // break=on-false df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 parsing [public->not-found] continue=false df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_dptools.c:1548 SET sofia/internal/106@66.228.34.184 [caller_id_number]=[106] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 set(caller_id_number=106) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action set(caller_id_number=${caller_id_number}) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_dptools.c:1548 SET sofia/internal/106@66.228.34.184 [caller_id_name]=[106] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 set(caller_id_name=106) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action set(caller_id_name=${caller_id_name}) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_dptools.c:1548 SET sofia/internal/106@66.228.34.184 [caller_destination]=[106] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 set(caller_destination=106) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action set(caller_destination=${destination_number}) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[inbound] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 export(call_direction=inbound) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action export(call_direction=inbound) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Regex (PASS) [caller-details] () =~ // break=never df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 parsing [public->caller-details] continue=true df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [INFO] mod_dialplan_xml.c:637 Processing 106 <106>->106 in context public df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:236 sofia/internal/106@66.228.34.184 Standard ROUTING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_sofia.c:143 sofia/internal/106@66.228.34.184 SOFIA ROUTING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/106@66.228.34.184) State ROUTING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_channel.c:2249 (sofia/internal/106@66.228.34.184) Callstate Change DOWN -> RINGING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@66.228.34.184) Running State Change CS_ROUTING (Cur 1 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/106@66.228.34.184) State INIT going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/106@66.228.34.184) State Change CS_INIT -> CS_ROUTING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:40 sofia/internal/106@66.228.34.184 Standard INIT df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_sofia.c:90 sofia/internal/106@66.228.34.184 SOFIA INIT df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/106@66.228.34.184) State INIT df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@66.228.34.184) Running State Change CS_INIT (Cur 1 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/106@66.228.34.184) State NEW df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] sofia.c:7507 (sofia/internal/106@66.228.34.184) State Change CS_NEW -> CS_INIT df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4767 sofia/internal/106@66.228.34.184 Set 2833 dtmf send payload to 101 recv payload to 101 df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_codec.c:111 sofia/internal/106@66.228.34.184 Original read codec set to PCMU:0 df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/106@66.228.34.184 PCMU/8000 20 ms 160 samples 64000 bits 1 channels df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:123:48000:20:0:2]/[PCMA:8:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:123:48000:20:0:2]/[PCMU:0:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:123:48000:20:0:2]/[G722:9:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMA:8:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMU:0:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G726-32:2:8000:20:0:1]/[G722:9:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:20:0:1]/[PCMA:8:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:20:0:1]/[PCMU:0:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:97:8000:20:0:1]/[G722:9:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G723:4:8000:20:6300:1]/[PCMA:8:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G723:4:8000:20:6300:1]/[PCMU:0:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G723:4:8000:20:6300:1]/[G722:9:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] df8715a6-4bbd-4e70-8927-a105e8682a76 df8715a6-4bbd-4e70-8927-a105e8682a76 a=ptime:20 df8715a6-4bbd-4e70-8927-a105e8682a76 a=fmtp:101 0-15 df8715a6-4bbd-4e70-8927-a105e8682a76 a=rtpmap:101 telephone-event/8000 df8715a6-4bbd-4e70-8927-a105e8682a76 a=rtpmap:123 opus/48000/2 df8715a6-4bbd-4e70-8927-a105e8682a76 a=rtpmap:2 G726-32/8000 df8715a6-4bbd-4e70-8927-a105e8682a76 a=fmtp:97 mode=30 df8715a6-4bbd-4e70-8927-a105e8682a76 a=rtpmap:97 iLBC/8000 df8715a6-4bbd-4e70-8927-a105e8682a76 a=rtpmap:9 G722/8000 df8715a6-4bbd-4e70-8927-a105e8682a76 a=fmtp:18 annexb=no df8715a6-4bbd-4e70-8927-a105e8682a76 a=rtpmap:18 G729/8000 df8715a6-4bbd-4e70-8927-a105e8682a76 a=rtpmap:4 G723/8000 df8715a6-4bbd-4e70-8927-a105e8682a76 a=rtpmap:8 PCMA/8000 df8715a6-4bbd-4e70-8927-a105e8682a76 a=rtpmap:0 PCMU/8000 df8715a6-4bbd-4e70-8927-a105e8682a76 m=audio 5006 RTP/AVP 0 8 4 18 9 97 2 123 101 df8715a6-4bbd-4e70-8927-a105e8682a76 t=0 0 df8715a6-4bbd-4e70-8927-a105e8682a76 c=IN IP4 10.215.5.32 df8715a6-4bbd-4e70-8927-a105e8682a76 s=SIP Call df8715a6-4bbd-4e70-8927-a105e8682a76 o=106 8000 8000 IN IP4 10.215.5.32 df8715a6-4bbd-4e70-8927-a105e8682a76 v=0 df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] sofia.c:7094 Remote SDP: df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] sofia.c:7084 Channel sofia/internal/106@66.228.34.184 entering state [received][100] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] sofia.c:11168 Setting NAT mode based on via received 2018-04-17 21:20:38.594152 [DEBUG] sofia.c:9989 IP 173.29.209.241 Approved by acl "domains[]". Access Granted. df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] sofia.c:9873 sofia/internal/106@66.228.34.184 receiving invite from 173.29.209.241:37309 version: 1.6.20 -37-987c9b9 64bit df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@66.228.34.184) Running State Change CS_NEW (Cur 1 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/106@66.228.34.184 [df8715a6-4bbd-4e70-8927-a105e8682a76]