FreeSWITCH log, successful connection

Logfile contents of a successful OpenSim chat connection using FreeSWITCH, including text coloring.
2009-08-27 14:13:40.525224 [DEBUG] switch_core_state_machine.c:397 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) Running State Change CS_NEW
2009-08-27 14:13:40.525224 [DEBUG] switch_core_state_machine.c:403 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) State NEW
2009-08-27 14:13:40.525224 [DEBUG] sofia_glue.c:3079 Audio Codec Compare [SIREN14-3D:111:32000:0]/[G7221:115:32000:20]
2009-08-27 14:13:40.525224 [DEBUG] sofia_glue.c:3079 Audio Codec Compare [SIREN14-3D:111:32000:0]/[G722:9:8000:20]
2009-08-27 14:13:40.525224 [DEBUG] sofia_glue.c:3079 Audio Codec Compare [SIREN14-3D:111:32000:0]/[PCMU:0:8000:20]
2009-08-27 14:13:40.525224 [DEBUG] sofia_glue.c:3079 Audio Codec Compare [SIREN14-3D:111:32000:0]/[PCMA:8:8000:20]
2009-08-27 14:13:40.525224 [DEBUG] sofia_glue.c:3079 Audio Codec Compare [SIREN14-3D:111:32000:0]/[GSM:3:8000:20]
2009-08-27 14:13:40.525224 [DEBUG] sofia_glue.c:3079 Audio Codec Compare [PCMU:0:8000:0]/[G7221:115:32000:20]
2009-08-27 14:13:40.525224 [DEBUG] sofia_glue.c:3079 Audio Codec Compare [PCMU:0:8000:0]/[G722:9:8000:20]
2009-08-27 14:13:40.525224 [DEBUG] sofia_glue.c:3079 Audio Codec Compare [PCMU:0:8000:0]/[PCMU:0:8000:20]
2009-08-27 14:13:40.540848 [DEBUG] sofia_glue.c:2037 Set Codec sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 PCMU/8000 20 ms 160 samples
2009-08-27 14:13:40.540848 [DEBUG] sofia_glue.c:3039 Set 2833 dtmf payload to 101
2009-08-27 14:13:40.540848 [DEBUG] sofia.c:3376 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) State Change CS_NEW -> CS_INIT
2009-08-27 14:13:40.540848 [DEBUG] switch_core_session.c:933 Send signal sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 [BREAK]
2009-08-27 14:13:40.540848 [DEBUG] switch_core_state_machine.c:397 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) Running State Change CS_INIT
2009-08-27 14:13:40.540848 [DEBUG] switch_core_state_machine.c:480 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) State INIT
2009-08-27 14:13:40.540848 [DEBUG] mod_sofia.c:83 sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 SOFIA INIT
2009-08-27 14:13:40.540848 [DEBUG] mod_sofia.c:111 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) State Change CS_INIT -> CS_ROUTING
2009-08-27 14:13:40.540848 [DEBUG] switch_core_session.c:933 Send signal sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 [BREAK]
2009-08-27 14:13:40.540848 [DEBUG] switch_core_state_machine.c:480 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) State INIT going to sleep
2009-08-27 14:13:40.540848 [DEBUG] switch_core_state_machine.c:397 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) Running State Change CS_ROUTING
2009-08-27 14:13:40.540848 [DEBUG] switch_core_state_machine.c:483 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) State ROUTING
2009-08-27 14:13:40.540848 [DEBUG] mod_sofia.c:130 sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 SOFIA ROUTING
2009-08-27 14:13:40.540848 [DEBUG] switch_core_state_machine.c:78 sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 Standard ROUTING
2009-08-27 14:13:40.540848 [INFO] mod_dialplan_xml.c:252 Processing Sophie Nauman->confctl-xNDFhZTQ4MjEtMDFmMi00NDNkLTkzMWEtOWRhMjA3YmQ1ZTFk in context default
Dialplan: sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 parsing [default->sip_uri] continue=false
Dialplan: sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 Regex (FAIL) [sip_uri] destination_number(confctl-xNDFhZTQ4MjEtMDFmMi00NDNkLTkzMWEtOWRhMjA3YmQ1ZTFk) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 parsing [default->opensim_conferences] continue=false
Dialplan: sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 Regex (PASS) [opensim_conferences] destination_number(confctl-xNDFhZTQ4MjEtMDFmMi00NDNkLTkzMWEtOWRhMjA3YmQ1ZTFk) =~ /^confctl-(.*)$/ break=on-false
Dialplan: sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 Action answer()
Dialplan: sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 Action conference(xNDFhZTQ4MjEtMDFmMi00NDNkLTkzMWEtOWRhMjA3YmQ1ZTFk-${domain_name}@default)
2009-08-27 14:13:41.556460 [DEBUG] switch_core_state_machine.c:114 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) State Change CS_ROUTING -> CS_EXECUTE
2009-08-27 14:13:41.556460 [DEBUG] switch_core_session.c:933 Send signal sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 [BREAK]
2009-08-27 14:13:41.556460 [DEBUG] switch_core_state_machine.c:483 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) State ROUTING going to sleep
2009-08-27 14:13:41.556460 [DEBUG] switch_core_state_machine.c:397 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) Running State Change CS_EXECUTE
2009-08-27 14:13:41.556460 [DEBUG] switch_core_state_machine.c:490 (sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26) State EXECUTE
2009-08-27 14:13:41.556460 [DEBUG] mod_sofia.c:173 sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 SOFIA EXECUTE
2009-08-27 14:13:41.556460 [DEBUG] switch_core_state_machine.c:151 sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 Standard EXECUTE
EXECUTE sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 answer()
2009-08-27 14:13:41.556460 [DEBUG] mod_dptools.c:649 sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26 receive message [ANSWER]
2009-08-27 14:13:41.556460 [DEBUG] sofia_glue.c:2271 AUDIO RTP [sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26] 210.195.1.26 port 17858 -> 210.195.0.163 port 22860 codec: 0 ms: 20
2009-08-27 14:13:41.556460 [DEBUG] switch_rtp.c:1138 Starting timer [soft] 160 bytes per 20ms
2009-08-27 14:13:41.556460 [DEBUG] mod_sofia.c:549 Local SDP sofia/internal/xWOiLAj9AQHGGCji61A8MMw==@210.195.1.26:
v=0
o=FreeSWITCH 1251378963 1251378964 IN IP4 210.195.1.26
s=FreeSWITCH
c=IN IP4 210.195.1.26
t=0 0
m=audio 17858 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv