Help with Auto-Answer/Intercom for Polycom Phones

I have been trying to get the auto-answer function to work on some Polycom phones for one of my customers. I am very new to working with phones and have come to a standstill on troubleshooting this.

I would like to be able to dial *80ext (*80206 for example) to call an extension and have it auto-answer on speaker. I am testing by calling a Polycom 501 from a soft phone (x-lite) I don’t think the soft phone has anything to do with it, but I could be wrong. When I dial *80206, extension 206 rings for 5 seconds and then hangs up. I cannot figure out why it’s hanging up. I think the alert-info is not correct somewhere, but I don’t have a good enough understanding of it to be able to fix it! If anyone could lend me a hand, I’d appreciate it.

There’s some code that I have added to my sip_318.cfg in attempt to make this work:

<alertInfo voIpProt.SIP.alertInfo.1.value="Ring Answer" voIpProt.SIP.alertInfo.1.class="4"/>
<RING_ANSWER se.rt.4.name="Ring Answer" se.rt.4.type="ring-answer" se.rt.4.timeout="2000" se.rt.4.ringer="2" se.rt.4.callWait="6" se.rt.4.mod="1"/>

Here are the related dialplans:

[ Context 'macro-autoanswer' created by 'pbx_config' ]
  's' =>            1. Set(DIAL=${DB(DEVICE/${ARG1}/dial)})       [pbx_config]
                    2. ExecIf($["${DIAL:0:3}" = "ZAP"]?Set(DIAL=DAHDI${DIAL:3})) [pbx_config]
                    3. GotoIf($["${DB(DEVICE/${ARG1}/autoanswer/macro)}" != "" ]?macro) [pbx_config]
                    4. Set(phone=${SIPPEER(${CUT(DIAL,/,2)}:useragent)}) [pbx_config]
                    5. ExecIf($["${phone:0:5}" = "Mitel"]?Set(CALLINFO=Call-Info: <sip:broadworks.net>\;answer-after=0)) [pbx_config]
                    6. ExecIf($["${phone:0:9}" = "Panasonic"]?Set(ALERTINFO=Alert-Info: Intercom)) [pbx_config]
                    7. ExecIf($["${phone:0:7}" = "Polycom"]?Set(ALERTINFO=Alert-Info: info=Auto Answer)) [pbx_config]
                    8. ExecIf($["${phone:0:6}" = "Digium"]?Set(ALERTINFO=Alert-Info: ring-answer)) [pbx_config]
                    9. ExecIf($["${ALERTINFO}" != ""]?SipAddHeader(${ALERTINFO})) [pbx_config]
                    10. ExecIf($["${CALLINFO}" != ""]?SipAddHeader(${CALLINFO})) [pbx_config]
                    11. ExecIf($["${SIPURI}" != ""]?Set(__SIP_URI_OPTIONS=${SIPURI})) [pbx_config]
     [macro]        14. Macro(${DB(DEVICE/${ARG1}/autoanswer/macro)},${ARG1}) [pbx_config]
  Include =>        'macro-autoanswer-custom'                     [pbx_config]

