There's a fax (unknown model, but other faxes are routed onto this (?))
This worked very good on an older openpbx-1.2-rc3 system a the beginning
but now it doesn't anymore on exactly the same system.
(the system was turned off temporarily between)
Now i get
........
chan_sip.c: Oooh, format changed to 4
chan_sip.c: Oooh, format changed to 4
............
very early in the logs which wasn't there before
Finally the fax is said to be successfully received but it's always a 0byte tif.
Almost impossible that the error is in opbx as nothing changed, but maybe
you could give me a hint whereelse this problem might come from?
EDIT
Here's a logfile of a non-working fax:
Jul 12 11:50:42 DEBUG[3060837296] chan_sip.c: Got this other party (o = '- 1999832 0 IN IP4 xxx.xxx.xxx.xxx.')
Jul 12 11:50:42 DEBUG[3060837296] chan_sip.c: Activating RTP on response xxxxxx@xxxxxxxxxx (1)
Jul 12 11:50:42 DEBUG[3060837296] chan_sip.c: T38 state changed to 0 on channel <none>
Jul 12 11:50:42 DEBUG[3060837296] chan_sip.c: Checking SIP call limits for device
Jul 12 11:50:42 DEBUG[3060837296] chan_sip.c: build_route: Record-Route hop: <sip:xxxxxxxxxxx;ftag=xxxxx;lr=on>
Jul 12 11:50:42 DEBUG[3060837296] chan_sip.c: build_route: Record-Route hop: <sip:xxxxxxx;lr=on;ftag=xxxxxxxx>
Jul 12 11:50:42 VERBOSE[3057556400] logger.c: -- Executing FaxDetect("SIP-XXXX-XXXXXXXXXX", "25") in new stack
Jul 12 11:50:42 DEBUG[3057556400] app_faxdetect.c: Preparing detect of fax (waitdur=25ms, sildur=1000ms, mindur=100ms, maxdur=-1ms)
Jul 12 11:50:42 DEBUG[3060837296] chan_sip.c: Stopping retransmission on 'xxxxxxxx@xxxxxxxx' of Response 1: Match Found
Jul 12 11:50:42 DEBUG[3057556400] chan_sip.c: Oooh, format changed to 8
Jul 12 11:50:42 DEBUG[3057556400] chan_sip.c: Oooh, format changed to 4
Jul 12 11:50:42 DEBUG[3057556400] rtp.c: Had to change frame MS from 0 to 20
Jul 12 11:50:42 DEBUG[3057556400] rtp.c: Able to create smoother :) ms: 20 len 160
Jul 12 11:50:42 DEBUG[3057556400] app_faxdetect.c: Start of voice token
Jul 12 11:50:43 DEBUG[3057556400] chan_sip.c: Fax CNG detected on SIP-XXXX-XXXXXXXXXX
Jul 12 11:50:43 DEBUG[3057556400] chan_sip.c: * Detected inband DTMF 'f'
Jul 12 11:50:43 DEBUG[3057556400] chan_sip.c: T38MaxFaxRate 14400 found
Jul 12 11:50:43 DEBUG[3057556400] chan_sip.c: T38 state changed to 2 on channel SIP-XXXX-XXXXXXXXXX
Jul 12 11:50:43 DEBUG[3057556400] app_faxdetect.c: Fax detected on SIP-XXXX-XXXXXXXXXX
Jul 12 11:50:43 NOTICE[3057556400] app_faxdetect.c: Redirecting SIP-XXXX-XXXXXXXXXX to fax extension [DTMF]
Jul 12 11:50:43 VERBOSE[3057556400] logger.c: -- Executing NoOp("SIP-XXXX-XXXXXXXXXX", "Incoming Fax") in new stack
Jul 12 11:50:43 DEBUG[3060837296] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on 'xxxxxxxx@xxxxxxxxxxx' Request 102: Found
Jul 12 11:50:43 VERBOSE[3057556400] logger.c: -- Executing System("SIP-XXXX-XXXXXXXXXX", "mkdir -p /var/tmp/fax/") in new stack
Jul 12 11:50:43 VERBOSE[3057556400] logger.c: -- Executing Set("SIP-XXXX-XXXXXXXXXX", "LOCALSTATIONID=Faxserver") in new stack
Jul 12 11:50:43 VERBOSE[3057556400] logger.c: -- Executing Set("SIP-XXXX-XXXXXXXXXX", "LOCALHEADERINFO=Fax xxxxxxx") in new stack
Jul 12 11:50:43 VERBOSE[3057556400] logger.c: -- Executing Set("SIP-XXXX-XXXXXXXXXX", "FAXFILE=/var/tmp/fax/xxxxx0.tif") in new stack
Jul 12 11:50:43 VERBOSE[3057556400] logger.c: -- Executing Set("SIP-XXXX-XXXXXXXXXX", "FAXFILENOEXT=/var/tmp/fax/xxxx") in new stack
Jul 12 11:50:43 VERBOSE[3057556400] logger.c: -- Executing RxFAX("SIP-XXXX-XXXXXXXXXX", "/var/tmp/fax/xxxxxxxx.tif") in new stack
Jul 12 11:50:45 DEBUG[3060837296] chan_sip.c: Acked pending invite 102
Jul 12 11:50:45 DEBUG[3060837296] chan_sip.c: Stopping retransmission on 'xxxxxxx@sxxxxxxxxxx' of Request 102: Match Found
Jul 12 11:50:45 DEBUG[3060837296] chan_sip.c: Got this other party (o = '- 1999832 2 IN IP4 xxx.xxx.xxx.xxx.')
Jul 12 11:50:45 DEBUG[3060837296] chan_sip.c: Activating UDPTL on response xxxx@xxxxxxx (1)
Jul 12 11:50:45 DEBUG[3060837296] chan_sip.c: T38 state changed to 4 on channel SIP-XXXX-XXXXXXXXXX
Jul 12 11:50:45 DEBUG[3060837296] chan_sip.c: build_route: Record-Route hop: <sip:xx.xx;ftag=xxxxxxx;lr=on>
Jul 12 11:50:45 DEBUG[3060837296] chan_sip.c: build_route: Record-Route hop: <sip:xx.xx.xx.;lr=on;ftag=xxxxxxx>
Jul 12 11:50:45 DEBUG[3060837296] chan_sip.c: Channel Bridge information is non-existant. T38 Termination requested.
Jul 12 11:51:21 DEBUG[3057556400] app_rxfax.c: ==============================================================================
Jul 12 11:51:21 DEBUG[3057556400] app_rxfax.c: Fax successfully received.
Jul 12 11:51:21 DEBUG[3057556400] app_rxfax.c: Remote station id:
Jul 12 11:51:21 DEBUG[3057556400] app_rxfax.c: Local station id: Faxserver
Jul 12 11:51:21 DEBUG[3057556400] app_rxfax.c: Pages transferred: 0
Jul 12 11:51:21 DEBUG[3057556400] app_rxfax.c: Image resolution: 0 x 0
Jul 12 11:51:21 DEBUG[3057556400] app_rxfax.c: Transfer Rate: 14400
Jul 12 11:51:21 DEBUG[3057556400] app_rxfax.c: ==============================================================================
Jul 12 11:51:21 VERBOSE[3057556400] logger.c: -- Executing NoOp("SIP-XXXX-XXXXXXXXXX", "RX: REMOTESTATIONID is ") in new stack
Jul 12 11:51:21 VERBOSE[3057556400] logger.c: -- Executing NoOp("SIP-XXXX-XXXXXXXXXX", "RX: PHASEESTATUS is 0") in new stack
Jul 12 11:51:21 VERBOSE[3057556400] logger.c: -- Executing GotoIf("SIP-XXXX-XXXXXXXXXX", "0!=0 ? end : tiff2pdf") in new stack