Random asterisk unresponsiveness

MacsOffice · June 29, 2019, 8:19pm

I went back to v13.22 but this has happened again. is there anything in these log excerpts that shed light on what happens ? - its been about once a week and its about due again…

The random non responsiveness continues - This is the log at the time it occurs

25 08:28:05] VERBOSE[30192][C-00000025] pbx.c: Executing [s@ivr-1:7] Answer(“SIP/5XXXXXX3-out-00000048”, “”) in new stack
[2019-06-25 08:28:05] VERBOSE[30192][C-00000025] pbx.c: Executing [s@ivr-1:8] Wait(“SIP/5XXXXXX3-out-00000048”, “1”) in new stack
[2019-06-25 08:28:06] VERBOSE[30192][C-00000025] pbx.c: Executing [s@ivr-1:9] Set(“SIP/5XXXXXX3-out-00000048”, “IVR_MSG=custom/ivr-welcome-header&custom/ivr-valley-open-choices”) in new stack
[2019-06-25 08:28:06] VERBOSE[30192][C-00000025] pbx.c: Executing [s@ivr-1:10] Set(“SIP/5XXXXXX3-out-00000048”, “TIMEOUT(digit)=3”) in new stack
[2019-06-25 08:28:06] VERBOSE[30192][C-00000025] func_timeout.c: Digit timeout set to 3.000
[2019-06-25 08:28:06] VERBOSE[30192][C-00000025] pbx.c: Executing [s@ivr-1:11] ExecIf(“SIP/5XXXXXX3-out-00000048”, “1?Background(custom/ivr-welcome-header&custom/ivr-valley-open-choices)”) in new stack
[2019-06-25 08:28:06] VERBOSE[30192][C-00000025] file.c: <SIP/5XXXXXX3-out-00000048> Playing ‘custom/ivr-welcome-header.slin’ (language ‘en’)
[2019-06-25 08:28:17] VERBOSE[30192][C-00000025] file.c: <SIP/5XXXXXX3-out-00000048> Playing ‘custom/ivr-valley-open-choices.slin’ (language ‘en’)
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] pbx.c: Spawn extension (ivr-1, s, 11) exited non-zero on ‘SIP/5XXXXXX3-out-00000048’
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] pbx.c: Executing [h@ivr-1:1] Hangup(“SIP/5XXXXXX3-out-00000048”, “”) in new stack
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] pbx.c: Spawn extension (ivr-1, h, 1) exited non-zero on ‘SIP/5XXXXXX3-out-00000048’
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] app_stack.c: SIP/5XXXXXX3-out-00000048 Internal Gosub(crm-hangup,s,1) start
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/5XXXXXX3-out-00000048”, “Sending Hangup to CRM”) in new stack
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/5XXXXXX3-out-00000048”, “HANGUP CAUSE: 16”) in new stack
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/5XXXXXX3-out-00000048”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/5XXXXXX3-out-00000048”, “MASTER CHANNEL: 1561476485.93 = 1561476485.93”) in new stack
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/5XXXXXX3-out-00000048”, “0?return”) in new stack
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] pbx.c: Executing [s@crm-hangup:6] Set(“SIP/5XXXXXX3-out-00000048”, “__CRM_HANGUP=1”) in new stack
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] pbx.c: Executing [s@crm-hangup:7] AGI(“SIP/5XXXXXX3-out-00000048”, “sangomacrm.agi”) in new stack
[2019-06-25 08:28:34] VERBOSE[30192][C-00000025] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2019-06-25 08:58:08] VERBOSE[2677] loader.c: Reloading module ‘app_voicemail.so’ (Comedian Mail (Voicemail System))
[2019-06-25 08:58:08] WARNING[2677] app_voicemail.c: maxsilence should be less than minsecs or you may get empty messages
[2019-06-25 09:50:23] VERBOSE[11288] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-25 09:50:23] VERBOSE[11288] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-25 09:51:50] VERBOSE[11499] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-25 09:51:50] VERBOSE[11499] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-25 09:52:09] VERBOSE[11649] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-25 09:52:09] VERBOSE[11649] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-25 09:52:43] VERBOSE[11682] netsock2.c: Using SIP RTP TOS bits 184

Issued Reboot -h

