Retardo en inicio de llamada

Español
1

Buenas tardes.
En la empresa tenemos teléfonos IP Vtech715 conectados a un servidor FreePBX y la salida analógica es a través de un GrandStream.
El problema que tenemos es que, ante cualquier llamada saliente (ya sea dirigida a alguna extensión o a teléfonos al exterior) tras digitar el número completo, hay en tiempo de espera o un retardo como de 15 a 20 segundos aproximadamente, hasta que enlaza al teléfono y empieza a sonar el timbre. Ya actualicé el firmware de un teléfono para hacer la prueba, pero todo sigue igual. Revisé la configuración del teléfono, pero no veo nada fuera de lo normal,. ni tampoco algún indicio de que sea alguna opción con un tiempo especificado en ese sentido.
Le ha pasado algo así a alguien? Agradecería me pudiesen ayudar o aconsejar en este sentido.
De antemano, muchas gracias.
Saludos,
EDSON

2

Hola Edson.
por favor proporcionanos el log de alguna llamada para revisar la causa de este problema.

Saludos.

3

Espero que esto pueda ser de utilidad:

Timestamp Event Type Unique ID Linked ID CID Name CID Num Exten Context Channel
2019-06-03 13:44:52 CHAN_START 1559587492.867 1559587492.867 Levy 1015 0459811673076 from-internal SIP/1015-00000323
2019-06-03 13:44:52 CHAN_START 1559587492.868 1559587492.867 s from-pstn SIP/100-00000324
2019-06-03 13:45:12 ANSWER 1559587492.868 1559587492.867 CID:1015 0459811673076 0459811673076 from-pstn SIP/100-00000324
2019-06-03 13:45:12 ANSWER 1559587492.867 1559587492.867 1015 s macro-dialout-trunk SIP/1015-00000323
2019-06-03 13:45:12 BRIDGE_ENTER 1559587492.868 1559587492.867 CID:1015 0459811673076 from-pstn SIP/100-00000324
2019-06-03 13:45:12 BRIDGE_ENTER 1559587492.867 1559587492.867 1015 s macro-dialout-trunk SIP/1015-00000323
2019-06-03 13:45:20 BRIDGE_EXIT 1559587492.867 1559587492.867 1015 s macro-dialout-trunk SIP/1015-00000323
2019-06-03 13:45:20 BRIDGE_EXIT 1559587492.868 1559587492.867 CID:1015 0459811673076 from-pstn SIP/100-00000324
2019-06-03 13:45:20 HANGUP 1559587492.868 1559587492.867 CID:1015 0459811673076 from-pstn SIP/100-00000324
2019-06-03 13:45:20 CHAN_END 1559587492.868 1559587492.867 CID:1015 0459811673076 from-pstn SIP/100-00000324
2019-06-03 13:45:20 HANGUP 1559587492.867 1559587492.867 1015 h from-internal SIP/1015-00000323
2019-06-03 13:45:20 CHAN_END 1559587492.867 1559587492.867 1015 h from-internal SIP/1015-00000323
2019-06-03 13:45:20 LINKEDID_END 1559587492.867 1559587492.867 1015 h from-internal SIP/1015-00000323
4

Esta es la siguiente prueba que hice:

2019-06-03 14:13:37 00:00:20 Levy <1015> 0459811673076 2019-06-03 14:13:37 00:00:20 Levy <1015> called Unknown <>
— — —
2019-06-03 14:13:57 00:00:00 Levy <1015> hung up
Timestamp Event Type Unique ID Linked ID CID Name CID Num Exten Context Channel
— — — — — — — — —
2019-06-03 14:13:37 CHAN_START 1559589217.869 1559589217.869 Levy 1015 0459811673076 from-internal SIP/1015-00000325
2019-06-03 14:13:37 CHAN_START 1559589217.870 1559589217.869 s from-pstn SIP/100-00000326
2019-06-03 14:13:57 HANGUP 1559589217.870 1559589217.869 CID:1015 0459811673076 0459811673076 from-pstn SIP/100-00000326
2019-06-03 14:13:57 CHAN_END 1559589217.870 1559589217.869 CID:1015 0459811673076 0459811673076 from-pstn SIP/100-00000326
2019-06-03 14:13:57 HANGUP 1559589217.869 1559589217.869 1015 h from-internal SIP/1015-00000325
2019-06-03 14:13:57 CHAN_END 1559589217.869 1559589217.869 1015 h from-internal SIP/1015-00000325
2019-06-03 14:13:57 LINKEDID_END 1559589217.869 1559589217.869 1015 h from-internal SIP/1015-00000325

Luego esta:

2019-06-03 14:15:46 00:00:19 Edson <1015> 0459811673076 2019-06-03 14:15:46 00:00:19 Edson <1015> called Unknown <>
— — —
2019-06-03 14:16:05 00:00:00 Edson <1015> hung up
Timestamp Event Type Unique ID Linked ID CID Name CID Num Exten Context Channel
— — — — — — — — —
2019-06-03 14:15:46 CHAN_START 1559589346.871 1559589346.871 Edson 1015 0459811673076 from-internal SIP/1015-00000327
2019-06-03 14:15:46 CHAN_START 1559589346.872 1559589346.871 s from-pstn SIP/100-00000328
2019-06-03 14:16:05 HANGUP 1559589346.872 1559589346.871 CID:1015 0459811673076 0459811673076 from-pstn SIP/100-00000328
2019-06-03 14:16:05 CHAN_END 1559589346.872 1559589346.871 CID:1015 0459811673076 0459811673076 from-pstn SIP/100-00000328
2019-06-03 14:16:05 HANGUP 1559589346.871 1559589346.871 1015 h from-internal SIP/1015-00000327
2019-06-03 14:16:05 CHAN_END 1559589346.871 1559589346.871 1015 h from-internal SIP/1015-00000327
2019-06-03 14:16:05 LINKEDID_END 1559589346.871 1559589346.871 1015 h from-internal SIP/1015-00000327

Y así, hice 2 más.
Como ven, la duración de la “llamada” es de, alrededor de los 20 segundos. Sin embargo, ese fue el tiempo nada más, entre que se marcó el número y sonó el primer tono, puesto que inmediatamente después, colgué. NO hubo una “llamada” como tal.
De antemano, mil gracias.
EDSON

5

Me refiero a un log de Asterisk.
Se encuentra en /var/log/asterisk/full

