[unstable version] reason_poisoned=pq: relation "webhooks" does not exist #1655

Closed
opened 2023-11-27 05:43:35 +00:00 by perfectra1n · 35 comments

Description

When importing data from 0.21.0 to the latest unstable, I get the following errors:

2023-11-27T05:41:54.823791115Z: ERROR	▶ events/func1 5068 Error while handling message 139eb8e4-9e44-4b40-8d41-3aa948b80aaf, reason_poisoned=pq: relation "webhooks" does not exist, topic_poisoned=task.created, handler_poisoned=task.created.webhook.listener, subscriber_poisoned=gochannel.GoChannel

I'm not sure if you are expecting to support Vikunja exports/imports across versions, or if that's out of scope.

Vikunja Frontend Version

Frontend Version: 0.21.0+368-282ec3164b

Vikunja API Version

API Version: v0.21.0+214-f26f1326ea

Browser and version

No response

Can you reproduce the bug on the Vikunja demo site?

No

Screenshots

No response

### Description When importing data from 0.21.0 to the latest unstable, I get the following errors: ``` 2023-11-27T05:41:54.823791115Z: ERROR ▶ events/func1 5068 Error while handling message 139eb8e4-9e44-4b40-8d41-3aa948b80aaf, reason_poisoned=pq: relation "webhooks" does not exist, topic_poisoned=task.created, handler_poisoned=task.created.webhook.listener, subscriber_poisoned=gochannel.GoChannel ``` I'm not sure if you are expecting to support Vikunja exports/imports across versions, or if that's out of scope. ### Vikunja Frontend Version Frontend Version: 0.21.0+368-282ec3164b ### Vikunja API Version API Version: v0.21.0+214-f26f1326ea ### Browser and version _No response_ ### Can you reproduce the bug on the Vikunja demo site? No ### Screenshots _No response_
perfectra1n added the
kind/bug
label 2023-11-27 05:43:35 +00:00
Owner

Importing across versions is not explicitly supported but it might still work.

It looks like you tried to import a dump generated by the vikunja dump cli command via the web ui? That won't work in any case. Use vikunja restore for that.

