User's name missing on GitLab.com OIDC first login #874

Open
opened 4 weeks ago by tpansino · 5 comments
tpansino commented 4 weeks ago

While debugging #864, I noticed that the first time a user logs in via a GitLab OIDC connection, the real name of the user is not populated in the UI.

On second login, however, it is.

The only error I see in the logs is:

▶ handler/ReadAllWeb 090 pq: syntax error at or near ")"

Let me know if there's other infomation I can provide. Also see the other issue for an example config.yml.

While debugging #864, I noticed that the first time a user logs in via a GitLab OIDC connection, the real name of the user is not populated in the UI. On second login, however, it is. The only error I see in the logs is: ``` ▶ handler/ReadAllWeb 090 pq: syntax error at or near ")" ``` Let me know if there's other infomation I can provide. Also see the other issue for an example `config.yml`.
konrad added the
kind/bug
label 4 weeks ago
Owner

What route got logged right after the error message?

What route got logged right after the error message?
Poster

I honestly can't tell, in fact I can't even reproduce the error message anymore. 😕 The bug is still present though.

Here's the full log with all three log levels set to DEBUG, starting from the moment I click LOG IN WITH GITLAB:

service_1  | 2021-05-27T17:39:21.39486194Z: WEB         ▶ 10.67.11.1  GET 200 /api/v1/info 391.082µs - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
service_1  | 2021-05-27T17:39:22.079426688Z: DEBUG      ▶ [DATABASE] 199 [cache] Get SQL: SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1, [https://gitlab.example.com 1]
service_1  | 2021-05-27T17:39:22.079481653Z: DEBUG      ▶ [DATABASE] 19a [redis_cacher]  GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1-[https://gitlab.example.com 1]|key:xorm:sql:users:1877853377
service_1  | 2021-05-27T17:39:22.088091988Z: INFO       ▶ [DATABASE] 19b [SQL] SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1 [https://gitlab.example.com 1] - 7.486439ms
service_1  | 2021-05-27T17:39:22.089923504Z: INFO       ▶ [DATABASE] 19c [SQL] SELECT "id", "name", "username", "password", "email", "is_active", "password_reset_token", "email_confirm_token", "avatar_provider", "avatar_file_id", "issuer", "subject", "email_reminders_enabled", "discoverable_by_name", "discoverable_by_email", "overdue_tasks_reminders_enabled", "created", "updated" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1 [https://gitlab.example.com 1] - 1.196918ms
service_1  | 2021-05-27T17:39:22.090090183Z: DEBUG      ▶ [DATABASE] 19d [cache] Get SQL: SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1, [tpansino]
service_1  | 2021-05-27T17:39:22.090125665Z: DEBUG      ▶ [DATABASE] 19e [redis_cacher]  GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1-[tpansino]|key:xorm:sql:users:210234968
service_1  | 2021-05-27T17:39:22.091536498Z: INFO       ▶ [DATABASE] 19f [SQL] SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1 [tpansino] - 1.145706ms
service_1  | 2021-05-27T17:39:22.092176785Z: INFO       ▶ [DATABASE] 1a0 [SQL] SELECT "id", "name", "username", "password", "email", "is_active", "password_reset_token", "email_confirm_token", "avatar_provider", "avatar_file_id", "issuer", "subject", "email_reminders_enabled", "discoverable_by_name", "discoverable_by_email", "overdue_tasks_reminders_enabled", "created", "updated" FROM "users" WHERE "username"=$1 LIMIT 1 [tpansino] - 602.566µs
service_1  | 2021-05-27T17:39:22.092256635Z: DEBUG      ▶ [DATABASE] 1a1 [cache] Get SQL: SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1, [https://gitlab.example.com 1]
service_1  | 2021-05-27T17:39:22.092274236Z: DEBUG      ▶ [DATABASE] 1a2 [redis_cacher]  GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1-[https://gitlab.example.com 1]|key:xorm:sql:users:1877853377
service_1  | 2021-05-27T17:39:22.092986659Z: INFO       ▶ [DATABASE] 1a3 [SQL] SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1 [https://gitlab.example.com 1] - 516.929µs
service_1  | 2021-05-27T17:39:22.093674555Z: INFO       ▶ [DATABASE] 1a4 [SQL] SELECT "id", "name", "username", "password", "email", "is_active", "password_reset_token", "email_confirm_token", "avatar_provider", "avatar_file_id", "issuer", "subject", "email_reminders_enabled", "discoverable_by_name", "discoverable_by_email", "overdue_tasks_reminders_enabled", "created", "updated" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1 [https://gitlab.example.com 1] - 660.982µs
service_1  | 2021-05-27T17:39:22.098847784Z: INFO       ▶ [DATABASE] 1a5 [SQL] INSERT INTO "users" ("name","username","password","email","is_active","password_reset_token","email_confirm_token","avatar_provider","avatar_file_id","issuer","subject","email_reminders_enabled","discoverable_by_name","discoverable_by_email","overdue_tasks_reminders_enabled","created","updated") VALUES ($1,$2,$3,$4,$5,$6,$7,$8,$9,$10,$11,$12,$13,$14,$15,$16,$17) RETURNING "id" [ tpansino  tpansino@example.com true   initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 17:39:22 2021-05-27 17:39:22] - 5.07152ms
service_1  | 2021-05-27T17:39:22.098925977Z: DEBUG      ▶ [DATABASE] 1a6 [cache] clear SQL: users
service_1  | 2021-05-27T17:39:22.098953249Z: DEBUG      ▶ [DATABASE] 1a7 [redis_cacher] delObjects key:[xorm:sql:users:*]
service_1  | 2021-05-27T17:39:22.099204608Z: DEBUG      ▶ [DATABASE] 1a8 [redis_cacher] delObjects keys: []
service_1  | 2021-05-27T17:39:22.099284278Z: DEBUG      ▶ [DATABASE] 1a9 [cache] Get SQL: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [1]
service_1  | 2021-05-27T17:39:22.099300089Z: DEBUG      ▶ [DATABASE] 1aa [redis_cacher]  GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|key:xorm:sql:users:3584688328
service_1  | 2021-05-27T17:39:22.099852183Z: INFO       ▶ [DATABASE] 1ab [SQL] SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1 [1] - 383.751µs
service_1  | 2021-05-27T17:39:22.099897684Z: DEBUG      ▶ [DATABASE] 1ac [cache] cache ids: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [[1]]
service_1  | 2021-05-27T17:39:22.10007554Z: DEBUG       ▶ [DATABASE] 1ad [redis_cacher] PutIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|ke��������PK����int64|type:string
service_1  | 2021-05-27T17:39:22.100259489Z: DEBUG      ▶ [DATABASE] 1ae [redis_cacher] registerGobConcreteType:string
service_1  | 2021-05-27T17:39:22.100285241Z: DEBUG      ▶ [DATABASE] 1af [redis_cacher] serialize type:string
service_1  | 2021-05-27T17:39:22.101315139Z: DEBUG      ▶ [DATABASE] 1b0 [cache] get bean: users, [1]
service_1  | 2021-05-27T17:39:22.101365086Z: DEBUG      ▶ [DATABASE] 1b1 [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64
service_1  | 2021-05-27T17:39:22.106941405Z: INFO       ▶ [DATABASE] 1b2 [SQL] SELECT "id", "name", "username", "password", "email", "is_active", "password_reset_token", "email_confirm_token", "avatar_provider", "avatar_file_id", "issuer", "subject", "email_reminders_enabled", "discoverable_by_name", "discoverable_by_email", "overdue_tasks_reminders_enabled", "created", "updated" FROM "users" WHERE "id"=$1 LIMIT 1 [1] - 5.385078ms
service_1  | 2021-05-27T17:39:22.107070958Z: DEBUG      ▶ [DATABASE] 1b3 empty zone key[created] : 2021-05-27 17:39:22 +0000 +0000 | zone:  | location: {name: zone:[{name: offset:0 isDST:false}] tx:[{when:-9223372036854775808 index:0 isstd:false isutc:false}] extend: cacheStart:-9223372036854775808 cacheEnd:9223372036854775807 cacheZone:0xc00018cc80}
service_1  |
service_1  | 2021-05-27T17:39:22.107114337Z: DEBUG      ▶ [DATABASE] 1b4 empty zone key[updated] : 2021-05-27 17:39:22 +0000 +0000 | zone:  | location: {name: zone:[{name: offset:0 isDST:false}] tx:[{when:-9223372036854775808 index:0 isstd:false isutc:false}] extend: cacheStart:-9223372036854775808 cacheEnd:9223372036854775807 cacheZone:0xc00018cc80}
service_1  |
service_1  | 2021-05-27T17:39:22.107139231Z: DEBUG      ▶ [DATABASE] 1b5 [cache] cache bean: users, [1], &{1  tpansino  tpansino@example.com true   initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 PDT 2021-05-27 10:39:22 -0700 PDT <nil>}
service_1  | 2021-05-27T17:39:22.107175814Z: DEBUG      ▶ [DATABASE] 1b6 [redis_cacher] PutBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64|type:*user.User
service_1  | 2021-05-27T17:39:22.107329512Z: DEBUG      ▶ [DATABASE] 1b7 [redis_cacher] registerGobConcreteType:*user.User
service_1  | 2021-05-27T17:39:22.107354722Z: DEBUG      ▶ [DATABASE] 1b8 [redis_cacher] serialize type:*user.User
service_1  | 2021-05-27T17:39:22.108499195Z: DEBUG      ▶ [EVENTS] 1b9 Sending msg to subscriber, topic=user.created, message_uuid=82ba0779-1c6b-4f82-a5bb-8fa5e06dbb98
service_1  | 2021-05-27T17:39:22.10852998Z: DEBUG       ▶ [EVENTS] 1ba Sent message to subscriber, message_uuid=82ba0779-1c6b-4f82-a5bb-8fa5e06dbb98, topic=user.created
service_1  | 2021-05-27T17:39:22.108564304Z: DEBUG      ▶ [EVENTS] 1bb Received message, message_uuid=82ba0779-1c6b-4f82-a5bb-8fa5e06dbb98
service_1  | 2021-05-27T17:39:22.108601869Z: DEBUG      ▶ [EVENTS] 1bc Message acked, message_uuid=82ba0779-1c6b-4f82-a5bb-8fa5e06dbb98
service_1  | 2021-05-27T17:39:22.108617756Z: DEBUG      ▶ [EVENTS] 1bd Message acked, message_uuid=82ba0779-1c6b-4f82-a5bb-8fa5e06dbb98, topic=user.created
service_1  | 2021-05-27T17:39:22.1092808Z: DEBUG        ▶ [DATABASE] 1be [cache] Get SQL: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [1]
service_1  | 2021-05-27T17:39:22.109317927Z: DEBUG      ▶ [DATABASE] 1bf [redis_cacher]  GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|key:xorm:sql:users:3584688328
service_1  | 2021-05-27T17:39:22.109649065Z: DEBUG      ▶ [DATABASE] 1c0 [redis_cacher] deserialize type:string
service_1  | 2021-05-27T17:39:22.109788495Z: DEBUG      ▶ [DATABASE] 1c1 [cache] cache hit: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [[1]]
service_1  | 2021-05-27T17:39:22.109823207Z: DEBUG      ▶ [DATABASE] 1c2 [cache] get bean: users, [1]
service_1  | 2021-05-27T17:39:22.10985454Z: DEBUG       ▶ [DATABASE] 1c3 [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64
service_1  | 2021-05-27T17:39:22.110322695Z: DEBUG      ▶ [DATABASE] 1c4 [redis_cacher] deserialize type:*user.User
service_1  | 2021-05-27T17:39:22.110349419Z: DEBUG      ▶ [DATABASE] 1c5 [cache] cache hit: users, [1], &{1  tpansino  tpansino@example.com true   initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>}
service_1  | 2021-05-27T17:39:22.112299663Z: INFO       ▶ [DATABASE] 1c6 [SQL] INSERT INTO "namespaces" ("title","description","owner_id","hex_color","is_archived","created","updated") VALUES ($1,$2,$3,$4,$5,$6,$7) RETURNING "id" [tpansino tpansino's namespace. 1  false 2021-05-27 17:39:22 2021-05-27 17:39:22] - 1.862448ms
service_1  | 2021-05-27T17:39:22.11234857Z: DEBUG       ▶ [DATABASE] 1c7 [cache] clear SQL: namespaces
service_1  | 2021-05-27T17:39:22.112366543Z: DEBUG      ▶ [DATABASE] 1c8 [redis_cacher] delObjects key:[xorm:sql:namespaces:*]
service_1  | 2021-05-27T17:39:22.112668344Z: DEBUG      ▶ [DATABASE] 1c9 [redis_cacher] delObjects keys: []
service_1  | 2021-05-27T17:39:22.112965486Z: WEB        ▶ 10.67.11.1  POST 200 /api/v1/auth/openid/gitlab/callback 634.620893ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
service_1  | 2021-05-27T17:39:22.112974749Z: DEBUG      ▶ [EVENTS] 1ca Sending msg to subscriber, message_uuid=8b85802c-0004-4ba1-ad33-fe7a4c939d77, topic=namespace.created
service_1  | 2021-05-27T17:39:22.113103594Z: DEBUG      ▶ [EVENTS] 1cb Sent message to subscriber, message_uuid=8b85802c-0004-4ba1-ad33-fe7a4c939d77, topic=namespace.created
service_1  | 2021-05-27T17:39:22.113164194Z: DEBUG      ▶ [EVENTS] 1cc Received message, message_uuid=8b85802c-0004-4ba1-ad33-fe7a4c939d77
service_1  | 2021-05-27T17:39:22.113221882Z: DEBUG      ▶ [EVENTS] 1cd Message acked, message_uuid=8b85802c-0004-4ba1-ad33-fe7a4c939d77
service_1  | 2021-05-27T17:39:22.113258385Z: DEBUG      ▶ [EVENTS] 1ce Message acked, message_uuid=8b85802c-0004-4ba1-ad33-fe7a4c939d77, topic=namespace.created
service_1  | 2021-05-27T17:39:22.142079274Z: DEBUG      ▶ [DATABASE] 1cf [cache] Get SQL: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [1]
service_1  | 2021-05-27T17:39:22.142146241Z: DEBUG      ▶ [DATABASE] 1d0 [redis_cacher]  GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|key:xorm:sql:users:3584688328
service_1  | 2021-05-27T17:39:22.142549973Z: DEBUG      ▶ [DATABASE] 1d1 [redis_cacher] deserialize type:string
service_1  | 2021-05-27T17:39:22.142719365Z: DEBUG      ▶ [DATABASE] 1d2 [cache] cache hit: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [[1]]
service_1  | 2021-05-27T17:39:22.14275391Z: DEBUG       ▶ [DATABASE] 1d3 [cache] get bean: users, [1]
service_1  | 2021-05-27T17:39:22.142797021Z: DEBUG      ▶ [DATABASE] 1d4 [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64
service_1  | 2021-05-27T17:39:22.143341061Z: DEBUG      ▶ [DATABASE] 1d5 [redis_cacher] deserialize type:*user.User
service_1  | 2021-05-27T17:39:22.143375827Z: DEBUG      ▶ [DATABASE] 1d6 [cache] cache hit: users, [1], &{1  tpansino  tpansino@example.com true   initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>}
service_1  | 2021-05-27T17:39:22.143564206Z: WEB        ▶ 10.67.11.1  GET 200 /api/v1/user 1.742686ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
service_1  | 2021-05-27T17:39:22.167907769Z: DEBUG      ▶ [DATABASE] 1d7 [cache] Get SQL: SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1, [tpansino]
service_1  | 2021-05-27T17:39:22.167932621Z: DEBUG      ▶ [DATABASE] 1d8 [redis_cacher]  GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1-[tpansino]|key:xorm:sql:users:210234968
service_1  | 2021-05-27T17:39:22.169020396Z: INFO       ▶ [DATABASE] 1d9 [SQL] SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1 [tpansino] - 689.752µs
service_1  | 2021-05-27T17:39:22.169048115Z: DEBUG      ▶ [DATABASE] 1da [cache] cache ids: SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1, [[1]]
service_1  | 2021-05-27T17:39:22.16908114Z: DEBUG       ▶ [DATABASE] 1db [redis_cacher] PutIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1-��������PK����int64|type:string
service_1  | 2021-05-27T17:39:22.169257264Z: DEBUG      ▶ [DATABASE] 1dc [redis_cacher] registerGobConcreteType:string
service_1  | 2021-05-27T17:39:22.16927495Z: DEBUG       ▶ [DATABASE] 1dd [redis_cacher] serialize type:string
service_1  | 2021-05-27T17:39:22.170239066Z: DEBUG      ▶ [DATABASE] 1de [cache] get bean: users, [1]
service_1  | 2021-05-27T17:39:22.170288958Z: DEBUG      ▶ [DATABASE] 1df [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64
service_1  | 2021-05-27T17:39:22.170700548Z: DEBUG      ▶ [DATABASE] 1e0 [redis_cacher] deserialize type:*user.User
service_1  | 2021-05-27T17:39:22.17072525Z: DEBUG       ▶ [DATABASE] 1e1 [cache] cache hit: users, [1], &{1  tpansino  tpansino@example.com true   initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>}
service_1  | 2021-05-27T17:39:22.170767044Z: DEBUG      ▶ initials/GetAvatar 1e2 Initials avatar for user 1 and size 50 not cached, creating...
service_1  | 2021-05-27T17:39:22.170821912Z: DEBUG      ▶ initials/getAvatarForUser 1e3 Initials avatar for user 1 not cached, creating...
service_1  | 2021-05-27T17:39:22.296308339Z: WEB        ▶ 10.67.11.1  GET 200 /api/v1/avatar/tpansino?size=50 128.441961ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
service_1  | 2021-05-27T17:39:22.322531079Z: DEBUG      ▶ [DATABASE] 1e4 [redis_cacher]  GetIds|tableName:notifications|sql:SELECT "id" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50-[1]|key:xorm:sql:notifications:3832039880
service_1  | 2021-05-27T17:39:22.323035345Z: DEBUG      ▶ [DATABASE] 1e5 [cache] Get SQL: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [1]
service_1  | 2021-05-27T17:39:22.323171244Z: DEBUG      ▶ [DATABASE] 1e6 [redis_cacher]  GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|key:xorm:sql:users:3584688328
service_1  | 2021-05-27T17:39:22.323592534Z: DEBUG      ▶ [DATABASE] 1e7 [redis_cacher] deserialize type:string
service_1  | 2021-05-27T17:39:22.323757008Z: DEBUG      ▶ [DATABASE] 1e8 [cache] cache hit: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [[1]]
service_1  | 2021-05-27T17:39:22.323792331Z: DEBUG      ▶ [DATABASE] 1e9 [cache] get bean: users, [1]
service_1  | 2021-05-27T17:39:22.323850847Z: DEBUG      ▶ [DATABASE] 1ea [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64
service_1  | 2021-05-27T17:39:22.324401769Z: DEBUG      ▶ [DATABASE] 1eb [redis_cacher] deserialize type:*user.User
service_1  | 2021-05-27T17:39:22.324430685Z: DEBUG      ▶ [DATABASE] 1ec [cache] cache hit: users, [1], &{1  tpansino  tpansino@example.com true   initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>}
service_1  | 2021-05-27T17:39:22.324603208Z: WEB        ▶ 10.67.11.1  POST 200 /api/v1/user/token 1.904207ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
service_1  | 2021-05-27T17:39:22.324959072Z: INFO       ▶ [DATABASE] 1ed [SQL] SELECT "id" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50 [1] - 2.053675ms
service_1  | 2021-05-27T17:39:22.32500896Z: DEBUG       ▶ [DATABASE] 1ee [cache] cache sql: [], notifications, SELECT "id", "notifiable_id", "notification", "name", "read_at", "created" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50, SELECT "id" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50, [1]
service_1  | 2021-05-27T17:39:22.325059831Z: DEBUG      ▶ [DATABASE] 1ef [redis_cacher] PutIds|tableName:notifications|sql:SELECT "id" FROM "notifications" WHERE (notif��������PK����|type:string0-[1]|key:xorm:sql:notifications:3832039880|obj:
service_1  | 2021-05-27T17:39:22.32525861Z: DEBUG       ▶ [DATABASE] 1f0 [redis_cacher] registerGobConcreteType:string
service_1  | 2021-05-27T17:39:22.325291998Z: DEBUG      ▶ [DATABASE] 1f1 [redis_cacher] serialize type:string
service_1  | 2021-05-27T17:39:22.325671486Z: INFO       ▶ [DATABASE] 1f2 [SQL] SELECT namespaces.* FROM "namespaces" LEFT JOIN "team_namespaces" ON namespaces.id = team_namespaces.namespace_id LEFT JOIN "team_members" ON team_members.team_id = team_namespaces.team_id LEFT JOIN "users_namespaces" ON users_namespaces.namespace_id = namespaces.id WHERE (((team_members.user_id = $1)) OR (namespaces.owner_id = $2) OR (users_namespaces.user_id = $3)) AND namespaces.title LIKE $4 AND 1=$5 GROUP BY namespaces.id LIMIT 50 [1 1 1 %% 1] - 3.837452ms
service_1  | 2021-05-27T17:39:22.325839539Z: DEBUG      ▶ [DATABASE] 1f3 empty zone key[created] : 2021-05-27 17:39:22 +0000 +0000 | zone:  | location: {name: zone:[{name: offset:0 isDST:false}] tx:[{when:-9223372036854775808 index:0 isstd:false isutc:false}] extend: cacheStart:-9223372036854775808 cacheEnd:9223372036854775807 cacheZone:0xc00018cc80}
service_1  |
service_1  | 2021-05-27T17:39:22.325901798Z: DEBUG      ▶ [DATABASE] 1f4 empty zone key[updated] : 2021-05-27 17:39:22 +0000 +0000 | zone:  | location: {name: zone:[{name: offset:0 isDST:false}] tx:[{when:-9223372036854775808 index:0 isstd:false isutc:false}] extend: cacheStart:-9223372036854775808 cacheEnd:9223372036854775807 cacheZone:0xc00018cc80}
service_1  |
service_1  | 2021-05-27T17:39:22.327598378Z: INFO       ▶ [DATABASE] 1f5 [SQL] SELECT count(*) FROM "notifications" WHERE (notifiable_id = $1) [1] - 737.384µs
service_1  | 2021-05-27T17:39:22.327697521Z: WEB        ▶ 10.67.11.1  GET 200 /api/v1/notifications?page=1 5.504033ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
service_1  | 2021-05-27T17:39:22.328937852Z: INFO       ▶ [DATABASE] 1f6 [SQL] SELECT count(*) FROM "namespaces" LEFT JOIN "team_namespaces" ON namespaces.id = team_namespaces.namespace_id LEFT JOIN "team_members" ON team_members.team_id = team_namespaces.team_id LEFT JOIN "users_namespaces" ON users_namespaces.namespace_id = namespaces.id WHERE (((team_members.user_id = $1)) OR (namespaces.owner_id = $2) OR (users_namespaces.user_id = $3)) AND (namespaces.is_archived = false) AND namespaces.title LIKE $4 AND 1=$5 GROUP BY namespaces.id [1 1 1 %% 1] - 2.839885ms
service_1  | 2021-05-27T17:39:22.329032211Z: DEBUG      ▶ [DATABASE] 1f7 [redis_cacher]  GetIds|tableName:subscriptions|sql:SELECT "id" FROM "subscriptions" WHERE (entity_type = $1 AND user_id = $2) AND "entity_id" IN ($3)-[1 1 1]|key:xorm:sql:subscriptions:3189446348
service_1  | 2021-05-27T17:39:22.330306537Z: INFO       ▶ [DATABASE] 1f8 [SQL] SELECT "id" FROM "subscriptions" WHERE (entity_type = $1 AND user_id = $2) AND "entity_id" IN ($3) [1 1 1] - 1.045128ms
service_1  | 2021-05-27T17:39:22.330341295Z: DEBUG      ▶ [DATABASE] 1f9 [cache] cache sql: [], subscriptions, SELECT "id", "entity_type", "entity_id", "user_id", "created" FROM "subscriptions" WHERE (entity_type = $1 AND user_id = $2) AND "entity_id" IN ($3), SELECT "id" FROM "subscriptions" WHERE (entity_type = $1 AND user_id = $2) AND "entity_id" IN ($3), [1 1 1]
service_1  | 2021-05-27T17:39:22.330377481Z: DEBUG      ▶ [DATABASE] 1fa [redis_cacher] PutIds|tableName:subscriptions|sql:SELECT "id" FROM "subscriptions" WHERE (entit��������PK����|type:stringty_id" IN ($3)-[1 1 1]|key:xorm:sql:subscriptions:3189446348|obj:
service_1  | 2021-05-27T17:39:22.33050497Z: DEBUG       ▶ [DATABASE] 1fb [redis_cacher] registerGobConcreteType:string
service_1  | 2021-05-27T17:39:22.330524279Z: DEBUG      ▶ [DATABASE] 1fc [redis_cacher] serialize type:string
service_1  | 2021-05-27T17:39:22.331407757Z: DEBUG      ▶ [DATABASE] 1fd [redis_cacher]  GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id" IN ($1)-[1]|key:xorm:sql:users:760853834
service_1  | 2021-05-27T17:39:22.332111299Z: INFO       ▶ [DATABASE] 1fe [SQL] SELECT "id" FROM "users" WHERE "id" IN ($1) [1] - 434.257µs
service_1  | 2021-05-27T17:39:22.332163989Z: DEBUG      ▶ [DATABASE] 1ff [cache] cache sql: [[1]], users, SELECT "id", "name", "username", "password", "email", "is_active", "password_reset_token", "email_confirm_token", "avatar_provider", "avatar_file_id", "issuer", "subject", "email_reminders_enabled", "discoverable_by_name", "discoverable_by_email", "overdue_tasks_reminders_enabled", "created", "updated" FROM "users" WHERE "id" IN ($1), SELECT "id" FROM "users" WHERE "id" IN ($1), [1]
service_1  | 2021-05-27T17:39:22.332228948Z: DEBUG      ▶ [DATABASE] 200 [redis_cacher] PutIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id" IN ($1)-[1]|key:x��������PK����int64|type:string
service_1  | 2021-05-27T17:39:22.332362145Z: DEBUG      ▶ [DATABASE] 201 [redis_cacher] registerGobConcreteType:string
service_1  | 2021-05-27T17:39:22.332384661Z: DEBUG      ▶ [DATABASE] 202 [redis_cacher] serialize type:string
service_1  | 2021-05-27T17:39:22.333255543Z: DEBUG      ▶ [DATABASE] 203 [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64
service_1  | 2021-05-27T17:39:22.333584649Z: DEBUG      ▶ [DATABASE] 204 [redis_cacher] deserialize type:*user.User
service_1  | 2021-05-27T17:39:22.333608142Z: DEBUG      ▶ [DATABASE] 205 [cache] cache hit bean: users, [1], &{1  tpansino  tpansino@example.com true   initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>}
service_1  | 2021-05-27T17:39:22.33370878Z: DEBUG       ▶ [DATABASE] 206 [redis_cacher]  GetIds|tableName:lists|sql:SELECT "id" FROM "lists" WHERE "namespace_id" IN ($1)-[1]|key:xorm:sql:lists:1635515353
service_1  | 2021-05-27T17:39:22.334707915Z: INFO       ▶ [DATABASE] 207 [SQL] SELECT "id" FROM "lists" WHERE "namespace_id" IN ($1) [1] - 763.74µs
service_1  | 2021-05-27T17:39:22.334741745Z: DEBUG      ▶ [DATABASE] 208 [cache] cache sql: [], lists, SELECT "id", "title", "description", "identifier", "hex_color", "owner_id", "namespace_id", "is_archived", "background_file_id", "is_favorite", "created", "updated" FROM "lists" WHERE "namespace_id" IN ($1), SELECT "id" FROM "lists" WHERE "namespace_id" IN ($1), [1]
service_1  | 2021-05-27T17:39:22.334784974Z: DEBUG      ▶ [DATABASE] 209 [redis_cacher] PutIds|tableName:lists|sql:SELECT "id" FROM "lists" WHERE "namespace_id" IN ($1)��������PK����|type:string
service_1  | 2021-05-27T17:39:22.334923219Z: DEBUG      ▶ [DATABASE] 20a [redis_cacher] registerGobConcreteType:string
service_1  | 2021-05-27T17:39:22.334942337Z: DEBUG      ▶ [DATABASE] 20b [redis_cacher] serialize type:string
service_1  | 2021-05-27T17:39:22.337757Z: INFO  ▶ [DATABASE] 20c [SQL] SELECT l.* FROM "lists" AS "l" LEFT JOIN "team_lists" AS "tl" ON l.id = tl.list_id LEFT JOIN "team_members" AS "tm" ON tm.team_id = tl.team_id LEFT JOIN "users_lists" AS "ul" ON ul.list_id = l.id WHERE ((tm.user_id=$1 AND l.owner_id<>$2) OR (ul.user_id=$3 AND l.owner_id<>$4)) GROUP BY l.id [1 1 1 1] - 1.991582ms
service_1  | 2021-05-27T17:39:22.337845492Z: DEBUG      ▶ [DATABASE] 20d [redis_cacher]  GetIds|tableName:saved_filters|sql:SELECT "id" FROM "saved_filters" WHERE (owner_id = $1)-[1]|key:xorm:sql:saved_filters:2326935452
service_1  | 2021-05-27T17:39:22.338827855Z: INFO       ▶ [DATABASE] 20e [SQL] SELECT "id" FROM "saved_filters" WHERE (owner_id = $1) [1] - 726.623µs
service_1  | 2021-05-27T17:39:22.338863034Z: DEBUG      ▶ [DATABASE] 20f [cache] cache sql: [], saved_filters, SELECT "id", "filters", "title", "description", "owner_id", "is_favorite", "created", "updated" FROM "saved_filters" WHERE (owner_id = $1), SELECT "id" FROM "saved_filters" WHERE (owner_id = $1), [1]
service_1  | 2021-05-27T17:39:22.338913018Z: DEBUG      ▶ [DATABASE] 210 [redis_cacher] PutIds|tableName:saved_filters|sql:SELECT "id" FROM "saved_filters" WHERE (owner��������PK����|type:strings:2326935452|obj:
service_1  | 2021-05-27T17:39:22.339049991Z: DEBUG      ▶ [DATABASE] 211 [redis_cacher] registerGobConcreteType:string
service_1  | 2021-05-27T17:39:22.339069804Z: DEBUG      ▶ [DATABASE] 212 [redis_cacher] serialize type:string
service_1  | 2021-05-27T17:39:22.341109702Z: INFO       ▶ [DATABASE] 213 [SQL] SELECT count(*) FROM "tasks" INNER JOIN "lists" ON tasks.list_id = lists.id INNER JOIN "namespaces" ON lists.namespace_id = namespaces.id WHERE tasks.is_favorite=$1 AND namespaces.id IN ($2) [true 1] - 1.19613ms
service_1  | 2021-05-27T17:39:22.341318317Z: WEB        ▶ 10.67.11.1  GET 200 /api/v1/namespaces?is_archived=true&page=1 19.964317ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
service_1  | 2021-05-27T17:39:22.415988944Z: DEBUG      ▶ [DATABASE] 214 [cache] Get SQL: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [1]
service_1  | 2021-05-27T17:39:22.416035878Z: DEBUG      ▶ [DATABASE] 215 [redis_cacher]  GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|key:xorm:sql:users:3584688328
service_1  | 2021-05-27T17:39:22.416400564Z: DEBUG      ▶ [DATABASE] 216 [redis_cacher] deserialize type:string
service_1  | 2021-05-27T17:39:22.41657246Z: DEBUG       ▶ [DATABASE] 217 [cache] cache hit: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [[1]]
service_1  | 2021-05-27T17:39:22.416604933Z: DEBUG      ▶ [DATABASE] 218 [cache] get bean: users, [1]
service_1  | 2021-05-27T17:39:22.416645221Z: DEBUG      ▶ [DATABASE] 219 [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64
service_1  | 2021-05-27T17:39:22.417117975Z: DEBUG      ▶ [DATABASE] 21a [redis_cacher] deserialize type:*user.User
service_1  | 2021-05-27T17:39:22.417160078Z: DEBUG      ▶ [DATABASE] 21b [cache] cache hit: users, [1], &{1  tpansino  tpansino@example.com true   initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>}
service_1  | 2021-05-27T17:39:22.417245797Z: WEB        ▶ 10.67.11.1  GET 200 /api/v1/user 1.422625ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
service_1  | 2021-05-27T17:39:32.198367085Z: DEBUG      ▶ [DATABASE] 21c [redis_cacher]  GetIds|tableName:notifications|sql:SELECT "id" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50-[1]|key:xorm:sql:notifications:3832039880
service_1  | 2021-05-27T17:39:32.19882207Z: DEBUG       ▶ [DATABASE] 21d [redis_cacher] deserialize type:string
service_1  | 2021-05-27T17:39:32.198996334Z: DEBUG      ▶ [DATABASE] 21e [cache] cache hit sql: notifications, SELECT "id", "notifiable_id", "notification", "name", "read_at", "created" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50, SELECT "id" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50, [1]
service_1  | 2021-05-27T17:39:32.206670786Z: INFO       ▶ [DATABASE] 21f [SQL] SELECT count(*) FROM "notifications" WHERE (notifiable_id = $1) [1] - 7.569612ms
service_1  | 2021-05-27T17:39:32.206754079Z: WEB        ▶ 10.67.11.1  GET 200 /api/v1/notifications?page=1 8.62545ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
I honestly can't tell, in fact I can't even reproduce the error message anymore. 😕 The bug is still present though. Here's the full log with all three log levels set to `DEBUG`, starting from the moment I click `LOG IN WITH GITLAB`: ``` service_1 | 2021-05-27T17:39:21.39486194Z: WEB ▶ 10.67.11.1 GET 200 /api/v1/info 391.082µs - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0 service_1 | 2021-05-27T17:39:22.079426688Z: DEBUG ▶ [DATABASE] 199 [cache] Get SQL: SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1, [https://gitlab.example.com 1] service_1 | 2021-05-27T17:39:22.079481653Z: DEBUG ▶ [DATABASE] 19a [redis_cacher] GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1-[https://gitlab.example.com 1]|key:xorm:sql:users:1877853377 service_1 | 2021-05-27T17:39:22.088091988Z: INFO ▶ [DATABASE] 19b [SQL] SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1 [https://gitlab.example.com 1] - 7.486439ms service_1 | 2021-05-27T17:39:22.089923504Z: INFO ▶ [DATABASE] 19c [SQL] SELECT "id", "name", "username", "password", "email", "is_active", "password_reset_token", "email_confirm_token", "avatar_provider", "avatar_file_id", "issuer", "subject", "email_reminders_enabled", "discoverable_by_name", "discoverable_by_email", "overdue_tasks_reminders_enabled", "created", "updated" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1 [https://gitlab.example.com 1] - 1.196918ms service_1 | 2021-05-27T17:39:22.090090183Z: DEBUG ▶ [DATABASE] 19d [cache] Get SQL: SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1, [tpansino] service_1 | 2021-05-27T17:39:22.090125665Z: DEBUG ▶ [DATABASE] 19e [redis_cacher] GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1-[tpansino]|key:xorm:sql:users:210234968 service_1 | 2021-05-27T17:39:22.091536498Z: INFO ▶ [DATABASE] 19f [SQL] SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1 [tpansino] - 1.145706ms service_1 | 2021-05-27T17:39:22.092176785Z: INFO ▶ [DATABASE] 1a0 [SQL] SELECT "id", "name", "username", "password", "email", "is_active", "password_reset_token", "email_confirm_token", "avatar_provider", "avatar_file_id", "issuer", "subject", "email_reminders_enabled", "discoverable_by_name", "discoverable_by_email", "overdue_tasks_reminders_enabled", "created", "updated" FROM "users" WHERE "username"=$1 LIMIT 1 [tpansino] - 602.566µs service_1 | 2021-05-27T17:39:22.092256635Z: DEBUG ▶ [DATABASE] 1a1 [cache] Get SQL: SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1, [https://gitlab.example.com 1] service_1 | 2021-05-27T17:39:22.092274236Z: DEBUG ▶ [DATABASE] 1a2 [redis_cacher] GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1-[https://gitlab.example.com 1]|key:xorm:sql:users:1877853377 service_1 | 2021-05-27T17:39:22.092986659Z: INFO ▶ [DATABASE] 1a3 [SQL] SELECT "id" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1 [https://gitlab.example.com 1] - 516.929µs service_1 | 2021-05-27T17:39:22.093674555Z: INFO ▶ [DATABASE] 1a4 [SQL] SELECT "id", "name", "username", "password", "email", "is_active", "password_reset_token", "email_confirm_token", "avatar_provider", "avatar_file_id", "issuer", "subject", "email_reminders_enabled", "discoverable_by_name", "discoverable_by_email", "overdue_tasks_reminders_enabled", "created", "updated" FROM "users" WHERE "issuer"=$1 AND "subject"=$2 LIMIT 1 [https://gitlab.example.com 1] - 660.982µs service_1 | 2021-05-27T17:39:22.098847784Z: INFO ▶ [DATABASE] 1a5 [SQL] INSERT INTO "users" ("name","username","password","email","is_active","password_reset_token","email_confirm_token","avatar_provider","avatar_file_id","issuer","subject","email_reminders_enabled","discoverable_by_name","discoverable_by_email","overdue_tasks_reminders_enabled","created","updated") VALUES ($1,$2,$3,$4,$5,$6,$7,$8,$9,$10,$11,$12,$13,$14,$15,$16,$17) RETURNING "id" [ tpansino tpansino@example.com true initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 17:39:22 2021-05-27 17:39:22] - 5.07152ms service_1 | 2021-05-27T17:39:22.098925977Z: DEBUG ▶ [DATABASE] 1a6 [cache] clear SQL: users service_1 | 2021-05-27T17:39:22.098953249Z: DEBUG ▶ [DATABASE] 1a7 [redis_cacher] delObjects key:[xorm:sql:users:*] service_1 | 2021-05-27T17:39:22.099204608Z: DEBUG ▶ [DATABASE] 1a8 [redis_cacher] delObjects keys: [] service_1 | 2021-05-27T17:39:22.099284278Z: DEBUG ▶ [DATABASE] 1a9 [cache] Get SQL: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [1] service_1 | 2021-05-27T17:39:22.099300089Z: DEBUG ▶ [DATABASE] 1aa [redis_cacher] GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|key:xorm:sql:users:3584688328 service_1 | 2021-05-27T17:39:22.099852183Z: INFO ▶ [DATABASE] 1ab [SQL] SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1 [1] - 383.751µs service_1 | 2021-05-27T17:39:22.099897684Z: DEBUG ▶ [DATABASE] 1ac [cache] cache ids: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [[1]] service_1 | 2021-05-27T17:39:22.10007554Z: DEBUG ▶ [DATABASE] 1ad [redis_cacher] PutIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|ke��������PK����int64|type:string service_1 | 2021-05-27T17:39:22.100259489Z: DEBUG ▶ [DATABASE] 1ae [redis_cacher] registerGobConcreteType:string service_1 | 2021-05-27T17:39:22.100285241Z: DEBUG ▶ [DATABASE] 1af [redis_cacher] serialize type:string service_1 | 2021-05-27T17:39:22.101315139Z: DEBUG ▶ [DATABASE] 1b0 [cache] get bean: users, [1] service_1 | 2021-05-27T17:39:22.101365086Z: DEBUG ▶ [DATABASE] 1b1 [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64 service_1 | 2021-05-27T17:39:22.106941405Z: INFO ▶ [DATABASE] 1b2 [SQL] SELECT "id", "name", "username", "password", "email", "is_active", "password_reset_token", "email_confirm_token", "avatar_provider", "avatar_file_id", "issuer", "subject", "email_reminders_enabled", "discoverable_by_name", "discoverable_by_email", "overdue_tasks_reminders_enabled", "created", "updated" FROM "users" WHERE "id"=$1 LIMIT 1 [1] - 5.385078ms service_1 | 2021-05-27T17:39:22.107070958Z: DEBUG ▶ [DATABASE] 1b3 empty zone key[created] : 2021-05-27 17:39:22 +0000 +0000 | zone: | location: {name: zone:[{name: offset:0 isDST:false}] tx:[{when:-9223372036854775808 index:0 isstd:false isutc:false}] extend: cacheStart:-9223372036854775808 cacheEnd:9223372036854775807 cacheZone:0xc00018cc80} service_1 | service_1 | 2021-05-27T17:39:22.107114337Z: DEBUG ▶ [DATABASE] 1b4 empty zone key[updated] : 2021-05-27 17:39:22 +0000 +0000 | zone: | location: {name: zone:[{name: offset:0 isDST:false}] tx:[{when:-9223372036854775808 index:0 isstd:false isutc:false}] extend: cacheStart:-9223372036854775808 cacheEnd:9223372036854775807 cacheZone:0xc00018cc80} service_1 | service_1 | 2021-05-27T17:39:22.107139231Z: DEBUG ▶ [DATABASE] 1b5 [cache] cache bean: users, [1], &{1 tpansino tpansino@example.com true initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 PDT 2021-05-27 10:39:22 -0700 PDT <nil>} service_1 | 2021-05-27T17:39:22.107175814Z: DEBUG ▶ [DATABASE] 1b6 [redis_cacher] PutBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64|type:*user.User service_1 | 2021-05-27T17:39:22.107329512Z: DEBUG ▶ [DATABASE] 1b7 [redis_cacher] registerGobConcreteType:*user.User service_1 | 2021-05-27T17:39:22.107354722Z: DEBUG ▶ [DATABASE] 1b8 [redis_cacher] serialize type:*user.User service_1 | 2021-05-27T17:39:22.108499195Z: DEBUG ▶ [EVENTS] 1b9 Sending msg to subscriber, topic=user.created, message_uuid=82ba0779-1c6b-4f82-a5bb-8fa5e06dbb98 service_1 | 2021-05-27T17:39:22.10852998Z: DEBUG ▶ [EVENTS] 1ba Sent message to subscriber, message_uuid=82ba0779-1c6b-4f82-a5bb-8fa5e06dbb98, topic=user.created service_1 | 2021-05-27T17:39:22.108564304Z: DEBUG ▶ [EVENTS] 1bb Received message, message_uuid=82ba0779-1c6b-4f82-a5bb-8fa5e06dbb98 service_1 | 2021-05-27T17:39:22.108601869Z: DEBUG ▶ [EVENTS] 1bc Message acked, message_uuid=82ba0779-1c6b-4f82-a5bb-8fa5e06dbb98 service_1 | 2021-05-27T17:39:22.108617756Z: DEBUG ▶ [EVENTS] 1bd Message acked, message_uuid=82ba0779-1c6b-4f82-a5bb-8fa5e06dbb98, topic=user.created service_1 | 2021-05-27T17:39:22.1092808Z: DEBUG ▶ [DATABASE] 1be [cache] Get SQL: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [1] service_1 | 2021-05-27T17:39:22.109317927Z: DEBUG ▶ [DATABASE] 1bf [redis_cacher] GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|key:xorm:sql:users:3584688328 service_1 | 2021-05-27T17:39:22.109649065Z: DEBUG ▶ [DATABASE] 1c0 [redis_cacher] deserialize type:string service_1 | 2021-05-27T17:39:22.109788495Z: DEBUG ▶ [DATABASE] 1c1 [cache] cache hit: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [[1]] service_1 | 2021-05-27T17:39:22.109823207Z: DEBUG ▶ [DATABASE] 1c2 [cache] get bean: users, [1] service_1 | 2021-05-27T17:39:22.10985454Z: DEBUG ▶ [DATABASE] 1c3 [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64 service_1 | 2021-05-27T17:39:22.110322695Z: DEBUG ▶ [DATABASE] 1c4 [redis_cacher] deserialize type:*user.User service_1 | 2021-05-27T17:39:22.110349419Z: DEBUG ▶ [DATABASE] 1c5 [cache] cache hit: users, [1], &{1 tpansino tpansino@example.com true initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>} service_1 | 2021-05-27T17:39:22.112299663Z: INFO ▶ [DATABASE] 1c6 [SQL] INSERT INTO "namespaces" ("title","description","owner_id","hex_color","is_archived","created","updated") VALUES ($1,$2,$3,$4,$5,$6,$7) RETURNING "id" [tpansino tpansino's namespace. 1 false 2021-05-27 17:39:22 2021-05-27 17:39:22] - 1.862448ms service_1 | 2021-05-27T17:39:22.11234857Z: DEBUG ▶ [DATABASE] 1c7 [cache] clear SQL: namespaces service_1 | 2021-05-27T17:39:22.112366543Z: DEBUG ▶ [DATABASE] 1c8 [redis_cacher] delObjects key:[xorm:sql:namespaces:*] service_1 | 2021-05-27T17:39:22.112668344Z: DEBUG ▶ [DATABASE] 1c9 [redis_cacher] delObjects keys: [] service_1 | 2021-05-27T17:39:22.112965486Z: WEB ▶ 10.67.11.1 POST 200 /api/v1/auth/openid/gitlab/callback 634.620893ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0 service_1 | 2021-05-27T17:39:22.112974749Z: DEBUG ▶ [EVENTS] 1ca Sending msg to subscriber, message_uuid=8b85802c-0004-4ba1-ad33-fe7a4c939d77, topic=namespace.created service_1 | 2021-05-27T17:39:22.113103594Z: DEBUG ▶ [EVENTS] 1cb Sent message to subscriber, message_uuid=8b85802c-0004-4ba1-ad33-fe7a4c939d77, topic=namespace.created service_1 | 2021-05-27T17:39:22.113164194Z: DEBUG ▶ [EVENTS] 1cc Received message, message_uuid=8b85802c-0004-4ba1-ad33-fe7a4c939d77 service_1 | 2021-05-27T17:39:22.113221882Z: DEBUG ▶ [EVENTS] 1cd Message acked, message_uuid=8b85802c-0004-4ba1-ad33-fe7a4c939d77 service_1 | 2021-05-27T17:39:22.113258385Z: DEBUG ▶ [EVENTS] 1ce Message acked, message_uuid=8b85802c-0004-4ba1-ad33-fe7a4c939d77, topic=namespace.created service_1 | 2021-05-27T17:39:22.142079274Z: DEBUG ▶ [DATABASE] 1cf [cache] Get SQL: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [1] service_1 | 2021-05-27T17:39:22.142146241Z: DEBUG ▶ [DATABASE] 1d0 [redis_cacher] GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|key:xorm:sql:users:3584688328 service_1 | 2021-05-27T17:39:22.142549973Z: DEBUG ▶ [DATABASE] 1d1 [redis_cacher] deserialize type:string service_1 | 2021-05-27T17:39:22.142719365Z: DEBUG ▶ [DATABASE] 1d2 [cache] cache hit: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [[1]] service_1 | 2021-05-27T17:39:22.14275391Z: DEBUG ▶ [DATABASE] 1d3 [cache] get bean: users, [1] service_1 | 2021-05-27T17:39:22.142797021Z: DEBUG ▶ [DATABASE] 1d4 [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64 service_1 | 2021-05-27T17:39:22.143341061Z: DEBUG ▶ [DATABASE] 1d5 [redis_cacher] deserialize type:*user.User service_1 | 2021-05-27T17:39:22.143375827Z: DEBUG ▶ [DATABASE] 1d6 [cache] cache hit: users, [1], &{1 tpansino tpansino@example.com true initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>} service_1 | 2021-05-27T17:39:22.143564206Z: WEB ▶ 10.67.11.1 GET 200 /api/v1/user 1.742686ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0 service_1 | 2021-05-27T17:39:22.167907769Z: DEBUG ▶ [DATABASE] 1d7 [cache] Get SQL: SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1, [tpansino] service_1 | 2021-05-27T17:39:22.167932621Z: DEBUG ▶ [DATABASE] 1d8 [redis_cacher] GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1-[tpansino]|key:xorm:sql:users:210234968 service_1 | 2021-05-27T17:39:22.169020396Z: INFO ▶ [DATABASE] 1d9 [SQL] SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1 [tpansino] - 689.752µs service_1 | 2021-05-27T17:39:22.169048115Z: DEBUG ▶ [DATABASE] 1da [cache] cache ids: SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1, [[1]] service_1 | 2021-05-27T17:39:22.16908114Z: DEBUG ▶ [DATABASE] 1db [redis_cacher] PutIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "username"=$1 LIMIT 1-��������PK����int64|type:string service_1 | 2021-05-27T17:39:22.169257264Z: DEBUG ▶ [DATABASE] 1dc [redis_cacher] registerGobConcreteType:string service_1 | 2021-05-27T17:39:22.16927495Z: DEBUG ▶ [DATABASE] 1dd [redis_cacher] serialize type:string service_1 | 2021-05-27T17:39:22.170239066Z: DEBUG ▶ [DATABASE] 1de [cache] get bean: users, [1] service_1 | 2021-05-27T17:39:22.170288958Z: DEBUG ▶ [DATABASE] 1df [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64 service_1 | 2021-05-27T17:39:22.170700548Z: DEBUG ▶ [DATABASE] 1e0 [redis_cacher] deserialize type:*user.User service_1 | 2021-05-27T17:39:22.17072525Z: DEBUG ▶ [DATABASE] 1e1 [cache] cache hit: users, [1], &{1 tpansino tpansino@example.com true initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>} service_1 | 2021-05-27T17:39:22.170767044Z: DEBUG ▶ initials/GetAvatar 1e2 Initials avatar for user 1 and size 50 not cached, creating... service_1 | 2021-05-27T17:39:22.170821912Z: DEBUG ▶ initials/getAvatarForUser 1e3 Initials avatar for user 1 not cached, creating... service_1 | 2021-05-27T17:39:22.296308339Z: WEB ▶ 10.67.11.1 GET 200 /api/v1/avatar/tpansino?size=50 128.441961ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0 service_1 | 2021-05-27T17:39:22.322531079Z: DEBUG ▶ [DATABASE] 1e4 [redis_cacher] GetIds|tableName:notifications|sql:SELECT "id" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50-[1]|key:xorm:sql:notifications:3832039880 service_1 | 2021-05-27T17:39:22.323035345Z: DEBUG ▶ [DATABASE] 1e5 [cache] Get SQL: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [1] service_1 | 2021-05-27T17:39:22.323171244Z: DEBUG ▶ [DATABASE] 1e6 [redis_cacher] GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|key:xorm:sql:users:3584688328 service_1 | 2021-05-27T17:39:22.323592534Z: DEBUG ▶ [DATABASE] 1e7 [redis_cacher] deserialize type:string service_1 | 2021-05-27T17:39:22.323757008Z: DEBUG ▶ [DATABASE] 1e8 [cache] cache hit: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [[1]] service_1 | 2021-05-27T17:39:22.323792331Z: DEBUG ▶ [DATABASE] 1e9 [cache] get bean: users, [1] service_1 | 2021-05-27T17:39:22.323850847Z: DEBUG ▶ [DATABASE] 1ea [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64 service_1 | 2021-05-27T17:39:22.324401769Z: DEBUG ▶ [DATABASE] 1eb [redis_cacher] deserialize type:*user.User service_1 | 2021-05-27T17:39:22.324430685Z: DEBUG ▶ [DATABASE] 1ec [cache] cache hit: users, [1], &{1 tpansino tpansino@example.com true initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>} service_1 | 2021-05-27T17:39:22.324603208Z: WEB ▶ 10.67.11.1 POST 200 /api/v1/user/token 1.904207ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0 service_1 | 2021-05-27T17:39:22.324959072Z: INFO ▶ [DATABASE] 1ed [SQL] SELECT "id" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50 [1] - 2.053675ms service_1 | 2021-05-27T17:39:22.32500896Z: DEBUG ▶ [DATABASE] 1ee [cache] cache sql: [], notifications, SELECT "id", "notifiable_id", "notification", "name", "read_at", "created" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50, SELECT "id" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50, [1] service_1 | 2021-05-27T17:39:22.325059831Z: DEBUG ▶ [DATABASE] 1ef [redis_cacher] PutIds|tableName:notifications|sql:SELECT "id" FROM "notifications" WHERE (notif��������PK����|type:string0-[1]|key:xorm:sql:notifications:3832039880|obj: service_1 | 2021-05-27T17:39:22.32525861Z: DEBUG ▶ [DATABASE] 1f0 [redis_cacher] registerGobConcreteType:string service_1 | 2021-05-27T17:39:22.325291998Z: DEBUG ▶ [DATABASE] 1f1 [redis_cacher] serialize type:string service_1 | 2021-05-27T17:39:22.325671486Z: INFO ▶ [DATABASE] 1f2 [SQL] SELECT namespaces.* FROM "namespaces" LEFT JOIN "team_namespaces" ON namespaces.id = team_namespaces.namespace_id LEFT JOIN "team_members" ON team_members.team_id = team_namespaces.team_id LEFT JOIN "users_namespaces" ON users_namespaces.namespace_id = namespaces.id WHERE (((team_members.user_id = $1)) OR (namespaces.owner_id = $2) OR (users_namespaces.user_id = $3)) AND namespaces.title LIKE $4 AND 1=$5 GROUP BY namespaces.id LIMIT 50 [1 1 1 %% 1] - 3.837452ms service_1 | 2021-05-27T17:39:22.325839539Z: DEBUG ▶ [DATABASE] 1f3 empty zone key[created] : 2021-05-27 17:39:22 +0000 +0000 | zone: | location: {name: zone:[{name: offset:0 isDST:false}] tx:[{when:-9223372036854775808 index:0 isstd:false isutc:false}] extend: cacheStart:-9223372036854775808 cacheEnd:9223372036854775807 cacheZone:0xc00018cc80} service_1 | service_1 | 2021-05-27T17:39:22.325901798Z: DEBUG ▶ [DATABASE] 1f4 empty zone key[updated] : 2021-05-27 17:39:22 +0000 +0000 | zone: | location: {name: zone:[{name: offset:0 isDST:false}] tx:[{when:-9223372036854775808 index:0 isstd:false isutc:false}] extend: cacheStart:-9223372036854775808 cacheEnd:9223372036854775807 cacheZone:0xc00018cc80} service_1 | service_1 | 2021-05-27T17:39:22.327598378Z: INFO ▶ [DATABASE] 1f5 [SQL] SELECT count(*) FROM "notifications" WHERE (notifiable_id = $1) [1] - 737.384µs service_1 | 2021-05-27T17:39:22.327697521Z: WEB ▶ 10.67.11.1 GET 200 /api/v1/notifications?page=1 5.504033ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0 service_1 | 2021-05-27T17:39:22.328937852Z: INFO ▶ [DATABASE] 1f6 [SQL] SELECT count(*) FROM "namespaces" LEFT JOIN "team_namespaces" ON namespaces.id = team_namespaces.namespace_id LEFT JOIN "team_members" ON team_members.team_id = team_namespaces.team_id LEFT JOIN "users_namespaces" ON users_namespaces.namespace_id = namespaces.id WHERE (((team_members.user_id = $1)) OR (namespaces.owner_id = $2) OR (users_namespaces.user_id = $3)) AND (namespaces.is_archived = false) AND namespaces.title LIKE $4 AND 1=$5 GROUP BY namespaces.id [1 1 1 %% 1] - 2.839885ms service_1 | 2021-05-27T17:39:22.329032211Z: DEBUG ▶ [DATABASE] 1f7 [redis_cacher] GetIds|tableName:subscriptions|sql:SELECT "id" FROM "subscriptions" WHERE (entity_type = $1 AND user_id = $2) AND "entity_id" IN ($3)-[1 1 1]|key:xorm:sql:subscriptions:3189446348 service_1 | 2021-05-27T17:39:22.330306537Z: INFO ▶ [DATABASE] 1f8 [SQL] SELECT "id" FROM "subscriptions" WHERE (entity_type = $1 AND user_id = $2) AND "entity_id" IN ($3) [1 1 1] - 1.045128ms service_1 | 2021-05-27T17:39:22.330341295Z: DEBUG ▶ [DATABASE] 1f9 [cache] cache sql: [], subscriptions, SELECT "id", "entity_type", "entity_id", "user_id", "created" FROM "subscriptions" WHERE (entity_type = $1 AND user_id = $2) AND "entity_id" IN ($3), SELECT "id" FROM "subscriptions" WHERE (entity_type = $1 AND user_id = $2) AND "entity_id" IN ($3), [1 1 1] service_1 | 2021-05-27T17:39:22.330377481Z: DEBUG ▶ [DATABASE] 1fa [redis_cacher] PutIds|tableName:subscriptions|sql:SELECT "id" FROM "subscriptions" WHERE (entit��������PK����|type:stringty_id" IN ($3)-[1 1 1]|key:xorm:sql:subscriptions:3189446348|obj: service_1 | 2021-05-27T17:39:22.33050497Z: DEBUG ▶ [DATABASE] 1fb [redis_cacher] registerGobConcreteType:string service_1 | 2021-05-27T17:39:22.330524279Z: DEBUG ▶ [DATABASE] 1fc [redis_cacher] serialize type:string service_1 | 2021-05-27T17:39:22.331407757Z: DEBUG ▶ [DATABASE] 1fd [redis_cacher] GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id" IN ($1)-[1]|key:xorm:sql:users:760853834 service_1 | 2021-05-27T17:39:22.332111299Z: INFO ▶ [DATABASE] 1fe [SQL] SELECT "id" FROM "users" WHERE "id" IN ($1) [1] - 434.257µs service_1 | 2021-05-27T17:39:22.332163989Z: DEBUG ▶ [DATABASE] 1ff [cache] cache sql: [[1]], users, SELECT "id", "name", "username", "password", "email", "is_active", "password_reset_token", "email_confirm_token", "avatar_provider", "avatar_file_id", "issuer", "subject", "email_reminders_enabled", "discoverable_by_name", "discoverable_by_email", "overdue_tasks_reminders_enabled", "created", "updated" FROM "users" WHERE "id" IN ($1), SELECT "id" FROM "users" WHERE "id" IN ($1), [1] service_1 | 2021-05-27T17:39:22.332228948Z: DEBUG ▶ [DATABASE] 200 [redis_cacher] PutIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id" IN ($1)-[1]|key:x��������PK����int64|type:string service_1 | 2021-05-27T17:39:22.332362145Z: DEBUG ▶ [DATABASE] 201 [redis_cacher] registerGobConcreteType:string service_1 | 2021-05-27T17:39:22.332384661Z: DEBUG ▶ [DATABASE] 202 [redis_cacher] serialize type:string service_1 | 2021-05-27T17:39:22.333255543Z: DEBUG ▶ [DATABASE] 203 [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64 service_1 | 2021-05-27T17:39:22.333584649Z: DEBUG ▶ [DATABASE] 204 [redis_cacher] deserialize type:*user.User service_1 | 2021-05-27T17:39:22.333608142Z: DEBUG ▶ [DATABASE] 205 [cache] cache hit bean: users, [1], &{1 tpansino tpansino@example.com true initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>} service_1 | 2021-05-27T17:39:22.33370878Z: DEBUG ▶ [DATABASE] 206 [redis_cacher] GetIds|tableName:lists|sql:SELECT "id" FROM "lists" WHERE "namespace_id" IN ($1)-[1]|key:xorm:sql:lists:1635515353 service_1 | 2021-05-27T17:39:22.334707915Z: INFO ▶ [DATABASE] 207 [SQL] SELECT "id" FROM "lists" WHERE "namespace_id" IN ($1) [1] - 763.74µs service_1 | 2021-05-27T17:39:22.334741745Z: DEBUG ▶ [DATABASE] 208 [cache] cache sql: [], lists, SELECT "id", "title", "description", "identifier", "hex_color", "owner_id", "namespace_id", "is_archived", "background_file_id", "is_favorite", "created", "updated" FROM "lists" WHERE "namespace_id" IN ($1), SELECT "id" FROM "lists" WHERE "namespace_id" IN ($1), [1] service_1 | 2021-05-27T17:39:22.334784974Z: DEBUG ▶ [DATABASE] 209 [redis_cacher] PutIds|tableName:lists|sql:SELECT "id" FROM "lists" WHERE "namespace_id" IN ($1)��������PK����|type:string service_1 | 2021-05-27T17:39:22.334923219Z: DEBUG ▶ [DATABASE] 20a [redis_cacher] registerGobConcreteType:string service_1 | 2021-05-27T17:39:22.334942337Z: DEBUG ▶ [DATABASE] 20b [redis_cacher] serialize type:string service_1 | 2021-05-27T17:39:22.337757Z: INFO ▶ [DATABASE] 20c [SQL] SELECT l.* FROM "lists" AS "l" LEFT JOIN "team_lists" AS "tl" ON l.id = tl.list_id LEFT JOIN "team_members" AS "tm" ON tm.team_id = tl.team_id LEFT JOIN "users_lists" AS "ul" ON ul.list_id = l.id WHERE ((tm.user_id=$1 AND l.owner_id<>$2) OR (ul.user_id=$3 AND l.owner_id<>$4)) GROUP BY l.id [1 1 1 1] - 1.991582ms service_1 | 2021-05-27T17:39:22.337845492Z: DEBUG ▶ [DATABASE] 20d [redis_cacher] GetIds|tableName:saved_filters|sql:SELECT "id" FROM "saved_filters" WHERE (owner_id = $1)-[1]|key:xorm:sql:saved_filters:2326935452 service_1 | 2021-05-27T17:39:22.338827855Z: INFO ▶ [DATABASE] 20e [SQL] SELECT "id" FROM "saved_filters" WHERE (owner_id = $1) [1] - 726.623µs service_1 | 2021-05-27T17:39:22.338863034Z: DEBUG ▶ [DATABASE] 20f [cache] cache sql: [], saved_filters, SELECT "id", "filters", "title", "description", "owner_id", "is_favorite", "created", "updated" FROM "saved_filters" WHERE (owner_id = $1), SELECT "id" FROM "saved_filters" WHERE (owner_id = $1), [1] service_1 | 2021-05-27T17:39:22.338913018Z: DEBUG ▶ [DATABASE] 210 [redis_cacher] PutIds|tableName:saved_filters|sql:SELECT "id" FROM "saved_filters" WHERE (owner��������PK����|type:strings:2326935452|obj: service_1 | 2021-05-27T17:39:22.339049991Z: DEBUG ▶ [DATABASE] 211 [redis_cacher] registerGobConcreteType:string service_1 | 2021-05-27T17:39:22.339069804Z: DEBUG ▶ [DATABASE] 212 [redis_cacher] serialize type:string service_1 | 2021-05-27T17:39:22.341109702Z: INFO ▶ [DATABASE] 213 [SQL] SELECT count(*) FROM "tasks" INNER JOIN "lists" ON tasks.list_id = lists.id INNER JOIN "namespaces" ON lists.namespace_id = namespaces.id WHERE tasks.is_favorite=$1 AND namespaces.id IN ($2) [true 1] - 1.19613ms service_1 | 2021-05-27T17:39:22.341318317Z: WEB ▶ 10.67.11.1 GET 200 /api/v1/namespaces?is_archived=true&page=1 19.964317ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0 service_1 | 2021-05-27T17:39:22.415988944Z: DEBUG ▶ [DATABASE] 214 [cache] Get SQL: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [1] service_1 | 2021-05-27T17:39:22.416035878Z: DEBUG ▶ [DATABASE] 215 [redis_cacher] GetIds|tableName:users|sql:SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1-[1]|key:xorm:sql:users:3584688328 service_1 | 2021-05-27T17:39:22.416400564Z: DEBUG ▶ [DATABASE] 216 [redis_cacher] deserialize type:string service_1 | 2021-05-27T17:39:22.41657246Z: DEBUG ▶ [DATABASE] 217 [cache] cache hit: SELECT "id" FROM "users" WHERE "id"=$1 LIMIT 1, [[1]] service_1 | 2021-05-27T17:39:22.416604933Z: DEBUG ▶ [DATABASE] 218 [cache] get bean: users, [1] service_1 | 2021-05-27T17:39:22.416645221Z: DEBUG ▶ [DATABASE] 219 [redis_cacher] [xorm/redis_cacher] GetBean|tableName:users|id:��PK����int64|key:xorm:bean:users:��PK����int64 service_1 | 2021-05-27T17:39:22.417117975Z: DEBUG ▶ [DATABASE] 21a [redis_cacher] deserialize type:*user.User service_1 | 2021-05-27T17:39:22.417160078Z: DEBUG ▶ [DATABASE] 21b [cache] cache hit: users, [1], &{1 tpansino tpansino@example.com true initials 0 https://gitlab.example.com 1 false false false false 2021-05-27 10:39:22 -0700 -0700 2021-05-27 10:39:22 -0700 -0700 <nil>} service_1 | 2021-05-27T17:39:22.417245797Z: WEB ▶ 10.67.11.1 GET 200 /api/v1/user 1.422625ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0 service_1 | 2021-05-27T17:39:32.198367085Z: DEBUG ▶ [DATABASE] 21c [redis_cacher] GetIds|tableName:notifications|sql:SELECT "id" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50-[1]|key:xorm:sql:notifications:3832039880 service_1 | 2021-05-27T17:39:32.19882207Z: DEBUG ▶ [DATABASE] 21d [redis_cacher] deserialize type:string service_1 | 2021-05-27T17:39:32.198996334Z: DEBUG ▶ [DATABASE] 21e [cache] cache hit sql: notifications, SELECT "id", "notifiable_id", "notification", "name", "read_at", "created" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50, SELECT "id" FROM "notifications" WHERE (notifiable_id = $1) ORDER BY id DESC LIMIT 50, [1] service_1 | 2021-05-27T17:39:32.206670786Z: INFO ▶ [DATABASE] 21f [SQL] SELECT count(*) FROM "notifications" WHERE (notifiable_id = $1) [1] - 7.569612ms service_1 | 2021-05-27T17:39:32.206754079Z: WEB ▶ 10.67.11.1 GET 200 /api/v1/notifications?page=1 8.62545ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0 ```
Owner

I guess the syntax error error message is unrelated then.

I guess the `syntax error` error message is unrelated then.
Poster

@konrad what would you like to do with this bug? It seems minor, and the logs don't seem to yield much insight.

I'm inclined to close and drop it, unless you want to pursue it further?

@konrad what would you like to do with this bug? It seems minor, and the logs don't seem to yield much insight. I'm inclined to close and drop it, unless you want to pursue it further?
Owner

I agree this is a minor issue but it should be fixed at some point nonetheless.

Low prio and then doing it some time when I find the time (that will probably not be very soon :))

I agree this is a minor issue but it should be fixed at some point nonetheless. Low prio and then doing it some time when I find the time (that will probably not be very soon :))
Sign in to join this conversation.
No Milestone
No Assignees
2 Participants
Notifications
Due Date

No due date set.

Dependencies

This issue currently doesn't have any dependencies.

Loading…
There is no content yet.