2016-11-26 12:59:30.950009 [NOTICE] switch_channel.c:1104 New Channel sofia/external/91000@10.211.55.12 [19eb752c-b48a-11e6-9bd4-932d16b3469b]
2016-11-26 12:59:30.950009 [DEBUG] switch_core_state_machine.c:584 (sofia/external/91000@10.211.55.12) Running State Change CS_NEW (Cur 1 Tot 137)
2016-11-26 12:59:30.950009 [DEBUG] sofia.c:9838 sofia/external/91000@10.211.55.12 receiving invite from 10.211.55.2:61191 version: 1.9.0 git 7884bee 2016-11-23 21:05:12Z 64bit
2016-11-26 12:59:30.950009 [DEBUG] sofia.c:7107 Channel sofia/external/91000@10.211.55.12 entering state [received][100]
2016-11-26 12:59:30.950009 [DEBUG] sofia.c:7117 Remote SDP:
v=0
o=- 3689230391 3689230391 IN IP4 192.168.7.20
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4032 RTP/AVP 103 102 104 125 109 3 0 8 9 101
c=IN IP4 192.168.7.20
b=TIAS:96000
a=rtpmap:103 speex/16000
a=rtpmap:102 speex/8000
a=rtpmap:104 speex/32000
a=rtpmap:125 opus/48000/2
a=fmtp:125 useinbandfec=1
a=rtpmap:109 iLBC/8000
a=fmtp:109 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtcp:4033 IN IP4 192.168.7.20