[ Context 'ext-intercom-users' created by 'pbx_config' ]
  '*54' =>          1. Answer()                                   [pbx_config]
                    2. Wait(1)                                    [pbx_config]
                    3. Macro(user-callerid,)                      [pbx_config]
                    4. Set(DB(AMPUSER/${AMPUSER}/intercom)=enabled) [pbx_config]
                    5. Playback(intercom&enabled)                 [pbx_config]
                    6. Macro(hangupcall,)                         [pbx_config]
  '*55' =>          1. Answer()                                   [pbx_config]
                    2. Wait(1)                                    [pbx_config]
                    3. Macro(user-callerid,)                      [pbx_config]
                    4. Set(DB(AMPUSER/${AMPUSER}/intercom)=disabled) [pbx_config]
                    5. Playback(intercom&disabled)                [pbx_config]
                    6. Macro(hangupcall,)                         [pbx_config]
  '*80100' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80101' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80102' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80103' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80104' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80105' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80106' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80110' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80111' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80120' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80121' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80122' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80123' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80130' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80200' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80201' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80202' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80203' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80204' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80205' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80206' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80208' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80209' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80220' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80221' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80301' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80302' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80303' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80304' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80305' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80320' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80321' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80323' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80401' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*80402' =>       1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '*809969' =>      1. Goto(ext-intercom,${EXTEN},1)              [pbx_config]
  '_*54.' =>        1. Answer()                                   [pbx_config]
                    2. Wait(1)                                    [pbx_config]
                    3. Macro(user-callerid,)                      [pbx_config]
                    4. GotoIf($["${DB(AMPUSER/${AMPUSER}/intercom/${EXTEN:3})}" = "allow" ]}?unset) [pbx_config]
                    5. GotoIf($[${DB_EXISTS(AMPUSER/${EXTEN:3}/device)} != 1]?invaliduser) [pbx_config]
                    6. Set(DB(AMPUSER/${AMPUSER}/intercom/${EXTEN:3})=allow) [pbx_config]
                    7. Playback(intercom&enabled&for&extension&number) [pbx_config]
                    8. SayDigits(${EXTEN:3})                      [pbx_config]
                    9. Macro(hangupcall,)                         [pbx_config]
     [unset]        10. dbDeltree(AMPUSER/${AMPUSER}/intercom/${EXTEN:3}) [pbx_config]
                    11. Playback(intercom&enabled&cancelled&for&extension&number) [pbx_config]
                    12. SayDigits(${EXTEN:3})                     [pbx_config]
                    13. Macro(hangupcall,)                        [pbx_config]
     [invaliduser]  14. Playback(extension&number)                [pbx_config]
                    15. SayDigits(${EXTEN:3})                     [pbx_config]
                    16. Playback(is&invalid)                      [pbx_config]
                    17. Macro(hangupcall,)                        [pbx_config]
  '_*55.' =>        1. Answer()                                   [pbx_config]
                    2. Wait(1)                                    [pbx_config]
                    3. Macro(user-callerid,)                      [pbx_config]
                    4. GotoIf($["${DB(AMPUSER/${AMPUSER}/intercom/${EXTEN:3})}" = "deny" ]}?unset2) [pbx_config]
                    5. GotoIf($[${DB_EXISTS(AMPUSER/${EXTEN:3}/device)} != 1]?invaliduser2) [pbx_config]
                    6. Set(DB(AMPUSER/${AMPUSER}/intercom/${EXTEN:3})=deny) [pbx_config]
                    7. Playback(intercom&disabled&for&extension&number) [pbx_config]
                    8. SayDigits(${EXTEN:3})                      [pbx_config]
                    9. Macro(hangupcall,)                         [pbx_config]
     [unset2]       10. dbDeltree(AMPUSER/${AMPUSER}/intercom/${EXTEN:3}) [pbx_config]
                    11. Playback(intercom&disabled&cancelled&for&extension&number) [pbx_config]
                    12. SayDigits(${EXTEN:3})                     [pbx_config]
                    13. Macro(hangupcall,)                        [pbx_config]
     [invaliduser2] 14. Playback(extension&number)                [pbx_config]
                    15. SayDigits(${EXTEN:3})                     [pbx_config]
                    16. Playback(is&invalid)                      [pbx_config]
                    17. Macro(hangupcall,)                        [pbx_config]
  Include =>        'ext-intercom-users-custom'                   [pbx_config]

[ Context 'ext-intercom' created by 'pbx_config' ]
  'page' =>         1. Set(PAGE_CONF=${EPOCH}${RAND(100,999)})    [pbx_config]
                    2. Set(PAGEMODE=PAGE)                         [pbx_config]
                    3. Set(PAGE_MEMBERS=${ARG1})                  [pbx_config]
                    4. Set(PAGE_CONF_OPTS=duplex)                 [pbx_config]
                    5. AGI(page.agi)                              [pbx_config]
                    6. ConfBridge(${PAGE_CONF},qwAG,,)            [pbx_config]
                    7. Hangup()                                   [pbx_config]
  '_*80.' =>        1. Macro(user-callerid,)                      [pbx_config]
                    2. Set(dialnumber=${EXTEN:3})                 [pbx_config]
                    3. Set(INTERCOM_CALL=TRUE)                    [pbx_config]
                    4. GotoIf($["${DB(AMPUSER/${AMPUSER}/intercom/block)}" = "blocked"]?end) [pbx_config]
                    5. GotoIf($["${DB(DND/${dialnumber})}" = "YES"]?end) [pbx_config]
                    6. GotoIf($["${DB(AMPUSER/${dialnumber}/intercom/${AMPUSER})}" = "allow" ]?allow) [pbx_config]
                    7. GotoIf($["${DB(AMPUSER/${dialnumber}/intercom/${AMPUSER})}" = "deny" ]?nointercom) [pbx_config]
                    8. GotoIf($["${DB(AMPUSER/${dialnumber}/intercom)}" = "disabled" ]?nointercom) [pbx_config]
     [allow]        9. Set(DEVICES=${DB(AMPUSER/${dialnumber}/device)}) [pbx_config]
                    10. GotoIf($["${DEVICES}" = "" ]?end)         [pbx_config]
                    11. Set(LOOPCNT=${FIELDQTY(DEVICES,&)})       [pbx_config]
                    12. Set(_SIPURI=)                             [pbx_config]
                    13. Set(_ALERTINFO=Alert-Info: Ring Answer)   [pbx_config]
                    14. Set(_CALLINFO=Call-Info: <uri>\;answer-after=0) [pbx_config]
                    15. Set(_SIPURI=intercom=true)                [pbx_config]
                    16. Set(_DOPTIONS=A(beep))                    [pbx_config]
                    17. Set(_DTIME=5)                             [pbx_config]
                    18. Set(_ANSWERMACRO=)                        [pbx_config]
                    19. GotoIf($[${LOOPCNT} > 1 ]?pagemode)       [pbx_config]
                    20. Macro(autoanswer,${DEVICES})              [pbx_config]
     [check]        21. ChanIsAvail(${DIAL},s)                    [pbx_config]
                    22. GotoIf($["${AVAILORIGCHAN}" = ""]?end)    [pbx_config]
                    23. GotoIf($["${DB(AMPUSER/${EXTEN:3}/cidname)}" = ""]?godial) [pbx_config]
                    24. Set(CONNECTEDLINE(name,i)=${DB(AMPUSER/${EXTEN:3}/cidname)}) [pbx_config]
                    25. Set(CONNECTEDLINE(num)=${EXTEN:3})        [pbx_config]
     [godial]       26. Dial(${DIAL},${DTIME},I${DOPTIONS}${INTERCOM_EXT_DOPTIONS}) [pbx_config]
     [end]          27. ExecIf($[${INTERCOM_RETURN}]?Return())    [pbx_config]
                    28. Busy(20)                                  [pbx_config]
                    29. Macro(hangupcall,)                        [pbx_config]
     [pagemode]     30. Set(ITER=1)                               [pbx_config]
                    31. Set(DIALSTR=)                             [pbx_config]
     [begin]        32. ChanIsAvail(${DB(DEVICE/${CUT(DEVICES,&,${ITER})}/dial)},s) [pbx_config]
                    33. GotoIf($["${AVAILORIGCHAN}" = ""]?skip)   [pbx_config]
                    34. Set(DIALSTR=${DIALSTR}-${CUT(DEVICES,&,${ITER})}) [pbx_config]
     [skip]         35. Set(ITER=$[${ITER} + 1])                  [pbx_config]
                    36. GotoIf($[${ITER} <= ${LOOPCNT}]?begin)    [pbx_config]
                    37. Set(DIALSTR=${DIALSTR:1})                 [pbx_config]
                    38. GotoIf($["${DIALSTR}" = ""]?end2)         [pbx_config]
                    39. Set(_AMPUSER=${AMPUSER})                  [pbx_config]
                    40. Gosub(page,1(${DIALSTR}))                 [pbx_config]
     [end2]         41. ExecIf($[${INTERCOM_RETURN}]?Return())    [pbx_config]
                    42. Busy(20)                                  [pbx_config]
                    43. Macro(hangupcall,)                        [pbx_config]
     [nointercom]   44. Noop(Intercom disallowed by ${dialnumber}) [pbx_config]
                    45. ExecIf($[${INTERCOM_RETURN}]?Return())    [pbx_config]
                    46. Playback(intercom&for&extension)          [pbx_config]
                    47. SayDigits(${dialnumber})                  [pbx_config]
                    48. Playback(is&disabled)                     [pbx_config]
                    49. Congestion(20)                            [pbx_config]
  Include =>        'ext-intercom-custom'                         [pbx_config]

