IAXModem Hangs up.. suspect not getting audio

[McNaz7]

Hi there.

I've been looking at this all day and could appreciate some help right about now.

I've got asterisk 1.2.12.1 and hylafax 4.3.0 on a gentoo box.

Ive downloaded iaxmodem-0.1.14 and built iaxmodem statically.

I'm trying to get an incoming fax via sip (I know... dangerous teritory but I feel like I'm missing something simple).

I would appreciate it if someone could spare me a few minutes and point me in the right direction or offer any hints.

My thanks for any help.

Cheers.

Asterisk extensions.conf:
exten => 4808098,1,Answer()
exten => 4808098,2,Dial(IAX2/iaxmodem/${EXTEN})

iaxmodem is running:

Modem started
[2006-10-16 20:42:32] Setting device = '/dev/ttyIAX'
[2006-10-16 20:42:32] Setting owner = 'uucp:uucp'
[2006-10-16 20:42:32] Setting mode = '660'
[2006-10-16 20:42:32] Setting port = 4570
[2006-10-16 20:42:32] Setting refresh = 300
[2006-10-16 20:42:32] Setting server = '127.0.0.1'
[2006-10-16 20:42:32] Setting peername = 'iaxmodem'
[2006-10-16 20:42:32] Setting secret = 'secret'
[2006-10-16 20:42:32] Setting cidname = 'Panther Software'
[2006-10-16 20:42:32] Setting cidnumber = '08450048984'
[2006-10-16 20:42:32] Setting codec = slinear
[2006-10-16 20:42:32] Enabling record
[2006-10-16 20:42:32] Opened pty, slave device: /dev/pts/4
[2006-10-16 20:42:32] Created /dev/ttyIAX symbolic link
[2006-10-16 20:42:32] Registration completed successfully.

Permissions look good.

lrwxrwxrwx 1 root root      10 Oct 16 21:28 ttyIAX -> /dev/pts/2

ls -l /var/spool/fax/
total 76
-r--r--r-- 1 root root 5426 Oct 16 18:31 COPYRIGHT
prw------- 1 uucp uucp    0 Oct 16 21:44 FIFO
prw-r--r-- 1 uucp uucp    0 Oct 16 20:43 FIFO.ttyIAX
drwx------ 2 uucp uucp 4096 Oct 16 18:31 archive
drwxr-xr-x 2 uucp uucp 4096 Oct 16 19:10 bin
drwxr-xr-x 2 uucp uucp 4096 Oct 16 18:31 client
drwxr-xr-x 2 uucp uucp 4096 Oct 16 18:31 config
drwxr-xr-x 2 uucp uucp 4096 Oct 16 19:11 dev
drwx------ 2 uucp uucp 4096 Oct 16 18:31 docq
drwx------ 2 uucp uucp 4096 Oct 16 18:31 doneq
drwxr-xr-x 2 uucp uucp 4096 Oct 16 19:11 etc
drwxr-xr-x 2 fax  fax  4096 Oct 16 18:19 incoming
drwxr-xr-x 2 uucp uucp 4096 Oct 16 18:31 info
drwxr-xr-x 2 uucp uucp 4096 Oct 16 21:44 log
drwxr-xr-x 3 fax  fax  4096 Oct 16 18:19 outgoing
drwx------ 2 uucp uucp 4096 Oct 16 18:31 pollq
drwxr-xr-x 2 uucp uucp 4096 Oct 16 21:44 recvq
drwx------ 2 uucp uucp 4096 Oct 16 18:31 sendq
drwxr-xr-x 2 uucp uucp 4096 Oct 16 19:26 status
drwx------ 2 uucp uucp 4096 Oct 16 21:26 tmp

Call comes in through sip and it gets passed to the IAX channel. I can hear iaxmodem pick the line up with the following logs:

2006-10-16 21:40:14] Incoming call connected 4808098, 01934628711, 01934628711.
[2006-10-16 21:40:14] Answering
[2006-10-16 21:40:14] Adjusting skew to -50.
[2006-10-16 21:40:14] Adjusting skew to -100.
[2006-10-16 21:40:14] Adjusting skew to -150.
[2006-10-16 21:40:17] IAX2 jitter - last_ts: 2960, ts: 3000
[2006-10-16 21:40:21] Adjusting skew to -200.
[2006-10-16 21:40:21] IAX2 jitter - last_ts: 7040, ts: 7080
[2006-10-16 21:40:22] IAX2 jitter - last_ts: 7940, ts: 7980
[2006-10-16 21:40:23] IAX2 jitter - last_ts: 9140, ts: 9180
[2006-10-16 21:40:24] IAX2 jitter - last_ts: 9960, ts: 10000
[2006-10-16 21:40:24] Adjusting skew to -250.
[2006-10-16 21:40:24] Adjusting skew to -300.
[2006-10-16 21:40:24] Adjusting skew to -350.
[2006-10-16 21:40:24] Adjusting skew to -400.
[2006-10-16 21:40:24] Adjusting skew to -450.
[2006-10-16 21:40:24] IAX2 jitter - last_ts: 10020, ts: 10580
[2006-10-16 21:40:25] Adjusting skew to -500.
[2006-10-16 21:40:25] Adjusting skew to -550.
[2006-10-16 21:40:25] Adjusting skew to -600.
[2006-10-16 21:40:25] Adjusting skew to -650.
[2006-10-16 21:40:25] Adjusting skew to -700.
[2006-10-16 21:40:25] IAX2 jitter - last_ts: 10720, ts: 11340
[2006-10-16 21:40:26] Adjusting skew to -750.
[2006-10-16 21:40:26] Adjusting skew to -800.
[2006-10-16 21:40:26] Adjusting skew to -850.
[2006-10-16 21:40:26] Adjusting skew to -900.
[2006-10-16 21:40:26] Adjusting skew to -950.
[2006-10-16 21:40:26] Adjusting skew to -1000.
[2006-10-16 21:40:26] Adjusting skew to -1050.
[2006-10-16 21:40:26] IAX2 jitter - last_ts: 11720, ts: 12680
[2006-10-16 21:40:26] Adjusting skew to -1100.
[2006-10-16 21:40:27] Adjusting skew to -1150.
[2006-10-16 21:40:27] Adjusting skew to -1200.
[2006-10-16 21:40:27] Adjusting skew to -1250.
[2006-10-16 21:40:27] Adjusting skew to -1300.
[2006-10-16 21:40:27] IAX2 jitter - last_ts: 12720, ts: 13300
[2006-10-16 21:40:28] Adjusting skew to -1350.
[2006-10-16 21:40:28] Adjusting skew to -1400.
[2006-10-16 21:40:28] Adjusting skew to -1450.
[2006-10-16 21:40:28] Adjusting skew to -1500.
[2006-10-16 21:40:28] Adjusting skew to -1550.
[2006-10-16 21:40:28] IAX2 jitter - last_ts: 13720, ts: 14320
[2006-10-16 21:40:29] Adjusting skew to -1600.
[2006-10-16 21:40:29] Adjusting skew to -1650.
[2006-10-16 21:40:29] Adjusting skew to -1700.
[2006-10-16 21:40:29] Adjusting skew to -1750.
[2006-10-16 21:40:29] Adjusting skew to -1800.
[2006-10-16 21:40:29] Adjusting skew to -1850.
[2006-10-16 21:40:29] IAX2 jitter - last_ts: 14720, ts: 15500
[2006-10-16 21:40:35] IAX2 jitter - last_ts: 21060, ts: 21100
[2006-10-16 21:40:35] Adjusting skew to -1900.
[2006-10-16 21:40:35] IAX2 jitter - last_ts: 21360, ts: 21400
[2006-10-16 21:40:35] IAX2 jitter - last_ts: 21520, ts: 21560
[2006-10-16 21:40:41] IAX2 jitter - last_ts: 27640, ts: 27680
[2006-10-16 21:40:46] IAX2 jitter - last_ts: 32420, ts: 32460

faxgetty logs:

Oct 16 21:40:14 [FaxGetty] STATE CHANGE: RUNNING -> LISTENING
Oct 16 21:40:14 [FaxGetty] --> [9:DATE=1016]
Oct 16 21:40:14 [FaxGetty] --> [9:TIME=2140]
Oct 16 21:40:14 [FaxGetty] --> [16:NAME=01934628711]
Oct 16 21:40:14 [FaxGetty] --> [16:NMBR=01934628711]
Oct 16 21:40:14 [FaxGetty] --> [11:ANID=<NONE>]
Oct 16 21:40:14 [FaxGetty] --> [12:NDID=4808098]
Oct 16 21:40:14 [FaxGetty] --> [4:RING]
Oct 16 21:40:14 [FaxGetty] ANSWER: Call ID 1 "01934628711"
Oct 16 21:40:14 [FaxGetty] ANSWER: Call ID 2 "01934628711"
Oct 16 21:40:14 [FaxGetty] ANSWER: Call ID 3 "<NONE>"
Oct 16 21:40:14 [FaxGetty] ANSWER: Call ID 4 "4808098"
Oct 16 21:40:14 [FaxGetty] STATE CHANGE: LISTENING -> ANSWERING
Oct 16 21:40:18 [FaxGetty] ANSWER: FAX CONNECTION  DEVICE '/dev/ttyIAX'
Oct 16 21:41:03 [FaxGetty] <-- [5:ATH0\r]
Oct 16 21:41:03 [FaxGetty] --> [2:OK]
Oct 16 21:41:03 [FaxGetty] MODEM set DTR OFF
Oct 16 21:41:03 [FaxGetty] MODEM set baud rate: 0 baud (flow control unchanged)
Oct 16 21:41:04 [FaxGetty] MODEM set DTR OFF
Oct 16 21:41:04 [FaxGetty] MODEM set baud rate: 0 baud (flow control unchanged)
Oct 16 21:41:04 [FaxGetty] DELAY 75 ms
Oct 16 21:41:04 [FaxGetty] MODEM set DTR ON
Oct 16 21:41:04 [FaxGetty] DELAY 2600 ms
Oct 16 21:41:07 [FaxGetty] MODEM set baud rate: 19200 baud, input flow XON/XOFF, output flow XON/XOFF
Oct 16 21:41:07 [FaxGetty] DELAY 10 ms
Oct 16 21:41:07 [FaxGetty] MODEM flush i/o
Oct 16 21:41:07 [FaxGetty] <-- [4:ATZ\r]
Oct 16 21:41:07 [FaxGetty] --> [2:OK]
Oct 16 21:41:07 [FaxGetty] DELAY 3000 ms
Oct 16 21:41:10 [FaxGetty] MODEM flush i/o
Oct 16 21:41:10 [FaxGetty] <-- [10:AT+VCID=1\r]
Oct 16 21:41:10 [FaxGetty] --> [9:AT+VCID=1]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] <-- [7:ATS0=0\r]
Oct 16 21:41:10 [FaxGetty] --> [6:ATS0=0]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] <-- [5:ATE0\r]
Oct 16 21:41:10 [FaxGetty] --> [4:ATE0]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] <-- [5:ATV1\r]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] <-- [5:ATQ0\r]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] <-- [7:ATS8=2\r]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] <-- [8:ATS7=60\r]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] <-- [12:AT+FCLASS=?\r]
Oct 16 21:41:10 [FaxGetty] --> [7:0,1,1.0]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] MODEM Supports "Data"
Oct 16 21:41:10 [FaxGetty] MODEM Supports "Class 1"
Oct 16 21:41:10 [FaxGetty] MODEM Supports "Class 1.0"
Oct 16 21:41:10 [FaxGetty] <-- [12:AT+FCLASS=1\r]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] <-- [5:ATI3\r]
Oct 16 21:41:10 [FaxGetty] --> [19:www.soft-switch.org]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] MODEM: Mfr www.soft-switch.org
Oct 16 21:41:10 [FaxGetty] <-- [5:ATI0\r]
Oct 16 21:41:10 [FaxGetty] --> [7:spandsp]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] MODEM: Model spandsp
Oct 16 21:41:10 [FaxGetty] <-- [9:AT+FTM=?\r]
Oct 16 21:41:10 [FaxGetty] --> [39:24,48,72,73,74,96,97,98,121,122,145,146]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] MODEM Supports 3.85 line/mm
Oct 16 21:41:10 [FaxGetty] MODEM Supports 7.7 line/mm
Oct 16 21:41:10 [FaxGetty] MODEM Supports 15.4 line/mm
Oct 16 21:41:10 [FaxGetty] MODEM Supports R16 x 15.4 line/mm
Oct 16 21:41:10 [FaxGetty] MODEM Supports 200 x 100 dpi
Oct 16 21:41:10 [FaxGetty] MODEM Supports 200 x 200 dpi
Oct 16 21:41:10 [FaxGetty] MODEM Supports 200 x 400 dpi
Oct 16 21:41:10 [FaxGetty] MODEM Supports 300 x 300 dpi
Oct 16 21:41:10 [FaxGetty] MODEM Supports 2400 bit/s
Oct 16 21:41:10 [FaxGetty] MODEM Supports 4800 bit/s
Oct 16 21:41:10 [FaxGetty] MODEM Supports 7200 bit/s
Oct 16 21:41:10 [FaxGetty] MODEM Supports 9600 bit/s
Oct 16 21:41:10 [FaxGetty] MODEM Supports 12000 bit/s
Oct 16 21:41:10 [FaxGetty] MODEM Supports 14400 bit/s
Oct 16 21:41:10 [FaxGetty] MODEM Supports A4 page width (215 mm)
Oct 16 21:41:10 [FaxGetty] MODEM Supports B4 page width (255 mm)
Oct 16 21:41:10 [FaxGetty] MODEM Supports A3 page width (303 mm)
Oct 16 21:41:10 [FaxGetty] MODEM Supports A4 page length (297 mm)
Oct 16 21:41:10 [FaxGetty] MODEM Supports B4 page length (364 mm)
Oct 16 21:41:10 [FaxGetty] MODEM Supports unlimited page length
Oct 16 21:41:10 [FaxGetty] MODEM Supports 1-D MH
Oct 16 21:41:10 [FaxGetty] MODEM Supports 2-D MR
Oct 16 21:41:10 [FaxGetty] MODEM Supports 2-D MMR
Oct 16 21:41:10 [FaxGetty] MODEM Supports no ECM
Oct 16 21:41:10 [FaxGetty] MODEM Supports T.30 Annex A, 64-byte ECM
Oct 16 21:41:10 [FaxGetty] MODEM Supports T.30 Annex A, 256-byte ECM
Oct 16 21:41:10 [FaxGetty] MODEM Supports 0 ms/scanline
Oct 16 21:41:10 [FaxGetty] MODEM Supports 5 ms/scanline
Oct 16 21:41:10 [FaxGetty] MODEM Supports 10 ms, 5 ms/scanline
Oct 16 21:41:10 [FaxGetty] MODEM Supports 10 ms/scanline
Oct 16 21:41:10 [FaxGetty] MODEM Supports 20 ms, 10 ms/scanline
Oct 16 21:41:10 [FaxGetty] MODEM Supports 20 ms/scanline
Oct 16 21:41:10 [FaxGetty] MODEM Supports 40 ms, 20 ms/scanline
Oct 16 21:41:10 [FaxGetty] MODEM Supports 40 ms/scanline
Oct 16 21:41:10 [FaxGetty] MODEM WWW.SOFT-SWITCH.ORG spandsp/
Oct 16 21:41:10 [FaxGetty] <-- [5:ATM0\r]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] <-- [9:AT+FAR=1\r]
Oct 16 21:41:10 [FaxGetty] --> [2:OK]
Oct 16 21:41:10 [FaxGetty] STATE CHANGE: RECEIVING -> RUNNING (timeout 30)
Oct 16 21:44:03 [FaxGetty] STATE CHANGE: RUNNING -> LISTENING
Oct 16 21:44:03 [FaxGetty] --> [9:DATE=1016]
Oct 16 21:44:03 [FaxGetty] --> [9:TIME=2144]
Oct 16 21:44:03 [FaxGetty] --> [16:NAME=01934628711]
Oct 16 21:44:03 [FaxGetty] --> [16:NMBR=01934628711]
Oct 16 21:44:03 [FaxGetty] --> [11:ANID=<NONE>]
Oct 16 21:44:03 [FaxGetty] --> [12:NDID=4808098]
Oct 16 21:44:03 [FaxGetty] --> [4:RING]
Oct 16 21:44:03 [FaxGetty] ANSWER: Call ID 1 "01934628711"
Oct 16 21:44:03 [FaxGetty] ANSWER: Call ID 2 "01934628711"
Oct 16 21:44:03 [FaxGetty] ANSWER: Call ID 3 "<NONE>"
Oct 16 21:44:03 [FaxGetty] ANSWER: Call ID 4 "4808098"
Oct 16 21:44:03 [FaxGetty] STATE CHANGE: LISTENING -> ANSWERING
Oct 16 21:44:06 [FaxGetty] ANSWER: FAX CONNECTION  DEVICE '/dev/ttyIAX'
Oct 16 21:44:52 [FaxGetty] <-- [5:ATH0\r]
Oct 16 21:44:52 [FaxGetty] --> [2:OK]
Oct 16 21:44:52 [FaxGetty] MODEM set DTR OFF
Oct 16 21:44:52 [FaxGetty] MODEM set baud rate: 0 baud (flow control unchanged)
Oct 16 21:44:53 [FaxGetty] MODEM set DTR OFF
Oct 16 21:44:53 [FaxGetty] MODEM set baud rate: 0 baud (flow control unchanged)
Oct 16 21:44:53 [FaxGetty] DELAY 75 ms
Oct 16 21:44:53 [FaxGetty] MODEM set DTR ON
Oct 16 21:44:53 [FaxGetty] DELAY 2600 ms
Oct 16 21:44:56 [FaxGetty] MODEM set baud rate: 19200 baud, input flow XON/XOFF, output flow XON/XOFF
Oct 16 21:44:56 [FaxGetty] DELAY 10 ms
Oct 16 21:44:56 [FaxGetty] MODEM flush i/o
Oct 16 21:44:56 [FaxGetty] <-- [4:ATZ\r]
Oct 16 21:44:56 [FaxGetty] --> [2:OK]
Oct 16 21:44:56 [FaxGetty] DELAY 3000 ms
Oct 16 21:44:59 [FaxGetty] MODEM flush i/o
Oct 16 21:44:59 [FaxGetty] <-- [10:AT+VCID=1\r]
Oct 16 21:44:59 [FaxGetty] --> [9:AT+VCID=1]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] <-- [7:ATS0=0\r]
Oct 16 21:44:59 [FaxGetty] --> [6:ATS0=0]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] <-- [5:ATE0\r]
Oct 16 21:44:59 [FaxGetty] --> [4:ATE0]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] <-- [5:ATV1\r]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] <-- [5:ATQ0\r]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] <-- [7:ATS8=2\r]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] <-- [8:ATS7=60\r]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] <-- [12:AT+FCLASS=?\r]
Oct 16 21:44:59 [FaxGetty] --> [7:0,1,1.0]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] MODEM Supports "Data"
Oct 16 21:44:59 [FaxGetty] MODEM Supports "Class 1"
Oct 16 21:44:59 [FaxGetty] MODEM Supports "Class 1.0"
Oct 16 21:44:59 [FaxGetty] <-- [12:AT+FCLASS=1\r]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] <-- [5:ATI3\r]
Oct 16 21:44:59 [FaxGetty] --> [19:www.soft-switch.org]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] MODEM: Mfr www.soft-switch.org
Oct 16 21:44:59 [FaxGetty] <-- [5:ATI0\r]
Oct 16 21:44:59 [FaxGetty] --> [7:spandsp]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] MODEM: Model spandsp
Oct 16 21:44:59 [FaxGetty] <-- [9:AT+FTM=?\r]
Oct 16 21:44:59 [FaxGetty] --> [39:24,48,72,73,74,96,97,98,121,122,145,146]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] MODEM Supports 3.85 line/mm
Oct 16 21:44:59 [FaxGetty] MODEM Supports 7.7 line/mm
Oct 16 21:44:59 [FaxGetty] MODEM Supports 15.4 line/mm
Oct 16 21:44:59 [FaxGetty] MODEM Supports R16 x 15.4 line/mm
Oct 16 21:44:59 [FaxGetty] MODEM Supports 200 x 100 dpi
Oct 16 21:44:59 [FaxGetty] MODEM Supports 200 x 200 dpi
Oct 16 21:44:59 [FaxGetty] MODEM Supports 200 x 400 dpi
Oct 16 21:44:59 [FaxGetty] MODEM Supports 300 x 300 dpi
Oct 16 21:44:59 [FaxGetty] MODEM Supports 2400 bit/s
Oct 16 21:44:59 [FaxGetty] MODEM Supports 4800 bit/s
Oct 16 21:44:59 [FaxGetty] MODEM Supports 7200 bit/s
Oct 16 21:44:59 [FaxGetty] MODEM Supports 9600 bit/s
Oct 16 21:44:59 [FaxGetty] MODEM Supports 12000 bit/s
Oct 16 21:44:59 [FaxGetty] MODEM Supports 14400 bit/s
Oct 16 21:44:59 [FaxGetty] MODEM Supports A4 page width (215 mm)
Oct 16 21:44:59 [FaxGetty] MODEM Supports B4 page width (255 mm)
Oct 16 21:44:59 [FaxGetty] MODEM Supports A3 page width (303 mm)
Oct 16 21:44:59 [FaxGetty] MODEM Supports A4 page length (297 mm)
Oct 16 21:44:59 [FaxGetty] MODEM Supports B4 page length (364 mm)
Oct 16 21:44:59 [FaxGetty] MODEM Supports unlimited page length
Oct 16 21:44:59 [FaxGetty] MODEM Supports 1-D MH
Oct 16 21:44:59 [FaxGetty] MODEM Supports 2-D MR
Oct 16 21:44:59 [FaxGetty] MODEM Supports 2-D MMR
Oct 16 21:44:59 [FaxGetty] MODEM Supports no ECM
Oct 16 21:44:59 [FaxGetty] MODEM Supports T.30 Annex A, 64-byte ECM
Oct 16 21:44:59 [FaxGetty] MODEM Supports T.30 Annex A, 256-byte ECM
Oct 16 21:44:59 [FaxGetty] MODEM Supports 0 ms/scanline
Oct 16 21:44:59 [FaxGetty] MODEM Supports 5 ms/scanline
Oct 16 21:44:59 [FaxGetty] MODEM Supports 10 ms, 5 ms/scanline
Oct 16 21:44:59 [FaxGetty] MODEM Supports 10 ms/scanline
Oct 16 21:44:59 [FaxGetty] MODEM Supports 20 ms, 10 ms/scanline
Oct 16 21:44:59 [FaxGetty] MODEM Supports 20 ms/scanline
Oct 16 21:44:59 [FaxGetty] MODEM Supports 40 ms, 20 ms/scanline
Oct 16 21:44:59 [FaxGetty] MODEM Supports 40 ms/scanline
Oct 16 21:44:59 [FaxGetty] MODEM WWW.SOFT-SWITCH.ORG spandsp/
Oct 16 21:44:59 [FaxGetty] <-- [5:ATM0\r]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] <-- [9:AT+FAR=1\r]
Oct 16 21:44:59 [FaxGetty] --> [2:OK]
Oct 16 21:44:59 [FaxGetty] STATE CHANGE: RECEIVING -> RUNNING (timeout 30)
Oct 16 21:45:01 [cron] (root) CMD (/usr/bin/php /var/www/localhost/htdocs/cacti/poller.php > /dev/null 2>&1)
Oct 16 21:45:02 [snmpd] Connection from UDP: [127.0.0.1]:-32748_
                - Last output repeated 3 times -
