Asterisk 故障排查

Hangup Cause Mappings

系统问题排查

硬件的问题

中断

版本问题

考虑降级测试

驱动问题

如果是用的 OpenVox, 那么需要从新编译
编译驱动时和内核版本有关

系统问题

切换操作系统
AsteriskNow, Alpine, CentOS

配置问题
SIP/PJSIP 的问题

切换使用 chan_sip 和 chan_pjsip 进行尝试

PJSIP 的问题

可能是由于 pjproject 的 bug 问题, 使用 asterisk bundle 的 pj

诊断命令

CLI commands useful for debugging

# 系统状态 - 内存, 运行时间
core show sysinfo
# 查看线程
core show threads


# 查看通话统计
# uptime 会显示通话时间
core show calls uptime
# 查看通道信息
core show channels
# 终止一个 channel
channel request hangup SIP/4003-00000a2f

# 查看注册的 PBX 交换
core show switches

# 开启日志进行排查
core set verbose 4
core set debug 4

# 开启 SIP 日志
pjsip set logger on

From 头包含的地址无效导致解析问题

尝试修改 hostname

error parsing header 'Contact' error parsing header 'From'

From: "100" <sip:100@0f7a4948f2>;tag=b6a43727-f5d2-40af-8a93-80739de6d092

Contact: <sip:asterisk@0f7a4948f2ba:5060;transport=ws>

这里的 0f7a4948f2 是不符合 uri 规范的，会导致部分客户端异常

0f7a4948f2 是 hostname - 可以修改

PJSIP 会将请求过来的地址进行转换

same => n,DumpChan()
same => n,NoOp(CALLERID ${CALLERID(all)})
same => n,NoOp(From ${PJSIP_HEADER(read,From)})

原始的 From 里是 IP 地址

接口 originate 一定概率没有声音

No voice when using AMI originate between two external destinations

查看通话数量和当前开启通道

asterisk -rx 'core show channels'

终端问题排查

确保 context 正确
尝试关闭 ICE

常见 HTTP 异常状态码

No Response 408 Request Timeout 500 Internal Server Error 502 Bad Gateway 503 Service Unavailable 线路忙、拥塞 504 Server Timeout Any 600-class response

401 Unauthorized 407 Proxy Authentication Required

Assertion failed: sess && sess->endpt

该异常后程序会直接崩溃
pjproject sip_auth_client.c#L507
https://github.com/asterisk/pjproject/blob/master/pjsip/src/pjsip/sip_auth_client.c
https://bugs.debian.org/cgi-bin/bugreport.cgi?bug=842878
asterisk 14.4.1 依赖的 pjproject 2.5.5 未修复
代码
- #1946 Assertion in deinitializing client auth session when dialog creation fails
- changeset#5401
  - 避免处理未初始化的客户端授权会话
- changeset#5373
  - 添加了断言
建议编译 asterisk 使用 bundle pjproject 的形式, 避免外部依赖, 并且 asterisk 也有不少针对 pjproject 的补丁

Assertion failed: sess && sess->endpt (../src/pjsip/sip_auth_client.c: pjsip_auth_clt_deinit: 507)

Bridge: Could not create class basic. No technology to support it.

可能是没有加载 bridge 模块

Couldn't execute statement: SQL logic error

看看是不是数据库没有权限

CallerID presentation

allowed_not_screened - Presentation Allowed, Not Screened.
allowed_passed_screen - Presentation Allowed, Passed Screen.
allowed_failed_screen - Presentation Allowed, Failed Screen.
allowed - Presentation Allowed, Network Number.
prohib_not_screened - Presentation Prohibited, Not Screened.
prohib_passed_screen - Presentation Prohibited, Passed Screen.
prohib_failed_screen - Presentation Prohibited, Failed Screen.
prohib - Presentation Prohibited, Network Number.
unavailable - Number Unavailable.

translate.c:407 framein: no samples for ulawtolin

http://lists.digium.com/pipermail/asterisk-users/2012-October/275509.html
- It means that one of clients, is using 'silence suppression' mechanism
RTP Silence Suppression
Asterisk config sip.conf
Silence suppression:wiki
pjsip Adaptive Silence Detection
X-Lite 中可以设置 Transmit Silence=YES
在配合 Asterisk 时, 建议都将客户端的静音抑制都关闭
讯时网关中有静音压缩(G.723，GSM，iLBC) 和静音包丢弃的相关设置

