[2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:1] Answer("Local/18085557536@dialer-a229;1", "") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:2] Gosub("Local/18085557536@dialer-a229;1", "set_company_info,s,1(company)") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@set_company_info:1] Set("Local/18085557536@dialer-a229;1", "__companyID=company") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@set_company_info:2] Log("Local/18085557536@dialer-a229;1", "NOTICE, Local/18085557536@dialer-a229;1--1344975108.133524") in new stack [2012-08-14 15:12:17] NOTICE[21166] Ext. s: Local/18085557536@dialer-a229;1--1344975108.133524 [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@set_company_info:3] Set("Local/18085557536@dialer-a229;1", "HASH(company_info)=company,8085551255,808,admin@company.com,30,default,Goto(call_to_user\,opsassis\,1),pbx.provider.net,http://web.provider.net:80,100,Company Name,live,8864b85e-7e43-4f80-b779-8183d327aa5a,company-1334790074,0") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@set_company_info:4] Set("Local/18085557536@dialer-a229;1", "__admin_email=admin@company.com") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@set_company_info:5] Set("Local/18085557536@dialer-a229;1", "__local_calling_area=808") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@set_company_info:6] Set("Local/18085557536@dialer-a229;1", "__config_root=/etc/asterisk/dogstar_configs/") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@set_company_info:7] Set("Local/18085557536@dialer-a229;1", "CHANNEL(musicclass)=default") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@set_company_info:8] Return("Local/18085557536@dialer-a229;1", "") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:3] Gosub("Local/18085557536@dialer-a229;1", "load_host_name,s,1(PBX)") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@load_host_name:1] Set("Local/18085557536@dialer-a229;1", "var=PBX") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@load_host_name:2] Set("SIP/204.244.44.108:5060-00019c54", "PBX=pbx.provider.net [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@load_host_name:3] Set("SIP/204.244.44.108:5060-00019c54", "PBX=pbx.provider.net") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@load_host_name:4] Return("SIP/204.244.44.108:5060-00019c54", "") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:4] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,date)=2012-08-14 15:12:17") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:5] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,direction)=SEND") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:6] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,clid)="User Name" <8085551255>") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:7] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,ani)=8085551255") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:8] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,dnis)=18085557536") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:9] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,company_id)=company") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:10] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,user)=company-user") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:11] Set("SIP/204.244.44.108:5060-00019c54", "CDR(ds_type)=company-FAX-OUT") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:12] Set("SIP/204.244.44.108:5060-00019c54", "CDR(ds_ani)=8085551255") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:13] Set("SIP/204.244.44.108:5060-00019c54", "CDR(ds_dnis)=18085557536") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:14] Set("SIP/204.244.44.108:5060-00019c54", "CDR(ds_from)=company-user") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:15] Set("SIP/204.244.44.108:5060-00019c54", "CDR(accountcode)=company-user") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:16] Set("SIP/204.244.44.108:5060-00019c54", "FAXOPT(headerinfo)=From: "User Name" 8085551255") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:17] Set("SIP/204.244.44.108:5060-00019c54", "FAXOPT(localstationid)="User Name" <8085551255>") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:18] Set("SIP/204.244.44.108:5060-00019c54", "FAXOPT(ecm)=yes") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:19] NoOp("SIP/204.244.44.108:5060-00019c54", "Sending FAX to 18085557536.") in new stack [2012-08-14 15:12:17] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:20] SendFAX("SIP/204.244.44.108:5060-00019c54", "/tmp/resend_1344975108450.tiff,dfzs") in new stack [2012-08-14 15:12:17] VERBOSE[21166] res_fax.c: -- Channel 'SIP/204.244.44.108:5060-00019c54' sending FAX: [2012-08-14 15:12:17] VERBOSE[21166] res_fax.c: -- /tmp/resend_1344975108450.tiff [2012-08-14 15:12:22] VERBOSE[21166] res_fax_digium.c: -- Channel 'SIP/204.244.44.108:5060-00019c54' FAX session '50' started [2012-08-14 15:12:22] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 000.000034 ], STAT_EVT_STRT_TX st: IDLE rt: IDLENSTX [2012-08-14 15:12:22] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 000.000243 ], STAT_EVT_TX_HW_RDY st: WT_TX_HW_RDY rt: TRDYNHTY [2012-08-14 15:12:22] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 000.000528 ], P30EVN_SEND_STARTED [2012-08-14 15:12:32] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 009.859822 ], STAT_INFO_CSI [2012-08-14 15:12:32] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 010.219870 ], STAT_INFO_DIS [2012-08-14 15:12:32] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 010.219965 ], STAT_EVT_DIS st: WT_DIS rt: XXXXNDIS [2012-08-14 15:12:32] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 010.219988 ], STAT_EVT_REMOTE_RX st: WT_DIS rt: WDISNRRX [2012-08-14 15:12:32] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 010.220666 ], STAT_NEG_V17_9600 [2012-08-14 15:12:32] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 010.220688 ], STAT_NEG_MH [2012-08-14 15:12:32] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 010.220741 ], STAT_NEG_RES_204x196 [2012-08-14 15:12:32] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 010.220789 ], STAT_NEG_A4 [2012-08-14 15:12:32] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 010.220809 ], STAT_INFO_TSI [2012-08-14 15:12:32] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 010.220833 ], STAT_INFO_DCS [2012-08-14 15:12:34] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 012.250488 ], STAT_EVT_TX_V21_DONE st: WT_DIS rt: WDISNT21 [2012-08-14 15:12:38] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 015.487242 ], STAT_EVT_TX_TRN_END st: WT_TRN_RESP rt: XXXXNT21 [2012-08-14 15:12:41] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 018.502042 ], STAT_EVT_T4_EXP st: WT_TRN_RESP rt: XXXXNT4X [2012-08-14 15:12:41] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 018.502148 ], STAT_EVT_FSC_ERR st: WT_TRN_RESP rt: WTTNNFSC [2012-08-14 15:12:41] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 018.502173 ], STAT_EVT_DIS st: WT_TRN_RESP rt: XXXXNDIS [2012-08-14 15:12:41] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 018.502189 ], STAT_EVT_REMOTE_RX st: WT_TRN_RESP rt: WDISNRRX [2012-08-14 15:12:41] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 018.503211 ], STAT_NEG_V17_9600 [2012-08-14 15:12:41] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 018.503278 ], STAT_NEG_MH [2012-08-14 15:12:41] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 018.503292 ], STAT_NEG_RES_204x196 [2012-08-14 15:12:41] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 018.503306 ], STAT_NEG_A4 [2012-08-14 15:12:41] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 018.503324 ], STAT_INFO_TSI [2012-08-14 15:12:41] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 018.503348 ], STAT_INFO_DCS [2012-08-14 15:12:42] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 019.514993 ], STAT_FRM_CFR [2012-08-14 15:12:42] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 019.515103 ], STAT_EVT_CFR st: WT_DIS rt: GO_TO_B [2012-08-14 15:12:42] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 019.515127 ], STAT_EVT_GO_TO_B st: WT_DIS rt: XXXXNGOB [2012-08-14 15:12:43] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 020.528851 ], STAT_EVT_TX_V21_DONE st: WT_HW_CLS rt: UNEXPECT [2012-08-14 15:12:44] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 022.018749 ], STAT_EVT_HW_CLOSE st: WT_HW_CLS rt: WCLSNCLS [2012-08-14 15:12:44] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 022.019549 ], STAT_SES_COMPLETE [2012-08-14 15:12:44] VERBOSE[21174] res_fax_digium.c: -- FAX handle 0: [ 022.019644 ], P30EVN_COMPLETE [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [s@send_fax:21] Hangup("SIP/204.244.44.108:5060-00019c54", "") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: == Spawn extension (send_fax, s, 21) exited non-zero on 'SIP/204.244.44.108:5060-00019c54' [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:1] NoOp("SIP/204.244.44.108:5060-00019c54", "Sending fax finished.") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:2] NoOp("SIP/204.244.44.108:5060-00019c54", "T.38 Passthrough: 0") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:3] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax ECM: no") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:4] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Error: NO_ERROR") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:5] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax File: /tmp/resend_1344975108450.tiff") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:6] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Files: /tmp/resend_1344975108450.tiff") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:7] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Header: From: "User Name" 8085551255") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:8] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Local StationID: "User Name" <8085551255>") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:9] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Max Rate: 9600") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:10] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Min Rate: 2400") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:11] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Modem: V17,V27,V29") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:12] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Pages: 0") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:13] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Rate: 2400") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:14] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Remote Station ID: ") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:15] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Resolution: unknown") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:16] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Status: FAILED") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:17] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Verbose Status: FAX_FAILURE_PROTOCOL_ERROR") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:18] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Channel Status: FAILED") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:19] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Channel Error: NO_ERROR") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:20] NoOp("SIP/204.244.44.108:5060-00019c54", "Fax Channel Pages: 0") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:21] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,duration)=27") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:22] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,pages)=0") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:23] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,status)=FAILED") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:24] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,verbose_status)=FAX_FAILURE_PROTOCOL_ERROR") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:25] Set("SIP/204.244.44.108:5060-00019c54", "WRITE_FAX_CDR(1344975108.133524,pbx.provider.net,error)=NO_ERROR") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:26] Set("SIP/204.244.44.108:5060-00019c54", "CDR(userfield)=FAILED (FAX_FAILURE_PROTOCOL_ERROR)") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:27] System("SIP/204.244.44.108:5060-00019c54", "tiff2pdf -d -o /tmp/resend_1344975108450.pdf /tmp/resend_1344975108450.tiff") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:28] System("SIP/204.244.44.108:5060-00019c54", "rm -f /tmp/resend_1344975108450.tiff") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:29] Gosub("SIP/204.244.44.108:5060-00019c54", "store_fax,s,1(SEND,company-user,18085557536,/tmp/resend_1344975108450.pdf,admin@company.com)") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [s@store_fax:1] Set("SIP/204.244.44.108:5060-00019c54", "backupDir=/tmp/faxes/") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [s@store_fax:2] System("SIP/204.244.44.108:5060-00019c54", "mkdir -p /tmp/faxes/") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [s@store_fax:3] System("SIP/204.244.44.108:5060-00019c54", "mv /tmp/resend_1344975108450.pdf /tmp/faxes//1344975108.133524.pdf") in new stack [2012-08-14 15:12:44] VERBOSE[21166] pbx.c: -- Executing [s@store_fax:4] System("SIP/204.244.44.108:5060-00019c54", "echo -e "direction=SEND\nfrom=company-user\nto=18085557536\ntime=1344975164\npages=0\nerror=NO_ERROR\nstatus=FAILED\nverboseStatus=FAX_FAILURE_PROTOCOL_ERROR\npdf=/ravon/tmp/faxes//1344975108.133524.pdf\nemail=admin@company.com" > /ravon/tmp/faxes//1344975108.133524.txt") in new stack [2012-08-14 15:12:45] VERBOSE[21166] pbx.c: -- Executing [s@store_fax:5] System("SIP/204.244.44.108:5060-00019c54", "chmod +x /scripts/poll_for_new_fax.php") in new stack [2012-08-14 15:12:45] VERBOSE[21166] pbx.c: -- Executing [s@store_fax:6] System("SIP/204.244.44.108:5060-00019c54", "dos2unix /scripts/poll_for_new_fax.php") in new stack [2012-08-14 15:12:45] VERBOSE[21166] pbx.c: -- Executing [s@store_fax:7] System("SIP/204.244.44.108:5060-00019c54", "/scripts/poll_for_new_fax.php dir=/tmp/faxes/ &") in new stack [2012-08-14 15:12:45] VERBOSE[21166] pbx.c: -- Executing [s@store_fax:8] Return("SIP/204.244.44.108:5060-00019c54", "") in new stack [2012-08-14 15:12:45] VERBOSE[21166] pbx.c: -- Executing [h@send_fax:30] Hangup("SIP/204.244.44.108:5060-00019c54", "") in new stack [2012-08-14 15:12:45] VERBOSE[21166] pbx.c: == Spawn extension (send_fax, h, 30) exited non-zero on 'SIP/204.244.44.108:5060-00019c54'