[Dec 3 09:55:01] VERBOSE[2842] logger.c: -- Remote UNIX connection
[Dec 3 09:55:02] VERBOSE[29540] logger.c: -- Remote UNIX connection disconnected
[Dec 3 09:56:00] VERBOSE[2947] logger.c: -- Unregistered SIP '399'
[Dec 3 09:56:00] VERBOSE[2947] logger.c: -- Unregistered SIP '398'
[Dec 3 09:56:00] VERBOSE[2947] logger.c: -- Unregistered SIP '101'
[Dec 3 09:57:01] VERBOSE[2947] logger.c: -- Unregistered SIP '399'
[Dec 3 09:57:01] VERBOSE[2947] logger.c: -- Unregistered SIP '398'
[Dec 3 09:57:01] VERBOSE[2947] logger.c: -- Unregistered SIP '101'
[Dec 3 09:57:02] VERBOSE[2947] logger.c: -- Registered SIP '399' at 10.72.100.2 port 55087
[Dec 3 09:57:02] NOTICE[2947] chan_sip.c: Peer '399' is now Reachable. (99ms / 2000ms)
[Dec 3 09:57:02] VERBOSE[2947] logger.c: -- Registered SIP '398' at 10.72.100.2 port 55087
[Dec 3 09:57:03] VERBOSE[2947] logger.c: -- Registered SIP '101' at 10.72.100.2 port 55087
[Dec 3 09:57:03] NOTICE[2947] chan_sip.c: Peer '398' is now Reachable. (234ms / 2000ms)
[Dec 3 09:57:03] NOTICE[2947] chan_sip.c: Peer '101' is now Reachable. (345ms / 2000ms)
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:1] GotoIf("SIP/399-08eec700", "0?ext-local|324|1") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:2] Macro("SIP/399-08eec700", "user-callerid|") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/399-08eec700", "AMPUSER=399") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: Set
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/399-08eec700", "0?report") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: GotoIf
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/399-08eec700", "1|Set|REALCALLERIDNUM=399") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: ExecIf
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/399-08eec700", "AMPUSER=399") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: Set
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/399-08eec700", "AMPUSERCIDNAME=399") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: Set
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/399-08eec700", "0?report") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: GotoIf
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/399-08eec700", "AMPUSERCID=399") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: Set
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/399-08eec700", "CALLERID(all)="399" <399>") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: Set
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:9] ExecIf("SIP/399-08eec700", "0|Set|CHANNEL(language)=") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: ExecIf
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/399-08eec700", "0?continue") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: GotoIf
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/399-08eec700", "__TTL=64") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: Set
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/399-08eec700", "1?continue") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Goto (macro-user-callerid,s,19)
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: GotoIf
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-user-callerid:19] NoOp("SIP/399-08eec700", "Using CallerID "399" <399>") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: Noop
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:3] GotoIf("SIP/399-08eec700", "1?skipdb") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Goto (from-internal,324,5)
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:5] Set("SIP/399-08eec700", "__NODEST=") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:6] Set("SIP/399-08eec700", "__BLKVM_OVERRIDE=BLKVM/324/SIP/399-08eec700") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:7] Set("SIP/399-08eec700", "__BLKVM_BASE=324") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:8] Set("SIP/399-08eec700", "DB(BLKVM/324/SIP/399-08eec700)=TRUE") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:9] Set("SIP/399-08eec700", "RRNODEST=") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:10] Set("SIP/399-08eec700", "__NODEST=324") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:11] Set("SIP/399-08eec700", "RecordMethod=Group") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:12] Macro("SIP/399-08eec700", "record-enable|324|Group") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/399-08eec700", "1?check") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Goto (macro-record-enable,s,4)
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: GotoIf
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/399-08eec700", "recordingcheck|20101203-095919|1291352359.357") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- AGI Script recordingcheck completed, returning 0
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: AGI
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/399-08eec700", "") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:13] Set("SIP/399-08eec700", "RingGroupMethod=ringallv2") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:14] Set("SIP/399-08eec700", "_FMGRP=324") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:15] GotoIf("SIP/399-08eec700", "0?doconfirm") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:16] Macro("SIP/399-08eec700", "dial|20|tr|324") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-dial:1] GotoIf("SIP/399-08eec700", "1?dial") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Goto (macro-dial,s,3)
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: GotoIf
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-dial:3] AGI("SIP/399-08eec700", "dialparties.agi") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Dec 3 09:59:19] VERBOSE[29543] logger.c: dialparties.agi: Starting New Dialparties.agi
[Dec 3 09:59:19] VERBOSE[29546] logger.c: == Parsing '/etc/asterisk/manager.conf': [Dec 3 09:59:19] VERBOSE[29546] logger.c: Found
[Dec 3 09:59:19] VERBOSE[29546] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [Dec 3 09:59:19] VERBOSE[29546] logger.c: Found
[Dec 3 09:59:19] VERBOSE[29546] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Dec 3 09:59:19] VERBOSE[29546] logger.c: Found
[Dec 3 09:59:19] WARNING[29546] config.c: Unknown directive '#permit=192.168.1.0/255.255.255.0' at line 18 of /etc/asterisk/manager_custom.conf
[Dec 3 09:59:19] VERBOSE[29546] logger.c: == Manager 'admin' logged on from 127.0.0.1
[Dec 3 09:59:19] VERBOSE[29543] logger.c: dialparties.agi: Caller ID name is '399' number is '399'
[Dec 3 09:59:19] VERBOSE[29543] logger.c: dialparties.agi: Methodology of ring is 'ringallv2'
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- dialparties.agi: Added extension 324 to extension map
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- dialparties.agi: Extension 324 cf is disabled
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- dialparties.agi: Extension 324 do not disturb is disabled
[Dec 3 09:59:19] VERBOSE[29543] logger.c: dialparties.agi: ExtensionState: 4
[Dec 3 09:59:19] VERBOSE[29543] logger.c: dialparties.agi: Extension 324 has ExtensionState: 4
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 324
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- dialparties.agi: dbset CALLTRACE/324 to 399
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- dialparties.agi: Filtered ARG3: 324
[Dec 3 09:59:19] VERBOSE[29546] logger.c: == Manager 'admin' logged off from 127.0.0.1
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- AGI Script dialparties.agi completed, returning 0
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: AGI
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-dial:7] Dial("SIP/399-08eec700", "SIP/324|22|trM(auto-blkvm)") in new stack
[Dec 3 09:59:19] NOTICE[29543] app_dial.c: Hey! chan SIP/399-08eec700's context='macro-dial', and exten='s'
[Dec 3 09:59:19] WARNING[29543] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Unknown)
[Dec 3 09:59:19] VERBOSE[29543] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: Dial
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-dial:8] Set("SIP/399-08eec700", "DIALSTATUS=CHANUNAVAIL") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: Set
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@macro-dial:9] GosubIf("SIP/399-08eec700", "0?CHANUNAVAIL|1") in new stack
[Dec 3 09:59:19] DEBUG[29543] app_macro.c: Executed application: GosubIf
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:17] Goto("SIP/399-08eec700", "nextstep") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Goto (from-internal,324,19)
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:19] Set("SIP/399-08eec700", "RingGroupMethod=") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:20] GotoIf("SIP/399-08eec700", "0?nodest") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:21] Set("SIP/399-08eec700", "__NODEST=") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:22] DBdel("SIP/399-08eec700", "BLKVM/324/SIP/399-08eec700") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- DBdel: family=BLKVM, key=324/SIP/399-08eec700
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [324@from-internal:23] Goto("SIP/399-08eec700", "ivr-12|s|1") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Goto (ivr-12,s,1)
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:1] Set("SIP/399-08eec700", "MSG=custom/07_01") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:2] Set("SIP/399-08eec700", "LOOPCOUNT=0") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:3] Set("SIP/399-08eec700", "__DIR-CONTEXT=") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:4] Set("SIP/399-08eec700", "_IVR_CONTEXT_ivr-12=") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:5] Set("SIP/399-08eec700", "_IVR_CONTEXT=ivr-12") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:6] GotoIf("SIP/399-08eec700", "0?begin") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:7] Answer("SIP/399-08eec700", "") in new stack
[Dec 3 09:59:19] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:8] Wait("SIP/399-08eec700", "1") in new stack
[Dec 3 09:59:20] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:9] Set("SIP/399-08eec700", "TIMEOUT(digit)=3") in new stack
[Dec 3 09:59:20] VERBOSE[29543] logger.c: -- Digit timeout set to 3
[Dec 3 09:59:20] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:10] Set("SIP/399-08eec700", "TIMEOUT(response)=10") in new stack
[Dec 3 09:59:20] VERBOSE[29543] logger.c: -- Response timeout set to 10
[Dec 3 09:59:20] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:11] Set("SIP/399-08eec700", "__IVR_RETVM=") in new stack
[Dec 3 09:59:20] VERBOSE[29543] logger.c: -- Executing [s@ivr-12:12] ExecIf("SIP/399-08eec700", "1|Background|custom/07_01") in new stack
[Dec 3 09:59:20] VERBOSE[29543] logger.c: -- <SIP/399-08eec700> Playing 'custom/07_01' (language 'en')
[Dec 3 10:00:01] VERBOSE[2842] logger.c: -- Remote UNIX connection
[Dec 3 10:00:01] VERBOSE[29550] logger.c: -- Remote UNIX connection disconnected
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [2@ivr-12:1] DBdel("SIP/399-08eec700", "BLKVM/324/SIP/399-08eec700") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- DBdel: family=BLKVM, key=324/SIP/399-08eec700
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- DBdel: Error deleting key from database.
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [2@ivr-12:2] Set("SIP/399-08eec700", "__NODEST=") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [2@ivr-12:3] Goto("SIP/399-08eec700", "ivr-27|s|1") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Goto (ivr-27,s,1)
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [s@ivr-27:1] Set("SIP/399-08eec700", "MSG=custom/07_02_02") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [s@ivr-27:2] Set("SIP/399-08eec700", "LOOPCOUNT=0") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [s@ivr-27:3] Set("SIP/399-08eec700", "__DIR-CONTEXT=") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [s@ivr-27:4] Set("SIP/399-08eec700", "_IVR_CONTEXT_ivr-27=ivr-12") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [s@ivr-27:5] Set("SIP/399-08eec700", "_IVR_CONTEXT=ivr-27") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [s@ivr-27:6] GotoIf("SIP/399-08eec700", "1?begin") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Goto (ivr-27,s,9)
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [s@ivr-27:9] Set("SIP/399-08eec700", "TIMEOUT(digit)=3") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Digit timeout set to 3
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [s@ivr-27:10] Set("SIP/399-08eec700", "TIMEOUT(response)=10") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Response timeout set to 10
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [s@ivr-27:11] Set("SIP/399-08eec700", "__IVR_RETVM=") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- Executing [s@ivr-27:12] ExecIf("SIP/399-08eec700", "1|Background|custom/07_02_02") in new stack
[Dec 3 10:00:21] VERBOSE[29543] logger.c: -- <SIP/399-08eec700> Playing 'custom/07_02_02' (language 'en')
[Dec 3 10:00:57] VERBOSE[29543] logger.c: -- Executing [h@ivr-27:1] Hangup("SIP/399-08eec700", "") in new stack
[Dec 3 10:00:57] VERBOSE[29543] logger.c: == Spawn extension (ivr-27, h, 1) exited non-zero on 'SIP/399-08eec700'

