Skip to main content

Unknown Features in Asterisk "full" logs on every call, MTE 9.1.4.14

Posted by biznet on Thu, 09/26/2019

I'm having an intermittent issue with one way calling I"m trying to track down and understand the NAT issues but for now I"m seeing these
"features_config.c: Unknown DYNAMIC_FEATURES"

on every call and would like to know if anyone else is seeing this, and should I worry about it? This appears to be where Asterisk is setting up the bridges between our upstream and one of our extensions, this is the end of the many lines for each call and is consistent and intermittently one of the bridges has no audio on it (one way call) generally it is the outbound channel from our system, but not always :

[2019-09-25 16:43:37] VERBOSE[28505][C-00003ebd] netsock2.c: Using SIP RTP TOS bits 184
[2019-09-25 16:43:37] VERBOSE[28505][C-00003ebd] netsock2.c: Using SIP RTP CoS mark 5
[2019-09-25 16:43:37] VERBOSE[28505][C-00003ebd] app_dial.c: Called SIP/+15407501050@Bandwidth.com
[2019-09-25 16:43:37] VERBOSE[28505][C-00003ebd] app_dial.c: SIP/Bandwidth.com-000096b2 is making progress passing it to SIP/2411-CMG-000096b1
[2019-09-25 16:43:40] VERBOSE[28505][C-00003ebd] app_dial.c: SIP/Bandwidth.com-000096b2 is making progress passing it to SIP/2411-CMG-000096b1
[2019-09-25 16:43:40] VERBOSE[28505][C-00003ebd] app_dial.c: SIP/Bandwidth.com-000096b2 requested media update control 26, passing it to SIP/2411-CMG-000096b1
[2019-09-25 16:43:51] VERBOSE[28505][C-00003ebd] app_dial.c: SIP/Bandwidth.com-000096b2 answered SIP/2411-CMG-000096b1
[2019-09-25 16:43:51] WARNING[28505][C-00003ebd] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel SIP/2411-CMG-000096b1.
[2019-09-25 16:43:51] WARNING[28505][C-00003ebd] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel SIP/2411-CMG-000096b1.
[2019-09-25 16:43:51] WARNING[28571][C-00003ebd] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel SIP/Bandwidth.com-000096b2.
[2019-09-25 16:43:51] WARNING[28571][C-00003ebd] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel SIP/Bandwidth.com-000096b2.
[2019-09-25 16:43:51] VERBOSE[28571][C-00003ebd] bridge_channel.c: Channel SIP/Bandwidth.com-000096b2 joined 'simple_bridge' basic-bridge
[2019-09-25 16:43:51] WARNING[28505][C-00003ebd] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel SIP/2411-CMG-000096b1.
[2019-09-25 16:43:51] WARNING[28505][C-00003ebd] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel SIP/2411-CMG-000096b1.
[2019-09-25 16:43:51] VERBOSE[28505][C-00003ebd] bridge_channel.c: Channel SIP/2411-CMG-000096b1 joined 'simple_bridge' basic-bridge


Submitted by volodya on Thu, 09/26/2019 Permalink

Hello,

Is this a new system or it was updated to 9.1.4?

Please show contents of /etc/asterisk/extconfig.conf, /etc/asterisk/features.conf and just in case /etc/asterisk/res_parking.conf configuration files.

Submitted by biznet on Thu, 09/26/2019 Permalink

root@pbx /var/log/asterisk # less /etc/asterisk/extconfig.conf
;
; Static and realtime external configuration
; engine configuration
;
; See https://wiki.asterisk.org/wiki/display/AST/Realtime+Database+Configurat…
; for basic table formatting information.
;
[settings]
iaxusers => mysql,pbxconf,iax_users
iaxpeers => mysql,pbxconf,iax_users
voicemail => mysql,pbxconf,voicemail_users
queues => mysql,pbxconf,queues
queue_members => mysql,pbxconf,queue_members
queue_dmembers => mysql,pbxconf,queue_dmembers
queue_log => mysql,queuelog,queue_log
extensions => mysql,pbxconf,extensions
extensions.conf => mysql,pbxconf,ast_static_config
extensions.include => mysql,pbxconf,ast_static_config
outbound.include => mysql,pbxconf,ast_static_config
inbound.include => mysql,pbxconf,ast_static_config
inbound_actions.include => mysql,pbxconf,ast_static_config
menus.include => mysql,pbxconf,ast_static_config
huntlists.include => mysql,pbxconf,ast_static_config
followme.conf => mysql,pbxconf,ast_static_config
pickupgroups.include => mysql,pbxconf,ast_static_config
meetme => mysql,pbxconf,meetme
features.conf => mysql,pbxconf,ast_static_config
musiconhold.conf => mysql,pbxconf,ast_static_config
hints => mysql,pbxconf,hints
; added by awk@bnt.com 2019-02-11
res_parking.include => mysql,pbxconf,ast_static_config

Submitted by biznet on Thu, 09/26/2019 Permalink