6

el log es demasiado largo, así que lo mandaré por partes:

[2019-06-04 11:31:21] VERBOSE[9979][C-000001d1] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“SIP/1015-00000341”, “SIPHEADERKEYS=”) in new stack
[2019-06-04 11:31:21] VERBOSE[9979][C-000001d1] pbx.c: Executing [s@func-apply-sipheaders:3] While(“SIP/1015-00000341”, “0”) in new stack
[2019-06-04 11:31:21] VERBOSE[9979][C-000001d1] app_while.c: Jumping to priority 6
[2019-06-04 11:31:21] VERBOSE[9979][C-000001d1] pbx.c: Executing [s@func-apply-sipheaders:7] Return(“SIP/1015-00000341”, “”) in new stack
[2019-06-04 11:31:21] VERBOSE[9979][C-000001d1] app_stack.c: Spawn extension (from-internal, 1015, 1) exited non-zero on ‘SIP/1015-00000341’
[2019-06-04 11:31:21] VERBOSE[9979][C-000001d1] app_stack.c: SIP/1015-00000341 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-06-04 11:31:21] VERBOSE[9979][C-000001d1] app_dial.c: Called SIP/1015
[2019-06-04 11:31:21] VERBOSE[9979][C-000001d1] app_dial.c: Connected line update to SIP/1001-00000340 prevented.
[2019-06-04 11:31:22] VERBOSE[9979][C-000001d1] app_dial.c: SIP/1015-00000341 is ringing
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] app_macro.c: Spawn extension (macro-dial-one, s, 51) exited non-zero on ‘SIP/1001-00000340’ in macro ‘dial-one’
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] app_macro.c: Spawn extension (macro-exten-vm, s, 20) exited non-zero on ‘SIP/1001-00000340’ in macro ‘exten-vm’
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Spawn extension (ext-local, 1015, 2) exited non-zero on ‘SIP/1001-00000340’
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Executing [h@ext-local:1] Macro(“SIP/1001-00000340”, “hangupcall,”) in new stack
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/1001-00000340”, “1?theend”) in new stack
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/1001-00000340”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/1001-00000340”, “”) in new stack
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/1001-00000340’ in macro ‘hangupcall’
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/1001-00000340’
[2019-06-04 11:52:56] VERBOSE[2007][C-000001d2] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-04 11:52:56] VERBOSE[2007][C-000001d2] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [015526152289@from-internal:1] Macro(“SIP/1000-00000342”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/1000-00000342”, “TOUCH_MONITOR=1559667176.898”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/1000-00000342”, “AMPUSER=1000”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/1000-00000342”, “0?report”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/1000-00000342”, “1?Set(REALCALLERIDNUM=1000)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/1000-00000342”, “AMPUSER=1000”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/1000-00000342”, “0?limit”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/1000-00000342”, “AMPUSERCIDNAME=Rafael”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/1000-00000342”, “0?report”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:9] Set(“SIP/1000-00000342”, “AMPUSERCID=1000”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/1000-00000342”, “__DIAL_OPTIONS=Ttr”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/1000-00000342”, “CALLERID(all)=“Rafael” <1000>”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:12] GotoIf(“SIP/1000-00000342”, “0?limit”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/1000-00000342”, “1?Set(GROUP(concurrency_limit)=1000)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/1000-00000342”, “0?Set(CHANNEL(language)=)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:15] GotoIf(“SIP/1000-00000342”, “1?continue”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:29] Set(“SIP/1000-00000342”, “CALLERID(number)=1000”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:30] Set(“SIP/1000-00000342”, “CALLERID(name)=Rafael”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“SIP/1000-00000342”, “0?cnum”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:32] Set(“SIP/1000-00000342”, “CDR(cnam)=Rafael”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:33] Set(“SIP/1000-00000342”, “CDR(cnum)=1000”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-user-callerid:34] Set(“SIP/1000-00000342”, “CHANNEL(language)=en”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [015526152289@from-internal:2] Gosub(“SIP/1000-00000342”, “sub-record-check,s,1(out,015526152289,dontcare)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/1000-00000342”, “0?initialized”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/1000-00000342”, “__REC_STATUS=INITIALIZED”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/1000-00000342”, “NOW=1559667176”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/1000-00000342”, “__DAY=04”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/1000-00000342”, “__MONTH=06”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/1000-00000342”, “__YEAR=2019”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/1000-00000342”, “__TIMESTR=20190604-115256”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/1000-00000342”, “__FROMEXTEN=1000”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/1000-00000342”, “__MON_FMT=wav”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/1000-00000342”, “Recordings initialized”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/1000-00000342”, “0?Set(ARG3=dontcare)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/1000-00000342”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/1000-00000342”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/1000-00000342”, “3?checkaction”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/1000-00000342”, “1?sub-record-check,out,1”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx_builtins.c: Goto (sub-record-check,out,1)
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [out@sub-record-check:1] NoOp(“SIP/1000-00000342”, “Outbound Recording Check from 1000 to 015526152289”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [out@sub-record-check:2] Set(“SIP/1000-00000342”, “RECMODE=dontcare”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [out@sub-record-check:3] ExecIf(“SIP/1000-00000342”, “1?Goto(routewins)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx_builtins.c: Goto (sub-record-check,out,7)
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [out@sub-record-check:7] Gosub(“SIP/1000-00000342”, “recordcheck,1(dontcare,out,015526152289)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/1000-00000342”, “Starting recording check against dontcare”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/1000-00000342”, “dontcare”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/1000-00000342”, “”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [out@sub-record-check:8] Return(“SIP/1000-00000342”, “”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [015526152289@from-internal:3] ExecIf(“SIP/1000-00000342”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [015526152289@from-internal:4] Set(“SIP/1000-00000342”, “MOHCLASS=default”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [015526152289@from-internal:5] Set(“SIP/1000-00000342”, “_NODEST=”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [015526152289@from-internal:6] Macro(“SIP/1000-00000342”, “dialout-trunk,2,015526152289,off”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“SIP/1000-00000342”, “DIAL_TRUNK=2”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/1000-00000342”, “0?sub-pincheck,s,1()”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/1000-00000342”, “0?disabletrunk,1”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:4] Set(“SIP/1000-00000342”, “DIAL_NUMBER=015526152289”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:5] Set(“SIP/1000-00000342”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“SIP/1000-00000342”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/1000-00000342”, “1?nomax”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx_builtins.c: Goto (macro-dialout-trunk,s,9)
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/1000-00000342”, “0?skipoutcid”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:10] Set(“SIP/1000-00000342”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:11] Macro(“SIP/1000-00000342”, “outbound-callerid,2”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/1000-00000342”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/1000-00000342”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf(“SIP/1000-00000342”, “0?Set(REALCALLERIDNUM=1000)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf(“SIP/1000-00000342”, “1?normcid”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx_builtins.c: Goto (macro-outbound-callerid,s,7)
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:7] Set(“SIP/1000-00000342”, “USEROUTCID=1000”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:8] Set(“SIP/1000-00000342”, “EMERGENCYCID=”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:9] Set(“SIP/1000-00000342”, “TRUNKOUTCID=”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf(“SIP/1000-00000342”, “1?trunkcid”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx_builtins.c: Goto (macro-outbound-callerid,s,15)
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/1000-00000342”, “0?Set(CALLERID(all)=)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/1000-00000342”, “1?Set(CALLERID(all)=1000)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/1000-00000342”, “0?Set(CALLERID(all)=)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf(“SIP/1000-00000342”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf(“SIP/1000-00000342”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:20] Set(“SIP/1000-00000342”, “CDR(outbound_cnum)=1000”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-outbound-callerid:21] Set(“SIP/1000-00000342”, “CDR(outbound_cnam)=”) in new stack
[2019-06-04 11:52:56] WARNING[1960] func_cdr.c: CDR requires a value (CDR(variable)=value)
)[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/1000-00000342”, “0?sub-flp-2,s,1()”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:13] Set(“SIP/1000-00000342”, “OUTNUM=015526152289”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:14] Set(“SIP/1000-00000342”, “custom=SIP/100”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/1000-00000342”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/1000-00000342”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:17] Macro(“SIP/1000-00000342”, “dialout-trunk-predial-hook,”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/1000-00000342”, “”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/1000-00000342”, “0?bypass,1”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/1000-00000342”, “1?Set(CONNECTEDLINE(num,i)=015526152289)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/1000-00000342”, “1?Set(CONNECTEDLINE(name,i)=CID:1000)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/1000-00000342”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)1000)”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf(“SIP/1000-00000342”, “0?customtrunk”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-dialout-trunk:23] Dial(“SIP/1000-00000342”, “SIP/100/015526152289,300,T”) in new stack
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] app_dial.c: Called SIP/100/015526152289
[2019-06-04 11:53:10] VERBOSE[11784][C-000001d2] app_dial.c: SIP/100-00000343 is ringing
[2019-06-04 11:53:12] VERBOSE[11784][C-000001d2] app_dial.c: SIP/100-00000343 answered SIP/1000-00000342
[2019-06-04 11:53:12] VERBOSE[11832][C-000001d2] bridge_channel.c: Channel SIP/100-00000343 joined ‘simple_bridge’ basic-bridge <883c527e-5bff-4167-bd7d-30642338b718>
[2019-06-04 11:53:12] VERBOSE[11784][C-000001d2] bridge_channel.c: Channel SIP/1000-00000342 joined ‘simple_bridge’ basic-bridge <883c527e-5bff-4167-bd7d-30642338b718>
[2019-06-04 11:53:30] VERBOSE[11784][C-000001d2] bridge_channel.c: Channel SIP/1000-00000342 left ‘simple_bridge’ basic-bridge <883c527e-5bff-4167-bd7d-30642338b718>
[2019-06-04 11:53:30] VERBOSE[11832][C-000001d2] bridge_channel.c: Channel SIP/100-00000343 left ‘simple_bridge’ basic-bridge <883c527e-5bff-4167-bd7d-30642338b718>
[2019-06-04 11:53:30] VERBOSE[11784][C-000001d2] app_macro.c: Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on ‘SIP/1000-00000342’ in macro ‘dialout-trunk’
[2019-06-04 11:53:30] VERBOSE[11784][C-000001d2] pbx.c: Spawn extension (from-internal, 015526152289, 6) exited non-zero on ‘SIP/1000-00000342’
[2019-06-04 11:53:30] VERBOSE[11784][C-000001d2] pbx.c: Executing [h@from-internal:1] Macro(“SIP/1000-00000342”, “hangupcall”) in new stack
[2019-06-04 11:53:30] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/1000-00000342”, “1?theend”) in new stack
[2019-06-04 11:53:30] VERBOSE[11784][C-000001d2] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-06-04 11:53:30] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/1000-00000342”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-06-04 11:53:30] VERBOSE[11784][C-000001d2] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/1000-00000342”, “”) in new stack
[2019-06-04 11:53:30] VERBOSE[11784][C-000001d2] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/1000-00000342’ in macro ‘hangupcall’
[2019-06-04 11:53:30] VERBOSE[11784][C-000001d2] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/1000-00000342’

7

[2019-06-04 11:53:36] VERBOSE[2007][C-000001d3] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-04 11:53:36] VERBOSE[2007][C-000001d3] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [015526152508@from-internal:1] Macro(“SIP/1000-00000344”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/1000-00000344”, “TOUCH_MONITOR=1559667216.900”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/1000-00000344”, “AMPUSER=1000”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/1000-00000344”, “0?report”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/1000-00000344”, “1?Set(REALCALLERIDNUM=1000)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/1000-00000344”, “AMPUSER=1000”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/1000-00000344”, “0?limit”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/1000-00000344”, “AMPUSERCIDNAME=Rafael”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/1000-00000344”, “0?report”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:9] Set(“SIP/1000-00000344”, “AMPUSERCID=1000”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/1000-00000344”, “__DIAL_OPTIONS=Ttr”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/1000-00000344”, “CALLERID(all)=“Rafael” <1000>”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:12] GotoIf(“SIP/1000-00000344”, “0?limit”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/1000-00000344”, “1?Set(GROUP(concurrency_limit)=1000)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/1000-00000344”, “0?Set(CHANNEL(language)=)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:15] GotoIf(“SIP/1000-00000344”, “1?continue”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:29] Set(“SIP/1000-00000344”, “CALLERID(number)=1000”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:30] Set(“SIP/1000-00000344”, “CALLERID(name)=Rafael”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“SIP/1000-00000344”, “0?cnum”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:32] Set(“SIP/1000-00000344”, “CDR(cnam)=Rafael”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:33] Set(“SIP/1000-00000344”, “CDR(cnum)=1000”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-user-callerid:34] Set(“SIP/1000-00000344”, “CHANNEL(language)=en”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [015526152508@from-internal:2] Gosub(“SIP/1000-00000344”, “sub-record-check,s,1(out,015526152508,dontcare)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/1000-00000344”, “0?initialized”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/1000-00000344”, “__REC_STATUS=INITIALIZED”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/1000-00000344”, “NOW=1559667216”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/1000-00000344”, “__DAY=04”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/1000-00000344”, “__MONTH=06”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/1000-00000344”, “__YEAR=2019”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/1000-00000344”, “__TIMESTR=20190604-115336”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/1000-00000344”, “__FROMEXTEN=1000”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/1000-00000344”, “__MON_FMT=wav”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/1000-00000344”, “Recordings initialized”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/1000-00000344”, “0?Set(ARG3=dontcare)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/1000-00000344”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/1000-00000344”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/1000-00000344”, “3?checkaction”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/1000-00000344”, “1?sub-record-check,out,1”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx_builtins.c: Goto (sub-record-check,out,1)
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [out@sub-record-check:1] NoOp(“SIP/1000-00000344”, “Outbound Recording Check from 1000 to 015526152508”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [out@sub-record-check:2] Set(“SIP/1000-00000344”, “RECMODE=dontcare”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [out@sub-record-check:3] ExecIf(“SIP/1000-00000344”, “1?Goto(routewins)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx_builtins.c: Goto (sub-record-check,out,7)
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [out@sub-record-check:7] Gosub(“SIP/1000-00000344”, “recordcheck,1(dontcare,out,015526152508)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/1000-00000344”, “Starting recording check against dontcare”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/1000-00000344”, “dontcare”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/1000-00000344”, “”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [out@sub-record-check:8] Return(“SIP/1000-00000344”, “”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [015526152508@from-internal:3] ExecIf(“SIP/1000-00000344”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [015526152508@from-internal:4] Set(“SIP/1000-00000344”, “MOHCLASS=default”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [015526152508@from-internal:5] Set(“SIP/1000-00000344”, “_NODEST=”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [015526152508@from-internal:6] Macro(“SIP/1000-00000344”, “dialout-trunk,2,015526152508,off”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“SIP/1000-00000344”, “DIAL_TRUNK=2”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/1000-00000344”, “0?sub-pincheck,s,1()”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/1000-00000344”, “0?disabletrunk,1”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:4] Set(“SIP/1000-00000344”, “DIAL_NUMBER=015526152508”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:5] Set(“SIP/1000-00000344”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“SIP/1000-00000344”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/1000-00000344”, “1?nomax”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx_builtins.c: Goto (macro-dialout-trunk,s,9)
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/1000-00000344”, “0?skipoutcid”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:10] Set(“SIP/1000-00000344”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:11] Macro(“SIP/1000-00000344”, “outbound-callerid,2”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/1000-00000344”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/1000-00000344”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf(“SIP/1000-00000344”, “0?Set(REALCALLERIDNUM=1000)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf(“SIP/1000-00000344”, “1?normcid”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx_builtins.c: Goto (macro-outbound-callerid,s,7)
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:7] Set(“SIP/1000-00000344”, “USEROUTCID=1000”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:8] Set(“SIP/1000-00000344”, “EMERGENCYCID=”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:9] Set(“SIP/1000-00000344”, “TRUNKOUTCID=”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf(“SIP/1000-00000344”, “1?trunkcid”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx_builtins.c: Goto (macro-outbound-callerid,s,15)
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/1000-00000344”, “0?Set(CALLERID(all)=)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/1000-00000344”, “1?Set(CALLERID(all)=1000)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/1000-00000344”, “0?Set(CALLERID(all)=)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf(“SIP/1000-00000344”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf(“SIP/1000-00000344”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:20] Set(“SIP/1000-00000344”, “CDR(outbound_cnum)=1000”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-outbound-callerid:21] Set(“SIP/1000-00000344”, “CDR(outbound_cnam)=”) in new stack
[2019-06-04 11:53:36] WARNING[1960] func_cdr.c: CDR requires a value (CDR(variable)=value)
)[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/1000-00000344”, “0?sub-flp-2,s,1()”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:13] Set(“SIP/1000-00000344”, “OUTNUM=015526152508”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:14] Set(“SIP/1000-00000344”, “custom=SIP/100”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/1000-00000344”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/1000-00000344”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:17] Macro(“SIP/1000-00000344”, “dialout-trunk-predial-hook,”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/1000-00000344”, “”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/1000-00000344”, “0?bypass,1”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/1000-00000344”, “1?Set(CONNECTEDLINE(num,i)=015526152508)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/1000-00000344”, “1?Set(CONNECTEDLINE(name,i)=CID:1000)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/1000-00000344”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)1000)”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf(“SIP/1000-00000344”, “0?customtrunk”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-dialout-trunk:23] Dial(“SIP/1000-00000344”, “SIP/100/015526152508,300,T”) in new stack
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-04 11:53:36] VERBOSE[11864][C-000001d3] app_dial.c: Called SIP/100/015526152508
[2019-06-04 11:53:50] VERBOSE[11864][C-000001d3] app_dial.c: SIP/100-00000345 is ringing
[2019-06-04 11:53:51] VERBOSE[11864][C-000001d3] app_dial.c: SIP/100-00000345 answered SIP/1000-00000344
[2019-06-04 11:53:51] VERBOSE[11882][C-000001d3] bridge_channel.c: Channel SIP/100-00000345 joined ‘simple_bridge’ basic-bridge <24e45478-df4c-4caa-b648-ec36bb8060c5>
[2019-06-04 11:53:51] VERBOSE[11864][C-000001d3] bridge_channel.c: Channel SIP/1000-00000344 joined ‘simple_bridge’ basic-bridge <24e45478-df4c-4caa-b648-ec36bb8060c5>

8

[2019-06-04 11:55:11] VERBOSE[11864][C-000001d3] bridge_channel.c: Channel SIP/1000-00000344 left ‘simple_bridge’ basic-bridge <24e45478-df4c-4caa-b648-ec36bb8060c5>
[2019-06-04 11:55:11] VERBOSE[11882][C-000001d3] bridge_channel.c: Channel SIP/100-00000345 left ‘simple_bridge’ basic-bridge <24e45478-df4c-4caa-b648-ec36bb8060c5>
[2019-06-04 11:55:11] VERBOSE[11864][C-000001d3] app_macro.c: Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on ‘SIP/1000-00000344’ in macro ‘dialout-trunk’
[2019-06-04 11:55:11] VERBOSE[11864][C-000001d3] pbx.c: Spawn extension (from-internal, 015526152508, 6) exited non-zero on ‘SIP/1000-00000344’
[2019-06-04 11:55:11] VERBOSE[11864][C-000001d3] pbx.c: Executing [h@from-internal:1] Macro(“SIP/1000-00000344”, “hangupcall”) in new stack
[2019-06-04 11:55:11] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/1000-00000344”, “1?theend”) in new stack
[2019-06-04 11:55:11] VERBOSE[11864][C-000001d3] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-06-04 11:55:11] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/1000-00000344”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-06-04 11:55:11] VERBOSE[11864][C-000001d3] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/1000-00000344”, “”) in new stack
[2019-06-04 11:55:11] VERBOSE[11864][C-000001d3] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/1000-00000344’ in macro ‘hangupcall’
[2019-06-04 11:55:11] VERBOSE[11864][C-000001d3] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/1000-00000344’
[2019-06-04 12:04:03] VERBOSE[1931] asterisk.c: Remote UNIX connection
[2019-06-04 12:04:03] VERBOSE[12811] asterisk.c: Remote UNIX connection disconnected
[2019-06-04 12:04:03] VERBOSE[1931] asterisk.c: Remote UNIX connection
[2019-06-04 12:04:03] VERBOSE[12813] asterisk.c: Remote UNIX connection disconnected
[2019-06-04 12:04:03] VERBOSE[1931] asterisk.c: Remote UNIX connection
[2019-06-04 12:04:03] VERBOSE[12815] asterisk.c: Remote UNIX connection disconnected
[2019-06-04 13:04:03] VERBOSE[1931] asterisk.c: Remote UNIX connection
[2019-06-04 13:04:03] VERBOSE[17906] asterisk.c: Remote UNIX connection disconnected
[2019-06-04 13:04:03] VERBOSE[1931] asterisk.c: Remote UNIX connection
[2019-06-04 13:04:03] VERBOSE[17908] asterisk.c: Remote UNIX connection disconnected
[2019-06-04 13:04:03] VERBOSE[1931] asterisk.c: Remote UNIX connection
[2019-06-04 13:04:03] VERBOSE[17910] asterisk.c: Remote UNIX connection disconnected
[2019-06-04 13:42:25] VERBOSE[2007][C-000001d4] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-04 13:42:25] VERBOSE[2007][C-000001d4] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [9284663@from-internal:1] Macro(“SIP/1015-00000346”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/1015-00000346”, “TOUCH_MONITOR=1559673745.902”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/1015-00000346”, “AMPUSER=1015”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/1015-00000346”, “0?report”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/1015-00000346”, “1?Set(REALCALLERIDNUM=1015)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/1015-00000346”, “AMPUSER=1015”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/1015-00000346”, “0?limit”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/1015-00000346”, “AMPUSERCIDNAME=Edson”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/1015-00000346”, “0?report”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:9] Set(“SIP/1015-00000346”, “AMPUSERCID=1015”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/1015-00000346”, “__DIAL_OPTIONS=Ttr”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/1015-00000346”, “CALLERID(all)=“Edson” <1015>”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:12] GotoIf(“SIP/1015-00000346”, “0?limit”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/1015-00000346”, “1?Set(GROUP(concurrency_limit)=1015)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/1015-00000346”, “1?Set(CHANNEL(language)=es)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:15] GotoIf(“SIP/1015-00000346”, “1?continue”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:29] Set(“SIP/1015-00000346”, “CALLERID(number)=1015”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:30] Set(“SIP/1015-00000346”, “CALLERID(name)=Edson”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“SIP/1015-00000346”, “0?cnum”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:32] Set(“SIP/1015-00000346”, “CDR(cnam)=Edson”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:33] Set(“SIP/1015-00000346”, “CDR(cnum)=1015”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-user-callerid:34] Set(“SIP/1015-00000346”, “CHANNEL(language)=es”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [9284663@from-internal:2] Gosub(“SIP/1015-00000346”, “sub-record-check,s,1(out,9284663,dontcare)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/1015-00000346”, “0?initialized”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/1015-00000346”, “__REC_STATUS=INITIALIZED”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/1015-00000346”, “NOW=1559673745”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/1015-00000346”, “__DAY=04”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/1015-00000346”, “__MONTH=06”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/1015-00000346”, “__YEAR=2019”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/1015-00000346”, “__TIMESTR=20190604-134225”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/1015-00000346”, “__FROMEXTEN=1015”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/1015-00000346”, “__MON_FMT=wav”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/1015-00000346”, “Recordings initialized”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/1015-00000346”, “0?Set(ARG3=dontcare)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/1015-00000346”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/1015-00000346”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/1015-00000346”, “3?checkaction”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/1015-00000346”, “1?sub-record-check,out,1”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx_builtins.c: Goto (sub-record-check,out,1)
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [out@sub-record-check:1] NoOp(“SIP/1015-00000346”, “Outbound Recording Check from 1015 to 9284663”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [out@sub-record-check:2] Set(“SIP/1015-00000346”, “RECMODE=dontcare”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [out@sub-record-check:3] ExecIf(“SIP/1015-00000346”, “1?Goto(routewins)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx_builtins.c: Goto (sub-record-check,out,7)
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [out@sub-record-check:7] Gosub(“SIP/1015-00000346”, “recordcheck,1(dontcare,out,9284663)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/1015-00000346”, “Starting recording check against dontcare”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/1015-00000346”, “dontcare”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/1015-00000346”, “”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [out@sub-record-check:8] Return(“SIP/1015-00000346”, “”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [9284663@from-internal:3] ExecIf(“SIP/1015-00000346”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [9284663@from-internal:4] Set(“SIP/1015-00000346”, “MOHCLASS=default”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [9284663@from-internal:5] Set(“SIP/1015-00000346”, “_NODEST=”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [9284663@from-internal:6] Macro(“SIP/1015-00000346”, “dialout-trunk,2,9284663,off”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“SIP/1015-00000346”, “DIAL_TRUNK=2”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/1015-00000346”, “0?sub-pincheck,s,1()”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/1015-00000346”, “0?disabletrunk,1”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:4] Set(“SIP/1015-00000346”, “DIAL_NUMBER=9284663”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:5] Set(“SIP/1015-00000346”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“SIP/1015-00000346”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/1015-00000346”, “1?nomax”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx_builtins.c: Goto (macro-dialout-trunk,s,9)
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/1015-00000346”, “0?skipoutcid”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:10] Set(“SIP/1015-00000346”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:11] Macro(“SIP/1015-00000346”, “outbound-callerid,2”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/1015-00000346”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/1015-00000346”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf(“SIP/1015-00000346”, “0?Set(REALCALLERIDNUM=1015)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf(“SIP/1015-00000346”, “1?normcid”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx_builtins.c: Goto (macro-outbound-callerid,s,7)
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:7] Set(“SIP/1015-00000346”, “USEROUTCID=1015”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:8] Set(“SIP/1015-00000346”, “EMERGENCYCID=”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:9] Set(“SIP/1015-00000346”, “TRUNKOUTCID=”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf(“SIP/1015-00000346”, “1?trunkcid”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx_builtins.c: Goto (macro-outbound-callerid,s,15)
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/1015-00000346”, “0?Set(CALLERID(all)=)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/1015-00000346”, “1?Set(CALLERID(all)=1015)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/1015-00000346”, “0?Set(CALLERID(all)=)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf(“SIP/1015-00000346”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf(“SIP/1015-00000346”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:20] Set(“SIP/1015-00000346”, “CDR(outbound_cnum)=1015”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-outbound-callerid:21] Set(“SIP/1015-00000346”, “CDR(outbound_cnam)=”) in new stack
[2019-06-04 13:42:25] WARNING[1960] func_cdr.c: CDR requires a value (CDR(variable)=value)
)[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/1015-00000346”, “0?sub-flp-2,s,1()”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:13] Set(“SIP/1015-00000346”, “OUTNUM=9284663”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:14] Set(“SIP/1015-00000346”, “custom=SIP/100”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/1015-00000346”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/1015-00000346”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:17] Macro(“SIP/1015-00000346”, “dialout-trunk-predial-hook,”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/1015-00000346”, “”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/1015-00000346”, “0?bypass,1”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/1015-00000346”, “1?Set(CONNECTEDLINE(num,i)=9284663)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/1015-00000346”, “1?Set(CONNECTEDLINE(name,i)=CID:1015)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/1015-00000346”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)1015)”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf(“SIP/1015-00000346”, “0?customtrunk”) in new stack
[2019-06-04 13:42:25] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-dialout-trunk:23] Dial(“SIP/1015-00000346”, “SIP/100/9284663,300,T”) in new stack
[2019-06-04 13:42:26] VERBOSE[21572][C-000001d4] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-04 13:42:26] VERBOSE[21572][C-000001d4] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-04 13:42:26] VERBOSE[21572][C-000001d4] app_dial.c: Called SIP/100/9284663

9

[2019-06-04 13:42:26] VERBOSE[21572][C-000001d4] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-04 13:42:26] VERBOSE[21572][C-000001d4] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-04 13:42:26] VERBOSE[21572][C-000001d4] app_dial.c: Called SIP/100/9284663
[2019-06-04 13:42:34] VERBOSE[21572][C-000001d4] app_dial.c: SIP/100-00000347 is ringing
[2019-06-04 13:42:35] VERBOSE[21572][C-000001d4] app_dial.c: SIP/100-00000347 answered SIP/1015-00000346
[2019-06-04 13:42:35] VERBOSE[21573][C-000001d4] bridge_channel.c: Channel SIP/100-00000347 joined ‘simple_bridge’ basic-bridge <74fc498b-a03b-4446-bd08-0508f88142d9>
[2019-06-04 13:42:35] VERBOSE[21572][C-000001d4] bridge_channel.c: Channel SIP/1015-00000346 joined ‘simple_bridge’ basic-bridge <74fc498b-a03b-4446-bd08-0508f88142d9>
[2019-06-04 13:42:36] VERBOSE[21572][C-000001d4] bridge_channel.c: Channel SIP/1015-00000346 left ‘simple_bridge’ basic-bridge <74fc498b-a03b-4446-bd08-0508f88142d9>
[2019-06-04 13:42:36] VERBOSE[21573][C-000001d4] bridge_channel.c: Channel SIP/100-00000347 left ‘simple_bridge’ basic-bridge <74fc498b-a03b-4446-bd08-0508f88142d9>
[2019-06-04 13:42:36] VERBOSE[21572][C-000001d4] app_macro.c: Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on ‘SIP/1015-00000346’ in macro ‘dialout-trunk’
[2019-06-04 13:42:36] VERBOSE[21572][C-000001d4] pbx.c: Spawn extension (from-internal, 9284663, 6) exited non-zero on ‘SIP/1015-00000346’
[2019-06-04 13:42:36] VERBOSE[21572][C-000001d4] pbx.c: Executing [h@from-internal:1] Macro(“SIP/1015-00000346”, “hangupcall”) in new stack
[2019-06-04 13:42:36] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/1015-00000346”, “1?theend”) in new stack
[2019-06-04 13:42:36] VERBOSE[21572][C-000001d4] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-06-04 13:42:36] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/1015-00000346”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-06-04 13:42:36] VERBOSE[21572][C-000001d4] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/1015-00000346”, “”) in new stack
[2019-06-04 13:42:36] VERBOSE[21572][C-000001d4] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/1015-00000346’ in macro ‘hangupcall’
[2019-06-04 13:42:36] VERBOSE[21572][C-000001d4] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/1015-00000346’
[2019-06-04 13:43:59] VERBOSE[2007][C-000001d5] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-04 13:43:59] VERBOSE[2007][C-000001d5] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [0459811673076@from-internal:1] Macro(“SIP/1015-00000348”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/1015-00000348”, “TOUCH_MONITOR=1559673839.904”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/1015-00000348”, “AMPUSER=1015”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/1015-00000348”, “0?report”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/1015-00000348”, “1?Set(REALCALLERIDNUM=1015)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/1015-00000348”, “AMPUSER=1015”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/1015-00000348”, “0?limit”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/1015-00000348”, “AMPUSERCIDNAME=Edson”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/1015-00000348”, “0?report”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:9] Set(“SIP/1015-00000348”, “AMPUSERCID=1015”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/1015-00000348”, “__DIAL_OPTIONS=Ttr”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/1015-00000348”, “CALLERID(all)=“Edson” <1015>”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:12] GotoIf(“SIP/1015-00000348”, “0?limit”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/1015-00000348”, “1?Set(GROUP(concurrency_limit)=1015)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/1015-00000348”, “1?Set(CHANNEL(language)=es)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:15] GotoIf(“SIP/1015-00000348”, “1?continue”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:29] Set(“SIP/1015-00000348”, “CALLERID(number)=1015”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:30] Set(“SIP/1015-00000348”, “CALLERID(name)=Edson”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“SIP/1015-00000348”, “0?cnum”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:32] Set(“SIP/1015-00000348”, “CDR(cnam)=Edson”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:33] Set(“SIP/1015-00000348”, “CDR(cnum)=1015”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-user-callerid:34] Set(“SIP/1015-00000348”, “CHANNEL(language)=es”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [0459811673076@from-internal:2] Gosub(“SIP/1015-00000348”, “sub-record-check,s,1(out,0459811673076,dontcare)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/1015-00000348”, “0?initialized”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/1015-00000348”, “__REC_STATUS=INITIALIZED”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/1015-00000348”, “NOW=1559673839”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/1015-00000348”, “__DAY=04”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/1015-00000348”, “__MONTH=06”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/1015-00000348”, “__YEAR=2019”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/1015-00000348”, “__TIMESTR=20190604-134359”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/1015-00000348”, “__FROMEXTEN=1015”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/1015-00000348”, “__MON_FMT=wav”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/1015-00000348”, “Recordings initialized”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/1015-00000348”, “0?Set(ARG3=dontcare)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/1015-00000348”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/1015-00000348”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/1015-00000348”, “3?checkaction”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/1015-00000348”, “1?sub-record-check,out,1”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx_builtins.c: Goto (sub-record-check,out,1)
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [out@sub-record-check:1] NoOp(“SIP/1015-00000348”, “Outbound Recording Check from 1015 to 0459811673076”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [out@sub-record-check:2] Set(“SIP/1015-00000348”, “RECMODE=dontcare”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [out@sub-record-check:3] ExecIf(“SIP/1015-00000348”, “1?Goto(routewins)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx_builtins.c: Goto (sub-record-check,out,7)
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [out@sub-record-check:7] Gosub(“SIP/1015-00000348”, “recordcheck,1(dontcare,out,0459811673076)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/1015-00000348”, “Starting recording check against dontcare”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/1015-00000348”, “dontcare”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/1015-00000348”, “”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [out@sub-record-check:8] Return(“SIP/1015-00000348”, “”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [0459811673076@from-internal:3] ExecIf(“SIP/1015-00000348”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [0459811673076@from-internal:4] Set(“SIP/1015-00000348”, “MOHCLASS=default”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [0459811673076@from-internal:5] Set(“SIP/1015-00000348”, “_NODEST=”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [0459811673076@from-internal:6] Macro(“SIP/1015-00000348”, “dialout-trunk,2,0459811673076,off”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“SIP/1015-00000348”, “DIAL_TRUNK=2”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/1015-00000348”, “0?sub-pincheck,s,1()”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/1015-00000348”, “0?disabletrunk,1”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:4] Set(“SIP/1015-00000348”, “DIAL_NUMBER=0459811673076”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:5] Set(“SIP/1015-00000348”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“SIP/1015-00000348”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/1015-00000348”, “1?nomax”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx_builtins.c: Goto (macro-dialout-trunk,s,9)
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/1015-00000348”, “0?skipoutcid”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:10] Set(“SIP/1015-00000348”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:11] Macro(“SIP/1015-00000348”, “outbound-callerid,2”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/1015-00000348”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/1015-00000348”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf(“SIP/1015-00000348”, “0?Set(REALCALLERIDNUM=1015)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf(“SIP/1015-00000348”, “1?normcid”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx_builtins.c: Goto (macro-outbound-callerid,s,7)
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:7] Set(“SIP/1015-00000348”, “USEROUTCID=1015”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:8] Set(“SIP/1015-00000348”, “EMERGENCYCID=”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:9] Set(“SIP/1015-00000348”, “TRUNKOUTCID=”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf(“SIP/1015-00000348”, “1?trunkcid”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx_builtins.c: Goto (macro-outbound-callerid,s,15)
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/1015-00000348”, “0?Set(CALLERID(all)=)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/1015-00000348”, “1?Set(CALLERID(all)=1015)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/1015-00000348”, “0?Set(CALLERID(all)=)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf(“SIP/1015-00000348”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf(“SIP/1015-00000348”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:20] Set(“SIP/1015-00000348”, “CDR(outbound_cnum)=1015”) in new stack
[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-outbound-callerid:21] Set(“SIP/1015-00000348”, “CDR(outbound_cnam)=”) in new stack
[2019-06-04 13:43:59] WARNING[1960] func_cdr.c: CDR requires a value (CDR(variable)=value)
)[2019-06-04 13:43:59] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/1015-00000348”, “0?sub-flp-2,s,1()”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:13] Set(“SIP/1015-00000348”, “OUTNUM=0459811673076”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:14] Set(“SIP/1015-00000348”, “custom=SIP/100”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/1015-00000348”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/1015-00000348”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:17] Macro(“SIP/1015-00000348”, “dialout-trunk-predial-hook,”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/1015-00000348”, “”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/1015-00000348”, “0?bypass,1”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/1015-00000348”, “1?Set(CONNECTEDLINE(num,i)=0459811673076)”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/1015-00000348”, “1?Set(CONNECTEDLINE(name,i)=CID:1015)”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/1015-00000348”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)1015)”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf(“SIP/1015-00000348”, “0?customtrunk”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-dialout-trunk:23] Dial(“SIP/1015-00000348”, “SIP/100/0459811673076,300,T”) in new stack
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-04 13:44:00] VERBOSE[21691][C-000001d5] app_dial.c: Called SIP/100/0459811673076
[2019-06-04 13:44:14] VERBOSE[21691][C-000001d5] app_dial.c: SIP/100-00000349 is ringing
[2019-06-04 13:44:17] VERBOSE[21691][C-000001d5] app_macro.c: Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on ‘SIP/1015-00000348’ in macro ‘dialout-trunk’
[2019-06-04 13:44:17] VERBOSE[21691][C-000001d5] pbx.c: Spawn extension (from-internal, 0459811673076, 6) exited non-zero on ‘SIP/1015-00000348’
[2019-06-04 13:44:17] VERBOSE[21691][C-000001d5] pbx.c: Executing [h@from-internal:1] Macro(“SIP/1015-00000348”, “hangupcall”) in new stack
[2019-06-04 13:44:17] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/1015-00000348”, “1?theend”) in new stack
[2019-06-04 13:44:17] VERBOSE[21691][C-000001d5] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-06-04 13:44:17] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/1015-00000348”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-06-04 13:44:17] VERBOSE[21691][C-000001d5] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/1015-00000348”, “”) in new stack
[2019-06-04 13:44:17] VERBOSE[21691][C-000001d5] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/1015-00000348’ in macro ‘hangupcall’
[2019-06-04 13:44:17] VERBOSE[21691][C-000001d5] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/1015-00000348’
[2019-06-04 14:04:03] VERBOSE[1931] asterisk.c: Remote UNIX connection
[2019-06-04 14:04:03] VERBOSE[23611] asterisk.c: Remote UNIX connection disconnected
[2019-06-04 14:04:03] VERBOSE[1931] asterisk.c: Remote UNIX connection
[2019-06-04 14:04:03] VERBOSE[23613] asterisk.c: Remote UNIX connection disconnected
[2019-06-04 14:04:03] VERBOSE[1931] asterisk.c: Remote UNIX connection
[2019-06-04 14:04:03] VERBOSE[23615] asterisk.c: Remote UNIX connection disconnected

10

ese es todo el log.
espero sea de utilidad
saludos,
EDSON

11

Revisa si tienes algun desvio activado o algun desborde en tus extensiones. Veo que entra la llamada, ringuea la extension y luego luego se cuelga y se llama a un numero externo:

2019-06-04 11:31:21] VERBOSE[9979][C-000001d1] app_dial.c: Connected line update to SIP/1001-00000340 prevented.
[2019-06-04 11:31:22] VERBOSE[9979][C-000001d1] app_dial.c: SIP/1015-00000341 is ringing
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] app_macro.c: Spawn extension (macro-dial-one, s, 51) exited non-zero on ‘SIP/1001-00000340’ in macro ‘dial-one’
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] app_macro.c: Spawn extension (macro-exten-vm, s, 20) exited non-zero on ‘SIP/1001-00000340’ in macro ‘exten-vm’
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Spawn extension (ext-local, 1015, 2) exited non-zero on ‘SIP/1001-00000340’
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Executing [h@ext-local:1] Macro(“SIP/1001-00000340”, “hangupcall,”) in new stack
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/1001-00000340”, “1?theend”) in new stack
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/1001-00000340”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/1001-00000340”, “”) in new stack
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/1001-00000340’ in macro ‘hangupcall’
[2019-06-04 11:31:34] VERBOSE[9979][C-000001d1] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/1001-00000340’
[2019-06-04 11:52:56] VERBOSE[2007][C-000001d2] netsock2.c: Using SIP RTP TOS bits 184
[2019-06-04 11:52:56] VERBOSE[2007][C-000001d2] netsock2.c: Using SIP RTP CoS mark 5
[2019-06-04 11:52:56] VERBOSE[11784][C-000001d2] pbx.c: Executing [015526152289@from-internal:1] Macro(“SIP/1000-00000342”, “user-callerid,LIMIT,EXTERNAL,”) in new stack

12

De manera práctica, esto es lo que sucede (en las pruebas que hice y de las que hablaba):

  1. Marco el número.
  2. Hay un tiempo de espera de aproximadamente de entre 12 y 15 segundos
  3. Empieza a sonar el timbre.
  4. Y, en estos casos, es cuando yo he colgado.
    Creo que por eso dices que luego luego se cuelga, no será?
    Lo que me llama la atención es lo que me dices con respecto de que luego de que cuelgo, llama a un número externo.
13

No.
Esa espera de 12 se da mientras el PBX busca marcar el numero externo.
Revisa la parte de Followme de las extensiones con las que probaste.

14

Ya chequé y en el FreePBX no tenía configurado nada en el sígueme. Revisé la configuración del teléfono, y ese sí lo tenía. Lo desactivé y reinicié el teléfono. Hice la prueba y, de nuevo, tuve tiempo de espera entre terminar la marcación y el primer timbre de hasta 16 y 18 segundos, aproximadamente.

15

Saludos Edson, has probado a digitar # al final del numero que estas marcando o simplemente esperas que parta la llamada?

16

Buenas tardes. Simplemente espero a que parta la llamada. Intenté la tecla # al final dle número y el mensaje es que me manda es que la llamada no puede completarse como se marcó.

17

Saludos Edson,
qué tipo de teléfono IP utilizas? Yo utilizo los Fanvil, en particular los X4G. En la configuración del teléfono (Dial Plan del Account) puedo indicarle que para finalizar la introducción de un número para enviar la llamada, puedo presionar la tecla # (Press # to invoke dialing), por lo que Asterisk la reconoce como Envio del numero. Has probado con un teléfono analógico conectado a un gateway FXS? También debería funcionar con #.

Saludos,
Walter.

18

Buenos días.
Acá se usan VTech ErisTerminal. Algunos son VSP715 (como el mío), otros son VSP735. Verifiqué lo que dijiste, y en los equipos, el código que aparece es el siguiente: x+P2,. No sé cómo sea el tuyo. Estoy averiguando en ese sentido cómo modificarlo y la codificaci{on que debo incluir.
Gracias.
Saludos,
EDSON

19

En el plan de marcación, lo dejé de la siguiente manera: x0xx|9xxxxxx|01xxxxxxxxxx|04[4-5]xxxxxxxxxx|911
Las llamadas entre extensiones, sin problema.
Las llamadas hacia afuera, son donde presenta el retardo (o tiempo de espera) de unos 10 segundos, aprox.
El formato anterior era: x+P3 (serie ilimitada de dígitos y una pausa de 3 segundos).

20

En teoria podrías marcar el número con la corneta en su lugar y una vez que levantas la corneta, el teléfono debería partir de inmediato. También podrías digitar el número con la corneta alzada y luego presionar la softkey Dial, o prueba a utilizar el siguiente Dial Plan: x+(#:)|x+P

Saludos,
Walter.