[Dec 3 09:10:01] VERBOSE[29452] logger.c: -- Remote UNIX connection disconnected
[Dec 3 09:14:29] WARNING[2947] chan_sip.c: username mismatch, have <399>, digest has <398>
[Dec 3 09:14:29] NOTICE[2947] chan_sip.c: Failed to authenticate user <sip:anonymous@anonymous.invalid>;tag=78ebc6b3-686966

[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:1] GotoIf("SIP/399-08eec700", "0?ext-local|321|1") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:2] Macro("SIP/399-08eec700", "user-callerid|") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/399-08eec700", "AMPUSER=399") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: Set
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/399-08eec700", "0?report") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: GotoIf
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/399-08eec700", "1|Set|REALCALLERIDNUM=399") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: ExecIf
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/399-08eec700", "AMPUSER=399") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: Set
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/399-08eec700", "AMPUSERCIDNAME=399") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: Set
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/399-08eec700", "0?report") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: GotoIf
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/399-08eec700", "AMPUSERCID=399") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: Set
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/399-08eec700", "CALLERID(all)="399" <399>") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: Set
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:9] ExecIf("SIP/399-08eec700", "0|Set|CHANNEL(language)=") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: ExecIf
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/399-08eec700", "0?continue") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: GotoIf
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/399-08eec700", "__TTL=64") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: Set
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/399-08eec700", "1?continue") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Goto (macro-user-callerid,s,19)
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: GotoIf
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-user-callerid:19] NoOp("SIP/399-08eec700", "Using CallerID "399" <399>") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: Noop
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:3] GotoIf("SIP/399-08eec700", "1?skipdb") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Goto (from-internal,321,5)
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:5] Set("SIP/399-08eec700", "__NODEST=") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:6] Set("SIP/399-08eec700", "__BLKVM_OVERRIDE=BLKVM/321/SIP/399-08eec700") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:7] Set("SIP/399-08eec700", "__BLKVM_BASE=321") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:8] Set("SIP/399-08eec700", "DB(BLKVM/321/SIP/399-08eec700)=TRUE") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:9] Set("SIP/399-08eec700", "RRNODEST=") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:10] Set("SIP/399-08eec700", "__NODEST=321") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:11] Set("SIP/399-08eec700", "RecordMethod=Group") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:12] Macro("SIP/399-08eec700", "record-enable|321|Group") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/399-08eec700", "1?check") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Goto (macro-record-enable,s,4)
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: GotoIf
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/399-08eec700", "recordingcheck|20101203-095001|1291351801.356") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- AGI Script recordingcheck completed, returning 0
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: AGI
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/399-08eec700", "") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:13] Set("SIP/399-08eec700", "RingGroupMethod=ringallv2") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:14] Set("SIP/399-08eec700", "_FMGRP=321") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:15] GotoIf("SIP/399-08eec700", "0?doconfirm") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:16] Macro("SIP/399-08eec700", "dial|20|tr|321") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-dial:1] GotoIf("SIP/399-08eec700", "1?dial") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Goto (macro-dial,s,3)
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: GotoIf
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-dial:3] AGI("SIP/399-08eec700", "dialparties.agi") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Dec 3 09:50:01] VERBOSE[29525] logger.c: dialparties.agi: Starting New Dialparties.agi
[Dec 3 09:50:01] VERBOSE[29528] logger.c: == Parsing '/etc/asterisk/manager.conf': [Dec 3 09:50:01] VERBOSE[29528] logger.c: Found
[Dec 3 09:50:01] VERBOSE[29528] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [Dec 3 09:50:01] VERBOSE[29528] logger.c: Found
[Dec 3 09:50:01] VERBOSE[29528] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Dec 3 09:50:01] VERBOSE[29528] logger.c: Found
[Dec 3 09:50:01] WARNING[29528] config.c: Unknown directive '#permit=192.168.1.0/255.255.255.0' at line 18 of /etc/asterisk/manager_custom.conf
[Dec 3 09:50:01] VERBOSE[29528] logger.c: == Manager 'admin' logged on from 127.0.0.1
[Dec 3 09:50:01] VERBOSE[29525] logger.c: dialparties.agi: Caller ID name is '399' number is '399'
[Dec 3 09:50:01] VERBOSE[29525] logger.c: dialparties.agi: Methodology of ring is 'ringallv2'
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- dialparties.agi: Added extension 321 to extension map
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- dialparties.agi: Extension 321 cf is disabled
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- dialparties.agi: Extension 321 do not disturb is disabled
[Dec 3 09:50:01] VERBOSE[29525] logger.c: dialparties.agi: ExtensionState: 4
[Dec 3 09:50:01] VERBOSE[29525] logger.c: dialparties.agi: Extension 321 has ExtensionState: 4
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 321
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- dialparties.agi: dbset CALLTRACE/321 to 399
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- dialparties.agi: Filtered ARG3: 321
[Dec 3 09:50:01] VERBOSE[29528] logger.c: == Manager 'admin' logged off from 127.0.0.1
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- AGI Script dialparties.agi completed, returning 0
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: AGI
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-dial:7] Dial("SIP/399-08eec700", "SIP/321|22|trM(auto-blkvm)") in new stack
[Dec 3 09:50:01] NOTICE[29525] app_dial.c: Hey! chan SIP/399-08eec700's context='macro-dial', and exten='s'
[Dec 3 09:50:01] WARNING[29525] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Unknown)
[Dec 3 09:50:01] VERBOSE[29525] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: Dial
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-dial:8] Set("SIP/399-08eec700", "DIALSTATUS=CHANUNAVAIL") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: Set
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@macro-dial:9] GosubIf("SIP/399-08eec700", "0?CHANUNAVAIL|1") in new stack
[Dec 3 09:50:01] DEBUG[29525] app_macro.c: Executed application: GosubIf
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:17] Goto("SIP/399-08eec700", "nextstep") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Goto (from-internal,321,19)
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:19] Set("SIP/399-08eec700", "RingGroupMethod=") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:20] GotoIf("SIP/399-08eec700", "0?nodest") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:21] Set("SIP/399-08eec700", "__NODEST=") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:22] DBdel("SIP/399-08eec700", "BLKVM/321/SIP/399-08eec700") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- DBdel: family=BLKVM, key=321/SIP/399-08eec700
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [321@from-internal:23] Goto("SIP/399-08eec700", "ivr-9|s|1") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Goto (ivr-9,s,1)
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:1] Set("SIP/399-08eec700", "MSG=custom/01_01") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:2] Set("SIP/399-08eec700", "LOOPCOUNT=0") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:3] Set("SIP/399-08eec700", "__DIR-CONTEXT=") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:4] Set("SIP/399-08eec700", "_IVR_CONTEXT_ivr-9=") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:5] Set("SIP/399-08eec700", "_IVR_CONTEXT=ivr-9") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:6] GotoIf("SIP/399-08eec700", "0?begin") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:7] Answer("SIP/399-08eec700", "") in new stack
[Dec 3 09:50:01] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:8] Wait("SIP/399-08eec700", "1") in new stack
[Dec 3 09:50:01] VERBOSE[2842] logger.c: -- Remote UNIX connection
[Dec 3 09:50:02] VERBOSE[29532] logger.c: -- Remote UNIX connection disconnected
[Dec 3 09:50:02] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:9] Set("SIP/399-08eec700", "TIMEOUT(digit)=3") in new stack
[Dec 3 09:50:02] VERBOSE[29525] logger.c: -- Digit timeout set to 3
[Dec 3 09:50:02] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:10] Set("SIP/399-08eec700", "TIMEOUT(response)=10") in new stack
[Dec 3 09:50:02] VERBOSE[29525] logger.c: -- Response timeout set to 10
[Dec 3 09:50:02] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:11] Set("SIP/399-08eec700", "__IVR_RETVM=") in new stack
[Dec 3 09:50:02] VERBOSE[29525] logger.c: -- Executing [s@ivr-9:12] ExecIf("SIP/399-08eec700", "1|Background|custom/01_01") in new stack
[Dec 3 09:50:02] VERBOSE[29525] logger.c: -- <SIP/399-08eec700> Playing 'custom/01_01' (language 'en')
[Dec 3 09:50:09] VERBOSE[29525] logger.c: -- Executing [h@ivr-9:1] Hangup("SIP/399-08eec700", "") in new stack
[Dec 3 09:50:09] VERBOSE[29525] logger.c: == Spawn extension (ivr-9, h, 1) exited non-zero on 'SIP/399-08eec700'

