[asterisk-bugs] [JIRA] (ASTERISK-28131) ${ANSWEREDTIME} is incorrect in Dial app in Asterisk 15.6

Fri Oct 26 01:53:47 CDT 2018

    [ https://issues.asterisk.org/jira/browse/ASTERISK-28131?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=245256#comment-245256 ] 

Kapil Bansal edited comment on ASTERISK-28131 at 10/26/18 1:53 AM:
-------------------------------------------------------------------

Please check below logs for asterisk 15.6 and 11.25 for same dial plan.
Call duration almost same after call answered but value of  ${ANSWEREDTIME} is different.

DialPlan:- 

[test_dial]
exten => s,1,Answer()
exten => s,2,Dial(SIP/99853XXXXXX at LCR1,30,g)
exten => s,3,Verbose(1,"Ansewered Time : ${ANSWEREDTIME}")
exten => s,4,Verbose(1,"Dialeded Time : ${DIALEDTIME}")
exten => s,5,Hangup()

CLI logs for asterisk 15.6 :-

   -- Executing [s at test_dial:1] Answer("SIP/SIP10-0000004e", "") in new stack
       > 0x7fcfe802c740 -- Strict RTP learning after ICE completion
       > 0x7fcfe802c740 -- Strict RTP switching to RTP target address 172.16.15.10:7544 as source
    -- Executing [s at test_dial:2] Dial("SIP/SIP10-0000004e", "SIP/9985392390 at LCR1,30,g") in new stack
  == Using SIP RTP CoS mark 5
    -- Called SIP/9985392390 at LCR1
       > 0x7fd0100660d0 -- Strict RTP learning after remote address set to: 172.16.15.103:32506
    -- SIP/LCR1-0000004f is making progress passing it to SIP/SIP10-0000004e
       > 0x7fd0100660d0 -- Strict RTP switching to RTP target address 172.16.15.103:32506 as source
  == Manager 'kookoo' logged on from 172.16.15.238
  == Manager 'kookoo' logged off from 172.16.15.238
  == Manager 'kookoo' logged on from 172.16.15.238
  == Manager 'kookoo' logged off from 172.16.15.238
       > 0x7fcfe802c740 -- Strict RTP learning complete - Locking on source address 172.16.15.10:7544
       > 0x7fd0100660d0 -- Strict RTP learning complete - Locking on source address 172.16.15.103:32506
    -- SIP/LCR1-0000004f answered SIP/SIP10-0000004e
    -- Channel SIP/LCR1-0000004f joined 'simple_bridge' basic-bridge <637b46a6-2cd8-440f-906b-488a7fb0b705>
    -- Channel SIP/SIP10-0000004e joined 'simple_bridge' basic-bridge <637b46a6-2cd8-440f-906b-488a7fb0b705>
    -- Channel SIP/LCR1-0000004f left 'simple_bridge' basic-bridge <637b46a6-2cd8-440f-906b-488a7fb0b705>
    -- Channel SIP/SIP10-0000004e left 'simple_bridge' basic-bridge <637b46a6-2cd8-440f-906b-488a7fb0b705>
    -- Executing [s at test_dial:3] Verbose("SIP/SIP10-0000004e", "1,"Ansewered Time : 8"") in new stack
 "Ansewered Time : 8"
    -- Executing [s at test_dial:4] Verbose("SIP/SIP10-0000004e", "1,"Dialeded Time : 8"") in new stack
 "Dialeded Time : 8"
    -- Executing [s at test_dial:5] Hangup("SIP/SIP10-0000004e", "") in new stack

CLI Logs for asterisk 11.25 :- 

    -- Executing [s at test_dial:1] Answer("SIP/LCR-000001c0", "") in new stack
    -- Executing [s at test_dial:2] Dial("SIP/LCR-000001c0", "SIP/9985392390 at LCR1,30,g") in new stack
  == Using SIP RTP CoS mark 5
    -- Called SIP/9985392390 at LCR1
    -- SIP/LCR1-000001c1 is making progress passing it to SIP/LCR-000001c0
  == Manager 'kookoo' logged on from 172.16.15.103
    -- SIP/LCR1-000001c1 answered SIP/LCR-000001c0
    -- Locally bridging SIP/LCR-000001c0 and SIP/LCR1-000001c1
    -- Executing [s at test_dial:3] Verbose("SIP/LCR-000001c0", "1,"Ansewered Time : 3"") in new stack
 "Ansewered Time : 3"
    -- Executing [s at test_dial:4] Verbose("SIP/LCR-000001c0", "1,"Dialeded Time : 11"") in new stack
 "Dialeded Time : 11"
    -- Executing [s at test_dial:5] Hangup("SIP/LCR-000001c0", "") in new stack
  == Spawn extension (test_dial, s, 5) exited non-zero on 'SIP/LCR-000001c0'

was (Author: kkbansal004 at gmail.com):
Please check below logs for asterisk 15.6 and 11.25 for same dial plan.
Call duration almost same after call answered but value of  ${ANSWEREDTIME} is different.

DialPlan:- 

[test_dial]
exten => s,1,Answer()
exten => s,2,Dial(SIP/9985392390 at LCR1,30,g)
exten => s,3,Verbose(1,"Ansewered Time : ${ANSWEREDTIME}")
exten => s,4,Verbose(1,"Dialeded Time : ${DIALEDTIME}")
exten => s,5,Hangup()

CLI logs for asterisk 15.6 :-

   -- Executing [s at test_dial:1] Answer("SIP/SIP10-0000004e", "") in new stack
       > 0x7fcfe802c740 -- Strict RTP learning after ICE completion
       > 0x7fcfe802c740 -- Strict RTP switching to RTP target address 172.16.15.10:7544 as source
    -- Executing [s at test_dial:2] Dial("SIP/SIP10-0000004e", "SIP/9985392390 at LCR1,30,g") in new stack
  == Using SIP RTP CoS mark 5
    -- Called SIP/9985392390 at LCR1
       > 0x7fd0100660d0 -- Strict RTP learning after remote address set to: 172.16.15.103:32506
    -- SIP/LCR1-0000004f is making progress passing it to SIP/SIP10-0000004e
       > 0x7fd0100660d0 -- Strict RTP switching to RTP target address 172.16.15.103:32506 as source
  == Manager 'kookoo' logged on from 172.16.15.238
  == Manager 'kookoo' logged off from 172.16.15.238
  == Manager 'kookoo' logged on from 172.16.15.238
  == Manager 'kookoo' logged off from 172.16.15.238
       > 0x7fcfe802c740 -- Strict RTP learning complete - Locking on source address 172.16.15.10:7544
       > 0x7fd0100660d0 -- Strict RTP learning complete - Locking on source address 172.16.15.103:32506
    -- SIP/LCR1-0000004f answered SIP/SIP10-0000004e
    -- Channel SIP/LCR1-0000004f joined 'simple_bridge' basic-bridge <637b46a6-2cd8-440f-906b-488a7fb0b705>
    -- Channel SIP/SIP10-0000004e joined 'simple_bridge' basic-bridge <637b46a6-2cd8-440f-906b-488a7fb0b705>
    -- Channel SIP/LCR1-0000004f left 'simple_bridge' basic-bridge <637b46a6-2cd8-440f-906b-488a7fb0b705>
    -- Channel SIP/SIP10-0000004e left 'simple_bridge' basic-bridge <637b46a6-2cd8-440f-906b-488a7fb0b705>
    -- Executing [s at test_dial:3] Verbose("SIP/SIP10-0000004e", "1,"Ansewered Time : 8"") in new stack
 "Ansewered Time : 8"
    -- Executing [s at test_dial:4] Verbose("SIP/SIP10-0000004e", "1,"Dialeded Time : 8"") in new stack
 "Dialeded Time : 8"
    -- Executing [s at test_dial:5] Hangup("SIP/SIP10-0000004e", "") in new stack

CLI Logs for asterisk 11.25 :- 

    -- Executing [s at test_dial:1] Answer("SIP/LCR-000001c0", "") in new stack
    -- Executing [s at test_dial:2] Dial("SIP/LCR-000001c0", "SIP/9985392390 at LCR1,30,g") in new stack
  == Using SIP RTP CoS mark 5
    -- Called SIP/9985392390 at LCR1
    -- SIP/LCR1-000001c1 is making progress passing it to SIP/LCR-000001c0
  == Manager 'kookoo' logged on from 172.16.15.103
    -- SIP/LCR1-000001c1 answered SIP/LCR-000001c0
    -- Locally bridging SIP/LCR-000001c0 and SIP/LCR1-000001c1
    -- Executing [s at test_dial:3] Verbose("SIP/LCR-000001c0", "1,"Ansewered Time : 3"") in new stack
 "Ansewered Time : 3"
    -- Executing [s at test_dial:4] Verbose("SIP/LCR-000001c0", "1,"Dialeded Time : 11"") in new stack
 "Dialeded Time : 11"
    -- Executing [s at test_dial:5] Hangup("SIP/LCR-000001c0", "") in new stack
  == Spawn extension (test_dial, s, 5) exited non-zero on 'SIP/LCR-000001c0'

> ${ANSWEREDTIME} is incorrect in Dial app in Asterisk 15.6
> ---------------------------------------------------------
>
>                 Key: ASTERISK-28131
>                 URL: https://issues.asterisk.org/jira/browse/ASTERISK-28131
>             Project: Asterisk
>          Issue Type: Bug
>      Security Level: None
>          Components: Applications/app_dial
>    Affects Versions: 15.6.0, 15.6.1
>         Environment: Centos 6.9
>            Reporter: Kapil Bansal
>            Assignee: Unassigned
>            Severity: Critical
>
> $\{ANSWEREDTIME} is incorrect and same as $\{DIALEDTIME} in Dial app in Asterisk 15.6

--
This message was sent by Atlassian JIRA
(v6.2#6252)