Importing across versions is not explicitly supported but it might still work. It looks like you tried to import a dump generated by the [`vikunja dump`](https://vikunja.io/docs/cli/#dump) cli command via the web ui? That won't work in any case. Use [`vikunja restore`](https://vikunja.io/docs/cli/#restore) for that.
Author

I utilized the web UI to generate the Vikunja export (old instance):
image

Which I then tried to import to the new Vikunja instance.

I will give the vikunja restore command a shot though...

I utilized the web UI to generate the Vikunja export (old instance): ![image](/attachments/51f58e4d-6317-431f-91ce-a0d0b3741206) Which I then tried to import to the new Vikunja instance. I will give the `vikunja restore` command a shot though...
Owner

Using the CLI will replace the whole instance - and doesn't really support migration across versions either.

Using the CLI will replace the whole instance - and doesn't really support migration across versions either.
Owner

What's interesting about this is the error - it actually seems unrelated to the import. Does the same error happen if you create a new task?

What's interesting about this is the error - it actually seems unrelated to the import. Does the same error happen if you create a new task?
Author

Oh, I hadn't even seen that webhooks were added yet, I should've looked the error some more lol. It seems to return an error when projects that existed even prior to the import, have their webhooks "edited":
image

It returns the following error:

2023-11-29T00:36:05.977304448Z: ERROR    -> handler/ReadAllWeb ac90 pq: relation "webhooks" does not exist
Oh, I hadn't even seen that webhooks were added yet, I should've looked the error some more lol. It seems to return an error when projects that existed even prior to the import, have their webhooks "edited": ![image](/attachments/78536e8e-6a62-42cf-b7bb-0683a26e1a75) It returns the following error: ``` 2023-11-29T00:36:05.977304448Z: ERROR -> handler/ReadAllWeb ac90 pq: relation "webhooks" does not exist ```
Author

Creating a new task doesn't seem to lead to any errors...

Creating a new task doesn't seem to lead to any errors...
Owner

Looks like the migration which should've created the webhooks table didn't run (I think we've seen this before). Can you check in the migrations table if the 20230913202615 migration has been run?

Looks like the migration which should've created the webhooks table didn't run (I think we've seen this before). Can you check in the `migrations` table if the 20230913202615 migration has been run?
Author

image

image

![image](/attachments/864feb8a-d4ec-4ff5-9738-915adc3b819b) ![image](/attachments/b8f6e611-72ff-4184-8a09-f8d527e27db6)
108 KiB
128 KiB
Author

So it doesn't look like 20230913202615 was created/run then...

This was just a "fresh" Vikunja running the latest unstable api/frontend, with a completely empty DB. So I'm assuming that the migration should have been run?

So it doesn't look like `20230913202615` was created/run then... This was just a "fresh" Vikunja running the latest unstable api/frontend, with a completely empty DB. So I'm assuming that the migration *should* have been run?
Author

I feel like a broken record always coming to you with:
"Hey brand new setups are broken w/ Postgres because of " 😅

I'm not sure if I'm just cursed or if it's just me lol

I feel like a broken record always coming to you with: "Hey brand new setups are broken w/ Postgres because of <x>" 😅 I'm not sure if I'm just cursed or if it's just me lol
Owner

So it doesn't look like 20230913202615 was created/run then...

But it is included in the list of your screenshot? The migration_status table seems to have been modified correctly as per 20231108231513. Maybe this happens only with new tables?

Does it work if you remove the entry from 20230913202615 and restart the api?

So I'm assuming that the migration should have been run?

That's exactly the thing. All pending migrations are applied on start of the api automatically. Even if the api or the datasbase crashes mid-way while running a migration, it should be picked up next time the api is restarted.

Doesn't seem to be related to postgres either from what I could check on the instances I run.

> So it doesn't look like 20230913202615 was created/run then... But it is included in the list of your screenshot? The `migration_status` table seems to have been modified correctly as per `20231108231513`. Maybe this happens only with new tables? Does it work if you remove the entry from `20230913202615` and restart the api? > So I'm assuming that the migration should have been run? That's exactly the thing. All pending migrations are applied on start of the api automatically. Even if the api or the datasbase crashes mid-way while running a migration, it should be picked up next time the api is restarted. Doesn't seem to be related to postgres either from what I could check on the instances I run.
Author

I should've just done a SELECT statement then looking at it via my eyeballs, yep, that migration is there then.

I'm assuming that the relation "webhooks" does not exist error is because of the 20230913202615 not executing? I'll go ahead and try with removing that entry in the table, and restarting the API container...

I should've just done a `SELECT` statement then looking at it via my eyeballs, yep, that migration is there then. I'm assuming that the `relation "webhooks" does not exist` error is because of the `20230913202615` not executing? I'll go ahead and try with removing that entry in the table, and restarting the API container...
Author

Well perhaps it would be easier to replicate if I lay out what I was trying to accomplish:

  1. Vikunja1 (v0.21.0) create export of your data via the GUI
  2. Vikunja2 (unstable, "default" install, no user provided data) import via the GUI, the previously exported data

That's how I was able to get the above error...

The only nuance that I can see is that I had a subtask of a task, that was imported into one of the newly imported projects. It seemed to create this weird infinite loop, and perhaps that's what broke the import?

Well perhaps it would be easier to replicate if I lay out what I was trying to accomplish: 1. Vikunja1 (v0.21.0) create export of your data via the GUI 2. Vikunja2 (unstable, "default" install, no user provided data) import via the GUI, the previously exported data That's how I was able to get the above error... The only nuance that I can see is that I had a subtask of a task, that was imported into one of the newly imported projects. It seemed to create this weird infinite loop, and perhaps that's what broke the import?
Owner

Are you importing with the cli commands? Because that runs all migrations, maybe there's something wrong there.

Are you importing with the cli commands? Because that runs all migrations, maybe there's something wrong there.
Author

No, I'm using the web UI :)

image

No, I'm using the web UI :) ![image](/attachments/2c3441e7-d927-482d-bee3-0416f5b099ad)
Author

Well, with the migration table looking like this:
image

I'm at least able to get further during an import, but still get the following error...

2023-12-01T16:18:30.167974756Z: ERROR	▶ models/Handle 31a Event payload does not contain task ID
2023-12-01T16:18:30.28219516Z: ERROR	▶ models/Handle 411 Event payload does not contain task ID
2023-12-01T16:18:30.314804592Z: ERROR	▶ models/Handle 45b Event payload does not contain task ID
2023-12-01T16:18:30.321008229Z: ERROR	▶ models/Handle 46d Event payload does not contain task ID
2023-12-01T16:18:30.360624322Z: ERROR	▶ models/Handle 4d4 Event payload does not contain task ID
2023-12-01T16:18:30.384341155Z: ERROR	▶ models/Handle 507 Event payload does not contain task ID
2023-12-01T16:18:30.447337846Z: ERROR	▶ models/Handle 592 Event payload does not contain task ID
2023-12-01T16:18:30.448843393Z: WEB 	▶ 10.233.114.192 b06eaa5190aa896425c4f6f3400dff20 GET 200 /api/v1/notifications?page=1 3.918477ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36
2023-12-01T16:18:30.469989716Z: ERROR	▶ models/Handle 5c9 Event payload does not contain task ID
2023-12-01T16:18:30.519182862Z: ERROR	▶ models/Handle 63c Event payload does not contain task ID
2023-12-01T16:18:30.558560209Z: ERROR	▶ models/Handle 6a7 Event payload does not contain task ID
2023-12-01T16:18:30.595771209Z: ERROR	▶ models/Handle 70c Event payload does not contain task ID
2023-12-01T16:18:30.663914531Z: ERROR	▶ models/Handle 79f Event payload does not contain task ID
2023-12-01T16:18:30.720005124Z: ERROR	▶ models/Handle 81d Event payload does not contain task ID
2023-12-01T16:18:30.725057692Z: ERROR	▶ models/Handle 837 Event payload does not contain task ID
2023-12-01T16:18:30.779517189Z: ERROR	▶ models/Handle 8a0 Event payload does not contain task ID
2023-12-01T16:18:30.786168634Z: ERROR	▶ models/Handle 8b5 Event payload does not contain task ID
2023-12-01T16:18:30.83208741Z: ERROR	▶ models/Handle 919 Event payload does not contain task ID

Let me know if you just want me to send you my export as well!

Well, with the `migration` table looking like this: ![image](/attachments/fd19d5c2-1b7e-42eb-987d-674f2f5f11a7) I'm at least able to get further during an import, but still get the following error... ``` 2023-12-01T16:18:30.167974756Z: ERROR ▶ models/Handle 31a Event payload does not contain task ID 2023-12-01T16:18:30.28219516Z: ERROR ▶ models/Handle 411 Event payload does not contain task ID 2023-12-01T16:18:30.314804592Z: ERROR ▶ models/Handle 45b Event payload does not contain task ID 2023-12-01T16:18:30.321008229Z: ERROR ▶ models/Handle 46d Event payload does not contain task ID 2023-12-01T16:18:30.360624322Z: ERROR ▶ models/Handle 4d4 Event payload does not contain task ID 2023-12-01T16:18:30.384341155Z: ERROR ▶ models/Handle 507 Event payload does not contain task ID 2023-12-01T16:18:30.447337846Z: ERROR ▶ models/Handle 592 Event payload does not contain task ID 2023-12-01T16:18:30.448843393Z: WEB ▶ 10.233.114.192 b06eaa5190aa896425c4f6f3400dff20 GET 200 /api/v1/notifications?page=1 3.918477ms - Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36 2023-12-01T16:18:30.469989716Z: ERROR ▶ models/Handle 5c9 Event payload does not contain task ID 2023-12-01T16:18:30.519182862Z: ERROR ▶ models/Handle 63c Event payload does not contain task ID 2023-12-01T16:18:30.558560209Z: ERROR ▶ models/Handle 6a7 Event payload does not contain task ID 2023-12-01T16:18:30.595771209Z: ERROR ▶ models/Handle 70c Event payload does not contain task ID 2023-12-01T16:18:30.663914531Z: ERROR ▶ models/Handle 79f Event payload does not contain task ID 2023-12-01T16:18:30.720005124Z: ERROR ▶ models/Handle 81d Event payload does not contain task ID 2023-12-01T16:18:30.725057692Z: ERROR ▶ models/Handle 837 Event payload does not contain task ID 2023-12-01T16:18:30.779517189Z: ERROR ▶ models/Handle 8a0 Event payload does not contain task ID 2023-12-01T16:18:30.786168634Z: ERROR ▶ models/Handle 8b5 Event payload does not contain task ID 2023-12-01T16:18:30.83208741Z: ERROR ▶ models/Handle 919 Event payload does not contain task ID ``` Let me know if you just want me to send you my export as well!
Owner

Importing via the web UI won't run any migrations or change anything else with the table structure. I don't think this is related to that (other than the error with the missing table becoming visible). Especially since we've seen this before, I think it was the Typesense sync table back then?

Did removing the migration entry and restarting the api create the table as expected?

Importing via the web UI won't run any migrations or change anything else with the table structure. I don't think this is related to that (other than the error with the missing table becoming visible). Especially since we've seen this before, I think it was the Typesense sync table back then? Did removing the migration entry and restarting the api create the table as expected?
Author

I removed the 20230913202615 from the migrations table, and then restarted the API container. It then created the new entry as expected:
image

I can try completely removing the migrations table if you would like me to try?

Yes, I believe that it was due to the index not being created within Typesense before (maybe they were unrelated to one another and were two separate issues?)

I removed the `20230913202615` from the `migrations` table, and then restarted the API container. It then created the new entry as expected: ![image](/attachments/18855dba-f9ce-4326-859f-295820f56b3e) I can try completely removing the `migrations` table if you would like me to try? Yes, I believe that it was due to the index not being created within Typesense before (maybe they were unrelated to one another and were two separate issues?)
Owner

Did it create the webhooks table?

Did it create the webhooks table?
Author

Oh, no it did not! In the screenshot here (vikunja/api#1655 (comment)), these are all the tables in the DB.

Oh, no it did not! In the screenshot here (https://kolaente.dev/vikunja/api/issues/1655#issuecomment-55973), these are all the tables in the DB.
Owner

Was there anything in the logs when you restarted it to re-run the migration?

Was there anything in the logs when you restarted it to re-run the migration?
Author

I restarted the pod a few more times to add the DEBUG values to get more interesting output:

          # Debugging values
          - name: VIKUNJA_LOG_DATABASE
            value: "on"
          - name: VIKUNJA_LOG_DATABASELEVEL
            value: "DEBUG"
          - name: VIKUNJA_LOG_EVENTS
            value: "on"
          - name: VIKUNJA_LOG_EVENTSLEVEL
            value: "DEBUG"
          - name: VIKUNJA_LOG_MAIL
            value: "on"
          - name: VIKUNJA_LOG_MAILLEVEL
            value: "DEBUG"

Then it...finally added the webhooks table?:

info: creating the new user vikunja with 1000:1000
usermod: no changes
2023-12-07T17:53:03.894192378Z: INFO	▶ config/InitConfig 001 No config file found, using default or config from environment variables.
2023-12-07T17:53:04.025298117Z: INFO	▶ [DATABASE] 003 [SQL] SELECT tablename FROM pg_tables WHERE schemaname = $1 [public] - 129.850358ms
2023-12-07T17:53:04.460470394Z: INFO	▶ [DATABASE] 004 [SQL] SELECT column_name, column_default, is_nullable, data_type, character_maximum_length, description,
    CASE WHEN p.contype = 'p' THEN true ELSE false END AS primarykey,
    CASE WHEN p.contype = 'u' THEN true ELSE false END AS uniquekey
FROM pg_attribute f
    JOIN pg_class c ON c.oid = f.attrelid JOIN pg_type t ON t.oid = f.atttypid
    LEFT JOIN pg_attrdef d ON d.adrelid = c.oid AND d.adnum = f.attnum
    LEFT JOIN pg_description de ON f.attrelid=de.objoid AND f.attnum=de.objsubid
    LEFT JOIN pg_namespace n ON n.oid = c.relnamespace
    LEFT JOIN pg_constraint p ON p.conrelid = c.oid AND f.attnum = ANY (p.conkey)
    LEFT JOIN pg_class AS g ON p.confrelid = g.oid
    LEFT JOIN INFORMATION_SCHEMA.COLUMNS s ON s.column_name=f.attname AND c.relname=s.table_name
WHERE n.nspname= s.table_schema AND c.relkind = 'r' AND c.relname = $1 AND s.table_schema = $2 AND f.attnum > 0 ORDER BY f.attnum; [migration public] - 434.904942ms
2023-12-07T17:53:04.484382057Z: INFO	▶ [DATABASE] 005 [SQL] SELECT indexname, indexdef FROM pg_indexes WHERE tablename=$1 AND schemaname=$2 [migration public] - 23.819609ms
2023-12-07T17:53:04.499257027Z: INFO	▶ [DATABASE] 006 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [SCHEMA_INIT] - 14.778531ms
2023-12-07T17:53:04.500689631Z: INFO	▶ [DATABASE] 007 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190324205606] - 1.38926ms
2023-12-07T17:53:04.50196764Z: INFO	▶ [DATABASE] 008 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190328074430] - 1.240159ms
2023-12-07T17:53:04.50313278Z: INFO	▶ [DATABASE] 009 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190430111111] - 1.128617ms
2023-12-07T17:53:04.50432353Z: INFO	▶ [DATABASE] 00a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190511202210] - 1.154136ms
2023-12-07T17:53:04.50551721Z: INFO	▶ [DATABASE] 00b [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190514192749] - 1.150039ms
2023-12-07T17:53:04.50678539Z: INFO	▶ [DATABASE] 00c [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190524205441] - 1.23191ms
2023-12-07T17:53:04.508304581Z: INFO	▶ [DATABASE] 00d [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190718200716] - 1.484989ms
2023-12-07T17:53:04.509888059Z: INFO	▶ [DATABASE] 00e [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190818210133] - 1.546775ms
2023-12-07T17:53:04.511326865Z: INFO	▶ [DATABASE] 00f [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190920185205] - 1.350083ms
2023-12-07T17:53:04.512770324Z: INFO	▶ [DATABASE] 010 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190922205826] - 1.404411ms
2023-12-07T17:53:04.514362751Z: INFO	▶ [DATABASE] 011 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20191008194238] - 1.555443ms
2023-12-07T17:53:04.515861074Z: INFO	▶ [DATABASE] 012 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20191010131430] - 1.459896ms
2023-12-07T17:53:04.517453039Z: INFO	▶ [DATABASE] 013 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20191207204427] - 1.492238ms
2023-12-07T17:53:04.518957043Z: INFO	▶ [DATABASE] 014 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20191207220736] - 1.4664ms
2023-12-07T17:53:04.52045032Z: INFO	▶ [DATABASE] 015 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200120201756] - 1.455439ms
2023-12-07T17:53:04.522167346Z: INFO	▶ [DATABASE] 016 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200219183248] - 1.679962ms
2023-12-07T17:53:04.523724721Z: INFO	▶ [DATABASE] 017 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200308205855] - 1.512756ms
2023-12-07T17:53:04.525126214Z: INFO	▶ [DATABASE] 018 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200308210130] - 1.366476ms
2023-12-07T17:53:04.526702016Z: INFO	▶ [DATABASE] 019 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200322214440] - 1.462973ms
2023-12-07T17:53:04.528097662Z: INFO	▶ [DATABASE] 01a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200322214624] - 1.361017ms
2023-12-07T17:53:04.529608623Z: INFO	▶ [DATABASE] 01b [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200417175201] - 1.476956ms
2023-12-07T17:53:04.531024807Z: INFO	▶ [DATABASE] 01c [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200418230432] - 1.371623ms
2023-12-07T17:53:04.532548014Z: INFO	▶ [DATABASE] 01d [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200418230605] - 1.486937ms
2023-12-07T17:53:04.534779035Z: INFO	▶ [DATABASE] 01e [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200420215928] - 2.191781ms
2023-12-07T17:53:04.536232769Z: INFO	▶ [DATABASE] 01f [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200425182634] - 1.389911ms
2023-12-07T17:53:04.53767696Z: INFO	▶ [DATABASE] 020 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200509103709] - 1.403153ms
2023-12-07T17:53:04.539371687Z: INFO	▶ [DATABASE] 021 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200515172220] - 1.658163ms
2023-12-07T17:53:04.541125072Z: INFO	▶ [DATABASE] 022 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200515195546] - 1.720178ms
2023-12-07T17:53:04.542878207Z: INFO	▶ [DATABASE] 023 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200516123847] - 1.718754ms
2023-12-07T17:53:04.54457483Z: INFO	▶ [DATABASE] 024 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200524221534] - 1.660308ms
2023-12-07T17:53:04.546402809Z: INFO	▶ [DATABASE] 025 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200524224611] - 1.79188ms
2023-12-07T17:53:04.548221262Z: INFO	▶ [DATABASE] 026 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200614113230] - 1.781313ms
2023-12-07T17:53:04.54999015Z: INFO	▶ [DATABASE] 027 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200621214452] - 1.713521ms
2023-12-07T17:53:04.551685888Z: INFO	▶ [DATABASE] 028 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200801183357] - 1.647376ms
2023-12-07T17:53:04.553245131Z: INFO	▶ [DATABASE] 029 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200904101559] - 1.522139ms
2023-12-07T17:53:04.554864179Z: INFO	▶ [DATABASE] 02a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200905151040] - 1.585086ms
2023-12-07T17:53:04.556349517Z: INFO	▶ [DATABASE] 02b [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200905232458] - 1.453026ms
2023-12-07T17:53:04.558058879Z: INFO	▶ [DATABASE] 02c [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200906184746] - 1.588726ms
2023-12-07T17:53:04.559690402Z: INFO	▶ [DATABASE] 02d [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20201025195822] - 1.589011ms
2023-12-07T17:53:04.561119297Z: INFO	▶ [DATABASE] 02e [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20201121181647] - 1.390548ms
2023-12-07T17:53:04.56273181Z: INFO	▶ [DATABASE] 02f [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20201218152741] - 1.576026ms
2023-12-07T17:53:04.564347775Z: INFO	▶ [DATABASE] 030 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20201218220204] - 1.584071ms
2023-12-07T17:53:04.565938886Z: INFO	▶ [DATABASE] 031 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20201219145028] - 1.544491ms
2023-12-07T17:53:04.567362132Z: INFO	▶ [DATABASE] 032 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210207192805] - 1.388569ms
2023-12-07T17:53:04.568984322Z: INFO	▶ [DATABASE] 033 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210209204715] - 1.590499ms
2023-12-07T17:53:04.570738874Z: INFO	▶ [DATABASE] 034 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210220222121] - 1.718297ms
2023-12-07T17:53:04.572328001Z: INFO	▶ [DATABASE] 035 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210221111953] - 1.550974ms
2023-12-07T17:53:04.57386788Z: INFO	▶ [DATABASE] 036 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210321185225] - 1.508051ms
2023-12-07T17:53:04.575385327Z: INFO	▶ [DATABASE] 037 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210328191017] - 1.482483ms
2023-12-07T17:53:04.576878036Z: INFO	▶ [DATABASE] 038 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210403145503] - 1.457527ms
2023-12-07T17:53:04.578377783Z: INFO	▶ [DATABASE] 039 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210403220653] - 1.464949ms
2023-12-07T17:53:04.579932128Z: INFO	▶ [DATABASE] 03a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210407170753] - 1.509279ms
2023-12-07T17:53:04.581359899Z: INFO	▶ [DATABASE] 03b [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210411113105] - 1.389846ms
2023-12-07T17:53:04.58274552Z: INFO	▶ [DATABASE] 03c [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210411161337] - 1.351537ms
2023-12-07T17:53:04.584112926Z: INFO	▶ [DATABASE] 03d [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210413131057] - 1.334135ms
2023-12-07T17:53:04.585460492Z: INFO	▶ [DATABASE] 03e [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210527105701] - 1.317755ms
2023-12-07T17:53:04.586824124Z: INFO	▶ [DATABASE] 03f [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210603174608] - 1.333258ms
2023-12-07T17:53:04.5882155Z: INFO	▶ [DATABASE] 040 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210709191101] - 1.360789ms
2023-12-07T17:53:04.589623049Z: INFO	▶ [DATABASE] 041 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210709211508] - 1.374471ms
2023-12-07T17:53:04.591092339Z: INFO	▶ [DATABASE] 042 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210711173657] - 1.433036ms
2023-12-07T17:53:04.592627058Z: INFO	▶ [DATABASE] 043 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210713213622] - 1.50313ms
2023-12-07T17:53:04.594345993Z: INFO	▶ [DATABASE] 044 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210725153703] - 1.688473ms
2023-12-07T17:53:04.595764089Z: INFO	▶ [DATABASE] 045 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210727204942] - 1.38511ms
2023-12-07T17:53:04.596987514Z: INFO	▶ [DATABASE] 046 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210727211037] - 1.189751ms
2023-12-07T17:53:04.598496635Z: INFO	▶ [DATABASE] 047 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210729142940] - 1.477718ms
2023-12-07T17:53:04.600088073Z: INFO	▶ [DATABASE] 048 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210802081716] - 1.558484ms
2023-12-07T17:53:04.6017396Z: INFO	▶ [DATABASE] 049 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210829194722] - 1.60977ms
2023-12-07T17:53:04.603046554Z: INFO	▶ [DATABASE] 04a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20211212151642] - 1.271402ms
2023-12-07T17:53:04.604365538Z: INFO	▶ [DATABASE] 04b [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20211212210054] - 1.269035ms
2023-12-07T17:53:04.60570266Z: INFO	▶ [DATABASE] 04c [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20220112211537] - 1.301276ms
2023-12-07T17:53:04.607129338Z: INFO	▶ [DATABASE] 04d [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20220616145228] - 1.39385ms
2023-12-07T17:53:04.608350243Z: INFO	▶ [DATABASE] 04e [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20220815200851] - 1.188548ms
2023-12-07T17:53:04.609766182Z: INFO	▶ [DATABASE] 04f [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20221002120521] - 1.380691ms
2023-12-07T17:53:04.61111297Z: INFO	▶ [DATABASE] 050 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20221113170740] - 1.314792ms
2023-12-07T17:53:04.61253493Z: INFO	▶ [DATABASE] 051 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20221228112131] - 1.388011ms
2023-12-07T17:53:04.614022403Z: INFO	▶ [DATABASE] 052 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230307171848] - 1.446619ms
2023-12-07T17:53:04.615437353Z: INFO	▶ [DATABASE] 053 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230611170341] - 1.379643ms
2023-12-07T17:53:04.616775818Z: INFO	▶ [DATABASE] 054 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230824132533] - 1.307658ms
2023-12-07T17:53:04.618228391Z: INFO	▶ [DATABASE] 055 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230828125443] - 1.419613ms
2023-12-07T17:53:04.619732211Z: INFO	▶ [DATABASE] 056 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230831155832] - 1.449739ms
2023-12-07T17:53:04.621289641Z: INFO	▶ [DATABASE] 057 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230903143017] - 1.508624ms
2023-12-07T17:53:04.622697939Z: INFO	▶ [DATABASE] 058 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230913202615] - 1.360494ms
2023-12-07T17:53:04.624106126Z: INFO	▶ [DATABASE] 059 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20231022144641] - 1.370659ms
2023-12-07T17:53:04.625382102Z: INFO	▶ [DATABASE] 05a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20231108231513] - 1.239361ms
2023-12-07T17:53:04.625405184Z: INFO	▶ migration/Migrate 05b Ran all migrations successfully.
2023-12-07T17:53:04.625708009Z: INFO	▶ cmd/func27 05d Vikunja version v0.21.0+214-f26f1326ea
2023-12-07T17:53:04.626034138Z: DEBUG	▶ [EVENTS] 05e Adding publisher decorators, count=1