либо так - http://paste.org.ru/?jy3po0

либо так - http://depositfiles.com/files/xpdvu0wxi

Nov 28 04:02:04 elastix syslogd 1.4.1: restart.
Nov 30 16:27:47 elastix php: /var/lib/asterisk/agi-bin/phpagi.php[1658]: Undefined index: phpagi
Dec 2 16:05:37 elastix last message repeated 2 times
Dec 2 16:05:37 elastix php: /var/lib/asterisk/agi-bin/phpagi.php[1658]: Undefined index: phpagi
Dec 3 10:13:20 elastix shutdown[29605]: shutting down for system reboot
Dec 3 10:13:20 elastix init: Switching to runlevel: 6
Dec 3 10:13:22 elastix FaxQueuer[2904]: QUIT
Dec 3 10:13:22 elastix saslauthd[2940]: server_exit : master exited: 2940
Dec 3 10:13:29 elastix xinetd[2417]: Exiting...
Dec 3 10:13:30 elastix ntpd[2435]: ntpd exiting on signal 15
Dec 3 10:13:30 elastix rpc.statd[2090]: Caught signal 15, un-registering and exiting.
Dec 3 10:13:31 elastix auditd[1992]: The audit daemon is exiting.
Dec 3 10:13:31 elastix kernel: audit(1291353211.265:102799): audit_pid=0 old=1992 by auid=4294967295
Dec 3 10:13:31 elastix kernel: Kernel logging (proc) stopped.
Dec 3 10:13:31 elastix kernel: Kernel log daemon terminating.
Dec 3 10:13:32 elastix exiting on signal 15
Dec 3 10:14:52 elastix syslogd 1.4.1: restart.
Dec 3 10:14:52 elastix kernel: klogd 1.4.1, log source = /proc/kmsg started.
Dec 3 10:14:52 elastix kernel: Linux version 2.6.18-164.el5 (mockbuild@builder16.centos.org) (gcc version 4.1.2 20080704 (Red Hat 4.1.2-46)) #1 SMP Thu Sep 3 03:33:56 EDT 2009
Dec 3 10:14:52 elastix kernel: BIOS-provided physical RAM map:
Dec 3 10:14:52 elastix kernel: BIOS-e820: 0000000000010000 - 000000000009f800 (usable)
Dec 3 10:14:52 elastix kernel: BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
Dec 3 10:14:52 elastix kernel: BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
Dec 3 10:14:52 elastix kernel: BIOS-e820: 0000000000100000 - 000000003bee0000 (usable)
Dec 3 10:14:52 elastix kernel: BIOS-e820: 000000003bee0000 - 000000003bee3000 (ACPI NVS)
Dec 3 10:14:52 elastix kernel: BIOS-e820: 000000003bee3000 - 000000003bef0000 (ACPI data)
Dec 3 10:14:52 elastix kernel: BIOS-e820: 000000003bef0000 - 000000003bf00000 (reserved)
Dec 3 10:14:52 elastix kernel: BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved)
Dec 3 10:14:52 elastix kernel: BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved)
Dec 3 10:14:52 elastix kernel: 62MB HIGHMEM available.
Dec 3 10:14:52 elastix kernel: 896MB LOWMEM available.
Dec 3 10:14:52 elastix kernel: found SMP MP-table at 000f3d60
Dec 3 10:14:52 elastix kernel: Memory for crash kernel (0x0 to 0x0) notwithin permissible range
Dec 3 10:14:52 elastix kernel: disabling kdump
Dec 3 10:14:52 elastix kernel: Using x86 segment limits to approximate NX protection
Dec 3 10:14:52 elastix kernel: DMI 2.4 present.
Dec 3 10:14:52 elastix kernel: Using APIC driver default
Dec 3 10:14:52 elastix kernel: ACPI: PM-Timer IO Port: 0x408
Dec 3 10:14:52 elastix kernel: ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
Dec 3 10:14:52 elastix kernel: Processor #0 15:2 APIC version 20
Dec 3 10:14:52 elastix kernel: ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] disabled)
Dec 3 10:14:52 elastix kernel: ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] disabled)
Dec 3 10:14:52 elastix kernel: ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] disabled)
Dec 3 10:14:52 elastix kernel: ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
Dec 3 10:14:52 elastix kernel: ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
Dec 3 10:14:52 elastix kernel: ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1])
Dec 3 10:14:52 elastix kernel: ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1])
Dec 3 10:14:52 elastix kernel: ACPI: IOAPIC (id[0x04] address[0xfec00000] gsi_base[0])
Dec 3 10:14:52 elastix kernel: IOAPIC[0]: apic_id 4, version 3, address 0xfec00000, GSI 0-23
Dec 3 10:14:52 elastix kernel: ACPI: IOAPIC (id[0x05] address[0xfecc0000] gsi_base[24])
Dec 3 10:14:52 elastix kernel: IOAPIC[1]: apic_id 5, version 3, address 0xfecc0000, GSI 24-47
Dec 3 10:14:52 elastix kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Dec 3 10:14:52 elastix kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
Dec 3 10:14:52 elastix kernel: Enabling APIC mode: Flat. Using 2 I/O APICs
Dec 3 10:14:52 elastix kernel: ACPI: HPET id: 0x11068201 base: 0xfe800000
Dec 3 10:14:52 elastix kernel: Using ACPI (MADT) for SMP configuration information
Dec 3 10:14:52 elastix kernel: Allocating PCI resources starting at 40000000 (gap: 3bf00000:a4100000)
Dec 3 10:14:52 elastix kernel: Detected 2200.166 MHz processor.
Dec 3 10:14:52 elastix kernel: Built 1 zonelists. Total pages: 245472
Dec 3 10:14:52 elastix kernel: Kernel command line: ro root=/dev/VolGroup00/LogVol00
Dec 3 10:14:52 elastix kernel: Enabling fast FPU save and restore... done.
Dec 3 10:14:52 elastix kernel: Enabling unmasked SIMD FPU exception support... done.
Dec 3 10:14:52 elastix kernel: Initializing CPU#0
Dec 3 10:14:52 elastix kernel: CPU 0 irqstacks, hard=c0761000 soft=c0741000
Dec 3 10:14:52 elastix kernel: PID hash table entries: 4096 (order: 12, 16384 bytes)
Dec 3 10:14:52 elastix kernel: Console: colour VGA+ 80x25
Dec 3 10:14:52 elastix kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Dec 3 10:14:52 elastix kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Dec 3 10:14:52 elastix kernel: Memory: 965396k/981888k available (2154k kernel code, 15788k reserved, 899k data, 232k init, 64384k highmem)
Dec 3 10:14:52 elastix kernel: Checking if this processor honours the WP bit even in supervisor mode... Ok.
Dec 3 10:14:52 elastix kernel: hpet0: at MMIO 0xfe800000 (virtual 0xf8800000), IRQs 2, 8, 0
Dec 3 10:14:52 elastix kernel: hpet0: 3 32-bit timers, 14318180 Hz
Dec 3 10:14:52 elastix kernel: Using HPET for base-timer
Dec 3 10:14:52 elastix kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 4400.33 BogoMIPS (lpj=2200166)
Dec 3 10:14:52 elastix kernel: Security Framework v1.0.0 initialized
Dec 3 10:14:52 elastix kernel: SELinux: Initializing.
Dec 3 10:14:52 elastix kernel: selinux_register_security: Registering secondary module capability
Dec 3 10:14:52 elastix kernel: Capability LSM initialized as secondary
Dec 3 10:14:52 elastix kernel: Mount-cache hash table entries: 512
Dec 3 10:14:52 elastix kernel: CPU: Trace cache: 12K uops, L1 D cache: 8K
Dec 3 10:14:52 elastix kernel: CPU: L2 cache: 128K
Dec 3 10:14:52 elastix kernel: CPU: Hyper-Threading is disabled
Dec 3 10:14:52 elastix kernel: Intel machine check architecture supported.
Dec 3 10:14:52 elastix kernel: Intel machine check reporting enabled on CPU#0.
Dec 3 10:14:52 elastix kernel: CPU0: Intel P4/Xeon Extended MCE MSRs (12) available
Dec 3 10:14:52 elastix kernel: CPU0: Thermal monitoring enabled
Dec 3 10:14:52 elastix kernel: Checking 'hlt' instruction... OK.
Dec 3 10:14:52 elastix kernel: SMP alternatives: switching to UP code
Dec 3 10:14:52 elastix kernel: Freeing SMP alternatives: 14k freed
Dec 3 10:14:52 elastix kernel: ACPI: Core revision 20060707
Dec 3 10:14:52 elastix kernel: CPU0: Intel(R) Celeron(R) CPU 2.20GHz stepping 09
Dec 3 10:14:52 elastix kernel: Total of 1 processors activated (4400.33 BogoMIPS).
Dec 3 10:14:52 elastix kernel: ENABLING IO-APIC IRQs
Dec 3 10:14:52 elastix kernel: ..TIMER: vector=0x31 apic1=0 pin1=2 apic2=-1 pin2=-1
Dec 3 10:14:52 elastix kernel: Brought up 1 CPUs
Dec 3 10:14:52 elastix kernel: checking if image is initramfs... it is
Dec 3 10:14:52 elastix kernel: Freeing initrd memory: 3128k freed
Dec 3 10:14:52 elastix kernel: NET: Registered protocol family 16
Dec 3 10:14:52 elastix kernel: ACPI: bus type pci registered
Dec 3 10:14:52 elastix kernel: PCI: Using MMCONFIG
Dec 3 10:14:52 elastix kernel: Setting up standard PCI resources
Dec 3 10:14:52 elastix kernel: ACPI: Interpreter enabled
Dec 3 10:14:52 elastix kernel: ACPI: Using IOAPIC for interrupt routing
Dec 3 10:14:52 elastix kernel: ACPI: No dock devices found.
Dec 3 10:14:52 elastix kernel: ACPI: PCI Root Bridge [PCI0] (0000:00)
Dec 3 10:14:52 elastix kernel: PCI: Transparent bridge - 0000:00:13.1
Dec 3 10:14:52 elastix kernel: ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 6 7 *10 11 12)
Dec 3 10:14:52 elastix kernel: ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 6 7 10 *11 12)
Dec 3 10:14:52 elastix kernel: ACPI: PCI Interrupt Link [LNKC] (IRQs *3 4 6 7 10 11 12)
Dec 3 10:14:52 elastix kernel: ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 6 7 10 *11 12)
Dec 3 10:14:52 elastix kernel: ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 6 7 10 11 12) *0, disabled.
Dec 3 10:14:52 elastix kernel: ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 6 7 10 11 12) *0, disabled.
Dec 3 10:14:52 elastix kernel: ACPI: PCI Interrupt Link [LNK0] (IRQs 3 4 6 7 10 11 12) *0, disabled.
Dec 3 10:14:52 elastix kernel: ACPI: PCI Interrupt Link [LNK1] (IRQs 3 4 6 7 10 11 12) *5
Dec 3 10:14:52 elastix kernel: ACPI: PCI Root Bridge [PCI1] (0000:80)
Dec 3 10:14:52 elastix kernel: Linux Plug and Play Support v0.97 (c) Adam Belay
Dec 3 10:14:52 elastix kernel: pnp: PnP ACPI init
Dec 3 10:14:52 elastix kernel: pnp: PnP ACPI: found 19 devices
Dec 3 10:14:52 elastix kernel: usbcore: registered new driver usbfs
Dec 3 10:14:52 elastix kernel: usbcore: registered new driver hub
Dec 3 10:14:52 elastix kernel: PCI: Using ACPI for IRQ routing
Dec 3 10:14:52 elastix kernel: PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
Dec 3 10:14:52 elastix kernel: NetLabel: Initializing
Dec 3 10:14:52 elastix kernel: NetLabel: domain hash size = 128
Dec 3 10:14:52 elastix kernel: NetLabel: protocols = UNLABELED CIPSOv4
Dec 3 10:14:52 elastix kernel: NetLabel: unlabeled traffic allowed by default
Dec 3 10:14:52 elastix kernel: pnp: 00:01: ioport range 0x400-0x47f could not be reserved
Dec 3 10:14:52 elastix kernel: pnp: 00:01: ioport range 0x500-0x50f has been reserved
Dec 3 10:14:52 elastix kernel: PCI: Bridge: 0000:00:01.0
Dec 3 10:14:52 elastix kernel: IO window: c000-0000
Dec 3 10:14:52 elastix kernel: MEM window: fb000000-00000000
Dec 3 10:14:52 elastix kernel: PREFETCH window 0x00000000f4000000-0x00000000f7ffffff
Dec 3 10:14:52 elastix kernel: PCI: Bridge: 0000:00:02.0
Dec 3 10:14:52 elastix kernel: IO window: b000-0000
Dec 3 10:14:53 elastix kernel: MEM window: fdc00000-00000000
Dec 3 10:14:53 elastix kernel: PREFETCH window 0x00000000fdb00000-0x00000000fdbfffff
Dec 3 10:14:53 elastix kernel: PCI: Bridge: 0000:00:03.0
Dec 3 10:14:53 elastix kernel: IO window: a000-0000
Dec 3 10:14:53 elastix kernel: MEM window: fde00000-00000000
Dec 3 10:14:53 elastix kernel: PREFETCH window 0x00000000fdd00000-0x00000000fddfffff
Dec 3 10:14:53 elastix kernel: PCI: Bridge: 0000:00:13.1
Dec 3 10:14:53 elastix kernel: IO window: 9000-0000
Dec 3 10:14:53 elastix kernel: MEM window: fda00000-00000000
Dec 3 10:14:53 elastix kernel: PREFETCH window 0x00000000fd900000-0x00000000fd9fffff
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:02.0[A] -> GSI 27 (level, low) -> IRQ 169
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:03.0[A] -> GSI 31 (level, low) -> IRQ 177
Dec 3 10:14:53 elastix kernel: NET: Registered protocol family 2
Dec 3 10:14:53 elastix kernel: IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
Dec 3 10:14:53 elastix kernel: TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
Dec 3 10:14:53 elastix kernel: TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
Dec 3 10:14:53 elastix kernel: TCP: Hash tables configured (established 131072 bind 65536)
Dec 3 10:14:53 elastix kernel: TCP reno registered
Dec 3 10:14:53 elastix kernel: apm: BIOS version 1.2 Flags 0x07 (Driver version 1.16ac)
Dec 3 10:14:53 elastix kernel: apm: overridden by ACPI.
Dec 3 10:14:53 elastix kernel: audit: initializing netlink socket (disabled)
Dec 3 10:14:53 elastix kernel: type=2000 audit(1291371243.840:1): initialized
Dec 3 10:14:53 elastix kernel: highmem bounce pool size: 64 pages
Dec 3 10:14:53 elastix kernel: Total HugeTLB memory allocated, 0
Dec 3 10:14:53 elastix kernel: VFS: Disk quotas dquot_6.5.1
Dec 3 10:14:53 elastix kernel: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Dec 3 10:14:53 elastix kernel: Initializing Cryptographic API
Dec 3 10:14:53 elastix kernel: alg: No test for crc32c (crc32c-generic)
Dec 3 10:14:53 elastix kernel: ksign: Installing public key data
Dec 3 10:14:53 elastix kernel: Loading keyring
Dec 3 10:14:53 elastix kernel: - Added public key 581431131FF6A527
Dec 3 10:14:53 elastix kernel: - User ID: CentOS (Kernel Module GPG key)
Dec 3 10:14:53 elastix kernel: io scheduler noop registered
Dec 3 10:14:53 elastix kernel: io scheduler anticipatory registered
Dec 3 10:14:53 elastix kernel: io scheduler deadline registered
Dec 3 10:14:53 elastix kernel: io scheduler cfq registered (default)
Dec 3 10:14:53 elastix kernel: PCI: MSI quirk detected. MSI deactivated.
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:02.0[A] -> GSI 27 (level, low) -> IRQ 169
Dec 3 10:14:53 elastix kernel: assign_interrupt_mode Found MSI capability
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:03.0[A] -> GSI 31 (level, low) -> IRQ 177
Dec 3 10:14:53 elastix kernel: assign_interrupt_mode Found MSI capability
Dec 3 10:14:53 elastix kernel: pci_hotplug: PCI Hot Plug PCI Core version: 0.5
Dec 3 10:14:53 elastix kernel: ACPI: Fan [FAN] (on)
Dec 3 10:14:53 elastix kernel: ACPI: Thermal Zone [THRM] (14 C)
Dec 3 10:14:53 elastix kernel: Real Time Clock Driver v1.12ac
Dec 3 10:14:53 elastix kernel: Non-volatile memory driver v1.2
Dec 3 10:14:53 elastix kernel: Linux agpgart interface v0.101 (c) Dave Jones
Dec 3 10:14:53 elastix kernel: Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
Dec 3 10:14:53 elastix kernel: serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Dec 3 10:14:53 elastix kernel: 00:0b: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Dec 3 10:14:53 elastix kernel: brd: module loaded
Dec 3 10:14:53 elastix kernel: Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
Dec 3 10:14:53 elastix kernel: ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
Dec 3 10:14:53 elastix kernel: VP_IDE: IDE controller at PCI slot 0000:00:0f.1
Dec 3 10:14:53 elastix kernel: PCI: VIA IRQ fixup for 0000:00:0f.1, from 255 to 0
Dec 3 10:14:53 elastix kernel: VP_IDE: chipset revision 7
Dec 3 10:14:53 elastix kernel: VP_IDE: not 100% native mode: will probe irqs later
Dec 3 10:14:53 elastix kernel: VP_IDE: VIA vt8237a (rev 00) IDE UDMA133 controller on pci0000:00:0f.1
Dec 3 10:14:53 elastix kernel: ide0: BM-DMA at 0xe400-0xe407, BIOS settings: hda:DMA, hdb:pio
Dec 3 10:14:53 elastix kernel: ide1: BM-DMA at 0xe408-0xe40f, BIOS settings: hdc:DMA, hdd:pio
Dec 3 10:14:53 elastix kernel: hda: ST340014A, ATA DISK drive
Dec 3 10:14:53 elastix kernel: ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
Dec 3 10:14:53 elastix kernel: hdc: GCR-8523B, ATAPI CD/DVD-ROM drive
Dec 3 10:14:53 elastix kernel: ide1 at 0x170-0x177,0x376 on irq 15
Dec 3 10:14:53 elastix kernel: hda: max request size: 512KiB
Dec 3 10:14:53 elastix kernel: hda: 78165360 sectors (40020 MB) w/2048KiB Cache, CHS=16383/255/63, UDMA(100)
Dec 3 10:14:53 elastix kernel: hda: cache flushes supported
Dec 3 10:14:53 elastix kernel: hda: hda1 hda2
Dec 3 10:14:53 elastix kernel: ide-floppy driver 0.99.newide
Dec 3 10:14:53 elastix kernel: usbcore: registered new driver hiddev
Dec 3 10:14:53 elastix kernel: usbcore: registered new driver usbhid
Dec 3 10:14:53 elastix kernel: drivers/usb/input/hid-core.c: v2.6:USB HID core driver
Dec 3 10:14:53 elastix kernel: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
Dec 3 10:14:53 elastix kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Dec 3 10:14:53 elastix kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Dec 3 10:14:53 elastix kernel: mice: PS/2 mouse device common for all mice
Dec 3 10:14:53 elastix kernel: md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27
Dec 3 10:14:53 elastix kernel: md: bitmap version 4.39
Dec 3 10:14:53 elastix kernel: TCP bic registered
Dec 3 10:14:53 elastix kernel: Initializing IPsec netlink socket
Dec 3 10:14:53 elastix kernel: NET: Registered protocol family 1
Dec 3 10:14:53 elastix kernel: NET: Registered protocol family 17
Dec 3 10:14:53 elastix kernel: Using IPI No-Shortcut mode
Dec 3 10:14:53 elastix kernel: ACPI: (supports S0<6>Time: hpet clocksource has been installed.
Dec 3 10:14:53 elastix kernel: S1 S4 S5)
Dec 3 10:14:53 elastix kernel: Initalizing network drop monitor service
Dec 3 10:14:53 elastix kernel: Freeing unused kernel memory: 232k freed
Dec 3 10:14:53 elastix kernel: Write protecting the kernel read-only data: 404k
Dec 3 10:14:53 elastix kernel: input: AT Translated Set 2 keyboard as /class/input/input0
Dec 3 10:14:53 elastix kernel: input: ImExPS/2 Generic Explorer Mouse as /class/input/input1
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:10.4[C] -> GSI 21 (level, low) -> IRQ 185
Dec 3 10:14:53 elastix kernel: PCI: VIA IRQ fixup for 0000:00:10.4, from 3 to 9
Dec 3 10:14:53 elastix kernel: ehci_hcd 0000:00:10.4: EHCI Host Controller
Dec 3 10:14:53 elastix kernel: ehci_hcd 0000:00:10.4: new USB bus registered, assigned bus number 1
Dec 3 10:14:53 elastix kernel: ehci_hcd 0000:00:10.4: irq 185, io mem 0xfdfff000
Dec 3 10:14:53 elastix kernel: ehci_hcd 0000:00:10.4: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
Dec 3 10:14:53 elastix kernel: usb usb1: configuration #1 chosen from 1 choice
Dec 3 10:14:53 elastix kernel: hub 1-0:1.0: USB hub found
Dec 3 10:14:53 elastix kernel: hub 1-0:1.0: 8 ports detected
Dec 3 10:14:53 elastix kernel: USB Universal Host Controller Interface driver v3.0
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:10.0[A] -> GSI 20 (level, low) -> IRQ 193
Dec 3 10:14:53 elastix kernel: PCI: VIA IRQ fixup for 0000:00:10.0, from 10 to 1
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.0: UHCI Host Controller
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 2
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.0: irq 193, io base 0x0000e000
Dec 3 10:14:53 elastix kernel: usb usb2: configuration #1 chosen from 1 choice
Dec 3 10:14:53 elastix kernel: hub 2-0:1.0: USB hub found
Dec 3 10:14:53 elastix kernel: hub 2-0:1.0: 2 ports detected
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:10.1[B] -> GSI 22 (level, low) -> IRQ 201
Dec 3 10:14:53 elastix kernel: PCI: VIA IRQ fixup for 0000:00:10.1, from 11 to 9
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.1: UHCI Host Controller
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.1: new USB bus registered, assigned bus number 3
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.1: irq 201, io base 0x0000dc00
Dec 3 10:14:53 elastix kernel: usb usb3: configuration #1 chosen from 1 choice
Dec 3 10:14:53 elastix kernel: hub 3-0:1.0: USB hub found
Dec 3 10:14:53 elastix kernel: hub 3-0:1.0: 2 ports detected
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:10.2[C] -> GSI 21 (level, low) -> IRQ 185
Dec 3 10:14:53 elastix kernel: PCI: VIA IRQ fixup for 0000:00:10.2, from 3 to 9
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.2: UHCI Host Controller
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.2: new USB bus registered, assigned bus number 4
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.2: irq 185, io base 0x0000d800
Dec 3 10:14:53 elastix kernel: usb usb4: configuration #1 chosen from 1 choice
Dec 3 10:14:53 elastix kernel: hub 4-0:1.0: USB hub found
Dec 3 10:14:53 elastix kernel: hub 4-0:1.0: 2 ports detected
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:10.3[D] -> GSI 23 (level, low) -> IRQ 209
Dec 3 10:14:53 elastix kernel: PCI: VIA IRQ fixup for 0000:00:10.3, from 11 to 1
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.3: UHCI Host Controller
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.3: new USB bus registered, assigned bus number 5
Dec 3 10:14:53 elastix kernel: uhci_hcd 0000:00:10.3: irq 209, io base 0x0000d400
Dec 3 10:14:53 elastix kernel: usb usb5: configuration #1 chosen from 1 choice
Dec 3 10:14:53 elastix kernel: hub 5-0:1.0: USB hub found
Dec 3 10:14:53 elastix kernel: hub 5-0:1.0: 2 ports detected
Dec 3 10:14:53 elastix kernel: SCSI subsystem initialized
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:0f.0[B] -> GSI 21 (level, low) -> IRQ 185
Dec 3 10:14:53 elastix kernel: sata_via 0000:00:0f.0: routed to hard irq line 11
Dec 3 10:14:53 elastix kernel: scsi0 : sata_via
Dec 3 10:14:53 elastix kernel: scsi1 : sata_via
Dec 3 10:14:53 elastix kernel: ata1: SATA max UDMA/133 cmd 0xfc00 ctl 0xf800 bmdma 0xec00 irq 185
Dec 3 10:14:53 elastix kernel: ata2: SATA max UDMA/133 cmd 0xf400 ctl 0xf000 bmdma 0xec08 irq 185
Dec 3 10:14:53 elastix kernel: ata1: SATA link down 1.5 Gbps (SStatus 0 SControl 300)
Dec 3 10:14:53 elastix kernel: ata2: SATA link down 1.5 Gbps (SStatus 0 SControl 300)
Dec 3 10:14:53 elastix kernel: device-mapper: uevent: version 1.0.3
Dec 3 10:14:53 elastix kernel: device-mapper: ioctl: 4.11.5-ioctl (2007-12-12) initialised: dm-devel@redhat.com
Dec 3 10:14:53 elastix kernel: device-mapper: dm-raid45: initialized v0.2594l
Dec 3 10:14:53 elastix kernel: kjournald starting. Commit interval 5 seconds
Dec 3 10:14:53 elastix kernel: EXT3-fs: mounted filesystem with ordered data mode.
Dec 3 10:14:53 elastix kernel: SELinux: Disabled at runtime.
Dec 3 10:14:53 elastix kernel: type=1404 audit(1291371269.341:2): selinux=0 auid=4294967295 ses=4294967295
Dec 3 10:14:53 elastix kernel: parport: PnPBIOS parport detected.
Dec 3 10:14:53 elastix kernel: parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
Dec 3 10:14:53 elastix kernel: hdc: ATAPI 52X CD-ROM drive, 128kB Cache, DMA
Dec 3 10:14:53 elastix kernel: Uniform CD-ROM driver Revision: 3.20
Dec 3 10:14:53 elastix kernel: via-rhine.c:v1.10-LK1.4.1 July-24-2006 Written by Donald Becker
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:00:12.0[A] -> GSI 23 (level, low) -> IRQ 209
Dec 3 10:14:53 elastix kernel: eth0: VIA Rhine II at 0xfdffe000, 00:30:67:11:db:26, IRQ 209.
Dec 3 10:14:53 elastix kernel: eth0: MII PHY found at address 1, status 0x786d advertising 01e1 Link 43e1.
Dec 3 10:14:53 elastix kernel: input: PC Speaker as /class/input/input2
Dec 3 10:14:53 elastix kernel: Floppy drive(s): fd0 is 1.44M
Dec 3 10:14:53 elastix kernel: FDC 0 is a post-1991 82077
Dec 3 10:14:53 elastix kernel: ACPI: PCI Interrupt 0000:80:01.0[A] -> GSI 17 (level, low) -> IRQ 217
Dec 3 10:14:53 elastix kernel: hda_codec: num_steps = 0 for NID=0x1c (ctl = Front Playback Volume)
Dec 3 10:14:53 elastix kernel: hda_codec: num_steps = 0 for NID=0x1d (ctl = Headphone Playback Volume)
Dec 3 10:14:53 elastix last message repeated 8 times
Dec 3 10:14:53 elastix kernel: lp0: using parport0 (interrupt-driven).
Dec 3 10:14:53 elastix kernel: lp0: console ready
Dec 3 10:14:53 elastix kernel: ACPI: Power Button (FF) [PWRF]
Dec 3 10:14:53 elastix kernel: ACPI: Power Button (CM) [PWRB]
Dec 3 10:14:53 elastix kernel: ACPI: Sleep Button (CM) [SLPB]
Dec 3 10:14:53 elastix kernel: md: Autodetecting RAID arrays.
Dec 3 10:14:53 elastix kernel: md: autorun ...
Dec 3 10:14:53 elastix kernel: md: ... autorun DONE.
Dec 3 10:14:53 elastix kernel: device-mapper: multipath: version 1.0.5 loaded
Dec 3 10:14:53 elastix kernel: EXT3 FS on dm-0, internal journal
Dec 3 10:14:53 elastix kernel: kjournald starting. Commit interval 5 seconds
Dec 3 10:14:53 elastix kernel: EXT3 FS on hda1, internal journal
Dec 3 10:14:53 elastix kernel: EXT3-fs: mounted filesystem with ordered data mode.
Dec 3 10:14:53 elastix kernel: Adding 1966072k swap on /dev/VolGroup00/LogVol01. Priority:-1 extents:1 across:1966072k
Dec 3 10:14:53 elastix kernel: eth0: link up, 100Mbps, full-duplex, lpa 0x43E1
Dec 3 10:14:53 elastix rpc.statd[2072]: Version 1.0.9 Starting
Dec 3 10:14:53 elastix rpc.statd[2072]: statd running as root. chown /var/lib/nfs/statd/sm to choose different user
Dec 3 10:14:55 elastix kernel: dahdi: Telephony Interface Registered on major 196
Dec 3 10:14:55 elastix kernel: dahdi: Version: 2.2.0.2
Dec 3 10:14:55 elastix kernel: dahdi_transcode: Loaded.
Dec 3 10:14:55 elastix kernel: INFO-xpp: revision trunk-r6963 MAX_XPDS=64 (8*8)
Dec 3 10:14:55 elastix kernel: INFO-xpp: FEATURE: with BRISTUFF support
Dec 3 10:14:55 elastix kernel: INFO-xpp: FEATURE: with PROTOCOL_DEBUG
Dec 3 10:14:55 elastix kernel: INFO-xpp: FEATURE: with sync_tick() from DAHDI
Dec 3 10:14:55 elastix kernel: INFO-xpp_usb: revision trunk-r6963
Dec 3 10:14:55 elastix kernel: usbcore: registered new driver xpp_usb
Dec 3 10:14:55 elastix kernel: Unified AP4XX PCI Card Driver
Dec 3 10:14:55 elastix kernel: DAHDI Dynamic Span support LOADED
Dec 3 10:14:56 elastix kernel: rxt1: no version for "dahdi_hdlc_putbuf" found: kernel tainted.
Dec 3 10:14:56 elastix kernel: dahdi: Registered tone zone 0 (United States / North America)
Dec 3 10:14:56 elastix kernel: All TDMoE multiframe span groups are active.
Dec 3 10:14:58 elastix kernel: NET: Registered protocol family 10
Dec 3 10:14:58 elastix kernel: lo: Disabled Privacy Extensions
Dec 3 10:14:58 elastix kernel: IPv6 over IPv4 tunneling driver
Dec 3 10:14:59 elastix xinetd[2401]: xinetd Version 2.3.14 started with libwrap loadavg labeled-networking options compiled in.
Dec 3 10:14:59 elastix xinetd[2401]: Started working: 1 available service
Dec 3 10:14:59 elastix ntpd[2418]: ntpd 4.2.2p1@1.1570-o Tue May 19 13:58:05 UTC 2009 (1)
Dec 3 10:14:59 elastix ntpd[2419]: precision = 2.000 usec
Dec 3 10:14:59 elastix ntpd[2419]: Listening on interface wildcard, 0.0.0.0#123 Disabled
Dec 3 10:14:59 elastix ntpd[2419]: Listening on interface wildcard, ::#123 Disabled
Dec 3 10:14:59 elastix ntpd[2419]: Listening on interface lo, ::1#123 Enabled
Dec 3 10:14:59 elastix ntpd[2419]: Listening on interface eth0, fe80::230:67ff:fe11:db26#123 Enabled
Dec 3 10:14:59 elastix ntpd[2419]: Listening on interface lo, 127.0.0.1#123 Enabled
Dec 3 10:14:59 elastix ntpd[2419]: Listening on interface eth0, 10.72.100.3#123 Enabled
Dec 3 10:14:59 elastix ntpd[2419]: kernel time sync status 0040
Dec 3 10:14:59 elastix ntpd[2419]: frequency initialized 0.000 PPM from /var/lib/ntp/drift
Dec 3 10:15:13 elastix FaxQueuer[2886]: HylaFAX (tm) Version 4.3.3
Dec 3 10:15:13 elastix FaxQueuer[2886]: Copyright (c) 1990-1996 Sam Leffler
Dec 3 10:15:13 elastix FaxQueuer[2886]: Copyright (c) 1991-1996 Silicon Graphics, Inc.
Dec 3 10:15:14 elastix HylaFAX[2889]: HylaFAX INET Protocol Server: restarted.
Dec 3 10:15:15 elastix saslauthd[2922]: detach_tty : master pid is: 2922
Dec 3 10:15:15 elastix saslauthd[2922]: ipc_init : listening on socket: /var/run/saslauthd/mux
Dec 3 10:18:13 elastix ntpd[2419]: synchronized to LOCAL(0), stratum 10
Dec 3 10:18:13 elastix ntpd[2419]: kernel time sync enabled 0001

