./fs_cli +OK log level [7] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI: 0 State 7(Multi-frame established) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < V(A)=67, V(S)=67, V(R)=31 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < T200_id=0, N200=3, T203_id=8192 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < [ 02 01 3e 86 08 02 00 91 05 a1 04 03 80 90 a3 18 03 a9 83 9a 1e 02 81 83 6c 06 21 80 31 37 33 30 70 05 81 33 30 30 31 ] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Informational frame: 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < SAPI: 00 C/R: 1 EA: 0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI: 000 EA: 1 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < N(S): 031 0: 0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < N(R): 067 P: 0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < 35 bytes of data 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Protocol Discriminator: Q.931 (8) len=35 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI=0 Call Ref: len= 2 (reference 145/0x91) (Sent from originator) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Message Type: SETUP (5) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < [a1] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Sending Complete (len= 1) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < [04 03 80 90 a3] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < User information layer 1: A-Law (35) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < [18 03 a9 83 9a] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < ChanSel: As indicated in following octets 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Ext: 1 Coding: 0 Number Specified Channel Type: 3 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Ext: 1 Channel: 26 Type: CPE] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < [1e 02 81 83] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < [6c 06 21 80 31 37 33 30] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Calling Party Number (len= 8) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Presentation: Presentation allowed, User-provided, not screened (0) '1730' ] 0 31] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Called Party Number (len= 7) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '3001' ] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Got ACK for N(S)=67 to (but not including) N(S)=67 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- T200 requested to stop when not started 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] T203 requested to start without stopping first 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Starting T203 timer 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Making new call for cref 145 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] Received message for call 0x7f2c10009f10 on link 0x7f2c10000b20 TEI/SAPI 0/0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Processing Q.931 Call Setup 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Processing IE 161 (cs0, Sending Complete) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Processing IE 4 (cs0, Bearer Capability) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Processing IE 24 (cs0, Channel ID) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Processing IE 30 (cs0, Progress Indicator) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Processing IE 108 (cs0, Calling Party Number) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Processing IE 112 (cs0, Called Party Number) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] q931.c:8646 post_handle_q931_message: Call 145 enters state 6 (Call Present). Hold state: Idle 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI: 0 State 7(Multi-frame established) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > V(A)=67, V(S)=67, V(R)=32 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > T200_id=0, N200=3, T203_id=8192 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > [ 02 01 01 40 ] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Supervisory frame: 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > SAPI: 00 C/R: 1 EA: 0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI: 000 EA: 1 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Zero: 0 S: 0 01: 1 [ RR (receive ready) ] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > N(R): 032 P/F: 0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > 0 bytes of data 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] Done handling message for SAPI/TEI=0/0 2014-03-24 16:49:25.261446 [NOTICE] ftmod_libpri.c:1930 -- Ring on channel 1:26 (from 1730 to 3001) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:1938 RING event with complete indicator (or overlap receive disabled) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:1939 [s1c26][1:26] Changed state from DOWN to RING 2014-03-24 16:49:25.261446 [DEBUG] ftdm_state.c:541 [s1c26][1:26] Executing state processor for RING 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:936 -- 1:26 STATE [RING] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:944 [s1c26][1:26] Completed state change from DOWN to RING in 0 ms 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] q931.c:5704 q931_call_proceeding: Call 145 enters state 9 (Incoming Call Proceeding). Hold state: Idle mod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > DL-DATA request 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Protocol Discriminator: Q.931 (8) len=10 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI=0 Call Ref: len= 2 (reference 145/0x91) (Sent to originator) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Message Type: CALL PROCEEDING (2) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] TEI=0 Transmitting N(S)=67, window is open V(A)=67 K=7 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI: 0 State 7(Multi-frame established) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > V(A)=67, V(S)=67, V(R)=32 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > T200_id=0, N200=3, T203_id=8192 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > [ 00 01 86 40 08 02 80 91 02 18 03 a9 83 9a ] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Informational frame: 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > SAPI: 00 C/R: 0 EA: 0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI: 000 EA: 1 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > N(S): 067 0: 0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > N(R): 032 P: 0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > 10 bytes of data 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Protocol Discriminator: Q.931 (8) len=10 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI=0 Call Ref: len= 2 (reference 145/0x91) (Sent to originator) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Message Type: CALL PROCEEDING (2) 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > [18 03 a9 83 9a] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > ChanSel: As indicated in following octets 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Ext: 1 Coding: 0 Number Specified Channel Type: 3 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Ext: 1 Channel: 26 Type: CPE] 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Stopping T203 timer 2014-03-24 16:49:25.261446 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Starting T200 timer 2014-03-24 16:49:25.261446 [DEBUG] mod_freetdm.c:2704 got clear channel sig [START] 2014-03-24 16:49:25.261446 [DEBUG] ftdm_io.c:3390 [s1c26][1:26] Enabled software DTMF detector 2014-03-24 16:49:25.261446 [DEBUG] mod_freetdm.c:403 Set codec PCMA 20ms 2014-03-24 16:49:25.261446 [DEBUG] mod_freetdm.c:1896 Connect inbound channel FreeTDM/1:26/3001 2014-03-24 16:49:25.261446 [NOTICE] switch_channel.c:1050 New Channel FreeTDM/1:26/3001 [0cbefd10-010f-45e1-b4a4-1f5797ef7890] 2014-03-24 16:49:25.261446 [DEBUG] mod_freetdm.c:2099 (FreeTDM/1:26/3001) State Change CS_NEW -> CS_INIT 2014-03-24 16:49:25.261446 [DEBUG] switch_core_session.c:1351 Send signal FreeTDM/1:26/3001 [BREAK] 2014-03-24 16:49:25.261446 [DEBUG] switch_core_state_machine.c:415 (FreeTDM/1:26/3001) Running State Change CS_INIT 2014-03-24 16:49:25.261446 [DEBUG] switch_core_state_machine.c:455 (FreeTDM/1:26/3001) State INIT _INIT -> CS_ROUTING 2014-03-24 16:49:25.261446 [DEBUG] switch_core_session.c:1351 Send signal FreeTDM/1:26/3001 [BREAK] 2014-03-24 16:49:25.261446 [DEBUG] switch_core_state_machine.c:455 (FreeTDM/1:26/3001) State INIT going to sleep 2014-03-24 16:49:25.261446 [DEBUG] switch_core_state_machine.c:415 (FreeTDM/1:26/3001) Running State Change CS_ROUTING 2014-03-24 16:49:25.261446 [DEBUG] switch_channel.c:2163 (FreeTDM/1:26/3001) Callstate Change DOWN -> RINGING 2014-03-24 16:49:25.261446 [DEBUG] switch_core_state_machine.c:471 (FreeTDM/1:26/3001) State ROUTING 2014-03-24 16:49:25.261446 [DEBUG] mod_freetdm.c:453 FreeTDM/1:26/3001 CHANNEL ROUTING 2014-03-24 16:49:25.261446 [DEBUG] mod_freetdm.c:456 [s1c26][1:26] Indicating PROCEED in state RING 2014-03-24 16:49:25.261446 [DEBUG] mod_freetdm.c:456 [s1c26][1:26] Changed state from RING to PROCEED 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI: 0 State 7(Multi-frame established) 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < V(A)=67, V(S)=68, V(R)=32 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < T200_id=8192, N200=3, T203_id=0 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < [ 00 01 01 88 ] 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Supervisory frame: 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < SAPI: 00 C/R: 0 EA: 0 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI: 000 EA: 1 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Zero: 0 S: 0 01: 1 [ RR (receive ready) ] 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < N(R): 068 P/F: 0 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < 0 bytes of data 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Got ACK for N(S)=67 to (but not including) N(S)=68 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- ACKing N(S)=67, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted) 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Stopping T200 timer 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Starting T203 timer 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] Done handling message for SAPI/TEI=0/0 2014-03-24 16:49:25.301448 [DEBUG] ftdm_state.c:541 [s1c26][1:26] Executing state processor for PROCEED 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:936 -- 1:26 STATE [PROCEED] 2014-03-24 16:49:25.301448 [DEBUG] ftmod_libpri.c:944 [s1c26][1:26] Completed state change from RING to PROCEED in 34 ms 2014-03-24 16:49:25.301448 [DEBUG] switch_core_state_machine.c:117 FreeTDM/1:26/3001 Standard ROUTING 2014-03-24 16:49:25.301448 [INFO] mod_dialplan_xml.c:558 Processing 1730 <1730>->3001 in context default 2014-03-24 16:49:25.301448 [WARNING] mod_dialplan_xml.c:588 Context default not found 2014-03-24 16:49:25.301448 [INFO] switch_core_state_machine.c:192 No Route, Aborting 2014-03-24 16:49:25.301448 [NOTICE] switch_core_state_machine.c:193 Hangup FreeTDM/1:26/3001 [CS_ROUTING] [NO_ROUTE_DESTINATION] 2014-03-24 16:49:25.301448 [DEBUG] switch_channel.c:3187 Send signal FreeTDM/1:26/3001 [KILL] 2014-03-24 16:49:25.301448 [DEBUG] switch_core_session.c:1351 Send signal FreeTDM/1:26/3001 [BREAK] 2014-03-24 16:49:25.301448 [DEBUG] switch_core_state_machine.c:471 (FreeTDM/1:26/3001) State ROUTING going to sleep 2014-03-24 16:49:25.301448 [DEBUG] switch_core_state_machine.c:415 (FreeTDM/1:26/3001) Running State Change CS_HANGUP RINGING -> HANGUP 2014-03-24 16:49:25.301448 [DEBUG] switch_core_state_machine.c:680 (FreeTDM/1:26/3001) State HANGUP 2014-03-24 16:49:25.301448 [DEBUG] mod_freetdm.c:544 [1:26] FreeTDM/1:26/3001 CHANNEL HANGUP ENTER 2014-03-24 16:49:25.301448 [DEBUG] mod_freetdm.c:620 [s1c26][1:26] Changed state from PROCEED to HANGUP 2014-03-24 16:49:25.401453 [DEBUG] ftdm_state.c:541 [s1c26][1:26] Executing state processor for HANGUP 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:936 -- 1:26 STATE [HANGUP] 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:944 [s1c26][1:26] Completed state change from PROCEED to HANGUP in 104 ms 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] q931.c:7135 q931_hangup: Hangup other cref:145 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] q931.c:6892 __q931_hangup: ourstate Incoming Call Proceeding, peerstate Outgoing Call Proceeding, hold-state Idle 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] q931.c:6081 q931_disconnect: Call 145 enters state 11 (Disconnect Request). Hold state: Idle 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > DL-DATA request 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Protocol Discriminator: Q.931 (8) len=9 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI=0 Call Ref: len= 2 (reference 145/0x91) (Sent to originator) 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Message Type: DISCONNECT (69) 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] TEI=0 Transmitting N(S)=68, window is open V(A)=68 K=7 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI: 0 State 7(Multi-frame established) 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > V(A)=68, V(S)=68, V(R)=32 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > T200_id=0, N200=3, T203_id=8192 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > [ 00 01 88 40 08 02 80 91 45 08 02 81 83 ] 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Informational frame: 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > SAPI: 00 C/R: 0 EA: 0 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI: 000 EA: 1 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > N(S): 068 0: 0 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > N(R): 032 P: 0 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > 9 bytes of data 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Protocol Discriminator: Q.931 (8) len=9 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI=0 Call Ref: len= 2 (reference 145/0x91) (Sent to originator) 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Message Type: DISCONNECT (69) 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > [08 02 81 83] 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Ext: 1 Cause: No route to destination (3), class = Normal Event (0) ] 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Stopping T203 timer 2014-03-24 16:49:25.401453 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Starting T200 timer EXIT 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:48 FreeTDM/1:26/3001 Standard HANGUP, cause: NO_ROUTE_DESTINATION 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:680 (FreeTDM/1:26/3001) State HANGUP going to sleep 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:447 (FreeTDM/1:26/3001) State Change CS_HANGUP -> CS_REPORTING 2014-03-24 16:49:25.401453 [DEBUG] switch_core_session.c:1351 Send signal FreeTDM/1:26/3001 [BREAK] 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:415 (FreeTDM/1:26/3001) Running State Change CS_REPORTING 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:764 (FreeTDM/1:26/3001) State REPORTING 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:92 FreeTDM/1:26/3001 Standard REPORTING, cause: NO_ROUTE_DESTINATION 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:764 (FreeTDM/1:26/3001) State REPORTING going to sleep 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:441 (FreeTDM/1:26/3001) State Change CS_REPORTING -> CS_DESTROY 2014-03-24 16:49:25.401453 [DEBUG] switch_core_session.c:1351 Send signal FreeTDM/1:26/3001 [BREAK] 2014-03-24 16:49:25.401453 [DEBUG] switch_core_session.c:1559 Session 4 (FreeTDM/1:26/3001) Locked, Waiting on external entities 2014-03-24 16:49:25.401453 [NOTICE] switch_core_session.c:1577 Session 4 (FreeTDM/1:26/3001) Ended 2014-03-24 16:49:25.401453 [NOTICE] switch_core_session.c:1581 Close Channel FreeTDM/1:26/3001 [CS_DESTROY] 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:566 (FreeTDM/1:26/3001) Callstate Change HANGUP -> DOWN 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:569 (FreeTDM/1:26/3001) Running State Change CS_DESTROY 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:579 (FreeTDM/1:26/3001) State DESTROY 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:99 FreeTDM/1:26/3001 Standard DESTROY 2014-03-24 16:49:25.401453 [DEBUG] switch_core_state_machine.c:579 (FreeTDM/1:26/3001) State DESTROY going to sleep 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI: 0 State 7(Multi-frame established) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < V(A)=68, V(S)=69, V(R)=32 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < T200_id=8192, N200=3, T203_id=0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < [ 00 01 01 8a ] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Supervisory frame: 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < SAPI: 00 C/R: 0 EA: 0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI: 000 EA: 1 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Zero: 0 S: 0 01: 1 [ RR (receive ready) ] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < N(R): 069 P/F: 0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < 0 bytes of data 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Got ACK for N(S)=68 to (but not including) N(S)=69 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- ACKing N(S)=68, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Stopping T200 timer 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Starting T203 timer 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] Done handling message for SAPI/TEI=0/0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] [1:16] < TEI: 0 State 7(Multi-frame established) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < V(A)=69, V(S)=69, V(R)=32 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < T200_id=0, N200=3, T203_id=8192 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < [ 02 01 40 8a 08 02 00 91 4d ] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Informational frame: 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < SAPI: 00 C/R: 1 EA: 0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI: 000 EA: 1 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < N(S): 032 0: 0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < N(R): 069 P: 0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < 5 bytes of data 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Protocol Discriminator: Q.931 (8) len=5 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI=0 Call Ref: len= 2 (reference 145/0x91) (Sent from originator) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Message Type: RELEASE (77) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Got ACK for N(S)=69 to (but not including) N(S)=69 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- T200 requested to stop when not started 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] T203 requested to start without stopping first 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Starting T203 timer 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] Received message for call 0x7f2c10009f10 on link 0x7f2c10000b20 TEI/SAPI 0/0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] q931.c:8997 post_handle_q931_message: Call 145 enters state 0 (Null). Hold state: Idle 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI: 0 State 7(Multi-frame established) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > V(A)=69, V(S)=69, V(R)=33 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > T200_id=0, N200=3, T203_id=8192 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > [ 02 01 01 42 ] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Supervisory frame: 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > SAPI: 00 C/R: 1 EA: 0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI: 000 EA: 1 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Zero: 0 S: 0 01: 1 [ RR (receive ready) ] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > N(R): 033 P/F: 0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > 0 bytes of data 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] Done handling message for SAPI/TEI=0/0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:1454 -- Hangup on channel 1:26 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] q931.c:7135 q931_hangup: Hangup other cref:145 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] q931.c:6892 __q931_hangup: ourstate Null, peerstate Release Request, hold-state Idle 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > DL-DATA request .c:134 [s1c16][1:16] > Protocol Discriminator: Q.931 (8) len=9 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI=0 Call Ref: len= 2 (reference 145/0x91) (Sent to originator) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Message Type: RELEASE COMPLETE (90) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] TEI=0 Transmitting N(S)=69, window is open V(A)=69 K=7 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI: 0 State 7(Multi-frame established) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > V(A)=69, V(S)=69, V(R)=33 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > T200_id=0, N200=3, T203_id=8192 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > [ 00 01 8a 42 08 02 80 91 5a 08 02 81 83 ] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Informational frame: 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > SAPI: 00 C/R: 0 EA: 0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI: 000 EA: 1 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > N(S): 069 0: 0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > N(R): 033 P: 0 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > 9 bytes of data 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Protocol Discriminator: Q.931 (8) len=9 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > TEI=0 Call Ref: len= 2 (reference 145/0x91) (Sent to originator) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Message Type: RELEASE COMPLETE (90) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > [08 02 81 83] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] > Ext: 1 Cause: No route to destination (3), class = Normal Event (0) ] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Stopping T203 timer 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Starting T200 timer 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] q931.c:7135 q931_hangup: Hangup other cref:145 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] q931.c:6892 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] Destroying call 0x7f2c10009f10, ourstate Null, peerstate Null, hold-state Idle 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:1473 [s1c26][1:26] Changed state from HANGUP to HANGUP_COMPLETE 2014-03-24 16:49:25.441450 [DEBUG] ftdm_state.c:541 [s1c26][1:26] Executing state processor for HANGUP_COMPLETE 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:936 -- 1:26 STATE [HANGUP_COMPLETE] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:944 [s1c26][1:26] Completed state change from HANGUP to HANGUP_COMPLETE in 0 ms 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:1260 [s1c26][1:26] Changed state from HANGUP_COMPLETE to DOWN 2014-03-24 16:49:25.441450 [DEBUG] ftdm_state.c:541 [s1c26][1:26] Executing state processor for DOWN 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:936 -- 1:26 STATE [DOWN] 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:944 [s1c26][1:26] Completed state change from HANGUP_COMPLETE to DOWN in 0 ms 2014-03-24 16:49:25.441450 [WARNING] ftdm_io.c:3022 [s1c26][1:26] Channel not opened, proceeding anyway freeswitch@internal> 14-03-24 16:49:25.441450 [DEBUG] ftdm_io.c:3189 [s1c26][1:26] DTMF debug is already disabled 2014-03-24 16:49:25.441450 [DEBUG] ftdm_io.c:3221 [s1c26][1:26] No need to disable input dump 2014-03-24 16:49:25.441450 [DEBUG] ftdm_io.c:3252 [s1c26][1:26] No need to disable output dump 2014-03-24 16:49:25.441450 [DEBUG] mod_freetdm.c:2704 got clear channel sig [RELEASED] 2014-03-24 16:49:25.441450 [DEBUG] ftdm_io.c:6786 Cleared call with id 4 2014-03-24 16:49:25.441450 [DEBUG] ftdm_io.c:2994 [s1c26][1:26] channel done 2014-03-24 16:49:25.441450 [DEBUG] ftmod_libpri.c:972 -- Closed channel 1:26 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI: 0 State 7(Multi-frame established) 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < V(A)=69, V(S)=70, V(R)=33 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < T200_id=8192, N200=3, T203_id=0 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < [ 00 01 01 8c ] 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Supervisory frame: 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < SAPI: 00 C/R: 0 EA: 0 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < TEI: 000 EA: 1 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < Zero: 0 S: 0 01: 1 [ RR (receive ready) ] 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < N(R): 070 P/F: 0 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] < 0 bytes of data 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Got ACK for N(S)=69 to (but not including) N(S)=70 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- ACKing N(S)=69, tx_queue head is N(S)=-1 (-1 is empty, -2 is not transmitted) 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Stopping T200 timer 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] -- Starting T203 timer 2014-03-24 16:49:25.481426 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16] Done handling message for SAPI/TEI=0/0 /exit
|