[DEBUG 00:00:00] *** starting Client::Source::file_event::testInsertTwice ***
[DEBUG 00:00:00] ClientTest.cpp:1221: starting CPPUNIT_ASSERT(config.m_createSourceA)
[DEBUG 00:00:00] ClientTest.cpp:1221: ending CPPUNIT_ASSERT(config.m_createSourceA)
[DEBUG 00:00:00] ClientTest.cpp:1222: starting CPPUNIT_ASSERT(!config.m_insertItem.empty())
[DEBUG 00:00:00] ClientTest.cpp:1222: ending CPPUNIT_ASSERT(!config.m_insertItem.empty())
[DEBUG 00:00:00] ClientTest.cpp:1223: starting CPPUNIT_ASSERT(config.m_insertItem.find("\nUID:") != std::string::npos)
[DEBUG 00:00:00] ClientTest.cpp:1223: ending CPPUNIT_ASSERT(config.m_insertItem.find("\nUID:") != std::string::npos)
[DEBUG 00:00:00] ClientTest.cpp:1224: starting deleteAll(createSourceA)
[DEBUG 00:00:00] ClientTest.cpp:802: starting CPPUNIT_ASSERT(createSource.createSource)
[DEBUG 00:00:00] ClientTest.cpp:802: ending CPPUNIT_ASSERT(createSource.createSource)
[DEBUG 00:00:00] ClientTest.cpp:807: starting source.reset(createSource())
[DEBUG 00:00:00] instantiating testing source file_event in config target-config@client-test-file, with tracking name _1_A
[DEBUG 00:00:00] overriding testing source file_event properties with the ones from config file_1 = /data/runtests/work/downstream-unstable/home/file/config/syncevolution/client-test-1/peers/file_1
[DEBUG 00:00:00]    sync = disabled (set)
[DEBUG 00:00:00]    uri = cal2 (set)
[DEBUG 00:00:00]    backend = file (set)
[DEBUG 00:00:00]    syncFormat =  (default)
[DEBUG 00:00:00]    forceSyncFormat = 0 (default)
[DEBUG 00:00:00]    database = Test_file_event_1 (set)
[DEBUG 00:00:00]    databaseFormat =  (default)
[DEBUG 00:00:00]    databaseUser =  (default)
[DEBUG 00:00:00]    databasePassword =  (default)
[DEBUG 00:00:00] ClientTest.cpp:424: starting CPPUNIT_ASSERT(get())
[DEBUG 00:00:00] ClientTest.cpp:424: ending CPPUNIT_ASSERT(get())
[DEBUG 00:00:00] ClientTest.cpp:425: starting CPPUNIT_ASSERT(!m_active)
[DEBUG 00:00:00] ClientTest.cpp:425: ending CPPUNIT_ASSERT(!m_active)
[DEBUG 00:00:00] ClientTest.cpp:431: starting get()->open()
[DEBUG 00:00:00] ClientTest.cpp:431: ending get()->open()
[DEBUG 00:00:00] file_event: slow sync or testing, do full item scan to detect changes
[DEBUG 00:00:00] ClientTest.cpp:807: ending source.reset(createSource())
[DEBUG 00:00:00] ClientTest.cpp:807: starting CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:00] ClientTest.cpp:807: ending CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:00] ClientTest.cpp:810: starting source->removeAllItems()
[DEBUG 00:00:00] ClientTest.cpp:810: ending source->removeAllItems()
[DEBUG 00:00:00] ClientTest.cpp:810: starting CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:00] ClientTest.cpp:810: ending CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:00] ClientTest.cpp:811: starting source.reset()
[DEBUG 00:00:00] ClientTest.cpp:454: starting CPPUNIT_ASSERT(get())
[DEBUG 00:00:00] ClientTest.cpp:454: ending CPPUNIT_ASSERT(get())
[DEBUG 00:00:00] ClientTest.cpp:455: starting CPPUNIT_ASSERT(m_active)
[DEBUG 00:00:00] ClientTest.cpp:455: ending CPPUNIT_ASSERT(m_active)
[DEBUG 00:00:00] ClientTest.cpp:458: starting get()->getOperations().m_endDataWrite.getPostSignal()(*get(), OPERATION_FINISHED, sysync::LOCERR_OK, true, &dummy)
[DEBUG 00:00:01] ClientTest.cpp:458: ending get()->getOperations().m_endDataWrite.getPostSignal()(*get(), OPERATION_FINISHED, sysync::LOCERR_OK, true, &dummy)
[DEBUG 00:00:01] ClientTest.cpp:461: starting anchor = get()->endSync(true)
[DEBUG 00:00:01] ClientTest.cpp:461: ending anchor = get()->endSync(true)
[DEBUG 00:00:01] ClientTest.cpp:463: starting get()->close()
[DEBUG 00:00:01] ClientTest.cpp:463: ending get()->close()
[DEBUG 00:00:01] ClientTest.cpp:811: ending source.reset()
[DEBUG 00:00:01] ClientTest.cpp:814: starting source.reset(createSource())
[DEBUG 00:00:01] instantiating testing source file_event in config target-config@client-test-file, with tracking name _1_A
[DEBUG 00:00:01] overriding testing source file_event properties with the ones from config file_1 = /data/runtests/work/downstream-unstable/home/file/config/syncevolution/client-test-1/peers/file_1
[DEBUG 00:00:01]    sync = disabled (set)
[DEBUG 00:00:01]    uri = cal2 (set)
[DEBUG 00:00:01]    backend = file (set)
[DEBUG 00:00:01]    syncFormat =  (default)
[DEBUG 00:00:01]    forceSyncFormat = 0 (default)
[DEBUG 00:00:01]    database = Test_file_event_1 (set)
[DEBUG 00:00:01]    databaseFormat =  (default)
[DEBUG 00:00:01]    databaseUser =  (default)
[DEBUG 00:00:01]    databasePassword =  (default)
[DEBUG 00:00:01] ClientTest.cpp:424: starting CPPUNIT_ASSERT(get())
[DEBUG 00:00:01] ClientTest.cpp:424: ending CPPUNIT_ASSERT(get())
[DEBUG 00:00:01] ClientTest.cpp:425: starting CPPUNIT_ASSERT(!m_active)
[DEBUG 00:00:01] ClientTest.cpp:425: ending CPPUNIT_ASSERT(!m_active)
[DEBUG 00:00:01] ClientTest.cpp:431: starting get()->open()
[DEBUG 00:00:01] ClientTest.cpp:431: ending get()->open()
[DEBUG 00:00:01] file_event: using full item scan to detect changes
[DEBUG 00:00:01] ClientTest.cpp:814: ending source.reset(createSource())
[DEBUG 00:00:01] ClientTest.cpp:814: starting CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:01] ClientTest.cpp:814: ending CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:01] ClientTest.cpp:818: starting CPPUNIT_ASSERT_MESSAGE(("should be empty now"), ((countItems(source.get()) == 0)))
[DEBUG 00:00:01] ClientTest.cpp:818: ending CPPUNIT_ASSERT_MESSAGE(("should be empty now"), ((countItems(source.get()) == 0)))
[DEBUG 00:00:01] ClientTest.cpp:818: starting CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:01] ClientTest.cpp:818: ending CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:01] ClientTest.cpp:819: starting CPPUNIT_ASSERT_EQUAL((0),(countNewItems(source.get())))
[DEBUG 00:00:01] ClientTest.cpp:819: ending CPPUNIT_ASSERT_EQUAL((0),(countNewItems(source.get())))
[DEBUG 00:00:01] ClientTest.cpp:820: starting CPPUNIT_ASSERT_EQUAL((0),(countUpdatedItems(source.get())))
[DEBUG 00:00:01] ClientTest.cpp:820: ending CPPUNIT_ASSERT_EQUAL((0),(countUpdatedItems(source.get())))
[DEBUG 00:00:01] ClientTest.cpp:821: starting CPPUNIT_ASSERT_EQUAL((0),(countDeletedItems(source.get())))
[DEBUG 00:00:01] ClientTest.cpp:821: ending CPPUNIT_ASSERT_EQUAL((0),(countDeletedItems(source.get())))
[DEBUG 00:00:01] ClientTest.cpp:391: starting reset(__null)
[DEBUG 00:00:01] ClientTest.cpp:454: starting CPPUNIT_ASSERT(get())
[DEBUG 00:00:01] ClientTest.cpp:454: ending CPPUNIT_ASSERT(get())
[DEBUG 00:00:01] ClientTest.cpp:455: starting CPPUNIT_ASSERT(m_active)
[DEBUG 00:00:01] ClientTest.cpp:455: ending CPPUNIT_ASSERT(m_active)
[DEBUG 00:00:01] ClientTest.cpp:458: starting get()->getOperations().m_endDataWrite.getPostSignal()(*get(), OPERATION_FINISHED, sysync::LOCERR_OK, true, &dummy)
[DEBUG 00:00:01] ClientTest.cpp:458: ending get()->getOperations().m_endDataWrite.getPostSignal()(*get(), OPERATION_FINISHED, sysync::LOCERR_OK, true, &dummy)
[DEBUG 00:00:01] ClientTest.cpp:461: starting anchor = get()->endSync(true)
[DEBUG 00:00:01] ClientTest.cpp:461: ending anchor = get()->endSync(true)
[DEBUG 00:00:01] ClientTest.cpp:463: starting get()->close()
[DEBUG 00:00:01] ClientTest.cpp:463: ending get()->close()
[DEBUG 00:00:01] ClientTest.cpp:391: ending reset(__null)
[DEBUG 00:00:01] ClientTest.cpp:1224: ending deleteAll(createSourceA)
[DEBUG 00:00:01] ClientTest.cpp:1228: starting source.reset(createSourceA())
[DEBUG 00:00:01] instantiating testing source file_event in config target-config@client-test-file, with tracking name _1_A
[DEBUG 00:00:01] overriding testing source file_event properties with the ones from config file_1 = /data/runtests/work/downstream-unstable/home/file/config/syncevolution/client-test-1/peers/file_1
[DEBUG 00:00:01]    sync = disabled (set)
[DEBUG 00:00:01]    uri = cal2 (set)
[DEBUG 00:00:01]    backend = file (set)
[DEBUG 00:00:01]    syncFormat =  (default)
[DEBUG 00:00:01]    forceSyncFormat = 0 (default)
[DEBUG 00:00:01]    database = Test_file_event_1 (set)
[DEBUG 00:00:01]    databaseFormat =  (default)
[DEBUG 00:00:01]    databaseUser =  (default)
[DEBUG 00:00:01]    databasePassword =  (default)
[DEBUG 00:00:01] ClientTest.cpp:424: starting CPPUNIT_ASSERT(get())
[DEBUG 00:00:01] ClientTest.cpp:424: ending CPPUNIT_ASSERT(get())
[DEBUG 00:00:01] ClientTest.cpp:425: starting CPPUNIT_ASSERT(!m_active)
[DEBUG 00:00:01] ClientTest.cpp:425: ending CPPUNIT_ASSERT(!m_active)
[DEBUG 00:00:01] ClientTest.cpp:431: starting get()->open()
[DEBUG 00:00:01] ClientTest.cpp:431: ending get()->open()
[DEBUG 00:00:01] file_event: using full item scan to detect changes
[DEBUG 00:00:01] ClientTest.cpp:1228: ending source.reset(createSourceA())
[DEBUG 00:00:01] ClientTest.cpp:1228: starting CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:01] ClientTest.cpp:1228: ending CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:01] ClientTest.cpp:1235: starting first = source->insertItemRaw("", data)
[DEBUG 00:00:01] ClientTest.cpp:1235: ending first = source->insertItemRaw("", data)
[DEBUG 00:00:01] ClientTest.cpp:1235: starting CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:01] ClientTest.cpp:1235: ending CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:01] ClientTest.cpp:1236: starting CPPUNIT_ASSERT_EQUAL((ITEM_OKAY),(first.m_state))
[DEBUG 00:00:01] ClientTest.cpp:1236: ending CPPUNIT_ASSERT_EQUAL((ITEM_OKAY),(first.m_state))
[DEBUG 00:00:01] ClientTest.cpp:1240: starting second = source->insertItemRaw("", data)
[DEBUG 00:00:01] ClientTest.cpp:1240: ending second = source->insertItemRaw("", data)
[DEBUG 00:00:01] ClientTest.cpp:1240: starting CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:01] ClientTest.cpp:1240: ending CPPUNIT_ASSERT((source.get()))
[DEBUG 00:00:01]
[DEBUG 00:00:01] ClientTest.cpp:1246 *** item was added, which is broken!
[DEBUG 00:00:01] ClientTest.cpp:1251: starting CPPUNIT_ASSERT(second.m_state == ITEM_OKAY)
[DEBUG 00:00:01] ClientTest.cpp:1251: ending CPPUNIT_ASSERT(second.m_state == ITEM_OKAY)
[DEBUG 00:00:01] ClientTest.cpp:1252: starting CPPUNIT_ASSERT(first.m_luid != second.m_luid)
[DEBUG 00:00:01] ClientTest.cpp:1252: ending CPPUNIT_ASSERT(first.m_luid != second.m_luid)
[DEBUG 00:00:01] ClientTest.cpp:391: starting reset(__null)
[DEBUG 00:00:01] ClientTest.cpp:454: starting CPPUNIT_ASSERT(get())
[DEBUG 00:00:01] ClientTest.cpp:454: ending CPPUNIT_ASSERT(get())
[DEBUG 00:00:01] ClientTest.cpp:455: starting CPPUNIT_ASSERT(m_active)
[DEBUG 00:00:01] ClientTest.cpp:455: ending CPPUNIT_ASSERT(m_active)
[DEBUG 00:00:01] ClientTest.cpp:458: starting get()->getOperations().m_endDataWrite.getPostSignal()(*get(), OPERATION_FINISHED, sysync::LOCERR_OK, true, &dummy)
[DEBUG 00:00:02] ClientTest.cpp:458: ending get()->getOperations().m_endDataWrite.getPostSignal()(*get(), OPERATION_FINISHED, sysync::LOCERR_OK, true, &dummy)
[DEBUG 00:00:02] ClientTest.cpp:461: starting anchor = get()->endSync(true)
[DEBUG 00:00:02] ClientTest.cpp:461: ending anchor = get()->endSync(true)
[DEBUG 00:00:02] ClientTest.cpp:463: starting get()->close()
[DEBUG 00:00:02] ClientTest.cpp:463: ending get()->close()
[DEBUG 00:00:02] ClientTest.cpp:391: ending reset(__null)
[DEBUG 00:00:02] *** ending Client::Source::file_event::testInsertTwice: okay ***