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@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@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@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