Unexpected session timeout

[lvangool]

One of our Sugar installations has a strange problem with the session. Sometimes, while flling in a new Account, the user gets kicked out of Sugar when she's pressing the Save button. This happens quite randomly. The user is not being inactive for a long period of time, and the PHP configuration hasn't been altered. Below is the sugarcrm.log file snippet.

This is happening on SugarCRM OS 4.2.1b on a Linux box.

acces.log
=========

80.126.211.143 - - [10/Oct/2006:15:37:23 +0200] "GET
/sugarcrm/index.php?module=Accounts&action=EditView&return_module=Accounts&return_action=DetailView
HTTP/1.1" 200 59420 "-" "Mozilla/4.0 (compatible; MSIE 6.0; Windows NT
5.1; SV1; .NET CLR 1.1.4322; .NET CLR 2.0.50727)"

80.126.211.143 - - [10/Oct/2006:15:42:28 +0200] "POST
/sugarcrm/index.php HTTP/1.1" 302 -
"http://www.borgprojecten.nl/sugarcrm/index.php?module=Accounts&action=EditView&return_module=Accounts&return_action=DetailView"
"Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR
1.1.4322; .NET CLR 2.0.50727)"

80.126.211.143 - - [10/Oct/2006:15:42:28 +0200] "GET
/sugarcrm/index.php?action=Login&module=Users HTTP/1.1" 200 11924
"http://www.borgprojecten.nl/sugarcrm/index.php?module=Accounts&action=EditView&return_module=Accounts&return_action=DetailView"
"Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR
1.1.4322; .NET CLR 2.0.50727)"

sugarcrm.log
============

Tue Oct 10 15:37:23 2006,535 [22131] INFO SugarCRM - Connect:Resource id
#155
Tue Oct 10 15:37:23 2006,538 [22131] INFO SugarCRM - Query:SELECT
category, name, value FROM config WHERE category='system'
Tue Oct 10 15:37:23 2006,538 [22131] INFO SugarCRM - Query Execution
Time:0.000116
Tue Oct 10 15:37:23 2006,538 [22131] DEBUG SugarCRM - ****Starting
Application for  session 1e834054cf243eb2efbab76de470de93
Tue Oct 10 15:37:23 2006,538 [22131] DEBUG SugarCRM - We have an
authenticated user id: 761dc7d9-95de-96af-a6a5-447d625f2aab
Tue Oct 10 15:37:23 2006,540 [22131] DEBUG SugarCRM - Retrieve User :
SELECT users.* FROM users  WHERE users.id =
'761dc7d9-95de-96af-a6a5-447d625f2aab'
Tue Oct 10 15:37:23 2006,541 [22131] DEBUG SugarCRM - Limit Query:SELECT
users.* FROM users  WHERE users.id =
'761dc7d9-95de-96af-a6a5-447d625f2aab'  Start: 0 count: 1
Tue Oct 10 15:37:23 2006,541 [22131] INFO SugarCRM - Query:SELECT
users.* FROM users  WHERE users.id =
'761dc7d9-95de-96af-a6a5-447d625f2aab'  LIMIT 0,1
Tue Oct 10 15:37:23 2006,541 [22131] INFO SugarCRM - Query Execution
Time:0.000208
Tue Oct 10 15:37:23 2006,544 [22131] INFO SugarCRM - Query:SELECT
u1.first_name, u1.last_name from users  u1, users  u2 where u1.id =
u2.reports_to_id AND u2.id = '761dc7d9-95de-96af-a6a5-447d625f2aab' and
u1.deleted=0
Tue Oct 10 15:37:23 2006,545 [22131] INFO SugarCRM - Query Execution
Time:8.69999999999E-05
Tue Oct 10 15:37:23 2006,545 [22131] DEBUG SugarCRM - additional detail
query results:
Tue Oct 10 15:37:23 2006,545 [22131] DEBUG SugarCRM - Current user is:
hboerma
Tue Oct 10 15:37:23 2006,545 [22131] DEBUG SugarCRM - array (
  'module' => 'Accounts',
  'action' => 'EditView',
  'return_module' => 'Accounts',
  'return_action' => 'DetailView',
  'ck_login_id_20' => '761dc7d9-95de-96af-a6a5-447d625f2aab',
  'ck_login_theme_20' => 'Sugar',
  'ck_login_language_20' => 'nl_nl',
  'PHPSESSID' => '1e834054cf243eb2efbab76de470de93',
  'showLeftCol' => 'true',
)
Tue Oct 10 15:37:23 2006,545 [22131] INFO SugarCRM - In module: Accounts
-- About to take action EditView
Tue Oct 10 15:37:23 2006,546 [22131] DEBUG SugarCRM - in module Accounts
-- in EditView
Tue Oct 10 15:37:23 2006,546 [22131] DEBUG SugarCRM -
----------------------------------------------------------------------------------------------------------------------------------------------
Tue Oct 10 15:37:23 2006,546 [22131] INFO SugarCRM - current page is
modules/Accounts/EditView.php


-----


Tue Oct 10 15:42:28 2006,601 [22215] INFO SugarCRM - Connect:Resource id
#155
Tue Oct 10 15:42:28 2006,813 [22080] INFO SugarCRM - Connect:Resource id
#155
Tue Oct 10 15:42:28 2006,814 [22080] INFO SugarCRM - Query:SELECT
category, name, value FROM config WHERE category='system'
Tue Oct 10 15:42:28 2006,814 [22080] INFO SugarCRM - Query Execution
Time:9.2E-05
Tue Oct 10 15:42:28 2006,814 [22080] DEBUG SugarCRM - ****Starting
Application for  session 72f361cbb4512cbad935ee77358f8685
Tue Oct 10 15:42:28 2006,815 [22080] DEBUG SugarCRM - The current user
does not have a session.  Going to the login page
Tue Oct 10 15:42:28 2006,816 [22080] DEBUG SugarCRM - array (
  'action' => 'Login',
  'module' => 'Users',
  'ck_login_id_20' => '761dc7d9-95de-96af-a6a5-447d625f2aab',
  'ck_login_theme_20' => 'Sugar',
  'ck_login_language_20' => 'nl_nl',
  'PHPSESSID' => '72f361cbb4512cbad935ee77358f8685',
  'showLeftCol' => 'true',
)
Tue Oct 10 15:42:28 2006,817 [22080] INFO SugarCRM - In module: Users --
About to take action Login

[lvangool]

anyone with any thoughts on this?

[aatamer]

The same thing happened for one of my users. there was about 20 minutes of idle time in one incident, but in another incident only 12 minutes. I can't understand it, but my user doesn't like losing all the edits he made.

[lvangool]

This problem (and some other session issues) keep popping up in the sugar install of one of our clients (4.2.1b). These session issues are very hard to debug/grasp, and are very annoying to the users. Also, these kind of errors are only seen on windows systems (so far)

[smbslt42]

We're using Sugar Professional 6.2.1 but have recently started having the same issue - users get kicked off after only a few minutes. Did you ever resolve this?