[DEBUG 00:00:00] *** starting Client::Source::davical_carddav::testReadItem404 ***
[DEBUG 00:00:00] ClientTest.cpp:1160: starting CPPUNIT_ASSERT(config.m_createSourceA)
[DEBUG 00:00:00] ClientTest.cpp:1160: ending CPPUNIT_ASSERT(config.m_createSourceA)
[DEBUG 00:00:00] ClientTest.cpp:1164: starting source.reset(createSourceA())
[DEBUG 00:00:00] instantiating testing source davical_carddav in config target-config@client-test-davical, with tracking name _1_A
[DEBUG 00:00:00] checking password property 'password' in config 'davical_1@client-test-davical-1' with user identity ''
[DEBUG 00:00:00] checking password property 'databasePassword' in datastore 'davical_carddav' of config 'davical_1@client-test-davical-1' with user identity 'tester2'
[DEBUG 00:00:00] loading password from keyring with key user=tester2 object=@client-test-davical-1 davical_carddav backend
[DEBUG 00:00:00] not using KWallet
[DEBUG 00:00:00] databasePassword for davical_carddav backend in datastore davical_carddav: loaded password from GNOME keyring using user=tester2 object=@client-test-davical-1 davical_carddav backend
[DEBUG 00:00:00] overriding testing source davical_carddav properties with the ones from config davical_1 = /data/runtests/work/testing-amd64/home/davical/config/syncevolution/client-test-davical-1/peers/davical_1
[DEBUG 00:00:00]    sync = disabled (default)
[DEBUG 00:00:00]    uri =  (default)
[DEBUG 00:00:00]    backend = CardDAV (set)
[DEBUG 00:00:00]    syncFormat =  (default)
[DEBUG 00:00:00]    forceSyncFormat = 0 (default)
[DEBUG 00:00:00]    database = http://localhost:9009/davical/caldav.php/tester2/Test_davical_carddav_1/ (set)
[DEBUG 00:00:00]    databaseFormat =  (default)
[DEBUG 00:00:00]    databaseUser = tester2 (set)
[DEBUG 00:00:00]    databasePassword = testing2 (set)
[DEBUG 00:00:00]    additional property backend = carddav (from CLIENT_TEST_WEBDAV)
[DEBUG 00:00:00] ClientTest.cpp:424: starting CPPUNIT_ASSERT(get())
[DEBUG 00:00:00] ClientTest.cpp:424: ending CPPUNIT_ASSERT(get())
[DEBUG 00:00:00] ClientTest.cpp:425: starting CPPUNIT_ASSERT(!m_active)
[DEBUG 00:00:00] ClientTest.cpp:425: ending CPPUNIT_ASSERT(!m_active)
[DEBUG 00:00:00] ClientTest.cpp:431: starting get()->open()
[DEBUG 00:00:00] ClientTest.cpp:431: ending get()->open()
[DEBUG 00:00:00] using libneon neon 0.30.2: Library build, IPv6, libxml 2.9.4, zlib 1.2.8, GNU TLS 3.5.6. with SSL, ZLIB, IPV6, TS_SSL, I18N
[DEVELOPER 00:00:00] stderr: HTTP session to http://localhost:9009 begins.
[INFO 00:00:00] carddav #A: using configured database=http://localhost:9009/davical/caldav.php/tester2/Test_davical_carddav_1/
[DEBUG 00:00:00] using username 'tester2' from datastore config for WebDAV, password was set
[DEBUG 00:00:00] using plain username/password for tester2
[DEBUG 00:00:00] carddav #A: slow sync or testing, do full item scan to detect changes
[DEBUG 00:00:00] starting PROPFIND, credentials unverified, deadline in 120.0s
[DEVELOPER 00:00:00] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:00:00] stderr: Running pre_send hooks
[DEBUG 00:00:00] skipping forced sending credentials because not using https
[DEVELOPER 00:00:00] stderr: Sending request headers:
[DEVELOPER 00:00:00] stderr: PROPFIND /davical/caldav.php/tester2/Test_davical_carddav_1/ HTTP/1.1
[DEVELOPER 00:00:00] stderr: Keep-Alive:
[DEVELOPER 00:00:00] stderr: Connection: TE, Keep-Alive
[DEVELOPER 00:00:00] stderr: TE: trailers
[DEVELOPER 00:00:00] stderr: Host: localhost:9009
[DEVELOPER 00:00:00] stderr: Depth: 1
[DEVELOPER 00:00:00] stderr: Content-Length: 141
[DEVELOPER 00:00:00] stderr: Content-Type: application/xml
[DEVELOPER 00:00:00] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:00] stderr:
[DEVELOPER 00:00:00] stderr: Sending request-line and headers:
[DEVELOPER 00:00:00] stderr: Doing DNS lookup on localhost...
[DEVELOPER 00:00:00] stderr: req: Connecting to 127.0.0.1:9009
[DEVELOPER 00:00:00] stderr: Sending request body:
[DEVELOPER 00:00:00] stderr: Body block (141 bytes):
[DEVELOPER 00:00:00] stderr: [<?xml version="1.0" encoding="utf-8"?>
[DEVELOPER 00:00:00] stderr: <propfind xmlns="DAV:"><prop>
[DEVELOPER 00:00:00] stderr: <getetag xmlns="DAV:"/>
[DEVELOPER 00:00:00] stderr: <resourcetype xmlns="DAV:"/>
[DEVELOPER 00:00:00] stderr: </prop></propfind>
[DEVELOPER 00:00:00] stderr: ]
[DEVELOPER 00:00:00] stderr: Request sent; retry is 0.
[DEVELOPER 00:00:00] stderr: [status-line] < HTTP/1.1 401 Unauthorized
[DEVELOPER 00:00:00] stderr: [hdr] Server: 1.1
[DEVELOPER 00:00:00] stderr: Header Name: [server], Value: [1.1]
[DEVELOPER 00:00:00] stderr: [hdr] Content-type: text/plain; ; charset="utf-8"
[DEVELOPER 00:00:00] stderr: Header Name: [content-type], Value: [text/plain; ; charset="utf-8"]
[DEVELOPER 00:00:00] stderr: [hdr] WWW-Authenticate: Basic realm="DAViCal CalDAV Server"
[DEVELOPER 00:00:00] stderr: Header Name: [www-authenticate], Value: [Basic realm="DAViCal CalDAV Server"]
[DEVELOPER 00:00:00] stderr: [hdr] Transfer-Encoding: chunked
[DEVELOPER 00:00:00] stderr: Header Name: [transfer-encoding], Value: [chunked]
[DEVELOPER 00:00:00] stderr: [hdr] Date: Fri, 05 Jan 2018 17:44:16 GMT
[DEVELOPER 00:00:00] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 17:44:16 GMT]
[DEVELOPER 00:00:00] stderr: [hdr]
[DEVELOPER 00:00:00] stderr: End of headers.
[DEVELOPER 00:00:00] stderr: Running post_headers hooks
[DEVELOPER 00:00:00] stderr: [chunk] < 28
[DEVELOPER 00:00:00] stderr: Got chunk size: 40
[DEVELOPER 00:00:00] stderr: Reading 40 bytes of response body.
[DEVELOPER 00:00:00] stderr: Got 40 bytes.
[DEVELOPER 00:00:00] stderr: Read block (40 bytes):
[DEVELOPER 00:00:00] stderr: [Please log in for access to this system.]
[DEVELOPER 00:00:00] stderr: [chunk] < 0
[DEVELOPER 00:00:00] stderr: Got chunk size: 0
[DEVELOPER 00:00:00] stderr: [hdr]
[DEVELOPER 00:00:00] stderr: End of headers.
[DEVELOPER 00:00:00] stderr: Running post_send hooks
[DEVELOPER 00:00:00] stderr: ah_post_send (#0), code is 401 (want 401), WWW-Authenticate is Basic realm="DAViCal CalDAV Server"
[DEVELOPER 00:00:00] stderr: auth: Got challenge (code 401).
[DEVELOPER 00:00:00] stderr: auth: Got 'Basic' challenge.
[DEVELOPER 00:00:00] stderr: auth: Trying Basic challenge...
[DEBUG 00:00:00] retry request with credentials
[DEVELOPER 00:00:00] stderr: auth: Accepted Basic challenge.
[DEVELOPER 00:00:00] stderr: Running pre_send hooks
[DEVELOPER 00:00:00] stderr: auth: Sending 'Basic' response.
[DEVELOPER 00:00:00] stderr: Sending request headers:
[DEVELOPER 00:00:00] stderr: PROPFIND /davical/caldav.php/tester2/Test_davical_carddav_1/ HTTP/1.1
[DEVELOPER 00:00:00] stderr: Keep-Alive:
[DEVELOPER 00:00:00] stderr: Connection: TE, Keep-Alive
[DEVELOPER 00:00:00] stderr: TE: trailers
[DEVELOPER 00:00:00] stderr: Host: localhost:9009
[DEVELOPER 00:00:00] stderr: Depth: 1
[DEVELOPER 00:00:00] stderr: Content-Length: 141
[DEVELOPER 00:00:00] stderr: Content-Type: application/xml
[DEVELOPER 00:00:00] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:00:00] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:00] stderr:
[DEVELOPER 00:00:00] stderr: Sending request-line and headers:
[DEVELOPER 00:00:00] stderr: Sending request body:
[DEVELOPER 00:00:00] stderr: Body block (141 bytes):
[DEVELOPER 00:00:00] stderr: [<?xml version="1.0" encoding="utf-8"?>
[DEVELOPER 00:00:00] stderr: <propfind xmlns="DAV:"><prop>
[DEVELOPER 00:00:00] stderr: <getetag xmlns="DAV:"/>
[DEVELOPER 00:00:00] stderr: <resourcetype xmlns="DAV:"/>
[DEVELOPER 00:00:00] stderr: </prop></propfind>
[DEVELOPER 00:00:00] stderr: ]
[DEVELOPER 00:00:00] stderr: Request sent; retry is 1.
[DEVELOPER 00:00:00] stderr: [status-line] < HTTP/1.1 207 Multi-status
[DEVELOPER 00:00:00] stderr: [hdr] Server: 1.1
[DEVELOPER 00:00:00] stderr: Header Name: [server], Value: [1.1]
[DEVELOPER 00:00:00] stderr: [hdr] DAV: 1, 2, 3, access-control, calendar-access, calendar-schedule
[DEVELOPER 00:00:00] stderr: Header Name: [dav], Value: [1, 2, 3, access-control, calendar-access, calendar-schedule]
[DEVELOPER 00:00:00] stderr: [hdr] DAV: extended-mkcol, bind, addressbook, calendar-auto-schedule, calendar-proxy
[DEVELOPER 00:00:00] stderr: Header Name: [dav], Value: [extended-mkcol, bind, addressbook, calendar-auto-schedule, calendar-proxy]
[DEVELOPER 00:00:00] stderr: [hdr] ETag: "c2cc374e8bc74b87fffa12e9bab0b0b1"
[DEVELOPER 00:00:00] stderr: Header Name: [etag], Value: ["c2cc374e8bc74b87fffa12e9bab0b0b1"]
[DEVELOPER 00:00:00] stderr: [hdr] X-DAViCal-Version: DAViCal/1.1.4; DB/1.2.12
[DEVELOPER 00:00:00] stderr: Header Name: [x-davical-version], Value: [DAViCal/1.1.4; DB/1.2.12]
[DEVELOPER 00:00:00] stderr: [hdr] Content-type: text/xml; charset="utf-8"
[DEVELOPER 00:00:00] stderr: Header Name: [content-type], Value: [text/xml; charset="utf-8"]
[DEVELOPER 00:00:00] stderr: [hdr] Content-Length: 5135
[DEVELOPER 00:00:00] stderr: Header Name: [content-length], Value: [5135]
[DEVELOPER 00:00:00] stderr: [hdr] Date: Fri, 05 Jan 2018 17:44:16 GMT
[DEVELOPER 00:00:00] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 17:44:16 GMT]
[DEVELOPER 00:00:00] stderr: [hdr]
[DEVELOPER 00:00:00] stderr: End of headers.
[DEVELOPER 00:00:00] stderr: Running post_headers hooks
[DEVELOPER 00:00:00] stderr: Reading 5135 bytes of response body.
[DEVELOPER 00:00:00] stderr: Got 3721 bytes.
[DEVELOPER 00:00:00] stderr: Read block (3721 bytes):
[DEVELOPER 00:00:00] stderr: [<?xml version="1.0" encoding="utf-8" ?>
[DEVELOPER 00:00:00] stderr: <multistatus xmlns="DAV:" xmlns:C="urn:ietf:params:xml:ns:carddav">
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <resourcetype>
[DEVELOPER 00:00:00] stderr:      <collection/>
[DEVELOPER 00:00:00] stderr:      <C:addressbook/>
[DEVELOPER 00:00:00] stderr:     </resourcetype>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 404 Not Found</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user9.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"05ab3690b40c42d3948fade68320f6b7"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user2.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"4c021f166a2c820ad96d32fc52e3a969"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user8.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"ece6b25628265860462f74980435c423"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user7.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"efaaee8909944eae7cf0bb5bd268ee3e"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/6f354d698b7ccd22.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"a4373aa6fd432a3470ab9cef59fb6dff"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user5.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"06fbbc7184bb50fe9cb5d33dd6d51b63"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user4.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"5fcdba427933d480b92882a9a4915e9f"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user6.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"a130b8e6361293ae25680806648deff9"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user3.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"d6248831c7c048d3b8b31d31a967a01c"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user10.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"cc4abb1d4747c8ab3b64cc8295f9b3c9"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user17.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"dd43ca0772f4d4fbf794fb955c3bea85"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user16.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"a7312185d375216cc71e0888ce9c8063"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status]
[DEBUG 00:00:00] item unique-id-user9.vcf = rev 05ab3690b40c42d3948fade68320f6b7
[DEBUG 00:00:00] item unique-id-user2.vcf = rev 4c021f166a2c820ad96d32fc52e3a969
[DEBUG 00:00:00] item unique-id-user8.vcf = rev ece6b25628265860462f74980435c423
[DEBUG 00:00:00] item unique-id-user7.vcf = rev efaaee8909944eae7cf0bb5bd268ee3e
[DEBUG 00:00:00] item 6f354d698b7ccd22.vcf = rev a4373aa6fd432a3470ab9cef59fb6dff
[DEBUG 00:00:00] item unique-id-user5.vcf = rev 06fbbc7184bb50fe9cb5d33dd6d51b63
[DEBUG 00:00:00] item unique-id-user4.vcf = rev 5fcdba427933d480b92882a9a4915e9f
[DEBUG 00:00:00] item unique-id-user6.vcf = rev a130b8e6361293ae25680806648deff9
[DEBUG 00:00:00] item unique-id-user3.vcf = rev d6248831c7c048d3b8b31d31a967a01c
[DEBUG 00:00:00] item unique-id-user10.vcf = rev cc4abb1d4747c8ab3b64cc8295f9b3c9
[DEBUG 00:00:00] item unique-id-user17.vcf = rev dd43ca0772f4d4fbf794fb955c3bea85
[DEVELOPER 00:00:00] stderr: Reading 1414 bytes of response body.
[DEVELOPER 00:00:00] stderr: Got 1414 bytes.
[DEVELOPER 00:00:00] stderr: Read block (1414 bytes):
[DEVELOPER 00:00:00] stderr: [>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user11.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"d2eb96a908b627e3ea333e394faa62a6"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user12.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"54484dc0945f5ddfd58580e8868efe12"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user13.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"2618a7f0d36fd1fed4b214520c63a6e1"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user14.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"d378246d6cc8961ac6613c42346040e1"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/unique-id-user15.vcf</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <getetag>"7afc8d3a93210da08ae83f560136898f"</getetag>
[DEVELOPER 00:00:00] stderr:     <resourcetype/>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr: </multistatus>
[DEVELOPER 00:00:00] stderr: ]
[DEBUG 00:00:00] item unique-id-user16.vcf = rev a7312185d375216cc71e0888ce9c8063
[DEBUG 00:00:00] item unique-id-user11.vcf = rev d2eb96a908b627e3ea333e394faa62a6
[DEBUG 00:00:00] item unique-id-user12.vcf = rev 54484dc0945f5ddfd58580e8868efe12
[DEBUG 00:00:00] item unique-id-user13.vcf = rev 2618a7f0d36fd1fed4b214520c63a6e1
[DEBUG 00:00:00] item unique-id-user14.vcf = rev d378246d6cc8961ac6613c42346040e1
[DEBUG 00:00:00] item unique-id-user15.vcf = rev 7afc8d3a93210da08ae83f560136898f
[DEVELOPER 00:00:00] stderr: Running post_send hooks
[DEVELOPER 00:00:00] stderr: ah_post_send (#1), code is 207 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:00:00] stderr: Request ends, status 207 class 2xx, error line:
[DEVELOPER 00:00:00] stderr: 207 Multi-status
[DEBUG 00:00:00] credentials accepted
[DEVELOPER 00:00:00] stderr: Running destroy hooks.
[DEVELOPER 00:00:00] stderr: Request ends.
[DEBUG 00:00:00] ClientTest.cpp:439: starting get()->enableServerMode()
[DEBUG 00:00:00] ClientTest.cpp:439: ending get()->enableServerMode()
[DEBUG 00:00:00] ClientTest.cpp:1164: ending source.reset(createSourceA())
[DEBUG 00:00:00] ClientTest.cpp:1164: starting CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:00] ClientTest.cpp:1164: ending CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:00] starting GET, credentials okay, deadline in 120.0s
[DEVELOPER 00:00:00] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:00:00] stderr: Running pre_send hooks
[DEVELOPER 00:00:00] stderr: auth: Sending 'Basic' response.
[DEVELOPER 00:00:00] stderr: Sending request headers:
[DEVELOPER 00:00:00] stderr: GET /davical/caldav.php/tester2/Test_davical_carddav_1/no-such-item HTTP/1.1
[DEVELOPER 00:00:00] stderr: Connection: TE
[DEVELOPER 00:00:00] stderr: TE: trailers
[DEVELOPER 00:00:00] stderr: Host: localhost:9009
[DEVELOPER 00:00:00] stderr: Content-Length: 0
[DEVELOPER 00:00:00] stderr: Accept: text/vcard; charset=utf-8
[DEVELOPER 00:00:00] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:00:00] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:00] stderr:
[DEVELOPER 00:00:00] stderr: Sending request-line and headers:
[DEVELOPER 00:00:00] stderr: Request sent; retry is 1.
[DEVELOPER 00:00:00] stderr: [status-line] < HTTP/1.1 404 Not Found
[DEVELOPER 00:00:00] stderr: [hdr] Server: 1.1
[DEVELOPER 00:00:00] stderr: Header Name: [server], Value: [1.1]
[DEVELOPER 00:00:00] stderr: [hdr] DAV: 1, 2, 3, access-control, calendar-access, calendar-schedule
[DEVELOPER 00:00:00] stderr: Header Name: [dav], Value: [1, 2, 3, access-control, calendar-access, calendar-schedule]
[DEVELOPER 00:00:00] stderr: [hdr] DAV: extended-mkcol, bind, addressbook, calendar-auto-schedule, calendar-proxy
[DEVELOPER 00:00:00] stderr: Header Name: [dav], Value: [extended-mkcol, bind, addressbook, calendar-auto-schedule, calendar-proxy]
[DEVELOPER 00:00:00] stderr: [hdr] X-DAViCal-Version: DAViCal/1.1.4; DB/1.2.12
[DEVELOPER 00:00:00] stderr: Header Name: [x-davical-version], Value: [DAViCal/1.1.4; DB/1.2.12]
[DEVELOPER 00:00:00] stderr: [hdr] Content-type: text/plain; charset="utf-8"
[DEVELOPER 00:00:00] stderr: Header Name: [content-type], Value: [text/plain; charset="utf-8"]
[DEVELOPER 00:00:00] stderr: [hdr] Content-Length: 19
[DEVELOPER 00:00:00] stderr: Header Name: [content-length], Value: [19]
[DEVELOPER 00:00:00] stderr: [hdr] Date: Fri, 05 Jan 2018 17:44:16 GMT
[DEVELOPER 00:00:00] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 17:44:16 GMT]
[DEVELOPER 00:00:00] stderr: [hdr]
[DEVELOPER 00:00:00] stderr: End of headers.
[DEVELOPER 00:00:00] stderr: Running post_headers hooks
[DEVELOPER 00:00:00] stderr: Reading 19 bytes of response body.
[DEVELOPER 00:00:00] stderr: Got 19 bytes.
[DEVELOPER 00:00:00] stderr: Read block (19 bytes):
[DEVELOPER 00:00:00] stderr: [Resource Not Found.]
[DEVELOPER 00:00:00] stderr: Running post_send hooks
[DEVELOPER 00:00:00] stderr: ah_post_send (#0), code is 404 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:00:00] stderr: Request ends, status 404 class 4xx, error line:
[DEVELOPER 00:00:00] stderr: 404 Not Found
[DEBUG 00:00:00] GET: bad HTTP status: <status 1.1, code 404, class 4, Not Found>, must not retry
[DEVELOPER 00:00:00] stderr: Running destroy hooks.
[DEVELOPER 00:00:00] stderr: Request ends.
[DEBUG 00:00:00] ClientTest.cpp:1172: starting CPPUNIT_ASSERT_EQUAL((STATUS_NOT_FOUND),(status))
[DEBUG 00:00:00] ClientTest.cpp:1172: ending CPPUNIT_ASSERT_EQUAL((STATUS_NOT_FOUND),(status))
[DEBUG 00:00:00] ClientTest.cpp:391: starting reset(__null)
[DEBUG 00:00:00] ClientTest.cpp:454: starting CPPUNIT_ASSERT(get())
[DEBUG 00:00:00] ClientTest.cpp:454: ending CPPUNIT_ASSERT(get())
[DEBUG 00:00:00] ClientTest.cpp:455: starting CPPUNIT_ASSERT(m_active)
[DEBUG 00:00:00] ClientTest.cpp:455: ending CPPUNIT_ASSERT(m_active)
[DEBUG 00:00:00] ClientTest.cpp:458: starting get()->getOperations().m_endDataWrite.getPostSignal()(*get(), OPERATION_FINISHED, sysync::LOCERR_OK, true, &dummy)
[DEBUG 00:00:00] ClientTest.cpp:458: ending get()->getOperations().m_endDataWrite.getPostSignal()(*get(), OPERATION_FINISHED, sysync::LOCERR_OK, true, &dummy)
[DEBUG 00:00:00] ClientTest.cpp:461: starting anchor = get()->endSync(true)
[DEBUG 00:00:00] read ctag of /davical/caldav.php/tester2/Test_davical_carddav_1/
[DEBUG 00:00:00] starting PROPFIND, credentials okay, deadline in 120.0s
[DEVELOPER 00:00:00] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:00:00] stderr: Running pre_send hooks
[DEVELOPER 00:00:00] stderr: auth: Sending 'Basic' response.
[DEVELOPER 00:00:00] stderr: Sending request headers:
[DEVELOPER 00:00:00] stderr: PROPFIND /davical/caldav.php/tester2/Test_davical_carddav_1/ HTTP/1.1
[DEVELOPER 00:00:00] stderr: Connection: TE
[DEVELOPER 00:00:00] stderr: TE: trailers
[DEVELOPER 00:00:00] stderr: Host: localhost:9009
[DEVELOPER 00:00:00] stderr: Depth: 0
[DEVELOPER 00:00:00] stderr: Content-Length: 137
[DEVELOPER 00:00:00] stderr: Content-Type: application/xml
[DEVELOPER 00:00:00] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:00:00] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:00] stderr:
[DEVELOPER 00:00:00] stderr: Sending request-line and headers:
[DEVELOPER 00:00:00] stderr: Sending request body:
[DEVELOPER 00:00:00] stderr: Body block (137 bytes):
[DEVELOPER 00:00:00] stderr: [<?xml version="1.0" encoding="utf-8"?>
[DEVELOPER 00:00:00] stderr: <propfind xmlns="DAV:"><prop>
[DEVELOPER 00:00:00] stderr: <getctag xmlns="http://calendarserver.org/ns/"/>
[DEVELOPER 00:00:00] stderr: </prop></propfind>
[DEVELOPER 00:00:00] stderr: ]
[DEVELOPER 00:00:00] stderr: Request sent; retry is 1.
[DEVELOPER 00:00:00] stderr: [status-line] < HTTP/1.1 207 Multi-status
[DEVELOPER 00:00:00] stderr: [hdr] Server: 1.1
[DEVELOPER 00:00:00] stderr: Header Name: [server], Value: [1.1]
[DEVELOPER 00:00:00] stderr: [hdr] DAV: 1, 2, 3, access-control, calendar-access, calendar-schedule
[DEVELOPER 00:00:00] stderr: Header Name: [dav], Value: [1, 2, 3, access-control, calendar-access, calendar-schedule]
[DEVELOPER 00:00:00] stderr: [hdr] DAV: extended-mkcol, bind, addressbook, calendar-auto-schedule, calendar-proxy
[DEVELOPER 00:00:00] stderr: Header Name: [dav], Value: [extended-mkcol, bind, addressbook, calendar-auto-schedule, calendar-proxy]
[DEVELOPER 00:00:00] stderr: [hdr] ETag: "a63844406a416ab23b9b75977e2680ed"
[DEVELOPER 00:00:00] stderr: Header Name: [etag], Value: ["a63844406a416ab23b9b75977e2680ed"]
[DEVELOPER 00:00:00] stderr: [hdr] X-DAViCal-Version: DAViCal/1.1.4; DB/1.2.12
[DEVELOPER 00:00:00] stderr: Header Name: [x-davical-version], Value: [DAViCal/1.1.4; DB/1.2.12]
[DEVELOPER 00:00:00] stderr: [hdr] Content-type: text/xml; charset="utf-8"
[DEVELOPER 00:00:00] stderr: Header Name: [content-type], Value: [text/xml; charset="utf-8"]
[DEVELOPER 00:00:00] stderr: [hdr] Content-Length: 360
[DEVELOPER 00:00:00] stderr: Header Name: [content-length], Value: [360]
[DEVELOPER 00:00:00] stderr: [hdr] Date: Fri, 05 Jan 2018 17:44:16 GMT
[DEVELOPER 00:00:00] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 17:44:16 GMT]
[DEVELOPER 00:00:00] stderr: [hdr]
[DEVELOPER 00:00:00] stderr: End of headers.
[DEVELOPER 00:00:00] stderr: Running post_headers hooks
[DEVELOPER 00:00:00] stderr: Reading 360 bytes of response body.
[DEVELOPER 00:00:00] stderr: Got 32 bytes.
[DEVELOPER 00:00:00] stderr: Read block (32 bytes):
[DEVELOPER 00:00:00] stderr: [<?xml version="1.0" encoding="ut]
[DEVELOPER 00:00:00] stderr: Reading 328 bytes of response body.
[DEVELOPER 00:00:00] stderr: Got 328 bytes.
[DEVELOPER 00:00:00] stderr: Read block (328 bytes):
[DEVELOPER 00:00:00] stderr: [f-8" ?>
[DEVELOPER 00:00:00] stderr: <multistatus xmlns="DAV:" xmlns:C="http://calendarserver.org/ns/">
[DEVELOPER 00:00:00] stderr:  <response>
[DEVELOPER 00:00:00] stderr:   <href>/davical/caldav.php/tester2/Test_davical_carddav_1/</href>
[DEVELOPER 00:00:00] stderr:   <propstat>
[DEVELOPER 00:00:00] stderr:    <prop>
[DEVELOPER 00:00:00] stderr:     <C:getctag>"df36ff0f94eb85368b0cf64278b68191"</C:getctag>
[DEVELOPER 00:00:00] stderr:    </prop>
[DEVELOPER 00:00:00] stderr:    <status>HTTP/1.1 200 OK</status>
[DEVELOPER 00:00:00] stderr:   </propstat>
[DEVELOPER 00:00:00] stderr:  </response>
[DEVELOPER 00:00:00] stderr: </multistatus>
[DEVELOPER 00:00:00] stderr: ]
[DEVELOPER 00:00:00] stderr: Running post_send hooks
[DEVELOPER 00:00:00] stderr: ah_post_send (#0), code is 207 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:00:00] stderr: Request ends, status 207 class 2xx, error line:
[DEVELOPER 00:00:00] stderr: 207 Multi-status
[DEVELOPER 00:00:00] stderr: Running destroy hooks.
[DEVELOPER 00:00:00] stderr: Request ends.
[DEBUG 00:00:00] ClientTest.cpp:461: ending anchor = get()->endSync(true)
[DEBUG 00:00:00] ClientTest.cpp:463: starting get()->close()
[DEBUG 00:00:00] ClientTest.cpp:463: ending get()->close()
[DEBUG 00:00:00] ClientTest.cpp:391: ending reset(__null)
[DEBUG 00:00:00] *** ending Client::Source::davical_carddav::testReadItem404: okay ***