[DRE-commits] [ruby-diaspora-vines] 08/08: build changes
Praveen Arimbrathodiyil
praveen at moszumanska.debian.org
Tue Jun 7 08:58:16 UTC 2016
This is an automated email from the git hooks/post-receive script.
praveen pushed a commit to branch master
in repository ruby-diaspora-vines.
commit e5e924c1bc399023f0512c14eac72a5f8a337096
Author: sudheesh <sudheeshshetty at gmail.com>
Date: Fri Feb 12 23:54:54 2016 +0530
build changes
---
debian/control | 12 +-
debian/patches/storage_test_patch.diff | 5 +
debian/ruby-diaspora-vines.debhelper.log | 1 +
debian/ruby-diaspora-vines/DEBIAN/control | 4 +-
debian/ruby-diaspora-vines/DEBIAN/md5sums | 1 -
debian/ruby-diaspora-vines/usr/bin/vines | 4 -
debian/rules | 3 +
vines.log | 331 ++++++++++++------------------
8 files changed, 150 insertions(+), 211 deletions(-)
diff --git a/debian/control b/debian/control
index 4432e66..518bc71 100644
--- a/debian/control
+++ b/debian/control
@@ -3,8 +3,14 @@ Section: ruby
Priority: optional
Maintainer: Debian Ruby Extras Maintainers <pkg-ruby-extras-maintainers at lists.alioth.debian.org>
Uploaders: Sudheesh Shetty <sudheeshshetty at gmail.com>
-Build-Depends: debhelper (>= 7.0.50~), gem2deb (>= 0.6.1~), ruby-rails, ruby-bcrypt,ruby-em-hiredis,ruby-http-parser.rb, ruby-sqlite3
-Standards-Version: 3.9.5
+Build-Depends: debhelper (>= 9.0),
+ gem2deb (>= 0.6.1~),
+ ruby-bcrypt,
+ ruby-em-hiredis,
+ ruby-http-parser.rb,
+ ruby-rails,
+ ruby-sqlite3
+Standards-Version: 3.9.7
Homepage: https://github.com/diaspora/vines
XS-Ruby-Versions: all
@@ -20,7 +26,7 @@ Depends: ruby | ruby-interpreter,
ruby-nokogiri (>= 1.6),
${misc:Depends},
${shlibs:Depends}
-Description: Diaspora-vines is a Vines fork build for diaspora integration
+Description: Vines fork build for diaspora integration
The vines gem installs a fully standards-compliant XMPP chat server.
Vines is an XMPP chat server that connects you with large clusters easily.
DO NOT use it unless you know what you are doing!
diff --git a/debian/patches/storage_test_patch.diff b/debian/patches/storage_test_patch.diff
index 7842dec..37c0dff 100644
--- a/debian/patches/storage_test_patch.diff
+++ b/debian/patches/storage_test_patch.diff
@@ -1,3 +1,8 @@
+Description: Remove requirements on bundler and rubygems
+Author: Sudheesh Shetty <sudheeshshetty at gmail.com>
+Last-Update: 2016-02-12
+---
+This patch header follows DEP-3: http://dep.debian.net/deps/dep3/
--- a/test/store_test.rb
+++ b/test/store_test.rb
@@ -37,10 +37,10 @@
diff --git a/debian/ruby-diaspora-vines.debhelper.log b/debian/ruby-diaspora-vines.debhelper.log
index c4f912d..63f42d6 100644
--- a/debian/ruby-diaspora-vines.debhelper.log
+++ b/debian/ruby-diaspora-vines.debhelper.log
@@ -3,6 +3,7 @@ dh_auto_build
dh_auto_test
dh_prep
dh_auto_install
+override_dh_install dh_install
dh_install
dh_installdocs
dh_installchangelogs
diff --git a/debian/ruby-diaspora-vines/DEBIAN/control b/debian/ruby-diaspora-vines/DEBIAN/control
index 4cc1570..77e676b 100644
--- a/debian/ruby-diaspora-vines/DEBIAN/control
+++ b/debian/ruby-diaspora-vines/DEBIAN/control
@@ -2,12 +2,12 @@ Package: ruby-diaspora-vines
Version: 0.2.0~develop.4-1
Architecture: all
Maintainer: Debian Ruby Extras Maintainers <pkg-ruby-extras-maintainers at lists.alioth.debian.org>
-Installed-Size: 375
+Installed-Size: 371
Depends: ruby | ruby-interpreter, ruby-activerecord (>= 4.1), ruby-bcrypt (>= 3.1), ruby-em-hiredis (>= 0.3.0), ruby-eventmachine (>= 1.0.8), ruby-http-parser.rb (>= 0.6), ruby-nokogiri (>= 1.6)
Section: ruby
Priority: optional
Homepage: https://github.com/diaspora/vines
-Description: Diaspora-vines is a Vines fork build for diaspora integration
+Description: Vines fork build for diaspora integration
The vines gem installs a fully standards-compliant XMPP chat server.
Vines is an XMPP chat server that connects you with large clusters easily.
DO NOT use it unless you know what you are doing!
diff --git a/debian/ruby-diaspora-vines/DEBIAN/md5sums b/debian/ruby-diaspora-vines/DEBIAN/md5sums
index 7afaedd..c840134 100644
--- a/debian/ruby-diaspora-vines/DEBIAN/md5sums
+++ b/debian/ruby-diaspora-vines/DEBIAN/md5sums
@@ -1,4 +1,3 @@
-89067bf8a7453cf53de38aa62379caaa usr/bin/vines
4cebb877edead3b1bd8915e4ad6dba48 usr/lib/ruby/vendor_ruby/vines.rb
2dc850867be96859426755728e267af3 usr/lib/ruby/vendor_ruby/vines/cli.rb
badf6a7a9fc46ee0067eb13b5599fa77 usr/lib/ruby/vendor_ruby/vines/cluster.rb
diff --git a/debian/ruby-diaspora-vines/usr/bin/vines b/debian/ruby-diaspora-vines/usr/bin/vines
deleted file mode 100755
index dd89747..0000000
--- a/debian/ruby-diaspora-vines/usr/bin/vines
+++ /dev/null
@@ -1,4 +0,0 @@
-#!/usr/bin/env ruby
-
-require 'vines'
-Vines::CLI.start
diff --git a/debian/rules b/debian/rules
index e3d5e43..1cba1e2 100755
--- a/debian/rules
+++ b/debian/rules
@@ -16,3 +16,6 @@
override_dh_clean:
rm -rf vines.log
dh_clean
+override_dh_install:
+ dh_install
+ rm -rf debian/ruby-diaspora-vines/usr/bin
diff --git a/vines.log b/vines.log
index 29554ce..deb56da 100644
--- a/vines.log
+++ b/vines.log
@@ -1,201 +1,130 @@
-D, [2016-02-12T08:58:18.874851 #5510] DEBUG -- : [1m[36m (163.6ms)[0m [1mCREATE TABLE "people" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "guid" varchar(255) NOT NULL, "url" text NOT NULL, "diaspora_handle" varchar(255) NOT NULL, "serialized_public_key" text NOT NULL, "owner_id" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "closed_account" boolean DEFAULT 'f', "fetch_status" integer DEFAULT 0) [0m
-D, [2016-02-12T08:58:19.008720 #5510] DEBUG -- : [1m[35m (132.6ms)[0m CREATE UNIQUE INDEX "index_people_on_diaspora_handle" ON "people" ("diaspora_handle")
-D, [2016-02-12T08:58:19.142427 #5510] DEBUG -- : [1m[36m (131.5ms)[0m [1mCREATE UNIQUE INDEX "index_people_on_guid" ON "people" ("guid")[0m
-D, [2016-02-12T08:58:19.276078 #5510] DEBUG -- : [1m[35m (131.2ms)[0m CREATE UNIQUE INDEX "index_people_on_owner_id" ON "people" ("owner_id")
-D, [2016-02-12T08:58:19.409757 #5510] DEBUG -- : [1m[36m (131.0ms)[0m [1mCREATE TABLE "profiles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "diaspora_handle" varchar(255), "first_name" varchar(127), "last_name" varchar(127), "image_url" varchar(255), "image_url_small" varchar(255), "image_url_medium" varchar(255), "birthday" date, "gender" varchar(255), "bio" text, "searchable" boolean DEFAULT 't' NOT NULL, "person_id" integer NOT NULL, "created_at" datetime NOT NULL, "upda [...]
-D, [2016-02-12T08:58:19.543422 #5510] DEBUG -- : [1m[35m (132.4ms)[0m CREATE INDEX "index_profiles_on_full_name_and_searchable" ON "profiles" ("full_name", "searchable")
-D, [2016-02-12T08:58:19.677072 #5510] DEBUG -- : [1m[36m (131.4ms)[0m [1mCREATE INDEX "index_profiles_on_full_name" ON "profiles" ("full_name")[0m
-D, [2016-02-12T08:58:19.788423 #5510] DEBUG -- : [1m[35m (109.1ms)[0m CREATE INDEX "index_profiles_on_person_id" ON "profiles" ("person_id")
-D, [2016-02-12T08:58:19.911183 #5510] DEBUG -- : [1m[36m (120.7ms)[0m [1mCREATE TABLE "aspects" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255) NOT NULL, "user_id" integer NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "contacts_visible" boolean DEFAULT 't' NOT NULL, "order_id" integer, "chat_enabled" boolean DEFAULT 'f') [0m
-D, [2016-02-12T08:58:20.011614 #5510] DEBUG -- : [1m[35m (98.8ms)[0m CREATE INDEX "index_aspects_on_user_id_and_contacts_visible" ON "aspects" ("user_id", "contacts_visible")
-D, [2016-02-12T08:58:20.111981 #5510] DEBUG -- : [1m[36m (98.2ms)[0m [1mCREATE INDEX "index_aspects_on_user_id" ON "aspects" ("user_id")[0m
-D, [2016-02-12T08:58:20.212416 #5510] DEBUG -- : [1m[35m (98.5ms)[0m CREATE TABLE "aspect_memberships" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "aspect_id" integer NOT NULL, "contact_id" integer NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
-D, [2016-02-12T08:58:20.312687 #5510] DEBUG -- : [1m[36m (98.8ms)[0m [1mCREATE UNIQUE INDEX "index_aspect_memberships_on_aspect_id_and_contact_id" ON "aspect_memberships" ("aspect_id", "contact_id")[0m
-D, [2016-02-12T08:58:20.413105 #5510] DEBUG -- : [1m[35m (98.6ms)[0m CREATE INDEX "index_aspect_memberships_on_aspect_id" ON "aspect_memberships" ("aspect_id")
-D, [2016-02-12T08:58:20.513541 #5510] DEBUG -- : [1m[36m (97.8ms)[0m [1mCREATE INDEX "index_aspect_memberships_on_contact_id" ON "aspect_memberships" ("contact_id")[0m
-D, [2016-02-12T08:58:20.636149 #5510] DEBUG -- : [1m[35m (120.5ms)[0m CREATE TABLE "contacts" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer NOT NULL, "person_id" integer NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "sharing" boolean DEFAULT 'f' NOT NULL, "receiving" boolean DEFAULT 'f' NOT NULL)
-D, [2016-02-12T08:58:20.736539 #5510] DEBUG -- : [1m[36m (98.9ms)[0m [1mCREATE INDEX "index_contacts_on_person_id" ON "contacts" ("person_id")[0m
-D, [2016-02-12T08:58:20.848111 #5510] DEBUG -- : [1m[35m (109.3ms)[0m CREATE UNIQUE INDEX "index_contacts_on_user_id_and_person_id" ON "contacts" ("user_id", "person_id")
-D, [2016-02-12T08:58:20.959673 #5510] DEBUG -- : [1m[36m (109.8ms)[0m [1mCREATE TABLE "chat_contacts" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer NOT NULL, "jid" varchar(255) NOT NULL, "name" varchar(255), "ask" varchar(128), "subscription" varchar(128) NOT NULL, "groups" text) [0m
-D, [2016-02-12T08:58:21.094974 #5510] DEBUG -- : [1m[35m (133.9ms)[0m CREATE UNIQUE INDEX "index_chat_contacts_on_user_id_and_jid" ON "chat_contacts" ("user_id", "jid")
-D, [2016-02-12T08:58:21.228850 #5510] DEBUG -- : [1m[36m (132.2ms)[0m [1mCREATE TABLE "chat_fragments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer NOT NULL, "root" varchar(256) NOT NULL, "namespace" varchar(256) NOT NULL, "xml" text NOT NULL) [0m
-D, [2016-02-12T08:58:21.362527 #5510] DEBUG -- : [1m[35m (132.3ms)[0m CREATE UNIQUE INDEX "index_chat_fragments_on_user_id" ON "chat_fragments" ("user_id")
-D, [2016-02-12T08:58:21.474061 #5510] DEBUG -- : [1m[36m (109.5ms)[0m [1mCREATE TABLE "chat_offline_messages" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "from" varchar(255) NOT NULL, "to" varchar(255) NOT NULL, "message" text NOT NULL, "created_at" datetime NOT NULL) [0m
-D, [2016-02-12T08:58:21.607878 #5510] DEBUG -- : [1m[35m (130.4ms)[0m CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "username" varchar(255), "serialized_private_key" text, "getting_started" boolean DEFAULT 't' NOT NULL, "disable_mail" boolean DEFAULT 'f' NOT NULL, "language" varchar(255), "email" varchar(255) DEFAULT '' NOT NULL, "encrypted_password" varchar(255) DEFAULT '' NOT NULL, "invitation_token" varchar(60), "invitation_sent_at" datetime, "reset_pass [...]
-D, [2016-02-12T08:58:21.741424 #5510] DEBUG -- : [1m[36m (132.1ms)[0m [1mCREATE UNIQUE INDEX "index_users_on_authentication_token" ON "users" ("authentication_token")[0m
-D, [2016-02-12T08:58:21.875117 #5510] DEBUG -- : [1m[35m (131.8ms)[0m CREATE INDEX "index_users_on_email" ON "users" ("email")
-D, [2016-02-12T08:58:21.997611 #5510] DEBUG -- : [1m[36m (119.9ms)[0m [1mCREATE UNIQUE INDEX "index_users_on_invitation_service_and_invitation_identifier" ON "users" ("invitation_service", "invitation_identifier")[0m
-D, [2016-02-12T08:58:22.098095 #5510] DEBUG -- : [1m[35m (98.0ms)[0m CREATE INDEX "index_users_on_invitation_token" ON "users" ("invitation_token")
-D, [2016-02-12T08:58:22.198457 #5510] DEBUG -- : [1m[36m (97.1ms)[0m [1mCREATE UNIQUE INDEX "index_users_on_username" ON "users" ("username")[0m
-D, [2016-02-12T08:58:22.225219 #5510] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
-D, [2016-02-12T08:58:22.228744 #5510] DEBUG -- : [1m[36mSQL (0.5ms)[0m [1mINSERT INTO "users" ("authentication_token", "created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?)[0m [["authentication_token", "1234"], ["created_at", 2016-02-12 03:28:22 UTC], ["email", "test at test.de"], ["encrypted_password", "$2a$10$c2G6rHjGeamQIOFI0c1/b.4mvFBw4AfOtgVrAkO1QPMuAyporj5e6"], ["updated_at", 2016-02-12 03:28:22 UTC], ["username", "test"]]
-D, [2016-02-12T08:58:22.321087 #5510] DEBUG -- : [1m[35m (92.0ms)[0m commit transaction
-D, [2016-02-12T08:58:22.337338 #5510] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
-D, [2016-02-12T08:58:22.339732 #5510] DEBUG -- : [1m[35mSQL (1.1ms)[0m INSERT INTO "people" ("created_at", "diaspora_handle", "guid", "owner_id", "serialized_public_key", "updated_at", "url") VALUES (?, ?, ?, ?, ?, ?, ?) [["created_at", 2016-02-12 03:28:22 UTC], ["diaspora_handle", "test at local.host"], ["guid", "1697a4b0198901321e9b10e6ba921ce9"], ["owner_id", 1], ["serialized_public_key", "some pub key"], ["updated_at", 2016-02-12 03:28:22 UTC], ["url", "http://remote.host/"]]
-D, [2016-02-12T08:58:22.443613 #5510] DEBUG -- : [1m[36m (103.2ms)[0m [1mcommit transaction[0m
-D, [2016-02-12T08:58:22.461921 #5510] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
-D, [2016-02-12T08:58:22.464415 #5510] DEBUG -- : [1m[36mSQL (1.2ms)[0m [1mINSERT INTO "profiles" ("created_at", "diaspora_handle", "first_name", "image_url", "last_name", "person_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)[0m [["created_at", 2016-02-12 03:28:22 UTC], ["diaspora_handle", "test at local.host"], ["first_name", "Harry"], ["image_url", "http://path.to/image.png"], ["last_name", "Hirsch"], ["person_id", 1], ["updated_at", 2016-02-12 03:28:22 UTC]]
-D, [2016-02-12T08:58:22.566101 #5510] DEBUG -- : [1m[35m (101.1ms)[0m commit transaction
-D, [2016-02-12T08:58:22.590992 #5510] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
-D, [2016-02-12T08:58:22.592820 #5510] DEBUG -- : [1m[35mSQL (0.5ms)[0m INSERT INTO "contacts" ("created_at", "person_id", "receiving", "sharing", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", 2016-02-12 03:28:22 UTC], ["person_id", 1], ["receiving", true], ["sharing", true], ["updated_at", 2016-02-12 03:28:22 UTC], ["user_id", 1]]
-D, [2016-02-12T08:58:22.688630 #5510] DEBUG -- : [1m[36m (95.5ms)[0m [1mcommit transaction[0m
-D, [2016-02-12T08:58:22.717678 #5510] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
-D, [2016-02-12T08:58:22.719930 #5510] DEBUG -- : [1m[36mSQL (0.9ms)[0m [1mINSERT INTO "aspects" ("created_at", "name", "updated_at", "user_id") VALUES (?, ?, ?, ?)[0m [["created_at", 2016-02-12 03:28:22 UTC], ["name", "without_chat"], ["updated_at", 2016-02-12 03:28:22 UTC], ["user_id", 1]]
-D, [2016-02-12T08:58:22.822355 #5510] DEBUG -- : [1m[35m (101.8ms)[0m commit transaction
-D, [2016-02-12T08:58:22.831742 #5510] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
-D, [2016-02-12T08:58:22.834692 #5510] DEBUG -- : [1m[35mSQL (1.1ms)[0m INSERT INTO "aspect_memberships" ("aspect_id", "contact_id", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["aspect_id", 1], ["contact_id", 1], ["created_at", 2016-02-12 03:28:22 UTC], ["updated_at", 2016-02-12 03:28:22 UTC]]
-D, [2016-02-12T08:58:22.933775 #5510] DEBUG -- : [1m[36m (98.5ms)[0m [1mcommit transaction[0m
-D, [2016-02-12T08:58:22.975602 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Person Load (0.2ms)[0m SELECT "people".* FROM "people" WHERE "people"."diaspora_handle" = 'someone at inthe.void' LIMIT 1
-D, [2016-02-12T08:58:22.977280 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.4ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."diaspora_handle" = 'test at local.host' LIMIT 1[0m
-D, [2016-02-12T08:58:23.005436 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Profile Load (0.3ms)[0m SELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1 [["person_id", 1]]
-D, [2016-02-12T08:58:23.010183 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:23.017173 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Person Load (0.4ms)[0m SELECT "people".* FROM "people" WHERE "people"."diaspora_handle" = 'test at local.host' LIMIT 1
-D, [2016-02-12T08:58:23.021585 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Profile Load (0.5ms)[0m [1mSELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1[0m [["person_id", 1]]
-D, [2016-02-12T08:58:23.027355 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
-D, [2016-02-12T08:58:23.076979 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.5ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1[0m
-D, [2016-02-12T08:58:23.093832 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Contact Load (0.3ms)[0m SELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id [["user_id", 1]]
-D, [2016-02-12T08:58:23.095820 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatContact Load (0.2ms)[0m [1mSELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ?[0m [["user_id", 1]]
-D, [2016-02-12T08:58:23.200439 #5510] DEBUG -- : [1m[35mSQL (104.0ms)[0m UPDATE "aspects" SET "name" = 'with_chat', "chat_enabled" = 't' WHERE "aspects"."id" = 1
-D, [2016-02-12T08:58:23.207448 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.7ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1[0m
-D, [2016-02-12T08:58:23.212876 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Contact Load (0.4ms)[0m SELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id [["user_id", 1]]
-D, [2016-02-12T08:58:23.217374 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.3ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1[0m [["id", 1]]
-D, [2016-02-12T08:58:23.219504 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Profile Load (0.3ms)[0m SELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1 [["person_id", 1]]
-D, [2016-02-12T08:58:23.225305 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Aspect Load (0.3ms)[0m [1mSELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ?[0m [["contact_id", 1]]
-D, [2016-02-12T08:58:23.227587 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::ChatContact Load (0.2ms)[0m SELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ? [["user_id", 1]]
-D, [2016-02-12T08:58:23.229452 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.2ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:23.256012 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::User Load (0.3ms)[0m SELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1
-D, [2016-02-12T08:58:23.261822 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Contact Load (0.4ms)[0m [1mSELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id[0m [["user_id", 1]]
-D, [2016-02-12T08:58:23.263914 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Person Load (0.3ms)[0m SELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1 [["id", 1]]
-D, [2016-02-12T08:58:23.266157 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Profile Load (0.3ms)[0m [1mSELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1[0m [["person_id", 1]]
-D, [2016-02-12T08:58:23.267814 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Aspect Load (0.4ms)[0m SELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ? [["contact_id", 1]]
-D, [2016-02-12T08:58:23.269755 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatContact Load (0.3ms)[0m [1mSELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ?[0m [["user_id", 1]]
-D, [2016-02-12T08:58:23.271597 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::User Load (0.5ms)[0m SELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1
-D, [2016-02-12T08:58:23.273928 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Contact Load (0.3ms)[0m [1mSELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id[0m [["user_id", 1]]
-D, [2016-02-12T08:58:23.275484 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Person Load (0.3ms)[0m SELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1 [["id", 1]]
-D, [2016-02-12T08:58:23.276996 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Profile Load (0.3ms)[0m [1mSELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1[0m [["person_id", 1]]
-D, [2016-02-12T08:58:23.278858 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Aspect Load (0.2ms)[0m SELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ? [["contact_id", 1]]
-D, [2016-02-12T08:58:23.280363 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatContact Load (0.2ms)[0m [1mSELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ?[0m [["user_id", 1]]
-D, [2016-02-12T08:58:23.282222 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::User Load (0.6ms)[0m SELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1
-D, [2016-02-12T08:58:23.284496 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Contact Load (0.3ms)[0m [1mSELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id[0m [["user_id", 1]]
-D, [2016-02-12T08:58:23.286050 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Person Load (0.2ms)[0m SELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1 [["id", 1]]
-D, [2016-02-12T08:58:23.287497 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Profile Load (0.2ms)[0m [1mSELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1[0m [["person_id", 1]]
-D, [2016-02-12T08:58:23.289345 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Aspect Load (0.2ms)[0m SELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ? [["contact_id", 1]]
-D, [2016-02-12T08:58:23.290800 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatContact Load (0.2ms)[0m [1mSELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ?[0m [["user_id", 1]]
-D, [2016-02-12T08:58:23.293516 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
-D, [2016-02-12T08:58:23.299134 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.1ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-E, [2016-02-12T08:58:23.302960 #5510] ERROR -- : Thread pool operation failed: Unknown key: to
-D, [2016-02-12T08:58:23.305711 #5510] DEBUG -- : [1m[35m (0.2ms)[0m SELECT COUNT(*) FROM "chat_offline_messages" WHERE "chat_offline_messages"."to" = 'someone at inthe.void'
-D, [2016-02-12T08:58:23.318192 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.6ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:23.326652 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::User Load (0.2ms)[0m SELECT "users".* FROM "users" WHERE "users"."username" IS NULL LIMIT 1
-D, [2016-02-12T08:58:23.328128 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.4ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1[0m
-D, [2016-02-12T08:58:23.331359 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Contact Load (0.3ms)[0m SELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id [["user_id", 1]]
-D, [2016-02-12T08:58:23.332863 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.2ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1[0m [["id", 1]]
-D, [2016-02-12T08:58:23.334481 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Profile Load (0.2ms)[0m SELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1 [["person_id", 1]]
-D, [2016-02-12T08:58:23.335654 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Aspect Load (0.2ms)[0m [1mSELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ?[0m [["contact_id", 1]]
-D, [2016-02-12T08:58:23.337028 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::ChatContact Load (0.2ms)[0m SELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ? [["user_id", 1]]
-D, [2016-02-12T08:58:23.404333 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.7ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1[0m
-D, [2016-02-12T08:58:23.406194 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Contact Load (0.5ms)[0m SELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id [["user_id", 1]]
-D, [2016-02-12T08:58:23.407461 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.5ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1[0m [["id", 1]]
-D, [2016-02-12T08:58:23.408849 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::Profile Load (0.6ms)[0m SELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1 [["person_id", 1]]
-D, [2016-02-12T08:58:23.410473 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::Aspect Load (0.4ms)[0m [1mSELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ?[0m [["contact_id", 1]]
-D, [2016-02-12T08:58:23.411723 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::ChatContact Load (0.3ms)[0m SELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ? [["user_id", 1]]
-D, [2016-02-12T08:58:23.481912 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.1ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:23.487758 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages" WHERE "chat_offline_messages"."to" = 'someone at inthe.void'
-D, [2016-02-12T08:58:23.495388 #5510] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
-D, [2016-02-12T08:58:23.498692 #5510] DEBUG -- : [1m[35mSQL (1.0ms)[0m INSERT INTO "chat_offline_messages" ("created_at", "from", "message", "to") VALUES (?, ?, ?, ?) [["created_at", 2016-02-12 03:28:23 UTC], ["from", "test at local.host"], ["message", "test"], ["to", "someone at inthe.void"]]
-D, [2016-02-12T08:58:23.600288 #5510] DEBUG -- : [1m[36m (101.2ms)[0m [1mcommit transaction[0m
-D, [2016-02-12T08:58:23.603981 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages" WHERE "chat_offline_messages"."to" = 'someone at inthe.void'
-D, [2016-02-12T08:58:23.606817 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.5ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:23.607293 #5510] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
-D, [2016-02-12T08:58:23.608869 #5510] DEBUG -- : [1m[36mSQL (0.6ms)[0m [1mDELETE FROM "chat_offline_messages" WHERE "chat_offline_messages"."id" = ?[0m [["id", 1]]
-D, [2016-02-12T08:58:23.722973 #5510] DEBUG -- : [1m[35m (113.5ms)[0m commit transaction
-D, [2016-02-12T08:58:23.728359 #5510] DEBUG -- : [1m[36m (1.2ms)[0m [1mbegin transaction[0m
-D, [2016-02-12T08:58:23.730255 #5510] DEBUG -- : [1m[35mSQL (0.7ms)[0m INSERT INTO "chat_offline_messages" ("created_at", "from", "message", "to") VALUES (?, ?, ?, ?) [["created_at", 2016-02-12 03:28:23 UTC], ["from", "test at local.host"], ["message", "test"], ["to", "someone at inthe.void"]]
-D, [2016-02-12T08:58:23.856512 #5510] DEBUG -- : [1m[36m (125.9ms)[0m [1mcommit transaction[0m
-D, [2016-02-12T08:58:23.858093 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.2ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
-D, [2016-02-12T08:58:23.860727 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.2ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages" WHERE "chat_offline_messages"."id" = ? LIMIT 1[0m [["id", 2]]
-D, [2016-02-12T08:58:23.861332 #5510] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
-D, [2016-02-12T08:58:23.861895 #5510] DEBUG -- : [1m[36mSQL (0.2ms)[0m [1mDELETE FROM "chat_offline_messages" WHERE "chat_offline_messages"."id" = ?[0m [["id", 2]]
-D, [2016-02-12T08:58:23.981050 #5510] DEBUG -- : [1m[35m (119.0ms)[0m commit transaction
-D, [2016-02-12T08:58:23.983568 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:23.986425 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.4ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
-D, [2016-02-12T08:58:23.993380 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.5ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test2' LIMIT 1[0m
-D, [2016-02-12T08:58:23.995265 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::User Load (0.6ms)[0m SELECT "users".* FROM "users" WHERE "users"."username" = 'test2' LIMIT 1
-D, [2016-02-12T08:58:23.998860 #5510] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:24.000285 #5510] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.2ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
-I, [2016-02-12T08:58:24.210388 #5510] INFO -- : Authenticating user: alice at wonderland.lit
-I, [2016-02-12T08:58:24.211355 #5510] INFO -- : Authenticating user: alice at wonderland.lit
-I, [2016-02-12T08:58:24.211418 #5510] INFO -- : Authentication succeeded: alice at wonderland.lit
-I, [2016-02-12T08:58:24.211759 #5510] INFO -- : Authenticating user: alice at wonderland.lit
-D, [2016-02-12T08:58:44.574057 #5726] DEBUG -- : [1m[36m (121.3ms)[0m [1mCREATE TABLE "people" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "guid" varchar(255) NOT NULL, "url" text NOT NULL, "diaspora_handle" varchar(255) NOT NULL, "serialized_public_key" text NOT NULL, "owner_id" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "closed_account" boolean DEFAULT 'f', "fetch_status" integer DEFAULT 0) [0m
-D, [2016-02-12T08:58:44.674290 #5726] DEBUG -- : [1m[35m (99.1ms)[0m CREATE UNIQUE INDEX "index_people_on_diaspora_handle" ON "people" ("diaspora_handle")
-D, [2016-02-12T08:58:44.774769 #5726] DEBUG -- : [1m[36m (99.3ms)[0m [1mCREATE UNIQUE INDEX "index_people_on_guid" ON "people" ("guid")[0m
-D, [2016-02-12T08:58:44.875395 #5726] DEBUG -- : [1m[35m (98.6ms)[0m CREATE UNIQUE INDEX "index_people_on_owner_id" ON "people" ("owner_id")
-D, [2016-02-12T08:58:44.975811 #5726] DEBUG -- : [1m[36m (96.6ms)[0m [1mCREATE TABLE "profiles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "diaspora_handle" varchar(255), "first_name" varchar(127), "last_name" varchar(127), "image_url" varchar(255), "image_url_small" varchar(255), "image_url_medium" varchar(255), "birthday" date, "gender" varchar(255), "bio" text, "searchable" boolean DEFAULT 't' NOT NULL, "person_id" integer NOT NULL, "created_at" datetime NOT NULL, "updat [...]
-D, [2016-02-12T08:58:45.076161 #5726] DEBUG -- : [1m[35m (98.9ms)[0m CREATE INDEX "index_profiles_on_full_name_and_searchable" ON "profiles" ("full_name", "searchable")
-D, [2016-02-12T08:58:45.176599 #5726] DEBUG -- : [1m[36m (98.3ms)[0m [1mCREATE INDEX "index_profiles_on_full_name" ON "profiles" ("full_name")[0m
-D, [2016-02-12T08:58:45.277069 #5726] DEBUG -- : [1m[35m (98.1ms)[0m CREATE INDEX "index_profiles_on_person_id" ON "profiles" ("person_id")
-D, [2016-02-12T08:58:45.377579 #5726] DEBUG -- : [1m[36m (98.4ms)[0m [1mCREATE TABLE "aspects" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255) NOT NULL, "user_id" integer NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "contacts_visible" boolean DEFAULT 't' NOT NULL, "order_id" integer, "chat_enabled" boolean DEFAULT 'f') [0m
-D, [2016-02-12T08:58:45.511184 #5726] DEBUG -- : [1m[35m (132.4ms)[0m CREATE INDEX "index_aspects_on_user_id_and_contacts_visible" ON "aspects" ("user_id", "contacts_visible")
-D, [2016-02-12T08:58:45.644892 #5726] DEBUG -- : [1m[36m (131.5ms)[0m [1mCREATE INDEX "index_aspects_on_user_id" ON "aspects" ("user_id")[0m
-D, [2016-02-12T08:58:45.789775 #5726] DEBUG -- : [1m[35m (142.9ms)[0m CREATE TABLE "aspect_memberships" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "aspect_id" integer NOT NULL, "contact_id" integer NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
-D, [2016-02-12T08:58:45.934507 #5726] DEBUG -- : [1m[36m (143.3ms)[0m [1mCREATE UNIQUE INDEX "index_aspect_memberships_on_aspect_id_and_contact_id" ON "aspect_memberships" ("aspect_id", "contact_id")[0m
-D, [2016-02-12T08:58:46.079285 #5726] DEBUG -- : [1m[35m (142.6ms)[0m CREATE INDEX "index_aspect_memberships_on_aspect_id" ON "aspect_memberships" ("aspect_id")
-D, [2016-02-12T08:58:46.224080 #5726] DEBUG -- : [1m[36m (142.2ms)[0m [1mCREATE INDEX "index_aspect_memberships_on_contact_id" ON "aspect_memberships" ("contact_id")[0m
-D, [2016-02-12T08:58:46.348315 #5726] DEBUG -- : [1m[35m (122.5ms)[0m CREATE TABLE "contacts" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer NOT NULL, "person_id" integer NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "sharing" boolean DEFAULT 'f' NOT NULL, "receiving" boolean DEFAULT 'f' NOT NULL)
-D, [2016-02-12T08:58:46.460037 #5726] DEBUG -- : [1m[36m (110.8ms)[0m [1mCREATE INDEX "index_contacts_on_person_id" ON "contacts" ("person_id")[0m
-D, [2016-02-12T08:58:46.571590 #5726] DEBUG -- : [1m[35m (109.3ms)[0m CREATE UNIQUE INDEX "index_contacts_on_user_id_and_person_id" ON "contacts" ("user_id", "person_id")
-D, [2016-02-12T08:58:46.671907 #5726] DEBUG -- : [1m[36m (98.2ms)[0m [1mCREATE TABLE "chat_contacts" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer NOT NULL, "jid" varchar(255) NOT NULL, "name" varchar(255), "ask" varchar(128), "subscription" varchar(128) NOT NULL, "groups" text) [0m
-D, [2016-02-12T08:58:46.772225 #5726] DEBUG -- : [1m[35m (98.9ms)[0m CREATE UNIQUE INDEX "index_chat_contacts_on_user_id_and_jid" ON "chat_contacts" ("user_id", "jid")
-D, [2016-02-12T08:58:46.872575 #5726] DEBUG -- : [1m[36m (98.5ms)[0m [1mCREATE TABLE "chat_fragments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer NOT NULL, "root" varchar(256) NOT NULL, "namespace" varchar(256) NOT NULL, "xml" text NOT NULL) [0m
-D, [2016-02-12T08:58:46.984233 #5726] DEBUG -- : [1m[35m (110.2ms)[0m CREATE UNIQUE INDEX "index_chat_fragments_on_user_id" ON "chat_fragments" ("user_id")
-D, [2016-02-12T08:58:47.106670 #5726] DEBUG -- : [1m[36m (120.6ms)[0m [1mCREATE TABLE "chat_offline_messages" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "from" varchar(255) NOT NULL, "to" varchar(255) NOT NULL, "message" text NOT NULL, "created_at" datetime NOT NULL) [0m
-D, [2016-02-12T08:58:47.206989 #5726] DEBUG -- : [1m[35m (97.2ms)[0m CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "username" varchar(255), "serialized_private_key" text, "getting_started" boolean DEFAULT 't' NOT NULL, "disable_mail" boolean DEFAULT 'f' NOT NULL, "language" varchar(255), "email" varchar(255) DEFAULT '' NOT NULL, "encrypted_password" varchar(255) DEFAULT '' NOT NULL, "invitation_token" varchar(60), "invitation_sent_at" datetime, "reset_passw [...]
-D, [2016-02-12T08:58:47.318554 #5726] DEBUG -- : [1m[36m (110.5ms)[0m [1mCREATE UNIQUE INDEX "index_users_on_authentication_token" ON "users" ("authentication_token")[0m
-D, [2016-02-12T08:58:47.430227 #5726] DEBUG -- : [1m[35m (109.8ms)[0m CREATE INDEX "index_users_on_email" ON "users" ("email")
-D, [2016-02-12T08:58:47.552749 #5726] DEBUG -- : [1m[36m (120.1ms)[0m [1mCREATE UNIQUE INDEX "index_users_on_invitation_service_and_invitation_identifier" ON "users" ("invitation_service", "invitation_identifier")[0m
-D, [2016-02-12T08:58:47.675196 #5726] DEBUG -- : [1m[35m (119.7ms)[0m CREATE INDEX "index_users_on_invitation_token" ON "users" ("invitation_token")
-D, [2016-02-12T08:58:47.808847 #5726] DEBUG -- : [1m[36m (131.3ms)[0m [1mCREATE UNIQUE INDEX "index_users_on_username" ON "users" ("username")[0m
-D, [2016-02-12T08:58:47.855733 #5726] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
-D, [2016-02-12T08:58:47.858935 #5726] DEBUG -- : [1m[36mSQL (0.4ms)[0m [1mINSERT INTO "users" ("authentication_token", "created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?)[0m [["authentication_token", "1234"], ["created_at", 2016-02-12 03:28:47 UTC], ["email", "test at test.de"], ["encrypted_password", "$2a$10$c2G6rHjGeamQIOFI0c1/b.4mvFBw4AfOtgVrAkO1QPMuAyporj5e6"], ["updated_at", 2016-02-12 03:28:47 UTC], ["username", "test"]]
-D, [2016-02-12T08:58:47.987080 #5726] DEBUG -- : [1m[35m (127.8ms)[0m commit transaction
-D, [2016-02-12T08:58:48.000400 #5726] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
-D, [2016-02-12T08:58:48.002840 #5726] DEBUG -- : [1m[35mSQL (0.6ms)[0m INSERT INTO "people" ("created_at", "diaspora_handle", "guid", "owner_id", "serialized_public_key", "updated_at", "url") VALUES (?, ?, ?, ?, ?, ?, ?) [["created_at", 2016-02-12 03:28:48 UTC], ["diaspora_handle", "test at local.host"], ["guid", "1697a4b0198901321e9b10e6ba921ce9"], ["owner_id", 1], ["serialized_public_key", "some pub key"], ["updated_at", 2016-02-12 03:28:48 UTC], ["url", "http://remote.host/"]]
-D, [2016-02-12T08:58:48.131969 #5726] DEBUG -- : [1m[36m (128.7ms)[0m [1mcommit transaction[0m
-D, [2016-02-12T08:58:48.149458 #5726] DEBUG -- : [1m[35m (0.0ms)[0m begin transaction
-D, [2016-02-12T08:58:48.151222 #5726] DEBUG -- : [1m[36mSQL (0.5ms)[0m [1mINSERT INTO "profiles" ("created_at", "diaspora_handle", "first_name", "image_url", "last_name", "person_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)[0m [["created_at", 2016-02-12 03:28:48 UTC], ["diaspora_handle", "test at local.host"], ["first_name", "Harry"], ["image_url", "http://path.to/image.png"], ["last_name", "Hirsch"], ["person_id", 1], ["updated_at", 2016-02-12 03:28:48 UTC]]
-D, [2016-02-12T08:58:48.276598 #5726] DEBUG -- : [1m[35m (125.0ms)[0m commit transaction
-D, [2016-02-12T08:58:48.291658 #5726] DEBUG -- : [1m[36m (0.2ms)[0m [1mbegin transaction[0m
-D, [2016-02-12T08:58:48.294250 #5726] DEBUG -- : [1m[35mSQL (1.1ms)[0m INSERT INTO "contacts" ("created_at", "person_id", "receiving", "sharing", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", 2016-02-12 03:28:48 UTC], ["person_id", 1], ["receiving", true], ["sharing", true], ["updated_at", 2016-02-12 03:28:48 UTC], ["user_id", 1]]
-D, [2016-02-12T08:58:48.410279 #5726] DEBUG -- : [1m[36m (115.4ms)[0m [1mcommit transaction[0m
-D, [2016-02-12T08:58:48.424950 #5726] DEBUG -- : [1m[35m (0.0ms)[0m begin transaction
-D, [2016-02-12T08:58:48.426732 #5726] DEBUG -- : [1m[36mSQL (0.5ms)[0m [1mINSERT INTO "aspects" ("created_at", "name", "updated_at", "user_id") VALUES (?, ?, ?, ?)[0m [["created_at", 2016-02-12 03:28:48 UTC], ["name", "without_chat"], ["updated_at", 2016-02-12 03:28:48 UTC], ["user_id", 1]]
-D, [2016-02-12T08:58:48.554880 #5726] DEBUG -- : [1m[35m (127.8ms)[0m commit transaction
-D, [2016-02-12T08:58:48.567195 #5726] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
-D, [2016-02-12T08:58:48.569164 #5726] DEBUG -- : [1m[35mSQL (0.5ms)[0m INSERT INTO "aspect_memberships" ("aspect_id", "contact_id", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["aspect_id", 1], ["contact_id", 1], ["created_at", 2016-02-12 03:28:48 UTC], ["updated_at", 2016-02-12 03:28:48 UTC]]
-D, [2016-02-12T08:58:48.677477 #5726] DEBUG -- : [1m[36m (108.0ms)[0m [1mcommit transaction[0m
-D, [2016-02-12T08:58:48.682110 #5726] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
-D, [2016-02-12T08:58:48.750653 #5726] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.4ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test2' LIMIT 1[0m
-D, [2016-02-12T08:58:48.753247 #5726] DEBUG -- : [1m[35mVines::Storage::Sql::User Load (0.4ms)[0m SELECT "users".* FROM "users" WHERE "users"."username" = 'test2' LIMIT 1
-D, [2016-02-12T08:58:48.756164 #5726] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:48.763930 #5726] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages" WHERE "chat_offline_messages"."to" = 'someone at inthe.void'
-D, [2016-02-12T08:58:48.772419 #5726] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
-D, [2016-02-12T08:58:48.774628 #5726] DEBUG -- : [1m[35mSQL (1.0ms)[0m INSERT INTO "chat_offline_messages" ("created_at", "from", "message", "to") VALUES (?, ?, ?, ?) [["created_at", 2016-02-12 03:28:48 UTC], ["from", "test at local.host"], ["message", "test"], ["to", "someone at inthe.void"]]
-D, [2016-02-12T08:58:48.901547 #5726] DEBUG -- : [1m[36m (126.6ms)[0m [1mcommit transaction[0m
-D, [2016-02-12T08:58:48.906555 #5726] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.4ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages" WHERE "chat_offline_messages"."to" = 'someone at inthe.void'
-D, [2016-02-12T08:58:48.910620 #5726] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.6ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:48.911221 #5726] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
-D, [2016-02-12T08:58:48.913326 #5726] DEBUG -- : [1m[36mSQL (0.8ms)[0m [1mDELETE FROM "chat_offline_messages" WHERE "chat_offline_messages"."id" = ?[0m [["id", 1]]
-D, [2016-02-12T08:58:49.024635 #5726] DEBUG -- : [1m[35m (111.0ms)[0m commit transaction
-D, [2016-02-12T08:58:49.029172 #5726] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.2ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."diaspora_handle" = 'test at local.host' LIMIT 1[0m
-D, [2016-02-12T08:58:49.038893 #5726] DEBUG -- : [1m[35mVines::Storage::Sql::Profile Load (0.3ms)[0m SELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1 [["person_id", 1]]
-D, [2016-02-12T08:58:49.042260 #5726] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.1ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:49.044939 #5726] DEBUG -- : [1m[35m (0.6ms)[0m begin transaction
-D, [2016-02-12T08:58:49.045996 #5726] DEBUG -- : [1m[36mSQL (0.3ms)[0m [1mINSERT INTO "chat_offline_messages" ("created_at", "from", "message", "to") VALUES (?, ?, ?, ?)[0m [["created_at", 2016-02-12 03:28:49 UTC], ["from", "test at local.host"], ["message", "test"], ["to", "someone at inthe.void"]]
-D, [2016-02-12T08:58:49.146690 #5726] DEBUG -- : [1m[35m (100.4ms)[0m commit transaction
-D, [2016-02-12T08:58:49.149175 #5726] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.4ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
-D, [2016-02-12T08:58:49.154447 #5726] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.4ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages" WHERE "chat_offline_messages"."id" = ? LIMIT 1 [["id", 2]]
-D, [2016-02-12T08:58:49.154983 #5726] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
-D, [2016-02-12T08:58:49.155963 #5726] DEBUG -- : [1m[35mSQL (0.3ms)[0m DELETE FROM "chat_offline_messages" WHERE "chat_offline_messages"."id" = ? [["id", 2]]
-D, [2016-02-12T08:58:49.258153 #5726] DEBUG -- : [1m[36m (102.0ms)[0m [1mcommit transaction[0m
-D, [2016-02-12T08:58:49.259768 #5726] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.4ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
+E, [2016-02-12T23:47:55.085846 #25416] ERROR -- : Thread pool operation failed: Unknown key: to
+D, [2016-02-12T23:48:06.486896 #25629] DEBUG -- : [1m[36m (143.3ms)[0m [1mCREATE TABLE "people" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "guid" varchar(255) NOT NULL, "url" text NOT NULL, "diaspora_handle" varchar(255) NOT NULL, "serialized_public_key" text NOT NULL, "owner_id" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "closed_account" boolean DEFAULT 'f', "fetch_status" integer DEFAULT 0) [0m
+D, [2016-02-12T23:48:06.577985 #25629] DEBUG -- : [1m[35m (89.6ms)[0m CREATE UNIQUE INDEX "index_people_on_diaspora_handle" ON "people" ("diaspora_handle")
+D, [2016-02-12T23:48:06.645059 #25629] DEBUG -- : [1m[36m (65.5ms)[0m [1mCREATE UNIQUE INDEX "index_people_on_guid" ON "people" ("guid")[0m
+D, [2016-02-12T23:48:06.712373 #25629] DEBUG -- : [1m[35m (65.2ms)[0m CREATE UNIQUE INDEX "index_people_on_owner_id" ON "people" ("owner_id")
+D, [2016-02-12T23:48:06.779652 #25629] DEBUG -- : [1m[36m (63.4ms)[0m [1mCREATE TABLE "profiles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "diaspora_handle" varchar(255), "first_name" varchar(127), "last_name" varchar(127), "image_url" varchar(255), "image_url_small" varchar(255), "image_url_medium" varchar(255), "birthday" date, "gender" varchar(255), "bio" text, "searchable" boolean DEFAULT 't' NOT NULL, "person_id" integer NOT NULL, "created_at" datetime NOT NULL, "upda [...]
+D, [2016-02-12T23:48:06.846775 #25629] DEBUG -- : [1m[35m (65.6ms)[0m CREATE INDEX "index_profiles_on_full_name_and_searchable" ON "profiles" ("full_name", "searchable")
+D, [2016-02-12T23:48:06.924884 #25629] DEBUG -- : [1m[36m (75.4ms)[0m [1mCREATE INDEX "index_profiles_on_full_name" ON "profiles" ("full_name")[0m
+D, [2016-02-12T23:48:07.025226 #25629] DEBUG -- : [1m[35m (98.2ms)[0m CREATE INDEX "index_profiles_on_person_id" ON "profiles" ("person_id")
+D, [2016-02-12T23:48:07.125525 #25629] DEBUG -- : [1m[36m (98.3ms)[0m [1mCREATE TABLE "aspects" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255) NOT NULL, "user_id" integer NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "contacts_visible" boolean DEFAULT 't' NOT NULL, "order_id" integer, "chat_enabled" boolean DEFAULT 'f') [0m
+D, [2016-02-12T23:48:07.238834 #25629] DEBUG -- : [1m[35m (112.4ms)[0m CREATE INDEX "index_aspects_on_user_id_and_contacts_visible" ON "aspects" ("user_id", "contacts_visible")
+D, [2016-02-12T23:48:07.360023 #25629] DEBUG -- : [1m[36m (120.2ms)[0m [1mCREATE INDEX "index_aspects_on_user_id" ON "aspects" ("user_id")[0m
+D, [2016-02-12T23:48:07.484572 #25629] DEBUG -- : [1m[35m (122.6ms)[0m CREATE TABLE "aspect_memberships" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "aspect_id" integer NOT NULL, "contact_id" integer NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
+D, [2016-02-12T23:48:07.595908 #25629] DEBUG -- : [1m[36m (109.9ms)[0m [1mCREATE UNIQUE INDEX "index_aspect_memberships_on_aspect_id_and_contact_id" ON "aspect_memberships" ("aspect_id", "contact_id")[0m
+D, [2016-02-12T23:48:07.708083 #25629] DEBUG -- : [1m[35m (110.7ms)[0m CREATE INDEX "index_aspect_memberships_on_aspect_id" ON "aspect_memberships" ("aspect_id")
+D, [2016-02-12T23:48:07.817086 #25629] DEBUG -- : [1m[36m (106.8ms)[0m [1mCREATE INDEX "index_aspect_memberships_on_contact_id" ON "aspect_memberships" ("contact_id")[0m
+D, [2016-02-12T23:48:07.928633 #25629] DEBUG -- : [1m[35m (109.8ms)[0m CREATE TABLE "contacts" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer NOT NULL, "person_id" integer NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "sharing" boolean DEFAULT 'f' NOT NULL, "receiving" boolean DEFAULT 'f' NOT NULL)
+D, [2016-02-12T23:48:08.040263 #25629] DEBUG -- : [1m[36m (110.4ms)[0m [1mCREATE INDEX "index_contacts_on_person_id" ON "contacts" ("person_id")[0m
+D, [2016-02-12T23:48:08.154332 #25629] DEBUG -- : [1m[35m (111.8ms)[0m CREATE UNIQUE INDEX "index_contacts_on_user_id_and_person_id" ON "contacts" ("user_id", "person_id")
+D, [2016-02-12T23:48:08.254694 #25629] DEBUG -- : [1m[36m (98.3ms)[0m [1mCREATE TABLE "chat_contacts" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer NOT NULL, "jid" varchar(255) NOT NULL, "name" varchar(255), "ask" varchar(128), "subscription" varchar(128) NOT NULL, "groups" text) [0m
+D, [2016-02-12T23:48:08.321845 #25629] DEBUG -- : [1m[35m (65.8ms)[0m CREATE UNIQUE INDEX "index_chat_contacts_on_user_id_and_jid" ON "chat_contacts" ("user_id", "jid")
+D, [2016-02-12T23:48:08.388890 #25629] DEBUG -- : [1m[36m (64.9ms)[0m [1mCREATE TABLE "chat_fragments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer NOT NULL, "root" varchar(256) NOT NULL, "namespace" varchar(256) NOT NULL, "xml" text NOT NULL) [0m
+D, [2016-02-12T23:48:08.489381 #25629] DEBUG -- : [1m[35m (99.1ms)[0m CREATE UNIQUE INDEX "index_chat_fragments_on_user_id" ON "chat_fragments" ("user_id")
+D, [2016-02-12T23:48:08.600853 #25629] DEBUG -- : [1m[36m (109.2ms)[0m [1mCREATE TABLE "chat_offline_messages" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "from" varchar(255) NOT NULL, "to" varchar(255) NOT NULL, "message" text NOT NULL, "created_at" datetime NOT NULL) [0m
+D, [2016-02-12T23:48:08.733871 #25629] DEBUG -- : [1m[35m (129.6ms)[0m CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "username" varchar(255), "serialized_private_key" text, "getting_started" boolean DEFAULT 't' NOT NULL, "disable_mail" boolean DEFAULT 'f' NOT NULL, "language" varchar(255), "email" varchar(255) DEFAULT '' NOT NULL, "encrypted_password" varchar(255) DEFAULT '' NOT NULL, "invitation_token" varchar(60), "invitation_sent_at" datetime, "reset_pas [...]
+D, [2016-02-12T23:48:08.878627 #25629] DEBUG -- : [1m[36m (143.4ms)[0m [1mCREATE UNIQUE INDEX "index_users_on_authentication_token" ON "users" ("authentication_token")[0m
+D, [2016-02-12T23:48:09.006061 #25629] DEBUG -- : [1m[35m (125.8ms)[0m CREATE INDEX "index_users_on_email" ON "users" ("email")
+D, [2016-02-12T23:48:09.172983 #25629] DEBUG -- : [1m[36m (165.6ms)[0m [1mCREATE UNIQUE INDEX "index_users_on_invitation_service_and_invitation_identifier" ON "users" ("invitation_service", "invitation_identifier")[0m
+D, [2016-02-12T23:48:09.331707 #25629] DEBUG -- : [1m[35m (156.9ms)[0m CREATE INDEX "index_users_on_invitation_token" ON "users" ("invitation_token")
+D, [2016-02-12T23:48:09.561798 #25629] DEBUG -- : [1m[36m (227.6ms)[0m [1mCREATE UNIQUE INDEX "index_users_on_username" ON "users" ("username")[0m
+D, [2016-02-12T23:48:09.609386 #25629] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
+D, [2016-02-12T23:48:09.613066 #25629] DEBUG -- : [1m[36mSQL (0.5ms)[0m [1mINSERT INTO "users" ("authentication_token", "created_at", "email", "encrypted_password", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?)[0m [["authentication_token", "1234"], ["created_at", 2016-02-12 18:18:09 UTC], ["email", "test at test.de"], ["encrypted_password", "$2a$10$c2G6rHjGeamQIOFI0c1/b.4mvFBw4AfOtgVrAkO1QPMuAyporj5e6"], ["updated_at", 2016-02-12 18:18:09 UTC], ["username", "test"]]
+D, [2016-02-12T23:48:09.740016 #25629] DEBUG -- : [1m[35m (126.5ms)[0m commit transaction
+D, [2016-02-12T23:48:09.752720 #25629] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
+D, [2016-02-12T23:48:09.755773 #25629] DEBUG -- : [1m[35mSQL (1.0ms)[0m INSERT INTO "people" ("created_at", "diaspora_handle", "guid", "owner_id", "serialized_public_key", "updated_at", "url") VALUES (?, ?, ?, ?, ?, ?, ?) [["created_at", 2016-02-12 18:18:09 UTC], ["diaspora_handle", "test at local.host"], ["guid", "1697a4b0198901321e9b10e6ba921ce9"], ["owner_id", 1], ["serialized_public_key", "some pub key"], ["updated_at", 2016-02-12 18:18:09 UTC], ["url", "http://remote.host/"]]
+D, [2016-02-12T23:48:09.918366 #25629] DEBUG -- : [1m[36m (162.0ms)[0m [1mcommit transaction[0m
+D, [2016-02-12T23:48:09.937458 #25629] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
+D, [2016-02-12T23:48:09.939663 #25629] DEBUG -- : [1m[36mSQL (0.9ms)[0m [1mINSERT INTO "profiles" ("created_at", "diaspora_handle", "first_name", "image_url", "last_name", "person_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)[0m [["created_at", 2016-02-12 18:18:09 UTC], ["diaspora_handle", "test at local.host"], ["first_name", "Harry"], ["image_url", "http://path.to/image.png"], ["last_name", "Hirsch"], ["person_id", 1], ["updated_at", 2016-02-12 18:18:09 UTC]]
+D, [2016-02-12T23:48:10.098728 #25629] DEBUG -- : [1m[35m (158.7ms)[0m commit transaction
+D, [2016-02-12T23:48:10.109731 #25629] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
+D, [2016-02-12T23:48:10.112385 #25629] DEBUG -- : [1m[35mSQL (0.6ms)[0m INSERT INTO "contacts" ("created_at", "person_id", "receiving", "sharing", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", 2016-02-12 18:18:10 UTC], ["person_id", 1], ["receiving", true], ["sharing", true], ["updated_at", 2016-02-12 18:18:10 UTC], ["user_id", 1]]
+D, [2016-02-12T23:48:10.244333 #25629] DEBUG -- : [1m[36m (131.5ms)[0m [1mcommit transaction[0m
+D, [2016-02-12T23:48:10.260495 #25629] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
+D, [2016-02-12T23:48:10.262514 #25629] DEBUG -- : [1m[36mSQL (0.7ms)[0m [1mINSERT INTO "aspects" ("created_at", "name", "updated_at", "user_id") VALUES (?, ?, ?, ?)[0m [["created_at", 2016-02-12 18:18:10 UTC], ["name", "without_chat"], ["updated_at", 2016-02-12 18:18:10 UTC], ["user_id", 1]]
+D, [2016-02-12T23:48:10.389111 #25629] DEBUG -- : [1m[35m (126.2ms)[0m commit transaction
+D, [2016-02-12T23:48:10.402073 #25629] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
+D, [2016-02-12T23:48:10.405202 #25629] DEBUG -- : [1m[35mSQL (0.8ms)[0m INSERT INTO "aspect_memberships" ("aspect_id", "contact_id", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["aspect_id", 1], ["contact_id", 1], ["created_at", 2016-02-12 18:18:10 UTC], ["updated_at", 2016-02-12 18:18:10 UTC]]
+D, [2016-02-12T23:48:10.555570 #25629] DEBUG -- : [1m[36m (149.7ms)[0m [1mcommit transaction[0m
+D, [2016-02-12T23:48:10.567804 #25629] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
+D, [2016-02-12T23:48:10.571148 #25629] DEBUG -- : [1m[36mSQL (1.4ms)[0m [1mINSERT INTO "chat_offline_messages" ("created_at", "from", "message", "to") VALUES (?, ?, ?, ?)[0m [["created_at", 2016-02-12 18:18:10 UTC], ["from", "test at local.host"], ["message", "test"], ["to", "someone at inthe.void"]]
+D, [2016-02-12T23:48:10.700746 #25629] DEBUG -- : [1m[35m (128.9ms)[0m commit transaction
+D, [2016-02-12T23:48:10.702464 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.7ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
+D, [2016-02-12T23:48:10.769189 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.4ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages" WHERE "chat_offline_messages"."id" = ? LIMIT 1 [["id", 1]]
+D, [2016-02-12T23:48:10.769857 #25629] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
+D, [2016-02-12T23:48:10.771754 #25629] DEBUG -- : [1m[35mSQL (0.5ms)[0m DELETE FROM "chat_offline_messages" WHERE "chat_offline_messages"."id" = ? [["id", 1]]
+D, [2016-02-12T23:48:10.923421 #25629] DEBUG -- : [1m[36m (151.3ms)[0m [1mcommit transaction[0m
+D, [2016-02-12T23:48:10.925786 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.4ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
+D, [2016-02-12T23:48:10.988387 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.5ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1[0m
+D, [2016-02-12T23:48:11.012548 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Contact Load (0.3ms)[0m SELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id [["user_id", 1]]
+D, [2016-02-12T23:48:11.015611 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::ChatContact Load (0.2ms)[0m [1mSELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ?[0m [["user_id", 1]]
+D, [2016-02-12T23:48:11.634679 #25629] DEBUG -- : [1m[35mSQL (618.1ms)[0m UPDATE "aspects" SET "name" = 'with_chat', "chat_enabled" = 't' WHERE "aspects"."id" = 1
+D, [2016-02-12T23:48:11.641931 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.6ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1[0m
+D, [2016-02-12T23:48:11.648091 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Contact Load (0.6ms)[0m SELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id [["user_id", 1]]
+D, [2016-02-12T23:48:11.653867 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.2ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1[0m [["id", 1]]
+D, [2016-02-12T23:48:11.657626 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Profile Load (0.2ms)[0m SELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1 [["person_id", 1]]
+D, [2016-02-12T23:48:11.661667 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Aspect Load (0.3ms)[0m [1mSELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ?[0m [["contact_id", 1]]
+D, [2016-02-12T23:48:11.663822 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatContact Load (0.2ms)[0m SELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ? [["user_id", 1]]
+D, [2016-02-12T23:48:11.666804 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
+D, [2016-02-12T23:48:11.668970 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.2ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
+D, [2016-02-12T23:48:11.675639 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages" WHERE "chat_offline_messages"."to" = 'someone at inthe.void'[0m
+D, [2016-02-12T23:48:11.676873 #25629] DEBUG -- : [1m[35m (0.1ms)[0m begin transaction
+D, [2016-02-12T23:48:11.678910 #25629] DEBUG -- : [1m[36mSQL (0.8ms)[0m [1mINSERT INTO "chat_offline_messages" ("created_at", "from", "message", "to") VALUES (?, ?, ?, ?)[0m [["created_at", 2016-02-12 18:18:11 UTC], ["from", "test at local.host"], ["message", "test"], ["to", "someone at inthe.void"]]
+D, [2016-02-12T23:48:11.834382 #25629] DEBUG -- : [1m[35m (155.0ms)[0m commit transaction
+D, [2016-02-12T23:48:11.840302 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.5ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages" WHERE "chat_offline_messages"."to" = 'someone at inthe.void'[0m
+D, [2016-02-12T23:48:11.843017 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.4ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
+D, [2016-02-12T23:48:11.843478 #25629] DEBUG -- : [1m[36m (0.1ms)[0m [1mbegin transaction[0m
+D, [2016-02-12T23:48:11.845474 #25629] DEBUG -- : [1m[35mSQL (0.6ms)[0m DELETE FROM "chat_offline_messages" WHERE "chat_offline_messages"."id" = ? [["id", 2]]
+D, [2016-02-12T23:48:12.023724 #25629] DEBUG -- : [1m[36m (153.5ms)[0m [1mcommit transaction[0m
+E, [2016-02-12T23:48:12.030536 #25629] ERROR -- : Thread pool operation failed: Unknown key: to
+D, [2016-02-12T23:48:12.034472 #25629] DEBUG -- : [1m[35m (0.3ms)[0m SELECT COUNT(*) FROM "chat_offline_messages" WHERE "chat_offline_messages"."to" = 'someone at inthe.void'
+D, [2016-02-12T23:48:12.048540 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.8ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
+D, [2016-02-12T23:48:12.052123 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.4ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
+D, [2016-02-12T23:48:12.060116 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.4ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."diaspora_handle" = 'test at local.host' LIMIT 1[0m
+D, [2016-02-12T23:48:12.063652 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Profile Load (0.6ms)[0m SELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1 [["person_id", 1]]
+D, [2016-02-12T23:48:12.070412 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
+D, [2016-02-12T23:48:12.080356 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
+D, [2016-02-12T23:48:12.090811 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.5ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1[0m
+D, [2016-02-12T23:48:12.096454 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Contact Load (0.4ms)[0m SELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id [["user_id", 1]]
+D, [2016-02-12T23:48:12.097640 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.1ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1[0m [["id", 1]]
+D, [2016-02-12T23:48:12.098852 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Profile Load (0.2ms)[0m SELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1 [["person_id", 1]]
+D, [2016-02-12T23:48:12.099977 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Aspect Load (0.3ms)[0m [1mSELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ?[0m [["contact_id", 1]]
+D, [2016-02-12T23:48:12.101192 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatContact Load (0.1ms)[0m SELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ? [["user_id", 1]]
+D, [2016-02-12T23:48:12.103202 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.6ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1[0m
+D, [2016-02-12T23:48:12.105621 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Contact Load (0.4ms)[0m SELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id [["user_id", 1]]
+D, [2016-02-12T23:48:12.107151 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.4ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1[0m [["id", 1]]
+D, [2016-02-12T23:48:12.108644 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Profile Load (0.3ms)[0m SELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1 [["person_id", 1]]
+D, [2016-02-12T23:48:12.110462 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Aspect Load (0.2ms)[0m [1mSELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ?[0m [["contact_id", 1]]
+D, [2016-02-12T23:48:12.112166 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatContact Load (0.2ms)[0m SELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ? [["user_id", 1]]
+D, [2016-02-12T23:48:12.114044 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.5ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1[0m
+D, [2016-02-12T23:48:12.116255 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Contact Load (0.3ms)[0m SELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id [["user_id", 1]]
+D, [2016-02-12T23:48:12.117670 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.2ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1[0m [["id", 1]]
+D, [2016-02-12T23:48:12.119046 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Profile Load (0.2ms)[0m SELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1 [["person_id", 1]]
+D, [2016-02-12T23:48:12.120133 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Aspect Load (0.1ms)[0m [1mSELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ?[0m [["contact_id", 1]]
+D, [2016-02-12T23:48:12.120731 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatContact Load (0.1ms)[0m SELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ? [["user_id", 1]]
+D, [2016-02-12T23:48:12.122647 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::ChatOfflineMessage Load (0.2ms)[0m [1mSELECT "chat_offline_messages".* FROM "chat_offline_messages"[0m
+D, [2016-02-12T23:48:12.132814 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::User Load (0.5ms)[0m SELECT "users".* FROM "users" WHERE "users"."username" = 'test2' LIMIT 1
+D, [2016-02-12T23:48:12.134433 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.6ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" = 'test2' LIMIT 1[0m
+D, [2016-02-12T23:48:12.136764 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.3ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
+D, [2016-02-12T23:48:12.148501 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::User Load (0.3ms)[0m [1mSELECT "users".* FROM "users" WHERE "users"."username" IS NULL LIMIT 1[0m
+D, [2016-02-12T23:48:12.149870 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::User Load (0.3ms)[0m SELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1
+D, [2016-02-12T23:48:12.153525 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Contact Load (0.3ms)[0m [1mSELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id[0m [["user_id", 1]]
+D, [2016-02-12T23:48:12.155170 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Person Load (0.2ms)[0m SELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1 [["id", 1]]
+D, [2016-02-12T23:48:12.156944 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Profile Load (0.2ms)[0m [1mSELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1[0m [["person_id", 1]]
+D, [2016-02-12T23:48:12.158140 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Aspect Load (0.2ms)[0m SELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ? [["contact_id", 1]]
+D, [2016-02-12T23:48:12.159616 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::ChatContact Load (0.2ms)[0m [1mSELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ?[0m [["user_id", 1]]
+D, [2016-02-12T23:48:12.229953 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::User Load (0.5ms)[0m SELECT "users".* FROM "users" WHERE "users"."username" = 'test' LIMIT 1
+D, [2016-02-12T23:48:12.231801 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Contact Load (0.3ms)[0m [1mSELECT "contacts".* FROM "contacts" INNER JOIN "aspect_memberships" ON "aspect_memberships"."contact_id" = "contacts"."id" INNER JOIN "aspects" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "contacts"."user_id" = ? AND (aspects.chat_enabled = 't') GROUP BY person_id, contacts.id[0m [["user_id", 1]]
+D, [2016-02-12T23:48:12.233066 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Person Load (0.4ms)[0m SELECT "people".* FROM "people" WHERE "people"."id" = ? ORDER BY "people"."id" ASC LIMIT 1 [["id", 1]]
+D, [2016-02-12T23:48:12.234183 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Profile Load (0.3ms)[0m [1mSELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1[0m [["person_id", 1]]
+D, [2016-02-12T23:48:12.235585 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Aspect Load (0.2ms)[0m SELECT "aspects".* FROM "aspects" INNER JOIN "aspect_memberships" ON "aspects"."id" = "aspect_memberships"."aspect_id" WHERE "aspect_memberships"."contact_id" = ? [["contact_id", 1]]
+D, [2016-02-12T23:48:12.236636 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::ChatContact Load (0.2ms)[0m [1mSELECT "chat_contacts".* FROM "chat_contacts" WHERE "chat_contacts"."user_id" = ?[0m [["user_id", 1]]
+D, [2016-02-12T23:48:12.312647 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.4ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
+D, [2016-02-12T23:48:12.316812 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Person Load (0.2ms)[0m [1mSELECT "people".* FROM "people" WHERE "people"."diaspora_handle" = 'someone at inthe.void' LIMIT 1[0m
+D, [2016-02-12T23:48:12.317598 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::Person Load (0.2ms)[0m SELECT "people".* FROM "people" WHERE "people"."diaspora_handle" = 'test at local.host' LIMIT 1
+D, [2016-02-12T23:48:12.319368 #25629] DEBUG -- : [1m[36mVines::Storage::Sql::Profile Load (0.2ms)[0m [1mSELECT "profiles".* FROM "profiles" WHERE "profiles"."person_id" = ? ORDER BY "profiles"."id" ASC LIMIT 1[0m [["person_id", 1]]
+D, [2016-02-12T23:48:12.320718 #25629] DEBUG -- : [1m[35mVines::Storage::Sql::ChatOfflineMessage Load (0.1ms)[0m SELECT "chat_offline_messages".* FROM "chat_offline_messages"
+I, [2016-02-12T23:48:12.542995 #25629] INFO -- : Authenticating user: alice at wonderland.lit
+I, [2016-02-12T23:48:12.544076 #25629] INFO -- : Authenticating user: alice at wonderland.lit
+I, [2016-02-12T23:48:12.544442 #25629] INFO -- : Authenticating user: alice at wonderland.lit
+I, [2016-02-12T23:48:12.544535 #25629] INFO -- : Authentication succeeded: alice at wonderland.lit
--
Alioth's /usr/local/bin/git-commit-notice on /srv/git.debian.org/git/pkg-ruby-extras/ruby-diaspora-vines.git
More information about the Pkg-ruby-extras-commits
mailing list