All Circuits are busy now

Nksahu · January 3, 2018, 1:48pm

Dear Team,

Now-a-days we are facing strange and serious issue with DAHDI calling. We are using FreePBX with this card. We are getting message “All Circuits are busy now”. I am novice to handle this issue so please suggest me some steps to get rid off this issue.

avayax · January 3, 2018, 4:43pm

Call logs are required to troubleshoot problems like this

Nksahu · January 5, 2018, 7:13am

Can you please tell me the procedure to get specific logs?

james · January 5, 2018, 7:15am

E1/T1 or analog cards? if T1/E1, please check the"pri show spans" from CLI, make sure it is up and active.

Nksahu · January 5, 2018, 7:17am

Command: pri show spans

Result: PRI span 1/0: Up, Active

Nksahu · January 5, 2018, 7:41am

If I power down my PRI Modem or disconnect cable between PRI Modem and PBX for 10 minutes, my DAHDI calls start working. After sometime calls stop working again.

dicko · January 5, 2018, 3:51pm

From bash:-

pri set debug on span 1

likelt a d-channel event.

Nksahu · January 11, 2018, 11:34am

Command: pri set debug on span 1
Result: Enabled debugging on span 1

Command: pri show spans
Result: PRI span 1/0: Down, Active

chan_dahdi.conf as following:

; Auto-generated by /usr/sbin/hardware_detector
[trunkgroups]

[channels]
context=from-pstn
signalling=fxs_ks
rxwink=300 ; Atlas seems to use long (250ms) winks
usecallerid=yes
hidecallerid=no
callwaiting=yes
usecallingpres=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
canpark=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=no
faxdetect=incoming
echotraining=800
rxgain=0.0
txgain=0.0
callgroup=1
pickupgroup=1
relaxdtmf=yes

;Uncomment these lines if you have problems with the disconection of your analog lines
;busydetect=yes
;busycount=3

immediate=no

#include dahdi-channels.conf
#include chan_dahdi_additional.conf

dahdi-channels.conf as following:

; Autogenerated by /usr/sbin/dahdi_genconf on Thu Jan 11 18:55:11 2018
; If you edit this file and execute /usr/sbin/dahdi_genconf again,
; your manual changes will be LOST.
; Dahdi Channels Configurations (chan_dahdi.conf)
;
; This is not intended to be a complete chan_dahdi.conf. Rather, it is intended
; to be #include-d by /etc/chan_dahdi.conf that will include the global settings
;

; Span 1: WCT1/0 “Wildcard TE121 Card 0” (MASTER)
group=0,11
context=from-pstn
switchtype = euroisdn
signalling = pri_cpe
channel => 1-15,17-31
context = default
group = 63

But still struggling with All circuits are busy now

dicko · January 11, 2018, 3:02pm

Now you have to monitor the log file when that happens

Nksahu · January 12, 2018, 7:48am

Well we restarted our pri modem and the calls started for couple of minutes then again All Circuits are busy now message appeared. Below are the logs after the message :-

