search

owncloud / core

:cloud: ownCloud web server core (Files, DAV, etc.)
https://owncloud.com
GNU Affero General Public License v3.0
8.36k stars 2.06k forks source link

Syncevolution stopps due to 403 error Access denied #12809

Closed ghost closed 9 years ago

ghost commented 9 years ago

Steps to reproduce

  1. try to sync normal

    Expected behaviour

Server and client should sync calendar events

Actual behaviour

Sync stopps due to 403 error access denied

Server configuration

Operating system: Ubunut 14.04 Web server: Apache2 Database: sqlite PHP version: 5.5.9-1ubuntu4.5 ownCloud version: (see ownCloud admin page) 7.0.4 (issue since 7.0.0.) Updated from an older ownCloud or fresh install: fresh List of activated apps: none The content of config/config.php:

<?php
$CONFIG = array (
  'instanceid' => 'oc2eda8a0b6f',
  'passwordsalt' => '',
  'secret' => '',
  'trusted_domains' =>
  array (
    0 => 'xorlosch.diskstation.org',
  ),
  'datadirectory' => '/var/www/owncloud/data',
  'overwrite.cli.url' => 'https://xorlosch.diskstation.org/owncloud',
  'dbtype' => 'sqlite3',
  'version' => '7.0.4.2',
  'installed' => true,
  'mail_smtpmode' => 'smtp',
  'mail_smtpsecure' => 'tls',
  'loglevel' => '0',
);

Are you using external storage, if yes which one: local/smb/sftp/... no Are you using encryption: yes/no SSL?Tried both same result

Client configuration

Browser: N/A Operating system: SailfishOS

Logs

Web server error log

[Fri Dec 12 09:53:51.774309 2014] [mpm_prefork:notice] [pid 2461] AH00163: Apache/2.4.7 (Ubuntu) PHP/5.5.9-1ubuntu4.5 OpenSSL/1.0.1f configured -- resuming normal operations
[Fri Dec 12 09:53:51.774317 2014] [core:notice] [pid 2461] AH00094: Command line: '/usr/sbin/apache2'
[Fri Dec 12 09:55:00.768581 2014] [access_compat:error] [pid 25009] [client 93.215.168.235:60698] AH01797: client denied by server configuration: /var/www/owncloud/data/htaccesstest.txt
[Fri Dec 12 09:55:01.920686 2014] [access_compat:error] [pid 25199] [client 93.215.168.235:60704] AH01797: client denied by server configuration: /var/www/owncloud/data/htaccesstest.txt
[Fri Dec 12 09:55:29.940751 2014] [access_compat:error] [pid 25202] [client 93.215.168.235:60707] AH01797: client denied by server configuration: /var/www/owncloud/data/htaccesstest.txt
[Fri Dec 12 09:55:29.974829 2014] [:error] [pid 25200] [client 93.215.168.235:60706] creating sqlite db, referer: https://xorlosch.diskstation.org/owncloud/
[Fri Dec 12 09:56:55.028146 2014] [access_compat:error] [pid 25174] [client 93.215.168.235:60726] AH01797: client denied by server configuration: /var/www/owncloud/data/htaccesstest.txt
[Fri Dec 12 10:11:08.136122 2014] [access_compat:error] [pid 25215] [client 93.215.168.235:60758] AH01797: client denied by server configuration: /var/www/owncloud/data/htaccesstest.txt
[Fri Dec 12 10:29:33.584082 2014] [access_compat:error] [pid 28203] [client 93.215.168.235:60857] AH01797: client denied by server configuration: /var/www/owncloud/data/htaccesstest.txt

ownCloud log (data/owncloud.log)

{"app":"PHP","message":"fopen(\/var\/www\/owncloud\/config\/config.php): failed to open stream: No such file or directory at \/var\/www\/owncloud\/lib\/private\/config.php#141","level":3,"time":"2014-12-12T08:55:00+00:00"}
{"reqId":"548ab1324fca5","app":"core","message":"OC\\Tags::loadTags, tags: Array\n(\n    [11] => Anderes\n    [3] => Anruf\n    [15] => Arbeit\n    [14] => Fragen\n    [1] => Geburtstag\n    [2] => Gesch\u00e4ftlich\n    [7] => Ideen\n    [9] => Jubil\u00e4um\n    [4] => Kunden\n    [5] => Lieferant\n    [12] => Pers\u00f6nlich\n    [13] => Projekte\n    [8] => Reise\n    [10] => Treffen\n    [6] => Urlaub\n)\n","level":0,"time":"2014-12-12T09:11:14+00:00","method":"GET","url":"\/owncloud\/index.php\/apps\/calendar\/js\/l10n.php"}
{"reqId":"548ab1324fca5","app":"core","message":"OC\\Tags::loadTags, tags: Array\n(\n    [11] => Anderes\n    [3] => Anruf\n    [15] => Arbeit\n    [14] => Fragen\n    [1] => Geburtstag\n    [2] => Gesch\u00e4ftlich\n    [7] => Ideen\n    [9] => Jubil\u00e4um\n    [4] => Kunden\n    [5] => Lieferant\n    [12] => Pers\u00f6nlich\n    [13] => Projekte\n    [8] => Reise\n    [10] => Treffen\n    [6] => Urlaub\n)\n","level":0,"time":"2014-12-12T09:11:14+00:00","method":"GET","url":"\/owncloud\/index.php\/apps\/calendar\/js\/l10n.php"}
{"reqId":"548ab624e4513","app":"core","message":"OC\\Tags::loadTags, tags: Array\n(\n    [11] => Anderes\n    [3] => Anruf\n    [15] => Arbeit\n    [14] => Fragen\n    [1] => Geburtstag\n    [2] => Gesch\u00e4ftlich\n    [7] => Ideen\n    [9] => Jubil\u00e4um\n    [4] => Kunden\n    [5] => Lieferant\n    [12] => Pers\u00f6nlich\n    [13] => Projekte\n    [8] => Reise\n    [10] => Treffen\n    [6] => Urlaub\n)\n","level":0,"time":"2014-12-12T09:32:20+00:00","method":"GET","url":"\/owncloud\/index.php\/apps\/calendar\/js\/l10n.php"}
{"reqId":"548ab624e4513","app":"core","message":"OC\\Tags::loadTags, tags: Array\n(\n    [11] => Anderes\n    [3] => Anruf\n    [15] => Arbeit\n    [14] => Fragen\n    [1] => Geburtstag\n    [2] => Gesch\u00e4ftlich\n    [7] => Ideen\n    [9] => Jubil\u00e4um\n    [4] => Kunden\n    [5] => Lieferant\n    [12] => Pers\u00f6nlich\n    [13] => Projekte\n    [8] => Reise\n    [10] => Treffen\n    [6] => Urlaub\n)\n","level":0,"time":"2014-12-12T09:32:20+00:00","method":"GET","url":"\/owncloud\/index.php\/apps\/calendar\/js\/l10n.php"}
{"reqId":"548ab6285a40d","app":"core","message":"OC\\Tags::loadTags, tags: Array\n(\n    [11] => Anderes\n    [3] => Anruf\n    [15] => Arbeit\n    [14] => Fragen\n    [1] => Geburtstag\n    [2] => Gesch\u00e4ftlich\n    [7] => Ideen\n    [9] => Jubil\u00e4um\n    [4] => Kunden\n    [5] => Lieferant\n    [12] => Pers\u00f6nlich\n    [13] => Projekte\n    [8] => Reise\n    [10] => Treffen\n    [6] => Urlaub\n)\n","level":0,"time":"2014-12-12T09:32:24+00:00","method":"POST","url":"\/owncloud\/index.php\/apps\/calendar\/ajax\/event\/edit.form.php"}
{"reqId":"548ab6285a40d","app":"core","message":"OC\\Tags::loadTags, tags: Array\n(\n    [11] => Anderes\n    [3] => Anruf\n    [15] => Arbeit\n    [14] => Fragen\n    [1] => Geburtstag\n    [2] => Gesch\u00e4ftlich\n    [7] => Ideen\n    [9] => Jubil\u00e4um\n    [4] => Kunden\n    [5] => Lieferant\n    [12] => Pers\u00f6nlich\n    [13] => Projekte\n    [8] => Reise\n    [10] => Treffen\n    [6] => Urlaub\n)\n","level":0,"time":"2014-12-12T09:32:24+00:00","method":"POST","url":"\/owncloud\/index.php\/apps\/calendar\/ajax\/event\/edit.form.php"}
{"reqId":"548ab65f219f1","app":"core","message":"OC\\Tags::loadTags, tags: Array\n(\n    [11] => Anderes\n    [3] => Anruf\n    [15] => Arbeit\n    [14] => Fragen\n    [1] => Geburtstag\n    [2] => Gesch\u00e4ftlich\n    [7] => Ideen\n    [9] => Jubil\u00e4um\n    [4] => Kunden\n    [5] => Lieferant\n    [12] => Pers\u00f6nlich\n    [13] => Projekte\n    [8] => Reise\n    [10] => Treffen\n    [6] => Urlaub\n)\n","level":0,"time":"2014-12-12T09:33:19+00:00","method":"GET","url":"\/owncloud\/index.php\/apps\/calendar\/js\/l10n.php"}
{"reqId":"548ab65f219f1","app":"core","message":"OC\\Tags::loadTags, tags: Array\n(\n    [11] => Anderes\n    [3] => Anruf\n    [15] => Arbeit\n    [14] => Fragen\n    [1] => Geburtstag\n    [2] => Gesch\u00e4ftlich\n    [7] => Ideen\n    [9] => Jubil\u00e4um\n    [4] => Kunden\n    [5] => Lieferant\n    [12] => Pers\u00f6nlich\n    [13] => Projekte\n    [8] => Reise\n    [10] => Treffen\n    [6] => Urlaub\n)\n","level":0,"time":"2014-12-12T09:33:19+00:00","method":"GET","url":"\/owncloud\/index.php\/apps\/calendar\/js\/l10n.php"}
{"reqId":"548ab7a85bef9","app":"webdav","message":"Sabre\\DAV\\Exception\\NotAuthenticated: No basic authentication headers were found","level":0,"time":"2014-12-12T09:38:48+00:00","method":"PROPFIND","url":"\/owncloud\/remote.php\/webdav\/"}
{"reqId":"548ac392f2101","app":"PHP","message":"apc_store(): GC cache entry 'oc2eda8a0b6f\/AutoloaderDoctrine\\DBAL\\Driver' was on gc-list for 4319 seconds at \/var\/www\/owncloud\/lib\/private\/memcache\/apc.php#21","level":3,"time":"2014-12-12T10:29:38+00:00","method":"REPORT","url":"\/owncloud\/remote.php\/caldav\/"}

Browser log

SyncEvolution Log:

<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN" "http://www.w3.org/TR/html4/loose.dtd">
<html><head><title>SySync SyncML Engine 3.4.0.47 Log</title>
<meta http-equiv="content-type" content="text/html;charset=UTF-8">
<style type="text/css" media="screen"><!--
.block { color: #0000FF; font-weight: bold; }
.attribute { color: #A5002C; }
.attrval { color: #D80039; font-weight: bold; }
.error { color: red; font-weight: bold; }
.hotalone { color: #000000; font-weight: bold; }
.hot { font-weight: bold; }
.script { color: #996633; }
.source { color: #3333FF; font-family:courier,monospace; font-size: 90%; font-weight: bold; }
.comment { color: #669933; font-family:courier,monospace; font-size: 90%; font-weight: bold; }
.skipped { color: #BBBBBB; font-family:courier,monospace; font-size: 90%; font-weight: bold; }
.value { color: #FF3300; }
.filter { color: #997F66; }
.match { color: #A95E38; }
.dbapi { color: #CC3366; }
.plugin { color: #9151A3; }
.incoming { color: #196D00; }
.outgoing { color: #002C84; }
.conflict { color: #990000; }
.remote { color: #709900; }
.proto { color: #777100; }
.rest { color: #AAAAAA; }
.exotic { color: #FF9900; }
a.jump { color: #5D82BA; }
pre { font-size: 90%; }
.exp {
 color: #FF0000;
 font-weight: bold;
 font-size: 90%;
 width: 1em;
 height: 1em;
 display: inline;
 border-width: 0.2em;
 border-style: solid;
 text-align: center;
 vertical-align: middle;
 padding: 0px 0.2em 0px 0.2em;
 margin: 0 4px 2px 0;
}
.coll {
 color: #754242;
 font-weight: bold;
 font-size: 90%;
 width: 1em;
 height: 1em;
 display: inline;
 border-width: 0.2em;
 border-style: solid;
 text-align: center;
 vertical-align: middle;
 padding: 0px 0.2em 0px 0.2em;
 margin: 0 4px 2px 0;
}
.doall { color: #754242; }
--></style>
</head><body><h2>Start of log - SySync SyncML Engine 3.4.0.47</h2>
<ul>

<script language=javascript1.2 type=text/javascript><!--
function div_ref_style (id) {
 if      (document.layers)         return document.layers[id];
 else if (document.all)            return document.all[id].style;
 else if (document.getElementById) return document.getElementById(id).style;
 else                              return null;
}
function exp(id) {
 if(div_ref_style('B'+id).display!='block') {
  div_ref_style('B'+id).display='block';
  div_ref_style('E'+id).display='none';
  div_ref_style('C'+id).display='inline';
 }
}
function coll(id) {
 if(div_ref_style('B'+id).display!='none') {
  div_ref_style('B'+id).display='none';
  div_ref_style('E'+id).display='inline';
  div_ref_style('C'+id).display='none';
 }
}
function doall(id,collapse) {
 // get parent element
 if (id=='') {
  mydiv=document;
 }
 else {
  mydiv=document.getElementById('B'+id); // get div to collapse or expand
  if (collapse) {
   coll(id);
  }
  else {
   exp(id);
  }
 }
 // get all contained blocks
 divs=mydiv.getElementsByTagName('div')  // all divs
 for (i=0 ; i<divs.length ; i++) {
  if (divs[i].className=='blk') {
   // this is a foldable block div
   bid = divs[i].id.substring(1);
   if (collapse) {
    coll(bid);
   }
   else {
    exp(bid);
   }
  }
 }
}
--></script>
<li><span class="doall" onclick="doall('',true)">[-- collapse all --]</span><span class="doall" onclick="doall('',false)">[++ expand all ++]</span></li>

<li><i>[2014-12-12 11:43:51.160]</i>&nbsp;CreateContext SyncEvolution// =&gt; 0</li>
<li><i>[2014-12-12 11:43:51.160]</i>&nbsp;Module_Capabilities:
<br/>PLATFORM:Linux
<br/>DLL:true
<br/>MINVERSION:V1.0.6.0
<br/>MANUFACTURER:SyncEvolution
<br/>DESCRIPTION:SyncEvolution Synthesis DB Plugin
<br/>plugin_datastore_str:no
<br/>plugin_datastore_key:yes
<br/>ITEM_AS_KEY:yes
<br/>plugin_datablob:no</li>
<li><i>[2014-12-12 11:43:51.163]</i>&nbsp;Module_PluginParams
<br/> Engine=01090100
<br/> </li>
<li><span class="block"><div id="E1" style="display:inline" class="exp" onclick="exp('1')">+</div><div id="C1" style="display:none" class="coll" onclick="coll('1')">&ndash;</div><a name="H1">[2014-12-12 11:43:51.166] 'loadSystemZoneDefinitions' - Linux system time zones</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('1',true)">[--]</span><span class="doall" onclick="doall('1',false)">[++]</span>&nbsp;<a class="jump" href="#F1">[->end]</a><div class="blk" id="B1" style="display:none"><ul>
</ul><span class="block"><span class="coll" onclick="coll('1')">&ndash;</span><a name="F1">[2014-12-12 11:43:51.390] End of 'loadSystemZoneDefinitions'</a></span>&nbsp;<a class="jump" href="#H1">[->top]</a></div></li>
<li><i>[2014-12-12 11:43:51.390]</i>&nbsp;SyncML server account: </li>
<li><i>[2014-12-12 11:43:51.391]</i>&nbsp;client: SyncEvolution 1.3.99.7+20140126+SE+1e6fc71+unclean for Sailfish</li>
<li><i>[2014-12-12 11:43:51.391]</i>&nbsp;device ID: </li>
<li><i>[2014-12-12 11:43:51.391]</i>&nbsp;Scanning backend libraries in /usr/lib/syncevolution/backends/
<br/>Loading backend library syncxmlrpc
<br/>Loading backend library syncsqlite
<br/>Loading backend library syncqtcontacts
<br/>Loading backend library syncpbap
<br/>Loading backend library syncmaemocal
<br/>Loading backend library synckcalextended
<br/>Loading backend library syncfile
<br/>Loading backend library syncecal
<br/>Loading backend library syncebook
<br/>Loading backend library syncdav
<br/>Loading backend library syncakonadi
<br/>Loading backend library syncaddressbook
<br/>Loading backend library syncactivesync
<br/>Loading backend library providergoa
<br/>Loading backend library platformkde
<br/>Loading backend library platformgnome</li>
<li><i>[2014-12-12 11:43:51.405]</i>&nbsp;<span class="hotalone">@owncloud-/addressbook: inactive</span></li>
<li><i>[2014-12-12 11:43:51.414]</i>&nbsp;<span class="hotalone">@owncloud-/memo: inactive</span></li>
<li><i>[2014-12-12 11:43:51.419]</i>&nbsp;<span class="hotalone">@owncloud-/todo: inactive</span></li>
<li><i>[2014-12-12 11:43:51.429]</i>&nbsp;checking password property 'password' in config 'owncloud-@owncloud-' with user identity ''</li>
<li><i>[2014-12-12 11:43:51.430]</i>&nbsp;checking password property 'databasePassword' in source 'calendar' of config 'owncloud-@owncloud-' with user identity ''</li>
<li><i>[2014-12-12 11:43:51.430]</i>&nbsp;sync is starting, catch signals</li>
<li><i>[2014-12-12 11:43:51.430]</i>&nbsp;SuspendFlags: (re)activating, currently active</li>
<li><i>[2014-12-12 11:43:51.430]</i>&nbsp;ready to sync</li>
<li><i>[2014-12-12 11:43:51.466]</i>&nbsp;Module_DeleteContext 'session'</li>
<li><i>[2014-12-12 11:43:51.505]</i>&nbsp;CreateContext SyncEvolution// =&gt; 0</li>
<li><i>[2014-12-12 11:43:51.506]</i>&nbsp;Module_Capabilities:
<br/>PLATFORM:Linux
<br/>DLL:true
<br/>MINVERSION:V1.0.6.0
<br/>MANUFACTURER:SyncEvolution
<br/>DESCRIPTION:SyncEvolution Synthesis DB Plugin
<br/>plugin_datastore_str:no
<br/>plugin_datastore_key:yes
<br/>ITEM_AS_KEY:yes
<br/>plugin_datablob:no</li>
<li><i>[2014-12-12 11:43:51.508]</i>&nbsp;Module_PluginParams
<br/> Engine=01090100
<br/> </li>
<li><i>[2014-12-12 11:43:51.509]</i>&nbsp;CreateContext SyncEvolution//calendar =&gt; 0</li>
<li><i>[2014-12-12 11:43:51.509]</i>&nbsp;Module_Version = 01090100</li>
<li><i>[2014-12-12 11:43:51.509]</i>&nbsp;Module_Capabilities:
<br/>PLATFORM:Linux
<br/>DLL:true
<br/>MINVERSION:V1.0.6.0
<br/>MANUFACTURER:SyncEvolution
<br/>DESCRIPTION:SyncEvolution Synthesis DB Plugin
<br/>plugin_datastore_str:no
<br/>plugin_datastore_key:yes
<br/>ITEM_AS_KEY:yes
<br/>plugin_datablob:yes
<br/>plugin_datastoreadmin:yes</li>
<li><i>[2014-12-12 11:43:51.512]</i>&nbsp;@owncloud-/calendar: Module_PluginParams
<br/> Engine=01090100
<br/> </li>
<li><i>[2014-12-12 11:43:51.512]</i>&nbsp;Module_Capabilities:
<br/>PLATFORM:Linux
<br/>DLL:true
<br/>MINVERSION:V1.0.6.0
<br/>MANUFACTURER:SyncEvolution
<br/>DESCRIPTION:SyncEvolution Synthesis DB Plugin
<br/>plugin_datastore_str:no
<br/>plugin_datastore_key:yes
<br/>ITEM_AS_KEY:yes
<br/>plugin_datablob:yes
<br/>plugin_datastoreadmin:yes</li>
<li><i>[2014-12-12 11:43:51.513]</i>&nbsp;CreateContext SyncEvolution//calendar =&gt; 0</li>
<li><i>[2014-12-12 11:43:51.513]</i>&nbsp;Module_Version = 01090100</li>
<li><i>[2014-12-12 11:43:51.513]</i>&nbsp;Module_Capabilities:
<br/>PLATFORM:Linux
<br/>DLL:true
<br/>MINVERSION:V1.0.6.0
<br/>MANUFACTURER:SyncEvolution
<br/>DESCRIPTION:SyncEvolution Synthesis DB Plugin
<br/>plugin_datastore_str:no
<br/>plugin_datastore_key:yes
<br/>ITEM_AS_KEY:yes
<br/>plugin_datablob:yes
<br/>plugin_datastoreadmin:yes</li>
<li><i>[2014-12-12 11:43:51.515]</i>&nbsp;@owncloud-/calendar: Module_PluginParams
<br/> Engine=01090100
<br/> </li>
<li><i>[2014-12-12 11:43:51.520]</i>&nbsp;ForkExecParent: preparing for child process syncevo-local-sync</li>
<li><i>[2014-12-12 11:43:51.532]</i>&nbsp;setting up D-Bus server on unix:abstract=gdbuscxx-1 failed, trying next address: Error binding to address: Address already in use</li>
<li><i>[2014-12-12 11:43:51.532]</i>&nbsp;ForkExecParent: running /usr/libexec/syncevo-local-sync with D-Bus address unix:abstract=gdbuscxx-2</li>
<li><i>[2014-12-12 11:43:51.540]</i>&nbsp;ForkExecParent: child process for /usr/libexec/syncevo-local-sync has pid 7305</li>
<li><i>[2014-12-12 11:43:51.547]</i>&nbsp;<span class="hotalone">--------- START of embedded log for session ID '7166071432577307786' ---------</span></li>
<li><i>[2014-12-12 11:43:51.547]</i>&nbsp;Executing Script 'sessioninitscript'</li>
<li><i>[2014-12-12 11:43:51.547]</i>&nbsp;<span class="hotalone">==== Server Session started with SyncML (Server+Client) Engine Version 3.4.0.47</span></li>
<li><i>[2014-12-12 11:43:51.547]</i>&nbsp;<span class="hotalone">---- Hardcoded Product name: SySync SyncML Library OpenSource Linux</span></li>
<li><i>[2014-12-12 11:43:51.547]</i>&nbsp;<span class="hotalone">---- Configured Model/Manufacturer: SyncEvolution / Patrick Ohly</span></li>
<li><i>[2014-12-12 11:43:51.548]</i>&nbsp;<span class="hotalone">---- Running on Linux, URI/deviceID='linux:Tofino'</span></li>
<li><i>[2014-12-12 11:43:51.548]</i>&nbsp;<span class="hotalone">---- Process ID = 7298, Thread ID = 1139314688</span></li>
<li><i>[2014-12-12 11:43:51.548]</i>&nbsp;<span class="hotalone">---- Platform Hardware Name/Version = 'Linux PC', Firmware/OS Version = 'unknown'</span></li>
<li><i>[2014-12-12 11:43:51.548]</i>&nbsp;<span class="hotalone">---- Configured Hardware Version = 'unknown', Firmware Version = '1.3.99.7+20140126+SE+1e6fc71+unclean'</span></li>
<li><i>[2014-12-12 11:43:51.548]</i>&nbsp;<span class="hotalone">---- System local time  : 2014-12-12 11:43:51.548  (time zone 'CET/CEST', offset 1:00 hours east of UTC)</span></li>
<li><i>[2014-12-12 11:43:51.548]</i>&nbsp;<span class="hotalone">---- System time in UTC : 2014-12-12 10:43:51.548</span></li>
<li><i>[2014-12-12 11:43:51.548]</i>&nbsp;<span class="hotalone">==== Config file='', Last Change=2014-12-12 10:43:51.000</span></li>
<li><i>[2014-12-12 11:43:51.548]</i>&nbsp;<span class="hotalone">==== Config ID string='SyncEvolution client config'</span></li>
<li><i>[2014-12-12 11:43:51.548]</i>&nbsp;<span class="proto">Created command 'Status' (outgoing)</span></li>
<li><i>[2014-12-12 11:43:51.549]</i>&nbsp;Session_CreateContext '7166071432577307786' found</li>
<li><i>[2014-12-12 11:43:51.549]</i>&nbsp;waiting for child to send message</li>
<li><i>[2014-12-12 11:43:51.718]</i>&nbsp;Client connected.
<br/>Peer credentials: GCredentials:linux-ucred:pid=7305,uid=100000,gid=998
<br/>Negotiated capabilities: unix-fd-passing=1</li>
<li><i>[2014-12-12 11:43:51.718]</i>&nbsp;ForkExecParent: child syncevo-local-sync 7305 has connected</li>
<li><i>[2014-12-12 11:43:51.718]</i>&nbsp;child is ready</li>
<li><i>[2014-12-12 11:43:51.722]</i>&nbsp;ForkExecParentDBusAPI /org/syncevolution/forkexec/parent/forkexec1: received 'Watch' method call from child</li>
<li><span class="block"><div id="E2" style="display:none" class="exp" onclick="exp('2')">+</div><div id="C2" style="display:inline" class="coll" onclick="coll('2')">&ndash;</div><a name="H2">[2014-12-12 11:43:52.104] 'SyncML_Outgoing' - preparing for response before starting to analyze new incoming message</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('2',true)">[--]</span><span class="doall" onclick="doall('2',false)">[++]</span>&nbsp;<a class="jump" href="#F2">[->end]</a><div class="blk" id="B2" style="display:inline"><ul>
  <li><span class="block"><div id="E3" style="display:none" class="exp" onclick="exp('3')">+</div><div id="C3" style="display:inline" class="coll" onclick="coll('3')">&ndash;</div><a name="H3">[2014-12-12 11:43:52.105] 'SyncML_Incoming' - Starting to analyze incoming message</a></span><span class="attribute">, RequestNo=<span class="attrval">0</span>, SySyncVers=<span class="attrval">3.4.0.47</span></span>&nbsp;<span class="doall" onclick="doall('3',true)">[--]</span><span class="doall" onclick="doall('3',false)">[++]</span>&nbsp;<a class="jump" href="#F3">[->end]</a>&nbsp;<a class="jump" href="#H2">[->enclosing]</a><div class="blk" id="B3" style="display:inline"><ul>
    <li><i>[2014-12-12 11:43:52.105]</i>&nbsp;<span class="hotalone">=================&gt; Starting to analyze incoming message, SySync V3.4.0.47, RequestNo=0</span></li>
    <li><i>[2014-12-12 11:43:52.105]</i>&nbsp;<span class="proto">Created command 'SyncHdr' (incoming)</span></li>
    <li><span class="block"><div id="E4" style="display:none" class="exp" onclick="exp('4')">+</div><div id="C4" style="display:inline" class="coll" onclick="coll('4')">&ndash;</div><a name="H4">[2014-12-12 11:43:52.105] 'processHdr' - Processing incoming SyncHdr</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('4',true)">[--]</span><span class="doall" onclick="doall('4',false)">[++]</span>&nbsp;<a class="jump" href="#F4">[->end]</a>&nbsp;<a class="jump" href="#H3">[->enclosing]</a><div class="blk" id="B4" style="display:inline"><ul>
      <li><span class="block"><div id="E5" style="display:none" class="exp" onclick="exp('5')">+</div><div id="C5" style="display:inline" class="coll" onclick="coll('5')">&ndash;</div><a name="H5">[2014-12-12 11:43:52.105] 'SyncHdr' - Processing incoming SyncHdr</a></span><span class="attribute">, IncomingMsgID=<span class="attrval">1</span></span>&nbsp;<span class="doall" onclick="doall('5',true)">[--]</span><span class="doall" onclick="doall('5',false)">[++]</span>&nbsp;<a class="jump" href="#F5">[->end]</a>&nbsp;<a class="jump" href="#H4">[->enclosing]</a><div class="blk" id="B5" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:52.106]</i>&nbsp;<span class="hotalone">Started Processing of message #1 (SyncML/1.2)</span></li>
        <li><i>[2014-12-12 11:43:52.106]</i>&nbsp;<span class="remote">MaxMsgSize found in SyncHdr: 150000 -&gt; set for outgoing msgs</span></li>
        <li><i>[2014-12-12 11:43:52.106]</i>&nbsp;<span class="remote">MaxObjSize found in SyncHdr: 4000000</span></li>
        <li><i>[2014-12-12 11:43:52.106]</i>&nbsp;<span class="proto">Created command 'Status' (outgoing)</span></li>
        <li><i>[2014-12-12 11:43:52.106]</i>&nbsp;<span class="error">Missing clear-text username in Source LocName (SyncML 1.0.1)</span></li>
        <li><i>[2014-12-12 11:43:52.106]</i>&nbsp;Executing Script 'logininitscript'</li>
<li><i>[2014-12-12 11:43:52.106]</i>&nbsp;Session_CheckDevice dev='syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500' nonce='9X%^^"!!' res=0</li>
        <li><i>[2014-12-12 11:43:52.106]</i>&nbsp;<span class="hotalone">==== Config file='', Last Change=2014-12-12 10:43:51.000</span></li>
        <li><i>[2014-12-12 11:43:52.106]</i>&nbsp;<span class="hotalone">==== Config ID string='SyncEvolution client config'</span></li>
        <li><i>[2014-12-12 11:43:52.106]</i>&nbsp;<span class="hotalone">==== Authorisation successful with SyncML Engine Version 3.4.0.47</span></li>
        <li><i>[2014-12-12 11:43:52.107]</i>&nbsp;<span class="hotalone">==== SyncML URL used = '', username as sent by remote = ''</span></li>
<li><i>[2014-12-12 11:43:52.107]</i>&nbsp;Session_SaveNonce nonce='LM%C=!!!'</li>
        <li><i>[2014-12-12 11:43:52.124]</i>&nbsp;<span class="proto">Challenge for next auth: AuthType=(null), Nonce='LM%C=!!!', binary allowed</span></li>
        <li><i>[2014-12-12 11:43:52.125]</i>&nbsp;<span class="proto">Authorization accepted: 212</span></li>
        <li><i>[2014-12-12 11:43:52.125]</i>&nbsp;<span class="proto">Created command 'SyncHdr' (outgoing)</span></li>
        <li><i>[2014-12-12 11:43:52.125]</i>&nbsp;<span class="proto">Target (Remote URI) = 'syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500'</span></li>
        <li><span class="block"><div id="E6" style="display:none" class="exp" onclick="exp('6')">+</div><div id="C6" style="display:inline" class="coll" onclick="coll('6')">&ndash;</div><a name="H6">[2014-12-12 11:43:52.125] 'SyncHdr' - SyncHdr generation</a></span><span class="attribute">, SyncMLVers=<span class="attrval">1.2</span>, OutgoingMsgID=<span class="attrval">1</span></span>&nbsp;<span class="doall" onclick="doall('6',true)">[--]</span><span class="doall" onclick="doall('6',false)">[++]</span>&nbsp;<a class="jump" href="#F6">[->end]</a>&nbsp;<a class="jump" href="#H5">[->enclosing]</a><div class="blk" id="B6" style="display:inline"><ul>
          <li><i>[2014-12-12 11:43:52.125]</i>&nbsp;<span class="proto">SyncHdr: issued in MsgID=1, now queueing for status</span></li>
          <li><i>[2014-12-12 11:43:52.125]</i>&nbsp;<span class="proto">Outgoing Message size is now 164 bytes</span></li>
        </ul><span class="block"><span class="coll" onclick="coll('6')">&ndash;</span><a name="F6">[2014-12-12 11:43:52.125] End of 'SyncHdr'</a></span>&nbsp;<a class="jump" href="#H6">[->top]</a>&nbsp;<a class="jump" href="#F5">[->enclosing]</a></div></li>
        <li><span class="block"><div id="E7" style="display:none" class="exp" onclick="exp('7')">+</div><div id="C7" style="display:inline" class="coll" onclick="coll('7')">&ndash;</div><a name="H7">[2014-12-12 11:43:52.125] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Status</span></span>&nbsp;<span class="doall" onclick="doall('7',true)">[--]</span><span class="doall" onclick="doall('7',false)">[++]</span>&nbsp;<a class="jump" href="#F7">[->end]</a>&nbsp;<a class="jump" href="#H5">[->enclosing]</a><div class="blk" id="B7" style="display:inline"><ul>
          <li><i>[2014-12-12 11:43:52.126]</i>&nbsp;<span class="hotalone">Status Code 200 issued for Cmd=SyncHdr, (incoming MsgID=1, CmdID=0)</span></li>
          <li><i>[2014-12-12 11:43:52.126]</i>&nbsp;<span class="hotalone">- SourceRef (remoteID) = 'syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500'</span></li>
          <li><i>[2014-12-12 11:43:52.126]</i>&nbsp;<span class="hotalone">Status: issued as (outgoing MsgID=1, CmdID=1), not waiting for status</span></li>
          <li><i>[2014-12-12 11:43:52.126]</i>&nbsp;<span class="proto">Deleted command 'Status' (outgoing MsgID=1, CmdID=1)</span></li>
          <li><i>[2014-12-12 11:43:52.126]</i>&nbsp;<span class="proto">Outgoing Message size is now 319 bytes</span></li>
        </ul><span class="block"><span class="coll" onclick="coll('7')">&ndash;</span><a name="F7">[2014-12-12 11:43:52.126] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H7">[->top]</a>&nbsp;<a class="jump" href="#F5">[->enclosing]</a></div></li>
        <li><i>[2014-12-12 11:43:52.126]</i>&nbsp;<span class="hotalone">Incoming SyncHdr processed, incomingMsgID=1, SyncMLVers=1.2</span></li>
        <li><i>[2014-12-12 11:43:52.126]</i>&nbsp;<span class="hotalone">- Session ID='18'</span></li>
        <li><i>[2014-12-12 11:43:52.126]</i>&nbsp;<span class="hotalone">- Source (Remote party): URI='syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500' DisplayName=''</span></li>
        <li><i>[2014-12-12 11:43:52.126]</i>&nbsp;<span class="hotalone">- Response to be sent to URI='[none specified, back to source]'</span></li>
        <li><i>[2014-12-12 11:43:52.126]</i>&nbsp;<span class="hotalone">- Target (Local party) : URI='' DisplayName=''</span></li>
        <li><i>[2014-12-12 11:43:52.127]</i>&nbsp;<span class="proto">Deleted command 'SyncHdr' (incoming MsgID=1, CmdID=0)</span></li>
      </ul><span class="block"><span class="coll" onclick="coll('5')">&ndash;</span><a name="F5">[2014-12-12 11:43:52.127] End of 'SyncHdr'</a></span>&nbsp;<a class="jump" href="#H5">[->top]</a>&nbsp;<a class="jump" href="#F4">[->enclosing]</a></div></li>
    </ul><span class="block"><span class="coll" onclick="coll('4')">&ndash;</span><a name="F4">[2014-12-12 11:43:52.127] End of 'processHdr'</a></span>&nbsp;<a class="jump" href="#H4">[->top]</a>&nbsp;<a class="jump" href="#F3">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:52.128]</i>&nbsp;<span class="proto">Created command 'Put' (incoming)</span></li>
    <li><i>[2014-12-12 11:43:52.128]</i>&nbsp;<span class="hotalone">Started processing Command 'Put' (incoming MsgID=1, CmdID=1)</span></li>
    <li><span class="block"><div id="E8" style="display:none" class="exp" onclick="exp('8')">+</div><div id="C8" style="display:inline" class="coll" onclick="coll('8')">&ndash;</div><a name="H8">[2014-12-12 11:43:52.128] 'processCmd' - Processing incoming command</a></span><span class="attribute">, Cmd=<span class="attrval">Put</span>, IncomingMsgID=<span class="attrval">1</span>, CmdID=<span class="attrval">1</span></span>&nbsp;<span class="doall" onclick="doall('8',true)">[--]</span><span class="doall" onclick="doall('8',false)">[++]</span>&nbsp;<a class="jump" href="#F8">[->end]</a>&nbsp;<a class="jump" href="#H3">[->enclosing]</a><div class="blk" id="B8" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:52.128]</i>&nbsp;<span class="hotalone">processing item with locURI=./devinf12</span></li>
      <li><i>[2014-12-12 11:43:52.128]</i>&nbsp;<span class="proto">Created command 'Status' (outgoing)</span></li>
      <li><span class="block"><div id="E9" style="display:none" class="exp" onclick="exp('9')">+</div><div id="C9" style="display:inline" class="coll" onclick="coll('9')">&ndash;</div><a name="H9">[2014-12-12 11:43:52.128] 'DevInf_Analyze' - Analyzing remote devInf</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('9',true)">[--]</span><span class="doall" onclick="doall('9',false)">[++]</span>&nbsp;<a class="jump" href="#F9">[->end]</a>&nbsp;<a class="jump" href="#H8">[->enclosing]</a><div class="blk" id="B9" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:52.128]</i>&nbsp;<span class="remote"><span class="hot">Device ID='syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500', Type='Sailfish', Model='SyncEvolution'</span></span></li>
        <li><i>[2014-12-12 11:43:52.129]</i>&nbsp;<span class="remote"><span class="hot">Manufacturer='Patrick Ohly', OEM='Synthesis AG'</span></span></li>
        <li><i>[2014-12-12 11:43:52.129]</i>&nbsp;<span class="remote"><span class="hot">Softwarevers='3.4.0.47', Firmwarevers='1.3.99.7+20140126+SE+1e6fc71+u', Hardwarevers='unknown'</span></span></li>
        <li><i>[2014-12-12 11:43:52.129]</i>&nbsp;<span class="remote"><span class="hot">SyncML Version: SyncML/1.2</span></span></li>
        <li><i>[2014-12-12 11:43:52.129]</i>&nbsp;<span class="remote"><span class="hot">SyncML capability flags: wantsNOC=Yes, canHandleUTC=Yes, supportsLargeObjs=Yes</span></span></li>
        <li><span class="block"><div id="E10" style="display:none" class="exp" onclick="exp('10')">+</div><div id="C10" style="display:inline" class="coll" onclick="coll('10')">&ndash;</div><a name="H10">[2014-12-12 11:43:52.129] 'RemoteRules' - Checking for remote rules</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('10',true)">[--]</span><span class="doall" onclick="doall('10',false)">[++]</span>&nbsp;<a class="jump" href="#F10">[->end]</a>&nbsp;<a class="jump" href="#H9">[->enclosing]</a><div class="blk" id="B10" style="display:inline"><ul>
          <li><i>[2014-12-12 11:43:52.129]</i>&nbsp;<span class="hotalone">Found &lt;remoterule&gt; 'SyncEvolution' matching for this peer</span></li>
          <li><i>[2014-12-12 11:43:52.129]</i>&nbsp;<span class="hotalone">- rule also activates sub-rule 'HAVE-SYNCEVOLUTION-EXDATE-DETACHED'</span></li>
          <li><i>[2014-12-12 11:43:52.129]</i>&nbsp;<span class="hotalone">- rule also activates sub-rule 'HAVE-EVOLUTION-UI-SLOT'</span></li>
        </ul><span class="block"><span class="coll" onclick="coll('10')">&ndash;</span><a name="F10">[2014-12-12 11:43:52.129] End of 'RemoteRules'</a></span>&nbsp;<a class="jump" href="#H10">[->top]</a>&nbsp;<a class="jump" href="#F9">[->enclosing]</a></div></li>
        <li><i>[2014-12-12 11:43:52.129]</i>&nbsp;<span class="remote"><span class="hot">Summary of all behaviour options (possibly modified by remote rule(s))</span></span></li>
        <li><i>[2014-12-12 11:43:52.129]</i>&nbsp;<span class="remote"><span class="hot">- Remote Description        : Patrick Ohly SyncEvolution</span></span></li>
        <li><i>[2014-12-12 11:43:52.129]</i>&nbsp;<span class="remote"><span class="hot">- Legacy mode               : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Lenient mode              : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Limited Field Lengths     : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Do not send empty props   : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Quote 8bit content        : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Prevent Content Folding   : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- No replace in slowsync    : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Treat remote TZ as local  : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Treat remote TZ as UTC    : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Use 23:59:59 end dates    : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Ignore field maxSize      : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Ignore CTCap              : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- send DS path in devInf    : Yes</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- send DS CGI in devInf     : Yes</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Update Client in slowsync : Yes</span></span></li>
        <li><i>[2014-12-12 11:43:52.130]</i>&nbsp;<span class="remote"><span class="hot">- Update Server in slowsync : Yes</span></span></li>
        <li><i>[2014-12-12 11:43:52.131]</i>&nbsp;<span class="remote"><span class="hot">- Allow message retries     : Yes</span></span></li>
        <li><i>[2014-12-12 11:43:52.131]</i>&nbsp;<span class="remote"><span class="hot">- Strict SyncML exec order  : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.131]</i>&nbsp;<span class="remote"><span class="hot">- Treat copy like add       : No</span></span></li>
        <li><i>[2014-12-12 11:43:52.131]</i>&nbsp;<span class="remote"><span class="hot">- Complete From-Client-Only : Yes</span></span></li>
        <li><i>[2014-12-12 11:43:52.131]</i>&nbsp;<span class="remote"><span class="hot">- Remote can handle UTC     : Yes</span></span></li>
        <li><i>[2014-12-12 11:43:52.131]</i>&nbsp;<span class="remote"><span class="hot">- Max Request time [sec]    : 0</span></span></li>
        <li><i>[2014-12-12 11:43:52.131]</i>&nbsp;<span class="remote"><span class="hot">- Content output charset    : UTF-8</span></span></li>
        <li><i>[2014-12-12 11:43:52.131]</i>&nbsp;<span class="remote"><span class="hot">- Content input charset     : UTF-8</span></span></li>
        <li><span class="block"><div id="E11" style="display:none" class="exp" onclick="exp('11')">+</div><div id="C11" style="display:inline" class="coll" onclick="coll('11')">&ndash;</div><a name="H11">[2014-12-12 11:43:52.131] 'RemoteDatastores' - Analyzing remote datastores</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('11',true)">[--]</span><span class="doall" onclick="doall('11',false)">[++]</span>&nbsp;<a class="jump" href="#F11">[->end]</a>&nbsp;<a class="jump" href="#H9">[->enclosing]</a><div class="blk" id="B11" style="display:inline"><ul>
          <li><span class="block"><div id="E12" style="display:none" class="exp" onclick="exp('12')">+</div><div id="C12" style="display:inline" class="coll" onclick="coll('12')">&ndash;</div><a name="H12">[2014-12-12 11:43:52.131] 'RemoteDSDevInf' - Registering remote Datastore from devInf</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('12',true)">[--]</span><span class="doall" onclick="doall('12',false)">[++]</span>&nbsp;<a class="jump" href="#F12">[->end]</a>&nbsp;<a class="jump" href="#H11">[->enclosing]</a><div class="blk" id="B12" style="display:inline"><ul>
            <li><i>[2014-12-12 11:43:52.131]</i>&nbsp;<span class="remote"><span class="hot">Remote Datastore Name='./calendar', DisplayName='calendar', MaxGUIDSize=64</span></span></li>
            <li><i>[2014-12-12 11:43:52.131]</i>&nbsp;<span class="remote">Preferred Rx='text/calendar' version '2.0', preferred Tx='text/calendar' version '2.0'</span></li>
            <li><span class="block"><div id="E13" style="display:none" class="exp" onclick="exp('13')">+</div><div id="C13" style="display:inline" class="coll" onclick="coll('13')">&ndash;</div><a name="H13">[2014-12-12 11:43:52.131] 'RemoteTypes' - Analyzing remote types listed in datastore level CTCap</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('13',true)">[--]</span><span class="doall" onclick="doall('13',false)">[++]</span>&nbsp;<a class="jump" href="#F13">[->end]</a>&nbsp;<a class="jump" href="#H12">[->enclosing]</a><div class="blk" id="B13" style="display:inline"><ul>
              <li><span class="block"><div id="E14" style="display:inline" class="exp" onclick="exp('14')">+</div><div id="C14" style="display:none" class="coll" onclick="coll('14')">&ndash;</div><a name="H14">[2014-12-12 11:43:52.132] 'RemoteCTCap' - Registering remote Type/Version from >=DS 1.2 style CTCap</a></span><span class="attribute">, type=<span class="attrval">text/calendar</span>, version=<span class="attrval">2.0</span></span>&nbsp;<span class="doall" onclick="doall('14',true)">[--]</span><span class="doall" onclick="doall('14',false)">[++]</span>&nbsp;<a class="jump" href="#F14">[->end]</a>&nbsp;<a class="jump" href="#H13">[->enclosing]</a><div class="blk" id="B14" style="display:none"><ul>
                <li><i>[2014-12-12 11:43:52.132]</i>&nbsp;<span class="remote"><span class="hot">Registered Type 'text/calendar' Version='2.0', implemented by local type iCalendar20, related to remote datastore './calendar'</span></span></li>
              </ul><span class="block"><span class="coll" onclick="coll('14')">&ndash;</span><a name="F14">[2014-12-12 11:43:52.132] End of 'RemoteCTCap'</a></span>&nbsp;<a class="jump" href="#H14">[->top]</a>&nbsp;<a class="jump" href="#F13">[->enclosing]</a></div></li>
              <li><span class="block"><div id="E15" style="display:inline" class="exp" onclick="exp('15')">+</div><div id="C15" style="display:none" class="coll" onclick="coll('15')">&ndash;</div><a name="H15">[2014-12-12 11:43:52.132] 'RemoteCTCap' - Registering remote Type/Version from >=DS 1.2 style CTCap</a></span><span class="attribute">, type=<span class="attrval">text/x-vcalendar</span>, version=<span class="attrval">1.0</span></span>&nbsp;<span class="doall" onclick="doall('15',true)">[--]</span><span class="doall" onclick="doall('15',false)">[++]</span>&nbsp;<a class="jump" href="#F15">[->end]</a>&nbsp;<a class="jump" href="#H13">[->enclosing]</a><div class="blk" id="B15" style="display:none"><ul>
                <li><i>[2014-12-12 11:43:52.132]</i>&nbsp;<span class="remote"><span class="hot">Registered Type 'text/x-vcalendar' Version='1.0', implemented by local type vCalendar10, related to remote datastore './calendar'</span></span></li>
              </ul><span class="block"><span class="coll" onclick="coll('15')">&ndash;</span><a name="F15">[2014-12-12 11:43:52.133] End of 'RemoteCTCap'</a></span>&nbsp;<a class="jump" href="#H15">[->top]</a>&nbsp;<a class="jump" href="#F13">[->enclosing]</a></div></li>
            </ul><span class="block"><span class="coll" onclick="coll('13')">&ndash;</span><a name="F13">[2014-12-12 11:43:52.133] End of 'RemoteTypes'</a></span>&nbsp;<a class="jump" href="#H13">[->top]</a>&nbsp;<a class="jump" href="#F12">[->enclosing]</a></div></li>
            <li><span class="block"><div id="E16" style="display:inline" class="exp" onclick="exp('16')">+</div><div id="C16" style="display:none" class="coll" onclick="coll('16')">&ndash;</div><a name="H16">[2014-12-12 11:43:52.133] 'RemoteSyncTypes' - Analyzing remote sync types listed in datastore level SyncCap</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('16',true)">[--]</span><span class="doall" onclick="doall('16',false)">[++]</span>&nbsp;<a class="jump" href="#F16">[->end]</a>&nbsp;<a class="jump" href="#H12">[->enclosing]</a><div class="blk" id="B16" style="display:none"><ul>
              <li><i>[2014-12-12 11:43:52.133]</i>&nbsp;<span class="remote">SyncType='1'</span></li>
              <li><i>[2014-12-12 11:43:52.133]</i>&nbsp;<span class="remote">SyncType='2'</span></li>
              <li><i>[2014-12-12 11:43:52.133]</i>&nbsp;<span class="remote">SyncType='3'</span></li>
              <li><i>[2014-12-12 11:43:52.133]</i>&nbsp;<span class="remote">SyncType='4'</span></li>
              <li><i>[2014-12-12 11:43:52.133]</i>&nbsp;<span class="remote">SyncType='5'</span></li>
              <li><i>[2014-12-12 11:43:52.133]</i>&nbsp;<span class="remote">SyncType='6'</span></li>
              <li><i>[2014-12-12 11:43:52.133]</i>&nbsp;<span class="remote">SyncType='7'</span></li>
              <li><i>[2014-12-12 11:43:52.134]</i>&nbsp;<span class="remote">SyncType='390001'</span></li>
            </ul><span class="block"><span class="coll" onclick="coll('16')">&ndash;</span><a name="F16">[2014-12-12 11:43:52.134] End of 'RemoteSyncTypes'</a></span>&nbsp;<a class="jump" href="#H16">[->top]</a>&nbsp;<a class="jump" href="#F12">[->enclosing]</a></div></li>
          </ul><span class="block"><span class="coll" onclick="coll('12')">&ndash;</span><a name="F12">[2014-12-12 11:43:52.134] End of 'RemoteDSDevInf'</a></span>&nbsp;<a class="jump" href="#H12">[->top]</a>&nbsp;<a class="jump" href="#F11">[->enclosing]</a></div></li>
        </ul><span class="block"><span class="coll" onclick="coll('11')">&ndash;</span><a name="F11">[2014-12-12 11:43:52.134] End of 'RemoteDatastores'</a></span>&nbsp;<a class="jump" href="#H11">[->top]</a>&nbsp;<a class="jump" href="#F9">[->enclosing]</a></div></li>
<li><i>[2014-12-12 11:43:52.134]</i>&nbsp;Session_SaveDeviceInfo info='REMOTE_URI:syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500
<br/>REMOTE_DESC:Patrick Ohly SyncEvolution
<br/>REMOTE_INFO:Sailfish (unknown, 1.3.99.7+20140126+SE+1e6fc71+unclean, 3.4.0.47) Synthesis AG
<br/>DOMAIN:
<br/>MOD:SyncEvolution
<br/>MAN:Patrick Ohly
<br/>OEM:Synthesis AG
<br/>FWV:1.3.99.7+20140126+SE+1e6fc71+unclean
<br/>SWV:3.4.0.47
<br/>HWV:unknown
<br/>'</li>
      </ul><span class="block"><span class="coll" onclick="coll('9')">&ndash;</span><a name="F9">[2014-12-12 11:43:52.134] End of 'DevInf_Analyze'</a></span>&nbsp;<a class="jump" href="#H9">[->top]</a>&nbsp;<a class="jump" href="#F8">[->enclosing]</a></div></li>
      <li><span class="block"><div id="E17" style="display:none" class="exp" onclick="exp('17')">+</div><div id="C17" style="display:inline" class="coll" onclick="coll('17')">&ndash;</div><a name="H17">[2014-12-12 11:43:52.135] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Status</span></span>&nbsp;<span class="doall" onclick="doall('17',true)">[--]</span><span class="doall" onclick="doall('17',false)">[++]</span>&nbsp;<a class="jump" href="#F17">[->end]</a>&nbsp;<a class="jump" href="#H8">[->enclosing]</a><div class="blk" id="B17" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:52.135]</i>&nbsp;<span class="hotalone">Status Code 200 issued for Cmd=Put, (incoming MsgID=1, CmdID=1)</span></li>
        <li><i>[2014-12-12 11:43:52.135]</i>&nbsp;<span class="hotalone">- SourceRef (remoteID) = './devinf12'</span></li>
        <li><i>[2014-12-12 11:43:52.135]</i>&nbsp;<span class="hotalone">Status: issued as (outgoing MsgID=1, CmdID=2), not waiting for status</span></li>
        <li><i>[2014-12-12 11:43:52.135]</i>&nbsp;<span class="proto">Deleted command 'Status' (outgoing MsgID=1, CmdID=2)</span></li>
        <li><i>[2014-12-12 11:43:52.135]</i>&nbsp;<span class="proto">Outgoing Message size is now 364 bytes</span></li>
      </ul><span class="block"><span class="coll" onclick="coll('17')">&ndash;</span><a name="F17">[2014-12-12 11:43:52.135] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H17">[->top]</a>&nbsp;<a class="jump" href="#F8">[->enclosing]</a></div></li>
      <li><i>[2014-12-12 11:43:52.135]</i>&nbsp;<span class="proto">Deleted command 'Put' (incoming MsgID=1, CmdID=1)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('8')">&ndash;</span><a name="F8">[2014-12-12 11:43:52.136] End of 'processCmd'</a></span>&nbsp;<a class="jump" href="#H8">[->top]</a>&nbsp;<a class="jump" href="#F3">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:52.136]</i>&nbsp;<span class="proto">Created command 'Get' (incoming)</span></li>
    <li><i>[2014-12-12 11:43:52.136]</i>&nbsp;<span class="hotalone">Started processing Command 'Get' (incoming MsgID=1, CmdID=2)</span></li>
    <li><span class="block"><div id="E18" style="display:none" class="exp" onclick="exp('18')">+</div><div id="C18" style="display:inline" class="coll" onclick="coll('18')">&ndash;</div><a name="H18">[2014-12-12 11:43:52.136] 'processCmd' - Processing incoming command</a></span><span class="attribute">, Cmd=<span class="attrval">Get</span>, IncomingMsgID=<span class="attrval">1</span>, CmdID=<span class="attrval">2</span></span>&nbsp;<span class="doall" onclick="doall('18',true)">[--]</span><span class="doall" onclick="doall('18',false)">[++]</span>&nbsp;<a class="jump" href="#F18">[->end]</a>&nbsp;<a class="jump" href="#H3">[->enclosing]</a><div class="blk" id="B18" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:52.136]</i>&nbsp;<span class="hotalone">processing item with locURI=./devinf12</span></li>
      <li><i>[2014-12-12 11:43:52.136]</i>&nbsp;<span class="proto">Created command 'Status' (outgoing)</span></li>
      <li><i>[2014-12-12 11:43:52.136]</i>&nbsp;<span class="proto">Created command 'Results' (outgoing)</span></li>
      <li><span class="block"><div id="E19" style="display:none" class="exp" onclick="exp('19')">+</div><div id="C19" style="display:inline" class="coll" onclick="coll('19')">&ndash;</div><a name="H19">[2014-12-12 11:43:52.137] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Status</span></span>&nbsp;<span class="doall" onclick="doall('19',true)">[--]</span><span class="doall" onclick="doall('19',false)">[++]</span>&nbsp;<a class="jump" href="#F19">[->end]</a>&nbsp;<a class="jump" href="#H18">[->enclosing]</a><div class="blk" id="B19" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:52.138]</i>&nbsp;<span class="hotalone">Status Code 200 issued for Cmd=Get, (incoming MsgID=1, CmdID=2)</span></li>
        <li><i>[2014-12-12 11:43:52.138]</i>&nbsp;<span class="hotalone">- TargetRef (localID) = './devinf12'</span></li>
        <li><i>[2014-12-12 11:43:52.138]</i>&nbsp;<span class="hotalone">Status: issued as (outgoing MsgID=1, CmdID=3), not waiting for status</span></li>
        <li><i>[2014-12-12 11:43:52.138]</i>&nbsp;<span class="proto">Deleted command 'Status' (outgoing MsgID=1, CmdID=3)</span></li>
        <li><i>[2014-12-12 11:43:52.138]</i>&nbsp;<span class="proto">Outgoing Message size is now 409 bytes</span></li>
      </ul><span class="block"><span class="coll" onclick="coll('19')">&ndash;</span><a name="F19">[2014-12-12 11:43:52.138] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H19">[->top]</a>&nbsp;<a class="jump" href="#F18">[->enclosing]</a></div></li>
      <li><span class="block"><div id="E20" style="display:none" class="exp" onclick="exp('20')">+</div><div id="C20" style="display:inline" class="coll" onclick="coll('20')">&ndash;</div><a name="H20">[2014-12-12 11:43:52.138] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Results</span></span>&nbsp;<span class="doall" onclick="doall('20',true)">[--]</span><span class="doall" onclick="doall('20',false)">[++]</span>&nbsp;<a class="jump" href="#F20">[->end]</a>&nbsp;<a class="jump" href="#H18">[->enclosing]</a><div class="blk" id="B20" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:52.139]</i>&nbsp;<span class="hotalone">results issued for (incoming MsgID=1, CmdID=2)</span></li>
        <li><i>[2014-12-12 11:43:52.140]</i>&nbsp;<span class="hotalone">Results: issued as (outgoing MsgID=1, CmdID=4), now queueing for <a name="IO_1_4" href="#SO_1_4">status</a></span></li>
        <li><i>[2014-12-12 11:43:52.140]</i>&nbsp;<span class="proto">Outgoing Message size is now 3010 bytes</span></li>
      </ul><span class="block"><span class="coll" onclick="coll('20')">&ndash;</span><a name="F20">[2014-12-12 11:43:52.140] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H20">[->top]</a>&nbsp;<a class="jump" href="#F18">[->enclosing]</a></div></li>
      <li><i>[2014-12-12 11:43:52.140]</i>&nbsp;<span class="proto">Deleted command 'Get' (incoming MsgID=1, CmdID=2)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('18')">&ndash;</span><a name="F18">[2014-12-12 11:43:52.140] End of 'processCmd'</a></span>&nbsp;<a class="jump" href="#H18">[->top]</a>&nbsp;<a class="jump" href="#F3">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:52.140]</i>&nbsp;<span class="proto">Created command 'Alert' (incoming)</span></li>
    <li><i>[2014-12-12 11:43:52.140]</i>&nbsp;<span class="hotalone">Started processing Command 'Alert' (incoming MsgID=1, CmdID=3)</span></li>
    <li><span class="block"><div id="E21" style="display:none" class="exp" onclick="exp('21')">+</div><div id="C21" style="display:inline" class="coll" onclick="coll('21')">&ndash;</div><a name="H21">[2014-12-12 11:43:52.141] 'processCmd' - Processing incoming command</a></span><span class="attribute">, Cmd=<span class="attrval">Alert</span>, IncomingMsgID=<span class="attrval">1</span>, CmdID=<span class="attrval">3</span></span>&nbsp;<span class="doall" onclick="doall('21',true)">[--]</span><span class="doall" onclick="doall('21',false)">[++]</span>&nbsp;<a class="jump" href="#F21">[->end]</a>&nbsp;<a class="jump" href="#H3">[->enclosing]</a><div class="blk" id="B21" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:52.141]</i>&nbsp;<span class="hotalone">Code=225. No Cred. Analyzing Items</span></li>
      <li><i>[2014-12-12 11:43:52.141]</i>&nbsp;<span class="remote">MaxObjSize found in Alert command: 4000000</span></li>
      <li><i>[2014-12-12 11:43:52.141]</i>&nbsp;<span class="proto">Created command 'Status' (outgoing)</span></li>
      <li><i>[2014-12-12 11:43:52.141]</i>&nbsp;<span class="hotalone">- Processing Alert Item (code=225), Source='./calendar', Target='calendar'</span></li>
      <li><span class="block"><div id="E22" style="display:inline" class="exp" onclick="exp('22')">+</div><div id="C22" style="display:none" class="coll" onclick="coll('22')">&ndash;</div><a name="H22">[2014-12-12 11:43:52.141] 'MakeAdminReady' - Making Admin Data ready to check sync anchors</a></span><span class="attribute">, localDB=<span class="attrval">calendar</span>, remoteDB=<span class="attrval">./calendar</span></span>&nbsp;<span class="doall" onclick="doall('22',true)">[--]</span><span class="doall" onclick="doall('22',false)">[++]</span>&nbsp;<a class="jump" href="#F22">[->end]</a>&nbsp;<a class="jump" href="#H21">[->enclosing]</a><div class="blk" id="B22" style="display:none"><ul>
<li><i>[2014-12-12 11:43:52.141]</i>&nbsp;@owncloud-/calendar: 'calendar' dev='syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500' usr='anonymous' err=0</li>
<li><i>[2014-12-12 11:43:52.141]</i>&nbsp;@owncloud-/calendar: 'calendar' dev='syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500' usr='anonymous' err=0</li>
<li><i>[2014-12-12 11:43:52.142]</i>&nbsp;@owncloud-/calendar: LoadAdminData 'calendar' './calendar', 'remotesyncanchor:20141212T091556Z
<br/>lastsync:20141212T091556Z
<br/>lasttoremotesync:20141212T091556Z
<br/>lasttoremotesyncid:2014-12-12T09:15:58Z
<br/>resumealertcode:0
<br/>lastsuspend:20141212T091556Z
<br/>lastsuspendid:
<br/>partialitemstate:0
<br/>lastitemstatus:0
<br/>lastsourceURI:
<br/>lasttargetURI:
<br/>totalsize:0
<br/>unconfirmedsize:0
<br/>storedsize:0
<br/>stored;BLOBID=PIStored
<br/>' res=0</li>
<li><i>[2014-12-12 11:43:52.142]</i>&nbsp;@owncloud-/calendar: ReadNextMapItem '(none)' + 0 = '(none)' + 0 first=yes res=0</li>
        <li><i>[2014-12-12 11:43:52.142]</i>&nbsp;<span class="proto">First time sync or config changed since last sync -&gt; remote should see our devinf</span></li>
      </ul><span class="block"><span class="coll" onclick="coll('22')">&ndash;</span><a name="F22">[2014-12-12 11:43:52.143] End of 'MakeAdminReady'</a></span>&nbsp;<a class="jump" href="#H22">[->top]</a>&nbsp;<a class="jump" href="#F21">[->enclosing]</a></div></li>
      <li><span class="block"><div id="E23" style="display:none" class="exp" onclick="exp('23')">+</div><div id="C23" style="display:inline" class="coll" onclick="coll('23')">&ndash;</div><a name="H23">[2014-12-12 11:43:52.143] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">idle</span>, newstate=<span class="attrval">admin_ready</span></span>&nbsp;<span class="doall" onclick="doall('23',true)">[--]</span><span class="doall" onclick="doall('23',false)">[++]</span>&nbsp;<a class="jump" href="#F23">[->end]</a>&nbsp;<a class="jump" href="#H21">[->enclosing]</a><div class="blk" id="B23" style="display:inline"><ul>
      </ul><span class="block"><span class="coll" onclick="coll('23')">&ndash;</span><a name="F23">[2014-12-12 11:43:52.143] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H23">[->top]</a>&nbsp;<a class="jump" href="#F21">[->enclosing]</a></div></li>
      <li><i>[2014-12-12 11:43:52.143]</i>&nbsp;<span class="proto">Saved Last Remote Client Anchor='20141212T091556Z', received &lt;last&gt; Remote Client Anchor='20141212T091556Z' (must match for normal sync)</span></li>
      <li><i>[2014-12-12 11:43:52.143]</i>&nbsp;<span class="proto">Received &lt;next&gt; Remote Client Anchor='20141212T104352Z' (to be compared with &lt;last&gt; in NEXT session)</span></li>
      <li><i>[2014-12-12 11:43:52.143]</i>&nbsp;<span class="proto">(Saved) Last Local Server Anchor='20141212T091556Z', (generated) Next Local Server Anchor='20141212T104352Z' (sent to client as &lt;last&gt;/&lt;next&gt; in &lt;alert&gt;)</span></li>
      <li><i>[2014-12-12 11:43:52.143]</i>&nbsp;<span class="proto">(Saved) fResumeAlertCode = 0 (valid for &gt;DS 1.2 only)</span></li>
      <li><i>[2014-12-12 11:43:52.143]</i>&nbsp;<span class="error">Cannot resume, suggesting a normal sync</span></li>
      <li><span class="block"><div id="E24" style="display:none" class="exp" onclick="exp('24')">+</div><div id="C24" style="display:inline" class="coll" onclick="coll('24')">&ndash;</div><a name="H24">[2014-12-12 11:43:52.143] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">admin_ready</span>, newstate=<span class="attrval">server_alerted</span></span>&nbsp;<span class="doall" onclick="doall('24',true)">[--]</span><span class="doall" onclick="doall('24',false)">[++]</span>&nbsp;<a class="jump" href="#F24">[->end]</a>&nbsp;<a class="jump" href="#H21">[->enclosing]</a><div class="blk" id="B24" style="display:inline"><ul>
      </ul><span class="block"><span class="coll" onclick="coll('24')">&ndash;</span><a name="F24">[2014-12-12 11:43:52.143] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H24">[->top]</a>&nbsp;<a class="jump" href="#F21">[->enclosing]</a></div></li>
      <li><i>[2014-12-12 11:43:52.143]</i>&nbsp;<span class="hotalone">Alerted (code=225) for two-way Normal Sync </span></li>
      <li><i>[2014-12-12 11:43:52.143]</i>&nbsp;<span class="proto">Created command 'Alert' (outgoing)</span></li>
      <li><i>[2014-12-12 11:43:52.143]</i>&nbsp;<span class="hotalone">ALERTED from client for normal Sync</span></li>
      <li><span class="block"><div id="E25" style="display:none" class="exp" onclick="exp('25')">+</div><div id="C25" style="display:inline" class="coll" onclick="coll('25')">&ndash;</div><a name="H25">[2014-12-12 11:43:52.144] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">server_alerted</span>, newstate=<span class="attrval">server_answered_alert</span></span>&nbsp;<span class="doall" onclick="doall('25',true)">[--]</span><span class="doall" onclick="doall('25',false)">[++]</span>&nbsp;<a class="jump" href="#F25">[->end]</a>&nbsp;<a class="jump" href="#H21">[->enclosing]</a><div class="blk" id="B25" style="display:inline"><ul>
      </ul><span class="block"><span class="coll" onclick="coll('25')">&ndash;</span><a name="F25">[2014-12-12 11:43:52.144] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H25">[->top]</a>&nbsp;<a class="jump" href="#F21">[->enclosing]</a></div></li>
      <li><span class="block"><div id="E26" style="display:none" class="exp" onclick="exp('26')">+</div><div id="C26" style="display:inline" class="coll" onclick="coll('26')">&ndash;</div><a name="H26">[2014-12-12 11:43:52.144] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Status</span></span>&nbsp;<span class="doall" onclick="doall('26',true)">[--]</span><span class="doall" onclick="doall('26',false)">[++]</span>&nbsp;<a class="jump" href="#F26">[->end]</a>&nbsp;<a class="jump" href="#H21">[->enclosing]</a><div class="blk" id="B26" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:52.144]</i>&nbsp;<span class="error">WARNING: Non-OK Status 509 returned to remote!</span></li>
        <li><i>[2014-12-12 11:43:52.144]</i>&nbsp;<span class="hotalone">Status Code 509 issued for Cmd=Alert, (incoming MsgID=1, CmdID=3)</span></li>
        <li><i>[2014-12-12 11:43:52.144]</i>&nbsp;<span class="hotalone">- TargetRef (localID) = 'calendar'</span></li>
        <li><i>[2014-12-12 11:43:52.144]</i>&nbsp;<span class="hotalone">- SourceRef (remoteID) = './calendar'</span></li>
        <li><i>[2014-12-12 11:43:52.144]</i>&nbsp;<span class="hotalone">Status: issued as (outgoing MsgID=1, CmdID=5), not waiting for status</span></li>
        <li><i>[2014-12-12 11:43:52.144]</i>&nbsp;<span class="proto">Deleted command 'Status' (outgoing MsgID=1, CmdID=5)</span></li>
        <li><i>[2014-12-12 11:43:52.144]</i>&nbsp;<span class="proto">Outgoing Message size is now 3142 bytes</span></li>
      </ul><span class="block"><span class="coll" onclick="coll('26')">&ndash;</span><a name="F26">[2014-12-12 11:43:52.144] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H26">[->top]</a>&nbsp;<a class="jump" href="#F21">[->enclosing]</a></div></li>
      <li><i>[2014-12-12 11:43:52.144]</i>&nbsp;<span class="proto">Deleted command 'Alert' (incoming MsgID=1, CmdID=3)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('21')">&ndash;</span><a name="F21">[2014-12-12 11:43:52.145] End of 'processCmd'</a></span>&nbsp;<a class="jump" href="#H21">[->top]</a>&nbsp;<a class="jump" href="#F3">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:52.145]</i>&nbsp;<span class="hotalone">=================&gt; Finished processing incoming message #1 (final), request=0</span></li>
    <li><span class="block"><div id="E27" style="display:none" class="exp" onclick="exp('27')">+</div><div id="C27" style="display:inline" class="coll" onclick="coll('27')">&ndash;</div><a name="H27">[2014-12-12 11:43:52.145] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Alert</span></span>&nbsp;<span class="doall" onclick="doall('27',true)">[--]</span><span class="doall" onclick="doall('27',false)">[++]</span>&nbsp;<a class="jump" href="#F27">[->end]</a>&nbsp;<a class="jump" href="#H3">[->enclosing]</a><div class="blk" id="B27" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:52.145]</i>&nbsp;<span class="hotalone">Alert Code 200 sent</span></li>
      <li><i>[2014-12-12 11:43:52.145]</i>&nbsp;<span class="hotalone">- Alert Item: Source='calendar', Target='./calendar'</span></li>
      <li><i>[2014-12-12 11:43:52.145]</i>&nbsp;<span class="hotalone">Alert: issued as (outgoing MsgID=1, CmdID=6), now queueing for <a name="IO_1_6" href="#SO_1_6">status</a></span></li>
      <li><i>[2014-12-12 11:43:52.146]</i>&nbsp;<span class="proto">Outgoing Message size is now 3247 bytes</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('27')">&ndash;</span><a name="F27">[2014-12-12 11:43:52.146] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H27">[->top]</a>&nbsp;<a class="jump" href="#F3">[->enclosing]</a></div></li>
  </ul><span class="block"><span class="coll" onclick="coll('3')">&ndash;</span><a name="F3">[2014-12-12 11:43:52.146] End of 'SyncML_Incoming'</a></span>&nbsp;<a class="jump" href="#H3">[->top]</a>&nbsp;<a class="jump" href="#F2">[->enclosing]</a></div></li>
  <li><i>[2014-12-12 11:43:52.146]</i>&nbsp;<span class="proto">Ending message with 0 next-message/0 next-package commands: FINAL (final allowed by caller)</span></li>
  <li><i>[2014-12-12 11:43:52.146]</i>&nbsp;<span class="proto">Entire message size is now 3252 Bytes</span></li>
  <li><i>[2014-12-12 11:43:52.146]</i>&nbsp;<span class="hotalone">---&gt; MessageEnded finishes : new incoming state='sync', new outgoing state='sync', NeedToAnswer</span></li>
  <li><i>[2014-12-12 11:43:52.146]</i>&nbsp;<span class="hotalone">Local Datastore 'calendar': State=server_answered_alert, normal sync, two-way</span></li>
  <li><i>[2014-12-12 11:43:52.146]</i>&nbsp;<span class="hotalone">=================&gt; Finished generating outgoing message #1, request=0</span></li>
</ul><span class="block"><span class="coll" onclick="coll('2')">&ndash;</span><a name="F2">[2014-12-12 11:43:52.146] End of 'SyncML_Outgoing'</a></span>&nbsp;<a class="jump" href="#H2">[->top]</a></div></li>
<li><i>[2014-12-12 11:43:52.146]</i>&nbsp;<span class="hotalone">========= Finished request (session in progress), processing time=42 msec, incoming bytes=3004, outgoing bytes=3252</span></li>
<li><i>[2014-12-12 11:43:52.147]</i>&nbsp;@owncloud-/calendar: total number of items received 0</li>
<li><i>[2014-12-12 11:43:52.157]</i>&nbsp;waiting for child to send message</li>
<li><span class="block"><div id="E28" style="display:none" class="exp" onclick="exp('28')">+</div><div id="C28" style="display:inline" class="coll" onclick="coll('28')">&ndash;</div><a name="H28">[2014-12-12 11:43:56.170] 'SyncML_Outgoing' - preparing for response before starting to analyze new incoming message</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('28',true)">[--]</span><span class="doall" onclick="doall('28',false)">[++]</span>&nbsp;<a class="jump" href="#F28">[->end]</a><div class="blk" id="B28" style="display:inline"><ul>
  <li><span class="block"><div id="E29" style="display:none" class="exp" onclick="exp('29')">+</div><div id="C29" style="display:inline" class="coll" onclick="coll('29')">&ndash;</div><a name="H29">[2014-12-12 11:43:56.170] 'SyncML_Incoming' - Starting to analyze incoming message</a></span><span class="attribute">, RequestNo=<span class="attrval">0</span>, SySyncVers=<span class="attrval">3.4.0.47</span></span>&nbsp;<span class="doall" onclick="doall('29',true)">[--]</span><span class="doall" onclick="doall('29',false)">[++]</span>&nbsp;<a class="jump" href="#F29">[->end]</a>&nbsp;<a class="jump" href="#H28">[->enclosing]</a><div class="blk" id="B29" style="display:inline"><ul>
    <li><i>[2014-12-12 11:43:56.170]</i>&nbsp;<span class="hotalone">=================&gt; Starting to analyze incoming message, SySync V3.4.0.47, RequestNo=0</span></li>
    <li><i>[2014-12-12 11:43:56.171]</i>&nbsp;<span class="proto">Created command 'SyncHdr' (incoming)</span></li>
    <li><span class="block"><div id="E30" style="display:none" class="exp" onclick="exp('30')">+</div><div id="C30" style="display:inline" class="coll" onclick="coll('30')">&ndash;</div><a name="H30">[2014-12-12 11:43:56.171] 'processHdr' - Processing incoming SyncHdr</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('30',true)">[--]</span><span class="doall" onclick="doall('30',false)">[++]</span>&nbsp;<a class="jump" href="#F30">[->end]</a>&nbsp;<a class="jump" href="#H29">[->enclosing]</a><div class="blk" id="B30" style="display:inline"><ul>
      <li><span class="block"><div id="E31" style="display:none" class="exp" onclick="exp('31')">+</div><div id="C31" style="display:inline" class="coll" onclick="coll('31')">&ndash;</div><a name="H31">[2014-12-12 11:43:56.171] 'SyncHdr' - Processing incoming SyncHdr</a></span><span class="attribute">, IncomingMsgID=<span class="attrval">2</span></span>&nbsp;<span class="doall" onclick="doall('31',true)">[--]</span><span class="doall" onclick="doall('31',false)">[++]</span>&nbsp;<a class="jump" href="#F31">[->end]</a>&nbsp;<a class="jump" href="#H30">[->enclosing]</a><div class="blk" id="B31" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:56.172]</i>&nbsp;<span class="hotalone">Started Processing of message #2 (SyncML/1.2)</span></li>
        <li><i>[2014-12-12 11:43:56.172]</i>&nbsp;<span class="remote">MaxMsgSize found in SyncHdr: 150000 -&gt; set for outgoing msgs</span></li>
        <li><i>[2014-12-12 11:43:56.172]</i>&nbsp;<span class="remote">MaxObjSize found in SyncHdr: 4000000</span></li>
        <li><i>[2014-12-12 11:43:56.172]</i>&nbsp;<span class="proto">Created command 'Status' (outgoing)</span></li>
        <li><i>[2014-12-12 11:43:56.172]</i>&nbsp;<span class="proto">Authorization ok from previous request: 200</span></li>
        <li><i>[2014-12-12 11:43:56.172]</i>&nbsp;<span class="proto">Created command 'SyncHdr' (outgoing)</span></li>
        <li><i>[2014-12-12 11:43:56.172]</i>&nbsp;<span class="proto">Target (Remote URI) = 'syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500'</span></li>
        <li><span class="block"><div id="E32" style="display:none" class="exp" onclick="exp('32')">+</div><div id="C32" style="display:inline" class="coll" onclick="coll('32')">&ndash;</div><a name="H32">[2014-12-12 11:43:56.172] 'SyncHdr' - SyncHdr generation</a></span><span class="attribute">, SyncMLVers=<span class="attrval">1.2</span>, OutgoingMsgID=<span class="attrval">2</span></span>&nbsp;<span class="doall" onclick="doall('32',true)">[--]</span><span class="doall" onclick="doall('32',false)">[++]</span>&nbsp;<a class="jump" href="#F32">[->end]</a>&nbsp;<a class="jump" href="#H31">[->enclosing]</a><div class="blk" id="B32" style="display:inline"><ul>
          <li><i>[2014-12-12 11:43:56.173]</i>&nbsp;<span class="proto">SyncHdr: issued in MsgID=2, now queueing for status</span></li>
          <li><i>[2014-12-12 11:43:56.173]</i>&nbsp;<span class="proto">Outgoing Message size is now 160 bytes</span></li>
        </ul><span class="block"><span class="coll" onclick="coll('32')">&ndash;</span><a name="F32">[2014-12-12 11:43:56.173] End of 'SyncHdr'</a></span>&nbsp;<a class="jump" href="#H32">[->top]</a>&nbsp;<a class="jump" href="#F31">[->enclosing]</a></div></li>
        <li><span class="block"><div id="E33" style="display:none" class="exp" onclick="exp('33')">+</div><div id="C33" style="display:inline" class="coll" onclick="coll('33')">&ndash;</div><a name="H33">[2014-12-12 11:43:56.173] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Status</span></span>&nbsp;<span class="doall" onclick="doall('33',true)">[--]</span><span class="doall" onclick="doall('33',false)">[++]</span>&nbsp;<a class="jump" href="#F33">[->end]</a>&nbsp;<a class="jump" href="#H31">[->enclosing]</a><div class="blk" id="B33" style="display:inline"><ul>
          <li><i>[2014-12-12 11:43:56.173]</i>&nbsp;<span class="hotalone">Status Code 200 issued for Cmd=SyncHdr, (incoming MsgID=2, CmdID=0)</span></li>
          <li><i>[2014-12-12 11:43:56.173]</i>&nbsp;<span class="hotalone">- TargetRef (localID) = '?sessionid=7166071432577307786'</span></li>
          <li><i>[2014-12-12 11:43:56.173]</i>&nbsp;<span class="hotalone">- SourceRef (remoteID) = 'syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500'</span></li>
          <li><i>[2014-12-12 11:43:56.173]</i>&nbsp;<span class="hotalone">Status: issued as (outgoing MsgID=2, CmdID=1), not waiting for status</span></li>
          <li><i>[2014-12-12 11:43:56.173]</i>&nbsp;<span class="proto">Deleted command 'Status' (outgoing MsgID=2, CmdID=1)</span></li>
          <li><i>[2014-12-12 11:43:56.174]</i>&nbsp;<span class="proto">Outgoing Message size is now 283 bytes</span></li>
        </ul><span class="block"><span class="coll" onclick="coll('33')">&ndash;</span><a name="F33">[2014-12-12 11:43:56.174] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H33">[->top]</a>&nbsp;<a class="jump" href="#F31">[->enclosing]</a></div></li>
        <li><i>[2014-12-12 11:43:56.174]</i>&nbsp;<span class="hotalone">Incoming SyncHdr processed, incomingMsgID=2, SyncMLVers=1.2</span></li>
        <li><i>[2014-12-12 11:43:56.174]</i>&nbsp;<span class="hotalone">- Session ID='18'</span></li>
        <li><i>[2014-12-12 11:43:56.174]</i>&nbsp;<span class="hotalone">- Source (Remote party): URI='syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500' DisplayName=''</span></li>
        <li><i>[2014-12-12 11:43:56.174]</i>&nbsp;<span class="hotalone">- Response to be sent to URI='[none specified, back to source]'</span></li>
        <li><i>[2014-12-12 11:43:56.174]</i>&nbsp;<span class="hotalone">- Target (Local party) : URI='?sessionid=7166071432577307786' DisplayName=''</span></li>
        <li><i>[2014-12-12 11:43:56.174]</i>&nbsp;<span class="proto">Deleted command 'SyncHdr' (incoming MsgID=2, CmdID=0)</span></li>
      </ul><span class="block"><span class="coll" onclick="coll('31')">&ndash;</span><a name="F31">[2014-12-12 11:43:56.174] End of 'SyncHdr'</a></span>&nbsp;<a class="jump" href="#H31">[->top]</a>&nbsp;<a class="jump" href="#F30">[->enclosing]</a></div></li>
    </ul><span class="block"><span class="coll" onclick="coll('30')">&ndash;</span><a name="F30">[2014-12-12 11:43:56.174] End of 'processHdr'</a></span>&nbsp;<a class="jump" href="#H30">[->top]</a>&nbsp;<a class="jump" href="#F29">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:56.174]</i>&nbsp;<span class="proto">Created command 'Status' (incoming)</span></li>
    <li><span class="block"><div id="E34" style="display:none" class="exp" onclick="exp('34')">+</div><div id="C34" style="display:inline" class="coll" onclick="coll('34')">&ndash;</div><a name="H34">[2014-12-12 11:43:56.174] 'processStatus' - Processing incoming Status</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('34',true)">[--]</span><span class="doall" onclick="doall('34',false)">[++]</span>&nbsp;<a class="jump" href="#F34">[->end]</a>&nbsp;<a class="jump" href="#H29">[->enclosing]</a><div class="blk" id="B34" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.175]</i>&nbsp;<span class="hotalone">Started processing Command 'Status' (incoming MsgID=2, CmdID=1)</span></li>
      <li><i>[2014-12-12 11:43:56.175]</i>&nbsp;<span class="hotalone">RECEIVED STATUS 200 for for <a name="SO_1_0" href="#IO_1_0">command 'SyncHdr'</a> (outgoing MsgID=1, CmdID=0)</span></li>
      <li><i>[2014-12-12 11:43:56.175]</i>&nbsp;<span class="hotalone">- TargetRef (remoteID) = 'syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500'</span></li>
      <li><i>[2014-12-12 11:43:56.175]</i>&nbsp;<span class="proto">Found matching command 'SyncHdr' for Status</span></li>
      <li><i>[2014-12-12 11:43:56.175]</i>&nbsp;<span class="proto">Status: 200: successful --&gt; accept as ok</span></li>
      <li><i>[2014-12-12 11:43:56.175]</i>&nbsp;<span class="proto">Deleted command 'SyncHdr' (outgoing MsgID=1, CmdID=0)</span></li>
      <li><i>[2014-12-12 11:43:56.175]</i>&nbsp;<span class="proto">Deleted command 'Status' (incoming MsgID=2, CmdID=1)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('34')">&ndash;</span><a name="F34">[2014-12-12 11:43:56.175] End of 'processStatus'</a></span>&nbsp;<a class="jump" href="#H34">[->top]</a>&nbsp;<a class="jump" href="#F29">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:56.176]</i>&nbsp;<span class="proto">Created command 'Status' (incoming)</span></li>
    <li><span class="block"><div id="E35" style="display:none" class="exp" onclick="exp('35')">+</div><div id="C35" style="display:inline" class="coll" onclick="coll('35')">&ndash;</div><a name="H35">[2014-12-12 11:43:56.176] 'processStatus' - Processing incoming Status</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('35',true)">[--]</span><span class="doall" onclick="doall('35',false)">[++]</span>&nbsp;<a class="jump" href="#F35">[->end]</a>&nbsp;<a class="jump" href="#H29">[->enclosing]</a><div class="blk" id="B35" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.176]</i>&nbsp;<span class="hotalone">Started processing Command 'Status' (incoming MsgID=2, CmdID=2)</span></li>
      <li><i>[2014-12-12 11:43:56.176]</i>&nbsp;<span class="hotalone">RECEIVED STATUS 200 for for <a name="SO_1_4" href="#IO_1_4">command 'Results'</a> (outgoing MsgID=1, CmdID=4)</span></li>
      <li><i>[2014-12-12 11:43:56.176]</i>&nbsp;<span class="hotalone">- SourceRef (localID) = './devinf12'</span></li>
      <li><i>[2014-12-12 11:43:56.176]</i>&nbsp;<span class="proto">Found matching command 'Results' for Status</span></li>
      <li><i>[2014-12-12 11:43:56.176]</i>&nbsp;<span class="proto">Status: 200: successful --&gt; accept as ok</span></li>
      <li><i>[2014-12-12 11:43:56.176]</i>&nbsp;<span class="proto">Deleted command 'Results' (outgoing MsgID=1, CmdID=4)</span></li>
      <li><i>[2014-12-12 11:43:56.176]</i>&nbsp;<span class="proto">Deleted command 'Status' (incoming MsgID=2, CmdID=2)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('35')">&ndash;</span><a name="F35">[2014-12-12 11:43:56.176] End of 'processStatus'</a></span>&nbsp;<a class="jump" href="#H35">[->top]</a>&nbsp;<a class="jump" href="#F29">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:56.177]</i>&nbsp;<span class="proto">Created command 'Status' (incoming)</span></li>
    <li><span class="block"><div id="E36" style="display:none" class="exp" onclick="exp('36')">+</div><div id="C36" style="display:inline" class="coll" onclick="coll('36')">&ndash;</div><a name="H36">[2014-12-12 11:43:56.177] 'processStatus' - Processing incoming Status</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('36',true)">[--]</span><span class="doall" onclick="doall('36',false)">[++]</span>&nbsp;<a class="jump" href="#F36">[->end]</a>&nbsp;<a class="jump" href="#H29">[->enclosing]</a><div class="blk" id="B36" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.178]</i>&nbsp;<span class="hotalone">Started processing Command 'Status' (incoming MsgID=2, CmdID=3)</span></li>
      <li><i>[2014-12-12 11:43:56.178]</i>&nbsp;<span class="hotalone">RECEIVED STATUS 200 for for <a name="SO_1_6" href="#IO_1_6">command 'Alert'</a> (outgoing MsgID=1, CmdID=6)</span></li>
      <li><i>[2014-12-12 11:43:56.178]</i>&nbsp;<span class="hotalone">- TargetRef (remoteID) = './calendar'</span></li>
      <li><i>[2014-12-12 11:43:56.178]</i>&nbsp;<span class="hotalone">- SourceRef (localID) = 'calendar'</span></li>
      <li><i>[2014-12-12 11:43:56.178]</i>&nbsp;<span class="hotalone">- Item data = </span></li>
      <li><i>[2014-12-12 11:43:56.179]</i>&nbsp;<span class="proto">Found matching command 'Alert' for Status</span></li>
      <li><span class="block"><div id="E37" style="display:none" class="exp" onclick="exp('37')">+</div><div id="C37" style="display:inline" class="coll" onclick="coll('37')">&ndash;</div><a name="H37">[2014-12-12 11:43:56.179] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">server_answered_alert</span>, newstate=<span class="attrval">sync_mode_stable</span></span>&nbsp;<span class="doall" onclick="doall('37',true)">[--]</span><span class="doall" onclick="doall('37',false)">[++]</span>&nbsp;<a class="jump" href="#F37">[->end]</a>&nbsp;<a class="jump" href="#H36">[->enclosing]</a><div class="blk" id="B37" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:56.179]</i>&nbsp;<span class="hotalone">executing normal Sync, two-way</span></li>
      </ul><span class="block"><span class="coll" onclick="coll('37')">&ndash;</span><a name="F37">[2014-12-12 11:43:56.179] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H37">[->top]</a>&nbsp;<a class="jump" href="#F36">[->enclosing]</a></div></li>
      <li><i>[2014-12-12 11:43:56.179]</i>&nbsp;<span class="proto">Deleted command 'Alert' (outgoing MsgID=1, CmdID=6)</span></li>
      <li><i>[2014-12-12 11:43:56.179]</i>&nbsp;<span class="proto">Deleted command 'Status' (incoming MsgID=2, CmdID=3)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('36')">&ndash;</span><a name="F36">[2014-12-12 11:43:56.179] End of 'processStatus'</a></span>&nbsp;<a class="jump" href="#H36">[->top]</a>&nbsp;<a class="jump" href="#F29">[->enclosing]</a></div></li>
<li><i>[2014-12-12 11:43:56.180]</i>&nbsp;<span class="hotalone">@owncloud-/calendar: starting normal sync, two-way (peer is client)</span></li>
<li><i>[2014-12-12 11:43:56.180]</i>&nbsp;reading: set read-ahead based on sync mode two-way</li>
    <li><i>[2014-12-12 11:43:56.181]</i>&nbsp;<span class="proto">Created command 'Sync' (incoming)</span></li>
    <li><i>[2014-12-12 11:43:56.182]</i>&nbsp;<span class="hotalone">Started processing Command 'Sync' (incoming MsgID=2, CmdID=4)</span></li>
    <li><span class="block"><div id="E38" style="display:none" class="exp" onclick="exp('38')">+</div><div id="C38" style="display:inline" class="coll" onclick="coll('38')">&ndash;</div><a name="H38">[2014-12-12 11:43:56.187] 'processCmd' - Processing incoming command</a></span><span class="attribute">, Cmd=<span class="attrval">Sync</span>, IncomingMsgID=<span class="attrval">2</span>, CmdID=<span class="attrval">4</span></span>&nbsp;<span class="doall" onclick="doall('38',true)">[--]</span><span class="doall" onclick="doall('38',false)">[++]</span>&nbsp;<a class="jump" href="#F38">[->end]</a>&nbsp;<a class="jump" href="#H29">[->enclosing]</a><div class="blk" id="B38" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.187]</i>&nbsp;<span class="proto">Created command 'Status' (outgoing)</span></li>
      <li><i>[2014-12-12 11:43:56.187]</i>&nbsp;<span class="hotalone">Processing Sync, Source='./calendar', Target='calendar'</span></li>
      <li><i>[2014-12-12 11:43:56.187]</i>&nbsp;initSync: setting fLocalSyncDatastoreP</li>
      <li><i>[2014-12-12 11:43:56.187]</i>&nbsp;Local Datastore 'calendar' - Types: tx to remote: 'iCalendar20': text/calendar (2.0), rx from remote: 'iCalendar20': text/calendar (2.0)</li>
      <li><span class="block"><div id="E39" style="display:none" class="exp" onclick="exp('39')">+</div><div id="C39" style="display:inline" class="coll" onclick="coll('39')">&ndash;</div><a name="H39">[2014-12-12 11:43:56.188] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">sync_mode_stable</span>, newstate=<span class="attrval">data_access_started</span></span>&nbsp;<span class="doall" onclick="doall('39',true)">[--]</span><span class="doall" onclick="doall('39',false)">[++]</span>&nbsp;<a class="jump" href="#F39">[->end]</a>&nbsp;<a class="jump" href="#H38">[->enclosing]</a><div class="blk" id="B39" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:56.188]</i>&nbsp;<span class="hotalone">TStdLogicDS::startDataAccessForServer</span></li>
        <li><i>[2014-12-12 11:43:56.188]</i>&nbsp;- number of items in list before StartDataRead = 0</li>
<li><i>[2014-12-12 11:43:56.188]</i>&nbsp;@owncloud-/calendar: FilterSupport staticfilter:
<br/>dynamicfilter:
<br/>invisiblefilter:</li>
        <li><i>[2014-12-12 11:43:56.188]</i>&nbsp;MultiThread disabled</li>
        <li><span class="block"><div id="E40" style="display:inline" class="exp" onclick="exp('40')">+</div><div id="C40" style="display:none" class="coll" onclick="coll('40')">&ndash;</div><a name="H40">[2014-12-12 11:43:56.188] 'ReadSyncSet' - Reading Sync Set from Database</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span></span>&nbsp;<span class="doall" onclick="doall('40',true)">[--]</span><span class="doall" onclick="doall('40',false)">[++]</span>&nbsp;<a class="jump" href="#F40">[->end]</a>&nbsp;<a class="jump" href="#H39">[->enclosing]</a><div class="blk" id="B40" style="display:none"><ul>
          <li><i>[2014-12-12 11:43:56.188]</i>&nbsp;Executing Script 'initscript'</li>
          <li><i>[2014-12-12 11:43:56.189]</i>&nbsp;Now reading local sync set: report changes since reference1 at 2014-12-12 09:15:56.000, and since reference2 at 2014-12-12 09:15:56.000</li>
<li><i>[2014-12-12 11:43:56.290]</i>&nbsp;<span class="hotalone">creating complete data backup of source calendar before sync (because it was enabled with dumpData)</span></li>
<li><i>[2014-12-12 11:43:56.292]</i>&nbsp;creating /home/nemo/.cache/syncevolution/owncloud_+@owncloud_+-2014-12-12-11-43/calendar.before</li>
<li><i>[2014-12-12 11:43:56.307]</i>&nbsp;/home/nemo/.cache/syncevolution/owncloud_+@owncloud_+-2014-12-12-11-43/calendar.before created</li>
<li><i>[2014-12-12 11:43:56.307]</i>&nbsp;item e29fdf27-7117-4cee-81dc-d012398ddba7-rid exists</li>
<li><i>[2014-12-12 11:43:56.307]</i>&nbsp;checking for changes since 2014-12-12T09:15:58Z UTC</li>
<li><i>[2014-12-12 11:43:56.314]</i>&nbsp;item e29fdf27-7117-4cee-81dc-d012398ddba7-rid is new</li>
<li><i>[2014-12-12 11:43:56.315]</i>&nbsp;@owncloud-/calendar: StartDataRead last='2014-12-12T09:15:58Z' resume='' res=0</li>
<li><i>[2014-12-12 11:43:56.315]</i>&nbsp;@owncloud-/calendar: ReadNextItemAsKey aStatus=1 aID=(e29fdf27-7117-4cee-81dc-d012398ddba7-rid,(null)) res=0</li>
<li><i>[2014-12-12 11:43:56.315]</i>&nbsp;@owncloud-/calendar: ReadNextItemAsKey aStatus=0 aID=((null),(null)) res=0</li>
<li><i>[2014-12-12 11:43:56.315]</i>&nbsp;@owncloud-/calendar: EndDataRead res=0</li>
        </ul><span class="block"><span class="coll" onclick="coll('40')">&ndash;</span><a name="F40">[2014-12-12 11:43:56.315] End of 'ReadSyncSet'</a></span>&nbsp;<a class="jump" href="#H40">[->top]</a>&nbsp;<a class="jump" href="#F39">[->enclosing]</a></div></li>
        <li><span class="block"><div id="E41" style="display:inline" class="exp" onclick="exp('41')">+</div><div id="C41" style="display:none" class="coll" onclick="coll('41')">&ndash;</div><a name="H41">[2014-12-12 11:43:56.315] 'GetItems' - Read items from DB implementation</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span></span>&nbsp;<span class="doall" onclick="doall('41',true)">[--]</span><span class="doall" onclick="doall('41',false)">[++]</span>&nbsp;<a class="jump" href="#F41">[->end]</a>&nbsp;<a class="jump" href="#H39">[->enclosing]</a><div class="blk" id="B41" style="display:none"><ul>
<li><i>[2014-12-12 11:43:56.322]</i>&nbsp;@owncloud-/calendar: ReadItemAsKey aID=(e29fdf27-7117-4cee-81dc-d012398ddba7-rid,) res=0</li>
          <li><i>[2014-12-12 11:43:56.322]</i>&nbsp;Executing Script 'afterreadscript'</li>
        </ul><span class="block"><span class="coll" onclick="coll('41')">&ndash;</span><a name="F41">[2014-12-12 11:43:56.325] End of 'GetItems'</a></span>&nbsp;<a class="jump" href="#H41">[->top]</a>&nbsp;<a class="jump" href="#F39">[->enclosing]</a></div></li>
        <li><i>[2014-12-12 11:43:56.325]</i>&nbsp;<span class="hotalone">calendar: number of local items involved in sync = 1</span></li>
<li><i>[2014-12-12 11:43:56.325]</i>&nbsp;@owncloud-/calendar: StartDataWrite</li>
        <li><i>[2014-12-12 11:43:56.325]</i>&nbsp;startDataWrite called, status=0</li>
      </ul><span class="block"><span class="coll" onclick="coll('39')">&ndash;</span><a name="F39">[2014-12-12 11:43:56.325] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H39">[->top]</a>&nbsp;<a class="jump" href="#F38">[->enclosing]</a></div></li>
      <li><span class="block"><div id="E42" style="display:none" class="exp" onclick="exp('42')">+</div><div id="C42" style="display:inline" class="coll" onclick="coll('42')">&ndash;</div><a name="H42">[2014-12-12 11:43:56.325] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">data_access_started</span>, newstate=<span class="attrval">sync_set_ready</span></span>&nbsp;<span class="doall" onclick="doall('42',true)">[--]</span><span class="doall" onclick="doall('42',false)">[++]</span>&nbsp;<a class="jump" href="#F42">[->end]</a>&nbsp;<a class="jump" href="#H38">[->enclosing]</a><div class="blk" id="B42" style="display:inline"><ul>
      </ul><span class="block"><span class="coll" onclick="coll('42')">&ndash;</span><a name="F42">[2014-12-12 11:43:56.326] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H42">[->top]</a>&nbsp;<a class="jump" href="#F38">[->enclosing]</a></div></li>
      <li><i>[2014-12-12 11:43:56.326]</i>&nbsp;<span class="hotalone">- Started normal Sync (first &lt;sync&gt; command)</span></li>
      <li><i>[2014-12-12 11:43:56.326]</i>&nbsp;<span class="hotalone">&lt;Sync&gt; started, cmd-incoming state='sync', incoming state='sync', outgoing state='sync'</span></li>
      <li><span class="block"><div id="E43" style="display:none" class="exp" onclick="exp('43')">+</div><div id="C43" style="display:inline" class="coll" onclick="coll('43')">&ndash;</div><a name="H43">[2014-12-12 11:43:56.326] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Status</span></span>&nbsp;<span class="doall" onclick="doall('43',true)">[--]</span><span class="doall" onclick="doall('43',false)">[++]</span>&nbsp;<a class="jump" href="#F43">[->end]</a>&nbsp;<a class="jump" href="#H38">[->enclosing]</a><div class="blk" id="B43" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:56.326]</i>&nbsp;<span class="hotalone">Status Code 200 issued for Cmd=Sync, (incoming MsgID=2, CmdID=4)</span></li>
        <li><i>[2014-12-12 11:43:56.326]</i>&nbsp;<span class="hotalone">- TargetRef (localID) = 'calendar'</span></li>
        <li><i>[2014-12-12 11:43:56.326]</i>&nbsp;<span class="hotalone">- SourceRef (remoteID) = './calendar'</span></li>
        <li><i>[2014-12-12 11:43:56.326]</i>&nbsp;<span class="hotalone">Status: issued as (outgoing MsgID=2, CmdID=2), not waiting for status</span></li>
        <li><i>[2014-12-12 11:43:56.326]</i>&nbsp;<span class="proto">Deleted command 'Status' (outgoing MsgID=2, CmdID=2)</span></li>
        <li><i>[2014-12-12 11:43:56.326]</i>&nbsp;<span class="proto">Outgoing Message size is now 341 bytes</span></li>
      </ul><span class="block"><span class="coll" onclick="coll('43')">&ndash;</span><a name="F43">[2014-12-12 11:43:56.326] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H43">[->top]</a>&nbsp;<a class="jump" href="#F38">[->enclosing]</a></div></li>
      <li><i>[2014-12-12 11:43:56.327]</i>&nbsp;<span class="proto">Deleted command 'Sync' (incoming MsgID=2, CmdID=4)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('38')">&ndash;</span><a name="F38">[2014-12-12 11:43:56.327] End of 'processCmd'</a></span>&nbsp;<a class="jump" href="#H38">[->top]</a>&nbsp;<a class="jump" href="#F29">[->enclosing]</a></div></li>
<li><i>[2014-12-12 11:43:56.330]</i>&nbsp;<span class="hotalone">@owncloud-/calendar: started</span></li>
    <li><i>[2014-12-12 11:43:56.332]</i>&nbsp;<span class="proto">Created command 'Sync' (incoming)</span></li>
    <li><span class="block"><div id="E44" style="display:none" class="exp" onclick="exp('44')">+</div><div id="C44" style="display:inline" class="coll" onclick="coll('44')">&ndash;</div><a name="H44">[2014-12-12 11:43:56.332] 'processCmd' - Processing incoming command</a></span><span class="attribute">, Cmd=<span class="attrval">Sync</span>, IncomingMsgID=<span class="attrval">2</span>, CmdID=<span class="attrval">0</span></span>&nbsp;<span class="doall" onclick="doall('44',true)">[--]</span><span class="doall" onclick="doall('44',false)">[++]</span>&nbsp;<a class="jump" href="#F44">[->end]</a>&nbsp;<a class="jump" href="#H29">[->enclosing]</a><div class="blk" id="B44" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.332]</i>&nbsp;processSyncEnd: unsetting fLocalSyncDatastoreP</li>
      <li><i>[2014-12-12 11:43:56.332]</i>&nbsp;<span class="proto">Deleted command 'Sync' (incoming MsgID=2, CmdID=0)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('44')">&ndash;</span><a name="F44">[2014-12-12 11:43:56.333] End of 'processCmd'</a></span>&nbsp;<a class="jump" href="#H44">[->top]</a>&nbsp;<a class="jump" href="#F29">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:56.333]</i>&nbsp;<span class="hotalone">=================&gt; Finished processing incoming message #2 (final), request=0</span></li>
  </ul><span class="block"><span class="coll" onclick="coll('29')">&ndash;</span><a name="F29">[2014-12-12 11:43:56.333] End of 'SyncML_Incoming'</a></span>&nbsp;<a class="jump" href="#H29">[->top]</a>&nbsp;<a class="jump" href="#F28">[->enclosing]</a></div></li>
  <li><span class="block"><div id="E45" style="display:none" class="exp" onclick="exp('45')">+</div><div id="C45" style="display:inline" class="coll" onclick="coll('45')">&ndash;</div><a name="H45">[2014-12-12 11:43:56.333] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">sync_set_ready</span>, newstate=<span class="attrval">server_seen_client_mods</span></span>&nbsp;<span class="doall" onclick="doall('45',true)">[--]</span><span class="doall" onclick="doall('45',false)">[++]</span>&nbsp;<a class="jump" href="#F45">[->end]</a>&nbsp;<a class="jump" href="#H28">[->enclosing]</a><div class="blk" id="B45" style="display:inline"><ul>
  </ul><span class="block"><span class="coll" onclick="coll('45')">&ndash;</span><a name="F45">[2014-12-12 11:43:56.333] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H45">[->top]</a>&nbsp;<a class="jump" href="#F28">[->enclosing]</a></div></li>
  <li><span class="block"><div id="E46" style="display:none" class="exp" onclick="exp('46')">+</div><div id="C46" style="display:inline" class="coll" onclick="coll('46')">&ndash;</div><a name="H46">[2014-12-12 11:43:56.333] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">server_seen_client_mods</span>, newstate=<span class="attrval">server_sync_gen_started</span></span>&nbsp;<span class="doall" onclick="doall('46',true)">[--]</span><span class="doall" onclick="doall('46',false)">[++]</span>&nbsp;<a class="jump" href="#F46">[->end]</a>&nbsp;<a class="jump" href="#H28">[->enclosing]</a><div class="blk" id="B46" style="display:inline"><ul>
  </ul><span class="block"><span class="coll" onclick="coll('46')">&ndash;</span><a name="F46">[2014-12-12 11:43:56.333] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H46">[->top]</a>&nbsp;<a class="jump" href="#F28">[->enclosing]</a></div></li>
  <li><i>[2014-12-12 11:43:56.334]</i>&nbsp;<span class="proto">Created command 'Sync' (outgoing)</span></li>
  <li><span class="block"><div id="E47" style="display:none" class="exp" onclick="exp('47')">+</div><div id="C47" style="display:inline" class="coll" onclick="coll('47')">&ndash;</div><a name="H47">[2014-12-12 11:43:56.335] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Sync</span></span>&nbsp;<span class="doall" onclick="doall('47',true)">[--]</span><span class="doall" onclick="doall('47',false)">[++]</span>&nbsp;<a class="jump" href="#F47">[->end]</a>&nbsp;<a class="jump" href="#H28">[->enclosing]</a><div class="blk" id="B47" style="display:inline"><ul>
    <li><span class="block"><div id="E48" style="display:none" class="exp" onclick="exp('48')">+</div><div id="C48" style="display:inline" class="coll" onclick="coll('48')">&ndash;</div><a name="H48">[2014-12-12 11:43:56.335] 'sync' - Opened Sync command bracket</a></span><span class="attribute">, Reopen=<span class="attrval">no</span>, SourceURI=<span class="attrval">calendar</span>, TargetURI=<span class="attrval">./calendar</span>, IncomingMsgID=<span class="attrval">2</span>, CmdID=<span class="attrval">3</span></span>&nbsp;<span class="doall" onclick="doall('48',true)">[--]</span><span class="doall" onclick="doall('48',false)">[++]</span>&nbsp;<a class="jump" href="#F48">[->end]</a>&nbsp;<a class="jump" href="#H47">[->enclosing]</a><div class="blk" id="B48" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.335]</i>&nbsp;<span class="hotalone">Opened &lt;Sync&gt; bracket, Source='calendar', Target='./calendar' as (outgoing MsgID=2, CmdID=3)</span></li>
      <li><span class="block"><div id="E49" style="display:none" class="exp" onclick="exp('49')">+</div><div id="C49" style="display:inline" class="coll" onclick="coll('49')">&ndash;</div><a name="H49">[2014-12-12 11:43:56.335] 'SyncGen' - Now generating sync commands</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span></span>&nbsp;<span class="doall" onclick="doall('49',true)">[--]</span><span class="doall" onclick="doall('49',false)">[++]</span>&nbsp;<a class="jump" href="#F49">[->end]</a>&nbsp;<a class="jump" href="#H48">[->enclosing]</a><div class="blk" id="B49" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:56.335]</i>&nbsp;<span class="proto">Created command '[unknown]' (outgoing)</span></li>
        <li><span class="block"><div id="E50" style="display:none" class="exp" onclick="exp('50')">+</div><div id="C50" style="display:inline" class="coll" onclick="coll('50')">&ndash;</div><a name="H50">[2014-12-12 11:43:56.335] 'Item_Generate' - generating SyncML item</a></span><span class="attribute">, SyncOp=<span class="attrval">wants-add</span>, LocalID=<span class="attrval">e29fdf27-7117-4cee-81dc-d012398ddba7-rid</span></span>&nbsp;<span class="doall" onclick="doall('50',true)">[--]</span><span class="doall" onclick="doall('50',false)">[++]</span>&nbsp;<a class="jump" href="#F50">[->end]</a>&nbsp;<a class="jump" href="#H49">[->enclosing]</a><div class="blk" id="B50" style="display:inline"><ul>
        </ul><span class="block"><span class="coll" onclick="coll('50')">&ndash;</span><a name="F50">[2014-12-12 11:43:56.337] End of 'Item_Generate'</a></span>&nbsp;<a class="jump" href="#H50">[->top]</a>&nbsp;<a class="jump" href="#F49">[->enclosing]</a></div></li>
        <li><span class="block"><div id="E51" style="display:none" class="exp" onclick="exp('51')">+</div><div id="C51" style="display:inline" class="coll" onclick="coll('51')">&ndash;</div><a name="H51">[2014-12-12 11:43:56.337] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Add</span></span>&nbsp;<span class="doall" onclick="doall('51',true)">[--]</span><span class="doall" onclick="doall('51',false)">[++]</span>&nbsp;<a class="jump" href="#F51">[->end]</a>&nbsp;<a class="jump" href="#H49">[->enclosing]</a><div class="blk" id="B51" style="display:inline"><ul>
          <li><i>[2014-12-12 11:43:56.337]</i>&nbsp;<span class="proto">Item remoteID='', localID='e29fdf27-7117-4cee-81dc-d012398ddba7-rid', datasize=730</span></li>
          <li><i>[2014-12-12 11:43:56.337]</i>&nbsp;<span class="hotalone">Add: issued as (outgoing MsgID=2, CmdID=4), now queueing for <a name="IO_2_4" href="#SO_2_4">status</a></span></li>
          <li><i>[2014-12-12 11:43:56.337]</i>&nbsp;<span class="proto">Outgoing Message size is now 1195 bytes</span></li>
        </ul><span class="block"><span class="coll" onclick="coll('51')">&ndash;</span><a name="F51">[2014-12-12 11:43:56.337] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H51">[->top]</a>&nbsp;<a class="jump" href="#F49">[->enclosing]</a></div></li>
        <li><span class="block"><div id="E52" style="display:none" class="exp" onclick="exp('52')">+</div><div id="C52" style="display:inline" class="coll" onclick="coll('52')">&ndash;</div><a name="H52">[2014-12-12 11:43:56.337] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">server_sync_gen_started</span>, newstate=<span class="attrval">sync_gen_done</span></span>&nbsp;<span class="doall" onclick="doall('52',true)">[--]</span><span class="doall" onclick="doall('52',false)">[++]</span>&nbsp;<a class="jump" href="#F52">[->end]</a>&nbsp;<a class="jump" href="#H49">[->enclosing]</a><div class="blk" id="B52" style="display:inline"><ul>
        </ul><span class="block"><span class="coll" onclick="coll('52')">&ndash;</span><a name="F52">[2014-12-12 11:43:56.337] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H52">[->top]</a>&nbsp;<a class="jump" href="#F49">[->enclosing]</a></div></li>
        <li><i>[2014-12-12 11:43:56.338]</i>&nbsp;engGenerateSyncCommands ended, state='sync_gen_done', sync generation done</li>
      </ul><span class="block"><span class="coll" onclick="coll('49')">&ndash;</span><a name="F49">[2014-12-12 11:43:56.338] End of 'SyncGen'</a></span>&nbsp;<a class="jump" href="#H49">[->top]</a>&nbsp;<a class="jump" href="#F48">[->enclosing]</a></div></li>
      <li><i>[2014-12-12 11:43:56.338]</i>&nbsp;<span class="hotalone">Closed &lt;/Sync&gt; bracket, final</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('48')">&ndash;</span><a name="F48">[2014-12-12 11:43:56.338] End of 'sync'</a></span>&nbsp;<a class="jump" href="#H48">[->top]</a>&nbsp;<a class="jump" href="#F47">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:56.338]</i>&nbsp;<span class="hotalone">Sync: issued as (outgoing MsgID=2, CmdID=3), now queueing for <a name="IO_2_3" href="#SO_2_3">status</a></span></li>
    <li><i>[2014-12-12 11:43:56.338]</i>&nbsp;<span class="proto">Outgoing Message size is now 1196 bytes</span></li>
  </ul><span class="block"><span class="coll" onclick="coll('47')">&ndash;</span><a name="F47">[2014-12-12 11:43:56.338] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H47">[->top]</a>&nbsp;<a class="jump" href="#F28">[->enclosing]</a></div></li>
  <li><i>[2014-12-12 11:43:56.338]</i>&nbsp;<span class="hotalone">All datastores are done with generating &lt;Sync&gt;</span></li>
  <li><i>[2014-12-12 11:43:56.338]</i>&nbsp;<span class="proto">Ending message with 0 next-message/0 next-package commands: FINAL (final allowed by caller)</span></li>
  <li><i>[2014-12-12 11:43:56.338]</i>&nbsp;<span class="proto">Entire message size is now 1199 Bytes</span></li>
  <li><i>[2014-12-12 11:43:56.340]</i>&nbsp;<span class="hotalone">---&gt; MessageEnded finishes : new incoming state='map', new outgoing state='map', NeedToAnswer</span></li>
  <li><i>[2014-12-12 11:43:56.340]</i>&nbsp;<span class="hotalone">Local Datastore 'calendar': State=sync_gen_done, normal sync, two-way</span></li>
  <li><i>[2014-12-12 11:43:56.340]</i>&nbsp;<span class="hotalone">=================&gt; Finished generating outgoing message #2, request=0</span></li>
</ul><span class="block"><span class="coll" onclick="coll('28')">&ndash;</span><a name="F28">[2014-12-12 11:43:56.340] End of 'SyncML_Outgoing'</a></span>&nbsp;<a class="jump" href="#H28">[->top]</a></div></li>
<li><i>[2014-12-12 11:43:56.341]</i>&nbsp;<span class="proto">SaveSuspendState not possible (SyncML&lt;1.2 or not supported by DB)</span></li>
<li><i>[2014-12-12 11:43:56.341]</i>&nbsp;<span class="hotalone">========= Finished request (session in progress), processing time=171 msec, incoming bytes=489, outgoing bytes=1199</span></li>
<li><i>[2014-12-12 11:43:56.342]</i>&nbsp;@owncloud-/calendar: total number of items received 0</li>
<li><i>[2014-12-12 11:43:56.342]</i>&nbsp;<span class="hotalone">@owncloud-/calendar: sent 1</span></li>
<li><i>[2014-12-12 11:43:56.348]</i>&nbsp;waiting for child to send message</li>
<li><span class="block"><div id="E53" style="display:none" class="exp" onclick="exp('53')">+</div><div id="C53" style="display:inline" class="coll" onclick="coll('53')">&ndash;</div><a name="H53">[2014-12-12 11:43:56.528] 'SyncML_Outgoing' - preparing for response before starting to analyze new incoming message</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('53',true)">[--]</span><span class="doall" onclick="doall('53',false)">[++]</span>&nbsp;<a class="jump" href="#F53">[->end]</a><div class="blk" id="B53" style="display:inline"><ul>
  <li><span class="block"><div id="E54" style="display:none" class="exp" onclick="exp('54')">+</div><div id="C54" style="display:inline" class="coll" onclick="coll('54')">&ndash;</div><a name="H54">[2014-12-12 11:43:56.528] 'SyncML_Incoming' - Starting to analyze incoming message</a></span><span class="attribute">, RequestNo=<span class="attrval">0</span>, SySyncVers=<span class="attrval">3.4.0.47</span></span>&nbsp;<span class="doall" onclick="doall('54',true)">[--]</span><span class="doall" onclick="doall('54',false)">[++]</span>&nbsp;<a class="jump" href="#F54">[->end]</a>&nbsp;<a class="jump" href="#H53">[->enclosing]</a><div class="blk" id="B54" style="display:inline"><ul>
    <li><i>[2014-12-12 11:43:56.528]</i>&nbsp;<span class="hotalone">=================&gt; Starting to analyze incoming message, SySync V3.4.0.47, RequestNo=0</span></li>
    <li><i>[2014-12-12 11:43:56.529]</i>&nbsp;<span class="proto">Created command 'SyncHdr' (incoming)</span></li>
    <li><span class="block"><div id="E55" style="display:none" class="exp" onclick="exp('55')">+</div><div id="C55" style="display:inline" class="coll" onclick="coll('55')">&ndash;</div><a name="H55">[2014-12-12 11:43:56.529] 'processHdr' - Processing incoming SyncHdr</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('55',true)">[--]</span><span class="doall" onclick="doall('55',false)">[++]</span>&nbsp;<a class="jump" href="#F55">[->end]</a>&nbsp;<a class="jump" href="#H54">[->enclosing]</a><div class="blk" id="B55" style="display:inline"><ul>
      <li><span class="block"><div id="E56" style="display:none" class="exp" onclick="exp('56')">+</div><div id="C56" style="display:inline" class="coll" onclick="coll('56')">&ndash;</div><a name="H56">[2014-12-12 11:43:56.529] 'SyncHdr' - Processing incoming SyncHdr</a></span><span class="attribute">, IncomingMsgID=<span class="attrval">3</span></span>&nbsp;<span class="doall" onclick="doall('56',true)">[--]</span><span class="doall" onclick="doall('56',false)">[++]</span>&nbsp;<a class="jump" href="#F56">[->end]</a>&nbsp;<a class="jump" href="#H55">[->enclosing]</a><div class="blk" id="B56" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:56.529]</i>&nbsp;<span class="hotalone">Started Processing of message #3 (SyncML/1.2)</span></li>
        <li><i>[2014-12-12 11:43:56.529]</i>&nbsp;<span class="remote">MaxMsgSize found in SyncHdr: 150000 -&gt; set for outgoing msgs</span></li>
        <li><i>[2014-12-12 11:43:56.529]</i>&nbsp;<span class="remote">MaxObjSize found in SyncHdr: 4000000</span></li>
        <li><i>[2014-12-12 11:43:56.529]</i>&nbsp;<span class="proto">Created command 'Status' (outgoing)</span></li>
        <li><i>[2014-12-12 11:43:56.530]</i>&nbsp;<span class="proto">Authorization ok from previous request: 200</span></li>
        <li><i>[2014-12-12 11:43:56.530]</i>&nbsp;<span class="proto">Created command 'SyncHdr' (outgoing)</span></li>
        <li><i>[2014-12-12 11:43:56.530]</i>&nbsp;<span class="proto">Target (Remote URI) = 'syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500'</span></li>
        <li><span class="block"><div id="E57" style="display:none" class="exp" onclick="exp('57')">+</div><div id="C57" style="display:inline" class="coll" onclick="coll('57')">&ndash;</div><a name="H57">[2014-12-12 11:43:56.530] 'SyncHdr' - SyncHdr generation</a></span><span class="attribute">, SyncMLVers=<span class="attrval">1.2</span>, OutgoingMsgID=<span class="attrval">3</span></span>&nbsp;<span class="doall" onclick="doall('57',true)">[--]</span><span class="doall" onclick="doall('57',false)">[++]</span>&nbsp;<a class="jump" href="#F57">[->end]</a>&nbsp;<a class="jump" href="#H56">[->enclosing]</a><div class="blk" id="B57" style="display:inline"><ul>
          <li><i>[2014-12-12 11:43:56.530]</i>&nbsp;<span class="proto">SyncHdr: issued in MsgID=3, now queueing for status</span></li>
          <li><i>[2014-12-12 11:43:56.530]</i>&nbsp;<span class="proto">Outgoing Message size is now 160 bytes</span></li>
        </ul><span class="block"><span class="coll" onclick="coll('57')">&ndash;</span><a name="F57">[2014-12-12 11:43:56.531] End of 'SyncHdr'</a></span>&nbsp;<a class="jump" href="#H57">[->top]</a>&nbsp;<a class="jump" href="#F56">[->enclosing]</a></div></li>
        <li><span class="block"><div id="E58" style="display:none" class="exp" onclick="exp('58')">+</div><div id="C58" style="display:inline" class="coll" onclick="coll('58')">&ndash;</div><a name="H58">[2014-12-12 11:43:56.531] 'issue' - issuing command</a></span><span class="attribute">, Cmd=<span class="attrval">Status</span></span>&nbsp;<span class="doall" onclick="doall('58',true)">[--]</span><span class="doall" onclick="doall('58',false)">[++]</span>&nbsp;<a class="jump" href="#F58">[->end]</a>&nbsp;<a class="jump" href="#H56">[->enclosing]</a><div class="blk" id="B58" style="display:inline"><ul>
          <li><i>[2014-12-12 11:43:56.531]</i>&nbsp;<span class="hotalone">Status Code 200 issued for Cmd=SyncHdr, (incoming MsgID=3, CmdID=0)</span></li>
          <li><i>[2014-12-12 11:43:56.531]</i>&nbsp;<span class="hotalone">- TargetRef (localID) = '?sessionid=7166071432577307786'</span></li>
          <li><i>[2014-12-12 11:43:56.531]</i>&nbsp;<span class="hotalone">- SourceRef (remoteID) = 'syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500'</span></li>
          <li><i>[2014-12-12 11:43:56.531]</i>&nbsp;<span class="hotalone">Status: issued as (outgoing MsgID=3, CmdID=1), not waiting for status</span></li>
          <li><i>[2014-12-12 11:43:56.531]</i>&nbsp;<span class="proto">Deleted command 'Status' (outgoing MsgID=3, CmdID=1)</span></li>
          <li><i>[2014-12-12 11:43:56.531]</i>&nbsp;<span class="proto">Outgoing Message size is now 283 bytes</span></li>
        </ul><span class="block"><span class="coll" onclick="coll('58')">&ndash;</span><a name="F58">[2014-12-12 11:43:56.531] End of 'issue'</a></span>&nbsp;<a class="jump" href="#H58">[->top]</a>&nbsp;<a class="jump" href="#F56">[->enclosing]</a></div></li>
        <li><i>[2014-12-12 11:43:56.532]</i>&nbsp;<span class="hotalone">Incoming SyncHdr processed, incomingMsgID=3, SyncMLVers=1.2</span></li>
        <li><i>[2014-12-12 11:43:56.532]</i>&nbsp;<span class="hotalone">- Session ID='18'</span></li>
        <li><i>[2014-12-12 11:43:56.532]</i>&nbsp;<span class="hotalone">- Source (Remote party): URI='syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500' DisplayName=''</span></li>
        <li><i>[2014-12-12 11:43:56.532]</i>&nbsp;<span class="hotalone">- Response to be sent to URI='[none specified, back to source]'</span></li>
        <li><i>[2014-12-12 11:43:56.532]</i>&nbsp;<span class="hotalone">- Target (Local party) : URI='?sessionid=7166071432577307786' DisplayName=''</span></li>
        <li><i>[2014-12-12 11:43:56.532]</i>&nbsp;<span class="proto">Deleted command 'SyncHdr' (incoming MsgID=3, CmdID=0)</span></li>
      </ul><span class="block"><span class="coll" onclick="coll('56')">&ndash;</span><a name="F56">[2014-12-12 11:43:56.532] End of 'SyncHdr'</a></span>&nbsp;<a class="jump" href="#H56">[->top]</a>&nbsp;<a class="jump" href="#F55">[->enclosing]</a></div></li>
    </ul><span class="block"><span class="coll" onclick="coll('55')">&ndash;</span><a name="F55">[2014-12-12 11:43:56.532] End of 'processHdr'</a></span>&nbsp;<a class="jump" href="#H55">[->top]</a>&nbsp;<a class="jump" href="#F54">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:56.532]</i>&nbsp;<span class="proto">Created command 'Status' (incoming)</span></li>
    <li><span class="block"><div id="E59" style="display:none" class="exp" onclick="exp('59')">+</div><div id="C59" style="display:inline" class="coll" onclick="coll('59')">&ndash;</div><a name="H59">[2014-12-12 11:43:56.532] 'processStatus' - Processing incoming Status</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('59',true)">[--]</span><span class="doall" onclick="doall('59',false)">[++]</span>&nbsp;<a class="jump" href="#F59">[->end]</a>&nbsp;<a class="jump" href="#H54">[->enclosing]</a><div class="blk" id="B59" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.533]</i>&nbsp;<span class="hotalone">Started processing Command 'Status' (incoming MsgID=3, CmdID=1)</span></li>
      <li><i>[2014-12-12 11:43:56.533]</i>&nbsp;<span class="hotalone">RECEIVED STATUS 200 for for <a name="SO_2_0" href="#IO_2_0">command 'SyncHdr'</a> (outgoing MsgID=2, CmdID=0)</span></li>
      <li><i>[2014-12-12 11:43:56.533]</i>&nbsp;<span class="hotalone">- TargetRef (remoteID) = 'syncevolution-436bfb58-23c7-4bf6-b85e-5cf2126d7500'</span></li>
      <li><i>[2014-12-12 11:43:56.533]</i>&nbsp;<span class="proto">Found matching command 'SyncHdr' for Status</span></li>
      <li><i>[2014-12-12 11:43:56.533]</i>&nbsp;<span class="proto">Status: 200: successful --&gt; accept as ok</span></li>
      <li><i>[2014-12-12 11:43:56.533]</i>&nbsp;<span class="proto">Deleted command 'SyncHdr' (outgoing MsgID=2, CmdID=0)</span></li>
      <li><i>[2014-12-12 11:43:56.533]</i>&nbsp;<span class="proto">Deleted command 'Status' (incoming MsgID=3, CmdID=1)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('59')">&ndash;</span><a name="F59">[2014-12-12 11:43:56.533] End of 'processStatus'</a></span>&nbsp;<a class="jump" href="#H59">[->top]</a>&nbsp;<a class="jump" href="#F54">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:56.534]</i>&nbsp;<span class="proto">Created command 'Status' (incoming)</span></li>
    <li><span class="block"><div id="E60" style="display:none" class="exp" onclick="exp('60')">+</div><div id="C60" style="display:inline" class="coll" onclick="coll('60')">&ndash;</div><a name="H60">[2014-12-12 11:43:56.534] 'processStatus' - Processing incoming Status</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('60',true)">[--]</span><span class="doall" onclick="doall('60',false)">[++]</span>&nbsp;<a class="jump" href="#F60">[->end]</a>&nbsp;<a class="jump" href="#H54">[->enclosing]</a><div class="blk" id="B60" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.534]</i>&nbsp;<span class="hotalone">Started processing Command 'Status' (incoming MsgID=3, CmdID=2)</span></li>
      <li><i>[2014-12-12 11:43:56.534]</i>&nbsp;<span class="hotalone">RECEIVED STATUS 200 for for <a name="SO_2_3" href="#IO_2_3">command 'Sync'</a> (outgoing MsgID=2, CmdID=3)</span></li>
      <li><i>[2014-12-12 11:43:56.534]</i>&nbsp;<span class="hotalone">- TargetRef (remoteID) = './calendar'</span></li>
      <li><i>[2014-12-12 11:43:56.534]</i>&nbsp;<span class="hotalone">- SourceRef (localID) = 'calendar'</span></li>
      <li><i>[2014-12-12 11:43:56.534]</i>&nbsp;<span class="proto">Found matching command 'Sync' for Status</span></li>
      <li><i>[2014-12-12 11:43:56.535]</i>&nbsp;<span class="proto">Status: 200: successful --&gt; accept as ok</span></li>
      <li><i>[2014-12-12 11:43:56.535]</i>&nbsp;<span class="proto">Deleted command 'Sync' (outgoing MsgID=2, CmdID=3)</span></li>
      <li><i>[2014-12-12 11:43:56.535]</i>&nbsp;<span class="proto">Deleted command 'Status' (incoming MsgID=3, CmdID=2)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('60')">&ndash;</span><a name="F60">[2014-12-12 11:43:56.535] End of 'processStatus'</a></span>&nbsp;<a class="jump" href="#H60">[->top]</a>&nbsp;<a class="jump" href="#F54">[->enclosing]</a></div></li>
    <li><i>[2014-12-12 11:43:56.535]</i>&nbsp;<span class="proto">Created command 'Status' (incoming)</span></li>
    <li><span class="block"><div id="E61" style="display:none" class="exp" onclick="exp('61')">+</div><div id="C61" style="display:inline" class="coll" onclick="coll('61')">&ndash;</div><a name="H61">[2014-12-12 11:43:56.535] 'processStatus' - Processing incoming Status</a></span><span class="attribute"></span>&nbsp;<span class="doall" onclick="doall('61',true)">[--]</span><span class="doall" onclick="doall('61',false)">[++]</span>&nbsp;<a class="jump" href="#F61">[->end]</a>&nbsp;<a class="jump" href="#H54">[->enclosing]</a><div class="blk" id="B61" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.536]</i>&nbsp;<span class="hotalone">Started processing Command 'Status' (incoming MsgID=3, CmdID=3)</span></li>
      <li><i>[2014-12-12 11:43:56.536]</i>&nbsp;<span class="error">WARNING: RECEIVED NON-OK STATUS 403 for <a name="SO_2_4" href="#IO_2_4">command 'Add'</a> (outgoing MsgID=2, CmdID=4)</span></li>
      <li><i>[2014-12-12 11:43:56.536]</i>&nbsp;<span class="hotalone">- SourceRef (localID) = 'e29fdf27-7117-4cee-81dc-d012398ddba7-rid'</span></li>
      <li><i>[2014-12-12 11:43:56.536]</i>&nbsp;<span class="proto">Found matching command 'Add' for Status</span></li>
      <li><i>[2014-12-12 11:43:56.536]</i>&nbsp;<span class="error">Status: 403: originator exception</span></li>
      <li><span class="block"><div id="E62" style="display:none" class="exp" onclick="exp('62')">+</div><div id="C62" style="display:inline" class="coll" onclick="coll('62')">&ndash;</div><a name="H62">[2014-12-12 11:43:56.536] 'SessionAbort' - Aborting Session</a></span><span class="attribute">, Status=<span class="attrval">403</span>, ProblemSource=<span class="attrval">REMOTE</span></span>&nbsp;<span class="doall" onclick="doall('62',true)">[--]</span><span class="doall" onclick="doall('62',false)">[++]</span>&nbsp;<a class="jump" href="#F62">[->end]</a>&nbsp;<a class="jump" href="#H61">[->enclosing]</a><div class="blk" id="B62" style="display:inline"><ul>
        <li><i>[2014-12-12 11:43:56.536]</i>&nbsp;<span class="error">WARNING: Aborting Session with Reason Status 403 (REMOTE problem) ***</span></li>
        <li><i>[2014-12-12 11:43:56.536]</i>&nbsp;<span class="hotalone">--------------- Ignoring all commands in this message (after 0 sec. request processing, 4 sec. total) with Status 514 (0=none) from here on</span></li>
        <li><span class="block"><div id="E63" style="display:none" class="exp" onclick="exp('63')">+</div><div id="C63" style="display:inline" class="coll" onclick="coll('63')">&ndash;</div><a name="H63">[2014-12-12 11:43:56.536] 'DSAbort' - Aborting datastore sync</a></span><span class="attribute">, abortStatusCode=<span class="attrval">403</span>, localProblem=<span class="attrval">no</span>, resumable=<span class="attrval">yes</span></span>&nbsp;<span class="doall" onclick="doall('63',true)">[--]</span><span class="doall" onclick="doall('63',false)">[++]</span>&nbsp;<a class="jump" href="#F63">[->end]</a>&nbsp;<a class="jump" href="#H62">[->enclosing]</a><div class="blk" id="B63" style="display:inline"><ul>
          <li><i>[2014-12-12 11:43:56.537]</i>&nbsp;<span class="proto">SaveSuspendState not possible (SyncML&lt;1.2 or not supported by DB)</span></li>
          <li><i>[2014-12-12 11:43:56.537]</i>&nbsp;<span class="error">*************** Warning: Datastore flagged aborted (after 0 sec. request processing, 4 sec. total) with REMOTE Status 403</span></li>
        </ul><span class="block"><span class="coll" onclick="coll('63')">&ndash;</span><a name="F63">[2014-12-12 11:43:56.537] End of 'DSAbort'</a></span>&nbsp;<a class="jump" href="#H63">[->top]</a>&nbsp;<a class="jump" href="#F62">[->enclosing]</a></div></li>
      </ul><span class="block"><span class="coll" onclick="coll('62')">&ndash;</span><a name="F62">[2014-12-12 11:43:56.537] End of 'SessionAbort'</a></span>&nbsp;<a class="jump" href="#H62">[->top]</a>&nbsp;<a class="jump" href="#F61">[->enclosing]</a></div></li>
      <li><i>[2014-12-12 11:43:56.537]</i>&nbsp;<span class="proto">Deleted command 'Add' (outgoing MsgID=2, CmdID=4)</span></li>
      <li><i>[2014-12-12 11:43:56.537]</i>&nbsp;<span class="proto">Deleted command 'Status' (incoming MsgID=3, CmdID=3)</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('61')">&ndash;</span><a name="F61">[2014-12-12 11:43:56.537] End of 'processStatus'</a></span>&nbsp;<a class="jump" href="#H61">[->top]</a>&nbsp;<a class="jump" href="#F54">[->enclosing]</a></div></li>
<li><i>[2014-12-12 11:43:56.539]</i>&nbsp;<span class="hotalone">@owncloud-/calendar: normal sync done unsuccessfully</span></li>
<li><i>[2014-12-12 11:43:56.541]</i>&nbsp;<span class="hotalone">@owncloud-/calendar: log in succeeded, but server refuses access - contact server operator</span></li>
<li><i>[2014-12-12 11:43:56.544]</i>&nbsp;closing session</li>
    <li><span class="block"><div id="E64" style="display:none" class="exp" onclick="exp('64')">+</div><div id="C64" style="display:inline" class="coll" onclick="coll('64')">&ndash;</div><a name="H64">[2014-12-12 11:43:56.544] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">sync_gen_done</span>, newstate=<span class="attrval">completed</span></span>&nbsp;<span class="doall" onclick="doall('64',true)">[--]</span><span class="doall" onclick="doall('64',false)">[++]</span>&nbsp;<a class="jump" href="#F64">[->end]</a>&nbsp;<a class="jump" href="#H54">[->enclosing]</a><div class="blk" id="B64" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.544]</i>&nbsp;<span class="hotalone">Sync Statistics for 'calendar' (calendar), normal sync</span></li>
<li><i>[2014-12-12 11:43:56.544]</i>&nbsp;<span class="hotalone"><pre>==================================================
                             on Server   on Client
Added:                               0           0
Deleted:                             0           0
Updated:                             0           0
Rejected with error:                 0           0
SlowSync Matches:                    0
Server won Conflicts:                0
Client won Conflicts:                0
Conflicts with Duplication:          0
Content Data Bytes sent:           730
Content Data Bytes received:         0
Duration of sync [seconds]:          4</pre></span></li>
      <li><i>[2014-12-12 11:43:56.545]</i>&nbsp;<span class="error">Warning: Failed with status code=403, statistics are incomplete!!</span></li>
    </ul><span class="block"><span class="coll" onclick="coll('64')">&ndash;</span><a name="F64">[2014-12-12 11:43:56.545] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H64">[->top]</a>&nbsp;<a class="jump" href="#F54">[->enclosing]</a></div></li>
    <li><span class="block"><div id="E65" style="display:none" class="exp" onclick="exp('65')">+</div><div id="C65" style="display:inline" class="coll" onclick="coll('65')">&ndash;</div><a name="H65">[2014-12-12 11:43:56.545] 'DSStateChange' - Datastore changes state</a></span><span class="attribute">, datastore=<span class="attrval">calendar</span>, oldstate=<span class="attrval">completed</span>, newstate=<span class="attrval">idle</span></span>&nbsp;<span class="doall" onclick="doall('65',true)">[--]</span><span class="doall" onclick="doall('65',false)">[++]</span>&nbsp;<a class="jump" href="#F65">[->end]</a>&nbsp;<a class="jump" href="#H54">[->enclosing]</a><div class="blk" id="B65" style="display:inline"><ul>
      <li><i>[2014-12-12 11:43:56.545]</i>&nbsp;endDataWrite called, write is started</li>
<li><i>[2014-12-12 11:43:56.546]</i>&nbsp;@owncloud-/calendar: EndDataWrite COMMIT '2014-12-12T10:43:56Z' res=0</li>
    </ul><span class="block"><span class="coll" onclick="coll('65')">&ndash;</span><a name="F65">[2014-12-12 11:43:56.546] End of 'DSStateChange'</a></span>&nbsp;<a class="jump" href="#H65">[->top]</a>&nbsp;<a class="jump" href="#F54">[->enclosing]</a></div></li>
<li><i>[2014-12-12 11:43:56.546]</i>&nbsp;@owncloud-/calendar: DeleteContext</li>
<li><i>[2014-12-12 11:43:56.546]</i>&nbsp;@owncloud-/calendar: DeleteContext</li>
    <li><i>[2014-12-12 11:43:56.547]</i>&nbsp;<span class="proto">Never received status for <a name="SO_3_0" href="#IO_3_0">command 'SyncHdr'</a>, (outgoing MsgID=3, CmdID=0)</span></li>
    <li><i>[2014-12-12 11:43:56.547]</i>&nbsp;<span class="proto">Deleted command 'SyncHdr' (outgoing MsgID=3, CmdID=0)</span></li>
    <li><i>[2014-12-12 11:43:56.547]</i>&nbsp;<span class="proto">Deleted command 'Status' (outgoing MsgID=0, CmdID=0)</span></li>
    <li><i>[2014-12-12 11:43:56.547]</i>&nbsp;<span class="hotalone">Session data transfer statistics: incoming bytes=3493, outgoing bytes=4451</span></li>
<li><i>[2014-12-12 11:43:56.547]</i>&nbsp;<span class="hotalone">TSyncAgent::~TSyncAgent: Deleted SyncML session (local session id=7166071432577307786)</span></li>
    <li><i>[2014-12-12 11:43:56.547]</i>&nbsp;<span class="hotalone">--------- END of embedded log for session ID '7166071432577307786' ---------</span></li>
  </ul><span class="block"><span class="coll" onclick="coll('54')">&ndash;</span><a name="F54">[2014-12-12 11:43:56.547] End of 'SyncML_Incoming' - closed because sub-log ends here - Block Nest Warning: implicitly closed (by explicitly closing '<unknown parent>')</a></span>&nbsp;<a class="jump" href="#H54">[->top]</a>&nbsp;<a class="jump" href="#F53">[->enclosing]</a></div></li>
</ul><span class="block"><span class="coll" onclick="coll('53')">&ndash;</span><a name="F53">[2014-12-12 11:43:56.548] End of 'SyncML_Outgoing' - closed because sub-log ends here - Block Nest Warning: closing '<unknown>', but expected 'SyncML_Outgoing'</a></span>&nbsp;<a class="jump" href="#H53">[->top]</a></div></li>
<li><i>[2014-12-12 11:43:56.549]</i>&nbsp;session closed</li>
ghost commented 9 years ago

Hi,

have you tried to run litmus against your WebDAV URL (not CalDAV) like recommended in the FAQ thread:

https://forum.owncloud.org/viewtopic.php?f=17&t=7536

posted at the older issue https://github.com/owncloud/core/issues/11943 about this?

This won't test the CalDAV sync but could show you if there is a general problem in your server config.

ghost commented 9 years ago

I tried to run it but failed to get litmus running. I use another App to access the files in my account. That works fine. I added an entry through the web interface. It got downloaded and added to my calender but the events on my phone do not get uploaded. "Login Successful but server denied access". This sounds to me like there is something set up wrong on my server.

ghost commented 9 years ago

Hi,

yes this is a sign for some misconfiguration on server-side or some security modules like mod_security blocking the DAV methods (see linkd FAQ)

An normal file access is just using a GET which is always allowed but syncing calendars and contacts are using PUT, PROPFIND, PROPGET and so on.

ghost commented 9 years ago

But I did not change anything on the server since OC 6. The trouble started with OC 7.

ghost commented 9 years ago

Mhhh, very strange. Which URL are you're using to sync your calendar? (You can redact your domain name when posting it)

What's conspicuous in my opinion are those entries:

{"app":"PHP","message":"fopen(\/var\/www\/owncloud\/config\/config.php): failed to open stream: No such file or directory at \/var\/www\/owncloud\/lib\/private\/config.php#141","level":3,"time":"2014-12-12T08:55:00+00:00"}

Seems your config can't be read by OC. Was this just a temporary problem?

and

{"reqId":"548ac392f2101","app":"PHP","message":"apc_store(): GC cache entry 'oc2eda8a0b6f\/AutoloaderDoctrine\DBAL\Driver' was on gc-list for 4319 seconds at \/var\/www\/owncloud\/lib\/private\/memcache\/apc.php#21","level":3,"time":"2014-12-12T10:29:38+00:00","method":"REPORT","url":"\/owncloud\/remote.php\/caldav\/"}

Have you tried to disable APC in your php config?

PVince81 commented 9 years ago

User has left github and issue not reproducible => closing