[Sep 11 09:25:37] WARNING[1109][C-00000068]: translate.c:407 framein: no samples for ulawtolin

app_dial.c:2472 dial_exec_full: Dial argument takes format (technology/resource)

一般是 Dial 的参数没传, 或者是错误的参数

[Sep 11 09:43:18] WARNING[11181][C-000000bb]: app_dial.c:2472 dial_exec_full: Dial argument takes format (technology/resource)

tcptls.c:746 ast_tcptls_close_session_file: ast_tcptls_close_session_file invoked on session instance without file or file descriptor

调用 ARI 时会触发
ASTERISK-26842
- 这里提到使用 ARI Websocket 时也会触发
tcptls.c#L780

[Sep 12 11:13:38] ERROR[25317]: tcptls.c:746 ast_tcptls_close_session_file: ast_tcptls_close_session_file invoked on session instance without file or file descriptor

Log queue threshold exceeded. Discarding new message

logger.c#L1980
logger.c#L727
- logger.conf 中配置 [general]/logger_queue_limit 默认为 1000
- 示例配置中没有说明
系统卡顿的时候会出现该问题
请求加压过多时会出现该问题

[Jun 29 11:11:57] WARNING[5024]: logger:0 ***: Log queue threshold (5000) exceeded.  Discarding new messages.
[Jun 29 11:16:37] WARNING[28086]: logger:0 ***: Logging resumed.  158329 messages discarded.

系统间歇性卡顿

Asterisk hanging occasionally

Error loading module 'res_pjsip.so': undefined symbol: ast_sip_session_register_supplement

ASTERISK-26518
- 将部分代码注释即可

curl -O https://issues.asterisk.org/jira/secure/attachment/54695/ast.diff

Read factory 0xb6d0acb8 was pretty quick last time, waiting for them / DTMF best

ASTERISK-15743
- Read factory 0xb6d0acb8 was pretty quick last time, waiting for them
audiohook.c#L275
- "Read factory %p and write factory %p both fail to provide %zu samples\n"
audiohook.c#L281
- "Write factory %p was pretty quick last time, waiting for them.\n"
dsp.c#L735
- "DTMF best '%c' Erow=%.4E Ecol=%.4E Erc=%.4E Et=%.4E\n"
出现大量这样的日志时, 还会有噪音
可能是线路干扰导致, 尝试调整线路测试噪音是否发生变化

[Sep 15 10:51:36] DEBUG[27592][C-00000024] audiohook.c: Read factory 0x559839609568 and write factory 0x559839609fa8 both fail to provide 160 samples
[Sep 15 10:51:36] DEBUG[27592][C-00000024] audiohook.c: Write factory 0x559839609fa8 was pretty quick last time, waiting for them.
[Sep 15 10:51:36] DEBUG[27589][C-00000024] dsp.c: DTMF best 'B' Erow=4.6720E+03 Ecol=1.9114E+04 Erc=2.3786E+04 Et=5.8752E+04
[Sep 15 10:51:36] DEBUG[27592][C-00000024] audiohook.c: Read factory 0x559839609568 and write factory 0x559839609fa8 both fail to provide 160 samples
[Sep 15 10:51:36] DEBUG[27592][C-00000024] audiohook.c: Write factory 0x559839609fa8 was pretty quick last time, waiting for them.
[Sep 15 10:51:36] DEBUG[27589][C-00000024] dsp.c: DTMF best 'B' Erow=4.6720E+03 Ecol=1.9114E+04 Erc=2.3786E+04 Et=5.8752E+04
[Sep 15 10:51:36] DEBUG[27589][C-00000024] dsp.c: DTMF best 'B' Erow=4.6720E+03 Ecol=1.9114E+04 Erc=2.3786E+04 Et=5.8752E+04
[Sep 15 10:51:36] DEBUG[27592][C-00000024] audiohook.c: Read factory 0x559839609568 and write factory 0x559839609fa8 both fail to provide 160 samples
[Sep 15 10:51:36] DEBUG[27592][C-00000024] audiohook.c: Write factory 0x559839609fa8 was pretty quick last time, waiting for them.
[Sep 15 10:51:36] DEBUG[27589][C-00000024] dsp.c: DTMF best 'B' Erow=4.6720E+03 Ecol=1.9114E+04 Erc=2.3786E+04 Et=5.8752E+04
[Sep 15 10:51:36] DEBUG[27592][C-00000024] audiohook.c: Read factory 0x559839609568 and write factory 0x559839609fa8 both fail to provide 160 samples
[Sep 15 10:51:36] DEBUG[27592][C-00000024] audiohook.c: Write factory 0x559839609fa8 was pretty quick last time, waiting for them.
[Sep 15 10:51:36] DEBUG[27589][C-00000024] dsp.c: DTMF best 'B' Erow=4.6720E+03 Ecol=1.9114E+04 Erc=2.3786E+04 Et=5.8752E+04