2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > [04 03 80 90 a3]
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0)
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > User information layer 1: A-Law (35)
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > [18 03 a1 83 81]
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Preferred Dchan: 0
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > ChanSel: As indicated in following octets
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Ext: 1 Coding: 0 Number Specified Channel Type: 3
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Ext: 1 Channel: 1 Type: CPE]
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > [6c 05 21 80 38 36 36]
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Calling Party Number (len= 7) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Presentation: Presentation allowed, User-provided, not screened (0) ‘866’ ]
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > [70 0b 80 38 38 31 37 38 39 35 36 38 33]
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Called Party Number (len=13) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) ‘XXXXXXXXXX’ ]
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > [a1]
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Sending Complete (len= 1)
2018-01-12 12:40:03 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 q931.c:6350 q931_setup: Call 32785 enters state 1 (Call Initiated). Hold state: Idle
2018-01-12 12:40:03 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending REJ N®=0
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=10
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 17/0x11) (Sent to originator)
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Message Type: SETUP ACKNOWLEDGE (13)
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < [18 03 a9 83 81]
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < ChanSel: As indicated in following octets
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Ext: 1 Coding: 0 Number Specified Channel Type: 3
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Ext: 1 Channel: 1 Type: CPE]
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Received message for call 0x1b2408d0 on link 0x1b1473d0 TEI/SAPI 0/0
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 – Processing IE 24 (cs0, Channel ID)
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:9211 post_handle_q931_message: Call 32785 enters state 2 (Overlap Sending). Hold state: Idle
2018-01-12 12:40:03 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_SETUP_ACK(14)
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=5
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 17/0x11) (Sent to originator)
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Message Type: CALL PROCEEDING (2)
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Received message for call 0x1b2408d0 on link 0x1b1473d0 TEI/SAPI 0/0
2018-01-12 12:40:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:8846 post_handle_q931_message: Call 32785 enters state 3 (Outgoing Call Proceeding). Hold state: Idle
2018-01-12 12:40:03 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_PROCEEDING(13)
2018-01-12 12:40:06
WARNING[5818][C-0000005c] func_presencestate.c: PRESENCE_STATE unknown
2018-01-12 12:40:11 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-12 12:40:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (I): T200 expired N200 times sending RR/RNR in state 8(Timer recovery)
2018-01-12 12:40:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-12 12:40:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 8(Timer recovery) to 5(Awaiting establishment)
2018-01-12 12:40:17 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_DOWN(2)
2018-01-12 12:40:17 WARNING [3139] sig_pri.c:
Span 1: D-channel is down!
2018-01-12 12:40:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Got UA
2018-01-12 12:40:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 7(Multi-frame established)
2018-01-12 12:40:17 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_UP(1)
2018-01-12 12:40:18 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-12 12:40:21 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-12 12:40:22 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Got SABME from network peer.
2018-01-12 12:40:22 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending UA
2018-01-12 12:40:22 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (F): SABME in state 7(Multi-frame established)
2018-01-12 12:40:27
WARNING[5819][C-0000005d] func_presencestate.c: PRESENCE_STATE unknown
2018-01-12 12:41:15
WARNING[5823][C-0000005e] func_presencestate.c: PRESENCE_STATE unknown
2018-01-12 12:41:22
WARNING[5824][C-0000005f] func_presencestate.c: PRESENCE_STATE unknown
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 q931.c:7151 q931_hangup: Hangup other cref:32785
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 q931.c:6908 __q931_hangup: ourstate Outgoing Call Proceeding, peerstate Incoming Call Proceeding, hold-state Idle
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 q931.c:6097 q931_disconnect: Call 32785 enters state 11 (Disconnect Request). Hold state: Idle
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > DL-DATA request
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 17/0x11) (Sent from originator)
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Message Type: DISCONNECT (69)
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 TEI=0 Transmitting N(S)=0, window is open V(A)=0 K=7
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 17/0x11) (Sent from originator)
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Message Type: DISCONNECT (69)
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > [08 02 81 90]
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
2018-01-12 12:41:23 VERBOSE [5817] chan_dahdi.c:
PRI Span: 1 > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=13
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 17/0x11) (Sent to originator)
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Message Type: RELEASE COMPLETE (90)
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < [08 06 82 d1 00 00 00 00]
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Cause (len= 8) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Public network serving the local user (2)
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Ext: 1 Cause: Invalid call reference value (81), class = Invalid message (e.g. parameter out of range) (5) ]
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Cause data 1: 00 (0)
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Cause data 2: 00 (0)
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Cause data 3: 00 (0)
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Cause data 4: 00 (0)
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Received message for call 0x1b2408d0 on link 0x1b1473d0 TEI/SAPI 0/0
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 – Processing IE 8 (cs0, Cause)
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:8959 post_handle_q931_message: Call 32785 enters state 0 (Null). Hold state: Idle
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:7151 q931_hangup: Hangup other cref:32785
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:6908 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle
2018-01-12 12:41:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Destroying call 0x1b2408d0, ourstate Null, peerstate Null, hold-state Idle

dicko · January 12, 2018, 4:15pm

It looks like a failure on the d channel , but you need the logs BEFOREthe event to see which end caused it

Nksahu · January 15, 2018, 6:46am

Hello,
Sorry for the long log file

Initially when calls were connecting :
PRI span 1/0: Up, Active

After all circuits are busy error :
PRI span 1/0: Down, Active

Logs include from the last time call connected till the error message appeared