[2019-06-25 10:06:07] VERBOSE[13981] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-25 10:07:59] VERBOSE[14220] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-25 10:07:59] VERBOSE[14220] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-25 11:29:40] VERBOSE[15699] asterisk.c: Remote UNIX connection
[2019-06-25 11:30:20] VERBOSE[28243] asterisk.c: Remote UNIX connection disconnected
[2019-06-25 11:30:34] VERBOSE[15664] asterisk.c: Asterisk uncleanly ending (0).
[2019-06-25 11:30:34] VERBOSE[15664] asterisk.c: Executing last minute cleanups
[2019-06-25 11:30:34] VERBOSE[15664] res_musiconhold.c: Destroying musiconhold processes
[2019-06-25 11:30:35] VERBOSE[15664] manager.c: Manager unregistered action DBGet
[2019-06-25 11:30:35] VERBOSE[15664] manager.c: Manager unregistered action DBPut
[2019-06-25 11:30:35] VERBOSE[15664] manager.c: Manager unregistered action DBDel
[2019-06-25 11:30:35] VERBOSE[15664] manager.c: Manager unregistered action DBDelTree
[2019-06-25 11:32:20] Asterisk 13.22.0 built by mockbuild @ jenkins7 on a x86_64 running Linux on 2018-07-25 22:30:39 UTC
[2019-06-25 11:32:20] VERBOSE[5889] message.c: Message handler ‘dialplan’ registered.

And what is this message you have to be kidding?
[2019-06-25 11:32:20] WARNING[5889] presencestate.c: No provider found for label CustomPresence
[2019-06-25 11:32:20] WARNING[5889] presencestate.c: No provider found for label CustomPresence
[2019-06-25 11:32:20] WARNING[5889] presencestate.c: No provider found for label CustomPresence
[2019-06-25 11:32:20] WARNING[5889] pbx.c: Unable to register extension ‘*8670’ priority -1 in ‘park-hints’, already in use
[2019-06-25 11:32:20] WARNING[5889] pbx_config.c: Unable to register extension at line 4505 of /etc/asterisk/extensions_additional.conf
[2019-06-25 11:32:20] WARNING[5889] pbx.c: Extension ‘s’ priority 13 in ‘macro-user-logon’, label ‘gotpass’ already in use at priority 9
[2019-06-25 11:32:20] ERROR[5889] pbx.c: You have to be kidding – add exten ‘’ to context clean? Figure out a name and call me back. Action ignored.

dicko · June 30, 2019, 2:59am

Look in /var/log/messages for events soon after

2019-06-25 09:52:43] VERBOSE[11682] netsock2.c: Using SIP RTP TOS bits 184

MacsOffice · July 1, 2019, 4:25pm

cat messages shows entries starting Jul 1
cat messages-20190623 have entries up to 6/23
cat messages-20190630 has entries starting at 6/25 at 11:30 am which is after the reboot. The event itself appears to not be captured.

Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -A fpbxfirewall -m mark --mark 0x2/0x2 -j fpbxrfw
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -A fpbxfirewall -p udp -m state --state RELATED,ESTABLISHED -j ACCEPT
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -A fpbxfirewall -p udp -m state --state RELATED,ESTABLISHED -j ACCEPT
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -N fpbxlogdrop
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -N fpbxlogdrop
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -A fpbxfirewall -j fpbxlogdrop
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -A fpbxfirewall -j fpbxlogdrop
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -N zone-trusted
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -N zone-trusted
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -A zone-trusted -j ACCEPT
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -A zone-trusted -j ACCEPT
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -N zone-internal
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -N zone-internal
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -A zone-internal -m mark --mark 0x4/0x4
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -A zone-internal -m mark --mark 0x4/0x4
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -N zone-other
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -N zone-other
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -A zone-other -m mark --mark 0x8/0x8
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -A zone-other -m mark --mark 0x8/0x8
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -N zone-external
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -N zone-external
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -A zone-external -m mark --mark 0x10/0x10
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -A zone-external -m mark --mark 0x10/0x10
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -A fpbxrfw -m recent --rcheck --seconds 90 --hitcount 1 --name WHITELIST --rsource -j ACCEPT
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -A fpbxrfw -m recent --rcheck --seconds 90 --hitcount 1 --name WHITELIST --rsource -j ACCEPT
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -A fpbxrfw -m recent --set --name REPEAT --rsource
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -A fpbxrfw -m recent --set --name REPEAT --rsource
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -A fpbxrfw -m recent --set --name DISCOVERED --rsource
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -A fpbxrfw -m recent --set --name DISCOVERED --rsource
Jun 25 11:32:26 pbx php: /sbin/iptables -w5 -W10000 -N fpbxattacker
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -N fpbxattacker
Jun 25 11:32:26 pbx php: /sbin/ip6tables -w5 -W10000 -A fpbxrfw -m recent --rcheck --seconds 10 --hitcount 50 --name REPEAT --rsource -j fpbxattacker

MacsOffice · July 5, 2019, 9:36pm

(keep alive ping because of holidays)

system · July 12, 2019, 9:36pm

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