FreePBX 6 - inbound calls terminate in 30 seconds

esokolov · December 14, 2014, 4:50am

Hi,

This is my first post on this forum, so excuse me if I’m offtopic.

A while ago I did a fresh install of FreePBX6, replacing my old faithful FreePBX5, PJSIP for trunks and extensions.

A few weeks ago all inbound calls started dropping at about 30 sec. Outbound calls are fine. Don’t know what happened, and there’s no ‘pjsip debug’ command in ‘asterisk -r’ so I’m blind.

I’m not a big SIP guru but experienced network engineer so my primary tool is Wireshark. I’ve captured a trace on the FPBX that shows the progress of the call. I see that on the 30th second it sends a very strange ‘bye’ to both my VoIP provider (FreePhoneLine.ca) and to my PAP2T. Can someone look at the screenshot and tell me where to dig further?

FPBX version is most recent one.

On the trace, FreePBX is 10.170.4.6, PAP2T is 10.170.4.7. I’m showing only SIP commands, filtering out RTP.

Note the different proxy address and port in the ‘bye’ message to provider.

UPDATE: Can’t upload the screenshot since I’m a new user… Here’s the link:

hxxps://drive.google.com/file/d/0B_c1atotBx0jbGVydjAtMTRkRTQ/view?usp=sharing

esokolov · December 14, 2014, 8:01pm

Here’s the asterisk log for similar events. All private information is masked with xxx, and @ replaced by # (damn new user status):

