Attached are logs from the problem I am encountering. It looks like fetchmail is doing something that provokes the dbmail imap daemon to send portions of its active log to the imap client, instead of an email. I'm afraid that this might have the potential to cause damage in a production environment.
This is a dbmail 1.1 system on OpenBSD 3.3. Fetchmail: release 6.1.0+NTLM+SSL+INET6 on OpenBSD 3.2 Anyways, let me know if any of you would like further testing/information. -chuck -------------------- [EMAIL PROTECTED] http://www.lemure.net all that tasty chuck fun, without the nasty aftertaste
fetchmail_problems.txt
Description: Binary data
Script started on Thu Jul 3 06:42:40 2003 # tail -f /var/log/maillog Jul 3 06:43:00 zebu dbmail/imap4d[25812]: PerformChildTask(): incoming connection from [66.92.73.103 (dns.lemure.net)] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: PerformChildTask(): client info init complete, calling client handler Jul 3 06:43:00 zebu dbmail/imap4d[25812]: IMAPClientHandler(): line read for PID 25812 Jul 3 06:43:00 zebu dbmail/imap4d[25812]: COMMAND: [A0001 CAPABILITY] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Executing command capability... Jul 3 06:43:00 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Finished command capability Jul 3 06:43:00 zebu dbmail/imap4d[25812]: IMAPClientHandler(): line read for PID 25812 Jul 3 06:43:00 zebu dbmail/imap4d[25812]: COMMAND: [A0002 LOGIN "chuck" "chuckchuck"] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: arg[0]: 'chuck' Jul 3 06:43:00 zebu dbmail/imap4d[25812]: arg[1]: 'chuckchuck' Jul 3 06:43:00 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Executing command login... Jul 3 06:43:00 zebu dbmail/imap4d[25812]: _ic_login(): trying to validate user Jul 3 06:43:00 zebu dbmail/imap4d[25812]: __auth_query(): executing query [SELECT user_idnr, passwd, encryption_type FROM users WHERE userid = 'chuck'] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: auth_validate(): validating using crypt() encryption Jul 3 06:43:00 zebu dbmail/imap4d[25812]: __auth_query(): executing query [UPDATE users SET last_login = '2003-07-03 06:43:00' WHERE user_idnr = 6] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: _ic_login(): user (id:6, name chuck) tries login\^M Jul 3 06:43:00 zebu dbmail/imap4d[25812]: _ic_login(): user (id 6, name chuck) login accepted @ 2003-07-03 06:43:00\^M Jul 3 06:43:00 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Finished command login Jul 3 06:43:00 zebu dbmail/imap4d[25812]: IMAPClientHandler(): line read for PID 25812 Jul 3 06:43:00 zebu dbmail/imap4d[25812]: COMMAND: [A0003 SELECT "INBOX"] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: arg[0]: 'INBOX' Jul 3 06:43:00 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Executing command select... Jul 3 06:43:00 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT mailbox_idnr FROM mailboxes WHERE name='INBOX' AND owner_idnr=6] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT no_select FROM mailboxes WHERE mailbox_idnr = 20] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag,draft_flag FROM mailboxes WHERE mailbox_idnr = 20] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr, seen_flag, recent_flag FROM messages WHERE mailbox_idnr = 20 AND status<2 AND unique_id!="" ORDER BY message_idnr ASC] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE unique_id!=""ORDER BY message_idnr DESC LIMIT 0,1] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE mailbox_idnr = 20 AND status<2 AND seen_flag = 0 AND unique_id != "" ORDER BY message_idnr ASC LIMIT 0,1] Jul 3 06:43:00 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Finished command select Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): line read for PID 25812 Jul 3 06:43:01 zebu dbmail/imap4d[25812]: COMMAND: [A0004 EXPUNGE] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Executing command expunge... Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag,draft_flag FROM mailboxes WHERE mailbox_idnr = 20] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr, seen_flag, recent_flag FROM messages WHERE mailbox_idnr = 20 AND status<2 AND unique_id!="" ORDER BY message_idnr ASC] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE unique_id!=""ORDER BY message_idnr DESC LIMIT 0,1] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE mailbox_idnr = 20 AND deleted_flag=1 AND status<2 ORDER BY message_idnr DESC] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [UPDATE messages SET status=2 WHERE mailbox_idnr = 20 AND deleted_flag=1 AND status<2] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag,draft_flag FROM mailboxes WHERE mailbox_idnr = 20] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr, seen_flag, recent_flag FROM messages WHERE mailbox_idnr = 20 AND status<2 AND unique_id!="" ORDER BY message_idnr ASC] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE unique_id!=""ORDER BY message_idnr DESC LIMIT 0,1] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Finished command expunge Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): line read for PID 25812 Jul 3 06:43:01 zebu dbmail/imap4d[25812]: COMMAND: [A0005 SEARCH UNSEEN] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: arg[0]: 'UNSEEN' Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Executing command search... Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag,draft_flag FROM mailboxes WHERE mailbox_idnr = 20] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr, seen_flag, recent_flag FROM messages WHERE mailbox_idnr = 20 AND status<2 AND unique_id!="" ORDER BY message_idnr ASC] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE unique_id!=""ORDER BY message_idnr DESC LIMIT 0,1] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag,draft_flag FROM mailboxes WHERE mailbox_idnr = 20] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr, seen_flag, recent_flag FROM messages WHERE mailbox_idnr = 20 AND status<2 AND unique_id!="" ORDER BY message_idnr ASC] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE unique_id!=""ORDER BY message_idnr DESC LIMIT 0,1] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE mailbox_idnr = 20 AND status<2 AND unique_id!="" AND seen_flag=0] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Finished command search Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): line read for PID 25812 Jul 3 06:43:01 zebu dbmail/imap4d[25812]: COMMAND: [A0006 FETCH 1:2 RFC822.SIZE] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: arg[0]: '1:2' Jul 3 06:43:01 zebu dbmail/imap4d[25812]: arg[1]: 'RFC822.SIZE' Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Executing command fetch... Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag,draft_flag FROM mailboxes WHERE mailbox_idnr = 20] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr, seen_flag, recent_flag FROM messages WHERE mailbox_idnr = 20 AND status<2 AND unique_id!="" ORDER BY message_idnr ASC] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE unique_id!=""ORDER BY message_idnr DESC LIMIT 0,1] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: next_fetch_item(): args[idx = 1] = RFC822.SIZE (returning 2) Jul 3 06:43:01 zebu dbmail/imap4d[25812]: ic_fetch(): no parsing at all Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT seen_flag, answered_flag, deleted_flag, flagged_flag, draft_flag, recent_flag, internal_date, rfcsize, message_idnr FROM messages WHERE message_idnr >= 53 AND message_idnr <= 227 AND mailbox_idnr = 20 AND status<2 AND unique_id != '' ORDER BY message_idnr ASC] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: _ic_fetch(): no parsing, into fetch loop Jul 3 06:43:01 zebu dbmail/imap4d[25812]: _ic_fetch(): no parsing, into fetch loop Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Finished command fetch Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): line read for PID 25812 Jul 3 06:43:01 zebu dbmail/imap4d[25812]: COMMAND: [A0007 FETCH 1 RFC822.HEADER] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: arg[0]: '1' Jul 3 06:43:01 zebu dbmail/imap4d[25812]: arg[1]: 'RFC822.HEADER' Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Executing command fetch... Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag,draft_flag FROM mailboxes WHERE mailbox_idnr = 20] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr, seen_flag, recent_flag FROM messages WHERE mailbox_idnr = 20 AND status<2 AND unique_id!="" ORDER BY message_idnr ASC] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE unique_id!=""ORDER BY message_idnr DESC LIMIT 0,1] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: next_fetch_item(): args[idx = 1] = RFC822.HEADER (returning 2) Jul 3 06:43:01 zebu dbmail/imap4d[25812]: Fetching msgID 53 (fetch num 1)\^M Jul 3 06:43:01 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT messageblk FROM messageblks WHERE message_idnr = 53 ORDER BY messageblk_idnr LIMIT 1] Jul 3 06:43:01 zebu dbmail/imap4d[25812]: mime_readheader(): entering mime loop Jul 3 06:43:01 zebu dbmail/imap4d[25812]: mime_readheader(): mimeloop finished Jul 3 06:43:01 zebu dbmail/imap4d[25812]: *** mime_readheader() done *** Jul 3 06:43:01 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Finished command fetch Jul 3 06:43:02 zebu dbmail/imap4d[25812]: IMAPClientHandler(): line read for PID 25812 Jul 3 06:43:02 zebu dbmail/imap4d[25812]: COMMAND: [A0008 FETCH 1 BODY.PEEK[TEXT]] Jul 3 06:43:02 zebu dbmail/imap4d[25812]: arg[0]: '1' Jul 3 06:43:02 zebu dbmail/imap4d[25812]: arg[1]: 'BODY.PEEK' Jul 3 06:43:02 zebu dbmail/imap4d[25812]: arg[2]: '[' Jul 3 06:43:02 zebu dbmail/imap4d[25812]: arg[3]: 'TEXT' Jul 3 06:43:02 zebu dbmail/imap4d[25812]: arg[4]: ']' Jul 3 06:43:02 zebu dbmail/imap4d[25812]: IMAPClientHandler(): Executing command fetch... Jul 3 06:43:02 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag,draft_flag FROM mailboxes WHERE mailbox_idnr = 20] Jul 3 06:43:02 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr, seen_flag, recent_flag FROM messages WHERE mailbox_idnr = 20 AND status<2 AND unique_id!="" ORDER BY message_idnr ASC] Jul 3 06:43:02 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT message_idnr FROM messages WHERE unique_id!=""ORDER BY message_idnr DESC LIMIT 0,1] Jul 3 06:43:02 zebu dbmail/imap4d[25812]: next_fetch_item(): args[idx = 4] = ] (returning 5) Jul 3 06:43:02 zebu dbmail/imap4d[25812]: Fetching msgID 53 (fetch num 1)\^M Jul 3 06:43:02 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT messageblk FROM messageblks WHERE message_idnr = 53 ORDER BY messageblk_idnr] Jul 3 06:43:02 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT messageblk FROM messageblks WHERE message_idnr = 53 ORDER BY messageblk_idnr] Jul 3 06:43:02 zebu dbmail/imap4d[25812]: db_start_msg(): starting, stopbound: '(null)' Jul 3 06:43:02 zebu dbmail/imap4d[25812]: mime_readheader(): entering mime loop Jul 3 06:43:02 zebu dbmail/imap4d[25812]: mime_readheader(): mimeloop finished Jul 3 06:43:02 zebu dbmail/imap4d[25812]: *** mime_readheader() done *** Jul 3 06:43:02 zebu dbmail/imap4d[25812]: db_start_msg(): found singlepart msg Jul 3 06:43:02 zebu dbmail/imap4d[25812]: db_start_msg(): exit Jul 3 06:43:02 zebu dbmail/imap4d[25812]: Dumping range: (1,0) - (1,0) Jul 3 06:43:02 zebu dbmail/imap4d[25812]: db_query(): executing [SELECT messageblk FROM messageblks WHERE message_idnr = 53 ORDER BY messageblk_idnr] Jul 3 06:43:02 zebu dbmail/imap4d[25812]: db_dump_range(): bad range specified Jul 3 06:43:02 zebu dbmail/imap4d[25812]: ChildSighandler(): got signal [11] Jul 3 06:43:02 zebu dbmail/imap4d[25812]: ChildSighandler(): cannot ignore this. Terminating Jul 3 06:43:02 zebu dbmail/imap4d[30705]: ParentSigHandler(): got signal [20] Jul 3 06:43:02 zebu dbmail/imap4d[30705]: StartServer(): child [25812] has exited Jul 3 06:43:02 zebu dbmail/imap4d[30705]: StartServer(): creating new child Jul 3 06:43:02 zebu dbmail/imap4d[20288]: CreateChild(): signal handler placed, going to perform task now Jul 3 06:43:02 zebu dbmail/imap4d[20288]: PerformChildTask(): waiting for connection ^C # Script done on Thu Jul 3 06:43:09 2003
