[asterisk-users] Why is CDR(recordingfile) not being written to the database despite being set in the dialplan?
Alex Villacís Lasso
a_villacis at palosanto.com
Fri Apr 17 10:06:35 CDT 2015
I am using Asterisk 11.17.1 with my program that uses AMI Originate calls to generate a bunch of calls for a callcenter. The PBX configuration is handled by FreePBX 2.11. I want to understand the dialplan behavior in order to figure out why the
CDR(recordingfile) is blank on the CDR records despite the dialplan setting it.
My program generates the calls by setting Channel=Local/NUMBERTODIAL at from-internal and by setting Exten=QUEUENUM, Context=from-internal, Priority=1 . The FreePBX-generated dialplan results in the following trace as seen in the Asterisk console. In
particular, please note that CDR(recordingfile) is being set in Local/9991416445 at from-internal-0000017b;1 :
-- Executing [9991416445 at from-internal:1] Macro("Local/9991416445 at from-internal-0000017b;2", "user-callerid,LIMIT,EXTERNAL,") in new stack
-- Executing [s at macro-user-callerid:1] Set("Local/9991416445 at from-internal-0000017b;2", "TOUCH_MONITOR=1429224932.21697") in new stack
-- Executing [s at macro-user-callerid:2] Set("Local/9991416445 at from-internal-0000017b;2", "AMPUSER=9991416445") in new stack
-- Executing [s at macro-user-callerid:3] GotoIf("Local/9991416445 at from-internal-0000017b;2", "0?report") in new stack
...
-- Executing [s at sub-record-check:19] Set("Local/9991416445 at from-internal-0000017b;2", "__TIMESTR=20150416-175532") in new stack
-- Executing [s at sub-record-check:20] Set("Local/9991416445 at from-internal-0000017b;2", "__FROMEXTEN=9991416445") in new stack
-- Executing [s at sub-record-check:21] Set("Local/9991416445 at from-internal-0000017b;2", "__CALLFILENAME=out-9991416445-9991416445-20150416-175532-1429224932.21697") in new stack
-- Executing [s at sub-record-check:22] Goto("Local/9991416445 at from-internal-0000017b;2", "out,1") in new stack
-- Goto (sub-record-check,out,1)
-- Executing [out at sub-record-check:1] ExecIf("Local/9991416445 at from-internal-0000017b;2", "1?Set(__REC_POLICY_MODE=)") in new stack
-- Executing [out at sub-record-check:2] GosubIf("Local/9991416445 at from-internal-0000017b;2", "0?record,1(exten,9991416445,9991416445)") in new stack
-- Executing [out at sub-record-check:3] Return("Local/9991416445 at from-internal-0000017b;2", "") in new stack
-- Executing [9991416445 at from-internal:5] PlayTones("Local/9991416445 at from-internal-0000017b;2", "ring") in new stack
-- Executing [9991416445 at from-internal:6] Dial("Local/9991416445 at from-internal-0000017b;2", "SIP/5547741200/019991416445,40,tTorR") in new stack
...
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called SIP/5547741200/0459991416445
-- SIP/5547741200-000051cb is ringing
-- SIP/5547741200-000051cb is ringing
-- SIP/5547741200-000051cb is making progress passing it to Local/9991416445 at from-internal-0000017b;2
-- SIP/5547741200-000051cb is making progress passing it to Local/9991416445 at from-internal-0000017b;2
-- SIP/5547741200-000051cb is making progress passing it to Local/9991416445 at from-internal-0000017b;2
-- SIP/5547741200-000051cb is ringing
-- SIP/5547741200-000051cb is making progress passing it to Local/9991416445 at from-internal-0000017b;2
-- SIP/5547741200-000051cb answered Local/9991416445 at from-internal-0000017b;2
-- Executing [6001 at from-internal:1] Macro("Local/9991416445 at from-internal-0000017b;1", "user-callerid,") in new stack
-- Executing [s at macro-user-callerid:1] Set("Local/9991416445 at from-internal-0000017b;1", "TOUCH_MONITOR=1429224932.21696") in new stack
-- Executing [s at macro-user-callerid:2] Set("Local/9991416445 at from-internal-0000017b;1", "AMPUSER=9991416445") in new stack
-- Executing [s at macro-user-callerid:3] GotoIf("Local/9991416445 at from-internal-0000017b;1", "0?report") in new stack
-- Executing [s at macro-user-callerid:4] ExecIf("Local/9991416445 at from-internal-0000017b;1", "1?Set(REALCALLERIDNUM=9991416445)") in new stack
-- Executing [s at macro-user-callerid:5] Set("Local/9991416445 at from-internal-0000017b;1", "AMPUSER=") in new stack
...
-- Executing [6001 at from-internal:29] Set("Local/9991416445 at from-internal-0000017b;1", "VQ_POSITION=") in new stack
-- Executing [6001 at from-internal:30] Set("Local/9991416445 at from-internal-0000017b;1", "__MIXMON_FORMAT=wav") in new stack
-- Executing [6001 at from-internal:31] Set("Local/9991416445 at from-internal-0000017b;1", "MONITOR_OPTIONS=b") in new stack
-- Executing [6001 at from-internal:32] Gosub("Local/9991416445 at from-internal-0000017b;1", "sub-record-check,s,1(q,6001,always)") in new stack
-- Executing [s at sub-record-check:1] Set("Local/9991416445 at from-internal-0000017b;1", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s at sub-record-check:2] GotoIf("Local/9991416445 at from-internal-0000017b;1", "1?check") in new stack
-- Goto (sub-record-check,s,7)
-- Executing [s at sub-record-check:7] Set("Local/9991416445 at from-internal-0000017b;1", "__MON_FMT=wav") in new stack
...
-- Executing [q at sub-record-check:1] GosubIf("Local/9991416445 at from-internal-0000017b;1", "1?recq,1(q,6001,9991416445)") in new stack
-- Executing [recq at sub-record-check:1] Set("Local/9991416445 at from-internal-0000017b;1", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
-- Executing [recq at sub-record-check:2] Set("Local/9991416445 at from-internal-0000017b;1", "MONITOR_FILENAME=2015/04/16/q-6001-9991416445-20150416-175556-1429224932.21696") in new stack
-- Executing [recq at sub-record-check:3] MixMonitor("Local/9991416445 at from-internal-0000017b;1", "2015/04/16/q-6001-9991416445-20150416-175556-1429224932.21696.wav,b,") in new stack
-- Executing [recq at sub-record-check:4] Set("Local/9991416445 at from-internal-0000017b;1", "__REC_STATUS=RECORDING") in new stack
== Begin MixMonitor Recording Local/9991416445 at from-internal-0000017b;1
-- Executing [recq at sub-record-check:5] Set("Local/9991416445 at from-internal-0000017b;1", "CDR(recordingfile)=q-6001-9991416445-20150416-175556-1429224932.21696.wav") in new stack
-- Executing [recq at sub-record-check:6] Return("Local/9991416445 at from-internal-0000017b;1", "") in new stack
-- Executing [q at sub-record-check:2] Return("Local/9991416445 at from-internal-0000017b;1", "") in new stack
-- Executing [6001 at from-internal:33] Set("Local/9991416445 at from-internal-0000017b;1", "__CWIGNORE=TRUE") in new stack
-- Executing [6001 at from-internal:34] ExecIf("Local/9991416445 at from-internal-0000017b;1", "0?Playback(, )") in new stack
-- Executing [6001 at from-internal:35] QueueLog("Local/9991416445 at from-internal-0000017b;1", "6001,1429224932.21696,NONE,DID,") in new stack
-- Executing [6001 at from-internal:36] Set("Local/9991416445 at from-internal-0000017b;1", "QAANNOUNCE=") in new stack
-- Executing [6001 at from-internal:37] Set("Local/9991416445 at from-internal-0000017b;1", "VQ_AANNOUNCE=") in new stack
-- Executing [6001 at from-internal:38] Set("Local/9991416445 at from-internal-0000017b;1", "QMAXWAIT=") in new stack
-- Executing [6001 at from-internal:39] Set("Local/9991416445 at from-internal-0000017b;1", "VQ_MAXWAIT=") in new stack
-- Executing [6001 at from-internal:40] Set("Local/9991416445 at from-internal-0000017b;1", "QUEUENUM=6001") in new stack
-- Executing [6001 at from-internal:41] Set("Local/9991416445 at from-internal-0000017b;1", "QUEUEJOINTIME=1429224956") in new stack
-- Executing [6001 at from-internal:42] Queue("Local/9991416445 at from-internal-0000017b;1", "6001,t,,,,,,,,") in new stack
-- Started music on hold, class 'default', on Local/9991416445 at from-internal-0000017b;1
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Executing [h at from-internal:1] Hangup("Local/9991416445 at from-internal-0000017b;2", "") in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on 'Local/9991416445 at from-internal-0000017b;2'
-- SIP/2029-000051d4 is ringing
-- SIP/2029-000051d4 is ringing
== Spawn extension (from-internal, 9991416445, 13) exited non-zero on 'Local/9991416445 at from-internal-0000017b;2'
-- SIP/2029-000051d4 answered Local/9991416445 at from-internal-0000017b;1
-- Stopped music on hold on SIP/5547741200-000051cb
-- Manager 'admin' from 127.0.0.1, hanging up channel: SIP/2029-000051d4
-- Executing [h at from-internal:1] Hangup("SIP/5547741200-000051cb", "") in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/5547741200-000051cb'
== Spawn extension (from-internal, 6001, 42) exited non-zero on 'SIP/5547741200-000051cb'
== MixMonitor close filestream (mixed)
== End MixMonitor Recording Local/9991416445 at from-internal-0000017b;1
However, when I check the CDRs in the database, I see only the following:
+---------------------+------------+------------+------------+---------------+-------------------------------------------+-------------------------+---------+---------------------------------------+----------+---------+-------------+----------+-------------+------------------+-----------+---------------+------------+------+---------------+---------------+----------+-----+
| calldate | clid | src | dst | dcontext | channel | dstchannel | lastapp | lastdata | duration | billsec | disposition | amaflags | accountcode
| uniqueid | userfield | recordingfile | cnum | cnam | outbound_cnum | outbound_cnam | dst_cnam | did |
+---------------------+------------+------------+------------+---------------+-------------------------------------------+-------------------------+---------+---------------------------------------+----------+---------+-------------+----------+-------------+------------------+-----------+---------------+------------+------+---------------+---------------+----------+-----+
| 2015-04-16 17:55:56 | 9991416445 | 9991416445 | 6001 | from-internal | SIP/5547741200-000051cb | SIP/2029-000051d4 | Queue | 6001,t,,,,,,,, | 9 | 8 | ANSWERED | 3 |
| 1429224932.21699 | | | | | | | | |
| 2015-04-16 17:55:32 | 9991416445 | 9991416445 | 9991416445 | from-internal | Local/9991416445 at from-internal-0000017b;2 | SIP/5547741200-000051cb | Dial | SIP/5547741200/0459991416445,40,tTorR | 24 | 0 | ANSWERED | 3 |
| 1429224932.21697 | | | 9991416445 | | | | | |
+---------------------+------------+------------+------------+---------------+-------------------------------------------+-------------------------+---------+---------------------------------------+----------+---------+-------------+----------+-------------+------------------+-----------+---------------+------------+------+---------------+---------------+----------+-----+
None of the CDR records have "recordingfile" set, and also none of the CDRs make any mention of Local/9991416445 at from-internal-0000017b;1 .
I compared this trace with one from a FreePBX-2.8. The machine with FreePBX-2.8 does write the "userfield" field when calls are generated by the same dialer. The trace is as follows. The most significant differences I have noticed is that in the failure
case, the Dial was made with the tTorRset of options, and in the successful trace, the Dial options were left blank, and also that in the failure case the MixMonitor starts on a Local channel, but in the successful trace the MixMonitor starts on the
outgoing SIP channel.
[root at pbx ~]# grep C-000105c6 /var/log/asterisk/full
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [94018188888300 at from-internal:1] Macro("Local/94018188888300 at from-internal-0000ad96;2", "user-callerid,SKIPTTL,") in new stack
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [s at macro-user-callerid:1] Set("Local/94018188888300 at from-internal-0000ad96;2", "AMPUSER=94018188888300") in new stack
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [s at macro-user-callerid:2] GotoIf("Local/94018188888300 at from-internal-0000ad96;2", "1?report") in new stack
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] pbx.c: -- Goto (macro-user-callerid,s,10)
...
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [s at macro-dialout-trunk:17] GotoIf("Local/94018188888300 at from-internal-0000ad96;2", "0?bypass,1") in new stack
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [s at macro-dialout-trunk:18] GotoIf("Local/94018188888300 at from-internal-0000ad96;2", "0?customtrunk") in new stack
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [s at macro-dialout-trunk:19] Dial("Local/94018188888300 at from-internal-0000ad96;2", "SIP/5547740414/018188888300,300,") in new stack
[Apr 16 13:42:31] WARNING[20858][C-000105c6] pbx.c: Maximum PBX stack exceeded
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] netsock2.c: == Using SIP RTP TOS bits 184
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] netsock2.c: == Using SIP RTP CoS mark 5
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] app_dial.c: -- Called SIP/5547740414/018188888300
[Apr 16 13:42:31] VERBOSE[20858][C-000105c6] app_dial.c: -- SIP/5547740414-00018da4 is ringing
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] app_dial.c: -- SIP/5547740414-00018da4 is ringing
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] app_dial.c: -- SIP/5547740414-00018da4 is making progress passing it to Local/94018188888300 at from-internal-0000ad96;2
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] app_dial.c: -- SIP/5547740414-00018da4 answered Local/94018188888300 at from-internal-0000ad96;2
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [94003 at from-internal:1] Macro("Local/94018188888300 at from-internal-0000ad96;1", "user-callerid,") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-user-callerid:1] Set("Local/94018188888300 at from-internal-0000ad96;1", "AMPUSER=94018188888300") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-user-callerid:2] GotoIf("Local/94018188888300 at from-internal-0000ad96;1", "1?report") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Goto (macro-user-callerid,s,10)
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-user-callerid:10] GotoIf("Local/94018188888300 at from-internal-0000ad96;1", "0?continue") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-user-callerid:11] Set("Local/94018188888300 at from-internal-0000ad96;1", "__TTL=64") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-user-callerid:12] GotoIf("Local/94018188888300 at from-internal-0000ad96;1", "1?continue") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Goto (macro-user-callerid,s,19)
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-user-callerid:19] Set("Local/94018188888300 at from-internal-0000ad96;1", "CALLERID(number)=94018188888300") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-user-callerid:20] Set("Local/94018188888300 at from-internal-0000ad96;1", "CALLERID(name)=") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-user-callerid:21] NoOp("Local/94018188888300 at from-internal-0000ad96;1", "Using CallerID "" <94018188888300>") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [94003 at from-internal:2] Answer("Local/94018188888300 at from-internal-0000ad96;1", "") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [94003 at from-internal:3] Set("Local/94018188888300 at from-internal-0000ad96;1", "__BLKVM_OVERRIDE=BLKVM/94003/Local/94018188888300 at from-internal-0000ad96;1") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [94003 at from-internal:4] Set("Local/94018188888300 at from-internal-0000ad96;1", "__BLKVM_BASE=94003") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [94003 at from-internal:5] Set("Local/94018188888300 at from-internal-0000ad96;1", "DB(BLKVM/94003/Local/94018188888300 at from-internal-0000ad96;1)=TRUE") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [94003 at from-internal:6] ExecIf("Local/94018188888300 at from-internal-0000ad96;1", "1?Set(_DIAL_OPTIONS=trTM(auto-blkvm))") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [94003 at from-internal:7] Set("Local/94018188888300 at from-internal-0000ad96;1", "__NODEST=94003") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [94003 at from-internal:8] Set("Local/94018188888300 at from-internal-0000ad96;1", "MONITOR_FILENAME=/var/spool/asterisk/monitor/q94003-20150416-134239-1429209751.190681") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [94003 at from-internal:9] Set("Local/94018188888300 at from-internal-0000ad96;1", "__CWIGNORE=TRUE") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [94003 at from-internal:10] Queue("Local/94018188888300 at from-internal-0000ad96;1", "94003,t,,custom/beep26_converted") in new stack
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] res_musiconhold.c: -- Started music on hold, class 'default', on Local/94018188888300 at from-internal-0000ad96;1
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] netsock2.c: == Using SIP RTP TOS bits 184
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] netsock2.c: == Using SIP RTP CoS mark 5
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [h at macro-dialout-trunk:1] Macro("Local/94018188888300 at from-internal-0000ad96;2", "hangupcall,") in new stack
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [s at macro-hangupcall:1] GotoIf("Local/94018188888300 at from-internal-0000ad96;2", "1?endmixmoncheck") in new stack
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] pbx.c: -- Goto (macro-hangupcall,s,9)
...
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [s at macro-hangupcall:48] GotoIf("Local/94018188888300 at from-internal-0000ad96;2", "1?theend") in new stack
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] pbx.c: -- Goto (macro-hangupcall,s,50)
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [s at macro-hangupcall:50] AGI("Local/94018188888300 at from-internal-0000ad96;2", "hangup.agi") in new stack
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] res_agi.c: -- <Local/94018188888300 at from-internal-0000ad96;2>AGI Script hangup.agi completed, returning 0
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] pbx.c: -- Executing [s at macro-hangupcall:51] Hangup("Local/94018188888300 at from-internal-0000ad96;2", "") in new stack
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] app_macro.c: == Spawn extension (macro-hangupcall, s, 51) exited non-zero on 'Local/94018188888300 at from-internal-0000ad96;2' in macro 'hangupcall'
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] pbx.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'Local/94018188888300 at from-internal-0000ad96;2'
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'Local/94018188888300 at from-internal-0000ad96;2' in macro 'dialout-trunk'
[Apr 16 13:42:39] VERBOSE[20858][C-000105c6] pbx.c: == Spawn extension (from-internal, 94018188888300, 6) exited non-zero on 'Local/94018188888300 at from-internal-0000ad96;2'
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] app_queue.c: -- SIP/243-00018dab is ringing
[Apr 16 13:42:39] VERBOSE[21378][C-000105c6] app_queue.c: -- SIP/243-00018dab is ringing
[Apr 16 13:42:42] VERBOSE[21378][C-000105c6] app_queue.c: -- SIP/243-00018dab answered Local/94018188888300 at from-internal-0000ad96;1
[Apr 16 13:42:42] DEBUG[21378][C-000105c6] format_wav.c: Skipping unknown block 'LIST'
[Apr 16 13:42:42] VERBOSE[21378][C-000105c6] file.c: -- <SIP/243-00018dab> Playing 'custom/beep26_converted.slin' (language 'es')
[Apr 16 13:42:42] VERBOSE[21378][C-000105c6] res_musiconhold.c: -- Stopped music on hold on SIP/5547740414-00018da4
[Apr 16 13:42:42] VERBOSE[21568][C-000105c6] app_mixmonitor.c: == Begin MixMonitor Recording SIP/5547740414-00018da4
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [h at from-internal:1] Macro("SIP/5547740414-00018da4", "hangupcall") in new stack
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-hangupcall:1] GotoIf("SIP/5547740414-00018da4", "1?endmixmoncheck") in new stack
...
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-hangupcall:48] GotoIf("SIP/5547740414-00018da4", "1?theend") in new stack
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] pbx.c: -- Goto (macro-hangupcall,s,50)
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-hangupcall:50] AGI("SIP/5547740414-00018da4", "hangup.agi") in new stack
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] res_agi.c: -- <SIP/5547740414-00018da4>AGI Script hangup.agi completed, returning 0
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] pbx.c: -- Executing [s at macro-hangupcall:51] Hangup("SIP/5547740414-00018da4", "") in new stack
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] app_macro.c: == Spawn extension (macro-hangupcall, s, 51) exited non-zero on 'SIP/5547740414-00018da4' in macro 'hangupcall'
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/5547740414-00018da4'
[Apr 16 13:44:57] VERBOSE[21378][C-000105c6] pbx.c: == Spawn extension (from-internal, 94003, 10) exited non-zero on 'SIP/5547740414-00018da4'
[Apr 16 13:44:57] VERBOSE[21568][C-000105c6] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[Apr 16 13:44:57] VERBOSE[21568][C-000105c6] app_mixmonitor.c: == End MixMonitor Recording SIP/5547740414-00018da4
Are the Dial options relevant for the observed behavior? If so, which one is responsible?
Is it expected for variables set in Local/XXX at from-internal-YYYY;1 to not be visible in Local/XXX at from-internal-YYYY;2 and viceversa?
Is there something else I have not noticed that might explain the difference in behavior?
More information about the asterisk-users
mailing list