chan_dahdi.c:19031 process_dahdi: Ignoring any changes to 'userbase' (on reload)

只是说这部分配置不能被重载

[Sep 11 14:07:34] WARNING[12741]: chan_dahdi.c:19031 process_dahdi: Ignoring any changes to 'userbase' (on reload) at line 23.
[Sep 11 14:07:34] WARNING[12741]: chan_dahdi.c:19031 process_dahdi: Ignoring any changes to 'vmsecret' (on reload) at line 31.
[Sep 11 14:07:34] WARNING[12741]: chan_dahdi.c:19031 process_dahdi: Ignoring any changes to 'hassip' (on reload) at line 35.
[Sep 11 14:07:34] WARNING[12741]: chan_dahdi.c:19031 process_dahdi: Ignoring any changes to 'hasiax' (on reload) at line 39.
[Sep 11 14:07:34] WARNING[12741]: chan_dahdi.c:19031 process_dahdi: Ignoring any changes to 'hasmanager' (on reload) at line 47.

pbx.c: sent to invalid extension but no invalid handler: context,exten,priority=from-pstn,s,1

DADHI FXO 进入的 exten 为 s, 所以需要 exten => s,1,NoOp()

[Sep 25 18:49:33] WARNING[3328][C-00000004]: pbx.c:4458 __ast_pbx_run: Channel 'DAHDI/2-1' sent to invalid extension but no invalid handler: context,exten,priority=from-pstn,s,1

Unable to create channel of type 'DAHDI' (cause 17 - User busy)

DAHDI FXO 可能会出现挂断后通道未释放的情况

[Sep 25 18:32:19] WARNING[3709][C-00000005]: app_dial.c:2530 dial_exec_full: Unable to create channel of type 'DAHDI' (cause 17 - User busy)

codec_dahdi.c:820 find_transcoders: Failed to open /dev/dahdi/transcode: No such file or directory

[Sep 25 18:54:00] ERROR[3333]: codec_dahdi.c:820 find_transcoders: Failed to open /dev/dahdi/transcode: No such file or directory

Couldn't find mailbox 1234 in context default

示例配置中的配置文件中包含了这几个配置, 把相关的配置取消掉即可

  == Parsing '/etc/asterisk/voicemail.conf': Found
  == Parsing '/etc/asterisk/users.conf': Found
[Sep 25 18:54:00] ERROR[3333]: app_voicemail.c:2836 inboxcount2: Couldn't find mailbox 1234 in context default
[Sep 25 18:54:00] ERROR[3333]: app_voicemail.c:2836 inboxcount2: Couldn't find mailbox 1234 in context other

sig_pri.c:6425 pri_dchannel: pri_check_event returned error 22 (Invalid argument)

sig_pri.c#L6425
pri_check_event pri.c#L776
- pri->read_func
- q921_receive
https://downloads.asterisk.org/pub/telephony/libpri/
TODD
- 导致该问题的具体原因还不清楚
- 会有什么影响也暂不清楚

PRI locks randomly, hangup cause 102, "recovery on timer expiry". ASTERISK-15529

http://lists.digium.com/pipermail/asterisk-users/2009-August/236460.html http://lists.digium.com/pipermail/asterisk-users/2014-August/284268.html