2016-11-26 12:59:30.950009 [DEBUG] sofia.c:7485 (sofia/external/91000@10.211.55.12) State Change CS_NEW -> CS_INIT
2016-11-26 12:59:30.950009 [DEBUG] switch_core_state_machine.c:603 (sofia/external/91000@10.211.55.12) State NEW
2016-11-26 12:59:30.950009 [DEBUG] switch_core_state_machine.c:584 (sofia/external/91000@10.211.55.12) Running State Change CS_INIT (Cur 1 Tot 137)
2016-11-26 12:59:30.950009 [DEBUG] switch_core_state_machine.c:627 (sofia/external/91000@10.211.55.12) State INIT
2016-11-26 12:59:30.950009 [DEBUG] mod_sofia.c:92 sofia/external/91000@10.211.55.12 SOFIA INIT
2016-11-26 12:59:30.950009 [DEBUG] switch_core_state_machine.c:40 sofia/external/91000@10.211.55.12 Standard INIT
2016-11-26 12:59:30.950009 [DEBUG] switch_core_state_machine.c:48 (sofia/external/91000@10.211.55.12) State Change CS_INIT -> CS_ROUTING
2016-11-26 12:59:30.950009 [DEBUG] switch_core_state_machine.c:627 (sofia/external/91000@10.211.55.12) State INIT going to sleep
2016-11-26 12:59:30.950009 [DEBUG] switch_core_state_machine.c:584 (sofia/external/91000@10.211.55.12) Running State Change CS_ROUTING (Cur 1 Tot 137)
2016-11-26 12:59:30.950009 [DEBUG] switch_channel.c:2249 (sofia/external/91000@10.211.55.12) Callstate Change DOWN -> RINGING
2016-11-26 12:59:30.950009 [DEBUG] switch_core_state_machine.c:643 (sofia/external/91000@10.211.55.12) State ROUTING
2016-11-26 12:59:30.950009 [DEBUG] mod_sofia.c:145 sofia/external/91000@10.211.55.12 SOFIA ROUTING
2016-11-26 12:59:30.950009 [DEBUG] switch_core_state_machine.c:236 sofia/external/91000@10.211.55.12 Standard ROUTING
2016-11-26 12:59:30.950009 [INFO] mod_dialplan_xml.c:637 Processing Naseer <91000>->92301 in context default
Dialplan: sofia/external/91000@10.211.55.12 parsing [default->vBilling] continue=false
Dialplan: sofia/external/91000@10.211.55.12 Regex (PASS) [vBilling] destination_number(92301) =~ /$/ break=on-false
Dialplan: sofia/external/91000@10.211.55.12 Action set(hangup_after_bridge=true)
Dialplan: sofia/external/91000@10.211.55.12 Action set(continue_on_fail=true)
Dialplan: sofia/external/91000@10.211.55.12 Action set(disable_hold=true)
Dialplan: sofia/external/91000@10.211.55.12 Action set(map_type=pbx)
Dialplan: sofia/external/91000@10.211.55.12 Action set(map_id=10)
Dialplan: sofia/external/91000@10.211.55.12 Action set(state=NC)
Dialplan: sofia/external/91000@10.211.55.12 Action set(npa=910)
Dialplan: sofia/external/91000@10.211.55.12 Action set(export_vars=map_type,map_id,state,npa,dispatcher_ip)
Dialplan: sofia/external/91000@10.211.55.12 Action set(import=dispatcher_ip)
Dialplan: sofia/external/91000@10.211.55.12 Action set(process_cdr=b_only)
Dialplan: sofia/external/91000@10.211.55.12 Action set(bypass_media=true)
Dialplan: sofia/external/91000@10.211.55.12 Action limit_execute(hiredis default 5.9.50.206:5060 3 bridge [dispatcher_ip=5.9.50.206]sofia/external/92301@5.9.50.206:5060)
Dialplan: sofia/external/91000@10.211.55.12 Action limit_execute(hiredis default 10.211.55.26:15080 1 bridge [dispatcher_ip=10.211.55.26]sofia/external/92301@10.211.55.26:15080)
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:286 (sofia/external/91000@10.211.55.12) State Change CS_ROUTING -> CS_EXECUTE
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:643 (sofia/external/91000@10.211.55.12) State ROUTING going to sleep
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:584 (sofia/external/91000@10.211.55.12) Running State Change CS_EXECUTE (Cur 1 Tot 137)
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:650 (sofia/external/91000@10.211.55.12) State EXECUTE
2016-11-26 12:59:30.968642 [DEBUG] mod_sofia.c:200 sofia/external/91000@10.211.55.12 SOFIA EXECUTE
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:328 sofia/external/91000@10.211.55.12 Standard EXECUTE
EXECUTE sofia/external/91000@10.211.55.12 set(hangup_after_bridge=true)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [hangup_after_bridge]=[true]
EXECUTE sofia/external/91000@10.211.55.12 set(continue_on_fail=true)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [continue_on_fail]=[true]
EXECUTE sofia/external/91000@10.211.55.12 set(disable_hold=true)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [disable_hold]=[true]
EXECUTE sofia/external/91000@10.211.55.12 set(map_type=pbx)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [map_type]=[pbx]
EXECUTE sofia/external/91000@10.211.55.12 set(map_id=10)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [map_id]=[10]
EXECUTE sofia/external/91000@10.211.55.12 set(state=NC)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [state]=[NC]
EXECUTE sofia/external/91000@10.211.55.12 set(npa=910)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [npa]=[910]
EXECUTE sofia/external/91000@10.211.55.12 set(export_vars=map_type,map_id,state,npa,dispatcher_ip)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [export_vars]=[map_type,map_id,state,npa,dispatcher_ip]
EXECUTE sofia/external/91000@10.211.55.12 set(import=dispatcher_ip)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [import]=[dispatcher_ip]
EXECUTE sofia/external/91000@10.211.55.12 set(process_cdr=b_only)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [process_cdr]=[b_only]
EXECUTE sofia/external/91000@10.211.55.12 set(bypass_media=true)
2016-11-26 12:59:30.968642 [DEBUG] mod_dptools.c:1562 SET sofia/external/91000@10.211.55.12 [bypass_media]=[true]
EXECUTE sofia/external/91000@10.211.55.12 limit_execute(hiredis default 5.9.50.206:5060 3 bridge [dispatcher_ip=5.9.50.206]sofia/external/92301@5.9.50.206:5060)
2016-11-26 12:59:30.968642 [DEBUG] switch_limit.c:126 incr called: default_5.9.50.206:5060 max:3, interval:0
2016-11-26 12:59:30.968642 [DEBUG] hiredis_profile.c:79 hiredis: waiting for [localhost, 6379]
2016-11-26 12:59:30.968642 [DEBUG] hiredis_profile.c:105 hiredis: recycled from pool[localhost, 6379]
2016-11-26 12:59:30.968642 [DEBUG] hiredis_profile.c:227 hiredis: incr 5.9.50.206:5060
2016-11-26 12:59:30.968642 [DEBUG] hiredis_profile.c:68 hiredis: release back to pool [localhost, 6379]
EXECUTE sofia/external/91000@10.211.55.12 bridge([dispatcher_ip=5.9.50.206]sofia/external/92301@5.9.50.206:5060)
2016-11-26 12:59:30.968642 [DEBUG] switch_channel.c:1250 sofia/external/91000@10.211.55.12 EXPORTING[export_vars] [map_type]=[pbx] to event
2016-11-26 12:59:30.968642 [DEBUG] switch_channel.c:1250 sofia/external/91000@10.211.55.12 EXPORTING[export_vars] [map_id]=[10] to event
2016-11-26 12:59:30.968642 [DEBUG] switch_channel.c:1250 sofia/external/91000@10.211.55.12 EXPORTING[export_vars] [state]=[NC] to event
2016-11-26 12:59:30.968642 [DEBUG] switch_channel.c:1250 sofia/external/91000@10.211.55.12 EXPORTING[export_vars] [npa]=[910] to event
2016-11-26 12:59:30.968642 [DEBUG] switch_ivr_originate.c:2143 Parsing global variables
2016-11-26 12:59:30.968642 [DEBUG] switch_ivr_originate.c:2664 Parsing session specific variables
2016-11-26 12:59:30.968642 [NOTICE] switch_channel.c:1104 New Channel sofia/external/92301@5.9.50.206:5060 [19ee734e-b48a-11e6-9be6-932d16b3469b]
2016-11-26 12:59:30.968642 [DEBUG] mod_sofia.c:4881 (sofia/external/92301@5.9.50.206:5060) State Change CS_NEW -> CS_INIT
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:584 (sofia/external/92301@5.9.50.206:5060) Running State Change CS_INIT (Cur 2 Tot 138)
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:627 (sofia/external/92301@5.9.50.206:5060) State INIT
2016-11-26 12:59:30.968642 [DEBUG] mod_sofia.c:92 sofia/external/92301@5.9.50.206:5060 SOFIA INIT
2016-11-26 12:59:30.968642 [DEBUG] sofia_glue.c:1285 sofia/external/92301@5.9.50.206:5060 sending invite version: 1.9.0 git 7884bee 2016-11-23 21:05:12Z 64bit
Local SDP:
v=0
o=- 3689230391 3689230391 IN IP4 192.168.7.20
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4032 RTP/AVP 103 102 104 125 109 3 0 8 9 101
c=IN IP4 192.168.7.20
b=TIAS:96000
a=rtpmap:103 speex/16000
a=rtpmap:102 speex/8000
a=rtpmap:104 speex/32000
a=rtpmap:125 opus/48000/2
a=fmtp:125 useinbandfec=1
a=rtpmap:109 iLBC/8000
a=fmtp:109 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtcp:4033 IN IP4 192.168.7.20

