ckan / ckan-docker

Scripts and images to run CKAN using Docker Compose
104 stars 197 forks source link

Nginx fails to start after starting a container, resulting in a "-Internal Server Error:" message. #147

Closed ivanDvernitsky closed 6 months ago

ivanDvernitsky commented 6 months ago

I've been trying to get CKAN running on my MAC OS with M1 chip. After doing docker compose build and then docker compose up -d got CKAN to work. But on the next attempts to start the container it fails. In particular when starting the container with 'docker compose up' or ''docker compose up -d' - NGINX container exits right away. Sometimes it helps to just manually start it with Docker Desktop, but most of the times I can't access CKAN and see "-Internal Server Error:" message in the browser.

Trying to start container group in the Docker desktop it takes forever to start for NGINX, and it never does.

In the development mode NGINX also exits right away and I get a 404 not found message from it.

If I manage start NGINX through Docker desktop, Datapusher container comes up as unhealthy.

dd670ab3b287 ckan/ckan-base-datapusher:0.0.20 "sh -c 'uwsgi --plug…" 18 hours ago Up 2 minutes (unhealthy)

Here are the log records:

redis-1       | 1:C 07 May 2024 18:29:41.760 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
redis-1       | 1:C 07 May 2024 18:29:41.760 # Redis version=6.2.14, bits=64, commit=00000000, modified=0, pid=1, just started
redis-1       | 1:C 07 May 2024 18:29:41.760 # Warning: no config file specified, using the default config. In order to specify a config file use redis-server /path/to/redis.conf
redis-1       | 1:M 07 May 2024 18:29:41.761 * monotonic clock: POSIX clock_gettime
redis-1       | 1:M 07 May 2024 18:29:41.762 * Running mode=standalone, port=6379.
redis-1       | 1:M 07 May 2024 18:29:41.762 # Server initialized
redis-1       | 1:M 07 May 2024 18:29:41.764 * Loading RDB produced by version 6.2.14
redis-1       | 1:M 07 May 2024 18:29:41.764 * RDB age 68 seconds
redis-1       | 1:M 07 May 2024 18:29:41.764 * RDB memory usage when created 0.81 Mb
redis-1       | 1:M 07 May 2024 18:29:41.764 # Done loading RDB, keys loaded: 0, keys expired: 0.
redis-1       | 1:M 07 May 2024 18:29:41.764 * DB loaded from disk: 0.001 seconds
redis-1       | 1:M 07 May 2024 18:29:41.764 * Ready to accept connections
datapusher-1  | [uWSGI] getting INI configuration from /srv/app/datapusher-uwsgi.ini
datapusher-1  | *** Starting uWSGI 2.0.19.1 (64bit) on [Tue May  7 18:29:41 2024] ***
datapusher-1  | compiled with version: 10.3.1 20210424 on 05 April 2021 18:18:03
datapusher-1  | os: Linux-6.6.22-linuxkit #1 SMP Fri Mar 29 12:21:27 UTC 2024
datapusher-1  | nodename: dd670ab3b287
datapusher-1  | machine: x86_64
datapusher-1  | clock source: unix
datapusher-1  | pcre jit disabled
datapusher-1  | detected number of CPU cores: 8
datapusher-1  | current working directory: /srv/app
datapusher-1  | writing pidfile to /tmp/datapusher-uwsgi.pid
datapusher-1  | detected binary path: /usr/sbin/uwsgi
datapusher-1  | your memory page size is 4096 bytes
datapusher-1  |  *** WARNING: you have enabled harakiri without post buffering. Slow upload could be rejected on post-unbuffered webservers *** 
datapusher-1  | detected max file descriptor number: 1048576
datapusher-1  | lock engine: pthread robust mutexes
datapusher-1  | thunder lock: disabled (you can enable it with --thunder-lock)
datapusher-1  | uWSGI http bound on 0.0.0.0:8800 fd 7
datapusher-1  | uwsgi socket 0 bound to UNIX address /tmp/uwsgi.sock fd 10
datapusher-1  | Python version: 3.9.16 (main, Dec 10 2022, 13:47:19)  [GCC 10.3.1 20210424]
datapusher-1  | Python main interpreter initialized at 0x7fffff74cc00
datapusher-1  | python threads support enabled
datapusher-1  | your server socket listen backlog is limited to 100 connections
datapusher-1  | your mercy for graceful operations on workers is 60 seconds
datapusher-1  | mapped 203184 bytes (198 KB) for 1 cores
datapusher-1  | *** Operational MODE: single process ***
datapusher-1  | /usr/lib/python3.9/site-packages/tzlocal/unix.py:183: UserWarning: Can not find any timezone configuration, defaulting to UTC.
datapusher-1  |   warnings.warn("Can not find any timezone configuration, defaulting to UTC.")
datapusher-1  | WSGI app 0 (mountpoint='') ready in 2 seconds on interpreter 0x7fffff74cc00 pid: 1 (default app)
datapusher-1  | *** uWSGI is running in multiple interpreter mode ***
datapusher-1  | spawned uWSGI master process (pid: 1)
datapusher-1  | spawned uWSGI worker 1 (pid: 8, cores: 1)
datapusher-1  | spawned uWSGI http 1 (pid: 9)
db-1          | 
db-1          | PostgreSQL Database directory appears to contain a database; Skipping initialization
db-1          | 
db-1          | 2024-05-07 18:29:41.723 UTC [1] LOG:  starting PostgreSQL 12.18 on aarch64-unknown-linux-musl, compiled by gcc (Alpine 13.2.1_git20231014) 13.2.1 20231014, 64-bit
db-1          | 2024-05-07 18:29:41.723 UTC [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
db-1          | 2024-05-07 18:29:41.723 UTC [1] LOG:  listening on IPv6 address "::", port 5432
db-1          | 2024-05-07 18:29:41.725 UTC [1] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
db-1          | 2024-05-07 18:29:41.739 UTC [21] LOG:  database system was shut down at 2024-05-07 18:28:33 UTC
db-1          | 2024-05-07 18:29:41.748 UTC [1] LOG:  database system is ready to accept connections
ckan-1        | Setting a temporary value for ckan.datapusher.api_token
solr-1        | Executing /opt/solr/docker/scripts/solr-precreate ckan /opt/solr/server/solr/configsets/ckan
solr-1        | Executing /opt/solr/docker/scripts/precreate-core ckan /opt/solr/server/solr/configsets/ckan
solr-1        | Core ckan already exists
solr-1        | Starting Solr
solr-1        | Java 17 detected. Enabled workaround for SOLR-16463
solr-1        | [0.011s][warning][pagesize] UseLargePages disabled, no large pages configured and available on the system.
solr-1        | CompileCommand: exclude com/github/benmanes/caffeine/cache/BoundedLocalCache.put bool exclude = true
solr-1        | WARNING: A command line option has enabled the Security Manager
solr-1        | WARNING: The Security Manager is deprecated and will be removed in a future release
solr-1        | 2024-05-07 18:29:46.336 INFO  (main) [] o.e.j.s.Server jetty-10.0.13; built: 2022-12-07T20:13:20.134Z; git: 1c2636ea05c0ca8de1ffd6ca7f3a98ac084c766d; jvm 17.0.7+7
solr-1        | 2024-05-07 18:29:47.160 WARN  (main) [] o.e.j.u.DeprecationWarning Using @Deprecated Class org.eclipse.jetty.servlet.listener.ELContextCleaner
solr-1        | 2024-05-07 18:29:47.359 INFO  (main) [] o.a.s.s.CoreContainerProvider Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
solr-1        | 2024-05-07 18:29:47.371 INFO  (main) [] o.a.s.s.CoreContainerProvider  ___      _       Welcome to Apache Solr™ version 9.2.1
solr-1        | 2024-05-07 18:29:47.371 INFO  (main) [] o.a.s.s.CoreContainerProvider / __| ___| |_ _   Starting in standalone mode on port 8983
solr-1        | 2024-05-07 18:29:47.372 INFO  (main) [] o.a.s.s.CoreContainerProvider \__ \/ _ \ | '_|  Install dir: /opt/solr-9.2.1
solr-1        | 2024-05-07 18:29:47.373 INFO  (main) [] o.a.s.s.CoreContainerProvider |___/\___/_|_|    Start time: 2024-05-07T18:29:47.373055466Z
solr-1        | 2024-05-07 18:29:47.380 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_7.log
solr-1        | 2024-05-07 18:29:47.388 INFO  (main) [] o.a.s.s.CoreContainerProvider Solr Home: /var/solr/data (source: system property: solr.solr.home)
solr-1        | 2024-05-07 18:29:47.391 INFO  (main) [] o.a.s.c.SolrXmlConfig solr.xml not found in SOLR_HOME, using built-in default
solr-1        | 2024-05-07 18:29:47.391 INFO  (main) [] o.a.s.c.SolrXmlConfig Loading solr.xml from /opt/solr-9.2.1/server/solr/solr.xml
solr-1        | 2024-05-07 18:29:47.544 INFO  (main) [] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/opt/solr-9.2.1/lib]
solr-1        | 2024-05-07 18:29:49.526 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.
solr-1        | 2024-05-07 18:29:49.533 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
solr-1        | 2024-05-07 18:29:49.973 INFO  (main) [] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /var/solr/data
solr-1        | 2024-05-07 18:29:49.975 INFO  (main) [] o.a.s.c.CorePropertiesLocator Cores are: [ckan]
solr-1        | 2024-05-07 18:29:50.122 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.4.0
solr-1        | 2024-05-07 18:29:50.424 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.s.IndexSchema Schema name=ckan-2.10
solr-1        | 2024-05-07 18:29:50.603 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.s.IndexSchema Loaded schema ckan-2.10/1.6 with uniqueid field index_id
solr-1        | 2024-05-07 18:29:50.625 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.c.CoreContainer Creating SolrCore 'ckan' using configuration from instancedir /var/solr/data/ckan, trusted=true
solr-1        | 2024-05-07 18:29:50.680 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.c.SolrCore Opening new SolrCore at [/var/solr/data/ckan], dataDir=[/var/solr/data/ckan/data/]
solr-1        | 2024-05-07 18:29:51.054 INFO  (main) [] o.a.s.j.SolrRequestAuthorizer Creating a new SolrRequestAuthorizer
solr-1        | 2024-05-07 18:29:51.763 INFO  (main) [] o.e.j.s.h.ContextHandler Started o.e.j.w.WebAppContext@3f093abe{solr-jetty-context.xml,/solr,file:///opt/solr-9.2.1/server/solr-webapp/webapp/,AVAILABLE}{/opt/solr-9.2.1/server/solr-webapp/webapp}
solr-1        | 2024-05-07 18:29:51.792 INFO  (main) [] o.e.j.s.RequestLogWriter Opened /var/solr/logs/2024_05_07.request.log
solr-1        | 2024-05-07 18:29:51.800 INFO  (main) [] o.e.j.s.AbstractConnector Started ServerConnector@45c8d09f{HTTP/1.1, (http/1.1, h2c)}{0.0.0.0:8983}
solr-1        | 2024-05-07 18:29:51.803 INFO  (main) [] o.e.j.s.Server Started Server@38be305c{STARTING}[10.0.13,sto=0] @9071ms
solr-1        | 2024-05-07 18:29:52.104 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.j.SolrRequestAuthorizer Creating a new SolrRequestAuthorizer
solr-1        | 2024-05-07 18:29:52.135 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
solr-1        | 2024-05-07 18:29:52.135 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
solr-1        | 2024-05-07 18:29:52.155 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; 
solr-1        | 2024-05-07 18:29:52.156 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.u.CommitTracker Soft AutoCommit: disabled
solr-1        | 2024-05-07 18:29:52.254 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /var/solr/data/ckan/conf
solr-1        | 2024-05-07 18:29:52.290 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] 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}
solr-1        | 2024-05-07 18:29:52.307 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
solr-1        | 2024-05-07 18:29:52.312 INFO  (coreLoadExecutor-10-thread-1) [ x:ckan] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1798419610140147712
solr-1        | 2024-05-07 18:29:52.316 INFO  (searcherExecutor-12-thread-1-processing-ckan) [ x:ckan] o.a.s.c.QuerySenderListener QuerySenderListener done.
solr-1        | 2024-05-07 18:29:52.316 INFO  (searcherExecutor-12-thread-1-processing-ckan) [ x:ckan] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
solr-1        | 2024-05-07 18:29:52.329 INFO  (searcherExecutor-12-thread-1-processing-ckan) [ x:ckan] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
solr-1        | 2024-05-07 18:30:14.861 INFO  (qtp1663888181-21) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/select params={q=*:*&rows=1&wt=json} hits=0 status=0 QTime=34
solr-1        | 2024-05-07 18:30:14.883 INFO  (qtp1663888181-19) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/schema params={wt=schema.xml} status=0 QTime=9
ckan-1        | [prerun] Initializing or upgrading db - start
ckan-1        | Traceback (most recent call last):
ckan-1        |   File "/srv/app/prerun.py", line 102, in init_db
ckan-1        |     subprocess.check_output(db_command, stderr=subprocess.STDOUT)
ckan-1        |   File "/usr/lib/python3.10/subprocess.py", line 421, in check_output
ckan-1        |     return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,
ckan-1        |   File "/usr/lib/python3.10/subprocess.py", line 526, in run
ckan-1        |     raise CalledProcessError(retcode, process.args,
ckan-1        | subprocess.CalledProcessError: Command '['ckan', '-c', '/srv/app/ckan.ini', 'db', 'init']' returned non-zero exit status 1.
ckan-1        | 
ckan-1        | During handling of the above exception, another exception occurred:
ckan-1        | 
ckan-1        | Traceback (most recent call last):
ckan-1        |   File "/srv/app/prerun.py", line 215, in <module>
ckan-1        |     init_db()
ckan-1        |   File "/srv/app/prerun.py", line 105, in init_db
ckan-1        |     if "OperationalError" in e.output:
ckan-1        | TypeError: a bytes-like object is required, not 'str'
ckan-1        | /srv/app/start_ckan.sh: Running init file /docker-entrypoint.d/01_setup_datapusher.sh
ckan-1        | Set up ckan.datapusher.api_token in the CKAN config file
ckan-1        | 2024-05-07 18:30:15,987 INFO  [ckan.cli] Using configuration file /srv/app/ckan.ini
ckan-1        | 2024-05-07 18:30:15,988 INFO  [ckan.config.environment] Loading static files from public
solr-1        | 2024-05-07 18:30:16.257 INFO  (qtp1663888181-18) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/select params={q=*:*&rows=1&wt=json} hits=0 status=0 QTime=13
solr-1        | 2024-05-07 18:30:16.265 INFO  (qtp1663888181-24) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/schema params={wt=schema.xml} status=0 QTime=1
ckan-1        | 2024-05-07 18:30:16,278 INFO  [ckan.config.environment] Loading templates from /srv/app/src/ckan/ckan/templates
ckan-1        | Traceback (most recent call last):
ckan-1        |   File "/usr/bin/ckan", line 8, in <module>
ckan-1        |     sys.exit(ckan())
ckan-1        |   File "/usr/lib/python3.10/site-packages/click/core.py", line 1130, in __call__
ckan-1        |     return self.main(*args, **kwargs)
ckan-1        |   File "/usr/lib/python3.10/site-packages/click/core.py", line 1054, in main
ckan-1        |     with self.make_context(prog_name, args, **extra) as ctx:
ckan-1        |   File "/usr/lib/python3.10/site-packages/click/core.py", line 920, in make_context
ckan-1        |     self.parse_args(ctx, args)
ckan-1        |   File "/srv/app/src/ckan/ckan/cli/cli.py", line 121, in parse_args
ckan-1        |     result = super().parse_args(ctx, args)
ckan-1        |   File "/usr/lib/python3.10/site-packages/click/core.py", line 1613, in parse_args
ckan-1        |     rest = super().parse_args(ctx, args)
ckan-1        |   File "/usr/lib/python3.10/site-packages/click/core.py", line 1378, in parse_args
ckan-1        |     value, args = param.handle_parse_result(ctx, opts, args)
ckan-1        |   File "/usr/lib/python3.10/site-packages/click/core.py", line 2360, in handle_parse_result
ckan-1        |     value = self.process_value(ctx, value)
ckan-1        |   File "/usr/lib/python3.10/site-packages/click/core.py", line 2322, in process_value
ckan-1        |     value = self.callback(ctx, self, value)
ckan-1        |   File "/srv/app/src/ckan/ckan/cli/cli.py", line 131, in _init_ckan_config
ckan-1        |     _add_ctx_object(ctx, value)
ckan-1        |   File "/srv/app/src/ckan/ckan/cli/cli.py", line 140, in _add_ctx_object
ckan-1        |     ctx.obj = CtxObject(path)
ckan-1        |   File "/srv/app/src/ckan/ckan/cli/cli.py", line 57, in __init__
ckan-1        |     self.app = make_app(raw_config)
ckan-1        |   File "/srv/app/src/ckan/ckan/config/middleware/__init__.py", line 27, in make_app
ckan-1        |     load_environment(conf)
ckan-1        |   File "/srv/app/src/ckan/ckan/config/environment.py", line 69, in load_environment
ckan-1        |     p.load_all()
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 222, in load_all
ckan-1        |     load(*plugins)
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 238, in load
ckan-1        |     service = _get_service(plugin)
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 346, in _get_service
ckan-1        |     raise PluginNotFoundException(plugin_name)
ckan-1        | ckan.plugins.core.PluginNotFoundException: testEXTENT
ckan-1        | /srv/app/start_ckan.sh: Ignoring /docker-entrypoint.d/README.md (not an sh or py file)
ckan-1        | *** Starting uWSGI 2.0.21 (64bit) on [Tue May  7 18:30:17 2024] ***
ckan-1        | compiled with version: 12.2.1 20220924 on 09 November 2022 12:47:28
ckan-1        | os: Linux-6.6.22-linuxkit #1 SMP Fri Mar 29 12:21:27 UTC 2024
ckan-1        | nodename: 7a25eb70d4df
ckan-1        | machine: x86_64
ckan-1        | clock source: unix
ckan-1        | pcre jit disabled
ckan-1        | detected number of CPU cores: 8
ckan-1        | current working directory: /srv/app
ckan-1        | detected binary path: /usr/sbin/uwsgi
ckan-1        | uWSGI running as root, you can use --uid/--gid/--chroot options
ckan-1        | setgid() to 92
ckan-1        | setuid() to 92
ckan-1        | your memory page size is 4096 bytes
ckan-1        |  *** WARNING: you have enabled harakiri without post buffering. Slow upload could be rejected on post-unbuffered webservers *** 
ckan-1        | detected max file descriptor number: 1048576
ckan-1        | lock engine: pthread robust mutexes
ckan-1        | thunder lock: disabled (you can enable it with --thunder-lock)
ckan-1        | uWSGI http bound on 0.0.0.0:5000 fd 7
ckan-1        | uwsgi socket 0 bound to UNIX address /tmp/uwsgi.sock fd 10
ckan-1        | Python version: 3.10.13 (main, Aug 26 2023, 11:33:35) [GCC 12.2.1 20220924]
ckan-1        | Python main interpreter initialized at 0x7fffff749c10
ckan-1        | python threads support enabled
ckan-1        | your server socket listen backlog is limited to 100 connections
ckan-1        | your mercy for graceful operations on workers is 60 seconds
ckan-1        | mapped 304776 bytes (297 KB) for 2 cores
ckan-1        | *** Operational MODE: preforking ***
ckan-1        | *** uWSGI is running in multiple interpreter mode ***
ckan-1        | spawned uWSGI master process (pid: 17)
ckan-1        | spawned uWSGI worker 1 (pid: 18, cores: 1)
ckan-1        | spawned uWSGI worker 2 (pid: 19, cores: 1)
ckan-1        | spawned uWSGI http 1 (pid: 20)
ckan-1        | 2024-05-07 18:30:17,712 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.
ckan-1        | 2024-05-07 18:30:17,713 WARN No file matches via include "/etc/supervisord.d/*.conf"
ckan-1        | Unlinking stale socket /tmp/supervisor.sock
ckan-1        | 2024-05-07 18:30:18,022 INFO RPC interface 'supervisor' initialized
ckan-1        | 2024-05-07 18:30:18,022 CRIT Server 'unix_http_server' running without any HTTP authentication checking
ckan-1        | 2024-05-07 18:30:18,022 INFO supervisord started with pid 16
ckan-1        | 2024-05-07 18:30:18,286 INFO  [ckan.config.environment] Loading static files from public
ckan-1        | 2024-05-07 18:30:18,286 INFO  [ckan.config.environment] Loading static files from public
solr-1        | 2024-05-07 18:30:18.555 INFO  (qtp1663888181-25) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/select params={q=*:*&rows=1&wt=json} hits=0 status=0 QTime=2
solr-1        | 2024-05-07 18:30:18.555 INFO  (qtp1663888181-27) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/select params={q=*:*&rows=1&wt=json} hits=0 status=0 QTime=3
solr-1        | 2024-05-07 18:30:18.569 INFO  (qtp1663888181-24) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/schema params={wt=schema.xml} status=0 QTime=1
solr-1        | 2024-05-07 18:30:18.569 INFO  (qtp1663888181-21) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/schema params={wt=schema.xml} status=0 QTime=2
ckan-1        | 2024-05-07 18:30:18,594 INFO  [ckan.config.environment] Loading templates from /srv/app/src/ckan/ckan/templates
ckan-1        | 2024-05-07 18:30:18,594 INFO  [ckan.config.environment] Loading templates from /srv/app/src/ckan/ckan/templates
ckan-1        | Traceback (most recent call last):
ckan-1        |   File "/srv/app/./wsgi.py", line 20, in <module>
ckan-1        |     application = make_app(config)
ckan-1        |   File "/srv/app/src/ckan/ckan/config/middleware/__init__.py", line 27, in make_app
ckan-1        |     load_environment(conf)
ckan-1        |   File "/srv/app/src/ckan/ckan/config/environment.py", line 69, in load_environment
ckan-1        |     p.load_all()
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 222, in load_all
ckan-1        | Traceback (most recent call last):
ckan-1        |   File "/srv/app/./wsgi.py", line 20, in <module>
ckan-1        |     load(*plugins)
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 238, in load
ckan-1        |     service = _get_service(plugin)
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 346, in _get_service
ckan-1        |     raise PluginNotFoundException(plugin_name)
ckan-1        | ckan.plugins.core.PluginNotFoundException: testEXTENT
ckan-1        | unable to load app 0 (mountpoint='') (callable not found or import error)
ckan-1        |     application = make_app(config)
ckan-1        |   File "/srv/app/src/ckan/ckan/config/middleware/__init__.py", line 27, in make_app
ckan-1        |     load_environment(conf)
ckan-1        |   File "/srv/app/src/ckan/ckan/config/environment.py", line 69, in load_environment
ckan-1        |     p.load_all()
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 222, in load_all
ckan-1        |     load(*plugins)
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 238, in load
ckan-1        |     service = _get_service(plugin)
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 346, in _get_service
ckan-1        |     raise PluginNotFoundException(plugin_name)
ckan-1        | ckan.plugins.core.PluginNotFoundException: testEXTENT
ckan-1        | unable to load app 0 (mountpoint='') (callable not found or import error)
ckan-1        | 2024-05-07 18:30:18,743 INFO  [ckan.config.environment] Loading static files from public
ckan-1        | 2024-05-07 18:30:18,743 INFO  [ckan.config.environment] Loading static files from public
solr-1        | 2024-05-07 18:30:18.909 INFO  (qtp1663888181-25) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/select params={q=*:*&rows=1&wt=json} hits=0 status=0 QTime=1
solr-1        | 2024-05-07 18:30:18.909 INFO  (qtp1663888181-27) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/select params={q=*:*&rows=1&wt=json} hits=0 status=0 QTime=1
solr-1        | 2024-05-07 18:30:18.920 INFO  (qtp1663888181-24) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/schema params={wt=schema.xml} status=0 QTime=3
solr-1        | 2024-05-07 18:30:18.920 INFO  (qtp1663888181-21) [ x:ckan] o.a.s.c.S.Request webapp=/solr path=/schema params={wt=schema.xml} status=0 QTime=3
ckan-1        | 2024-05-07 18:30:18,928 INFO  [ckan.config.environment] Loading templates from /srv/app/src/ckan/ckan/templates
ckan-1        | 2024-05-07 18:30:18,928 INFO  [ckan.config.environment] Loading templates from /srv/app/src/ckan/ckan/templates
ckan-1        | Traceback (most recent call last):
ckan-1        | Traceback (most recent call last):
ckan-1        |   File "/srv/app/wsgi.py", line 20, in <module>
ckan-1        |   File "/srv/app/wsgi.py", line 20, in <module>
ckan-1        |     application = make_app(config)
ckan-1        |     application = make_app(config)
ckan-1        |   File "/srv/app/src/ckan/ckan/config/middleware/__init__.py", line 27, in make_app
ckan-1        |   File "/srv/app/src/ckan/ckan/config/middleware/__init__.py", line 27, in make_app
ckan-1        |     load_environment(conf)
ckan-1        |     load_environment(conf)
ckan-1        |   File "/srv/app/src/ckan/ckan/config/environment.py", line 69, in load_environment
ckan-1        |   File "/srv/app/src/ckan/ckan/config/environment.py", line 69, in load_environment
ckan-1        |     p.load_all()
ckan-1        |     p.load_all()
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 222, in load_all
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 222, in load_all
ckan-1        |     load(*plugins)
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 238, in load
ckan-1        |     load(*plugins)
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 238, in load
ckan-1        |     service = _get_service(plugin)
ckan-1        |     service = _get_service(plugin)
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 346, in _get_service
ckan-1        |   File "/srv/app/src/ckan/ckan/plugins/core.py", line 346, in _get_service
ckan-1        |     raise PluginNotFoundException(plugin_name)
ckan-1        |     raise PluginNotFoundException(plugin_name)
ckan-1        | ckan.plugins.core.PluginNotFoundException: testEXTENT
ckan-1        | ckan.plugins.core.PluginNotFoundException: testEXTENT
ckan-1        | unable to load app 0 (mountpoint='') (callable not found or import error)
ckan-1        | unable to load app 0 (mountpoint='') (callable not found or import error)
ckan-1        | *** no app loaded. going in full dynamic mode ***
ckan-1        | *** no app loaded. going in full dynamic mode ***
kowh-ai commented 6 months ago

I'm unable to recreate this error. Seems a couple of issues going on here.

The first one is the ckan, -c /srv/app/ckan.ini db init command from the prerun.py setup when the CKAN container starts. Are you able to attach to the running CKAN container and execute the ckan-c /srv/app/ckan.ini db init command from the shell? It should error but may give more information

ivanDvernitsky commented 6 months ago

I'm closing this issue because I've found a stabla way to run CKAN in a development mode