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!