Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Error thrown after successful login #307

Closed
jase64 opened this issue Jan 17, 2024 · 5 comments
Closed

Error thrown after successful login #307

jase64 opened this issue Jan 17, 2024 · 5 comments
Assignees
@ciur
Labels
3.0.1 bug Something isn't working confirmed medium

Comments

@jase64
Copy link

jase64 commented Jan 17, 2024

Hi,

I just installed papermerge via the docker compose script (w/ mySQL&SOLR).

Docker composing went fine and so the startup of the app. I could get to the login page. But once the right credentials were entered, the web page just shows "error" in the top left corner.

The server logs show the following:

papermerge-web-1     | INFO:     Waiting for application startup.
papermerge-web-1     | INFO:     Application startup complete.
papermerge-web-1     | INFO:     127.0.0.1:43994 - "GET /token-validator HTTP/1.0" 401 Unauthorized
papermerge-web-1     | 172.22.0.1 - - [17/Jan/2024:20:59:57 +0000] "GET / HTTP/1.1" 200 702 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:44008 - "GET /token-validator HTTP/1.0" 401 Unauthorized
papermerge-web-1     | 172.22.0.1 - - [17/Jan/2024:20:59:57 +0000] "GET /papermerge-runtime-config.js HTTP/1.1" 200 60 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:56698 - "GET /token-validator HTTP/1.0" 401 Unauthorized
papermerge-web-1     | INFO 2024-01-17 21:00:11,065 auth Database based authentication for 'eugen'
papermerge-web-1     | INFO 2024-01-17 21:00:11,089 auth Authentication succeded for 'eugen'
papermerge-web-1     | 172.22.0.1 - - [17/Jan/2024:21:00:11 +0000] "POST /api/token HTTP/1.1" 200 225 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:56702 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | 172.22.0.1 - - [17/Jan/2024:21:00:11 +0000] "GET / HTTP/1.1" 200 680 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:56704 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | 172.22.0.1 - - [17/Jan/2024:21:00:11 +0000] "GET /api/users/me HTTP/1.1" 500 21 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | Exception in ASGI application
papermerge-web-1     | Traceback (most recent call last):
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/protocols/http/h11_impl.py", line 408, in run_asgi
papermerge-web-1     |     result = await app(  # type: ignore[func-returns-value]
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/middleware/proxy_headers.py", line 84, in __call__
papermerge-web-1     |     return await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/applications.py", line 289, in __call__
papermerge-web-1     |     await super().__call__(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/applications.py", line 122, in __call__
papermerge-web-1     |     await self.middleware_stack(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/errors.py", line 184, in __call__
papermerge-web-1     |     raise exc
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/errors.py", line 162, in __call__
papermerge-web-1     |     await self.app(scope, receive, _send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/cors.py", line 83, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 79, in __call__
papermerge-web-1     |     raise exc
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 68, in __call__
papermerge-web-1     |     await self.app(scope, receive, sender)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 20, in __call__
papermerge-web-1     |     raise e
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 17, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 718, in __call__
papermerge-web-1     |     await route.handle(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 276, in handle
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 66, in app
papermerge-web-1     |     response = await func(request)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/routing.py", line 263, in app
papermerge-web-1     |     solved_result = await solve_dependencies(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/dependencies/utils.py", line 586, in solve_dependencies
papermerge-web-1     |     solved = await run_in_threadpool(call, **sub_values)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/concurrency.py", line 41, in run_in_threadpool
papermerge-web-1     |     return await anyio.to_thread.run_sync(func, *args)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/to_thread.py", line 56, in run_sync
papermerge-web-1     |     return await get_async_backend().run_sync_in_worker_thread(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 2134, in run_sync_in_worker_thread
papermerge-web-1     |     return await future
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 851, in run
papermerge-web-1     |     result = context.run(func, *args)
papermerge-web-1     |   File "/core_app/papermerge/core/auth/__init__.py", line 43, in get_current_user
papermerge-web-1     |     user = db.get_user(engine, user_id)
papermerge-web-1     |   File "/core_app/papermerge/core/db/users.py", line 35, in get_user
papermerge-web-1     |     db_user = session.scalars(stmt, params).one()
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 1824, in one
papermerge-web-1     |     return self._only_one_row(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 757, in _only_one_row
papermerge-web-1     |     raise exc.NoResultFound(
papermerge-web-1     | sqlalchemy.exc.NoResultFound: No row was found when one was required
papermerge-web-1     | INFO:     127.0.0.1:56706 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | Exception in ASGI application
papermerge-web-1     | Traceback (most recent call last):
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/protocols/websockets/websockets_impl.py", line 247, in run_asgi
papermerge-web-1     |     result = await self.app(self.scope, self.asgi_receive, self.asgi_send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/middleware/proxy_headers.py", line 84, in __call__
papermerge-web-1     |     return await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/applications.py", line 289, in __call__
papermerge-web-1     |     await super().__call__(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/applications.py", line 122, in __call__
papermerge-web-1     |     await self.middleware_stack(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/errors.py", line 149, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/cors.py", line 75, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 79, in __call__
papermerge-web-1     |     raise exc
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 68, in __call__
papermerge-web-1     |     await self.app(scope, receive, sender)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 20, in __call__
papermerge-web-1     |     raise e
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 17, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 718, in __call__
papermerge-web-1     |     await route.handle(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 341, in handle
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 82, in app
papermerge-web-1     |     await func(session)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/routing.py", line 315, in app
papermerge-web-1     |     solved_result = await solve_dependencies(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/dependencies/utils.py", line 586, in solve_dependencies
papermerge-web-1     |     solved = await run_in_threadpool(call, **sub_values)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/concurrency.py", line 41, in run_in_threadpool
papermerge-web-1     |     return await anyio.to_thread.run_sync(func, *args)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/to_thread.py", line 56, in run_sync
papermerge-web-1     |     return await get_async_backend().run_sync_in_worker_thread(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 2134, in run_sync_in_worker_thread
papermerge-web-1     |     return await future
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 851, in run
papermerge-web-1     |     result = context.run(func, *args)
papermerge-web-1     |   File "/core_app/papermerge/core/auth/__init__.py", line 97, in get_ws_current_user
papermerge-web-1     |     user = db.get_user(engine, user_id)
papermerge-web-1     |   File "/core_app/papermerge/core/db/users.py", line 35, in get_user
papermerge-web-1     |     db_user = session.scalars(stmt, params).one()
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 1824, in one
papermerge-web-1     |     return self._only_one_row(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 757, in _only_one_row
papermerge-web-1     |     raise exc.NoResultFound(
papermerge-web-1     | sqlalchemy.exc.NoResultFound: No row was found when one was required
papermerge-web-1     | 172.22.0.1 - - [17/Jan/2024:21:00:11 +0000] "GET /ws/ocr HTTP/1.1" 500 21 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"

Any clue?
@ciur
Copy link
Member

ciur commented Jan 18, 2024 •
edited

Your compose file looks like following one, except credentials of course, right?

version: "3.9"

x-backend: &common
  image: papermerge/papermerge:3.0.1
  environment:
    PAPERMERGE__SECURITY__SECRET_KEY: 12345
    PAPERMERGE__AUTH__USERNAME: john
    PAPERMERGE__AUTH__PASSWORD: hohoho
    PAPERMERGE__DATABASE__URL: postgresql://scott:tiger@db:5432/mydatabase
    PAPERMERGE__REDIS__URL: redis://redis:6379/0
    PAPERMERGE__SEARCH__URL: solr://solr:8983/pmg-index
  volumes:
    - media:/core_app/media

services:
  web:
    <<: *common
    ports:
     - "12000:80"
    depends_on:
      - redis
      - db
      - solr
  worker:
    <<: *common
    command: worker
  redis:
    image: redis:6
  db:
    image: bitnami/postgresql:14.4.0
    volumes:
      - postgres_data:/var/lib/postgresql/data/
    environment:
      POSTGRES_USER: scott
      POSTGRES_PASSWORD: tiger
      POSTGRES_DB: mydatabase
  solr:
    image: solr:9.3
    ports:
     - "8983:8983"
    volumes:
      - solr_data:/var/solr
    command:
      - solr-precreate
      - pmg-index

volumes:
  postgres_data:
  solr_data:
  media:

With above compose file, I cannot reproduce the issue :(

Did you change anything in docker compose ?
In case you change credentials in compose file, did you change them before or after starting the app?

Can you please remove database/data/solr volumes and start over again with all anew and provide here
the exact steps order so that I can reproduce the issue?

@jase64
Copy link
Author

jase64 commented Jan 18, 2024

Actually my compose file is the one with mySQL + SOLR (To which I didn't even change the credentials)

version: "3.9"

x-backend: &common
  image: papermerge/papermerge:3.0.1
  environment:
      PAPERMERGE__SECURITY__SECRET_KEY: 1234  # top secret
      PAPERMERGE__AUTH__USERNAME: eugen
      PAPERMERGE__AUTH__PASSWORD: 1234
      PAPERMERGE__DATABASE__URL: mysql://myuser:mypass@db:3306/paperdb
      PAPERMERGE__REDIS__URL: redis://redis:6379/0
      PAPERMERGE__SEARCH__URL: solr://solr:8983/pmg-index
  volumes:
    - media_root:/core_app/media
  depends_on:
    - redis
    - solr
    - db

services:
  web:
    <<: *common
    ports:
     - "12000:80"
  worker:
    <<: *common
    command: worker
  redis:
    image: redis:6
  solr:
    image: solr:9.3
    ports:
     - "8983:8983"
    volumes:
      - solr_data:/var/solr
    command:
      - solr-precreate
      - pmg-index
  db:
    image: mariadb:11.2
    volumes:
      - maria:/var/lib/mysql
    environment:
      MYSQL_ROOT_PASSWORD: mypass
      MYSQL_DATABASE: paperdb
      MYSQL_USER: myuser
      MYSQL_PASSWORD: mypass
volumes:
  maria:
  solr_data:
  media_root:

Some context in case it might help: I'm running Papermerge in a VM under Debian 11 + docker version 24.0.7, build afdd53b. VM has been allocated 8Gb RAM and 6 cores.

So I did as instructed:

  • remove /var/lib/docker/volumes/papermerge_solr_data
  • reload solr and papermerge docker images
  • recreate /var/lib/docker/volumes/papermerge_solr_data/_data directory (else docker was throwing an error)
  • compose up

Unfortunately, same effect (I did all the verbose log for better understanding; everything looks to happen fine until 2024-01-18 07:43:40,315; at 07:43:40,50 it crashes now without even try to log in)

[+] Running 6/4
 ✔ Network papermerge_default     Created                                                                                                                                                       0.1s 
 ✔ Container papermerge-db-1      Created                                                                                                                                                       0.1s 
 ✔ Container papermerge-solr-1    Created                                                                                                                                                       0.1s 
 ✔ Container papermerge-redis-1   Created                                                                                                                                                       0.1s 
 ✔ Container papermerge-worker-1  Created                                                                                                                                                       0.1s 
 ✔ Container papermerge-web-1     Created                                                                                                                                                       0.1s 
Attaching to papermerge-db-1, papermerge-redis-1, papermerge-solr-1, papermerge-web-1, papermerge-worker-1
papermerge-redis-1   | 1:C 18 Jan 2024 07:43:28.209 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
papermerge-redis-1   | 1:C 18 Jan 2024 07:43:28.209 # Redis version=6.2.14, bits=64, commit=00000000, modified=0, pid=1, just started
papermerge-redis-1   | 1:C 18 Jan 2024 07:43:28.209 # Warning: no config file specified, using the default config. In order to specify a config file use redis-server /path/to/redis.conf
papermerge-redis-1   | 1:M 18 Jan 2024 07:43:28.210 * monotonic clock: POSIX clock_gettime
papermerge-redis-1   | 1:M 18 Jan 2024 07:43:28.210 * Running mode=standalone, port=6379.
papermerge-redis-1   | 1:M 18 Jan 2024 07:43:28.210 # Server initialized
papermerge-redis-1   | 1:M 18 Jan 2024 07:43:28.210 # WARNING Memory overcommit must be enabled! Without it, a background save or replication may fail under low memory condition. Being disabled, it can can also cause failures without low memory condition, see https://github.com/jemalloc/jemalloc/issues/1328. To fix this issue add 'vm.overcommit_memory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.overcommit_memory=1' for this to take effect.
papermerge-redis-1   | 1:M 18 Jan 2024 07:43:28.210 * Ready to accept connections
papermerge-db-1      | 2024-01-18 07:43:28+00:00 [Note] [Entrypoint]: Entrypoint script for MariaDB Server 1:11.2.2+maria~ubu2204 started.
papermerge-solr-1    | Executing /opt/solr/docker/scripts/solr-precreate pmg-index
papermerge-solr-1    | Executing /opt/solr/docker/scripts/precreate-core pmg-index
papermerge-solr-1    | Created pmg-index
papermerge-solr-1    | Starting Solr
papermerge-db-1      | 2024-01-18 07:43:28+00:00 [Note] [Entrypoint]: Switching to dedicated user 'mysql'
papermerge-db-1      | 2024-01-18 07:43:28+00:00 [Note] [Entrypoint]: Entrypoint script for MariaDB Server 1:11.2.2+maria~ubu2204 started.
papermerge-solr-1    | Java 17 detected. Enabled workaround for SOLR-16463
papermerge-solr-1    | [0.001s][warning][pagesize] UseLargePages disabled, no large pages configured and available on the system.
papermerge-solr-1    | CompileCommand: exclude com/github/benmanes/caffeine/cache/BoundedLocalCache.put bool exclude = true
papermerge-solr-1    | WARNING: A command line option has enabled the Security Manager
papermerge-solr-1    | WARNING: The Security Manager is deprecated and will be removed in a future release
papermerge-db-1      | 2024-01-18 07:43:29+00:00 [Note] [Entrypoint]: MariaDB upgrade not required
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] Starting MariaDB 11.2.2-MariaDB-1:11.2.2+maria~ubu2204 source revision 929532a9426d085111c24c63de9c23cc54382259 as process 1
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: Number of transaction pools: 1
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] mariadbd: O_TMPFILE is not supported on /tmp (disabling future attempts)
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: Using liburing
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: Initializing buffer pool, total size = 128.000MiB, chunk size = 2.000MiB
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: Completed initialization of buffer pool
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: File system buffers for log disabled (block size=512 bytes)
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: End of log at LSN=432617
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: Opened 3 undo tablespaces
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: 128 rollback segments in 3 undo tablespaces are active.
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: Setting file './ibtmp1' size to 12.000MiB. Physically writing the file full; Please wait ...
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: File './ibtmp1' size is now 12.000MiB.
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: log sequence number 432617; transaction id 468
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] Plugin 'FEEDBACK' is disabled.
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] Plugin 'wsrep-provider' is disabled.
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] Server socket created on IP: '0.0.0.0'.
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] Server socket created on IP: '::'.
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] InnoDB: Buffer pool(s) load completed at 240118  7:43:29
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] mariadbd: Event Scheduler: Loaded 0 events
papermerge-db-1      | 2024-01-18  7:43:29 0 [Note] mariadbd: ready for connections.
papermerge-db-1      | Version: '11.2.2-MariaDB-1:11.2.2+maria~ubu2204'  socket: '/run/mysqld/mysqld.sock'  port: 3306  mariadb.org binary distribution
papermerge-solr-1    | 2024-01-18 07:43:30.078 INFO  (main) [] o.e.j.s.Server jetty-10.0.15; built: 2023-04-11T17:25:14.480Z; git: 68017dbd00236bb7e187330d7585a059610f661d; jvm 17.0.9+9
papermerge-worker-1  | Installing dependencies from lock file
papermerge-solr-1    | 2024-01-18 07:43:30.332 WARN  (main) [] o.e.j.u.DeprecationWarning Using @Deprecated Class org.eclipse.jetty.servlet.listener.ELContextCleaner
papermerge-solr-1    | 2024-01-18 07:43:30.353 INFO  (main) [] o.a.s.s.CoreContainerProvider Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
papermerge-solr-1    | 2024-01-18 07:43:30.358 INFO  (main) [] o.a.s.s.CoreContainerProvider  ___      _       Welcome to Apache Solr™ version 9.3.0
papermerge-solr-1    | 2024-01-18 07:43:30.358 INFO  (main) [] o.a.s.s.CoreContainerProvider / __| ___| |_ _   Starting in standalone mode on port 8983
papermerge-solr-1    | 2024-01-18 07:43:30.358 INFO  (main) [] o.a.s.s.CoreContainerProvider \__ \/ _ \ | '_|  Install dir: /opt/solr-9.3.0
papermerge-solr-1    | 2024-01-18 07:43:30.358 INFO  (main) [] o.a.s.s.CoreContainerProvider |___/\___/_|_|    Start time: 2024-01-18T07:43:30.358820315Z
papermerge-solr-1    | 2024-01-18 07:43:30.366 INFO  (main) [] o.a.s.s.CoreContainerProvider Solr started with "-XX:+CrashOnOutOfMemoryError" that will crash on any OutOfMemoryError exception. The cause of the OOME will be logged in the crash file at the following path: /var/solr/logs/jvm_crash_17.log
papermerge-solr-1    | 2024-01-18 07:43:30.384 INFO  (main) [] o.a.s.s.CoreContainerProvider Solr Home: /var/solr/data (source: system property: solr.solr.home)
papermerge-solr-1    | 2024-01-18 07:43:30.398 INFO  (main) [] o.a.s.c.SolrXmlConfig solr.xml not found in SOLR_HOME, using built-in default
papermerge-solr-1    | 2024-01-18 07:43:30.398 INFO  (main) [] o.a.s.c.SolrXmlConfig Loading solr.xml from /opt/solr-9.3.0/server/solr/solr.xml
papermerge-solr-1    | 2024-01-18 07:43:30.433 INFO  (main) [] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/opt/solr-9.3.0/lib]
papermerge-web-1     | Installing dependencies from lock file
papermerge-worker-1  | 
papermerge-worker-1  | No dependencies to install or update
papermerge-worker-1  | 
papermerge-worker-1  | Installing the current project: papermerge-core (3.0.1)
papermerge-web-1     | 
papermerge-web-1     | No dependencies to install or update
papermerge-web-1     | 
papermerge-web-1     | Installing the current project: papermerge-core (3.0.1)
papermerge-solr-1    | 2024-01-18 07:43:31.227 WARN  (main) [] o.a.s.u.StartupLoggingUtils Jetty request logging enabled. Will retain logs for last 3 days. See chapter "Configuring Logging" in reference guide for how to configure.
papermerge-solr-1    | 2024-01-18 07:43:31.229 WARN  (main) [] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
papermerge-solr-1    | 2024-01-18 07:43:31.350 INFO  (main) [] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /var/solr/data
papermerge-solr-1    | 2024-01-18 07:43:31.350 INFO  (main) [] o.a.s.c.CorePropertiesLocator Cores are: [pmg-index]
papermerge-solr-1    | 2024-01-18 07:43:31.370 WARN  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.c.SolrConfig You should not use LATEST as luceneMatchVersion property: if you use this setting, and then Solr upgrades to a newer release of Lucene, sizable changes may happen. If precise back compatibility is important then you should instead explicitly specify an actual Lucene version.
papermerge-solr-1    | 2024-01-18 07:43:31.370 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.7.0
papermerge-solr-1    | 2024-01-18 07:43:31.426 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.s.IndexSchema Schema name=default-config
papermerge-solr-1    | 2024-01-18 07:43:31.900 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
papermerge-solr-1    | 2024-01-18 07:43:31.900 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.c.CoreContainer Creating SolrCore 'pmg-index' using configuration from instancedir /var/solr/data/pmg-index, trusted=true
papermerge-solr-1    | 2024-01-18 07:43:31.936 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.c.SolrCore Opening new SolrCore at [/var/solr/data/pmg-index], dataDir=[/var/solr/data/pmg-index/data/]
papermerge-worker-1  | System check identified some issues:
papermerge-worker-1  | 
papermerge-worker-1  | WARNINGS:
papermerge-worker-1  | core.BaseTreeNode: (models.W036) MariaDB does not support unique constraints with conditions.
papermerge-worker-1  |  HINT: A constraint won't be created. Silence this warning if you don't care about it.
papermerge-solr-1    | 2024-01-18 07:43:32.795 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.j.SolrRequestAuthorizer Creating a new SolrRequestAuthorizer
papermerge-solr-1    | 2024-01-18 07:43:32.796 INFO  (main) [] o.a.s.j.SolrRequestAuthorizer Creating a new SolrRequestAuthorizer
papermerge-worker-1  | Operations to perform:
papermerge-worker-1  |   Apply all migrations: auth, contenttypes, core, sites
papermerge-worker-1  | Running migrations:
papermerge-worker-1  |   No migrations to apply.
papermerge-solr-1    | 2024-01-18 07:43:32.843 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
papermerge-solr-1    | 2024-01-18 07:43:32.843 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
papermerge-solr-1    | 2024-01-18 07:43:32.852 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; 
papermerge-solr-1    | 2024-01-18 07:43:32.853 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.u.CommitTracker Soft AutoCommit: disabled
papermerge-solr-1    | 2024-01-18 07:43:32.894 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /var/solr/data/pmg-index/conf
papermerge-solr-1    | 2024-01-18 07:43:32.935 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.s.DirectSolrSpellChecker init: {maxEdits=2, minPrefix=1, maxInspections=5, minQueryLength=4, accuracy=0.5, maxQueryFrequency=0.01, name=default, field=_text_, classname=solr.DirectSolrSpellChecker, distanceMeasure=internal}
papermerge-solr-1    | 2024-01-18 07:43:32.942 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
papermerge-solr-1    | 2024-01-18 07:43:32.947 INFO  (coreLoadExecutor-10-thread-1) [ x:pmg-index] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1788413280724713472
papermerge-solr-1    | 2024-01-18 07:43:32.950 INFO  (searcherExecutor-12-thread-1-processing-pmg-index) [ x:pmg-index] o.a.s.c.QuerySenderListener QuerySenderListener done.
papermerge-solr-1    | 2024-01-18 07:43:32.951 INFO  (searcherExecutor-12-thread-1-processing-pmg-index) [ x:pmg-index] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
papermerge-solr-1    | 2024-01-18 07:43:32.952 INFO  (main) [] o.e.j.s.h.ContextHandler Started o.e.j.w.WebAppContext@7e1a1da6{solr-jetty-context.xml,/solr,file:///opt/solr-9.3.0/server/solr-webapp/webapp/,AVAILABLE}{/opt/solr-9.3.0/server/solr-webapp/webapp}
papermerge-solr-1    | 2024-01-18 07:43:32.965 INFO  (searcherExecutor-12-thread-1-processing-pmg-index) [ x:pmg-index] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
papermerge-solr-1    | 2024-01-18 07:43:32.965 INFO  (main) [] o.e.j.s.RequestLogWriter Opened /var/solr/logs/2024_01_18.request.log
papermerge-solr-1    | 2024-01-18 07:43:32.970 INFO  (main) [] o.e.j.s.AbstractConnector Started ServerConnector@58ebfd03{HTTP/1.1, (http/1.1, h2c)}{0.0.0.0:8983}
papermerge-solr-1    | 2024-01-18 07:43:32.972 INFO  (main) [] o.e.j.s.Server Started Server@74cadd41{STARTING}[10.0.15,sto=0] @4321ms
papermerge-web-1     | System check identified some issues:
papermerge-web-1     | 
papermerge-web-1     | WARNINGS:
papermerge-web-1     | core.BaseTreeNode: (models.W036) MariaDB does not support unique constraints with conditions.
papermerge-web-1     |  HINT: A constraint won't be created. Silence this warning if you don't care about it.
papermerge-web-1     | Operations to perform:
papermerge-web-1     |   Apply all migrations: auth, contenttypes, core, sites
papermerge-web-1     | Running migrations:
papermerge-web-1     |   No migrations to apply.
papermerge-worker-1  | Installing dependencies from lock file
papermerge-worker-1  | 
papermerge-worker-1  | Package operations: 6 installs, 0 updates, 0 removals
papermerge-worker-1  | 
papermerge-worker-1  |   • Installing iniconfig (2.0.0)
papermerge-worker-1  |   • Installing packaging (23.1)
papermerge-worker-1  |   • Installing pluggy (1.3.0)
papermerge-worker-1  |   • Installing tomli (2.0.1)
papermerge-web-1     | Installing dependencies from lock file
papermerge-web-1     | 
papermerge-web-1     | Package operations: 6 installs, 0 updates, 0 removals
papermerge-web-1     | 
papermerge-web-1     |   • Installing iniconfig (2.0.0)
papermerge-web-1     |   • Installing packaging (23.1)
papermerge-web-1     |   • Installing pluggy (1.3.0)
papermerge-web-1     |   • Installing tomli (2.0.1)
papermerge-worker-1  |   • Installing pytest (7.4.1)
papermerge-worker-1  |   • Installing pytest-env (0.8.2)
papermerge-worker-1  | 
papermerge-worker-1  | Installing the current project: auth-server (0.6.2)
papermerge-web-1     |   • Installing pytest (7.4.1)
papermerge-web-1     |   • Installing pytest-env (0.8.2)
papermerge-web-1     | 
papermerge-web-1     | Installing the current project: auth-server (0.6.2)
papermerge-worker-1  | User 'eugen' already exists.
papermerge-web-1     | User 'eugen' already exists.
papermerge-worker-1  | /core_app/.venv/lib/python3.10/site-packages/celery/platforms.py:829: SecurityWarning: You're running the worker with superuser privileges: this is
papermerge-worker-1  | absolutely not recommended!
papermerge-worker-1  | 
papermerge-worker-1  | Please specify a different user using the --uid option.
papermerge-worker-1  | 
papermerge-worker-1  | User information: uid=0 euid=0 gid=0 egid=0
papermerge-worker-1  | 
papermerge-worker-1  |   warnings.warn(SecurityWarning(ROOT_DISCOURAGED.format(
papermerge-worker-1  |  
papermerge-worker-1  |  -------------- worker-node-4ecd1e8f8f67@papermerge v5.3.6 (emerald-rush)
papermerge-worker-1  | --- ***** ----- 
papermerge-worker-1  | -- ******* ---- Linux-5.10.0-27-amd64-x86_64-with-glibc2.36 2024-01-18 08:43:36
papermerge-worker-1  | - *** --- * --- 
papermerge-worker-1  | - ** ---------- [config]
papermerge-worker-1  | - ** ---------- .> app:         papermerge:0x7f5c1171ec50
papermerge-worker-1  | - ** ---------- .> transport:   redis://redis:6379/0
papermerge-worker-1  | - ** ---------- .> results:     disabled://
papermerge-worker-1  | - *** --- * --- .> concurrency: 6 (prefork)
papermerge-worker-1  | -- ******* ---- .> task events: OFF (enable -E to monitor tasks in this worker)
papermerge-worker-1  | --- ***** ----- 
papermerge-worker-1  |  -------------- [queues]
papermerge-worker-1  |                 .> celery           exchange=papermerge(direct) key=papermerge
papermerge-worker-1  |                 
papermerge-worker-1  | 
papermerge-solr-1    | 2024-01-18 07:43:36.634 INFO  (qtp1827171553-23) [ x:pmg-index] o.a.s.c.S.Request webapp=/solr path=/schema/fields/document_id params={} status=404 QTime=6
papermerge-solr-1    | 2024-01-18 07:43:36.659 INFO  (qtp1827171553-19) [ x:pmg-index] o.a.s.c.S.Request webapp=/solr path=/schema/fields/lang params={} status=404 QTime=0
papermerge-solr-1    | 2024-01-18 07:43:36.664 INFO  (qtp1827171553-17) [ x:pmg-index] o.a.s.c.S.Request webapp=/solr path=/schema/fields/user_id params={} status=404 QTime=0
papermerge-solr-1    | 2024-01-18 07:43:36.672 INFO  (qtp1827171553-18) [ x:pmg-index] o.a.s.c.S.Request webapp=/solr path=/schema/fields/parent_id params={} status=404 QTime=0
papermerge-solr-1    | 2024-01-18 07:43:36.678 INFO  (qtp1827171553-26) [ x:pmg-index] o.a.s.c.S.Request webapp=/solr path=/schema/fields/entity_type params={} status=404 QTime=0
papermerge-solr-1    | 2024-01-18 07:43:36.684 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.c.S.Request webapp=/solr path=/schema/fields/breadcrumb params={} status=404 QTime=0
papermerge-solr-1    | 2024-01-18 07:43:36.690 INFO  (qtp1827171553-21) [ x:pmg-index] o.a.s.c.S.Request webapp=/solr path=/schema/fields/tags params={} status=404 QTime=0
papermerge-solr-1    | 2024-01-18 07:43:36.697 INFO  (qtp1827171553-26) [ x:pmg-index] o.a.s.c.S.Request webapp=/solr path=/schema/fields/page_number params={} status=404 QTime=0
papermerge-solr-1    | 2024-01-18 07:43:36.702 INFO  (qtp1827171553-24) [ x:pmg-index] o.a.s.c.S.Request webapp=/solr path=/schema/fields/page_count params={} status=404 QTime=0
papermerge-solr-1    | 2024-01-18 07:43:36.708 INFO  (qtp1827171553-21) [ x:pmg-index] o.a.s.c.S.Request webapp=/solr path=/schema/dynamicfields/*_orig_ params={} status=404 QTime=1
papermerge-worker-1  | [2024-01-18 08:43:36,724: WARNING/MainProcess] /core_app/.venv/lib/python3.10/site-packages/celery/worker/consumer/consumer.py:507: CPendingDeprecationWarning: The broker_connection_retry configuration setting will no longer determine
papermerge-worker-1  | whether broker connection retries are made during startup in Celery 6.0 and above.
papermerge-worker-1  | If you wish to retain the existing behavior for retrying connections on startup,
papermerge-worker-1  | you should set broker_connection_retry_on_startup to True.
papermerge-worker-1  |   warnings.warn(
papermerge-worker-1  | 
papermerge-worker-1  | [2024-01-18 08:43:36,745: WARNING/MainProcess] /core_app/.venv/lib/python3.10/site-packages/celery/worker/consumer/consumer.py:507: CPendingDeprecationWarning: The broker_connection_retry configuration setting will no longer determine
papermerge-worker-1  | whether broker connection retries are made during startup in Celery 6.0 and above.
papermerge-worker-1  | If you wish to retain the existing behavior for retrying connections on startup,
papermerge-worker-1  | you should set broker_connection_retry_on_startup to True.
papermerge-worker-1  |   warnings.warn(
papermerge-worker-1  | 
papermerge-solr-1    | 2024-01-18 07:43:36.975 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /var/solr/data/pmg-index/conf/managed-schema.xml
papermerge-solr-1    | 2024-01-18 07:43:37.003 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.7.0
papermerge-solr-1    | 2024-01-18 07:43:37.011 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.s.IndexSchema Schema name=default-config
papermerge-solr-1    | 2024-01-18 07:43:37.035 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
papermerge-solr-1    | 2024-01-18 07:43:37.035 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.c.CoreContainer Reloading SolrCore 'pmg-index' using configuration from instancedir /var/solr/data/pmg-index
papermerge-solr-1    | 2024-01-18 07:43:37.059 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.c.SolrCore Opening new SolrCore at [/var/solr/data/pmg-index], dataDir=[/var/solr/data/pmg-index/data/]
papermerge-solr-1    | 2024-01-18 07:43:37.090 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; 
papermerge-solr-1    | 2024-01-18 07:43:37.091 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.u.CommitTracker Soft AutoCommit: disabled
papermerge-solr-1    | 2024-01-18 07:43:37.094 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /var/solr/data/pmg-index/conf
papermerge-solr-1    | 2024-01-18 07:43:37.095 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.s.DirectSolrSpellChecker init: {maxEdits=2, minPrefix=1, maxInspections=5, minQueryLength=4, accuracy=0.5, maxQueryFrequency=0.01, name=default, field=_text_, classname=solr.DirectSolrSpellChecker, distanceMeasure=internal}
papermerge-solr-1    | 2024-01-18 07:43:37.096 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
papermerge-solr-1    | 2024-01-18 07:43:37.096 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1788413285075255296
papermerge-solr-1    | 2024-01-18 07:43:37.098 INFO  (searcherExecutor-18-thread-1-processing-pmg-index) [ x:pmg-index] o.a.s.c.QuerySenderListener QuerySenderListener done.
papermerge-solr-1    | 2024-01-18 07:43:37.098 INFO  (searcherExecutor-18-thread-1-processing-pmg-index) [ x:pmg-index] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
papermerge-solr-1    | 2024-01-18 07:43:37.100 INFO  (qtp1827171553-22) [ x:pmg-index] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
papermerge-solr-1    | 2024-01-18 07:43:37.107 INFO  (searcherExecutor-18-thread-1-processing-pmg-index) [ x:pmg-index] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
papermerge-solr-1    | 2024-01-18 07:43:37.110 INFO  (qtp1827171553-22) [] o.a.s.c.S.Request webapp=/solr path=/schema params={} status=0 QTime=392
papermerge-solr-1    | 2024-01-18 07:43:37.111 INFO  (qtp1827171553-22) [] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@3543be8e pmg-index
papermerge-solr-1    | 2024-01-18 07:43:37.112 INFO  (qtp1827171553-22) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.pmg-index tag=SolrCore@3543be8e
papermerge-solr-1    | 2024-01-18 07:43:37.115 INFO  (searcherExecutor-18-thread-1-processing-pmg-index) [ x:pmg-index] o.a.s.c.QuerySenderListener QuerySenderListener done.
papermerge-solr-1    | 2024-01-18 07:43:37.119 INFO  (searcherExecutor-18-thread-1-processing-pmg-index) [ x:pmg-index] o.a.s.c.SolrCore Registered new searcher autowarm time: 5 ms
papermerge-web-1     | /usr/lib/python3/dist-packages/supervisor/options.py:474: UserWarning: Supervisord is running as root and it is searching for its configuration file in default locations (including its current working directory); you probably want to specify a "-c" argument specifying an absolute path to a configuration file for improved security.
papermerge-web-1     |   self.warnings.warn(
papermerge-web-1     | 2024-01-18 07:43:37,418 CRIT Supervisor is running as root.  Privileges were not dropped because no user is specified in the config file.  If you intend to run as root, you can set user=root in the config file to avoid this message.
papermerge-web-1     | 2024-01-18 07:43:37,420 INFO supervisord started with pid 1
papermerge-web-1     | 2024-01-18 07:43:38,422 INFO spawned: 'tivoli' with pid 75
papermerge-web-1     | 2024-01-18 07:43:38,426 INFO spawned: 'auth_server' with pid 76
papermerge-web-1     | 2024-01-18 07:43:38,427 INFO spawned: 'core' with pid 77
papermerge-web-1     | 2024-01-18 07:43:38,429 INFO spawned: 'nginx' with pid 78
papermerge-web-1     | 2024/01/18 07:43:38 [notice] 78#78: using the "epoll" event method
papermerge-web-1     | 2024/01/18 07:43:38 [notice] 78#78: nginx/1.22.1
papermerge-web-1     | 2024/01/18 07:43:38 [notice] 78#78: OS: Linux 5.10.0-27-amd64
papermerge-web-1     | 2024/01/18 07:43:38 [notice] 78#78: getrlimit(RLIMIT_NOFILE): 1048576:1048576
papermerge-web-1     | 2024/01/18 07:43:38 [notice] 78#78: start worker processes
papermerge-web-1     | 2024/01/18 07:43:38 [notice] 78#78: start worker process 79
papermerge-web-1     | INFO:     Uvicorn running on http://0.0.0.0:8100 (Press CTRL+C to quit)
papermerge-web-1     | INFO:     Started parent process [75]
papermerge-web-1     | 2024-01-18 07:43:40,311 INFO success: tivoli entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
papermerge-web-1     | 2024-01-18 07:43:40,315 INFO success: auth_server entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
papermerge-web-1     | 2024-01-18 07:43:40,315 INFO success: core entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
papermerge-web-1     | 2024-01-18 07:43:40,315 INFO success: nginx entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
papermerge-web-1     | INFO:     Started server process [87]
papermerge-web-1     | INFO:     Waiting for application startup.
papermerge-web-1     | INFO:     Application startup complete.
papermerge-web-1     | INFO:     Started server process [89]
papermerge-web-1     | INFO:     Waiting for application startup.
papermerge-web-1     | INFO:     Application startup complete.
papermerge-web-1     | INFO:     Started server process [88]
papermerge-web-1     | INFO:     Waiting for application startup.
papermerge-web-1     | INFO:     Application startup complete.
papermerge-web-1     | INFO:     127.0.0.1:34926 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:43:50 +0000] "GET / HTTP/1.1" 304 0 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:34938 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:43:50 +0000] "GET /api/users/me HTTP/1.1" 500 21 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | Exception in ASGI application
papermerge-web-1     | Traceback (most recent call last):
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/protocols/http/h11_impl.py", line 408, in run_asgi
papermerge-web-1     |     result = await app(  # type: ignore[func-returns-value]
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/middleware/proxy_headers.py", line 84, in __call__
papermerge-web-1     |     return await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/applications.py", line 289, in __call__
papermerge-web-1     |     await super().__call__(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/applications.py", line 122, in __call__
papermerge-web-1     |     await self.middleware_stack(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/errors.py", line 184, in __call__
papermerge-web-1     |     raise exc
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/errors.py", line 162, in __call__
papermerge-web-1     |     await self.app(scope, receive, _send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/cors.py", line 83, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 79, in __call__
papermerge-web-1     |     raise exc
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 68, in __call__
papermerge-web-1     |     await self.app(scope, receive, sender)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 20, in __call__
papermerge-web-1     |     raise e
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 17, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 718, in __call__
papermerge-web-1     |     await route.handle(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 276, in handle
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 66, in app
papermerge-web-1     |     response = await func(request)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/routing.py", line 263, in app
papermerge-web-1     |     solved_result = await solve_dependencies(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/dependencies/utils.py", line 586, in solve_dependencies
papermerge-web-1     |     solved = await run_in_threadpool(call, **sub_values)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/concurrency.py", line 41, in run_in_threadpool
papermerge-web-1     |     return await anyio.to_thread.run_sync(func, *args)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/to_thread.py", line 56, in run_sync
papermerge-web-1     |     return await get_async_backend().run_sync_in_worker_thread(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 2134, in run_sync_in_worker_thread
papermerge-web-1     |     return await future
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 851, in run
papermerge-web-1     |     result = context.run(func, *args)
papermerge-web-1     |   File "/core_app/papermerge/core/auth/__init__.py", line 43, in get_current_user
papermerge-web-1     |     user = db.get_user(engine, user_id)
papermerge-web-1     |   File "/core_app/papermerge/core/db/users.py", line 35, in get_user
papermerge-web-1     |     db_user = session.scalars(stmt, params).one()
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 1824, in one
papermerge-web-1     |     return self._only_one_row(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 757, in _only_one_row
papermerge-web-1     |     raise exc.NoResultFound(
papermerge-web-1     | sqlalchemy.exc.NoResultFound: No row was found when one was required
papermerge-web-1     | INFO:     127.0.0.1:34954 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | Exception in ASGI application
papermerge-web-1     | Traceback (most recent call last):
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/protocols/websockets/websockets_impl.py", line 247, in run_asgi
papermerge-web-1     |     result = await self.app(self.scope, self.asgi_receive, self.asgi_send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/middleware/proxy_headers.py", line 84, in __call__
papermerge-web-1     |     return await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/applications.py", line 289, in __call__
papermerge-web-1     |     await super().__call__(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/applications.py", line 122, in __call__
papermerge-web-1     |     await self.middleware_stack(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/errors.py", line 149, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/cors.py", line 75, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 79, in __call__
papermerge-web-1     |     raise exc
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 68, in __call__
papermerge-web-1     |     await self.app(scope, receive, sender)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 20, in __call__
papermerge-web-1     |     raise e
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 17, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 718, in __call__
papermerge-web-1     |     await route.handle(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 341, in handle
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 82, in app
papermerge-web-1     |     await func(session)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/routing.py", line 315, in app
papermerge-web-1     |     solved_result = await solve_dependencies(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/dependencies/utils.py", line 586, in solve_dependencies
papermerge-web-1     |     solved = await run_in_threadpool(call, **sub_values)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/concurrency.py", line 41, in run_in_threadpool
papermerge-web-1     |     return await anyio.to_thread.run_sync(func, *args)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/to_thread.py", line 56, in run_sync
papermerge-web-1     |     return await get_async_backend().run_sync_in_worker_thread(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 2134, in run_sync_in_worker_thread
papermerge-web-1     |     return await future
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 851, in run
papermerge-web-1     |     result = context.run(func, *args)
papermerge-web-1     |   File "/core_app/papermerge/core/auth/__init__.py", line 97, in get_ws_current_user
papermerge-web-1     |     user = db.get_user(engine, user_id)
papermerge-web-1     |   File "/core_app/papermerge/core/db/users.py", line 35, in get_user
papermerge-web-1     |     db_user = session.scalars(stmt, params).one()
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 1824, in one
papermerge-web-1     |     return self._only_one_row(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 757, in _only_one_row
papermerge-web-1     |     raise exc.NoResultFound(
papermerge-web-1     | sqlalchemy.exc.NoResultFound: No row was found when one was required
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:43:50 +0000] "GET /ws/ocr HTTP/1.1" 500 21 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:46618 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:44:07 +0000] "GET / HTTP/1.1" 200 680 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:46626 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | INFO:     127.0.0.1:46620 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:44:07 +0000] "GET /static/css/main.9355bcdf.css HTTP/1.1" 200 327796 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:44:07 +0000] "GET /static/js/main.cbdd01fc.js HTTP/1.1" 200 362497 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:46646 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:44:07 +0000] "GET /favicon.ico HTTP/1.1" 200 13942 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:46630 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:44:07 +0000] "GET /api/users/me HTTP/1.1" 500 21 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | Exception in ASGI application
papermerge-web-1     | Traceback (most recent call last):
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/protocols/http/h11_impl.py", line 408, in run_asgi
papermerge-web-1     |     result = await app(  # type: ignore[func-returns-value]
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/middleware/proxy_headers.py", line 84, in __call__
papermerge-web-1     |     return await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/applications.py", line 289, in __call__
papermerge-web-1     |     await super().__call__(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/applications.py", line 122, in __call__
papermerge-web-1     |     await self.middleware_stack(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/errors.py", line 184, in __call__
papermerge-web-1     |     raise exc
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/errors.py", line 162, in __call__
papermerge-web-1     |     await self.app(scope, receive, _send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/cors.py", line 83, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 79, in __call__
papermerge-web-1     |     raise exc
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 68, in __call__
papermerge-web-1     |     await self.app(scope, receive, sender)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 20, in __call__
papermerge-web-1     |     raise e
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 17, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 718, in __call__
papermerge-web-1     |     await route.handle(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 276, in handle
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 66, in app
papermerge-web-1     |     response = await func(request)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/routing.py", line 263, in app
papermerge-web-1     |     solved_result = await solve_dependencies(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/dependencies/utils.py", line 586, in solve_dependencies
papermerge-web-1     |     solved = await run_in_threadpool(call, **sub_values)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/concurrency.py", line 41, in run_in_threadpool
papermerge-web-1     |     return await anyio.to_thread.run_sync(func, *args)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/to_thread.py", line 56, in run_sync
papermerge-web-1     |     return await get_async_backend().run_sync_in_worker_thread(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 2134, in run_sync_in_worker_thread
papermerge-web-1     |     return await future
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 851, in run
papermerge-web-1     |     result = context.run(func, *args)
papermerge-web-1     |   File "/core_app/papermerge/core/auth/__init__.py", line 43, in get_current_user
papermerge-web-1     |     user = db.get_user(engine, user_id)
papermerge-web-1     |   File "/core_app/papermerge/core/db/users.py", line 35, in get_user
papermerge-web-1     |     db_user = session.scalars(stmt, params).one()
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 1824, in one
papermerge-web-1     |     return self._only_one_row(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 757, in _only_one_row
papermerge-web-1     |     raise exc.NoResultFound(
papermerge-web-1     | sqlalchemy.exc.NoResultFound: No row was found when one was required
papermerge-web-1     | INFO:     127.0.0.1:46666 - "GET /token-validator HTTP/1.0" 401 Unauthorized
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:44:07 +0000] "GET /manifest.json HTTP/1.1" 200 492 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:46650 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | INFO:     127.0.0.1:46680 - "GET /token-validator HTTP/1.0" 200 OK
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:44:08 +0000] "GET /logo192.png HTTP/1.1" 200 680 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | Exception in ASGI application
papermerge-web-1     | Traceback (most recent call last):
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/protocols/websockets/websockets_impl.py", line 247, in run_asgi
papermerge-web-1     |     result = await self.app(self.scope, self.asgi_receive, self.asgi_send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/uvicorn/middleware/proxy_headers.py", line 84, in __call__
papermerge-web-1     |     return await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/applications.py", line 289, in __call__
papermerge-web-1     |     await super().__call__(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/applications.py", line 122, in __call__
papermerge-web-1     |     await self.middleware_stack(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/errors.py", line 149, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/cors.py", line 75, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 79, in __call__
papermerge-web-1     |     raise exc
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/middleware/exceptions.py", line 68, in __call__
papermerge-web-1     |     await self.app(scope, receive, sender)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 20, in __call__
papermerge-web-1     |     raise e
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/middleware/asyncexitstack.py", line 17, in __call__
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 718, in __call__
papermerge-web-1     |     await route.handle(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 341, in handle
papermerge-web-1     |     await self.app(scope, receive, send)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/routing.py", line 82, in app
papermerge-web-1     |     await func(session)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/routing.py", line 315, in app
papermerge-web-1     |     solved_result = await solve_dependencies(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/fastapi/dependencies/utils.py", line 586, in solve_dependencies
papermerge-web-1     |     solved = await run_in_threadpool(call, **sub_values)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/starlette/concurrency.py", line 41, in run_in_threadpool
papermerge-web-1     |     return await anyio.to_thread.run_sync(func, *args)
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/to_thread.py", line 56, in run_sync
papermerge-web-1     |     return await get_async_backend().run_sync_in_worker_thread(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 2134, in run_sync_in_worker_thread
papermerge-web-1     |     return await future
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/anyio/_backends/_asyncio.py", line 851, in run
papermerge-web-1     |     result = context.run(func, *args)
papermerge-web-1     |   File "/core_app/papermerge/core/auth/__init__.py", line 97, in get_ws_current_user
papermerge-web-1     |     user = db.get_user(engine, user_id)
papermerge-web-1     |   File "/core_app/papermerge/core/db/users.py", line 35, in get_user
papermerge-web-1     |     db_user = session.scalars(stmt, params).one()
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 1824, in one
papermerge-web-1     |     return self._only_one_row(
papermerge-web-1     |   File "/core_app/.venv/lib/python3.10/site-packages/sqlalchemy/engine/result.py", line 757, in _only_one_row
papermerge-web-1     |     raise exc.NoResultFound(
papermerge-web-1     | sqlalchemy.exc.NoResultFound: No row was found when one was required
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:07:44:08 +0000] "GET /ws/ocr HTTP/1.1" 500 21 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"

@jase64
Copy link
Author

jase64 commented Jan 18, 2024

After some tests with different configurations (changing small things in compose script like removing SOLR), the issue always seems to occur on the following 401 Unauthorized

papermerge-web-1     | INFO:     127.0.0.1:42308 - "GET /token-validator HTTP/1.0" 401 Unauthorized
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:09:44:09 +0000] "GET /api/users/me HTTP/1.1" 401 12 "http://localhost:12000/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
papermerge-web-1     | INFO:     127.0.0.1:42314 - "GET /token-validator HTTP/1.0" 401 Unauthorized
papermerge-web-1     | 172.22.0.1 - - [18/Jan/2024:09:44:09 +0000] "GET /ws/ocr HTTP/1.1" 200 702 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"

Shall I clear MariaDB tables as well to clear potential inconsistencies?

@ciur
Copy link
Member

ciur commented Jan 19, 2024 •
edited

Found it. It happens with mysql/mediadb/sqlite because of UUID/string inconsistencies.

I guess it is related to this issue.

@ciur ciur self-assigned this Jan 19, 2024
@ciur ciur added bug Something isn't working confirmed 3.0.1 labels Jan 19, 2024
@ciur
Copy link
Member

ciur commented Jan 21, 2024 •
edited

@jase64

I've published 3.0.2 which should fix current issue.
Also in documentation I've updated docker-compose examples to include healthcheck key - which ensures that webapp/workers will start only after database process (not just db container) is up and running. Please use updated docker compose examples.

@ciur ciur added the medium label Jan 27, 2024
@ciur ciur closed this as completed Jan 28, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@ciur ciur

Labels
3.0.1 bug Something isn't working confirmed medium
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@jase64 @ciur