freeswitch@internal> 2014-05-05 23:52:52.201785 [NOTICE] switch_channel.c:1054 New Channel sofia/internal/47842...@x.x.x.x [b527b179-ed3a-4466-8f4d-f3d18d47a421]
2014-05-05 23:52:52.201785 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.201785 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.201785 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/47842...@x.x.x.x) Running State Change CS_NEW
2014-05-05 23:52:52.201785 [DEBUG] sofia.c:8331 sofia/internal/47842...@x.x.x.x receiving invite from
192.168.166.30:60430 version: 1.5.12b git 29ea8e9 2014-05-05 00:33:55Z 64bit
2014-05-05 23:52:52.201785 [DEBUG] sofia.c:8498 IP 192.168.166.30 Rejected by acl "domains". Falling back to Digest auth.
2014-05-05 23:52:52.201785 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.201785 [DEBUG] sofia.c:2034 detaching session b527b179-ed3a-4466-8f4d-f3d18d47a421
2014-05-05 23:52:52.201785 [DEBUG] switch_core_state_machine.c:486 (sofia/internal/47842...@x.x.x.x) State NEW
2014-05-05 23:52:52.501742 [DEBUG] sofia.c:2139 Re-attaching to session b527b179-ed3a-4466-8f4d-f3d18d47a421
2014-05-05 23:52:52.501742 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.501742 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.521732 [DEBUG] sofia.c:8331 sofia/internal/47842...@x.x.x.x receiving invite from
192.168.166.30:60430 version: 1.5.12b git 29ea8e9 2014-05-05 00:33:55Z 64bit
2014-05-05 23:52:52.521732 [DEBUG] sofia.c:8498 IP 192.168.166.30 Rejected by acl "domains". Falling back to Digest auth.
2014-05-05 23:52:52.641728 [DEBUG] sofia.c:9587 Setting NAT mode based on nat.auto
2014-05-05 23:52:52.641728 [DEBUG] sofia.c:6197 Channel sofia/internal/47842...@x.x.x.x entering state [received][100]
2014-05-05 23:52:52.641728 [DEBUG] sofia.c:6207 Remote SDP:
v=0
o=Z 0 0 IN IP4 y.y.y.y
s=Z
c=IN IP4 y.y.y.y
t=0 0
m=audio 8000 RTP/AVP 3 110 8 0 98 101
a=rtpmap:110 speex/8000
a=rtpmap:98 iLBC/8000
a=fmtp:98 mode=20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2014-05-05 23:52:52.641728 [DEBUG] sofia.c:6462 (sofia/internal/47842...@x.x.x.x) State Change CS_NEW -> CS_INIT
2014-05-05 23:52:52.641728 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.641728 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/47842...@x.x.x.x) Running State Change CS_INIT
2014-05-05 23:52:52.641728 [DEBUG] switch_core_state_machine.c:507 (sofia/internal/47842...@x.x.x.x) State INIT
2014-05-05 23:52:52.641728 [DEBUG] mod_sofia.c:87 sofia/internal/47842...@x.x.x.x SOFIA INIT
2014-05-05 23:52:52.641728 [DEBUG] switch_core_state_machine.c:40 sofia/internal/47842...@x.x.x.x Standard INIT
2014-05-05 23:52:52.641728 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/47842...@x.x.x.x) State Change CS_INIT -> CS_ROUTING
2014-05-05 23:52:52.641728 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.641728 [DEBUG] switch_core_state_machine.c:507 (sofia/internal/47842...@x.x.x.x) State INIT going to sleep
2014-05-05 23:52:52.641728 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/47842...@x.x.x.x) Running State Change CS_ROUTING
2014-05-05 23:52:52.641728 [DEBUG] switch_channel.c:2178 (sofia/internal/47842...@x.x.x.x) Callstate Change DOWN -> RINGING
2014-05-05 23:52:52.641728 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/47842...@x.x.x.x) State ROUTING
2014-05-05 23:52:52.641728 [DEBUG] mod_sofia.c:123 sofia/internal/47842...@x.x.x.x SOFIA ROUTING
2014-05-05 23:52:52.641728 [DEBUG] switch_core_state_machine.c:164 sofia/internal/47842...@x.x.x.x Standard ROUTING
2014-05-05 23:52:52.641728 [INFO] mod_dialplan_xml.c:558 Processing 47842178569 <47842178569>->458752545874 in context public
Dialplan: sofia/internal/47842...@x.x.x.x parsing [public->unloop] continue=false
Dialplan: sofia/internal/47842...@x.x.x.x Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/47842...@x.x.x.x Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/47842...@x.x.x.x parsing [public->outside_call] continue=true
Dialplan: sofia/internal/47842...@x.x.x.x Absolute Condition [outside_call]
Dialplan: sofia/internal/47842...@x.x.x.x Action set(outside_call=true)
Dialplan: sofia/internal/47842...@x.x.x.x Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/47842...@x.x.x.x parsing [public->call_debug] continue=true
Dialplan: sofia/internal/47842...@x.x.x.x Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/47842...@x.x.x.x parsing [public->public_extensions] continue=false
Dialplan: sofia/internal/47842...@x.x.x.x Regex (FAIL) [public_extensions] destination_number(458752545874) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/47842...@x.x.x.x parsing [public->public_did] continue=false
Dialplan: sofia/internal/47842...@x.x.x.x Regex (FAIL) [public_did] destination_number(458752545874) =~ /^(5551212)$/ break=on-false
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:214 (sofia/internal/47842...@x.x.x.x) State Change CS_ROUTING -> CS_EXECUTE
2014-05-05 23:52:52.781764 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/47842...@x.x.x.x) State ROUTING going to sleep
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/47842...@x.x.x.x) Running State Change CS_EXECUTE
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:530 (sofia/internal/47842...@x.x.x.x) State EXECUTE
2014-05-05 23:52:52.781764 [DEBUG] mod_sofia.c:178 sofia/internal/47842...@x.x.x.x SOFIA EXECUTE
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:256 sofia/internal/47842...@x.x.x.x Standard EXECUTE
EXECUTE sofia/internal/47842...@x.x.x.x set(outside_call=true)
2014-05-05 23:52:52.781764 [DEBUG] mod_dptools.c:1435 sofia/internal/47842...@x.x.x.x SET [outside_call]=[true]
EXECUTE sofia/internal/47842...@x.x.x.x export(RFC2822_DATE=Mon, 05 May 2014 23:52:52 -0400)
2014-05-05 23:52:52.781764 [DEBUG] switch_channel.c:1246 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 05 May 2014 23:52:52 -0400]
2014-05-05 23:52:52.781764 [NOTICE] switch_core_state_machine.c:313 sofia/internal/47842...@x.x.x.x has executed the last dialplan instruction, hanging up.
2014-05-05 23:52:52.781764 [NOTICE] switch_core_state_machine.c:315 Hangup sofia/internal/47842...@x.x.x.x [CS_EXECUTE] [NORMAL_CLEARING]
2014-05-05 23:52:52.781764 [DEBUG] switch_channel.c:3216 Send signal sofia/internal/47842...@x.x.x.x [KILL]
2014-05-05 23:52:52.781764 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:530 (sofia/internal/47842...@x.x.x.x) State EXECUTE going to sleep
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/47842...@x.x.x.x) Running State Change CS_HANGUP
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:730 (sofia/internal/47842...@x.x.x.x) Callstate Change RINGING -> HANGUP
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:732 (sofia/internal/47842...@x.x.x.x) State HANGUP
2014-05-05 23:52:52.781764 [DEBUG] mod_sofia.c:413 Channel sofia/internal/47842...@x.x.x.x hanging up, cause: NORMAL_CLEARING
2014-05-05 23:52:52.781764 [DEBUG] mod_sofia.c:547 Responding to INVITE with: 480
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:58 sofia/internal/47842...@x.x.x.x Standard HANGUP, cause: NORMAL_CLEARING
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:732 (sofia/internal/47842...@x.x.x.x) State HANGUP going to sleep
2014-05-05 23:52:52.781764 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/47842...@x.x.x.x) State Change CS_HANGUP -> CS_REPORTING
2014-05-05 23:52:52.781764 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.801731 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/47842...@x.x.x.x) Running State Change CS_REPORTING
2014-05-05 23:52:52.801731 [DEBUG] switch_core_state_machine.c:818 (sofia/internal/47842...@x.x.x.x) State REPORTING
2014-05-05 23:52:52.961727 [DEBUG] switch_core_state_machine.c:102 sofia/internal/47842...@x.x.x.x Standard REPORTING, cause: NORMAL_CLEARING
2014-05-05 23:52:52.961727 [DEBUG] switch_core_state_machine.c:818 (sofia/internal/47842...@x.x.x.x) State REPORTING going to sleep
2014-05-05 23:52:52.961727 [DEBUG] switch_core_state_machine.c:493 (sofia/internal/47842...@x.x.x.x) State Change CS_REPORTING -> CS_DESTROY
2014-05-05 23:52:52.961727 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/47842...@x.x.x.x [BREAK]
2014-05-05 23:52:52.961727 [DEBUG] switch_core_session.c:1604 Session 12 (sofia/internal/47842...@x.x.x.x) Locked, Waiting on external entities
2014-05-05 23:52:52.961727 [NOTICE] switch_core_session.c:1622 Session 12 (sofia/internal/47842...@x.x.x.x) Ended
2014-05-05 23:52:52.961727 [NOTICE] switch_core_session.c:1626 Close Channel sofia/internal/47842...@x.x.x.x [CS_DESTROY]
2014-05-05 23:52:52.961727 [DEBUG] switch_core_state_machine.c:618 (sofia/internal/47842...@x.x.x.x) Callstate Change HANGUP -> DOWN
2014-05-05 23:52:52.961727 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/47842...@x.x.x.x) Running State Change CS_DESTROY
2014-05-05 23:52:52.961727 [DEBUG] switch_core_state_machine.c:631 (sofia/internal/47842...@x.x.x.x) State DESTROY
2014-05-05 23:52:52.961727 [DEBUG] mod_sofia.c:323 sofia/internal/47842...@x.x.x.x SOFIA DESTROY
2014-05-05 23:52:52.961727 [DEBUG] switch_core_state_machine.c:109 sofia/internal/47842...@x.x.x.x Standard DESTROY
2014-05-05 23:52:52.961727 [DEBUG] switch_core_state_machine.c:631 (sofia/internal/47842...@x.x.x.x) State DESTROY going to sleep
==================================================================================