Let me try to access the webhooks from the UI now....

I restarted the pod a few more times to add the `DEBUG` values to get more interesting output: ```yaml # Debugging values - name: VIKUNJA_LOG_DATABASE value: "on" - name: VIKUNJA_LOG_DATABASELEVEL value: "DEBUG" - name: VIKUNJA_LOG_EVENTS value: "on" - name: VIKUNJA_LOG_EVENTSLEVEL value: "DEBUG" - name: VIKUNJA_LOG_MAIL value: "on" - name: VIKUNJA_LOG_MAILLEVEL value: "DEBUG" ``` Then it...finally added the webhooks table?: ``` info: creating the new user vikunja with 1000:1000 usermod: no changes 2023-12-07T17:53:03.894192378Z: INFO ▶ config/InitConfig 001 No config file found, using default or config from environment variables. 2023-12-07T17:53:04.025298117Z: INFO ▶ [DATABASE] 003 [SQL] SELECT tablename FROM pg_tables WHERE schemaname = $1 [public] - 129.850358ms 2023-12-07T17:53:04.460470394Z: INFO ▶ [DATABASE] 004 [SQL] SELECT column_name, column_default, is_nullable, data_type, character_maximum_length, description, CASE WHEN p.contype = 'p' THEN true ELSE false END AS primarykey, CASE WHEN p.contype = 'u' THEN true ELSE false END AS uniquekey FROM pg_attribute f JOIN pg_class c ON c.oid = f.attrelid JOIN pg_type t ON t.oid = f.atttypid LEFT JOIN pg_attrdef d ON d.adrelid = c.oid AND d.adnum = f.attnum LEFT JOIN pg_description de ON f.attrelid=de.objoid AND f.attnum=de.objsubid LEFT JOIN pg_namespace n ON n.oid = c.relnamespace LEFT JOIN pg_constraint p ON p.conrelid = c.oid AND f.attnum = ANY (p.conkey) LEFT JOIN pg_class AS g ON p.confrelid = g.oid LEFT JOIN INFORMATION_SCHEMA.COLUMNS s ON s.column_name=f.attname AND c.relname=s.table_name WHERE n.nspname= s.table_schema AND c.relkind = 'r' AND c.relname = $1 AND s.table_schema = $2 AND f.attnum > 0 ORDER BY f.attnum; [migration public] - 434.904942ms 2023-12-07T17:53:04.484382057Z: INFO ▶ [DATABASE] 005 [SQL] SELECT indexname, indexdef FROM pg_indexes WHERE tablename=$1 AND schemaname=$2 [migration public] - 23.819609ms 2023-12-07T17:53:04.499257027Z: INFO ▶ [DATABASE] 006 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [SCHEMA_INIT] - 14.778531ms 2023-12-07T17:53:04.500689631Z: INFO ▶ [DATABASE] 007 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190324205606] - 1.38926ms 2023-12-07T17:53:04.50196764Z: INFO ▶ [DATABASE] 008 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190328074430] - 1.240159ms 2023-12-07T17:53:04.50313278Z: INFO ▶ [DATABASE] 009 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190430111111] - 1.128617ms 2023-12-07T17:53:04.50432353Z: INFO ▶ [DATABASE] 00a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190511202210] - 1.154136ms 2023-12-07T17:53:04.50551721Z: INFO ▶ [DATABASE] 00b [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190514192749] - 1.150039ms 2023-12-07T17:53:04.50678539Z: INFO ▶ [DATABASE] 00c [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190524205441] - 1.23191ms 2023-12-07T17:53:04.508304581Z: INFO ▶ [DATABASE] 00d [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190718200716] - 1.484989ms 2023-12-07T17:53:04.509888059Z: INFO ▶ [DATABASE] 00e [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190818210133] - 1.546775ms 2023-12-07T17:53:04.511326865Z: INFO ▶ [DATABASE] 00f [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190920185205] - 1.350083ms 2023-12-07T17:53:04.512770324Z: INFO ▶ [DATABASE] 010 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20190922205826] - 1.404411ms 2023-12-07T17:53:04.514362751Z: INFO ▶ [DATABASE] 011 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20191008194238] - 1.555443ms 2023-12-07T17:53:04.515861074Z: INFO ▶ [DATABASE] 012 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20191010131430] - 1.459896ms 2023-12-07T17:53:04.517453039Z: INFO ▶ [DATABASE] 013 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20191207204427] - 1.492238ms 2023-12-07T17:53:04.518957043Z: INFO ▶ [DATABASE] 014 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20191207220736] - 1.4664ms 2023-12-07T17:53:04.52045032Z: INFO ▶ [DATABASE] 015 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200120201756] - 1.455439ms 2023-12-07T17:53:04.522167346Z: INFO ▶ [DATABASE] 016 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200219183248] - 1.679962ms 2023-12-07T17:53:04.523724721Z: INFO ▶ [DATABASE] 017 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200308205855] - 1.512756ms 2023-12-07T17:53:04.525126214Z: INFO ▶ [DATABASE] 018 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200308210130] - 1.366476ms 2023-12-07T17:53:04.526702016Z: INFO ▶ [DATABASE] 019 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200322214440] - 1.462973ms 2023-12-07T17:53:04.528097662Z: INFO ▶ [DATABASE] 01a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200322214624] - 1.361017ms 2023-12-07T17:53:04.529608623Z: INFO ▶ [DATABASE] 01b [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200417175201] - 1.476956ms 2023-12-07T17:53:04.531024807Z: INFO ▶ [DATABASE] 01c [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200418230432] - 1.371623ms 2023-12-07T17:53:04.532548014Z: INFO ▶ [DATABASE] 01d [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200418230605] - 1.486937ms 2023-12-07T17:53:04.534779035Z: INFO ▶ [DATABASE] 01e [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200420215928] - 2.191781ms 2023-12-07T17:53:04.536232769Z: INFO ▶ [DATABASE] 01f [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200425182634] - 1.389911ms 2023-12-07T17:53:04.53767696Z: INFO ▶ [DATABASE] 020 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200509103709] - 1.403153ms 2023-12-07T17:53:04.539371687Z: INFO ▶ [DATABASE] 021 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200515172220] - 1.658163ms 2023-12-07T17:53:04.541125072Z: INFO ▶ [DATABASE] 022 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200515195546] - 1.720178ms 2023-12-07T17:53:04.542878207Z: INFO ▶ [DATABASE] 023 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200516123847] - 1.718754ms 2023-12-07T17:53:04.54457483Z: INFO ▶ [DATABASE] 024 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200524221534] - 1.660308ms 2023-12-07T17:53:04.546402809Z: INFO ▶ [DATABASE] 025 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200524224611] - 1.79188ms 2023-12-07T17:53:04.548221262Z: INFO ▶ [DATABASE] 026 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200614113230] - 1.781313ms 2023-12-07T17:53:04.54999015Z: INFO ▶ [DATABASE] 027 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200621214452] - 1.713521ms 2023-12-07T17:53:04.551685888Z: INFO ▶ [DATABASE] 028 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200801183357] - 1.647376ms 2023-12-07T17:53:04.553245131Z: INFO ▶ [DATABASE] 029 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200904101559] - 1.522139ms 2023-12-07T17:53:04.554864179Z: INFO ▶ [DATABASE] 02a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200905151040] - 1.585086ms 2023-12-07T17:53:04.556349517Z: INFO ▶ [DATABASE] 02b [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200905232458] - 1.453026ms 2023-12-07T17:53:04.558058879Z: INFO ▶ [DATABASE] 02c [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20200906184746] - 1.588726ms 2023-12-07T17:53:04.559690402Z: INFO ▶ [DATABASE] 02d [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20201025195822] - 1.589011ms 2023-12-07T17:53:04.561119297Z: INFO ▶ [DATABASE] 02e [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20201121181647] - 1.390548ms 2023-12-07T17:53:04.56273181Z: INFO ▶ [DATABASE] 02f [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20201218152741] - 1.576026ms 2023-12-07T17:53:04.564347775Z: INFO ▶ [DATABASE] 030 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20201218220204] - 1.584071ms 2023-12-07T17:53:04.565938886Z: INFO ▶ [DATABASE] 031 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20201219145028] - 1.544491ms 2023-12-07T17:53:04.567362132Z: INFO ▶ [DATABASE] 032 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210207192805] - 1.388569ms 2023-12-07T17:53:04.568984322Z: INFO ▶ [DATABASE] 033 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210209204715] - 1.590499ms 2023-12-07T17:53:04.570738874Z: INFO ▶ [DATABASE] 034 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210220222121] - 1.718297ms 2023-12-07T17:53:04.572328001Z: INFO ▶ [DATABASE] 035 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210221111953] - 1.550974ms 2023-12-07T17:53:04.57386788Z: INFO ▶ [DATABASE] 036 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210321185225] - 1.508051ms 2023-12-07T17:53:04.575385327Z: INFO ▶ [DATABASE] 037 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210328191017] - 1.482483ms 2023-12-07T17:53:04.576878036Z: INFO ▶ [DATABASE] 038 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210403145503] - 1.457527ms 2023-12-07T17:53:04.578377783Z: INFO ▶ [DATABASE] 039 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210403220653] - 1.464949ms 2023-12-07T17:53:04.579932128Z: INFO ▶ [DATABASE] 03a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210407170753] - 1.509279ms 2023-12-07T17:53:04.581359899Z: INFO ▶ [DATABASE] 03b [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210411113105] - 1.389846ms 2023-12-07T17:53:04.58274552Z: INFO ▶ [DATABASE] 03c [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210411161337] - 1.351537ms 2023-12-07T17:53:04.584112926Z: INFO ▶ [DATABASE] 03d [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210413131057] - 1.334135ms 2023-12-07T17:53:04.585460492Z: INFO ▶ [DATABASE] 03e [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210527105701] - 1.317755ms 2023-12-07T17:53:04.586824124Z: INFO ▶ [DATABASE] 03f [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210603174608] - 1.333258ms 2023-12-07T17:53:04.5882155Z: INFO ▶ [DATABASE] 040 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210709191101] - 1.360789ms 2023-12-07T17:53:04.589623049Z: INFO ▶ [DATABASE] 041 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210709211508] - 1.374471ms 2023-12-07T17:53:04.591092339Z: INFO ▶ [DATABASE] 042 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210711173657] - 1.433036ms 2023-12-07T17:53:04.592627058Z: INFO ▶ [DATABASE] 043 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210713213622] - 1.50313ms 2023-12-07T17:53:04.594345993Z: INFO ▶ [DATABASE] 044 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210725153703] - 1.688473ms 2023-12-07T17:53:04.595764089Z: INFO ▶ [DATABASE] 045 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210727204942] - 1.38511ms 2023-12-07T17:53:04.596987514Z: INFO ▶ [DATABASE] 046 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210727211037] - 1.189751ms 2023-12-07T17:53:04.598496635Z: INFO ▶ [DATABASE] 047 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210729142940] - 1.477718ms 2023-12-07T17:53:04.600088073Z: INFO ▶ [DATABASE] 048 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210802081716] - 1.558484ms 2023-12-07T17:53:04.6017396Z: INFO ▶ [DATABASE] 049 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20210829194722] - 1.60977ms 2023-12-07T17:53:04.603046554Z: INFO ▶ [DATABASE] 04a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20211212151642] - 1.271402ms 2023-12-07T17:53:04.604365538Z: INFO ▶ [DATABASE] 04b [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20211212210054] - 1.269035ms 2023-12-07T17:53:04.60570266Z: INFO ▶ [DATABASE] 04c [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20220112211537] - 1.301276ms 2023-12-07T17:53:04.607129338Z: INFO ▶ [DATABASE] 04d [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20220616145228] - 1.39385ms 2023-12-07T17:53:04.608350243Z: INFO ▶ [DATABASE] 04e [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20220815200851] - 1.188548ms 2023-12-07T17:53:04.609766182Z: INFO ▶ [DATABASE] 04f [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20221002120521] - 1.380691ms 2023-12-07T17:53:04.61111297Z: INFO ▶ [DATABASE] 050 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20221113170740] - 1.314792ms 2023-12-07T17:53:04.61253493Z: INFO ▶ [DATABASE] 051 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20221228112131] - 1.388011ms 2023-12-07T17:53:04.614022403Z: INFO ▶ [DATABASE] 052 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230307171848] - 1.446619ms 2023-12-07T17:53:04.615437353Z: INFO ▶ [DATABASE] 053 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230611170341] - 1.379643ms 2023-12-07T17:53:04.616775818Z: INFO ▶ [DATABASE] 054 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230824132533] - 1.307658ms 2023-12-07T17:53:04.618228391Z: INFO ▶ [DATABASE] 055 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230828125443] - 1.419613ms 2023-12-07T17:53:04.619732211Z: INFO ▶ [DATABASE] 056 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230831155832] - 1.449739ms 2023-12-07T17:53:04.621289641Z: INFO ▶ [DATABASE] 057 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230903143017] - 1.508624ms 2023-12-07T17:53:04.622697939Z: INFO ▶ [DATABASE] 058 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20230913202615] - 1.360494ms 2023-12-07T17:53:04.624106126Z: INFO ▶ [DATABASE] 059 [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20231022144641] - 1.370659ms 2023-12-07T17:53:04.625382102Z: INFO ▶ [DATABASE] 05a [SQL] SELECT count(*) FROM "migration" WHERE "id" IN ($1) [20231108231513] - 1.239361ms 2023-12-07T17:53:04.625405184Z: INFO ▶ migration/Migrate 05b Ran all migrations successfully. 2023-12-07T17:53:04.625708009Z: INFO ▶ cmd/func27 05d Vikunja version v0.21.0+214-f26f1326ea 2023-12-07T17:53:04.626034138Z: DEBUG ▶ [EVENTS] 05e Adding publisher decorators, count=1 ``` Let me try to access the webhooks from the UI now....
Author