And here is a log file taken after trying dialing *80206 from the soft phone (ext 206 is the Polycom 501 and ext 208 is the soft phone) For some reason, every line shows up twice:

[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@from-internal:1] Goto("SIP/208-00004775", "ext-intercom,*80206,1") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@from-internal:1] Goto("SIP/208-00004775", "ext-intercom,*80206,1") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Goto (ext-intercom,*80206,1)
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Goto (ext-intercom,*80206,1)
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:1] Macro("SIP/208-00004775", "user-callerid,") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:1] Macro("SIP/208-00004775", "user-callerid,") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/208-00004775", "AMPUSER=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/208-00004775", "AMPUSER=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/208-00004775", "0?report") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/208-00004775", "0?report") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/208-00004775", "1?Set(REALCALLERIDNUM=208)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/208-00004775", "1?Set(REALCALLERIDNUM=208)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/208-00004775", "AMPUSER=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/208-00004775", "AMPUSER=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/208-00004775", "AMPUSERCIDNAME=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/208-00004775", "AMPUSERCIDNAME=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/208-00004775", "0?report") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/208-00004775", "0?report") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/208-00004775", "AMPUSERCID=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/208-00004775", "AMPUSERCID=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:8] Set("SIP/208-00004775", "CALLERID(all)="208" <208>") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:8] Set("SIP/208-00004775", "CALLERID(all)="208" <208>") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/208-00004775", "0?limit") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/208-00004775", "0?limit") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:10] ExecIf("SIP/208-00004775", "0?Set(GROUP(concurrency_limit)=208)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:10] ExecIf("SIP/208-00004775", "0?Set(GROUP(concurrency_limit)=208)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:11] GosubIf("SIP/208-00004775", "7?sub-ccss,s,1(ext-intercom,*80206)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:11] GosubIf("SIP/208-00004775", "7?sub-ccss,s,1(ext-intercom,*80206)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@sub-ccss:1] ExecIf("SIP/208-00004775", "0?Return()") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@sub-ccss:1] ExecIf("SIP/208-00004775", "0?Return()") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@sub-ccss:2] Set("SIP/208-00004775", "CCSS_SETUP=TRUE") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@sub-ccss:2] Set("SIP/208-00004775", "CCSS_SETUP=TRUE") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@sub-ccss:3] GosubIf("SIP/208-00004775", "0?monitor_config,1(ext-intercom,*80206):monitor_default,1(ext-intercom,*80206)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@sub-ccss:3] GosubIf("SIP/208-00004775", "0?monitor_config,1(ext-intercom,*80206):monitor_default,1(ext-intercom,*80206)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/208-00004775", "0?is_exten") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/208-00004775", "0?is_exten") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [monitor_default@sub-ccss:2] StackPop("SIP/208-00004775", "") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [monitor_default@sub-ccss:2] StackPop("SIP/208-00004775", "") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [monitor_default@sub-ccss:3] Return("SIP/208-00004775", "FALSE") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [monitor_default@sub-ccss:3] Return("SIP/208-00004775", "FALSE") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/208-00004775", "0?continue") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/208-00004775", "0?continue") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:13] Set("SIP/208-00004775", "__TTL=64") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:13] Set("SIP/208-00004775", "__TTL=64") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/208-00004775", "1?continue") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/208-00004775", "1?continue") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Goto (macro-user-callerid,s,25)
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Goto (macro-user-callerid,s,25)
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:25] Set("SIP/208-00004775", "CALLERID(number)=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:25] Set("SIP/208-00004775", "CALLERID(number)=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/208-00004775", "CALLERID(name)=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/208-00004775", "CALLERID(name)=208") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/208-00004775", "CHANNEL(language)=en") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/208-00004775", "CHANNEL(language)=en") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:2] Set("SIP/208-00004775", "dialnumber=206") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:2] Set("SIP/208-00004775", "dialnumber=206") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:3] Set("SIP/208-00004775", "INTERCOM_CALL=TRUE") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:3] Set("SIP/208-00004775", "INTERCOM_CALL=TRUE") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:4] GotoIf("SIP/208-00004775", "0?end") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:4] GotoIf("SIP/208-00004775", "0?end") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:5] GotoIf("SIP/208-00004775", "0?end") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:5] GotoIf("SIP/208-00004775", "0?end") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:6] GotoIf("SIP/208-00004775", "0?allow") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:6] GotoIf("SIP/208-00004775", "0?allow") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:7] GotoIf("SIP/208-00004775", "0?nointercom") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:7] GotoIf("SIP/208-00004775", "0?nointercom") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:8] GotoIf("SIP/208-00004775", "0?nointercom") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:8] GotoIf("SIP/208-00004775", "0?nointercom") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:9] Set("SIP/208-00004775", "DEVICES=206") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:9] Set("SIP/208-00004775", "DEVICES=206") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:10] GotoIf("SIP/208-00004775", "0?end") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:10] GotoIf("SIP/208-00004775", "0?end") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:11] Set("SIP/208-00004775", "LOOPCNT=1") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:11] Set("SIP/208-00004775", "LOOPCNT=1") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:12] Set("SIP/208-00004775", "_SIPURI=") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:12] Set("SIP/208-00004775", "_SIPURI=") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:13] Set("SIP/208-00004775", "_ALERTINFO=Alert-Info: Ring Answer") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:13] Set("SIP/208-00004775", "_ALERTINFO=Alert-Info: Ring Answer") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:14] Set("SIP/208-00004775", "_CALLINFO=Call-Info: <uri>\;answer-after=0") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:14] Set("SIP/208-00004775", "_CALLINFO=Call-Info: <uri>\;answer-after=0") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:15] Set("SIP/208-00004775", "_SIPURI=intercom=true") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:15] Set("SIP/208-00004775", "_SIPURI=intercom=true") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:16] Set("SIP/208-00004775", "_DOPTIONS=A(beep)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:16] Set("SIP/208-00004775", "_DOPTIONS=A(beep)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:17] Set("SIP/208-00004775", "_DTIME=5") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:17] Set("SIP/208-00004775", "_DTIME=5") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:18] Set("SIP/208-00004775", "_ANSWERMACRO=") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:18] Set("SIP/208-00004775", "_ANSWERMACRO=") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:19] GotoIf("SIP/208-00004775", "0?pagemode") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:19] GotoIf("SIP/208-00004775", "0?pagemode") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:20] Macro("SIP/208-00004775", "autoanswer,206") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:20] Macro("SIP/208-00004775", "autoanswer,206") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:1] Set("SIP/208-00004775", "DIAL=SIP/206") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:1] Set("SIP/208-00004775", "DIAL=SIP/206") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:2] ExecIf("SIP/208-00004775", "0?Set(DIAL=DAHDI/206)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:2] ExecIf("SIP/208-00004775", "0?Set(DIAL=DAHDI/206)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:3] GotoIf("SIP/208-00004775", "0?macro") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:3] GotoIf("SIP/208-00004775", "0?macro") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:4] Set("SIP/208-00004775", "phone=PolycomSoundPointIP-SPIP_501-UA/3.1.8.0070") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:4] Set("SIP/208-00004775", "phone=PolycomSoundPointIP-SPIP_501-UA/3.1.8.0070") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:5] ExecIf("SIP/208-00004775", "0?Set(CALLINFO=Call-Info: <sip:broadworks.net>\;answer-after=0)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:5] ExecIf("SIP/208-00004775", "0?Set(CALLINFO=Call-Info: <sip:broadworks.net>\;answer-after=0)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:6] ExecIf("SIP/208-00004775", "0?Set(ALERTINFO=Alert-Info: Intercom)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:6] ExecIf("SIP/208-00004775", "0?Set(ALERTINFO=Alert-Info: Intercom)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:7] ExecIf("SIP/208-00004775", "1?Set(ALERTINFO=Alert-Info: info=Auto Answer)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:7] ExecIf("SIP/208-00004775", "1?Set(ALERTINFO=Alert-Info: info=Auto Answer)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:8] ExecIf("SIP/208-00004775", "0?Set(ALERTINFO=Alert-Info: ring-answer)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:8] ExecIf("SIP/208-00004775", "0?Set(ALERTINFO=Alert-Info: ring-answer)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:9] ExecIf("SIP/208-00004775", "1?SipAddHeader(Alert-Info: info=Auto Answer)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:9] ExecIf("SIP/208-00004775", "1?SipAddHeader(Alert-Info: info=Auto Answer)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:10] ExecIf("SIP/208-00004775", "1?SipAddHeader(Call-Info: <uri>\;answer-after=0)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:10] ExecIf("SIP/208-00004775", "1?SipAddHeader(Call-Info: <uri>\;answer-after=0)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:11] ExecIf("SIP/208-00004775", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [s@macro-autoanswer:11] ExecIf("SIP/208-00004775", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:21] ChanIsAvail("SIP/208-00004775", "SIP/206,s") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:21] ChanIsAvail("SIP/208-00004775", "SIP/206,s") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] netsock2.c: == Using SIP RTP TOS bits 184
[2012-10-31 08:53:25] VERBOSE[16824] netsock2.c: == Using SIP RTP TOS bits 184
[2012-10-31 08:53:25] VERBOSE[16824] netsock2.c: == Using SIP RTP CoS mark 5
[2012-10-31 08:53:25] VERBOSE[16824] netsock2.c: == Using SIP RTP CoS mark 5
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:22] GotoIf("SIP/208-00004775", "0?end") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:22] GotoIf("SIP/208-00004775", "0?end") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:23] GotoIf("SIP/208-00004775", "0?godial") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:23] GotoIf("SIP/208-00004775", "0?godial") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:24] Set("SIP/208-00004775", "CONNECTEDLINE(name,i)=PMLeahy") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:24] Set("SIP/208-00004775", "CONNECTEDLINE(name,i)=PMLeahy") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:25] Set("SIP/208-00004775", "CONNECTEDLINE(num)=206") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:25] Set("SIP/208-00004775", "CONNECTEDLINE(num)=206") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:26] Dial("SIP/208-00004775", "SIP/206,5,IA(beep)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:26] Dial("SIP/208-00004775", "SIP/206,5,IA(beep)") in new stack
[2012-10-31 08:53:25] VERBOSE[16824] netsock2.c: == Using SIP RTP TOS bits 184
[2012-10-31 08:53:25] VERBOSE[16824] netsock2.c: == Using SIP RTP TOS bits 184
[2012-10-31 08:53:25] VERBOSE[16824] netsock2.c: == Using SIP RTP CoS mark 5
[2012-10-31 08:53:25] VERBOSE[16824] netsock2.c: == Using SIP RTP CoS mark 5
[2012-10-31 08:53:25] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 111 
[2012-10-31 08:53:25] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 111 
[2012-10-31 08:53:25] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 202 
[2012-10-31 08:53:25] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 202 
[2012-10-31 08:53:25] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 200 
[2012-10-31 08:53:25] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 200 
[2012-10-31 08:53:25] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 100 
[2012-10-31 08:53:25] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 100 
[2012-10-31 08:53:25] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 201 
[2012-10-31 08:53:25] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 201 
[2012-10-31 08:53:25] VERBOSE[16824] app_dial.c: -- Called SIP/206
[2012-10-31 08:53:25] VERBOSE[16824] app_dial.c: -- Called SIP/206
[2012-10-31 08:53:25] VERBOSE[16824] app_dial.c: -- Connected line update to SIP/208-00004775 prevented.
[2012-10-31 08:53:25] VERBOSE[16824] app_dial.c: -- Connected line update to SIP/208-00004775 prevented.
[2012-10-31 08:53:25] VERBOSE[16824] app_dial.c: -- SIP/206-00004777 is ringing
[2012-10-31 08:53:25] VERBOSE[16824] app_dial.c: -- SIP/206-00004777 is ringing
[2012-10-31 08:53:27] VERBOSE[16826] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2012-10-31 08:53:27] VERBOSE[16826] manager.c: == Manager 'admin' logged on from 127.0.0.1
[2012-10-31 08:53:29] VERBOSE[16826] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2012-10-31 08:53:29] VERBOSE[16826] manager.c: == Manager 'admin' logged off from 127.0.0.1
[2012-10-31 08:53:30] VERBOSE[16824] app_dial.c: -- Nobody picked up in 5000 ms
[2012-10-31 08:53:30] VERBOSE[16824] app_dial.c: -- Nobody picked up in 5000 ms
[2012-10-31 08:53:30] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:27] ExecIf("SIP/208-00004775", "?Return()") in new stack
[2012-10-31 08:53:30] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:27] ExecIf("SIP/208-00004775", "?Return()") in new stack
[2012-10-31 08:53:30] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:28] Busy("SIP/208-00004775", "20") in new stack
[2012-10-31 08:53:30] VERBOSE[16824] pbx.c: -- Executing [*80206@ext-intercom:28] Busy("SIP/208-00004775", "20") in new stack
[2012-10-31 08:53:30] WARNING[16824] channel.c: Prodding channel 'SIP/208-00004775' failed
[2012-10-31 08:53:30] WARNING[16824] channel.c: Prodding channel 'SIP/208-00004775' failed
[2012-10-31 08:53:30] VERBOSE[16824] pbx.c: == Spawn extension (ext-intercom, *80206, 28) exited non-zero on 'SIP/208-00004775'
[2012-10-31 08:53:30] VERBOSE[16824] pbx.c: == Spawn extension (ext-intercom, *80206, 28) exited non-zero on 'SIP/208-00004775'

