debian 12 bookworm installation issues

[zenny]

@rkrenn

I tried to install in vanilla debian 12 from your script as discussed in #368, and noticed following warnings and errors in the install.log file:

[ESC[1;34mINFOESC[m] ESC[1;32mBUILD SUCCESSESC[m
[ESC[1;34mINFOESC[m] ESC[1m------------------------------------------------------------------------ESC[m
[ESC[1;34mINFOESC[m] Total time:  18:08 min
[ESC[1;34mINFOESC[m] Finished at: 2025-05-30T18:24:42+02:00
[ESC[1;34mINFOESC[m] ESC[1m------------------------------------------------------------------------ESC[m
ESC[0mESC[0m[ESC[1;34mINFOESC[m] Scanning for projects...
[ESC[1;33mWARNINGESC[m]
[ESC[1;33mWARNINGESC[m] Some problems were encountered while building the effective model for org.phoenixctms.ctsms:ctsms-core:jar:1.8.1
[ESC[1;33mWARNINGESC[m] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: org.seleniumhq.selenium:selenium-chrome-driver:jar -> duplicate declaration of version (?) @ line 166, column 15
[ESC[1;33mWARNINGESC[m]
[ESC[1;33mWARNINGESC[m] It is highly recommended to fix these problems because they threaten the stability of your build.
[ESC[1;33mWARNINGESC[m]
[ESC[1;33mWARNINGESC[m] For this reason, future Maven versions might no longer support building such malformed projects.
[ESC[1;33mWARNINGESC[m]

Permission denied error:

[ESC[1;34mINFOESC[m]  Executed script: /home/phoenix/ctsms/core/db/schema-create.sql
[ESC[1;34mINFOESC[m]  0  SQL statements executed
[ESC[1;34mINFOESC[m]  Failures: 0
[ESC[1;34mINFOESC[m]  Successes: 0
[ESC[1;34mINFOESC[m] ESC[1m------------------------------------------------------------------------ESC[m
[ESC[1;34mINFOESC[m] ESC[1;32mBUILD SUCCESSESC[m
[ESC[1;34mINFOESC[m] ESC[1m------------------------------------------------------------------------ESC[m
[ESC[1;34mINFOESC[m] Total time:  19.503 s
[ESC[1;34mINFOESC[m] Finished at: 2025-05-30T18:25:03+02:00
[ESC[1;34mINFOESC[m] ESC[1m------------------------------------------------------------------------ESC[m
ESC[0mESC[0mcould not change directory to "/home/phoenix/ctsms": Permission denied
CREATE ROLE
could not change directory to "/home/phoenix/ctsms": Permission denied
CREATE DATABASE
could not change directory to "/home/phoenix/ctsms": Permission denied
GRANT
could not change directory to "/home/phoenix/ctsms": Permission denied
ALTER DATABASE
could not change directory to "/home/phoenix/ctsms": Permission denied
CREATE EXTENSION
CREATE FUNCTION
could not change directory to "/home/phoenix/ctsms": Permission denied
CREATE TABLE
CREATE TABLE
CREATE TABLE
CREATE TABLE
...
ALTER TABLE
...
CREATE SEQUENCE
...
could not change directory to "/home/phoenix/ctsms": Permission denied
CREATE INDEX
...
NOTICE:  identifier "ecrf_field_value_list_entry_fk_visit_fk_ecrf_field_fk_index_version" will be truncated to "ecrf_field_value_list_entry_fk_visit_fk_ecrf_field_fk_index_ver"
CREATE INDEX
NOTICE:  identifier "ecrf_field_status_entry_list_entry_fk_ecrf_field_fk_index_status_fk_queue" will be truncated to "ecrf_field_status_entry_list_entry_fk_ecrf_field_fk_index_statu"
...
could not change directory to "/home/phoenix/ctsms": Permission denied
CREATE FUNCTION
CREATE FUNCTION
NOTICE:  database schema version set to 010801099
 set_database_version
----------------------

(1 row)

...
30.05.2025 18:58:13 com.mchange.v2.resourcepool.BasicResourcePool [com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0]
WARN  com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@19c12560 -- Acquisition Attempt Failed!!! Clearing pending acquires. While trying to acquire a needed new resource, we failed t
o succeed more than the maximum number of allowed acquisition attempts (30). Last acquisition attempt exception:
org.postgresql.util.PSQLException: FATAL: password authentication failed for user "ctsms"
        at org.postgresql.core.v3.ConnectionFactoryImpl.doAuthentication(ConnectionFactoryImpl.java:646)
        at org.postgresql.core.v3.ConnectionFactoryImpl.tryConnect(ConnectionFactoryImpl.java:180)
        at org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl(ConnectionFactoryImpl.java:235)
        at org.postgresql.core.ConnectionFactory.openConnection(ConnectionFactory.java:49)
        at org.postgresql.jdbc.PgConnection.<init>(PgConnection.java:223)
        at org.postgresql.Driver.makeConnection(Driver.java:402)
        at org.postgresql.Driver.connect(Driver.java:261)
        at com.mchange.v2.c3p0.DriverManagerDataSource.getConnection(DriverManagerDataSource.java:135)
        at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:182)
        at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:171)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:137)
        at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1014)
        at com.mchange.v2.resourcepool.BasicResourcePool.access$800(BasicResourcePool.java:32)
        at com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask.run(BasicResourcePool.java:1810)
        at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
2025-05-30 16:58:13: ERROR OCCURRED
...
2025-05-30 18:58:13: starting...
CTSMS_PROPERTIES: /ctsms/properties
CTSMS_JAVA: /ctsms/java
30.05.2025 18:58:38 com.mchange.v2.async.ThreadPoolAsynchronousRunner [Timer-0]
WARN  com.mchange.v2.async.ThreadPoolAsynchronousRunner$DeadlockDetector@729f6c5 -- APPARENT DEADLOCK!!! Creating emergency threads for unassigned pending tasks!
30.05.2025 18:58:38 com.mchange.v2.async.ThreadPoolAsynchronousRunner [Timer-0]
WARN  com.mchange.v2.async.ThreadPoolAsynchronousRunner$DeadlockDetector@729f6c5 -- APPARENT DEADLOCK!!! Complete Status:
        Managed Threads: 3
        Active Threads: 3
        Active Tasks:
                com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@79d1feb2 (com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0)
                com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@786ce808 (com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1)
                com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@28fced24 (com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2)
        Pending Tasks:
                com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@a09a55b
                com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@7c5beec6
                com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@636865ed
                com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@2cb23169
                com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@6bd66ad9
                com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@485894bb
                com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@683fc750
Pool thread stack traces:
        Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0,5,main]
                java.base@17.0.15/java.lang.Thread.sleep(Native Method)
                app//com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask.run(BasicResourcePool.java:1805)
                app//com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
        Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1,5,main]
                java.base@17.0.15/java.lang.Thread.sleep(Native Method)
                app//com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask.run(BasicResourcePool.java:1805)
                app//com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
        Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2,5,main]
                java.base@17.0.15/sun.nio.ch.SocketDispatcher.read0(Native Method)
                java.base@17.0.15/sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:47)
                java.base@17.0.15/sun.nio.ch.NioSocketImpl.tryRead(NioSocketImpl.java:266)
...

30.05.2025 18:59:10 com.mchange.v2.resourcepool.BasicResourcePool [Task-Thread-for-com.mchange.v2.async.ThreadPerTaskAsynchronousRunner@2d24e7cd]
WARN  com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@2cb23169 -- Acquisition Attempt Failed!!! Clearing pending acquires. While trying to acquire a needed new resource, we failed to succeed more than the maximum number of allowed acquisition attempts (30). Last acquisition attempt exception:
org.postgresql.util.PSQLException: FATAL: password authentication failed for user "ctsms"
        at org.postgresql.core.v3.ConnectionFactoryImpl.doAuthentication(ConnectionFactoryImpl.java:646)
        at org.postgresql.core.v3.ConnectionFactoryImpl.tryConnect(ConnectionFactoryImpl.java:180)
        at org.postgresql.core.v3.ConnectionFactoryImpl.openConnectionImpl(ConnectionFactoryImpl.java:235)
        at org.postgresql.core.ConnectionFactory.openConnection(ConnectionFactory.java:49)
        at org.postgresql.jdbc.PgConnection.<init>(PgConnection.java:223)
        at org.postgresql.Driver.makeConnection(Driver.java:402)
        at org.postgresql.Driver.connect(Driver.java:261)
        at com.mchange.v2.c3p0.DriverManagerDataSource.getConnection(DriverManagerDataSource.java:135)
        at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:182)
        at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:171)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:137)
        at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1014)
        at com.mchange.v2.resourcepool.BasicResourcePool.access$800(BasicResourcePool.java:32)
        at com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask.run(BasicResourcePool.java:1810)
        at com.mchange.v2.async.ThreadPerTaskAsynchronousRunner$TaskThread.run(ThreadPerTaskAsynchronousRunner.java:255)
The department passphrase for 'my_department' when adding users with /ctsms/dbtool.sh is 'fAaqFG7EBkbgcVn9yh6cr7IjD2EMMEqS'.
Log in at https://192.168.122.93 with username 'phoenix' password 'phk'.

Fyi, the /home/phoenix/ctsms directory has 777 persmission:

~$ ls -la ctsms/
total 232
drwxrwxrwx 9 root    root     4096 May 30 18:41 .
drwxrwxrwx 4 phoenix phoenix  4096 May 30 19:31 ..
-rw-r--r-- 1 root    root        0 May 30 18:12 andromda-hibernate.log
-rw-r--r-- 1 root    root        0 May 30 18:16 andromda-java.log
-rw-r--r-- 1 root    root        0 May 30 18:25 andromda.log
-rw-r--r-- 1 root    root        0 May 30 18:09 andromda-spring.log
drwxr-xr-x 4 root    root     4096 May 30 18:09 common
drwxr-xr-x 5 root    root     4096 May 30 18:09 core
-rw-r--r-- 1 root    root    23996 May 30 18:06 crowdin.yml
-rw-r--r-- 1 root    root     4672 May 30 18:06 ctsms-cleanup.xml
-rw-r--r-- 1 root    root    39413 May 30 18:06 ctsms-formatting.xml
drwxr-xr-x 8 root    root     4096 May 30 18:06 .git
-rw-r--r-- 1 root    root     1387 May 30 18:06 .gitattributes
drwxr-xr-x 3 root    root     4096 May 30 18:06 .github
-rw-r--r-- 1 root    root      512 May 30 18:06 .gitignore
-rw-r--r-- 1 root    root    26427 May 30 18:06 LICENSE
-rw-r--r-- 1 root    root      536 May 30 18:06 maven.yml
drwxr-xr-x 4 root    root     4096 May 30 18:12 mda
-rw-r--r-- 1 root    root    34083 May 30 18:06 pom.xml
-rw-r--r-- 1 root    root     4722 May 30 18:06 README.md
-rw-r--r-- 1 root    root    12592 May 30 18:06 readme.txt
drwxr-xr-x 3 root    root     4096 May 30 18:06 repository
drwxrwxrwx 4 root    root     4096 May 30 18:24 web
-rw-r--r-- 1 root    root      144 May 30 18:26 wget-log
-rw-r--r-- 1 root    root      153 May 30 18:26 wget-log.1
-rw-r--r-- 1 root    root      152 May 30 18:41 wget-log.2
-rw-r--r-- 1 root    root      152 May 30 18:41 wget-log.3
-rw-r--r-- 1 root    root      152 May 30 18:41 wget-log.4
-rw-r--r-- 1 root    root      160 May 30 18:41 wget-log.5

There was a blank page at the specified IP!?

Thanks.

[zenny]

Not all necessary services are running either when I tried to check the list of systemd services:

$ systemctl --type service
  UNIT                               LOAD   ACTIVE SUB     DESCRIPTION
  apparmor.service                   loaded active exited  Load AppArmor profiles
  console-setup.service              loaded active exited  Set console font and keymap
  cron.service                       loaded active running Regular background program processing daemon
  dbus.service                       loaded active running D-Bus System Message Bus
  getty@tty1.service                 loaded active running Getty on tty1
  ifup@enp1s0.service                loaded active exited  ifup for enp1s0
  ifupdown-pre.service               loaded active exited  Helper to synchronize boot up for ifupdown
  keyboard-setup.service             loaded active exited  Set the console keyboard layout
  kmod-static-nodes.service          loaded active exited  Create List of Static Device Nodes
  networking.service                 loaded active exited  Raise network interfaces
  postgresql.service                 loaded active exited  PostgreSQL RDBMS
  postgresql@15-main.service         loaded active running PostgreSQL Cluster 15-main
  qemu-guest-agent.service           loaded active running QEMU Guest Agent
  ssh.service                        loaded active running OpenBSD Secure Shell server
  systemd-binfmt.service             loaded active exited  Set Up Additional Binary Formats
  systemd-journal-flush.service      loaded active exited  Flush Journal to Persistent Storage
  systemd-journald.service           loaded active running Journal Service
  systemd-logind.service             loaded active running User Login Management
  systemd-modules-load.service       loaded active exited  Load Kernel Modules
  systemd-random-seed.service        loaded active exited  Load/Save Random Seed
  systemd-remount-fs.service         loaded active exited  Remount Root and Kernel File Systems
  systemd-sysctl.service             loaded active exited  Apply Kernel Variables
  systemd-sysusers.service           loaded active exited  Create System Users
  systemd-timesyncd.service          loaded active running Network Time Synchronization
  systemd-tmpfiles-setup-dev.service loaded active exited  Create Static Device Nodes in /dev
  systemd-tmpfiles-setup.service     loaded active exited  Create System Files and Directories
  systemd-udev-trigger.service       loaded active exited  Coldplug All udev Devices
  systemd-udevd.service              loaded active running Rule-based Manager for Device Events and Files
  systemd-update-utmp.service        loaded active exited  Record System Boot/Shutdown in UTMP
  systemd-user-sessions.service      loaded active exited  Permit User Sessions
  tomcat10.service                   loaded active running Apache Tomcat 10 Web Application Server
  user-runtime-dir@1000.service      loaded active exited  User Runtime Directory /run/user/1000
  user@1000.service                  loaded active running User Manager for UID 1000

No apache2 running either after reboot!

~$ systemctl --type service | grep running
  cron.service                       loaded active running Regular background program processing daemon
  dbus.service                       loaded active running D-Bus System Message Bus
  getty@tty1.service                 loaded active running Getty on tty1
  postgresql@15-main.service         loaded active running PostgreSQL Cluster 15-main
  qemu-guest-agent.service           loaded active running QEMU Guest Agent
  ssh.service                        loaded active running OpenBSD Secure Shell server
  systemd-journald.service           loaded active running Journal Service
  systemd-logind.service             loaded active running User Login Management
  systemd-timesyncd.service          loaded active running Network Time Synchronization
  systemd-udevd.service              loaded active running Rule-based Manager for Device Events and Files
  tomcat10.service                   loaded active running Apache Tomcat 10 Web Application Server
  user@1000.service                  loaded active running User Manager for UID 1000

[rkrenn]

No apache2 running either after reboot!

yes, the debain 11 https://www.phoenixctms.org/wp-content/uploads/downloads/deb_11_2_phoenix.ova takes care of all of that.

the full-blown debian install https://github.com/phoenixctms/install-debian is not elaborated for debian12 yet. as said @josharmenta is wokring on a ansible playbook for it already, phoenixctms/install-debian#16 . the #308 (comment) script is a minimal thing, derived from the github action test/worker setup https://github.com/phoenixctms/ctsms/blob/master/.github/workflows/test.yml . the main focus of #308 was to establish compatibility/migrate to tomcat10.

[rkrenn]

• any maven deprecations issues are of no relevance as of now. the latest maven is neither used in github actions.

• PSQLException: FATAL: password authentication failed for user "ctsms": this will result in all the subsequent error from the mchange caching, including the deadlock false positives it reports. be assured, the application is proven to rund hundreds of users.

please go ahead with debian11 for your testing meanwhile.