Sorry to bombard the list today. :( I've backed myself into a corner
and have pushed dbmail into production a tad early. Regardless, figure
the real world testing would be appreciated. I'm getting the following
in my mail logs:
[EMAIL PROTECTED]: [local] 54147 2004-11-09 16:15:47 PST LOG: unexpected
EOF on client connection
dbmail-imapd in free(): error: junk pointer, too high to make sense
Nov 9 16:19:03 mail kernel: pid 58145 (dbmail-imapd), uid 65534:
exited on signal 6
Is anyone else seeing this when trying to synchronize a mailbox? This
looks like the same bug, actually... but this is a more fatal version
of it. The upload/copy seems to be happening fine, but the sync is now
causing a problem. Fortunately, since this is a crash, I was able to
get a backtrace from a live imapd. Classic double-free(3). I couldn't
trace where it's coming from, but commenting out the offending line on
1667 seems to fix things even though the backend starts to leak memory
like its nobody's business. Hey, that's why setrlimit(2) was
implemented, right? *grin* -sc
Program received signal SIGABRT, Aborted.
0x0000000801435048 in kill () from /lib/libc.so.5
(gdb) bt
#0 0x0000000801435048 in kill () from /lib/libc.so.5
#1 0x000000080149d99d in abort () from /lib/libc.so.5
#2 0x0000000801440935 in ldexp () from /lib/libc.so.5
#3 0x000000080144096c in ldexp () from /lib/libc.so.5
#4 0x000000080144186d in ldexp () from /lib/libc.so.5
#5 0x0000000800e4c93d in g_list_foreach () from
/usr/local/lib/libglib-2.0.so.400
#6 0x00000000004164e0 in dbmail_imap_session_fetch_get_items
(self=0x528400) at dbmail-imapsession.c:1667
#7 0x000000000040d944 in _ic_fetch (self=0x528400) at
imapcommands.c:2318
#8 0x000000000040e831 in _ic_uid (self=0x528400) at imapcommands.c:2835
#9 0x00000000004046d8 in IMAPClientHandler (ci=0x6651a0) at imap4.c:353
#10 0x000000080065a0ec in PerformChildTask (info=0x800768c80) at
serverchild.c:375
#11 0x000000080065a1e6 in CreateChild (info=0x800768c80) at
serverchild.c:250
#12 0x000000080065ad7c in manage_start_children () at pool.c:348
#13 0x0000000800659603 in StartServer (conf=0x7fffffffe5e0) at
server.c:109
#14 0x00000000004118b3 in main (argc=-7744, argv=0x0) at imapd.c:186
(gdb) frame 6
#6 0x00000000004164e0 in dbmail_imap_session_fetch_get_items
(self=0x528400) at dbmail-imapsession.c:1667
1667 g_list_foreach(tlist, (GFunc)g_free, NULL);
(gdb) p *self
$1 = {ci = 0x800768ca0, use_uid = 1, msg_idnr = 1705, tag = 0x526390
"6", command = 0x5263a0 "UID",
args = 0x51f4e8, fi = {bodyfetch = {noseen = 0, itemtype = -1,
argstart = 0, argcnt = 0, octetstart = 0,
octetcnt = 0, partspec = '\0' <repeats 99 times>},
msgparse_needed = 1, hdrparse_needed = 0,
getBodyTotal = 0, getBodyTotalPeek = 0, getInternalDate = 0,
getFlags = 0, getUID = 1, getMIME_IMB = 1,
getEnvelope = 0, getSize = 0, getMIME_IMB_noextension = 0,
getRFC822Header = 0, getRFC822Text = 0,
getRFC822 = 0, getRFC822Peek = 0}, message = 0x0, headermsg =
{mimeheader = {start = 0x0, total_nodes = 0},
rfcheader = {start = 0x0, total_nodes = 0}, message_has_errors = 0,
bodystart = {block = 0, pos = 0},
bodyend = {block = 0, pos = 0}, bodysize = 0, bodylines = 0,
rfcheadersize = 0, children = {start = 0x0,
total_nodes = 0}, rfcheaderlines = 0, mimerfclines = 0}, msginfo
= 0x668000}
(gdb) p *self->msginfo
$2 = {flags = {538976357, 538976288, 538976288, 1327505440, 1633946187,
1852142194},
internaldate = "thetigerdotcom.db_sequence ", rfcsize =
2314885530818453536, uid = 741773563029102624}
(gdb) frame 7
#7 0x000000000040d944 in _ic_fetch (self=0x528400) at
imapcommands.c:2318
2318 if
(dbmail_imap_session_fetch_get_items(self) < 0)
(gdb) p *self
$3 = {ci = 0x800768ca0, use_uid = 1, msg_idnr = 1705, tag = 0x526390
"6", command = 0x5263a0 "UID",
args = 0x51f4e8, fi = {bodyfetch = {noseen = 0, itemtype = -1,
argstart = 0, argcnt = 0, octetstart = 0,
octetcnt = 0, partspec = '\0' <repeats 99 times>},
msgparse_needed = 1, hdrparse_needed = 0,
getBodyTotal = 0, getBodyTotalPeek = 0, getInternalDate = 0,
getFlags = 0, getUID = 1, getMIME_IMB = 1,
getEnvelope = 0, getSize = 0, getMIME_IMB_noextension = 0,
getRFC822Header = 0, getRFC822Text = 0,
getRFC822 = 0, getRFC822Peek = 0}, message = 0x0, headermsg =
{mimeheader = {start = 0x0, total_nodes = 0},
rfcheader = {start = 0x0, total_nodes = 0}, message_has_errors = 0,
bodystart = {block = 0, pos = 0},
bodyend = {block = 0, pos = 0}, bodysize = 0, bodylines = 0,
rfcheadersize = 0, children = {start = 0x0,
total_nodes = 0}, rfcheaderlines = 0, mimerfclines = 0}, msginfo
= 0x668000}
(gdb) p *self->msginfo
$4 = {flags = {538976357, 538976288, 538976288, 1327505440, 1633946187,
1852142194},
internaldate = "thetigerdotcom.db_sequence ", rfcsize =
2314885530818453536, uid = 741773563029102624}
(gdb) frame 8
#8 0x000000000040e831 in _ic_uid (self=0x528400) at imapcommands.c:2835
2835 result = _ic_fetch(self);
(gdb) p *self
$5 = {ci = 0x800768ca0, use_uid = 1, msg_idnr = 1705, tag = 0x526390
"6", command = 0x5263a0 "UID",
args = 0x51f4e8, fi = {bodyfetch = {noseen = 0, itemtype = -1,
argstart = 0, argcnt = 0, octetstart = 0,
octetcnt = 0, partspec = '\0' <repeats 99 times>},
msgparse_needed = 1, hdrparse_needed = 0,
getBodyTotal = 0, getBodyTotalPeek = 0, getInternalDate = 0,
getFlags = 0, getUID = 1, getMIME_IMB = 1,
getEnvelope = 0, getSize = 0, getMIME_IMB_noextension = 0,
getRFC822Header = 0, getRFC822Text = 0,
getRFC822 = 0, getRFC822Peek = 0}, message = 0x0, headermsg =
{mimeheader = {start = 0x0, total_nodes = 0},
rfcheader = {start = 0x0, total_nodes = 0}, message_has_errors = 0,
bodystart = {block = 0, pos = 0},
bodyend = {block = 0, pos = 0}, bodysize = 0, bodylines = 0,
rfcheadersize = 0, children = {start = 0x0,
total_nodes = 0}, rfcheaderlines = 0, mimerfclines = 0}, msginfo
= 0x668000}
(gdb) p *self->msginfo
$6 = {flags = {538976357, 538976288, 538976288, 1327505440, 1633946187,
1852142194},
internaldate = "thetigerdotcom.db_sequence ", rfcsize =
2314885530818453536, uid = 741773563029102624}
(gdb) frame 9
#9 0x00000000004046d8 in IMAPClientHandler (ci=0x6651a0) at imap4.c:353
353 result = (*imap_handler_functions[i])
(session);
(gdb) set print elements 0
(gdb) p *ci
$7 = {tx = 0x4354454600444955, rx = 0x4220353037312048,
ip = "ODYSTRUCTURE", '\0' <repeats 13 times>,
"\200S\000\000\000\000",
clientname =
"\000\b\v\000\002\000\002\000\003\000\000\000\000\000\000\000\000\220S\0
00\000\000\000\000\200\020\000\000\000\000\000\000\000Rf\000\000\000\000
\000\005\000\000\000\000\000\000\000\000pf\000\000\000\000\000\200\020\0
00\000\000\000\000\000
Rf\000\000\000\000\0001\0000\0000\0000\000\000\220f\000\000\000\000\000\
[EMAIL PROTECTED]
f\000\000\000\000\000\200\020\000\000\000\000\000\000`Rf\000\000\000\000
\000\001\000\000\000\000\000\000\000\000
k\000\000\000\000\000\200\020\000\000\000\000\000\000\200Rf\000\000\000\
[EMAIL PROTECTED]
0\000\000\000\000\000\000?
Rf\000\000\000\000\000\001\000\000\000\000\000\000\000\000`k\000\000\000
\000\000\200\020\000\000\000\000\000\000?
Rf\000\000\000\000\000\004\000\000\000\000\000\000\000\000\200k\000\000\
000\000\000\200\020\000\000\000\000\000\000?
Rf\000\000\000\000\0001\0000\0000\0000\000\000?
k\000\000\000\000\000\200\020\000\000\000\000\000\000\000Sf\000\000\000\
000\0001315\000189\000?
k\000\000\000\000\000\200\020\000\000\000\000\000\000
Sf\000\000\000\000\000\001\000\000\000\000\000\000\000\000?
[EMAIL PROTECTED]
\000\001\000\000\000\000\000\000\000\000\000l\000\000\000\000\000\200\02
0\000\000\000\000\000\000`Sf\000\000\000\000\000\001\000\000\000\000\000
\000\000\000
l\000\000\000\000\000\200\020\000\000\000\000\000\000\200Sf\000\000\000\
[EMAIL PROTECTED]
0\000\000\000\000\000\000?
Sf\000\000\000\000\0001\0000\0000\0000\000\000`l\000\000\000\000\000\200
\020\000\000\000\000\000\000?
Sf\000\000\000\000\00040061\00018\000\200l\000\000\000\000\000\200\020\0
00\000\000\000\000\000?
Sf\000\000\000\000\000\001\000\000\000\000\000\000\000\000?
l\000\000\000\000\000\200\020\000\000\000\000\000\000\000Tf\000\000\000\
000\000\001\000\000\000\000\000\000\000\000?
l\000\000\000\000\000\200\020\000\000\000\000\000\000
Tf\000\000\000\000\000\001\000\000\000\000\000\000\000\000?
[EMAIL PROTECTED]
\000\004\000\000\000\000\000\000\000\000\000m\000\000\000\000\000\200\02
0\000\000\000\000\000\000`Tf\000\000\000\000\0001\0000\0000\0000\000\000
m\000\000\000\000\000\200\020\000\000\000\000\000\000\200Tf\000\000\000\
[EMAIL PROTECTED]
\000?
Tf\000\000\000\000\000\001\000\000\000\000\000\000\000\000`m\000\000\000
\000\000\200\020", '\0' <repeats 14 times>,
"\001\000\000\000\000\000\000\000\036Uf\000\000\000\000\000\001\000\000\
000\000\000\000\000
Uf\000\000\000\000\000\001\000\000\000\000\000\000\000\"Uf\000\000\000\0
00\000\023\000\000\000\000\000\000\000$Uf\000\000\000\000\000\005\000\00
0\000\000\000\000\0008Uf\000\000\000\000\000\004\000\000\000\000\000\000
\000>Uf\000\000\000\000\0001\0000\0000\0000\0000\0000\0002004-02-29
04:02:
00\00040065\0001899\000\000\000\000\000\000\001\000\000\000\000\000\000\
000?Uf\000\000\000\000\000\001\000\000\000\000\000\000\000?
Uf\000\000\000\000\000\001\000\000\000\000\000\000\000?
Uf\000\000\000\000\000\001\000\000\000\000\000\000\000?
Uf\000\000\000\000\000\001\000\000\000\000\000\000\000?
Uf\000\000\000\000\000\001\000\000\000\000\000\000\000?
Uf\000\000\000\000\000\023\000\000\000\000\000\000\000?
Uf\000\000\000\000\000\004\000\000\000\000\000\000\000?
Uf\000\000\000\000\000\004\000\000\000\000\000\000", timeoutMsg =
0x6655fd "1900", timeout = 3145777,
userData = 0x3430303200300030}
(gdb) p *ci->userData
Attempt to dereference a generic pointer.
353 result = (*imap_handler_functions[i])
(session);
(gdb) p *imap_handler_functions
$8 = 0
(gdb) p *session
$9 = {ci = 0x800768ca0, use_uid = 1, msg_idnr = 1705, tag = 0x526390
"6", command = 0x5263a0 "UID",
args = 0x51f4e8, fi = {bodyfetch = {noseen = 0, itemtype = -1,
argstart = 0, argcnt = 0, octetstart = 0,
octetcnt = 0, partspec = '\0' <repeats 99 times>},
msgparse_needed = 1, hdrparse_needed = 0,
getBodyTotal = 0, getBodyTotalPeek = 0, getInternalDate = 0,
getFlags = 0, getUID = 1, getMIME_IMB = 1,
getEnvelope = 0, getSize = 0, getMIME_IMB_noextension = 0,
getRFC822Header = 0, getRFC822Text = 0,
getRFC822 = 0, getRFC822Peek = 0}, message = 0x0, headermsg =
{mimeheader = {start = 0x0, total_nodes = 0},
rfcheader = {start = 0x0, total_nodes = 0}, message_has_errors = 0,
bodystart = {block = 0, pos = 0},
bodyend = {block = 0, pos = 0}, bodysize = 0, bodylines = 0,
rfcheadersize = 0, children = {start = 0x0,
total_nodes = 0}, rfcheaderlines = 0, mimerfclines = 0}, msginfo
= 0x668000}
(gdb) frame 10
#10 0x000000080065a0ec in PerformChildTask (info=0x800768c80) at
serverchild.c:375
375 info->ClientHandler(&client);
(gdb) p *info
$10 = {maxConnect = 10000, listenSocket = 5, resolveIP = 1, timeout =
4000,
timeoutMsg = 0x41b708 "* BYE dbmail IMAP4 server signing off due to
timeout\r\n",
ClientHandler = 0x403fb0 <IMAPClientHandler>}
(gdb) p *ClientHandler
No symbol "ClientHandler" in current context.
(gdb) p *info->ClientHandler
$11 = {int (clientinfo_t *)} 0x403fb0 <IMAPClientHandler>
(gdb) frame 11
#11 0x000000080065a1e6 in CreateChild (info=0x800768c80) at
serverchild.c:250
250 PerformChildTask(info);
(gdb) p *info
$12 = {maxConnect = 10000, listenSocket = 5, resolveIP = 1, timeout =
4000,
timeoutMsg = 0x41b708 "* BYE dbmail IMAP4 server signing off due to
timeout\r\n",
ClientHandler = 0x403fb0 <IMAPClientHandler>}
(gdb) frame 12
#12 0x000000080065ad7c in manage_start_children () at pool.c:348
348 if (CreateChild(&childinfo) == -1) {
Here's the log:
Nov 9 16:24:03 mail dbmail/imap4d[60094]: pool.c,child_reg_connected:
[60094]
Nov 9 16:24:03 mail dbmail/imap4d[60094]: PerformChildTask(): incoming
connection from [a.b.c.d (client.example.com)]
Nov 9 16:24:03 mail dbmail/imap4d[60094]: PerformChildTask(): client
info init complete, calling client handler
Nov 9 16:24:03 mail dbmail/imap4d[60094]: IMAPClientHandler(): line
read for PID 60094
Nov 9 16:24:03 mail dbmail/imap4d[60094]: COMMAND: [flim07 CAPABILITY]
Nov 9 16:24:03 mail dbmail/imap4d[60094]: imap4.c,IMAPClientHandler:
COMMAND [CAPABILITY]
Nov 9 16:24:03 mail dbmail/imap4d[60094]: IMAPClientHandler():
Executing command capability...
Nov 9 16:24:03 mail dbmail/imap4d[60094]: IMAPClientHandler():
Finished command capability [0]
Nov 9 16:24:03 mail dbmail/imap4d[60094]: IMAPClientHandler(): line
read for PID 60094
Nov 9 16:24:03 mail dbmail/imap4d[60094]: COMMAND: [flim08 LOGIN {19}]
Nov 9 16:24:03 mail dbmail/imap4d[60094]: imap4.c,IMAPClientHandler:
COMMAND [LOGIN]
Nov 9 16:24:03 mail dbmail/imap4d[60094]:
imaputil.c,build_args_array_ext: last char = }
Nov 9 16:24:03 mail dbmail/imap4d[60094]: build_args_array_ext(): got
extra line [ {6}]
Nov 9 16:24:03 mail dbmail/imap4d[60094]:
imaputil.c,build_args_array_ext: last char = }
Nov 9 16:24:03 mail dbmail/imap4d[60094]: build_args_array_ext(): got
extra line []
Nov 9 16:24:03 mail dbmail/imap4d[60094]: arg[0]: '[EMAIL PROTECTED]'
Nov 9 16:24:03 mail dbmail/imap4d[60094]: arg[1]: 'testpw'
Nov 9 16:24:03 mail dbmail/imap4d[60094]: IMAPClientHandler():
Executing command login...
Nov 9 16:24:03 mail dbmail/imap4d[60094]: _ic_login(): trying to
validate user
Nov 9 16:24:03 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT user_idnr, passwd, encryption_type FROM
dbmail_users WHERE userid = '[EMAIL PROTECTED]']
Nov 9 16:24:03 mail dbmail/imap4d[60094]: authsql.c,auth_validate:
validating using plaintext passwords
Nov 9 16:24:03 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [UPDATE dbmail_users SET last_login = '2004-11-09
16:24:03' WHERE user_idnr = '3']
Nov 9 16:24:03 mail dbmail/imap4d[60094]: _ic_login(): user (id:3,
name [EMAIL PROTECTED]) tries login
Nov 9 16:24:03 mail dbmail/imap4d[60094]: _ic_login(): user (id 3,
name [EMAIL PROTECTED]) login accepted @ 2004-11-09 16:24:03
Nov 9 16:24:03 mail dbmail/imap4d[60094]: IMAPClientHandler():
Finished command login [0]
Nov 9 16:24:03 mail dbmail/imap4d[60094]: IMAPClientHandler(): line
read for PID 60094
Nov 9 16:24:03 mail dbmail/imap4d[60094]: COMMAND: [2 CAPABILITY]
Nov 9 16:24:03 mail dbmail/imap4d[60094]: imap4.c,IMAPClientHandler:
COMMAND [CAPABILITY]
Nov 9 16:24:03 mail dbmail/imap4d[60094]: IMAPClientHandler():
Executing command capability...
Nov 9 16:24:03 mail dbmail/imap4d[60094]: IMAPClientHandler():
Finished command capability [0]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler(): line
read for PID 60094
Nov 9 16:24:04 mail dbmail/imap4d[60094]: COMMAND: [3 SELECT
INBOX/Test]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: imap4.c,IMAPClientHandler:
COMMAND [SELECT]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[0]: 'INBOX/Test'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler():
Executing command select...
Nov 9 16:24:04 mail dbmail/imap4d[60094]: db.c,db_findmailbox: looking
for mailbox with FQN [INBOX/Test].
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT mailbox_idnr FROM dbmail_mailboxes WHERE
name='INBOX/Test' AND owner_idnr='3']
Nov 9 16:24:04 mail dbmail/imap4d[60094]: db.c,db_acl_has_right:
checking ACL for user [3] on mailbox [8]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT mailbox_idnr FROM dbmail_mailboxes WHERE
mailbox_idnr = '8' AND owner_idnr = '3']
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT no_select FROM dbmail_mailboxes WHERE
mailbox_idnr = '8']
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT
permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag
,draft_flag FROM dbmail_mailboxes WHERE mailbox_idnr = '8']
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT COUNT(message_idnr), COUNT(message_idnr) -
SUM(seen_flag), SUM(recent_flag) FROM dbmail_messages WHERE
mailbox_idnr = '8' AND status < '2' ]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT message_idnr FROM dbmail_messages WHERE
mailbox_idnr = '8' AND status < '2' ORDER BY message_idnr ASC]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: db.c,db_getmailbox: exists
[336] num_rows [336]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT MAX(message_idnr)+1 FROM dbmail_messages]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT MIN(message_idnr) FROM dbmail_messages WHERE
mailbox_idnr = '8' AND status < '2' AND seen_flag = '0']
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler():
Finished command select [0]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT
permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag
,draft_flag FROM dbmail_mailboxes WHERE mailbox_idnr = '8']
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT COUNT(message_idnr), COUNT(message_idnr) -
SUM(seen_flag), SUM(recent_flag) FROM dbmail_messages WHERE
mailbox_idnr = '8' AND status < '2' ]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT message_idnr FROM dbmail_messages WHERE
mailbox_idnr = '8' AND status < '2' ORDER BY message_idnr ASC]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: db.c,db_getmailbox: exists
[336] num_rows [336]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT MAX(message_idnr)+1 FROM dbmail_messages]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler(): line
read for PID 60094
Nov 9 16:24:04 mail dbmail/imap4d[60094]: COMMAND: [4 UID FETCH 1951:*
(UID FLAGS)]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: imap4.c,IMAPClientHandler:
COMMAND [UID]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[0]: 'FETCH'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[1]: '1951:*'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[2]: '('
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[3]: 'UID'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[4]: 'FLAGS'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[5]: ')'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler():
Executing command uid...
Nov 9 16:24:04 mail dbmail/imap4d[60094]: db.c,db_acl_has_right:
checking ACL for user [3] on mailbox [8]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT mailbox_idnr FROM dbmail_mailboxes WHERE
mailbox_idnr = '8' AND owner_idnr = '3']
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: parse
args[2] = [UID]
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: args[idx =
2] = UID (returning 3)
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: parse
args[3] = [FLAGS]
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: args[idx =
3] = FLAGS (returning 4)
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: parse
args[4] = [)]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: imapcommands.c,_ic_fetch:
hdrparse_needed [0]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: imapcommands.c,_ic_fetch:
msgparse_needed [0]
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_get_unparsed: no parsing
at all
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_free_result:
trying to free a result set that is already NULL!
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT seen_flag, answered_flag, deleted_flag,
flagged_flag, draft_flag, recent_flag, TO_CHAR(internal_date,
'YYYY-MM-DD HH24:MI:SS' ), rfcsize, message_idnr FROM dbmail_messages
msg, dbmail_physmessage pm WHERE pm.id = msg.physmessage_id AND
message_idnr BETWEEN '1951' AND '2200' AND mailbox_idnr = '8' AND
status < '2' ORDER BY message_idnr ASC]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler():
Finished command uid [0]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler(): line
read for PID 60094
Nov 9 16:24:04 mail dbmail/imap4d[60094]: COMMAND: [5 UID FETCH 1:1950
(UID FLAGS)]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: imap4.c,IMAPClientHandler:
COMMAND [UID]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[0]: 'FETCH'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[1]: '1:1950'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[2]: '('
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[3]: 'UID'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[4]: 'FLAGS'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[5]: ')'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler():
Executing command uid...
Nov 9 16:24:04 mail dbmail/imap4d[60094]: db.c,db_acl_has_right:
checking ACL for user [3] on mailbox [8]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT mailbox_idnr FROM dbmail_mailboxes WHERE
mailbox_idnr = '8' AND owner_idnr = '3']
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: parse
args[2] = [UID]
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: args[idx =
2] = UID (returning 3)
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: parse
args[3] = [FLAGS]
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: args[idx =
3] = FLAGS (returning 4)
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: parse
args[4] = [)]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: imapcommands.c,_ic_fetch:
hdrparse_needed [0]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: imapcommands.c,_ic_fetch:
msgparse_needed [0]
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_get_unparsed: no parsing
at all
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_free_result:
trying to free a result set that is already NULL!
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT seen_flag, answered_flag, deleted_flag,
flagged_flag, draft_flag, recent_flag, TO_CHAR(internal_date,
'YYYY-MM-DD HH24:MI:SS' ), rfcsize, message_idnr FROM dbmail_messages
msg, dbmail_physmessage pm WHERE pm.id = msg.physmessage_id AND
message_idnr BETWEEN '1' AND '1950' AND mailbox_idnr = '8' AND status <
'2' ORDER BY message_idnr ASC]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: _ic_fetch(): no parsing,
into fetch loop
Nov 9 16:24:04 mail last message repeated 335 times
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler():
Finished command uid [0]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler(): line
read for PID 60094
Nov 9 16:24:04 mail dbmail/imap4d[60094]: COMMAND: [6 UID FETCH 1811
BODYSTRUCTURE]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: imap4.c,IMAPClientHandler:
COMMAND [UID]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[0]: 'FETCH'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[1]: '1811'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: arg[2]: 'BODYSTRUCTURE'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: IMAPClientHandler():
Executing command uid...
Nov 9 16:24:04 mail dbmail/imap4d[60094]: db.c,db_acl_has_right:
checking ACL for user [3] on mailbox [8]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT mailbox_idnr FROM dbmail_mailboxes WHERE
mailbox_idnr = '8' AND owner_idnr = '3']
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: parse
args[1] = [BODYSTRUCTURE]
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,dbmail_imap_session_fetch_parse_args: args[idx =
1] = BODYSTRUCTURE (returning 2)
Nov 9 16:24:04 mail dbmail/imap4d[60094]: imapcommands.c,_ic_fetch:
hdrparse_needed [0]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: imapcommands.c,_ic_fetch:
msgparse_needed [1]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: Fetching msgID 1811 (fetch
num 1812)
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbpgsql.c,db_query:
executing query [SELECT block.messageblk FROM dbmail_messageblks block,
dbmail_messages msg WHERE block.physmessage_id = msg.physmessage_id AND
msg.message_idnr = '1811' ORDER BY block.messageblk_idnr]
Nov 9 16:24:04 mail dbmail/imap4d[60094]: db_start_msg(): starting,
stopbound: '<null>'
Nov 9 16:24:04 mail dbmail/imap4d[60094]: mime_readheader(): entering
mime loop
Nov 9 16:24:04 mail dbmail/imap4d[60094]: mime_readheader(): found
double newline; header size: 31 lines
Nov 9 16:24:04 mail dbmail/imap4d[60094]: db_start_msg(): found
singlepart msg
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbmsgbuf.c,db_update_msgbuf:
update msgbuf_buf updating 131072, 39712, 38147, 38147
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbmsgbuf.c,db_update_msgbuf:
update msgbuf: entire fit
Nov 9 16:24:04 mail dbmail/imap4d[60094]: dbmsgbuf.c,db_update_msgbuf
update msgbuf succes NOMORE
Nov 9 16:24:04 mail dbmail/imap4d[60094]: db_start_msg(): exit
Nov 9 16:24:04 mail dbmail/imap4d[60094]:
dbmail-imapsession.c,_imap_get_structure
Nov 9 16:24:04 mail dbmail/imap4d[60093]: ParentSigHandler(): got
signal [20]
Nov 9 16:24:04 mail dbmail/imap4d[60093]:
pool.c,manage_restart_children: child [60094] exited. Restarting...
--
Sean Chittenden