2018-01-15 11:31:41 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > [6c 05 21 80 38 39 32]
2018-01-15 11:31:41 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > Calling Party Number (len= 7) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
2018-01-15 11:31:41 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > Presentation: Presentation allowed, User-provided, not screened (0) ‘892’ ]
2018-01-15 11:31:41 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > [70 0b 80 39 36 35 34 32 32 32 36 32 36]
2018-01-15 11:31:41 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > Called Party Number (len=13) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) ‘XXXXXXXXXX’ ] **

(Last Number to be successfully dialed)

**
2018-01-15 11:31:41 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > [a1]
2018-01-15 11:31:41 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > Sending Complete (len= 1)
2018-01-15 11:31:41 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 q931.c:6350 q931_setup: Call 32864 enters state 1 (Call Initiated). Hold state: Idle
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=10
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 96/0x60) (Sent to originator)
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Message Type: SETUP ACKNOWLEDGE (13)
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < [18 03 a9 83 81]
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < ChanSel: As indicated in following octets
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Ext: 1 Coding: 0 Number Specified Channel Type: 3
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Ext: 1 Channel: 1 Type: CPE]
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Received message for call 0x1b1cdb70 on link 0x1b1473d0 TEI/SAPI 0/0
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 – Processing IE 24 (cs0, Channel ID)
2018-01-15 11:31:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:9211 post_handle_q931_message: Call 32864 enters state 2 (Overlap Sending). Hold state: Idle
2018-01-15 11:31:41 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_SETUP_ACK(14)
2018-01-15 11:31:41 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-15 11:31:42 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Got SABME from network peer.
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending UA
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (F): SABME in state 7(Multi-frame established)
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=5
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 96/0x60) (Sent to originator)
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Message Type: CONNECT (7)
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Received message for call 0x1b1cdb70 on link 0x1b1473d0 TEI/SAPI 0/0
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:8764 post_handle_q931_message: Call 32864 enters state 8 (Connect Request). Hold state: Idle
2018-01-15 11:31:48 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_ANSWER(8)
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:6436 q931_connect_acknowledge: Call 32864 enters state 10 (Active). Hold state: Idle
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > DL-DATA request
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=5
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 96/0x60) (Sent from originator)
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > Message Type: CONNECT ACKNOWLEDGE (15)
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Transmitting N(S)=0, window is open V(A)=0 K=7
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=5
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 96/0x60) (Sent from originator)
2018-01-15 11:31:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > Message Type: CONNECT ACKNOWLEDGE (15)
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=13
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 96/0x60) (Sent to originator)
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Message Type: DISCONNECT (69)
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < [08 06 82 90 00 00 00 00]
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Cause (len= 8) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Public network serving the local user (2)
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Cause data 1: 00 (0)
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Cause data 2: 00 (0)
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Cause data 3: 00 (0)
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 < Cause data 4: 00 (0)
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Received message for call 0x1b1cdb70 on link 0x1b1473d0 TEI/SAPI 0/0
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 – Processing IE 8 (cs0, Cause)
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 – Found active call: 0x1b1cdb70 cref:32864
2018-01-15 11:33:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:9099 post_handle_q931_message: Call 32864 enters state 12 (Disconnect Indication). Hold state: Idle
2018-01-15 11:33:17 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_HANGUP_REQ(15)
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 q931.c:7151 q931_hangup: Hangup other cref:32864
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 q931.c:6908 __q931_hangup: ourstate Disconnect Indication, peerstate Disconnect Request, hold-state Idle
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 q931.c:5946 q931_release: Call 32864 enters state 19 (Release Request). Hold state: Idle
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > DL-DATA request
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 96/0x60) (Sent from originator)
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > Message Type: RELEASE (77)
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 TEI=0 Transmitting N(S)=1, window is open V(A)=1 K=7
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 96/0x60) (Sent from originator)
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > Message Type: RELEASE (77)
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > [08 02 81 90]
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
2018-01-15 11:33:17 VERBOSE [14727] chan_dahdi.c:
PRI Span: 1 > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
2018-01-15 11:33:17 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-15 11:33:20 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-15 11:33:21
WARNING[14740][C-000001cc] func_presencestate.c: PRESENCE_STATE unknown
2018-01-15 11:33:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Timed out looking for release complete
2018-01-15 11:33:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1
2018-01-15 11:33:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > DL-DATA request
2018-01-15 11:33:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
2018-01-15 11:33:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 96/0x60) (Sent from originator)
2018-01-15 11:33:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > Message Type: RELEASE (77)
2018-01-15 11:33:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Just queued I-frame since in state 8(Timer recovery)
2018-01-15 11:33:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (I): T200 expired N200 times sending RR/RNR in state 8(Timer recovery)
2018-01-15 11:33:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 8(Timer recovery) to 5(Awaiting establishment)
2018-01-15 11:33:21 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_DOWN(2)
2018-01-15 11:33:21 WARNING [3139] sig_pri.c:
Span 1: D-channel is down!
2018-01-15 11:33:22 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:22 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Got UA
2018-01-15 11:33:22 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 7(Multi-frame established)
2018-01-15 11:33:22 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_ESTABLISH_IND(1)
2018-01-15 11:33:22 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_UP(1)
2018-01-15 11:33:25 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Final time-out looking for release complete
2018-01-15 11:33:25 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:7151 q931_hangup: Hangup other cref:32864
2018-01-15 11:33:25 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:6908 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle
2018-01-15 11:33:25 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Destroying call 0x1b1cdb70, ourstate Null, peerstate Null, hold-state Idle
2018-01-15 11:33:25 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_HANGUP_ACK(9)
2018-01-15 11:33:34 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-15 11:33:36 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (I): T200 expired N200 times sending RR/RNR in state 8(Timer recovery)
2018-01-15 11:33:36 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:36 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 8(Timer recovery) to 5(Awaiting establishment)
2018-01-15 11:33:36 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_DOWN(2)
2018-01-15 11:33:36 WARNING [3139] sig_pri.c:
Span 1: D-channel is down!
2018-01-15 11:33:37 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:38 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:39 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:40 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:33:40 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:33:40 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:33:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:33:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:41 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:33:42 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:43 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:44 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:44 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Got UA
2018-01-15 11:33:44 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 7(Multi-frame established)
2018-01-15 11:33:44 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_ESTABLISH_CONFIRM(2)
2018-01-15 11:33:44 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_UP(1)
2018-01-15 11:33:56 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1 – Making new call for cref 32865
2018-01-15 11:33:56 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1
2018-01-15 11:33:56 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1 > DL-DATA request
2018-01-15 11:33:56 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=36
2018-01-15 11:33:56 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 97/0x61) (Sent from originator)
2018-01-15 11:33:56 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1 > Message Type: SETUP (5)
2018-01-15 11:33:56 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1 TEI=0 Just queued I-frame since in state 8(Timer recovery)
2018-01-15 11:33:56 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1 q931.c:6350 q931_setup: Call 32865 enters state 1 (Call Initiated). Hold state: Idle
2018-01-15 11:33:57 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-15 11:33:58 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (I): T200 expired N200 times sending RR/RNR in state 8(Timer recovery)
2018-01-15 11:33:58 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:33:58 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 8(Timer recovery) to 5(Awaiting establishment)
2018-01-15 11:33:58 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_DOWN(2)
2018-01-15 11:33:58 WARNING [3139] sig_pri.c:
Span 1: D-channel is down!
2018-01-15 11:33:59 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:00 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 T303 timed out. cref:32865
2018-01-15 11:34:00 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1
2018-01-15 11:34:00 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > DL-DATA request
2018-01-15 11:34:00 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=36
2018-01-15 11:34:00 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 97/0x61) (Sent from originator)
2018-01-15 11:34:00 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 > Message Type: SETUP (5)
2018-01-15 11:34:00 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Just queued I-frame since in state 5(Awaiting establishment)
2018-01-15 11:34:00 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:01 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:02 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:34:02 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:34:02 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:34:02 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Cancel call cref=32865 on channel 1 in state 1 (Call Initiated)
2018-01-15 11:34:02 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Cancel call after data link failure
2018-01-15 11:34:02 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:9687 pri_dl_down_cancelcall: Call 32865 enters state 0 (Null). Hold state: Idle
2018-01-15 11:34:02 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 q931.c:9622 pri_internal_clear: alive 1, hangupack 1
2018-01-15 11:34:02 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_HANGUP(6)
2018-01-15 11:34:02 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1 q931.c:7151 q931_hangup: Hangup other cref:32865
2018-01-15 11:34:02 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1 q931.c:6908 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle
2018-01-15 11:34:02 VERBOSE [14741] chan_dahdi.c:
PRI Span: 1 Destroying call 0x1b1fbe90, ourstate Null, peerstate Null, hold-state Idle
2018-01-15 11:34:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:34:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:34:04 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:05 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:06 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:06 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-15 11:34:07 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:34:07 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:34:07 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:34:08
WARNING[14742][C-000001ce] app_dial.c: Unable to create channel of type ‘DAHDI’ (cause 34 - Circuit/channel congestion)
2018-01-15 11:34:08 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:34:08 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:08 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:34:09 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:10 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:11 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:12 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:34:12 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:34:12 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:34:13 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:34:13 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:13 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:34:14 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:15 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:16 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:34:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:34:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:34:18 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:34:18 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:18 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:34:19 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:20 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:21 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:22 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:34:22 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:34:22 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:34:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:34:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:23 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:34:24 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:25 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:26 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:27 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:34:27 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:34:27 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:34:28 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:34:28 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:28 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:34:29 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:30
WARNING[14743][C-000001cf] app_dial.c: Unable to create channel of type ‘DAHDI’ (cause 34 - Circuit/channel congestion)
2018-01-15 11:34:30 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:31 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:32 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:34:32 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:34:32 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:34:33
WARNING[14744][C-000001d0] app_dial.c: Unable to create channel of type ‘DAHDI’ (cause 34 - Circuit/channel congestion)
2018-01-15 11:34:33 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:34:33 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:33 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:34:33 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Got UA
2018-01-15 11:34:33 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 7(Multi-frame established)
2018-01-15 11:34:33 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_ESTABLISH_CONFIRM(2)
2018-01-15 11:34:33 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_UP(1)
2018-01-15 11:34:47 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (I): T200 expired N200 times sending RR/RNR in state 8(Timer recovery)
2018-01-15 11:34:47 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:47 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 8(Timer recovery) to 5(Awaiting establishment)
2018-01-15 11:34:47 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_DOWN(2)
2018-01-15 11:34:47 WARNING [3139] sig_pri.c:
Span 1: D-channel is down!
2018-01-15 11:34:48 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:49 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:49 NOTICE [3139] chan_dahdi.c:
PRI got event: HDLC Abort (6) on D-channel of span 1
2018-01-15 11:34:50 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:51 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:34:51 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:34:51 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:34:52 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:34:52 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:52 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:34:53 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:54 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:55 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:56 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:34:56 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:34:56 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:34:57 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:34:57 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:57 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:34:58 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:34:59 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:35:00 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:35:01 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
2018-01-15 11:35:01 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
2018-01-15 11:35:01 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
2018-01-15 11:35:02 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 SAPI/TEI=0/0 Kick starting link
2018-01-15 11:35:02 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:35:02 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
2018-01-15 11:35:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:35:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Got UA
2018-01-15 11:35:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 5(Awaiting establishment) to 7(Multi-frame established)
2018-01-15 11:35:03 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 DL event: Q931_DL_EVENT_DL_ESTABLISH_CONFIRM(2)
2018-01-15 11:35:03 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_UP(1)
2018-01-15 11:35:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 MDL-ERROR (I): T200 expired N200 times sending RR/RNR in state 8(Timer recovery)
2018-01-15 11:35:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 TEI=0 Sending SABME
2018-01-15 11:35:17 VERBOSE [3139] chan_dahdi.c:
PRI Span: 1 Changing from state 8(Timer recovery) to 5(Awaiting establishment)
2018-01-15 11:35:17 VERBOSE [3139] sig_pri.c:
Span 1: Processing event PRI_EVENT_DCHAN_DOWN(2)

dicko · January 15, 2018, 9:12pm

If you filter your logs on DCHAN, you will see that the d-channel is “bouncing” , you need to confirm with your provider that you have everything right on your end, if an E1 very often you need the D-channel to be set to hardhdlc and not dchan, but only your provider and you can know.

system · January 15, 2019, 9:13pm

This topic was automatically closed 365 days after the last reply. New replies are no longer allowed.