Am 02.02.2012 20:32, schrieb Paul J Stevens: > On 02/02/2012 07:57 PM, Reindl Harald wrote: >> Am 02.02.2012 19:33, schrieb Paul J Stevens: >>> On 02/02/2012 02:32 PM, Reindl Harald wrote: >>> >>>> SELECT DISTINCT m.message_idnr FROM dbmail_mimeparts k LEFT JOIN >>>> dbmail_partlists l ON k.id=l.part_id LEFT JOIN >>>> dbmail_physmessage p ON l.physmessage_id=p.id LEFT JOIN dbmail_header h ON >>>> h.physmessage_id=p.id LEFT JOIN >>>> dbmail_headervalue v ON h.headervalue_id=v.id LEFT JOIN dbmail_messages m >>>> ON m.physmessage_id=p.id WHERE >>>> m.mailbox_idnr = 1406 AND m.status IN (0,1) AND v.headervalue LIKE >>>> '%charsettest%' OR k.data LIKE BINARY >>>> '%charsettest%' ORDER BY m.message_idnr; >>> >>> Dude, that's a full mailbody search. Horde does that just to login??? I >>> can't believe that. >> >> repeatable, everytime i touch a login with horde3 >> using IMAP as authentication this happens > > I really need to see the IMAP commands leading up to this query. I don't > like guessing.
no problem, as developer i understand this well attached the whole maillog from login on horde3 until dovecot confirms the succesfull login but never responds to horde and only my password is obfuscated at this momemt the query above is running, the hard-disk is screaming and "show processlist" displays "Copying to tmp table" for some minutes, horde will never respond and stay on the login-screen in the scenario where i saw this first horde was running on another machine connecting to our "backup-dbmail" and from the moment on i revert to the dbmail-2.2 snapshot all is fine, reproduceable as often i do it many thanks for your time and work Harry
Feb 2 20:58:16 dbmail-test dovecot: auth-worker:
mysql(/var/lib/mysql/mysql.sock): Connected to database dbmail
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[server]
_sock_cb(+468): 15 2, 0x1301980, ssl:N
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[server]
_sock_cb(+508): connection accepted
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
client_init(+162): saddr [0x11f6640] sa_family [2] len [16]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280]
Notice:[clientbase] client_init(+167): incoming connection on [127.0.0.1:143]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
client_init(+170): caddr [0x11f65b0] sa_family [2] len [16]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280]
Notice:[clientbase] client_init(+185): incoming connection from
[127.0.0.1:24385]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[CAPA]
Capa_new(+65): key "capability" section "IMAP" var val value [IMAP4 IMAP4rev1
AUTH=LOGIN ACL RIGHTS=texk NAMESPACE CHILDREN SORT QUOTA
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[CAPA]
Capa_new(+65): key "capability" section "IMAP" var val value [IMAP4 IMAP4rev1
AUTH=LOGIN ACL RIGHTS=texk NAMESPACE CHILDREN SORT QUOTA
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_new(+121): imap session [0x1303730] created
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_set_state(+1647): state [1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_set_state(+1676): [0x1303730] state [0]->[1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x7f3c38b03010]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
send_greeting(+217): key "banner" section "IMAP" var banner value [IMAP]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_write(+312): [0x7f3c38b03010] S > [54/54:* OK [CAPABILITY IMAP4 IMAP4rev1
AUTH=LOGIN ID] IMAP#015#012]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_set_state(+1647): state [1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
socket_read_cb(+176): [0x1303730]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_read_cb(+342): [0x7f3c38b03010] reset timeout [60]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_cb_read(+155): reading 1: 208/0
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_readln(+423): [0x7f3c38b03010] C < [124:I ID ("x-originating-ip"
"10.0.0.111" "x-originating-port" "45615" "x-connected-ip" "10.0.0.11
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[imap]
imap4_tokenizer(+495): [0x1303730] COMMAND: [I ID ("x-originating-ip"
"10.0.0.111" "x-originating-port" "45615" "x-connected-ip" "10.0.0.111" "
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+1919): [0x1303730] tokenize [120/0] [ ("x-originating-ip"
"10.0.0.111" "x-originating-port" "45615" "x-connected-ip" "10.0.0.11
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2116): [0x1303730] tag: [I], command: [ID], [10] args
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[0]: '('
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[1]: 'x-originating-ip'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[2]: '10.0.0.111'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[3]: 'x-originating-port'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[4]: '45615'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[5]: 'x-connected-ip'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[6]: '10.0.0.111'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[7]: 'x-connected-port'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[8]: '993'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[9]: ')'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer(+538): parser_state: [1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Database:[db]
db_con_get(+256): [0x11f6320] connection from pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Database:[db]
db_con_close(+274): [0x11f6320] connection to pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[0]: '('
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[1]: 'x-originating-ip'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[2]: '10.0.0.111'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[3]: 'x-originating-port'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[4]: '45615'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[5]: 'x-connected-ip'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[6]: '10.0.0.111'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[7]: 'x-connected-port'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[8]: '993'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[9]: ')'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[imap]
imap4(+626): dispatch [id]...
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_cork(+205): [0x7f3c38b03010]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[server]
dm_thread_data_push(+118): [0x7f3c38ac2010] [0x1303730]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c9a0] Debug:[server]
dm_thread_dispatch(+156): data[0x7f3c38ac2010], user_data[(nil)]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_handle_input(+396): imap4 returned [0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
_ic_cb_leave(+547): handling imap session [0x1303730]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x7f3c38b03010]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_handle_exit(+262): [0x1303730] state [1] command_status [1] [ID] returned
with status [0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_write(+312): [0x7f3c38b03010] S > [113/113:* ID ("name" "dbmail" "version"
"3.0.0" "os" "Linux" "os-version" "2.6.41.10-3.fc15.x86_64
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_reset(+454): [0x1303730]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x7f3c38b03010]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_readln(+423): [0x7f3c38b03010] C < [14:C CAPABILITY#015#012]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[imap]
imap4_tokenizer(+495): [0x1303730] COMMAND: [C CAPABILITY#015#012]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2116): [0x1303730] tag: [C], command: [CAPABILITY], [0]
args
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer(+538): parser_state: [1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Database:[db]
db_con_get(+256): [0x11f6320] connection from pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Database:[db]
db_con_close(+274): [0x11f6320] connection to pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[imap]
imap4(+626): dispatch [capability]...
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_cork(+205): [0x7f3c38b03010]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[server]
dm_thread_data_push(+118): [0x7f3c38a81010] [0x1303730]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c7e0] Debug:[server]
dm_thread_dispatch(+156): data[0x7f3c38a81010], user_data[(nil)]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_handle_input(+396): imap4 returned [0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
_ic_cb_leave(+547): handling imap session [0x1303730]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x7f3c38b03010]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_handle_exit(+262): [0x1303730] state [1] command_status [1] [CAPABILITY]
returned with status [0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_write(+312): [0x7f3c38b03010] S > [153/153:* CAPABILITY IMAP4 IMAP4rev1
AUTH=LOGIN ACL RIGHTS=texk NAMESPACE CHILDREN SORT QUOTA THRE
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_reset(+454): [0x1303730]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x7f3c38b03010]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_readln(+423): [0x7f3c38b03010] C < [70:L LOGIN "[email protected]"
"*********************************"#015#012]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[imap]
imap4_tokenizer(+495): [0x1303730] COMMAND: [L LOGIN "[email protected]"
"*********************************"#015#012]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+1919): [0x1303730] tokenize [63/0] [
"[email protected]" "*********************************"#015#012]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2116): [0x1303730] tag: [L], command: [LOGIN], [2] args
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[0]: '[email protected]'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x1303730] arg[1]:
'*********************************'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer(+538): parser_state: [1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Database:[db]
db_con_get(+256): [0x11f6320] connection from pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Database:[db]
db_con_close(+274): [0x11f6320] connection to pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[0]: '[email protected]'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x1303730] arg[1]:
'*********************************'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[imap]
imap4(+626): dispatch [login]...
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_cork(+205): [0x7f3c38b03010]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[server]
dm_thread_data_push(+118): [0x1305820] [0x1303730]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Debug:[server]
dm_thread_dispatch(+156): data[0x1305820], user_data[(nil)]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_con_get(+256): [0x11f6320] connection from pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_query(+344): [0x11f6320] [SELECT 1=1 FROM dbmail_usermap LIMIT 1 OFFSET 0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_handle_input(+396): imap4 returned [0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
log_query_time(+290): last query took [0.002] seconds
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_con_close(+274): [0x11f6320] connection to pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Debug:[db]
db_use_usermap(+845): enabling usermap lookups
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Debug:[db]
db_usermap_resolve(+3107): checking userid [[email protected]] in usermap
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Debug:[db]
db_usermap_resolve(+3113): client on inet socket [inet:127.0.0.1:143]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_con_get(+256): [0x11f6320] connection from pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_stmt_prepare(+400): [0x11f6320] [SELECT login, sock_allow, sock_deny, userid
FROM dbmail_usermap WHERE login in (?,'ANY') ORDER BY sock_allo
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_stmt_set_str(+408): [0x7f3c18002e40] 1:[[email protected]]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_con_close(+274): [0x11f6320] connection to pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Debug:[db]
db_usermap_resolve(+3159): login [[email protected]] not found in usermap
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_con_get(+256): [0x11f6320] connection from pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_stmt_prepare(+400): [0x11f6320] [SELECT user_idnr FROM dbmail_users WHERE
lower(userid) = lower(?)]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_stmt_set_str(+408): [0x7f3c18002e50] 1:[[email protected]]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_con_close(+274): [0x11f6320] connection to pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_con_get(+256): [0x11f6320] connection from pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_query(+344): [0x11f6320] [SELECT passwd, encryption_type FROM dbmail_users
WHERE user_idnr = 155]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
log_query_time(+290): last query took [0.000] seconds
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_con_close(+274): [0x11f6320] connection to pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Debug:[auth]
auth_validate(+361): validating using plaintext passwords
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_con_get(+256): [0x11f6320] connection from pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_begin_transaction(+514): BEGIN
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_exec(+312): [0x11f6320] [UPDATE dbmail_users SET last_login = '2012-02-02
20:58:16' WHERE user_idnr = 155]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
log_query_time(+290): last query took [0.001] seconds
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_commit_transaction(+521): COMMIT
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Database:[db]
db_con_close(+274): [0x11f6320] connection to pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Debug:[imap]
dbmail_imap_session_handle_auth(+1217): [0x1303730] trying to validate user
[[email protected]]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Notice:[imap]
dbmail_imap_session_handle_auth(+1246): [0x1303730] login accepted: user
[[email protected]] from [127.0.0.1:24385]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Debug:[imap]
dbmail_imap_session_set_state(+1647): state [2]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Debug:[imap]
dbmail_imap_session_set_state(+1665): [0x1303730] set timeout to [4000]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c620] Debug:[imap]
dbmail_imap_session_set_state(+1676): [0x1303730] state [1]->[2]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
_ic_cb_leave(+547): handling imap session [0x1303730]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x7f3c38b03010]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_handle_exit(+262): [0x1303730] state [2] command_status [1] [LOGIN]
returned with status [0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_write(+312): [0x7f3c38b03010] S > [162/162:L OK [CAPABILITY IMAP4 IMAP4rev1
ACL RIGHTS=texk NAMESPACE CHILDREN SORT QUOTA THREAD=ORDE
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_reset(+454): [0x1303730]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x7f3c38b03010]
Feb 2 20:58:16 dbmail-test dovecot: imap-login: proxy([email protected]):
started proxying to 127.0.0.1:143: user=<[email protected]>,
method=CRAM-MD5, rip=10.0.0.111, lip=10.0.0.111, TLS
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[server]
_sock_cb(+468): 15 2, 0x1301980, ssl:N
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[server]
_sock_cb(+508): connection accepted
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
client_init(+162): saddr [0x1305110] sa_family [2] len [16]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280]
Notice:[clientbase] client_init(+167): incoming connection on [127.0.0.1:143]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
client_init(+170): caddr [0x11f6760] sa_family [2] len [16]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280]
Notice:[clientbase] client_init(+185): incoming connection from
[127.0.0.1:24387]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[CAPA]
Capa_new(+65): key "capability" section "IMAP" var val value [IMAP4 IMAP4rev1
AUTH=LOGIN ACL RIGHTS=texk NAMESPACE CHILDREN SORT QUOTA
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[CAPA]
Capa_new(+65): key "capability" section "IMAP" var val value [IMAP4 IMAP4rev1
AUTH=LOGIN ACL RIGHTS=texk NAMESPACE CHILDREN SORT QUOTA
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_new(+121): imap session [0x122eaa0] created
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_set_state(+1647): state [1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_set_state(+1676): [0x122eaa0] state [0]->[1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x1305800]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
send_greeting(+217): key "banner" section "IMAP" var banner value [IMAP]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_write(+312): [0x1305800] S > [54/54:* OK [CAPABILITY IMAP4 IMAP4rev1
AUTH=LOGIN ID] IMAP#015#012]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_set_state(+1647): state [1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
socket_read_cb(+176): [0x122eaa0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_read_cb(+342): [0x1305800] reset timeout [60]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_cb_read(+155): reading 1: 208/0
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_readln(+423): [0x1305800] C < [124:I ID ("x-originating-ip" "10.0.0.111"
"x-originating-port" "45617" "x-connected-ip" "10.0.0.111" "x
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[imap]
imap4_tokenizer(+495): [0x122eaa0] COMMAND: [I ID ("x-originating-ip"
"10.0.0.111" "x-originating-port" "45617" "x-connected-ip" "10.0.0.111" "
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+1919): [0x122eaa0] tokenize [120/0] [ ("x-originating-ip"
"10.0.0.111" "x-originating-port" "45617" "x-connected-ip" "10.0.0.11
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2116): [0x122eaa0] tag: [I], command: [ID], [10] args
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x122eaa0] arg[0]: '('
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x122eaa0] arg[1]: 'x-originating-ip'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x122eaa0] arg[2]: '10.0.0.111'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x122eaa0] arg[3]: 'x-originating-port'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x122eaa0] arg[4]: '45617'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x122eaa0] arg[5]: 'x-connected-ip'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x122eaa0] arg[6]: '10.0.0.111'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x122eaa0] arg[7]: 'x-connected-port'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x122eaa0] arg[8]: '993'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2121): [0x122eaa0] arg[9]: ')'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer(+538): parser_state: [1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Database:[db]
db_con_get(+256): [0x11f6320] connection from pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Database:[db]
db_con_close(+274): [0x11f6320] connection to pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x122eaa0] arg[0]: '('
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x122eaa0] arg[1]: 'x-originating-ip'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x122eaa0] arg[2]: '10.0.0.111'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x122eaa0] arg[3]: 'x-originating-port'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x122eaa0] arg[4]: '45617'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x122eaa0] arg[5]: 'x-connected-ip'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x122eaa0] arg[6]: '10.0.0.111'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x122eaa0] arg[7]: 'x-connected-port'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x122eaa0] arg[8]: '993'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_unescape_args(+579): [0x122eaa0] arg[9]: ')'
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[imap]
imap4(+626): dispatch [id]...
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_cork(+205): [0x1305800]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[server]
dm_thread_data_push(+118): [0x1346c00] [0x122eaa0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c460] Debug:[server]
dm_thread_dispatch(+156): data[0x1346c00], user_data[(nil)]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_handle_input(+396): imap4 returned [0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
_ic_cb_leave(+547): handling imap session [0x122eaa0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x1305800]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_handle_exit(+262): [0x122eaa0] state [1] command_status [1] [ID] returned
with status [0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_write(+312): [0x1305800] S > [113/113:* ID ("name" "dbmail" "version"
"3.0.0" "os" "Linux" "os-version" "2.6.41.10-3.fc15.x86_64")#015#012I
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_reset(+454): [0x122eaa0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x1305800]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_readln(+423): [0x1305800] C < [14:C CAPABILITY#015#012]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[imap]
imap4_tokenizer(+495): [0x122eaa0] COMMAND: [C CAPABILITY#015#012]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer_main(+2116): [0x122eaa0] tag: [C], command: [CAPABILITY], [0]
args
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap4_tokenizer(+538): parser_state: [1]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Database:[db]
db_con_get(+256): [0x11f6320] connection from pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Database:[db]
db_con_close(+274): [0x11f6320] connection to pool
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[imap]
imap4(+626): dispatch [capability]...
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_cork(+205): [0x1305800]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[server]
dm_thread_data_push(+118): [0x1387930] [0x122eaa0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x122c2a0] Debug:[server]
dm_thread_dispatch(+156): data[0x1387930], user_data[(nil)]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_handle_input(+396): imap4 returned [0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
_ic_cb_leave(+547): handling imap session [0x122eaa0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x1305800]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
imap_handle_exit(+262): [0x122eaa0] state [1] command_status [1] [CAPABILITY]
returned with status [0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Info:[clientbase]
ci_write(+312): [0x1305800] S > [153/153:* CAPABILITY IMAP4 IMAP4rev1
AUTH=LOGIN ACL RIGHTS=texk NAMESPACE CHILDREN SORT QUOTA THREAD=OR
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[imap]
dbmail_imap_session_reset(+454): [0x122eaa0]
Feb 2 20:58:16 dbmail-test dbmail/imap4d[1170]: [0x11cb280] Debug:[clientbase]
ci_uncork(+212): [0x1305800]
Feb 2 20:58:16 dbmail-test dovecot: imap-login: proxy([email protected]):
started proxying to 127.0.0.1:143: user=<[email protected]>, method=PLAIN,
rip=10.0.0.111, lip=10.0.0.111, TLS
signature.asc
Description: OpenPGP digital signature
_______________________________________________ DBmail mailing list [email protected] http://mailman.fastxs.nl/cgi-bin/mailman/listinfo/dbmail