Oct 16 21:50:01 [cron] (root) CMD (/usr/bin/php /var/www/localhost/htdocs/cacti/poller.php > /dev/null 2>&1)
Oct 16 21:50:01 [cron] (root) CMD (test -x /usr/sbin/run-crons && /usr/sbin/run-crons )
Oct 16 21:50:02 [snmpd] Connection from UDP: [127.0.0.1]:-32748_
                - Last output repeated 3 times -
Oct 16 21:55:01 [cron] (root) CMD (/usr/bin/php /var/www/localhost/htdocs/cacti/poller.php > /dev/null 2>&1)
Oct 16 21:55:03 [snmpd] Connection from UDP: [127.0.0.1]:-32748_
                - Last output repeated 3 times -

Looks like even though the call gets passed to iaxmodem no sound is coming through. I've rang the fax number and could hear the the fax sounds (but were abit faint).

ttyIAX etc:

device        /dev/ttyIAX
owner        uucp:uucp
mode        660
port        4570
refresh        300
server        127.0.0.1
peername    iaxmodem
secret        secret
cidname        Panther Software
cidnumber    08450048984
codec          slinear
record

config.ttyIAX in /var/spool/fax

CountryCode:    44
AreaCode:        845
FAXNumber:        +44.845.0048984
LongDistancePrefix:    0
InternationalPrefix:    0
DialStringRules:    etc/dialrules
ServerTracing:        0xFFF
SessionTracing:        0xFFF
RecvFileMode:        0600
LogFileMode:        0600
DeviceMode:        0600
RingsBeforeAnswer:    1
SpeakerVolume:        off
GettyArgs:        "-h %l dx_%s"
LocalIdentifier:    "IAXmodem"
TagLineFont:        etc/lutRS18.pcf
TagLineFormat:        "From %%l|%c|Page %%P of %%T"
MaxRecvPages:        200
#
#
# Modem-related stuff: should reflect modem command interface
# and hardware connection/cabling (e.g. flow control).
#
ModemType:        Class1        # use this to supply a hint

