May 29 18:53:26 DEBUG[22136] chan_sip.c: Setting NAT on RTP to 0
May 29 18:53:26 WARNING[22136] chan_sip.c: Unknown SDP media type in offer: video 11512 RTP/AVP 26 31 34 103
May 29 18:53:26 DEBUG[22136] chan_sip.c: Checking SIP call limits for device 25804760
May 29 18:53:26 DEBUG[22136] chan_sip.c: build_route: Contact hop: <sip:28551911@200.27.79.52>
May 29 18:53:26 VERBOSE[22683] logger.c: -- Executing NoOp("SIP/25804760-2d4b", "Received incoming SIP connection from unknown peer to 25804760") in new stack
May 29 18:53:26 DEBUG[22683] pbx.c: Expression result is '0'
May 29 18:53:26 DEBUG[22683] pbx.c: Function result is '25804760'
May 29 18:53:26 VERBOSE[22683] logger.c: -- Executing Set("SIP/25804760-2d4b", "DID=25804760") in new stack
May 29 18:53:26 VERBOSE[22683] logger.c: -- Executing Goto("SIP/25804760-2d4b", "s|1") in new stack
May 29 18:53:26 VERBOSE[22683] logger.c: -- Goto (from-sip-external,s,1)
May 29 18:53:26 DEBUG[22683] pbx.c: Expression result is '1'
May 29 18:53:26 VERBOSE[22683] logger.c: -- Executing GotoIf("SIP/25804760-2d4b", "1?from-trunk|25804760|1") in new stack
May 29 18:53:26 VERBOSE[22683] logger.c: -- Goto (from-trunk,25804760,1)
May 29 18:53:26 VERBOSE[22683] logger.c: -- Executing Set("SIP/25804760-2d4b", "FROM_DID=25804760") in new stack
May 29 18:53:26 VERBOSE[22683] logger.c: -- Executing Set("SIP/25804760-2d4b", "FAX_RX=system") in new stack
May 29 18:53:26 VERBOSE[22683] logger.c: -- Executing Set("SIP/25804760-2d4b", "FAX_RX_EMAIL=tco@apache.cl") in new stack
May 29 18:53:26 VERBOSE[22683] logger.c: -- Executing Answer("SIP/25804760-2d4b", "") in new stack
May 29 18:53:26 DEBUG[22106] channel.c: Avoiding initial deadlock for 'SIP/25804760-2d4b'
May 29 18:53:26 VERBOSE[22683] logger.c: -- Executing PlayTones("SIP/25804760-2d4b", "ring") in new stack
May 29 18:53:26 DEBUG[22683] channel.c: Scheduling timer at 160 sample intervals
May 29 18:53:26 DEBUG[22106] channel.c: Avoiding initial deadlock for 'SIP/25804760-2d4b'
May 29 18:53:26 VERBOSE[22683] logger.c: -- Executing NVFaxDetect("SIP/25804760-2d4b", "30") in new stack
May 29 18:53:26 DEBUG[22683] app_nv_faxdetect.c: Preparing detect of fax (waitdur=30ms, sildur=1000ms, mindur=100ms, maxdur=-1ms)
May 29 18:53:26 DEBUG[22136] chan_sip.c: Stopping retransmission on '663c9c96552bc97a1025016d5355832d@200.27.79.52' of Response 102: Match Found
May 29 18:53:26 DEBUG[22683] channel.c: Generator got voice, switching to phase locked mode
May 29 18:53:26 DEBUG[22683] channel.c: Scheduling timer at 0 sample intervals
May 29 18:53:26 DEBUG[22683] app_nv_faxdetect.c: Start of voice token!
May 29 18:53:27 DEBUG[22683] app_nv_faxdetect.c: Found unqualified token of 0 ms
May 29 18:53:30 DEBUG[22683] app_nv_faxdetect.c: Start of voice token!
May 29 18:53:30 DEBUG[22683] app_nv_faxdetect.c: Fax detected on SIP/25804760-2d4b
May 29 18:53:30 NOTICE[22683] app_nv_faxdetect.c: Redirecting SIP/25804760-2d4b to fax extension
May 29 18:53:30 VERBOSE[22683] logger.c: -- Executing Goto("SIP/25804760-2d4b", "ext-fax|in_fax|1") in new stack
May 29 18:53:30 VERBOSE[22683] logger.c: -- Goto (ext-fax,in_fax,1)
May 29 18:53:30 VERBOSE[22683] logger.c: -- Executing StopPlayTones("SIP/25804760-2d4b", "") in new stack
May 29 18:53:30 DEBUG[22683] channel.c: Scheduling timer at 0 sample intervals
May 29 18:53:30 DEBUG[22683] pbx.c: Expression result is '1'
May 29 18:53:30 VERBOSE[22683] logger.c: -- Executing GotoIf("SIP/25804760-2d4b", "1?3:analog_fax|1") in new stack
May 29 18:53:30 VERBOSE[22683] logger.c: -- Goto (ext-fax,in_fax,3)
May 29 18:53:30 VERBOSE[22683] logger.c: -- Executing Macro("SIP/25804760-2d4b", "faxreceive") in new stack
May 29 18:53:30 VERBOSE[22683] logger.c: -- Executing Set("SIP/25804760-2d4b", "FAXFILE=/var/spool/asterisk/fax/1148943206.13.tif") in new stack
May 29 18:53:30 VERBOSE[22683] logger.c: -- Executing Set("SIP/25804760-2d4b", "EMAILADDR=tco@apache.cl") in new stack
May 29 18:53:30 VERBOSE[22683] logger.c: -- Executing RxFAX("SIP/25804760-2d4b", "/var/spool/asterisk/fax/1148943206.13.tif") in new stack
May 29 18:53:30 NOTICE[22683] channel.c: Dropping incompatible voice frame on SIP/25804760-2d4b of format slin since our native format has changed to g726
May 29 18:54:15 DEBUG[22136] chan_sip.c: Scheduled a registration timeout for 200.27.79.52 id #602
May 29 18:54:15 DEBUG[22136] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '71d54a1b07dd1269415e903a23ff67bc@200.28.21.121' Request 104: Found
May 29 18:54:15 DEBUG[22136] chan_sip.c: Stopping retransmission on '71d54a1b07dd1269415e903a23ff67bc@200.28.21.121' of Request 104: Match Found
May 29 18:54:15 DEBUG[22136] chan_sip.c: Scheduled a registration timeout for 200.27.79.52 id #605
May 29 18:54:15 DEBUG[22136] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '71d54a1b07dd1269415e903a23ff67bc@200.28.21.121' Request 105: Found
May 29 18:54:16 DEBUG[22136] chan_sip.c: Stopping retransmission on '71d54a1b07dd1269415e903a23ff67bc@200.28.21.121' of Request 105: Match Found
May 29 18:54:16 DEBUG[22136] chan_sip.c: Registration successful
May 29 18:54:16 DEBUG[22136] chan_sip.c: Cancelling timeout 602
May 29 18:54:16 DEBUG[22136] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '700b1f843f83ef9e03c4a8d15bf91df8@200.28.21.121' Request 104: Found
May 29 18:54:16 DEBUG[22136] chan_sip.c: Scheduled a re