[DEBUG 00:00:00] *** starting Client::Sync::eds_contact::testDownload ***
[DEBUG 00:00:00] ClientTest.cpp:6157: starting CPPUNIT_ASSERT_EQUAL((sources.size()),(1))
[DEBUG 00:00:00] ClientTest.cpp:6157: ending CPPUNIT_ASSERT_EQUAL((sources.size()),(1))
[DEBUG 00:00:00] ClientTest.cpp:6162: starting CPPUNIT_ASSERT(!peer.empty())
[DEBUG 00:00:00] ClientTest.cpp:6162: ending CPPUNIT_ASSERT(!peer.empty())
[DEBUG 00:00:00] ClientTest.cpp:6163: starting CPPUNIT_ASSERT(!peerSource.empty())
[DEBUG 00:00:00] ClientTest.cpp:6163: ending CPPUNIT_ASSERT(!peerSource.empty())
[DEBUG 00:00:00] ClientTest.cpp:6166: starting CPPUNIT_ASSERT_MESSAGE(localTestdata, (!access(localTestdata.c_str(), 4)))
[DEBUG 00:00:00] ClientTest.cpp:6166: ending CPPUNIT_ASSERT_MESSAGE(localTestdata, (!access(localTestdata.c_str(), 4)))
[DEBUG 00:00:00] ClientTest.cpp:6168: starting CPPUNIT_ASSERT_MESSAGE(remoteTestdata, (!access(remoteTestdata.c_str(), 4)))
[DEBUG 00:00:00] ClientTest.cpp:6168: ending CPPUNIT_ASSERT_MESSAGE(remoteTestdata, (!access(remoteTestdata.c_str(), 4)))
[DEBUG 00:00:00] ClientTest.cpp:6170: starting CPPUNIT_ASSERT_MESSAGE(modifyLocal, (!access(modifyLocal.c_str(), 4|1)))
[DEBUG 00:00:00] ClientTest.cpp:6170: ending CPPUNIT_ASSERT_MESSAGE(modifyLocal, (!access(modifyLocal.c_str(), 4|1)))
[DEBUG 00:00:00] ClientTest.cpp:6172: starting CPPUNIT_ASSERT_MESSAGE(remoteSyncedTestdata, (!access(remoteSyncedTestdata.c_str(), 4)))
[DEBUG 00:00:00] ClientTest.cpp:6172: ending CPPUNIT_ASSERT_MESSAGE(remoteSyncedTestdata, (!access(remoteSyncedTestdata.c_str(), 4)))
[DEBUG 00:00:00] ClientTest.cpp:6183: starting CPPUNIT_ASSERT(cmdline->parse())
[DEBUG 00:00:00] ClientTest.cpp:6183: ending CPPUNIT_ASSERT(cmdline->parse())
[DEBUG 00:00:00] ClientTest.cpp:6184: starting CPPUNIT_ASSERT_MESSAGE(peer + " " + peerSource, (cmdline->run()))
[DEBUG 00:00:00] checking password property 'password' in config 'target-config@client-test-googlecontacts' with user identity 'goa:syncevolution.list@gmail.com'
[DEBUG 00:00:00] checking password property 'proxyPassword' in config 'target-config@client-test-googlecontacts' with user identity ''
[DEBUG 00:00:00] checking password property 'databasePassword' in datastore 'carddav' of config 'target-config@client-test-googlecontacts' with user identity ''
[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 https://www.googleapis.com:443 begins.
[DEBUG 00:00:00] client cert is missing
[INFO 00:00:00] carddav: using configured database=https://www.googleapis.com:443/carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/
[DEBUG 00:00:00] using username 'goa:syncevolution.list@gmail.com' from context for WebDAV, password not set
[DEBUG 00:00:00] looking for identity provider for goa:syncevolution.list@gmail.com
[DEBUG 00:00:01] Looking up all accounts in GNOME Online Accounts, searching for 'syncevolution.list@gmail.com'.
[DEBUG 00:00:01] GOA object /org/gnome/OnlineAccounts/Accounts/account_1379073187 implements org.gnome.OnlineAccounts.Account, org.gnome.OnlineAccounts.Calendar, org.gnome.OnlineAccounts.Chat, org.gnome.OnlineAccounts.Contacts, org.gnome.OnlineAccounts.Documents, org.gnome.OnlineAccounts.Mail, org.gnome.OnlineAccounts.OAuth2Based
[DEBUG 00:00:01] GOA account Google, syncevolution.list@gmail.com = account_1379073187
[DEBUG 00:00:01] found matching GNOME Online Account for 'syncevolution.list@gmail.com': Google, syncevolution.list@gmail.com = account_1379073187
[DEBUG 00:00:01] GOA object /org/gnome/OnlineAccounts/Manager implements org.gnome.OnlineAccounts.Manager
[DEBUG 00:00:01] carddav: slow sync or testing, do full item scan to detect changes
[DEBUG 00:00:01] starting PROPFIND, credentials unverified, deadline in 300.0s
[DEBUG 00:00:01] got new OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' for next request
[DEVELOPER 00:00:01] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:00:01] stderr: Running pre_send hooks
[DEBUG 00:00:01] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:00:01] stderr: Sending request headers:
[DEVELOPER 00:00:01] stderr: PROPFIND /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/ HTTP/1.1
[DEVELOPER 00:00:01] stderr: Connection: TE
[DEVELOPER 00:00:01] stderr: TE: trailers
[DEVELOPER 00:00:01] stderr: Host: www.googleapis.com
[DEVELOPER 00:00:01] stderr: Depth: 1
[DEVELOPER 00:00:01] stderr: Content-Length: 141
[DEVELOPER 00:00:01] stderr: Content-Type: application/xml
[DEVELOPER 00:00:01] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:01] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:00:01] stderr:
[DEVELOPER 00:00:01] stderr: Sending request-line and headers:
[DEVELOPER 00:00:01] stderr: Doing DNS lookup on proxy.vlan13.01.org...
[DEVELOPER 00:00:01] stderr: req: Connecting to 10.0.13.1:3128
[DEVELOPER 00:00:01] stderr: Running pre_send hooks
[DEBUG 00:00:01] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:00:02] stderr: Sending request headers:
[DEVELOPER 00:00:02] stderr: CONNECT www.googleapis.com:443 HTTP/1.1
[DEVELOPER 00:00:02] stderr: Connection: TE
[DEVELOPER 00:00:02] stderr: TE: trailers
[DEVELOPER 00:00:02] stderr: Host: www.googleapis.com
[DEVELOPER 00:00:02] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:02] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:00:02] stderr:
[DEVELOPER 00:00:02] stderr: Sending request-line and headers:
[DEVELOPER 00:00:02] stderr: Request sent; retry is 0.
[DEVELOPER 00:00:02] stderr: [status-line] < HTTP/1.1 200 Connection established
[DEVELOPER 00:00:02] stderr: [hdr]
[DEVELOPER 00:00:02] stderr: End of headers.
[DEVELOPER 00:00:02] stderr: Running post_headers hooks
[DEVELOPER 00:00:02] stderr: Running post_send hooks
[DEVELOPER 00:00:02] stderr: Request ends, status 200 class 2xx, error line:
[DEVELOPER 00:00:02] stderr: 200 Connection established
[DEVELOPER 00:00:02] stderr: Running destroy hooks.
[DEVELOPER 00:00:02] stderr: Request ends.
[DEVELOPER 00:00:02] stderr: Sending request body:
[DEVELOPER 00:00:02] stderr: Body block (141 bytes):
[DEVELOPER 00:00:02] stderr: [<?xml version="1.0" encoding="utf-8"?>
[DEVELOPER 00:00:02] stderr: <propfind xmlns="DAV:"><prop>
[DEVELOPER 00:00:02] stderr: <getetag xmlns="DAV:"/>
[DEVELOPER 00:00:02] stderr: <resourcetype xmlns="DAV:"/>
[DEVELOPER 00:00:02] stderr: </prop></propfind>
[DEVELOPER 00:00:02] stderr: ]
[DEVELOPER 00:00:02] stderr: Request sent; retry is 0.
[DEVELOPER 00:00:02] stderr: [status-line] < HTTP/1.1 207 Multi-Status
[DEVELOPER 00:00:02] stderr: [hdr] DAV: 1, 2, 3, addressbook, access-control
[DEVELOPER 00:00:02] stderr: Header Name: [dav], Value: [1, 2, 3, addressbook, access-control]
[DEVELOPER 00:00:02] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:00:02] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:00:02] stderr: [hdr] Content-Type: text/xml; charset=UTF-8
[DEVELOPER 00:00:02] stderr: Header Name: [content-type], Value: [text/xml; charset=UTF-8]
[DEVELOPER 00:00:02] stderr: [hdr] Date: Fri, 05 Jan 2018 16:47:31 GMT
[DEVELOPER 00:00:02] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:47:31 GMT]
[DEVELOPER 00:00:02] stderr: [hdr] Expires: Fri, 05 Jan 2018 16:47:31 GMT
[DEVELOPER 00:00:02] stderr: Header Name: [expires], Value: [Fri, 05 Jan 2018 16:47:31 GMT]
[DEVELOPER 00:00:02] stderr: [hdr] Cache-Control: private, max-age=0
[DEVELOPER 00:00:02] stderr: Header Name: [cache-control], Value: [private, max-age=0]
[DEVELOPER 00:00:02] stderr: [hdr] X-Content-Type-Options: nosniff
[DEVELOPER 00:00:02] stderr: Header Name: [x-content-type-options], Value: [nosniff]
[DEVELOPER 00:00:02] stderr: [hdr] X-Frame-Options: SAMEORIGIN
[DEVELOPER 00:00:02] stderr: Header Name: [x-frame-options], Value: [SAMEORIGIN]
[DEVELOPER 00:00:02] stderr: [hdr] X-XSS-Protection: 1; mode=block
[DEVELOPER 00:00:02] stderr: Header Name: [x-xss-protection], Value: [1; mode=block]
[DEVELOPER 00:00:02] stderr: [hdr] Server: GSE
[DEVELOPER 00:00:02] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:00:02] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:00:02] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:00:02] stderr: [hdr] Accept-Ranges: none
[DEVELOPER 00:00:02] stderr: Header Name: [accept-ranges], Value: [none]
[DEVELOPER 00:00:02] stderr: [hdr] Vary: Origin,Accept-Encoding
[DEVELOPER 00:00:02] stderr: Header Name: [vary], Value: [Origin,Accept-Encoding]
[DEVELOPER 00:00:02] stderr: [hdr] Transfer-Encoding: chunked
[DEVELOPER 00:00:02] stderr: Header Name: [transfer-encoding], Value: [chunked]
[DEVELOPER 00:00:02] stderr: [hdr]
[DEVELOPER 00:00:02] stderr: End of headers.
[DEVELOPER 00:00:02] stderr: Running post_headers hooks
[DEVELOPER 00:00:02] stderr: [chunk] < 15e0
[DEVELOPER 00:00:02] stderr: Got chunk size: 5600
[DEVELOPER 00:00:02] stderr: Reading 5600 bytes of response body.
[DEVELOPER 00:00:02] stderr: Got 604 bytes.
[DEVELOPER 00:00:02] stderr: Read block (604 bytes):
[DEVELOPER 00:00:02] stderr: [<?xml version="1.0" encoding="UTF-8"?>
[DEVELOPER 00:00:02] stderr: <d:multistatus xmlns:cal="urn:ietf:params:xml:ns:caldav" xmlns:card="urn:ietf:params:xml:ns:carddav" xmlns:cs="http://calendarserver.org/ns/" xmlns:d="DAV:" xmlns:ical="http://apple.com/ns/ical/">
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype>
[DEVELOPER 00:00:02] stderr:      <d:collection/>
[DEVELOPER 00:00:02] stderr:      <card:addressbook/>
[DEVELOPER 00:00:02] stderr:     </d:resourcetype>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 404 Not Found</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:ge]
[DEVELOPER 00:00:02] stderr: Reading 4996 bytes of response body.
[DEVELOPER 00:00:02] stderr: Got 1187 bytes.
[DEVELOPER 00:00:02] stderr: Read block (1187 bytes):
[DEVELOPER 00:00:02] stderr: [tetag/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/4378b3f8b22ad6b</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:44:10.058-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/10754d30085d14f7</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:46:17.113-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/1c71018c8bd69cc4</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:45:02.471-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/26481c8188ac137b</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08]
[DEBUG 00:00:02] item 4378b3f8b22ad6b = rev 2018-01-05T08:44:10.058-08:00
[DEBUG 00:00:02] item 10754d30085d14f7 = rev 2018-01-05T08:46:17.113-08:00
[DEBUG 00:00:02] item 1c71018c8bd69cc4 = rev 2018-01-05T08:45:02.471-08:00
[DEVELOPER 00:00:02] stderr: Reading 3809 bytes of response body.
[DEVELOPER 00:00:02] stderr: Got 1187 bytes.
[DEVELOPER 00:00:02] stderr: Read block (1187 bytes):
[DEVELOPER 00:00:02] stderr: [:44:20.563-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/29aeaa900ba7666a</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:44:41.540-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/3c26379f88ef2824</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:45:55.689-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/4296442f88a61b98</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:46:38.621-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/4faf3db70c866603</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:stat]
[DEBUG 00:00:02] item 26481c8188ac137b = rev 2018-01-05T08:44:20.563-08:00
[DEBUG 00:00:02] item 29aeaa900ba7666a = rev 2018-01-05T08:44:41.540-08:00
[DEBUG 00:00:02] item 3c26379f88ef2824 = rev 2018-01-05T08:45:55.689-08:00
[DEBUG 00:00:02] item 4296442f88a61b98 = rev 2018-01-05T08:46:38.621-08:00
[DEVELOPER 00:00:02] stderr: Reading 2622 bytes of response body.
[DEVELOPER 00:00:02] stderr: Got 1187 bytes.
[DEVELOPER 00:00:02] stderr: Read block (1187 bytes):
[DEVELOPER 00:00:02] stderr: [us>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:45:44.697-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/51c1b14e8df7be34</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:44:31.094-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/52e2bbb888e90e26</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:46:06.151-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/5c4d669a8813dff3</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:43:59.512-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/5d7cbeb30e59bc30</d:href>
[DEVELOPER 00:00:02] stderr:   <d:p]
[DEBUG 00:00:02] item 4faf3db70c866603 = rev 2018-01-05T08:45:44.697-08:00
[DEBUG 00:00:02] item 51c1b14e8df7be34 = rev 2018-01-05T08:44:31.094-08:00
[DEBUG 00:00:02] item 52e2bbb888e90e26 = rev 2018-01-05T08:46:06.151-08:00
[DEBUG 00:00:02] item 5c4d669a8813dff3 = rev 2018-01-05T08:43:59.512-08:00
[DEVELOPER 00:00:02] stderr: Reading 1435 bytes of response body.
[DEVELOPER 00:00:02] stderr: Got 1187 bytes.
[DEVELOPER 00:00:02] stderr: Read block (1187 bytes):
[DEVELOPER 00:00:02] stderr: [ropstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:44:51.995-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/5e31096508bc4a44</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:45:12.941-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/5fa30f530d0ba0ad</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:45:23.359-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/6d68259e0ee5552f</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:45:34.232-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr:  <d:response>
[DEVELOPER 00:00:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/l]
[DEBUG 00:00:02] item 5d7cbeb30e59bc30 = rev 2018-01-05T08:44:51.995-08:00
[DEBUG 00:00:02] item 5e31096508bc4a44 = rev 2018-01-05T08:45:12.941-08:00
[DEBUG 00:00:02] item 5fa30f530d0ba0ad = rev 2018-01-05T08:45:23.359-08:00
[DEBUG 00:00:02] item 6d68259e0ee5552f = rev 2018-01-05T08:45:34.232-08:00
[DEVELOPER 00:00:02] stderr: Reading 248 bytes of response body.
[DEVELOPER 00:00:02] stderr: Got 248 bytes.
[DEVELOPER 00:00:02] stderr: Read block (248 bytes):
[DEVELOPER 00:00:02] stderr: [ists/default/727bb5298c07d8c5</d:href>
[DEVELOPER 00:00:02] stderr:   <d:propstat>
[DEVELOPER 00:00:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:00:02] stderr:    <d:prop>
[DEVELOPER 00:00:02] stderr:     <d:getetag>"2018-01-05T08:46:27.625-08:00"</d:getetag>
[DEVELOPER 00:00:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:00:02] stderr:    </d:prop>
[DEVELOPER 00:00:02] stderr:   </d:propstat>
[DEVELOPER 00:00:02] stderr:  </d:response>
[DEVELOPER 00:00:02] stderr: </d:multistatus>
[DEVELOPER 00:00:02] stderr: ]
[DEBUG 00:00:02] item 727bb5298c07d8c5 = rev 2018-01-05T08:46:27.625-08:00
[DEVELOPER 00:00:02] stderr: [chunk] < 0
[DEVELOPER 00:00:02] stderr: Got chunk size: 0
[DEVELOPER 00:00:02] stderr: [hdr]
[DEVELOPER 00:00:02] stderr: End of headers.
[DEVELOPER 00:00:02] stderr: Running post_send hooks
[DEVELOPER 00:00:02] stderr: ah_post_send (#0), code is 207 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:00:02] stderr: Request ends, status 207 class 2xx, error line:
[DEVELOPER 00:00:02] stderr: 207 Multi-Status
[DEBUG 00:00:02] credentials accepted
[DEVELOPER 00:00:02] stderr: Running destroy hooks.
[DEVELOPER 00:00:02] stderr: Request ends.
[INFO 00:00:02] carddav: deleting <10754d30085d14f7>
[DEBUG 00:00:02] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:00:02] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:00:02] stderr: Running pre_send hooks
[DEBUG 00:00:02] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:00:12] stderr: Sending request headers:
[DEVELOPER 00:00:12] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/10754d30085d14f7 HTTP/1.1
[DEVELOPER 00:00:12] stderr: Connection: TE
[DEVELOPER 00:00:12] stderr: TE: trailers
[DEVELOPER 00:00:12] stderr: Host: www.googleapis.com
[DEVELOPER 00:00:12] stderr: Content-Length: 0
[DEVELOPER 00:00:12] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:12] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:00:12] stderr:
[DEVELOPER 00:00:12] stderr: Sending request-line and headers:
[DEVELOPER 00:00:12] stderr: Request sent; retry is 1.
[DEVELOPER 00:00:12] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:00:12] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:00:12] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:00:12] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:00:12] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:00:12] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:00:12] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:00:12] stderr: [hdr] Date: Fri, 05 Jan 2018 16:47:42 GMT
[DEVELOPER 00:00:12] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:47:42 GMT]
[DEVELOPER 00:00:12] stderr: [hdr] Vary: Origin
[DEVELOPER 00:00:12] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:00:12] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:00:12] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:00:12] stderr: [hdr] Server: GSE
[DEVELOPER 00:00:12] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:00:12] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:00:12] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:00:12] stderr: [hdr]
[DEVELOPER 00:00:12] stderr: End of headers.
[DEVELOPER 00:00:12] stderr: Running post_headers hooks
[DEVELOPER 00:00:12] stderr: Running post_send hooks
[DEVELOPER 00:00:12] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:00:12] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:00:12] stderr: 204 No Content
[DEBUG 00:00:12] credentials accepted
[DEBUG 00:00:12] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:00:12] stderr: Running destroy hooks.
[DEVELOPER 00:00:12] stderr: Request ends.
[INFO 00:00:12] carddav: deleting <1c71018c8bd69cc4>
[DEBUG 00:00:12] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:00:12] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:00:12] stderr: Running pre_send hooks
[DEBUG 00:00:12] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:00:23] stderr: Sending request headers:
[DEVELOPER 00:00:23] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/1c71018c8bd69cc4 HTTP/1.1
[DEVELOPER 00:00:23] stderr: Connection: TE
[DEVELOPER 00:00:23] stderr: TE: trailers
[DEVELOPER 00:00:23] stderr: Host: www.googleapis.com
[DEVELOPER 00:00:23] stderr: Content-Length: 0
[DEVELOPER 00:00:23] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:23] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:00:23] stderr:
[DEVELOPER 00:00:23] stderr: Sending request-line and headers:
[DEVELOPER 00:00:23] stderr: Request sent; retry is 1.
[DEVELOPER 00:00:23] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:00:23] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:00:23] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:00:23] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:00:23] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:00:23] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:00:23] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:00:23] stderr: [hdr] Date: Fri, 05 Jan 2018 16:47:52 GMT
[DEVELOPER 00:00:23] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:47:52 GMT]
[DEVELOPER 00:00:23] stderr: [hdr] Vary: Origin
[DEVELOPER 00:00:23] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:00:23] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:00:23] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:00:23] stderr: [hdr] Server: GSE
[DEVELOPER 00:00:23] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:00:23] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:00:23] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:00:23] stderr: [hdr]
[DEVELOPER 00:00:23] stderr: End of headers.
[DEVELOPER 00:00:23] stderr: Running post_headers hooks
[DEVELOPER 00:00:23] stderr: Running post_send hooks
[DEVELOPER 00:00:23] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:00:23] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:00:23] stderr: 204 No Content
[DEBUG 00:00:23] credentials accepted
[DEBUG 00:00:23] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:00:23] stderr: Running destroy hooks.
[DEVELOPER 00:00:23] stderr: Request ends.
[INFO 00:00:23] carddav: deleting <26481c8188ac137b>
[DEBUG 00:00:23] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:00:23] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:00:23] stderr: Running pre_send hooks
[DEBUG 00:00:23] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:00:33] stderr: Sending request headers:
[DEVELOPER 00:00:33] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/26481c8188ac137b HTTP/1.1
[DEVELOPER 00:00:33] stderr: Connection: TE
[DEVELOPER 00:00:33] stderr: TE: trailers
[DEVELOPER 00:00:33] stderr: Host: www.googleapis.com
[DEVELOPER 00:00:33] stderr: Content-Length: 0
[DEVELOPER 00:00:33] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:33] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:00:33] stderr:
[DEVELOPER 00:00:33] stderr: Sending request-line and headers:
[DEVELOPER 00:00:33] stderr: Request sent; retry is 1.
[DEVELOPER 00:00:33] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:00:33] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:00:33] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:00:33] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:00:33] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:00:33] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:00:33] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:00:33] stderr: [hdr] Date: Fri, 05 Jan 2018 16:48:03 GMT
[DEVELOPER 00:00:33] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:48:03 GMT]
[DEVELOPER 00:00:33] stderr: [hdr] Vary: Origin
[DEVELOPER 00:00:33] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:00:33] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:00:33] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:00:33] stderr: [hdr] Server: GSE
[DEVELOPER 00:00:33] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:00:33] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:00:33] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:00:33] stderr: [hdr]
[DEVELOPER 00:00:33] stderr: End of headers.
[DEVELOPER 00:00:33] stderr: Running post_headers hooks
[DEVELOPER 00:00:33] stderr: Running post_send hooks
[DEVELOPER 00:00:33] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:00:33] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:00:33] stderr: 204 No Content
[DEBUG 00:00:33] credentials accepted
[DEBUG 00:00:33] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:00:33] stderr: Running destroy hooks.
[DEVELOPER 00:00:33] stderr: Request ends.
[INFO 00:00:33] carddav: deleting <29aeaa900ba7666a>
[DEBUG 00:00:33] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:00:33] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:00:33] stderr: Running pre_send hooks
[DEBUG 00:00:33] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:00:43] stderr: Sending request headers:
[DEVELOPER 00:00:43] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/29aeaa900ba7666a HTTP/1.1
[DEVELOPER 00:00:43] stderr: Connection: TE
[DEVELOPER 00:00:43] stderr: TE: trailers
[DEVELOPER 00:00:43] stderr: Host: www.googleapis.com
[DEVELOPER 00:00:43] stderr: Content-Length: 0
[DEVELOPER 00:00:43] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:43] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:00:43] stderr:
[DEVELOPER 00:00:43] stderr: Sending request-line and headers:
[DEVELOPER 00:00:43] stderr: Request sent; retry is 1.
[DEVELOPER 00:00:43] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:00:43] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:00:43] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:00:43] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:00:43] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:00:43] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:00:43] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:00:43] stderr: [hdr] Date: Fri, 05 Jan 2018 16:48:13 GMT
[DEVELOPER 00:00:43] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:48:13 GMT]
[DEVELOPER 00:00:43] stderr: [hdr] Vary: Origin
[DEVELOPER 00:00:43] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:00:43] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:00:43] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:00:43] stderr: [hdr] Server: GSE
[DEVELOPER 00:00:43] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:00:43] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:00:43] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:00:43] stderr: [hdr]
[DEVELOPER 00:00:43] stderr: End of headers.
[DEVELOPER 00:00:43] stderr: Running post_headers hooks
[DEVELOPER 00:00:43] stderr: Running post_send hooks
[DEVELOPER 00:00:43] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:00:43] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:00:43] stderr: 204 No Content
[DEBUG 00:00:43] credentials accepted
[DEBUG 00:00:43] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:00:43] stderr: Running destroy hooks.
[DEVELOPER 00:00:43] stderr: Request ends.
[INFO 00:00:43] carddav: deleting <3c26379f88ef2824>
[DEBUG 00:00:43] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:00:43] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:00:43] stderr: Running pre_send hooks
[DEBUG 00:00:43] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:00:54] stderr: Sending request headers:
[DEVELOPER 00:00:54] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/3c26379f88ef2824 HTTP/1.1
[DEVELOPER 00:00:54] stderr: Connection: TE
[DEVELOPER 00:00:54] stderr: TE: trailers
[DEVELOPER 00:00:54] stderr: Host: www.googleapis.com
[DEVELOPER 00:00:54] stderr: Content-Length: 0
[DEVELOPER 00:00:54] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:00:54] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:00:54] stderr:
[DEVELOPER 00:00:54] stderr: Sending request-line and headers:
[DEVELOPER 00:00:54] stderr: Request sent; retry is 1.
[DEVELOPER 00:00:54] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:00:54] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:00:54] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:00:54] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:00:54] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:00:54] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:00:54] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:00:54] stderr: [hdr] Date: Fri, 05 Jan 2018 16:48:23 GMT
[DEVELOPER 00:00:54] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:48:23 GMT]
[DEVELOPER 00:00:54] stderr: [hdr] Vary: Origin
[DEVELOPER 00:00:54] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:00:54] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:00:54] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:00:54] stderr: [hdr] Server: GSE
[DEVELOPER 00:00:54] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:00:54] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:00:54] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:00:54] stderr: [hdr]
[DEVELOPER 00:00:54] stderr: End of headers.
[DEVELOPER 00:00:54] stderr: Running post_headers hooks
[DEVELOPER 00:00:54] stderr: Running post_send hooks
[DEVELOPER 00:00:54] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:00:54] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:00:54] stderr: 204 No Content
[DEBUG 00:00:54] credentials accepted
[DEBUG 00:00:54] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:00:54] stderr: Running destroy hooks.
[DEVELOPER 00:00:54] stderr: Request ends.
[INFO 00:00:54] carddav: deleting <4296442f88a61b98>
[DEBUG 00:00:54] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:00:54] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:00:54] stderr: Running pre_send hooks
[DEBUG 00:00:54] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:01:04] stderr: Sending request headers:
[DEVELOPER 00:01:04] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/4296442f88a61b98 HTTP/1.1
[DEVELOPER 00:01:04] stderr: Connection: TE
[DEVELOPER 00:01:04] stderr: TE: trailers
[DEVELOPER 00:01:04] stderr: Host: www.googleapis.com
[DEVELOPER 00:01:04] stderr: Content-Length: 0
[DEVELOPER 00:01:04] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:01:04] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:01:04] stderr:
[DEVELOPER 00:01:04] stderr: Sending request-line and headers:
[DEVELOPER 00:01:04] stderr: Request sent; retry is 1.
[DEVELOPER 00:01:04] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:01:04] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:01:04] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:01:04] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:01:04] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:01:04] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:01:04] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:01:04] stderr: [hdr] Date: Fri, 05 Jan 2018 16:48:34 GMT
[DEVELOPER 00:01:04] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:48:34 GMT]
[DEVELOPER 00:01:04] stderr: [hdr] Vary: Origin
[DEVELOPER 00:01:04] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:01:04] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:01:04] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:01:04] stderr: [hdr] Server: GSE
[DEVELOPER 00:01:04] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:01:04] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:01:04] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:01:04] stderr: [hdr]
[DEVELOPER 00:01:04] stderr: End of headers.
[DEVELOPER 00:01:04] stderr: Running post_headers hooks
[DEVELOPER 00:01:04] stderr: Running post_send hooks
[DEVELOPER 00:01:04] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:01:04] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:01:04] stderr: 204 No Content
[DEBUG 00:01:04] credentials accepted
[DEBUG 00:01:04] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:01:04] stderr: Running destroy hooks.
[DEVELOPER 00:01:04] stderr: Request ends.
[INFO 00:01:04] carddav: deleting <4378b3f8b22ad6b>
[DEBUG 00:01:04] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:01:04] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:01:04] stderr: Running pre_send hooks
[DEBUG 00:01:04] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:01:14] stderr: Sending request headers:
[DEVELOPER 00:01:14] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/4378b3f8b22ad6b HTTP/1.1
[DEVELOPER 00:01:14] stderr: Connection: TE
[DEVELOPER 00:01:14] stderr: TE: trailers
[DEVELOPER 00:01:14] stderr: Host: www.googleapis.com
[DEVELOPER 00:01:14] stderr: Content-Length: 0
[DEVELOPER 00:01:14] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:01:14] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:01:14] stderr:
[DEVELOPER 00:01:14] stderr: Sending request-line and headers:
[DEVELOPER 00:01:14] stderr: Request sent; retry is 1.
[DEVELOPER 00:01:14] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:01:14] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:01:14] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:01:14] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:01:14] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:01:14] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:01:14] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:01:14] stderr: [hdr] Date: Fri, 05 Jan 2018 16:48:44 GMT
[DEVELOPER 00:01:14] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:48:44 GMT]
[DEVELOPER 00:01:14] stderr: [hdr] Vary: Origin
[DEVELOPER 00:01:14] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:01:14] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:01:14] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:01:14] stderr: [hdr] Server: GSE
[DEVELOPER 00:01:14] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:01:14] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:01:14] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:01:14] stderr: [hdr]
[DEVELOPER 00:01:14] stderr: End of headers.
[DEVELOPER 00:01:14] stderr: Running post_headers hooks
[DEVELOPER 00:01:14] stderr: Running post_send hooks
[DEVELOPER 00:01:14] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:01:14] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:01:14] stderr: 204 No Content
[DEBUG 00:01:14] credentials accepted
[DEBUG 00:01:14] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:01:14] stderr: Running destroy hooks.
[DEVELOPER 00:01:14] stderr: Request ends.
[INFO 00:01:14] carddav: deleting <4faf3db70c866603>
[DEBUG 00:01:14] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:01:14] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:01:14] stderr: Running pre_send hooks
[DEBUG 00:01:14] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:01:25] stderr: Sending request headers:
[DEVELOPER 00:01:25] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/4faf3db70c866603 HTTP/1.1
[DEVELOPER 00:01:25] stderr: Connection: TE
[DEVELOPER 00:01:25] stderr: TE: trailers
[DEVELOPER 00:01:25] stderr: Host: www.googleapis.com
[DEVELOPER 00:01:25] stderr: Content-Length: 0
[DEVELOPER 00:01:25] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:01:25] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:01:25] stderr:
[DEVELOPER 00:01:25] stderr: Sending request-line and headers:
[DEVELOPER 00:01:25] stderr: Request sent; retry is 1.
[DEVELOPER 00:01:25] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:01:25] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:01:25] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:01:25] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:01:25] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:01:25] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:01:25] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:01:25] stderr: [hdr] Date: Fri, 05 Jan 2018 16:48:54 GMT
[DEVELOPER 00:01:25] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:48:54 GMT]
[DEVELOPER 00:01:25] stderr: [hdr] Vary: Origin
[DEVELOPER 00:01:25] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:01:25] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:01:25] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:01:25] stderr: [hdr] Server: GSE
[DEVELOPER 00:01:25] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:01:25] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:01:25] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:01:25] stderr: [hdr]
[DEVELOPER 00:01:25] stderr: End of headers.
[DEVELOPER 00:01:25] stderr: Running post_headers hooks
[DEVELOPER 00:01:25] stderr: Running post_send hooks
[DEVELOPER 00:01:25] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:01:25] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:01:25] stderr: 204 No Content
[DEBUG 00:01:25] credentials accepted
[DEBUG 00:01:25] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:01:25] stderr: Running destroy hooks.
[DEVELOPER 00:01:25] stderr: Request ends.
[INFO 00:01:25] carddav: deleting <51c1b14e8df7be34>
[DEBUG 00:01:25] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:01:25] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:01:25] stderr: Running pre_send hooks
[DEBUG 00:01:25] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:01:35] stderr: Sending request headers:
[DEVELOPER 00:01:35] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/51c1b14e8df7be34 HTTP/1.1
[DEVELOPER 00:01:35] stderr: Connection: TE
[DEVELOPER 00:01:35] stderr: TE: trailers
[DEVELOPER 00:01:35] stderr: Host: www.googleapis.com
[DEVELOPER 00:01:35] stderr: Content-Length: 0
[DEVELOPER 00:01:35] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:01:35] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:01:35] stderr:
[DEVELOPER 00:01:35] stderr: Sending request-line and headers:
[DEVELOPER 00:01:35] stderr: Request sent; retry is 1.
[DEVELOPER 00:01:35] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:01:35] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:01:35] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:01:35] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:01:35] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:01:35] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:01:35] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:01:35] stderr: [hdr] Date: Fri, 05 Jan 2018 16:49:05 GMT
[DEVELOPER 00:01:35] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:49:05 GMT]
[DEVELOPER 00:01:35] stderr: [hdr] Vary: Origin
[DEVELOPER 00:01:35] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:01:35] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:01:35] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:01:35] stderr: [hdr] Server: GSE
[DEVELOPER 00:01:35] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:01:35] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:01:35] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:01:35] stderr: [hdr]
[DEVELOPER 00:01:35] stderr: End of headers.
[DEVELOPER 00:01:35] stderr: Running post_headers hooks
[DEVELOPER 00:01:35] stderr: Running post_send hooks
[DEVELOPER 00:01:35] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:01:35] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:01:35] stderr: 204 No Content
[DEBUG 00:01:35] credentials accepted
[DEBUG 00:01:35] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:01:35] stderr: Running destroy hooks.
[DEVELOPER 00:01:35] stderr: Request ends.
[INFO 00:01:35] carddav: deleting <52e2bbb888e90e26>
[DEBUG 00:01:35] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:01:35] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:01:35] stderr: Running pre_send hooks
[DEBUG 00:01:35] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:01:46] stderr: Sending request headers:
[DEVELOPER 00:01:46] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/52e2bbb888e90e26 HTTP/1.1
[DEVELOPER 00:01:46] stderr: Connection: TE
[DEVELOPER 00:01:46] stderr: TE: trailers
[DEVELOPER 00:01:46] stderr: Host: www.googleapis.com
[DEVELOPER 00:01:46] stderr: Content-Length: 0
[DEVELOPER 00:01:46] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:01:46] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:01:46] stderr:
[DEVELOPER 00:01:46] stderr: Sending request-line and headers:
[DEVELOPER 00:01:46] stderr: Request sent; retry is 1.
[DEVELOPER 00:01:46] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:01:46] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:01:46] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:01:46] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:01:46] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:01:46] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:01:46] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:01:46] stderr: [hdr] Date: Fri, 05 Jan 2018 16:49:15 GMT
[DEVELOPER 00:01:46] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:49:15 GMT]
[DEVELOPER 00:01:46] stderr: [hdr] Vary: Origin
[DEVELOPER 00:01:46] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:01:46] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:01:46] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:01:46] stderr: [hdr] Server: GSE
[DEVELOPER 00:01:46] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:01:46] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:01:46] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:01:46] stderr: [hdr]
[DEVELOPER 00:01:46] stderr: End of headers.
[DEVELOPER 00:01:46] stderr: Running post_headers hooks
[DEVELOPER 00:01:46] stderr: Running post_send hooks
[DEVELOPER 00:01:46] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:01:46] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:01:46] stderr: 204 No Content
[DEBUG 00:01:46] credentials accepted
[DEBUG 00:01:46] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:01:46] stderr: Running destroy hooks.
[DEVELOPER 00:01:46] stderr: Request ends.
[INFO 00:01:46] carddav: deleting <5c4d669a8813dff3>
[DEBUG 00:01:46] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:01:46] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:01:46] stderr: Running pre_send hooks
[DEBUG 00:01:46] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:01:56] stderr: Sending request headers:
[DEVELOPER 00:01:56] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/5c4d669a8813dff3 HTTP/1.1
[DEVELOPER 00:01:56] stderr: Connection: TE
[DEVELOPER 00:01:56] stderr: TE: trailers
[DEVELOPER 00:01:56] stderr: Host: www.googleapis.com
[DEVELOPER 00:01:56] stderr: Content-Length: 0
[DEVELOPER 00:01:56] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:01:56] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:01:56] stderr:
[DEVELOPER 00:01:56] stderr: Sending request-line and headers:
[DEVELOPER 00:01:56] stderr: Request sent; retry is 1.
[DEVELOPER 00:01:56] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:01:56] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:01:56] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:01:56] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:01:56] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:01:56] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:01:56] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:01:56] stderr: [hdr] Date: Fri, 05 Jan 2018 16:49:25 GMT
[DEVELOPER 00:01:56] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:49:25 GMT]
[DEVELOPER 00:01:56] stderr: [hdr] Vary: Origin
[DEVELOPER 00:01:56] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:01:56] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:01:56] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:01:56] stderr: [hdr] Server: GSE
[DEVELOPER 00:01:56] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:01:56] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:01:56] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:01:56] stderr: [hdr]
[DEVELOPER 00:01:56] stderr: End of headers.
[DEVELOPER 00:01:56] stderr: Running post_headers hooks
[DEVELOPER 00:01:56] stderr: Running post_send hooks
[DEVELOPER 00:01:56] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:01:56] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:01:56] stderr: 204 No Content
[DEBUG 00:01:56] credentials accepted
[DEBUG 00:01:56] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:01:56] stderr: Running destroy hooks.
[DEVELOPER 00:01:56] stderr: Request ends.
[INFO 00:01:56] carddav: deleting <5d7cbeb30e59bc30>
[DEBUG 00:01:56] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:01:56] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:01:56] stderr: Running pre_send hooks
[DEBUG 00:01:56] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:02:06] stderr: Sending request headers:
[DEVELOPER 00:02:06] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/5d7cbeb30e59bc30 HTTP/1.1
[DEVELOPER 00:02:06] stderr: Connection: TE
[DEVELOPER 00:02:06] stderr: TE: trailers
[DEVELOPER 00:02:06] stderr: Host: www.googleapis.com
[DEVELOPER 00:02:06] stderr: Content-Length: 0
[DEVELOPER 00:02:06] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:02:06] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:02:06] stderr:
[DEVELOPER 00:02:06] stderr: Sending request-line and headers:
[DEVELOPER 00:02:06] stderr: Request sent; retry is 1.
[DEVELOPER 00:02:06] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:02:06] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:02:06] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:02:06] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:02:06] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:02:06] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:02:06] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:02:06] stderr: [hdr] Date: Fri, 05 Jan 2018 16:49:36 GMT
[DEVELOPER 00:02:06] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:49:36 GMT]
[DEVELOPER 00:02:06] stderr: [hdr] Vary: Origin
[DEVELOPER 00:02:06] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:02:06] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:02:06] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:02:06] stderr: [hdr] Server: GSE
[DEVELOPER 00:02:06] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:02:06] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:02:06] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:02:06] stderr: [hdr]
[DEVELOPER 00:02:06] stderr: End of headers.
[DEVELOPER 00:02:06] stderr: Running post_headers hooks
[DEVELOPER 00:02:06] stderr: Running post_send hooks
[DEVELOPER 00:02:06] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:02:06] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:02:06] stderr: 204 No Content
[DEBUG 00:02:06] credentials accepted
[DEBUG 00:02:06] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:02:06] stderr: Running destroy hooks.
[DEVELOPER 00:02:06] stderr: Request ends.
[INFO 00:02:06] carddav: deleting <5e31096508bc4a44>
[DEBUG 00:02:06] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:02:06] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:02:06] stderr: Running pre_send hooks
[DEBUG 00:02:06] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:02:17] stderr: Sending request headers:
[DEVELOPER 00:02:17] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/5e31096508bc4a44 HTTP/1.1
[DEVELOPER 00:02:17] stderr: Connection: TE
[DEVELOPER 00:02:17] stderr: TE: trailers
[DEVELOPER 00:02:17] stderr: Host: www.googleapis.com
[DEVELOPER 00:02:17] stderr: Content-Length: 0
[DEVELOPER 00:02:17] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:02:17] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:02:17] stderr:
[DEVELOPER 00:02:17] stderr: Sending request-line and headers:
[DEVELOPER 00:02:17] stderr: Request sent; retry is 1.
[DEVELOPER 00:02:17] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:02:17] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:02:17] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:02:17] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:02:17] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:02:17] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:02:17] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:02:17] stderr: [hdr] Date: Fri, 05 Jan 2018 16:49:46 GMT
[DEVELOPER 00:02:17] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:49:46 GMT]
[DEVELOPER 00:02:17] stderr: [hdr] Vary: Origin
[DEVELOPER 00:02:17] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:02:17] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:02:17] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:02:17] stderr: [hdr] Server: GSE
[DEVELOPER 00:02:17] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:02:17] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:02:17] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:02:17] stderr: [hdr]
[DEVELOPER 00:02:17] stderr: End of headers.
[DEVELOPER 00:02:17] stderr: Running post_headers hooks
[DEVELOPER 00:02:17] stderr: Running post_send hooks
[DEVELOPER 00:02:17] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:02:17] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:02:17] stderr: 204 No Content
[DEBUG 00:02:17] credentials accepted
[DEBUG 00:02:17] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:02:17] stderr: Running destroy hooks.
[DEVELOPER 00:02:17] stderr: Request ends.
[INFO 00:02:17] carddav: deleting <5fa30f530d0ba0ad>
[DEBUG 00:02:17] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:02:17] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:02:17] stderr: Running pre_send hooks
[DEBUG 00:02:17] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:02:27] stderr: Sending request headers:
[DEVELOPER 00:02:27] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/5fa30f530d0ba0ad HTTP/1.1
[DEVELOPER 00:02:27] stderr: Connection: TE
[DEVELOPER 00:02:27] stderr: TE: trailers
[DEVELOPER 00:02:27] stderr: Host: www.googleapis.com
[DEVELOPER 00:02:27] stderr: Content-Length: 0
[DEVELOPER 00:02:27] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:02:27] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:02:27] stderr:
[DEVELOPER 00:02:27] stderr: Sending request-line and headers:
[DEVELOPER 00:02:27] stderr: Request sent; retry is 1.
[DEVELOPER 00:02:27] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:02:27] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:02:27] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:02:27] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:02:27] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:02:27] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:02:27] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:02:27] stderr: [hdr] Date: Fri, 05 Jan 2018 16:49:56 GMT
[DEVELOPER 00:02:27] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:49:56 GMT]
[DEVELOPER 00:02:27] stderr: [hdr] Vary: Origin
[DEVELOPER 00:02:27] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:02:27] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:02:27] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:02:27] stderr: [hdr] Server: GSE
[DEVELOPER 00:02:27] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:02:27] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:02:27] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:02:27] stderr: [hdr]
[DEVELOPER 00:02:27] stderr: End of headers.
[DEVELOPER 00:02:27] stderr: Running post_headers hooks
[DEVELOPER 00:02:27] stderr: Running post_send hooks
[DEVELOPER 00:02:27] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:02:27] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:02:27] stderr: 204 No Content
[DEBUG 00:02:27] credentials accepted
[DEBUG 00:02:27] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:02:27] stderr: Running destroy hooks.
[DEVELOPER 00:02:27] stderr: Request ends.
[INFO 00:02:27] carddav: deleting <6d68259e0ee5552f>
[DEBUG 00:02:27] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:02:27] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:02:27] stderr: Running pre_send hooks
[DEBUG 00:02:27] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:02:37] stderr: Sending request headers:
[DEVELOPER 00:02:37] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/6d68259e0ee5552f HTTP/1.1
[DEVELOPER 00:02:37] stderr: Connection: TE
[DEVELOPER 00:02:37] stderr: TE: trailers
[DEVELOPER 00:02:37] stderr: Host: www.googleapis.com
[DEVELOPER 00:02:37] stderr: Content-Length: 0
[DEVELOPER 00:02:37] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:02:37] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:02:37] stderr:
[DEVELOPER 00:02:37] stderr: Sending request-line and headers:
[DEVELOPER 00:02:37] stderr: Request sent; retry is 1.
[DEVELOPER 00:02:37] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:02:37] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:02:37] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:02:37] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:02:37] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:02:37] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:02:37] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:02:37] stderr: [hdr] Date: Fri, 05 Jan 2018 16:50:07 GMT
[DEVELOPER 00:02:37] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:50:07 GMT]
[DEVELOPER 00:02:37] stderr: [hdr] Vary: Origin
[DEVELOPER 00:02:37] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:02:37] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:02:37] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:02:37] stderr: [hdr] Server: GSE
[DEVELOPER 00:02:37] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:02:37] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:02:37] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:02:37] stderr: [hdr]
[DEVELOPER 00:02:37] stderr: End of headers.
[DEVELOPER 00:02:37] stderr: Running post_headers hooks
[DEVELOPER 00:02:37] stderr: Running post_send hooks
[DEVELOPER 00:02:37] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:02:37] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:02:37] stderr: 204 No Content
[DEBUG 00:02:37] credentials accepted
[DEBUG 00:02:37] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:02:37] stderr: Running destroy hooks.
[DEVELOPER 00:02:37] stderr: Request ends.
[INFO 00:02:37] carddav: deleting <727bb5298c07d8c5>
[DEBUG 00:02:37] starting DELETE, credentials okay, deadline in 300.0s
[DEVELOPER 00:02:37] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:02:37] stderr: Running pre_send hooks
[DEBUG 00:02:37] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:02:48] stderr: Sending request headers:
[DEVELOPER 00:02:48] stderr: DELETE /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/727bb5298c07d8c5 HTTP/1.1
[DEVELOPER 00:02:48] stderr: Connection: TE
[DEVELOPER 00:02:48] stderr: TE: trailers
[DEVELOPER 00:02:48] stderr: Host: www.googleapis.com
[DEVELOPER 00:02:48] stderr: Content-Length: 0
[DEVELOPER 00:02:48] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:02:48] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:02:48] stderr:
[DEVELOPER 00:02:48] stderr: Sending request-line and headers:
[DEVELOPER 00:02:48] stderr: Request sent; retry is 1.
[DEVELOPER 00:02:48] stderr: [status-line] < HTTP/1.1 204 No Content
[DEVELOPER 00:02:48] stderr: [hdr] Cache-Control: no-cache, no-store, max-age=0, must-revalidate
[DEVELOPER 00:02:48] stderr: Header Name: [cache-control], Value: [no-cache, no-store, max-age=0, must-revalidate]
[DEVELOPER 00:02:48] stderr: [hdr] Pragma: no-cache
[DEVELOPER 00:02:48] stderr: Header Name: [pragma], Value: [no-cache]
[DEVELOPER 00:02:48] stderr: [hdr] Expires: Mon, 01 Jan 1990 00:00:00 GMT
[DEVELOPER 00:02:48] stderr: Header Name: [expires], Value: [Mon, 01 Jan 1990 00:00:00 GMT]
[DEVELOPER 00:02:48] stderr: [hdr] Date: Fri, 05 Jan 2018 16:50:17 GMT
[DEVELOPER 00:02:48] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:50:17 GMT]
[DEVELOPER 00:02:48] stderr: [hdr] Vary: Origin
[DEVELOPER 00:02:48] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:02:48] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:02:48] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:02:48] stderr: [hdr] Server: GSE
[DEVELOPER 00:02:48] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:02:48] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:02:48] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:02:48] stderr: [hdr]
[DEVELOPER 00:02:48] stderr: End of headers.
[DEVELOPER 00:02:48] stderr: Running post_headers hooks
[DEVELOPER 00:02:48] stderr: Running post_send hooks
[DEVELOPER 00:02:48] stderr: ah_post_send (#0), code is 204 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:02:48] stderr: Request ends, status 204 class 2xx, error line:
[DEVELOPER 00:02:48] stderr: 204 No Content
[DEBUG 00:02:48] credentials accepted
[DEBUG 00:02:48] remove item status: <status 1.1, code 204, class 2, No Content>
[DEVELOPER 00:02:48] stderr: Running destroy hooks.
[DEVELOPER 00:02:48] stderr: Request ends.
[DEBUG 00:02:48] read ctag of /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/
[DEBUG 00:02:48] starting PROPFIND, credentials okay, deadline in 300.0s
[DEVELOPER 00:02:48] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:02:48] stderr: Running pre_send hooks
[DEBUG 00:02:48] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:02:48] stderr: Sending request headers:
[DEVELOPER 00:02:48] stderr: PROPFIND /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/ HTTP/1.1
[DEVELOPER 00:02:48] stderr: Connection: TE
[DEVELOPER 00:02:48] stderr: TE: trailers
[DEVELOPER 00:02:48] stderr: Host: www.googleapis.com
[DEVELOPER 00:02:48] stderr: Depth: 0
[DEVELOPER 00:02:48] stderr: Content-Length: 137
[DEVELOPER 00:02:48] stderr: Content-Type: application/xml
[DEVELOPER 00:02:48] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:02:48] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:02:48] stderr:
[DEVELOPER 00:02:48] stderr: Sending request-line and headers:
[DEVELOPER 00:02:48] stderr: Sending request body:
[DEVELOPER 00:02:48] stderr: Body block (137 bytes):
[DEVELOPER 00:02:48] stderr: [<?xml version="1.0" encoding="utf-8"?>
[DEVELOPER 00:02:48] stderr: <propfind xmlns="DAV:"><prop>
[DEVELOPER 00:02:48] stderr: <getctag xmlns="http://calendarserver.org/ns/"/>
[DEVELOPER 00:02:48] stderr: </prop></propfind>
[DEVELOPER 00:02:48] stderr: ]
[DEVELOPER 00:02:48] stderr: Request sent; retry is 1.
[DEVELOPER 00:02:48] stderr: [status-line] < HTTP/1.1 207 Multi-Status
[DEVELOPER 00:02:48] stderr: [hdr] DAV: 1, 2, 3, addressbook, access-control
[DEVELOPER 00:02:48] stderr: Header Name: [dav], Value: [1, 2, 3, addressbook, access-control]
[DEVELOPER 00:02:48] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:02:48] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:02:48] stderr: [hdr] Content-Type: text/xml; charset=UTF-8
[DEVELOPER 00:02:48] stderr: Header Name: [content-type], Value: [text/xml; charset=UTF-8]
[DEVELOPER 00:02:48] stderr: [hdr] Date: Fri, 05 Jan 2018 16:50:17 GMT
[DEVELOPER 00:02:48] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:50:17 GMT]
[DEVELOPER 00:02:48] stderr: [hdr] Expires: Fri, 05 Jan 2018 16:50:17 GMT
[DEVELOPER 00:02:48] stderr: Header Name: [expires], Value: [Fri, 05 Jan 2018 16:50:17 GMT]
[DEVELOPER 00:02:48] stderr: [hdr] Cache-Control: private, max-age=0
[DEVELOPER 00:02:48] stderr: Header Name: [cache-control], Value: [private, max-age=0]
[DEVELOPER 00:02:48] stderr: [hdr] X-Content-Type-Options: nosniff
[DEVELOPER 00:02:48] stderr: Header Name: [x-content-type-options], Value: [nosniff]
[DEVELOPER 00:02:48] stderr: [hdr] X-Frame-Options: SAMEORIGIN
[DEVELOPER 00:02:48] stderr: Header Name: [x-frame-options], Value: [SAMEORIGIN]
[DEVELOPER 00:02:48] stderr: [hdr] X-XSS-Protection: 1; mode=block
[DEVELOPER 00:02:48] stderr: Header Name: [x-xss-protection], Value: [1; mode=block]
[DEVELOPER 00:02:48] stderr: [hdr] Server: GSE
[DEVELOPER 00:02:48] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:02:48] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:02:48] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:02:48] stderr: [hdr] Accept-Ranges: none
[DEVELOPER 00:02:48] stderr: Header Name: [accept-ranges], Value: [none]
[DEVELOPER 00:02:48] stderr: [hdr] Vary: Origin,Accept-Encoding
[DEVELOPER 00:02:48] stderr: Header Name: [vary], Value: [Origin,Accept-Encoding]
[DEVELOPER 00:02:48] stderr: [hdr] Transfer-Encoding: chunked
[DEVELOPER 00:02:48] stderr: Header Name: [transfer-encoding], Value: [chunked]
[DEVELOPER 00:02:48] stderr: [hdr]
[DEVELOPER 00:02:48] stderr: End of headers.
[DEVELOPER 00:02:48] stderr: Running post_headers hooks
[DEVELOPER 00:02:48] stderr: [chunk] < 202
[DEVELOPER 00:02:48] stderr: Got chunk size: 514
[DEVELOPER 00:02:48] stderr: Reading 514 bytes of response body.
[DEVELOPER 00:02:48] stderr: Got 514 bytes.
[DEVELOPER 00:02:48] stderr: Read block (514 bytes):
[DEVELOPER 00:02:48] stderr: [<?xml version="1.0" encoding="UTF-8"?>
[DEVELOPER 00:02:48] stderr: <d:multistatus xmlns:cal="urn:ietf:params:xml:ns:caldav" xmlns:card="urn:ietf:params:xml:ns:carddav" xmlns:cs="http://calendarserver.org/ns/" xmlns:d="DAV:" xmlns:ical="http://apple.com/ns/ical/">
[DEVELOPER 00:02:48] stderr:  <d:response>
[DEVELOPER 00:02:48] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/</d:href>
[DEVELOPER 00:02:48] stderr:   <d:propstat>
[DEVELOPER 00:02:48] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:02:48] stderr:    <d:prop>
[DEVELOPER 00:02:48] stderr:     <cs:getctag>"23ad7c904aa665e3.0"</cs:getctag>
[DEVELOPER 00:02:48] stderr:    </d:prop>
[DEVELOPER 00:02:48] stderr:   </d:propstat>
[DEVELOPER 00:02:48] stderr:  </d:response>
[DEVELOPER 00:02:48] stderr: </d:multistatus>
[DEVELOPER 00:02:48] stderr: ]
[DEVELOPER 00:02:48] stderr: [chunk] < 0
[DEVELOPER 00:02:48] stderr: Got chunk size: 0
[DEVELOPER 00:02:48] stderr: [hdr]
[DEVELOPER 00:02:48] stderr: End of headers.
[DEVELOPER 00:02:48] stderr: Running post_send hooks
[DEVELOPER 00:02:48] stderr: ah_post_send (#0), code is 207 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:02:48] stderr: Request ends, status 207 class 2xx, error line:
[DEVELOPER 00:02:48] stderr: 207 Multi-Status
[DEBUG 00:02:48] credentials accepted
[DEVELOPER 00:02:48] stderr: Running destroy hooks.
[DEVELOPER 00:02:48] stderr: Request ends.
[DEBUG 00:02:48] ClientTest.cpp:6184: ending CPPUNIT_ASSERT_MESSAGE(peer + " " + peerSource, (cmdline->run()))
[DEBUG 00:02:48] ClientTest.cpp:6191: starting CPPUNIT_ASSERT(cmdline->parse())
[DEBUG 00:02:48] ClientTest.cpp:6191: ending CPPUNIT_ASSERT(cmdline->parse())
[DEBUG 00:02:48] ClientTest.cpp:6192: starting CPPUNIT_ASSERT_MESSAGE(peer + " " + peerSource, (cmdline->run()))
[DEBUG 00:02:48] checking password property 'password' in config 'target-config@client-test-googlecontacts' with user identity 'goa:syncevolution.list@gmail.com'
[DEBUG 00:02:48] checking password property 'proxyPassword' in config 'target-config@client-test-googlecontacts' with user identity ''
[DEBUG 00:02:48] checking password property 'databasePassword' in datastore 'carddav' of config 'target-config@client-test-googlecontacts' with user identity ''
[DEBUG 00:02:48] 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
[INFO 00:02:48] carddav: using configured database=https://www.googleapis.com:443/carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/
[DEBUG 00:02:48] using username 'goa:syncevolution.list@gmail.com' from context for WebDAV, password not set
[DEBUG 00:02:48] looking for identity provider for goa:syncevolution.list@gmail.com
[DEBUG 00:02:48] Looking up all accounts in GNOME Online Accounts, searching for 'syncevolution.list@gmail.com'.
[DEBUG 00:02:48] GOA object /org/gnome/OnlineAccounts/Accounts/account_1379073187 implements org.gnome.OnlineAccounts.Account, org.gnome.OnlineAccounts.Calendar, org.gnome.OnlineAccounts.Chat, org.gnome.OnlineAccounts.Contacts, org.gnome.OnlineAccounts.Documents, org.gnome.OnlineAccounts.Mail, org.gnome.OnlineAccounts.OAuth2Based
[DEBUG 00:02:48] GOA account Google, syncevolution.list@gmail.com = account_1379073187
[DEBUG 00:02:48] found matching GNOME Online Account for 'syncevolution.list@gmail.com': Google, syncevolution.list@gmail.com = account_1379073187
[DEBUG 00:02:48] GOA object /org/gnome/OnlineAccounts/Manager implements org.gnome.OnlineAccounts.Manager
[DEBUG 00:02:48] carddav: slow sync or testing, do full item scan to detect changes
[DEBUG 00:02:48] starting PROPFIND, credentials unverified, deadline in 300.0s
[DEVELOPER 00:02:48] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:02:48] stderr: Running pre_send hooks
[DEBUG 00:02:48] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:02:48] stderr: Sending request headers:
[DEVELOPER 00:02:48] stderr: PROPFIND /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/ HTTP/1.1
[DEVELOPER 00:02:48] stderr: Connection: TE
[DEVELOPER 00:02:48] stderr: TE: trailers
[DEVELOPER 00:02:48] stderr: Host: www.googleapis.com
[DEVELOPER 00:02:48] stderr: Depth: 1
[DEVELOPER 00:02:48] stderr: Content-Length: 141
[DEVELOPER 00:02:48] stderr: Content-Type: application/xml
[DEVELOPER 00:02:48] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:02:48] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:02:48] stderr:
[DEVELOPER 00:02:48] stderr: Sending request-line and headers:
[DEVELOPER 00:02:48] stderr: Sending request body:
[DEVELOPER 00:02:48] stderr: Body block (141 bytes):
[DEVELOPER 00:02:48] stderr: [<?xml version="1.0" encoding="utf-8"?>
[DEVELOPER 00:02:48] stderr: <propfind xmlns="DAV:"><prop>
[DEVELOPER 00:02:48] stderr: <getetag xmlns="DAV:"/>
[DEVELOPER 00:02:48] stderr: <resourcetype xmlns="DAV:"/>
[DEVELOPER 00:02:48] stderr: </prop></propfind>
[DEVELOPER 00:02:48] stderr: ]
[DEVELOPER 00:02:48] stderr: Request sent; retry is 1.
[DEVELOPER 00:02:48] stderr: [status-line] < HTTP/1.1 207 Multi-Status
[DEVELOPER 00:02:48] stderr: [hdr] DAV: 1, 2, 3, addressbook, access-control
[DEVELOPER 00:02:48] stderr: Header Name: [dav], Value: [1, 2, 3, addressbook, access-control]
[DEVELOPER 00:02:48] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:02:48] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:02:48] stderr: [hdr] Content-Type: text/xml; charset=UTF-8
[DEVELOPER 00:02:48] stderr: Header Name: [content-type], Value: [text/xml; charset=UTF-8]
[DEVELOPER 00:02:48] stderr: [hdr] Date: Fri, 05 Jan 2018 16:50:17 GMT
[DEVELOPER 00:02:48] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:50:17 GMT]
[DEVELOPER 00:02:48] stderr: [hdr] Expires: Fri, 05 Jan 2018 16:50:17 GMT
[DEVELOPER 00:02:48] stderr: Header Name: [expires], Value: [Fri, 05 Jan 2018 16:50:17 GMT]
[DEVELOPER 00:02:48] stderr: [hdr] Cache-Control: private, max-age=0
[DEVELOPER 00:02:48] stderr: Header Name: [cache-control], Value: [private, max-age=0]
[DEVELOPER 00:02:48] stderr: [hdr] X-Content-Type-Options: nosniff
[DEVELOPER 00:02:48] stderr: Header Name: [x-content-type-options], Value: [nosniff]
[DEVELOPER 00:02:48] stderr: [hdr] X-Frame-Options: SAMEORIGIN
[DEVELOPER 00:02:48] stderr: Header Name: [x-frame-options], Value: [SAMEORIGIN]
[DEVELOPER 00:02:48] stderr: [hdr] X-XSS-Protection: 1; mode=block
[DEVELOPER 00:02:48] stderr: Header Name: [x-xss-protection], Value: [1; mode=block]
[DEVELOPER 00:02:48] stderr: [hdr] Server: GSE
[DEVELOPER 00:02:48] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:02:48] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:02:48] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:02:48] stderr: [hdr] Accept-Ranges: none
[DEVELOPER 00:02:48] stderr: Header Name: [accept-ranges], Value: [none]
[DEVELOPER 00:02:48] stderr: [hdr] Vary: Origin,Accept-Encoding
[DEVELOPER 00:02:48] stderr: Header Name: [vary], Value: [Origin,Accept-Encoding]
[DEVELOPER 00:02:48] stderr: [hdr] Transfer-Encoding: chunked
[DEVELOPER 00:02:48] stderr: Header Name: [transfer-encoding], Value: [chunked]
[DEVELOPER 00:02:48] stderr: [hdr]
[DEVELOPER 00:02:48] stderr: End of headers.
[DEVELOPER 00:02:48] stderr: Running post_headers hooks
[DEVELOPER 00:02:48] stderr: [chunk] < 2a1
[DEVELOPER 00:02:48] stderr: Got chunk size: 673
[DEVELOPER 00:02:48] stderr: Reading 673 bytes of response body.
[DEVELOPER 00:02:48] stderr: Got 605 bytes.
[DEVELOPER 00:02:48] stderr: Read block (605 bytes):
[DEVELOPER 00:02:48] stderr: [<?xml version="1.0" encoding="UTF-8"?>
[DEVELOPER 00:02:48] stderr: <d:multistatus xmlns:cal="urn:ietf:params:xml:ns:caldav" xmlns:card="urn:ietf:params:xml:ns:carddav" xmlns:cs="http://calendarserver.org/ns/" xmlns:d="DAV:" xmlns:ical="http://apple.com/ns/ical/">
[DEVELOPER 00:02:48] stderr:  <d:response>
[DEVELOPER 00:02:48] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/</d:href>
[DEVELOPER 00:02:48] stderr:   <d:propstat>
[DEVELOPER 00:02:48] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:02:48] stderr:    <d:prop>
[DEVELOPER 00:02:48] stderr:     <d:resourcetype>
[DEVELOPER 00:02:48] stderr:      <d:collection/>
[DEVELOPER 00:02:48] stderr:      <card:addressbook/>
[DEVELOPER 00:02:48] stderr:     </d:resourcetype>
[DEVELOPER 00:02:48] stderr:    </d:prop>
[DEVELOPER 00:02:48] stderr:   </d:propstat>
[DEVELOPER 00:02:48] stderr:   <d:propstat>
[DEVELOPER 00:02:48] stderr:    <d:status>HTTP/1.1 404 Not Found</d:status>
[DEVELOPER 00:02:48] stderr:    <d:prop>
[DEVELOPER 00:02:48] stderr:     <d:get]
[DEVELOPER 00:02:48] stderr: Reading 68 bytes of response body.
[DEVELOPER 00:02:48] stderr: Got 68 bytes.
[DEVELOPER 00:02:48] stderr: Read block (68 bytes):
[DEVELOPER 00:02:48] stderr: [etag/>
[DEVELOPER 00:02:48] stderr:    </d:prop>
[DEVELOPER 00:02:48] stderr:   </d:propstat>
[DEVELOPER 00:02:48] stderr:  </d:response>
[DEVELOPER 00:02:48] stderr: </d:multistatus>
[DEVELOPER 00:02:48] stderr: ]
[DEVELOPER 00:02:48] stderr: [chunk] < 0
[DEVELOPER 00:02:48] stderr: Got chunk size: 0
[DEVELOPER 00:02:48] stderr: [hdr]
[DEVELOPER 00:02:48] stderr: End of headers.
[DEVELOPER 00:02:48] stderr: Running post_send hooks
[DEVELOPER 00:02:48] stderr: ah_post_send (#0), code is 207 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:02:48] stderr: Request ends, status 207 class 2xx, error line:
[DEVELOPER 00:02:48] stderr: 207 Multi-Status
[DEBUG 00:02:48] credentials accepted
[DEVELOPER 00:02:48] stderr: Running destroy hooks.
[DEVELOPER 00:02:48] stderr: Request ends.
[DEBUG 00:02:48] check POST support of /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/
[DEBUG 00:02:48] starting PROPFIND, credentials okay, deadline in 300.0s
[DEVELOPER 00:02:48] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:02:48] stderr: Running pre_send hooks
[DEBUG 00:02:48] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:02:48] stderr: Sending request headers:
[DEVELOPER 00:02:48] stderr: PROPFIND /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/ HTTP/1.1
[DEVELOPER 00:02:48] stderr: Connection: TE
[DEVELOPER 00:02:48] stderr: TE: trailers
[DEVELOPER 00:02:48] stderr: Host: www.googleapis.com
[DEVELOPER 00:02:48] stderr: Depth: 0
[DEVELOPER 00:02:48] stderr: Content-Length: 115
[DEVELOPER 00:02:48] stderr: Content-Type: application/xml
[DEVELOPER 00:02:48] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:02:48] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:02:48] stderr:
[DEVELOPER 00:02:48] stderr: Sending request-line and headers:
[DEVELOPER 00:02:48] stderr: Sending request body:
[DEVELOPER 00:02:48] stderr: Body block (115 bytes):
[DEVELOPER 00:02:48] stderr: [<?xml version="1.0" encoding="utf-8"?>
[DEVELOPER 00:02:48] stderr: <propfind xmlns="DAV:"><prop>
[DEVELOPER 00:02:48] stderr: <add-member xmlns="DAV:"/>
[DEVELOPER 00:02:48] stderr: </prop></propfind>
[DEVELOPER 00:02:48] stderr: ]
[DEVELOPER 00:02:48] stderr: Request sent; retry is 1.
[DEVELOPER 00:02:48] stderr: [status-line] < HTTP/1.1 207 Multi-Status
[DEVELOPER 00:02:48] stderr: [hdr] DAV: 1, 2, 3, addressbook, access-control
[DEVELOPER 00:02:48] stderr: Header Name: [dav], Value: [1, 2, 3, addressbook, access-control]
[DEVELOPER 00:02:48] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:02:48] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:02:48] stderr: [hdr] Content-Type: text/xml; charset=UTF-8
[DEVELOPER 00:02:48] stderr: Header Name: [content-type], Value: [text/xml; charset=UTF-8]
[DEVELOPER 00:02:48] stderr: [hdr] Date: Fri, 05 Jan 2018 16:50:18 GMT
[DEVELOPER 00:02:48] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:50:18 GMT]
[DEVELOPER 00:02:48] stderr: [hdr] Expires: Fri, 05 Jan 2018 16:50:18 GMT
[DEVELOPER 00:02:48] stderr: Header Name: [expires], Value: [Fri, 05 Jan 2018 16:50:18 GMT]
[DEVELOPER 00:02:48] stderr: [hdr] Cache-Control: private, max-age=0
[DEVELOPER 00:02:48] stderr: Header Name: [cache-control], Value: [private, max-age=0]
[DEVELOPER 00:02:48] stderr: [hdr] X-Content-Type-Options: nosniff
[DEVELOPER 00:02:48] stderr: Header Name: [x-content-type-options], Value: [nosniff]
[DEVELOPER 00:02:48] stderr: [hdr] X-Frame-Options: SAMEORIGIN
[DEVELOPER 00:02:48] stderr: Header Name: [x-frame-options], Value: [SAMEORIGIN]
[DEVELOPER 00:02:48] stderr: [hdr] X-XSS-Protection: 1; mode=block
[DEVELOPER 00:02:48] stderr: Header Name: [x-xss-protection], Value: [1; mode=block]
[DEVELOPER 00:02:48] stderr: [hdr] Server: GSE
[DEVELOPER 00:02:48] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:02:48] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:02:48] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:02:48] stderr: [hdr] Accept-Ranges: none
[DEVELOPER 00:02:48] stderr: Header Name: [accept-ranges], Value: [none]
[DEVELOPER 00:02:48] stderr: [hdr] Vary: Origin,Accept-Encoding
[DEVELOPER 00:02:48] stderr: Header Name: [vary], Value: [Origin,Accept-Encoding]
[DEVELOPER 00:02:48] stderr: [hdr] Transfer-Encoding: chunked
[DEVELOPER 00:02:48] stderr: Header Name: [transfer-encoding], Value: [chunked]
[DEVELOPER 00:02:48] stderr: [hdr]
[DEVELOPER 00:02:48] stderr: End of headers.
[DEVELOPER 00:02:48] stderr: Running post_headers hooks
[DEVELOPER 00:02:48] stderr: [chunk] < 250
[DEVELOPER 00:02:48] stderr: Got chunk size: 592
[DEVELOPER 00:02:48] stderr: Reading 592 bytes of response body.
[DEVELOPER 00:02:48] stderr: Got 592 bytes.
[DEVELOPER 00:02:48] stderr: Read block (592 bytes):
[DEVELOPER 00:02:48] stderr: [<?xml version="1.0" encoding="UTF-8"?>
[DEVELOPER 00:02:48] stderr: <d:multistatus xmlns:cal="urn:ietf:params:xml:ns:caldav" xmlns:card="urn:ietf:params:xml:ns:carddav" xmlns:cs="http://calendarserver.org/ns/" xmlns:d="DAV:" xmlns:ical="http://apple.com/ns/ical/">
[DEVELOPER 00:02:48] stderr:  <d:response>
[DEVELOPER 00:02:48] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/</d:href>
[DEVELOPER 00:02:48] stderr:   <d:propstat>
[DEVELOPER 00:02:48] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:02:48] stderr:    <d:prop>
[DEVELOPER 00:02:48] stderr:     <d:add-member>
[DEVELOPER 00:02:48] stderr:      <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/</d:href>
[DEVELOPER 00:02:48] stderr:     </d:add-member>
[DEVELOPER 00:02:48] stderr:    </d:prop>
[DEVELOPER 00:02:48] stderr:   </d:propstat>
[DEVELOPER 00:02:48] stderr:  </d:response>
[DEVELOPER 00:02:48] stderr: </d:multistatus>
[DEVELOPER 00:02:48] stderr: ]
[DEVELOPER 00:02:48] stderr: [chunk] < 0
[DEVELOPER 00:02:48] stderr: Got chunk size: 0
[DEVELOPER 00:02:48] stderr: [hdr]
[DEVELOPER 00:02:48] stderr: End of headers.
[DEVELOPER 00:02:48] stderr: Running post_send hooks
[DEVELOPER 00:02:48] stderr: ah_post_send (#0), code is 207 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:02:48] stderr: Request ends, status 207 class 2xx, error line:
[DEVELOPER 00:02:48] stderr: 207 Multi-Status
[DEBUG 00:02:48] credentials accepted
[DEVELOPER 00:02:48] stderr: Running destroy hooks.
[DEVELOPER 00:02:48] stderr: Request ends.
[DEBUG 00:02:48] /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/ POST support: /carddav/v1/principals/syncevolution.list@gmail.com/lists/default/
[DEBUG 00:02:48] starting POST, credentials okay, deadline in 300.0s
[DEVELOPER 00:02:48] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:02:48] stderr: Running pre_send hooks
[DEBUG 00:02:48] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:02:58] stderr: Sending request headers:
[DEVELOPER 00:02:58] stderr: POST /carddav/v1/principals/syncevolution.list@gmail.com/lists/default/ HTTP/1.1
[DEVELOPER 00:02:58] stderr: Connection: TE
[DEVELOPER 00:02:58] stderr: TE: trailers
[DEVELOPER 00:02:58] stderr: Host: www.googleapis.com
[DEVELOPER 00:02:58] stderr: Content-Length: 2897
[DEVELOPER 00:02:58] stderr: Content-Type: text/vcard; charset=utf-8
[DEVELOPER 00:02:58] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:02:58] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:02:58] stderr:
[DEVELOPER 00:02:58] stderr: Sending request-line and headers:
[DEVELOPER 00:02:58] stderr: Sending request body:
[DEVELOPER 00:02:58] stderr: Body block (2897 bytes):
[DEVELOPER 00:02:58] stderr: [BEGIN:VCARD
[DEVELOPER 00:02:58] stderr: VERSION:3.0
[DEVELOPER 00:02:58] stderr: N:Doe;John;1;Mr.;Sr.
[DEVELOPER 00:02:58] stderr: FN:Mr. John 1 Doe Sr.
[DEVELOPER 00:02:58] stderr: NICKNAME:Johnny
[DEVELOPER 00:02:58] stderr: TITLE:tester
[DEVELOPER 00:02:58] stderr: ORG:at company
[DEVELOPER 00:02:58] stderr: REV:2014-04-11T08:43:42Z
[DEVELOPER 00:02:58] stderr: UID:6f354d698b7ccd22
[DEVELOPER 00:02:58] stderr: BDAY;VALUE=DATE:1970-12-30
[DEVELOPER 00:02:58] stderr: ADR;TYPE=HOME:PO;neighborhood;home address\n;City;State;ZIP;Country
[DEVELOPER 00:02:58] stderr: ADR;TYPE=WORK:;;work address;;;;
[DEVELOPER 00:02:58] stderr: ADR:;;custom address;;;;
[DEVELOPER 00:02:58] stderr: TEL;TYPE=WORK:business 1
[DEVELOPER 00:02:58] stderr: TEL;TYPE=CELL:mobile
[DEVELOPER 00:02:58] stderr: TEL;TYPE=HOME:home
[DEVELOPER 00:02:58] stderr: TEL:main
[DEVELOPER 00:02:58] stderr: TEL;TYPE=FAX,WORK:work fax
[DEVELOPER 00:02:58] stderr: TEL;TYPE=FAX,HOME:home fax
[DEVELOPER 00:02:58] stderr: item14.TEL:google voice
[DEVELOPER 00:02:58] stderr: TEL;TYPE=PAGER:pager
[DEVELOPER 00:02:58] stderr: item15.TEL:custom
[DEVELOPER 00:02:58] stderr: EMAIL;TYPE=HOME,PREF:john.doe@home.com
[DEVELOPER 00:02:58] stderr: EMAIL;TYPE=WORK:doe@work.com
[DEVELOPER 00:02:58] stderr: item2.EMAIL:john@custom.com
[DEVELOPER 00:02:58] stderr: NOTE:A test contact.
[DEVELOPER 00:02:58] stderr: item5.IMPP;X-SERVICE-TYPE=GoogleTalk:xmpp:google%20talk
[DEVELOPER 00:02:58] stderr: item6.IMPP;X-SERVICE-TYPE=AIM:aim:aim
[DEVELOPER 00:02:58] stderr: item7.IMPP;X-SERVICE-TYPE=Yahoo:ymsgr:yahoo
[DEVELOPER 00:02:58] stderr: item8.IMPP;X-SERVICE-TYPE=Skype:skype:skype
[DEVELOPER 00:02:58] stderr: item9.IMPP;X-SERVICE-TYPE=QQ:x-apple:QQ
[DEVELOPER 00:02:58] stderr: item10.IMPP;X-SERVICE-TYPE=MSN:msnim:MSN
[DEVELOPER 00:02:58] stderr: item11.IMPP;X-SERVICE-TYPE=ICQ:aim:ICQ
[DEVELOPER 00:02:58] stderr: item12.IMPP;X-SERVICE-TYPE=Jabber:xmpp:Jabber
[DEVELOPER 00:02:58] stderr: item13.IMPP;X-SERVICE-TYPE=chat-label:x-apple:custom%20chat
[DEVELOPER 00:02:58] stderr: X-EVOLUTION-FILE-AS:Doe\, John
[DEVELOPER 00:02:58] stderr: X-MOZILLA-HTML:FALSE
[DEVELOPER 00:02:58] stderr: X-PHONETIC-FIRST-NAME:John
[DEVELOPER 00:02:58] stderr: X-PHONETIC-LAST-NAME:Doe
[DEVELOPER 00:02:58] stderr: item2.X-ABLabel:custom-label2
[DEVELOPER 00:02:58] stderr: item3.X-ABDATE:1971-01-01
[DEVELOPER 00:02:58] stderr: item3.X-ABLabel:Anniversary
[DEVELOPER 00:02:58] stderr: item4.X-ABDATE:2000-02-01
[DEVELOPER 00:02:58] stderr: item4.X-ABLabel:custom-label3
[DEVELOPER 00:02:58] stderr: item5.X-ABLabel:Other
[DEVELOPER 00:02:58] stderr: item6.X-ABLabel:Other
[DEVELOPER 00:02:58] stderr: item7.X-ABLabel:Other
[DEVELOPER 00:02:58] stderr: item8.X-ABLabel:Other
[DEVELOPER 00:02:58] stderr: item9.X-ABLabel:Other
[DEVELOPER 00:02:58] stderr: item10.X-ABLabel:Other
[DEVELOPER 00:02:58] stderr: item11.X-ABLabel:Other
[DEVELOPER 00:02:58] stderr: item12.X-ABLabel:Other
[DEVELOPER 00:02:58] stderr: item13.X-ABLabel:Other
[DEVELOPER 00:02:58] stderr: item14.X-ABLabel:Google Voice
[DEVELOPER 00:02:58] stderr: item15.X-ABLabel:custom-label4
[DEVELOPER 00:02:58] stderr: item16.X-ABRELATEDNAMES:spouse
[DEVELOPER 00:02:58] stderr: item16.X-ABLabel:Spouse
[DEVELOPER 00:02:58] stderr: item17.X-ABRELATEDNAMES:child
[DEVELOPER 00:02:58] stderr: item17.X-ABLabel:Child
[DEVELOPER 00:02:58] stderr: item18.X-ABRELATEDNAMES:mother
[DEVELOPER 00:02:58] stderr: item18.X-ABLabel:Mother
[DEVELOPER 00:02:58] stderr: item19.X-ABRELATEDNAMES:father
[DEVELOPER 00:02:58] stderr: item19.X-ABLabel:Father
[DEVELOPER 00:02:58] stderr: item20.X-ABRELATEDNAMES:parent
[DEVELOPER 00:02:58] stderr: item20.X-ABLabel:Parent
[DEVELOPER 00:02:58] stderr: item21.X-ABRELATEDNAMES:brother
[DEVELOPER 00:02:58] stderr: item21.X-ABLabel:Brother
[DEVELOPER 00:02:58] stderr: item22.X-ABRELATEDNAMES:sister
[DEVELOPER 00:02:58] stderr: item22.X-ABLabel:Sister
[DEVELOPER 00:02:58] stderr: item23.X-ABRELATEDNAMES:friend
[DEVELOPER 00:02:58] stderr: item23.X-ABLabel:Friend
[DEVELOPER 00:02:58] stderr: item24.X-ABRELATEDNAMES:relative
[DEVELOPER 00:02:58] stderr: item24.X-ABLabel:relative
[DEVELOPER 00:02:58] stderr: item25.X-ABRELATEDNAMES:manager
[DEVELOPER 00:02:58] stderr: item25.X-ABLabel:Manager
[DEVELOPER 00:02:58] stderr: item26.X-ABRELATEDNAMES:assistant
[DEVELOPER 00:02:58] stderr: item26.X-ABLabel:Assistant
[DEVELOPER 00:02:58] stderr: item27.X-ABRELATEDNAMES:referred-by
[DEVELOPER 00:02:58] stderr: item27.X-ABLabel:referred-by-label
[DEVELOPER 00:02:58] stderr: item28.X-ABRELATEDNAMES:partner
[DEVELOPER 00:02:58] stderr: item28.X-ABLabel:Partner
[DEVELOPER 00:02:58] stderr: item29.X-ABRELATEDNAMES:domestic partner
[DEVELOPER 00:02:58] stderr: item29.X-ABLabel:domestic-partner-label
[DEVELOPER 00:02:58] stderr: item30.X-ABRELATEDNAMES:custom relationship
[DEVELOPER 00:02:58] stderr: item30.X-ABLabel:custom-label5\nUmlaut ä\nSemicolon \; Backslash \\ newline \n tab	end of label
[DEVELOPER 00:02:58] stderr: item31.URL:http\://profile.com
[DEVELOPER 00:02:58] stderr: item31.X-ABLabel:Profile
[DEVELOPER 00:02:58] stderr: item32.URL:http\://blog.com
[DEVELOPER 00:02:58] stderr: item32.X-ABLabel:Blog
[DEVELOPER 00:02:58] stderr: item33.URL:http\://homepage.com
[DEVELOPER 00:02:58] stderr: item33.X-ABLabel:HomePage
[DEVELOPER 00:02:58] stderr: item34.URL:http\://company.com
[DEVELOPER 00:02:58] stderr: item34.X-ABLabel:Work
[DEVELOPER 00:02:58] stderr: item35.URL:http\://custom.com
[DEVELOPER 00:02:58] stderr: item35.X-ABLabel:Custom-label6 Backslash \\
[DEVELOPER 00:02:58] stderr: END:VCARD
[DEVELOPER 00:02:58] stderr: ]
[DEVELOPER 00:02:58] stderr: Request sent; retry is 1.
[DEVELOPER 00:02:58] stderr: [status-line] < HTTP/1.1 201 Created
[DEVELOPER 00:02:58] stderr: [hdr] Content-Type: text/xml
[DEVELOPER 00:02:58] stderr: Header Name: [content-type], Value: [text/xml]
[DEVELOPER 00:02:58] stderr: [hdr] ETag: "2018-01-05T08:50:18.177-08:00"
[DEVELOPER 00:02:58] stderr: Header Name: [etag], Value: ["2018-01-05T08:50:18.177-08:00"]
[DEVELOPER 00:02:58] stderr: [hdr] Location: /carddav/v1/principals/syncevolution.list@gmail.com/lists/default/182b96310e5fbc3f
[DEVELOPER 00:02:58] stderr: Header Name: [location], Value: [/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/182b96310e5fbc3f]
[DEVELOPER 00:02:58] stderr: [hdr] Vary: Origin
[DEVELOPER 00:02:58] stderr: Header Name: [vary], Value: [Origin]
[DEVELOPER 00:02:58] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:02:58] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:02:58] stderr: [hdr] Date: Fri, 05 Jan 2018 16:50:28 GMT
[DEVELOPER 00:02:58] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:50:28 GMT]
[DEVELOPER 00:02:58] stderr: [hdr] Expires: Fri, 05 Jan 2018 16:50:28 GMT
[DEVELOPER 00:02:58] stderr: Header Name: [expires], Value: [Fri, 05 Jan 2018 16:50:28 GMT]
[DEVELOPER 00:02:58] stderr: [hdr] Cache-Control: private, max-age=0
[DEVELOPER 00:02:58] stderr: Header Name: [cache-control], Value: [private, max-age=0]
[DEVELOPER 00:02:58] stderr: [hdr] X-Content-Type-Options: nosniff
[DEVELOPER 00:02:58] stderr: Header Name: [x-content-type-options], Value: [nosniff]
[DEVELOPER 00:02:58] stderr: [hdr] X-Frame-Options: SAMEORIGIN
[DEVELOPER 00:02:58] stderr: Header Name: [x-frame-options], Value: [SAMEORIGIN]
[DEVELOPER 00:02:58] stderr: [hdr] X-XSS-Protection: 1; mode=block
[DEVELOPER 00:02:58] stderr: Header Name: [x-xss-protection], Value: [1; mode=block]
[DEVELOPER 00:02:58] stderr: [hdr] Content-Length: 0
[DEVELOPER 00:02:58] stderr: Header Name: [content-length], Value: [0]
[DEVELOPER 00:02:58] stderr: [hdr] Server: GSE
[DEVELOPER 00:02:58] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:02:58] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:02:58] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:02:58] stderr: [hdr]
[DEVELOPER 00:02:58] stderr: End of headers.
[DEVELOPER 00:02:58] stderr: Running post_headers hooks
[DEVELOPER 00:02:58] stderr: Running post_send hooks
[DEVELOPER 00:02:58] stderr: ah_post_send (#0), code is 201 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:02:58] stderr: Request ends, status 201 class 2xx, error line:
[DEVELOPER 00:02:58] stderr: 201 Created
[DEBUG 00:02:58] credentials accepted
[DEBUG 00:02:58] add item status: <status 1.1, code 201, class 2, Created>
[DEBUG 00:02:58] new item mapped to 182b96310e5fbc3f
[DEVELOPER 00:02:58] stderr: Running destroy hooks.
[DEVELOPER 00:02:58] stderr: Request ends.
#0: 182b96310e5fbc3f
[DEBUG 00:02:58] read ctag of /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/
[DEBUG 00:02:58] starting PROPFIND, credentials okay, deadline in 300.0s
[DEVELOPER 00:02:58] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:02:58] stderr: Running pre_send hooks
[DEBUG 00:02:58] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:02:59] stderr: Sending request headers:
[DEVELOPER 00:02:59] stderr: PROPFIND /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/ HTTP/1.1
[DEVELOPER 00:02:59] stderr: Connection: TE
[DEVELOPER 00:02:59] stderr: TE: trailers
[DEVELOPER 00:02:59] stderr: Host: www.googleapis.com
[DEVELOPER 00:02:59] stderr: Depth: 0
[DEVELOPER 00:02:59] stderr: Content-Length: 137
[DEVELOPER 00:02:59] stderr: Content-Type: application/xml
[DEVELOPER 00:02:59] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:02:59] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:02:59] stderr:
[DEVELOPER 00:02:59] stderr: Sending request-line and headers:
[DEVELOPER 00:02:59] stderr: Sending request body:
[DEVELOPER 00:02:59] stderr: Body block (137 bytes):
[DEVELOPER 00:02:59] stderr: [<?xml version="1.0" encoding="utf-8"?>
[DEVELOPER 00:02:59] stderr: <propfind xmlns="DAV:"><prop>
[DEVELOPER 00:02:59] stderr: <getctag xmlns="http://calendarserver.org/ns/"/>
[DEVELOPER 00:02:59] stderr: </prop></propfind>
[DEVELOPER 00:02:59] stderr: ]
[DEVELOPER 00:02:59] stderr: Request sent; retry is 1.
[DEVELOPER 00:02:59] stderr: [status-line] < HTTP/1.1 207 Multi-Status
[DEVELOPER 00:02:59] stderr: [hdr] DAV: 1, 2, 3, addressbook, access-control
[DEVELOPER 00:02:59] stderr: Header Name: [dav], Value: [1, 2, 3, addressbook, access-control]
[DEVELOPER 00:02:59] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:02:59] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:02:59] stderr: [hdr] Content-Type: text/xml; charset=UTF-8
[DEVELOPER 00:02:59] stderr: Header Name: [content-type], Value: [text/xml; charset=UTF-8]
[DEVELOPER 00:02:59] stderr: [hdr] Date: Fri, 05 Jan 2018 16:50:28 GMT
[DEVELOPER 00:02:59] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:50:28 GMT]
[DEVELOPER 00:02:59] stderr: [hdr] Expires: Fri, 05 Jan 2018 16:50:28 GMT
[DEVELOPER 00:02:59] stderr: Header Name: [expires], Value: [Fri, 05 Jan 2018 16:50:28 GMT]
[DEVELOPER 00:02:59] stderr: [hdr] Cache-Control: private, max-age=0
[DEVELOPER 00:02:59] stderr: Header Name: [cache-control], Value: [private, max-age=0]
[DEVELOPER 00:02:59] stderr: [hdr] X-Content-Type-Options: nosniff
[DEVELOPER 00:02:59] stderr: Header Name: [x-content-type-options], Value: [nosniff]
[DEVELOPER 00:02:59] stderr: [hdr] X-Frame-Options: SAMEORIGIN
[DEVELOPER 00:02:59] stderr: Header Name: [x-frame-options], Value: [SAMEORIGIN]
[DEVELOPER 00:02:59] stderr: [hdr] X-XSS-Protection: 1; mode=block
[DEVELOPER 00:02:59] stderr: Header Name: [x-xss-protection], Value: [1; mode=block]
[DEVELOPER 00:02:59] stderr: [hdr] Server: GSE
[DEVELOPER 00:02:59] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:02:59] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:02:59] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:02:59] stderr: [hdr] Accept-Ranges: none
[DEVELOPER 00:02:59] stderr: Header Name: [accept-ranges], Value: [none]
[DEVELOPER 00:02:59] stderr: [hdr] Vary: Origin,Accept-Encoding
[DEVELOPER 00:02:59] stderr: Header Name: [vary], Value: [Origin,Accept-Encoding]
[DEVELOPER 00:02:59] stderr: [hdr] Transfer-Encoding: chunked
[DEVELOPER 00:02:59] stderr: Header Name: [transfer-encoding], Value: [chunked]
[DEVELOPER 00:02:59] stderr: [hdr]
[DEVELOPER 00:02:59] stderr: End of headers.
[DEVELOPER 00:02:59] stderr: Running post_headers hooks
[DEVELOPER 00:02:59] stderr: [chunk] < 202
[DEVELOPER 00:02:59] stderr: Got chunk size: 514
[DEVELOPER 00:02:59] stderr: Reading 514 bytes of response body.
[DEVELOPER 00:02:59] stderr: Got 514 bytes.
[DEVELOPER 00:02:59] stderr: Read block (514 bytes):
[DEVELOPER 00:02:59] stderr: [<?xml version="1.0" encoding="UTF-8"?>
[DEVELOPER 00:02:59] stderr: <d:multistatus xmlns:cal="urn:ietf:params:xml:ns:caldav" xmlns:card="urn:ietf:params:xml:ns:carddav" xmlns:cs="http://calendarserver.org/ns/" xmlns:d="DAV:" xmlns:ical="http://apple.com/ns/ical/">
[DEVELOPER 00:02:59] stderr:  <d:response>
[DEVELOPER 00:02:59] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/</d:href>
[DEVELOPER 00:02:59] stderr:   <d:propstat>
[DEVELOPER 00:02:59] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:02:59] stderr:    <d:prop>
[DEVELOPER 00:02:59] stderr:     <cs:getctag>"e4b73638902948c7.1"</cs:getctag>
[DEVELOPER 00:02:59] stderr:    </d:prop>
[DEVELOPER 00:02:59] stderr:   </d:propstat>
[DEVELOPER 00:02:59] stderr:  </d:response>
[DEVELOPER 00:02:59] stderr: </d:multistatus>
[DEVELOPER 00:02:59] stderr: ]
[DEVELOPER 00:02:59] stderr: [chunk] < 0
[DEVELOPER 00:02:59] stderr: Got chunk size: 0
[DEVELOPER 00:02:59] stderr: [hdr]
[DEVELOPER 00:02:59] stderr: End of headers.
[DEVELOPER 00:02:59] stderr: Running post_send hooks
[DEVELOPER 00:02:59] stderr: ah_post_send (#0), code is 207 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:02:59] stderr: Request ends, status 207 class 2xx, error line:
[DEVELOPER 00:02:59] stderr: 207 Multi-Status
[DEBUG 00:02:59] credentials accepted
[DEVELOPER 00:02:59] stderr: Running destroy hooks.
[DEVELOPER 00:02:59] stderr: Request ends.
[DEBUG 00:02:59] ClientTest.cpp:6192: ending CPPUNIT_ASSERT_MESSAGE(peer + " " + peerSource, (cmdline->run()))
[DEBUG 00:02:59] ClientTest.cpp:6195: starting doSync(logPrefix, options)
[DEBUG 00:02:59] 1. starting Client_Sync_eds_contact_testDownload.download.client.A with sync mode refresh-from-remote
[DEBUG 00:02:59] ClientTest.cpp:6460: starting res = client.doSync(sourceArray, logname, options)
[DEBUG 00:02:59] instantiating testing source eds_contact in config target-config@client-test-googlecontacts, with tracking name _1_A
[DEBUG 00:02:59] overriding testing source eds_contact properties with the ones from config googlecontacts_1 = /data/runtests/work/testing-amd64/home/googlecontacts/config/syncevolution/client-test-googlecontacts-1/peers/googlecontacts_1
[DEBUG 00:02:59]    sync = two-way (set)
[DEBUG 00:02:59]    uri = carddav (set)
[DEBUG 00:02:59]    backend = Evolution Address Book (set)
[DEBUG 00:02:59]    syncFormat =  (default)
[DEBUG 00:02:59]    forceSyncFormat = 0 (default)
[DEBUG 00:02:59]    database = Test_eds_contact_1 (set)
[DEBUG 00:02:59]    databaseFormat =  (default)
[DEBUG 00:02:59]    databaseUser =  (default)
[DEBUG 00:02:59]    databasePassword =  (default)
[DEBUG 00:03:00] log path -> /data/runtests/work/testing-amd64/home/googlecontacts/cache/syncevolution, No such file or directory
[DEBUG 00:03:00] log path -> Client_Sync_eds_contact_testDownload.download.client.A, No such file or directory
[DEBUG 00:03:00] log path -> Client_Sync_eds_contact_testDownload.download.client.A, No such file or directory
[DEBUG 00:03:00] checking log dir Client_Sync_eds_contact_testDownload.download.client.A
[DEBUG 00:03:00] logfile: Client_Sync_eds_contact_testDownload.download.client.A/syncevolution-log.html
[WARNING 00:03:00] googlecontacts_1@client-test-googlecontacts-1: ignoring username , it is not needed
[DEBUG 00:03:01] CreateContext SyncEvolution// => 0
[DEBUG 00:03:01] Module_Capabilities:
[DEBUG 00:03:01] PLATFORM:Linux
[DEBUG 00:03:01] DLL:true
[DEBUG 00:03:01] MINVERSION:V1.0.6.0
[DEBUG 00:03:01] MANUFACTURER:SyncEvolution
[DEBUG 00:03:01] DESCRIPTION:SyncEvolution Synthesis DB Plugin
[DEBUG 00:03:01] plugin_datastore_str:no
[DEBUG 00:03:01] plugin_datastore_key:yes
[DEBUG 00:03:01] ITEM_AS_KEY:yes
[DEBUG 00:03:01] plugin_datablob:no
[DEBUG 00:03:01] Module_PluginParams
[DEBUG 00:03:01]  Engine=01090100
[DEBUG 00:03:01]
[DEVELOPER 00:03:03] SyncML server account:
[DEVELOPER 00:03:03] client: SyncEvolution 1.5.3 for workstation
[DEVELOPER 00:03:03] device ID: syncevolution-6058a162-5fc4-4d3e-969a-7095a50343dc
[DEVELOPER 00:03:03]
[DEVELOPER 00:03:03]
[INFO 00:03:03] @client-test-googlecontacts-1/addressbook: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/calendar: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/calendar+todo: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/eas_contact: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/eas_event: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/eas_memo: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/eas_task: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/eds_event: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/eds_memo: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/eds_task: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/file_calendar+todo: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/file_contact: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/file_event: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/file_task: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/google_carddav: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/kde_contact: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/kde_event: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/kde_memo: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/kde_task: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/memo: inactive
[INFO 00:03:03] @client-test-googlecontacts-1/todo: inactive
[DEBUG 00:03:04] checking password property 'password' in config 'googlecontacts_1@client-test-googlecontacts-1' with user identity ''
[DEBUG 00:03:04] checking password property 'databasePassword' in datastore 'eds_contact' of config 'googlecontacts_1@client-test-googlecontacts-1' with user identity ''
[DEBUG 00:03:04] sync is starting, catch signals
[DEBUG 00:03:04] SuspendFlags: (re)activating, currently inactive
[DEBUG 00:03:04] SuspendFlags: activating signal handler(s) with fds 35->34
[DEBUG 00:03:04] SuspendFlags: catch signal 15
[DEBUG 00:03:04] ready to sync
[WARNING 00:03:04] googlecontacts_1@client-test-googlecontacts-1: ignoring username , it is not needed
[DEBUG 00:03:04] Module_DeleteContext 'session'
[DEBUG 00:03:04] CreateContext SyncEvolution// => 0
[DEBUG 00:03:04] Module_Capabilities:
[DEBUG 00:03:04] PLATFORM:Linux
[DEBUG 00:03:04] DLL:true
[DEBUG 00:03:04] MINVERSION:V1.0.6.0
[DEBUG 00:03:04] MANUFACTURER:SyncEvolution
[DEBUG 00:03:04] DESCRIPTION:SyncEvolution Synthesis DB Plugin
[DEBUG 00:03:04] plugin_datastore_str:no
[DEBUG 00:03:04] plugin_datastore_key:yes
[DEBUG 00:03:04] ITEM_AS_KEY:yes
[DEBUG 00:03:04] plugin_datablob:no
[DEBUG 00:03:04] Module_PluginParams
[DEBUG 00:03:04]  Engine=01090100
[DEBUG 00:03:04]
[DEBUG 00:03:04] CreateContext SyncEvolution//eds_contact => 0
[DEBUG 00:03:04] Module_Version = 01090100
[DEBUG 00:03:04] Module_Capabilities:
[DEBUG 00:03:04] PLATFORM:Linux
[DEBUG 00:03:04] DLL:true
[DEBUG 00:03:04] MINVERSION:V1.0.6.0
[DEBUG 00:03:04] MANUFACTURER:SyncEvolution
[DEBUG 00:03:04] DESCRIPTION:SyncEvolution Synthesis DB Plugin
[DEBUG 00:03:04] plugin_datastore_str:no
[DEBUG 00:03:04] plugin_datastore_key:yes
[DEBUG 00:03:04] ITEM_AS_KEY:yes
[DEBUG 00:03:04] plugin_datablob:yes
[DEBUG 00:03:04] plugin_datastoreadmin:yes
[DEBUG 00:03:04] @client-test-googlecontacts-1/eds_contact: Module_PluginParams
[DEBUG 00:03:04] @client-test-googlecontacts-1/eds_contact:  Engine=01090100
[DEBUG 00:03:04] @client-test-googlecontacts-1/eds_contact:
[DEBUG 00:03:04] Module_Capabilities:
[DEBUG 00:03:04] PLATFORM:Linux
[DEBUG 00:03:04] DLL:true
[DEBUG 00:03:04] MINVERSION:V1.0.6.0
[DEBUG 00:03:04] MANUFACTURER:SyncEvolution
[DEBUG 00:03:04] DESCRIPTION:SyncEvolution Synthesis DB Plugin
[DEBUG 00:03:04] plugin_datastore_str:no
[DEBUG 00:03:04] plugin_datastore_key:yes
[DEBUG 00:03:04] ITEM_AS_KEY:yes
[DEBUG 00:03:04] plugin_datablob:yes
[DEBUG 00:03:04] plugin_datastoreadmin:yes
[DEBUG 00:03:04] CreateContext SyncEvolution//eds_contact => 0
[DEBUG 00:03:04] Module_Version = 01090100
[DEBUG 00:03:04] Module_Capabilities:
[DEBUG 00:03:04] PLATFORM:Linux
[DEBUG 00:03:04] DLL:true
[DEBUG 00:03:04] MINVERSION:V1.0.6.0
[DEBUG 00:03:04] MANUFACTURER:SyncEvolution
[DEBUG 00:03:04] DESCRIPTION:SyncEvolution Synthesis DB Plugin
[DEBUG 00:03:04] plugin_datastore_str:no
[DEBUG 00:03:04] plugin_datastore_key:yes
[DEBUG 00:03:04] ITEM_AS_KEY:yes
[DEBUG 00:03:04] plugin_datablob:yes
[DEBUG 00:03:04] plugin_datastoreadmin:yes
[DEBUG 00:03:04] @client-test-googlecontacts-1/eds_contact: Module_PluginParams
[DEBUG 00:03:04] @client-test-googlecontacts-1/eds_contact:  Engine=01090100
[DEBUG 00:03:04] @client-test-googlecontacts-1/eds_contact:
[DEBUG 00:03:04] ForkExecParent: preparing for child process syncevo-local-sync
[DEBUG 00:03:04] ForkExecParent: running syncevo-local-sync with D-Bus address 39
[DEBUG 00:03:04] ForkExecParent: child process for syncevo-local-sync has pid 348
[DEBUG 00:03:05] Session_CreateContext '6227663099111955023' found
[DEBUG 00:03:05] waiting for child to send message
[DEBUG 00:03:05] ForkExecParent: child syncevo-local-sync 348 has connected
[DEBUG 00:03:05] child is ready
[DEBUG 00:03:11] ForkExecParentDBusAPI /org/syncevolution/forkexec/parent/forkexec1: received 'Watch' method call from child
[INFO @client-test-googlecontacts 00:03:12] target side of local sync ready
[INFO @client-test-googlecontacts 00:03:15] @client-test-googlecontacts/eds_contact: inactive
[INFO @client-test-googlecontacts 00:03:15] @client-test-googlecontacts/google_carddav: inactive
[INFO @client-test-googlecontacts 00:03:15] @client-test-googlecontacts/carddav: using configured database=https://www.googleapis.com:443/carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/
[DEBUG 00:03:18] Session_CheckDevice dev='syncevolution-3ad8173d-16d5-455f-8cd8-55a037c9010b' nonce='' res=0
[DEBUG 00:03:18] Session_SaveDeviceInfo info='REMOTE_URI:syncevolution-3ad8173d-16d5-455f-8cd8-55a037c9010b
[DEBUG 00:03:18] REMOTE_DESC:Patrick Ohly SyncEvolution
[DEBUG 00:03:18] REMOTE_INFO:workstation (unknown, 1.5.3, 3.4.0.47) Synthesis AG
[DEBUG 00:03:18] DOMAIN:
[DEBUG 00:03:18] MOD:SyncEvolution
[DEBUG 00:03:18] MAN:Patrick Ohly
[DEBUG 00:03:18] OEM:Synthesis AG
[DEBUG 00:03:18] FWV:1.5.3
[DEBUG 00:03:18] SWV:3.4.0.47
[DEBUG 00:03:18] HWV:unknown
[DEBUG 00:03:18] '
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: 'eds_contact' dev='syncevolution-3ad8173d-16d5-455f-8cd8-55a037c9010b' usr='anonymous' err=0
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: 'eds_contact' dev='syncevolution-3ad8173d-16d5-455f-8cd8-55a037c9010b' usr='anonymous' err=0
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: LoadAdminData 'eds_contact' './carddav', 'remotesyncanchor:20180105T164356Z
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: lastsync:20180105T164356Z
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: lasttoremotesync:20180105T164356Z
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: lasttoremotesyncid:1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: resumealertcode:0
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: lastsuspend:20180105T164356Z
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: lastsuspendid:
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: partialitemstate:0
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: lastitemstatus:0
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: lastsourceURI:
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: lasttargetURI:
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: totalsize:0
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: unconfirmedsize:0
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: storedsize:0
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: stored;BLOBID=PIStored
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ' res=0
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-1cd8dcf38d6543298061cbf78a04fe6287ee7f9d' + 1 = '5c4d669a8813dff3' + 0 first=yes res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-3db35e8b66d27840925db13c06083233a9488518' + 1 = '4378b3f8b22ad6b' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-501e93065ba8a482077325f8cf3b65e0ebfadb22' + 1 = '26481c8188ac137b' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-5a00f1695bb59abbcb09d9b5419968474049d5b9' + 1 = '51c1b14e8df7be34' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-759b3f96f21e22b05a2b714cc21821806405124d' + 1 = '29aeaa900ba7666a' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-7f576509051b54aa697c929e08120a7788a97947' + 1 = '5d7cbeb30e59bc30' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-8f07eeb7642ae9fee23293414d54642c554ecb3d' + 1 = '1c71018c8bd69cc4' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-8fa9be4bcb1e1b1b8523d02c98920197dec4ebb6' + 1 = '5e31096508bc4a44' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-97ef60eec4385fd47f2fd1b02c415f73b3c7f700' + 1 = '5fa30f530d0ba0ad' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-b394cb8eeb6abc039f39ed18415f83d175268bf3' + 1 = '6d68259e0ee5552f' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-b6c84e389c03d080899a71978693e302a2ead99c' + 1 = '4faf3db70c866603' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-bc5d2ef71d9429870f8a8c561927abfc2ff1b6c3' + 1 = '3c26379f88ef2824' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-d5dcdc18856ea6f2e1401e90fc3ec82213d055e3' + 1 = '52e2bbb888e90e26' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-def5a0bc4ccdeaa9998ec72fc3c9b6251bba591d' + 1 = '10754d30085d14f7' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-ee9da8fc79a88eae7d211ad0bc462c198b4797d5' + 1 = '727bb5298c07d8c5' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-fd945a0d2525d6faac80744e34b054c8b189e5e8' + 1 = '4296442f88a61b98' + 0 first=no res=1
[DEBUG 00:03:18] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem '(none)' + 0 = '(none)' + 0 first=no res=0
[DEBUG 00:03:19] @client-test-googlecontacts-1/eds_contact: total number of items received 0
[DEBUG 00:03:19] waiting for child to send message
[INFO @client-test-googlecontacts 00:03:19] @client-test-googlecontacts/carddav: starting first time sync from client (peer is server)
[INFO @client-test-googlecontacts 00:03:22] @client-test-googlecontacts/carddav: sent 1/1
[INFO 00:03:22] @client-test-googlecontacts-1/eds_contact: starting slow sync from client (peer is client)
[DEBUG 00:03:22] reading: set read-ahead based on sync mode refresh-from-remote
[DEBUG 00:03:22] @client-test-googlecontacts-1/eds_contact: reading: set order 'none', 0 luids
[DEBUG 00:03:22] @client-test-googlecontacts-1/eds_contact: FilterSupport staticfilter:
[DEBUG 00:03:22] @client-test-googlecontacts-1/eds_contact: dynamicfilter:
[DEBUG 00:03:22] @client-test-googlecontacts-1/eds_contact: invisiblefilter:
[DEBUG 00:03:22] @client-test-googlecontacts-1/eds_contact: ContextSupport ReadNextItem:allfields
[DEBUG 00:03:22] @client-test-googlecontacts-1/eds_contact:
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: using full item scan to detect changes
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: StartDataRead last='1' resume='' res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-1cd8dcf38d6543298061cbf78a04fe6287ee7f9d,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-3db35e8b66d27840925db13c06083233a9488518,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-501e93065ba8a482077325f8cf3b65e0ebfadb22,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-5a00f1695bb59abbcb09d9b5419968474049d5b9,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-759b3f96f21e22b05a2b714cc21821806405124d,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-7f576509051b54aa697c929e08120a7788a97947,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-8f07eeb7642ae9fee23293414d54642c554ecb3d,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-8fa9be4bcb1e1b1b8523d02c98920197dec4ebb6,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-97ef60eec4385fd47f2fd1b02c415f73b3c7f700,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-b394cb8eeb6abc039f39ed18415f83d175268bf3,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-b6c84e389c03d080899a71978693e302a2ead99c,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-bc5d2ef71d9429870f8a8c561927abfc2ff1b6c3,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-d5dcdc18856ea6f2e1401e90fc3ec82213d055e3,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-def5a0bc4ccdeaa9998ec72fc3c9b6251bba591d,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-ee9da8fc79a88eae7d211ad0bc462c198b4797d5,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=2 aID=(pas-id-fd945a0d2525d6faac80744e34b054c8b189e5e8,(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=0 aID=((null),(null)) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: EndDataRead res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: StartDataWrite
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteSyncSet not implemented
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-1cd8dcf38d6543298061cbf78a04fe6287ee7f9d
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "quotation marks"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-1cd8dcf38d6543298061cbf78a04fe6287ee7f9d,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-3db35e8b66d27840925db13c06083233a9488518
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "line breaks"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-3db35e8b66d27840925db13c06083233a9488518,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-501e93065ba8a482077325f8cf3b65e0ebfadb22
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "Umlaut Ä Ö Ü ß"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-501e93065ba8a482077325f8cf3b65e0ebfadb22,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-5a00f1695bb59abbcb09d9b5419968474049d5b9
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "parserbug="
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-5a00f1695bb59abbcb09d9b5419968474049d5b9,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-759b3f96f21e22b05a2b714cc21821806405124d
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "large vcard"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-759b3f96f21e22b05a2b714cc21821806405124d,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-7f576509051b54aa697c929e08120a7788a97947
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "long line"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-7f576509051b54aa697c929e08120a7788a97947,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-8f07eeb7642ae9fee23293414d54642c554ecb3d
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "Mr. First ; special ; middle Last"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-8f07eeb7642ae9fee23293414d54642c554ecb3d,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-8fa9be4bcb1e1b1b8523d02c98920197dec4ebb6
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "special characters"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-8fa9be4bcb1e1b1b8523d02c98920197dec4ebb6,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-97ef60eec4385fd47f2fd1b02c415f73b3c7f700
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "Ms. JPG"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-97ef60eec4385fd47f2fd1b02c415f73b3c7f700,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-b394cb8eeb6abc039f39ed18415f83d175268bf3
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "xml entities"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-b394cb8eeb6abc039f39ed18415f83d175268bf3,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-b6c84e389c03d080899a71978693e302a2ead99c
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "Mr. GIF"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-b6c84e389c03d080899a71978693e302a2ead99c,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-bc5d2ef71d9429870f8a8c561927abfc2ff1b6c3
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "colon"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-bc5d2ef71d9429870f8a8c561927abfc2ff1b6c3,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-d5dcdc18856ea6f2e1401e90fc3ec82213d055e3
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "incomplete"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-d5dcdc18856ea6f2e1401e90fc3ec82213d055e3,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-def5a0bc4ccdeaa9998ec72fc3c9b6251bba591d
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "John Doe"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-def5a0bc4ccdeaa9998ec72fc3c9b6251bba591d,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-ee9da8fc79a88eae7d211ad0bc462c198b4797d5
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "Mrs. PNG"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-ee9da8fc79a88eae7d211ad0bc462c198b4797d5,) res=0
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-fd945a0d2525d6faac80744e34b054c8b189e5e8
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting "spouse name"
[DEBUG 00:03:23] @client-test-googlecontacts-1/eds_contact: DeleteItem aID=(pas-id-fd945a0d2525d6faac80744e34b054c8b189e5e8,) res=0
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: deleting 16/16
[INFO 00:03:23] @client-test-googlecontacts-1/eds_contact: started
[INFO 00:03:24] @client-test-googlecontacts-1/eds_contact: adding "John 1 Doe"
[DEBUG 00:03:24] @client-test-googlecontacts-1/eds_contact: add operation #0: queueing for batched add
[DEBUG 00:03:24] @client-test-googlecontacts-1/eds_contact: InsertItemAsKey res=1
[DEBUG 00:03:24] @client-test-googlecontacts-1/eds_contact: batch add of 1 contacts starting
[DEBUG 00:03:24] @client-test-googlecontacts-1/eds_contact: waiting for 1 pending operations to complete
[DEBUG 00:03:24] @client-test-googlecontacts-1/eds_contact: batch add of 1 contacts completed
[DEBUG 00:03:24] @client-test-googlecontacts-1/eds_contact: add operation #0: completed: <<successfully>>
[DEBUG 00:03:24] @client-test-googlecontacts-1/eds_contact: pending operations completed
[DEBUG 00:03:24] @client-test-googlecontacts-1/eds_contact: add operation #0: checking operation: inserted
[DEBUG 00:03:24] @client-test-googlecontacts-1/eds_contact: InsertItemAsKey res=0
[DEBUG 00:03:24] @client-test-googlecontacts-1/eds_contact: total number of items received 0
[INFO 00:03:24] @client-test-googlecontacts-1/eds_contact: received 1
[DEBUG 00:03:24] waiting for child to send message
[INFO @client-test-googlecontacts 00:03:24] @client-test-googlecontacts/carddav: started
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: EndDataWrite COMMIT '1' res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: InsertMapItem 'pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d' + 1 = '182b96310e5fbc3f' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-1cd8dcf38d6543298061cbf78a04fe6287ee7f9d' + 1 = '5c4d669a8813dff3' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-3db35e8b66d27840925db13c06083233a9488518' + 1 = '4378b3f8b22ad6b' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-501e93065ba8a482077325f8cf3b65e0ebfadb22' + 1 = '26481c8188ac137b' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-5a00f1695bb59abbcb09d9b5419968474049d5b9' + 1 = '51c1b14e8df7be34' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-759b3f96f21e22b05a2b714cc21821806405124d' + 1 = '29aeaa900ba7666a' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-7f576509051b54aa697c929e08120a7788a97947' + 1 = '5d7cbeb30e59bc30' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-8f07eeb7642ae9fee23293414d54642c554ecb3d' + 1 = '1c71018c8bd69cc4' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-8fa9be4bcb1e1b1b8523d02c98920197dec4ebb6' + 1 = '5e31096508bc4a44' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-97ef60eec4385fd47f2fd1b02c415f73b3c7f700' + 1 = '5fa30f530d0ba0ad' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-b394cb8eeb6abc039f39ed18415f83d175268bf3' + 1 = '6d68259e0ee5552f' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-b6c84e389c03d080899a71978693e302a2ead99c' + 1 = '4faf3db70c866603' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-bc5d2ef71d9429870f8a8c561927abfc2ff1b6c3' + 1 = '3c26379f88ef2824' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-d5dcdc18856ea6f2e1401e90fc3ec82213d055e3' + 1 = '52e2bbb888e90e26' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-def5a0bc4ccdeaa9998ec72fc3c9b6251bba591d' + 1 = '10754d30085d14f7' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-ee9da8fc79a88eae7d211ad0bc462c198b4797d5' + 1 = '727bb5298c07d8c5' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteMapItem 'pas-id-fd945a0d2525d6faac80744e34b054c8b189e5e8' + 1 = '4296442f88a61b98' + 0 res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: SaveAdminData 'remotesyncanchor:20180105T165047Z
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: lastsync:20180105T165048Z
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: lasttoremotesync:20180105T165048Z
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: lasttoremotesyncid:1
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: resumealertcode:0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: lastsuspend:20180105T165048Z
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: lastsuspendid:
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: partialitemstate:0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: lastitemstatus:0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: lastsourceURI:
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: lasttargetURI:
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: totalsize:0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: unconfirmedsize:0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: storedsize:0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: stored;BLOBID=PIStored
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: ' res=0
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteBlob aID=(,) aBlobID=(PIStored) res=0
[INFO 00:03:25] @client-test-googlecontacts-1/eds_contact: slow sync done successfully
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: total number of items received 1
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteContext
[DEBUG 00:03:25] @client-test-googlecontacts-1/eds_contact: DeleteContext
[DEBUG 00:03:25] parent is shutting down
[DEBUG 00:03:25] waiting for child to stop
[INFO @client-test-googlecontacts 00:03:25] @client-test-googlecontacts/carddav: first time sync done successfully
[DEBUG 00:03:25] waiting for child to stop
[DEBUG 00:03:25] got child sync report:
[DEBUG 00:03:25] end = 1515171055
[DEBUG 00:03:25] source-carddav-backup-after = -1
[DEBUG 00:03:25] source-carddav-backup-before = -1
[DEBUG 00:03:25] source-carddav-first = true
[DEBUG 00:03:25] source-carddav-mode = refresh-from-local
[DEBUG 00:03:25] source-carddav-resume = false
[DEBUG 00:03:25] source-carddav-stat-local-any-sent = 2162
[DEBUG 00:03:25] source-carddav-stat-remote-added-total = 1
[DEBUG 00:03:25] source-carddav-status = 0
[DEBUG 00:03:25] start = 1515171041
[DEBUG 00:03:25] status = 200
[DEVELOPER 00:03:26] stderr: sess: Destroying session.
[DEBUG 00:03:26] child process has quit with status 0
[DEBUG 00:03:26] ForkExecParent: shutting down, telling syncevo-local-sync 348 that it lost the connection, it is watching
[DEBUG 00:03:26] ForkExecParentDBusAPI /org/syncevolution/forkexec/parent/forkexec1: destroying with 1 active watches
[DEBUG 00:03:26] closing session
[DEBUG 00:03:26] session closed
[DEBUG 00:03:26] SuspendFlags: deactivating fds 35->34
[DEBUG 00:03:26] SuspendFlags: close m_receiverFD 34
[DEBUG 00:03:26] SuspendFlags: close m_senderFD 35
[DEBUG 00:03:26] SuspendFlags: done with deactivation
[DEBUG 00:03:26] Module_DeleteContext eds_contact
[DEBUG 00:03:26] Module_DeleteContext eds_contact
[DEBUG 00:03:26] Module_DeleteContext 'session'
[INFO 00:03:26] sync report:
[INFO 00:03:26] +---------------|-----------------------|-----------------------|-CON-+
[INFO 00:03:26] |               |@client-test-googlecontacts-1|@client-test-googlecontacts| FLI |
[INFO 00:03:26] |        Source | NEW | MOD | DEL | ERR | NEW | MOD | DEL | ERR | CTS |
[INFO 00:03:26] +---------------+-----+-----+-----+-----+-----+-----+-----+-----+-----+
[INFO 00:03:26] |   eds_contact |  1  |  0  | 16  |  0  |  0  |  0  |  0  |  0  |  0  |
[INFO 00:03:26] |   refresh-from-remote, 0 KB sent by client, 2 KB received           |
[INFO 00:03:26] +---------------+-----+-----+-----+-----+-----+-----+-----+-----+-----+
[INFO 00:03:26] |          start Fri Jan  5 16:50:29 2018, duration 0:27min           |
[INFO 00:03:26] |               synchronization completed successfully                |
[INFO 00:03:26] +---------------+-----+-----+-----+-----+-----+-----+-----+-----+-----+
[INFO 00:03:26] ----------|--------CLIENT---------|--------SERVER---------|
[INFO 00:03:26]           |  NEW  |  MOD  |  DEL  |  NEW  |  MOD  |  DEL  |
[INFO 00:03:26] ----------|-----------------------------------------------|
[INFO 00:03:26] Expected  |   -1  |    0  |   -1  |    0  |    0  |    0  |
[INFO 00:03:26] Expected sync mode: refresh-from-remote
[INFO 00:03:26] Expected cycles: 1
[INFO 00:03:26]
[DEBUG 00:03:26] ClientTest.cpp:8283: starting CPPUNIT_ASSERT_EQUAL((string("no error (remote, status 0)")),(Status2String(status)))
[DEBUG 00:03:26] ClientTest.cpp:8283: ending CPPUNIT_ASSERT_EQUAL((string("no error (remote, status 0)")),(Status2String(status)))
[DEBUG 00:03:26] ClientTest.cpp:8284: starting CPPUNIT_ASSERT_EQUAL((STATUS_OK),(status))
[DEBUG 00:03:26] ClientTest.cpp:8284: ending CPPUNIT_ASSERT_EQUAL((STATUS_OK),(status))
[DEBUG 00:03:26] Checking sync source eds_contact...
[DEBUG 00:03:26] ClientTest.cpp:8293: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "STATUS_OK" + " == " + "source.getStatus()",(STATUS_OK),(source.getStatus()))
[DEBUG 00:03:26] ClientTest.cpp:8293: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "STATUS_OK" + " == " + "source.getStatus()",(STATUS_OK),(source.getStatus()))
[DEBUG 00:03:26] ClientTest.cpp:8305: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "0" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)",(0),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)))
[DEBUG 00:03:26] ClientTest.cpp:8305: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "0" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)",(0),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)))
[DEBUG 00:03:26] ClientTest.cpp:8308: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "0" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)",(0),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)))
[DEBUG 00:03:26] ClientTest.cpp:8308: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "0" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)",(0),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)))
[DEBUG 00:03:26] ClientTest.cpp:8319: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "syncMode" + " == " + "source.getFinalSyncMode()",(syncMode),(source.getFinalSyncMode()))
[DEBUG 00:03:26] ClientTest.cpp:8319: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "syncMode" + " == " + "source.getFinalSyncMode()",(syncMode),(source.getFinalSyncMode()))
[DEBUG 00:03:26] ClientTest.cpp:8322: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "restarts + 1" + " == " + "source.getRestarts() + 1",(restarts + 1),(source.getRestarts() + 1))
[DEBUG 00:03:26] ClientTest.cpp:8322: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "restarts + 1" + " == " + "source.getRestarts() + 1",(restarts + 1),(source.getRestarts() + 1))
[DEBUG 00:03:26] ClientTest.cpp:8334: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "clientUpdated" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)",(clientUpdated),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:03:26] ClientTest.cpp:8334: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "clientUpdated" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)",(clientUpdated),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:03:26] ClientTest.cpp:8347: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "serverAdded" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)",(serverAdded),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:03:26] ClientTest.cpp:8347: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "serverAdded" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)",(serverAdded),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:03:26] ClientTest.cpp:8353: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "serverUpdated" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)",(serverUpdated),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:03:26] ClientTest.cpp:8353: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "serverUpdated" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)",(serverUpdated),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:03:26] ClientTest.cpp:8359: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "serverDeleted" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)",(serverDeleted),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:03:26] ClientTest.cpp:8359: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "serverDeleted" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)",(serverDeleted),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:03:26] Done with checking sync report.
[DEBUG 00:03:26] ClientTest.cpp:6460: ending res = client.doSync(sourceArray, logname, options)
[DEBUG 00:03:26] ClientTest.cpp:6465: starting postSync(res, logname)
[DEBUG 00:03:26] ClientTest.cpp:6465: ending postSync(res, logname)
[DEBUG 00:03:26] ClientTest.cpp:6195: ending doSync(logPrefix, options)
[DEBUG 00:03:26] ClientTest.cpp:6202: starting copy.reset(sources[0].second->createSourceA(), TestingSyncSourcePtr::SLOW)
[DEBUG 00:03:26] instantiating testing source eds_contact in config target-config@client-test-googlecontacts, with tracking name _1_A
[DEBUG 00:03:26] overriding testing source eds_contact properties with the ones from config googlecontacts_1 = /data/runtests/work/testing-amd64/home/googlecontacts/config/syncevolution/client-test-googlecontacts-1/peers/googlecontacts_1
[DEBUG 00:03:26]    sync = two-way (set)
[DEBUG 00:03:26]    uri = carddav (set)
[DEBUG 00:03:26]    backend = Evolution Address Book (set)
[DEBUG 00:03:26]    syncFormat =  (default)
[DEBUG 00:03:26]    forceSyncFormat = 0 (default)
[DEBUG 00:03:26]    database = Test_eds_contact_1 (set)
[DEBUG 00:03:26]    databaseFormat =  (default)
[DEBUG 00:03:26]    databaseUser =  (default)
[DEBUG 00:03:26]    databasePassword =  (default)
[DEBUG 00:03:27] ClientTest.cpp:424: starting CPPUNIT_ASSERT(get())
[DEBUG 00:03:27] ClientTest.cpp:424: ending CPPUNIT_ASSERT(get())
[DEBUG 00:03:27] ClientTest.cpp:425: starting CPPUNIT_ASSERT(!m_active)
[DEBUG 00:03:27] ClientTest.cpp:425: ending CPPUNIT_ASSERT(!m_active)
[DEBUG 00:03:27] ClientTest.cpp:431: starting get()->open()
[DEBUG 00:03:27] ClientTest.cpp:431: ending get()->open()
[DEBUG 00:03:27] eds_contact: slow sync or testing, do full item scan to detect changes
[DEBUG 00:03:27] ClientTest.cpp:439: starting get()->enableServerMode()
[DEBUG 00:03:27] ClientTest.cpp:439: ending get()->enableServerMode()
[DEBUG 00:03:27] ClientTest.cpp:6202: ending copy.reset(sources[0].second->createSourceA(), TestingSyncSourcePtr::SLOW)
[DEBUG 00:03:27] ClientTest.cpp:6202: starting CPPUNIT_ASSERT((copy.get()))
[DEBUG 00:03:27] ClientTest.cpp:6202: ending CPPUNIT_ASSERT((copy.get()))
[DEBUG 00:03:27] ClientTest.cpp:6205: starting CPPUNIT_ASSERT(sources[0].second->compareDatabases(localTestdata.c_str(), *copy))
[DEBUG 00:03:27] ClientTest.cpp:846: starting CPPUNIT_ASSERT(config.m_dump)
[DEBUG 00:03:27] ClientTest.cpp:846: ending CPPUNIT_ASSERT(config.m_dump)
[DEBUG 00:03:27] ClientTest.cpp:863: starting CPPUNIT_ASSERT_EQUAL((0),(config.m_dump(client, copy, copyFile)))
[DEBUG 00:03:27] ClientTest.cpp:6698: starting CPPUNIT_ASSERT(source.getOperations().m_backupData)
[DEBUG 00:03:27] ClientTest.cpp:6698: ending CPPUNIT_ASSERT(source.getOperations().m_backupData)
[DEBUG 00:03:27] eds_contact: reading: set order 'selected', 1 luids
[DEBUG 00:03:27] eds_contact: reading: getting contact pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:27] eds_contact: reading: must read contact pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:27] eds_contact: reading: started contact read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1)
[DEBUG 00:03:27] eds_contact: reading: active cache pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1)
[DEBUG 00:03:27] eds_contact: reading: in running cache
[DEBUG 00:03:27] eds_contact: reading: contact read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1) finished: <<successful>>
[DEBUG 00:03:27] eds_contact: reading: contact read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1) got pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:27] eds_contact: reading: in cache, available
[DEBUG 00:03:27] eds_contact: reading: continue after contact pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:27] eds_contact: reading: read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d: <<okay>>
[DEBUG 00:03:27] eds_contact: requested 1, retrieved 1 from DB in 1 queries, misses 0/1 (0%), stalls 1
[DEBUG 00:03:27] eds_contact: reading: set order 'none', 0 luids
[DEBUG 00:03:27] ClientTest.cpp:863: ending CPPUNIT_ASSERT_EQUAL((0),(config.m_dump(client, copy, copyFile)))
[DEBUG 00:03:27] ClientTest.cpp:871: starting equal = config.m_compare(client, refFile.c_str(), actualFile.c_str())
[DEBUG 00:03:27] ClientTest.cpp:871: ending equal = config.m_compare(client, refFile.c_str(), actualFile.c_str())
[DEBUG 00:03:27] ClientTest.cpp:872: starting CPPUNIT_ASSERT(!raiseAssert || equal)
[DEBUG 00:03:27] ClientTest.cpp:872: ending CPPUNIT_ASSERT(!raiseAssert || equal)
[DEBUG 00:03:27] ClientTest.cpp:6205: ending CPPUNIT_ASSERT(sources[0].second->compareDatabases(localTestdata.c_str(), *copy))
[DEBUG 00:03:27] ClientTest.cpp:6215: starting UpdateLocal(currentServer() + "_1", config.m_sourceName, actualLocalData, localModified, modifyLocal)
[DEBUG 00:03:27] ClientTest.cpp:6049: starting CPPUNIT_ASSERT(cmdline->parse())
[DEBUG 00:03:27] ClientTest.cpp:6049: ending CPPUNIT_ASSERT(cmdline->parse())
[DEBUG 00:03:27] ClientTest.cpp:6050: starting CPPUNIT_ASSERT_MESSAGE("export " + currentServer() + "_1 " + source, (cmdline->run()))
[DEBUG 00:03:27] checking password property 'password' in config 'googlecontacts_1@client-test-googlecontacts-1' with user identity ''
[DEBUG 00:03:27] checking password property 'databasePassword' in datastore 'eds_contact' of config 'googlecontacts_1@client-test-googlecontacts-1' with user identity ''
[DEBUG 00:03:27] eds_contact: slow sync or testing, do full item scan to detect changes
[DEBUG 00:03:27] eds_contact: reading: set order 'all', 0 luids
[DEBUG 00:03:27] eds_contact: reading: getting contact pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:27] eds_contact: reading: must read contact pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:27] eds_contact: reading: started contact read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1)
[DEBUG 00:03:27] eds_contact: reading: active cache pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1)
[DEBUG 00:03:27] eds_contact: reading: in running cache
[DEBUG 00:03:27] eds_contact: reading: contact read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1) finished: <<successful>>
[DEBUG 00:03:27] eds_contact: reading: contact read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1) got pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:27] eds_contact: reading: in cache, available
[DEBUG 00:03:27] eds_contact: reading: continue after contact pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:27] eds_contact: reading: read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d: <<okay>>
[DEBUG 00:03:27] eds_contact: requested 1, retrieved 1 from DB in 1 queries, misses 0/1 (0%), stalls 1
[DEBUG 00:03:27] eds_contact: reading: set order 'none', 0 luids
[DEBUG 00:03:27] ClientTest.cpp:6050: ending CPPUNIT_ASSERT_MESSAGE("export " + currentServer() + "_1 " + source, (cmdline->run()))
[DEBUG 00:03:27] ClientTest.cpp:6055: starting CPPUNIT_ASSERT(!system(StringPrintf("%s %s %s", modifyLocal.c_str(), actualLocalData.c_str(), localModified.c_str()).c_str()))
[DEVELOPER 00:03:28] stderr: +
[DEVELOPER 00:03:28] stderr: IN=Client_Sync_eds_contact_testDownload.local.test.dat
[DEVELOPER 00:03:28] stderr:
[DEVELOPER 00:03:28] stderr: +
[DEVELOPER 00:03:28] stderr: OUT=Client_Sync_eds_contact_testDownload.local.modified.test.dat
[DEVELOPER 00:03:28] stderr:
[DEVELOPER 00:03:28] stderr: +
[DEVELOPER 00:03:28] stderr: rm
[DEVELOPER 00:03:28] stderr:  -rf
[DEVELOPER 00:03:28] stderr:  Client_Sync_eds_contact_testDownload.local.modified.test.dat
[DEVELOPER 00:03:28] stderr:
[DEVELOPER 00:03:28] stderr: +
[DEVELOPER 00:03:28] stderr: mkdir
[DEVELOPER 00:03:28] stderr:  Client_Sync_eds_contact_testDownload.local.modified.test.dat
[DEVELOPER 00:03:28] stderr:
[DEVELOPER 00:03:28] stderr: +
[DEVELOPER 00:03:28] stderr: [
[DEVELOPER 00:03:28] stderr:  -d
[DEVELOPER 00:03:28] stderr:  Client_Sync_eds_contact_testDownload.local.test.dat
[DEVELOPER 00:03:28] stderr:  ]
[DEVELOPER 00:03:28] stderr:
[DEVELOPER 00:03:28] stderr: +
[DEVELOPER 00:03:28] stderr: basename
[DEVELOPER 00:03:28] stderr:  Client_Sync_eds_contact_testDownload.local.test.dat/pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEVELOPER 00:03:28] stderr:
[DEVELOPER 00:03:28] stderr: +
[DEVELOPER 00:03:28] stderr: perl
[DEVELOPER 00:03:28] stderr:  -e
[DEVELOPER 00:03:28] stderr:  $_ = join("", <>); if (1) {
[DEVELOPER 00:03:28] stderr:  -e
[DEVELOPER 00:03:28] stderr:  s/END:VCARD/EMAIL:new local email\r\nEND:VCARD/;
[DEVELOPER 00:03:28] stderr:  -e
[DEVELOPER 00:03:28] stderr:  print;
[DEVELOPER 00:03:28] stderr:  -e
[DEVELOPER 00:03:28] stderr:  }
[DEVELOPER 00:03:28] stderr:
[DEVELOPER 00:03:28] stderr: +
[DEVELOPER 00:03:28] stderr: find
[DEVELOPER 00:03:28] stderr:  Client_Sync_eds_contact_testDownload.local.modified.test.dat
[DEVELOPER 00:03:28] stderr:  -size
[DEVELOPER 00:03:28] stderr:  0
[DEVELOPER 00:03:28] stderr:  -delete
[DEVELOPER 00:03:28] stderr:
[DEBUG 00:03:28] ClientTest.cpp:6055: ending CPPUNIT_ASSERT(!system(StringPrintf("%s %s %s", modifyLocal.c_str(), actualLocalData.c_str(), localModified.c_str()).c_str()))
[DEBUG 00:03:28] ClientTest.cpp:6056: starting CPPUNIT_ASSERT(isDir(localModified))
[DEBUG 00:03:28] ClientTest.cpp:6056: ending CPPUNIT_ASSERT(isDir(localModified))
[DEBUG 00:03:28] ClientTest.cpp:6063: starting CPPUNIT_ASSERT(cmdline->parse())
[DEBUG 00:03:28] ClientTest.cpp:6063: ending CPPUNIT_ASSERT(cmdline->parse())
[DEBUG 00:03:28] ClientTest.cpp:6064: starting CPPUNIT_ASSERT_MESSAGE("update " + config + " " + source, (cmdline->run()))
[DEBUG 00:03:28] checking password property 'password' in config 'googlecontacts_1@client-test-googlecontacts-1' with user identity ''
[DEBUG 00:03:28] checking password property 'databasePassword' in datastore 'eds_contact' of config 'googlecontacts_1@client-test-googlecontacts-1' with user identity ''
[DEBUG 00:03:28] eds_contact: slow sync or testing, do full item scan to detect changes
[DEBUG 00:03:28] eds_contact: insert pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d operation #0: queueing for batched update
[DEBUG 00:03:28] eds_contact: batch update of 1 contacts starting
[DEBUG 00:03:28] eds_contact: waiting for 1 pending operations to complete
[DEBUG 00:03:28] eds_contact: batch update of 1 contacts completed
[DEBUG 00:03:28] eds_contact: insert pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d operation #0: completed: <<successfully>>
[DEBUG 00:03:28] eds_contact: pending operations completed
[DEBUG 00:03:28] eds_contact: insert pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d operation #0: checking operation: inserted
#0: pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d: pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:28] ClientTest.cpp:6064: ending CPPUNIT_ASSERT_MESSAGE("update " + config + " " + source, (cmdline->run()))
[DEBUG 00:03:28] ClientTest.cpp:6215: ending UpdateLocal(currentServer() + "_1", config.m_sourceName, actualLocalData, localModified, modifyLocal)
[DEBUG 00:03:28] ClientTest.cpp:6218: starting doSync(logPrefix, options)
[DEBUG 00:03:28] 2. starting Client_Sync_eds_contact_testDownload.two-way.client.A with sync mode two-way
[DEBUG 00:03:28] ClientTest.cpp:6460: starting res = client.doSync(sourceArray, logname, options)
[DEBUG 00:03:28] log path -> /data/runtests/work/testing-amd64/home/googlecontacts/cache/syncevolution, No such file or directory
[DEBUG 00:03:28] log path -> Client_Sync_eds_contact_testDownload.two-way.client.A, No such file or directory
[DEBUG 00:03:28] log path -> Client_Sync_eds_contact_testDownload.two-way.client.A, No such file or directory
[DEBUG 00:03:28] checking log dir Client_Sync_eds_contact_testDownload.two-way.client.A
[DEBUG 00:03:28] logfile: Client_Sync_eds_contact_testDownload.two-way.client.A/syncevolution-log.html
[WARNING 00:03:28] googlecontacts_1@client-test-googlecontacts-1: ignoring username , it is not needed
[DEBUG 00:03:29] CreateContext SyncEvolution// => 0
[DEBUG 00:03:29] Module_Capabilities:
[DEBUG 00:03:29] PLATFORM:Linux
[DEBUG 00:03:29] DLL:true
[DEBUG 00:03:29] MINVERSION:V1.0.6.0
[DEBUG 00:03:29] MANUFACTURER:SyncEvolution
[DEBUG 00:03:29] DESCRIPTION:SyncEvolution Synthesis DB Plugin
[DEBUG 00:03:29] plugin_datastore_str:no
[DEBUG 00:03:29] plugin_datastore_key:yes
[DEBUG 00:03:29] ITEM_AS_KEY:yes
[DEBUG 00:03:29] plugin_datablob:no
[DEBUG 00:03:29] Module_PluginParams
[DEBUG 00:03:29]  Engine=01090100
[DEBUG 00:03:29]
[DEVELOPER 00:03:30] SyncML server account:
[DEVELOPER 00:03:30] client: SyncEvolution 1.5.3 for workstation
[DEVELOPER 00:03:30] device ID: syncevolution-6058a162-5fc4-4d3e-969a-7095a50343dc
[DEVELOPER 00:03:30]
[DEVELOPER 00:03:30]
[INFO 00:03:30] @client-test-googlecontacts-1/addressbook: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/calendar: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/calendar+todo: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/eas_contact: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/eas_event: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/eas_memo: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/eas_task: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/eds_event: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/eds_memo: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/eds_task: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/file_calendar+todo: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/file_contact: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/file_event: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/file_task: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/google_carddav: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/kde_contact: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/kde_event: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/kde_memo: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/kde_task: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/memo: inactive
[INFO 00:03:30] @client-test-googlecontacts-1/todo: inactive
[DEBUG 00:03:31] checking password property 'password' in config 'googlecontacts_1@client-test-googlecontacts-1' with user identity ''
[DEBUG 00:03:31] checking password property 'databasePassword' in datastore 'eds_contact' of config 'googlecontacts_1@client-test-googlecontacts-1' with user identity ''
[DEBUG 00:03:31] sync is starting, catch signals
[DEBUG 00:03:31] SuspendFlags: (re)activating, currently inactive
[DEBUG 00:03:31] SuspendFlags: activating signal handler(s) with fds 35->34
[DEBUG 00:03:31] SuspendFlags: catch signal 15
[DEBUG 00:03:31] ready to sync
[WARNING 00:03:31] googlecontacts_1@client-test-googlecontacts-1: ignoring username , it is not needed
[DEBUG 00:03:31] Module_DeleteContext 'session'
[DEBUG 00:03:31] CreateContext SyncEvolution// => 0
[DEBUG 00:03:31] Module_Capabilities:
[DEBUG 00:03:31] PLATFORM:Linux
[DEBUG 00:03:31] DLL:true
[DEBUG 00:03:31] MINVERSION:V1.0.6.0
[DEBUG 00:03:31] MANUFACTURER:SyncEvolution
[DEBUG 00:03:31] DESCRIPTION:SyncEvolution Synthesis DB Plugin
[DEBUG 00:03:31] plugin_datastore_str:no
[DEBUG 00:03:31] plugin_datastore_key:yes
[DEBUG 00:03:31] ITEM_AS_KEY:yes
[DEBUG 00:03:31] plugin_datablob:no
[DEBUG 00:03:31] Module_PluginParams
[DEBUG 00:03:31]  Engine=01090100
[DEBUG 00:03:31]
[DEBUG 00:03:31] CreateContext SyncEvolution//eds_contact => 0
[DEBUG 00:03:31] Module_Version = 01090100
[DEBUG 00:03:31] Module_Capabilities:
[DEBUG 00:03:31] PLATFORM:Linux
[DEBUG 00:03:31] DLL:true
[DEBUG 00:03:31] MINVERSION:V1.0.6.0
[DEBUG 00:03:31] MANUFACTURER:SyncEvolution
[DEBUG 00:03:31] DESCRIPTION:SyncEvolution Synthesis DB Plugin
[DEBUG 00:03:31] plugin_datastore_str:no
[DEBUG 00:03:31] plugin_datastore_key:yes
[DEBUG 00:03:31] ITEM_AS_KEY:yes
[DEBUG 00:03:31] plugin_datablob:yes
[DEBUG 00:03:31] plugin_datastoreadmin:yes
[DEBUG 00:03:31] @client-test-googlecontacts-1/eds_contact: Module_PluginParams
[DEBUG 00:03:31] @client-test-googlecontacts-1/eds_contact:  Engine=01090100
[DEBUG 00:03:31] @client-test-googlecontacts-1/eds_contact:
[DEBUG 00:03:31] Module_Capabilities:
[DEBUG 00:03:31] PLATFORM:Linux
[DEBUG 00:03:31] DLL:true
[DEBUG 00:03:31] MINVERSION:V1.0.6.0
[DEBUG 00:03:31] MANUFACTURER:SyncEvolution
[DEBUG 00:03:31] DESCRIPTION:SyncEvolution Synthesis DB Plugin
[DEBUG 00:03:31] plugin_datastore_str:no
[DEBUG 00:03:31] plugin_datastore_key:yes
[DEBUG 00:03:31] ITEM_AS_KEY:yes
[DEBUG 00:03:31] plugin_datablob:yes
[DEBUG 00:03:31] plugin_datastoreadmin:yes
[DEBUG 00:03:31] CreateContext SyncEvolution//eds_contact => 0
[DEBUG 00:03:31] Module_Version = 01090100
[DEBUG 00:03:31] Module_Capabilities:
[DEBUG 00:03:31] PLATFORM:Linux
[DEBUG 00:03:31] DLL:true
[DEBUG 00:03:31] MINVERSION:V1.0.6.0
[DEBUG 00:03:31] MANUFACTURER:SyncEvolution
[DEBUG 00:03:31] DESCRIPTION:SyncEvolution Synthesis DB Plugin
[DEBUG 00:03:31] plugin_datastore_str:no
[DEBUG 00:03:31] plugin_datastore_key:yes
[DEBUG 00:03:31] ITEM_AS_KEY:yes
[DEBUG 00:03:31] plugin_datablob:yes
[DEBUG 00:03:31] plugin_datastoreadmin:yes
[DEBUG 00:03:31] @client-test-googlecontacts-1/eds_contact: Module_PluginParams
[DEBUG 00:03:31] @client-test-googlecontacts-1/eds_contact:  Engine=01090100
[DEBUG 00:03:31] @client-test-googlecontacts-1/eds_contact:
[DEBUG 00:03:31] ForkExecParent: preparing for child process syncevo-local-sync
[DEBUG 00:03:31] ForkExecParent: running syncevo-local-sync with D-Bus address 39
[DEBUG 00:03:31] ForkExecParent: child process for syncevo-local-sync has pid 390
[DEBUG 00:03:31] Session_CreateContext '6231324931247856207' found
[DEBUG 00:03:31] waiting for child to send message
[DEBUG 00:03:31] ForkExecParent: child syncevo-local-sync 390 has connected
[DEBUG 00:03:31] child is ready
[DEBUG 00:03:38] ForkExecParentDBusAPI /org/syncevolution/forkexec/parent/forkexec2: received 'Watch' method call from child
[INFO @client-test-googlecontacts 00:03:38] target side of local sync ready
[INFO @client-test-googlecontacts 00:03:42] @client-test-googlecontacts/eds_contact: inactive
[INFO @client-test-googlecontacts 00:03:42] @client-test-googlecontacts/google_carddav: inactive
[INFO @client-test-googlecontacts 00:03:42] @client-test-googlecontacts/carddav: using configured database=https://www.googleapis.com:443/carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/
[DEBUG 00:03:45] Session_CheckDevice dev='syncevolution-3ad8173d-16d5-455f-8cd8-55a037c9010b' nonce='' res=0
[DEBUG 00:03:45] Session_SaveDeviceInfo info='REMOTE_URI:syncevolution-3ad8173d-16d5-455f-8cd8-55a037c9010b
[DEBUG 00:03:45] REMOTE_DESC:Patrick Ohly SyncEvolution
[DEBUG 00:03:45] REMOTE_INFO:workstation (unknown, 1.5.3, 3.4.0.47) Synthesis AG
[DEBUG 00:03:45] DOMAIN:
[DEBUG 00:03:45] MOD:SyncEvolution
[DEBUG 00:03:45] MAN:Patrick Ohly
[DEBUG 00:03:45] OEM:Synthesis AG
[DEBUG 00:03:45] FWV:1.5.3
[DEBUG 00:03:45] SWV:3.4.0.47
[DEBUG 00:03:45] HWV:unknown
[DEBUG 00:03:45] '
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: 'eds_contact' dev='syncevolution-3ad8173d-16d5-455f-8cd8-55a037c9010b' usr='anonymous' err=0
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: 'eds_contact' dev='syncevolution-3ad8173d-16d5-455f-8cd8-55a037c9010b' usr='anonymous' err=0
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: LoadAdminData 'eds_contact' './carddav', 'remotesyncanchor:20180105T165047Z
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: lastsync:20180105T165048Z
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: lasttoremotesync:20180105T165048Z
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: lasttoremotesyncid:1
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: resumealertcode:0
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: lastsuspend:20180105T165048Z
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: lastsuspendid:
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: partialitemstate:0
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: lastitemstatus:0
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: lastsourceURI:
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: lasttargetURI:
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: totalsize:0
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: unconfirmedsize:0
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: storedsize:0
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: stored;BLOBID=PIStored
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: ' res=0
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem 'pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d' + 1 = '182b96310e5fbc3f' + 0 first=yes res=1
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: ReadNextMapItem '(none)' + 0 = '(none)' + 0 first=no res=0
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: total number of items received 0
[DEBUG 00:03:45] waiting for child to send message
[INFO @client-test-googlecontacts 00:03:45] @client-test-googlecontacts/carddav: starting normal sync, two-way (peer is server)
[INFO 00:03:45] @client-test-googlecontacts-1/eds_contact: starting normal sync, two-way (peer is client)
[DEBUG 00:03:45] reading: set read-ahead based on sync mode two-way
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: reading: set order 'changed', 0 luids
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: FilterSupport staticfilter:
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: dynamicfilter:
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: invisiblefilter:
[DEBUG 00:03:45] @client-test-googlecontacts-1/eds_contact: using full item scan to detect changes
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: StartDataRead last='1' resume='' res=0
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=1 aID=(pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d,(null)) res=0
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: ReadNextItemAsKey aStatus=0 aID=((null),(null)) res=0
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: EndDataRead res=0
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: reading: getting contact pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: reading: must read contact pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: reading: started contact read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1)
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: reading: active cache pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1)
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: reading: in running cache
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: reading: contact read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1) finished: <<successful>>
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: reading: contact read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d-pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d (1) got pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: reading: in cache, available
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: reading: continue after contact pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: reading: read pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d: <<okay>>
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: requested 1, retrieved 1 from DB in 1 queries, misses 0/1 (0%), stalls 1
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: ReadItemAsKey aID=(pas-id-7b845595dda0109abea2bb54ad596ae06a9c862d,) res=0
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: StartDataWrite
[INFO 00:03:46] @client-test-googlecontacts-1/eds_contact: started
[DEBUG 00:03:46] @client-test-googlecontacts-1/eds_contact: total number of items received 0
[INFO 00:03:46] @client-test-googlecontacts-1/eds_contact: sent 1
[DEBUG 00:03:46] waiting for child to send message
[INFO @client-test-googlecontacts 00:03:46] @client-test-googlecontacts/carddav: started
[INFO @client-test-googlecontacts 00:03:50] @client-test-googlecontacts/carddav: updating "John 1 Doe"
[INFO @client-test-googlecontacts 00:04:00] @client-test-googlecontacts/carddav: received 1/1
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: EndDataWrite COMMIT '1' res=0
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: SaveAdminData 'remotesyncanchor:20180105T165114Z
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: lastsync:20180105T165114Z
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: lasttoremotesync:20180105T165114Z
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: lasttoremotesyncid:1
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: resumealertcode:0
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: lastsuspend:20180105T165114Z
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: lastsuspendid:
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: partialitemstate:0
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: lastitemstatus:0
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: lastsourceURI:
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: lasttargetURI:
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: totalsize:0
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: unconfirmedsize:0
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: storedsize:0
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: stored;BLOBID=PIStored
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: ' res=0
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: DeleteBlob aID=(,) aBlobID=(PIStored) res=0
[INFO 00:04:00] @client-test-googlecontacts-1/eds_contact: normal sync done successfully
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: total number of items received 0
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: DeleteContext
[DEBUG 00:04:00] @client-test-googlecontacts-1/eds_contact: DeleteContext
[DEBUG 00:04:00] parent is shutting down
[DEBUG 00:04:00] waiting for child to stop
[INFO @client-test-googlecontacts 00:04:01] @client-test-googlecontacts/carddav: normal sync done successfully
[DEBUG 00:04:01] waiting for child to stop
[DEBUG 00:04:01] got child sync report:
[DEBUG 00:04:01] end = 1515171091
[DEBUG 00:04:01] source-carddav-backup-after = -1
[DEBUG 00:04:01] source-carddav-backup-before = -1
[DEBUG 00:04:01] source-carddav-first = false
[DEBUG 00:04:01] source-carddav-mode = two-way
[DEBUG 00:04:01] source-carddav-resume = false
[DEBUG 00:04:01] source-carddav-stat-local-any-received = 2200
[DEBUG 00:04:01] source-carddav-stat-local-updated-total = 1
[DEBUG 00:04:01] source-carddav-status = 0
[DEBUG 00:04:01] start = 1515171068
[DEBUG 00:04:01] status = 200
[DEVELOPER 00:04:02] stderr: sess: Destroying session.
[DEBUG 00:04:02] child process has quit with status 0
[DEBUG 00:04:02] ForkExecParent: shutting down, telling syncevo-local-sync 390 that it lost the connection, it is watching
[DEBUG 00:04:02] ForkExecParentDBusAPI /org/syncevolution/forkexec/parent/forkexec2: destroying with 1 active watches
[DEBUG 00:04:02] closing session
[DEBUG 00:04:02] session closed
[DEBUG 00:04:02] SuspendFlags: deactivating fds 35->34
[DEBUG 00:04:02] SuspendFlags: close m_receiverFD 34
[DEBUG 00:04:02] SuspendFlags: close m_senderFD 35
[DEBUG 00:04:02] SuspendFlags: done with deactivation
[DEBUG 00:04:02] Module_DeleteContext eds_contact
[DEBUG 00:04:02] Module_DeleteContext eds_contact
[DEBUG 00:04:02] Module_DeleteContext 'session'
[INFO 00:04:02] sync report:
[INFO 00:04:02] +---------------|-----------------------|-----------------------|-CON-+
[INFO 00:04:02] |               |@client-test-googlecontacts-1|@client-test-googlecontacts| FLI |
[INFO 00:04:02] |        Source | NEW | MOD | DEL | ERR | NEW | MOD | DEL | ERR | CTS |
[INFO 00:04:02] +---------------+-----+-----+-----+-----+-----+-----+-----+-----+-----+
[INFO 00:04:02] |   eds_contact |  0  |  0  |  0  |  0  |  0  |  1  |  0  |  0  |  0  |
[INFO 00:04:02] |   two-way, 2 KB sent by client, 0 KB received                       |
[INFO 00:04:02] +---------------+-----+-----+-----+-----+-----+-----+-----+-----+-----+
[INFO 00:04:02] |          start Fri Jan  5 16:50:58 2018, duration 0:33min           |
[INFO 00:04:02] |               synchronization completed successfully                |
[INFO 00:04:02] +---------------+-----+-----+-----+-----+-----+-----+-----+-----+-----+
[INFO 00:04:02] ----------|--------CLIENT---------|--------SERVER---------|
[INFO 00:04:02]           |  NEW  |  MOD  |  DEL  |  NEW  |  MOD  |  DEL  |
[INFO 00:04:02] ----------|-----------------------------------------------|
[INFO 00:04:02] Expected  |    0  |    0  |    0  |    0  |   -1  |    0  |
[INFO 00:04:02] Expected sync mode: two-way
[INFO 00:04:02] Expected cycles: 1
[INFO 00:04:02]
[DEBUG 00:04:02] ClientTest.cpp:8283: starting CPPUNIT_ASSERT_EQUAL((string("no error (remote, status 0)")),(Status2String(status)))
[DEBUG 00:04:02] ClientTest.cpp:8283: ending CPPUNIT_ASSERT_EQUAL((string("no error (remote, status 0)")),(Status2String(status)))
[DEBUG 00:04:02] ClientTest.cpp:8284: starting CPPUNIT_ASSERT_EQUAL((STATUS_OK),(status))
[DEBUG 00:04:02] ClientTest.cpp:8284: ending CPPUNIT_ASSERT_EQUAL((STATUS_OK),(status))
[DEBUG 00:04:02] Checking sync source eds_contact...
[DEBUG 00:04:02] ClientTest.cpp:8293: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "STATUS_OK" + " == " + "source.getStatus()",(STATUS_OK),(source.getStatus()))
[DEBUG 00:04:02] ClientTest.cpp:8293: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "STATUS_OK" + " == " + "source.getStatus()",(STATUS_OK),(source.getStatus()))
[DEBUG 00:04:02] ClientTest.cpp:8305: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "0" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)",(0),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)))
[DEBUG 00:04:02] ClientTest.cpp:8305: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "0" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)",(0),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)))
[DEBUG 00:04:02] ClientTest.cpp:8308: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "0" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)",(0),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)))
[DEBUG 00:04:02] ClientTest.cpp:8308: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "0" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)",(0),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ANY, SyncSourceReport::ITEM_REJECT)))
[DEBUG 00:04:02] ClientTest.cpp:8319: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "syncMode" + " == " + "source.getFinalSyncMode()",(syncMode),(source.getFinalSyncMode()))
[DEBUG 00:04:02] ClientTest.cpp:8319: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "syncMode" + " == " + "source.getFinalSyncMode()",(syncMode),(source.getFinalSyncMode()))
[DEBUG 00:04:02] ClientTest.cpp:8322: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "restarts + 1" + " == " + "source.getRestarts() + 1",(restarts + 1),(source.getRestarts() + 1))
[DEBUG 00:04:02] ClientTest.cpp:8322: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "restarts + 1" + " == " + "source.getRestarts() + 1",(restarts + 1),(source.getRestarts() + 1))
[DEBUG 00:04:02] ClientTest.cpp:8328: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "clientAdded" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)",(clientAdded),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:04:02] ClientTest.cpp:8328: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "clientAdded" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)",(clientAdded),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:04:02] ClientTest.cpp:8334: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "clientUpdated" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)",(clientUpdated),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:04:02] ClientTest.cpp:8334: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "clientUpdated" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)",(clientUpdated),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_UPDATED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:04:02] ClientTest.cpp:8340: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "clientDeleted" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)",(clientDeleted),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:04:02] ClientTest.cpp:8340: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "clientDeleted" + " == " + "source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)",(clientDeleted),(source.getItemStat(SyncSourceReport::ITEM_LOCAL, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:04:02] ClientTest.cpp:8347: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "serverAdded" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)",(serverAdded),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:04:02] ClientTest.cpp:8347: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "serverAdded" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)",(serverAdded),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_ADDED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:04:02] ClientTest.cpp:8359: starting CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "serverDeleted" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)",(serverDeleted),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:04:02] ClientTest.cpp:8359: ending CPPUNIT_ASSERT_EQUAL_MESSAGE(std::string(name) + ": " + "serverDeleted" + " == " + "source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)",(serverDeleted),(source.getItemStat(SyncSourceReport::ITEM_REMOTE, SyncSourceReport::ITEM_REMOVED, SyncSourceReport::ITEM_TOTAL)))
[DEBUG 00:04:02] Done with checking sync report.
[DEBUG 00:04:02] ClientTest.cpp:6460: ending res = client.doSync(sourceArray, logname, options)
[DEBUG 00:04:02] ClientTest.cpp:6465: starting postSync(res, logname)
[DEBUG 00:04:02] ClientTest.cpp:6465: ending postSync(res, logname)
[DEBUG 00:04:02] ClientTest.cpp:6218: ending doSync(logPrefix, options)
[DEBUG 00:04:02] ClientTest.cpp:6234: starting CPPUNIT_ASSERT(cmdline->parse())
[DEBUG 00:04:02] ClientTest.cpp:6234: ending CPPUNIT_ASSERT(cmdline->parse())
[DEBUG 00:04:02] ClientTest.cpp:6235: starting CPPUNIT_ASSERT_MESSAGE("export " + peer + " " + peerSource, (cmdline->run()))
[DEBUG 00:04:02] checking password property 'password' in config 'target-config@client-test-googlecontacts' with user identity 'goa:syncevolution.list@gmail.com'
[DEBUG 00:04:02] checking password property 'proxyPassword' in config 'target-config@client-test-googlecontacts' with user identity ''
[DEBUG 00:04:02] checking password property 'databasePassword' in datastore 'carddav' of config 'target-config@client-test-googlecontacts' with user identity ''
[DEBUG 00:04:02] 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
[INFO 00:04:02] carddav: using configured database=https://www.googleapis.com:443/carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/
[DEBUG 00:04:02] using username 'goa:syncevolution.list@gmail.com' from context for WebDAV, password not set
[DEBUG 00:04:02] looking for identity provider for goa:syncevolution.list@gmail.com
[DEBUG 00:04:02] Looking up all accounts in GNOME Online Accounts, searching for 'syncevolution.list@gmail.com'.
[DEBUG 00:04:02] GOA object /org/gnome/OnlineAccounts/Accounts/account_1379073187 implements org.gnome.OnlineAccounts.Account, org.gnome.OnlineAccounts.Calendar, org.gnome.OnlineAccounts.Chat, org.gnome.OnlineAccounts.Contacts, org.gnome.OnlineAccounts.Documents, org.gnome.OnlineAccounts.Mail, org.gnome.OnlineAccounts.OAuth2Based
[DEBUG 00:04:02] GOA account Google, syncevolution.list@gmail.com = account_1379073187
[DEBUG 00:04:02] found matching GNOME Online Account for 'syncevolution.list@gmail.com': Google, syncevolution.list@gmail.com = account_1379073187
[DEBUG 00:04:02] GOA object /org/gnome/OnlineAccounts/Manager implements org.gnome.OnlineAccounts.Manager
[DEBUG 00:04:02] carddav: slow sync or testing, do full item scan to detect changes
[DEBUG 00:04:02] starting PROPFIND, credentials unverified, deadline in 300.0s
[DEVELOPER 00:04:02] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:04:02] stderr: Running pre_send hooks
[DEBUG 00:04:02] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:04:02] stderr: Sending request headers:
[DEVELOPER 00:04:02] stderr: PROPFIND /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/ HTTP/1.1
[DEVELOPER 00:04:02] stderr: Connection: TE
[DEVELOPER 00:04:02] stderr: TE: trailers
[DEVELOPER 00:04:02] stderr: Host: www.googleapis.com
[DEVELOPER 00:04:02] stderr: Depth: 1
[DEVELOPER 00:04:02] stderr: Content-Length: 141
[DEVELOPER 00:04:02] stderr: Content-Type: application/xml
[DEVELOPER 00:04:02] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:04:02] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:04:02] stderr:
[DEVELOPER 00:04:02] stderr: Sending request-line and headers:
[DEVELOPER 00:04:02] stderr: Sending request body:
[DEVELOPER 00:04:02] stderr: Body block (141 bytes):
[DEVELOPER 00:04:02] stderr: [<?xml version="1.0" encoding="utf-8"?>
[DEVELOPER 00:04:02] stderr: <propfind xmlns="DAV:"><prop>
[DEVELOPER 00:04:02] stderr: <getetag xmlns="DAV:"/>
[DEVELOPER 00:04:02] stderr: <resourcetype xmlns="DAV:"/>
[DEVELOPER 00:04:02] stderr: </prop></propfind>
[DEVELOPER 00:04:02] stderr: ]
[DEVELOPER 00:04:02] stderr: Request sent; retry is 1.
[DEVELOPER 00:04:02] stderr: [status-line] < HTTP/1.1 207 Multi-Status
[DEVELOPER 00:04:02] stderr: [hdr] DAV: 1, 2, 3, addressbook, access-control
[DEVELOPER 00:04:02] stderr: Header Name: [dav], Value: [1, 2, 3, addressbook, access-control]
[DEVELOPER 00:04:02] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:04:02] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:04:02] stderr: [hdr] Content-Type: text/xml; charset=UTF-8
[DEVELOPER 00:04:02] stderr: Header Name: [content-type], Value: [text/xml; charset=UTF-8]
[DEVELOPER 00:04:02] stderr: [hdr] Date: Fri, 05 Jan 2018 16:51:32 GMT
[DEVELOPER 00:04:02] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:51:32 GMT]
[DEVELOPER 00:04:02] stderr: [hdr] Expires: Fri, 05 Jan 2018 16:51:32 GMT
[DEVELOPER 00:04:02] stderr: Header Name: [expires], Value: [Fri, 05 Jan 2018 16:51:32 GMT]
[DEVELOPER 00:04:02] stderr: [hdr] Cache-Control: private, max-age=0
[DEVELOPER 00:04:02] stderr: Header Name: [cache-control], Value: [private, max-age=0]
[DEVELOPER 00:04:02] stderr: [hdr] X-Content-Type-Options: nosniff
[DEVELOPER 00:04:02] stderr: Header Name: [x-content-type-options], Value: [nosniff]
[DEVELOPER 00:04:02] stderr: [hdr] X-Frame-Options: SAMEORIGIN
[DEVELOPER 00:04:02] stderr: Header Name: [x-frame-options], Value: [SAMEORIGIN]
[DEVELOPER 00:04:02] stderr: [hdr] X-XSS-Protection: 1; mode=block
[DEVELOPER 00:04:02] stderr: Header Name: [x-xss-protection], Value: [1; mode=block]
[DEVELOPER 00:04:02] stderr: [hdr] Server: GSE
[DEVELOPER 00:04:02] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:04:02] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:04:02] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:04:02] stderr: [hdr] Accept-Ranges: none
[DEVELOPER 00:04:02] stderr: Header Name: [accept-ranges], Value: [none]
[DEVELOPER 00:04:02] stderr: [hdr] Vary: Origin,Accept-Encoding
[DEVELOPER 00:04:02] stderr: Header Name: [vary], Value: [Origin,Accept-Encoding]
[DEVELOPER 00:04:02] stderr: [hdr] Transfer-Encoding: chunked
[DEVELOPER 00:04:02] stderr: Header Name: [transfer-encoding], Value: [chunked]
[DEVELOPER 00:04:02] stderr: [hdr]
[DEVELOPER 00:04:02] stderr: End of headers.
[DEVELOPER 00:04:02] stderr: Running post_headers hooks
[DEVELOPER 00:04:02] stderr: [chunk] < 3d5
[DEVELOPER 00:04:02] stderr: Got chunk size: 981
[DEVELOPER 00:04:02] stderr: Reading 981 bytes of response body.
[DEVELOPER 00:04:02] stderr: Got 605 bytes.
[DEVELOPER 00:04:02] stderr: Read block (605 bytes):
[DEVELOPER 00:04:02] stderr: [<?xml version="1.0" encoding="UTF-8"?>
[DEVELOPER 00:04:02] stderr: <d:multistatus xmlns:cal="urn:ietf:params:xml:ns:caldav" xmlns:card="urn:ietf:params:xml:ns:carddav" xmlns:cs="http://calendarserver.org/ns/" xmlns:d="DAV:" xmlns:ical="http://apple.com/ns/ical/">
[DEVELOPER 00:04:02] stderr:  <d:response>
[DEVELOPER 00:04:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/</d:href>
[DEVELOPER 00:04:02] stderr:   <d:propstat>
[DEVELOPER 00:04:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:04:02] stderr:    <d:prop>
[DEVELOPER 00:04:02] stderr:     <d:resourcetype>
[DEVELOPER 00:04:02] stderr:      <d:collection/>
[DEVELOPER 00:04:02] stderr:      <card:addressbook/>
[DEVELOPER 00:04:02] stderr:     </d:resourcetype>
[DEVELOPER 00:04:02] stderr:    </d:prop>
[DEVELOPER 00:04:02] stderr:   </d:propstat>
[DEVELOPER 00:04:02] stderr:   <d:propstat>
[DEVELOPER 00:04:02] stderr:    <d:status>HTTP/1.1 404 Not Found</d:status>
[DEVELOPER 00:04:02] stderr:    <d:prop>
[DEVELOPER 00:04:02] stderr:     <d:get]
[DEVELOPER 00:04:02] stderr: Reading 376 bytes of response body.
[DEVELOPER 00:04:02] stderr: Got 376 bytes.
[DEVELOPER 00:04:02] stderr: Read block (376 bytes):
[DEVELOPER 00:04:02] stderr: [etag/>
[DEVELOPER 00:04:02] stderr:    </d:prop>
[DEVELOPER 00:04:02] stderr:   </d:propstat>
[DEVELOPER 00:04:02] stderr:  </d:response>
[DEVELOPER 00:04:02] stderr:  <d:response>
[DEVELOPER 00:04:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/182b96310e5fbc3f</d:href>
[DEVELOPER 00:04:02] stderr:   <d:propstat>
[DEVELOPER 00:04:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:04:02] stderr:    <d:prop>
[DEVELOPER 00:04:02] stderr:     <d:getetag>"2018-01-05T08:51:19.943-08:00"</d:getetag>
[DEVELOPER 00:04:02] stderr:     <d:resourcetype/>
[DEVELOPER 00:04:02] stderr:    </d:prop>
[DEVELOPER 00:04:02] stderr:   </d:propstat>
[DEVELOPER 00:04:02] stderr:  </d:response>
[DEVELOPER 00:04:02] stderr: </d:multistatus>
[DEVELOPER 00:04:02] stderr: ]
[DEBUG 00:04:02] item 182b96310e5fbc3f = rev 2018-01-05T08:51:19.943-08:00
[DEVELOPER 00:04:02] stderr: [chunk] < 0
[DEVELOPER 00:04:02] stderr: Got chunk size: 0
[DEVELOPER 00:04:02] stderr: [hdr]
[DEVELOPER 00:04:02] stderr: End of headers.
[DEVELOPER 00:04:02] stderr: Running post_send hooks
[DEVELOPER 00:04:02] stderr: ah_post_send (#0), code is 207 (want 401), WWW-Authenticate is (none)
[DEVELOPER 00:04:02] stderr: Request ends, status 207 class 2xx, error line:
[DEVELOPER 00:04:02] stderr: 207 Multi-Status
[DEBUG 00:04:02] credentials accepted
[DEVELOPER 00:04:02] stderr: Running destroy hooks.
[DEVELOPER 00:04:02] stderr: Request ends.
[DEBUG 00:04:02] carddav: reading: set order 'all', 0 luids
[DEBUG 00:04:02] starting MULTIGET, credentials okay, deadline in 300.0s
[DEVELOPER 00:04:02] stderr: ah_create, for WWW-Authenticate
[DEVELOPER 00:04:02] stderr: Running pre_send hooks
[DEBUG 00:04:02] using OAuth2 token 'ya29.Glw5BWZyVeL7FypYXuiuO9AeuliDmbKTb273unJ_Mq1vDyrpko9vAdLDkivSW_Vrl2-UdLQJPl14RAMWPYWM19MMt-MfO9ssTGAdjJ9WUQJAJQH9c-qL-aNpoPEqRA' to authenticate
[DEVELOPER 00:04:02] stderr: Sending request headers:
[DEVELOPER 00:04:02] stderr: REPORT /carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/ HTTP/1.1
[DEVELOPER 00:04:02] stderr: Connection: TE
[DEVELOPER 00:04:02] stderr: TE: trailers
[DEVELOPER 00:04:02] stderr: Host: www.googleapis.com
[DEVELOPER 00:04:02] stderr: Content-Length: 298
[DEVELOPER 00:04:02] stderr: Depth: 0
[DEVELOPER 00:04:02] stderr: Content-Type: application/xml; charset="utf-8"
[DEVELOPER 00:04:02] stderr: User-Agent: SyncEvolution
[DEVELOPER 00:04:02] stderr: Authorization: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx
[DEVELOPER 00:04:02] stderr:
[DEVELOPER 00:04:02] stderr: Sending request-line and headers:
[DEVELOPER 00:04:02] stderr: Sending request body:
[DEVELOPER 00:04:02] stderr: Body block (298 bytes):
[DEVELOPER 00:04:02] stderr: [<?xml version="1.0" encoding="utf-8" ?>
[DEVELOPER 00:04:02] stderr: <C:addressbook-multiget xmlns:D="DAV:" xmlns:C="urn:ietf:params:xml:ns:carddav">
[DEVELOPER 00:04:02] stderr: <D:prop>
[DEVELOPER 00:04:02] stderr: <D:getetag/>
[DEVELOPER 00:04:02] stderr: <C:address-data/>
[DEVELOPER 00:04:02] stderr: </D:prop>
[DEVELOPER 00:04:02] stderr: <D:href>/carddav/v1/principals/syncevolution.list%40gmail.com/lists/default/182b96310e5fbc3f</D:href>
[DEVELOPER 00:04:02] stderr: </C:addressbook-multiget>]
[DEVELOPER 00:04:02] stderr: Request sent; retry is 1.
[DEVELOPER 00:04:02] stderr: [status-line] < HTTP/1.1 207 Multi-Status
[DEVELOPER 00:04:02] stderr: [hdr] Vary: X-Origin
[DEVELOPER 00:04:02] stderr: Header Name: [vary], Value: [X-Origin]
[DEVELOPER 00:04:02] stderr: [hdr] Content-Type: text/xml; charset=UTF-8
[DEVELOPER 00:04:02] stderr: Header Name: [content-type], Value: [text/xml; charset=UTF-8]
[DEVELOPER 00:04:02] stderr: [hdr] Date: Fri, 05 Jan 2018 16:51:32 GMT
[DEVELOPER 00:04:02] stderr: Header Name: [date], Value: [Fri, 05 Jan 2018 16:51:32 GMT]
[DEVELOPER 00:04:02] stderr: [hdr] Expires: Fri, 05 Jan 2018 16:51:32 GMT
[DEVELOPER 00:04:02] stderr: Header Name: [expires], Value: [Fri, 05 Jan 2018 16:51:32 GMT]
[DEVELOPER 00:04:02] stderr: [hdr] Cache-Control: private, max-age=0
[DEVELOPER 00:04:02] stderr: Header Name: [cache-control], Value: [private, max-age=0]
[DEVELOPER 00:04:02] stderr: [hdr] X-Content-Type-Options: nosniff
[DEVELOPER 00:04:02] stderr: Header Name: [x-content-type-options], Value: [nosniff]
[DEVELOPER 00:04:02] stderr: [hdr] X-Frame-Options: SAMEORIGIN
[DEVELOPER 00:04:02] stderr: Header Name: [x-frame-options], Value: [SAMEORIGIN]
[DEVELOPER 00:04:02] stderr: [hdr] X-XSS-Protection: 1; mode=block
[DEVELOPER 00:04:02] stderr: Header Name: [x-xss-protection], Value: [1; mode=block]
[DEVELOPER 00:04:02] stderr: [hdr] Server: GSE
[DEVELOPER 00:04:02] stderr: Header Name: [server], Value: [GSE]
[DEVELOPER 00:04:02] stderr: [hdr] Alt-Svc: hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"
[DEVELOPER 00:04:02] stderr: Header Name: [alt-svc], Value: [hq=":443"; ma=2592000; quic=51303431; quic=51303339; quic=51303338; quic=51303337; quic=51303335,quic=":443"; ma=2592000; v="41,39,38,37,35"]
[DEVELOPER 00:04:02] stderr: [hdr] Accept-Ranges: none
[DEVELOPER 00:04:02] stderr: Header Name: [accept-ranges], Value: [none]
[DEVELOPER 00:04:02] stderr: [hdr] Vary: Origin,Accept-Encoding
[DEVELOPER 00:04:02] stderr: Header Name: [vary], Value: [Origin,Accept-Encoding]
[DEVELOPER 00:04:02] stderr: [hdr] Transfer-Encoding: chunked
[DEVELOPER 00:04:02] stderr: Header Name: [transfer-encoding], Value: [chunked]
[DEVELOPER 00:04:02] stderr: [hdr]
[DEVELOPER 00:04:02] stderr: End of headers.
[DEVELOPER 00:04:02] stderr: Running post_headers hooks
[DEVELOPER 00:04:02] stderr: [chunk] < db4
[DEVELOPER 00:04:02] stderr: Got chunk size: 3508
[DEVELOPER 00:04:02] stderr: Reading 3508 bytes of response body.
[DEVELOPER 00:04:02] stderr: Got 648 bytes.
[DEVELOPER 00:04:02] stderr: Read block (648 bytes):
[DEVELOPER 00:04:02] stderr: [<?xml version="1.0" encoding="UTF-8"?>
[DEVELOPER 00:04:02] stderr: <d:multistatus xmlns:cal="urn:ietf:params:xml:ns:caldav" xmlns:card="urn:ietf:params:xml:ns:carddav" xmlns:cs="http://calendarserver.org/ns/" xmlns:d="DAV:" xmlns:ical="http://apple.com/ns/ical/">
[DEVELOPER 00:04:02] stderr:  <d:response>
[DEVELOPER 00:04:02] stderr:   <d:href>/carddav/v1/principals/syncevolution.list@gmail.com/lists/default/182b96310e5fbc3f</d:href>
[DEVELOPER 00:04:02] stderr:   <d:propstat>
[DEVELOPER 00:04:02] stderr:    <d:status>HTTP/1.1 200 OK</d:status>
[DEVELOPER 00:04:02] stderr:    <d:prop>
[DEVELOPER 00:04:02] stderr:     <d:getetag>"2018-01-05T08:51:19.943-08:00"</d:getetag>
[DEVELOPER 00:04:02] stderr:     <card:address-data>BEGIN:VCARD
[DEVELOPER 00:04:02] stderr: VERSION:3.0
[DEVELOPER 00:04:02] stderr: N:Doe;John;1;Mr.;Sr.
[DEVELOPER 00:04:02] stderr: FN:Mr. John 1 Doe Sr.
[DEVELOPER 00:04:02] stderr: NICKNAME:Johnny
[DEVELOPER 00:04:02] stderr: TITLE:tester
[DEVELOPER 00:04:02] stderr: ORG:at company
[DEVELOPER 00:04:02] stderr: REV:2018-01-05T16:51:19Z
[DEVELOPER 00:04:02] stderr: UID]
[DEVELOPER 00:04:02] stderr: Reading 2860 bytes of response body.
[DEVELOPER 00:04:02] stderr: Got 1187 bytes.
[DEVELOPER 00:04:02] stderr: Read block (1187 bytes):
[DEVELOPER 00:04:02] stderr: [:182b96310e5fbc3f
[DEVELOPER 00:04:02] stderr: BDAY;VALUE=DATE:1970-12-30
[DEVELOPER 00:04:02] stderr: ADR;TYPE=HOME:PO;neighborhood;home address\n;City;State;ZIP;Country
[DEVELOPER 00:04:02] stderr: ADR;TYPE=WORK:;;work address;;;;
[DEVELOPER 00:04:02] stderr: ADR;TYPE=OTHER:;;custom address;;;;
[DEVELOPER 00:04:02] stderr: TEL;TYPE=WORK:business 1
[DEVELOPER 00:04:02] stderr: TEL;TYPE=CELL:mobile
[DEVELOPER 00:04:02] stderr: TEL;TYPE=HOME:home
[DEVELOPER 00:04:02] stderr: TEL:main
[DEVELOPER 00:04:02] stderr: TEL;TYPE=FAX,WORK:work fax
[DEVELOPER 00:04:02] stderr: TEL;TYPE=FAX,HOME:home fax
[DEVELOPER 00:04:02] stderr: item13.TEL:google voice
[DEVELOPER 00:04:02] stderr: TEL;TYPE=PAGER:pager
[DEVELOPER 00:04:02] stderr: item14.TEL:custom
[DEVELOPER 00:04:02] stderr: EMAIL;TYPE=HOME,PREF:john.doe@home.com
[DEVELOPER 00:04:02] stderr: EMAIL;TYPE=WORK:doe@work.com
[DEVELOPER 00:04:02] stderr: item1.EMAIL:john@custom.com
[DEVELOPER 00:04:02] stderr: EMAIL:new local email
[DEVELOPER 00:04:02] stderr: NOTE:A test contact.
[DEVELOPER 00:04:02] stderr: item4.IMPP;X-SERVICE-TYPE=GoogleTalk:xmpp:google%20talk
[DEVELOPER 00:04:02] stderr: item5.IMPP;X-SERVICE-TYPE=QQ:x-apple:QQ
[DEVELOPER 00:04:02] stderr: item6.IMPP;X-SERVICE-TYPE=Chat-label:x-apple:custom%20chat
[DEVELOPER 00:04:02] stderr: item7.IMPP;X-SERVICE-TYPE=AIM:aim:aim
[DEVELOPER 00:04:02] stderr: item8.IMPP;X-SERVICE-TYPE=ICQ:aim:ICQ
[DEVELOPER 00:04:02] stderr: item9.IMPP;X-SERVICE-TYPE=Jabber:xmpp:Jabber
[DEVELOPER 00:04:02] stderr: item10.IMPP;X-SERVICE-TYPE=MSN:msnim:MSN
[DEVELOPER 00:04:02] stderr: item11.IMPP;X-SERVICE-TYPE=Yahoo:ymsgr:yahoo
[DEVELOPER 00:04:02] stderr: item12.IMPP;X-SERVICE-TYPE=Skype:skype:skype
[DEVELOPER 00:04:02] stderr: X-EVOLUTION-FILE-AS:Doe\, John
[DEVELOPER 00:04:02] stderr: X-MOZILLA-HTML:FALSE
[DEVELOPER 00:04:02] stderr: X-PHONETIC-FIRST-NAME:John
[DEVELOPER 00:04:02] stderr: X-PHONETIC-LAST-NAME:Doe
[DEVELOPER 00:04:02] stderr: item1.X-ABLabel:custom-label2
[DEVELOPER 00:04:02] stderr: item2.X-ABDATE:1971-01-01
[DEVELOPER 00:04:02] stderr: item2.X-ABLabel:Anniversary
[DEVELOPER 00:04:02] stderr: item3.X-ABDATE:2000-02-01
[DEVELOPER 00:04:02] stderr: item3.X-ABLabel:cu]
[DEVELOPER 00:04:02] stderr: Reading 1673 bytes of response body.
[DEVELOPER 00:04:02] stderr: Got 1187 bytes.
[DEVELOPER 00:04:02] stderr: Read block (1187 bytes):
[DEVELOPER 00:04:02] stderr: [stom-label3
[DEVELOPER 00:04:02] stderr: item4.X-ABLabel:Other
[DEVELOPER 00:04:02] stderr: item5.X-ABLabel:Other
[DEVELOPER 00:04:02] stderr: item6.X-ABLabel:Other
[DEVELOPER 00:04:02] stderr: item7.X-ABLabel:Other
[DEVELOPER 00:04:02] stderr: item8.X-ABLabel:Other
[DEVELOPER 00:04:02] stderr: item9.X-ABLabel:Other
[DEVELOPER 00:04:02] stderr: item10.X-ABLabel:Other
[DEVELOPER 00:04:02] stderr: item11.X-ABLabel:Other
[DEVELOPER 00:04:02] stderr: item12.X-ABLabel:Other
[DEVELOPER 00:04:02] stderr: item13.X-ABLabel:Google Voice
[DEVELOPER 00:04:02] stderr: item14.X-ABLabel:custom-label4
[DEVELOPER 00:04:02] stderr: item15.X-ABRELATEDNAMES:custom relationship
[DEVELOPER 00:04:02] stderr: item15.X-ABLabel:custom-label5 Umlaut ä Semicolon \; Backslash \\ newline
[DEVELOPER 00:04:02] stderr:  tab	end of label
[DEVELOPER 00:04:02] stderr: item16.X-ABRELATEDNAMES:domestic partner
[DEVELOPER 00:04:02] stderr: item16.X-ABLabel:domestic-partner-label
[DEVELOPER 00:04:02] stderr: item17.X-ABRELATEDNAMES:partner
[DEVELOPER 00:04:02] stderr: item17.X-ABLabel:Partner
[DEVELOPER 00:04:02] stderr: item18.X-ABRELATEDNAMES:referred-by
[DEVELOPER 00:04:02] stderr: item18.X-ABLabel:referred-by-label
[DEVELOPER 00:04:02] stderr: item19.X-ABRELATEDNAMES:relative
[DEVELOPER 00:04:02] stderr: item19.X-ABLabel:relative
[DEVELOPER 00:04:02] stderr: item20.X-ABRELATEDNAMES:friend
[DEVELOPER 00:04:02] stderr: item20.X-ABLabel:Friend
[DEVELOPER 00:04:02] stderr: item21.X-ABRELATEDNAMES:sister
[DEVELOPER 00:04:02] stderr: item21.X-ABLabel:Sister
[DEVELOPER 00:04:02] stderr: item22.X-ABRELATEDNAMES:brother
[DEVELOPER 00:04:02] stderr: item22.X-ABLabel:Brother
[DEVELOPER 00:04:02] stderr: item23.X-ABRELATEDNAMES:parent
[DEVELOPER 00:04:02] stderr: item23.X-ABLabel:Parent
[DEVELOPER 00:04:02] stderr: item24.X-ABRELATEDNAMES:father
[DEVELOPER 00:04:02] stderr: item24.X-ABLabel:Father
[DEVELOPER 00:04:02] stderr: item25.X-ABRELATEDNAMES:mother
[DEVELOPER 00:04:02] stderr: item25.X-ABLabel:Mother
[DEVELOPER 00:04:02] stderr: item26.X-ABRELATEDNAMES:child
[DEVELOPER 00:04:02] stderr: item26.X-ABLabel:Child
[DEVELOPER 00:04:02] stderr: item27.X-ABRELATEDNAMES:assistant
[DEVELOPER 00:04:02] stderr: item27.X-ABLabel:Assistant
[DEVELOPER 00:04:02] stderr: item28.X-ABRELATEDNAM]
[DEVELOPER 00:04:02] stderr: Reading 486 bytes of response body.
[DEVELOPER 00:04:02] stderr: Got 486 bytes.
[DEVELOPER 00:04:02] stderr: Read block (486 bytes):
[DEVELOPER 00:04:02] stderr: [ES:manager
[DEVELOPER 00:04:02] stderr: item28.X-ABLabel:Manager
[DEVELOPER 00:04:02] stderr: item29.X-ABRELATEDNAMES:spouse
[DEVELOPER 00:04:02] stderr: item29.X-ABLabel:Spouse
[DEVELOPER 00:04:02] stderr: item30.URL:http\://profile.com
[DEVELOPER 00:04:02] stderr: item30.X-ABLabel:Profile
[DEVELOPER 00:04:02] stderr: item31.URL:http\://blog.com
[DEVELOPER 00:04:02] stderr: item31.X-ABLabel:Blog
[DEVELOPER 00:04:02] stderr: item32.URL:http\://homepage.com
[DEVELOPER 00:04:02] stderr: item32.X-ABLabel:HomePage
[DEVELOPER 00:04:02] stderr: item33.URL:http\://company.com
[DEVELOPER 00:04:02] stderr: item33.X-ABLabel:Work
[DEVELOPER 00:04:02] stderr: item34.URL:http\://custom.com
[DEVELOPER 00:04:02] stderr: item34.X-ABLabel:Custom-label6 Backslash
[DEVELOPER 00:04:02] stderr: END:VCARD
[DEVELOPER 00:04:02] stderr: </card:address-data>
[DEVELOPER 00:04:02] stderr:    </d:prop>
[DEVELOPER 00:04:02] stderr:   </d:propstat>
[DEVELOPER 00:04:02] stderr:  </d:response>
[DEVELOPER 00:04:02] stderr: </d:multistatus>
[DEVELOPER 00:04:02] stderr: ]
[DEBUG 00:04:02] carddav: batch response: got 2827 bytes of data for 182b96310e5fbc3f
[DEVELOPER 00:04:02] stderr: [chunk] < 0
[DEVELOPER 00:04:02] stderr: Got chunk size: 0
[DEVELOPER 00:04:02] stderr: [hdr]
[DEVELOPER 00:04:02] stderr: End of headers.
[DEVELOPER 00:04:02] stderr: Running post_send hooks
[DEVELOPER 00:04:02] stderr: ah_post_send (#0), code is 207 (want 401), WWW-Authenticate is (none)
[DEBUG 00:04:02] credentials accepted
[DEVELOPER 00:04:02] stderr: Running destroy hooks.
[DEVELOPER 00:04:02] stderr: Request ends.
[DEBUG 00:04:02] carddav: reading 182b96310e5fbc3f from cache
[DEBUG 00:04:02] carddav: requested 1, retrieved 1 from server in 1 queries, misses 0/1 (0%)
[DEBUG 00:04:02] carddav: reading: set order 'none', 0 luids
[DEBUG 00:04:02] ClientTest.cpp:6235: ending CPPUNIT_ASSERT_MESSAGE("export " + peer + " " + peerSource, (cmdline->run()))
[DEBUG 00:04:02] ClientTest.cpp:6242: starting CPPUNIT_ASSERT(sources[0].second->compareDatabases(remoteSyncedTestdata, syncedRemoteData))
[DEBUG 00:04:02] ClientTest.cpp:871: starting equal = config.m_compare(client, refFile.c_str(), actualFile.c_str())
[DEBUG 00:04:02] ClientTest.cpp:871: ending equal = config.m_compare(client, refFile.c_str(), actualFile.c_str())
[DEBUG 00:04:02] ClientTest.cpp:872: starting CPPUNIT_ASSERT(!raiseAssert || equal)
[DEBUG 00:04:02] ClientTest.cpp:872: ending CPPUNIT_ASSERT(!raiseAssert || equal)
[DEBUG 00:04:02] ClientTest.cpp:6242: ending CPPUNIT_ASSERT(sources[0].second->compareDatabases(remoteSyncedTestdata, syncedRemoteData))
[DEBUG 00:04:02] ClientTest.cpp:391: starting reset(__null)
[DEBUG 00:04:02] ClientTest.cpp:454: starting CPPUNIT_ASSERT(get())
[DEBUG 00:04:02] ClientTest.cpp:454: ending CPPUNIT_ASSERT(get())
[DEBUG 00:04:02] ClientTest.cpp:455: starting CPPUNIT_ASSERT(m_active)
[DEBUG 00:04:02] ClientTest.cpp:455: ending CPPUNIT_ASSERT(m_active)
[DEBUG 00:04:02] ClientTest.cpp:458: starting get()->getOperations().m_endDataWrite.getPostSignal()(*get(), OPERATION_FINISHED, sysync::LOCERR_OK, true, &dummy)
[DEBUG 00:04:02] ClientTest.cpp:458: ending get()->getOperations().m_endDataWrite.getPostSignal()(*get(), OPERATION_FINISHED, sysync::LOCERR_OK, true, &dummy)
[DEBUG 00:04:02] ClientTest.cpp:461: starting anchor = get()->endSync(true)
[DEBUG 00:04:02] ClientTest.cpp:461: ending anchor = get()->endSync(true)
[DEBUG 00:04:02] ClientTest.cpp:463: starting get()->close()
[DEBUG 00:04:02] ClientTest.cpp:463: ending get()->close()
[DEBUG 00:04:02] ClientTest.cpp:391: ending reset(__null)
[DEBUG 00:04:02] *** ending Client::Sync::eds_contact::testDownload: okay ***