search

dfir-iris / iris-web

Collaborative Incident Response platform
GNU Lesser General Public License v3.0
1.07k stars 182 forks source link

[BUG] Error after installation unable to access the web panel #315

Closed JN778 closed 1 month ago

JN778 commented 1 year ago

Hello there,

i run over the following bug during installation:

Describe the bug After following the default installation and starting the containers with docker-compose up the following error appears: iriswebapp_worker | IRISwebapp is unavailable - sleeping iriswebapp_nginx | 2023/10/04 19:45:30 [error] 10#10: 69 connect() failed (111: Connection refused) while connecting to upstream, client: 127.0.0.1, server: iris.app.dev, request: "GET / HTTP/1.1", upstream: "http://172.18.0.2:8000/", host: "127.0.0.1" iriswebapp_nginx | 127.0.0.1 - - [04/Oct/2023:19:45:30 +0000] "GET / HTTP/1.1" 502 497 "-" "curl/7.64.0" "-" iriswebapp_nginx | 2023/10/04 19:45:30 [info] 10#10: 69 client 127.0.0.1 closed keepalive connection iriswebapp_worker | IRISwebapp is unavailable - sleeping iriswebapp_worker | IRISwebapp is unavailable - sleeping iriswebapp_worker | IRISwebapp is unavailable - sleeping iriswebapp_worker | IRISwebapp is unavailable - sleeping iriswebapp_worker | IRISwebapp is unavailable - sleeping

In the Browser under port 443 i recieve An error occurred. Sorry, the page you are looking for is currently unavailable. Please try again later.

If you are the system administrator of this resource then you should check the error log for details.

Faithfully yours, nginx.

Tried to install it under Ubuntu 22.04 and Debian 12 both with the same error. Even in completly newvms build in under Virtualbox

To Reproduce Steps to reproduce the behavior: Create a new VM with Debain12 or Ubuntu 22.04 VM in Virtualbox Follow the installation

Clone the iris-web repository

git clone https://github.com/dfir-iris/iris-web.git cd iris-web

Checkout to the last tagged version

git checkout v2.3.2

Copy the environment file

cp .env.model .env

Build the dockers

docker-compose build

Run IRIS

docker-compose up

-> Recieve the error i mentioned under Describe the bug after running -> docker-compose up

Expected behavior The webinterface should start normaly

Desktop (please complete the following information):

whikernel commented 1 year ago

Hi @JN778

Could you please check if it is related to https://github.com/dfir-iris/iris-web/issues/314?

JN778 commented 1 year ago

Hey @whikernel i haven´t tried it yet but i seems to be the same error

TPower2112 commented 1 year ago

We ran into this exact issue yesterday. We will check #314

We had the same error as #314. We removed the iris web folder and pulled down the new code in build 2.3.3 and the install worked. thx.

JN778 commented 1 year ago

Even after the fix in v2.3.3 from https://github.com/dfir-iris/iris-web/issues/314 the error still occures. I also started the building process on a new vm so there could not be any data in cache from previous builds

micksetu commented 1 year ago

Having the same issue. Tried multiple VMs and directly on host hardware. Ubuntu 20.04.

whikernel commented 1 year ago

Hi @JN778, @mickitcarlow,

Can you show us the error logs when the app is starting please? We installed multiple times on Ubuntu and got no issues so far.

JN778 commented 1 year ago

Sure

the iriswebapp_nginx:v2.3.3 delivers ongoing the following: sudo docker logs cbc5b93a3683 2023/10/18 16:31:12 [error] 10#10: 1 connect() failed (111: Connection refused) while connecting to upstream, client: 127.0.0.1, server: iris.app.dev, request: "GET / HTTP/1.1", upstream: "http://172.18.0.2:8000/", host: "127.0.0.1" 127.0.0.1 - - [18/Oct/2023:16:31:12 +0000] "GET / HTTP/1.1" 502 497 "-" "curl/7.64.0" "-" 2023/10/18 16:31:12 [info] 10#10: 1 client 127.0.0.1 closed keepalive connection 127.0.0.1 - - [18/Oct/2023:16:31:17 +0000] "GET / HTTP/1.1" 502 497 "-" "curl/7.64.0" "-" 2023/10/18 16:31:17 [error] 10#10: *3 connect() failed (111: Connection refused) while connecting to upstream, client: 127.0.0.1, server: iris.app.dev, request: "GET / HTTP/1.1", upstream: "http://172.18.0.2:8000/", host: "127.0.0.1"

the iriswebapp_workerv2.3.3: sudo docker logs 116e9835b399 IRISwebapp is unavailable - sleeping IRISwebapp is unavailable - sleeping IRISwebapp is unavailable - sleeping IRISwebapp is unavailable - sleeping IRISwebapp is unavailable - sleeping ...