[Sep  5 19:42:22] NOTICE[14239]: chan_dahdi.c:2840 my_handle_dchan_exception: Got DAHDI event: HDLC Abort (6) on D-channel of span 1
[Sep  5 19:42:22] NOTICE[14239]: sig_pri.c:6425 pri_dchannel: pri_check_event returned error 22 (Invalid argument)
[Sep  5 19:42:22] NOTICE[14239]: chan_dahdi.c:2840 my_handle_dchan_exception: Got DAHDI event: HDLC Abort (6) on D-channel of span 1
[Sep  5 19:42:22] NOTICE[14239]: sig_pri.c:6425 pri_dchannel: pri_check_event returned error 22 (Invalid argument)
[Sep  5 19:42:23] NOTICE[14239]: sig_pri.c:6425 pri_dchannel: pri_check_event returned error 22 (Invalid argument)
[Sep  5 19:42:23] NOTICE[14239]: sig_pri.c:6425 pri_dchannel: pri_check_event returned error 22 (Invalid argument)
[Sep  5 19:42:32] NOTICE[14239]: chan_dahdi.c:2840 my_handle_dchan_exception: Got DAHDI event: HDLC Abort (6) on D-channel of span 1
[Sep  5 19:42:32] NOTICE[14239]: sig_pri.c:6425 pri_dchannel: pri_check_event returned error 22 (Invalid argument)

PRI Span: 1 < TEI: 0 State 7(Multi-frame established)
PRI Span: 1 < V(A)=0, V(S)=0, V(R)=0
PRI Span: 1 < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
PRI Span: 1 < T200_id=0, N200=3, T203_id=8192
PRI Span: 1 < [ 02 01 01 01 ]
PRI Span: 1 < Supervisory frame:
PRI Span: 1 < SAPI: 00  C/R: 1 EA: 0
PRI Span: 1 <  TEI: 000        EA: 1
PRI Span: 1 < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
PRI Span: 1 < N(R): 000 P/F: 1
PRI Span: 1 < 0 bytes of data
PRI Span: 1
PRI Span: 1 > TEI: 0 State 7(Multi-frame established)
PRI Span: 1 > V(A)=0, V(S)=0, V(R)=0
PRI Span: 1 > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
PRI Span: 1 > T200_id=0, N200=3, T203_id=8192
PRI Span: 1 > [ 02 01 01 01 ]
PRI Span: 1 > Supervisory frame:
PRI Span: 1 > SAPI: 00  C/R: 1 EA: 0
PRI Span: 1 >  TEI: 000        EA: 1
PRI Span: 1 > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
PRI Span: 1 > N(R): 000 P/F: 1
PRI Span: 1 > 0 bytes of data
PRI Span: 1 -- Got ACK for N(S)=0 to (but not including) N(S)=0
PRI Span: 1 -- T200 requested to stop when not started
PRI Span: 1 T203 requested to start without stopping first
PRI Span: 1 -- Starting T203 timer
PRI Span: 1 Done handling message for SAPI/TEI=0/0
pri_check_event returned error 22 (Invalid argument)
PRI Span: 1
PRI Span: 1 < TEI: 0 State 8(Timer recovery)
PRI Span: 1 < V(A)=0, V(S)=0, V(R)=0
PRI Span: 1 < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
PRI Span: 1 < T200_id=8192, N200=3, T203_id=0
PRI Span: 1 < [ 00 01 01 01 ]
PRI Span: 1 < Supervisory frame:
PRI Span: 1 < SAPI: 00  C/R: 0 EA: 0
PRI Span: 1 <  TEI: 000        EA: 1
PRI Span: 1 < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
PRI Span: 1 < N(R): 000 P/F: 1
PRI Span: 1 < 0 bytes of data
PRI Span: 1 -- Got ACK for N(S)=0 to (but not including) N(S)=0
PRI Span: 1 -- Stopping T200 timer
PRI Span: 1 -- Starting T203 timer
PRI Span: 1 Done handling message for SAPI/TEI=0/0
pri_check_event returned error 22 (Invalid argument)
PRI Span: 1
PRI Span: 1 < TEI: 0 State 7(Multi-frame established)
PRI Span: 1 < V(A)=0, V(S)=0, V(R)=0
PRI Span: 1 < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
PRI Span: 1 < T200_id=0, N200=3, T203_id=8192
PRI Span: 1 < [ 02 01 01 01 ]
PRI Span: 1 < Supervisory frame:
PRI Span: 1 < SAPI: 00  C/R: 1 EA: 0
PRI Span: 1 <  TEI: 000        EA: 1
PRI Span: 1 < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
PRI Span: 1 < N(R): 000 P/F: 1
PRI Span: 1 < 0 bytes of data
PRI Span: 1
PRI Span: 1 > TEI: 0 State 7(Multi-frame established)
PRI Span: 1 > V(A)=0, V(S)=0, V(R)=0
PRI Span: 1 > K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
PRI Span: 1 > T200_id=0, N200=3, T203_id=8192
PRI Span: 1 > [ 02 01 01 01 ]
PRI Span: 1 > Supervisory frame:
PRI Span: 1 > SAPI: 00  C/R: 1 EA: 0
PRI Span: 1 >  TEI: 000        EA: 1
PRI Span: 1 > Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
PRI Span: 1 > N(R): 000 P/F: 1
PRI Span: 1 > 0 bytes of data
PRI Span: 1 -- Got ACK for N(S)=0 to (but not including) N(S)=0
PRI Span: 1 -- T200 requested to stop when not started
PRI Span: 1 T203 requested to start without stopping first
PRI Span: 1 -- Starting T203 timer
PRI Span: 1 Done handling message for SAPI/TEI=0/0
pri_check_event returned error 22 (Invalid argument)
PRI Span: 1
PRI Span: 1 < TEI: 0 State 8(Timer recovery)
PRI Span: 1 < V(A)=0, V(S)=0, V(R)=0
PRI Span: 1 < K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
PRI Span: 1 < T200_id=8192, N200=3, T203_id=0
PRI Span: 1 < [ 00 01 01 01 ]
PRI Span: 1 < Supervisory frame:
PRI Span: 1 < SAPI: 00  C/R: 0 EA: 0
PRI Span: 1 <  TEI: 000        EA: 1
PRI Span: 1 < Zero: 0     S: 0 01: 1  [ RR (receive ready) ]
PRI Span: 1 < N(R): 000 P/F: 1
PRI Span: 1 < 0 bytes of data
PRI Span: 1 -- Got ACK for N(S)=0 to (but not including) N(S)=0
PRI Span: 1 -- Stopping T200 timer
PRI Span: 1 -- Starting T203 timer
PRI Span: 1 Done handling message for SAPI/TEI=0/0
pri_check_event returned error 22 (Invalid argument)