Thanks in advance for any help!

Matthew, I have been struggling with the same thing. It used to work but doesn’t any more - I believe you are correct that the Alert-Info is wrong and I have opened FreePBX bug # 6052.

As a workaround you can edit extensions_additional.conf, go to
[macro-autoanswer] and in the ninth line you will see:
exten => s,n,ExecIf($["${phone:0:7}" = “Polycom”]?Set(ALERTINFO=Alert-Info: info=Auto Answer))

Change that to:
exten => s,n,ExecIf($["${phone:0:7}" = “Polycom”]?Set(ALERTINFO=Alert-Info: Ring Answer))

Unfortunately any time you change the config in FreePBX and save it, you will have to repeat the edit.

(Oh - and of course I have made this workaround and the intercom function now works for me)

  • Norm

Please see #6052 for the complete history on this

Norman, thank you for the info. It is still not working for me and I have all the settings you are using that I know of. Not sure where to look from here but I know I must be very close!

This is in sip_318.cfg (from endpoint manager)

<alertInfo voIpProt.SIP.alertInfo.1.value="{$alertInfo_1_value|Ring Answer}" voIpProt.SIP.alertInfo.1.class="{$alertInfo_1_class|4}"/>

This is farther down in sip_318.cfg

<RING_ANSWER se.rt.4.name="Ring Answer" se.rt.4.type="Ring Answer" se.rt.4.timeout="2" se.rt.4.ringer="11" se.rt.4.callWait="6" se.rt.4.mod="1"/>