root@pbx /var/log/asterisk # less /etc/asterisk/features.conf
[general]
transferdigittimeout => 3 ; Number of seconds to wait between digits when transfering a call
xfersound = beep ; to indicate an attended transfer is complete
xferfailsound = beeperr ; to indicate a failed transfer
pickupexten = ** ; Configure the pickup extension. Default is *8
featuredigittimeout = 1000 ; Max time (ms) between digits for
; feature activation. Default is 500

[featuremap]
blindxfer => ## ; Blind transfer
atxfer => #* ; Attended transfer
disconnect => #0 ; Disconnect
automon => #9 ; One Touch Record

[applicationmap]
nway-start => *0,caller,Macro,tl-nway-start
nway-inv => *1,self/caller,Macro,tl-nway-ok
nway-noinv => *2,self/caller,Macro,tl-nway-notok
mix-mon => *9,self/both,Macro,tl-recording-helper

Submitted by biznet on Thu, 09/26/2019 Permalink

root@pbx /var/log/asterisk # less /etc/asterisk-/res_parking.include
[parkinglot_CMG]
context = parkedcalls-CMG
parkedmusicclass = default
parkingtime = 180
parkext = 700
parkpos = 701-703
findslot = first

[parkinglot_CMG-D]
context = parkedcalls-CMG
parkedmusicclass = default
parkingtime = 180
parkext = 715
parkpos = 716-718
findslot = first

[parkinglot_CMG-E]
context = parkedcalls-CMG
parkedmusicclass = default
parkingtime = 180
parkext = 720
parkpos = 721-723
findslot = first

Submitted by biznet on Thu, 09/26/2019 Permalink

We had some custom parking lot work done a couple of versions ago, which broke in the last version partially so we fixed it. Not sure how exactly I'm not the tech guy, but the extra parking lots for one of our MTE tenants, CMG, is working. FYI.

Submitted by biznet on Thu, 09/26/2019 Permalink

I may have found this issue in this line which is the only difference in a call that doesn't work with one that does. attached is the snippet from the call that Doesn't work, not the "failed" line:

[2019-09-26 11:06:33] VERBOSE[18971][C-00004328] pbx.c: Executing [onetrunk@macro-tl-dialout-base:4] GotoIf("SIP/2003-BNT-0000a059", "0?failed,1") in new stack

Submitted by biznet on Thu, 09/26/2019 Permalink

So, I don't know if the unknown features is causing our intermittent no audio at the remote end, or if it is the "failed" GotoIf section of the macro-tl-dialout-base in the call logs, both would be good to fix.

Submitted by biznet on Fri, 09/27/2019 Permalink

Apparently I was wrong about the "failed" lines in the logs above, all calls have this whether they worked or not... so ignore that part. I'd still like to know why the
"features_config.c: Unknown DYNAMIC_FEATURES"

since it COULD be related to the no remote audio problem.

Submitted by volodya on Mon, 09/30/2019 Permalink

Hello!

Please remove features.conf => mysql,pbxconf,ast_static_config line from /etc/asterisk/features.conf configuration file. Then apply changes by running reload features in Asterisk CLI and features show to check if your output will include dynamic features mix-mon, nway-inv, nway-noinv and nway-start.

Submitted by biznet on Thu, 10/03/2019 Permalink

Velodya,

AS you can see from my conf files above, the only reference (mysql,pbxconf,ast_static_config) is in the /etc/asterisk/extconfig.conf and there are numerous references please see above. For example in my logs the first appearance of the "issue" on INCOMING calls:

[2019-10-03 11:04:59] VERBOSE[25564][C-000089e6] app_dial.c: SIP/21-RLD-000120c2 is ringing
[2019-10-03 11:04:59] VERBOSE[25564][C-000089e6] app_dial.c: SIP/15-RLD-000120c1 is ringing
[2019-10-03 11:05:01] VERBOSE[25564][C-000089e6] app_dial.c: SIP/21-RLD-000120c2 connected line has changed. Saving it until answer for Local/*900@from-inside-RLD-00001336;2
[2019-10-03 11:05:01] VERBOSE[25564][C-000089e6] app_dial.c: SIP/21-RLD-000120c2 answered Local/*900@from-inside-RLD-00001336;2
[2019-10-03 11:05:01] WARNING[25564][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel Local/*900@from-inside-RLD-00001336;2.
[2019-10-03 11:05:01] WARNING[25564][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel Local/*900@from-inside-RLD-00001336;2.
[2019-10-03 11:05:01] VERBOSE[25562][C-000089e6] app_dial.c: Local/*900@from-inside-RLD-00001336;1 answered SIP/Bandwidth.com-000120c0
[2019-10-03 11:05:01] WARNING[25566][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel Local/*900@from-inside-RLD-00001336;1.
[2019-10-03 11:05:01] WARNING[25566][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel Local/*900@from-inside-RLD-00001336;1.
[2019-10-03 11:05:01] VERBOSE[25566][C-000089e6] bridge_channel.c: Channel Local/*900@from-inside-RLD-00001336;1 joined 'simple_bridge' basic-bridge
[2019-10-03 11:05:01] WARNING[25565][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel SIP/21-RLD-000120c2.
[2019-10-03 11:05:01] WARNING[25565][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel SIP/21-RLD-000120c2.
[2019-10-03 11:05:01] VERBOSE[25565][C-000089e6] bridge_channel.c: Channel SIP/21-RLD-000120c2 joined 'simple_bridge' basic-bridge
[2019-10-03 11:05:01] WARNING[25562][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel SIP/Bandwidth.com-000120c0.
[2019-10-03 11:05:01] WARNING[25562][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel SIP/Bandwidth.com-000120c0.
[2019-10-03 11:05:01] VERBOSE[25562][C-000089e6] bridge_channel.c: Channel SIP/Bandwidth.com-000120c0 joined 'simple_bridge' basic-bridge
[2019-10-03 11:05:01] WARNING[25564][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel Local/*900@from-inside-RLD-00001336;2.
[2019-10-03 11:05:01] WARNING[25564][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel Local/*900@from-inside-RLD-00001336;2.
[2019-10-03 11:05:01] VERBOSE[25564][C-000089e6] bridge_channel.c: Channel Local/*900@from-inside-RLD-00001336;2 joined 'simple_bridge' basic-bridge
[2019-10-03 11:05:01] VERBOSE[25564][C-000089e6] bridge_channel.c: Channel SIP/21-RLD-000120c2 left 'simple_bridge' basic-bridge
[2019-10-03 11:05:01] WARNING[25564][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel SIP/21-RLD-000120c2.
[2019-10-03 11:05:01] WARNING[25564][C-000089e6] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel SIP/21-RLD-000120c2.

Seems to be making a call from features_config.c which is just before or during the bridge setup between our upstream and our asterisk.

I hope this helps.

Submitted by biznet on Thu, 10/03/2019 Permalink

On an OUTBOUND call there are less of them and this is what we see:

[2019-10-03 11:12:53] VERBOSE[26609][C-00008a10] netsock2.c: Using SIP RTP TOS bits 184
[2019-10-03 11:12:53] VERBOSE[26609][C-00008a10] netsock2.c: Using SIP RTP CoS mark 5
[2019-10-03 11:12:53] VERBOSE[26609][C-00008a10] app_dial.c: Called SIP/+15402390612@Bandwidth.com
[2019-10-03 11:12:54] ERROR[1877][C-00008a10] sip/reqresp_parser.c: can not parse name and number from sip header.
[2019-10-03 11:12:54] VERBOSE[26609][C-00008a10] app_dial.c: SIP/Bandwidth.com-00012128 is making progress passing it to SIP/1003-brown-00012127
[2019-10-03 11:12:59] ERROR[1877][C-00008a10] sip/reqresp_parser.c: can not parse name and number from sip header.
[2019-10-03 11:12:59] VERBOSE[26609][C-00008a10] app_dial.c: SIP/Bandwidth.com-00012128 answered SIP/1003-brown-00012127
[2019-10-03 11:12:59] WARNING[26609][C-00008a10] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel SIP/1003-brown-00012127.
[2019-10-03 11:12:59] WARNING[26609][C-00008a10] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel SIP/1003-brown-00012127.
[2019-10-03 11:12:59] WARNING[26611][C-00008a10] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel SIP/Bandwidth.com-00012128.
[2019-10-03 11:12:59] WARNING[26611][C-00008a10] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel SIP/Bandwidth.com-00012128.
[2019-10-03 11:12:59] VERBOSE[26611][C-00008a10] bridge_channel.c: Channel SIP/Bandwidth.com-00012128 joined 'simple_bridge' basic-bridge <3156b26d-9398-41ac-ab67-23392fe96601>
[2019-10-03 11:12:59] WARNING[26609][C-00008a10] features_config.c: Unknown DYNAMIC_FEATURES item 'nway-start' on channel SIP/1003-brown-00012127.
[2019-10-03 11:12:59] WARNING[26609][C-00008a10] features_config.c: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel SIP/1003-brown-00012127.
[2019-10-03 11:12:59] VERBOSE[26609][C-00008a10] bridge_channel.c: Channel SIP/1003-brown-00012127 joined 'simple_bridge' basic-bridge <3156b26d-9398-41ac-ab67-23392fe96601>

What entries in the extconfig.conf file (if any) should I comment out or remove?

Submitted by biznet on Thu, 10/03/2019 Permalink

no DYNAMIC_FEATURES show from the "features show" but this may help, from the CLI output :

[2019-10-03 11:22:22] WARNING[27802][C-00008a3b]: features_config.c:1364 ast_get_chan_applicationmap: Unknown DYNAMIC_FEATURES item 'mix-mon' on channel SIP/Bandwidth.com-000121ab.

Submitted by biznet on Thu, 10/03/2019 Permalink

pbx*CLI> features show
Builtin Feature Default Current
--------------- ------- -------
Pickup *8 *8
Blind Transfer # #
Attended Transfer
One Touch Monitor
Disconnect Call * *
Park Call
One Touch MixMonitor

Dynamic Feature Default Current
--------------- ------- -------
(none)

Feature Groups:
---------------
(none)