Re: flexfax: Any additions to the TODO List?

[Neil Buckingham <neil@vic.design.telstra.com.au>]

> Neil Buckingham wrote:
>     
>     	Here is another item for the todo list.
>     
>     	hylafax v4.0pl1 IXO/TAP pager protocol not conforming to Australian ( 
>     Probably international also ) standard.
>     	When sending a pager message to a invalid pager number, hylafax just drops 
>     the line and does not go through the correct disconnect procedures. It does do 
>     the correct disconnect procedures when sending to a valid pager number.
>     
>     	e.g.
>     	Here is a trace from a valid pager number
> 
> 	...
> 
> Please provide a log for a invalid pager number with the trace
> of low level IXO/TAP protocol enabled. Thanks in advance.
> 
> 	matthias


	Sorry, I had a trace from a invalid pager but it seems to have been chopped 
off. Anyway here is another one.


Oct 10 07:36:25.95: [19240]: SESSION BEGIN 00003295 123456789
Oct 10 07:36:25.95: [19240]: SEND PAGE: JOB 2663 DEST 123456789 COMMID 00003295
Oct 10 07:36:25.95: [19240]: MODEM set DTR OFF
Oct 10 07:36:25.95: [19240]: DELAY 2600 ms
Oct 10 07:36:28.55: [19240]: MODEM set DTR ON
Oct 10 07:36:28.55: [19240]: MODEM set baud rate: 19200 baud, input flow 
XON/XOF
F, output flow XON/XOFF
Oct 10 07:36:28.55: [19240]: MODEM flush i/o
Oct 10 07:36:28.55: [19240]: <-- [15:ATE0V1Q0S0=0H0\r]
Oct 10 07:36:29.72: [19240]: --> [2:OK]
Oct 10 07:36:29.72: [19240]: <-- [21:ATS8=2S7=60&K4&D3&C1\r]
Oct 10 07:36:29.84: [19240]: --> [2:OK]
Oct 10 07:36:29.84: [19240]: <-- [5:ATM0\r]
Oct 10 07:36:29.94: [19240]: --> [2:OK]
Oct 10 07:36:29.94: [19240]: MODEM set parity: 7 bits, even parity
Oct 10 07:36:29.94: [19240]: MODEM input buffering enabled
Oct 10 07:36:29.96: [19240]: <-- [12:AT+FCLASS=0\r]
Oct 10 07:36:30.16: [19240]: --> [2:OK]
Oct 10 07:36:30.16: [19240]: DIAL 95104451
Oct 10 07:36:30.16: [19240]: <-- [13:ATDT1234567\r]
Oct 10 07:36:40.71: [19240]: --> [7:RINGING]
Oct 10 07:36:54.14: [19240]: --> [12:CONNECT 2400]
Oct 10 07:36:54.14: [19240]: EXPECT ID (paging central identification)
Oct 10 07:36:54.14: [19240]: <-- <1:\015>
Oct 10 07:36:56.15: [19240]: MODEM TIMEOUT: reading line from modem
Oct 10 07:36:56.15: [19240]: <-- <1:\015>
Oct 10 07:36:56.49: [19240]: --> <3:ID=>
Oct 10 07:36:56.49: [19240]: RECV ID ("ID=")
Oct 10 07:36:56.49: [19240]: SEND device identification/login request
Oct 10 07:36:56.49: [19240]: <-- <11:\033PG1pwpw\015>
Oct 10 07:36:57.23: [19240]: --> <39: Log-on accepted                       >
Oct 10 07:36:57.23: [19240]: RECV:  Log-on accepted
Oct 10 07:36:57.23: [19240]: --> <1:\006>
Oct 10 07:36:57.23: [19240]: RECV ACK (login successful)
Oct 10 07:36:57.23: [19240]: --> <39:Ready for messages                     >
Oct 10 07:36:57.23: [19240]: RECV: Ready for messages
Oct 10 07:36:57.23: [19240]: --> <3:\033[p>
Oct 10 07:36:57.23: [19240]: RECV go-ahead (prologue done)
Oct 10 07:36:57.23: [19240]: SEND message block
Oct 10 07:36:57.23: [19240]: <-- <22:\0021234\015hello test\015\0034==\015>
Oct 10 07:36:57.83: [19240]: --> <39:Pager Id 1234    is not valid          >
Oct 10 07:36:57.83: [19240]: RECV: Pager Id 1234    is not valid
Oct 10 07:36:57.83: [19240]: --> <1:\036>
Oct 10 07:36:57.83: [19240]: RECV RS (message block rejected; skip to next)
Oct 10 07:36:57.83: [19240]: <-- [5:ATH0\r]
Oct 10 07:37:02.84: [19240]: MODEM TIMEOUT: reading line from modem
Oct 10 07:37:02.84: [19240]: MODEM <Timeout>
Oct 10 07:37:02.84: [19240]: MODEM input buffering enabled
Oct 10 07:37:02.86: [19240]: MODEM set DTR OFF
Oct 10 07:37:02.86: [19240]: SESSION END


And here is a valid address

Oct 10 07:41:28.02: [19282]: SESSION BEGIN 00003296 123456789
Oct 10 07:41:28.02: [19282]: SEND PAGE: JOB 2664 DEST 123456789 COMMID 00003296
Oct 10 07:41:28.02: [19282]: MODEM set DTR OFF
Oct 10 07:41:28.02: [19282]: DELAY 2600 ms
Oct 10 07:41:30.61: [19282]: MODEM set DTR ON
Oct 10 07:41:30.61: [19282]: MODEM set baud rate: 19200 baud, input flow 
XON/XOF
F, output flow XON/XOFF
Oct 10 07:41:30.61: [19282]: MODEM flush i/o
Oct 10 07:41:30.61: [19282]: <-- [15:ATE0V1Q0S0=0H0\r]
Oct 10 07:41:31.78: [19282]: --> [2:OK]
Oct 10 07:41:31.78: [19282]: <-- [21:ATS8=2S7=60&K4&D3&C1\r]
Oct 10 07:41:31.90: [19282]: --> [2:OK]
Oct 10 07:41:31.90: [19282]: <-- [5:ATM0\r]
Oct 10 07:41:32.01: [19282]: --> [2:OK]
Oct 10 07:41:32.01: [19282]: MODEM set parity: 7 bits, even parity
Oct 10 07:41:32.01: [19282]: MODEM input buffering enabled
Oct 10 07:41:32.02: [19282]: <-- [12:AT+FCLASS=0\r]
Oct 10 07:41:32.22: [19282]: --> [2:OK]
Oct 10 07:41:32.22: [19282]: DIAL 95104451
Oct 10 07:41:32.22: [19282]: <-- [13:ATDT1234567\r]
Oct 10 07:41:42.79: [19282]: --> [7:RINGING]
Oct 10 07:41:56.21: [19282]: --> [12:CONNECT 2400]
Oct 10 07:41:56.21: [19282]: EXPECT ID (paging central identification)
Oct 10 07:41:56.21: [19282]: <-- <1:\015>
Oct 10 07:41:58.22: [19282]: MODEM TIMEOUT: reading line from modem
Oct 10 07:41:58.22: [19282]: <-- <1:\015>
Oct 10 07:41:58.57: [19282]: --> <3:ID=>
Oct 10 07:41:58.57: [19282]: RECV ID ("ID=")
Oct 10 07:41:58.57: [19282]: SEND device identification/login request
Oct 10 07:41:58.57: [19282]: <-- <11:\033PG1pwpw\015>
Oct 10 07:41:59.32: [19282]: --> <39: Log-on accepted                       >
Oct 10 07:41:59.32: [19282]: RECV:  Log-on accepted
Oct 10 07:41:59.32: [19282]: --> <1:\006>
Oct 10 07:41:59.32: [19282]: RECV ACK (login successful)
Oct 10 07:41:59.32: [19282]: --> <39:Ready for messages                     >
Oct 10 07:41:59.32: [19282]: RECV: Ready for messages
Oct 10 07:41:59.32: [19282]: --> <3:\033[p>
Oct 10 07:41:59.32: [19282]: RECV go-ahead (prologue done)
Oct 10 07:41:59.32: [19282]: SEND message block
Oct 10 07:41:59.32: [19282]: <-- <25:\0026388040\015hello test\015\003580\015>
Oct 10 07:41:59.96: [19282]: --> <39:Send next message                      >
Oct 10 07:41:59.96: [19282]: RECV: Send next message
Oct 10 07:41:59.96: [19282]: --> <1:\006>
Oct 10 07:41:59.96: [19282]: RECV ACK (message block accepted)
Oct 10 07:41:59.96: [19282]: <-- <2:\004\015>
Oct 10 07:42:00.48: [19282]: --> <39:Thank you for calling                  >
Oct 10 07:42:00.48: [19282]: RECV: Thank you for calling
Oct 10 07:42:00.48: [19282]: --> <2:\033\004>
Oct 10 07:42:00.48: [19282]: RECV EOT (disconnect)
Oct 10 07:42:00.48: [19282]: SEND PAGE: FROM root@brainiac.vic.design.telecom.c
o
m.au TO 95104451 (sent in 0:28)
Oct 10 07:42:00.48: [19282]: <-- [5:ATH0\r]




This problem causes it to fail the test that Telstra does ony any pager 
software connect to their system, and so it cannot be officially used.



While I am at it one more wish.
	Not sure how to explain this. But hylafax queues jobs for the same phone 
number and will only send one at a time. This make sense for a fax job. 
However pager jobs are all sent to the same phone number but otherwise are 
unreleated. hylafax should be able to send pager jobs in parallel. i.e. if you 
have 10 phone lines and 10 pager jobs in the queue then send the jobs in 
parallel using all the modems. As you can probably tell we are mainly using 
hylafax as a paging service. And so the way it is at the moment there is no 
point in having more than one modem line as it won't send pager jobs in 
parallel.



Thanks

Neil Buckingham.




-- 
----------------------------- /-_|\ -----------------------------------
Neil Buckingham              /     \         5th Floor 360 Elizabeth St
IBM GSA                      \_,-._/         Melbourne Australia 3000
Network Systems                   v          Phone +613 9203 7455
EMail n.buckingham@vic.design.telecom.com.au Fax +613 9663 8229
PGP Key fingerprint =  74 F2 64 A6 1C E0 48 8D  85 3C A4 A0 86 0C 85 0E 
-----------------------------------------------------------------------
"A coward is a frightened man who's scared to be brave. But a brave
man is only a coward who isn't scared to be frightened."
-- Maxwell Smart. Get Smart.