image

yep, and I'm able to access the UI now too:
image

![image](/attachments/d24db480-0ae8-4cd9-b35d-f0a718320f88) yep, and I'm able to access the UI now too: ![image](/attachments/f709f267-54f7-447c-ac3a-53c1a1138249)
Author

Looking through my logs, I don't see anything to indicate that anything weird/bad happened when trying to create the table? In order of oldest logs to new, for anything related to migration:
image
image
image
image
image

Perhaps, when the Vikunja database is first being created and there isn't a Typesense index, the migration isn't successful? Not sure if the two are related...

Looking through my logs, I don't see anything to indicate that anything weird/bad happened when trying to create the table? In order of oldest logs to new, for anything related to `migration`: ![image](/attachments/3e1ae6e2-e935-48e5-8dde-bb55e326650f) ![image](/attachments/51f92281-2ba1-43e5-9864-3cd904c522a9) ![image](/attachments/1c122400-b9e8-4e10-b8d4-2b67ade742f5) ![image](/attachments/9194387b-c548-4742-86fb-abbaa6d12bbc) ![image](/attachments/54343423-81c6-4eaa-aee5-5e08d4bbb8cc) Perhaps, when the Vikunja database is first being created and there isn't a Typesense index, the migration isn't successful? Not sure if the two are related...
Owner

