Вот вспомнил, решил написать, может кто-нить что-то подскажет...
С одним из серверов периодически, примерно раз в месяц, чаще или реже виснет. SIP клиенты отваливаются, поток иногда работает, иногда не отвечает. Сервер пингуется, но по SSH, HTTP не отвечает. На консоли однажды заметил что-то типа:

HLDC Receiver overrun on channel TE2/0/1/16 (master=TE2/0/1/16)
Write to 63 failed: Unknown error 500
Short write: 0/19 (Unknown error 500)

в логах путного ничего нет, в консоли примерно следующее


== Spawn extension (macro-exten-vm, s-BUSY, 3) exited non-zero on 'SIP/1333-09d7f368' in macro 'exten-vm'
== Spawn extension (macro-exten-vm, s-BUSY, 3) exited non-zero on 'SIP/1333-09d7f368'
== Spawn extension (macro-dialout-trunk, s, 21) exited non-zero on 'SIP/1418-09d2a5d0' in macro 'dialout-trunk'
== Spawn extension (macro-dialout-trunk, s, 21) exited non-zero on 'SIP/1418-09d2a5d0'
!! Got reject for frame 5, but we have nothing -- resetting!
-- B-channel 0/4 successfully restarted on span 1
!! Got reject for frame 6, retransmitting frame 6 now, updating n_r!
-- B-channel 0/5 successfully restarted on span 1
!! Got reject for frame 7, retransmitting frame 7 now, updating n_r!
-- B-channel 0/6 successfully restarted on span 1
!! Got reject for frame 8, retransmitting frame 8 now, updating n_r!
-- B-channel 0/7 successfully restarted on span 1

. . . . . в этом месте рвется связь по всем таймслотам

!! Got reject for frame 28, retransmitting frame 28 now, updating n_r!
-- B-channel 0/31 successfully restarted on span 1
!! Got reject for frame 29, but we have nothing -- resetting!
-- Executing Macro("SIP/1333-09c955c0", "exten-vm|novm|1318") in new stack
-- Executing Macro("SIP/1333-09c955c0", "user-callerid") in new stack

или что-то типа этого, тоже связь рвется:
-- Hungup 'Zap/1-1'
ACK received for '1' outside of window of '0' to '0', restarting
== Primary D-Channel on span 1 down
!! Got S-frame while link down
!! Got S-frame while link down
!! Got S-frame while link down
!! Got S-frame while link down
!! Got S-frame while link down
!! Got I-frame while link state 2
!! Got I-frame while link state 2
!! Got I-frame while link state 2
!! Got I-frame while link state 2
!! Got I-frame while link state 2
!! Got I-frame while link state 2
== Primary D-Channel on span 1 up
!! Got a UA, but i'm in state 1
!! Not good - head of queue has not been transmitted yet
== Primary D-Channel on span 1 down ну тут понятно, что отвалился Д канал, но почему?
!! Got S-frame while link down
!! Got I-frame while link state 2
== Primary D-Channel on span 1 up
== Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/1109-09fe5660' in macro 'dial'
== Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/1109-09fe5660' in macro 'exten-vm'
== Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/1109-09fe5660'

пробовал включить трейс PRI, но так как в нем ничего не понимаю (может кто подскажет что почитать?) но не понял сути проблемы. Вот фрагмент, похожий на предыдущий листинг, но с включеным pri intence debug (сорри за объем):

Stopping T_203 timer
Starting T_200 timer
> Protocol Discriminator: Q.931 (8) len=42
> Call Ref: len= 2 (reference 431/0x1AF) (Originator)
> Message type: SETUP (5)
> [04 02 80 90]
> Bearer Capability (len= 4) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0)
> Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
> Ext: 0 User information layer 1: Unknown (24)
> [18 03 a9 83 86]
> Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0
> ChanSel: Reserved
> Ext: 1 Coding: 0 Number Specified Channel Type: 3
> Ext: 1 Channel: 6 ]
> [6c 0c 21 83 33 34 37 32 39 31 32 31 31 32]
> Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
> Presentation: Presentation allowed of network provided number (3) '3472912112' ]
> [70 0c a1 38 39 32 37 33 30 31 39 32 32 30]
> Called Number (len=14) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '89273019220' ]
-- Called g1/89273019220
asterisk1*CLI>
< [ 02 01 5e 55 08 02 81 ae 5a 08 03 02 80 91 ]