As you can see, I tried changing the ringer to 11, but the ring does not change at all when I dial *80206 from the softphone (Polycom is 206, softphone is 208)

Here is my extensions_override_freepbx.cfg

[macro-autoanswer]
include => macro-autoanswer-custom
exten => s,1,Set(DIAL=${DB(DEVICE/${ARG1}/dial)})
exten => s,n,ExecIf($["${DIAL:0:3}" = "ZAP"]?Set(DIAL=DAHDI${DIAL:3}))
exten => s,n,GotoIf($["${DB(DEVICE/${ARG1}/autoanswer/macro)}" != "" ]?macro)
exten => s,n,Set(phone=${SIPPEER(${CUT(DIAL,/,2)}:useragent)})
exten => s,n,ExecIf($["${phone:0:5}" = "Mitel"]?Set(CALLINFO=Call-Info: <sip:broadworks.net>\;answer-after=0))
exten => s,n,ExecIf($["${phone:0:9}" = "Panasonic"]?Set(ALERTINFO=Alert-Info: Intercom))
exten => s,n,ExecIf($["${phone:0:7}" = "Polycom"]?Set(ALERTINFO=Alert-Info: Ring Answer))
exten => s,n,ExecIf($["${phone:0:6}" = "Digium"]?Set(ALERTINFO=Alert-Info: ring-answer))
exten => s,n,ExecIf($["${ALERTINFO}" != ""]?SipAddHeader(${ALERTINFO}))
exten => s,n,ExecIf($["${CALLINFO}" != ""]?SipAddHeader(${CALLINFO}))
exten => s,n,ExecIf($["${SIPURI}" != ""]?Set(__SIP_URI_OPTIONS=${SIPURI}))
exten => s,n+2(macro),Macro(${DB(DEVICE/${ARG1}/autoanswer/macro)},${ARG1})

