A BUGNOTE has been added to this bug. ====================================================================== http://www.dbmail.org/mantis/bug_view_advanced_page.php?bug_id=0000156 ====================================================================== Reported By: xing Assigned To: ====================================================================== Project: DBMail Bug ID: 156 Category: IMAP daemon Reproducibility: always Severity: feature Priority: normal Status: new ====================================================================== Date Submitted: 11-Jan-05 09:27 CET Last Modified: 11-Jan-05 12:57 CET ====================================================================== Summary: imapd terminates/restarts child at authentication stage... Description: Here is the following log from Thunderbird when trying to check mail.
Jan 11 00:17:30 dell3 dbmail/imap4d[23917]: CreateSocket(): socket IP requested [*] OK Jan 11 00:17:30 dell3 dbmail/imap4d[23917]: CreateSocket(): IP bound to socket Jan 11 00:17:30 dell3 dbmail/imap4d[23917]: CreateSocket(): socket creation complete Jan 11 00:17:30 dell3 dbmail/imap4d[23918]: StartServer(): init Jan 11 00:17:30 dell3 dbmail/imap4d[23918]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:17:30 dell3 dbmail/imap4d[23918]: StartServer(): init ok. Creating children.. Jan 11 00:17:30 dell3 dbmail/imap4d[23919]: pool.c,child_register: register child [23919] Jan 11 00:17:30 dell3 dbmail/imap4d[23919]: pool.c,child_register: initializing child_state [23919] using slot [0] Jan 11 00:17:30 dell3 dbmail/imap4d[23919]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:17:30 dell3 dbmail/imap4d[23919]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:17:30 dell3 dbmail/imap4d[23919]: PerformChildTask(): waiting for connection Jan 11 00:17:30 dell3 dbmail/imap4d[23919]: pool.c,child_reg_disconnected: [23919] Jan 11 00:17:30 dell3 dbmail/imap4d[23920]: pool.c,child_register: register child [23920] Jan 11 00:17:30 dell3 dbmail/imap4d[23920]: pool.c,child_register: initializing child_state [23920] using slot [1] Jan 11 00:17:30 dell3 dbmail/imap4d[23920]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:17:30 dell3 dbmail/imap4d[23920]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:17:30 dell3 dbmail/imap4d[23920]: PerformChildTask(): waiting for connection Jan 11 00:17:30 dell3 dbmail/imap4d[23920]: pool.c,child_reg_disconnected: [23920] Jan 11 00:17:30 dell3 dbmail/imap4d[23921]: pool.c,child_register: register child [23921] Jan 11 00:17:30 dell3 dbmail/imap4d[23922]: pool.c,child_register: register child [23922] Jan 11 00:17:30 dell3 dbmail/imap4d[23923]: pool.c,child_register: register child [23923] Jan 11 00:17:30 dell3 dbmail/imap4d[23918]: StartServer(): children created, starting main service loop Jan 11 00:17:30 dell3 dbmail/imap4d[23921]: pool.c,child_register: initializing child_state [23921] using slot [2] Jan 11 00:17:30 dell3 dbmail/imap4d[23921]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:17:30 dell3 dbmail/imap4d[23923]: pool.c,child_register: initializing child_state [23923] using slot [4] Jan 11 00:17:30 dell3 dbmail/imap4d[23922]: pool.c,child_register: initializing child_state [23922] using slot [3] Jan 11 00:17:30 dell3 dbmail/imap4d[23922]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:17:30 dell3 dbmail/imap4d[23923]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:17:30 dell3 dbmail/imap4d[23921]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:17:30 dell3 dbmail/imap4d[23921]: PerformChildTask(): waiting for connection Jan 11 00:17:30 dell3 dbmail/imap4d[23921]: pool.c,child_reg_disconnected: [23921] Jan 11 00:17:30 dell3 dbmail/imap4d[23922]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:17:30 dell3 dbmail/imap4d[23923]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:17:30 dell3 dbmail/imap4d[23922]: PerformChildTask(): waiting for connection Jan 11 00:17:30 dell3 dbmail/imap4d[23922]: pool.c,child_reg_disconnected: [23922] Jan 11 00:17:30 dell3 dbmail/imap4d[23923]: PerformChildTask(): waiting for connection Jan 11 00:17:30 dell3 dbmail/imap4d[23923]: pool.c,child_reg_disconnected: [23923] Jan 11 00:18:21 dell3 dbmail/imap4d[23919]: pool.c,child_reg_connected: [23919] Jan 11 00:18:21 dell3 dbmail/imap4d[23919]: PerformChildTask(): incoming connection from [66.54.66.36] Jan 11 00:18:21 dell3 dbmail/imap4d[23919]: PerformChildTask(): client info init complete, calling client handler Jan 11 00:18:21 dell3 dbmail/imap4d[23919]: IMAPClientHandler(): line read for PID 23919 Jan 11 00:18:21 dell3 dbmail/imap4d[23919]: COMMAND: [1 authenticate login] Jan 11 00:18:21 dell3 dbmail/imap4d[23919]: arg[0]: 'login' Jan 11 00:18:21 dell3 dbmail/imap4d[23919]: IMAPClientHandler(): Executing command authenticate... Jan 11 00:18:21 dell3 dbmail/imap4d[23919]: serverchild.c,active_child_sig_handler: got signal [13] Jan 11 00:18:21 dell3 dbmail/imap4d[23919]: serverchild.c,active_child_sig_handler: cannot ignore this. Terminating Jan 11 00:18:21 dell3 dbmail/imap4d[23919]: pool.c,child_unregister: child [23919] unregistered Jan 11 00:18:21 dell3 dbmail/imap4d[23918]: ParentSigHandler(): got signal [17] Jan 11 00:18:21 dell3 dbmail/imap4d[23918]: pool.c,manage_restart_children: child [23919] exited. Restarting... Jan 11 00:18:21 dell3 dbmail/imap4d[23942]: pool.c,child_register: register child [23942] Jan 11 00:18:21 dell3 dbmail/imap4d[23942]: pool.c,child_register: initializing child_state [23942] using slot [0] Jan 11 00:18:21 dell3 dbmail/imap4d[23942]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:18:21 dell3 dbmail/imap4d[23942]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:18:21 dell3 dbmail/imap4d[23942]: PerformChildTask(): waiting for connection Jan 11 00:18:21 dell3 dbmail/imap4d[23942]: pool.c,child_reg_disconnected: [23942] ====================================================================== ---------------------------------------------------------------------- xing - 11-Jan-05 09:53 CET ---------------------------------------------------------------------- Same with pop3d.. Jan 11 00:35:48 dell3 dbmail/pop3d[24242]: pool.c,child_register: register child [24242] Jan 11 00:35:48 dell3 dbmail/pop3d[24243]: pool.c,child_register: register child [24243] Jan 11 00:35:48 dell3 dbmail/pop3d[24244]: pool.c,child_register: register child [24244] Jan 11 00:35:48 dell3 dbmail/pop3d[24245]: pool.c,child_register: register child [24245] Jan 11 00:35:48 dell3 dbmail/pop3d[24246]: pool.c,child_register: register child [24246] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): searching value for config item [host] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): found value [localhost] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): searching value for config item [db] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): found value [dbmail] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): searching value for config item [user] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): found value [postfix] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): searching value for config item [pass] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): found value [postfix] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): searching value for config item [sqlport] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): item not found Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): searching value for config item [sqlsocket] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: GetConfigValue(): found value [/tmp/mysql.sock] Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: CreateSocket(): socket created Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: CreateSocket(): socket IP requested [*] OK Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: CreateSocket(): IP bound to socket Jan 11 00:36:33 dell3 dbmail/pop3d[24269]: CreateSocket(): socket creation complete Jan 11 00:36:33 dell3 dbmail/pop3d[24270]: StartServer(): init Jan 11 00:36:33 dell3 dbmail/pop3d[24270]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:36:33 dell3 dbmail/pop3d[24270]: StartServer(): init ok. Creating children.. Jan 11 00:36:33 dell3 dbmail/pop3d[24270]: pool.c,scoreboard_conf_check: MAXCHILDREN too large. Decreasing to [50] Jan 11 00:36:33 dell3 dbmail/pop3d[24271]: pool.c,child_register: register child [24271] Jan 11 00:36:33 dell3 dbmail/pop3d[24271]: pool.c,child_register: initializing child_state [24271] using slot [0] Jan 11 00:36:33 dell3 dbmail/pop3d[24271]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:36:33 dell3 dbmail/pop3d[24271]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:36:33 dell3 dbmail/pop3d[24271]: PerformChildTask(): waiting for connection Jan 11 00:36:33 dell3 dbmail/pop3d[24271]: pool.c,child_reg_disconnected: [24271] Jan 11 00:36:33 dell3 dbmail/pop3d[24272]: pool.c,child_register: register child [24272] Jan 11 00:36:33 dell3 dbmail/pop3d[24272]: pool.c,child_register: initializing child_state [24272] using slot [1] Jan 11 00:36:33 dell3 dbmail/pop3d[24272]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:36:33 dell3 dbmail/pop3d[24272]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:36:33 dell3 dbmail/pop3d[24272]: PerformChildTask(): waiting for connection Jan 11 00:36:33 dell3 dbmail/pop3d[24272]: pool.c,child_reg_disconnected: [24272] Jan 11 00:36:33 dell3 dbmail/pop3d[24273]: pool.c,child_register: register child [24273] Jan 11 00:36:33 dell3 dbmail/pop3d[24274]: pool.c,child_register: register child [24274] Jan 11 00:36:33 dell3 dbmail/pop3d[24275]: pool.c,child_register: register child [24275] Jan 11 00:36:33 dell3 dbmail/pop3d[24270]: StartServer(): children created, starting main service loop Jan 11 00:36:33 dell3 dbmail/pop3d[24273]: pool.c,child_register: initializing child_state [24273] using slot [2] Jan 11 00:36:33 dell3 dbmail/pop3d[24273]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:36:33 dell3 dbmail/pop3d[24275]: pool.c,child_register: initializing child_state [24275] using slot [4] Jan 11 00:36:33 dell3 dbmail/pop3d[24274]: pool.c,child_register: initializing child_state [24274] using slot [3] Jan 11 00:36:33 dell3 dbmail/pop3d[24274]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:36:33 dell3 dbmail/pop3d[24273]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:36:33 dell3 dbmail/pop3d[24273]: PerformChildTask(): waiting for connection Jan 11 00:36:33 dell3 dbmail/pop3d[24274]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:36:33 dell3 dbmail/pop3d[24273]: pool.c,child_reg_disconnected: [24273] Jan 11 00:36:33 dell3 dbmail/pop3d[24275]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:36:33 dell3 dbmail/pop3d[24274]: PerformChildTask(): waiting for connection Jan 11 00:36:33 dell3 dbmail/pop3d[24275]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:36:33 dell3 dbmail/pop3d[24274]: pool.c,child_reg_disconnected: [24274] Jan 11 00:36:33 dell3 dbmail/pop3d[24275]: PerformChildTask(): waiting for connection Jan 11 00:36:33 dell3 dbmail/pop3d[24275]: pool.c,child_reg_disconnected: [24275] Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: pool.c,child_reg_connected: [24271] Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: PerformChildTask(): incoming connection from [66.54.66.36] Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: PerformChildTask(): client info init complete, calling client handler Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: misc.c,create_unique_id: created: 59a7bba42500a05c1ea915451ea0f310 Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: pop3(): incoming buffer: [CAPA] Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: pop3(): command looked up as commandtype 14 Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: pop3(): incoming buffer: [USER [EMAIL PROTECTED] Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: pop3(): command issued :cmd [USER], value [EMAIL PROTECTED] Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: pop3(): command looked up as commandtype 1 Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: pop3(): incoming buffer: [PASS _password_very_safe_yes_yes] Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: pop3(): command issued :cmd [PASS], value [_password_very_safe_yes_yes] Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: pop3(): command looked up as commandtype 2 Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: serverchild.c,active_child_sig_handler: got signal [13] Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: serverchild.c,active_child_sig_handler: cannot ignore this. Terminating Jan 11 00:36:47 dell3 dbmail/pop3d[24271]: pool.c,child_unregister: child [24271] unregistered Jan 11 00:36:47 dell3 dbmail/pop3d[24270]: ParentSigHandler(): got signal [17] Jan 11 00:36:47 dell3 dbmail/pop3d[24270]: pool.c,manage_restart_children: child [24271] exited. Restarting... Jan 11 00:36:47 dell3 dbmail/pop3d[24280]: pool.c,child_register: register child [24280] Jan 11 00:36:47 dell3 dbmail/pop3d[24280]: pool.c,child_register: initializing child_state [24280] using slot [0] Jan 11 00:36:47 dell3 dbmail/pop3d[24280]: serverchild.c,CreateChild: signal handler placed, going to perform task now Jan 11 00:36:47 dell3 dbmail/pop3d[24280]: dbmysql.c,db_query: executing query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0] Jan 11 00:36:47 dell3 dbmail/pop3d[24280]: PerformChildTask(): waiting for connection Jan 11 00:36:47 dell3 dbmail/pop3d[24280]: pool.c,child_reg_disconnected: [24280] uname -r 2.6.8.1-4-686-smp If no one else is having issues, it just might be my distro: Ubuntu 4.10 "The Warty Warthog". Unfortunately their apt-get binary packages only has dbmail 1.2.8b so dbmail 2.0.2 is the first program I have ever tried compile on Ubuntu. edited on: 11-Jan-05 09:53 edited on: 11-Jan-05 09:53 ---------------------------------------------------------------------- aaron - 11-Jan-05 09:46 CET ---------------------------------------------------------------------- > xing feared: > Aaron, please don't kill me for filling out all the bug notes tonight. =) This should be a real error. At least on my end. =) Not at all! I'm actually really happy to see all the bugs tonight, well, except for this one :-P The other bugs have been the little polishing and detail problems that nobody pays attention to unless the program itself is solid ;-) Incidentally, signal 13 is SIGPIPE. I have no idea why that might be happening, but hopefully Paul will be waking up soon! ---------------------------------------------------------------------- paul - 11-Jan-05 10:35 CET ---------------------------------------------------------------------- Xing, Could you please test the debian packages I provide. See if they work. add: deb http://debian.nfgd.net/debian unstable main to you sources.list in /etc/apt/ But please make sure you (re)move all currently homebrew binaries and libs first. I'm not at all sure these will work out-of-the box on Ubuntu, but they may very well do so. Btw, someone reported occasional sigpipes with thunderbird last weekend. And he was on Gentoo. So I don't think it's Ubuntu related. But it might be something with the optimizition flag during compilation. Could you past a couple of lines from you 'make' output? ---------------------------------------------------------------------- xing - 11-Jan-05 12:57 CET ---------------------------------------------------------------------- Paul, Your prebuilt binaries generated the exact same errors. I did "locate dbmail" before the install to make sure my compiled dbmail libs and bins have all been removed. I tried to change apt to use deb-src to see if I can get it to grab source and compile and I couldn't even get it to download. I have only used Synaptic, Ubuntu's apt GUI so really have no clue how to get apt to grab source, compile, and install. Need a little hand holding here. Bug History Date Modified Username Field Change ====================================================================== 11-Jan-05 09:27xing New Bug 11-Jan-05 09:40xing Bugnote Added: 0000508 11-Jan-05 09:46aaron Bugnote Added: 0000509 11-Jan-05 09:53xing Bugnote Edited: 0000508 11-Jan-05 09:53xing Bugnote Edited: 0000508 11-Jan-05 10:35paul Bugnote Added: 0000510 11-Jan-05 12:57xing Bugnote Added: 0000511 ======================================================================
