Author |
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 24 November 2012 at 2:36pm | IP Logged
|
|
|
Some weeks ago I struggled to install webmail lite and let it work will change pass enables on a new win VPS (running hmail server), but I finally succeeded thanks to the help of another great user of this forum.
Now I had to change my VPS (the previous one was a server 2008 32bit, the new one is a server 2008 R2 X64). I first attempted to use the previous installation and let it work in the new server, but I did not succeed. Then I reinstalled webmail from the beginning in the new server, I did not use anything of the previous one (not even the mysql DB). I cheked eveything several times ... but I can not let it work.
I succeded to execute the install.htm process to the end, without errors. Then I can enter the admin panel and everything works. But when I attempt to login with any normal user I always get a message saying: "Internal Server Error. Please, contact your system administrator in order to report the problem." ...
I cheked the logs of webmail (nothing seems strange there), of hmailserver (idem) and IIS (idem) ... am getting crazy with this. I'm starting to think that could be a issue related to the X64 server (maybe plugins are not compatible).
Any idea or suggestion ??
Thanks
|
Back to Top |
|
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 24 November 2012 at 4:07pm | IP Logged
|
|
|
Just to complete the available information, below please find the log of webmail, and of hmailserver and IIS (I use the .net versione of webmail):
WEBMAIL
[20:34:14.50] [INFO] Assembly version: 6.0.2.220.
[20:34:14.50] [INFO] Will resolve host "mail.deall.com.br".
[20:34:14.50] [INFO] Host "mail.deall.com.br" resolved to IP address(es) 173.248.189.56.
[20:34:14.50] [INFO] Will connect to host "mail.deall.com.br" on port 110.
[20:34:14.51] [INFO] Socket connected to IP address 173.248.189.56 on port 110.
[20:34:14.54] [RECV] +OK januagroup.com - Welcome to Janua Group POP3 server\r\n
[20:34:14.54] [INFO] Connected to mail service at host "mail.deall.com.br" on port 110 and ready.
[20:34:14.56] [INFO] Get the list of POP3 capabilities via CAPA command.
[20:34:14.57] [SEND] CAPA\r\n
[20:34:14.59] [RECV] -ERR Invalid command in current state.\r\n
[20:34:14.62] [INFO] Warning: The server does not support CAPA command. POP3 pipelining will not be available. The server responded: -ERR Invalid command in current state..
[20:34:14.62] [INFO] Get the list of advertized SASL authentication methods via AUTH command.
[20:34:14.62] [SEND] AUTH\r\n
[20:34:14.62] [RECV] -ERR Invalid command in current state.\r\n
[20:34:14.67] [INFO] Warning: The server does not support AUTH command. SASL authentication will not be available. The server responded: -ERR Invalid command in current state..
[20:34:14.67] [INFO] Will login as "xxxx@deall.com.br".
[20:34:14.68] [INFO] Will try regular USER/PASS authentication.
[20:34:14.68] [SEND] USER lorpar@deall.com.br\r\n
[20:34:14.68] [RECV] +OK Send your password\r\n
[20:34:14.68] [SEND] PASS ********\r\n
[20:34:14.68] [RECV] +OK Mailbox locked and ready\r\n
[20:34:14.68] [INFO] Logged in as "xxxx@deall.com.br".
[20:34:14.68] [INFO] Download inbox statistics.
[20:34:14.68] [SEND] STAT\r\n
[20:34:14.68] [RECV] +OK 2 13582\r\n
[20:34:14.68] [INFO] Download the list of Unique-IDs of all messages in inbox.
[20:34:14.68] [SEND] UIDL\r\n
[20:34:14.70] [RECV] +OK 2 messages (13582 octets)\r\n1 1425\r\n2 1426\r\n.\r\n
[20:34:14.71] [SEND] QUIT\r\n
[20:34:14.71] [RECV] +OK POP3 server saying goodbye...\r\n
[20:34:14.71] [INFO] Will disconnect from host "mail.deall.com.br".
[20:34:14.71] [INFO] Disconnected from host "mail.deall.com.br".
HMAILSERVER
"TCPIP" 2940 "2012-11-24 20:34:14.515" "TCPConnection - Posting AcceptEx on 0.0.0.0:110"
"DEBUG" 2940 "2012-11-24 20:34:14.515" "Creating session 635"
"POP3D" 2940 635 "2012-11-24 20:34:14.515" "173.248.189.56" "SENT: +OK januagroup.com - Welcome to Janua Group POP3 server"
"POP3D" 2940 635 "2012-11-24 20:34:14.578" "173.248.189.56" "RECEIVED: CAPA"
"POP3D" 2940 635 "2012-11-24 20:34:14.578" "173.248.189.56" "SENT: -ERR Invalid command in current state."
"POP3D" 2960 635 "2012-11-24 20:34:14.625" "173.248.189.56" "RECEIVED: AUTH"
"POP3D" 2960 635 "2012-11-24 20:34:14.625" "173.248.189.56" "SENT: -ERR Invalid command in current state."
"POP3D" 2948 635 "2012-11-24 20:34:14.687" "173.248.189.56" "RECEIVED: USER xxxx@deall.com.br"
"POP3D" 2948 635 "2012-11-24 20:34:14.687" "173.248.189.56" "SENT: +OK Send your password"
"POP3D" 2948 635 "2012-11-24 20:34:14.687" "173.248.189.56" "RECEIVED: PASS ***"
"POP3D" 2948 635 "2012-11-24 20:34:14.687" "173.248.189.56" "SENT: +OK Mailbox locked and ready"
"POP3D" 2948 635 "2012-11-24 20:34:14.687" "173.248.189.56" "RECEIVED: STAT"
"POP3D" 2948 635 "2012-11-24 20:34:14.687" "173.248.189.56" "SENT: +OK 2 13582"
"POP3D" 2948 635 "2012-11-24 20:34:14.687" "173.248.189.56" "RECEIVED: UIDL"
"POP3D" 2948 635 "2012-11-24 20:34:14.687" "173.248.189.56" "SENT: +OK 2 messages (13582 octets)[nl]1 1425[nl]2 1426[nl]."
"POP3D" 2936 635 "2012-11-24 20:34:14.718" "173.248.189.56" "RECEIVED: QUIT"
"POP3D" 2936 635 "2012-11-24 20:34:14.718" "173.248.189.56" "SENT: +OK POP3 server saying goodbye..."
"DEBUG" 2936 "2012-11-24 20:34:14.718" "Closing TCP/IP socket"
"DEBUG" 2936 "2012-11-24 20:34:14.718" "Ending session 635"
"DEBUG" 2928 "2012-11-24 20:34:20.078" "Reading message from database"
IIS
2012-11-25 00:04:58 173.248.189.56 GET /webmail/default.aspx - 80 - 173.248.189.56 Mozilla/5.0+(Windows+NT+6.1;+WOW64;+rv:17.0)+Gecko/17.0+Firefox/17.0 200 0 0 4875
2012-11-25 00:04:58 173.248.189.56 GET /webmail/langs-js.aspx v=500&lang=English 80 - 173.248.189.56 Mozilla/5.0+(Windows+NT+6.1;+WOW64;+rv:17.0)+Gecko/17.0+Firefox/17.0 200 0 0 171
2012-11-25 00:05:11 173.248.189.56 POST /webmail/xml-processing.aspx - 80 - 173.248.189.56 Mozilla/5.0+(Windows+NT+6.1;+WOW64;+rv:17.0)+Gecko/17.0+Firefox/17.0 200 0 0 5703
|
Back to Top |
|
|
Igor AfterLogic Support
Joined: 24 June 2008 Location: United States
Online Status: Offline Posts: 6104
|
Posted: 26 November 2012 at 3:56am | IP Logged
|
|
|
When it comes to system errors, you need to check the other log enabled in web.config file, details are available here.
--
Regards,
Igor, AfterLogic Support
|
Back to Top |
|
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 30 November 2012 at 2:32am | IP Logged
|
|
|
ok enabled this log ... and got this result !!
------------------------------------------------------------
[11/30/2012 8:28:05 AM] ERROR!!!
[Message]
ERROR [HY000] [MySQL][ODBC 5.2(w) Driver][mysqld-5.5.28]Incorrect datetime value: '2012-11-30 08:28:05.637000000' for column 'last_login' at row 1
[TargetSite]
Void HandleError(System.Data.Odbc.OdbcHandle, RetCode)
[Stack Trace]
at System.Data.Odbc.OdbcConnection.HandleError(OdbcHandle hrHandle, RetCode retcode)
at System.Data.Odbc.OdbcCommand.ExecuteReaderObject(CommandBehavior behavior, String method, Boolean needReader, Object[] methodArguments, SQL_API odbcApiMethod)
at System.Data.Odbc.OdbcCommand.ExecuteReaderObject(CommandBehavior behavior, String method, Boolean needReader)
at System.Data.Odbc.OdbcCommand.ExecuteNonQuery()
at WebMail.MySqlDbManager.CreateUser(Boolean deleted)
[Method]: HandleError
[Line, Column]: [0, 0]
------------------------------------------------------------
------------------------------------------------------------
[11/30/2012 8:28:05 AM] ERROR!!!
[Message]
Internal Server Error. Please, contact your system administrator in order to report the problem.
[TargetSite]
Void HandleError(System.Data.Odbc.OdbcHandle, RetCode)
[Stack Trace]
at System.Data.Odbc.OdbcConnection.HandleError(OdbcHandle hrHandle, RetCode retcode)
at System.Data.Odbc.OdbcCommand.ExecuteReaderObject(CommandBehavior behavior, String method, Boolean needReader, Object[] methodArguments, SQL_API odbcApiMethod)
at System.Data.Odbc.OdbcCommand.ExecuteReaderObject(CommandBehavior behavior, String method, Boolean needReader)
at System.Data.Odbc.OdbcCommand.ExecuteNonQuery()
at WebMail.MySqlDbManager.CreateUser(Boolean deleted)
[Method]: CreateUser
[Line, Column]: [0, 0]
------------------------------------------------------------
|
Back to Top |
|
|
Igor AfterLogic Support
Joined: 24 June 2008 Location: United States
Online Status: Offline Posts: 6104
|
Posted: 30 November 2012 at 5:40am | IP Logged
|
|
|
The only suggestion I have at the moment is to try using ODBC connector for MySQL version 3.51 available here, you'll need to uninstall version 5 of connector first.
--
Regards,
Igor, AfterLogic Support
|
Back to Top |
|
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 30 November 2012 at 10:57am | IP Logged
|
|
|
Igor,
first of all thanks for your support and suggestion. On that server I run other softwares and servers which use mysql (at present running last 64 bit version of mysql server) and can not downgrade the ODBC connector to ver 3.51. Uf this is the only solution for the problem I'll have to find a different web mail manager ... at least until when this problem will be fixed and it will run with last mysql softwares.
Anyway, again, thanks very much ...
Lorenzo
|
Back to Top |
|
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 30 November 2012 at 12:45pm | IP Logged
|
|
|
Igor, I just dicovered that I can have more than one ODBC driver on the same machine ... thus I installed MySQL ODBC version 5.1 (which was working perfectly on my previous installation), and configured webmail to use it. But still get the same error.
|
Back to Top |
|
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 30 November 2012 at 12:52pm | IP Logged
|
|
|
[11/30/2012 6:44:36 PM][PrepareCommand] - SET NAMES utf8
[11/30/2012 6:44:36 PM][CreateParameter] - ?='mailadm'
[11/30/2012 6:44:36 PM][PrepareCommand] - SELECT * FROM awm_subadmins WHERE login=?
WebMail Version: 5.1.3
[11/30/2012 6:45:34 PM][] - >>>>>>>>>>>>>>>> IN >>>>>>>>>>>>>>>>
[11/30/2012 6:45:34 PM][] - <?xml version="1.0" encoding="utf-8"?><webmail><param name="action" value="login" /><param name="request" value="" /><param name="email"><![CDATA[xxx@deall.com.br]]></param><param name="mail_inc_login"><![CDATA[]]></param><param name="mail_inc_pass"><![CDATA[xxxx]]></param><param name="mail_inc_host"><![CDATA[localhost]]></param><param name="mail_inc_port" value="110"/><param name="mail_protocol" value="0"/><param name="mail_out_host"><![CDATA[localhost]]></param><param name="mail_out_port" value="25"/><param name="mail_out_auth" value="0"/><param name="sign_me" value="0"/><param name="language"><![CDATA[]]></param><param name="advanced_login" value="0"/></webmail>
[11/30/2012 6:45:34 PM][] - >>>>>>>>>>>>>>>> IN >>>>>>>>>>>>>>>>
[11/30/2012 6:45:35 PM][PrepareCommand] - SET NAMES utf8
[11/30/2012 6:45:35 PM][CreateParameter] - @name='deall.com.br'
[11/30/2012 6:45:35 PM][PrepareCommand] - SELECT * FROM awm_domains WHERE name=?
[11/30/2012 6:45:37 PM][PrepareCommand] - SET NAMES utf8
[11/30/2012 6:45:37 PM][PrepareCommand] - SELECT account.id_domain, account.id_acct, account.id_user, account.def_acct,
account.deleted, account.email, account.mail_protocol, account.mail_inc_host, account.mail_inc_login,
account.mail_inc_pass, account.mail_inc_port, account.mail_out_host, account.mail_out_login,
account.mail_out_pass, account.mail_out_port, account.mail_out_auth, account.friendly_nm,
account.use_friendly_nm, account.def_order, account.getmail_at_login, account.mail_mode,
account.mails_on_server_days, account.signature, account.signature_type, account.signature_opt,
account.delimiter, account.mailbox_size, account.mailing_list, account.imap_quota, account.namespace,
domains.mail_protocol AS domain_mail_protocol, domains.mail_inc_host AS domain_mail_inc_host,
domains.mail_inc_port AS domain_mail_inc_port, domains.mail_out_host AS domain_mail_out_host,
domains.mail_out_port AS domain_mail_out_port, domains.mail_out_auth AS domain_mail_out_auth
FROM awm_accounts AS account
LEFT JOIN awm_domains AS domains ON (account.id_domain = domains.id_domain)
WHERE email LIKE 'lorpar@deall.com.br' AND mailing_list=0
[11/30/2012 6:45:37 PM][POP3 Connect] - 'mail.deall.com.br' - '110'
[11/30/2012 6:45:37 PM][POP3 Connect] - IsConnected - 'False'
[11/30/2012 6:45:37 PM][POP3 Connect] - IsConnected - 'True'
[11/30/2012 6:45:37 PM][POP3 Login] - 'xxxx@deall.com.br' - 'xxxx'
[11/30/2012 6:45:37 PM][POP3 Login] - IsLogined - 'False'
[11/30/2012 6:45:37 PM][POP3 Login] - IsLogined - 'True'
[11/30/2012 6:45:38 PM][PrepareCommand] - SET NAMES utf8
[11/30/2012 6:45:38 PM][PrepareCommand] -
SELECT COUNT(a_users.id_user) FROM a_users
INNER JOIN awm_settings ON (a_users.id_user = awm_settings.id_user)
WHERE deleted = 0
[11/30/2012 6:45:38 PM][CreateParameter] - @deleted='False'
[11/30/2012 6:45:38 PM][PrepareCommand] - INSERT INTO a_users (deleted) VALUES(?);
[11/30/2012 6:45:38 PM][CreateParameter] - @id_user='8'
[11/30/2012 6:45:38 PM][CreateParameter] - @msgs_per_page='20'
[11/30/2012 6:45:38 PM][CreateParameter] - @white_listing='False'
[11/30/2012 6:45:38 PM][CreateParameter] - @x_spam='False'
[11/30/2012 6:45:38 PM][CreateParameter] - @last_login='11/30/2012 6:45:38 PM'
[11/30/2012 6:45:38 PM][CreateParameter] - @logins_count='1'
[11/30/2012 6:45:38 PM][CreateParameter] - @def_skin='AfterLogic'
[11/30/2012 6:45:38 PM][CreateParameter] - @def_lang='English'
[11/30/2012 6:45:38 PM][CreateParameter] - @def_charset_inc='65001'
[11/30/2012 6:45:38 PM][CreateParameter] - @def_charset_out='65001'
[11/30/2012 6:45:38 PM][CreateParameter] - @def_timezone='0'
[11/30/2012 6:45:38 PM][CreateParameter] - @def_date_fmt='mm/dd/yy'
[11/30/2012 6:45:38 PM][CreateParameter] - @hide_folders='False'
[11/30/2012 6:45:38 PM][CreateParameter] - @mailbox_limit='102400000'
[11/30/2012 6:45:38 PM][CreateParameter] - @allow_change_settings='True'
[11/30/2012 6:45:38 PM][CreateParameter] - @allow_dhtml_editor='True'
[11/30/2012 6:45:38 PM][CreateParameter] - @allow_direct_mode='True'
[11/30/2012 6:45:38 PM][CreateParameter] - @hide_contacts='False'
[11/30/2012 6:45:38 PM][CreateParameter] - @db_charset='65001'
[11/30/2012 6:45:38 PM][CreateParameter] - @horiz_resizer='150'
[11/30/2012 6:45:38 PM][CreateParameter] - @vert_resizer='115'
[11/30/2012 6:45:38 PM][CreateParameter] - @mark='0'
[11/30/2012 6:45:38 PM][CreateParameter] - @reply='0'
[11/30/2012 6:45:38 PM][CreateParameter] - @contacts_per_page='20'
[11/30/2012 6:45:38 PM][CreateParameter] - @view_mode='1'
[11/30/2012 6:45:38 PM][PrepareCommand] - INSERT INTO awm_settings (id_user, msgs_per_page, white_listing, x_spam,
last_login, logins_count, def_skin, def_lang, def_charset_inc, def_charset_out, def_timezone, def_date_fmt,
hide_folders, mailbox_limit, allow_change_settings, allow_dhtml_editor, allow_direct_mode, hide_contacts,
db_charset, horiz_resizer, vert_resizer, mark, reply, contacts_per_page, view_mode)
VALUES(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?);
------------------------------------------------------------
[11/30/2012 6:45:38 PM] ERROR!!!
[Message]
ERROR [HY000] [MySQL][ODBC 5.1 Driver][mysqld-5.5.28]Incorrect datetime value: '2012-11-30 18:45:38.967000000' for column 'last_login' at row 1
[TargetSite]
Void HandleError(System.Data.Odbc.OdbcHandle, RetCode)
[Stack Trace]
at System.Data.Odbc.OdbcConnection.HandleError(OdbcHandle hrHandle, RetCode retcode)
at System.Data.Odbc.OdbcCommand.ExecuteReaderObject(CommandBehavior behavior, String method, Boolean needReader, Object[] methodArguments, SQL_API odbcApiMethod)
at System.Data.Odbc.OdbcCommand.ExecuteReaderObject(CommandBehavior behavior, String method, Boolean needReader)
at System.Data.Odbc.OdbcCommand.ExecuteNonQuery()
at WebMail.MySqlDbManager.CreateUser(Boolean deleted)
[Method]: HandleError
[Line, Column]: [0, 0]
------------------------------------------------------------
------------------------------------------------------------
[11/30/2012 6:45:39 PM] ERROR!!!
[Message]
Internal Server Error. Please, contact your system administrator in order to report the problem.
[TargetSite]
Void HandleError(System.Data.Odbc.OdbcHandle, RetCode)
[Stack Trace]
at System.Data.Odbc.OdbcConnection.HandleError(OdbcHandle hrHandle, RetCode retcode)
at System.Data.Odbc.OdbcCommand.ExecuteReaderObject(CommandBehavior behavior, String method, Boolean needReader, Object[] methodArguments, SQL_API odbcApiMethod)
at System.Data.Odbc.OdbcCommand.ExecuteReaderObject(CommandBehavior behavior, String method, Boolean needReader)
at System.Data.Odbc.OdbcCommand.ExecuteNonQuery()
at WebMail.MySqlDbManager.CreateUser(Boolean deleted)
[Method]: CreateUser
[Line, Column]: [0, 0]
------------------------------------------------------------
[11/30/2012 6:45:39 PM][] - <<<<<<<<<<<<<<<<< OUT <<<<<<<<<<<<<<<
[11/30/2012 6:45:39 PM][] - <?xml version="1.0" encoding="utf-8"?><webmail><error><![CDATA[Internal Server Error. Please, contact your system administrator in order to report the problem.]]></error></webmail>
[11/30/2012 6:45:39 PM][] - <<<<<<<<<<<<<<<<< OUT <<<<<<<<<<<<<<<
|
Back to Top |
|
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 30 November 2012 at 1:02pm | IP Logged
|
|
|
Igor, I was wondering. I had an identical installa working perfectly on my previous VPS. The differences of the system where:
1) OLD: Win 2008 Standard 32Bit NEW: Win 2008 r2 X64
2) OLD: MySQL Server 5.1 (32Bit) NEW: MySQL Server 5.5 (X64)
3) OLD: MySQL ODBC 5.1 (32Bit) NEW: MySQL Odbc 5.2w and 5.1 (now installed), both 32 and 64 bit
There are the only differences I can see between the system working (OLD) and the one not working (NEW) ...
I was thinking if it may be a problem with mysql 5.5 or with 64Bit OS (maybe plugins just want 32 bit system) ???
Getting mad on this ...
|
Back to Top |
|
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 30 November 2012 at 1:39pm | IP Logged
|
|
|
I made some further tests and study ... I discovered that MySQL accepts just 6 digit fractional seconds (http://dev.mysql.com/doc/refman/5.1/en/fractional-seconds.html) ... which means format 2010-12-10 14:12:09.019473 ... and in the error log above i see that webmail attempts to write a 9 digit fractiona seconds (2012-11-30 18:45:38.967000000) ... i tryed to write this number manually into the DB (using navicat) and of course i got the same error. Cutting 3 digits to (2012-11-30 18:45:38.967000), it works like charm. So the problemas seems to be into the generation of this datetime which probably comes from a datetime.now statement. Since mysql does not store fractionals anywa, it could be a good idea to cut this from the beginning.
The interesting issue now is why this was not a problem in my previous VPS ??
Regards
|
Back to Top |
|
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 30 November 2012 at 2:29pm | IP Logged
|
|
|
I guess the problem is the same reported here:
http://bugs.mysql.com/bug.php?id=65418
... but can not understand how to fix it !! Any help ??
|
Back to Top |
|
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 30 November 2012 at 3:57pm | IP Logged
|
|
|
Well just to provide one more update ... I installed and used mySQL ODBC 5.1.10 (the release that according to the article in my previous message, should work fine). The result is that it does not work ... same error !!
|
Back to Top |
|
|
lorpar Newbie
Joined: 06 November 2012 Location: Brazil
Online Status: Offline Posts: 17
|
Posted: 30 November 2012 at 4:12pm | IP Logged
|
|
|
ok ... installed ODBC ver 3.51 and now it works !! The problem is compatibility with ODBC driver then ...
The strange thing is that on my previous VPS it was working perfectly with ODBC 5.1 ?!? very strange thing ...
|
Back to Top |
|
|
Alex AfterLogic Support
Joined: 19 November 2003
Online Status: Offline Posts: 2206
|
Posted: 03 December 2012 at 4:02am | IP Logged
|
|
|
Yes, MySQL + ODBC combination is full of surprises sometimes..
Regards,
Alex
|
Back to Top |
|
|
|
|