On Thursday 31 May 2007 10:59:54 Sam Varshavchik wrote:
> > # some lines removed
> Well, that's where the interesting stuff probably was. What the child
> process does is not important. The important stuff is what the parent
> process does.
Most of what I removed, I thought, was where it went in
and out of subshell processes from the bash script.
> Remove the -f flag from strace, let it trace just the parent process.
Okay, here we go, syslog output follows after strace...
# strace -p 17607
Process 17607 attached - interrupt to quit
select(6, [3 5], NULL, NULL, {119, 120000}) = 1 (in [5], left {113, 680000})
accept(5, {sa_family=AF_FILE, [EMAIL PROTECTED], [2]) = 4
fcntl64(4, F_SETFL, O_RDONLY) = 0
select(5, [4], NULL, NULL, {10, 0}) = 1 (in [4], left {10, 0})
read(4, "AUTH [EMAIL PROTECTED]"..., 8192) = 48
write(2, "DEBUG: received auth request, se"..., 59) = 59
write(2, "DEBUG: authpipe: trying this mod"..., 36) = 36
write(2, "DEBUG: closing pipe\n", 20) = 20
write(2, "DEBUG: forking new one\n", 23) = 23
pipe([6, 7]) = 0
pipe([8, 9]) = 0
write(2, "DEBUG: attempting to fork\n", 26) = 26
clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD,
child_tidptr=0xb7e50708) = 19327
write(2, "DEBUG: Pipe auth. started Pipe-p"..., 51) = 51
close(6) = 0
close(9) = 0
write(2, "DEBUG: new pipe has in: 8, out: "..., 34) = 34
select(8, NULL, [7], NULL, {10, 0}) = 1 (out [7], left {10, 0})
write(7, "AUTH [EMAIL PROTECTED]"..., 48) = 48
time(NULL) = 1180611232
time(NULL) = 1180611232
select(9, [8], NULL, NULL, {30, 0}) = 0 (Timeout)
write(2, "DEBUG: closing pipe\n", 20) = 20
close(8) = 0
close(7) = 0
write(2, "DEBUG: trying to wait for child "..., 54) = 54
waitpid(19327, NULL, WNOHANG) = 0
write(2, "DEBUG: sleep 2 seconds and try a"..., 59) = 59
rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
rt_sigaction(SIGCHLD, NULL, {SIG_DFL}, 8) = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
nanosleep({2, 0}, 0xbfe8c534) = ? ERESTART_RESTARTBLOCK (To be
restarted)
--- SIGCHLD (Child exited) @ 0 (0) ---
restart_syscall(<... resuming interrupted call ...>) = 0
waitpid(19327, NULL, WNOHANG) = 19327
write(2, "DEBUG: authpipe: TEMPFAIL - no m"..., 58) = 58
close(4) = 0
select(6, [3 5], NULL, NULL, {300, 0}
May 31 11:33:52 aria authdaemond: received auth request, service=imap,
authtype=login
May 31 11:33:52 aria authdaemond: authpipe: trying this module
May 31 11:33:52 aria authdaemond: closing pipe
May 31 11:33:52 aria authdaemond: forking new one
May 31 11:33:52 aria authdaemond: attempting to fork
May 31 11:33:52 aria authdaemond: executing /etc/authlib/authProg
May 31 11:33:52 aria authdaemond: Pipe auth. started Pipe-program (pid 19327)
May 31 11:33:52 aria authdaemond: new pipe has in: 8, out: 7
May 31 11:34:22 aria authdaemond: closing pipe
May 31 11:34:22 aria authdaemond: trying to wait for child (WNOHANG) (pid 19327)
May 31 11:34:22 aria authdaemond: sleep 2 seconds and try again to wait for pid
19327
May 31 11:34:22 aria authdaemond: /bin/cat: write error: Broken pipe
May 31 11:34:24 aria authdaemond: authpipe: TEMPFAIL - no more modules will be
tried
Invoked with...
# authtest -s imap [EMAIL PROTECTED] xxxxxx
Authentication FAILED: Input/output error
--markc
-------------------------------------------------------------------------
This SF.net email is sponsored by DB2 Express
Download DB2 Express C - the FREE version of DB2 express and take
control of your XML. No limits. Just data. Click to get it now.
http://sourceforge.net/powerbar/db2/
_______________________________________________
courier-users mailing list
[email protected]
Unsubscribe: https://lists.sourceforge.net/lists/listinfo/courier-users
