*CLI> May 18 17:30:29 DEBUG[3060489104]: chan_sip.c:1603 __sip_ack: Stopping retransmission on '53bcb31f5061cf857c777996038ac0a2@192.168.1.102' of Request 102: Match Found
May 18 17:30:30 DEBUG[3060489104]: db.c:343 opbx_db_del_main: SQL [delete from odb where family = 'SIP/Registry' and keys = '3001']
May 18 17:30:30 DEBUG[3060489104]: db.c:216 opbx_db_put: SQL [insert into odb values('SIP/Registry','3001','192.168.1.1:5060:120:3001:sip:3001@192.168.1.1')]
May 18 17:30:40 DEBUG[3060489104]: chan_sip.c:1603 __sip_ack: Stopping retransmission on '6fd110b94977c12a09bad5c60fddfec9@192.168.1.102' of Request 102: Match Found
May 18 17:30:45 DEBUG[3060489104]: chan_sip.c:1515 __sip_autodestruct: Auto destroying call 'A016BF02-E40E-4B22-8260-58A5707AA8AF@192.168.3.10'
May 18 17:31:16 DEBUG[3060489104]: chan_sip.c:9400 check_user_full: *** Setting NAT on RTP to 1
May 18 17:31:16 DEBUG[3060489104]: chan_sip.c:4789 process_sdp: Activating RTP on response 7DC008D4-64BC-4FD5-BA55-3D13D20F6BFB@192.168.3.10 (1)
May 18 17:31:16 DEBUG[3060489104]: chan_sip.c:5067 process_sdp: T38 state changed to 0 on channel <none>
May 18 17:31:16 DEBUG[3060489104]: chan_sip.c:13283 handle_request_invite: Checking SIP call limits for device 3001
May 18 17:31:16 DEBUG[3060489104]: chan_sip.c:8234 build_route: build_route: Contact hop: <sip:3001@192.168.1.1>
May 18 17:31:16 DEBUG[3078454160]: channel.c:784 channel_find_locked: Avoiding initial deadlock for 'SIP/3001-3555'
-- Executing Set("SIP/3001-3555", "LOCALSTATIONID=Navynet.it FAX") in new stack
-- Executing Set("SIP/3001-3555", "LOCALHEADERINFO=Navynet SRL is a solution provider for business") in new stack
-- Executing Set("SIP/3001-3555", "FAXFILE=/tmp/1179505876.0.tif") in new stack
-- Executing Set("SIP/3001-3555", "FAXFILENOEXT=/tmp/1179505876.0") in new stack
-- Executing RxFAX("SIP/3001-3555", "/tmp/1179505876.0.tif|debug") in new stack
May 18 17:31:16 DEBUG[3052288912]: app_rxfax.c:699 rxfax_exec: Could not answer channel 'SIP/3001-3555'
May 18 17:31:16 DEBUG[3060489104]: chan_sip.c:1603 __sip_ack: Stopping retransmission on '7DC008D4-64BC-4FD5-BA55-3D13D20F6BFB@192.168.3.10' of Response 1: Match Found
May 18 17:31:16 DEBUG[3052288912]: chan_sip.c:4047 sip_rtp_read: Oooh, format changed to 2
May 18 17:31:16 DEBUG[3052288912]: rtp.c:1783 opbx_rtp_write: Had to change frame MS from 0 to 20
May 18 17:31:16 DEBUG[3052288912]: rtp.c:1792 opbx_rtp_write: Able to create smoother :) ms: 20 len 33
May 18 17:31:20 DEBUG[3052288912]: generator.c:125 opbx_generator_deactivate: Trying to deactivate generator in SIP/3001-3555
May 18 17:31:20 DEBUG[3052288912]: generator.c:156 opbx_generator_deactivate: Generator on SIP/3001-3555 stopped after 0 iterations
May 18 17:31:20 DEBUG[3052288912]: app_rxfax.c:192 phase_e_handler: ==============================================================================
May 18 17:31:20 DEBUG[3052288912]: app_rxfax.c:215 phase_e_handler: Fax receive not successful - result (51) The call dropped prematurely.
May 18 17:31:20 DEBUG[3052288912]: app_rxfax.c:217 phase_e_handler: ==============================================================================
May 18 17:31:20 DEBUG[3052288912]: pbx.c:2754 __opbx_pbx_run: Extension 32, priority 5 returned normally even though call was hung up
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '"unknown" <3001>'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '3001'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '32'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is 'default'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is 'SIP/3001-3555'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '(null)'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is 'RxFAX'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '/tmp/1179505876.0.tif|debug'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '2007-05-18 17:31:16'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '2007-05-18 17:31:16'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '2007-05-18 17:31:20'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '4'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '4'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is 'ANSWERED'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '(null)'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '1179505876.0'
May 18 17:31:20 DEBUG[3052288912]: pbx.c:1862 pbx_substitute_variables_helper_full: Function result is '(null)'
May 18 17:31:20 DEBUG[3052288912]: cdr_sqlite3.c:167 sqlite_log: CDR SQLITE3 SQL [INSERT INTO cdr (clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata, start,answer,end,duration,billsec,disposition,amaflags, accountcode) VALUES ('"unknown" <3001>', '3001', '32', 'default', 'SIP/3001-3555', '', 'RxFAX', '/tmp/1179505876.0.tif|debug', '2007-05-18 17:31:16', '2007-05-18 17:31:16', '2007-05-18 17:31:20', 4, 4, 4, 3, '')]
May 18 17:31:21 DEBUG[3052288912]: chan_sip.c:3236 sip_hangup: update_call_counter(3001) - decrement call limit counter
May 18 17:31:21 DEBUG[3052288912]: generator.c:125 opbx_generator_deactivate: Trying to deactivate generator in SIP/3001-3555
May 18 17:31:21 DEBUG[3052288912]: generator.c:156 opbx_generator_deactivate: Generator on SIP/3001-3555 stopped after 0 iterations