[Tickets #9438] Re: [Regression] authentication fails via syncml

[bugs at horde.org]

DO NOT REPLY TO THIS MESSAGE. THIS EMAIL ADDRESS IS NOT MONITORED.

Ticket URL: http://bugs.horde.org/ticket/9438
------------------------------------------------------------------------------
  Ticket             | 9438
  Updated By         | d-fens at 00010111.de
  Summary            | [Regression] authentication fails via syncml
  Queue              | Synchronization
  Version            | Git master
  Type               | Bug
  State              | Assigned
  Priority           | 2. Medium
  Milestone          |
  Patch              |
  Owners             | Gunnar Wrobel
------------------------------------------------------------------------------


d-fens at 00010111.de (2010-12-09 13:49) wrote:

99,99% sure.

interesting is: it now seems to work one time but the second request  
gets denied (during one sync)

2010-12-09T19:44:03+01:00 DEBUG: HORDE [imp] Your browser is too old  
to display the dynamic mode. Using traditional mode instead. [pid  
17426 on line 27 of  
"/var/www/_horde/horde/framework/Core/lib/Horde/Core/Notification/Hordelog.php"]
2010-12-09T19:44:03+01:00 NOTICE: HORDE [imp] Login success for xxx  
[88.77.140.237] to {localhost:993 [imap]} [pid 17426 on line 199 of  
"/var/www/_horde/horde/imp/lib/Auth.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Authenticated: yes;  
version: 1.2; message ID: 1; source URI: aku-1291724651; target URI:  
http://xxxx/horde-git/rpc.php; user: xxx; charset: UTF-8; wbxml: no  
[pid 17426 on line 295 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] SQL Query by  
SyncML_Backend_Horde::readSyncAnchors(): SELECT syncml_clientanchor,  
syncml_serveranchor FROM horde_syncml_anchors WHERE syncml_syncpartner  
= ? AND syncml_db = ? AND syncml_uid = ?, values: aku-1291724651,  
calendar, xxx [pid 17426 on line 166 of  
"/var/www/_horde/horde/framework/SyncML/SyncML/Command/Alert.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] No info about previous  
syncs found for device aku-1291724651 and database event [pid 17426 on  
line 446 of "/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Creating SyncML_Sync  
object for database event; sync type 201 [pid 17426 on line 446 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Received <Final> from  
client. [pid 17426 on line 446 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Handle <Final> for  
state Init [pid 17426 on line 408 of  
"/var/www/_horde/horde/framework/SyncML/SyncML/State.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Sending <Final> to  
client [pid 17426 on line 310 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Return message  
completed [pid 17426 on line 310 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Finished at 2010-12-09  
19:44:03. Packet logged in /tmp/sync/server_50.xml [pid 17426 on line  
176 of "/var/www/_horde/horde/framework/SyncML/SyncML.php"]

and then:


010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Horde_Rpc::__construct  
complete [pid 17426 on line 97 of  
"/var/www/_horde/horde/framework/Rpc/lib/Horde/Rpc.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Backend of class  
SyncML_Backend_Horde created [pid 17426 on line 31 of  
"/var/www/_horde/horde/framework/SyncML/SyncML/Backend/Horde.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Started at 2010-12-09  
19:44:03. Packet logged in /tmp/sync/client_60.xml [pid 17426 on line  
108 of "/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] New session created:   
[pid 17426 on line 376 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Invalid authentication  
[pid 17426 on line 295 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Authenticated: no;  
version: 1.2; message ID: 2; source URI: aku-1291724651; target URI:  
http://hosting.aktionspotenzial.de/horde-git/rpc.php; user: ; charset:  
UTF-8; wbxml: no [pid 17426 on line 295 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Received <Final> from  
client. [pid 17426 on line 446 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Sending <Final> to  
client [pid 17426 on line 310 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Session completed and  
closed [pid 17426 on line 310 of  
"/var/www/_horde/horde/framework/SyncML/SyncML.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] PHP ERROR:  
session_destroy() [<a  
href='function.session-destroy'>function.session-destroy</a>]: Trying  
to destroy uninitialized session [pid 17426 on line 432 of  
"/var/www/_horde/horde/framework/SyncML/SyncML/Backend.php"]
2010-12-09T19:44:03+01:00 DEBUG: HORDE [horde] Finished at 2010-12-09  
19:44:03. Packet logged in /tmp/sync/server_60.xml [pid 17426 on line  
176 of "/var/www/_horde/horde/framework/SyncML/SyncML.php"]

so somehow session handling is borked?