< Informational frame:
< SAPI: 00 C/R: 1 EA: 0
< TEI: 000 EA: 1
< N(S): 047 0: 0
< N(R): 042 P: 1
< 10 bytes of data
Sending Reject (48)

> [ 02 01 09 61 ]

> Supervisory frame:
> SAPI: 00 C/R: 1 EA: 0
> TEI: 000 EA: 1
> Zero: 0 S: 2 01: 1 [ REJ (reject) ]
> N(R): 048 P/F: 1
> 0 bytes of data
-- T200 counter expired, What to do...
-- Retransmitting 46 bytes
!! Not good - head of queue has not been transmitted yet

> [ 00 01 54 61 08 02 01 af 05 04 02 80 90 18 03 a9 83 86 6c 0c 21 83 33 34 37 32 39 31 32 31 31 32 70 0c a1 38 39 32 37 33 30 31 39 32 32 30 ]

> Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 042 0: 0
> N(R): 048 P: 1
> 42 bytes of data
-- Rescheduling retransmission (2)
-- T200 counter expired, What to do...
-- Timeout occured, restarting PRI
Sending Set Asynchronous Balanced Mode Extended

> [ 00 01 7f ]

> Unnumbered frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> M3: 3 P/F: 1 M2: 3 11: 3 [ SABME (set asynchronous balanced mode extended) ]
> 0 bytes of data
== Primary D-Channel on span 1 down

> [ 00 01 00 00 08 02 e2 fe 45 08 02 81 90 ]

> Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 000 0: 0
> N(R): 000 P: 0
> 9 bytes of data
Starting T_200 timer
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 25342/0x62FE) (Terminator)
> Message type: DISCONNECT (69)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
> Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]

> [ 00 01 02 00 08 02 c5 a9 45 08 02 81 90 ]

> Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 001 0: 0
> N(R): 000 P: 0
> 9 bytes of data
T_200 timer already going (4)
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 17833/0x45A9) (Terminator)
> Message type: DISCONNECT (69)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
> Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]

> [ 00 01 04 00 08 02 c4 57 45 08 02 81 90 ]

Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 002 0: 0
> N(R): 000 P: 0
> 9 bytes of data
T_200 timer already going (4)
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 17495/0x4457) (Terminator)
> Message type: DISCONNECT (69)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
> Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]

> [ 00 01 06 00 08 02 01 ab 45 08 02 81 90 ]

> Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 003 0: 0
> N(R): 000 P: 0
> 9 bytes of data
T_200 timer already going (4)
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 427/0x1AB) (Originator)
> Message type: DISCONNECT (69)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
> Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]

> [ 00 01 08 00 08 02 01 aa 45 08 02 81 90 ]

> Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 004 0: 0
> N(R): 000 P: 0
> 9 bytes of data
T_200 timer already going (4)
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 426/0x1AA) (Originator)
> Message type: DISCONNECT (69)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
> Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]

> [ 00 01 0a 00 08 02 01 af 45 08 02 81 90 ]

> Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 005 0: 0
> N(R): 000 P: 0
> 9 bytes of data
T_200 timer already going (4)
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 431/0x1AF) (Originator)
> Message type: DISCONNECT (69)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
> Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]

> [ 00 01 0c 00 08 02 01 ad 45 08 02 81 90 ]

> Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 006 0: 0
> N(R): 000 P: 0
> 9 bytes of data
T_200 timer already going (4)
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 429/0x1AD) (Originator)
> Message type: DISCONNECT (69)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)

Пробовал менять: старую плату openvox на новую такой же модели, менял сервер (все на платформе интел. два раза - ксеон, сейчас P4, пром. железо адвантек), не менял только ПО (Trixbox 2.0). Точно такое же ПО стоит на десятке других серверов, в т.ч. на таком же железе, все остальные работают хорошо, аптайм по полгода минимум. На других нагрузка сопоставимая, от 1000 до 2000 звонков в час и более, но нигде такой проблемы небыло. Что примечательно, до астериска стояла АТС LG ipLDK 300. народ с ней намучался и решил поменять на астериск. Она похожим образом висла, думаю, дело в провайдере, так как ПО и железо проверено множеством инсталяций. Поток Е1 это ведь весьма чувствительная штука. Подумываю даже вынести терминацию потока за пределы основного сервера, взять маленький сервачок на ВИА, воткнуть в него плату, оставить только chan_zap и chan_sip, подавать поток по сип в основой сервак, посмотреть, будет ли он виснуть. Если будет виснуть маленький, то хотябы внутренняя связь останется... но вот что с факсами делать... и так они по сипу фигово ходят, хотя и 711 кодек и локальная сеть...
Кто-нить может чем помочь? уже устал в бубен бить....