Perhaps, when the Vikunja database is first being created and there isn't a Typesense index, the migration isn't successful? Not sure if the two are related...

The Typesense index is independent of the rest (at least it should be).

Glad it works for you now, but "restart the pod a few times until it works" isn't really a solution. Not sure what to do about it though. First step would be to have it reproducible.

> Perhaps, when the Vikunja database is first being created and there isn't a Typesense index, the migration isn't successful? Not sure if the two are related... The Typesense index is independent of the rest (at least it should be). Glad it works for you now, but "restart the pod a few times until it works" isn't really a solution. Not sure what to do about it though. First step would be to have it reproducible.
Author

If I'm lucky enough to reproduce it, I'll be sure to reopen the issue :)

You can close it if you would like @konrad!

If I'm lucky enough to reproduce it, I'll be sure to reopen the issue :) You can close it if you would like @konrad!
Owner

Thank you very much already for the report and your patience!

Thank you very much already for the report and your patience!
Author

@konrad what's the easiest environment to pass around do you think, is a Docker Compose the easiest thing to provide you to more easily reproduce issues?

@konrad what's the easiest environment to pass around do you think, is a Docker Compose the easiest thing to provide you to more easily reproduce issues?
Owner

Yeah docker compose should work.

Yeah docker compose should work.
Author