[2014-12-14 14:46:22] VERBOSE[15836] asterisk.c: Remote UNIX connection disconnected
[2014-12-14 14:48:56] WARNING[2245] res_odbc.c: SetConnectAttr (Txn isolation) returned an error: HY000: [MySQL][ODBC 5.1 Driver]MySQL server has gone away
[2014-12-14 14:48:56] WARNING[2245] res_odbc.c: SQL Execute returned an error -1: 08S01: [MySQL][ODBC 5.1 Driver][mysqld-5.1.73]MySQL server has gone away (65)
[2014-12-14 14:48:56] WARNING[2245] res_odbc.c: SQL Execute error -1! Verifying connection to asteriskcdrdb [MySQL-asteriskcdrdb]…
[2014-12-14 14:48:56] WARNING[2245] res_odbc.c: Connection is down attempting to reconnect…
[2014-12-14 14:48:56] NOTICE[2245] res_odbc.c: Connecting asteriskcdrdb
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:1] Set(“PJSIP/FPL-0000000e”, “__FROM_DID=1xxxxxxxxxx”) in new stack
[2014-12-14 14:48:56] NOTICE[2245] res_odbc.c: res_odbc: Connected to asteriskcdrdb [MySQL-asteriskcdrdb]
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:2] Gosub(“PJSIP/FPL-0000000e”, “app-blacklist-check,s,1()”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#app-blacklist-check:1] GotoIf(“PJSIP/FPL-0000000e”, “0?blacklisted”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#app-blacklist-check:2] Set(“PJSIP/FPL-0000000e”, “CALLED_BLACKLIST=1”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#app-blacklist-check:3] Return(“PJSIP/FPL-0000000e”, “”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:3] Gosub(“PJSIP/FPL-0000000e”, “cidlookup,cidlookup_2,1()”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [cidlookup_2#cidlookup:1] ExecIf(“PJSIP/FPL-0000000e”, “1?Set(CALLERID(name)=xxxxxxxxxx)”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [cidlookup_2#cidlookup:2] Return(“PJSIP/FPL-0000000e”, “”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:4] Set(“PJSIP/FPL-0000000e”, “CDR(did)=1xxxxxxxxxx”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:5] ExecIf(“PJSIP/FPL-0000000e”, “0 ?Set(CALLERID(name)=xxxxxxxxxx)”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:6] Set(“PJSIP/FPL-0000000e”, “CHANNEL(musicclass)=default”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:7] Set(“PJSIP/FPL-0000000e”, “__MOHCLASS=default”) in new stack
[2014-12-14 14:48:56] WARNING[15917][C-00000009] func_channel.c: Unknown or unavailable item requested: ‘reversecharge’
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:8] GotoIf(“PJSIP/FPL-0000000e”, “0?macro-hangupcall”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:9] Set(“PJSIP/FPL-0000000e”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:10] Set(“PJSIP/FPL-0000000e”, “CALLERPRES()=allowed_not_screened”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [1xxxxxxxxxx#from-pstn:11] Goto(“PJSIP/FPL-0000000e”, “ext-group,601,1”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Goto (ext-group,601,1)
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [601#ext-group:1] Macro(“PJSIP/FPL-0000000e”, “user-callerid,”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:1] Set(“PJSIP/FPL-0000000e”, “TOUCH_MONITOR=1418586536.130”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:2] Set(“PJSIP/FPL-0000000e”, “AMPUSER=xxxxxxxxxx”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:3] GotoIf(“PJSIP/FPL-0000000e”, “0?report”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:4] ExecIf(“PJSIP/FPL-0000000e”, “1?Set(REALCALLERIDNUM=xxxxxxxxxx)”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:5] Set(“PJSIP/FPL-0000000e”, “AMPUSER=”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:6] GotoIf(“PJSIP/FPL-0000000e”, “0?limit”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:7] Set(“PJSIP/FPL-0000000e”, “AMPUSERCIDNAME=”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:8] GotoIf(“PJSIP/FPL-0000000e”, “1?report”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Goto (macro-user-callerid,s,16)
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:16] GotoIf(“PJSIP/FPL-0000000e”, “0?continue”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:17] ExecIf(“PJSIP/FPL-0000000e”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:18] Set(“PJSIP/FPL-0000000e”, “__TTL=64”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:19] GotoIf(“PJSIP/FPL-0000000e”, “1?continue”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Goto (macro-user-callerid,s,30)
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:30] Set(“PJSIP/FPL-0000000e”, “CALLERID(number)=xxxxxxxxxx”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:31] Set(“PJSIP/FPL-0000000e”, “CALLERID(name)=xxxxxxxxxx”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:32] Set(“PJSIP/FPL-0000000e”, “CDR(cnum)=xxxxxxxxxx”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:33] Set(“PJSIP/FPL-0000000e”, “CDR(cnam)=xxxxxxxxxx”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-user-callerid:34] Set(“PJSIP/FPL-0000000e”, “CHANNEL(language)=en”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [601#ext-group:2] Macro(“PJSIP/FPL-0000000e”, “blkvm-setifempty,”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-blkvm-setifempty:1] GotoIf(“PJSIP/FPL-0000000e”, “1?init”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Goto (macro-blkvm-setifempty,s,4)
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-blkvm-setifempty:4] Set(“PJSIP/FPL-0000000e”, “__BLKVM_CHANNEL=PJSIP/FPL-0000000e”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-blkvm-setifempty:5] Set(“PJSIP/FPL-0000000e”, “SHARED(BLKVM,PJSIP/FPL-0000000e)=TRUE”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-blkvm-setifempty:6] Set(“PJSIP/FPL-0000000e”, “GOSUB_RETVAL=TRUE”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-blkvm-setifempty:7] MacroExit(“PJSIP/FPL-0000000e”, “”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [601#ext-group:3] GotoIf(“PJSIP/FPL-0000000e”, “1?skipov”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Goto (ext-group,601,6)
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [601#ext-group:6] Set(“PJSIP/FPL-0000000e”, “RRNODEST=”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [601#ext-group:7] Set(“PJSIP/FPL-0000000e”, “__NODEST=601”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [601#ext-group:8] GosubIf(“PJSIP/FPL-0000000e”, “0?sub-rgsetcid,s,1()”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [601#ext-group:9] Gosub(“PJSIP/FPL-0000000e”, “sub-record-check,s,1(rg,601,dontcare)”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:1] GotoIf(“PJSIP/FPL-0000000e”, “0?initialized”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:2] Set(“PJSIP/FPL-0000000e”, “__REC_STATUS=INITIALIZED”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:3] Set(“PJSIP/FPL-0000000e”, “NOW=1418586536”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:4] Set(“PJSIP/FPL-0000000e”, “__DAY=14”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:5] Set(“PJSIP/FPL-0000000e”, “__MONTH=12”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:6] Set(“PJSIP/FPL-0000000e”, “__YEAR=2014”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:7] Set(“PJSIP/FPL-0000000e”, “__TIMESTR=20141214-144856”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:8] Set(“PJSIP/FPL-0000000e”, “__FROMEXTEN=xxxxxxxxxx”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:9] Set(“PJSIP/FPL-0000000e”, “__MON_FMT=wav”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:10] NoOp(“PJSIP/FPL-0000000e”, “Recordings initialized”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:11] ExecIf(“PJSIP/FPL-0000000e”, “0?Set(ARG3=dontcare)”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:12] Set(“PJSIP/FPL-0000000e”, “REC_POLICY_MODE_SAVE=”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:13] GotoIf(“PJSIP/FPL-0000000e”, “2?checkaction”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Goto (sub-record-check,s,16)
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:16] GotoIf(“PJSIP/FPL-0000000e”, “0?sub-record-check,rg,1”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:17] NoOp(“PJSIP/FPL-0000000e”, “Generic rg Recording Check - xxxxxxxxxx 601”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:18] Gosub(“PJSIP/FPL-0000000e”, “recordcheck,1(dontcare,rg,601)”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [recordcheck#sub-record-check:1] NoOp(“PJSIP/FPL-0000000e”, “Starting recording check against dontcare”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [recordcheck#sub-record-check:2] Goto(“PJSIP/FPL-0000000e”, “dontcare”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Goto (sub-record-check,recordcheck,3)
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [recordcheck#sub-record-check:3] Return(“PJSIP/FPL-0000000e”, “”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#sub-record-check:19] Return(“PJSIP/FPL-0000000e”, “”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [601#ext-group:10] Set(“PJSIP/FPL-0000000e”, “RingGroupMethod=ringall”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [601#ext-group:11] Macro(“PJSIP/FPL-0000000e”, “dial,20,Ttr,101”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-dial:1] GotoIf(“PJSIP/FPL-0000000e”, “0?dial”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-dial:2] Set(“PJSIP/FPL-0000000e”, “CHANNEL(musicclass)=default”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-dial:3] AGI(“PJSIP/FPL-0000000e”, “dialparties.agi”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] res_agi.c: dialparties.agi: Caller ID name is ‘xxxxxxxxxx’ number is ‘xxxxxxxxxx’
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] res_agi.c: dialparties.agi: Added extension 101 to extension map
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] res_agi.c: dialparties.agi: Extension 101 cf is disabled
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] res_agi.c: dialparties.agi: Extension 101 do not disturb is disabled
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] res_agi.c: dialparties.agi: dbset CALLTRACE/101 to xxxxxxxxxx
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] res_agi.c: dialparties.agi: Filtered ARG3: 101
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] res_agi.c: <PJSIP/FPL-0000000e>AGI Script dialparties.agi completed, returning 0
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-dial:7] Dial(“PJSIP/FPL-0000000e”, “PJSIP/101,20,TtrM(auto-blkvm)”) in new stack
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] app_dial.c: Called PJSIP/101
[2014-12-14 14:48:56] VERBOSE[15917][C-00000009] app_dial.c: PJSIP/101-0000000f is ringing
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] app_dial.c: PJSIP/101-0000000f answered PJSIP/FPL-0000000e
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-auto-blkvm:1] Set(“PJSIP/101-0000000f”, “__MACRO_RESULT=”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-auto-blkvm:2] Set(“PJSIP/101-0000000f”, “CFIGNORE=”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-auto-blkvm:3] Set(“PJSIP/101-0000000f”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-auto-blkvm:4] Set(“PJSIP/101-0000000f”, “FORWARD_CONTEXT=from-internal”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-auto-blkvm:5] Set(“PJSIP/101-0000000f”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-auto-blkvm:6] Macro(“PJSIP/101-0000000f”, “blkvm-clr,”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-blkvm-clr:1] Set(“PJSIP/101-0000000f”, “SHARED(BLKVM,PJSIP/FPL-0000000e)=”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-blkvm-clr:2] Set(“PJSIP/101-0000000f”, “GOSUB_RETVAL=”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-blkvm-clr:3] MacroExit(“PJSIP/101-0000000f”, “”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-auto-blkvm:7] ExecIf(“PJSIP/101-0000000f”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=101)”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-auto-blkvm:8] ExecIf(“PJSIP/101-0000000f”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=xxxxxxxxxx)”) in new stack
[2014-12-14 14:49:00] VERBOSE[15917][C-00000009] bridge_channel.c: Channel PJSIP/FPL-0000000e joined ‘simple_bridge’ basic-bridge <627ff2b7-9bea-410d-a88a-0269a5cbcf9d>
[2014-12-14 14:49:00] VERBOSE[16077][C-00000009] bridge_channel.c: Channel PJSIP/101-0000000f joined ‘simple_bridge’ basic-bridge <627ff2b7-9bea-410d-a88a-0269a5cbcf9d>
[2014-12-14 14:49:32] VERBOSE[15917][C-00000009] bridge_channel.c: Channel PJSIP/FPL-0000000e left ‘simple_bridge’ basic-bridge <627ff2b7-9bea-410d-a88a-0269a5cbcf9d>
[2014-12-14 14:49:32] VERBOSE[16077][C-00000009] bridge_channel.c: Channel PJSIP/101-0000000f left ‘simple_bridge’ basic-bridge <627ff2b7-9bea-410d-a88a-0269a5cbcf9d>
[2014-12-14 14:49:32] VERBOSE[15917][C-00000009] app_macro.c: Spawn extension (macro-dial, s, 7) exited non-zero on ‘PJSIP/FPL-0000000e’ in macro ‘dial’
[2014-12-14 14:49:32] VERBOSE[15917][C-00000009] pbx.c: Spawn extension (ext-group, 601, 11) exited non-zero on ‘PJSIP/FPL-0000000e’
[2014-12-14 14:49:32] VERBOSE[15917][C-00000009] pbx.c: Executing [h#ext-group:1] Macro(“PJSIP/FPL-0000000e”, “hangupcall,”) in new stack
[2014-12-14 14:49:32] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-hangupcall:1] GotoIf(“PJSIP/FPL-0000000e”, “1?theend”) in new stack
[2014-12-14 14:49:32] VERBOSE[15917][C-00000009] pbx.c: Goto (macro-hangupcall,s,3)
[2014-12-14 14:49:32] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-hangupcall:3] ExecIf(“PJSIP/FPL-0000000e”, “0?Set(CDR(recordingfile)=)”) in new stack
[2014-12-14 14:49:32] VERBOSE[15917][C-00000009] pbx.c: Executing [s#macro-hangupcall:4] Hangup(“PJSIP/FPL-0000000e”, “”) in new stack
[2014-12-14 14:49:32] VERBOSE[15917][C-00000009] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘PJSIP/FPL-0000000e’ in macro ‘hangupcall’
[2014-12-14 14:49:32] VERBOSE[15917][C-00000009] pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on ‘PJSIP/FPL-0000000e’