Я бы отсюда начал. Почему может не отвечать SSH/FTP? Не получает CPU? Значит, кто-то его отжирает. Весь при том. Жаль только, что период появления проблем такой огромный - месяц! Это ж годами можно искать :-)
Попробуй зарядить vmstat 1 10000000000000 > log 2>&1, узнаем, на каком уровне происходит затык - в userspace, или kernel. Если второе, дело в драйвере, и тогда можно предположить, что провайдер делает нечно, что убивает zaptel. Если в user space, надо искать виновника.
Какое ядро используете? Что там насчет периода таймера, типа планировщика, и тд?
Очень похоже на глюк железа, но его меняли уже 3 раза... :-/
А! Попробуйте карту Digium! :-)) Кстати, если не лень, можете бесплатно взять потестировать, будет очень интересно - http://asteriskpbx.ru/hardware/digium/test-digium

Спасибо за ответ!
Вообще сервер - trixbox 2.0 с переделанной вебмордой
Linux asterisk2.local 2.6.9-34.0.2.EL #1 Fri Jul 7 19:24:57 CDT 2006 i686 i686 i386 GNU/Linux

за двухлетнюю эксплуатацию дистриба во многих инсталяциях особых нареканий нет. аптайм по полгода и более.

запустил команду vmstat 1 10000000000000 > log . жаль время не подставляет в строчки...

Карта Digium у меня есть. Поставлю ее. Думаете они принципиально с OpenVox различаются? все равно инфинионовские приемопередатчики...

Порыв в инете инфу, понял, что количество прерываний от платы зависит от нагрузки на поток. может от провайдера идут какие-то кадры, которые вешают стек из-за большого количесва прерываний? Опять же, у старой АТС похожие симптомы были. только раз в неделю приходилось дергать, висла намертво.

И ведь толку нет ставить в пару резервный сервер (linux HA, heartbeat+drbd), так как пинги-то есть! и второй сервак не узнает, что первый сдох-то. Кстати, кто как отслеживает, предоставляется ли сервис? я тут думал, может скриптом, который по сипу кольцом дозванивается и проверяет, пришел ли звонок. а еще хороший способ - пара дешевых однопо ртовых шлюзов длинк. Нтравить их на разные серваки ипоставить малое время регистрации и, если регистрация не возможна (а значит и нет связи), то у длинка загорается светодиод ALARM, и релюшкой в резет пускать...
Седня запущу vmstat и добавлю его в rc.local

Тоже виснет, примерно с такой же периодичностью. Причем никаких карт, кроме эзернета в сервере нет. Из заптеловских модулей загружены zaptel, ztdummy, zttranscode. По-моему где-то в них проблема. По поведению сервера можно предположить, что теряется доступ к дискам, т.е. открытые сессии ssh и консоли астериска живут, но при попытке обращения к диску сессия повисает.

какая операционка ?
попробуйте посмотреть на размер лога астериска
и поставить ротейт

слакварь, сервера hp, зависания замечены на разных ядрах от 2.6.16 до 2.6.23 и разных версиях астериска. ротейт включен. замечена связь между интенсивностью работы в консоли астериска и вероятностью зависания. например если в астериске 1.4.9 из консоли интенсивно слать sip notify, то вероятность зависания сильно увеличивается. в 1.4.19 на загруженном сервере при выполнении из консоли sip show peers список пиров может вдруг перестать выводиться до конца, при этом возрастает загрузка по uptime. астериск корректно не гасится, только по kill -9. м.б. race condition?

можно предположить что то где то мютекс какойто лочит ....
вообщем дело долгое
что бы выловить баг
как минимум вам нужно быть программером

новости по проблеме

У другого клиента второй день лежит связь, поток валится каждые 5 минут, чаще или реже.
Поменял сервер, поставил плату дижиум вместо опенвокс, астериск 1.4.18 (trixbox 2.6)
симптомы аналогичные, сообщения похожие. сервер не виснет, но связь валится. Пришли представители провайдера, завернули поток на себя. Сказали что линия в порядке.