@konrad one last thing to bother you - I accidentally pushed a container (a "package" as Gitea calls it) to this Gitea. I'm not sure if you meant to have that enabled or not since you have disabled allowing other users to create repositories. Gitea config docs on it are here if you wish to disable it for other users. :)

@konrad one last thing to bother you - I accidentally pushed a container (a "package" as Gitea calls it) to this Gitea. I'm not sure if you meant to have that enabled or not since you have disabled allowing other users to create repositories. Gitea config docs on it are [here](https://docs.gitea.com/next/administration/config-cheat-sheet#packages-packages) if you wish to disable it for other users. :)
Owner

Thanks for the hint, should be disabled now. Can you check again?

Thanks for the hint, should be disabled now. Can you check again?
Author

It's interesting, I'm able to push container layers (therefore taking up space "maliciously" on your Gitea), but it still returns a 403:
image

Here's what it returns:

DEBU[2023-12-13T09:08:12.286058959-08:00] 2023/12/13 09:08:12 WARNING: [core] [Channel #11 SubChannel #12] grpc: addrConn.createTransport failed to connect to {  library=grpc
DEBU[2023-12-13T09:08:12.286096059-08:00]   "Addr": "localhost",                        library=grpc
DEBU[2023-12-13T09:08:12.286100829-08:00]   "ServerName": "localhost",                  library=grpc
DEBU[2023-12-13T09:08:12.286114599-08:00]   "Attributes": null,                         library=grpc
DEBU[2023-12-13T09:08:12.286119779-08:00]   "BalancerAttributes": null,                 library=grpc
DEBU[2023-12-13T09:08:12.286122859-08:00]   "Type": 0,                                  library=grpc
DEBU[2023-12-13T09:08:12.286126409-08:00]   "Metadata": null                            library=grpc
DEBU[2023-12-13T09:08:12.286130349-08:00] }. Err: connection error: desc = "transport: Error while dialing only one connection allowed"  library=grpc
DEBU[2023-12-13T09:08:12.306229739-08:00] 2023/12/13 09:08:12 WARNING: [core] [Channel #14 SubChannel #15] grpc: addrConn.createTransport failed to connect to {  library=grpc
DEBU[2023-12-13T09:08:12.306254818-08:00]   "Addr": "localhost",                        library=grpc
DEBU[2023-12-13T09:08:12.306259508-08:00]   "ServerName": "localhost",                  library=grpc
DEBU[2023-12-13T09:08:12.306262908-08:00]   "Attributes": null,                         library=grpc
DEBU[2023-12-13T09:08:12.306276498-08:00]   "BalancerAttributes": null,                 library=grpc
DEBU[2023-12-13T09:08:12.306288338-08:00]   "Type": 0,                                  library=grpc
DEBU[2023-12-13T09:08:12.306299748-08:00]   "Metadata": null                            library=grpc
DEBU[2023-12-13T09:08:12.306311138-08:00] }. Err: connection error: desc = "transport: Error while dialing only one connection allowed"  library=grpc
INFO[2023-12-13T09:08:13.224615744-08:00] Attempting next endpoint for push after error: error parsing HTTP 403 response body: unexpected end of JSON input: ""
It's interesting, I'm able to push container layers (therefore taking up space "maliciously" on your Gitea), but it still returns a `403`: ![image](/attachments/970dc8a1-11bc-42cc-b444-896928f00a5e) Here's what it returns: ``` DEBU[2023-12-13T09:08:12.286058959-08:00] 2023/12/13 09:08:12 WARNING: [core] [Channel #11 SubChannel #12] grpc: addrConn.createTransport failed to connect to { library=grpc DEBU[2023-12-13T09:08:12.286096059-08:00] "Addr": "localhost", library=grpc DEBU[2023-12-13T09:08:12.286100829-08:00] "ServerName": "localhost", library=grpc DEBU[2023-12-13T09:08:12.286114599-08:00] "Attributes": null, library=grpc DEBU[2023-12-13T09:08:12.286119779-08:00] "BalancerAttributes": null, library=grpc DEBU[2023-12-13T09:08:12.286122859-08:00] "Type": 0, library=grpc DEBU[2023-12-13T09:08:12.286126409-08:00] "Metadata": null library=grpc DEBU[2023-12-13T09:08:12.286130349-08:00] }. Err: connection error: desc = "transport: Error while dialing only one connection allowed" library=grpc DEBU[2023-12-13T09:08:12.306229739-08:00] 2023/12/13 09:08:12 WARNING: [core] [Channel #14 SubChannel #15] grpc: addrConn.createTransport failed to connect to { library=grpc DEBU[2023-12-13T09:08:12.306254818-08:00] "Addr": "localhost", library=grpc DEBU[2023-12-13T09:08:12.306259508-08:00] "ServerName": "localhost", library=grpc DEBU[2023-12-13T09:08:12.306262908-08:00] "Attributes": null, library=grpc DEBU[2023-12-13T09:08:12.306276498-08:00] "BalancerAttributes": null, library=grpc DEBU[2023-12-13T09:08:12.306288338-08:00] "Type": 0, library=grpc DEBU[2023-12-13T09:08:12.306299748-08:00] "Metadata": null library=grpc DEBU[2023-12-13T09:08:12.306311138-08:00] }. Err: connection error: desc = "transport: Error while dialing only one connection allowed" library=grpc INFO[2023-12-13T09:08:13.224615744-08:00] Attempting next endpoint for push after error: error parsing HTTP 403 response body: unexpected end of JSON input: "" ```
Author