;--== end of [macro-autoanswer] ==--;

And here’s a log file snippet taken shortly after I dial *80206 from the softphone. Every line in the log is duplicated for some reason, so I’ve cut out the duplicate lines, but if anyone knows why it’s giving duplicates, that would also be nice to fix.

[2012-11-21 08:17:24] VERBOSE[21744] chan_sip.c: == Extension Changed 208[ext-local] new state InUse for Notify User 206 
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@from-internal:1] Goto("SIP/208-000085ad", "ext-intercom,*80206,1") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Goto (ext-intercom,*80206,1)
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:1] Macro("SIP/208-000085ad", "user-callerid,") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/208-000085ad", "AMPUSER=208") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/208-000085ad", "0?report") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/208-000085ad", "1?Set(REALCALLERIDNUM=208)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/208-000085ad", "AMPUSER=208") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/208-000085ad", "AMPUSERCIDNAME=208") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/208-000085ad", "0?report") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/208-000085ad", "AMPUSERCID=208") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:8] Set("SIP/208-000085ad", "CALLERID(all)="208" <208>") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/208-000085ad", "0?limit") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:10] ExecIf("SIP/208-000085ad", "0?Set(GROUP(concurrency_limit)=208)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:11] GosubIf("SIP/208-000085ad", "7?sub-ccss,s,1(ext-intercom,*80206)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@sub-ccss:1] ExecIf("SIP/208-000085ad", "0?Return()") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@sub-ccss:2] Set("SIP/208-000085ad", "CCSS_SETUP=TRUE") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@sub-ccss:3] GosubIf("SIP/208-000085ad", "0?monitor_config,1(ext-intercom,*80206):monitor_default,1(ext-intercom,*80206)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/208-000085ad", "0?is_exten") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [monitor_default@sub-ccss:2] StackPop("SIP/208-000085ad", "") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [monitor_default@sub-ccss:3] Return("SIP/208-000085ad", "FALSE") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/208-000085ad", "0?continue") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:13] Set("SIP/208-000085ad", "__TTL=64") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/208-000085ad", "1?continue") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Goto (macro-user-callerid,s,25)
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:25] Set("SIP/208-000085ad", "CALLERID(number)=208") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/208-000085ad", "CALLERID(name)=208") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/208-000085ad", "CHANNEL(language)=en") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:2] Set("SIP/208-000085ad", "dialnumber=206") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:3] Set("SIP/208-000085ad", "INTERCOM_CALL=TRUE") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:4] GotoIf("SIP/208-000085ad", "0?end") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:5] GotoIf("SIP/208-000085ad", "0?end") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:6] GotoIf("SIP/208-000085ad", "0?allow") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:7] GotoIf("SIP/208-000085ad", "0?nointercom") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:8] GotoIf("SIP/208-000085ad", "0?nointercom") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:9] Set("SIP/208-000085ad", "DEVICES=206") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:10] GotoIf("SIP/208-000085ad", "0?end") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:11] Set("SIP/208-000085ad", "LOOPCNT=1") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:12] Set("SIP/208-000085ad", "_SIPURI=") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:13] Set("SIP/208-000085ad", "_ALERTINFO=Alert-Info: Ring Answer") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:14] Set("SIP/208-000085ad", "_CALLINFO=Call-Info: <uri>\;answer-after=0") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:15] Set("SIP/208-000085ad", "_SIPURI=intercom=true") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:16] Set("SIP/208-000085ad", "_DOPTIONS=A(beep)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:17] Set("SIP/208-000085ad", "_DTIME=5") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:18] Set("SIP/208-000085ad", "_ANSWERMACRO=") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:19] GotoIf("SIP/208-000085ad", "0?pagemode") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:20] Macro("SIP/208-000085ad", "autoanswer,206") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:1] Set("SIP/208-000085ad", "DIAL=SIP/206") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:2] ExecIf("SIP/208-000085ad", "0?Set(DIAL=DAHDI/206)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:3] GotoIf("SIP/208-000085ad", "0?macro") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:4] Set("SIP/208-000085ad", "phone=PolycomSoundPointIP-SPIP_501-UA/3.1.8.0070") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:5] ExecIf("SIP/208-000085ad", "0?Set(CALLINFO=Call-Info: <sip:broadworks.net>\;answer-after=0)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:6] ExecIf("SIP/208-000085ad", "0?Set(ALERTINFO=Alert-Info: Intercom)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:7] ExecIf("SIP/208-000085ad", "1?Set(ALERTINFO=Alert-Info: Ring Answer)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:8] ExecIf("SIP/208-000085ad", "0?Set(ALERTINFO=Alert-Info: ring-answer)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:9] ExecIf("SIP/208-000085ad", "1?SipAddHeader(Alert-Info: Ring Answer)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:10] ExecIf("SIP/208-000085ad", "1?SipAddHeader(Call-Info: <uri>\;answer-after=0)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [s@macro-autoanswer:11] ExecIf("SIP/208-000085ad", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:21] ChanIsAvail("SIP/208-000085ad", "SIP/206,s") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] netsock2.c: == Using SIP RTP TOS bits 184
[2012-11-21 08:17:24] VERBOSE[28688] netsock2.c: == Using SIP RTP CoS mark 5
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:22] GotoIf("SIP/208-000085ad", "0?end") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:23] GotoIf("SIP/208-000085ad", "0?godial") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:24] Set("SIP/208-000085ad", "CONNECTEDLINE(name,i)=PMLeahy") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:25] Set("SIP/208-000085ad", "CONNECTEDLINE(num)=206") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:26] Dial("SIP/208-000085ad", "SIP/206,5,IA(beep)") in new stack
[2012-11-21 08:17:24] VERBOSE[28688] netsock2.c: == Using SIP RTP TOS bits 184
[2012-11-21 08:17:24] VERBOSE[28688] netsock2.c: == Using SIP RTP CoS mark 5
[2012-11-21 08:17:24] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 202 
[2012-11-21 08:17:24] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 111 
[2012-11-21 08:17:24] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 200 
[2012-11-21 08:17:24] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 100 
[2012-11-21 08:17:24] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Ringing for Notify User 201 
[2012-11-21 08:17:24] VERBOSE[28688] app_dial.c: -- Called SIP/206
[2012-11-21 08:17:24] VERBOSE[28688] app_dial.c: -- Connected line update to SIP/208-000085ad prevented.
[2012-11-21 08:17:24] VERBOSE[28688] app_dial.c: -- SIP/206-000085af is ringing
[2012-11-21 08:17:29] VERBOSE[28688] app_dial.c: -- Nobody picked up in 5000 ms
[2012-11-21 08:17:29] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:27] ExecIf("SIP/208-000085ad", "?Return()") in new stack
[2012-11-21 08:17:29] VERBOSE[28688] pbx.c: -- Executing [*80206@ext-intercom:28] Busy("SIP/208-000085ad", "20") in new stack
[2012-11-21 08:17:29] WARNING[28688] channel.c: Prodding channel 'SIP/208-000085ad' failed
[2012-11-21 08:17:29] VERBOSE[28688] pbx.c: == Spawn extension (ext-intercom, *80206, 28) exited non-zero on 'SIP/208-000085ad'
[2012-11-21 08:17:29] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Idle for Notify User 202 
[2012-11-21 08:17:29] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Idle for Notify User 111 
[2012-11-21 08:17:29] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Idle for Notify User 200 
[2012-11-21 08:17:29] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Idle for Notify User 100 
[2012-11-21 08:17:29] VERBOSE[21744] chan_sip.c: == Extension Changed 206[ext-local] new state Idle for Notify User 201 
[2012-11-21 08:17:29] VERBOSE[21744] chan_sip.c: == Extension Changed 208[ext-local] new state Idle for Notify User 206