Write to 47 failed: Unknown error 500
Short write: 0/15 (Unknown error 500)
-- Hungup 'Zap/3-1'
== Everyone is busy/congested at this time (1:0/0/1)
-- Hungup 'Zap/1-1'
-- Hungup 'Zap/2-1'
!! Got reject for frame 6, retransmitting frame 6 now, updating n_r!
!! Got reject for frame 6, retransmitting frame 7 now, updating n_r!
!! Got reject for frame 6, retransmitting frame 8 now, updating n_r!
!! Got reject for frame 6, retransmitting frame 9 now, updating n_r!
== Primary D-Channel on span 1 up
-- Accepting call from '3472732174' to '2911304' on channel 0/1, span 1
-- Executing Set("Zap/1-1", "CALLERID(number)=83472732174") in new stack
-- Executing Goto("Zap/1-1", "custom-to-panas1|2911304|1") in new stack
-- Goto (custom-to-panas1,2911304,1)
-- Executing Dial("Zap/1-1", "ZAP/g2/1215|60|tr") in new stack
-- Requested transfer capability: 0x00 - SPEECH
-- Called g2/1215
-- Zap/32-1 is proceeding passing it to Zap/1-1
-- Zap/32-1 is ringing
-- Hungup 'Zap/39-1'

Уже не знаю что делать. менял ПО, железо...
До этого полгода все работало без сбоев, перед инцидентами ничего не менял, никаких работ не проводил. случилось неожиданно утром.

фрагмент CLI в момент обрыва
pri intense debug span 1


T203 counter expired, sending RR and scheduling T203 again
Sending Receiver Ready (25)

> [ 00 01 01 33 ]

> Supervisory frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
> N(R): 025 P/F: 1
> 0 bytes of data
-- Restarting T203 counter
asterisk1*CLI>
< [ 00 01 01 2f ]

< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 023 P/F: 1
< 0 bytes of data
-- ACKing all packets from 22 to (but not including) 23
-- Since there was nothing left, stopping T200 counter
-- Stopping T203 counter since we got an ACK
-- Nothing left, starting T203 counter
-- Got RR response to our frame
-- Restarting T203 counter
asterisk1*CLI>
> [ 00 01 2e 32 08 02 00 03 45 08 02 81 90 ]

> Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 023 0: 0
> N(R): 025 P: 0
> 9 bytes of data
Write to 47 failed: Unknown error 500
Short write: 0/15 (Unknown error 500)
Stopping T_203 timer
Starting T_200 timer
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 3/0x3) (Originator)
> Message type: DISCONNECT (69)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
> Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
-- Hungup 'Zap/3-1'
== Spawn extension (macro-dialout-trunk, s, 20) exited non-zero on 'SIP/1103-08682b38' in macro 'dialout-trunk'
== Spawn extension (macro-dialout-trunk, s, 20) exited non-zero on 'SIP/1103-08682b38'
-- Executing Macro("SIP/1103-08682b38", "hangupcall") in new stack
-- Executing ResetCDR("SIP/1103-08682b38", "w") in new stack
-- Executing NoCDR("SIP/1103-08682b38", "") in new stack
-- Executing GotoIf("SIP/1103-08682b38", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing GotoIf("SIP/1103-08682b38", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing Wait("SIP/1103-08682b38", "5") in new stack
-- T200 counter expired, What to do...
-- Retransmitting 13 bytes

> [ 00 01 2e 33 08 02 00 03 45 08 02 81 90 ]

> Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 023 0: 0
> N(R): 025 P: 1
> 9 bytes of data
-- Rescheduling retransmission (1)
asterisk1*CLI>
< [ 00 01 01 31 ]

< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 024 P/F: 1
< 0 bytes of data
-- ACKing all packets from 22 to (but not including) 24
-- ACKing packet 23, new txqueue is -1 (-1 means empty)
-- Since there was nothing left, stopping T200 counter
-- Nothing left, starting T203 counter
-- Got RR response to our frame
-- Restarting T203 counter
asterisk1*CLI>
< [ 02 01 32 30 08 02 80 03 4d 08 02 81 90 ]

< Informational frame:
< SAPI: 00 C/R: 1 EA: 0
< TEI: 000 EA: 1
< N(S): 025 0: 0
< N(R): 024 P: 0
< 9 bytes of data
-- ACKing all packets from 23 to (but not including) 24
-- Since there was nothing left, stopping T200 counter
-- Stopping T203 counter since we got an ACK
-- Nothing left, starting T203 counter
< Protocol Discriminator: Q.931 (8) len=9
< Call Ref: len= 2 (reference 3/0x3) (Terminator)
< Message type: RELEASE (77)
< [08 02 81 90]
< Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
< Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]

> [ 00 01 30 34 08 02 00 03 5a 08 02 81 d1 ]

> Informational frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> N(S): 024 0: 0
> N(R): 026 P: 0
> 9 bytes of data
-- Restarting T203 counter
Stopping T_203 timer
Starting T_200 timer
> Protocol Discriminator: Q.931 (8) len=9
> Call Ref: len= 2 (reference 3/0x3) (Originator)
> Message type: RELEASE COMPLETE (90)
> [08 02 81 d1]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
> Ext: 1 Cause: Invalid call reference value (81), class = Invalid message (5) ]
asterisk1*CLI>
< [ 00 01 01 32 ]