#
# Enabling this will use the hfaxd-protocol to set Caller*ID
#
#ModemSetOriginCmd:    AT+VSID="%s","%d"

#
# If "glare" during initialization becomes a problem then take
# the modem off-hook during initialization, and then place it
# back on-hook when done.
#
#ModemResetCmds:    "ATH1\nAT+VCID=1"    # enables CallID display
#ModemReadyCmds:    ATH0

Class1AdaptRecvCmd:    AT+FAR=1
Class1TMConnectDelay:    400        # counteract quick CONNECT response

Class1RMQueryCmd:    "!24,48,72,96"    # enable this to disable V.17

#
# You'll likely want Caller*ID display (also displays DID) enabled.
#
ModemResetCmds:        AT+VCID=1    # enables CallID display

#
# If you are "missing" Caller*ID data on some calls (but not all)
# and if you do not have adequate glare protection you may want to
# not answer based on RINGs, but rather enable the CallIDAnswerLength
# for NDID, disable AT+VCID=1 and do this:
#
#RingsBeforeAnswer: 0
#ModemRingResponse: AT+VRID=1

CallIDPattern:          "NMBR="
CallIDPattern:          "NAME="
CallIDPattern:        "ANID="
CallIDPattern:          "NDID="
#CallIDAnswerLength:    4
# Uncomment these if you really want them, but you probably don't.
#CallIDPattern:          "DATE="
#CallIDPattern:          "TIME="