2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:40 sofia/external/92301@5.9.50.206:5060 Standard INIT
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:48 (sofia/external/92301@5.9.50.206:5060) State Change CS_INIT -> CS_ROUTING
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:627 (sofia/external/92301@5.9.50.206:5060) State INIT going to sleep
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:584 (sofia/external/92301@5.9.50.206:5060) Running State Change CS_ROUTING (Cur 2 Tot 138)
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:643 (sofia/external/92301@5.9.50.206:5060) State ROUTING
2016-11-26 12:59:30.968642 [DEBUG] mod_sofia.c:145 sofia/external/92301@5.9.50.206:5060 SOFIA ROUTING
2016-11-26 12:59:30.968642 [DEBUG] switch_ivr_originate.c:67 (sofia/external/92301@5.9.50.206:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:643 (sofia/external/92301@5.9.50.206:5060) State ROUTING going to sleep
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:584 (sofia/external/92301@5.9.50.206:5060) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 138)
2016-11-26 12:59:30.968642 [DEBUG] sofia.c:7107 Channel sofia/external/92301@5.9.50.206:5060 entering state [calling][0]
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:662 (sofia/external/92301@5.9.50.206:5060) State CONSUME_MEDIA
2016-11-26 12:59:30.968642 [DEBUG] switch_core_state_machine.c:662 (sofia/external/92301@5.9.50.206:5060) State CONSUME_MEDIA going to sleep
2016-11-26 12:59:32.708744 [DEBUG] sofia.c:7107 Channel sofia/external/91000@10.211.55.12 entering state [terminated][487]
2016-11-26 12:59:32.708744 [NOTICE] sofia.c:8234 Hangup sofia/external/91000@10.211.55.12 [CS_EXECUTE] [ORIGINATOR_CANCEL]
2016-11-26 12:59:32.708744 [DEBUG] hiredis_profile.c:79 hiredis: waiting for [localhost, 6379]
2016-11-26 12:59:32.708744 [DEBUG] hiredis_profile.c:105 hiredis: recycled from pool[localhost, 6379]
2016-11-26 12:59:32.708744 [DEBUG] hiredis_profile.c:227 hiredis: decr 5.9.50.206:5060
2016-11-26 12:59:32.708744 [DEBUG] hiredis_profile.c:68 hiredis: release back to pool [localhost, 6379]
2016-11-26 12:59:32.728822 [NOTICE] switch_ivr_originate.c:3606 Hangup sofia/external/92301@5.9.50.206:5060 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
2016-11-26 12:59:32.728822 [DEBUG] switch_ivr_originate.c:3823 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:584 (sofia/external/92301@5.9.50.206:5060) Running State Change CS_HANGUP (Cur 2 Tot 138)
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:850 (sofia/external/92301@5.9.50.206:5060) Callstate Change DOWN -> HANGUP
2016-11-26 12:59:32.728822 [INFO] mod_dptools.c:3444 Originate Failed.  Cause: ORIGINATOR_CANCEL
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:852 (sofia/external/92301@5.9.50.206:5060) State HANGUP
2016-11-26 12:59:32.728822 [DEBUG] switch_core_session.c:2867 sofia/external/91000@10.211.55.12 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-11-26 12:59:32.728822 [DEBUG] mod_sofia.c:434 sofia/external/92301@5.9.50.206:5060 Overriding SIP cause 487 with 487 from the other leg
2016-11-26 12:59:32.728822 [DEBUG] mod_sofia.c:440 Channel sofia/external/92301@5.9.50.206:5060 hanging up, cause: ORIGINATOR_CANCEL
2016-11-26 12:59:32.728822 [DEBUG] switch_core_session.c:2867 sofia/external/91000@10.211.55.12 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:650 (sofia/external/91000@10.211.55.12) State EXECUTE going to sleep
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:584 (sofia/external/91000@10.211.55.12) Running State Change CS_HANGUP (Cur 2 Tot 138)
2016-11-26 12:59:32.728822 [DEBUG] mod_sofia.c:504 Sending CANCEL to sofia/external/92301@5.9.50.206:5060
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:850 (sofia/external/91000@10.211.55.12) Callstate Change RINGING -> HANGUP
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:852 (sofia/external/91000@10.211.55.12) State HANGUP
2016-11-26 12:59:32.728822 [DEBUG] mod_sofia.c:440 Channel sofia/external/91000@10.211.55.12 hanging up, cause: ORIGINATOR_CANCEL
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:60 sofia/external/91000@10.211.55.12 Standard HANGUP, cause: ORIGINATOR_CANCEL
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:852 (sofia/external/91000@10.211.55.12) State HANGUP going to sleep
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:619 (sofia/external/91000@10.211.55.12) State Change CS_HANGUP -> CS_REPORTING
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:584 (sofia/external/91000@10.211.55.12) Running State Change CS_REPORTING (Cur 2 Tot 138)
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:60 sofia/external/92301@5.9.50.206:5060 Standard HANGUP, cause: ORIGINATOR_CANCEL
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:852 (sofia/external/92301@5.9.50.206:5060) State HANGUP going to sleep
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:938 (sofia/external/91000@10.211.55.12) State REPORTING
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:619 (sofia/external/92301@5.9.50.206:5060) State Change CS_HANGUP -> CS_REPORTING
2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:584 (sofia/external/92301@5.9.50.206:5060) Running State Change CS_REPORTING (Cur 2 Tot 138)
2016-11-26 12:59:32.728822 [INFO] mod_cdr_pg_csv.c:347 CHANNEL_DATA:
Event-Name: [COMMAND]
Core-UUID: [9de62d72-b331-11e6-9520-932d16b3469b]
FreeSWITCH-Hostname: [Tracker]
FreeSWITCH-Switchname: [Tracker]
FreeSWITCH-IPv4: [10.211.55.12]
FreeSWITCH-IPv6: [fdb2:2c26:f4e4:0:21c:42ff:fe51:ad2b]
Event-Date-Local: [2016-11-26 12:59:32]
Event-Date-GMT: [Sat, 26 Nov 2016 07:59:32 GMT]
Event-Date-Timestamp: [1480147172728822]
Event-Calling-File: [mod_cdr_pg_csv.c]
Event-Calling-Function: [my_on_reporting]
Event-Calling-Line-Number: [342]
Event-Sequence: [11660]
Channel-State: [CS_REPORTING]
Channel-Call-State: [HANGUP]
Channel-State-Number: [11]
Channel-Name: [sofia/external/91000@10.211.55.12]
Unique-ID: [19eb752c-b48a-11e6-9bd4-932d16b3469b]
Call-Direction: [inbound]
Presence-Call-Direction: [inbound]
Channel-HIT-Dialplan: [true]
Channel-Call-UUID: [19eb752c-b48a-11e6-9bd4-932d16b3469b]
Answer-State: [hangup]
Hangup-Cause: [ORIGINATOR_CANCEL]
Caller-Direction: [inbound]
Caller-Logical-Direction: [inbound]
Caller-Username: [91000]
Caller-Dialplan: [XML]
Caller-Caller-ID-Name: [Naseer]
Caller-Caller-ID-Number: [91000]
Caller-Orig-Caller-ID-Name: [Naseer]
Caller-Orig-Caller-ID-Number: [91000]
Caller-Network-Addr: [10.211.55.2]
Caller-ANI: [91000]
Caller-Destination-Number: [92301]
Caller-Unique-ID: [19eb752c-b48a-11e6-9bd4-932d16b3469b]
Caller-Source: [mod_sofia]
Caller-Context: [default]
Caller-Channel-Name: [sofia/external/91000@10.211.55.12]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1480147170950009]
Caller-Channel-Created-Time: [1480147170950009]
Caller-Channel-Answered-Time: [0]
Caller-Channel-Progress-Time: [0]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [1480147172728822]
Caller-Channel-Transfer-Time: [0]
Caller-Channel-Resurrect-Time: [0]
Caller-Channel-Bridged-Time: [0]
Caller-Channel-Last-Hold: [0]
Caller-Channel-Hold-Accum: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]