pjproject:0 sip_transport.c Error processing 430 bytes packet from UDP 192.168.8.121:5061 : PJSIP syntax error exception when parsing '' header on line 2 col 6:

网关的问题, 重启下网关就好了

[Oct 11 13:06:06] ERROR[15471]: pjproject:0 <?>:               sip_transport.c Error processing 430 bytes packet from UDP 192.168.8.121:5061 : PJSIP syntax error exception when parsing '' header on line 2 col 6:
REGISTER sip:192.168.8.125 SIP/2.0
Via: 0/2.0/0 192.168.8.121:5061;rport;branch=z9hG4bK--106272960715076983491506365490
To: <sip:[email protected]>
From: <sip:[email protected]>;tag=15064208101506326178-1
Call-ID: [email protected]
CSeq: 4535 REGISTER
Max-Forwards: 70
Expires: 600
Contact: <sip:[email protected]:5061>
User-Agent: NewRockTech MX8-8S VER.1.9.82.343/UA 2.0
Content-Length: 0


-- end of packet.

res_pjsip.c:3053 ast_sip_create_dialog_uac: Endpoint Could not create dialog to invalid URI

终端未注册上, 在 CONTACTS 中不存在对应记录, 使用 PJSIP 请求所有 CONTACTS 时不会返回任何东西
尝试将终端离线再从新上线

[Oct 11 13:08:04] ERROR[15472]: res_pjsip.c:3053 ast_sip_create_dialog_uac: Endpoint '8380': Could not create dialog to invalid URI '8380'.  Is endpoint registered and reachable?
[Oct 11 13:08:04] ERROR[15472]: chan_pjsip.c:2219 request: Failed to create outgoing session to endpoint '8380'