[Lee Howard]

Here's your mistake:

Asterisk extensions.conf:
exten => 4808098,1,Answer()
exten => 4808098,2,Dial(IAX2/iaxmodem/${EXTEN})

Why are you Answer()ing the call in the dialplan?  Don't.  Let iaxmodem answer the call.

[McNaz7]

Hi Faxguy.

First thank you for taking the time to look through my logs and your suggestion.

I removed the Answer() and replaced:

exten => 4808098,1,Dial(IAX2/iaxmodem/${EXTEN})

iax does answer the call:

    -- Loaded provisioning template 'default'
    -- Executing Dial("SIP/4808098-0814ac88", "IAX2/iaxmodem/8689764") in new stack
    -- Called iaxmodem/8689764
    -- Call accepted by 127.0.0.1 (format ulaw)
    -- Format for call is ulaw
    -- IAX2/iaxmodem-2 is ringing
    -- IAX2/iaxmodem-2 answered SIP/4808098-0814ac88

I still suspect that no sound is getting through as the iaxmodem window still displays the jitter messages then disconects with an error in around 60 seconds:

[2006-10-20 13:26:04] IAX2 jitter - last_ts: 17380, ts: 17420
[2006-10-20 13:26:06] Adjusting skew to -350.
[2006-10-20 13:26:10] IAX2 jitter - last_ts: 23620, ts: 23660
[2006-10-20 13:26:12] IAX2 jitter - last_ts: 25640, ts: 25680
[2006-10-20 13:26:13] IAX2 jitter - last_ts: 27080, ts: 27120
[2006-10-20 13:26:16] IAX2 jitter - last_ts: 29700, ts: 29740
[2006-10-20 13:26:18] IAX2 jitter - last_ts: 31480, ts: 31520
[2006-10-20 13:26:18] IAX2 jitter - last_ts: 31620, ts: 31660
[2006-10-20 13:26:26] Adjusting skew to -400.
[2006-10-20 13:26:27] IAX2 jitter - last_ts: 40860, ts: 40900
[2006-10-20 13:26:30] IAX2 jitter - last_ts: 43360, ts: 43400
[2006-10-20 13:26:32] IAX2 jitter - last_ts: 46120, ts: 46160