2016-11-26 12:59:32.728822 [DEBUG] switch_core_state_machine.c:938 (sofia/external/92301@5.9.50.206:5060) State REPORTING
2016-11-26 12:59:32.728822 [NOTICE] mod_cdr_pg_csv.c:256 Query: "INSERT INTO cdrs (local_ip_v4,caller_id_name,caller_id_number,destination_number,start_stamp,answer_stamp,end_stamp,duration,billsec,hangup_cause,uuid,npa,state,map_id,map_type,dispatcher_ip) VALUES ('10.211.55.12','Naseer','91000','92301','2016-11-26 12:59:30',null,'2016-11-26 12:59:32',2,0,'ORIGINATOR_CANCEL','19eb752c-b48a-11e6-9bd4-932d16b3469b','910','NC','10','pbx',null);"
2016-11-26 12:59:32.728822 [INFO] mod_cdr_pg_csv.c:347 CHANNEL_DATA:
Event-Name: [COMMAND]
Core-UUID: [9de62d72-b331-11e6-9520-932d16b3469b]
FreeSWITCH-Hostname: [Tracker]
FreeSWITCH-Switchname: [Tracker]
FreeSWITCH-IPv4: [10.211.55.12]
FreeSWITCH-IPv6: [fdb2:2c26:f4e4:0:21c:42ff:fe51:ad2b]
Event-Date-Local: [2016-11-26 12:59:32]
Event-Date-GMT: [Sat, 26 Nov 2016 07:59:32 GMT]
Event-Date-Timestamp: [1480147172728822]
Event-Calling-File: [mod_cdr_pg_csv.c]
Event-Calling-Function: [my_on_reporting]
Event-Calling-Line-Number: [342]
Event-Sequence: [11662]
Channel-State: [CS_REPORTING]
Channel-Call-State: [HANGUP]
Channel-State-Number: [11]
Channel-Name: [sofia/external/92301@5.9.50.206:5060]
Unique-ID: [19ee734e-b48a-11e6-9be6-932d16b3469b]
Call-Direction: [outbound]
Presence-Call-Direction: [outbound]
Channel-HIT-Dialplan: [false]
Channel-Call-UUID: [19eb752c-b48a-11e6-9bd4-932d16b3469b]
Answer-State: [hangup]
Hangup-Cause: [ORIGINATOR_CANCEL]
Caller-Direction: [outbound]
Caller-Logical-Direction: [outbound]
Caller-Username: [91000]
Caller-Dialplan: [XML]
Caller-Caller-ID-Name: [Naseer]
Caller-Caller-ID-Number: [91000]
Caller-Orig-Caller-ID-Name: [Naseer]
Caller-Orig-Caller-ID-Number: [91000]
Caller-Callee-ID-Name: [Outbound Call]
Caller-Callee-ID-Number: [92301]
Caller-Network-Addr: [10.211.55.2]
Caller-ANI: [91000]
Caller-Destination-Number: [92301]
Caller-Unique-ID: [19ee734e-b48a-11e6-9be6-932d16b3469b]
Caller-Source: [mod_sofia]
Caller-Context: [default]
Caller-Channel-Name: [sofia/external/92301@5.9.50.206:5060]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1480147170968642]
Caller-Channel-Created-Time: [1480147170968642]
Caller-Channel-Answered-Time: [0]
Caller-Channel-Progress-Time: [0]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [1480147172728822]
Caller-Channel-Transfer-Time: [0]
Caller-Channel-Resurrect-Time: [0]
Caller-Channel-Bridged-Time: [0]
Caller-Channel-Last-Hold: [0]
Caller-Channel-Hold-Accum: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]
Other-Type: [originator]
Other-Leg-Direction: [inbound]
Other-Leg-Logical-Direction: [inbound]
Other-Leg-Username: [91000]
Other-Leg-Dialplan: [XML]
Other-Leg-Caller-ID-Name: [Naseer]
Other-Leg-Caller-ID-Number: [91000]
Other-Leg-Orig-Caller-ID-Name: [Naseer]
Other-Leg-Orig-Caller-ID-Number: [91000]
Other-Leg-Network-Addr: [10.211.55.2]
Other-Leg-ANI: [91000]
Other-Leg-Destination-Number: [92301]
Other-Leg-Unique-ID: [19eb752c-b48a-11e6-9bd4-932d16b3469b]
Other-Leg-Source: [mod_sofia]
Other-Leg-Context: [default]
Other-Leg-Channel-Name: [sofia/external/91000@10.211.55.12]
Other-Leg-Profile-Created-Time: [0]
Other-Leg-Channel-Created-Time: [0]
Other-Leg-Channel-Answered-Time: [0]
Other-Leg-Channel-Progress-Time: [0]
Other-Leg-Channel-Progress-Media-Time: [0]
Other-Leg-Channel-Hangup-Time: [0]
Other-Leg-Channel-Transfer-Time: [0]
Other-Leg-Channel-Resurrect-Time: [0]
Other-Leg-Channel-Bridged-Time: [0]
Other-Leg-Channel-Last-Hold: [0]
Other-Leg-Channel-Hold-Accum: [0]
Other-Leg-Screen-Bit: [true]
Other-Leg-Privacy-Hide-Name: [false]
Other-Leg-Privacy-Hide-Number: [false]


