2010-03-08 17:14:08.640638 [DEBUG] mod_qsettings.cpp:143 We are being requested -> section: configuration | tag_name: configuration | key_name: name | key_value: enum.conf!
2010-03-08 17:14:08.640638 [NOTICE] switch_loadable_module.c:210 Adding Dialplan 'enum'
2010-03-08 17:14:08.640638 [NOTICE] switch_loadable_module.c:250 Adding Application 'enum'
2010-03-08 17:14:08.640638 [NOTICE] switch_loadable_module.c:272 Adding API Function 'enum'
2010-03-08 17:14:08.640638 [NOTICE] switch_loadable_module.c:272 Adding API Function 'enum_auto'
2010-03-08 17:14:08.656262 [DEBUG] mod_cdr_csv.c:321 Adding default template.
2010-03-08 17:14:08.656262 [DEBUG] mod_qsettings.cpp:143 We are being requested -> section: configuration | tag_name: configuration | key_name: name | key_value: cdr_csv.conf!
2010-03-08 17:14:08.656262 [DEBUG] mod_cdr_csv.c:368 Adding template example.
2010-03-08 17:14:08.656262 [DEBUG] mod_qsettings.cpp:143 We are being requested -> section: configuration | tag_name: configuration | key_name: name | key_value: sofia.conf!
2010-03-08 17:14:08.656262 [DEBUG] mod_qsettings.cpp:72 Config for sofia.conf requested. Providing the following XML:
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 apply-nat-acl [rfc1918]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 auth-all-packets [false]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 auth-calls [false]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 challenge-realm [auto_from]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 codec-prefs [CELT@48000h,G7221@32000h,G7221@16000h,G722,PCMU,PCMA,GSM]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 context [public]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 debug [0]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 dialplan [XML]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 disable-register [true]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 dtmf-duration [100]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:3266 Duration out of bounds, using default of 2000!
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 ext-rtp-ip [172.22.240.124]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 ext-sip-ip [172.22.240.124]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 hold-music [localstream://moh]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 inbound-codec-negotiation [generous]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 manage-presence [false]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 max-proceeding [3]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 nonce-ttl [60]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 rfc2833-pt [101]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 rtp-hold-timeout-sec [1800]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 rtp-ip [172.22.240.124]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 rtp-timeout-sec [300]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 rtp-timer-name [soft]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 sip-ip [172.22.240.124]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 sip-port [12345]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 sip-trace [false]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 use-rtp-timer [true]
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:2742 user-agent-string [FreeSWITCH/FSComm]
2010-03-08 17:14:08.656262 [NOTICE] sofia_reg.c:2440 Added gateway '33001' to profile 'softphone'
2010-03-08 17:14:08.656262 [NOTICE] sofia.c:3525 Started Profile softphone [sofia_reg_softphone]
2010-03-08 17:14:08.656262 [DEBUG] mod_sofia.c:3982 Waiting for profiles to start
2010-03-08 17:14:08.656262 [DEBUG] sofia.c:1315 Creating agent for softphone
2010-03-08 17:14:08.671887 [DEBUG] sofia.c:1351 Created agent for softphone
2010-03-08 17:14:08.671887 [DEBUG] sofia.c:1387 Set params for softphone
2010-03-08 17:14:08.671887 [DEBUG] sofia.c:1410 Activated db for softphone
2010-03-08 17:14:08.671887 [DEBUG] sofia.c:1437 Starting thread for softphone
2010-03-08 17:14:10.171878 [NOTICE] switch_loadable_module.c:144 Adding Endpoint 'sofia'
2010-03-08 17:14:10.171878 [NOTICE] switch_loadable_module.c:272 Adding API Function 'sofia'
2010-03-08 17:14:10.171878 [NOTICE] switch_loadable_module.c:272 Adding API Function 'sofia_gateway_data'
2010-03-08 17:14:10.171878 [NOTICE] switch_loadable_module.c:272 Adding API Function 'sofia_contact'
2010-03-08 17:14:10.171878 [NOTICE] switch_loadable_module.c:272 Adding API Function 'sofia_dig'
2010-03-08 17:14:10.171878 [NOTICE] switch_loadable_module.c:377 Adding Chat interface 'sip'
2010-03-08 17:14:10.171878 [NOTICE] switch_loadable_module.c:421 Adding Management interface 'mod_sofia' OID[.1.3.6.1.4.1.27880.1]
2010-03-08 17:14:10.171878 [NOTICE] switch_loadable_module.c:144 Adding Endpoint 'loopback'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'acl'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'alias'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'banner'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'bgapi'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'bg_system'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'break'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'complete'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'cond'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'console_complete'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'console_complete_xml'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'create_uuid'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'db_cache'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'domain_exists'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'echo'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'escape'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'eval'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'expand'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'find_user_xml'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'fsctl'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function '...'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'shutdown'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'version'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'global_getvar'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'global_setvar'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'group_call'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'help'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'host_lookup'
2010-03-08 17:14:10.250002 [NOTICE] switch_loadable_module.c:272 Adding API Function 'hostname'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'hupall'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'in_group'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'is_lan_addr'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'load'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'md5'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'module_exists'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'nat_map'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'originate'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'pause'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'regex'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'reloadacl'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'reload'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'reloadxml'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'sched_api'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'sched_broadcast'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'sched_del'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'sched_hangup'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'sched_transfer'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'show'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'sql_escape'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'status'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'strftime_tz'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'stun'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'system'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'time_test'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'timer_test'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'tone_detect'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'unload'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'unsched_api'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'url_decode'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'url_encode'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'user_data'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'user_exists'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_audio'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_bridge'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_broadcast'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_chat'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_debug_audio'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_deflect'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_displace'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_display'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_dump'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_exists'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_flush_dtmf'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_getvar'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_hold'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_kill'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_loglevel'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_media'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_park'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_preprocess'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_record'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_recv_dtmf'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_send_dtmf'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_session_heartbeat'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_setvar_multi'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_setvar'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_transfer'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'uuid_simplify'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'xml_locate'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:272 Adding API Function 'xml_wrap'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:144 Adding Endpoint 'error'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:144 Adding Endpoint 'group'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:144 Adding Endpoint 'user'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:210 Adding Dialplan 'inline'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'privacy'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'set_audio_level'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'set_mute'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'flush_dtmf'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'hold'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'unhold'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'transfer'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'check_acl'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'verbose_events'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'early_hangup'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'sleep'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'delay_echo'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'strftime'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'phrase'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'eval'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'pre_answer'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'answer'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'hangup'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'set_name'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'presence'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'log'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'info'
2010-03-08 17:14:10.265627 [NOTICE] switch_loadable_module.c:250 Adding Application 'event'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'sound_test'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'export'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'set'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'set_global'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'set_profile_var'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'unset'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'ring_ready'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'remove_bugs'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'break'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'detect_speech'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'ivr'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'redirect'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'send_display'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'respond'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'deflect'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'queue_dtmf'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'send_dtmf'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'sched_hangup'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'sched_broadcast'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'sched_transfer'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'execute_extension'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'sched_heartbeat'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'enable_heartbeat'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'mkdir'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'soft_hold'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'bind_meta_app'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'unbind_meta_app'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'intercept'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'eavesdrop'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'three_way'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'set_user'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'stop_dtmf'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'start_dtmf'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'stop_dtmf_generate'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'start_dtmf_generate'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'stop_tone_detect'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'fax_detect'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'tone_detect'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'echo'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'park'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'park_state'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'gentones'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'playback'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'endless_playback'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'att_xfer'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'read'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'play_and_get_digits'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'stop_record_session'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'record_session'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'record'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'preprocess'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'stop_displace_session'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'displace_session'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'speak'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'clear_speech_cache'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'bridge'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'system'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'say'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'wait_for_silence'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:250 Adding Application 'session_loglevel'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:272 Adding API Function 'strepoch'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:272 Adding API Function 'chat'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:272 Adding API Function 'strftime'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:272 Adding API Function 'presence'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:377 Adding Chat interface 'event'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:377 Adding Chat interface 'api'
2010-03-08 17:14:10.281252 [NOTICE] switch_loadable_module.c:210 Adding Dialplan 'XML'
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 70ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 80ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 90ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 100ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 110ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 120ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 70ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 80ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 90ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 100ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 110ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 120ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 70ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 80ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 90ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 100ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 110ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-16' (G.726 16k) 8000hz 120ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 70ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 80ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 90ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 100ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 110ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 120ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 70ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 80ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 90ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 100ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 110ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-24' (G.726 24k) 8000hz 120ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 70ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 80ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 90ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 100ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 110ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 120ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 70ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 80ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 90ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 100ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 110ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-32' (G.726 32k) 8000hz 120ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 70ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 80ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 90ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 100ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 110ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 120ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 70ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 80ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 90ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 100ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 110ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G726-40' (G.726 40k) 8000hz 120ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G722' (G.722) 16000hz 10ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G722' (G.722) 16000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G722' (G.722) 16000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G722' (G.722) 16000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G722' (G.722) 16000hz 50ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G722' (G.722) 16000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'GSM' (GSM) 8000hz 20ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'GSM' (GSM) 8000hz 40ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'GSM' (GSM) 8000hz 60ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'GSM' (GSM) 8000hz 80ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'GSM' (GSM) 8000hz 100ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'GSM' (GSM) 8000hz 120ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'LPC' (LPC-10) 8000hz 90ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'iLBC' (iLBC) 8000hz 30ms
2010-03-08 17:14:10.296877 [NOTICE] switch_loadable_module.c:184 Adding Codec 'iLBC' (iLBC) 8000hz 20ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'SPEEX' (Speex) 32000hz 20ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'SPEEX' (Speex) 16000hz 20ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'SPEEX' (Speex) 8000hz 20ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'CELT' (CELT ultra-low delay) 48000hz 10ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'CELT' (CELT ultra-low delay) 48000hz 8ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'CELT' (CELT ultra-low delay) 48000hz 6ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'CELT' (CELT ultra-low delay) 48000hz 4ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'CELT' (CELT ultra-low delay) 48000hz 2ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'CELT' (CELT ultra-low delay) 32000hz 10ms
2010-03-08 17:14:10.312502 [INFO] mod_siren.c:141 Audio coding: ITU-T Rec. G.722.1, licensed from Polycom(R)
2010-03-08 17:14:10.312502 [INFO] mod_siren.c:142 Audio coding: ITU-T Rec. G.722.1 Annex C, licensed from Polycom(R)
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G7221' (Polycom(R) G722.1/G722.1C) 32000hz 20ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G7221' (Polycom(R) G722.1/G722.1C) 32000hz 40ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G7221' (Polycom(R) G722.1/G722.1C) 32000hz 60ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G7221' (Polycom(R) G722.1/G722.1C) 16000hz 20ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G7221' (Polycom(R) G722.1/G722.1C) 16000hz 40ms
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:184 Adding Codec 'G7221' (Polycom(R) G722.1/G722.1C) 16000hz 60ms
2010-03-08 17:14:10.312502 [INFO] mod_sndfile.c:347
LibSndFile Version : libsndfile-1.0.12 Supported Formats
================================================================================
AIFF (Apple/SGI) (extension "aiff")
AU (Sun/NeXT) (extension "au")
AVR (Audio Visual Research) (extension "avr")
CAF (Apple Core Audio File) (extension "caf")
HTK (HMM Tool Kit) (extension "htk")
IFF (Amiga IFF/SVX8/SV16) (extension "iff")
MAT4 (GNU Octave 2.0 / Matlab 4.2) (extension "mat")
MAT5 (GNU Octave 2.1 / Matlab 5.0) (extension "mat")
PAF (Ensoniq PARIS) (extension "paf")
PVF (Portable Voice Format) (extension "pvf")
RAW (header-less) (extension "raw")
SD2 (Sound Designer II) (extension "sd2")
SDS (Midi Sample Dump Standard) (extension "sds")
SF (Berkeley/IRCAM/CARL) (extension "sf")
VOC (Creative Labs) (extension "voc")
W64 (SoundFoundry WAVE 64) (extension "w64")
WAV (Microsoft) (extension "wav")
WAV (NIST Sphere) (extension "wav")
WAVEX (Microsoft) (extension "wav")
WVE (Psion Series 3) (extension "wve")
XI (FastTracker 2) (extension "xi")
================================================================================
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'aiff'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'au'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'avr'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'caf'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'htk'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'iff'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'mat'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'mat'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'paf'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'pvf'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'raw'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'sd2'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'sds'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'sf'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'voc'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'w64'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'wav'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'wav'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'wav'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'wve'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'xi'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'r8'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'r16'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'r24'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'r32'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'gsm'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'ul'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'al'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'adpcm'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'tone_stream'
2010-03-08 17:14:10.312502 [NOTICE] switch_loadable_module.c:296 Adding File Format 'silence_stream'
2010-03-08 17:14:10.328127 [DEBUG] mod_qsettings.cpp:143 We are being requested -> section: configuration | tag_name: configuration | key_name: name | key_value: local_stream.conf!
2010-03-08 17:14:10.328127 [NOTICE] switch_loadable_module.c:272 Adding API Function 'stop_local_stream'
2010-03-08 17:14:10.328127 [NOTICE] switch_loadable_module.c:272 Adding API Function 'start_local_stream'
2010-03-08 17:14:10.328127 [NOTICE] switch_loadable_module.c:272 Adding API Function 'show_local_stream'
2010-03-08 17:14:10.328127 [NOTICE] switch_loadable_module.c:296 Adding File Format 'local_stream'
2010-03-08 17:14:10.359377 [DEBUG] mod_qsettings.cpp:143 We are being requested -> section: configuration | tag_name: configuration | key_name: name | key_value: portaudio.conf!
2010-03-08 17:14:10.359377 [DEBUG] mod_qsettings.cpp:72 Config for portaudio.conf requested. Providing the following XML:
2010-03-08 17:14:10.359377 [WARNING] mod_PortAudio.c:978 Switching to default input device
2010-03-08 17:14:10.359377 [WARNING] mod_PortAudio.c:988 Switching to default output device
2010-03-08 17:14:10.359377 [WARNING] mod_PortAudio.c:997 Invalid ring device configured using output device
2010-03-08 17:14:10.359377 [INFO] mod_PortAudio.c:1195 PortAudio version number = 1899
PortAudio version text = 'PortAudio V19-devel (built Mar 2 2010)'
2010-03-08 17:14:10.359377 [INFO] mod_PortAudio.c:1219 Number of devices = 6
2010-03-08 17:14:10.359377 [DEBUG] mod_PortAudio.c:823 Input Device: 0, Output Device: 3, Ring Device: 3 Sample Rate: 8000 MS: 20
2010-03-08 17:14:10.375001 [NOTICE] switch_loadable_module.c:144 Adding Endpoint 'portaudio'
2010-03-08 17:14:10.375001 [NOTICE] switch_loadable_module.c:272 Adding API Function 'pa'
2010-03-08 17:14:10.375001 [NOTICE] switch_loadable_module.c:250 Adding Application 'socket'
2010-03-08 17:14:10.375001 [NOTICE] switch_loadable_module.c:272 Adding API Function 'event_sink'
2010-03-08 17:14:10.375001 [DEBUG] mod_qsettings.cpp:143 We are being requested -> section: configuration | tag_name: configuration | key_name: name | key_value: post_load_modules.conf!
2010-03-08 17:14:10.375001 [DEBUG] mod_qsettings.cpp:143 We are being requested -> section: configuration | tag_name: configuration | key_name: name | key_value: event_socket.conf!
2010-03-08 17:14:10.375001 [NOTICE] switch_core.c:948 Created ip list rfc1918.auto default (deny)
2010-03-08 17:14:10.375001 [DEBUG] mod_event_socket.c:2646 Socket up listening on 127.0.0.1:8022
2010-03-08 17:14:10.375001 [NOTICE] switch_utils.c:195 Adding 10.0.0.0/8 (allow) [] to list rfc1918.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_utils.c:195 Adding 172.16.0.0/12 (allow) [] to list rfc1918.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_utils.c:195 Adding 192.168.0.0/16 (allow) [] to list rfc1918.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_core.c:956 Created ip list wan.auto default (allow)
2010-03-08 17:14:10.375001 [NOTICE] switch_utils.c:195 Adding 10.0.0.0/8 (deny) [] to list wan.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_utils.c:195 Adding 172.16.0.0/12 (deny) [] to list wan.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_utils.c:195 Adding 192.168.0.0/16 (deny) [] to list wan.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_core.c:964 Created ip list nat.auto default (deny)
2010-03-08 17:14:10.375001 [NOTICE] switch_core.c:966 Adding 172.22.240.124/255.255.240.0 (deny) to list nat.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_utils.c:195 Adding 10.0.0.0/8 (allow) [] to list nat.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_utils.c:195 Adding 172.16.0.0/12 (allow) [] to list nat.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_utils.c:195 Adding 192.168.0.0/16 (allow) [] to list nat.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_core.c:975 Created ip list loopback.auto default (deny)
2010-03-08 17:14:10.375001 [NOTICE] switch_utils.c:195 Adding 127.0.0.0/8 (allow) [] to list loopback.auto
2010-03-08 17:14:10.375001 [NOTICE] switch_core.c:981 Created ip list localnet.auto default (deny)
2010-03-08 17:14:10.375001 [NOTICE] switch_core.c:984 Adding 172.22.240.124/255.255.240.0 (allow) to list localnet.auto
2010-03-08 17:14:10.375001 [DEBUG] mod_qsettings.cpp:143 We are being requested -> section: configuration | tag_name: configuration | key_name: name | key_value: acl.conf!
2010-03-08 17:14:10.375001 [DEBUG] mod_qsettings.cpp:143 We are being requested -> section: configuration | tag_name: configuration | key_name: name | key_value: post_load_switch.conf!
2010-03-08 17:14:10.640625 [NOTICE] sofia_reg.c:336 Registering 33001
2010-03-08 17:14:10.671875 [DEBUG] sofia_reg.c:1478 Changing expire time to 3285 by request of proxy sip:162.22.253.163
2010-03-08 17:14:27.749890 [NOTICE] switch_channel.c:669 New Channel sofia/softphone/33004@test.com:5061 [763d0bb5-6417-4dae-8a14-fefb402e4a80]
2010-03-08 17:14:27.749890 [DEBUG] sofia.c:4139 Channel sofia/softphone/33004@test.com:5061 entering state [received][100]
2010-03-08 17:14:27.749890 [DEBUG] sofia.c:4150 Remote SDP:
v=0
o=- 1 1 IN IP4 172.22.253.171
s=-
c=IN IP4 127.0.0.2
b=AS:64
t=0 0
m=audio 2096 RTP/AVP 8 18
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
2010-03-08 17:14:27.749890 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[CELT:114:48000:10]
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/33004@test.com:5061) Running State Change CS_NEW
2010-03-08 17:14:27.749890 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[G722:9:8000:10]
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:320 (sofia/softphone/33004@test.com:5061) State NEW
2010-03-08 17:14:27.749890 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[PCMU:0:8000:10]
2010-03-08 17:14:27.749890 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[PCMA:8:8000:10]
2010-03-08 17:14:27.749890 [DEBUG] sofia_glue.c:3580 Substituting codec PCMA@20i@8000h
2010-03-08 17:14:27.749890 [DEBUG] sofia_glue.c:2333 Set Codec sofia/softphone/33004@test.com:5061 PCMA/8000 20 ms 160 samples
2010-03-08 17:14:27.749890 [DEBUG] sofia.c:4296 (sofia/softphone/33004@test.com:5061) State Change CS_NEW -> CS_INIT
2010-03-08 17:14:27.749890 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/33004@test.com:5061) Running State Change CS_INIT
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:338 (sofia/softphone/33004@test.com:5061) State INIT
2010-03-08 17:14:27.749890 [DEBUG] mod_sofia.c:83 sofia/softphone/33004@test.com:5061 SOFIA INIT
2010-03-08 17:14:27.749890 [DEBUG] mod_sofia.c:117 (sofia/softphone/33004@test.com:5061) State Change CS_INIT -> CS_ROUTING
2010-03-08 17:14:27.749890 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:338 (sofia/softphone/33004@test.com:5061) State INIT going to sleep
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/33004@test.com:5061) Running State Change CS_ROUTING
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:341 (sofia/softphone/33004@test.com:5061) State ROUTING
2010-03-08 17:14:27.749890 [DEBUG] mod_sofia.c:140 sofia/softphone/33004@test.com:5061 SOFIA ROUTING
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:77 sofia/softphone/33004@test.com:5061 Standard ROUTING
2010-03-08 17:14:27.749890 [INFO] mod_dialplan_xml.c:405 Processing SIP x33004 Kev->33001 in context public
Dialplan: sofia/softphone/33004@test.com:5061 parsing [public->public_extensions] continue=false
Dialplan: sofia/softphone/33004@test.com:5061 Regex (FAIL) [public_extensions] false() =~ /^true$/ break=on-false
Dialplan: sofia/softphone/33004@test.com:5061 parsing [public->public_extensions] continue=false
Dialplan: sofia/softphone/33004@test.com:5061 Regex (FAIL) [public_extensions] ${sip_to_params}() =~ /intercom=true/ break=on-false
Dialplan: sofia/softphone/33004@test.com:5061 parsing [public->public_extensions] continue=false
Dialplan: sofia/softphone/33004@test.com:5061 Regex (PASS) [public_extensions] destination_number(33001) =~ /^(.*)$/ break=on-false
Dialplan: sofia/softphone/33004@test.com:5061 Action export(origination_caller_id_number=${fscomm_caller_id_num})
Dialplan: sofia/softphone/33004@test.com:5061 Action export(origination_caller_id_name=${fscomm_caller_id_name})
Dialplan: sofia/softphone/33004@test.com:5061 Action info()
Dialplan: sofia/softphone/33004@test.com:5061 Action set(ringback=${us-ring})
Dialplan: sofia/softphone/33004@test.com:5061 Action pre_answer()
Dialplan: sofia/softphone/33004@test.com:5061 Action set(park_after_bridge=true)
Dialplan: sofia/softphone/33004@test.com:5061 Action bridge(portaudio)
Dialplan: sofia/softphone/33004@test.com:5061 Action set(transfer_after_bridge=1000)
Dialplan: sofia/softphone/33004@test.com:5061 Action set(hangup_after_bridge=true)
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:119 (sofia/softphone/33004@test.com:5061) State Change CS_ROUTING -> CS_EXECUTE
2010-03-08 17:14:27.749890 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:341 (sofia/softphone/33004@test.com:5061) State ROUTING going to sleep
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/33004@test.com:5061) Running State Change CS_EXECUTE
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:348 (sofia/softphone/33004@test.com:5061) State EXECUTE
2010-03-08 17:14:27.749890 [DEBUG] mod_sofia.c:226 sofia/softphone/33004@test.com:5061 SOFIA EXECUTE
2010-03-08 17:14:27.749890 [DEBUG] switch_core_state_machine.c:157 sofia/softphone/33004@test.com:5061 Standard EXECUTE
EXECUTE sofia/softphone/33004@test.com:5061 export(origination_caller_id_number=)
2010-03-08 17:14:27.749890 [DEBUG] mod_dptools.c:898 EXPORT [origination_caller_id_number]=[UNDEF]
EXECUTE sofia/softphone/33004@test.com:5061 export(origination_caller_id_name=)
2010-03-08 17:14:27.749890 [DEBUG] mod_dptools.c:898 EXPORT [origination_caller_id_name]=[UNDEF]
EXECUTE sofia/softphone/33004@test.com:5061 info()
2010-03-08 17:14:27.765515 [INFO] mod_dptools.c:963 CHANNEL_DATA:
Channel-State: [CS_EXECUTE]
Channel-State-Number: [4]
Channel-Name: [sofia/softphone/33004@test.com:5061]
Unique-ID: [763d0bb5-6417-4dae-8a14-fefb402e4a80]
Call-Direction: [inbound]
Presence-Call-Direction: [inbound]
Answer-State: [ringing]
Channel-Read-Codec-Name: [PCMA]
Channel-Read-Codec-Rate: [8000]
Channel-Write-Codec-Name: [PCMA]
Channel-Write-Codec-Rate: [8000]
Caller-Username: [33004]
Caller-Dialplan: [XML]
Caller-Caller-ID-Name: [SIP x33004 Kev]
Caller-Caller-ID-Number: [33004]
Caller-Network-Addr: [162.22.253.163]
Caller-ANI: [33004]
Caller-Destination-Number: [33001]
Caller-Unique-ID: [763d0bb5-6417-4dae-8a14-fefb402e4a80]
Caller-Source: [mod_sofia]
Caller-Context: [public]
Caller-Channel-Name: [sofia/softphone/33004@test.com:5061]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1268068467749890]
Caller-Channel-Created-Time: [1268068467749890]
Caller-Channel-Answered-Time: [0]
Caller-Channel-Progress-Time: [0]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [0]
Caller-Channel-Transfer-Time: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]
variable_uuid: [763d0bb5-6417-4dae-8a14-fefb402e4a80]
variable_sip_network_ip: [162.22.253.163]
variable_sip_network_port: [32774]
variable_sip_received_ip: [162.22.253.163]
variable_sip_received_port: [32774]
variable_sip_via_protocol: [udp]
variable_sip_from_user: [33004]
variable_sip_from_port: [5061]
variable_sip_from_uri: [33004@test.com:5061]
variable_sip_from_host: [test.com]
variable_sip_from_user_stripped: [33004]
variable_sip_from_tag: [80628b233a38df18c1f4b8bb32500]
variable_sofia_profile_name: [softphone]
variable_sip_P-Asserted-Identity: [33004]
variable_sip_cid_type: [pid]
variable_sip_full_via: [SIP/2.0/UDP 162.22.253.163:5060;branch=z9hG4bK838383030303636363c9ef.0]
variable_sip_from_display: [SIP x33004 Kev]
variable_sip_full_from: ["SIP x33004 Kev" ;tag=80628b233a38df18c1f4b8bb32500]
variable_sip_to_display: [33001]
variable_sip_full_to: ["33001" ]
variable_sip_req_params: [transport=udp;gw=33001]
variable_sip_req_user: [gw+33001]
variable_sip_req_port: [12345]
variable_sip_req_uri: [gw+33001@172.22.240.124:12345]
variable_sip_req_host: [172.22.240.124]
variable_sip_to_user: [33001]
variable_sip_to_uri: [33001@162.22.253.163]
variable_sip_to_host: [162.22.253.163]
variable_sip_contact_params: [transport=tls]
variable_sip_contact_user: [33004]
variable_sip_contact_port: [5061]
variable_sip_contact_uri: [33004@172.22.253.171:5061]
variable_sip_contact_host: [172.22.253.171]
variable_channel_name: [sofia/softphone/33004@test.com:5061]
variable_sip_call_id: [80628b233a38df18d1f4b8bb32500]
variable_sip_user_agent: [test CM/R015x.01.0.414.0]
variable_sip_via_host: [162.22.253.163]
variable_sip_via_port: [5060]
variable_max_forwards: [67]
variable_alert_info: [;test-cm-alert-type=internal]
variable_sip_gateway: [33001]
variable_sip_history_info: [;index=1,"33001" ;index=1.1]
variable_switch_r_sdp: [v=0
o=- 1 1 IN IP4 172.22.253.171
s=-
c=IN IP4 127.0.0.2
b=AS:64
t=0 0
m=audio 2096 RTP/AVP 8 18
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
]
variable_remote_media_ip: [127.0.0.2]
variable_remote_media_port: [2096]
variable_sip_use_codec_name: [PCMA]
variable_sip_use_codec_rate: [8000]
variable_sip_use_codec_ptime: [20]
variable_read_codec: [PCMA]
variable_read_rate: [8000]
variable_write_codec: [PCMA]
variable_write_rate: [8000]
variable_endpoint_disposition: [RECEIVED]
variable_current_application: [info]
EXECUTE sofia/softphone/33004@test.com:5061 set(ringback=%(2000, 4000, 440.0, 480.0))
2010-03-08 17:14:27.765515 [DEBUG] mod_dptools.c:814 sofia/softphone/33004@test.com:5061 SET [ringback]=[%(2000, 4000, 440.0, 480.0)]
EXECUTE sofia/softphone/33004@test.com:5061 pre_answer()
2010-03-08 17:14:27.765515 [INFO] mod_dptools.c:743 Sending early media
2010-03-08 17:14:27.765515 [DEBUG] sofia_glue.c:2573 AUDIO RTP [sofia/softphone/33004@test.com:5061] 172.22.240.124 port 16478 -> 127.0.0.2 port 2096 codec: 8 ms: 20
2010-03-08 17:14:27.765515 [DEBUG] switch_rtp.c:1181 Starting timer [soft] 160 bytes per 20ms
2010-03-08 17:14:27.765515 [INFO] mod_sofia.c:1901 Ring SDP:
v=0
o=FreeSWITCH 1268051989 1268051990 IN IP4 172.22.240.124
s=FreeSWITCH
c=IN IP4 172.22.240.124
t=0 0
m=audio 16478 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
2010-03-08 17:14:27.765515 [NOTICE] mod_sofia.c:1904 Pre-Answer sofia/softphone/33004@test.com:5061!
2010-03-08 17:14:27.765515 [DEBUG] switch_core_session.c:638 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
EXECUTE sofia/softphone/33004@test.com:5061 set(park_after_bridge=true)
2010-03-08 17:14:27.765515 [DEBUG] sofia.c:4134 Channel sofia/softphone/33004@test.com:5061 skipping state [early][183]
2010-03-08 17:14:27.765515 [DEBUG] mod_dptools.c:814 sofia/softphone/33004@test.com:5061 SET [park_after_bridge]=[true]
EXECUTE sofia/softphone/33004@test.com:5061 bridge(portaudio)
2010-03-08 17:14:27.765515 [NOTICE] switch_channel.c:669 New Channel portaudio/33004 [5458e790-e145-4b49-816e-35a921d83f8e]
2010-03-08 17:14:27.765515 [DEBUG] mod_PortAudio.c:778 (portaudio/33004) State Change CS_NEW -> CS_INIT
2010-03-08 17:14:27.765515 [DEBUG] switch_core_session.c:1018 Send signal portaudio/33004 [BREAK]
2010-03-08 17:14:27.765515 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:14:27.765515 [DEBUG] switch_core_state_machine.c:314 (portaudio/33004) Running State Change CS_INIT
2010-03-08 17:14:27.765515 [DEBUG] switch_core_state_machine.c:338 (portaudio/33004) State INIT
2010-03-08 17:14:27.765515 [DEBUG] mod_PortAudio.c:207 portaudio/33004 CHANNEL INIT 1 0
2010-03-08 17:14:28.343636 [DEBUG] mod_PortAudio.c:1448 Engage audio device rate: 8000 channels 1
2010-03-08 17:14:28.343636 [DEBUG] mod_PortAudio.c:1491 Engage ring device rate: 8000 channels 1
2010-03-08 17:14:28.359261 [NOTICE] mod_PortAudio.c:255 Ring-Ready portaudio/33004!
2010-03-08 17:14:28.359261 [DEBUG] mod_PortAudio.c:273 BRRRRING! BRRRRING! call 1
2010-03-08 17:14:28.749884 [DEBUG] mod_PortAudio.c:389 Stop ring device.
2010-03-08 17:14:28.749884 [DEBUG] switch_channel.c:2292 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:14:28.749884 [NOTICE] mod_PortAudio.c:1628 Channel [portaudio/33004] has been answered
2010-03-08 17:14:28.765509 [DEBUG] mod_PortAudio.c:313 (portaudio/33004) State Change CS_INIT -> CS_ROUTING
2010-03-08 17:14:28.765509 [DEBUG] switch_core_session.c:1018 Send signal portaudio/33004 [BREAK]
2010-03-08 17:14:28.765509 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:14:28.765509 [DEBUG] switch_core_state_machine.c:338 (portaudio/33004) State INIT going to sleep
2010-03-08 17:14:28.765509 [DEBUG] switch_core_state_machine.c:314 (portaudio/33004) Running State Change CS_ROUTING
2010-03-08 17:14:28.765509 [DEBUG] switch_core_state_machine.c:341 (portaudio/33004) State ROUTING
2010-03-08 17:14:28.765509 [DEBUG] mod_PortAudio.c:320 portaudio/33004 CHANNEL ROUTING
2010-03-08 17:14:28.765509 [DEBUG] switch_ivr_originate.c:66 (portaudio/33004) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2010-03-08 17:14:28.765509 [DEBUG] switch_core_session.c:1018 Send signal portaudio/33004 [BREAK]
2010-03-08 17:14:28.765509 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:14:28.765509 [DEBUG] switch_core_state_machine.c:341 (portaudio/33004) State ROUTING going to sleep
2010-03-08 17:14:28.765509 [DEBUG] switch_core_state_machine.c:314 (portaudio/33004) Running State Change CS_CONSUME_MEDIA
2010-03-08 17:14:28.765509 [DEBUG] switch_core_state_machine.c:360 (portaudio/33004) State CONSUME_MEDIA
2010-03-08 17:14:28.765509 [DEBUG] switch_core_state_machine.c:360 (portaudio/33004) State CONSUME_MEDIA going to sleep
2010-03-08 17:14:28.765509 [DEBUG] mod_sofia.c:633 Local SDP sofia/softphone/33004@test.com:5061:
v=0
o=FreeSWITCH 1268051989 1268051991 IN IP4 172.22.240.124
s=FreeSWITCH
c=IN IP4 172.22.240.124
t=0 0
m=audio 16478 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
2010-03-08 17:14:28.765509 [DEBUG] switch_core_session.c:699 Send signal portaudio/33004 [BREAK]
2010-03-08 17:14:28.765509 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:14:28.765509 [DEBUG] switch_core_session.c:638 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:14:28.765509 [NOTICE] switch_ivr_originate.c:3062 Channel [sofia/softphone/33004@test.com:5061] has been answered
2010-03-08 17:14:28.765509 [DEBUG] sofia.c:4139 Channel sofia/softphone/33004@test.com:5061 entering state [completed][200]
2010-03-08 17:14:28.765509 [DEBUG] switch_ivr_originate.c:3107 Originate Resulted in Success: [portaudio/33004]
2010-03-08 17:14:28.765509 [DEBUG] switch_core_session.c:638 Send signal portaudio/33004 [BREAK]
2010-03-08 17:14:28.765509 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:14:28.765509 [DEBUG] switch_core_session.c:638 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:14:28.765509 [DEBUG] switch_ivr_bridge.c:1178 (portaudio/33004) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2010-03-08 17:14:28.765509 [DEBUG] switch_core_session.c:1018 Send signal portaudio/33004 [BREAK]
2010-03-08 17:14:28.765509 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:14:28.765509 [DEBUG] switch_core_state_machine.c:314 (portaudio/33004) Running State Change CS_EXCHANGE_MEDIA
2010-03-08 17:14:28.765509 [DEBUG] switch_core_state_machine.c:351 (portaudio/33004) State EXCHANGE_MEDIA
2010-03-08 17:14:28.765509 [DEBUG] mod_PortAudio.c:537 CHANNEL LOOPBACK
2010-03-08 17:14:28.765509 [DEBUG] sofia.c:4139 Channel sofia/softphone/33004@test.com:5061 entering state [ready][200]
2010-03-08 17:14:28.781134 [DEBUG] switch_core_session.c:699 Send signal portaudio/33004 [BREAK]
2010-03-08 17:14:28.781134 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:14:28.781134 [DEBUG] switch_core_session.c:699 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:14:28.890508 [DEBUG] sofia.c:4139 Channel sofia/softphone/33004@test.com:5061 entering state [received][100]
2010-03-08 17:14:28.890508 [DEBUG] sofia.c:4139 Channel sofia/softphone/33004@test.com:5061 entering state [completed][200]
2010-03-08 17:14:28.984257 [DEBUG] sofia.c:4139 Channel sofia/softphone/33004@test.com:5061 entering state [ready][200]
2010-03-08 17:14:28.984257 [DEBUG] sofia.c:4150 Remote SDP:
v=0
o=- 1 2 IN IP4 172.22.253.171
s=-
c=IN IP4 172.22.253.176
b=AS:64
t=0 0
m=audio 5004 RTP/AVP 8
a=rtpmap:8 PCMA/8000
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[CELT:114:48000:10]
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[G722:9:8000:10]
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[PCMU:0:8000:10]
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[PCMA:8:8000:10]
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:3580 Substituting codec PCMA@20i@8000h
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:2271 Already using PCMA
2010-03-08 17:14:28.984257 [DEBUG] sofia.c:4590 Processing updated SDP
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:2562 Audio params changed for sofia/softphone/33004@test.com:5061 from 127.0.0.2:2096 to 172.22.253.176:5004
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:2573 AUDIO RTP [sofia/softphone/33004@test.com:5061] 172.22.240.124 port 16478 -> 172.22.253.176 port 5004 codec: 8 ms: 20
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:2592 AUDIO RTP CHANGING DEST TO: [172.22.253.176:5004]
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:3463 Our existing sdp is still good [PCMA 172.22.253.176:5004], let's keep it.
2010-03-08 17:14:28.984257 [DEBUG] sofia_glue.c:2558 Audio params are unchanged for sofia/softphone/33004@test.com:5061.
2010-03-08 17:14:29.218631 [DEBUG] switch_rtp.c:2055 Correct ip/port confirmed.
2010-03-08 17:15:20.640177 [NOTICE] switch_channel.c:669 New Channel portaudio/35011 [5c1dd447-46c0-484e-9abd-b2f4aeed1c50]
2010-03-08 17:15:20.640177 [NOTICE] mod_PortAudio.c:1811 Channel [portaudio/35011] has been answered
2010-03-08 17:15:20.640177 [DEBUG] mod_PortAudio.c:1812 (portaudio/35011) State Change CS_NEW -> CS_INIT
2010-03-08 17:15:20.640177 [DEBUG] switch_core_session.c:1018 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:20.640177 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:20.640177 [DEBUG] switch_core_state_machine.c:314 (portaudio/35011) Running State Change CS_INIT
2010-03-08 17:15:20.640177 [DEBUG] switch_core_state_machine.c:338 (portaudio/35011) State INIT
2010-03-08 17:15:20.640177 [DEBUG] mod_PortAudio.c:313 (portaudio/35011) State Change CS_INIT -> CS_ROUTING
2010-03-08 17:15:20.640177 [DEBUG] switch_core_session.c:1018 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:20.640177 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:20.640177 [DEBUG] switch_core_state_machine.c:338 (portaudio/35011) State INIT going to sleep
2010-03-08 17:15:20.640177 [DEBUG] switch_core_state_machine.c:314 (portaudio/35011) Running State Change CS_ROUTING
2010-03-08 17:15:20.640177 [DEBUG] switch_core_state_machine.c:341 (portaudio/35011) State ROUTING
2010-03-08 17:15:20.640177 [DEBUG] mod_PortAudio.c:320 portaudio/35011 CHANNEL ROUTING
2010-03-08 17:15:20.640177 [DEBUG] switch_core_state_machine.c:77 portaudio/35011 Standard ROUTING
2010-03-08 17:15:20.640177 [INFO] mod_dialplan_xml.c:405 Processing 33001->35011 in context default
Dialplan: portaudio/35011 parsing [default->codec_and_sip_uri] continue=false
Dialplan: portaudio/35011 Regex (FAIL) [codec_and_sip_uri] destination_number(35011) =~ /^sip:(.*):(.*)$/ break=on-false
Dialplan: portaudio/35011 parsing [default->sip_uri] continue=false
Dialplan: portaudio/35011 Regex (FAIL) [sip_uri] destination_number(35011) =~ /^sip:(.*)$/ break=on-false
Dialplan: portaudio/35011 parsing [default->codec_and_number] continue=false
Dialplan: portaudio/35011 Regex (FAIL) [codec_and_number] destination_number(35011) =~ /^(.*):(.*)@(.*)$/ break=on-false
Dialplan: portaudio/35011 parsing [default->number] continue=false
Dialplan: portaudio/35011 Regex (FAIL) [number] destination_number(35011) =~ /^(.*)@(.*)$/ break=on-false
Dialplan: portaudio/35011 parsing [default->number] continue=false
Dialplan: portaudio/35011 Regex (PASS) [number] destination_number(35011) =~ /^(.*)$/ break=on-false
Dialplan: portaudio/35011 Action export(origination_caller_id_number=${fscomm_caller_id_num})
Dialplan: portaudio/35011 Action export(origination_caller_id_name=${fscomm_caller_id_name})
Dialplan: portaudio/35011 Action info()
Dialplan: portaudio/35011 Action bridge(sofia/gateway/${default_gateway}/35011)
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:119 (portaudio/35011) State Change CS_ROUTING -> CS_EXECUTE
2010-03-08 17:15:20.655802 [DEBUG] switch_core_session.c:1018 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:20.655802 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:341 (portaudio/35011) State ROUTING going to sleep
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:314 (portaudio/35011) Running State Change CS_EXECUTE
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:348 (portaudio/35011) State EXECUTE
2010-03-08 17:15:20.655802 [DEBUG] mod_PortAudio.c:327 portaudio/35011 CHANNEL EXECUTE
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:157 portaudio/35011 Standard EXECUTE
EXECUTE portaudio/35011 export(origination_caller_id_number=)
2010-03-08 17:15:20.655802 [DEBUG] mod_dptools.c:898 EXPORT [origination_caller_id_number]=[UNDEF]
EXECUTE portaudio/35011 export(origination_caller_id_name=)
2010-03-08 17:15:20.655802 [DEBUG] mod_dptools.c:898 EXPORT [origination_caller_id_name]=[UNDEF]
EXECUTE portaudio/35011 info()
2010-03-08 17:15:20.655802 [INFO] mod_dptools.c:963 CHANNEL_DATA:
Channel-State: [CS_EXECUTE]
Channel-State-Number: [4]
Channel-Name: [portaudio/35011]
Unique-ID: [5c1dd447-46c0-484e-9abd-b2f4aeed1c50]
Call-Direction: [inbound]
Presence-Call-Direction: [inbound]
Answer-State: [answered]
Channel-Read-Codec-Name: [L16]
Channel-Read-Codec-Rate: [8000]
Channel-Write-Codec-Name: [L16]
Channel-Write-Codec-Rate: [8000]
Caller-Dialplan: [XML]
Caller-Caller-ID-Name: [33001]
Caller-Caller-ID-Number: [33001]
Caller-Network-Addr: [172.22.240.124]
Caller-Destination-Number: [35011]
Caller-Unique-ID: [5c1dd447-46c0-484e-9abd-b2f4aeed1c50]
Caller-Source: [mod_portaudio]
Caller-Context: [default]
Caller-Channel-Name: [portaudio/35011]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1268068520640177]
Caller-Channel-Created-Time: [1268068520640177]
Caller-Channel-Answered-Time: [1268068520640177]
Caller-Channel-Progress-Time: [0]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [0]
Caller-Channel-Transfer-Time: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]
variable_uuid: [5c1dd447-46c0-484e-9abd-b2f4aeed1c50]
variable_channel_name: [portaudio/35011]
variable_endpoint_disposition: [ANSWER]
variable_pa_call_id: [2]
variable_read_codec: [L16]
variable_read_rate: [8000]
variable_write_codec: [L16]
variable_write_rate: [8000]
variable_current_application: [info]
EXECUTE portaudio/35011 bridge(sofia/gateway/33001/35011)
2010-03-08 17:15:20.655802 [NOTICE] switch_channel.c:669 New Channel sofia/softphone/35011 [23c92448-5dea-4ef3-a507-b89b8fb2e008]
2010-03-08 17:15:20.655802 [DEBUG] mod_sofia.c:3375 (sofia/softphone/35011) State Change CS_NEW -> CS_INIT
2010-03-08 17:15:20.655802 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/35011) Running State Change CS_INIT
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:338 (sofia/softphone/35011) State INIT
2010-03-08 17:15:20.655802 [DEBUG] mod_sofia.c:83 sofia/softphone/35011 SOFIA INIT
2010-03-08 17:15:20.655802 [DEBUG] mod_sofia.c:117 (sofia/softphone/35011) State Change CS_INIT -> CS_ROUTING
2010-03-08 17:15:20.655802 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:338 (sofia/softphone/35011) State INIT going to sleep
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/35011) Running State Change CS_ROUTING
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:341 (sofia/softphone/35011) State ROUTING
2010-03-08 17:15:20.655802 [DEBUG] sofia.c:4139 Channel sofia/softphone/35011 entering state [calling][0]
2010-03-08 17:15:20.655802 [DEBUG] mod_sofia.c:140 sofia/softphone/35011 SOFIA ROUTING
2010-03-08 17:15:20.655802 [DEBUG] switch_ivr_originate.c:66 (sofia/softphone/35011) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2010-03-08 17:15:20.655802 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:341 (sofia/softphone/35011) State ROUTING going to sleep
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/35011) Running State Change CS_CONSUME_MEDIA
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:360 (sofia/softphone/35011) State CONSUME_MEDIA
2010-03-08 17:15:20.655802 [DEBUG] switch_core_state_machine.c:360 (sofia/softphone/35011) State CONSUME_MEDIA going to sleep
2010-03-08 17:15:20.671427 [DEBUG] sofia.c:4139 Channel sofia/softphone/35011 entering state [calling][0]
2010-03-08 17:15:20.780801 [INFO] sofia.c:660 Update Callee ID to "35011" <35011>
2010-03-08 17:15:20.780801 [DEBUG] sofia.c:4139 Channel sofia/softphone/35011 entering state [proceeding][180]
2010-03-08 17:15:20.780801 [DEBUG] sofia.c:4150 Remote SDP:
v=0
o=- 1 2 IN IP4 172.22.253.171
s=-
c=IN IP4 127.0.0.2
b=AS:64
t=0 0
m=audio 2120 RTP/AVP 8
a=rtpmap:8 PCMA/8000
2010-03-08 17:15:20.780801 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[L16:10:8000:20]
2010-03-08 17:15:20.780801 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[G7221:115:32000:20]
2010-03-08 17:15:20.780801 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[G7221:107:16000:20]
2010-03-08 17:15:20.780801 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[G722:9:8000:20]
2010-03-08 17:15:20.780801 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[PCMU:0:8000:20]
2010-03-08 17:15:20.780801 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[PCMA:8:8000:20]
2010-03-08 17:15:20.780801 [DEBUG] sofia_glue.c:2333 Set Codec sofia/softphone/35011 PCMA/8000 20 ms 160 samples
2010-03-08 17:15:20.780801 [DEBUG] sofia_glue.c:2573 AUDIO RTP [sofia/softphone/35011] 172.22.240.124 port 16450 -> 127.0.0.2 port 2120 codec: 8 ms: 20
2010-03-08 17:15:20.780801 [DEBUG] switch_rtp.c:1181 Starting timer [soft] 160 bytes per 20ms
2010-03-08 17:15:20.780801 [NOTICE] sofia_glue.c:3128 Pre-Answer sofia/softphone/35011!
2010-03-08 17:15:20.780801 [DEBUG] switch_channel.c:2179 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:20.780801 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:20.796426 [DEBUG] switch_ivr_originate.c:3107 Originate Resulted in Success: [sofia/softphone/35011]
2010-03-08 17:15:20.796426 [DEBUG] switch_core_session.c:638 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:20.796426 [DEBUG] switch_core_session.c:638 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:20.796426 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:20.796426 [DEBUG] switch_ivr_bridge.c:1178 (sofia/softphone/35011) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2010-03-08 17:15:20.796426 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:20.796426 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/35011) Running State Change CS_EXCHANGE_MEDIA
2010-03-08 17:15:20.796426 [DEBUG] switch_core_state_machine.c:351 (sofia/softphone/35011) State EXCHANGE_MEDIA
2010-03-08 17:15:20.796426 [DEBUG] mod_sofia.c:504 SOFIA EXCHANGE_MEDIA
2010-03-08 17:15:20.796426 [DEBUG] switch_core_session.c:699 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:20.796426 [DEBUG] switch_core_session.c:699 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:20.796426 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:22.468290 [DEBUG] sofia.c:4139 Channel sofia/softphone/35011 entering state [completing][200]
2010-03-08 17:15:22.468290 [DEBUG] sofia.c:4147 Duplicate SDP
v=0
o=- 1 2 IN IP4 172.22.253.171
s=-
c=IN IP4 127.0.0.2
b=AS:64
t=0 0
m=audio 2120 RTP/AVP 8
a=rtpmap:8 PCMA/8000
2010-03-08 17:15:22.468290 [DEBUG] sofia.c:4139 Channel sofia/softphone/35011 entering state [ready][200]
2010-03-08 17:15:22.468290 [DEBUG] switch_channel.c:2292 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:22.468290 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:22.468290 [NOTICE] sofia.c:4663 Channel [sofia/softphone/35011] has been answered
2010-03-08 17:15:22.562039 [DEBUG] sofia.c:4139 Channel sofia/softphone/35011 entering state [received][100]
2010-03-08 17:15:22.562039 [DEBUG] sofia.c:4139 Channel sofia/softphone/35011 entering state [completed][200]
2010-03-08 17:15:22.624539 [DEBUG] sofia.c:4139 Channel sofia/softphone/35011 entering state [ready][200]
2010-03-08 17:15:22.624539 [DEBUG] sofia.c:4150 Remote SDP:
v=0
o=- 1 3 IN IP4 172.22.253.171
s=-
c=IN IP4 172.22.253.175
b=AS:64
t=0 0
m=audio 5004 RTP/AVP 8
a=rtpmap:8 PCMA/8000
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[L16:10:8000:20]
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[G7221:115:32000:20]
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[G7221:107:16000:20]
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[G722:9:8000:20]
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[PCMU:0:8000:20]
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:3533 Audio Codec Compare [PCMA:8:8000:20]/[PCMA:8:8000:20]
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:2271 Already using PCMA
2010-03-08 17:15:22.624539 [DEBUG] sofia.c:4590 Processing updated SDP
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:2562 Audio params changed for sofia/softphone/35011 from 127.0.0.2:2120 to 172.22.253.175:5004
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:2573 AUDIO RTP [sofia/softphone/35011] 172.22.240.124 port 16450 -> 172.22.253.175 port 5004 codec: 8 ms: 20
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:2592 AUDIO RTP CHANGING DEST TO: [172.22.253.175:5004]
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:3463 Our existing sdp is still good [PCMA 172.22.253.175:5004], let's keep it.
2010-03-08 17:15:22.624539 [DEBUG] sofia_glue.c:2558 Audio params are unchanged for sofia/softphone/35011.
2010-03-08 17:15:23.046411 [DEBUG] switch_rtp.c:2055 Correct ip/port confirmed.
2010-03-08 17:15:49.671241 [DEBUG] switch_ivr_bridge.c:1416 (sofia/softphone/33004@test.com:5061) State Change CS_EXECUTE -> CS_HIBERNATE
2010-03-08 17:15:49.671241 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:15:49.671241 [DEBUG] switch_ivr_bridge.c:1418 (portaudio/35011) State Change CS_EXECUTE -> CS_HIBERNATE
2010-03-08 17:15:49.671241 [DEBUG] switch_core_session.c:1018 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:49.671241 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:49.671241 [DEBUG] switch_core_session.c:699 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:49.671241 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:49.671241 [DEBUG] switch_core_session.c:699 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:638 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:638 Send signal portaudio/33004 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:638 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:638 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:565 BRIDGE THREAD DONE [portaudio/35011]
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:565 BRIDGE THREAD DONE [sofia/softphone/33004@test.com:5061]
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:565 BRIDGE THREAD DONE [portaudio/33004]
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:565 BRIDGE THREAD DONE [sofia/softphone/35011]
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:585 Send signal portaudio/33004 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:585 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:585 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:585 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:49.686866 [NOTICE] switch_ivr_bridge.c:637 Hangup sofia/softphone/35011 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2010-03-08 17:15:49.686866 [NOTICE] switch_ivr_bridge.c:637 Hangup portaudio/33004 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:1275 (sofia/softphone/33004@test.com:5061) State Change CS_HIBERNATE -> CS_RESET
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] switch_channel.c:2070 Send signal portaudio/33004 [KILL]
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:1018 Send signal portaudio/33004 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:351 (portaudio/33004) State EXCHANGE_MEDIA going to sleep
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:314 (portaudio/33004) Running State Change CS_HANGUP
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:348 (sofia/softphone/33004@test.com:5061) State EXECUTE going to sleep
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:499 (portaudio/33004) State HANGUP
2010-03-08 17:15:49.686866 [DEBUG] switch_channel.c:2070 Send signal sofia/softphone/35011 [KILL]
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/33004@test.com:5061) Running State Change CS_RESET
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:504 portaudio/33004 CHANNEL HANGUP
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:351 (sofia/softphone/35011) State EXCHANGE_MEDIA going to sleep
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/35011) Running State Change CS_HANGUP
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:46 portaudio/33004 Standard HANGUP, cause: NORMAL_CLEARING
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:1275 (portaudio/35011) State Change CS_HIBERNATE -> CS_RESET
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:1018 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:499 (portaudio/33004) State HANGUP going to sleep
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:348 (portaudio/35011) State EXECUTE going to sleep
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:333 (portaudio/33004) State Change CS_HANGUP -> CS_REPORTING
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:344 (sofia/softphone/33004@test.com:5061) State RESET
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:314 (portaudio/35011) Running State Change CS_RESET
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:1018 Send signal portaudio/33004 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] mod_sofia.c:158 sofia/softphone/33004@test.com:5061 SOFIA RESET
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:344 (portaudio/35011) State RESET
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:700 portaudio/35011 CUSTOM RESET
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:314 (portaudio/33004) Running State Change CS_REPORTING
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:66 portaudio/35011 Standard RESET
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:344 (portaudio/35011) State RESET going to sleep
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:700 sofia/softphone/33004@test.com:5061 CUSTOM RESET
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:499 (sofia/softphone/35011) State HANGUP
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:590 (portaudio/33004) State REPORTING
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:707 (sofia/softphone/33004@test.com:5061) State Change CS_RESET -> CS_SOFT_EXECUTE
2010-03-08 17:15:49.686866 [DEBUG] mod_sofia.c:411 Channel sofia/softphone/35011 hanging up, cause: NORMAL_CLEARING
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:53 portaudio/33004 Standard REPORTING, cause: NORMAL_CLEARING
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] mod_sofia.c:454 Sending BYE to sofia/softphone/35011
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:590 (portaudio/33004) State REPORTING going to sleep
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:344 (sofia/softphone/33004@test.com:5061) State RESET going to sleep
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:327 (portaudio/33004) State Change CS_REPORTING -> CS_DESTROY
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:1018 Send signal portaudio/33004 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/33004@test.com:5061) Running State Change CS_SOFT_EXECUTE
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:524 portaudio/33004 CHANNEL KILL
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:1161 Session 2 (portaudio/33004) Locked, Waiting on external entities
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:354 (sofia/softphone/33004@test.com:5061) State SOFT_EXECUTE
2010-03-08 17:15:49.686866 [CRIT] fshost.cpp:243 We don't have a call object for B leg on event CHANNEL_STATE.
2010-03-08 17:15:49.686866 [DEBUG] mod_sofia.c:510 SOFIA SOFT_EXECUTE
2010-03-08 17:15:49.686866 [NOTICE] switch_core_session.c:1179 Session 2 (portaudio/33004) Ended
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:725 sofia/softphone/33004@test.com:5061 CUSTOM SOFT_EXECUTE
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:46 sofia/softphone/35011 Standard HANGUP, cause: NORMAL_CLEARING
2010-03-08 17:15:49.686866 [NOTICE] switch_core_session.c:1181 Close Channel portaudio/33004 [CS_DESTROY]
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:745 (portaudio/35011) State Change CS_RESET -> CS_SOFT_EXECUTE
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:499 (sofia/softphone/35011) State HANGUP going to sleep
2010-03-08 17:15:49.686866 [DEBUG] switch_core_session.c:1018 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:49.686866 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:314 (portaudio/35011) Running State Change CS_SOFT_EXECUTE
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:354 (portaudio/35011) State SOFT_EXECUTE
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:428 (portaudio/33004) Running State Change CS_DESTROY
2010-03-08 17:15:49.686866 [DEBUG] switch_core_state_machine.c:333 (sofia/softphone/35011) State Change CS_HANGUP -> CS_REPORTING
2010-03-08 17:15:49.686866 [DEBUG] switch_ivr_bridge.c:1083 (portaudio/35011) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA
2010-03-08 17:15:49.702491 [DEBUG] mod_PortAudio.c:531 CHANNEL TRANSMIT
2010-03-08 17:15:49.702491 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:49.702491 [CRIT] fshost.cpp:243 We don't have a call object for B leg on event CHANNEL_EXECUTE.
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:314 (sofia/softphone/35011) Running State Change CS_REPORTING
2010-03-08 17:15:49.702491 [DEBUG] switch_core_session.c:1018 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:49.702491 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:439 (portaudio/33004) State DESTROY
2010-03-08 17:15:49.702491 [DEBUG] switch_ivr_bridge.c:725 portaudio/35011 CUSTOM SOFT_EXECUTE
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:60 portaudio/33004 Standard DESTROY
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:439 (portaudio/33004) State DESTROY going to sleep
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:354 (portaudio/35011) State SOFT_EXECUTE going to sleep
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:590 (sofia/softphone/35011) State REPORTING
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:314 (portaudio/35011) Running State Change CS_CONSUME_MEDIA
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:53 sofia/softphone/35011 Standard REPORTING, cause: NORMAL_CLEARING
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:590 (sofia/softphone/35011) State REPORTING going to sleep
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:360 (portaudio/35011) State CONSUME_MEDIA
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:327 (sofia/softphone/35011) State Change CS_REPORTING -> CS_DESTROY
2010-03-08 17:15:49.702491 [DEBUG] switch_core_session.c:1018 Send signal sofia/softphone/35011 [BREAK]
2010-03-08 17:15:49.702491 [DEBUG] switch_core_session.c:1161 Session 4 (sofia/softphone/35011) Locked, Waiting on external entities
2010-03-08 17:15:49.702491 [DEBUG] switch_ivr_bridge.c:664 portaudio/35011 CUSTOM HOLD
2010-03-08 17:15:49.702491 [DEBUG] switch_core_session.c:638 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:49.702491 [CRIT] fshost.cpp:243 We don't have a call object for B leg on event CHANNEL_BRIDGE.
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:360 (portaudio/35011) State CONSUME_MEDIA going to sleep
2010-03-08 17:15:49.702491 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:49.702491 [NOTICE] switch_core_session.c:1179 Session 4 (sofia/softphone/35011) Ended
2010-03-08 17:15:49.702491 [DEBUG] switch_core_session.c:638 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:15:49.702491 [NOTICE] switch_core_session.c:1181 Close Channel sofia/softphone/35011 [CS_DESTROY]
2010-03-08 17:15:49.702491 [DEBUG] switch_ivr_bridge.c:1178 (portaudio/35011) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:428 (sofia/softphone/35011) Running State Change CS_DESTROY
2010-03-08 17:15:49.702491 [DEBUG] switch_core_session.c:1018 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:49.702491 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:314 (portaudio/35011) Running State Change CS_EXCHANGE_MEDIA
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:351 (portaudio/35011) State EXCHANGE_MEDIA
2010-03-08 17:15:49.702491 [DEBUG] mod_PortAudio.c:537 CHANNEL LOOPBACK
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:439 (sofia/softphone/35011) State DESTROY
2010-03-08 17:15:49.702491 [DEBUG] mod_sofia.c:338 sofia/softphone/35011 SOFIA DESTROY
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:60 sofia/softphone/35011 Standard DESTROY
2010-03-08 17:15:49.702491 [DEBUG] switch_core_state_machine.c:439 (sofia/softphone/35011) State DESTROY going to sleep
2010-03-08 17:15:49.702491 [DEBUG] switch_core_session.c:699 Send signal portaudio/35011 [BREAK]
2010-03-08 17:15:49.702491 [DEBUG] mod_PortAudio.c:524 portaudio/35011 CHANNEL KILL
2010-03-08 17:15:49.702491 [DEBUG] switch_core_session.c:699 Send signal sofia/softphone/33004@test.com:5061 [BREAK]
2010-03-08 17:15:49.718116 [CRIT] fshost.cpp:243 We don't have a call object for B leg on event CALL_UPDATE.