Need some help please - I have two IVRs… one for normal business hours and one for after hours.
The normal business hours IVR will not accept DTMF (from any source) and the after hours IVR works fine. The only difference between the two is the one that isn’t working is the destination of a Ring Group.
I’m running an SIP only build with Asterisk 11.21.0 and FreePBX 13.0.106 using a Raspberry Pi 3 and Yealink phones.
Any thoughts as to why the Ring Group is screwing up the IVR?
I also tried creating a new ring group, but this had the same result.
The way the system is set up, an incoming call will pass through a time condition that only passes the call to the ring group if it is within normal business hours. This ring group then rings all extensions. If no one answers, the call is sent to the main IVR. The caller can then select one of the people announced in the menu and dial a single number digit to be sent to that person’s extension.
I verified that this same IVR works fine if it is not the destination of the ring group… so the ring group is the cause of the IVR losing the ability to process a DTMF (from any source - internal, external, cell phone, etc.)
Any ideas here? Anyone??
We’re a little short on information, and you’re the only one that’s reporting a problem like this that didn’t mess up their IVR choices. A log extract from a phone call failing would be really helpful and waste a lot less time than us randomly guessing what the problems might be, then sending you on pointless trips down the rabbit hole to a destination that has nothing to do with the problems.
These are incoming calls from outside, not from your Yealinks, right?
The fact that you pass through a time-group should NOT have any effect on this. Passing the call to a ring-group, however, might. Check the codec and DTMF digit settings for your phones. You might be short-circuiting something on the way past your phones.
There are lots of prior posts about IVRs not working. I, in fact, had one (my phones were not working with external IVRs, but that was a DTMF setting in the phone and a Codec mismatch problem).
Here’s the Asterisk log just prior to a failed test (ringing the PBX from a cell phone using an SIP incoming trunk)
Filter
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 48000khz -> 32000khz’
from format slin48 to slin32, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 48000khz -> 44100khz’
from format slin48 to slin44, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 48000khz -> 96000khz’
from format slin48 to slin96, table cost, 800000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 48000khz -> 192000khz’
from format slin48 to slin192, table cost, 800000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 96000khz -> 8000khz’
from format slin96 to slin, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 96000khz -> 12000khz’
from format slin96 to slin12, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 96000khz -> 16000khz’
from format slin96 to slin16, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 96000khz -> 24000khz’
from format slin96 to slin24, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 96000khz -> 32000khz’
from format slin96 to slin32, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 96000khz -> 44100khz’
from format slin96 to slin44, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 96000khz -> 48000khz’
from format slin96 to slin48, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 96000khz -> 192000khz’
from format slin96 to slin192, table cost, 800000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 192000khz -> 8000khz’
from format slin192 to slin, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 192000khz -> 12000khz’
from format slin192 to slin12, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 192000khz -> 16000khz’
from format slin192 to slin16, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 192000khz -> 24000khz’
from format slin192 to slin24, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 192000khz -> 32000khz’
from format slin192 to slin32, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 192000khz -> 44100khz’
from format slin192 to slin44, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 192000khz -> 48000khz’
from format slin192 to slin48, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707]
translate.c: == Registered translator 'slin 192000khz -> 96000khz’
from format slin192 to slin96, table cost, 850000, computational cost
999999
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: codec_resample.so => (SLIN Resampling Codec)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘IFMODULE’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_module.so => (Checks if Asterisk module is loaded in memory)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘AGC’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘DENOISE’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_speex.so => (Noise reduction and Automatic Gain Control (AGC))
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘SYSINFO’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_sysinfo.so => (System information related functions)
[2016-04-25 16:26:26] VERBOSE[1707] res_fax.c: – Registered handler for ‘Spandsp’ (Spandsp FAX Driver)
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: res_fax_spandsp.so => (Spandsp G.711 and T.38 FAX Technologies)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘DB’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘DB_EXISTS’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘DB_DELETE’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘DB_KEYS’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_db.so => (Database (astdb) related dialplan functions)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SendImage’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_image.so => (Image Transmission Application)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘WaitUntil’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_waituntil.so => (Wait until specified time)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘DumpChan’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_dumpchan.so => (Dump Info About The Calling Channel)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘DIALGROUP’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_dialgroup.so => (Dialgroup dialplan function)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘HANGUPCAUSE’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘HANGUPCAUSE_KEYS’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘HangupCauseClear’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_hangupcause.so => (HANGUPCAUSE related functions and applications)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Dial’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘RetryDial’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_dial.so => (Dialing Application)
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: res_limit.so => (Resource limits)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘AST_CONFIG’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_config.so => (Asterisk configuration file variable access)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ParkAndAnnounce’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_parkandannounce.so => (Call Parking and Announce Application)
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: res_convert.so => (File format conversion CLI command)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘MATH’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘INC’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘DEC’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_math.so => (Mathematical dialplan function)
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘g722tolin’ from format g722 to slin, table cost, 960000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘lintog722’ from format slin to g722, table cost, 825000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘g722tolin16’ from format g722 to slin16, table cost, 900000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘lin16tog722’ from format slin16 to g722, table cost, 600000, computational cost 10000
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: codec_g722.so => (ITU G.722-64kbps G722 Transcoder)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘DISA’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_disa.so => (DISA (Direct Inward System Access) Application)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘EXTENSION_STATE’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_extstate.so => (Gets an extension’s state in the dialplan)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ControlPlayback’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_controlplayback.so => (Control Playback Application)
[2016-04-25 16:26:26] ERROR[1707] chan_unistim.c: Unable to load config unistim.conf
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘ENUMRESULT’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘ENUMQUERY’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘ENUMLOOKUP’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘TXTCIDNAME’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_enum.so => (ENUM related dialplan functions)
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: res_realtime.so => (Realtime Data Lookup/Rewrite)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SMS’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_sms.so => (SMS/PSTN handler)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘While’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘EndWhile’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ExitWhile’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ContinueWhile’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_while.so => (While Loops and Conditional Execution)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Transfer’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_transfer.so => (Transfers a caller to another extension)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘BASE64_ENCODE’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘BASE64_DECODE’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_base64.so => (base64 encode/decode dialplan functions)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ADSIProg’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_adsiprog.so => (Asterisk ADSI Programming Application)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Log’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Verbose’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_verbose.so => (Send verbose output)
[2016-04-25 16:26:26] ERROR[1707] pbx_dundi.c: Unable to load config dundi.conf
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: pbx_realtime.so => (Realtime Switch)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘AES_DECRYPT’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘AES_ENCRYPT’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_aes.so => (AES dialplan functions)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SendText’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_sendtext.so => (Send Text Applications)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘SPRINTF’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_sprintf.so => (SPRINTF dialplan function)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘URIDECODE’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘URIENCODE’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_uri.so => (URI encode/decode dialplan functions)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘TestClient’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘TestServer’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_test.so => (Interface Test Application)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ExternalIVR’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_externalivr.so => (External IVR Interface Application)
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘gsmtolin’ from format gsm to slin, table cost, 900000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘lintogsm’ from format slin to gsm, table cost, 600000, computational cost 10000
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: codec_gsm.so => (GSM Coder/Decoder)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘TIMEOUT’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_timeout.so => (Channel timeout dialplan functions)
[2016-04-25 16:26:26] VERBOSE[1711] asterisk.c: – Remote UNIX connection
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Zapateller’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_zapateller.so => (Block Telemarketers with Special Information Tone)
[2016-04-25 16:26:26] ERROR[1707] chan_phone.c: Unable to load config phone.conf
[2016-04-25 16:26:26] VERBOSE[1932] asterisk.c: – Remote UNIX connection disconnected
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘ENV’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘STAT’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘FILE’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘FILE_COUNT_LINE’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘FILE_FORMAT’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_env.so => (Environment/filesystem dialplan functions)
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘alawtolin’ from format alaw to slin, table cost, 900000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘lintoalaw’ from format slin to alaw, table cost, 600000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: codec_alaw.so => (A-law Coder/Decoder)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Echo’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_echo.so => (Simple Echo Application)
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘ilbctolin’ from format ilbc to slin, table cost, 900000, computational cost 10000
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘lintoilbc’ from format slin to ilbc, table cost, 600000, computational cost 30000
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: codec_ilbc.so => (iLBC Coder/Decoder)
[2016-04-25 16:26:26] VERBOSE[1707] res_security_log.c: – Security Logging Enabled
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: res_security_log.so => (Security Event Logging)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SpeechCreate’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SpeechLoadGrammar’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SpeechUnloadGrammar’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SpeechActivateGrammar’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SpeechDeactivateGrammar’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SpeechStart’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SpeechBackground’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SpeechDestroy’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SpeechProcessingSound’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘SPEECH’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘SPEECH_SCORE’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘SPEECH_TEXT’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘SPEECH_GRAMMAR’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘SPEECH_ENGINE’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘SPEECH_RESULTS_TYPE’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_speech_utils.so => (Dialplan Speech Applications)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Directory’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_directory.so => (Extension Directory)
[2016-04-25 16:26:26] VERBOSE[1707] bridging.c: == Registered bridge technology multiplexed_bridge
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: bridge_multiplexed.so => (Multiplexed two channel bridging module)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘PITCH_SHIFT’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_pitchshift.so => (Audio Effects Dialplan Functions)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Exec’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘TryExec’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ExecIf’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_exec.so => (Executes dialplan applications)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘ISNULL’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘SET’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘EXISTS’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘IF’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘IFTIME’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘IMPORT’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_logic.so => (Logical dialplan functions)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘GROUP_COUNT’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘GROUP_MATCH_COUNT’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘GROUP_LIST’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘GROUP’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_groupcount.so => (Channel group dialplan functions)
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘alawtoulaw’ from format alaw to ulaw, table cost, 915000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘ulawtoalaw’ from format ulaw to alaw, table cost, 915000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: codec_a_mu.so => (A-law and Mulaw direct Coder/Decoder)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ChannelRedirect’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_channelredirect.so => (Redirects a given channel to a dialplan target)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Originate’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_originate.so => (Originate call)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘PlayTones’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘StopPlayTones’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_playtones.so => (Playtones Application)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘MYSQL’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_mysql.so => (Simple Mysql Interface)
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘adpcmtolin’ from format adpcm to slin, table cost, 900000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘lintoadpcm’ from format slin to adpcm, table cost, 600000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: codec_adpcm.so => (Adaptive Differential PCM Coder/Decoder)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘RAND’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_rand.so => (Random number dialplan function)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘CDR’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_cdr.so => (Call Detail Record (CDR) dialplan function)
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: pbx_spool.so => (Outgoing Spool Support)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘TrySystem’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘System’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_system.so => (Generic System() application)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘AUDIOHOOK_INHERIT’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_audiohookinherit.so => (Audiohook inheritance function)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘CHANNEL’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘CHANNELS’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘MASTER_CHANNEL’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_channel.so => (Channel information dialplan functions)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Read’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_read.so => (Read Variable Application)
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action PlayDTMF
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘SendDTMF’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_senddtmf.so => (Send DTMF digits Application)
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: pbx_loopback.so => (Loopback Switch)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘GetCPEID’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_getcpeid.so => (Get ADSI CPE ID)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ChanSpy’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ExtenSpy’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘DAHDIScan’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_chanspy.so => (Listen to the audio of an active channel)
[2016-04-25 16:26:26] VERBOSE[1707] bridging.c: == Registered bridge technology simple_bridge
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: bridge_simple.so => (Simple two channel bridging module)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘SHA1’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_sha1.so => (SHA-1 computation dialplan function)
[2016-04-25 16:26:26] WARNING[1707] app_festival.c: No such configuration file festival.conf
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘CELGenUserEvent’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_celgenuserevent.so => (Generate an User-Defined CEL event)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘ReadExten’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_readexten.so => (Read and evaluate extension validity)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘BLACKLIST’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_blacklist.so => (Look up Caller*ID name/number from blacklist database)
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: res_format_attr_h263.so => (H.263 Format Attribute Module)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Record’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_record.so => (Trivial Record Application)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘UserEvent’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_userevent.so => (Custom User Event Application)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘LOCK’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘TRYLOCK’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘UNLOCK’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: func_lock.so => (Dialplan mutexes)
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘g726tolin’ from format g726 to slin, table cost, 900000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1711] asterisk.c: – Remote UNIX connection
[2016-04-25 16:26:26] VERBOSE[1939] asterisk.c: – Remote UNIX connection disconnected
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘lintog726’ from format slin to g726, table cost, 600000, computational cost 10000
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘g726aal2tolin’ from format g726aal2 to slin, table cost, 900000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] translate.c: == Registered translator ‘lintog726aal2’ from format slin to g726aal2, table cost, 600000, computational cost 1
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: codec_g726.so => (ITU G.726-32kbps G726 Transcoder)
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘WaitForSilence’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘WaitForNoise’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_waitforsilence.so => (Wait For Silence)
[2016-04-25 16:26:26] NOTICE[1707] app_queue.c: No queuerules.conf file found, queues will not follow penalty rules
[2016-04-25 16:26:26] VERBOSE[1707] config.c: == Parsing ‘/etc/asterisk/queues.conf’: Found
[2016-04-25 16:26:26] VERBOSE[1707] config.c: == Parsing ‘/etc/asterisk/queues_general_additional.conf’: Found
[2016-04-25 16:26:26] VERBOSE[1707] config.c: == Parsing ‘/etc/asterisk/queues_custom_general.conf’: Found
[2016-04-25 16:26:26] VERBOSE[1707] config.c: == Parsing ‘/etc/asterisk/queues_custom.conf’: Found
[2016-04-25 16:26:26] VERBOSE[1707] config.c: == Parsing ‘/etc/asterisk/queues_additional.conf’: Found
[2016-04-25 16:26:26] VERBOSE[1707] config.c: == Parsing ‘/etc/asterisk/queues_post_custom.conf’: Found
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘Queue’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘AddQueueMember’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘RemoveQueueMember’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘PauseQueueMember’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘UnpauseQueueMember’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered application ‘QueueLog’
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action Queues
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueueStatus
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueueSummary
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueueAdd
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueueRemove
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueuePause
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueueLog
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueuePenalty
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueueMemberRingInUse
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueueRule
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueueReload
[2016-04-25 16:26:26] VERBOSE[1707] manager.c: == Manager registered action QueueReset
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘QUEUE_VARIABLES’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘QUEUE_EXISTS’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘QUEUE_MEMBER’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘QUEUE_MEMBER_COUNT’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘QUEUE_MEMBER_LIST’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘QUEUE_WAITING_COUNT’
[2016-04-25 16:26:26] VERBOSE[1707] pbx.c: == Registered custom function ‘QUEUE_MEMBER_PENALTY’
[2016-04-25 16:26:26] VERBOSE[1707] loader.c: app_queue.so => (True Call Queueing)
This problem occurs on input from external cell phone calls, internal calls from one of the Yealinks and external calls from SIP. The problem does not occur on the very same IVR if no ring group is involved. The problem does not occur when the same time conditions dump the call straight into the same IVR.
I have read 15+ similar threads and am sure it is not a DTMF config - because, again, everything works just fine with all the same settings except no ring group.
Thanks for your time and response!
The only non-standard thing I have going on is I am using custom voice prompts and they are invoked using sip_general_custom.conf using the line “language=en” without the quotes.
I’m not sure why you posted this log - there is no incoming call in it anywhere. This is all boot-time stuff (registering codecs and functions, etc.)
From what I am seeing, though, you are using on-the-fly encoding to transcode your incoming data stream to various forms of slin.
So, let’s assume you are using custom voice prompts that are encoded as mp3 files (probably not, but play along for a minute). Once you establish the call using the “mp3” codec, the rest of your call will be processed as “mp3”. I don’t know if the DTMF recognition in Asterisk can handle on-the-fly transcoding of button pushes without them being “in-band”.
So, let’s try this one more time. We need a log on an incoming call going to a ring-group, falling through to your IVR. That should verify what is happening and give us a clue what is actually going on.
Hi Dave,
Thanks for your time and thoughts. Unfortunately the Call Error Log feature was not implemented until v12 …is there a log in /var I can send? Should I enable and send this one?
/var/log/asterisk/asterisk-cdr.log
As far as that on the fly reference goes, all I did was install some voice prompts and turn them on. If this causes some untoward transcoding, I could remove them I suppose. But again, only the ring group causes the issue… DTMF (with custom voice prompts and all) works fine when the IVR isn’t a ring group destination.
So why would only the ring group bring out this problem if anything mentioned is an issue?
/var/log/asterisk/full would be a good start.
Thanks Dave,
OK, here’s a log of an incoming call from a cell phone.
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [14154621401@from-trunk:11] Set(“SIP/Flowroute-00000010”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [14154621401@from-trunk:12] Set(“SIP/Flowroute-00000010”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [14154621401@from-trunk:13] Set(“SIP/Flowroute-00000010”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [14154621401@from-trunk:14] Set(“SIP/Flowroute-00000010”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [14154621401@from-trunk:15] NoOp(“SIP/Flowroute-00000010”, “CallerID Entry Point”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [14154621401@from-trunk:16] Goto(“SIP/Flowroute-00000010”, “timeconditions,1,1”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (timeconditions,1,1)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [1@timeconditions:1] Set(“SIP/Flowroute-00000010”, “DB(TC/1/INUSESTATE)=INUSE”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [1@timeconditions:2] Set(“SIP/Flowroute-00000010”, “DB(TC/1/NOT_INUSESTATE)=NOT_INUSE”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [1@timeconditions:3] GotoIfTime(“SIP/Flowroute-00000010”, “09:00-18:00,mon-fri,,,America/Los_Angeles?truestate”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (timeconditions,1,12)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [1@timeconditions:12] GotoIf(“SIP/Flowroute-00000010”, “0?falsegoto”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [1@timeconditions:13] ExecIf(“SIP/Flowroute-00000010”, “0?Set(DB(TC/1)=)”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [1@timeconditions:14] Set(“SIP/Flowroute-00000010”, “DEVICE_STATE(Custom:TC1)=NOT_INUSE”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [1@timeconditions:15] ExecIf(“SIP/Flowroute-00000010”, “0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [1@timeconditions:16] GotoIf(“SIP/Flowroute-00000010”, “1?ext-group,422,1”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (ext-group,422,1)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:1] Progress(“SIP/Flowroute-00000010”, “”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:2] Macro(“SIP/Flowroute-00000010”, “user-callerid,”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/Flowroute-00000010”, “TOUCH_MONITOR=1461687583.20”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:2] Set(“SIP/Flowroute-00000010”, “AMPUSER=+14154076719”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“SIP/Flowroute-00000010”, “0?report”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“SIP/Flowroute-00000010”, “1?Set(REALCALLERIDNUM=+14154076719)”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/Flowroute-00000010”, “AMPUSER=”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/Flowroute-00000010”, “0?limit”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/Flowroute-00000010”, “AMPUSERCIDNAME=”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf(“SIP/Flowroute-00000010”, “1?report”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (macro-user-callerid,s,14)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:14] GotoIf(“SIP/Flowroute-00000010”, “0?continue”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:15] Set(“SIP/Flowroute-00000010”, “__TTL=64”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:16] GotoIf(“SIP/Flowroute-00000010”, “1?continue”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (macro-user-callerid,s,27)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:27] Set(“SIP/Flowroute-00000010”, “CALLERID(number)=+14154076719”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/Flowroute-00000010”, “CALLERID(name)=WIRELESS CALLER”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:29] Set(“SIP/Flowroute-00000010”, “CDR(cnum)=+14154076719”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:30] Set(“SIP/Flowroute-00000010”, “CDR(cnam)=WIRELESS CALLER”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-user-callerid:31] Set(“SIP/Flowroute-00000010”, “CHANNEL(language)=en”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:3] Macro(“SIP/Flowroute-00000010”, “blkvm-setifempty,”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-blkvm-setifempty:1] GotoIf(“SIP/Flowroute-00000010”, “1?init”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (macro-blkvm-setifempty,s,4)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-blkvm-setifempty:4] Set(“SIP/Flowroute-00000010”, “__BLKVM_CHANNEL=SIP/Flowroute-00000010”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-blkvm-setifempty:5] Set(“SIP/Flowroute-00000010”, “SHARED(BLKVM,SIP/Flowroute-00000010)=TRUE”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-blkvm-setifempty:6] Set(“SIP/Flowroute-00000010”, “GOSUB_RETVAL=TRUE”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-blkvm-setifempty:7] MacroExit(“SIP/Flowroute-00000010”, “”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:4] GotoIf(“SIP/Flowroute-00000010”, “1?skipov”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (ext-group,422,7)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:7] Set(“SIP/Flowroute-00000010”, “RRNODEST=”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:8] Set(“SIP/Flowroute-00000010”, “__NODEST=422”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:9] GosubIf(“SIP/Flowroute-00000010”, “0?sub-rgsetcid,s,1()”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:10] Set(“SIP/Flowroute-00000010”, “__PICKUPMARK=422”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:11] Gosub(“SIP/Flowroute-00000010”, “sub-record-check,s,1(rg,422,never)”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/Flowroute-00000010”, “12?initialized”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (sub-record-check,s,10)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-check:10] NoOp(“SIP/Flowroute-00000010”, “Recordings initialized”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“SIP/Flowroute-00000010”, “0?Set(ARG3=dontcare)”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/Flowroute-00000010”, “REC_POLICY_MODE_SAVE=”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“SIP/Flowroute-00000010”, “0?Set(REC_STATUS=NO)”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-check:14] GotoIf(“SIP/Flowroute-00000010”, “2?checkaction”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (sub-record-check,s,17)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-check:17] GotoIf(“SIP/Flowroute-00000010”, “0?sub-record-check,rg,1”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-check:18] NoOp(“SIP/Flowroute-00000010”, “Generic rg Recording Check - +14154076719 422”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-check:19] Gosub(“SIP/Flowroute-00000010”, “recordcheck,1(never,rg,422)”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [recordcheck@sub-record-check:1] NoOp(“SIP/Flowroute-00000010”, “Starting recording check against never”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [recordcheck@sub-record-check:2] Goto(“SIP/Flowroute-00000010”, “never”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (sub-record-check,recordcheck,14)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [recordcheck@sub-record-check:14] Set(“SIP/Flowroute-00000010”, “__REC_POLICY_MODE=NEVER”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [recordcheck@sub-record-check:15] Goto(“SIP/Flowroute-00000010”, “stoprec”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Goto (sub-record-check,recordcheck,25)
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [recordcheck@sub-record-check:25] NoOp(“SIP/Flowroute-00000010”, “Stopping recording: rg, 422”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [recordcheck@sub-record-check:26] Set(“SIP/Flowroute-00000010”, “__REC_STATUS=STOPPED”) in new stack
[2016-04-26 09:19:43] VERBOSE[10031][C-00000009] pbx.c: – Executing [recordcheck@sub-record-check:27] System(“SIP/Flowroute-00000010”, "/var/lib/asterisk/bin/stoprecording.php “SIP/Flowroute-00000010"”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [recordcheck@sub-record-check:28] Return(“SIP/Flowroute-00000010”, “”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-check:20] Return(“SIP/Flowroute-00000010”, “”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:12] Set(“SIP/Flowroute-00000010”, “RingGroupMethod=ringall”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:13] Macro(“SIP/Flowroute-00000010”, “dial,20,Ttr,402-403”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:1] NoOp(“SIP/Flowroute-00000010”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:2] ExecIf(“SIP/Flowroute-00000010”, “0?Set(ALERT_INFO=)”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:3] ExecIf(“SIP/Flowroute-00000010”, “0?Set(ALERT_INFO=)”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:4] ExecIf(“SIP/Flowroute-00000010”, “0?Set(ALERT_INFO=)”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:5] ExecIf(“SIP/Flowroute-00000010”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:6] AGI(“SIP/Flowroute-00000010”, “dialparties.agi”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: dialparties.agi: Caller ID name is ‘WIRELESS CALLER’ number is ‘+14154076719’
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – dialparties.agi: Added extension 402 to extension map
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – dialparties.agi: Added extension 403 to extension map
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – dialparties.agi: Extension 402 cf is disabled
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – dialparties.agi: Extension 403 cf is disabled
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – dialparties.agi: Extension 402 do not disturb is disabled
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – dialparties.agi: Extension 403 do not disturb is disabled
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – dialparties.agi: dbset CALLTRACE/402 to +14154076719
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – dialparties.agi: dbset CALLTRACE/403 to +14154076719
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – dialparties.agi: Filtered ARG3: 402-403
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] res_agi.c: – <SIP/Flowroute-00000010>AGI Script dialparties.agi completed, returning 0
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:9] NoOp(“SIP/Flowroute-00000010”, “Returned from dialparties with groups to dial”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:10] Set(“SIP/Flowroute-00000010”, “LOOPCNT=2”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:11] Set(“SIP/Flowroute-00000010”, “ITER=1”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:12] Set(“SIP/Flowroute-00000010”, “EXTTOCALL=402”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:13] NoOp(“SIP/Flowroute-00000010”, “Working with 402”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:14] Set(“SIP/Flowroute-00000010”, “ITER=2”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:15] GotoIf(“SIP/Flowroute-00000010”, “1?ndloopbegin”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Goto (macro-dial,s,12)
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:12] Set(“SIP/Flowroute-00000010”, “EXTTOCALL=403”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:13] NoOp(“SIP/Flowroute-00000010”, “Working with 403”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:14] Set(“SIP/Flowroute-00000010”, “ITER=3”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:15] GotoIf(“SIP/Flowroute-00000010”, “0?ndloopbegin”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:16] Macro(“SIP/Flowroute-00000010”, “dial-ringall-predial-hook,”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial-ringall-predial-hook:1] MacroExit(“SIP/Flowroute-00000010”, “”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:17] Dial(“SIP/Flowroute-00000010”, “SIP/402&SIP/403,20,trM(auto-blkvm)b(func-apply-sipheaders^s^1),”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] netsock2.c: == Using SIP RTP TOS bits 184
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] netsock2.c: == Using SIP RTP CoS mark 5
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] netsock2.c: == Using SIP RTP TOS bits 184
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] netsock2.c: == Using SIP RTP CoS mark 5
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_stack.c: – SIP/402-00000011 Internal Gosub(func-apply-sipheaders,s,1) start
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@func-apply-sipheaders:1] NoOp(“SIP/402-00000011”, “Applying SIP Headers to channel”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@func-apply-sipheaders:2] Set(“SIP/402-00000011”, “SIPHEADERKEYS=”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@func-apply-sipheaders:3] While(“SIP/402-00000011”, “0”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_while.c: – Jumping to priority 7
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@func-apply-sipheaders:8] Return(“SIP/402-00000011”, “”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_stack.c: == Spawn extension (from-internal, 422, 1) exited non-zero on ‘SIP/402-00000011’
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_stack.c: – SIP/402-00000011 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_stack.c: – SIP/403-00000012 Internal Gosub(func-apply-sipheaders,s,1) start
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@func-apply-sipheaders:1] NoOp(“SIP/403-00000012”, “Applying SIP Headers to channel”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@func-apply-sipheaders:2] Set(“SIP/403-00000012”, “SIPHEADERKEYS=”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@func-apply-sipheaders:3] While(“SIP/403-00000012”, “0”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_while.c: – Jumping to priority 7
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@func-apply-sipheaders:8] Return(“SIP/403-00000012”, “”) in new stack
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_stack.c: == Spawn extension (from-internal, 422, 1) exited non-zero on ‘SIP/403-00000012’
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_stack.c: – SIP/403-00000012 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_dial.c: – Called SIP/402
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_dial.c: – Called SIP/403
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_dial.c: – SIP/403-00000012 connected line has changed. Saving it until answer for SIP/Flowroute-00000010
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_dial.c: – SIP/402-00000011 connected line has changed. Saving it until answer for SIP/Flowroute-00000010
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_dial.c: – SIP/403-00000012 is ringing
[2016-04-26 09:19:44] VERBOSE[10031][C-00000009] app_dial.c: – SIP/402-00000011 is ringing
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] app_dial.c: – Nobody picked up in 20000 ms
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:18] Set(“SIP/Flowroute-00000010”, “DIALSTATUS=NOANSWER”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:19] GosubIf(“SIP/Flowroute-00000010”, “0?NOANSWER,1()”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:20] NoOp(“SIP/Flowroute-00000010”, “Returning since nobody answered”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-dial:21] MacroExit(“SIP/Flowroute-00000010”, “”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:14] Gosub(“SIP/Flowroute-00000010”, “sub-record-cancel,s,1()”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@sub-record-cancel:1] Return(“SIP/Flowroute-00000010”, “”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:15] Set(“SIP/Flowroute-00000010”, “RingGroupMethod=”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:16] GotoIf(“SIP/Flowroute-00000010”, “0?nodest”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:17] Set(“SIP/Flowroute-00000010”, “__PICKUPMARK=”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:18] Set(“SIP/Flowroute-00000010”, “__NODEST=”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:19] Macro(“SIP/Flowroute-00000010”, “blkvm-clr,”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-blkvm-clr:1] Set(“SIP/Flowroute-00000010”, “SHARED(BLKVM,SIP/Flowroute-00000010)=”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-blkvm-clr:2] Set(“SIP/Flowroute-00000010”, “GOSUB_RETVAL=”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/Flowroute-00000010”, “”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [422@ext-group:20] Goto(“SIP/Flowroute-00000010”, “ivr-3,s,1”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Goto (ivr-3,s,1)
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:1] Set(“SIP/Flowroute-00000010”, “TIMEOUT_LOOPCOUNT=0”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:2] Set(“SIP/Flowroute-00000010”, “INVALID_LOOPCOUNT=0”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:3] Set(“SIP/Flowroute-00000010”, “_IVR_CONTEXT_ivr-3=”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:4] Set(“SIP/Flowroute-00000010”, “_IVR_CONTEXT=ivr-3”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:5] Set(“SIP/Flowroute-00000010”, “__IVR_RETVM=”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:6] GotoIf(“SIP/Flowroute-00000010”, “0?skip”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:7] Answer(“SIP/Flowroute-00000010”, “”) in new stack
[2016-04-26 09:20:04] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:8] Wait(“SIP/Flowroute-00000010”, “1”) in new stack
[2016-04-26 09:20:05] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:9] Set(“SIP/Flowroute-00000010”, “IVR_MSG=custom/posy1_louder_joan”) in new stack
[2016-04-26 09:20:05] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:10] Set(“SIP/Flowroute-00000010”, “TIMEOUT(digit)=3”) in new stack
[2016-04-26 09:20:05] VERBOSE[10031][C-00000009] func_timeout.c: – Digit timeout set to 3.000
[2016-04-26 09:20:05] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:11] ExecIf(“SIP/Flowroute-00000010”, “1?Background(custom/posy1_louder_joan)”) in new stack
[2016-04-26 09:20:05] VERBOSE[10031][C-00000009] file.c: – <SIP/Flowroute-00000010> Playing ‘custom/posy1_louder_joan.slin’ (language ‘en’)
[2016-04-26 09:20:23] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:12] WaitExten(“SIP/Flowroute-00000010”, “0,”) in new stack
[2016-04-26 09:20:33] VERBOSE[10031][C-00000009] pbx.c: – Timeout on SIP/Flowroute-00000010, going to ‘t’
[2016-04-26 09:20:33] VERBOSE[10031][C-00000009] pbx.c: – Executing [t@ivr-3:1] Set(“SIP/Flowroute-00000010”, “TIMEOUT_LOOPCOUNT=1”) in new stack
[2016-04-26 09:20:33] VERBOSE[10031][C-00000009] pbx.c: – Executing [t@ivr-3:2] GotoIf(“SIP/Flowroute-00000010”, “0?final”) in new stack
[2016-04-26 09:20:33] VERBOSE[10031][C-00000009] pbx.c: – Executing [t@ivr-3:3] Set(“SIP/Flowroute-00000010”, “IVR_MSG=no-valid-responce-pls-try-again”) in new stack
[2016-04-26 09:20:33] VERBOSE[10031][C-00000009] pbx.c: – Executing [t@ivr-3:4] Goto(“SIP/Flowroute-00000010”, “s,start”) in new stack
[2016-04-26 09:20:33] VERBOSE[10031][C-00000009] pbx.c: – Goto (ivr-3,s,10)
[2016-04-26 09:20:33] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:10] Set(“SIP/Flowroute-00000010”, “TIMEOUT(digit)=3”) in new stack
[2016-04-26 09:20:33] VERBOSE[10031][C-00000009] func_timeout.c: – Digit timeout set to 3.000
[2016-04-26 09:20:33] VERBOSE[10031][C-00000009] pbx.c: – Executing [s@ivr-3:11] ExecIf(“SIP/Flowroute-00000010”, “1?Background(no-valid-responce-pls-try-again)”) in new stack
[2016-04-26 09:20:33] VERBOSE[10031][C-00000009] file.c: – <SIP/Flowroute-00000010> Playing ‘no-valid-responce-pls-try-again.ulaw’ (language ‘en’)
[2016-04-26 09:20:35] NOTICE[1825] chan_sip.c: Disconnecting call ‘SIP/Flowroute-00000010’ for lack of RTP activity in 31 seconds
[2016-04-26 09:20:35] VERBOSE[10031][C-00000009] pbx.c: == Spawn extension (ivr-3, s, 11) exited non-zero on ‘SIP/Flowroute-00000010’
[2016-04-26 09:20:35] VERBOSE[10031][C-00000009] pbx.c: – Executing [h@ivr-3:1] Hangup(“SIP/Flowroute-00000010”, “”) in new stack
[2016-04-26 09:20:35] VERBOSE[10031][C-00000009] pbx.c: == Spawn extension (ivr-3, h, 1) exited non-zero on ‘SIP/Flowroute-00000010’
Most cell phone system expect the ulaw codec; some will also accept gsm.
At this point in the flow, you set the codec for the call to slin.
And at this point, the system switches the connection back to ulaw.
At this point, the call is terminated because it hasn’t received an RTP packet in 31 seconds.
It could be a codec issue, as I said earlier. Are you hear all of the prompts from the system? It could also be a firewall issue (I doubt it) but the switching back and forth from slin to ulaw doesn’t seem like it would be helping.
Hi Dave, thanks again.
I do hear all the prompts in the system except for this one, specific, broken IVR where it plays the “posy1_louder_joan” message and doesn’t accept of respond in any way to DTMF. Again, this very same IVR functions perfectly if invoked in any other way than from a ring group.
When I installed the custom voice prompts, they all came in several codecs including ulaw, sln, sln16, alaw, gsm, wav8k, g729 and g722. What conf file tells the IVR which codec to use and why is the problem only occurring when it’s a ring group destination?
Maybe I should just nuke the sln sound files?
Appreciate the help.
BTW, the only codecs I have enabled in my SIP Settings are ulaw, alaw, gsm and g726
I just noticed something interesting: look at the timestamps on all of these.
I don’t know if it’s important, but the digit time-out of “3” is completely ignored. The IVR fails instantly instead of waiting the tree seconds it’s supposed to.
Huh. I wonder why that would happen because of a ring group and not otherwise?
I removed all the SLN and SLN16 sounds, rebooted and still the same issue. I also disallowed all but ulaw on the Yealinks and the problem persists.
OK, so we know it isn’t codec transcoding problems.
Look in your extensions*conf files for ivr-3 and make sure it’s making sense - all the code is there and nothing has been removed of messed up.
So I decided to clone the SD card as a backup and just updated the Asterisk build to 13.7.0 - and that completely fixed the problem. I’m testing the system to assure everything is working but so far all is well!
Thanks for the help and the time spent! Apparently there was a bug in the latest Raspberry-Asterisk.org ISO build.