< Supervisory frame:
< SAPI: 00 C/R: 0 EA: 0
< TEI: 000 EA: 1
< Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
< N(R): 025 P/F: 0
< 0 bytes of data
-- ACKing all packets from 23 to (but not including) 25
-- ACKing packet 24, new txqueue is -1 (-1 means empty)
-- Since there was nothing left, stopping T200 counter
-- Nothing left, starting T203 counter
-- Restarting T203 counter
T203 counter expired, sending RR and scheduling T203 again
Sending Receiver Ready (26)

> [ 00 01 01 35 ]

> Supervisory frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
> N(R): 026 P/F: 1
> 0 bytes of data
-- Restarting T203 counter
-- Executing Hangup("SIP/1103-08682b38", "") in new stack
== Spawn extension (macro-hangupcall, s, 10) exited non-zero on 'SIP/1103-08682b38' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 10) exited non-zero on 'SIP/1103-08682b38'
-- Retrying poll with f-bit
Sending Receiver Ready (26)

> [ 00 01 01 35 ]

> Supervisory frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
> N(R): 026 P/F: 1
> 0 bytes of data
-- Restarting T203 counter
-- Retrying poll with f-bit
Sending Receiver Ready (26)

> [ 00 01 01 35 ]

> Supervisory frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> Zero: 0 S: 0 01: 1 [ RR (receive ready) ]
> N(R): 026 P/F: 1
> 0 bytes of data
-- Restarting T203 counter
-- Retrying poll with f-bit
-- Timeout occured, restarting PRI
Sending Set Asynchronous Balanced Mode Extended

> [ 00 01 7f ]
#######################в этот момент происходи обрыв #########
> Unnumbered frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> M3: 3 P/F: 1 M2: 3 11: 3 [ SABME (set asynchronous balanced mode extended) ]
> 0 bytes of data
== Primary D-Channel on span 1 down
Sending Set Asynchronous Balanced Mode Extended

> [ 00 01 7f ]

> Unnumbered frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> M3: 3 P/F: 1 M2: 3 11: 3 [ SABME (set asynchronous balanced mode extended) ]
> 0 bytes of data
Sending Set Asynchronous Balanced Mode Extended

> [ 00 01 7f ]

> Unnumbered frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> M3: 3 P/F: 1 M2: 3 11: 3 [ SABME (set asynchronous balanced mode extended) ]
> 0 bytes of data
Sending Set Asynchronous Balanced Mode Extended

> [ 00 01 7f ]

> Unnumbered frame:
> SAPI: 00 C/R: 0 EA: 0
> TEI: 000 EA: 1
> M3: 3 P/F: 1 M2: 3 11: 3 [ SABME (set asynchronous balanced mode extended) ]
> 0 bytes of data
asterisk1*CLI> exit
Executing last minute cleanups
[root@asterisk1 ~]#

в логах в момент обрыва появляется:

May 29 13:28:23 NOTICE[10216] chan_zap.c: PRI got event: Alarm (4) on Primary D-channel of span 1
May 29 13:28:23 WARNING[10216] chan_zap.c: No D-channels available! Using Primary channel 16 as D-channel anyway!
May 29 13:28:23 NOTICE[10216] chan_zap.c: PRI got event: No more alarm (5) on Primary D-channel of span 1
May 29 13:28:23 VERBOSE[10216] logger.c: Write to 47 failed: Unknown error 500
May 29 13:28:23 VERBOSE[10216] logger.c: Short write: 0/5 (Unknown error 500)
May 29 13:28:23 NOTICE[10216] chan_zap.c: PRI got event: HDLC Bad FCS (8) on Primary D-channel of span 1
May 29 13:28:24 VERBOSE[10216] logger.c: == Primary D-Channel on span 1 up
May 29 13:28:26 NOTICE[10216] chan_zap.c: PRI got event: HDLC Abort (6) on Primary D-channel of span 1