Does anyone have some insight on this? It would be much appreciated!

1 Like

Did you look at my link to that ticket. That explained it all I thought

Tony,
Thanks for your help. I looked at the link you posted to that ticket and read through the forum link in that ticket but have not been able to get it working. I have tried using “info=Auto Answer” and just “Auto Answer” and “Ring Answer” for the alert info. I have tried

<alertInfo voIpProt.SIP.alertInfo.1.class="{$alertInfo_1_class|ringAutoAnswer}" voIpProt.SIP.alertInfo.1.value="{$alertInfo_1_value|Auto Answer}"/>

in my sip_318.cfg (from endpoint manager) and I am really running out of ideas. There lines are farther down in my sip_318.cfg

<AUTO_ANSWER se.rt.3.name="Auto Answer" se.rt.3.type="answer"/>
<RING_ANSWER se.rt.4.name="Ring Answer" se.rt.4.type="Ring Answer" se.rt.4.timeout="2" se.rt.4.ringer="11" se.rt.4.callWait="6" se.rt.4.mod="1"/>

I think I’ve followed all the instructions in that forum post, but I still cannot get it working. Would there be any problem with calling FROM a soft phone? I am using X-Lite to call the Polycom 501 phone. Thanks again!

I have still not been able to get this working and I really don’t know what I’m doing wrong. If there’s anyone that could take another look,. I’d really appreciate it! I know it’s something simple that I’m missing, and I just cannot get it.