I wonder if you then have to enable the CLEANUP_PACKAGES cronjob then incase someone else does this? You can delete my comments, so that others can't see that it's even possible to push layers...

https://github.com/go-gitea/gitea/issues/20631

I wonder if you then have to enable the `CLEANUP_PACKAGES` cronjob then incase someone else does this? You can delete my comments, so that others can't see that it's even possible to push layers... https://github.com/go-gitea/gitea/issues/20631

I reproducible have this issue with the setup I described on GH here https://github.com/go-vikunja/api/issues/104. I am just commenting here for clarity. Brand new setup, no migration. Currently unable to resolve.

I reproducible have this issue with the setup I described on GH here https://github.com/go-vikunja/api/issues/104. I am just commenting here for clarity. Brand new setup, no migration. Currently unable to resolve.
Owner

Looks like the table was not created when starting from scratch, only when upgrading from a previous version. Hence I didn't see this error with any of my own installations.

Should be fixed in 09696aec1b. Please check with the next unstable build.

Looks like the table was not created when starting from scratch, only when upgrading from a previous version. Hence I didn't see this error with any of my own installations. Should be fixed in 09696aec1bea647a5bfc7be16b31054626d721e4. Please check with the next unstable build.
Sign in to join this conversation.
No Milestone
No Assignees
3 Participants
Notifications
Due Date
The due date is invalid or out of range. Please use the format 'yyyy-mm-dd'.

No due date set.

Dependencies

No dependencies set.

Reference: vikunja/vikunja#1655
No description provided.