rendered paste body[root@asterisk1 asterisk]# !tail
tail -f /var/log/asterisk/full
Dec 12 14:27:02 DEBUG[29291] chan_zap.c: master: 35, slave: 58, nothingok: 0
Dec 12 14:27:02 DEBUG[29291] chan_zap.c: Stopping tones on 35/0 talking to 58/0
Dec 12 14:27:02 DEBUG[29291] chan_zap.c: Stopping tones on 58/0 talking to 35/0
Dec 12 14:27:02 DEBUG[29291] chan_zap.c: disabled echo cancellation on channel 35
Dec 12 14:27:02 DEBUG[29291] chan_zap.c: disabled echo cancellation on channel 58
Dec 12 14:27:02 DEBUG[29291] chan_zap.c: Making 58 slave to master 35 at 0
Dec 12 14:27:02 DEBUG[29291] chan_zap.c: Added 38 to conference 9/35
Dec 12 14:27:02 DEBUG[29291] chan_zap.c: Added 23 to conference 9/58
Dec 12 14:27:02 DEBUG[29291] chan_zap.c: Updated conferencing on 35, with 0 conference users
Dec 12 14:27:02 DEBUG[29291] chan_zap.c: Updated conferencing on 58, with 0 conference users
Dec 12 14:27:10 DEBUG[5484] chan_sip.c: Auto destroying call '33289bb9-467e30ff@192.168.1.94'
Dec 12 14:27:22 DEBUG[5482] dsp.c: dsp busy pattern set to 2500,500
Dec 12 14:27:22 VERBOSE[5482] logger.c: -- Accepting overlap call from '3142052900' to '2757171' on channel 0/11, span 3
Dec 12 14:27:22 VERBOSE[29305] logger.c: -- Starting simple switch on 'Zap/59-1'
Dec 12 14:27:25 DEBUG[29305] chan_zap.c: Enabled echo cancellation on channel 59
Dec 12 14:27:25 VERBOSE[29305] logger.c: -- Executing Dial("Zap/59-1", "Zap/g0/2757171") in new stack
Dec 12 14:27:25 DEBUG[29305] dsp.c: dsp busy pattern set to 2500,500
Dec 12 14:27:25 VERBOSE[29305] logger.c: -- Making new call for cr 34828
Dec 12 14:27:25 VERBOSE[29305] logger.c: -- Requested transfer capability: 0x00 - SPEECH
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Protocol Discriminator: Q.931 (8) len=102
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Call Ref: len= 2 (reference 2060/0x80C) (Originator)
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Message type: SETUP (5)
Dec 12 14:27:25 VERBOSE[29305] logger.c: > [04 03 80 90 a2]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0)
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Ext: 1 User information layer 1: u-Law (34)
Dec 12 14:27:25 VERBOSE[29305] logger.c: > [18 03 a9 83 8a]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
Dec 12 14:27:25 VERBOSE[29305] logger.c: > ChanSel: Reserved
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Ext: 1 Coding: 0 Number Specified Channel Type: 3
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Ext: 1 Channel: 10 ]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > [1c 26 9f aa 06 80 01 00 82 01 00 8b 01 00 a1 18 02 01 12 02 01 00 80 10 52 65 63 65 70 74 69 6f 6e 69 73 74 20 41 6c 74]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Facility (len=40, codeset=0) [ Dec 12 14:27:25 VERBOSE[29305] logger.c: > Facility (len=40, codeset=0) [ 0x9f, 0xaa, 0x06, 0x80, 0x01, 0x00, 0x82, 0x01, 0x00, 0x8b, 0x01, 0x00, 0xa1, 0x18, 0x02, 0x01, 0x12, 0x02, 0x01, 0x00, 0x80, 0x10, 'Receptionist', 0x20, 'Alt'Dec 12 14:27:25 VERBOSE[29305] logger.c: > Facility (len=40, codeset=0) [ 0x9f, 0xaa, 0x06, 0x80, 0x01, 0x00, 0x82, 0x01, 0x00, 0x8b, 0x01, 0x00, 0xa1, 0x18, 0x02, 0x01, 0x12, 0x02, 0x01, 0x00, 0x80, 0x10, 'Receptionist', 0x20, 'Alt' ]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > [1e 02 80 83]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: User (0)
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > [28 11 b1 52 65 63 65 70 74 69 6f 6e 69 73 74 20 41 6c 74]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Display (len=17) Charset: 31 [ Receptionist Alt ]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > [6c 0c 21 81 33 31 34 32 30 35 32 39 30 30]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Presentation: Presentation permitted, user number passed network screening (1) '3142052900' ]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > [70 08 a1 32 37 35 37 31 37 31]
Dec 12 14:27:25 VERBOSE[29305] logger.c: > Called Number (len=10) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '2757171' ]
Dec 12 14:27:25 VERBOSE[29305] logger.c: -- Called g0/2757171
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8) len=13
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 2060/0x80C) (Terminator)
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Message type: STATUS (125)
Dec 12 14:27:25 VERBOSE[5481] logger.c: < [08 03 82 e3 28]
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Cause (len= 5) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2)
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Ext: 1 Cause: Info. element nonexist or not implemented (99), class = Protocol Error (6) ]
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Cause data 1: 28 (40, Display IE)
Dec 12 14:27:25 VERBOSE[5481] logger.c: < [14 01 00]
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Call State (len= 3) [ Ext: 0 Coding: CCITT (ITU) standard (0) Call state: Null (0)
Dec 12 14:27:25 VERBOSE[5481] logger.c: -- Processing IE 8 (cs0, Cause)
Dec 12 14:27:25 VERBOSE[5481] logger.c: -- Processing IE 20 (cs0, Call State)
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8) len=10
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 2060/0x80C) (Terminator)
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Message type: CALL PROCEEDING (2)
Dec 12 14:27:25 VERBOSE[5481] logger.c: < [18 03 a9 83 8a]
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
Dec 12 14:27:25 VERBOSE[5481] logger.c: < ChanSel: Reserved
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Ext: 1 Coding: 0 Number Specified Channel Type: 3
Dec 12 14:27:25 VERBOSE[5481] logger.c: < Ext: 1 Channel: 10 ]
Dec 12 14:27:25 VERBOSE[5481] logger.c: -- Processing IE 24 (cs0, Channel Identification)
Dec 12 14:27:25 DEBUG[5481] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/10 span 2
Dec 12 14:27:25 VERBOSE[29305] logger.c: -- Zap/34-1 is proceeding passing it to Zap/59-1
Dec 12 14:27:25 DEBUG[29305] chan_zap.c: Requested indication 15 on channel Zap/59-1
Dec 12 14:27:25 DEBUG[29305] chan_zap.c: Received AST_CONTROL_PROCEEDING on Zap/59-1
Dec 12 14:27:26 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8) len=9
Dec 12 14:27:26 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 2060/0x80C) (Terminator)
Dec 12 14:27:26 VERBOSE[5481] logger.c: < Message type: ALERTING (1)
Dec 12 14:27:26 VERBOSE[5481] logger.c: < [1e 02 82 88]
Dec 12 14:27:26 VERBOSE[5481] logger.c: < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2)
Dec 12 14:27:26 VERBOSE[5481] logger.c: < Ext: 1 Progress Description: Inband information or appropriate pattern now available. (8) ]
Dec 12 14:27:26 VERBOSE[5481] logger.c: -- Processing IE 30 (cs0, Progress Indicator)
Dec 12 14:27:26 DEBUG[5481] chan_zap.c: Enabled echo cancellation on channel 34
Dec 12 14:27:26 VERBOSE[29305] logger.c: -- Zap/34-1 is ringing
Dec 12 14:27:26 DEBUG[29305] chan_zap.c: Requested indication 3 on channel Zap/59-1
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8) len=65
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1684/0x694) (Originator)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Message type: SETUP (5)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < [04 03 80 90 a2]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Ext: 1 User information layer 1: u-Law (34)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < [18 03 a1 83 8c]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Preferred Dchan: 0
Dec 12 14:27:28 VERBOSE[5481] logger.c: < ChanSel: Reserved
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Ext: 1 Coding: 0 Number Specified Channel Type: 3
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Ext: 1 Channel: 12 ]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < [1c 17 9f 8b 01 00 a1 11 02 01 01 02 01 00 80 09 4c 41 44 55 45 2c 20 4d 4f]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Facility (len=25, codeset=0) [ Dec 12 14:27:28 VERBOSE[5481] logger.c: < Facility (len=25, codeset=0) [ 0x9f, 0x8b, 0x01, 0x00, 0xa1, 0x11, 0x02, 0x01, 0x01, 0x02, 0x01, 0x00, 0x80, 0x09, 'LADUE', 0x2c, 0x20, 'MO'Dec 12 14:27:28 VERBOSE[5481] logger.c: < Facility (len=25, codeset=0) [ 0x9f, 0x8b, 0x01, 0x00, 0xa1, 0x11, 0x02, 0x01, 0x01, 0x02, 0x01, 0x00, 0x80, 0x09, 'LADUE', 0x2c, 0x20, 'MO' ]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < [1e 02 82 83]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < [6c 0c 21 82 33 31 34 37 39 31 30 30 36 32]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Presentation: Presentation permitted, user number failed network screening (2) '3147910062' ]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < [70 05 a1 36 36 34 33]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Called Number (len= 7) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '6643' ]
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Making new call for cr 1684
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Processing Q.931 Call Setup
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Processing IE 4 (cs0, Bearer Capability)
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Processing IE 24 (cs0, Channel Identification)
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Processing IE 28 (cs0, Facility)
Dec 12 14:27:28 VERBOSE[5481] logger.c: Handle Q.932 ROSE Invoke component
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Processing IE 30 (cs0, Progress Indicator)
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Processing IE 108 (cs0, Calling Party Number)
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Processing IE 112 (cs0, Called Party Number)
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Protocol Discriminator: Q.931 (8) len=14
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Call Ref: len= 2 (reference 1684/0x694) (Terminator)
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Message type: SETUP ACKNOWLEDGE (13)
Dec 12 14:27:28 VERBOSE[5481] logger.c: > [18 03 a9 83 8c]
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
Dec 12 14:27:28 VERBOSE[5481] logger.c: > ChanSel: Reserved
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Ext: 1 Coding: 0 Number Specified Channel Type: 3
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Ext: 1 Channel: 12 ]
Dec 12 14:27:28 VERBOSE[5481] logger.c: > [1e 02 81 82]
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Ext: 1 Progress Description: Called equipment is non-ISDN. (2) ]
Dec 12 14:27:28 DEBUG[5481] dsp.c: dsp busy pattern set to 2500,500
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Accepting overlap call from '3147910062' to '6643' on channel 0/12, span 2
Dec 12 14:27:28 VERBOSE[29312] logger.c: -- Starting simple switch on 'Zap/36-1'
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8) len=13
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1684/0x694) (Originator)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Message type: STATUS (125)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < [08 03 82 e5 0d]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Cause (len= 5) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Ext: 1 Cause: Message not compatible with call state (101), class = Protocol Error (6) ]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Cause data 1: 0d (13, SETUP ACKNOWLEDGE message)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < [14 01 06]
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Call State (len= 3) [ Ext: 0 Coding: CCITT (ITU) standard (0) Call state: Call Present (6)
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Processing IE 8 (cs0, Cause)
Dec 12 14:27:28 VERBOSE[5481] logger.c: -- Processing IE 20 (cs0, Call State)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8) len=5
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 2060/0x80C) (Terminator)
Dec 12 14:27:28 VERBOSE[5481] logger.c: < Message type: CONNECT (7)
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Protocol Discriminator: Q.931 (8) len=5
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Call Ref: len= 2 (reference 2060/0x80C) (Originator)
Dec 12 14:27:28 VERBOSE[5481] logger.c: > Message type: CONNECT ACKNOWLEDGE (15)
Dec 12 14:27:28 DEBUG[5481] chan_zap.c: Echo cancellation already on
Dec 12 14:27:28 VERBOSE[29305] logger.c: -- Zap/34-1 answered Zap/59-1
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: Requested indication -1 on channel Zap/59-1
Dec 12 14:27:28 VERBOSE[29305] logger.c: -- Attempting native bridge of Zap/59-1 and Zap/34-1
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: master: 59, slave: 34, nothingok: 0
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: Stopping tones on 59/0 talking to 34/0
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: Stopping tones on 34/0 talking to 59/0
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: disabled echo cancellation on channel 59
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: disabled echo cancellation on channel 34
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: Making 34 slave to master 59 at 0
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: Added 22 to conference 9/59
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: Added 39 to conference 9/34
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: Updated conferencing on 59, with 0 conference users
Dec 12 14:27:28 DEBUG[29305] chan_zap.c: Updated conferencing on 34, with 0 conference users
Dec 12 14:27:31 DEBUG[29312] chan_zap.c: Enabled echo cancellation on channel 36
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Macro("Zap/36-1", "exten-vm|6643|6643") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Macro("Zap/36-1", "user-callerid") in new stack
Dec 12 14:27:31 DEBUG[29312] pbx.c: Expression result is '0'
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing GotoIf("Zap/36-1", "0?report") in new stack
Dec 12 14:27:31 DEBUG[29312] pbx.c: Not taking any branch
Dec 12 14:27:31 DEBUG[29312] pbx.c: Expression result is '0'
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing GotoIf("Zap/36-1", "0?start") in new stack
Dec 12 14:27:31 DEBUG[29312] pbx.c: Not taking any branch
Dec 12 14:27:31 DEBUG[29312] pbx.c: Function result is '3147910062'
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "REALCALLERIDNUM=3147910062") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing NoOp("Zap/36-1", "REALCALLERIDNUM is 3147910062") in new stack
Dec 12 14:27:31 DEBUG[29312] db.c: Unable to find key '3147910062/user' in family 'DEVICE'
Dec 12 14:27:31 DEBUG[29312] func_db.c: DB: DEVICE/3147910062/user not found in database.
Dec 12 14:27:31 DEBUG[29312] pbx.c: Function result is ''
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "AMPUSER=") in new stack
Dec 12 14:27:31 DEBUG[29312] db.c: Unable to find key '/cidname' in family 'AMPUSER'
Dec 12 14:27:31 DEBUG[29312] func_db.c: DB: AMPUSER//cidname not found in database.
Dec 12 14:27:31 DEBUG[29312] pbx.c: Function result is ''
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "AMPUSERCIDNAME=") in new stack
Dec 12 14:27:31 DEBUG[29312] pbx.c: Expression result is '1'
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing GotoIf("Zap/36-1", "1?report") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Goto (macro-user-callerid,s,10)
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing NoOp("Zap/36-1", "TTL: ARG1: 6643") in new stack
Dec 12 14:27:31 DEBUG[29312] pbx.c: Expression result is '0'
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing GotoIf("Zap/36-1", "0?continue") in new stack
Dec 12 14:27:31 DEBUG[29312] pbx.c: Not taking any branch
Dec 12 14:27:31 DEBUG[29312] pbx.c: Expression result is '1'
Dec 12 14:27:31 DEBUG[29312] pbx.c: Expression result is '-1'
Dec 12 14:27:31 DEBUG[29312] pbx.c: Function result is '64'
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "_TTL=64") in new stack
Dec 12 14:27:31 DEBUG[29312] pbx.c: Expression result is '1'
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing GotoIf("Zap/36-1", "1?continue") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Goto (macro-user-callerid,s,20)
Dec 12 14:27:31 DEBUG[29312] pbx.c: Function result is '"LADUE, MO" <3147910062>'
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing NoOp("Zap/36-1", "Using CallerID "LADUE, MO" <3147910062>") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing NoOp("Zap/36-1", "CAME FROM: ") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "RRNODEST=") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "_NODEST=") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "FROMCONTEXT=exten-vm") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "VMBOX=6643") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "EXTTOCALL=6643") in new stack
Dec 12 14:27:31 DEBUG[29312] db.c: Unable to find key '6643' in family 'CFU'
Dec 12 14:27:31 DEBUG[29312] func_db.c: DB: CFU/6643 not found in database.
Dec 12 14:27:31 DEBUG[29312] pbx.c: Function result is ''
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "CFUEXT=") in new stack
Dec 12 14:27:31 DEBUG[29312] pbx.c: Expression result is '1'
Dec 12 14:27:31 DEBUG[29312] pbx.c: Expression result is '0'
Dec 12 14:27:31 DEBUG[29312] pbx.c: Expression result is '1'
Dec 12 14:27:31 DEBUG[29312] pbx.c: Function result is '15'
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Set("Zap/36-1", "RT=15") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing Macro("Zap/36-1", "record-enable|6643|IN") in new stack
Dec 12 14:27:31 DEBUG[29312] pbx.c: Function result is '0'
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing GotoIf("Zap/36-1", "0 > 0?2:4") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Goto (macro-record-enable,s,4)
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Executing DeadAGI("Zap/36-1", "recordingcheck|20061212-142731|1165955248.7862") in new stack
Dec 12 14:27:31 VERBOSE[29312] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8) len=65
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1684/0x694) (Originator)
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Message type: SETUP (5)
Dec 12 14:27:32 VERBOSE[5481] logger.c: < [04 03 80 90 a2]
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0)
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Ext: 1 User information layer 1: u-Law (34)
Dec 12 14:27:32 VERBOSE[5481] logger.c: < [18 03 a1 83 8c]
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Preferred Dchan: 0
Dec 12 14:27:32 VERBOSE[5481] logger.c: < ChanSel: Reserved
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Ext: 1 Coding: 0 Number Specified Channel Type: 3
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Ext: 1 Channel: 12 ]
Dec 12 14:27:32 VERBOSE[5481] logger.c: < [1c 17 9f 8b 01 00 a1 11 02 01 01 02 01 00 80 09 4c 41 44 55 45 2c 20 4d 4f]
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Facility (len=25, codeset=0) [ Dec 12 14:27:32 VERBOSE[5481] logger.c: < Facility (len=25, codeset=0) [ 0x9f, 0x8b, 0x01, 0x00, 0xa1, 0x11, 0x02, 0x01, 0x01, 0x02, 0x01, 0x00, 0x80, 0x09, 'LADUE', 0x2c, 0x20, 'MO'Dec 12 14:27:32 VERBOSE[5481] logger.c: < Facility (len=25, codeset=0) [ 0x9f, 0x8b, 0x01, 0x00, 0xa1, 0x11, 0x02, 0x01, 0x01, 0x02, 0x01, 0x00, 0x80, 0x09, 'LADUE', 0x2c, 0x20, 'MO' ]
Dec 12 14:27:32 VERBOSE[5481] logger.c: < [1e 02 82 83]
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2)
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ]
Dec 12 14:27:32 VERBOSE[5481] logger.c: < [6c 0c 21 82 33 31 34 37 39 31 30 30 36 32]
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Presentation: Presentation permitted, user number failed network screening (2) '3147910062' ]
Dec 12 14:27:32 VERBOSE[5481] logger.c: < [70 05 a1 36 36 34 33]
Dec 12 14:27:32 VERBOSE[5481] logger.c: < Called Number (len= 7) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '6643' ]
Dec 12 14:27:32 VERBOSE[5481] logger.c: -- Processing Q.931 Call Setup
Dec 12 14:27:32 VERBOSE[5481] logger.c: -- Processing IE 4 (cs0, Bearer Capability)
Dec 12 14:27:32 VERBOSE[5481] logger.c: -- Processing IE 24 (cs0, Channel Identification)
Dec 12 14:27:32 VERBOSE[5481] logger.c: -- Processing IE 28 (cs0, Facility)
Dec 12 14:27:32 VERBOSE[5481] logger.c: Handle Q.932 ROSE Invoke component
Dec 12 14:27:32 VERBOSE[5481] logger.c: -- Processing IE 30 (cs0, Progress Indicator)
Dec 12 14:27:32 VERBOSE[5481] logger.c: -- Processing IE 108 (cs0, Calling Party Number)
Dec 12 14:27:32 VERBOSE[5481] logger.c: -- Processing IE 112 (cs0, Called Party Number)
Dec 12 14:27:32 DEBUG[5484] chan_sip.c: Stopping retransmission on '1ba72ca048e0cf4d6b8bb50e59406256@192.168.1.250' of Request 102: Match Found
Dec 12 14:27:33 VERBOSE[29312] logger.c: recordingcheck|20061212-142731|1165955248.7862: Inbound recording not enabled
Dec 12 14:27:33 VERBOSE[29312] logger.c: -- AGI Script recordingcheck completed, returning 0
Dec 12 14:27:33 VERBOSE[29312] logger.c: -- Executing NoOp("Zap/36-1", "No recording needed") in new stack
Dec 12 14:27:33 DEBUG[29312] pbx.c: Expression result is '0'
Dec 12 14:27:33 VERBOSE[29312] logger.c: -- Executing GotoIf("Zap/36-1", "0?dolocaldial|1") in new stack
Dec 12 14:27:33 DEBUG[29312] pbx.c: Not taking any branch
Dec 12 14:27:33 VERBOSE[29312] logger.c: -- Executing Macro("Zap/36-1", "dial|15|tr|6643") in new stack
Dec 12 14:27:33 VERBOSE[29312] logger.c: -- Executing DeadAGI("Zap/36-1", "dialparties.agi") in new stack
Dec 12 14:27:33 VERBOSE[29312] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
Dec 12 14:27:36 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8) len=9
Dec 12 14:27:36 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1684/0x694) (Originator)
Dec 12 14:27:36 VERBOSE[5481] logger.c: < Message type: DISCONNECT (69)
Dec 12 14:27:36 VERBOSE[5481] logger.c: < [08 02 82 e6]
Dec 12 14:27:36 VERBOSE[5481] logger.c: < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2)
Dec 12 14:27:36 VERBOSE[5481] logger.c: < Ext: 1 Cause: Recover on timer expiry (102), class = Protocol Error (6) ]
Dec 12 14:27:36 VERBOSE[5481] logger.c: -- Processing IE 8 (cs0, Cause)
Dec 12 14:27:36 VERBOSE[5481] logger.c: -- Channel 0/12, span 2 got hangup request
Dec 12 14:27:36 VERBOSE[29312] logger.c: dialparties.agi: Starting New Dialparties.agi
Dec 12 14:27:36 VERBOSE[29312] logger.c: dialparties.agi: priority is 1
Dec 12 14:27:36 VERBOSE[29312] logger.c: dialparties.agi: Caller ID name is 'LADUE, MO' number is '3147910062'
Dec 12 14:27:36 VERBOSE[29312] logger.c: dialparties.agi: Methodology of ring is 'none'
Dec 12 14:27:36 VERBOSE[29312] logger.c: > dialparties.agi: USE_CONFIRMATION: 'FALSE'
Dec 12 14:27:36 VERBOSE[29312] logger.c: > dialparties.agi: RINGGROUP_INDEX: ''
Dec 12 14:27:36 VERBOSE[29312] logger.c: -- dialparties.agi: Added extension 6643 to extension map
Dec 12 14:27:36 DEBUG[29312] db.c: Unable to find key '6643' in family 'CF'
Dec 12 14:27:36 VERBOSE[29312] logger.c: -- dialparties.agi: Extension 6643 cf is disabled
Dec 12 14:27:36 DEBUG[29312] db.c: Unable to find key '6643' in family 'DND'
Dec 12 14:27:36 VERBOSE[29312] logger.c: -- dialparties.agi: Extension 6643 do not disturb is disabled
Dec 12 14:27:36 DEBUG[29312] db.c: Unable to find key '6643' in family 'CW'
Dec 12 14:27:36 DEBUG[29312] db.c: Unable to find key '6643' in family 'CFB'
Dec 12 14:27:36 DEBUG[29312] db.c: Unable to find key '6643' in family 'CFU'
Dec 12 14:27:36 VERBOSE[29312] logger.c: > dialparties.agi: extnum: 6643
Dec 12 14:27:36 VERBOSE[29312] logger.c: > dialparties.agi: exthascw: 0
Dec 12 14:27:36 VERBOSE[29312] logger.c: > dialparties.agi: exthascfb: 0
Dec 12 14:27:36 VERBOSE[29312] logger.c: > dialparties.agi: extcfb:
Dec 12 14:27:36 VERBOSE[29312] logger.c: > dialparties.agi: exthascfu: 0
Dec 12 14:27:36 VERBOSE[29312] logger.c: > dialparties.agi: extcfu:
Dec 12 14:27:36 DEBUG[29320] manager.c: Manager received command 'login'
Dec 12 14:27:36 VERBOSE[29320] logger.c: == Parsing '/etc/asterisk/manager.conf': Dec 12 14:27:36 VERBOSE[29320] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
Dec 12 14:27:36 VERBOSE[29320] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Dec 12 14:27:36 VERBOSE[29320] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
Dec 12 14:27:36 DEBUG[29320] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Dec 12 14:27:36 DEBUG[29320] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Dec 12 14:27:36 DEBUG[29320] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Dec 12 14:27:36 DEBUG[29320] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Dec 12 14:27:36 VERBOSE[29320] logger.c: == Manager 'admin' logged on from 127.0.0.1
Dec 12 14:27:36 DEBUG[29320] manager.c: Manager received command 'ExtensionState'
Dec 12 14:27:36 DEBUG[29320] manager.c: Manager received command 'Logoff'
Dec 12 14:27:36 VERBOSE[29320] logger.c: == Manager 'admin' logged off from 127.0.0.1
Dec 12 14:27:36 VERBOSE[29312] logger.c: > dialparties.agi: ExtensionState: 0
Dec 12 14:27:36 VERBOSE[29312] logger.c: dialparties.agi: Extension 6643 has ExtensionState: 0
Dec 12 14:27:36 VERBOSE[29312] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 6643
Dec 12 14:27:36 VERBOSE[29312] logger.c: -- dialparties.agi: dbset CALLTRACE/6643 to 3147910062
Dec 12 14:27:36 VERBOSE[29312] logger.c: -- AGI Script dialparties.agi completed, returning 0
Dec 12 14:27:36 DEBUG[29312] app_macro.c: Extension s, macroexten 6643, priority 9 returned normally even though call was hung up
Dec 12 14:27:36 DEBUG[29312] app_macro.c: Extension s, macroexten 6643, priority 12 returned normally even though call was hung up
Dec 12 14:27:36 DEBUG[29312] pbx.c: Extension 6643, priority 1 returned normally even though call was hung up
Dec 12 14:27:36 DEBUG[29312] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Dec 12 14:27:36 DEBUG[29312] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2006-12-12 14:27:31','\"LADUE, MO\" <3147910062>','3147910062','6643','from-pstn', 'Zap/36-1','','DeadAGI','dialparties.agi',5,0,'NO ANSWER',2,'','1165955248.7862')
Dec 12 14:27:36 DEBUG[29312] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/36-1
Dec 12 14:27:36 DEBUG[29312] chan_zap.c: Hangup: channel: 36 index = 0, normal = 24, callwait = -1, thirdcall = -1
Dec 12 14:27:36 DEBUG[29312] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
Dec 12 14:27:36 VERBOSE[29312] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, peerstate Disconnect Request
Dec 12 14:27:36 VERBOSE[29312] logger.c: > Protocol Discriminator: Q.931 (8) len=9
Dec 12 14:27:36 VERBOSE[29312] logger.c: > Call Ref: len= 2 (reference 1684/0x694) (Terminator)
Dec 12 14:27:36 VERBOSE[29312] logger.c: > Message type: RELEASE (77)
Dec 12 14:27:36 VERBOSE[29312] logger.c: > [08 02 81 e6]
Dec 12 14:27:36 VERBOSE[29312] logger.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
Dec 12 14:27:36 VERBOSE[29312] logger.c: > Ext: 1 Cause: Recover on timer expiry (102), class = Protocol Error (6) ]
Dec 12 14:27:36 DEBUG[29312] chan_zap.c: disabled echo cancellation on channel 36
Dec 12 14:27:36 DEBUG[29312] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/36-1
Dec 12 14:27:36 DEBUG[29312] chan_zap.c: Updated conferencing on 36, with 0 conference users
Dec 12 14:27:36 DEBUG[29312] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/36-1
Dec 12 14:27:36 DEBUG[29312] chan_zap.c: disabled echo cancellation on channel 36
Dec 12 14:27:36 VERBOSE[29312] logger.c: -- Hungup 'Zap/36-1'
Dec 12 14:27:36 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8) len=5
Dec 12 14:27:36 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1684/0x694) (Originator)
Dec 12 14:27:36 VERBOSE[5481] logger.c: < Message type: RELEASE COMPLETE (90)
Dec 12 14:27:36 VERBOSE[5481] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
Dec 12 14:27:36 VERBOSE[5481] logger.c: NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null