I hooked the phone up to a hub with my laptop running wireshark. I tried changing the alert info in extensions_override_freepbx.conf to several of the suggestions I’ve found (Ring Answer, Auto Answer, Auto-Answer, info=Auto Answer) and the alert info always comes through as expected. I still do not know what should be in my sip.cfg or how the alert info should be, but I’ve tried many combinations and none are working.

Here is a screenshot of wireshark looking at the INVITE while I had alert info set to “info=Auto Answer”

Was this ever sorted out?

If so, what were the steps to get the Auto answer to work for a polycom?

A boo doo - Didn’t you say you were running Xorcom in another post?

Hi,

Xorcom uses FreePBX modules

Here is what I have in my SIP.cfg. This works on my VVX400’s, but polycoms (at least for me) are extremely finicky. I am currently battling an installation with a group of VVX400s with the same configuration that randomly decide to do a Ring Answer instead of an Auto Answer… its infuriating!

		<alertInfo
            voIpProt.SIP.alertInfo.1.value="info=Auto Answer"
	voIpProt.SIP.alertInfo.1.class="autoAnswer"/>

Here is what I have in my SIP.cfg. This works on my VVX400’s, but polycoms (at least for me) are extremely finicky. I am currently battling an installation with a group of VVX400s with the same configuration that randomly decide to do a Ring Answer instead of an Auto Answer… its infuriating!

Hello I am having the issue, when I use paging everything works fine when when I use intercom the target phone just rings… I cant find the link you reference above. I am using the most current distro, fresh install with paid module end point manager. Thank you for your help!