res_pjsip/config_system.c:236 system_create_resolver_and_set_nameservers: There are no local system nameservers configured, resorting to system resolution

[Sep 11 14:07:32] ERROR[12786]: res_pjsip/config_system.c:236 system_create_resolver_and_set_nameservers: There are no local system nameservers configured, resorting to system resolution

WARNING[14901][c-00000d1b]: res_rtp_asterisk.c:2524 __rtp_recvfrom: PJ ICE Rx error status code: 370401 'Unauthorized'.

发起通话的事件间隔很长

ISSUES

TBD

http://lists.digium.com/pipermail/asterisk-users/2011-May/262225.html

[Sep  5 19:36:48] NOTICE[14195]: chan_dahdi.c:2840 my_handle_dchan_exception: Got DAHDI event: HDLC Abort (6) on D-channel of span 1
[Sep  5 19:36:48] NOTICE[14195]: sig_pri.c:6425 pri_dchannel: pri_check_event returned error 22 (Invalid argument)
  == Primary D-Channel on span 1 down
[Sep  5 19:36:49] WARNING[14195]: sig_pri.c:1211 pri_find_dchan: Span 1: D-channel is down!

[Sep  7 00:17:21] VERBOSE[9550][C-00000005] res_rtp_asterisk.c: PT: 202(Unknown)
[Sep  7 00:17:21] VERBOSE[9550][C-00000005] res_rtp_asterisk.c: Reception reports: 1
[Sep  7 00:17:21] VERBOSE[9550][C-00000005] res_rtp_asterisk.c: SSRC of sender: 324822813
[Sep  7 00:17:21] VERBOSE[9550][C-00000005] res_rtp_asterisk.c: Received an SDES from 192.168.8.120:10021

Assertion failed: !"Calling pjlib from unknown/external thread. You must " "register external threads with pj_thread_register() " "before calling any pjlib functions." (../src/pj/os_core_unix.c: pj_thread_this: 692)

[Sep 18 14:11:42] DEBUG[6106][C-00000002] res_rtp_asterisk.c: Got RTCP report of 64 bytes
[Sep 18 14:11:42] DEBUG[6106][C-00000002] res_rtp_asterisk.c: 0x5651ad288300 -- Received RTCP report from 192.168.9.126:12723, dropping due to strict RTP protection. Received SSRC '1930845172' but expected '912503297'
[Sep 18 14:11:42] DEBUG[6106][C-00000002] res_rtp_asterisk.c: 0x5651ad288300 -- Received RTCP report from 192.168.9.126:12723, dropping due to strict RTP protection. Received SSRC '1930845172' but expected '912503297'

PRI Span: 2 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
PRI Span: 2 TEI=0 Sending SABME
PRI Span: 2 TEI=0 Sending SABME
PRI Span: 2 TEI=0 Sending SABME
PRI Span: 2 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
PRI Span: 2 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
PRI Span: 2 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
PRI Span: 2 SAPI/TEI=0/0 Kick starting link
PRI Span: 2 TEI=0 Sending SABME
PRI Span: 2 Changing from state 4(TEI assigned) to 5(Awaiting establishment)
PRI Span: 2 TEI=0 Sending SABME
PRI Span: 2 TEI=0 Sending SABME
PRI Span: 2 TEI=0 Sending SABME
PRI Span: 2 TEI=0 MDL-ERROR (G): T200 expired N200 times sending SABME in state 5(Awaiting establishment)
PRI Span: 2 Changing from state 5(Awaiting establishment) to 4(TEI assigned)
PRI Span: 2 TEI=0 DL event: Q931_DL_EVENT_DL_RELEASE_IND(3)
PRI Span: 2 SAPI/TEI=0/0 Kick starting link
PRI Span: 2 TEI=0 Sending SABME
PRI Span: 2 Changing from state 4(TEI assigned) to 5(Awaiting establishment)

PJ ICE Rx error status code: 370401 'Unauthorized'.

[Sep 11 09:48:21] WARNING[13742][C-000000c5]: app_dial.c:1625 wait_for_answer: Unable to write frametype: 2

[Sep 11 09:46:06] NOTICE[29187]: sig_pri.c:6425 pri_dchannel: pri_check_event returned error 22 (Invalid argument)