Again, your help is incredibly appreciated.

Cheers.

[McNaz7]

Hmmm... just spotted this in the debug log and I wonder if it is the cause:

Oct 20 13:17:56 DEBUG[7285] chan_iax2.c: Ooh, voice format changed to 64
Oct 20 13:17:56 DEBUG[7283] chan_sip.c: Stopping retransmission on '1a0e45f3371746d10344d71c2743892c@gw02.uk.sipgate.net' of Response 102: Match Found
Oct 20 13:17:57 DEBUG[7283] chan_sip.c: Auto destroying call '2d2b698d5a97cd3a6c43199b0547387f@192.168.0.10'
Oct 20 13:18:45 DEBUG[7285] chan_iax2.c: Immediately destroying 2, having received hangup
Oct 20 13:18:45 DEBUG[7414] channel.c: Didn't get a frame from channel: IAX2/iaxmodem-2
Oct 20 13:18:45 DEBUG[7414] channel.c: Bridge stops bridging channels SIP/4808098-0814ac88 and IAX2/iaxmodem-2
Oct 20 13:18:45 DEBUG[7414] chan_iax2.c: We're hanging up IAX2/iaxmodem-2 now...
Oct 20 13:18:45 DEBUG[7414] chan_iax2.c: Really destroying IAX2/iaxmodem-2 now...

I recall seeing on the digium mailing list that "Ooh, voice format changed" indicates that asterisk is unable to transcode from the presented codec to the required codec (ulaw in this case?). I wonder if this is the cause?

[Lee Howard]

Ooh, voice format changed to 64

64 is slinear

Anyway, I'm not sure what it is that you're doing wrong, but double check your codec support for the iaxmodem.  Check both your iaxmodem config and your Asterisk config.

[Dave West]

Was this problem ever resolved?

I am having the same problem.

Also I am wondering if IAXmodem is going to do what I want as I read somewhere that it does not do data.

I'm wanting to dail from a land line through a SIP provider into Asterisk then to IAXmodem and then to a standard getty so I can get to a shell. Thats not exactly true but if I can get that working then the rest is easy for me.

Thanks,
  Dave West

[Lee Howard]

IAXmodem doesn't currently support non-fax data communication.