[Logcheck-devel] Bug#270018: HylaFax send logs

[Ross Boylan]

On Sat, Oct 02, 2004 at 09:59:57PM +0200, maks attems wrote:
> tags 270019 wontfix
> tags 270018 wontfix
> tags 270018 moreinfo
> thanks
> 
> i'm leaving these so called bugs open for 1 month from now,
> if until then no valid data could be gathered out of them.
> they will be definetly closed.
> 
> 

The wontfix tag is such a big incentive (that's irony) that I'm
sending in more info.  I just sent a fax, so finally have some logs
for that (sending one page from ps source, one from png):

syslog:
Oct  2 23:30:31 wheat HylaFAX[19946]: Filesystem has SysV-style file creation semantics.
Oct  2 23:30:31 wheat FaxQueuer[1001]: FIFO RECV "Sclient/19946:112"
Oct  2 23:30:31 wheat FaxQueuer[1001]: SUBMIT JOB 112
Oct  2 23:30:31 wheat FaxQueuer[1001]: JOB 112 (suspended dest  pri 127 tts 0:00 killtime 2:59:00): CREATE
Oct  2 23:30:31 wheat FaxQueuer[1001]: JOB 112 (ready dest +18054808413 pri 127 tts 0:00 killtime 2:59:00): READY
Oct  2 23:30:31 wheat FaxQueuer[1001]: FIFO SEND client/19946 msg "S*"
Oct  2 23:30:31 wheat FaxQueuer[1001]: JOB 112 (ready dest +18054808413 pri 127 tts 0:00 killtime 2:59:00): PROCESS
Oct  2 23:30:31 wheat FaxQueuer[1001]: JOB 112 (active dest +18054808413 pri 127 tts 0:00 killtime 2:59:00): ACTIVE
Oct  2 23:30:31 wheat FaxQueuer[1001]: JOB 112 (active dest +18054808413 pri 127 tts 0:00 killtime 2:59:00): PREPARE START
Oct  2 23:30:31 wheat FaxQueuer[19988]: JOB 112 (active dest +18054808413 pri 127 tts 0:00 killtime 2:59:00): CONVERT DOCUMENT: bin/ps2fax -o docq/doc353.ps;f1 -r 196 -w 1728 -l 280 -m 4294967295 -3 docq/doc353.ps.112
Oct  2 23:30:31 wheat FaxGetty[1488]: LOCKWAIT
Oct  2 23:30:31 wheat FaxGetty[1488]: STATE CHANGE: RUNNING -> LOCKWAIT (timeout 30)
Oct  2 23:30:34 wheat FaxQueuer[19988]: JOB 112 (active dest +18054808413 pri 127 tts 0:00 killtime 2:58:57): CONVERT DOCUMENT: bin/tiff2fax -o docq/doc354.tif;f1 -r 196 -w 1728 -l 280 -m 4294967295 -3 docq/doc354.tif.112
Oct  2 23:30:37 wheat FaxQueuer[1001]: JOB 112 (active dest +18054808413 pri 127 tts 0:00 killtime 2:58:54): PREPARE DONE
Oct  2 23:30:37 wheat FaxQueuer[1001]: JOB 112 (active dest +18054808413 pri 127 tts 0:00 killtime 2:58:54): CMD START /usr/sbin/faxsend -m ttyS0 sendq/q112 (PID 20010)
Oct  2 23:30:49 wheat FaxSend[20010]: MODEM U.S. ROBOTICS 56K VOICE INT V4.9.1 5601/
Oct  2 23:30:49 wheat FaxSend[20010]: STATE CHANGE: BASE -> RUNNING (timeout 30)
Oct  2 23:30:49 wheat FaxSend[20010]: SEND FAX: JOB 112 DEST 1 805 480-8413 COMMID 000000152 DEVICE '/dev/ttyS0'
Oct  2 23:30:49 wheat FaxQueuer[1001]: FIFO RECV "*112:c"
Oct  2 23:31:02 wheat FaxQueuer[1001]: FIFO RECV "*112:C"
Oct  2 23:31:23 wheat FaxSend[20010]: SEND FAX: JOB 112 SENT in 0:19
Oct  2 23:31:23 wheat FaxQueuer[1001]: FIFO RECV "*112:d13,1,21e029,000000152,"docq/doc353.ps;f1""
Oct  2 23:31:23 wheat FaxQueuer[1001]: FIFO RECV "*112:D13,1,21e029,000000152,"docq/doc353.ps;f1""
Oct  2 23:31:39 wheat FaxSend[20010]: SEND FAX: JOB 112 SENT in 0:16
Oct  2 23:31:39 wheat FaxQueuer[1001]: FIFO RECV "*112:d10,2,21e029,000000152,"docq/doc354.tif;f1""
Oct  2 23:31:39 wheat FaxQueuer[1001]: FIFO RECV "*112:D10,2,21e029,000000152,"docq/doc354.tif;f1""
Oct  2 23:31:41 wheat FaxQueuer[1001]: JOB 112 (active dest +18054808413 pri 127 tts 0:00 killtime 2:57:50): CMD DONE: exit status 0x200
Oct  2 23:31:41 wheat FaxQueuer[1001]: NOTIFY: bin/notify "doneq/q112" "done" "1:04"
Oct  2 23:31:41 wheat FaxQueuer[1001]: JOB 112 (done dest +18054808413 pri 127 tts 0:00 killtime 2:57:50): SEND DONE: 1:04
Oct  2 23:31:41 wheat FaxQueuer[1001]: JOB 112 (done dest +18054808413 pri 127 tts 0:00 killtime 2:57:50): DEAD
Oct  2 23:31:41 wheat FaxQueuer[1001]: JOB 112 (done dest +18054808413 pri 127 tts 0:00 killtime 2:57:50): DELETE
Oct  2 23:31:41 wheat FaxQueuer[1001]: NOTIFY exit status: 0 (20028)
Oct  2 23:32:11 wheat FaxGetty[1488]: MODEM U.S. ROBOTICS 56K VOICE INT V4.9.1 5601/
Oct  2 23:32:11 wheat FaxGetty[1488]: STATE CHANGE: LOCKWAIT -> RUNNING (timeout 30)
Oct  2 23:32:11 wheat FaxQueuer[1001]: FIFO RECV "+ttyS0:RPcaf9c77f:ff"
Oct  2 23:32:11 wheat FaxQueuer[1001]: MODEM ttyS0: READY, capabilities Pcaf9c77f:ff


daemon.log, messages:
All entries appear in syslog.

By the way, some of the previously reported messages are not just at daemon start, but
whenever the modem goes on or off.  For example:
Oct  2 20:40:26 wheat FaxGetty[1488]: MODEM U.S. ROBOTICS 56K VOICE INT V4.9.1 5601/
Oct  2 20:40:26 wheat FaxGetty[1488]: STATE CHANGE: LOCKWAIT -> RUNNING (timeout 30)
Oct  2 20:40:26 wheat FaxQueuer[1001]: FIFO RECV "+ttyS0:RPcaf9c77f:ff"
Oct  2 20:40:26 wheat FaxQueuer[1001]: MODEM ttyS0: READY, capabilities Pcaf9c77f:ff

Oct  2 21:04:41 wheat FaxGetty[1488]: ANSWER: Can not lock modem device
Oct  2 21:04:41 wheat FaxGetty[1488]: STATE CHANGE: RUNNING -> LOCKWAIT (timeout 30)
Oct  2 21:04:41 wheat FaxQueuer[1001]: FIFO RECV "+ttyS0:U"
Oct  2 21:04:41 wheat FaxQueuer[1001]: MODEM ttyS0: BUSY