rendered paste body/*asterisk messages*/
[Jul 1 22:56:09] NOTICE[31730] chan_lcr.c: [call=NULL ast=NULL] Received request from Asterisk. (data=Ext/06082XXXX23)
[Jul 1 22:56:09] NOTICE[31730] chan_lcr.c: [call=0 ast=NULL] Call instance allocated.
[Jul 1 22:56:09] NOTICE[31730] chan_lcr.c: [call=NULL ast=lcr/4] Received call from Asterisk.
[Jul 1 22:56:09] NOTICE[31730] chan_lcr.c: [call=NULL ast=NULL] Sending (null) to socket.
[Jul 1 22:56:09] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Received new ref by LCR, as requested from chan_lcr. (ref=20)
[Jul 1 22:56:09] NOTICE[30160] chan_lcr.c: [call=20 ast=lcr/4] Sending setup to LCR. (interface=Ext dialstring=06082XXXX23, cid=)
[Jul 1 22:56:09] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_SETUP to socket.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Received new ref by LCR, due to incomming call. (ref=21)
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=0 ast=NULL] Call instance allocated.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=21 ast=NULL] Incomming setup from LCR. (callerid 6082XXXX0, dialing 23)
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=21 ast=lcr/5] Try to start pbx. (exten=23 context=from-ISDN-extern complete=no)
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_OVERLAP to socket.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=21 ast=lcr/5] Extensions matches.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=21 ast=lcr/5] Starting call to Asterisk due to matching extension.
[Jul 1 22:56:10] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Received answer from Asterisk (maybe during lcr_bridge).
[Jul 1 22:56:10] NOTICE[31731] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_CONNECT to socket.
[Jul 1 22:56:10] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Requesting B-channel.
[Jul 1 22:56:10] NOTICE[31731] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_HELLO to socket.
[Jul 1 22:56:10] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Received indicate -1.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Received BCHANNEL_ASSIGN message. (handle=00000002) for ref 21
[Jul 1 22:56:10] NOTICE[30160] bchannel.c: [call=21 ast=NULL] Open DSP audio
[Jul 1 22:56:10] NOTICE[30160] bchannel.c: [call=21 ast=NULL] Activating B-channel.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_HELLO to socket.
[Jul 1 22:56:10] NOTICE[30160] bchannel.c: [call=21 ast=NULL] DL_ESTABLISH confirm: bchannel is now activated (socket 50).
[Jul 1 22:56:10] NOTICE[30160] bchannel.c: [call=NULL ast=NULL] Sending PH_CONTROL DSP-TX_DEJITTER 240f,0
[Jul 1 22:56:10] NOTICE[30160] bchannel.c: [call=NULL ast=NULL] Sending PH_CONTROL DSP-DTMF 2100,0
[Jul 1 22:56:10] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Received indicate AST_CONTROL_RINGING from Asterisk.
[Jul 1 22:56:10] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Using Asterisk 'ring' indication
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=20 ast=lcr/4] Incomming pattern indication from LCR.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=20 ast=lcr/4] Requesting B-channel.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_HELLO to socket.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=20 ast=lcr/4] Sending queued PROGRESS to Asterisk.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=20 ast=lcr/4] Incomming setup acknowledge from LCR.
[Jul 1 22:56:10] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Received answer from Asterisk (maybe during lcr_bridge).
[Jul 1 22:56:10] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Received indicate -1.
[Jul 1 22:56:10] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Received AST_CONTROL_SRCUPDATE from Asterisk.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Received BCHANNEL_ASSIGN message. (handle=00000001) for ref 20
[Jul 1 22:56:10] NOTICE[30160] bchannel.c: [call=20 ast=NULL] Open DSP audio
[Jul 1 22:56:10] NOTICE[30160] bchannel.c: [call=20 ast=NULL] Activating B-channel.
[Jul 1 22:56:10] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_HELLO to socket.
[Jul 1 22:56:10] NOTICE[30160] bchannel.c: [call=20 ast=NULL] DL_ESTABLISH confirm: bchannel is now activated (socket 53).
[Jul 1 22:56:10] NOTICE[30160] bchannel.c: [call=NULL ast=NULL] Sending PH_CONTROL DSP-TX_DEJITTER 240f,0
[Jul 1 22:56:10] NOTICE[30160] bchannel.c: [call=NULL ast=NULL] Sending PH_CONTROL DSP-DTMF 2100,0
[Jul 1 22:56:11] NOTICE[30160] chan_lcr.c: [call=20 ast=lcr/4] Incomming connect (answer) from LCR.
[Jul 1 22:56:11] NOTICE[30160] chan_lcr.c: [call=20 ast=lcr/4] Sending queued ANSWER to Asterisk.
[Jul 1 22:56:11] NOTICE[31730] chan_lcr.c: [call=20 ast=lcr/4] Received AST_CONTROL_SRCUPDATE from Asterisk.
[Jul 1 22:56:11] NOTICE[30160] chan_lcr.c: [call=20 ast=lcr/4] Incomming facility from LCR.
[Jul 1 22:57:08] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Received AST_CONTROL_SRCUPDATE from Asterisk.
[Jul 1 22:57:08] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Received hangup from Asterisk thread.
[Jul 1 22:57:08] NOTICE[31731] chan_lcr.c: [call=21 ast=lcr/5] Releasing ref and freeing call instance.
[Jul 1 22:57:08] NOTICE[31731] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_HELLO to socket.
[Jul 1 22:57:08] NOTICE[31731] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_RELEASE to socket.
[Jul 1 22:57:08] NOTICE[31731] chan_lcr.c: [call=21 ast=NULL] Call instance freed.
[Jul 1 22:57:08] NOTICE[30160] bchannel.c: [call=NULL ast=NULL] ignoring data, because no call associated with bchannel
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Received BCHANNEL_REMOVE message. (handle=00000002)
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_HELLO to socket.
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=20 ast=lcr/4] Message 15 from LCR unhandled.
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=20 ast=lcr/4] Incomming disconnect from LCR. (cause=16)
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_HELLO to socket.
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_RELEASE to socket.
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=0 ast=lcr/4] Sending queued HANGUP to Asterisk.
[Jul 1 22:57:08] NOTICE[31730] chan_lcr.c: [call=NULL ast=NULL] Received request from Asterisk. (data=Ext/)
[Jul 1 22:57:08] NOTICE[31730] chan_lcr.c: [call=0 ast=NULL] Call instance allocated.
[Jul 1 22:57:08] NOTICE[31730] chan_lcr.c: [call=NULL ast=lcr/6] Received call from Asterisk.
[Jul 1 22:57:08] NOTICE[31730] chan_lcr.c: [call=NULL ast=NULL] Sending (null) to socket.
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Received BCHANNEL_REMOVE message. (handle=00000001)
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_HELLO to socket.
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Received new ref by LCR, as requested from chan_lcr. (ref=22)
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=22 ast=lcr/6] Sending setup to LCR. (interface=Ext dialstring=, cid=)
[Jul 1 22:57:08] NOTICE[30160] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_SETUP to socket.
[Jul 1 22:57:09] NOTICE[31730] chan_lcr.c: [call=22 ast=lcr/6] Received hangup from Asterisk thread.
[Jul 1 22:57:09] NOTICE[31730] chan_lcr.c: [call=22 ast=lcr/6] Releasing ref and freeing call instance.
[Jul 1 22:57:09] NOTICE[31730] chan_lcr.c: [call=NULL ast=NULL] Sending MESSAGE_RELEASE to socket.
[Jul 1 22:57:09] NOTICE[31730] chan_lcr.c: [call=22 ast=NULL] Call instance freed.
[Jul 1 22:57:09] NOTICE[31730] chan_lcr.c: [call=0 ast=lcr/4] Received hangup from Asterisk thread.
[Jul 1 22:57:09] NOTICE[31730] chan_lcr.c: [call=0 ast=lcr/4] Freeing call instance, because we have no ref AND we are requesting no ref.
[Jul 1 22:57:09] NOTICE[31730] chan_lcr.c: [call=0 ast=NULL] Call instance freed.
[Jul 1 22:58:16] NOTICE[31897] chan_lcr.c: [call=NULL ast=NULL] Received request from Asterisk. (data=Ext/06082XXXX23)
//next normal dial//
/*syslog*/
Jul 1 22:55:55 VLT FaxQueuer[31476]: SUBMIT JOB 17
Jul 1 22:55:56 VLT FaxGetty[31500]: LOCKWAIT
Jul 1 22:56:09 VLT FaxSend[31719]: MODEM WWW.SOFT-SWITCH.ORG spandsp/
Jul 1 22:56:09 VLT FaxSend[31719]: SEND FAX: JOB 17 DEST 006082XXXX23 COMMID 000000122 DEVICE '/dev/ttyIAX0' FROM 'root <root@VLT.kmkedv.local.>' USER root
Jul 1 22:56:10 VLT FaxGetty[31501]: ANSWER: Call ID 1 "6082XXXX0"
Jul 1 22:56:10 VLT FaxGetty[31501]: ANSWER: Call ID 2 "<NONE>"
Jul 1 22:56:10 VLT FaxGetty[31501]: ANSWER: Call ID 3 "<NONE>"
Jul 1 22:56:10 VLT FaxGetty[31501]: ANSWER: Call ID 4 "s"
Jul 1 22:56:14 VLT FaxGetty[31501]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyIAX1'
Jul 1 22:56:43 VLT kernel: [68444.845752] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.846013] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.849769] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.850002] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.853746] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.854015] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.857743] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.858007] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.861746] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.862000] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.865755] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.866014] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.869741] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.870003] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.873740] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.874002] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.877753] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.878017] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.881742] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.882001] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.885742] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.886001] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.889860] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.890014] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.893741] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.894003] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.897781] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.898000] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.901752] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.902002] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.908211] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.908981] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.909954] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.910012] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.914009] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.918036] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.922111] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.926060] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.930029] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.934167] mISDN_send: error -12
Jul 1 22:56:43 VLT kernel: [68444.938013] mISDN_send: error -12
Jul 1 22:57:06 VLT FaxSend[31719]: SEND FAILED: JOB 17 DEST 006082XXXX23 ERR [125] COMREC invalid command received/no DIS or DTC
Jul 1 22:57:08 VLT asterisk[30141]: rc_avpair_new: unknown attribute 1490026597
Jul 1 22:57:09 VLT asterisk[30141]: rc_avpair_new: unknown attribute 1490026597
Jul 1 22:57:15 VLT FaxGetty[31501]: MODEM WWW.SOFT-SWITCH.ORG spandsp/
Jul 1 22:57:31 VLT HylaFAX[31830]: Filesystem has SysV-style file creation semantics.
Jul 1 22:57:31 VLT HylaFAX[31830]: What we have here is a PDF file
//recovered from error? heh?//