[Sep 11 09:43:18] WARNING[11181][C-000000bb]: pjsip/dialplan_functions.c:875 pjsip_acf_dial_contacts_read: Specified endpoint '8319' was not found

[Sep 11 14:07:34] ERROR[12741]: codec_dahdi.c:820 find_transcoders: Failed to open /dev/dahdi/transcode: No such file or directory

[Sep 11 14:07:34] ERROR[12741]: app_voicemail.c:2833 inboxcount2: Couldn't find mailbox 1234 in context defaul
[Sep 11 14:07:34] ERROR[12741]: app_voicemail.c:2833 inboxcount2: Couldn't find mailbox 1234 in context other

[Sep 14 11:56:49] WARNING[19345]: sig_pri.c:1211 pri_find_dchan: Span 1: D-channel is down!
[Sep 14 11:57:08] WARNING[21631][C-00000015]: app_dial.c:2530 dial_exec_full: Unable to create channel of type'DAHDI' (cause 34 - Circuit/channel congestion)

S 口网关拨号异常

[ 6756.928153] traps: asterisk[3656] general protection ip:6d92805f5b54 sp:6d92557d86c0 error:0
[ 6756.928157]  in ld-musl-x86_64.so.1[6d92805d2000+89000]
[ 6756.928166] grsec: From 192.168.9.92: Segmentation fault occurred at            (nil) in /usr/sbin/asterisk[asterisk:3656] uid/euid:0/0 gid/egid:0/0, parent /bin/bash[bash:3188] uid/euid:0/0 gid/egid:0/0

  == Primary D-Channel on span 1 down
[Sep 14 12:40:22] WARNING[6093]: sig_pri.c:1211 pri_find_dchan: Span 1: D-channel is down!
  == Primary D-Channel on span 1 up

[Sep 12 09:33:08] ERROR[28000]: codec_dahdi.c:820 find_transcoders: Failed to open /dev/dahdi/transcode: No such file or directory

[255795.036971] dahdi: HDLC Receiver overrun on channel TE2/0/1/16 (master=TE2/0/1/16)
[255962.284877] asterisk[18859]: segfault at 7525fadecbb8 ip 0000752624ecd6bc sp 00007525fb3618c0 error 6 in ld-musl-x86_64.so.1[752624e7d000+89000]
[255962.284889] grsec: From 192.168.10.25: Segmentation fault occurred at 00007525fadecbb8 in /usr/sbin/asterisk[asterisk:18859] uid/euid:0/0 gid/egid:0/0, parent /bin/bash[bash:3484] uid/euid:0/0 gid/egid:0/0

[Sep 11 14:07:33] WARNING[12741]: res_phoneprov.c:1231 get_defaults: Unable to find a valid server address or name.

[Sep 12 11:18:57] NOTICE[25466]: res_pjsip_exten_state.c:418 new_subscribe: Endpoint '8292' state subscription failed: Extension '09198118662' does not exist in context 'inside' or has no associated hint

[Oct 24 15:48:58] ERROR[4217] chan_dahdi.c: PRI Span: 1 !! Not yet handling pre-handle message type USER_INFORMATION (0x20)
[Oct 24 15:48:58] ERROR[4217] chan_dahdi.c: PRI Span: 1 !! Don't know how to pre-handle message type USER_INFORMATION (0x20)

[Jun 29 11:28:22] NOTICE[3759]: res_pjsip_exten_state.c:418 new_subscribe: Endpoint '1009' state subscription failed: Extension '185555555555' does not exist in context 'inside' or has no associated hint

SecurityEvent="ChallengeSent",EventTV="2018-07-02T10:27:12.637+0800",Severity="Informational",Service="PJSIP",EventVersion="1",AccountID="9001",SessionID="90533OTQ3MGM0MzYwNjdjMjk5ODMyZTZmNTE2NzIyZTYwNzc",LocalAddress="IPV4/UDP/192.168.88.125/5060",RemoteAddress="IPV4/UDP/192.168.89.11/60448",Challenge=""
SecurityEvent="SuccessfulAuth",EventTV="2018-07-02T10:27:12.640+0800",Severity="Informational",Service="PJSIP",EventVersion="1",AccountID="9001",SessionID="90533OTQ3MGM0MzYwNjdjMjk5ODMyZTZmNTE2NzIyZTYwNzc",Local$ddress="IPV4/UDP/192.168.88.125/5060",RemoteAddress="IPV4/UDP/192.168.89.11/60448",UsingPassword="1"