the iriswebapp_appv2.3.3: sudo docker logs b42041d02d0f Running iriswebapp ... [2023-10-18 16:31:07 +0000] [7] [INFO] Starting gunicorn 20.1.0 [2023-10-18 16:31:07 +0000] [7] [INFO] Listening at: http://0.0.0.0:8000 (7) [2023-10-18 16:31:07 +0000] [7] [INFO] Using worker: eventlet [2023-10-18 16:31:07 +0000] [9] [INFO] Booting worker with pid: 9 2023-10-18 16:31:09 :: INFO :: post_init :: run_post_init :: IRIS v2.3.3 2023-10-18 16:31:09 :: INFO :: post_init :: run_post_init :: Running post initiation steps 2023-10-18 16:31:09 :: INFO :: post_init :: run_post_init :: Attempting to connect to the database... 2023-10-18 16:31:09 :: INFO :: post_init :: run_post_init :: Connecting to database, attempt 1/3 2023-10-18 16:31:09 :: INFO :: post_init :: run_post_init :: Adding pgcrypto extension 2023-10-18 16:31:09 :: ERROR :: views :: :: Post init failed. IRIS not started Traceback (most recent call last): File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 3250, in _wrap_pool_connect return fn() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 310, in connect return _ConnectionFairy._checkout(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 868, in _checkout fairy = _ConnectionRecord.checkout(pool) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 476, in checkout rec = pool._do_get() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/impl.py", line 146, in _do_get self._decoverflow() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/langhelpers.py", line 70, in exit compat.raise( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/compat.py", line 207, in raise_ raise exception File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/impl.py", line 143, in _do_get return self._create_connection() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 256, in _create_connection return ConnectionRecord(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 371, in init self.connect() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 666, in connect pool.logger.debug("Error on connect(): %s", e) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/langhelpers.py", line 70, in exit compat.raise( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/compat.py", line 207, in raise_ raise exception File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 661, in __connect self.dbapi_connection = connection = pool._invoke_creator(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/create.py", line 590, in connect return dialect.connect(*cargs, *cparams) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/default.py", line 597, in connect return self.dbapi.connect(cargs, cparams) File "/opt/venv/lib/python3.9/site-packages/psycopg2/init.py", line 122, in connect conn = _connect(dsn, connection_factory=connection_factory, kwasync) File "/opt/venv/lib/python3.9/site-packages/eventlet/support/psycopg2_patcher.py", line 46, in eventlet_wait_callback state = conn.poll() psycopg2.OperationalError: could not connect to server: Connection refused Is the server running on host "db" (172.19.0.2) and accepting TCP/IP connections on port 5432?

The above exception was the direct cause of the following exception:

