[bug]: CRM webhook does not trigger on End of call #943
Description
FreePBX Version
FreePBX 16
Issue Description
When an inbound call is placed, the CRM webhook does not trigger with the correct END of a call and all of the correct information of the call at the end such as duration.
I put the log output for a call in the output field.
There is a Case open with more information, I was told to report the bug here as well. Case number 02023153
Operating Environment
FreePBX Framework 16.0.44
Asterisk Version 18.9-cert15
+---------------------+------------+-----------------------------------+-------------+-----------+
| Module | Version | Status | License | Signature |
+---------------------+------------+-----------------------------------+-------------+-----------+
| accountcodepreserve | 16.0.0.1 | Enabled | GPLv2 | Sangoma |
| adv_recovery | | Not Installed (Locally available) | Commercial | Sangoma |
| allowlist | | Not Installed (Locally available) | GPLv3+ | Sangoma |
| amd | 16.0.3 | Enabled | GPLv3+ | Sangoma |
| announcement | 16.0.7 | Enabled | GPLv3+ | Sangoma |
| api | 16.0.15 | Enabled | AGPLv3+ | Sangoma |
| areminder | 16.0.16 | Enabled | Commercial | Sangoma |
| arimanager | 16.0.13 | Enabled | GPLv3+ | Sangoma |
| asterisk-cli | 16.0.8 | Enabled | GPLv3+ | Sangoma |
| asteriskinfo | 16.0.10 | Enabled | GPLv3+ | Sangoma |
| backup | 16.0.70.5 | Enabled | GPLv3+ | Sangoma |
| blacklist | 16.0.21 | Enabled | GPLv3+ | Sangoma |
| broadcast | 16.0.19 | Enabled | Commercial | Sangoma |
| builtin | | Enabled | | Unsigned |
| bulkhandler | 16.0.18 | Enabled | GPLv3+ | Sangoma |
| calendar | 16.0.34.2 | Enabled | GPLv3+ | Sangoma |
| callaccounting | | Not Installed (Locally available) | Commercial+ | Sangoma |
| callback | 16.0.4 | Enabled | GPLv3+ | Sangoma |
| callerid | 16.0.5 | Enabled | Commercial | Sangoma |
| callforward | 16.0.6 | Enabled | AGPLv3+ | Sangoma |
| calllimit | 16.0.6 | Enabled | Commercial | Sangoma |
| callrecording | 16.0.22 | Enabled | AGPLv3+ | Sangoma |
| callwaiting | 16.0.5 | Enabled | GPLv3+ | Sangoma |
| cdr | 16.0.46.27 | Enabled | GPLv3+ | Sangoma |
| cdrpro | 16.0.33.19 | Enabled | Commercial | Sangoma |
| cel | 16.0.20 | Enabled | GPLv3+ | Sangoma |
| certman | 16.0.30 | Enabled | AGPLv3+ | Sangoma |
| cidlookup | 16.0.16 | Enabled | GPLv3+ | Sangoma |
| cloudmigration | | Not Installed (Locally available) | Commercial | Sangoma |
| conferences | 16.0.9 | Enabled | GPLv3+ | Sangoma |
| conferencespro | 16.0.10 | Enabled | Commercial | Sangoma |
| configedit | 16.0.5 | Enabled | AGPLv3+ | Sangoma |
| contactmanager | 16.0.27 | Enabled | GPLv3+ | Sangoma |
| core | 16.0.68.39 | Enabled | GPLv3+ | Sangoma |
| cos | 16.0.7 | Enabled | Commercial | Sangoma |
| customappsreg | 16.0.5 | Enabled | GPLv3+ | Sangoma |
| cxpanel | 16.0.6 | Enabled | GPLv3 | Sangoma |
| dahdiconfig | 16.0.9 | Enabled | GPLv3+ | Sangoma |
| dashboard | 16.0.21 | Enabled | AGPLv3+ | Sangoma |
| daynight | 16.0.3 | Enabled | GPLv3+ | Sangoma |
| dictate | 16.0.1 | Enabled | GPLv3+ | Sangoma |
| directory | 16.0.3 | Enabled | GPLv3+ | Sangoma |
| disa | 16.0.5 | Enabled | AGPLv3+ | Sangoma |
| donotdisturb | 16.0.3 | Enabled | GPLv3+ | Sangoma |
| dpviz | 1.0.31 | Enabled | GPLv3+ | Unknown |
| dynroute | | Not Installed (Locally available) | GPLv3+ | Sangoma |
| endpoint | 16.0.96 | Enabled | Commercial | Sangoma |
| extensionroutes | 16.0.8 | Enabled | Commercial | Sangoma |
| extensionsettings | 16.0.5 | Enabled | GPLv3+ | Sangoma |
| fax | 16.0.13 | Enabled | GPLv3+ | Sangoma |
| faxpro | 16.0.15 | Enabled | Commercial | Sangoma |
| featurecodeadmin | 16.0.11 | Enabled | GPLv3+ | Sangoma |
| filestore | 16.0.18.6 | Enabled | AGPLv3 | Sangoma |
| findmefollow | 16.0.24 | Enabled | GPLv3+ | Sangoma |
| firewall | 16.0.57.11 | Enabled | AGPLv3+ | Sangoma |
| framework | 16.0.44 | Enabled | GPLv2+ | Sangoma |
| fw_langpacks | 16.0.1 | Enabled | GPLv3+ | Sangoma |
| hotelwakeup | 16.0.9 | Enabled | GPLv2 | Sangoma |
| iaxsettings | 16.0.4 | Enabled | AGPLv3 | Sangoma |
| infoservices | 16.0.2 | Enabled | GPLv2+ | Sangoma |
| iotserver | | Not Installed (Locally available) | Commercial | Sangoma |
| irc | 16.0.1 | Enabled | GPLv3+ | Sangoma |
| ivr | 16.0.9 | Enabled | GPLv3+ | Sangoma |
| languages | 16.0.4 | Enabled | GPLv3+ | Sangoma |
| logfiles | 16.0.8 | Enabled | GPLv3+ | Sangoma |
| manager | 16.0.24 | Enabled | GPLv2+ | Sangoma |
| miscapps | 16.0.2 | Enabled | GPLv3+ | Sangoma |
| miscdests | 16.0.2 | Enabled | GPLv3+ | Sangoma |
| missedcall | | Not Installed (Locally available) | GPLv3+ | Sangoma |
| music | 16.0.3 | Enabled | GPLv3+ | Sangoma |
| oracle_connector | | Not Installed (Locally available) | Commercial | Sangoma |
| outcnam | | Not Installed (Locally available) | GPLv3+ | Sangoma |
| outroutemsg | 16.0.1 | Enabled | GPLv3+ | Sangoma |
| paging | 16.0.13 | Enabled | GPLv3+ | Sangoma |
| pagingpro | 16.0.11 | Enabled | Commercial | Sangoma |
| parking | 16.0.4 | Enabled | GPLv3+ | Sangoma |
| parkpro | 16.0.5 | Enabled | Commercial | Sangoma |
| pbxmfa | | Not Installed (Locally available) | Commercial+ | Sangoma |
| phonebook | 16.0.3 | Enabled | GPLv3+ | Sangoma |
| phpinfo | 16.0.1 | Enabled | GPLv2+ | Sangoma |
| pinsets | 16.0.8 | Enabled | GPLv3+ | Sangoma |
| pinsetspro | 16.0.4 | Enabled | Commercial | Sangoma |
| pm2 | 16.0.8 | Enabled | AGPLv3+ | Sangoma |
| pms | 16.0.23 | Enabled | Commercial | Sangoma |
| presencestate | 16.0.4 | Enabled | GPLv3+ | Sangoma |
| printextensions | 16.0.9 | Enabled | GPLv3+ | Sangoma |
| queueprio | 16.0.3 | Enabled | GPLv3+ | Sangoma |
| queues | 16.0.28 | Enabled | GPLv2+ | Sangoma |
| queuestats | 16.0.28 | Enabled | Commercial | Sangoma |
| qxact_reports | 16.0.37 | Enabled | Commercial | Sangoma |
| recording_report | 16.0.33 | Enabled | Commercial | Sangoma |
| recordings | 16.0.17.14 | Enabled | GPLv3+ | Sangoma |
| restapps | 16.0.41 | Enabled | Commercial | Sangoma |
| ringgroups | 16.0.11 | Enabled | GPLv3+ | Sangoma |
| sangomaconnect | 16.0.51.27 | Enabled | Commercial | Sangoma |
| sangomacrm | 16.0.10.22 | Enabled | Commercial | Sangoma |
| sangomartapi | 16.0.54.27 | Enabled | Commercial | Sangoma |
| setcid | 16.0.3 | Enabled | GPLv3+ | Sangoma |
| sipsettings | 16.0.27.1 | Enabled | AGPLv3+ | Sangoma |
| sipstation | 16.0.28 | Enabled | Commercial | Sangoma |
| sms | 16.0.29 | Enabled | Commercial | Sangoma |
| smsplus | 16.0.2 | Enabled | Commercial | Sangoma |
| soundlang | 16.0.9 | Enabled | GPLv3+ | Sangoma |
| superfecta | 16.0.39 | Enabled | GPLv2+ | Sangoma |
| synologyabb | | Not Installed (Locally available) | GPLv3+ | Sangoma |
| sysadmin | 16.0.49.13 | Enabled | Commercial | Sangoma |
| timeconditions | 16.0.11 | Enabled | GPLv3+ | Sangoma |
| tts | 16.0.3 | Enabled | GPLv3+ | Sangoma |
| ttsengines | 16.0.3 | Enabled | AGPLv3 | Sangoma |
| ucp | 16.0.38.16 | Enabled | AGPLv3+ | Sangoma |
| userman | 16.0.44.28 | Enabled | AGPLv3+ | Sangoma |
| vega | | Not Installed (Locally available) | Commercial+ | Sangoma |
| vmblast | 16.0.11 | Enabled | GPLv3+ | Sangoma |
| vmnotify | 16.0.10 | Enabled | Commercial | Sangoma |
| voicemail | 16.0.64 | Enabled | GPLv3+ | Sangoma |
| voicemail_report | 16.0.3 | Enabled | Commercial | Sangoma |
| voipinnovations | 16.0.29 | Enabled | Commercial | Sangoma |
| vqplus | 16.0.26 | Enabled | Commercial | Sangoma |
| weakpasswords | 16.0.1 | Enabled | GPLv3+ | Sangoma |
| webcallback | 16.0.3 | Enabled | Commercial | Sangoma |
| webrtc | 16.0.19 | Enabled | GPLv3+ | Sangoma |
| xmpp | 16.0.7 | Enabled | AGPLv3 | Sangoma |
| zulu | 16.0.19 | Enabled | Commercial | Sangoma |
+---------------------+------------+-----------------------------------+-------------+-----------+
Relevant log output
12 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:1] Set("SIP/SBC-00001225", "__DIRECTION=INBOUND") in new stack
13 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:2] Gosub("SIP/SBC-00001225", "sub-record-check,s,1(in,8045541138,dontcare)") in new stack
14 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/SBC-00001225", "0?initialized") in new stack
15 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:2] Set("SIP/SBC-00001225", "__REC_STATUS=INITIALIZED") in new stack
16 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:3] Set("SIP/SBC-00001225", "NOW=1765889104") in new stack
17 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:4] Set("SIP/SBC-00001225", "__DAY=16") in new stack
18 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:5] Set("SIP/SBC-00001225", "__MONTH=12") in new stack
19 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:6] Set("SIP/SBC-00001225", "__YEAR=2025") in new stack
20 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:7] Set("SIP/SBC-00001225", "__TIMESTR=20251216-074504") in new stack
21 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:8] Set("SIP/SBC-00001225", "__FROMEXTEN=unknown") in new stack
22 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:9] Set("SIP/SBC-00001225", "__MON_FMT=wav") in new stack
23 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/SBC-00001225", "Recordings initialized") in new stack
24 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/SBC-00001225", "0?Set(ARG3=dontcare)") in new stack
25 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:12] Set("SIP/SBC-00001225", "REC_POLICY_MODE_SAVE=") in new stack
26 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/SBC-00001225", "0?Set(REC_STATUS=NO)") in new stack
27 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/SBC-00001225", "2?checkaction") in new stack
28 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (sub-record-check,s,17)
29 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/SBC-00001225", "1?sub-record-check,in,1") in new stack
30 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (sub-record-check,in,1)
31 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/SBC-00001225", "Inbound Recording Check to 8045541138") in new stack
32 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [in@sub-record-check:2] Set("SIP/SBC-00001225", "FROMEXTEN=unknown") in new stack
33 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/SBC-00001225", "10?Set(FROMEXTEN=8042666600)") in new stack
34 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/SBC-00001225", "recordcheck,1(dontcare,in,8045541138)") in new stack
35 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/SBC-00001225", "Starting recording check against dontcare") in new stack
36 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/SBC-00001225", "dontcare") in new stack
37 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
38 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/SBC-00001225", "") in new stack
39 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [in@sub-record-check:5] Return("SIP/SBC-00001225", "") in new stack
40 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:3] Set("SIP/SBC-00001225", "CHANNEL(tonezone)=us") in new stack
41 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:4] Set("SIP/SBC-00001225", "__FROM_DID=8045541138") in new stack
42 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:5] Set("SIP/SBC-00001225", "returnhere=1") in new stack
43 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:6] Gosub("SIP/SBC-00001225", "app-blacklist-check,s,1()") in new stack
44 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/SBC-00001225", "0?blacklisted") in new stack
45 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/SBC-00001225", "CALLED_BLACKLIST=1") in new stack
46 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/SBC-00001225", "") in new stack
47 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:7] Set("SIP/SBC-00001225", "CDR(did)=8045541138") in new stack
48 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:8] GotoIf("SIP/SBC-00001225", "0?") in new stack
49 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:9] ExecIf("SIP/SBC-00001225", "0 ?Set(CALLERID(name)=8042666600)") in new stack
50 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:10] Set("SIP/SBC-00001225", "__MOHCLASS=") in new stack
51 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:11] Set("SIP/SBC-00001225", "__REVERSAL_REJECT=FALSE") in new stack
52 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:12] GotoIf("SIP/SBC-00001225", "1?post-reverse-charge") in new stack
53 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (from-pstn,8045541138,14)
54 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:14] NoOp("SIP/SBC-00001225", "") in new stack
55 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:15] Set("SIP/SBC-00001225", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
56 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:16] Set("SIP/SBC-00001225", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
57 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:17] Set("SIP/SBC-00001225", "CALLERID(name-pres)=allowed_not_screened") in new stack
58 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:18] Set("SIP/SBC-00001225", "CALLERID(num-pres)=allowed_not_screened") in new stack
59 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:19] NoOp("SIP/SBC-00001225", "CallerID Entry Point") in new stack
60 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:20] Set("SIP/SBC-00001225", "__CRM_DIRECTION=INBOUND") in new stack
61 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:21] Set("SIP/SBC-00001225", "__CRM_SOURCE=8042666600") in new stack
62 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:22] Set("SIP/SBC-00001225", "__CRM_LINKEDID=1765889104.34412") in new stack
63 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:23] AGI("SIP/SBC-00001225", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
64 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi,true: LINKEDID: 1765889104.34412
65 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi,true: SOURCE: 8042666600
66 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi,true: DESTINATION:
67 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi,true: DIRECTION: INBOUND
68 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi,true: EXTTOCALL:
69 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi,true: START
70 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi,true: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTc2NTg4OTEwNC4zNDQxMiIsInNvdXJjZSI6IjgwNDI2NjY2MDAiLCJkZXN0aW5hdGlvbiI6IiIsImRpcmVjdGlvbiI6IklOQk9VTkQiLCJ0eXBlIjoiU1RBUlQiLCJ6dWx1X3Jhd190eXBlIjoiIiwienVsdV90eXBlIjoiIiwienVsdV91cmwiOiIiLCJleHR0b2NhbGwiOiIiLCJjbnVtIjoiODA0MjY2NjYwMCIsImNuYW0iOiI4MDQyNjY2NjAwIiwiY2FsbHBvcCI6dHJ1ZSwidm9pY2VtYWlsIjoiIiwiZnJvbV9kaWQiOiI4MDQ1NTQxMTM4In0=' > /dev/null 2>&1 &
71 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] res_agi.c: <SIP/SBC-00001225>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
72 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:24] ExecIf("SIP/SBC-00001225", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
73 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [8045541138@from-pstn:25] Goto("SIP/SBC-00001225", "from-did-direct,6008,1") in new stack
74 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (from-did-direct,6008,1)
75 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [6008@from-did-direct:1] GotoIf("SIP/SBC-00001225", "0?ext-local,*6008,1") in new stack
76 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [6008@from-did-direct:2] GotoIf("SIP/SBC-00001225", "1?ext-local,6008,1:followme-check,6008,1") in new stack
77 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (ext-local,6008,1)
78 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [6008@ext-local:1] Set("SIP/SBC-00001225", "__RINGTIMER=25") in new stack
79 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [6008@ext-local:2] ExecIf("SIP/SBC-00001225", "0?Set(__CWIGNORE=)") in new stack
80 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [6008@ext-local:3] Macro("SIP/SBC-00001225", "exten-vm,novm,6008,0,0,0") in new stack
81 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/SBC-00001225", "user-callerid,") in new stack
82 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/SBC-00001225", "TOUCH_MONITOR=1765889104.34412") in new stack
83 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/SBC-00001225", "CHANCONTEXT=") in new stack
84 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/SBC-00001225", "CHANCONTEXT=") in new stack
85 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/SBC-00001225", "CHANEXTENCONTEXT=SBC-00001225") in new stack
86 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/SBC-00001225", "CHANEXTEN=SBC-00001225") in new stack
87 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:6] Set("SIP/SBC-00001225", "CALLERID(number)=8042666600") in new stack
88 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/SBC-00001225", "AMPUSER=8042666600") in new stack
89 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:8] Set("SIP/SBC-00001225", "HOTDESCKCHAN=SBC-00001225") in new stack
90 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/SBC-00001225", "HOTDESKEXTEN=SBC") in new stack
91 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/SBC-00001225", "HOTDESKCALL=0") in new stack
92 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("SIP/SBC-00001225", "0?Set(HOTDESKCALL=1)") in new stack
93 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("SIP/SBC-00001225", "0?Set(CALLERID(name)=)") in new stack
94 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("SIP/SBC-00001225", "0?report") in new stack
95 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/SBC-00001225", "1?Set(REALCALLERIDNUM=8042666600)") in new stack
96 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/SBC-00001225", "AMPUSER=") in new stack
97 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/SBC-00001225", "0?limit") in new stack
98 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/SBC-00001225", "AMPUSERCIDNAME=") in new stack
99 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/SBC-00001225", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
100 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/SBC-00001225", "1?report") in new stack
101 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-user-callerid,s,28)
102 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:28] NoOp("SIP/SBC-00001225", "Macro Depth is 2") in new stack
103 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/SBC-00001225", "1?report2:macroerror") in new stack
104 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-user-callerid,s,30)
105 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/SBC-00001225", "0?continue") in new stack
106 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("SIP/SBC-00001225", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
107 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/SBC-00001225", "__TTL=64") in new stack
108 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("SIP/SBC-00001225", "1?continue") in new stack
109 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-user-callerid,s,49)
110 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/SBC-00001225", "CALLERID(number)=8042666600") in new stack
111 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/SBC-00001225", "CALLERID(name)=8042666600") in new stack
112 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("SIP/SBC-00001225", "0?cnum") in new stack
113 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:52] Set("SIP/SBC-00001225", "CDR(cnam)=8042666600") in new stack
114 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/SBC-00001225", "CDR(cnum)=8042666600") in new stack
115 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/SBC-00001225", "CHANNEL(language)=en") in new stack
116 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/SBC-00001225", "RingGroupMethod=none") in new stack
117 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/SBC-00001225", "__EXTTOCALL=6008") in new stack
118 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/SBC-00001225", "__PICKUPMARK=6008") in new stack
119 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/SBC-00001225", "RT=") in new stack
120 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:6] GotoIf("SIP/SBC-00001225", "1?startcheck:exitcheck") in new stack
121 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-exten-vm,s,7)
122 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:7] GotoIf("SIP/SBC-00001225", "1?featureSIP:featurePJSIP") in new stack
123 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-exten-vm,s,8)
124 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("SIP/SBC-00001225", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
125 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("SIP/SBC-00001225", "0?MacroExit()") in new stack
126 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:10] GotoIf("SIP/SBC-00001225", "1?checkfeaturemoni:featurePJSIP") in new stack
127 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-exten-vm,s,13)
128 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:13] GotoIf("SIP/SBC-00001225", "0?featuremoniPJSIP:featuremoniSIP") in new stack
129 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-exten-vm,s,17)
130 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("SIP/SBC-00001225", "0?ChanSpy(PJSIP/6008,q)") in new stack
131 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:18] ExecIf("SIP/SBC-00001225", "0?MacroExit()") in new stack
132 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:19] GotoIf("SIP/SBC-00001225", "1?ext-intercomSIP:ext-intercomPJSIP") in new stack
133 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-exten-vm,s,23)
134 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:23] ExecIf("SIP/SBC-00001225", "0?Gosub(ext-intercom,*806008,1())") in new stack
135 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:24] ExecIf("SIP/SBC-00001225", "0?MacroExit()") in new stack
136 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:25] NoOp("SIP/SBC-00001225", "Exiting Checks") in new stack
137 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:26] Gosub("SIP/SBC-00001225", "sub-record-check,s,1(exten,6008,dontcare)") in new stack
138 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/SBC-00001225", "10?initialized") in new stack
139 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (sub-record-check,s,10)
140 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/SBC-00001225", "Recordings initialized") in new stack
141 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/SBC-00001225", "0?Set(ARG3=dontcare)") in new stack
142 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:12] Set("SIP/SBC-00001225", "REC_POLICY_MODE_SAVE=") in new stack
143 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/SBC-00001225", "0?Set(REC_STATUS=NO)") in new stack
144 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/SBC-00001225", "5?checkaction") in new stack
145 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (sub-record-check,s,17)
146 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/SBC-00001225", "1?sub-record-check,exten,1") in new stack
147 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (sub-record-check,exten,1)
148 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/SBC-00001225", "Exten Recording Check between 8042666600 and 6008") in new stack
149 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/SBC-00001225", "CALLTYPE=external") in new stack
150 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/SBC-00001225", "0?Set(CALLTYPE=)") in new stack
151 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/SBC-00001225", "CALLEE=dontcare") in new stack
152 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/SBC-00001225", "0?Set(CALLEE=dontcare)") in new stack
153 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/SBC-00001225", "1?callee") in new stack
154 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (sub-record-check,exten,11)
155 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/SBC-00001225", "recordcheck,1(dontcare,external,6008)") in new stack
156 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/SBC-00001225", "Starting recording check against dontcare") in new stack
157 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/SBC-00001225", "dontcare") in new stack
158 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
159 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/SBC-00001225", "") in new stack
160 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/SBC-00001225", "") in new stack
161 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:27] GotoIf("SIP/SBC-00001225", "1?macrodial") in new stack
162 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-exten-vm,s,33)
163 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:33] GosubIf("SIP/SBC-00001225", "0?clrheader,1()") in new stack
164 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-exten-vm:34] Macro("SIP/SBC-00001225", "dial-one,,HhTtr,6008") in new stack
165 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/SBC-00001225", "DEXTEN=6008") in new stack
166 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:2] Set("SIP/SBC-00001225", "__CRM_SOURCE=8042666600") in new stack
167 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:3] Gosub("SIP/SBC-00001225", "zulu-user-settings-map,s,1(6008)") in new stack
168 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@zulu-user-settings-map:1] GotoIf("SIP/SBC-00001225", "0?6008,1") in new stack
169 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@zulu-user-settings-map:2] Return("SIP/SBC-00001225", "") in new stack
170 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:4] Gosub("SIP/SBC-00001225", "zulu-send-event,s,1(6008,8042666600)") in new stack
171 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@zulu-send-event:1] GotoIf("SIP/SBC-00001225", "1?skippopup") in new stack
172 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (zulu-send-event,s,4)
173 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@zulu-send-event:4] NoOp("SIP/SBC-00001225", "No Call Pop") in new stack
174 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@zulu-send-event:5] UserEvent("SIP/SBC-00001225", "zulu-call,eventtype:calling,extension:6008,type:,url:,cnam:ODA0MjY2NjYwMA==,cnum:8042666600,lid:1765889104.34412,from:8042666600,to:6008}") in new stack
175 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@zulu-send-event:6] Return("SIP/SBC-00001225", "") in new stack
176 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:5] UserEvent("SIP/SBC-00001225", "zulu-inbound-call,type:macro-dial-one,from:8042666600,to:6008") in new stack
177 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:6] ExecIf("SIP/SBC-00001225", "0?Set(__EXTTOCALL=6008)") in new stack
178 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:7] Set("SIP/SBC-00001225", "DIALSTATUS_CW=") in new stack
179 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:8] GosubIf("SIP/SBC-00001225", "0?screen,1()") in new stack
180 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:9] GosubIf("SIP/SBC-00001225", "0?cf,1()") in new stack
181 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:10] GotoIf("SIP/SBC-00001225", "1?skip1") in new stack
182 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-dial-one,s,13)
183 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:13] GotoIf("SIP/SBC-00001225", "0?nodial") in new stack
184 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:14] GotoIf("SIP/SBC-00001225", "0?continue") in new stack
185 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:15] Set("SIP/SBC-00001225", "EXTHASCW=ENABLED") in new stack
186 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:16] GotoIf("SIP/SBC-00001225", "0?next1:cwinusebusy") in new stack
187 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-dial-one,s,28)
188 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:28] GotoIf("SIP/SBC-00001225", "0?next3:continue") in new stack
189 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-dial-one,s,30)
190 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:30] GotoIf("SIP/SBC-00001225", "0?nodial") in new stack
191 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:31] GosubIf("SIP/SBC-00001225", "1?dstring,1():dlocal,1()") in new stack
192 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/SBC-00001225", "DSTRING=") in new stack
193 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/SBC-00001225", "DEVICES=6008") in new stack
194 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/SBC-00001225", "0?Return()") in new stack
195 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/SBC-00001225", "0?Set(DEVICES=008)") in new stack
196 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/SBC-00001225", "LOOPCNT=1") in new stack
197 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/SBC-00001225", "ITER=1") in new stack
198 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/SBC-00001225", "THISDIAL=PJSIP/6008") in new stack
199 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf("SIP/SBC-00001225", "0?docheck") in new stack
200 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:9] NoOp("SIP/SBC-00001225", "Debug: Found PJSIP Destination PJSIP/6008") in new stack
201 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:10] GotoIf("SIP/SBC-00001225", "0?doset") in new stack
202 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:11] NoOp("SIP/SBC-00001225", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
203 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:12] Set("SIP/SBC-00001225", "THISDIAL=PJSIP/6008/sip:6008@192.168.101.114:5260") in new stack
204 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:13] ExecIf("SIP/SBC-00001225", "0?Set(DIALSTATUS=CHANUNAVAIL)") in new stack
205 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("SIP/SBC-00001225", "0?skipset") in new stack
206 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:15] Set("SIP/SBC-00001225", "DSTRING=PJSIP/6008/sip:6008@192.168.101.114:5260&") in new stack
207 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/SBC-00001225", "ITER=2") in new stack
208 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("SIP/SBC-00001225", "0?begin") in new stack
209 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("SIP/SBC-00001225", "0?Return()") in new stack
210 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:19] Set("SIP/SBC-00001225", "DSTRING=PJSIP/6008/sip:6008@192.168.101.114:5260") in new stack
211 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [dstring@macro-dial-one:20] Return("SIP/SBC-00001225", "") in new stack
212 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:32] GotoIf("SIP/SBC-00001225", "0?nodial") in new stack
213 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:33] GotoIf("SIP/SBC-00001225", "0?skiptrace") in new stack
214 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:34] GosubIf("SIP/SBC-00001225", "1?ctset,1():ctclear,1()") in new stack
215 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [ctset@macro-dial-one:1] Set("SIP/SBC-00001225", "DB(CALLTRACE/6008)=8042666600") in new stack
216 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [ctset@macro-dial-one:2] Return("SIP/SBC-00001225", "") in new stack
217 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:35] Set("SIP/SBC-00001225", "D_OPTIONS=HhTtr") in new stack
218 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:36] GosubIf("SIP/SBC-00001225", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
219 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:37] NoOp("SIP/SBC-00001225", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
220 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:38] ExecIf("SIP/SBC-00001225", "0?Set(ALERT_INFO=)") in new stack
221 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:39] ExecIf("SIP/SBC-00001225", "0?Set(ALERT_INFO=)") in new stack
222 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:40] ExecIf("SIP/SBC-00001225", "0?Set(ALERT_INFO=)") in new stack
223 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:41] ExecIf("SIP/SBC-00001225", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
224 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:42] ExecIf("SIP/SBC-00001225", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
225 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:43] GosubIf("SIP/SBC-00001225", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
226 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:44] ExecIf("SIP/SBC-00001225", "0?Set(CHANNEL(musicclass)=)") in new stack
227 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:45] GosubIf("SIP/SBC-00001225", "0?qwait,1()") in new stack
228 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:46] Set("SIP/SBC-00001225", "__CWIGNORE=") in new stack
229 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:47] Set("SIP/SBC-00001225", "__KEEPCID=TRUE") in new stack
230 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:48] GotoIf("SIP/SBC-00001225", "0?usegoto,1") in new stack
231 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:49] GotoIf("SIP/SBC-00001225", "1?godial") in new stack
232 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-dial-one,s,54)
233 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:54] Macro("SIP/SBC-00001225", "dialout-one-predial-hook,") in new stack
234 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/SBC-00001225", "") in new stack
235 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:55] ExecIf("SIP/SBC-00001225", "1?Set(D_OPTIONS=HhtrI)") in new stack
236 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:56] ExecIf("SIP/SBC-00001225", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
237 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:57] Set("SIP/SBC-00001225", "__ZULU_TIMEOUT=25") in new stack
238 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:58] Set("SIP/SBC-00001225", "__ZULU_FROMEXTEN=8042666600") in new stack
239 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:59] Set("SIP/SBC-00001225", "__ZULU_DIALOPTS=HhTtr") in new stack
240 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:60] NoOp("SIP/SBC-00001225", "Finished") in new stack
241 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:61] NoOp("SIP/SBC-00001225", "") in new stack
242 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:62] ExecIf("SIP/SBC-00001225", "0?Set(D_OPTIONS=HhtrI)") in new stack
243 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:63] ExecIf("SIP/SBC-00001225", "0?Set(D_OPTIONS=HhtrIg)") in new stack
244 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-dial-one:64] Dial("SIP/SBC-00001225", "PJSIP/6008/sip:6008@192.168.101.114:5260,,HhtrIb(func-apply-sipheaders^s^1)") in new stack
245 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] app_stack.c: PJSIP/6008-000033e8 Internal Gosub(func-apply-sipheaders,s,1) start
246 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/6008-000033e8", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
247 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/6008-000033e8", "Applying SIP Headers to channel PJSIP/6008-000033e8") in new stack
248 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/6008-000033e8", "TECH=PJSIP") in new stack
249 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/6008-000033e8", "SIPHEADERKEYS=") in new stack
250 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/6008-000033e8", "0") in new stack
251 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] app_while.c: Jumping to priority 13
252 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/6008-000033e8", "") in new stack
253 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] app_stack.c: Spawn extension (from-internal, 6008, 1) exited non-zero on 'PJSIP/6008-000033e8'
254 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] app_stack.c: PJSIP/6008-000033e8 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
255 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] app_dial.c: Called PJSIP/6008/sip:6008@192.168.101.114:5260
256 [2025-12-16 07:45:04] VERBOSE[16095] netsock2.c: Using SIP RTP Audio TOS bits 184
257 [2025-12-16 07:45:04] VERBOSE[16095] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
258 [2025-12-16 07:45:04] VERBOSE[16095] netsock2.c: Using SIP RTP Audio CoS mark 5
259 [2025-12-16 07:45:04] VERBOSE[10120][C-00001cca] app_dial.c: Connected line update to SIP/SBC-00001225 prevented.
260 [2025-12-16 07:45:05] VERBOSE[10120][C-00001cca] app_dial.c: PJSIP/6008-000033e8 is ringing
261 [2025-12-16 07:45:06] VERBOSE[10120][C-00001cca] app_dial.c: PJSIP/6008-000033e8 answered SIP/SBC-00001225
262 [2025-12-16 07:45:06] VERBOSE[10187][C-00001cca] bridge_channel.c: Channel PJSIP/6008-000033e8 joined 'simple_bridge' basic-bridge <5c05f679-3dfe-4ce3-b5d4-3360d82b65af>
263 [2025-12-16 07:45:06] VERBOSE[10120][C-00001cca] bridge_channel.c: Channel SIP/SBC-00001225 joined 'simple_bridge' basic-bridge <5c05f679-3dfe-4ce3-b5d4-3360d82b65af>
264 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] bridge_channel.c: Channel PJSIP/6008-000033e8 left 'simple_bridge' basic-bridge <5c05f679-3dfe-4ce3-b5d4-3360d82b65af>
265 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] app_stack.c: PJSIP/6008-000033e8 Internal Gosub(crm-hangup,s,1) start
266 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/6008-000033e8", "Sending Hangup to CRM") in new stack
267 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/6008-000033e8", "HANGUP CAUSE: 16") in new stack
268 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/6008-000033e8", "0?Set(__CRM_VOICEMAIL=)") in new stack
269 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/6008-000033e8", "MASTER CHANNEL: 1765889104.34413 = 1765889104.34412") in new stack
270 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/6008-000033e8", "1?return") in new stack
271 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] pbx_builtins.c: Goto (crm-hangup,s,8)
272 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/6008-000033e8", "") in new stack
273 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] app_stack.c: Spawn extension (from-internal, , 1) exited non-zero on 'PJSIP/6008-000033e8'
274 [2025-12-16 07:45:11] VERBOSE[10187][C-00001cca] app_stack.c: PJSIP/6008-000033e8 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
275 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] bridge_channel.c: Channel SIP/SBC-00001225 left 'simple_bridge' basic-bridge <5c05f679-3dfe-4ce3-b5d4-3360d82b65af>
276 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] app_macro.c: Spawn extension (macro-dial-one, s, 64) exited non-zero on 'SIP/SBC-00001225' in macro 'dial-one'
277 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] app_macro.c: Spawn extension (macro-exten-vm, s, 34) exited non-zero on 'SIP/SBC-00001225' in macro 'exten-vm'
278 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Spawn extension (ext-local, 6008, 3) exited non-zero on 'SIP/SBC-00001225'
279 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [h@ext-local:1] Macro("SIP/SBC-00001225", "hangupcall,") in new stack
280 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-hangupcall:1] UserEvent("SIP/SBC-00001225", "MES,RTPAUDIOQOSMESBRIDGED:,RTPAUDIOQOSMES:") in new stack
281 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-hangupcall:2] GotoIf("SIP/SBC-00001225", "1?theend") in new stack
282 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx_builtins.c: Goto (macro-hangupcall,s,4)
283 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-hangupcall:4] ExecIf("SIP/SBC-00001225", "0?Set(CDR(recordingfile)=)") in new stack
284 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@macro-hangupcall:5] Hangup("SIP/SBC-00001225", "") in new stack
285 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] app_macro.c: Spawn extension (macro-hangupcall, s, 5) exited non-zero on 'SIP/SBC-00001225' in macro 'hangupcall'
286 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/SBC-00001225'
287 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] app_stack.c: SIP/SBC-00001225 Internal Gosub(crm-hangup,s,1) start
288 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/SBC-00001225", "Sending Hangup to CRM") in new stack
289 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/SBC-00001225", "HANGUP CAUSE: 16") in new stack
290 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/SBC-00001225", "0?Set(__CRM_VOICEMAIL=)") in new stack
291 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/SBC-00001225", "MASTER CHANNEL: 1765889104.34412 = 1765889104.34412") in new stack
292 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/SBC-00001225", "0?return") in new stack
293 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@crm-hangup:6] Set("SIP/SBC-00001225", "__CRM_HANGUP=1") in new stack
294 [2025-12-16 07:45:11] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/SBC-00001225", "agi://127.0.0.1/sangomacrm.agi") in new stack
295 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi: LINKEDID: 1765889104.34412
296 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi: SOURCE: 8042666600
297 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi: DESTINATION:
298 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi: DIRECTION: INBOUND
299 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi: EXTTOCALL: 6008
300 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi: START
301 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] res_agi.c: agi://127.0.0.1/sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTc2NTg4OTEwNC4zNDQxMiIsInNvdXJjZSI6IjgwNDI2NjY2MDAiLCJkZXN0aW5hdGlvbiI6IiIsImRpcmVjdGlvbiI6IklOQk9VTkQiLCJ0eXBlIjoiRU5EIiwienVsdV9yYXdfdHlwZSI6IiIsInp1bHVfdHlwZSI6IiIsInp1bHVfdXJsIjoiIiwiZXh0dG9jYWxsIjoiNjAwOCIsImNudW0iOiI4MDQyNjY2NjAwIiwiY25hbSI6IjgwNDI2NjY2MDAiLCJjYWxscG9wIjpmYWxzZSwidm9pY2VtYWlsIjoiIiwiZnJvbV9kaWQiOiI4MDQ1NTQxMTM4In0=' > /dev/null 2>&1 &
302 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] res_agi.c: <SIP/SBC-00001225>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
303 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] pbx.c: Executing [s@crm-hangup:8] Return("SIP/SBC-00001225", "") in new stack
304 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/SBC-00001225'
305 [2025-12-16 07:45:12] VERBOSE[10120][C-00001cca] app_stack.c: SIP/SBC-00001225 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=