[May 25 14:59:05] Asterisk 1.4.4, Copyright (C) 1999 - 2006 Digium, Inc. and others. [May 25 14:59:05] Created by Mark Spencer [May 25 14:59:05] Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details. [May 25 14:59:05] This is free software, with components licensed under the GNU General Public [May 25 14:59:05] License version 2 and other licenses; you are welcome to redistribute it under [May 25 14:59:05] certain conditions. Type 'core show license' for details. [May 25 14:59:05] ========================================================================= [May 25 14:59:05] == Parsing '/etc/asterisk/asterisk.conf': Parsing /etc/asterisk/asterisk.conf [May 25 14:59:05] Found [May 25 14:59:05] == Parsing '/etc/asterisk/extconfig.conf': Parsing /etc/asterisk/extconfig.conf [May 25 14:59:05] Found [May 25 14:59:05] == Binding sipusers to odbc/mysql_config/sip [May 25 14:59:05] == Binding sippeers to odbc/mysql_config/sip [May 25 14:59:05] == Parsing '/etc/asterisk/logger.conf': Parsing /etc/asterisk/logger.conf [May 25 14:59:05] Found [May 25 14:59:05] Asterisk Event Logger Started /var/log/asterisk/event_log [May 25 14:59:05] Asterisk Dynamic Loader Starting: [May 25 14:59:05] == Parsing '/etc/asterisk/modules.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/modules.conf [May 25 14:59:05] Found [May 25 14:59:05] == Manager registered action Ping [May 25 14:59:05] == Manager registered action Events [May 25 14:59:05] == Manager registered action Logoff [May 25 14:59:05] == Manager registered action Hangup [May 25 14:59:05] == Manager registered action Status [May 25 14:59:05] == Manager registered action Setvar [May 25 14:59:05] == Manager registered action Getvar [May 25 14:59:05] == Manager registered action GetConfig [May 25 14:59:05] == Manager registered action UpdateConfig [May 25 14:59:05] == Manager registered action Redirect [May 25 14:59:05] == Manager registered action Originate [May 25 14:59:05] == Manager registered action Command [May 25 14:59:05] == Manager registered action ExtensionState [May 25 14:59:05] == Manager registered action AbsoluteTimeout [May 25 14:59:05] == Manager registered action MailboxStatus [May 25 14:59:05] == Manager registered action MailboxCount [May 25 14:59:05] == Manager registered action ListCommands [May 25 14:59:05] == Manager registered action UserEvent [May 25 14:59:05] == Manager registered action WaitEvent [May 25 14:59:05] NOTICE[3030]: manager.c:2738 init_manager: Unable to open management configuration manager.conf. Call management disabled. [May 25 14:59:05] == Parsing '/etc/asterisk/cdr.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/cdr.conf [May 25 14:59:05] Found [May 25 14:59:05] NOTICE[3030]: cdr.c:1283 do_reload: CDR simple logging enabled. [May 25 14:59:05] == RTP Allocating from port range 5000 -> 31000 [May 25 14:59:05] == UDPTL allocating from port range 4500 -> 4999 [May 25 14:59:05] Asterisk PBX Core Initializing [May 25 14:59:05] Registering builtin applications: [May 25 14:59:05] [Answer] [May 25 14:59:05] == Registered application 'Answer' [May 25 14:59:05] [BackGround] [May 25 14:59:05] == Registered application 'BackGround' [May 25 14:59:05] [Busy] [May 25 14:59:05] == Registered application 'Busy' [May 25 14:59:05] [Congestion] [May 25 14:59:05] == Registered application 'Congestion' [May 25 14:59:05] [Goto] [May 25 14:59:05] == Registered application 'Goto' [May 25 14:59:05] [GotoIf] [May 25 14:59:05] == Registered application 'GotoIf' [May 25 14:59:05] [GotoIfTime] [May 25 14:59:05] == Registered application 'GotoIfTime' [May 25 14:59:05] [ExecIfTime] [May 25 14:59:05] == Registered application 'ExecIfTime' [May 25 14:59:05] [Hangup] [May 25 14:59:05] == Registered application 'Hangup' [May 25 14:59:05] [NoOp] [May 25 14:59:05] == Registered application 'NoOp' [May 25 14:59:05] [Progress] [May 25 14:59:05] == Registered application 'Progress' [May 25 14:59:05] [ResetCDR] [May 25 14:59:05] == Registered application 'ResetCDR' [May 25 14:59:05] [Ringing] [May 25 14:59:05] == Registered application 'Ringing' [May 25 14:59:05] [SayNumber] [May 25 14:59:05] == Registered application 'SayNumber' [May 25 14:59:05] [SayDigits] [May 25 14:59:05] == Registered application 'SayDigits' [May 25 14:59:05] [SayAlpha] [May 25 14:59:05] == Registered application 'SayAlpha' [May 25 14:59:05] [SayPhonetic] [May 25 14:59:05] == Registered application 'SayPhonetic' [May 25 14:59:05] [SetAMAFlags] [May 25 14:59:05] == Registered application 'SetAMAFlags' [May 25 14:59:05] [SetGlobalVar] [May 25 14:59:05] == Registered application 'SetGlobalVar' [May 25 14:59:05] [Set] [May 25 14:59:05] == Registered application 'Set' [May 25 14:59:05] [ImportVar] [May 25 14:59:05] == Registered application 'ImportVar' [May 25 14:59:05] [Wait] [May 25 14:59:05] == Registered application 'Wait' [May 25 14:59:05] [WaitExten] [May 25 14:59:05] == Registered application 'WaitExten' [May 25 14:59:05] == Manager registered action DBGet [May 25 14:59:05] == Manager registered action DBPut [May 25 14:59:05] Asterisk Dynamic Loader Starting: [May 25 14:59:05] == Parsing '/etc/asterisk/modules.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/modules.conf [May 25 14:59:05] Found [May 25 14:59:05] NOTICE[3030]: loader.c:799 load_modules: 139 modules will be loaded. [May 25 14:59:05] == Registered application 'MusicOnHold' [May 25 14:59:05] == Registered application 'WaitMusicOnHold' [May 25 14:59:05] == Registered application 'SetMusicOnHold' [May 25 14:59:05] == Registered application 'StartMusicOnHold' [May 25 14:59:05] == Registered application 'StopMusicOnHold' [May 25 14:59:05] WARNING[3030]: res_musiconhold.c:1270 load_module: No music on hold classes configured, disabling music on hold. [May 25 14:59:05] res_musiconhold.so => (Music On Hold Resource) [May 25 14:59:05] == Parsing '/etc/asterisk/features.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/features.conf [May 25 14:59:05] Found [May 25 14:59:05] DEBUG[3030]: pbx.c:3871 __ast_context_create: Registered context 'parkedcalls' [May 25 14:59:05] -- Registered extension context 'parkedcalls' [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension '700' priority 1 to parkedcalls [May 25 14:59:05] -- Added extension '700' priority 1 to parkedcalls [May 25 14:59:05] DEBUG[3030]: res_features.c:279 notify_metermaids: Notification of state change to metermaids 700@parkedcalls [May 25 14:59:05] DEBUG[3030]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel park:700@parkedcalls [May 25 14:59:05] DEBUG[3034]: devicestate.c:157 ast_device_state: Checking if I can find provider for "park" - number: 700@parkedcalls [May 25 14:59:05] DEBUG[3034]: devicestate.c:287 do_state_change: Changing state for park:700@parkedcalls - state 4 (Invalid) [May 25 14:59:05] == Registered application 'ParkedCall' [May 25 14:59:05] == Registered application 'Park' [May 25 14:59:05] == Manager registered action ParkedCalls [May 25 14:59:05] == Manager registered action Park [May 25 14:59:05] res_features.so => (Call Features Resource) [May 25 14:59:05] res_speech.so => (Generic Speech Recognition API) [May 25 14:59:05] NOTICE[3030]: config.c:1172 ast_config_engine_register: Registered Config Engine odbc [May 25 14:59:05] res_config_odbc loaded. [May 25 14:59:05] res_config_odbc.so => (ODBC Configuration) [May 25 14:59:05] res_adsi.so => (ADSI Resource) [May 25 14:59:05] == Registered application 'Monitor' [May 25 14:59:05] == Registered application 'StopMonitor' [May 25 14:59:05] == Registered application 'ChangeMonitor' [May 25 14:59:05] == Registered application 'PauseMonitor' [May 25 14:59:05] == Registered application 'UnpauseMonitor' [May 25 14:59:05] == Manager registered action Monitor [May 25 14:59:05] == Manager registered action StopMonitor [May 25 14:59:05] == Manager registered action ChangeMonitor [May 25 14:59:05] == Manager registered action PauseMonitor [May 25 14:59:05] == Manager registered action UnpauseMonitor [May 25 14:59:05] res_monitor.so => (Call Monitoring Resource) [May 25 14:59:05] == Parsing '/etc/asterisk/res_odbc.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/res_odbc.conf [May 25 14:59:05] Found [May 25 14:59:05] NOTICE[3030]: res_odbc.c:228 load_odbc_config: Adding ENV var: INFORMIXSERVER=my_special_database [May 25 14:59:05] NOTICE[3030]: res_odbc.c:228 load_odbc_config: Adding ENV var: INFORMIXDIR=/opt/informix [May 25 14:59:05] NOTICE[3030]: res_odbc.c:502 odbc_obj_connect: Connecting asterisk [May 25 14:59:05] WARNING[3030]: res_odbc.c:513 odbc_obj_connect: res_odbc: Error SQLConnect=-1 errno=0 [unixODBC][Driver Manager]Data source name not found, and no default driver specified [May 25 14:59:05] WARNING[3030]: res_odbc.c:435 ast_odbc_request_obj: Failed to connect to asterisk [May 25 14:59:05] NOTICE[3030]: res_odbc.c:301 load_odbc_config: Registered ODBC class 'asterisk' dsn->[asterisk] [May 25 14:59:05] NOTICE[3030]: res_odbc.c:502 odbc_obj_connect: Connecting mysql_cdr [May 25 14:59:05] NOTICE[3030]: res_odbc.c:516 odbc_obj_connect: res_odbc: Connected to mysql_cdr [MySQL-asterisk] [May 25 14:59:05] NOTICE[3030]: res_odbc.c:301 load_odbc_config: Registered ODBC class 'mysql_cdr' dsn->[MySQL-asterisk] [May 25 14:59:05] NOTICE[3030]: res_odbc.c:502 odbc_obj_connect: Connecting mysql_config [May 25 14:59:05] NOTICE[3030]: res_odbc.c:516 odbc_obj_connect: res_odbc: Connected to mysql_config [MySQL-asterisk] [May 25 14:59:05] NOTICE[3030]: res_odbc.c:301 load_odbc_config: Registered ODBC class 'mysql_config' dsn->[MySQL-asterisk] [May 25 14:59:05] NOTICE[3030]: res_odbc.c:678 load_module: res_odbc loaded. [May 25 14:59:05] res_odbc.so => (ODBC Resource) [May 25 14:59:05] == Registered application 'SendURL' [May 25 14:59:05] app_url.so => (Send URL Applications) [May 25 14:59:05] WARNING[3030]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 [May 25 14:59:05] == Registered translator 'ulawtolin' from format ulaw to slin, cost 1 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] == Registered translator 'lintoulaw' from format slin to ulaw, cost 1 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] codec_ulaw.so => (mu-Law Coder/Decoder) [May 25 14:59:05] == Registered file format g729, extension(s) g729 [May 25 14:59:05] format_g729.so => (Raw G729 data) [May 25 14:59:05] == Registered file format wav49, extension(s) WAV|wav49 [May 25 14:59:05] format_wav_gsm.so => (Microsoft WAV format (Proprietary GSM)) [May 25 14:59:05] == Registered file format vox, extension(s) vox [May 25 14:59:05] format_vox.so => (Dialogic VOX (ADPCM) File Format) [May 25 14:59:05] WARNING[3030]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 [May 25 14:59:05] == Registered translator 'adpcmtolin' from format adpcm to slin, cost 1 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] == Registered translator 'lintoadpcm' from format slin to adpcm, cost 1 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] codec_adpcm.so => (Adaptive Differential PCM Coder/Decoder) [May 25 14:59:05] == Registered custom function VMCOUNT [May 25 14:59:05] == Registered application 'HasVoicemail' [May 25 14:59:05] == Registered application 'HasNewVoicemail' [May 25 14:59:05] app_hasnewvoicemail.so => (Indicator for whether a voice mailbox has messages in a given folder.) [May 25 14:59:05] == Registered application 'ExternalIVR' [May 25 14:59:05] app_externalivr.so => (External IVR Interface Application) [May 25 14:59:05] NOTICE[3030]: app_queue.c:3737 reload_queues: No call queueing config file (queues.conf), so no call queues [May 25 14:59:05] == Registered application 'ZapSendKeypadFacility' [May 25 14:59:05] == Parsing '/etc/asterisk/zapata.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/zapata.conf [May 25 14:59:05] Found [May 25 14:59:05] DEBUG[3030]: chan_zap.c:1403 update_conf: Updated conferencing on 1, with 0 conference users [May 25 14:59:05] -- Registered channel 1, FXS Kewlstart signalling [May 25 14:59:05] DEBUG[3030]: chan_zap.c:1403 update_conf: Updated conferencing on 2, with 0 conference users [May 25 14:59:05] -- Registered channel 2, FXS Kewlstart signalling [May 25 14:59:05] DEBUG[3030]: chan_zap.c:1403 update_conf: Updated conferencing on 3, with 0 conference users [May 25 14:59:05] -- Registered channel 3, FXS Kewlstart signalling [May 25 14:59:05] DEBUG[3030]: chan_zap.c:1403 update_conf: Updated conferencing on 4, with 0 conference users [May 25 14:59:05] -- Registered channel 4, FXS Kewlstart signalling [May 25 14:59:05] -- Automatically generated pseudo channel [May 25 14:59:05] DEBUG[3030]: channel.c:532 ast_channel_register: Registered handler for 'Zap' (Zapata Telephony Driver w/PRI) [May 25 14:59:05] == Registered channel type 'Zap' (Zapata Telephony Driver w/PRI) [May 25 14:59:05] == Manager registered action ZapTransfer [May 25 14:59:05] == Manager registered action ZapHangup [May 25 14:59:05] == Manager registered action ZapDialOffhook [May 25 14:59:05] == Manager registered action ZapDNDon [May 25 14:59:05] == Manager registered action ZapDNDoff [May 25 14:59:05] == Manager registered action ZapShowChannels [May 25 14:59:05] == Manager registered action ZapRestart [May 25 14:59:05] chan_zap.so => (Zapata Telephony) [May 25 14:59:05] == Registered application 'Transfer' [May 25 14:59:05] app_transfer.so => (Transfer) [May 25 14:59:05] == Registered application 'SpeechCreate' [May 25 14:59:05] == Registered application 'SpeechLoadGrammar' [May 25 14:59:05] == Registered application 'SpeechUnloadGrammar' [May 25 14:59:05] == Registered application 'SpeechActivateGrammar' [May 25 14:59:05] == Registered application 'SpeechDeactivateGrammar' [May 25 14:59:05] == Registered application 'SpeechStart' [May 25 14:59:05] == Registered application 'SpeechBackground' [May 25 14:59:05] == Registered application 'SpeechDestroy' [May 25 14:59:05] == Registered application 'SpeechProcessingSound' [May 25 14:59:05] == Registered custom function SPEECH [May 25 14:59:05] == Registered custom function SPEECH_SCORE [May 25 14:59:05] == Registered custom function SPEECH_TEXT [May 25 14:59:05] == Registered custom function SPEECH_GRAMMAR [May 25 14:59:05] == Registered custom function SPEECH_ENGINE [May 25 14:59:05] == Registered custom function SPEECH_RESULTS_TYPE [May 25 14:59:05] app_speech_utils.so => (Dialplan Speech Applications) [May 25 14:59:05] pbx_realtime.so => (Realtime Switch) [May 25 14:59:05] == Registered application 'ControlPlayback' [May 25 14:59:05] app_controlplayback.so => (Control Playback Application) [May 25 14:59:05] == Registered application 'SayCountPL' [May 25 14:59:05] app_saycountpl.so => (Say polish counting words) [May 25 14:59:05] == Registered application 'Record' [May 25 14:59:05] app_record.so => (Trivial Record Application) [May 25 14:59:05] == Registered application 'DISA' [May 25 14:59:05] app_disa.so => (DISA (Direct Inward System Access) Application) [May 25 14:59:05] == Registered application 'VoiceMail' [May 25 14:59:05] == Registered application 'VoiceMailMain' [May 25 14:59:05] == Registered application 'MailboxExists' [May 25 14:59:05] == Registered application 'VMAuthenticate' [May 25 14:59:05] == Parsing '/etc/asterisk/voicemail.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/voicemail.conf [May 25 14:59:05] Found [May 25 14:59:05] WARNING[3030]: config.c:718 process_text_line: Unknown directive 'imapflags=notls/debug' at line 16 of /etc/asterisk/voicemail.conf [May 25 14:59:05] DEBUG[3030]: app_voicemail.c:7414 load_config: VM Review Option disabled globally [May 25 14:59:05] DEBUG[3030]: app_voicemail.c:7422 load_config: VM Temperary Greeting Reminder Option disabled globally [May 25 14:59:05] DEBUG[3030]: app_voicemail.c:7432 load_config: VM Operator break disabled globally [May 25 14:59:05] DEBUG[3030]: app_voicemail.c:7439 load_config: VM CID Info before msg disabled globally [May 25 14:59:05] DEBUG[3030]: app_voicemail.c:7453 load_config: ENVELOPE before msg enabled globally [May 25 14:59:05] DEBUG[3030]: app_voicemail.c:7460 load_config: Duration info before msg enabled globally [May 25 14:59:05] DEBUG[3030]: app_voicemail.c:7476 load_config: We are not going to skip to the next msg after save/delete [May 25 14:59:05] app_voicemail.so => (Comedian Mail (Voicemail System) with IMAP Storage) [May 25 14:59:05] == Registered application 'Exec' [May 25 14:59:05] == Registered application 'TryExec' [May 25 14:59:05] == Registered application 'ExecIf' [May 25 14:59:05] app_exec.so => (Executes dialplan applications) [May 25 14:59:05] == Registered custom function GLOBAL [May 25 14:59:05] func_global.so => (Global variable dialplan functions) [May 25 14:59:05] == Registered application 'MixMonitor' [May 25 14:59:05] == Registered application 'StopMixMonitor' [May 25 14:59:05] app_mixmonitor.so => (Mixed Audio Monitoring Application) [May 25 14:59:05] == Registered custom function CDR [May 25 14:59:05] func_cdr.so => (CDR dialplan function) [May 25 14:59:05] == Registered translator 'alawtoulaw' from format alaw to ulaw, cost 1 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] == Registered translator 'ulawtoalaw' from format ulaw to alaw, cost 1 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] codec_a_mu.so => (A-law and Mulaw direct Coder/Decoder) [May 25 14:59:05] == Registered custom function FIELDQTY [May 25 14:59:05] == Registered custom function FILTER [May 25 14:59:05] == Registered custom function REGEX [May 25 14:59:05] == Registered custom function ARRAY [May 25 14:59:05] == Registered custom function QUOTE [May 25 14:59:05] == Registered custom function LEN [May 25 14:59:05] == Registered custom function STRFTIME [May 25 14:59:05] == Registered custom function STRPTIME [May 25 14:59:05] == Registered custom function EVAL [May 25 14:59:05] == Registered custom function KEYPADHASH [May 25 14:59:05] == Registered custom function SPRINTF [May 25 14:59:05] func_strings.so => (String handling dialplan functions) [May 25 14:59:05] == Registered application 'DeadAGI' [May 25 14:59:05] == Registered application 'EAGI' [May 25 14:59:05] == Registered application 'AGI' [May 25 14:59:05] res_agi.so => (Asterisk Gateway Interface (AGI)) [May 25 14:59:05] == Registered application 'SetCallerPres' [May 25 14:59:05] == Registered application 'SetCallerID' [May 25 14:59:05] app_setcallerid.so => (Set CallerID Application) [May 25 14:59:05] == Registered application 'DumpChan' [May 25 14:59:05] app_dumpchan.so => (Dump Info About The Calling Channel) [May 25 14:59:05] == Registered application 'ParkAndAnnounce' [May 25 14:59:05] app_parkandannounce.so => (Call Parking and Announce Application) [May 25 14:59:05] == Manager registered action MeetmeMute [May 25 14:59:05] == Manager registered action MeetmeUnmute [May 25 14:59:05] == Registered application 'MeetMeAdmin' [May 25 14:59:05] == Registered application 'MeetMeCount' [May 25 14:59:05] == Registered application 'MeetMe' [May 25 14:59:05] == Registered application 'SLAStation' [May 25 14:59:05] == Registered application 'SLATrunk' [May 25 14:59:05] app_meetme.so => (MeetMe conference bridge) [May 25 14:59:05] == Registered custom function BASE64_ENCODE [May 25 14:59:05] == Registered custom function BASE64_DECODE [May 25 14:59:05] func_base64.so => (base64 encode/decode dialplan functions) [May 25 14:59:05] == Registered application 'Flash' [May 25 14:59:05] app_flash.so => (Flash channel application) [May 25 14:59:05] pbx_spool.so => (Outgoing Spool Support) [May 25 14:59:05] == Registered application 'ZapScan' [May 25 14:59:05] app_zapscan.so => (Scan Zap channels application) [May 25 14:59:05] == Registered custom function BLACKLIST [May 25 14:59:05] == Registered application 'LookupBlacklist' [May 25 14:59:05] app_lookupblacklist.so => (Look up Caller*ID name/number from blacklist database) [May 25 14:59:05] == Parsing '/etc/asterisk/cdr.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/cdr.conf [May 25 14:59:05] Found [May 25 14:59:05] DEBUG[3030]: cdr_csv.c:124 load_config: logging time in GMT [May 25 14:59:05] DEBUG[3030]: cdr_csv.c:132 load_config: logging CDR field UNIQUEID [May 25 14:59:05] DEBUG[3030]: cdr_csv.c:140 load_config: logging CDR user-defined field [May 25 14:59:05] cdr_csv.so => (Comma Separated Values CDR Backend) [May 25 14:59:05] == Registered file format h263, extension(s) h263 [May 25 14:59:05] format_h263.so => (Raw H.263 data) [May 25 14:59:05] == Registered application 'Authenticate' [May 25 14:59:05] app_authenticate.so => (Authentication Application) [May 25 14:59:05] == Registered application 'LookupCIDName' [May 25 14:59:05] app_lookupcidname.so => (Look up CallerID Name from local database) [May 25 14:59:05] DEBUG[3030]: channel.c:532 ast_channel_register: Registered handler for 'Local' (Local Proxy Channel Driver) [May 25 14:59:05] == Registered channel type 'Local' (Local Proxy Channel Driver) [May 25 14:59:05] chan_local.so => (Local Proxy Channel) [May 25 14:59:05] == Registered application 'ICES' [May 25 14:59:05] app_ices.so => (Encode and Stream via icecast and ices) [May 25 14:59:05] == Registered application 'Random' [May 25 14:59:05] app_random.so => (Random goto) [May 25 14:59:05] == Registered application 'Echo' [May 25 14:59:05] app_echo.so => (Simple Echo Application) [May 25 14:59:05] WARNING[3030]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 [May 25 14:59:05] == Registered translator 'gsmtolin' from format gsm to slin, cost 2 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] == Registered translator 'lintogsm' from format slin to gsm, cost 5 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 1 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] codec_gsm.so => (GSM Coder/Decoder) [May 25 14:59:05] == Registered custom function LANGUAGE [May 25 14:59:05] func_language.so => (Channel language dialplan function) [May 25 14:59:05] == Registered application 'SendText' [May 25 14:59:05] app_sendtext.so => (Send Text Applications) [May 25 14:59:05] == Manager registered action PlayDTMF [May 25 14:59:05] == Registered application 'SendDTMF' [May 25 14:59:05] app_senddtmf.so => (Send DTMF digits Application) [May 25 14:59:05] NOTICE[3030]: chan_oss.c:1837 load_module: Unable to load config oss.conf [May 25 14:59:05] == Registered application 'Milliwatt' [May 25 14:59:05] app_milliwatt.so => (Digital Milliwatt (mu-law) Test Application) [May 25 14:59:05] == Registered application 'Playback' [May 25 14:59:05] app_playback.so => (Sound File Playback Application) [May 25 14:59:05] == Registered application 'SendImage' [May 25 14:59:05] app_image.so => (Image Transmission Application) [May 25 14:59:05] == Registered custom function IAXPEER [May 25 14:59:05] == Registered application 'IAX2Provision' [May 25 14:59:05] == Manager registered action IAXpeers [May 25 14:59:05] == Manager registered action IAXnetstats [May 25 14:59:05] == Parsing '/etc/asterisk/iax.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/iax.conf [May 25 14:59:05] Found [May 25 14:59:05] > doing dnsmgr_lookup for '192.168.2.140' [May 25 14:59:05] == Using TOS bits 0 [May 25 14:59:05] == Binding IAX2 to default address 0.0.0.0:4569 [May 25 14:59:05] > doing dnsmgr_lookup for '216.207.245.47' [May 25 14:59:05] > doing dnsmgr_lookup for '192.168.2.140' [May 25 14:59:05] DEBUG[3030]: channel.c:532 ast_channel_register: Registered handler for 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) [May 25 14:59:05] == Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) [May 25 14:59:05] == 10 helper threaads started [May 25 14:59:05] == IAX Ready and Listening [May 25 14:59:05] DEBUG[3030]: chan_iax2.c:7726 iax2_do_register: Allocate call number [May 25 14:59:05] DEBUG[3030]: chan_iax2.c:7732 iax2_do_register: Registration created on call 1 [May 25 14:59:05] == Loaded firmware 'iaxy.bin' [May 25 14:59:05] NOTICE[3030]: iax2-provision.c:518 iax_provision_reload: No IAX provisioning configuration found, IAX provisioning disabled. [May 25 14:59:05] chan_iax2.so => (Inter Asterisk eXchange (Ver 2)) [May 25 14:59:05] == Parsing '/etc/asterisk/cdr_odbc.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/cdr_odbc.conf [May 25 14:59:05] Found [May 25 14:59:05] DEBUG[3030]: cdr_odbc.c:314 odbc_load_module: cdr_odbc: Not logging uniqueid [May 25 14:59:05] DEBUG[3030]: cdr_odbc.c:327 odbc_load_module: cdr_odbc: Not logging in GMT [May 25 14:59:05] -- cdr_odbc: dsn is MySQL-asterisk [May 25 14:59:05] -- cdr_odbc: username is asterisk [May 25 14:59:05] -- cdr_odbc: password is [secret] [May 25 14:59:05] -- cdr_odbc: table is cdr [May 25 14:59:05] cdr_odbc.so => (ODBC CDR Backend) [May 25 14:59:05] == No hardware transcoders found. [May 25 14:59:05] codec_zap.so => (Generic Zaptel Transcoder Codec Translator) [May 25 14:59:05] == Registered application 'MacroExit' [May 25 14:59:05] == Registered application 'MacroIf' [May 25 14:59:05] == Registered application 'MacroExclusive' [May 25 14:59:05] == Registered application 'Macro' [May 25 14:59:05] app_macro.so => (Extension Macros) [May 25 14:59:05] == Registered application 'ForkCDR' [May 25 14:59:05] app_forkcdr.so => (Fork The CDR into 2 separate entities) [May 25 14:59:05] WARNING[3030]: translate.c:675 __ast_register_translator: plc_samples 180 format 6 [May 25 14:59:05] == Registered translator 'lpc10tolin' from format lpc10 to slin, cost 4 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 1 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] == Registered translator 'lintolpc10' from format slin to lpc10, cost 7 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 1 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] codec_lpc10.so => (LPC10 2.4kbps Coder/Decoder) [May 25 14:59:05] == Parsing '/etc/asterisk/func_odbc.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/func_odbc.conf [May 25 14:59:05] Found [May 25 14:59:05] == Registered custom function ODBC_SQL [May 25 14:59:05] == Registered custom function ODBC_ANTIGF [May 25 14:59:05] == Registered custom function ODBC_PRESENCE [May 25 14:59:05] == Registered custom function SQL_ESC [May 25 14:59:05] func_odbc.so => (ODBC lookups) [May 25 14:59:05] == Registered custom function TIMEOUT [May 25 14:59:05] func_timeout.so => (Channel timeout dialplan functions) [May 25 14:59:05] == Registered application 'SayUnixTime' [May 25 14:59:05] == Registered application 'DateTime' [May 25 14:59:05] app_sayunixtime.so => (Say time) [May 25 14:59:05] == Registered file format iLBC, extension(s) ilbc [May 25 14:59:05] format_ilbc.so => (Raw iLBC data) [May 25 14:59:05] == Registered file format gsm, extension(s) gsm [May 25 14:59:05] format_gsm.so => (Raw GSM data) [May 25 14:59:05] == Registered application 'ChanSpy' [May 25 14:59:05] == Registered application 'ExtenSpy' [May 25 14:59:05] app_chanspy.so => (Listen to the audio of an active channel) [May 25 14:59:05] == Registered application 'WaitForRing' [May 25 14:59:05] app_waitforring.so => (Waits until first ring after time) [May 25 14:59:05] == Registered application 'Pickup' [May 25 14:59:05] app_directed_pickup.so => (Directed Call Pickup Application) [May 25 14:59:05] == Registered file format wav, extension(s) wav [May 25 14:59:05] format_wav.so => (Microsoft WAV format (8000Hz Signed Linear)) [May 25 14:59:05] == Registered custom function ENV [May 25 14:59:05] == Registered custom function STAT [May 25 14:59:05] func_env.so => (Environment/filesystem dialplan functions) [May 25 14:59:05] == Registered file format g726-40, extension(s) g726-40 [May 25 14:59:05] == Registered file format g726-32, extension(s) g726-32 [May 25 14:59:05] == Registered file format g726-24, extension(s) g726-24 [May 25 14:59:05] == Registered file format g726-16, extension(s) g726-16 [May 25 14:59:05] format_g726.so => (Raw G.726 (16/24/32/40kbps) data) [May 25 14:59:05] == Registered custom function CUT [May 25 14:59:05] == Registered custom function SORT [May 25 14:59:05] func_cut.so => (Cut out information from a string) [May 25 14:59:05] == Registered custom function ENUMLOOKUP [May 25 14:59:05] == Registered custom function TXTCIDNAME [May 25 14:59:05] func_enum.so => (ENUM related dialplan functions) [May 25 14:59:05] == Registered application 'SetCDRUserField' [May 25 14:59:05] == Registered application 'AppendCDRUserField' [May 25 14:59:05] == Manager registered action SetCDRUserField [May 25 14:59:05] app_setcdruserfield.so => (CDR user field apps) [May 25 14:59:05] == Registered application 'MP3Player' [May 25 14:59:05] app_mp3.so => (Silly MP3 Application) [May 25 14:59:05] == Registered translator 'ilbctolin' from format ilbc to slin, cost 6 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 1 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] -- Registered IAX2 to '192.168.2.140', who sees us as 192.168.2.150:4569 with no messages waiting [May 25 14:59:05] == Registered translator 'lintoilbc' from format slin to ilbc, cost 46 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 50 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 1 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] codec_ilbc.so => (iLBC Coder/Decoder) [May 25 14:59:05] == Parsing '/etc/asterisk/sip.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/sip.conf [May 25 14:59:05] Found [May 25 14:59:05] == SIP Listening on 0.0.0.0:5060 [May 25 14:59:05] == Using SIP TOS: none [May 25 14:59:05] == Parsing '/etc/asterisk/sip_notify.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/sip_notify.conf [May 25 14:59:05] Found [May 25 14:59:05] DEBUG[3030]: channel.c:532 ast_channel_register: Registered handler for 'SIP' (Session Initiation Protocol (SIP)) [May 25 14:59:05] == Registered channel type 'SIP' (Session Initiation Protocol (SIP)) [May 25 14:59:05] == Registered application 'SIPDtmfMode' [May 25 14:59:05] == Registered application 'SIPAddHeader' [May 25 14:59:05] == Registered custom function SIP_HEADER [May 25 14:59:05] == Registered custom function SIPPEER [May 25 14:59:05] == Registered custom function SIPCHANINFO [May 25 14:59:05] == Registered custom function CHECKSIPDOMAIN [May 25 14:59:05] == Manager registered action SIPpeers [May 25 14:59:05] == Manager registered action SIPshowpeer [May 25 14:59:05] chan_sip.so => (Session Initiation Protocol (SIP)) [May 25 14:59:05] == Registered custom function GROUP_COUNT [May 25 14:59:05] == Registered custom function GROUP_MATCH_COUNT [May 25 14:59:05] == Registered custom function GROUP_LIST [May 25 14:59:05] == Registered custom function GROUP [May 25 14:59:05] func_groupcount.so => (Channel group dialplan functions) [May 25 14:59:05] WARNING[3030]: app_festival.c:545 load_module: No such configuration file festival.conf [May 25 14:59:05] == Registered application 'SetTransferCapability' [May 25 14:59:05] app_settransfercapability.so => (Set ISDN Transfer Capability) [May 25 14:59:05] == Registered application 'Page' [May 25 14:59:05] app_page.so => (Page Multiple Phones) [May 25 14:59:05] == Registered application 'Read' [May 25 14:59:05] app_read.so => (Read Variable Application) [May 25 14:59:05] == Registered file format g723sf, extension(s) g723|g723sf [May 25 14:59:05] format_g723.so => (G.723.1 Simple Timestamp File Format) [May 25 14:59:05] == Registered application 'UserEvent' [May 25 14:59:05] app_userevent.so => (Custom User Event Application) [May 25 14:59:05] > AlarmReceiver: No config file [May 25 14:59:05] == Registered file format pcm, extension(s) pcm|ulaw|ul|mu [May 25 14:59:05] == Registered file format alaw, extension(s) alaw|al [May 25 14:59:05] == Registered file format au, extension(s) au [May 25 14:59:05] == Registered file format g722, extension(s) g722 [May 25 14:59:05] format_pcm.so => (Raw/Sun uLaw/ALaw 8KHz Audio support (PCM,PCMA,AU) and G.722 16Khz Audio Support) [May 25 14:59:05] == Registered file format h264, extension(s) h264 [May 25 14:59:05] format_h264.so => (Raw H.264 data) [May 25 14:59:05] == Registered custom function ISNULL [May 25 14:59:05] == Registered custom function SET [May 25 14:59:05] == Registered custom function EXISTS [May 25 14:59:05] == Registered custom function IF [May 25 14:59:05] == Registered custom function IFTIME [May 25 14:59:05] func_logic.so => (Logical dialplan functions) [May 25 14:59:05] == Registered application 'Dictate' [May 25 14:59:05] app_dictate.so => (Virtual Dictation Machine) [May 25 14:59:05] == Registered application 'StackPop' [May 25 14:59:05] == Registered application 'Return' [May 25 14:59:05] == Registered application 'GosubIf' [May 25 14:59:05] == Registered application 'Gosub' [May 25 14:59:05] app_stack.so => (Stack Routines) [May 25 14:59:05] == Registered application 'NoCDR' [May 25 14:59:05] app_cdr.so => (Tell Asterisk to not maintain a CDR for the current call) [May 25 14:59:05] ERROR[3030]: chan_phone.c:1330 load_module: Unable to load config phone.conf [May 25 14:59:05] pbx_loopback.so => (Loopback Switch) [May 25 14:59:05] res_clioriginate.so => (Call origination from the CLI) [May 25 14:59:05] == Registered application 'NBScat' [May 25 14:59:05] app_nbscat.so => (Silly NBS Stream Application) [May 25 14:59:05] == Registered custom function REALTIME [May 25 14:59:05] func_realtime.so => (Read/Write values from a RealTime repository) [May 25 14:59:05] == Registered application 'TestClient' [May 25 14:59:05] == Registered application 'TestServer' [May 25 14:59:05] app_test.so => (Interface Test Application) [May 25 14:59:05] == Registered custom function DB [May 25 14:59:05] == Registered custom function DB_EXISTS [May 25 14:59:05] == Registered custom function DB_DELETE [May 25 14:59:05] func_db.so => (Database (astdb) related dialplan functions) [May 25 14:59:05] == Registered application 'Zapateller' [May 25 14:59:05] app_zapateller.so => (Block Telemarketers with Special Information Tone) [May 25 14:59:05] == Registered application 'ChannelRedirect' [May 25 14:59:05] app_channelredirect.so => (Channel Redirect) [May 25 14:59:05] == Parsing '/etc/asterisk/extensions.conf': [May 25 14:59:05] DEBUG[3030]: config.c:839 config_text_file_load: Parsing /etc/asterisk/extensions.conf [May 25 14:59:05] Found [May 25 14:59:05] DEBUG[3030]: pbx.c:3871 __ast_context_create: Registered context 'macro-stdexten' [May 25 14:59:05] -- Registered extension context 'macro-stdexten' [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension 's' priority 1 to macro-stdexten [May 25 14:59:05] -- Added extension 's' priority 1 to macro-stdexten [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension 's' priority 2 to macro-stdexten [May 25 14:59:05] -- Added extension 's' priority 2 to macro-stdexten [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension 's-NOANSWER' priority 1 to macro-stdexten [May 25 14:59:05] -- Added extension 's-NOANSWER' priority 1 to macro-stdexten [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension 's-NOANSWER' priority 2 to macro-stdexten [May 25 14:59:05] -- Added extension 's-NOANSWER' priority 2 to macro-stdexten [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension 's-BUSY' priority 1 to macro-stdexten [May 25 14:59:05] -- Added extension 's-BUSY' priority 1 to macro-stdexten [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension 's-BUSY' priority 2 to macro-stdexten [May 25 14:59:05] -- Added extension 's-BUSY' priority 2 to macro-stdexten [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension '_s-.' priority 1 to macro-stdexten [May 25 14:59:05] -- Added extension '_s-.' priority 1 to macro-stdexten [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension 'a' priority 1 to macro-stdexten [May 25 14:59:05] -- Added extension 'a' priority 1 to macro-stdexten [May 25 14:59:05] DEBUG[3030]: pbx.c:3871 __ast_context_create: Registered context 'default' [May 25 14:59:05] -- Registered extension context 'default' [May 25 14:59:05] DEBUG[3030]: pbx.c:3871 __ast_context_create: Registered context 'sip-internal' [May 25 14:59:05] -- Registered extension context 'sip-internal' [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension '2300' priority 1 to sip-internal [May 25 14:59:05] -- Added extension '2300' priority 1 to sip-internal [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension '2400' priority 1 to sip-internal [May 25 14:59:05] -- Added extension '2400' priority 1 to sip-internal [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension '2500' priority 1 to sip-internal [May 25 14:59:05] -- Added extension '2500' priority 1 to sip-internal [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension '7777' priority 1 to sip-internal [May 25 14:59:05] -- Added extension '7777' priority 1 to sip-internal [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension 's' priority 1 to sip-internal [May 25 14:59:05] -- Added extension 's' priority 1 to sip-internal [May 25 14:59:05] DEBUG[3030]: pbx.c:3871 __ast_context_create: Registered context 'iax-default' [May 25 14:59:05] -- Registered extension context 'iax-default' [May 25 14:59:05] DEBUG[3030]: pbx.c:4843 ast_add_extension2: Added extension 's' priority 1 to iax-default [May 25 14:59:05] -- Added extension 's' priority 1 to iax-default [May 25 14:59:05] DEBUG[3030]: pbx.c:3946 ast_merge_contexts_and_delete: must remove any reg pbx_config [May 25 14:59:05] DEBUG[3030]: pbx.c:5284 __ast_context_destroy: check ctx parkedcalls res_features [May 25 14:59:05] pbx_config.so => (Text Extension Configuration) [May 25 14:59:05] == Registered application 'PrivacyManager' [May 25 14:59:05] app_privacy.so => (Require phone number to be entered, if no CallerID sent) [May 25 14:59:05] == Registered custom function MUSICCLASS [May 25 14:59:05] func_moh.so => (Music-on-hold dialplan function) [May 25 14:59:05] res_convert.so => (File format conversion CLI command) [May 25 14:59:05] == Registered custom function MATH [May 25 14:59:05] func_math.so => (Mathematical dialplan function) [May 25 14:59:05] == Registered application 'ZapRAS' [May 25 14:59:05] app_zapras.so => (Zap RAS Application) [May 25 14:59:05] == Registered application 'BackgroundDetect' [May 25 14:59:05] app_talkdetect.so => (Playback with Talk Detection) [May 25 14:59:05] == Registered custom function MD5 [May 25 14:59:05] == Registered custom function CHECK_MD5 [May 25 14:59:05] func_md5.so => (MD5 digest dialplan functions) [May 25 14:59:05] WARNING[3030]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 [May 25 14:59:05] == Registered translator 'alawtolin' from format alaw to slin, cost 1 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 50 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 1 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] == Registered translator 'lintoalaw' from format slin to alaw, cost 1 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 50 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 6 [May 25 14:59:05] codec_alaw.so => (A-law Coder/Decoder) [May 25 14:59:05] == Registered application 'Directory' [May 25 14:59:05] app_directory.so => (Extension Directory) [May 25 14:59:05] == Registered application 'DBdel' [May 25 14:59:05] == Registered application 'DBdeltree' [May 25 14:59:05] app_db.so => (Database Access Functions) [May 25 14:59:05] == Registered application 'SMS' [May 25 14:59:05] app_sms.so => (SMS/PSTN handler) [May 25 14:59:05] WARNING[3030]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 [May 25 14:59:05] == Registered translator 'g726tolin' from format g726 to slin, cost 3 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 50 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 49 cost path from unknown to unknown, via 6 [May 25 14:59:05] == Registered translator 'lintog726' from format slin to g726, cost 3 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 50 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 49 cost path from unknown to unknown, via 6 [May 25 14:59:05] WARNING[3030]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 [May 25 14:59:05] == Registered translator 'g726aal2tolin' from format g726aal2 to slin, cost 3 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from ulaw to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 49 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 50 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 49 cost path from unknown to unknown, via 6 [May 25 14:59:05] == Registered translator 'lintog726aal2' from format slin to g726aal2, cost 3 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from g723 to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from gsm to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to ulaw, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from ulaw to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 49 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 50 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 49 cost path from unknown to unknown, via 6 [May 25 14:59:05] == Registered translator 'g726aal2tog726' from format g726aal2 to g726, cost 1 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from g723 to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from gsm to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to ulaw, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from ulaw to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 49 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 50 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 49 cost path from unknown to unknown, via 6 [May 25 14:59:05] == Registered translator 'g726tog726aal2' from format g726 to g726aal2, cost 1 [May 25 14:59:05] DEBUG[3030]: translate.c:425 rebuild_matrix: Resetting translation matrix [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from g723 to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from g723 to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from gsm to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from gsm to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to g723, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to ulaw, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 48 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 2 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from ulaw to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 49 cost path from ulaw to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 47 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 50 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to ulaw, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 7 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 9 cost path from unknown to g723, via 4 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to gsm, via 4 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 4 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 4 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 4 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 50 cost path from unknown to unknown, via 4 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 8 cost path from unknown to g723, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 10 cost path from unknown to unknown, via 6 [May 25 14:59:05] DEBUG[3030]: translate.c:480 rebuild_matrix: Discovered 49 cost path from unknown to unknown, via 6 [May 25 14:59:05] codec_g726.so => (ITU G.726-32kbps G726 Transcoder) [May 25 14:59:05] == Registered application 'ZapBarge' [May 25 14:59:05] app_zapbarge.so => (Barge in on Zap channel application) [May 25 14:59:05] == Registered custom function CALLERID [May 25 14:59:05] func_callerid.so => (Caller ID related dialplan function) [May 25 14:59:05] == Registered custom function RAND [May 25 14:59:05] func_rand.so => (Random number dialplan function) [May 25 14:59:05] == Registered application 'ChanIsAvail' [May 25 14:59:05] app_chanisavail.so => (Check channel availability) [May 25 14:59:05] == Registered application 'SoftHangup' [May 25 14:59:05] app_softhangup.so => (Hangs up the requested channel) [May 25 14:59:05] == Registered application 'While' [May 25 14:59:05] == Registered application 'EndWhile' [May 25 14:59:05] == Registered application 'ExitWhile' [May 25 14:59:05] == Registered application 'ContinueWhile' [May 25 14:59:05] app_while.so => (While Loops and Conditional Execution) [May 25 14:59:05] == Registered application 'GetCPEID' [May 25 14:59:05] app_getcpeid.so => (Get ADSI CPE ID) [May 25 14:59:05] == Registered application 'ReadFile' [May 25 14:59:05] app_readfile.so => (Stores output of file into a variable) [May 25 14:59:05] == Registered application 'Morsecode' [May 25 14:59:05] app_morsecode.so => (Morse code) [May 25 14:59:05] == Registered application 'WaitForSilence' [May 25 14:59:05] app_waitforsilence.so => (Wait For Silence) [May 25 14:59:05] == Registered custom function URIDECODE [May 25 14:59:05] == Registered custom function URIENCODE [May 25 14:59:05] func_uri.so => (URI encode/decode dialplan functions) [May 25 14:59:05] == Registered format 'jpg' (JPEG (Joint Picture Experts Group)) [May 25 14:59:05] format_jpeg.so => (JPEG (Joint Picture Experts Group) Image Format) [May 25 14:59:05] == Registered file format sln, extension(s) sln|raw [May 25 14:59:05] format_sln.so => (Raw Signed Linear Audio support (SLN)) [May 25 14:59:05] == Registered application 'ADSIProg' [May 25 14:59:05] app_adsiprog.so => (Asterisk ADSI Programming Application) [May 25 14:59:05] == Registered custom function CHANNEL [May 25 14:59:05] func_channel.so => (Channel information dialplan function) [May 25 14:59:05] == Registered custom function SHA1 [May 25 14:59:05] func_sha1.so => (SHA-1 computation dialplan function) [May 25 14:59:05] == Registered application 'Log' [May 25 14:59:05] == Registered application 'Verbose' [May 25 14:59:05] app_verbose.so => (Send verbose output) [May 25 14:59:05] == Registered application 'RealTimeUpdate' [May 25 14:59:05] == Registered application 'RealTime' [May 25 14:59:05] app_realtime.so => (Realtime Data Lookup/Rewrite) [May 25 14:59:05] DEBUG[3030]: channel.c:532 ast_channel_register: Registered handler for 'Feature' (Feature Proxy Channel Driver) [May 25 14:59:05] == Registered channel type 'Feature' (Feature Proxy Channel Driver) [May 25 14:59:05] chan_features.so => (Feature Proxy Channel) [May 25 14:59:05] == Registered application 'TrySystem' [May 25 14:59:05] == Registered application 'System' [May 25 14:59:05] app_system.so => (Generic System() application) [May 25 14:59:05] == Registered application 'Dial' [May 25 14:59:05] == Registered application 'RetryDial' [May 25 14:59:05] app_dial.so => (Dialing Application) [May 25 14:59:05] Asterisk Ready. ]1;Asterisk]2;Asterisk Console on 'CapPBX' (pid 3030)*CLI> [May 25 14:59:22] DEBUG[3050]: chan_sip.c:2576 do_setnat: Setting NAT on RTP to Off [May 25 14:59:22] DEBUG[3050]: chan_sip.c:4312 sip_alloc: Allocating new SIP dialog for a915f1b8-26735276-77f1ecab@192.168.2.27 - INVITE (With RTP) [May 25 14:59:22] DEBUG[3050]: chan_sip.c:14709 handle_request: **** Received INVITE (5) - Command in SIP INVITE [May 25 14:59:22] DEBUG[3050]: chan_sip.c:2576 do_setnat: Setting NAT on RTP to Off [May 25 14:59:22] DEBUG[3050]: chan_sip.c:2501 sip_destroy_user: Destroying user object from memory: 2400 [May 25 14:59:22] DEBUG[3050]: chan_sip.c:4365 find_call: = Found Their Call ID: a915f1b8-26735276-77f1ecab@192.168.2.27 Their Tag ADE7B794-187943D9 Our tag: as0f9ac4d1 [May 25 14:59:22] DEBUG[3050]: chan_sip.c:14709 handle_request: **** Received ACK (6) - Command in SIP ACK [May 25 14:59:22] DEBUG[3050]: chan_sip.c:2090 __sip_ack: Stopping retransmission on 'a915f1b8-26735276-77f1ecab@192.168.2.27' of Response 1: Match Not Found [May 25 14:59:22] DEBUG[3050]: chan_sip.c:4365 find_call: = Found Their Call ID: a915f1b8-26735276-77f1ecab@192.168.2.27 Their Tag ADE7B794-187943D9 Our tag: as0f9ac4d1 [May 25 14:59:22] DEBUG[3050]: chan_sip.c:14709 handle_request: **** Received INVITE (5) - Command in SIP INVITE [May 25 14:59:22] DEBUG[3050]: chan_sip.c:2576 do_setnat: Setting NAT on RTP to Off [May 25 14:59:22] DEBUG[3050]: chan_sip.c:2501 sip_destroy_user: Destroying user object from memory: 2400 [May 25 14:59:22] DEBUG[3050]: chan_sip.c:5136 process_sdp: T38 state changed to 0 on channel [May 25 14:59:22] DEBUG[3050]: chan_sip.c:5216 process_sdp: We're settling with these formats: 0xc (ulaw|alaw) [May 25 14:59:22] DEBUG[3050]: chan_sip.c:13418 handle_request_invite: Checking SIP call limits for device 2400 [May 25 14:59:22] DEBUG[3050]: chan_sip.c:3004 update_call_counter: Updating call counter for incoming call [May 25 14:59:22] DEBUG[3050]: chan_sip.c:3806 sip_new: *** Our native formats are 0x4 (ulaw) [May 25 14:59:22] DEBUG[3050]: chan_sip.c:3807 sip_new: *** Joint capabilities are 0xc (ulaw|alaw) [May 25 14:59:22] DEBUG[3050]: chan_sip.c:3808 sip_new: *** Our capabilities are 0xe (gsm|ulaw|alaw) [May 25 14:59:22] DEBUG[3050]: chan_sip.c:3809 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [May 25 14:59:22] DEBUG[3050]: chan_sip.c:3832 sip_new: This channel will not be able to handle video. [May 25 14:59:22] DEBUG[3050]: chan_sip.c:7994 build_route: build_route: Contact hop: [May 25 14:59:22] DEBUG[3050]: chan_sip.c:13492 handle_request_invite: SIP/2400-08205710: New call is still down.... Trying... [May 25 14:59:22] DEBUG[3050]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/2400-08205710 [May 25 14:59:22] DEBUG[3034]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 2400 [May 25 14:59:22] DEBUG[3034]: chan_sip.c:15321 sip_devicestate: Checking device state for peer 2400 [May 25 14:59:22] DEBUG[3052]: pbx.c:1809 pbx_extension_helper: Launching 'Macro' [May 25 14:59:22] -- Executing [2500@sip-internal:1] Macro("SIP/2400-08205710", "stdexten|2500|SIP/2500") in new stack [May 25 14:59:22] DEBUG[3052]: pbx.c:1809 pbx_extension_helper: Launching 'Dial' [May 25 14:59:22] -- Executing [s@macro-stdexten:1] Dial("SIP/2400-08205710", "SIP/2500|20") in new stack [May 25 14:59:22] DEBUG[3052]: chan_sip.c:15387 sip_request_call: Asked to create a SIP channel with formats: 0x4 (ulaw) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:4312 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [May 25 14:59:22] DEBUG[3034]: chan_sip.c:7646 reg_source_db: SIP Seeding peer from astdb: '2400' at 2400@192.168.2.27:5060 for 3600 [May 25 14:59:22] DEBUG[3034]: chan_sip.c:2360 sip_destroy_peer: Destroying SIP peer 2400 [May 25 14:59:22] DEBUG[3034]: devicestate.c:287 do_state_change: Changing state for SIP/2400 - state 1 (Not in use) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:7646 reg_source_db: SIP Seeding peer from astdb: '2500' at 2500@192.168.2.20:5060 for 3600 [May 25 14:59:22] DEBUG[3052]: chan_sip.c:2576 do_setnat: Setting NAT on RTP to Off [May 25 14:59:22] DEBUG[3052]: chan_sip.c:2360 sip_destroy_peer: Destroying SIP peer 2500 [May 25 14:59:22] DEBUG[3052]: chan_sip.c:3806 sip_new: *** Our native formats are 0x4 (ulaw) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:3807 sip_new: *** Joint capabilities are 0x0 (nothing) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:3808 sip_new: *** Our capabilities are 0xe (gsm|ulaw|alaw) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:3809 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:3811 sip_new: *** Our preferred formats from the incoming channel are 0x4 (ulaw) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:3832 sip_new: This channel will not be able to handle video. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable STACK-macro-stdexten-s-1. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable MACRO_DEPTH. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable ARG2. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable ARG1. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable MACRO_PRIORITY. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable MACRO_CONTEXT. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable MACRO_EXTEN. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable STACK-sip-internal-2500-1. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable SIPCALLID. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. [May 25 14:59:22] DEBUG[3052]: channel.c:3376 ast_channel_inherit_variables: Not copying variable SIPURI. [May 25 14:59:22] DEBUG[3052]: chan_sip.c:2831 sip_call: Outgoing Call for 2500 [May 25 14:59:22] DEBUG[3052]: chan_sip.c:3004 update_call_counter: Updating call counter for outgoing call [May 25 14:59:22] DEBUG[3052]: chan_sip.c:2846 sip_call: Our T38 capability (0), joint T38 capability (0) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:6198 add_sdp: ** Our capability: 0xe (gsm|ulaw|alaw) Video flag: False [May 25 14:59:22] DEBUG[3052]: chan_sip.c:6199 add_sdp: ** Our prefcodec: 0x4 (ulaw) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:6330 add_sdp: -- Done with adding codecs to SDP [May 25 14:59:22] DEBUG[3052]: channel.c:2447 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=26) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:6375 add_sdp: Done building SDP. Settling with this capability: 0xe (gsm|ulaw|alaw) [May 25 14:59:22] -- Called 2500 [May 25 14:59:22] DEBUG[3050]: chan_sip.c:4365 find_call: = Found Their Call ID: 7df6be253e0202107391ea4779b560bc@192.168.2.150 Their Tag Our tag: as69f6b08b [May 25 14:59:22] DEBUG[3050]: chan_sip.c:2132 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '7df6be253e0202107391ea4779b560bc@192.168.2.150' Request 102: Found [May 25 14:59:22] DEBUG[3050]: chan_sip.c:11658 handle_response_invite: SIP response 100 to standard invite [May 25 14:59:22] DEBUG[3050]: chan_sip.c:4365 find_call: = Found Their Call ID: 7df6be253e0202107391ea4779b560bc@192.168.2.150 Their Tag 2FCCC0CC-2955CB0B Our tag: as69f6b08b [May 25 14:59:22] DEBUG[3050]: chan_sip.c:2072 __sip_ack: Acked pending invite 102 [May 25 14:59:22] DEBUG[3050]: chan_sip.c:2090 __sip_ack: Stopping retransmission on '7df6be253e0202107391ea4779b560bc@192.168.2.150' of Request 102: Match Not Found [May 25 14:59:22] -- Got SIP response 486 "Busy Here" back from 192.168.2.20 [May 25 14:59:22] DEBUG[3050]: chan_sip.c:1634 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 7df6be253e0202107391ea4779b560bc@192.168.2.150 [May 25 14:59:22] -- SIP/2500-b7b1af88 is busy [May 25 14:59:22] DEBUG[3052]: channel.c:1726 ast_hangup: Hanging up channel 'SIP/2500-b7b1af88' [May 25 14:59:22] DEBUG[3052]: chan_sip.c:3313 sip_hangup: Hangup call SIP/2500-b7b1af88, SIP callid 7df6be253e0202107391ea4779b560bc@192.168.2.150) [May 25 14:59:22] DEBUG[3052]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/2500-b7b1af88 [May 25 14:59:22] DEBUG[3034]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 2500 [May 25 14:59:22] DEBUG[3034]: chan_sip.c:15321 sip_devicestate: Checking device state for peer 2500 [May 25 14:59:22] DEBUG[3034]: chan_sip.c:7646 reg_source_db: SIP Seeding peer from astdb: '2500' at 2500@192.168.2.20:5060 for 3600 [May 25 14:59:22] DEBUG[3034]: chan_sip.c:2360 sip_destroy_peer: Destroying SIP peer 2500 [May 25 14:59:22] DEBUG[3034]: devicestate.c:287 do_state_change: Changing state for SIP/2500 - state 1 (Not in use) [May 25 14:59:22] == Everyone is busy/congested at this time (1:1/0/0) [May 25 14:59:22] DEBUG[3052]: rtp.c:1476 ast_rtp_early_bridge: Channel '' has no RTP, not doing anything [May 25 14:59:22] DEBUG[3052]: app_dial.c:1679 dial_exec_full: Exiting with DIALSTATUS=BUSY. [May 25 14:59:22] DEBUG[3052]: app_macro.c:320 _macro_exec: Executed application: Dial [May 25 14:59:22] DEBUG[3052]: pbx.c:1809 pbx_extension_helper: Launching 'Goto' [May 25 14:59:22] -- Executing [s@macro-stdexten:2] Goto("SIP/2400-08205710", "s-BUSY|1") in new stack [May 25 14:59:22] -- Goto (macro-stdexten,s-BUSY,1) [May 25 14:59:22] DEBUG[3052]: app_macro.c:320 _macro_exec: Executed application: Goto [May 25 14:59:22] DEBUG[3052]: pbx.c:1809 pbx_extension_helper: Launching 'VoiceMail' [May 25 14:59:22] -- Executing [s-BUSY@macro-stdexten:1] VoiceMail("SIP/2400-08205710", "2500|b") in new stack [May 25 14:59:22] DEBUG[3052]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/2400-08205710 [May 25 14:59:22] DEBUG[3034]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 2400 [May 25 14:59:22] DEBUG[3034]: chan_sip.c:15321 sip_devicestate: Checking device state for peer 2400 [May 25 14:59:22] DEBUG[3034]: chan_sip.c:7646 reg_source_db: SIP Seeding peer from astdb: '2400' at 2400@192.168.2.27:5060 for 3600 [May 25 14:59:22] DEBUG[3034]: chan_sip.c:2360 sip_destroy_peer: Destroying SIP peer 2400 [May 25 14:59:22] DEBUG[3034]: devicestate.c:287 do_state_change: Changing state for SIP/2400 - state 1 (Not in use) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:3464 sip_answer: SIP answering channel: SIP/2400-08205710 [May 25 14:59:22] DEBUG[3052]: chan_sip.c:6432 transmit_response_with_sdp: Setting framing from config on incoming call [May 25 14:59:22] DEBUG[3052]: chan_sip.c:6198 add_sdp: ** Our capability: 0xc (ulaw|alaw) Video flag: True [May 25 14:59:22] DEBUG[3052]: chan_sip.c:6199 add_sdp: ** Our prefcodec: 0x0 (nothing) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:6330 add_sdp: -- Done with adding codecs to SDP [May 25 14:59:22] DEBUG[3052]: channel.c:2447 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=23) [May 25 14:59:22] DEBUG[3052]: chan_sip.c:6375 add_sdp: Done building SDP. Settling with this capability: 0xc (ulaw|alaw) [May 25 14:59:22] DEBUG[3052]: app_voicemail.c:2859 leave_voicemail: Before find_user [May 25 14:59:22] DEBUG[3050]: chan_sip.c:4365 find_call: = No match Their Call ID: 7df6be253e0202107391ea4779b560bc@192.168.2.150 Their Tag 2FCCC0CC-2955CB0B Our tag: as69f6b08b [May 25 14:59:22] DEBUG[3050]: chan_sip.c:4365 find_call: = Found Their Call ID: a915f1b8-26735276-77f1ecab@192.168.2.27 Their Tag ADE7B794-187943D9 Our tag: as4788c128 [May 25 14:59:22] DEBUG[3050]: chan_sip.c:14709 handle_request: **** Received ACK (6) - Command in SIP ACK [May 25 14:59:22] DEBUG[3050]: chan_sip.c:2090 __sip_ack: Stopping retransmission on 'a915f1b8-26735276-77f1ecab@192.168.2.27' of Response 2: Match Not Found [May 25 14:59:22] Really destroying SIP dialog '7df6be253e0202107391ea4779b560bc@192.168.2.150' Method: INVITE [May 25 14:59:22] DEBUG[3052]: channel.c:2911 set_format: Set channel SIP/2400-08205710 to write format slin [May 25 14:59:22] DEBUG[3052]: rtp.c:2701 ast_rtp_write: Ooh, format changed from unknown to ulaw [May 25 14:59:22] DEBUG[3052]: rtp.c:2718 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160 [May 25 14:59:22] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 160 sample intervals [May 25 14:59:22] -- Playing '/var/spool/asterisk/voicemail/default/2500/temp' (language 'fr') [May 25 14:59:27] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 138 sample intervals [May 25 14:59:27] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 0 sample intervals [May 25 14:59:27] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 0 sample intervals [May 25 14:59:27] DEBUG[3052]: channel.c:2911 set_format: Set channel SIP/2400-08205710 to write format ulaw [May 25 14:59:27] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 160 sample intervals [May 25 14:59:27] -- Playing 'vm-intro' (language 'fr') [May 25 14:59:30] DEBUG[3052]: rtp.c:875 ast_rtcp_read: Got RTCP report of 76 bytes [May 25 14:59:34] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 103 sample intervals [May 25 14:59:34] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 0 sample intervals [May 25 14:59:34] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 0 sample intervals [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8719 get_vm_state_by_mailbox: Mailbox set to 2500 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8741 get_vm_state_by_mailbox: 2500 not found in vmstates [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:2458 inboxcount: Mailbox is set to 2500 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8709 get_vm_state_by_imapuser: sdess not found in vmstates [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8719 get_vm_state_by_mailbox: Mailbox set to 2500 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8741 get_vm_state_by_mailbox: 2500 not found in vmstates [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8709 get_vm_state_by_imapuser: sdess not found in vmstates [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8719 get_vm_state_by_mailbox: Mailbox set to 2500 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8741 get_vm_state_by_mailbox: 2500 not found in vmstates [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:2529 inboxcount: Adding new vmstate for sdess [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:2538 inboxcount: Copied sdess to sdess [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8784 vmstate_insert: Inserting vm_state for user:sdess, mailbox 2500 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:4656 init_mailstream: vm_state user is:sdess [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:4659 init_mailstream: mailstream not set. [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: Trying IP address [127.0.0.1] [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: Dovecot ready. [May 25 14:59:34] WARNING[3052]: app_voicemail.c:8533 mm_log: IMAP Warning: SECURITY PROBLEM: insecure server advertised AUTH=PLAIN [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8553 mm_login: Entering callback mm_login [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8438 mm_exists: Entering EXISTS callback for message 3 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8709 get_vm_state_by_imapuser: asterisk not found in vmstates [May 25 14:59:34] WARNING[3052]: app_voicemail.c:8849 set_update: User asterisk mailbox not found for update. [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: [UNSEEN 1] First unseen. [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8478 mm_list: Delimiter set to / and mailbox {localhost}jdoe [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8480 mm_list: no inferiors [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8486 mm_list: unmarked [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8478 mm_list: Delimiter set to / and mailbox {localhost}var [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8482 mm_list: no select [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8478 mm_list: Delimiter set to / and mailbox {localhost}var/vmail [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8482 mm_list: no select [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8478 mm_list: Delimiter set to / and mailbox {localhost}Courrier ind&AOk-sirable [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8480 mm_list: no inferiors [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8486 mm_list: unmarked [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8478 mm_list: Delimiter set to / and mailbox {localhost}sdess [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8480 mm_list: no inferiors [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8486 mm_list: unmarked [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8478 mm_list: Delimiter set to / and mailbox {localhost}johndoe [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8480 mm_list: no inferiors [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8486 mm_list: unmarked [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8478 mm_list: Delimiter set to / and mailbox {localhost}INBOX [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8480 mm_list: no inferiors [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8486 mm_list: unmarked [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:4685 init_mailstream: Before mail_open, server: {localhost:143/imap/authuser=asterisk/notls/user=sdess}INBOX, box:0 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: Reusing connection to localhost/user="sdess" [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8438 mm_exists: Entering EXISTS callback for message 3 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8709 get_vm_state_by_imapuser: asterisk not found in vmstates [May 25 14:59:34] WARNING[3052]: app_voicemail.c:8849 set_update: User asterisk mailbox not found for update. [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: [UNSEEN 1] First unseen. [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8236 mm_searched: saving mailbox message number 1 as message 0. Interactive set to 0 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8236 mm_searched: saving mailbox message number 2 as message 1. Interactive set to 0 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8236 mm_searched: saving mailbox message number 3 as message 2. Interactive set to 0 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8458 mm_flags: Entering FLAGS callback for message 1 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8845 set_update: User sdess mailbox set for update. [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8458 mm_flags: Entering FLAGS callback for message 2 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8845 set_update: User sdess mailbox set for update. [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8458 mm_flags: Entering FLAGS callback for message 3 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8845 set_update: User sdess mailbox set for update. [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8719 get_vm_state_by_mailbox: Mailbox set to 2500 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8724 get_vm_state_by_mailbox: comparing mailbox 2500 (i=0) to vmstate mailbox 2500 (i=0) [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:8727 get_vm_state_by_mailbox: Found it! [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:3017 leave_voicemail: Messagecount set to 3 [May 25 14:59:34] DEBUG[3052]: app_voicemail.c:3032 leave_voicemail: Messagecount set to 3 [May 25 14:59:34] DEBUG[3052]: rtp.c:2571 ast_rtp_raw_write: Difference is 688, ms is 106 [May 25 14:59:34] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 160 sample intervals [May 25 14:59:34] -- Playing 'beep' (language 'fr') [May 25 14:59:35] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 45 sample intervals [May 25 14:59:35] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 0 sample intervals [May 25 14:59:35] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 0 sample intervals [May 25 14:59:35] -- Recording the message [May 25 14:59:35] DEBUG[3052]: app.c:516 __ast_play_and_record: play_and_record: , /var/spool/asterisk/voicemail/default/2500/tmp/e2h3UP, 'gsm|wav49' [May 25 14:59:35] DEBUG[3052]: app.c:538 __ast_play_and_record: Recording Formats: sfmts=gsm [May 25 14:59:35] -- x=0, open writing: /var/spool/asterisk/voicemail/default/2500/tmp/e2h3UP format: gsm, 0xb7b1f6f8 [May 25 14:59:35] -- x=1, open writing: /var/spool/asterisk/voicemail/default/2500/tmp/e2h3UP format: wav49, 0xb7b2bfb0 [May 25 14:59:35] DEBUG[3052]: channel.c:2911 set_format: Set channel SIP/2400-08205710 to read format slin [May 25 14:59:37] DEBUG[3052]: rtp.c:727 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 25 14:59:37] DEBUG[3052]: rtp.c:626 send_dtmf: Sending dtmf: 35 (#), at 192.168.2.27 [May 25 14:59:37] DEBUG[3052]: rtp.c:727 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 25 14:59:37] DEBUG[3052]: rtp.c:727 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 25 14:59:37] DEBUG[3052]: rtp.c:727 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 25 14:59:37] DEBUG[3052]: rtp.c:727 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 25 14:59:37] DEBUG[3052]: rtp.c:727 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 25 14:59:37] DEBUG[3052]: rtp.c:626 send_dtmf: Sending dtmf: 35 (#), at 192.168.2.27 [May 25 14:59:37] -- User ended message by pressing # [May 25 14:59:37] DEBUG[3052]: channel.c:2911 set_format: Set channel SIP/2400-08205710 to read format ulaw [May 25 14:59:37] DEBUG[3052]: rtp.c:2571 ast_rtp_raw_write: Difference is 17760, ms is 2240 [May 25 14:59:37] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 160 sample intervals [May 25 14:59:37] -- Playing 'auth-thankyou' (language 'fr') [May 25 14:59:37] DEBUG[3052]: rtp.c:727 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 25 14:59:37] DEBUG[3052]: rtp.c:727 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 25 14:59:38] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 78 sample intervals [May 25 14:59:38] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 0 sample intervals [May 25 14:59:38] DEBUG[3052]: channel.c:2030 ast_settimeout: Scheduling timer at 0 sample intervals [May 25 14:59:38] DEBUG[3052]: app.c:983 ast_lock_path: Locked path '/var/spool/asterisk/voicemail/default/2500/INBOX' [May 25 14:59:38] DEBUG[3052]: app.c:1004 ast_unlock_path: Unlocked path '/var/spool/asterisk/voicemail/default/2500/INBOX' [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:2408 imap_store_file: Storing file '/var/spool/asterisk/voicemail/default/2500/INBOX/msg0003', format 'gsm' [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:1973 make_email_file: newtmp: /var/spool/asterisk/voicemail/default/2500/tmp/fpczGS [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:4656 init_mailstream: vm_state user is:sdess [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:4685 init_mailstream: Before mail_open, server: {localhost:143/imap/authuser=asterisk/notls/user=sdess}INBOX, box:0 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: Reusing connection to localhost/user="sdess" [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8438 mm_exists: Entering EXISTS callback for message 3 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8709 get_vm_state_by_imapuser: asterisk not found in vmstates [May 25 14:59:38] WARNING[3052]: app_voicemail.c:8849 set_update: User asterisk mailbox not found for update. [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: [UNSEEN 1] First unseen. [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8438 mm_exists: Entering EXISTS callback for message 4 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8845 set_update: User sdess mailbox set for update. [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:2434 imap_store_file: /var/spool/asterisk/voicemail/default/2500/INBOX/msg0003 stored [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:2018 sendmail: Attaching file '/var/spool/asterisk/voicemail/default/2500/INBOX/msg0003', format 'gsm', uservm is '2048', global is 2048 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:1973 make_email_file: newtmp: /var/spool/asterisk/voicemail/default/2500/tmp/Sghr6V [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:2030 sendmail: Sent mail to sdess with command '/usr/sbin/sendmail -t' [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:2458 inboxcount: Mailbox is set to 2500@default [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8709 get_vm_state_by_imapuser: sdess not found in vmstates [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8719 get_vm_state_by_mailbox: Mailbox set to 2500 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8724 get_vm_state_by_mailbox: comparing mailbox 2500 (i=1) to vmstate mailbox 2500 (i=0) [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8741 get_vm_state_by_mailbox: 2500 not found in vmstates [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:4656 init_mailstream: vm_state user is:sdess [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:4685 init_mailstream: Before mail_open, server: {localhost:143/imap/authuser=asterisk/notls/user=sdess}INBOX, box:0 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: Reusing connection to localhost/user="sdess" [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8438 mm_exists: Entering EXISTS callback for message 4 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8709 get_vm_state_by_imapuser: asterisk not found in vmstates [May 25 14:59:38] WARNING[3052]: app_voicemail.c:8849 set_update: User asterisk mailbox not found for update. [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: [UNSEEN 1] First unseen. [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:2458 inboxcount: Mailbox is set to 2500@default [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8709 get_vm_state_by_imapuser: sdess not found in vmstates [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8719 get_vm_state_by_mailbox: Mailbox set to 2500 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8724 get_vm_state_by_mailbox: comparing mailbox 2500 (i=1) to vmstate mailbox 2500 (i=0) [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8741 get_vm_state_by_mailbox: 2500 not found in vmstates [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:4656 init_mailstream: vm_state user is:sdess [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:4685 init_mailstream: Before mail_open, server: {localhost:143/imap/authuser=asterisk/notls/user=sdess}INBOX, box:0 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: Reusing connection to localhost/user="sdess" [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8438 mm_exists: Entering EXISTS callback for message 4 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8709 get_vm_state_by_imapuser: asterisk not found in vmstates [May 25 14:59:38] WARNING[3052]: app_voicemail.c:8849 set_update: User asterisk mailbox not found for update. [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8529 mm_log: IMAP Info: [UNSEEN 1] First unseen. [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8236 mm_searched: saving mailbox message number 1 as message 0. Interactive set to 0 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8236 mm_searched: saving mailbox message number 2 as message 1. Interactive set to 0 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8236 mm_searched: saving mailbox message number 3 as message 2. Interactive set to 0 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8236 mm_searched: saving mailbox message number 4 as message 3. Interactive set to 0 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8458 mm_flags: Entering FLAGS callback for message 1 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8845 set_update: User sdess mailbox set for update. [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8458 mm_flags: Entering FLAGS callback for message 2 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8845 set_update: User sdess mailbox set for update. [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8458 mm_flags: Entering FLAGS callback for message 3 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8845 set_update: User sdess mailbox set for update. [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8458 mm_flags: Entering FLAGS callback for message 4 [May 25 14:59:38] DEBUG[3052]: app_voicemail.c:8845 set_update: User sdess mailbox set for update. [May 25 14:59:38] DEBUG[3052]: app_macro.c:320 _macro_exec: Executed application: Voicemail [May 25 14:59:38] DEBUG[3052]: pbx.c:1809 pbx_extension_helper: Launching 'Goto' [May 25 14:59:38] -- Executing [s-BUSY@macro-stdexten:2] Goto("SIP/2400-08205710", "default|s|1") in new stack [May 25 14:59:38] -- Goto (default,s,1) [May 25 14:59:38] DEBUG[3052]: app_macro.c:320 _macro_exec: Executed application: Goto [May 25 14:59:38] == Channel 'SIP/2400-08205710' jumping out of macro 'stdexten' [May 25 14:59:38] WARNING[3052]: pbx.c:2450 __ast_pbx_run: Channel 'SIP/2400-08205710' sent into invalid extension 's' in context 'default', but no invalid handler [May 25 14:59:38] DEBUG[3052]: channel.c:1726 ast_hangup: Hanging up channel 'SIP/2400-08205710' [May 25 14:59:38] DEBUG[3052]: chan_sip.c:3313 sip_hangup: Hangup call SIP/2400-08205710, SIP callid a915f1b8-26735276-77f1ecab@192.168.2.27) [May 25 14:59:38] DEBUG[3052]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/2400-08205710 [May 25 14:59:38] DEBUG[3034]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 2400 [May 25 14:59:38] DEBUG[3034]: chan_sip.c:15321 sip_devicestate: Checking device state for peer 2400 [May 25 14:59:38] DEBUG[3034]: chan_sip.c:7646 reg_source_db: SIP Seeding peer from astdb: '2400' at 2400@192.168.2.27:5060 for 3600 [May 25 14:59:38] DEBUG[3034]: chan_sip.c:2360 sip_destroy_peer: Destroying SIP peer 2400 [May 25 14:59:38] DEBUG[3034]: devicestate.c:287 do_state_change: Changing state for SIP/2400 - state 1 (Not in use) [May 25 14:59:38] DEBUG[3050]: chan_sip.c:4365 find_call: = Found Their Call ID: a915f1b8-26735276-77f1ecab@192.168.2.27 Their Tag ADE7B794-187943D9 Our tag: as4788c128 [May 25 14:59:38] DEBUG[3050]: chan_sip.c:2090 __sip_ack: Stopping retransmission on 'a915f1b8-26735276-77f1ecab@192.168.2.27' of Request 102: Match Not Found [May 25 14:59:38] Really destroying SIP dialog 'a915f1b8-26735276-77f1ecab@192.168.2.27' Method: ACK [May 25 14:59:42] DEBUG[3050]: chan_sip.c:14893 sipsock_read: Invalid SIP message - rejected , no callid, len 594 [May 25 14:59:45] DEBUG[3050]: chan_sip.c:2576 do_setnat: Setting NAT on RTP to Off [May 25 14:59:45] DEBUG[3050]: chan_sip.c:4312 sip_alloc: Allocating new SIP dialog for 1067c228-31654de6-b5af249b@192.168.2.27 - INVITE (With RTP) [May 25 14:59:45] DEBUG[3050]: chan_sip.c:14709 handle_request: **** Received INVITE (5) - Command in SIP INVITE [May 25 14:59:45] DEBUG[3050]: chan_sip.c:2576 do_setnat: Setting NAT on RTP to Off [May 25 14:59:45] DEBUG[3050]: chan_sip.c:2501 sip_destroy_user: Destroying user object from memory: 2400 [May 25 14:59:45] DEBUG[3050]: chan_sip.c:4365 find_call: = Found Their Call ID: 1067c228-31654de6-b5af249b@192.168.2.27 Their Tag CF15CE04-CB9D3EC9 Our tag: as4bda968f [May 25 14:59:45] DEBUG[3050]: chan_sip.c:14709 handle_request: **** Received ACK (6) - Command in SIP ACK [May 25 14:59:45] DEBUG[3050]: chan_sip.c:2090 __sip_ack: Stopping retransmission on '1067c228-31654de6-b5af249b@192.168.2.27' of Response 1: Match Not Found [May 25 14:59:45] DEBUG[3050]: chan_sip.c:4365 find_call: = Found Their Call ID: 1067c228-31654de6-b5af249b@192.168.2.27 Their Tag CF15CE04-CB9D3EC9 Our tag: as4bda968f [May 25 14:59:45] DEBUG[3050]: chan_sip.c:14709 handle_request: **** Received INVITE (5) - Command in SIP INVITE [May 25 14:59:45] DEBUG[3050]: chan_sip.c:2576 do_setnat: Setting NAT on RTP to Off [May 25 14:59:45] DEBUG[3050]: chan_sip.c:2501 sip_destroy_user: Destroying user object from memory: 2400 [May 25 14:59:45] DEBUG[3050]: chan_sip.c:5136 process_sdp: T38 state changed to 0 on channel [May 25 14:59:45] DEBUG[3050]: chan_sip.c:5216 process_sdp: We're settling with these formats: 0xc (ulaw|alaw) [May 25 14:59:45] DEBUG[3050]: chan_sip.c:13418 handle_request_invite: Checking SIP call limits for device 2400 [May 25 14:59:45] DEBUG[3050]: chan_sip.c:3004 update_call_counter: Updating call counter for incoming call [May 25 14:59:45] DEBUG[3050]: chan_sip.c:3806 sip_new: *** Our native formats are 0x4 (ulaw) [May 25 14:59:45] DEBUG[3050]: chan_sip.c:3807 sip_new: *** Joint capabilities are 0xc (ulaw|alaw) [May 25 14:59:45] DEBUG[3050]: chan_sip.c:3808 sip_new: *** Our capabilities are 0xe (gsm|ulaw|alaw) [May 25 14:59:45] DEBUG[3050]: chan_sip.c:3809 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [May 25 14:59:45] DEBUG[3050]: chan_sip.c:3832 sip_new: This channel will not be able to handle video. [May 25 14:59:45] DEBUG[3050]: chan_sip.c:7994 build_route: build_route: Contact hop: [May 25 14:59:45] DEBUG[3050]: chan_sip.c:13492 handle_request_invite: SIP/2400-08206c90: New call is still down.... Trying... [May 25 14:59:45] DEBUG[3050]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/2400-08206c90 [May 25 14:59:45] DEBUG[3034]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 2400 [May 25 14:59:45] DEBUG[3059]: pbx.c:1809 pbx_extension_helper: Launching 'VoiceMailMain' [May 25 14:59:45] DEBUG[3034]: chan_sip.c:15321 sip_devicestate: Checking device state for peer 2400 [May 25 14:59:45] -- Executing [7777@sip-internal:1] VoiceMailMain("SIP/2400-08206c90", "2500@default") in new stack [May 25 14:59:45] DEBUG[3059]: app_voicemail.c:6268 vm_execmain: Before ast_answer [May 25 14:59:45] DEBUG[3059]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/2400-08206c90 [May 25 14:59:45] DEBUG[3059]: chan_sip.c:3464 sip_answer: SIP answering channel: SIP/2400-08206c90 [May 25 14:59:45] DEBUG[3059]: chan_sip.c:6432 transmit_response_with_sdp: Setting framing from config on incoming call [May 25 14:59:45] DEBUG[3059]: chan_sip.c:6198 add_sdp: ** Our capability: 0xc (ulaw|alaw) Video flag: True [May 25 14:59:45] DEBUG[3059]: chan_sip.c:6199 add_sdp: ** Our prefcodec: 0x0 (nothing) [May 25 14:59:45] DEBUG[3059]: chan_sip.c:6330 add_sdp: -- Done with adding codecs to SDP [May 25 14:59:45] DEBUG[3059]: channel.c:2447 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=23) [May 25 14:59:45] DEBUG[3059]: chan_sip.c:6375 add_sdp: Done building SDP. Settling with this capability: 0xc (ulaw|alaw) [May 25 14:59:45] DEBUG[3059]: app_voicemail.c:6175 vm_authenticate: Before find user for mailbox 2500 [May 25 14:59:45] DEBUG[3034]: chan_sip.c:7646 reg_source_db: SIP Seeding peer from astdb: '2400' at 2400@192.168.2.27:5060 for 3600 [May 25 14:59:45] DEBUG[3034]: chan_sip.c:2360 sip_destroy_peer: Destroying SIP peer 2400 [May 25 14:59:45] DEBUG[3034]: devicestate.c:287 do_state_change: Changing state for SIP/2400 - state 1 (Not in use) [May 25 14:59:45] DEBUG[3034]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 2400 [May 25 14:59:45] DEBUG[3034]: chan_sip.c:15321 sip_devicestate: Checking device state for peer 2400 [May 25 14:59:45] DEBUG[3034]: chan_sip.c:7646 reg_source_db: SIP Seeding peer from astdb: '2400' at 2400@192.168.2.27:5060 for 3600 [May 25 14:59:45] DEBUG[3034]: chan_sip.c:2360 sip_destroy_peer: Destroying SIP peer 2400 [May 25 14:59:45] DEBUG[3034]: devicestate.c:287 do_state_change: Changing state for SIP/2400 - state 1 (Not in use) [May 25 14:59:45] DEBUG[3059]: rtp.c:2701 ast_rtp_write: Ooh, format changed from unknown to ulaw [May 25 14:59:45] DEBUG[3059]: rtp.c:2718 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160 [May 25 14:59:45] DEBUG[3059]: channel.c:2030 ast_settimeout: Scheduling timer at 160 sample intervals [May 25 14:59:45] -- Playing 'vm-password' (language 'fr') [May 25 14:59:45] DEBUG[3050]: chan_sip.c:4365 find_call: = Found Their Call ID: 1067c228-31654de6-b5af249b@192.168.2.27 Their Tag CF15CE04-CB9D3EC9 Our tag: as36bf9645 [May 25 14:59:45] DEBUG[3050]: chan_sip.c:14709 handle_request: **** Received ACK (6) - Command in SIP ACK [May 25 14:59:45] DEBUG[3050]: chan_sip.c:2090 __sip_ack: Stopping retransmission on '1067c228-31654de6-b5af249b@192.168.2.27' of Response 2: Match Not Found [May 25 14:59:46] DEBUG[3059]: channel.c:2030 ast_settimeout: Scheduling timer at 106 sample intervals [May 25 14:59:46] DEBUG[3059]: channel.c:2030 ast_settimeout: Scheduling timer at 0 sample intervals [May 25 14:59:46] DEBUG[3059]: channel.c:2030 ast_settimeout: Scheduling timer at 0 sample intervals [May 25 14:59:47] DEBUG[3050]: chan_sip.c:4365 find_call: = Found Their Call ID: 1067c228-31654de6-b5af249b@192.168.2.27 Their Tag CF15CE04-CB9D3EC9 Our tag: as36bf9645 [May 25 14:59:47] DEBUG[3050]: chan_sip.c:14709 handle_request: **** Received BYE (8) - Command in SIP BYE [May 25 14:59:47] DEBUG[3050]: chan_sip.c:1634 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 1067c228-31654de6-b5af249b@192.168.2.27 [May 25 14:59:47] DEBUG[3050]: chan_sip.c:14263 handle_request_bye: Received bye, issuing owner hangup [May 25 14:59:47] WARNING[3059]: app_voicemail.c:6186 vm_authenticate: Unable to read password [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:6351 vm_execmain: After vm_authenticate [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8719 get_vm_state_by_mailbox: Mailbox set to 2500 [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8724 get_vm_state_by_mailbox: comparing mailbox 2500 (i=0) to vmstate mailbox 2500 (i=0) [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8727 get_vm_state_by_mailbox: Found it! [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8757 vmstate_insert: Duplicate mailbox 2500, copying message info... [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8761 vmstate_insert: check_msgArray before memcpy [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8766 vmstate_insert: check_msgArray after memcpy [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8868 check_msgArray: Item 0 set to 1 [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8868 check_msgArray: Item 1 set to 2 [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8868 check_msgArray: Item 2 set to 3 [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8868 check_msgArray: Item 3 set to 4 [May 25 14:59:47] DEBUG[3059]: app_voicemail.c:8784 vmstate_insert: Inserting vm_state for user:, mailbox 2500