Traceback (most recent call last): File "/iriswebapp/app/views.py", line 110, in run_post_init(development=app.config["DEVELOPMENT"]) File "/iriswebapp/app/post_init.py", line 143, in run_post_init pg_add_pgcrypto_ext() File "/iriswebapp/app/post_init.py", line 546, in pg_add_pgcrypto_ext with db.engine.connect() as con: File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 3204, in connect return self._connection_cls(self, close_with_result=close_with_result) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 96, in init else engine.raw_connection() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 3283, in raw_connection return self._wrap_pool_connect(self.pool.connect, _connection) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 3253, in _wrap_pool_connect Connection._handle_dbapi_exception_noconnection( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 2100, in _handle_dbapi_exceptionnoconnection util.raise( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/compat.py", line 207, in raise_ raise exception File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 3250, in _wrap_pool_connect return fn() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 310, in connect return _ConnectionFairy._checkout(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 868, in _checkout fairy = _ConnectionRecord.checkout(pool) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 476, in checkout rec = pool._do_get() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/impl.py", line 146, in _do_get self._decoverflow() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/langhelpers.py", line 70, in exit compat.raise( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/compat.py", line 207, in raise_ raise exception File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/impl.py", line 143, in _do_get return self._create_connection() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 256, in _create_connection return ConnectionRecord(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 371, in init self.connect() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 666, in connect pool.logger.debug("Error on connect(): %s", e) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/langhelpers.py", line 70, in exit compat.raise( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/compat.py", line 207, in raise_ raise exception File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 661, in __connect self.dbapi_connection = connection = pool._invoke_creator(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/create.py", line 590, in connect return dialect.connect(*cargs, *cparams) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/default.py", line 597, in connect return self.dbapi.connect(cargs, cparams) File "/opt/venv/lib/python3.9/site-packages/psycopg2/init.py", line 122, in connect conn = _connect(dsn, connection_factory=connection_factory, kwasync) File "/opt/venv/lib/python3.9/site-packages/eventlet/support/psycopg2_patcher.py", line 46, in eventlet_wait_callback state = conn.poll() sqlalchemy.exc.OperationalError: (psycopg2.OperationalError) could not connect to server: Connection refused Is the server running on host "db" (172.19.0.2) and accepting TCP/IP connections on port 5432?

(Background on this error at: https://sqlalche.me/e/14/e3q8) [2023-10-18 16:31:09 +0000] [9] [ERROR] Exception in worker process Traceback (most recent call last): File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 3250, in _wrap_pool_connect return fn() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 310, in connect return _ConnectionFairy._checkout(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 868, in _checkout fairy = _ConnectionRecord.checkout(pool) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 476, in checkout rec = pool._do_get() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/impl.py", line 146, in _do_get self._decoverflow() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/langhelpers.py", line 70, in exit compat.raise( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/compat.py", line 207, in raise_ raise exception File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/impl.py", line 143, in _do_get return self._create_connection() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 256, in _create_connection return ConnectionRecord(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 371, in init self.connect() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 666, in connect pool.logger.debug("Error on connect(): %s", e) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/langhelpers.py", line 70, in exit compat.raise( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/compat.py", line 207, in raise_ raise exception File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 661, in __connect self.dbapi_connection = connection = pool._invoke_creator(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/create.py", line 590, in connect return dialect.connect(*cargs, *cparams) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/default.py", line 597, in connect return self.dbapi.connect(cargs, cparams) File "/opt/venv/lib/python3.9/site-packages/psycopg2/init.py", line 122, in connect conn = _connect(dsn, connection_factory=connection_factory, kwasync) File "/opt/venv/lib/python3.9/site-packages/eventlet/support/psycopg2_patcher.py", line 46, in eventlet_wait_callback state = conn.poll() psycopg2.OperationalError: could not connect to server: Connection refused Is the server running on host "db" (172.19.0.2) and accepting TCP/IP connections on port 5432?

The above exception was the direct cause of the following exception:

Traceback (most recent call last): File "/opt/venv/lib/python3.9/site-packages/gunicorn/arbiter.py", line 589, in spawn_worker worker.init_process() File "/opt/venv/lib/python3.9/site-packages/gunicorn/workers/geventlet.py", line 134, in init_process super().init_process() File "/opt/venv/lib/python3.9/site-packages/gunicorn/workers/base.py", line 134, in init_process self.load_wsgi() File "/opt/venv/lib/python3.9/site-packages/gunicorn/workers/base.py", line 146, in load_wsgi self.wsgi = self.app.wsgi() File "/opt/venv/lib/python3.9/site-packages/gunicorn/app/base.py", line 67, in wsgi self.callable = self.load() File "/opt/venv/lib/python3.9/site-packages/gunicorn/app/wsgiapp.py", line 58, in load return self.load_wsgiapp() File "/opt/venv/lib/python3.9/site-packages/gunicorn/app/wsgiapp.py", line 48, in load_wsgiapp return util.import_app(self.app_uri) File "/opt/venv/lib/python3.9/site-packages/gunicorn/util.py", line 359, in import_app mod = importlib.import_module(module) File "/usr/local/lib/python3.9/importlib/init.py", line 127, in import_module return _bootstrap._gcd_import(name[level:], package, level) File "", line 1030, in _gcd_import File "", line 1007, in _find_and_load File "", line 986, in _find_and_load_unlocked File "", line 680, in _load_unlocked File "", line 850, in exec_module File "", line 228, in _call_with_frames_removed File "/iriswebapp/app/init.py", line 134, in from app import views File "/iriswebapp/app/views.py", line 114, in raise e File "/iriswebapp/app/views.py", line 110, in run_post_init(development=app.config["DEVELOPMENT"]) File "/iriswebapp/app/post_init.py", line 143, in run_post_init pg_add_pgcrypto_ext() File "/iriswebapp/app/post_init.py", line 546, in pg_add_pgcrypto_ext with db.engine.connect() as con: File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 3204, in connect return self._connection_cls(self, close_with_result=close_with_result) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 96, in init else engine.raw_connection() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 3283, in raw_connection return self._wrap_pool_connect(self.pool.connect, _connection) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 3253, in _wrap_pool_connect Connection._handle_dbapi_exception_noconnection( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 2100, in _handle_dbapi_exceptionnoconnection util.raise( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/compat.py", line 207, in raise_ raise exception File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 3250, in _wrap_pool_connect return fn() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 310, in connect return _ConnectionFairy._checkout(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 868, in _checkout fairy = _ConnectionRecord.checkout(pool) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 476, in checkout rec = pool._do_get() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/impl.py", line 146, in _do_get self._decoverflow() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/langhelpers.py", line 70, in exit compat.raise( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/compat.py", line 207, in raise_ raise exception File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/impl.py", line 143, in _do_get return self._create_connection() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 256, in _create_connection return ConnectionRecord(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 371, in init self.connect() File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 666, in connect pool.logger.debug("Error on connect(): %s", e) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/langhelpers.py", line 70, in exit compat.raise( File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/util/compat.py", line 207, in raise_ raise exception File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/pool/base.py", line 661, in __connect self.dbapi_connection = connection = pool._invoke_creator(self) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/create.py", line 590, in connect return dialect.connect(*cargs, *cparams) File "/opt/venv/lib/python3.9/site-packages/sqlalchemy/engine/default.py", line 597, in connect return self.dbapi.connect(cargs, cparams) File "/opt/venv/lib/python3.9/site-packages/psycopg2/init.py", line 122, in connect conn = _connect(dsn, connection_factory=connection_factory, kwasync) File "/opt/venv/lib/python3.9/site-packages/eventlet/support/psycopg2_patcher.py", line 46, in eventlet_wait_callback state = conn.poll() sqlalchemy.exc.OperationalError: (psycopg2.OperationalError) could not connect to server: Connection refused Is the server running on host "db" (172.19.0.2) and accepting TCP/IP connections on port 5432?

(Background on this error at: https://sqlalche.me/e/14/e3q8) [2023-10-18 16:31:09 +0000] [9] [INFO] Worker exiting (pid: 9) [2023-10-18 16:31:09 +0000] [7] [INFO] Shutting down: Master [2023-10-18 16:31:09 +0000] [7] [INFO] Reason: Worker failed to boot.

the iriswebapp_db:v2.3.3: sudo docker logs 64b0a9ae5fc9 The files belonging to this database system will be owned by user "postgres". This user must also own the server process.

The database cluster will be initialized with locale "en_US.utf8". The default database encoding has accordingly been set to "UTF8". The default text search configuration will be set to "english".

Data page checksums are disabled.

fixing permissions on existing directory /var/lib/postgresql/data ... ok creating subdirectories ... ok selecting dynamic shared memory implementation ... posix selecting default max_connections ... 100 selecting default shared_buffers ... 128MB selecting default time zone ... UTC creating configuration files ... ok running bootstrap script ... ok sh: locale: not found 2023-10-18 16:31:07.874 UTC [30] WARNING: no usable system locales were found performing post-bootstrap initialization ... ok initdb: warning: enabling "trust" authentication for local connections You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb. syncing data to disk ... ok

Success. You can now start the database server using:

pg_ctl -D /var/lib/postgresql/data -l logfile start

waiting for server to start....2023-10-18 16:31:11.063 UTC [36] LOG: starting PostgreSQL 12.16 on x86_64-pc-linux-musl, compiled by gcc (Alpine 12.2.1_git20220924-r10) 12.2.1 20220924, 64-bit 2023-10-18 16:31:11.073 UTC [36] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432" 2023-10-18 16:31:11.108 UTC [37] LOG: database system was shut down at 2023-10-18 16:31:08 UTC 2023-10-18 16:31:11.118 UTC [36] LOG: database system is ready to accept connections done server started CREATE DATABASE

/usr/local/bin/docker-entrypoint.sh: sourcing /docker-entrypoint-initdb.d/10-create_user.sh Creating database role: raptor CREATE ROLE

2023-10-18 16:31:12.308 UTC [36] LOG: received fast shutdown request waiting for server to shut down....2023-10-18 16:31:12.316 UTC [36] LOG: aborting any active transactions 2023-10-18 16:31:12.319 UTC [36] LOG: background worker "logical replication launcher" (PID 43) exited with exit code 1 2023-10-18 16:31:12.321 UTC [38] LOG: shutting down 2023-10-18 16:31:12.384 UTC [36] LOG: database system is shut down done server stopped

PostgreSQL init process complete; ready for start up.

2023-10-18 16:31:12.436 UTC [1] LOG: starting PostgreSQL 12.16 on x86_64-pc-linux-musl, compiled by gcc (Alpine 12.2.1_git20220924-r10) 12.2.1 20220924, 64-bit 2023-10-18 16:31:12.437 UTC [1] LOG: listening on IPv4 address "0.0.0.0", port 5432 2023-10-18 16:31:12.438 UTC [1] LOG: listening on IPv6 address "::", port 5432 2023-10-18 16:31:12.453 UTC [1] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432" 2023-10-18 16:31:12.483 UTC [53] LOG: database system was shut down at 2023-10-18 16:31:12 UTC 2023-10-18 16:31:12.497 UTC [1] LOG: database system is ready to accept connections

and lastly rabbitmq:3-management-alpine sudo docker logs 6e889fbf822f 2023-10-18 16:31:11.126509+00:00 [notice] <0.44.0> Application syslog exited with reason: stopped 2023-10-18 16:31:11.131285+00:00 [notice] <0.230.0> Logging: switching to configured handler(s); following messages may not be visible in this log output 2023-10-18 16:31:11.131945+00:00 [notice] <0.230.0> Logging: configured log handlers are now ACTIVE 2023-10-18 16:31:11.300850+00:00 [info] <0.230.0> ra: starting system quorum_queues 2023-10-18 16:31:11.300916+00:00 [info] <0.230.0> starting Ra system: quorum_queues in directory: /var/lib/rabbitmq/mnesia/rabbit@6e889fbf822f/quorum/rabbit@6e889fbf822f 2023-10-18 16:31:11.350173+00:00 [info] <0.261.0> ra system 'quorum_queues' running pre init for 0 registered servers 2023-10-18 16:31:11.358130+00:00 [info] <0.262.0> ra: meta data store initialised for system quorum_queues. 0 record(s) recovered 2023-10-18 16:31:11.371143+00:00 [notice] <0.267.0> WAL: ra_log_wal init, open tbls: ra_log_open_mem_tables, closed tbls: ra_log_closed_mem_tables 2023-10-18 16:31:11.396623+00:00 [info] <0.230.0> ra: starting system coordination 2023-10-18 16:31:11.396676+00:00 [info] <0.230.0> starting Ra system: coordination in directory: /var/lib/rabbitmq/mnesia/rabbit@6e889fbf822f/coordination/rabbit@6e889fbf822f 2023-10-18 16:31:11.398034+00:00 [info] <0.274.0> ra system 'coordination' running pre init for 0 registered servers 2023-10-18 16:31:11.399055+00:00 [info] <0.275.0> ra: meta data store initialised for system coordination. 0 record(s) recovered 2023-10-18 16:31:11.399595+00:00 [notice] <0.280.0> WAL: ra_coordination_log_wal init, open tbls: ra_coordination_log_open_mem_tables, closed tbls: ra_coordination_log_closed_mem_tables 2023-10-18 16:31:11.415379+00:00 [info] <0.230.0> 2023-10-18 16:31:11.415379+00:00 [info] <0.230.0> Starting RabbitMQ 3.12.6 on Erlang 25.3.2.7 [jit] 2023-10-18 16:31:11.415379+00:00 [info] <0.230.0> Copyright (c) 2007-2023 VMware, Inc. or its affiliates. 2023-10-18 16:31:11.415379+00:00 [info] <0.230.0> Licensed under the MPL 2.0. Website: https://rabbitmq.com

RabbitMQ 3.12.6

########## Copyright (c) 2007-2023 VMware, Inc. or its affiliates.

########## Licensed under the MPL 2.0. Website: https://rabbitmq.com

Erlang: 25.3.2.7 [jit] TLS Library: OpenSSL - OpenSSL 3.1.3 19 Sep 2023 Release series support status: supported

Doc guides: https://rabbitmq.com/documentation.html Support: https://rabbitmq.com/contact.html Tutorials: https://rabbitmq.com/getstarted.html Monitoring: https://rabbitmq.com/monitoring.html

Logs:

Config file(s): /etc/rabbitmq/conf.d/10-defaults.conf

Starting broker...2023-10-18 16:31:11.424339+00:00 [info] <0.230.0> 2023-10-18 16:31:11.424339+00:00 [info] <0.230.0> node : rabbit@6e889fbf822f 2023-10-18 16:31:11.424339+00:00 [info] <0.230.0> home dir : /var/lib/rabbitmq 2023-10-18 16:31:11.424339+00:00 [info] <0.230.0> config file(s) : /etc/rabbitmq/conf.d/10-defaults.conf 2023-10-18 16:31:11.424339+00:00 [info] <0.230.0> cookie hash : 1M6Tk5O22JTobHrfno8rag== 2023-10-18 16:31:11.424339+00:00 [info] <0.230.0> log(s) : 2023-10-18 16:31:11.424339+00:00 [info] <0.230.0> data dir : /var/lib/rabbitmq/mnesia/rabbit@6e889fbf822f 2023-10-18 16:31:13.649484+00:00 [info] <0.230.0> Running boot step pre_boot defined by app rabbit 2023-10-18 16:31:13.649538+00:00 [info] <0.230.0> Running boot step rabbit_global_counters defined by app rabbit 2023-10-18 16:31:13.649845+00:00 [info] <0.230.0> Running boot step rabbit_osiris_metrics defined by app rabbit 2023-10-18 16:31:13.650206+00:00 [info] <0.230.0> Running boot step rabbit_core_metrics defined by app rabbit 2023-10-18 16:31:13.651779+00:00 [info] <0.230.0> Running boot step rabbit_alarm defined by app rabbit 2023-10-18 16:31:13.662641+00:00 [info] <0.299.0> Memory high watermark set to 1564 MiB (1640742912 bytes) of 3911 MiB (4101857280 bytes) total 2023-10-18 16:31:13.667461+00:00 [info] <0.301.0> Enabling free disk space monitoring (disk free space: 13633257472, total memory: 4101857280) 2023-10-18 16:31:13.667532+00:00 [info] <0.301.0> Disk free limit set to 50MB 2023-10-18 16:31:13.669464+00:00 [info] <0.230.0> Running boot step code_server_cache defined by app rabbit 2023-10-18 16:31:13.669561+00:00 [info] <0.230.0> Running boot step file_handle_cache defined by app rabbit 2023-10-18 16:31:13.669699+00:00 [info] <0.304.0> Limiting to approx 1048479 file handles (943629 sockets) 2023-10-18 16:31:13.669837+00:00 [info] <0.305.0> FHC read buffering: OFF 2023-10-18 16:31:13.669874+00:00 [info] <0.305.0> FHC write buffering: ON 2023-10-18 16:31:13.670656+00:00 [info] <0.230.0> Running boot step worker_pool defined by app rabbit 2023-10-18 16:31:13.670718+00:00 [info] <0.282.0> Will use 3 processes for default worker pool 2023-10-18 16:31:13.670744+00:00 [info] <0.282.0> Starting worker pool 'worker_pool' with 3 processes in it 2023-10-18 16:31:13.671370+00:00 [info] <0.230.0> Running boot step database defined by app rabbit 2023-10-18 16:31:13.672477+00:00 [info] <0.230.0> Node database directory at /var/lib/rabbitmq/mnesia/rabbit@6e889fbf822f is empty. Assuming we need to join an existing cluster or initialise from scratch... 2023-10-18 16:31:13.673395+00:00 [info] <0.230.0> Configured peer discovery backend: rabbit_peer_discovery_classic_config 2023-10-18 16:31:13.673475+00:00 [info] <0.230.0> Will try to lock with peer discovery backend rabbit_peer_discovery_classic_config 2023-10-18 16:31:13.673607+00:00 [info] <0.230.0> All discovered existing cluster peers: 2023-10-18 16:31:13.673623+00:00 [info] <0.230.0> Discovered no peer nodes to cluster with. Some discovery backends can filter nodes out based on a readiness criteria. Enabling debug logging might help troubleshoot. 2023-10-18 16:31:13.676039+00:00 [notice] <0.44.0> Application mnesia exited with reason: stopped 2023-10-18 16:31:13.888276+00:00 [info] <0.230.0> Waiting for Mnesia tables for 30000 ms, 9 retries left 2023-10-18 16:31:13.888501+00:00 [info] <0.230.0> Successfully synced tables from a peer 2023-10-18 16:31:13.888732+00:00 [notice] <0.283.0> Feature flags: attempt to enable stream_sac_coordinator_unblock_group... 2023-10-18 16:31:13.914059+00:00 [notice] <0.283.0> Feature flags: stream_sac_coordinator_unblock_group enabled 2023-10-18 16:31:13.914203+00:00 [notice] <0.283.0> Feature flags: attempt to enable restart_streams... 2023-10-18 16:31:13.945237+00:00 [notice] <0.283.0> Feature flags: restart_streams enabled 2023-10-18 16:31:13.945448+00:00 [info] <0.230.0> Waiting for Mnesia tables for 30000 ms, 9 retries left 2023-10-18 16:31:13.945502+00:00 [info] <0.230.0> Successfully synced tables from a peer 2023-10-18 16:31:13.976033+00:00 [info] <0.230.0> Waiting for Mnesia tables for 30000 ms, 9 retries left 2023-10-18 16:31:13.976356+00:00 [info] <0.230.0> Successfully synced tables from a peer 2023-10-18 16:31:13.976390+00:00 [info] <0.230.0> Peer discovery backend rabbit_peer_discovery_classic_config does not support registration, skipping registration. 2023-10-18 16:31:13.976415+00:00 [info] <0.230.0> Will try to unlock with peer discovery backend rabbit_peer_discovery_classic_config 2023-10-18 16:31:13.976580+00:00 [info] <0.230.0> Running boot step tracking_metadata_store defined by app rabbit 2023-10-18 16:31:13.976707+00:00 [info] <0.481.0> Setting up a table for connection tracking on this node: tracked_connection 2023-10-18 16:31:13.976834+00:00 [info] <0.481.0> Setting up a table for per-vhost connection counting on this node: tracked_connection_per_vhost 2023-10-18 16:31:13.976879+00:00 [info] <0.481.0> Setting up a table for per-user connection counting on this node: tracked_connection_per_user 2023-10-18 16:31:13.977320+00:00 [info] <0.481.0> Setting up a table for channel tracking on this node: tracked_channel 2023-10-18 16:31:13.977444+00:00 [info] <0.481.0> Setting up a table for channel tracking on this node: tracked_channel_per_user 2023-10-18 16:31:13.977493+00:00 [info] <0.230.0> Running boot step networking_metadata_store defined by app rabbit 2023-10-18 16:31:13.977534+00:00 [info] <0.230.0> Running boot step feature_flags defined by app rabbit 2023-10-18 16:31:13.978048+00:00 [info] <0.230.0> Running boot step codec_correctness_check defined by app rabbit 2023-10-18 16:31:13.978080+00:00 [info] <0.230.0> Running boot step external_infrastructure defined by app rabbit 2023-10-18 16:31:13.978107+00:00 [info] <0.230.0> Running boot step rabbit_event defined by app rabbit 2023-10-18 16:31:13.978359+00:00 [info] <0.230.0> Running boot step rabbit_registry defined by app rabbit 2023-10-18 16:31:13.978408+00:00 [info] <0.230.0> Running boot step rabbit_auth_mechanism_amqplain defined by app rabbit 2023-10-18 16:31:13.978624+00:00 [info] <0.230.0> Running boot step rabbit_auth_mechanism_cr_demo defined by app rabbit 2023-10-18 16:31:13.978667+00:00 [info] <0.230.0> Running boot step rabbit_auth_mechanism_plain defined by app rabbit 2023-10-18 16:31:13.978693+00:00 [info] <0.230.0> Running boot step rabbit_exchange_type_direct defined by app rabbit 2023-10-18 16:31:13.979046+00:00 [info] <0.230.0> Running boot step rabbit_exchange_type_fanout defined by app rabbit 2023-10-18 16:31:13.979141+00:00 [info] <0.230.0> Running boot step rabbit_exchange_type_headers defined by app rabbit 2023-10-18 16:31:13.979205+00:00 [info] <0.230.0> Running boot step rabbit_exchange_type_topic defined by app rabbit 2023-10-18 16:31:13.979231+00:00 [info] <0.230.0> Running boot step rabbit_mirror_queue_mode_all defined by app rabbit 2023-10-18 16:31:13.979259+00:00 [info] <0.230.0> Running boot step rabbit_mirror_queue_mode_exactly defined by app rabbit 2023-10-18 16:31:13.979286+00:00 [info] <0.230.0> Running boot step rabbit_mirror_queue_mode_nodes defined by app rabbit 2023-10-18 16:31:13.979313+00:00 [info] <0.230.0> Running boot step rabbit_priority_queue defined by app rabbit 2023-10-18 16:31:13.979329+00:00 [info] <0.230.0> Priority queues enabled, real BQ is rabbit_variable_queue 2023-10-18 16:31:13.979476+00:00 [info] <0.230.0> Running boot step rabbit_queue_location_client_local defined by app rabbit 2023-10-18 16:31:13.979553+00:00 [info] <0.230.0> Running boot step rabbit_queue_location_min_masters defined by app rabbit 2023-10-18 16:31:13.980043+00:00 [info] <0.230.0> Running boot step rabbit_queue_location_random defined by app rabbit 2023-10-18 16:31:13.980179+00:00 [info] <0.230.0> Running boot step kernel_ready defined by app rabbit 2023-10-18 16:31:13.980233+00:00 [info] <0.230.0> Running boot step rabbit_sysmon_minder defined by app rabbit 2023-10-18 16:31:13.980734+00:00 [info] <0.230.0> Running boot step rabbit_epmd_monitor defined by app rabbit 2023-10-18 16:31:13.983040+00:00 [info] <0.489.0> epmd monitor knows us, inter-node communication (distribution) port: 25672 2023-10-18 16:31:13.983327+00:00 [info] <0.230.0> Running boot step guid_generator defined by app rabbit 2023-10-18 16:31:13.990308+00:00 [info] <0.230.0> Running boot step rabbit_node_monitor defined by app rabbit 2023-10-18 16:31:13.991300+00:00 [info] <0.493.0> Starting rabbit_node_monitor 2023-10-18 16:31:13.991819+00:00 [info] <0.230.0> Running boot step delegate_sup defined by app rabbit 2023-10-18 16:31:13.993460+00:00 [info] <0.230.0> Running boot step rabbit_memory_monitor defined by app rabbit 2023-10-18 16:31:13.993667+00:00 [info] <0.230.0> Running boot step rabbit_fifo_dlx_sup defined by app rabbit 2023-10-18 16:31:13.994286+00:00 [info] <0.230.0> Running boot step core_initialized defined by app rabbit 2023-10-18 16:31:13.994316+00:00 [info] <0.230.0> Running boot step rabbit_channel_tracking_handler defined by app rabbit 2023-10-18 16:31:13.995049+00:00 [info] <0.230.0> Running boot step rabbit_connection_tracking_handler defined by app rabbit 2023-10-18 16:31:13.995092+00:00 [info] <0.230.0> Running boot step rabbit_definitions_hashing defined by app rabbit 2023-10-18 16:31:13.995136+00:00 [info] <0.230.0> Running boot step rabbit_exchange_parameters defined by app rabbit 2023-10-18 16:31:13.995343+00:00 [info] <0.230.0> Running boot step rabbit_mirror_queue_misc defined by app rabbit 2023-10-18 16:31:13.996094+00:00 [info] <0.230.0> Running boot step rabbit_policies defined by app rabbit 2023-10-18 16:31:13.996299+00:00 [info] <0.230.0> Running boot step rabbit_policy defined by app rabbit 2023-10-18 16:31:13.996344+00:00 [info] <0.230.0> Running boot step rabbit_queue_location_validator defined by app rabbit 2023-10-18 16:31:13.996371+00:00 [info] <0.230.0> Running boot step rabbit_quorum_memory_manager defined by app rabbit 2023-10-18 16:31:13.996397+00:00 [info] <0.230.0> Running boot step rabbit_stream_coordinator defined by app rabbit 2023-10-18 16:31:13.998446+00:00 [info] <0.230.0> Running boot step rabbit_vhost_limit defined by app rabbit 2023-10-18 16:31:13.998694+00:00 [info] <0.230.0> Running boot step rabbit_mgmt_reset_handler defined by app rabbitmq_management 2023-10-18 16:31:13.998725+00:00 [info] <0.230.0> Running boot step rabbit_mgmt_db_handler defined by app rabbitmq_management_agent 2023-10-18 16:31:13.998742+00:00 [info] <0.230.0> Management plugin: using rates mode 'basic' 2023-10-18 16:31:13.998884+00:00 [info] <0.230.0> Running boot step recovery defined by app rabbit 2023-10-18 16:31:14.000190+00:00 [info] <0.230.0> Running boot step empty_db_check defined by app rabbit 2023-10-18 16:31:14.000234+00:00 [info] <0.230.0> Will seed default virtual host and user... 2023-10-18 16:31:14.000335+00:00 [info] <0.230.0> Adding vhost '/' (description: 'Default virtual host', tags: []) 2023-10-18 16:31:14.009669+00:00 [info] <0.230.0> Inserted a virtual host record {vhost,<<"/">>,[], 2023-10-18 16:31:14.009669+00:00 [info] <0.230.0> #{description => 2023-10-18 16:31:14.009669+00:00 [info] <0.230.0> <<"Default virtual host">>, 2023-10-18 16:31:14.009669+00:00 [info] <0.230.0> tags => []}} 2023-10-18 16:31:14.065524+00:00 [info] <0.538.0> Making sure data directory '/var/lib/rabbitmq/mnesia/rabbit@6e889fbf822f/msg_stores/vhosts/628WB79CIFDYO9LJI6DKMI09L' for vhost '/' exists 2023-10-18 16:31:14.067728+00:00 [info] <0.538.0> Setting segment_entry_count for vhost '/' with 0 queues to '2048' 2023-10-18 16:31:14.078488+00:00 [info] <0.538.0> Starting message stores for vhost '/' 2023-10-18 16:31:14.078883+00:00 [info] <0.548.0> Message store "628WB79CIFDYO9LJI6DKMI09L/msg_store_transient": using rabbit_msg_store_ets_index to provide index 2023-10-18 16:31:14.084337+00:00 [info] <0.538.0> Started message store of type transient for vhost '/' 2023-10-18 16:31:14.084818+00:00 [info] <0.552.0> Message store "628WB79CIFDYO9LJI6DKMI09L/msg_store_persistent": using rabbit_msg_store_ets_index to provide index 2023-10-18 16:31:14.088115+00:00 [warning] <0.552.0> Message store "628WB79CIFDYO9LJI6DKMI09L/msg_store_persistent": rebuilding indices from scratch 2023-10-18 16:31:14.090962+00:00 [info] <0.538.0> Started message store of type persistent for vhost '/' 2023-10-18 16:31:14.091146+00:00 [info] <0.538.0> Recovering 0 queues of type rabbit_classic_queue took 22ms 2023-10-18 16:31:14.091310+00:00 [info] <0.538.0> Recovering 0 queues of type rabbit_quorum_queue took 0ms 2023-10-18 16:31:14.091359+00:00 [info] <0.538.0> Recovering 0 queues of type rabbit_stream_queue took 0ms 2023-10-18 16:31:14.100661+00:00 [info] <0.230.0> Created user 'guest' 2023-10-18 16:31:14.108824+00:00 [info] <0.230.0> Successfully set user tags for user 'guest' to [administrator] 2023-10-18 16:31:14.119001+00:00 [info] <0.230.0> Successfully set permissions for user 'guest' in virtual host '/' to '.', '.', '.' 2023-10-18 16:31:14.119090+00:00 [info] <0.230.0> Running boot step rabbit_observer_cli defined by app rabbit 2023-10-18 16:31:14.119259+00:00 [info] <0.230.0> Running boot step rabbit_looking_glass defined by app rabbit 2023-10-18 16:31:14.119420+00:00 [info] <0.230.0> Running boot step rabbit_core_metrics_gc defined by app rabbit 2023-10-18 16:31:14.119799+00:00 [info] <0.230.0> Running boot step background_gc defined by app rabbit 2023-10-18 16:31:14.119917+00:00 [info] <0.230.0> Running boot step routing_ready defined by app rabbit 2023-10-18 16:31:14.119933+00:00 [info] <0.230.0> Running boot step pre_flight defined by app rabbit 2023-10-18 16:31:14.120044+00:00 [info] <0.230.0> Running boot step notify_cluster defined by app rabbit 2023-10-18 16:31:14.120074+00:00 [info] <0.230.0> Running boot step networking defined by app rabbit 2023-10-18 16:31:14.120100+00:00 [info] <0.230.0> Running boot step definition_import_worker_pool defined by app rabbit 2023-10-18 16:31:14.120127+00:00 [info] <0.282.0> Starting worker pool 'definition_import_pool' with 3 processes in it 2023-10-18 16:31:14.120969+00:00 [info] <0.230.0> Running boot step cluster_name defined by app rabbit 2023-10-18 16:31:14.121050+00:00 [info] <0.230.0> Initialising internal cluster ID to 'rabbitmq-cluster-id-3k-Bt_HpzljlcbQUBZvArg' 2023-10-18 16:31:14.130246+00:00 [info] <0.230.0> Running boot step direct_client defined by app rabbit 2023-10-18 16:31:14.130659+00:00 [info] <0.230.0> Running boot step rabbit_maintenance_mode_state defined by app rabbit 2023-10-18 16:31:14.130690+00:00 [info] <0.230.0> Creating table rabbit_node_maintenance_states for maintenance mode status 2023-10-18 16:31:14.140706+00:00 [info] <0.230.0> Running boot step rabbit_management_load_definitions defined by app rabbitmq_management 2023-10-18 16:31:14.140962+00:00 [info] <0.591.0> Resetting node maintenance status 2023-10-18 16:31:14.155375+00:00 [info] <0.650.0> Management plugin: HTTP (non-TLS) listener started on port 15672 2023-10-18 16:31:14.155742+00:00 [info] <0.678.0> Statistics database started. 2023-10-18 16:31:14.155832+00:00 [info] <0.677.0> Starting worker pool 'management_worker_pool' with 3 processes in it 2023-10-18 16:31:14.167905+00:00 [info] <0.692.0> Prometheus metrics: HTTP (non-TLS) listener started on port 15692 2023-10-18 16:31:14.168078+00:00 [info] <0.591.0> Ready to start client connection listeners 2023-10-18 16:31:14.171451+00:00 [info] <0.736.0> started TCP listener on [::]:5672 completed with 4 plugins. 2023-10-18 16:31:14.231852+00:00 [info] <0.591.0> Server startup complete; 4 plugins started. 2023-10-18 16:31:14.231852+00:00 [info] <0.591.0> rabbitmq_prometheus 2023-10-18 16:31:14.231852+00:00 [info] <0.591.0> rabbitmq_management 2023-10-18 16:31:14.231852+00:00 [info] <0.591.0> rabbitmq_management_agent 2023-10-18 16:31:14.231852+00:00 [info] <0.591.0> * rabbitmq_web_dispatch 2023-10-18 16:31:14.342506+00:00 [info] <0.9.0> Time to start RabbitMQ: 7518027 us

I hope all these informations are going to help you!

AnarulHossain commented 10 months ago

I faced the similar kind of problem. Trying different processes but none working for me , finally i found a silly solution. First completely uninstall docker and iris associate file from system. Then reinstall docker. Before install iris (docker-compose build) while change .env file keep in mind the key and password will be without special character & not too long . It seems very silly but worked for me . Hopefully your problem will be solved.