 HylaFAX The world's
most advanced open source fax server
|
[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]
Re: [hylafax-users] check modem power
- To: "Jay R. Ashworth" <jra@baylink.com>
- Subject: Re: [hylafax-users] check modem power
- From: David Coles <davidc@southern.com>
- Cc: hylafax-users <hylafax-users@hylafax.org>
- Date: Thu, 27 Jul 2000 15:37:47 +0100
On Tue, 25 Jul 2000, Jay R. Ashworth wrote:
> Which class are you running the modem in? Does it do this on more
> than one port of the terminal server? Post a commlog for us, and
> let's take a closer look.
It's a Class 2 modem. Yes it does it on more than one port of the terminal
server. It's a bit erratic though, 10 faxes may go OK, then 10 fail. Sigh.
I've included a log of a failed call, together with a par trace of the
process. I'm concerned by:
MODEM setInputBuffer::ioctl(SIOC_ITIMER): Invalid argument
Perhaps I should just kill this call in the source?
> Oh, and how, exactly, does that terminal driver work? (read: do you
> know about modemu, and are you willing to try it? :-)
The terminal driver works by multiplexing all 16 serial prots over one TCP
connection. You just get standard /dev/ttyXXX devices which are
ultimately serviced by a userland process. I know timing can be critical
in the T.30 stuff, but won't the class 2 modem handle all of that
internally ?
And no, what is modemu? If it fixes it I'll give it a go ;)
--
David Coles, System Administrator, Southern Studios davidc@southern.com
c00187158:
Jul 26 16:22:32.96: [38373]: SESSION BEGIN 00187158 442088880423
Jul 26 16:22:32.98: [38373]: SEND FAX: JOB 22740 DEST 88880423 COMMID 00187158
Jul 26 16:22:32.98: [38373]: MODEM set DTR OFF
Jul 26 16:22:32.99: [38373]: DELAY 2600 ms
Jul 26 16:22:35.60: [38373]: MODEM set DTR ON
Jul 26 16:22:35.83: [38373]: MODEM set baud rate: 38400 baud, input flow RTS/CTS, output flow RTS/CTS
Jul 26 16:22:35.84: [38373]: MODEM flush i/o
Jul 26 16:22:35.84: [38373]: <-- [15:ATE0V1Q0S0=0H0\r]
Jul 26 16:22:40.85: [38373]: MODEM TIMEOUT: reading line from modem
Jul 26 16:22:40.85: [38373]: MODEM <Timeout>
Jul 26 16:22:40.85: [38373]: <-- [5:ATM0\r]
Jul 26 16:22:40.93: [38373]: --> [4:ATM0]
Jul 26 16:22:40.93: [38373]: --> [2:OK]
Jul 26 16:22:40.93: [38373]: STATE CHANGE: RUNNING -> SENDING
Jul 26 16:22:40.93: [38373]: MODEM input buffering enabled
Jul 26 16:22:40.93: [38373]: MODEM setInputBuffer::ioctl(SIOC_ITIMER): Invalid argument
Jul 26 16:22:40.95: [38373]: <-- [12:AT+FCLASS=2\r]
Jul 26 16:22:41.07: [38373]: --> [11:AT+FCLASS=2]
Jul 26 16:22:41.07: [38373]: --> [2:OK]
Jul 26 16:22:41.07: [38373]: <-- [10:AT+FTBC=0\r]
Jul 26 16:22:41.19: [38373]: --> [9:AT+FTBC=0]
Jul 26 16:22:41.19: [38373]: --> [2:OK]
Jul 26 16:22:41.19: [38373]: <-- [10:AT+FBOR=0\r]
Jul 26 16:22:41.31: [38373]: --> [9:AT+FBOR=0]
Jul 26 16:22:41.31: [38373]: --> [2:OK]
Jul 26 16:22:41.31: [38373]: <-- [13:AT+FPHCTO=30\r]
Jul 26 16:22:41.42: [38373]: --> [12:AT+FPHCTO=30]
Jul 26 16:22:41.42: [38373]: --> [2:OK]
Jul 26 16:22:41.42: [38373]: <-- [10:AT+FBUG=1\r]
Jul 26 16:22:41.54: [38373]: --> [9:AT+FBUG=1]
Jul 26 16:22:41.54: [38373]: --> [2:OK]
Jul 26 16:22:41.54: [38373]: <-- [24:AT+FDCC=1,5,2,2,0,0,0,0\r]
Jul 26 16:22:41.66: [38373]: --> [23:AT+FDCC=1,5,2,2,0,0,0,0]
Jul 26 16:22:41.66: [38373]: --> [2:OK]
Jul 26 16:22:41.66: [38373]: <-- [24:AT+FLID="+442088896166"\r]
Jul 26 16:22:41.77: [38373]: --> [23:AT+FLID="+442088896166"]
Jul 26 16:22:41.77: [38373]: --> [2:OK]
Jul 26 16:22:41.78: [38373]: DIAL 9,88880423
Jul 26 16:22:41.78: [38373]: <-- [16:ATDT9,88880423@\r]
Jul 26 16:23:41.01: [38373]: --> [15:ATDT9,88880423@]
Jul 26 16:23:41.01: [38373]: SEND FAILED: Unknown problem (check modem power)
Jul 26 16:23:41.01: [38373]: <-- [5:ATH0\r]
Jul 26 16:23:41.01: [38373]: --> [9:NO ANSWER]
Jul 26 16:23:41.01: [38373]: MODEM No answer or ring back
Jul 26 16:23:41.02: [38373]: MODEM set DTR OFF
Jul 26 16:23:41.06: [38373]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jul 26 16:23:41.06: [38373]: SESSION END
par trace:
0mS : received signal SIGUSR1 (handler 0x100029d8)
0mS : END-pause() errno = 4 (Interrupted function call)
0mS : sigreturn(0x7fff2a90) OK
0mS : execve("/usr/southern/sbin/faxsend", 0x7fff2f0c, 0x7fff2f24) errno = 2 (No such file or directory)
1mS : execve("/usr/local/sbin/faxsend", 0x7fff2f0c, 0x7fff2f24)
9mS : END-execve() OK
10mS : open("/lib/rld", O_RDONLY, 04) = 4
11mS : read(4, <7f 45 4c 46 01 02 01 00 00 00 00 00 00 00 00 00>..., 512) = 512
11mS : elfmap(4, 0x7fff2d08, 2) = 0xfb60000
12mS : close(4) OK
13mS : getpagesize() = 4096
15mS : open("/dev/zero", O_RDONLY, 020000) = 4
16mS : mmap(0xfbda000, 24576, PROT_WRITE|PROT_READ, MAP_PRIVATE, 4, 0) = 0xfbda000
16mS : close(4) OK
18mS : syssgi(0x5c, 0, 0xfbd8524, 0xfbe0000, 0xfbda000, 0x726c643a) = 0
23mS : open("/usr/local/sbin/libfaxserver.so", O_RDONLY, 05) = 4
24mS : read(4, <7f 45 4c 46 01 02 01 00 00 00 00 00 00 00 00 00>..., 512) = 512
24mS : elfmap(4, 0x7fff0f50, 2) = 0x5ff00000
25mS : close(4) OK
26mS : open("/usr/local/sbin/libfaxutil.so", O_RDONLY, 05) = 4
27mS : read(4, <7f 45 4c 46 01 02 01 00 00 00 00 00 00 00 00 00>..., 512) = 512
27mS : elfmap(4, 0x7fff0f50, 2) = 0x8970000
28mS : close(4) OK
29mS : open("/usr/local/sbin/libtiff.so", O_RDONLY, 05) errno = 2 (No such file or directory)
30mS : open("/usr/local/lib/libtiff.so", O_RDONLY, 05) = 4
30mS : read(4, <7f 45 4c 46 01 02 01 00 00 00 00 00 00 00 00 00>..., 512) = 512
31mS : elfmap(4, 0x7fff0f00, 2) = 0x95a0000
31mS : close(4) OK
33mS : open("/usr/local/sbin/libz.so", O_RDONLY, 05) = 4
33mS : read(4, <7f 45 4c 46 01 02 01 00 00 00 00 00 00 00 00 00>..., 512) = 512
34mS : elfmap(4, 0x7fff0f50, 2) = 0x9580000
34mS : close(4) OK
35mS : open("/usr/local/sbin/libm.so", O_RDONLY, 05) errno = 2 (No such file or directory)
36mS : open("/usr/local/lib/libm.so", O_RDONLY, 05) errno = 2 (No such file or directory)
36mS : open("/usr/lib/libm.so", O_RDONLY, 05) = 4
37mS : read(4, <7f 45 4c 46 01 02 01 00 00 00 00 00 00 00 00 00>..., 512) = 512
37mS : elfmap(4, 0x7fff0f00, 2) = 0xf870000
38mS : close(4) OK
39mS : open("/usr/local/sbin/libC.so", O_RDONLY, 05) errno = 2 (No such file or directory)
39mS : open("/usr/local/lib/libC.so", O_RDONLY, 05) errno = 2 (No such file or directory)
40mS : open("/usr/lib/libC.so", O_RDONLY, 05) = 4
40mS : read(4, <7f 45 4c 46 01 02 01 00 00 00 00 00 00 00 00 00>..., 512) = 512
41mS : elfmap(4, 0x7fff0f00, 2) = 0xf890000
41mS : close(4) OK
42mS : open("/usr/local/sbin/libc.so.1", O_RDONLY, 05) errno = 2 (No such file or directory)
43mS : open("/usr/local/lib/libc.so.1", O_RDONLY, 05) errno = 2 (No such file or directory)
120mS : syssgi(SGI_USE_FP_BCOPY, 0, 0x1dc, 0xffffffff, 0x400, 0xfb86ba8) = 0
121mS : open("/dev/zero", O_RDONLY, 010000) = 4
122mS : mmap(0xfbe0000, 32768, PROT_WRITE|PROT_READ, MAP_PRIVATE, 4, 0) = 0xfbe0000
122mS : close(4) OK
126mS : getpagesize() = 4096
126mS : brk(0x10007000) OK
131mS : syssgi(SGI_TOSSTSAVE) OK
156mS : brk(0x10008000) OK
160mS : sigaction(SIGPIPE, {flags=SA_RESETHAND|SA_NODEFER handler=SIG_IGN mask=[<none>]}, {flags=0 handler=SIG_DFL mask=[<none>]}) OK
160mS : sigaction(SIGTERM, {flags=SA_RESETHAND|SA_NODEFER handler=0x403480 mask=[<none>]}, {flags=0 handler=SIG_DFL mask=[<none>]}) OK
160mS : sigaction(SIGINT, {flags=SA_RESETHAND|SA_NODEFER handler=0x403480 mask=[<none>]}, {flags=0 handler=SIG_DFL mask=[<none>]}) OK
162mS : open("status/ttyfn00f", O_WRONLY|O_CREAT|O_TRUNC, 0666)
172mS : END-open() = 4
172mS : fchmod(4, 0644) OK
174mS : fcntl(4, F_SETBSDLKW, {type=F_WRLCK, whence=SEEK_SET, start=0, len=9223140503757783042}) OK
174mS : lseek(4, 0, SEEK_SET) = 0
175mS : fstat64(4, 0x7fff2200) OK
175mS : brk(0x1000c000) OK
176mS : ioctl(4, __OLD_TCGETA, 0x7fff21a8) errno = 25 (Inappropriate I/O control operation)
177mS : write(4, "Initializing server\n", 20) = 20
178mS : lseek(4, 0, SEEK_CUR) = 20
178mS : ftruncate(4, 20) OK
179mS : fcntl(4, F_SETBSDLKW, {type=F_UNLCK, whence=SEEK_SET, start=263471044, len=85899345940}) OK
179mS : umask(077) = 022
179mS : stat("etc/config.ttyfn00f", 0x7fff2c80) OK
181mS : open("etc/config.ttyfn00f", O_RDONLY, 0666) = 5
182mS : fstat64(5, 0x7fff26b0) OK
182mS : brk(0x1000d000) OK
182mS : ioctl(5, __OLD_TCGETA, 0x7fff2658) errno = 25 (Inappropriate I/O control operation)
183mS : read(5, "# $Id: mt-1432,v 1.23 1996/06/", 4096) = 2970
190mS : read(5, 0x1000b140, 4096) = 0
190mS : close(5) OK
193mS : open("etc/dialrules", O_RDONLY, 0666) = 5
194mS : fstat64(5, 0x7fff2598) OK
194mS : ioctl(5, __OLD_TCGETA, 0x7fff2540) errno = 25 (Inappropriate I/O control operation)
194mS : read(5, "! $Id: dialrules,v 1.7 1996/06", 4096) = 1938
198mS : brk(0x1000e000) OK
201mS : brk(0x1000f000) OK
204mS : brk(0x10010000) OK
213mS : brk(0x10012000) OK
214mS : read(5, 0x1000b528, 4096) = 0
214mS : close(5) OK
215mS : gethostname("homer.southern.net", 64) OK
216mS : stat("/etc/passwd", 0x7fff2b3c) OK
217mS : open("/var/ns/cache/passwd.byname", O_RDONLY, 0400) = 5
218mS : fstat(5, 0x7fff20e8) OK
218mS : mmap(0, 65536, PROT_READ, MAP_SHARED, 5, 0) = 0x4000000
219mS : close(5) OK
220mS : time() = 964624947
220mS : stat("/etc/shadow", 0x7fff2a08) OK
220mS : open("/var/ns/cache/shadow.byname", O_RDONLY, 0400) = 5
221mS : fstat(5, 0x7fff1fc8) OK
221mS : mmap(0, 65536, PROT_READ, MAP_SHARED, 5, 0) = 0x4010000
222mS : close(5) OK
222mS : time() = 964624947
222mS : open("/ns/.local/shadow.byname/uucp", O_RDONLY, 072)
245mS : END-open() = 5
245mS : read(5, 0x100100dd, 4051)
249mS : END-read(5, "uucp:x:10402::::::-1\n", 4051) = 21
249mS : close(5)
251mS : END-close() OK
253mS : getpid() = 38373, ppid=37546
254mS : getuid() = 0, euid=0
254mS : setreuid(-1, 0) OK
255mS : getpid() = 38373, ppid=37546
255mS : open("/var/spool/locks//TM.fax038373", O_RDWR|O_CREAT|O_EXCL, 0600) = 5
257mS : write(5, " 38373\n", 11) = 11
258mS : fchmod(5, 0444) OK
259mS : fchown(5, 3, 5) OK
259mS : close(5) OK
260mS : link("/var/spool/locks//TM.fax038373", "/var/spool/locks/LCK..ttyfn00f") OK
262mS : unlink("/var/spool/locks//TM.fax038373") OK
263mS : setreuid(-1, 0) OK
263mS : getuid() = 0, euid=0
263mS : setreuid(-1, 0) OK
263mS : open("/dev/ttyfn00f", O_RDWR|O_NDELAY|O_NOCTTY, 0) = 5
264mS : fcntl(5, F_GETFL) = 0x6
264mS : fcntl(5, F_SETFL, 0x2) OK
264mS : fstat(5, 0x7fff2ca0) OK
264mS : fchown(5, 3, 0) OK
265mS : fchmod(5, 0600) OK
265mS : setreuid(-1, 0) OK
268mS : ioctl(5, TIOCMBIC, 0x7fff29d4)
276mS : END-ioctl() OK
277mS : select(0, 0, 0, 0, {sec=2, usec=600000})
2885mS : END-select() = 0
2885mS : ioctl(5, TIOCMBIS, 0x7fff29d4)
2893mS : END-ioctl() OK
2893mS : close(5) OK
2893mS : getuid() = 0, euid=0
2893mS : setreuid(-1, 0) OK
2893mS : open("/dev/ttyfn00f", O_RDWR|O_NDELAY|O_NOCTTY, 0) = 5
2894mS : fcntl(5, F_GETFL) = 0x6
2894mS : fcntl(5, F_SETFL, 0x2) OK
2894mS : fstat(5, 0x7fff2948) OK
2894mS : fchown(5, 3, 0)
2897mS : END-fchown() OK
2897mS : fchmod(5, 0600) OK
2897mS : setreuid(-1, 0) OK
2898mS : ioctl(5, TCGETS, 0x7fff29b0)
2904mS : END-ioctl() OK
2905mS : getuid() = 0, euid=0
2905mS : setreuid(-1, 0) OK
2905mS : ioctl(5, TCSETS|TCSANOW(0x540e), 0x7fff29b0) OK
2906mS : setreuid(-1, 0) OK
2906mS : ioctl(5, TCFLSH, 0) OK
2907mS : write(5, "ATE0V1Q0S0=0H0", 14) = 14
2909mS : write(5, "\r", 1) = 1
2910mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
2910mS : setitimer(ITIMER_REAL, 0x7fff2810, 0) OK
2910mS : read(5, 0x10006678, 1024)
3098mS : END-read(5, "\r\nOK\r\n", 1024) = 6
3098mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
3099mS : write(5, "ATS8=2S7=60&E4&D3&C1", 20) = 20
3099mS : write(5, "\r", 1) = 1
3100mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
3100mS : setitimer(ITIMER_REAL, 0x7fff2810, 0) OK
3100mS : read(5, 0x10006678, 1024)
3275mS : END-read(5, "\r\nOK\r\n", 1024) = 6
3275mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
3275mS : write(5, "AT+FCLASS=?", 11) = 11
3276mS : write(5, "\r", 1) = 1
3276mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
3276mS : setitimer(ITIMER_REAL, 0x7fff28e8, 0) OK
3276mS : read(5, 0x10006678, 1024)
3455mS : END-read(5, "\r\n+FCLASS=0,2\r\n\r\nOK\r\n", 1024) = 21
3455mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
3455mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
3455mS : setitimer(ITIMER_REAL, 0x7fff28e8, 0) OK
3455mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
3456mS : write(5, "AT+FCLASS=2", 11) = 11
3456mS : write(5, "\r", 1) = 1
3457mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
3457mS : setitimer(ITIMER_REAL, 0x7fff2870, 0) OK
3457mS : read(5, 0x10006678, 1024)
3637mS : END-read(5, "\r\nOK\r\n", 1024) = 6
3637mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
3638mS : write(5, "AT+FMFR?", 8) = 8
3638mS : write(5, "\r", 1) = 1
3639mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
3639mS : setitimer(ITIMER_REAL, 0x7fff28c8, 0) OK
3639mS : read(5, 0x10006678, 1024)
3805mS : END-read(5, "\r\n+FMFR=Multi-Tech Systems\r\n\r\n", 1024) = 34
3805mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
3805mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
3805mS : setitimer(ITIMER_REAL, 0x7fff28c8, 0) OK
3805mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
3806mS : write(5, "AT+FMDL?", 8) = 8
3806mS : write(5, "\r", 1) = 1
3807mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
3807mS : setitimer(ITIMER_REAL, 0x7fff28a0, 0) OK
3807mS : read(5, 0x10006678, 1024)
3985mS : END-read(5, "\r\n+FMDL=MT1932ZDXK\r\n\r\nOK\r\n", 1024) = 26
3985mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
3985mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
3985mS : setitimer(ITIMER_REAL, 0x7fff28a0, 0) OK
3985mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
3986mS : write(5, "AT+FREV?", 8) = 8
3986mS : write(5, "\r", 1) = 1
3987mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
3987mS : setitimer(ITIMER_REAL, 0x7fff2898, 0) OK
3987mS : read(5, 0x10006678, 1024)
4155mS : END-read(5, "\r\n+FREV=0115 \r\n\r\nOK\r\n", 1024) = 21
4155mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
4155mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
4155mS : setitimer(ITIMER_REAL, 0x7fff2898, 0) OK
4155mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
4156mS : write(5, "AT+FDCC=?", 9) = 9
4156mS : write(5, "\r", 1) = 1
4157mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
4157mS : setitimer(ITIMER_REAL, 0x7fff28e8, 0) OK
4157mS : read(5, 0x10006678, 1024)
4335mS : END-read(5, "\r\n+FDCC=(0-1),(0-5),(0-4),(0-2", 1024) = 57
4335mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
4335mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
4335mS : setitimer(ITIMER_REAL, 0x7fff28e8, 0) OK
4335mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
4336mS : write(5, "AT+FCQ=?", 8) = 8
4337mS : write(5, "\r", 1) = 1
4337mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
4337mS : setitimer(ITIMER_REAL, 0x7fff28e8, 0) OK
4338mS : read(5, 0x10006678, 1024)
4505mS : END-read(5, "\r\n+FCQ=0-1\r\n\r\nOK\r\n", 1024) = 18
4505mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
4505mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
4505mS : setitimer(ITIMER_REAL, 0x7fff28e8, 0) OK
4505mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
4506mS : write(5, "AT+FAP=?", 8) = 8
4506mS : write(5, "\r", 1) = 1
4507mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
4507mS : setitimer(ITIMER_REAL, 0x7fff28e8, 0) OK
4507mS : read(5, 0x10006678, 1024)
4685mS : END-read(5, "\r\nERROR\r\n", 1024) = 9
4685mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
4685mS : write(5, "AT+FSPL=?", 9) = 9
4686mS : write(5, "\r", 1) = 1
4686mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
4686mS : setitimer(ITIMER_REAL, 0x7fff28e8, 0) OK
4687mS : read(5, 0x10006678, 1024)
4855mS : END-read(5, "\r\n+FSPL=0-1\r\n\r\nOK\r\n", 1024) = 19
4855mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
4855mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
4855mS : setitimer(ITIMER_REAL, 0x7fff28e8, 0) OK
4855mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
4856mS : write(5, "AT+FCLASS=2", 11) = 11
4856mS : write(5, "\r", 1) = 1
4857mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
4857mS : setitimer(ITIMER_REAL, 0x7fff2850, 0) OK
4857mS : read(5, 0x10006678, 1024)
5035mS : END-read(5, "\r\nOK\r\n", 1024) = 6
5035mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
5035mS : write(5, "AT+FTBC=0", 9) = 9
5036mS : write(5, "\r", 1) = 1
5036mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
5036mS : setitimer(ITIMER_REAL, 0x7fff2850, 0) OK
5036mS : read(5, 0x10006678, 1024)
5205mS : END-read(5, "\r\nOK\r\n", 1024) = 6
5205mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
5205mS : write(5, "AT+FBOR=0", 9) = 9
5206mS : write(5, "\r", 1) = 1
5206mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
5206mS : setitimer(ITIMER_REAL, 0x7fff2850, 0) OK
5206mS : read(5, 0x10006678, 1024)
5385mS : END-read(5, "\r\nOK\r\n", 1024) = 6
5385mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
5385mS : write(5, "AT+FPHCTO=30", 12) = 12
5386mS : write(5, "\r", 1) = 1
5386mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
5386mS : setitimer(ITIMER_REAL, 0x7fff2850, 0) OK
5386mS : read(5, 0x10006678, 1024)
5555mS : END-read(5, "\r\nOK\r\n", 1024) = 6
5555mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
5555mS : write(5, "AT+FBUG=1", 9) = 9
5556mS : write(5, "\r", 1) = 1
5556mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
5556mS : setitimer(ITIMER_REAL, 0x7fff2850, 0) OK
5556mS : read(5, 0x10006678, 1024)
5735mS : END-read(5, "\r\nOK\r\n", 1024) = 6
5735mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
5736mS : write(5, "AT+FDCC=1,5,2,2,0,0,0,0", 23) = 23
5736mS : write(5, "\r", 1) = 1
5737mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
5737mS : setitimer(ITIMER_REAL, 0x7fff2780, 0) OK
5737mS : read(5, 0x10006678, 1024)
5905mS : END-read(5, "\r\nOK\r\n", 1024) = 6
5905mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
5906mS : open("/dev/log", O_WRONLY, 0) = 6
5907mS : fcntl(6, F_SETFD, 1) OK
5907mS : time() = 964624952
5908mS : getpid() = 38373, ppid=37546
5909mS : putmsg(6, 0x7fff1848, 0x7fff1854, 0) = 0
5909mS : write(5, "ATM0", 4) = 4
5910mS : write(5, "\r", 1) = 1
5910mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
5910mS : setitimer(ITIMER_REAL, 0x7fff2b28, 0) OK
5911mS : read(5, 0x10006678, 1024)
6085mS : END-read(5, "\r\nOK\r\n", 1024) = 6
6085mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
6085mS : getuid() = 0, euid=0
6085mS : setreuid(-1, 0) OK
6086mS : unlink("/var/spool/locks/LCK..ttyfn00f")
6088mS : END-unlink() OK
6088mS : setreuid(-1, 0) OK
6088mS : getuid() = 0, euid=0
6088mS : setreuid(-1, 0) OK
6088mS : schedctl(NDPRI, 0, 0) = 0
6088mS : setreuid(-1, 0) OK
6089mS : ioctl(5, I_STR, 0x7fff2dd8) errno = 22 (Invalid argument)
6089mS : ioctl(5, TCGETS, 0x7fff2dac) OK
6090mS : getuid() = 0, euid=0
6090mS : setreuid(-1, 0) OK
6090mS : ioctl(5, TCSETS|TCSANOW(0x540e), 0x7fff2dac) OK
6091mS : setreuid(-1, 0) OK
6091mS : fcntl(4, F_SETBSDLKW, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}) OK
6091mS : lseek(4, 0, SEEK_SET) = 0
6091mS : write(4, "Running and idle\n", 17) = 17
6092mS : lseek(4, 0, SEEK_CUR) = 17
6092mS : ftruncate(4, 17) OK
6093mS : fcntl(4, F_SETBSDLKW, {type=F_UNLCK, whence=SEEK_SET, start=263505360, len=6913556921890162128}) OK
6093mS : open("doneq/q22740", O_RDWR, 0) = 7
6094mS : fcntl(7, F_SETBSDLKW, {type=F_WRLCK, whence=SEEK_SET, start=0, len=2}) OK
6098mS : lseek(7, 0, SEEK_SET) = 0
6098mS : fstat(7, 0x7fff2ce8) OK
6098mS : read(7, "tts:964623958\nkilltime:9648074", 719) = 719
6101mS : stat("info/442088880423", 0x7fff2c20) OK
6102mS : open("info/442088880423", O_RDONLY, 0666) = 8
6102mS : fstat64(8, 0x7fff26b8) OK
6102mS : brk(0x10014000) OK
6104mS : ioctl(8, __OLD_TCGETA, 0x7fff2660) errno = 25 (Inappropriate I/O control operation)
6104mS : read(8, "supportsHighRes:yes\nsupports2D", 4096) = 356
6116mS : read(8, 0x100117f8, 4096) = 0
6116mS : close(8) OK
6116mS : time() = 964624952
6117mS : getuid() = 0, euid=0
6117mS : setreuid(-1, 0) OK
6117mS : getpid() = 38373, ppid=37546
6117mS : open("/var/spool/locks//TM.fax038373", O_RDWR|O_CREAT|O_EXCL, 0600) = 8
6119mS : write(8, " 38373\n", 11) = 11
6120mS : fchmod(8, 0444) OK
6121mS : fchown(8, 3, 5) OK
6121mS : close(8) OK
6122mS : link("/var/spool/locks//TM.fax038373", "/var/spool/locks/LCK..ttyfn00f") OK
6123mS : unlink("/var/spool/locks//TM.fax038373") OK
6124mS : setreuid(-1, 0) OK
6124mS : open("log/seqf", O_RDWR|O_CREAT, 0644) = 8
6125mS : fcntl(8, F_SETBSDLKW, {type=F_WRLCK, whence=SEEK_SET, start=0, len=1131746903789188232}) OK
6125mS : read(8, "187157", 1024) = 6
6126mS : umask(022) = 077
6126mS : open("log/c00187158", O_RDWR|O_CREAT|O_EXCL, 0600)
6129mS : END-open() = 9
6129mS : umask(077) = 022
6129mS : lseek(8, 0, SEEK_SET) = 0
6129mS : write(8, "187158", 6) = 6
6130mS : close(8) OK
6131mS : getpid() = 38373, ppid=37546
6132mS : gettimeofday({sec=964624952, usec=967462}) OK
6134mS : write(9, "Jul 26 16:22:32.96: [38373]: S", 65)
6142mS : END-write() = 65
6142mS : close(8) errno = 9 (Bad file number)
6142mS : time() = 964624952
6143mS : getpid() = 38373, ppid=37546
6143mS : putmsg(6, 0x7fff17c0, 0x7fff17cc, 0) = 0
6147mS : gettimeofday({sec=964624952, usec=982862}) OK
6148mS : write(9, "Jul 26 16:22:32.98: [38373]: S", 79) = 79
6149mS : gettimeofday({sec=964624952, usec=984371}) OK
6150mS : write(9, "Jul 26 16:22:32.98: [38373]: M", 47) = 47
6150mS : ioctl(5, TIOCMBIC, 0x7fff2b5c)
6160mS : END-ioctl() OK
6160mS : gettimeofday({sec=964624952, usec=995418}) OK
6161mS : write(9, "Jul 26 16:22:32.99: [38373]: D", 43) = 43
6161mS : select(0, 0, 0, 0, {sec=2, usec=600000})
8765mS : END-select() = 0
8765mS : gettimeofday({sec=964624955, usec=600368}) OK
8766mS : write(9, "Jul 26 16:22:35.60: [38373]: M", 46) = 46
8766mS : ioctl(5, TIOCMBIS, 0x7fff2b5c)
8776mS : END-ioctl() OK
8776mS : close(5)
8793mS : END-close() OK
8793mS : getuid() = 0, euid=0
8793mS : setreuid(-1, 0) OK
8794mS : open("/dev/ttyfn00f", O_RDWR|O_NDELAY|O_NOCTTY, 0)
8993mS : END-open() = 5
8993mS : fcntl(5, F_GETFL) = 0x6
8993mS : fcntl(5, F_SETFL, 0x2) OK
8993mS : fstat(5, 0x7fff2ad0) OK
8993mS : fchown(5, 3, 0) OK
8994mS : fchmod(5, 0600) OK
8994mS : setreuid(-1, 0) OK
8995mS : gettimeofday({sec=964624955, usec=830788}) OK
8996mS : write(9, "Jul 26 16:22:35.83: [38373]: M", 102) = 102
8997mS : ioctl(5, TCGETS, 0x7fff2b38) OK
8999mS : getuid() = 0, euid=0
8999mS : setreuid(-1, 0) OK
8999mS : ioctl(5, TCSETS|TCSANOW(0x540e), 0x7fff2b38)
9011mS : END-ioctl() OK
9011mS : setreuid(-1, 0) OK
9011mS : gettimeofday({sec=964624955, usec=846718}) OK
9012mS : write(9, "Jul 26 16:22:35.84: [38373]: M", 45) = 45
9012mS : ioctl(5, TCFLSH, 0) OK
9013mS : gettimeofday({sec=964624955, usec=848716}) OK
9014mS : write(9, "Jul 26 16:22:35.84: [38373]: <", 55) = 55
9015mS : write(5, "ATE0V1Q0S0=0H0", 14) = 14
9016mS : write(5, "\r", 1) = 1
9016mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
9017mS : setitimer(ITIMER_REAL, 0x7fff2998, 0) OK
14014mS (38373): was sent signal SIGALRM
9017mS : read(5, 0x10006678, 1024)
14015mS : received signal SIGALRM (handler 0x89932a0)
14015mS : END-read() errno = 4 (Interrupted function call)
14015mS : sigreturn(0x7fff25b8) OK
14015mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14016mS : gettimeofday({sec=964624960, usec=851199}) OK
14016mS : write(9, "Jul 26 16:22:40.85: [38373]: M", 68) = 68
14017mS : gettimeofday({sec=964624960, usec=852561}) OK
14018mS : write(9, "Jul 26 16:22:40.85: [38373]: M", 45) = 45
14018mS : gettimeofday({sec=964624960, usec=853937}) OK
14019mS : write(9, "Jul 26 16:22:40.85: [38373]: <", 44) = 44
14021mS : write(5, "ATM0", 4) = 4
14021mS : write(5, "\r", 1) = 1
14022mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14022mS : setitimer(ITIMER_REAL, 0x7fff29b8, 0) OK
14022mS : read(5, 0x10006678, 1024)
14044mS : END-read(5, "ATM0\r", 1024) = 5
14044mS : read(5, 0x10006678, 1024)
14094mS : END-read(5, "\r\nOK\r\n", 1024) = 6
14094mS : setitimer(ITIMER_REAL, 0x89e5d44, 0)
14097mS : END-setitimer() OK
14097mS : gettimeofday({sec=964624960, usec=932496}) OK
14098mS : write(9, "Jul 26 16:22:40.93: [38373]: -", 42) = 42
14098mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14099mS : setitimer(ITIMER_REAL, 0x7fff29b8, 0) OK
14099mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14099mS : gettimeofday({sec=964624960, usec=934337}) OK
14100mS : write(9, "Jul 26 16:22:40.93: [38373]: -", 40) = 40
14100mS : gettimeofday({sec=964624960, usec=935764}) OK
14101mS : write(9, "Jul 26 16:22:40.93: [38373]: S", 62) = 62
14102mS : getuid() = 0, euid=0
14102mS : setreuid(-1, 0) OK
14102mS : schedctl(NDPRI, 0, 39) = 0
14102mS : setreuid(-1, 0) OK
14102mS : gettimeofday({sec=964624960, usec=937700}) OK
14103mS : write(9, "Jul 26 16:22:40.93: [38373]: M", 59) = 59
14104mS : ioctl(5, I_STR, 0x7fff2c68) errno = 22 (Invalid argument)
14104mS : gettimeofday({sec=964624960, usec=939527}) OK
14106mS : stat("/usr/lib/locale/C/LC_MESSAGES/uxsyserr", 0x7ffee1a4) errno = 2 (No such file or directory)
14107mS : stat("/usr/lib/locale/C/Xopen/LC_MESSAGES/uxsyserr", 0x7ffee1a4) errno = 2 (No such file or directory)
14107mS : stat("/usr/lib/locale/C/LC_MESSAGES/uxsyserr.cat", 0x7ffee1a4) errno = 2 (No such file or directory)
14109mS : write(9, "Jul 26 16:22:40.93: [38373]: M", 88) = 88
14109mS : ioctl(5, TCGETS, 0x7fff2c3c) OK
14110mS : getuid() = 0, euid=0
14110mS : setreuid(-1, 0) OK
14110mS : ioctl(5, TCSETS|TCSANOW(0x540e), 0x7fff2c3c) OK
14111mS : setreuid(-1, 0) OK
14111mS : fcntl(4, F_SETBSDLKW, {type=F_WRLCK, whence=SEEK_SET, start=2147429508, len=0}) OK
14111mS : lseek(4, 0, SEEK_SET) = 0
14111mS : write(4, "Sending facsimile\n", 18) = 18
14113mS : lseek(4, 0, SEEK_CUR) = 18
14113mS : ftruncate(4, 18) OK
14113mS : fcntl(4, F_SETBSDLKW, {type=F_UNLCK, whence=SEEK_SET, start=263505360, len=6913556921890162128}) OK
14115mS : sysconf(_SC_OPEN_MAX) = 200
14115mS : fcntl(4, F_SETBSDLKW, {type=F_WRLCK, whence=SEEK_SET, start=268508096, len=6913554945941700608}) OK
14115mS : lseek(4, 0, SEEK_SET) = 0
14115mS : write(4, "Sending job 22740\n", 18) = 18
14116mS : lseek(4, 0, SEEK_CUR) = 18
14116mS : ftruncate(4, 18) OK
14116mS : fcntl(4, F_SETBSDLKW, {type=F_UNLCK, whence=SEEK_SET, start=263505360, len=6913556921890162128}) OK
14117mS : gettimeofday({sec=964624960, usec=952860}) OK
14118mS : write(9, "Jul 26 16:22:40.95: [38373]: <", 52) = 52
14119mS : write(5, "AT+FCLASS=2", 11) = 11
14119mS : write(5, "\r", 1) = 1
14120mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14120mS : setitimer(ITIMER_REAL, 0x7fff2910, 0) OK
14120mS : read(5, 0x10006678, 1024)
14235mS : END-read(5, "AT+FCLASS=2\r\r\nOK\r\n", 1024) = 18
14235mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14235mS : gettimeofday({sec=964624961, usec=70768}) OK
14236mS : write(9, "Jul 26 16:22:41.07: [38373]: -", 50) = 50
14237mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14237mS : setitimer(ITIMER_REAL, 0x7fff2910, 0) OK
14237mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14237mS : gettimeofday({sec=964624961, usec=72576}) OK
14238mS : write(9, "Jul 26 16:22:41.07: [38373]: -", 40) = 40
14238mS : gettimeofday({sec=964624961, usec=73966}) OK
14239mS : write(9, "Jul 26 16:22:41.07: [38373]: <", 50) = 50
14240mS : write(5, "AT+FTBC=0", 9) = 9
14240mS : write(5, "\r", 1) = 1
14241mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14241mS : setitimer(ITIMER_REAL, 0x7fff2910, 0) OK
14241mS : read(5, 0x10006678, 1024)
14355mS : END-read(5, "AT+FTBC=0\r\r\nOK\r\n", 1024) = 16
14355mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14355mS : gettimeofday({sec=964624961, usec=190741}) OK
14356mS : write(9, "Jul 26 16:22:41.19: [38373]: -", 47) = 47
14357mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14357mS : setitimer(ITIMER_REAL, 0x7fff2910, 0) OK
14357mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14357mS : gettimeofday({sec=964624961, usec=192557}) OK
14358mS : write(9, "Jul 26 16:22:41.19: [38373]: -", 40) = 40
14358mS : gettimeofday({sec=964624961, usec=193919}) OK
14359mS : write(9, "Jul 26 16:22:41.19: [38373]: <", 50) = 50
14360mS : write(5, "AT+FBOR=0", 9) = 9
14360mS : write(5, "\r", 1) = 1
14361mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14361mS : setitimer(ITIMER_REAL, 0x7fff2910, 0) OK
14361mS : read(5, 0x10006678, 1024)
14475mS : END-read(5, "AT+FBOR=0\r\r\nOK\r\n", 1024) = 16
14475mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14475mS : gettimeofday({sec=964624961, usec=310740}) OK
14476mS : write(9, "Jul 26 16:22:41.31: [38373]: -", 47) = 47
14477mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14477mS : setitimer(ITIMER_REAL, 0x7fff2910, 0) OK
14477mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14477mS : gettimeofday({sec=964624961, usec=312559}) OK
14478mS : write(9, "Jul 26 16:22:41.31: [38373]: -", 40) = 40
14478mS : gettimeofday({sec=964624961, usec=313947}) OK
14479mS : write(9, "Jul 26 16:22:41.31: [38373]: <", 53) = 53
14480mS : write(5, "AT+FPHCTO=30", 12) = 12
14480mS : write(5, "\r", 1) = 1
14481mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14481mS : setitimer(ITIMER_REAL, 0x7fff2910, 0) OK
14481mS : read(5, 0x10006678, 1024)
14585mS : END-read(5, "AT+FPHCTO=30\r\r\nOK\r\n", 1024) = 19
14585mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14585mS : gettimeofday({sec=964624961, usec=420729}) OK
14586mS : write(9, "Jul 26 16:22:41.42: [38373]: -", 51) = 51
14587mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14587mS : setitimer(ITIMER_REAL, 0x7fff2910, 0) OK
14587mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14587mS : gettimeofday({sec=964624961, usec=422561}) OK
14588mS : write(9, "Jul 26 16:22:41.42: [38373]: -", 40) = 40
14588mS : gettimeofday({sec=964624961, usec=423949}) OK
14589mS : write(9, "Jul 26 16:22:41.42: [38373]: <", 50) = 50
14590mS : write(5, "AT+FBUG=1", 9) = 9
14590mS : write(5, "\r", 1) = 1
14591mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14591mS : setitimer(ITIMER_REAL, 0x7fff2910, 0) OK
14591mS : read(5, 0x10006678, 1024)
14705mS : END-read(5, "AT+FBUG=1\r\r\nOK\r\n", 1024) = 16
14705mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14705mS : gettimeofday({sec=964624961, usec=540820}) OK
14706mS : write(9, "Jul 26 16:22:41.54: [38373]: -", 47) = 47
14707mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14707mS : setitimer(ITIMER_REAL, 0x7fff2910, 0) OK
14707mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14707mS : gettimeofday({sec=964624961, usec=542613}) OK
14708mS : write(9, "Jul 26 16:22:41.54: [38373]: -", 40) = 40
14709mS : gettimeofday({sec=964624961, usec=544196}) OK
14709mS : write(9, "Jul 26 16:22:41.54: [38373]: <", 64) = 64
14710mS : write(5, "AT+FDCC=1,5,2,2,0,0,0,0", 23) = 23
14711mS : write(5, "\r", 1) = 1
14711mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14711mS : setitimer(ITIMER_REAL, 0x7fff2840, 0) OK
14711mS : read(5, 0x10006678, 1024)
14825mS : END-read(5, "AT+FDCC=1,5,2,2,0,0,0,0\r\r\nOK\r\n", 1024) = 30
14825mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14825mS : gettimeofday({sec=964624961, usec=660769}) OK
14826mS : write(9, "Jul 26 16:22:41.66: [38373]: -", 62) = 62
14828mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14828mS : setitimer(ITIMER_REAL, 0x7fff2840, 0) OK
14828mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14828mS : gettimeofday({sec=964624961, usec=663527}) OK
14829mS : write(9, "Jul 26 16:22:41.66: [38373]: -", 40) = 40
14829mS : gettimeofday({sec=964624961, usec=665077}) OK
14830mS : write(9, "Jul 26 16:22:41.66: [38373]: <", 64) = 64
14831mS : write(5, "AT+FLID="+442088896166"", 23) = 23
14831mS : write(5, "\r", 1) = 1
14832mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14832mS : setitimer(ITIMER_REAL, 0x7fff2888, 0) OK
14832mS : read(5, 0x10006678, 1024)
14935mS : END-read(5, "AT+FLID="+442088896166"\r\r\nOK\r\n", 1024) = 30
14935mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14935mS : gettimeofday({sec=964624961, usec=770771}) OK
14936mS : write(9, "Jul 26 16:22:41.77: [38373]: -", 62) = 62
14937mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14937mS : setitimer(ITIMER_REAL, 0x7fff2888, 0) OK
14937mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
14937mS : gettimeofday({sec=964624961, usec=772595}) OK
14938mS : write(9, "Jul 26 16:22:41.77: [38373]: -", 40) = 40
14939mS : open("etc/lutRS18.pcf", O_RDONLY, 0666) = 8
14940mS : fstat64(8, 0x7fff2488) OK
14940mS : ioctl(8, __OLD_TCGETA, 0x7fff2430) errno = 25 (Inappropriate I/O control operation)
14940mS : read(8, <01 66 63 70 08 00 00 00 01 00 00 00 0d 00 00 00>..., 4096) = 4096
14941mS : lseek(8, 904, SEEK_SET) = 904
14941mS : read(8, <0d 01 00 00 00 e4 80 81 8f 81 80 85 89 8f 92 80>..., 4096) = 4096
14942mS : brk(0x10016000) OK
14943mS : lseek(8, 2052, SEEK_SET) = 2052
14943mS : read(8, <0d 00 00 00 00 00 00 e4 00 00 00 00 00 00 00 02>..., 4096) = 4096
14945mS : brk(0x10018000) OK
14946mS : syssgi(SGI_USE_FP_BCOPY, 0x100129b0, 0xc58, 0xffffffff, 0x100125c0, 0xfb66a18) = 0
14946mS : read(8, <ff c0 40 20 e0 70 6f 60 3f c0 70 e0 60 60 60 60>..., 4096) = 4096
14947mS : lseek(8, 10124, SEEK_SET) = 10124
14947mS : read(8, <0d 00 00 00 00 20 00 ff 00 00 00 00 00 00 00 00>..., 4096) = 3948
14948mS : lseek(8, 14024, SEEK_SET) = 14024
14948mS : read(8, <0d 00 00 00 00 00 00 00 00 00 00 00 00 00 00 17>..., 4096) = 48
14949mS : lseek64(8, -28, 0xffffffe4) = 14044
14949mS : close(8) OK
14949mS : time() = 964624961
14951mS : open("FIFO", O_WRONLY|O_NDELAY, 0) = 8
14951mS : fcntl(8, F_GETFL) = 0x5
14951mS : fcntl(8, F_SETFL, 0x1) OK
14952mS : write(8, "*22740:c\0", 9) = 9
14952mS : gettimeofday({sec=964624961, usec=787495}) OK
14953mS : write(9, "Jul 26 16:22:41.78: [38373]: D", 45) = 45
14953mS : gettimeofday({sec=964624961, usec=789022}) OK
14954mS : write(9, "Jul 26 16:22:41.78: [38373]: <", 56) = 56
14955mS : write(5, "ATDT9,88880423@", 15) = 15
14955mS : write(5, "\r", 1) = 1
14956mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
14956mS : setitimer(ITIMER_REAL, 0x7fff28d8, 0) OK
14956mS : read(5, 0x10006678, 1024)
15075mS : END-read(5, "ATDT9,88880423@\r", 1024) = 16
15075mS : read(5, 0x10006678, 1024)
74175mS : END-read(5, "\r\nNO ANSWER\r\n", 1024) = 13
74175mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
74175mS : gettimeofday({sec=964625021, usec=10726}) OK
74176mS : write(9, "Jul 26 16:23:41.01: [38373]: -", 54) = 54
74177mS : gettimeofday({sec=964625021, usec=12630}) OK
74177mS : select(0, 0x7fff2a20, 0x7fff29a0, 0x7fff2920, {sec=0, usec=0})
74177mS : END-select() = 0
74177mS : gettimeofday({sec=964625021, usec=12967}) OK
74178mS : time() = 964625021
74178mS : getpid() = 38373, ppid=37546
74178mS : putmsg(6, 0x7fff1638, 0x7fff1644, 0) = 0
74179mS : gettimeofday({sec=964625021, usec=14341}) OK
74180mS : write(9, "Jul 26 16:23:41.01: [38373]: S", 78) = 78
74180mS : gettimeofday({sec=964625021, usec=15873}) OK
74181mS : write(9, "Jul 26 16:23:41.01: [38373]: <", 44) = 44
74182mS : write(5, "ATH0", 4) = 4
74182mS : write(5, "\r", 1) = 1
74183mS : sigaction(SIGALRM, {flags=0 handler=0x89932a0 mask=[<none>]}, 0) OK
74183mS : setitimer(ITIMER_REAL, 0x7fff2938, 0) OK
74183mS : setitimer(ITIMER_REAL, 0x89e5d44, 0) OK
74183mS : gettimeofday({sec=964625021, usec=18541}) OK
74184mS : write(9, "Jul 26 16:23:41.01: [38373]: -", 47) = 47
74184mS : gettimeofday({sec=964625021, usec=19910}) OK
74185mS : write(9, "Jul 26 16:23:41.01: [38373]: M", 58) = 58
74186mS : gettimeofday({sec=964625021, usec=21439}) OK
74187mS : write(9, "Jul 26 16:23:41.02: [38373]: M", 47) = 47
74187mS : ioctl(5, TIOCMBIC, 0x7fff2c64)
74209mS : END-ioctl() OK
74209mS : close(5)
74226mS : END-close() OK
74227mS : gettimeofday({sec=964625021, usec=62428}) OK
74228mS : write(9, "Jul 26 16:23:41.06: [38373]: S", 76) = 76
74228mS : getuid() = 0, euid=0
74228mS : setreuid(-1, 0) OK
74228mS : schedctl(NDPRI, 0, 0) = 0x27
74229mS : setreuid(-1, 0) OK
74229mS : fcntl(4, F_SETBSDLKW, {type=F_WRLCK, whence=SEEK_SET, start=2147429508, len=1153009878117434832}) OK
74229mS : lseek(4, 0, SEEK_SET) = 0
74229mS : write(4, "Waiting for modem to come read", 32) = 32
74230mS : lseek(4, 0, SEEK_CUR) = 32
74230mS : ftruncate(4, 32) OK
74230mS : fcntl(4, F_SETBSDLKW, {type=F_UNLCK, whence=SEEK_SET, start=263505360, len=6913556921890162128}) OK
74230mS : gettimeofday({sec=964625021, usec=65925}) OK
74230mS : gettimeofday({sec=964625021, usec=66174}) OK
74231mS : write(9, "Jul 26 16:23:41.06: [38373]: S", 41) = 41
74232mS : close(9) OK
74234mS : getuid() = 0, euid=0
74234mS : setreuid(-1, 0) OK
74234mS : unlink("/var/spool/locks/LCK..ttyfn00f")
74251mS : END-unlink() OK
74251mS : setreuid(-1, 0) OK
74253mS : time() = 964625021
74253mS : open("etc/xferlog", O_RDWR|O_APPEND|O_CREAT, 0644) = 5
74255mS : fcntl(5, F_SETBSDLKW, {type=F_WRLCK, whence=SEEK_SET, start=2147428716, len=144650768}) OK
74255mS : write(5, "07/26/00 16:22\tSEND\t00187158\tt", 154) = 154
74256mS : close(5) OK
74257mS : lseek(7, 0, SEEK_SET) = 0
74257mS : write(7, "tts:964623958\nkilltime:9648074", 733) = 733
74258mS : ftruncate(7, 733) OK
74259mS : close(7) OK
74259mS : umask(022) = 077
74259mS : open("info/442088880423", O_WRONLY|O_CREAT, 0644) = 5
74260mS : umask(077) = 022
74260mS : write(5, "supportsHighRes:yes\nsupports2D", 371) = 371
74261mS : ftruncate(5, 371) OK
74261mS : close(5) OK
74261mS : getuid() = 0, euid=0
74261mS : setreuid(-1, 0) OK
74262mS : getpid() = 38373, ppid=37546
74262mS : open("/var/spool/locks//TM.fax038373", O_RDWR|O_CREAT|O_EXCL, 0600) = 5
74263mS : write(5, " 38373\n", 11) = 11
74265mS : fchmod(5, 0444) OK
74266mS : fchown(5, 3, 5) OK
74266mS : close(5) OK
74267mS : link("/var/spool/locks//TM.fax038373", "/var/spool/locks/LCK..ttyfn00f") OK
74268mS : unlink("/var/spool/locks//TM.fax038373") OK
74269mS : setreuid(-1, 0) OK
74269mS : getuid() = 0, euid=0
74269mS : setreuid(-1, 0) OK
74270mS : unlink("/var/spool/locks/LCK..ttyfn00f")
74272mS : END-unlink() OK
74272mS : setreuid(-1, 0) OK
74272mS : close(8) OK
74272mS : prctl(PR_LASTSHEXIT) = 1
74273mS : exit(0)
System call summary:
Average Total
Name #Calls Time(ms) Time(ms)
-----------------------------------------
read 52 1311.90 68218.67
select 3 1737.23 5211.68
open 37 6.88 254.38
ioctl 22 3.72 81.91
write 118 0.49 57.54
close 29 1.49 43.31
unlink 6 4.03 24.21
setitimer 88 0.11 10.10
execve 2 4.09 8.19
setreuid 32 0.14 4.37
fchown 6 0.68 4.10
link 3 1.17 3.50
stat 7 0.49 3.43
sigaction 47 0.06 3.02
elfmap 7 0.42 2.91
fcntl 22 0.11 2.40
ftruncate 7 0.32 2.27
gettimeofday 50 0.04 2.15
fchmod 7 0.30 2.09
brk 11 0.15 1.70
putmsg 3 0.36 1.09
mmap 4 0.25 1.00
lseek 17 0.04 0.70
fstat 6 0.10 0.62
fstat64 5 0.09 0.47
getuid 16 0.02 0.35
schedctl 3 0.12 0.35
time 8 0.03 0.21
getpid 8 0.03 0.20
syssgi 4 0.04 0.18
umask 5 0.02 0.12
sigreturn 2 0.06 0.12
getpagesize 2 0.03 0.07
gethostname 1 0.06 0.06
sysconf 1 0.05 0.05
lseek64 1 0.05 0.05
prctl 1 0.03 0.03
exit 1 0.00 0.00
____________________ HylaFAX(tm) Users Mailing List _______________________
To unsub: mail -s unsubscribe hylafax-users-request@hylafax.org < /dev/null
- References:
- Re: [hylafax-users] check modem power
- From: "Jay R. Ashworth" <jra@baylink.com>
- Re: [hylafax-users] check modem power
- Prev by Date: [hylafax-users] pcmcia fax modems for HylaFAX?
- Next by Date: [hylafax-users]
- Prev by thread: Re: [hylafax-users] check modem power
- Next by thread: Re: [hylafax-users] check modem power
- Index(es):
Project hosted by iFAX Solutions