[asterisk-users] spanDSP + rxfax

Thu Aug 17 06:06:37 MST 2006

Hi,
I trying to recieve fax with spanDSP and rxfax. scheme: 
asterisk---sipUA(audiocodes mp-112)--fax(hp5610). I'm allways getting by 
from UA at the beginning of transmission. Hi does that because sees that 
rtp connection is broken.  I suppose my multifunction hp box is not 
working correctly but I don't have other to try ;). Maybe somebody from 
debug may say thats wrong?

Aug 17 09:53:58 DEBUG[704] chan_sip.c: Setting NAT on RTP to 0
Aug 17 09:53:58 DEBUG[704] chan_sip.c: Checking SIP call limits for 
device 123
Aug 17 09:53:58 DEBUG[704] chan_sip.c: build_route: Contact hop: 
<sip:123 at 192.168.144.173>
Aug 17 09:53:58 VERBOSE[704] logger.c:     -- Executing 
Answer("SIP/123-df9b", "") in new stack
Aug 17 09:53:58 VERBOSE[704] logger.c:     -- Executing 
RxFAX("SIP/123-df9b", "/home/minkpr/test.tif|debug") in new stack
Aug 17 09:53:58 DEBUG[704] chan_sip.c: Stopping retransmission on 
'196416229711200004116 at 192.168.144.173' of Response 2: Match Found
Aug 17 09:53:59 DEBUG[704] app_rxfax.c: FLOW HDLC carrier up
Aug 17 09:53:59 DEBUG[704] app_rxfax.c: FLOW HDLC carrier down
Aug 17 09:53:59 DEBUG[704] app_rxfax.c: FLOW HDLC carrier up
Aug 17 09:53:59 DEBUG[704] app_rxfax.c: FLOW HDLC carrier down
Aug 17 09:54:01 DEBUG[704] app_rxfax.c: FLOW Changed from phase 1 to 4
Aug 17 09:54:01 DEBUG[704] app_rxfax.c: FLOW >>> DIS:Aug 17 09:54:01 
DEBUG[704] app_rxfax.c:  80Aug 17 09:54:01 DEBUG[704] app_rxfax.c:  
00Aug 17 09:54:01 DEBUG[704] app_rxfax.c:  ceAug 17 09:54:01 DEBUG[704] 
app_rxfax.c:  f4Aug 17 09:54:01 DEBUG[704] app_rxfax.c:  80Aug 17 
09:54:01 DEBUG[704] app_rxfax.c:  80Aug 17 09:54:01 DEBUG[704] 
app_rxfax.c:  81Aug 17 09:54:01 DEBUG[704] app_rxfax.c:  80Aug 17 
09:54:01 DEBUG[704] app_rxfax.c:  80Aug 17 09:54:01 DEBUG[704] 
app_rxfax.c:  80Aug 17 09:54:01 DEBUG[704] app_rxfax.c:  18Aug 17 
09:54:01 DEBUG[704] app_rxfax.c:
Aug 17 09:54:03 DEBUG[704] app_rxfax.c: FLOW HDLC underflow in state 9
Aug 17 09:54:03 DEBUG[704] app_rxfax.c: FLOW Changed from phase 4 to 3
Aug 17 09:54:06 DEBUG[704] app_rxfax.c: FLOW T4 timeout in state 9
Aug 17 09:54:06 DEBUG[704] app_rxfax.c: FLOW Changed from phase 3 to 4
Aug 17 09:54:06 DEBUG[704] app_rxfax.c: FLOW >>> DIS:Aug 17 09:54:06 
DEBUG[704] app_rxfax.c:  80Aug 17 09:54:06 DEBUG[704] app_rxfax.c:  
00Aug 17 09:54:06 DEBUG[704] app_rxfax.c:  ceAug 17 09:54:06 DEBUG[704] 
app_rxfax.c:  f4Aug 17 09:54:06 DEBUG[704] app_rxfax.c:  80Aug 17 
09:54:06 DEBUG[704] app_rxfax.c:  80Aug 17 09:54:06 DEBUG[704] 
app_rxfax.c:  81Aug 17 09:54:06 DEBUG[704] app_rxfax.c:  80Aug 17 
09:54:06 DEBUG[704] app_rxfax.c:  80Aug 17 09:54:06 DEBUG[704] 
app_rxfax.c:  80Aug 17 09:54:06 DEBUG[704] app_rxfax.c:  18Aug 17 
09:54:06 DEBUG[704] app_rxfax.c:
Aug 17 09:54:07 DEBUG[704] app_rxfax.c: FLOW T2 timeout
Aug 17 09:54:07 DEBUG[704] app_rxfax.c: FLOW Start receiving document
Aug 17 09:54:07 DEBUG[704] app_rxfax.c: FLOW >>> DIS:Aug 17 09:54:07 
DEBUG[704] app_rxfax.c:  80Aug 17 09:54:07 DEBUG[704] app_rxfax.c:  
00Aug 17 09:54:07 DEBUG[704] app_rxfax.c:  ceAug 17 09:54:07 DEBUG[704] 
app_rxfax.c:  f4Aug 17 09:54:07 DEBUG[704] app_rxfax.c:  80Aug 17 
09:54:07 DEBUG[704] app_rxfax.c:  80Aug 17 09:54:07 DEBUG[704] 
app_rxfax.c:  81Aug 17 09:54:07 DEBUG[704] app_rxfax.c:  80Aug 17 
09:54:07 DEBUG[704] app_rxfax.c:  80Aug 17 09:54:07 DEBUG[704] 
app_rxfax.c:  80Aug 17 09:54:07 DEBUG[704] app_rxfax.c:  18Aug 17 
09:54:07 DEBUG[704] app_rxfax.c:
Aug 17 09:54:08 DEBUG[704] app_rxfax.c: FLOW HDLC underflow in state 9
Aug 17 09:54:08 DEBUG[704] app_rxfax.c: FLOW Changed from phase 4 to 3
Aug 17 09:54:08 DEBUG[704] app_rxfax.c: FLOW HDLC carrier up
Aug 17 09:54:08 DEBUG[704] app_rxfax.c: FLOW HDLC framing OK
Aug 17 09:54:10 DEBUG[704] chan_sip.c: Stopping retransmission on 
'196416229711200004116 at 192.168.144.173' of Response 3: Match Found
Aug 17 09:54:10 VERBOSE[704] logger.c:   == Forcing Marker bit, because 
SSRC has changed
Aug 17 09:54:10 DEBUG[704] app_rxfax.c: FLOW <<< DCS:Aug 17 09:54:10 
DEBUG[704] app_rxfax.c:  83Aug 17 09:54:10 DEBUG[704] app_rxfax.c:  
00Aug 17 09:54:10 DEBUG[704] app_rxfax.c:  c6Aug 17 09:54:10 DEBUG[704] 
app_rxfax.c:  f4Aug 17 09:54:10 DEBUG[704] app_rxfax.c:  80Aug 17 
09:54:10 DEBUG[704] app_rxfax.c:  80Aug 17 09:54:10 DEBUG[704] 
app_rxfax.c:  80Aug 17 09:54:10 DEBUG[704] app_rxfax.c:  80Aug 17 
09:54:10 DEBUG[704] app_rxfax.c:  80Aug 17 09:54:10 DEBUG[704] 
app_rxfax.c:  80Aug 17 09:54:10 DEBUG[704] app_rxfax.c:  00Aug 17 
09:54:10 DEBUG[704] app_rxfax.c:
Aug 17 09:54:10 DEBUG[704] app_rxfax.c: FLOW DCS with final frame tag
Aug 17 09:54:10 DEBUG[704] app_rxfax.c: FLOW In state 9
Aug 17 09:54:10 DEBUG[704] app_rxfax.c: FLOW Get at 9600bps, modem 1
Aug 17 09:54:10 DEBUG[704] app_rxfax.c: FLOW Changed from phase 3 to 5
Aug 17 09:54:10 DEBUG[704] app_rxfax.c: FLOW Non-ECM carrier up
Aug 17 09:54:11 DEBUG[704] app_rxfax.c: FLOW Non-ECM carrier down
Aug 17 09:54:11 DEBUG[704] app_rxfax.c: FLOW Non-ECM carrier up
Aug 17 09:54:11 DEBUG[704] app_rxfax.c: FLOW Non-ECM carrier trained
Aug 17 09:54:12 DEBUG[704] app_rxfax.c: FLOW Non-ECM carrier down
Aug 17 09:54:12 DEBUG[704] app_rxfax.c: FLOW Changed from phase 5 to 4
Aug 17 09:54:12 DEBUG[704] app_rxfax.c: FLOW >>> CFR:Aug 17 09:54:12 
DEBUG[704] app_rxfax.c:  84Aug 17 09:54:12 DEBUG[704] app_rxfax.c:
Aug 17 09:54:14 DEBUG[704] app_rxfax.c: FLOW HDLC underflow in state 5
Aug 17 09:54:14 DEBUG[704] app_rxfax.c: FLOW Post trainability
Aug 17 09:54:14 DEBUG[704] app_rxfax.c: FLOW Changed from phase 4 to 5
Aug 17 09:54:14 DEBUG[704] app_rxfax.c: FLOW Non-ECM carrier up
Aug 17 09:54:15 DEBUG[704] app_rxfax.c: FLOW Non-ECM carrier trained
Aug 17 09:54:24 DEBUG[704] app_rxfax.c: Got hangup
Aug 17 09:54:24 DEBUG[704] pbx.c: Extension 123, priority 2 returned 
normally even though call was hung up
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '123'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '123'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '123'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is 'default'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is 'SIP/123-df9b'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '(null)'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is 'RxFAX'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is 
'/home/minkpr/test.tif|debug'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '2006-08-17 09:53:58'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '2006-08-17 09:53:58'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '2006-08-17 09:54:24'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '26'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '26'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is 'ANSWERED'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is 'DOCUMENTATION'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '(null)'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '1155797638.2'
Aug 17 09:54:24 DEBUG[704] pbx.c: Function result is '(null)'
Aug 17 09:54:24 DEBUG[704] chan_sip.c: update_call_counter(123) - 
decrement call limit counter

thanks
Mindaugas