Dec 12 14:16:01 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8)  len=65
Dec 12 14:16:01 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1668/0x684) (Originator)
Dec 12 14:16:01 VERBOSE[5481] logger.c: < Message type: SETUP (5)
Dec 12 14:16:01 VERBOSE[5481] logger.c: < [04 03 80 90 a2]
Dec 12 14:16:01 VERBOSE[5481] logger.c: < Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)
Dec 12 14:16:01 VERBOSE[5481] logger.c: <                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
Dec 12 14:16:01 VERBOSE[5481] logger.c: <                              Ext: 1  User information layer 1: u-Law (34)
Dec 12 14:16:01 VERBOSE[5481] logger.c: < [18 03 a1 83 89]
Dec 12 14:16:01 VERBOSE[5481] logger.c: < Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Preferred Dchan: 0
Dec 12 14:16:01 VERBOSE[5481] logger.c: <                        ChanSel: Reserved
Dec 12 14:16:01 VERBOSE[5481] logger.c: <                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
Dec 12 14:16:01 VERBOSE[5481] logger.c: <                       Ext: 1  Channel: 9 ]
Dec 12 14:16:01 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:16:01 VERBOSE[5481] logger.c: < Facility (len=25, codeset=0) [ Dec 12 14:16:01 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:16:01 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:16:01 VERBOSE[5481] logger.c: < [1e 02 82 83]
Dec 12 14:16:01 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:16:01 VERBOSE[5481] logger.c: <                               Ext: 1  Progress Description: Calling equipment is non-ISDN. (3) ]
Dec 12 14:16:01 VERBOSE[5481] logger.c: < [6c 0c 21 82 33 31 34 37 39 31 30 30 36 32]
Dec 12 14:16:01 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:16:01 VERBOSE[5481] logger.c: <                           Presentation: Presentation permitted, user number failed network screening (2) '3147910062' ]
Dec 12 14:16:01 VERBOSE[5481] logger.c: < [70 05 a1 39 37 34 34]
Dec 12 14:16:01 VERBOSE[5481] logger.c: < Called Number (len= 7) [ Ext: 1  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '9744' ]
Dec 12 14:16:01 VERBOSE[5481] logger.c: -- Making new call for cr 1668
Dec 12 14:16:01 VERBOSE[5481] logger.c: -- Processing Q.931 Call Setup
Dec 12 14:16:01 VERBOSE[5481] logger.c: -- Processing IE 4 (cs0, Bearer Capability)
Dec 12 14:16:01 VERBOSE[5481] logger.c: -- Processing IE 24 (cs0, Channel Identification)
Dec 12 14:16:01 VERBOSE[5481] logger.c: -- Processing IE 28 (cs0, Facility)
Dec 12 14:16:01 VERBOSE[5481] logger.c: Handle Q.932 ROSE Invoke component
Dec 12 14:16:01 VERBOSE[5481] logger.c: -- Processing IE 30 (cs0, Progress Indicator)
Dec 12 14:16:01 VERBOSE[5481] logger.c: -- Processing IE 108 (cs0, Calling Party Number)
Dec 12 14:16:01 VERBOSE[5481] logger.c: -- Processing IE 112 (cs0, Called Party Number)
Dec 12 14:16:01 VERBOSE[5481] logger.c: > Protocol Discriminator: Q.931 (8)  len=14
Dec 12 14:16:01 VERBOSE[5481] logger.c: > Call Ref: len= 2 (reference 1668/0x684) (Terminator)
Dec 12 14:16:01 VERBOSE[5481] logger.c: > Message type: SETUP ACKNOWLEDGE (13)
Dec 12 14:16:01 VERBOSE[5481] logger.c: > [18 03 a9 83 89]
Dec 12 14:16:01 VERBOSE[5481] logger.c: > Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
Dec 12 14:16:01 VERBOSE[5481] logger.c: >                        ChanSel: Reserved
Dec 12 14:16:01 VERBOSE[5481] logger.c: >                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
Dec 12 14:16:01 VERBOSE[5481] logger.c: >                       Ext: 1  Channel: 9 ]
Dec 12 14:16:01 VERBOSE[5481] logger.c: > [1e 02 81 82]
Dec 12 14:16:01 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:16:01 VERBOSE[5481] logger.c: >                               Ext: 1  Progress Description: Called equipment is non-ISDN. (2) ]
Dec 12 14:16:01 DEBUG[5481] dsp.c: dsp busy pattern set to 2500,500
Dec 12 14:16:01 VERBOSE[5481] logger.c:     -- Accepting overlap call from '3147910062' to '9744' on channel 0/9, span 2
Dec 12 14:16:01 VERBOSE[28143] logger.c:     -- Starting simple switch on 'Zap/33-1'
Dec 12 14:16:01 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8)  len=13
Dec 12 14:16:01 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1668/0x684) (Originator)
Dec 12 14:16:01 VERBOSE[5481] logger.c: < Message type: STATUS (125)
Dec 12 14:16:01 VERBOSE[5481] logger.c: < [08 03 82 e5 0d]
Dec 12 14:16:01 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:16:01 VERBOSE[5481] logger.c: <                  Ext: 1  Cause: Message not compatible with call state (101), class = Protocol Error (6) ]
Dec 12 14:16:01 VERBOSE[5481] logger.c: <              Cause data 1: 0d (13, SETUP ACKNOWLEDGE message)
Dec 12 14:16:01 VERBOSE[5481] logger.c: < [14 01 06]
Dec 12 14:16:01 VERBOSE[5481] logger.c: < Call State (len= 3) [ Ext: 0  Coding: CCITT (ITU) standard (0) Call state: Call Present (6)
Dec 12 14:16:01 VERBOSE[5481] logger.c: -- Processing IE 8 (cs0, Cause)
Dec 12 14:16:01 VERBOSE[5481] logger.c: -- Processing IE 20 (cs0, Call State)
Dec 12 14:16:05 DEBUG[28143] chan_zap.c: Enabled echo cancellation on channel 33
Dec 12 14:16:05 VERBOSE[28143] logger.c:     -- Executing Dial("Zap/33-1", "Zap/g1/9744") in new stack
Dec 12 14:16:05 DEBUG[28143] dsp.c: dsp busy pattern set to 2500,500
Dec 12 14:16:05 VERBOSE[28143] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
Dec 12 14:16:05 VERBOSE[28143] logger.c:     -- Called g1/9744
Dec 12 14:16:05 DEBUG[5482] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/9 span 3
Dec 12 14:16:05 VERBOSE[28143] logger.c:     -- Zap/57-1 is proceeding passing it to Zap/33-1
Dec 12 14:16:05 DEBUG[28143] chan_zap.c: Requested indication 15 on channel Zap/33-1
Dec 12 14:16:05 DEBUG[28143] chan_zap.c: Received AST_CONTROL_PROCEEDING on Zap/33-1
Dec 12 14:16:05 VERBOSE[28143] logger.c: > Protocol Discriminator: Q.931 (8)  len=14
Dec 12 14:16:05 VERBOSE[28143] logger.c: > Call Ref: len= 2 (reference 1668/0x684) (Terminator)
Dec 12 14:16:05 VERBOSE[28143] logger.c: > Message type: CALL PROCEEDING (2)
Dec 12 14:16:05 VERBOSE[28143] logger.c: > [18 03 a9 83 89]
Dec 12 14:16:05 VERBOSE[28143] logger.c: > Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
Dec 12 14:16:05 VERBOSE[28143] logger.c: >                        ChanSel: Reserved
Dec 12 14:16:05 VERBOSE[28143] logger.c: >                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
Dec 12 14:16:05 VERBOSE[28143] logger.c: >                       Ext: 1  Channel: 9 ]
Dec 12 14:16:05 VERBOSE[28143] logger.c: > [1e 02 81 88]
Dec 12 14:16:05 VERBOSE[28143] 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:16:05 VERBOSE[28143] logger.c: >                               Ext: 1  Progress Description: Inband information or appropriate pattern now available. (8) ]
Dec 12 14:16:05 DEBUG[5482] chan_zap.c: Enabled echo cancellation on channel 57
Dec 12 14:16:05 VERBOSE[28143] logger.c:     -- Zap/57-1 is ringing
Dec 12 14:16:05 DEBUG[28143] chan_zap.c: Requested indication 3 on channel Zap/33-1
Dec 12 14:16:05 VERBOSE[28143] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
Dec 12 14:16:05 VERBOSE[28143] logger.c: > Call Ref: len= 2 (reference 1668/0x684) (Terminator)
Dec 12 14:16:05 VERBOSE[28143] logger.c: > Message type: ALERTING (1)
Dec 12 14:16:05 VERBOSE[28143] logger.c: > [1e 02 81 88]
Dec 12 14:16:05 VERBOSE[28143] 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:16:05 VERBOSE[28143] logger.c: >                               Ext: 1  Progress Description: Inband information or appropriate pattern now available. (8) ]
Dec 12 14:16:05 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8)  len=13
Dec 12 14:16:05 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1668/0x684) (Originator)
Dec 12 14:16:05 VERBOSE[5481] logger.c: < Message type: STATUS (125)
Dec 12 14:16:05 VERBOSE[5481] logger.c: < [08 03 82 e3 1e]
Dec 12 14:16:05 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:16:05 VERBOSE[5481] logger.c: <                  Ext: 1  Cause: Info. element nonexist or not implemented (99), class = Protocol Error (6) ]
Dec 12 14:16:05 VERBOSE[5481] logger.c: <              Cause data 1: 1e (30, Progress Indicator IE)
Dec 12 14:16:05 VERBOSE[5481] logger.c: < [14 01 06]
Dec 12 14:16:05 VERBOSE[5481] logger.c: < Call State (len= 3) [ Ext: 0  Coding: CCITT (ITU) standard (0) Call state: Call Present (6)
Dec 12 14:16:05 VERBOSE[5481] logger.c: -- Processing IE 8 (cs0, Cause)
Dec 12 14:16:05 VERBOSE[5481] logger.c: -- Processing IE 20 (cs0, Call State)
Dec 12 14:16:08 DEBUG[5482] chan_zap.c: Echo cancellation already on
Dec 12 14:16:08 VERBOSE[28143] logger.c:     -- Zap/57-1 answered Zap/33-1
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: Requested indication -1 on channel Zap/33-1
Dec 12 14:16:08 VERBOSE[28143] logger.c: > Protocol Discriminator: Q.931 (8)  len=14
Dec 12 14:16:08 VERBOSE[28143] logger.c: > Call Ref: len= 2 (reference 1668/0x684) (Terminator)
Dec 12 14:16:08 VERBOSE[28143] logger.c: > Message type: CONNECT (7)
Dec 12 14:16:08 VERBOSE[28143] logger.c: > [18 03 a9 83 89]
Dec 12 14:16:08 VERBOSE[28143] logger.c: > Channel ID (len= 5) [ Ext: 1  IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
Dec 12 14:16:08 VERBOSE[28143] logger.c: >                        ChanSel: Reserved
Dec 12 14:16:08 VERBOSE[28143] logger.c: >                       Ext: 1  Coding: 0   Number Specified   Channel Type: 3
Dec 12 14:16:08 VERBOSE[28143] logger.c: >                       Ext: 1  Channel: 9 ]
Dec 12 14:16:08 VERBOSE[28143] logger.c: > [1e 02 81 82]
Dec 12 14:16:08 VERBOSE[28143] 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:16:08 VERBOSE[28143] logger.c: >                               Ext: 1  Progress Description: Called equipment is non-ISDN. (2) ]
Dec 12 14:16:08 VERBOSE[28143] logger.c:     -- Attempting native bridge of Zap/33-1 and Zap/57-1
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: master: 33, slave: 57, nothingok: 0
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: Stopping tones on 33/0 talking to 57/0
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: Stopping tones on 57/0 talking to 33/0
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: disabled echo cancellation on channel 33
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: disabled echo cancellation on channel 57
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: Making 57 slave to master 33 at 0
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: Added 37 to conference 9/33
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: Added 21 to conference 9/57
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: Updated conferencing on 33, with 0 conference users
Dec 12 14:16:08 DEBUG[28143] chan_zap.c: Updated conferencing on 57, with 0 conference users
Dec 12 14:16:08 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8)  len=5
Dec 12 14:16:08 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1668/0x684) (Originator)
Dec 12 14:16:08 VERBOSE[5481] logger.c: < Message type: CONNECT ACKNOWLEDGE (15)
Dec 12 14:16:15 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8)  len=9
Dec 12 14:16:15 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1668/0x684) (Originator)
Dec 12 14:16:15 VERBOSE[5481] logger.c: < Message type: DISCONNECT (69)
Dec 12 14:16:15 VERBOSE[5481] logger.c: < [08 02 80 90]
Dec 12 14:16:15 VERBOSE[5481] logger.c: < Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0) 0: 0   Location: User (0)
Dec 12 14:16:15 VERBOSE[5481] logger.c: <                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
Dec 12 14:16:15 VERBOSE[5481] logger.c: -- Processing IE 8 (cs0, Cause)
Dec 12 14:16:15 VERBOSE[5481] logger.c:     -- Channel 0/9, span 2 got hangup request
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Enabled echo cancellation on channel 33
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Enabled echo cancellation on channel 57
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Unlinking slave 57 from 33
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Removed 37 from conference 9/33
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Removed 21 from conference 9/57
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Updated conferencing on 33, with 0 conference users
Dec 12 14:16:15 DEBUG[28143] channel.c: Returning from native bridge, channels: Zap/33-1, Zap/57-1
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/57-1
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Hangup: channel: 57 index = 0, normal = 37, callwait = -1, thirdcall = -1
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: disabled echo cancellation on channel 57
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/57-1
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Updated conferencing on 57, with 0 conference users
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/57-1
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: disabled echo cancellation on channel 57
Dec 12 14:16:15 VERBOSE[28143] logger.c:     -- Hungup 'Zap/57-1'
Dec 12 14:16:15 DEBUG[28143] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Dec 12 14:16:15 VERBOSE[28143] logger.c:   == Spawn extension (from-pstn, 9744, 1) exited non-zero on 'Zap/33-1'
Dec 12 14:16:15 DEBUG[28143] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Dec 12 14:16:15 DEBUG[28143] 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:16:05','\"LADUE, MO\" <3147910062>','3147910062','9744','from-pstn', 'Zap/33-1','Zap/57-1','Dial','Zap/g1/9744',10,7,'ANSWERED',2,'','1165954561.7806')
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/33-1
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Hangup: channel: 33 index = 0, normal = 21, callwait = -1, thirdcall = -1
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Dec 12 14:16:15 VERBOSE[28143] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, peerstate Disconnect Request
Dec 12 14:16:15 VERBOSE[28143] logger.c: > Protocol Discriminator: Q.931 (8)  len=9
Dec 12 14:16:15 VERBOSE[28143] logger.c: > Call Ref: len= 2 (reference 1668/0x684) (Terminator)
Dec 12 14:16:15 VERBOSE[28143] logger.c: > Message type: RELEASE (77)
Dec 12 14:16:15 VERBOSE[28143] logger.c: > [08 02 81 90]
Dec 12 14:16:15 VERBOSE[28143] 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:16:15 VERBOSE[28143] logger.c: >                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: disabled echo cancellation on channel 33
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/33-1
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Updated conferencing on 33, with 0 conference users
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/33-1
Dec 12 14:16:15 DEBUG[28143] chan_zap.c: disabled echo cancellation on channel 33
Dec 12 14:16:15 VERBOSE[28143] logger.c:     -- Hungup 'Zap/33-1'
Dec 12 14:16:15 VERBOSE[5481] logger.c: < Protocol Discriminator: Q.931 (8)  len=5
Dec 12 14:16:15 VERBOSE[5481] logger.c: < Call Ref: len= 2 (reference 1668/0x684) (Originator)
Dec 12 14:16:15 VERBOSE[5481] logger.c: < Message type: RELEASE COMPLETE (90)
Dec 12 14:16:15 VERBOSE[5481] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
Dec 12 14:16:15 VERBOSE[5481] logger.c: NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null