2016-11-26 12:59:32.728822 [NOTICE] mod_cdr_pg_csv.c:256 Query: "INSERT INTO cdrs (local_ip_v4,caller_id_name,caller_id_number,destination_number,start_stamp,answer_stamp,end_stamp,duration,billsec,hangup_cause,uuid,npa,state,map_id,map_type,dispatcher_ip) VALUES ('10.211.55.12','Naseer','91000','92301','2016-11-26 12:59:30',null,'2016-11-26 12:59:32',2,0,'ORIGINATOR_CANCEL','19ee734e-b48a-11e6-9be6-932d16b3469b','910','NC','10','pbx','5.9.50.206');"
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:174 sofia/external/91000@10.211.55.12 Standard REPORTING, cause: ORIGINATOR_CANCEL
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:938 (sofia/external/91000@10.211.55.12) State REPORTING going to sleep
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:610 (sofia/external/91000@10.211.55.12) State Change CS_REPORTING -> CS_DESTROY
2016-11-26 12:59:32.748864 [DEBUG] switch_core_session.c:1695 Session 137 (sofia/external/91000@10.211.55.12) Locked, Waiting on external entities
2016-11-26 12:59:32.748864 [NOTICE] switch_core_session.c:1713 Session 137 (sofia/external/91000@10.211.55.12) Ended
2016-11-26 12:59:32.748864 [NOTICE] switch_core_session.c:1717 Close Channel sofia/external/91000@10.211.55.12 [CS_DESTROY]
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:741 (sofia/external/91000@10.211.55.12) Running State Change CS_DESTROY (Cur 1 Tot 138)
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:751 (sofia/external/91000@10.211.55.12) State DESTROY
2016-11-26 12:59:32.748864 [DEBUG] mod_sofia.c:345 sofia/external/91000@10.211.55.12 SOFIA DESTROY
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:181 sofia/external/91000@10.211.55.12 Standard DESTROY
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:751 (sofia/external/91000@10.211.55.12) State DESTROY going to sleep
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:174 sofia/external/92301@5.9.50.206:5060 Standard REPORTING, cause: ORIGINATOR_CANCEL
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:938 (sofia/external/92301@5.9.50.206:5060) State REPORTING going to sleep
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:610 (sofia/external/92301@5.9.50.206:5060) State Change CS_REPORTING -> CS_DESTROY
2016-11-26 12:59:32.748864 [DEBUG] switch_core_session.c:1695 Session 138 (sofia/external/92301@5.9.50.206:5060) Locked, Waiting on external entities
2016-11-26 12:59:32.748864 [NOTICE] switch_core_session.c:1713 Session 138 (sofia/external/92301@5.9.50.206:5060) Ended
2016-11-26 12:59:32.748864 [NOTICE] switch_core_session.c:1717 Close Channel sofia/external/92301@5.9.50.206:5060 [CS_DESTROY]
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:741 (sofia/external/92301@5.9.50.206:5060) Running State Change CS_DESTROY (Cur 0 Tot 138)
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:751 (sofia/external/92301@5.9.50.206:5060) State DESTROY
2016-11-26 12:59:32.748864 [DEBUG] mod_sofia.c:345 sofia/external/92301@5.9.50.206:5060 SOFIA DESTROY
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:181 sofia/external/92301@5.9.50.206:5060 Standard DESTROY
2016-11-26 12:59:32.748864 [DEBUG] switch_core_state_machine.c:751 (sofia/external/92301@5.9.50.206:5060) State DESTROY going to sleep
freeswitch@Tracker>