[Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: TEI: 0 State 7 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: V(S) 123 V(A) 123 V(R) 38 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: K 7, RC 0, l3initiated 0, reject_except 0 ack_pend 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: T200 0, N200 3, T203 1 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < [ 02 01 4c f6 08 02 2b aa 45 08 02 81 90 ] [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Informational frame: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < SAPI: 00 C/R: 1 EA: 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < TEI: 000 EA: 1 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < N(S): 038 0: 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < N(R): 123 P: 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < 9 bytes of data [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Protocol Discriminator: Q.931 (8) len=9 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Call Ref: len= 2 (reference 11178/0x2BAA) (Originator) [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Message Type: DISCONNECT (69) [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < [08 02 81 90] [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ] [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: Received message for call 0xb6e3a048 on 0x963e138 TEI/SAPI 0/0, call->pri is 0x963e138 TEI/SAPI 0/0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- Processing IE 8 (cs0, Cause) [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- Found active call: 0xb6e3a048 cref:11178 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: q931.c:6943 post_handle_q931_message: Call 11178 enters state 12 (Disconnect Indication). Hold state: Idle [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- ACKing all packets from 123 to (but not including) 123 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- T200 requested to stop when not started [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: T203 requested to start without stopping first [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- Starting T203 timer [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: Sending Receiver Ready (39) [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: TEI: 0 State 7 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: V(S) 123 V(A) 123 V(R) 39 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: K 7, RC 0, l3initiated 0, reject_except 0 ack_pend 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: T200 0, N200 3, T203 1 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: > [ 02 01 01 4e ] [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: > Supervisory frame: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: > SAPI: 00 C/R: 1 EA: 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: > TEI: 000 EA: 1 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: > Zero: 0 S: 0 01: 1 [ RR (receive ready) ] [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: > N(R): 039 P/F: 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: > 0 bytes of data [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: Handling message for SAPI/TEI=0/0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- Channel 0/31, span 1 got hangup request, cause 16 [Feb 4 17:21:12] DEBUG[608] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Feb 4 17:21:12] DEBUG[608] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Feb 4 17:21:12] DEBUG[608] channel.c: Set channel DAHDI/31-1 to write format alaw [Feb 4 17:21:12] DEBUG[608] pbx.c: Spawn extension (incoming,8500,12) exited non-zero on 'DAHDI/31-1' [Feb 4 17:21:12] VERBOSE[608] pbx.c: == Spawn extension (incoming, 8500, 12) exited non-zero on 'DAHDI/31-1' [Feb 4 17:21:12] DEBUG[608] channel.c: Soft-Hanging up channel 'DAHDI/31-1' [Feb 4 17:21:12] DEBUG[608] channel.c: Hanging up channel 'DAHDI/31-1' [Feb 4 17:21:12] DEBUG[608] chan_dahdi.c: dahdi_hangup(DAHDI/31-1) [Feb 4 17:21:12] DEBUG[608] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/31-1 [Feb 4 17:21:12] DEBUG[608] chan_dahdi.c: Hangup: channel: 31 index = 0, normal = 42, callwait = -1, thirdcall = -1 [Feb 4 17:21:12] DEBUG[608] chan_dahdi.c: Not yet hungup... Calling hangup once with icause, and clearing call [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: q931_hangup: other hangup [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, peerstate Disconnect Request, hold-state Idle [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: q931.c:4513 q931_release: Call 11178 enters state 19 (Release Request). Hold state: Idle [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: -- Finally transmitting 0, since window opened up (7) [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: TEI: 0 State 7 [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: V(S) 123 V(A) 123 V(R) 39 [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: K 7, RC 0, l3initiated 0, reject_except 0 ack_pend 0 [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: T200 0, N200 3, T203 1 [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > [ 00 01 f6 4e 08 02 ab aa 4d 08 02 81 90 ] [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > Informational frame: [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > SAPI: 00 C/R: 0 EA: 0 [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > TEI: 000 EA: 1 [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > N(S): 123 0: 0 [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > N(R): 039 P: 0 [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > 9 bytes of data [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: -- Stopping T203 timer [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: -- Starting T200 timer [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > Protocol Discriminator: Q.931 (8) len=9 [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > Call Ref: len= 2 (reference 11178/0x2BAA) (Terminator) [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > Message Type: RELEASE (77) [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > [08 02 81 90] [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ] [Feb 4 17:21:12] DEBUG[608] chan_dahdi.c: Disabled echo cancellation on channel 31 [Feb 4 17:21:12] DEBUG[608] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/31-1 [Feb 4 17:21:12] DEBUG[608] chan_dahdi.c: Updated conferencing on 31, with 0 conference users [Feb 4 17:21:12] DEBUG[608] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/31-1 [Feb 4 17:21:12] VERBOSE[608] chan_dahdi.c: -- Hungup 'DAHDI/31-1' [Feb 4 17:21:12] DEBUG[608] cdr_addon_mysql.c: Inserting a CDR record. [Feb 4 17:21:12] DEBUG[608] cdr_addon_mysql.c: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,lastapp,lastdata,duration,billsec,disposition,amaflags,uniqueid,dnid) VALUES ('2010-02-04 17:21:08','5604866','5604866','8500','incoming','DAHDI/31-1','SayPhonetic','5604866','4','4','ANSWERED','3','1265257268.440','8500') [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '5604866' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '5604866' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '8500' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is 'incoming' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is 'DAHDI/31-1' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '(null)' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is 'SayPhonetic' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '5604866' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '2010-02-04 17:21:08' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '2010-02-04 17:21:08' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '2010-02-04 17:21:12' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '4' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '4' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is 'ANSWERED' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is 'DOCUMENTATION' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '(null)' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '1265257268.440' [Feb 4 17:21:12] DEBUG[608] pbx.c: Function result is '(null)' [Feb 4 17:21:12] DEBUG[16856] devicestate.c: No provider found, checking channel drivers for DAHDI - 31 [Feb 4 17:21:12] DEBUG[16856] devicestate.c: Changing state for DAHDI/31 - state 0 (Unknown) [Feb 4 17:21:12] DEBUG[16856] devicestate.c: device 'DAHDI/31' state '0' [Feb 4 17:21:12] DEBUG[16863] app_queue.c: Device 'DAHDI/31' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: TEI: 0 State 7 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: V(S) 124 V(A) 123 V(R) 39 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: K 7, RC 0, l3initiated 0, reject_except 0 ack_pend 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: T200 1, N200 3, T203 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < [ 00 01 01 f8 ] [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Supervisory frame: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < SAPI: 00 C/R: 0 EA: 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < TEI: 000 EA: 1 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Zero: 0 S: 0 01: 1 [ RR (receive ready) ] [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < N(R): 124 P/F: 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < 0 bytes of data [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- ACKing all packets from 123 to (but not including) 124 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- ACKing packet 123, new txqueue is -1 (-1 means empty) [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- Stopping T200 timer [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- Starting T203 timer [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: Handling message for SAPI/TEI=0/0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: TEI: 0 State 7 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: V(S) 124 V(A) 124 V(R) 39 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: K 7, RC 0, l3initiated 0, reject_except 0 ack_pend 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: T200 0, N200 3, T203 1 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < [ 02 01 4e f8 08 02 2b aa 5a 08 02 81 90 ] [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Informational frame: [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < SAPI: 00 C/R: 1 EA: 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < TEI: 000 EA: 1 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < N(S): 039 0: 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < N(R): 124 P: 0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < 9 bytes of data [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Protocol Discriminator: Q.931 (8) len=9 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Call Ref: len= 2 (reference 11178/0x2BAA) (Originator) [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Message Type: RELEASE COMPLETE (90) [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < [08 02 81 90] [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: < Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ] [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: Received message for call 0xb6e3a048 on 0x963e138 TEI/SAPI 0/0, call->pri is 0x963e138 TEI/SAPI 0/0 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- Processing IE 8 (cs0, Cause) [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: q931.c:6825 post_handle_q931_message: Call 11178 enters state 0 (Null). Hold state: Idle [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: q931_hangup: other hangup [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null, hold-state Idle [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null, hold-state Idle [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- ACKing all packets from 124 to (but not including) 124 [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- T200 requested to stop when not started [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: T203 requested to start without stopping first [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: -- Starting T203 timer [Feb 4 17:21:12] VERBOSE[16866] chan_dahdi.c: Sending Receiver Ready (40)