[Dec 3 09:10:01] VERBOSE[29452] logger.c: -- Remote UNIX connection disconnected
[Dec 3 09:14:29] WARNING[2947] chan_sip.c: username mismatch, have <399>, digest has <398>
[Dec 3 09:14:29] NOTICE[2947] chan_sip.c: Failed to authenticate user <sip:anonymous@anonymous.invalid>;tag=78ebc6b3-686966

[398]
insecure = very
type = friend
callwaiting = no
cid_number = 398
context = numberplan-custom-3
email =
fullname = 3980
group =
hasagent = no
hasdirectory = no
hasiax = no
hasmanager = no
hassip = yes
hasvoicemail = no
host = dynamic
username = 398
secret = secret
threewaycalling = no
vmsecret =
zapchan =
registeriax = no
;registersip = yes
canreinvite = no
nat = no
dtmfmode = rfc2833
disallow = all
allow = ulaw,alaw

[399]
insecure = very
type = friend
callwaiting = no
cid_number = 399
context = numberplan-custom-3
email =
fullname = 3990
group =
hasagent = no
hasdirectory = no
hasiax = no
hasmanager = no
hassip = yes
hasvoicemail = no
host = dynamic
username = 399
secret = secret
threewaycalling = no
vmsecret =
zapchan =
registeriax = no
;registersip = yes
canreinvite = no
nat = no
dtmfmode = rfc2833
disallow = all
allow = ulaw,alaw