[Jul  2 10:39:00] WARNING[32235]: res_rtp_asterisk.c:2524 __rtp_recvfrom: PJ ICE Rx error status code: 370401 'Unauthorized'.

[Jan 15 18:32:43] WARNING[1855]: res_pjsip/config_transport.c:506 transport_apply: Transport 'transport-udp' is not reloadable, maintaining previous values
[Jan 15 18:32:43] WARNING[1904]: res_pjsip/config_transport.c:506 transport_apply: Transport 'transport-tcp' is not reloadable, maintaining previous values
[Jan 15 18:32:43] NOTICE[1904]: res_pjsip/pjsip_distributor.c:676 log_failed_request: Request 'REGISTER' from '<sip:[email protected]>' failed for '10.10.0.3:53380' (callid: 102009Y2Q2N2QwNDMwNjFkZmFiZDZjNWIxMDg5MzMzMzI1YTA) - No matching endpoint found
[Jan 15 18:32:43] NOTICE[1904]: res_pjsip/pjsip_distributor.c:676 log_failed_request: Request 'REGISTER' from '<sip:[email protected]>' failed for '10.10.0.3:53380' (callid: 102009Y2Q2N2QwNDMwNjFkZmFiZDZjNWIxMDg5MzMzMzI1YTA) - No matching endpoint found
[Jan 15 18:32:43] NOTICE[1904]: res_pjsip/pjsip_distributor.c:676 log_failed_request: Request 'REGISTER' from '<sip:[email protected]>' failed for '10.10.0.3:53380' (callid: 102009Y2Q2N2QwNDMwNjFkZmFiZDZjNWIxMDg5MzMzMzI1YTA) - Failed to authenticate

Asterisk 故障排查

系统问题排查​

诊断命令​

From 头包含的地址无效导致解析问题​

接口 originate 一定概率没有声音​

查看通话数量和当前开启通道​

终端问题排查​

常见 HTTP 异常状态码​

Assertion failed: sess && sess->endpt​

Bridge: Could not create class basic. No technology to support it.​

Couldn't execute statement: SQL logic error​

CallerID presentation​

translate.c:407 framein: no samples for ulawtolin​

app_dial.c:2472 dial_exec_full: Dial argument takes format (technology/resource)​

tcptls.c:746 ast_tcptls_close_session_file: ast_tcptls_close_session_file invoked on session instance without file or file descriptor​

Log queue threshold exceeded. Discarding new message​

系统间歇性卡顿​

Error loading module 'res_pjsip.so': undefined symbol: ast_sip_session_register_supplement​

Read factory 0xb6d0acb8 was pretty quick last time, waiting for them / DTMF best​

chan_dahdi.c:19031 process_dahdi: Ignoring any changes to 'userbase' (on reload)​

pbx.c: sent to invalid extension but no invalid handler: context,exten,priority=from-pstn,s,1​

Unable to create channel of type 'DAHDI' (cause 17 - User busy)​

codec_dahdi.c:820 find_transcoders: Failed to open /dev/dahdi/transcode: No such file or directory​

Couldn't find mailbox 1234 in context default​

sig_pri.c:6425 pri_dchannel: pri_check_event returned error 22 (Invalid argument)​

pjproject:0 sip_transport.c Error processing 430 bytes packet from UDP 192.168.8.121:5061 : PJSIP syntax error exception when parsing '' header on line 2 col 6:​

res_pjsip.c:3053 ast_sip_create_dialog_uac: Endpoint Could not create dialog to invalid URI​

res_pjsip/config_system.c:236 system_create_resolver_and_set_nameservers: There are no local system nameservers configured, resorting to system resolution​

WARNING[14901][c-00000d1b]: res_rtp_asterisk.c:2524 __rtp_recvfrom: PJ ICE Rx error status code: 370401 'Unauthorized'.​

发起通话的事件间隔很长​

ISSUES​

TBD​