Asterisk DIALSTATUS Issue

Asterisk Version:
Asterisk certified/13.13-cert3 built by root @ sles12-build on a x86_64 running Linux on 2017-04-27 18:26:28 UTC

OS:
SuSE Linux Enterprise Server 12 Service Pack 2.
Linux 28548ivr 4.4.49-92.11-default #1 SMP Fri Feb 17 08:29:30 UTC 2017 (8f9478a) x86_64 x86_64 x86_64 GNU/Linux

When we attempt to get the DIALSTATUS on an outbound call, we are sometimes getting back a status of NOANSWER and others ANSWERED even though we’re always calling the same phone an answering the same way. We cannot seem to figure out why this is happening.

   -- Called dial@outdial
    -- Executing [dial@outdial:1] Set("Local/dial@outdial-00000002;2", "TIMEOUT(response)=3") in new stack
    -- Response timeout set to 3.000
    -- Executing [dial@outdial:2] Set("Local/dial@outdial-00000002;2", "IVR_ANSWER_EXT_HIT=1") in new stack
    -- Executing [dial@outdial:3] NoOp("Local/dial@outdial-00000002;2", "Dial String = SIP/###########@servername") in new stack
    -- Executing [dial@outdial:4] NoOp("Local/dial@outdial-00000002;2", "Dial Timeout = 45") in new stack
    -- Executing [dial@outdial:5] Dial("Local/dial@outdial-00000002;2", "SIP/###########@servername, 45") in new stack
  == Using SIP RTP CoS mark 5
    -- Called SIP/###########@servername
    -- SIP/servername-00000002 is making progress passing it to Local/dial@outdial-00000002;2
    -- Local/dial@outdial-00000002;1 is making progress
    -- SIP/servername-00000002 answered Local/dial@outdial-00000002;2
    -- Local/dial@outdial-00000002;1 answered
    -- Executing [answer@outdial:1] NoOp("Local/dial@outdial-00000002;1", "Dial Status = ") in new stack
    -- Executing [answer@outdial:2] Wait("Local/dial@outdial-00000002;1", "0.5") in new stack
    -- Channel SIP/servername-00000002 joined 'simple_bridge' basic-bridge <9fdb6ca3-d472-4b03-8b17-473485245288>
    -- Channel Local/dial@outdial-00000002;2 joined 'simple_bridge' basic-bridge <9fdb6ca3-d472-4b03-8b17-473485245288>
    -- Executing [answer@outdial:3] Set("Local/dial@outdial-00000002;1", "IVR_ANSWER_EXT_HIT=0") in new stack
    -- Executing [answer@outdial:4] AGI("Local/dial@outdial-00000002;1", "agi://localhost/Outdial.agi") in new stack
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Getting channel status... 6 Getting dial status... NOANSWER)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Setting call in progress: true)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: In progress now set to: true)
    -- AGI Script Executing Application: (Background) Options: (silence/1)
    -- <Local/dial@outdial-00000002;1> Playing 'silence/1.gsm' (language 'en')
    -- AGI Script Executing Application: (AMD) Options: (2500,500,2000,5000,100,50,3,128)
    -- AMD: Local/dial@outdial-00000002;1 4065517251 (N/A) (Fmt: slin)
    -- AMD: initialSilence [2500] greeting [500] afterGreetingSilence [2000] totalAnalysisTime [5000] minimumWordLength [100] betweenWordsSilence [50] maximumNumberOfWords [3] silenceThreshold [128] maximumWordLength [5000]
    -- AMD: Channel [Local/dial@outdial-00000002;1]. Changed state to STATE_IN_SILENCE
    -- AMD: Channel [Local/dial@outdial-00000002;1]. Word detected. iWordsCount:1
    -- AMD: Channel [Local/dial@outdial-00000002;1]. Detected Talk, previous silence duration: 840
    -- AMD: Channel [Local/dial@outdial-00000002;1]. Changed state to STATE_IN_SILENCE
    -- AMD: Channel [Local/dial@outdial-00000002;1]. HUMAN: silenceDuration:2000 afterGreetingSilence:2000
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: AMD Status: HUMAN (Reason for HUMAN: Amount of silence after greeting was more than 2.00 seconds))
    -- AGI Script Executing Application: (wait) Options: (0.0)
    -- <Local/dial@outdial-00000002;1> Playing '/usr/vt/result/speakThis_Local_dial_outdial-00000002_1.ulaw' (escape_digits=) (sample_offset 0) (language 'en')
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Dial status is not empty or unknown. It is: NOANSWER)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Channel is up and has a dial status of NOANSWER)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Is Mobile: false)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Channel not answered.  Call Index (1808).  Dial Status = NOANSWER)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Got Response = false)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Scheduling retry for call index 1808)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Ending Call to SIP/16367552486@asp-ivrproxy-1)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Setting call in progress: false)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: In progress now set to: false)
    -- <Local/dial@outdial-00000002;1>AGI Script agi://localhost/Outdial.agi completed, returning 0
    -- Executing [h@outdial:1] NoOp("Local/dial@outdial-00000002;1", "Oudial Hangup Called") in new stack
    -- Executing [h@outdial:2] NoOp("Local/dial@outdial-00000002;1", "Dial Status = NOANSWER") in new stack
    -- Executing [h@outdial:3] NoOp("Local/dial@outdial-00000002;1", "Hangup Cause = 0") in new stack
    -- Executing [h@outdial:4] NoOp("Local/dial@outdial-00000002;1", "Is Sip = true") in new stack
    -- Executing [h@outdial:5] NoOp("Local/dial@outdial-00000002;1", "IVR_ANSWER_EXT_HIT = 0") in new stack
    -- Executing [h@outdial:6] GotoIf("Local/dial@outdial-00000002;1", "0?cleanup") in new stack
    -- Executing [h@outdial:7] GotoIf("Local/dial@outdial-00000002;1", "0?cleanup") in new stack
    -- Executing [h@outdial:8] Set("Local/dial@outdial-00000002;1", "OLD_VAL=true") in new stack
    -- Executing [h@outdial:9] Set("Local/dial@outdial-00000002;1", "IVR_RUN_CLEANUP=true") in new stack
    -- Executing [h@outdial:10] NoOp("Local/dial@outdial-00000002;1", "IVR_RUN_CLEANUP=true") in new stack
    -- Executing [h@outdial:11] AGI("Local/dial@outdial-00000002;1", "agi://localhost/Cleanup.agi") in new stack
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Cleanup complete.)
    -- AGI Script Executing Application: (NoOp) Options: (Local/dial@outdial-00000002;1: Setting call inactive.)
    -- <Local/dial@outdial-00000002;1>AGI Script agi://localhost/Cleanup.agi completed, returning 0
    -- Auto fallthrough, channel 'Local/dial@outdial-00000002;1' status is 'NOANSWER'
    -- Executing [h@outdial:1] NoOp("Local/dial@outdial-00000002;1", "Oudial Hangup Called") in new stack
    -- Executing [h@outdial:2] NoOp("Local/dial@outdial-00000002;1", "Dial Status = NOANSWER") in new stack
    -- Executing [h@outdial:3] NoOp("Local/dial@outdial-00000002;1", "Hangup Cause = 0") in new stack
    -- Executing [h@outdial:4] NoOp("Local/dial@outdial-00000002;1", "Is Sip = true") in new stack
    -- Executing [h@outdial:5] NoOp("Local/dial@outdial-00000002;1", "IVR_ANSWER_EXT_HIT = 0") in new stack
    -- Executing [h@outdial:6] GotoIf("Local/dial@outdial-00000002;1", "0?cleanup") in new stack
    -- Executing [h@outdial:7] GotoIf("Local/dial@outdial-00000002;1", "0?cleanup") in new stack
    -- Executing [h@outdial:8] Set("Local/dial@outdial-00000002;1", "OLD_VAL=true") in new stack
    -- Executing [h@outdial:9] Set("Local/dial@outdial-00000002;1", "IVR_RUN_CLEANUP=true") in new stack
    -- Executing [h@outdial:10] NoOp("Local/dial@outdial-00000002;1", "IVR_RUN_CLEANUP=true") in new stack
    -- Executing [h@outdial:11] AGI("Local/dial@outdial-00000002;1", "agi://localhost/Cleanup.agi") in new stack
    -- <Local/dial@outdial-00000002;1>AGI Script agi://localhost/Cleanup.agi completed, returning 0
    -- Channel Local/dial@outdial-00000002;2 left 'simple_bridge' basic-bridge <9fdb6ca3-d472-4b03-8b17-473485245288>
    -- Channel SIP/asp-ivrproxy-1-00000002 left 'simple_bridge' basic-bridge <9fdb6ca3-d472-4b03-8b17-473485245288>
  == Spawn extension (outdial, dial, 5) exited non-zero on 'Local/dial@outdial-00000002;2'
    -- Executing [h@outdial:1] NoOp("Local/dial@outdial-00000002;2", "Oudial Hangup Called") in new stack
    -- Executing [h@outdial:2] NoOp("Local/dial@outdial-00000002;2", "Dial Status = ANSWER") in new stack
    -- Executing [h@outdial:3] NoOp("Local/dial@outdial-00000002;2", "Hangup Cause = 16") in new stack
    -- Executing [h@outdial:4] NoOp("Local/dial@outdial-00000002;2", "Is Sip = true") in new stack
    -- Executing [h@outdial:5] NoOp("Local/dial@outdial-00000002;2", "IVR_ANSWER_EXT_HIT = 1") in new stack
    -- Executing [h@outdial:6] GotoIf("Local/dial@outdial-00000002;2", "16?cleanup") in new stack
    -- Goto (outdial,h,11)
    -- Executing [h@outdial:11] AGI("Local/dial@outdial-00000002;2", "agi://localhost/Cleanup.agi") in new stack
    -- <Local/dial@outdial-00000002;2>AGI Script agi://localhost/Cleanup.agi completed, returning 0