[SOLVED] Zoph update error

joseph · June 29, 2025, 5:35pm

Resolution
I uninstalled zoph after the Trixie upgrade and then installed anew. I think it’s okay after this, I was able to import a picture.

Problem Description
Zoph is working, but there is an error in the application page and I believe that Debian is stuck on processing this update. FreedomBox is now reporting that it is not up to date.

Steps to Reproduce

Look in the Zoph app management page.

Expected Results
Debian app Zoph updates normally.

Actual results
FreedomBox notification: Zoph

Error updating app: Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96.

Error from app management page:
Error updating app: Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96. Details


Error running action: zoph..setup(): CalledProcessError(96, ["zoph", "--config", "import.enable", "true"])
Stdout:
│ root is not a valid user
Action traceback:
╞   File "/usr/lib/python3/dist-packages/plinth/actions.py", line 398, in _privileged_call
╞     return_values = func(*arguments['args'], **arguments['kwargs'])
╞                     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
╞   File "/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py", line 55, in setup
╞     _zoph_configure('import.enable', 'true')
╞   File "/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py", line 45, in _zoph_configure
╞     subprocess.run(['zoph', '--config', key, value], check=True)
╞   File "/usr/lib/python3.11/subprocess.py", line 571, in run
╞     raise CalledProcessError(retcode, process.args,

App management page “Launch web client” button is not active.

App management settings menu only offers “Uninstall”

Screenshot

Information

FreedomBox version:

Caution:

You are running Debian GNU/Linux 12 (bookworm) and FreedomBox version 25.9. There is a new FreedomBox version available.

Hardware: Linux myhostname 6.1.0-37-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.140-1 (2025-05-22) x86_64 GNU/Linux
How did you install FreedomBox?: net inst, DEBIAN_FRONTEND=noninteractive apt-get freedombox -y

joseph · July 30, 2025, 2:20pm

This is ongoing and impacting Zoph and Freedombox updates.

Error updating app: Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96. Details

Error running action: zoph..setup(): CalledProcessError(96, ["zoph", "--config", "import.enable", "true"])
Stdout:
│ root is not a valid user
Action traceback:
╞   File "/usr/lib/python3/dist-packages/plinth/actions.py", line 398, in _privileged_call
╞     return_values = func(*arguments['args'], **arguments['kwargs'])
╞                     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
╞   File "/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py", line 55, in setup
╞     _zoph_configure('import.enable', 'true')
╞   File "/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py", line 45, in _zoph_configure
╞     subprocess.run(['zoph', '--config', key, value], check=True)
╞   File "/usr/lib/python3.11/subprocess.py", line 571, in run
╞     raise CalledProcessError(retcode, process.args,

joseph · August 10, 2025, 5:47pm

update

Freedombox was held back, but apparently unrelated to Zoph. I removed the freedombox hold manually and it updated without issue. Zoph is still problematic.

joseph · August 13, 2025, 11:29pm

Zoph upgrade failure loop - Aug 13 '25 update

Zoph is stuck in some sort of unsuccessful update loop.

The app is installed and current version according to apt search zoph.
Whenever I log into plinth it is updating. Whenever the update fails it tries again.
Here are journal entries for one iteration: Aug 13 13:17:12 myfreedombox /usr/bin/plinth[32167]: Operation: zoph: Updating app: added Aug 13 13:17:12 myfreedombox /usr/bin/plinth[32167]: Operation: zoph: Updating app: running Aug 13 13:17:12 myfreedombox /usr/bin/plinth[32167]: Setup run: zoph Aug 13 13:17:19 myfreedombox /usr/bin/plinth[32167]: App zoph does not need force upgrade Aug 13 13:17:19 myfreedombox /usr/bin/plinth[32167]: # zoph..pre_install() Aug 13 13:17:19 myfreedombox sudo[696135]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions zoph pre_install --write-fd 31 Aug 13 13:17:25 myfreedombox /usr/bin/plinth[32167]: Running install for app - zoph, packages - [‘zoph’, ‘default-mysql-server’] Aug 13 13:17:32 myfreedombox /usr/bin/plinth[32167]: # plinth..install(app_id=“zoph”, packages=[“zoph”, “default-mysql-server”], skip_recommends=false, force_configuration=null, reinstall=false, force_missing_configuration=false) Aug 13 13:17:34 myfreedombox /usr/share/plinth/actions/actions[696535]: Initialized apps - apache, api, names, avahi, storage, backups, cockpit, firewall, config, datetime, diagnostics, dynamicdns, first_boot, help, letsencrypt, networks, power, privacy, security, snapshot, ssh, sso, upgrades, users, bepasty, bind, calibre, coturn, deluge, ejabberd, email, featherwiki, gitweb, gnome, ikiwiki, infinoted, janus, jsxc, kiwix, matrixsynapse, mediawiki, minetest, minidlna, miniflux, mumble, nextcloud, openvpn, pagekite, performance, privoxy, quassel, radicale, roundcube, rssbridge, samba, searx, shaarli, shadowsocks, shadowsocksserver, sharing, sogo, syncthing, tiddlywiki, tor, torproxy, transmission, ttrss, wireguard, wordpress, zoph Aug 13 13:17:39 myfreedombox /usr/bin/plinth[32167]: # plinth..dropin_is_valid(“zoph”, “/etc/apache2/conf-available/zoph-freedombox.conf”, false, unlink_invalid=true) Aug 13 13:17:44 myfreedombox /usr/bin/plinth[32167]: # zoph..setup() Aug 13 13:17:44 myfreedombox sudo[696925]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions zoph setup --write-fd 31 Aug 13 13:17:45 myfreedombox /usr/bin/plinth[32167]: Error running action zoph..setup(): CalledProcessError(96, [“zoph”, “–config”, “import.enable”, “true”]) ╞ File “/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py”, line 55, in setup ╞ _zoph_configure(‘import.enable’, ‘true’) ╞ File “/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py”, line 45, in _zoph_configure ╞ subprocess.run([‘zoph’, ‘–config’, key, value], check=True) Aug 13 13:17:45 myfreedombox /usr/bin/plinth[32167]: Setup completed: zoph: Error updating app: {exception} Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96.Aug 13 13:17:46 myfreedombox /usr/bin/plinth[32167]: Error running setup - Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96. Aug 13 13:17:46 myfreedombox /usr/bin/plinth[32167]: Unable to complete setup: Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96. Aug 13 13:17:56 myfreedombox /usr/bin/plinth[32167]: Running setup for apps, essential - False, selected apps - [‘zoph’]

joseph · August 16, 2025, 4:46pm

Aug 16 update - This may be a problem in FreedomBox and I need some advice

I have removed tt-rss in preparation for the distribution upgrade
I have removed searx in preparation for the distribution upgrade
apt update tells me all (bookworm) packages are up to date
apt-get --fix-broken install tells me that there are no operations to do

Apt/dpkg clearly believe that the zoph package is just fine. Zoph works when you use it. Plinth constantly notifies that it is updating Zoph and in fact is because you can get some messages about apt being busy.

I’d prefer not to uninstall Zoph and have to deal with the many thousands of photos I’ve finally got in there, but I don’t see any way around this at the moment.

You cannot disable the app from the zoph app management page
There is no service associated directly with zoph to mask
dpkg says the package is up to date and the installation is not damaged

My only option in the zoph app management page is to uninstall.

Once the zoph update attempt fails I see this error in the app management page:

I have this notification:

Diagnostics are all passing:

dpkg-query -s zoph # dpkg-query -s zoph Package: zoph Status: install ok installed <= this is means everything checks out for the package. Priority: optional Section: web Installed-Size: 13258 Maintainer: John L… Architecture: all Version: 1.0.1-3 <= version is current for bookworm

joseph · August 18, 2025, 4:47am

Aug 17 09:18:50 fbox /usr/bin/plinth[932]: Running setup for apps, essential - False, selected apps - [‘zoph’]
Aug 17 09:18:50 fbox /usr/bin/plinth[932]: Operation: zoph: Updating app: added
Aug 17 09:18:50 fbox /usr/bin/plinth[932]: Operation: zoph: Updating app: running
Aug 17 09:18:50 fbox /usr/bin/plinth[932]: Setup run: zoph
Aug 17 09:18:50 fbox /usr/bin/plinth[932]: # plinth..update()

@Sunil it looks like this to me…

I have zoph in a non stop update loop and the package manager says the package is fine. I think this may be in freedombox. I’m suspicious of the message about root being an invalid user, maybe in a configuration script.

Sunil · August 19, 2025, 3:10am

In ‘journalctl -f’ FreedomBox service should show some error messages when it fails the setup process each time. Could you please post this?

joseph · August 19, 2025, 3:40pm

Here is journalctl -f output.

Aug 19 08:20:03 MYFREEDOMBOX /usr/share/plinth/actions/actions[775614]: Initialized apps - apache, api, names, avahi, storage, backups, cockpit, firewall, config, datetime, diagnostics, dynamicdns, first_boot, help, letsencrypt, networks, power, privacy, security, snapshot, ssh, sso, upgrades, users, bepasty, bind, calibre, coturn, deluge, ejabberd, email, featherwiki, gitweb, gnome, ikiwiki, infinoted, janus, jsxc, kiwix, matrixsynapse, mediawiki, minetest, minidlna, miniflux, mumble, nextcloud, openvpn, pagekite, performance, privoxy, quassel, radicale, roundcube, rssbridge, samba, searx, shaarli, shadowsocks, shadowsocksserver, sharing, sogo, syncthing, tiddlywiki, tor, torproxy, transmission, ttrss, wireguard, wordpress, zoph
Aug 19 08:20:03 MYFREEDOMBOX nslcd[2355]: [9d8253] <passwd=“pam_unix_non_existent:”> request denied by validnames option
Aug 19 08:20:07 MYFREEDOMBOX sudo[775617]: pam_unix(sudo:auth): authentication failure; logname= uid=10001 euid=0 tty=/dev/pts/0 ruser=joseph rhost= user=joseph
Aug 19 08:20:07 MYFREEDOMBOX sudo[775617]: joseph : TTY=pts/0 ; PWD=/home/joseph ; USER=root ; COMMAND=/usr/bin/journalctl -f
Aug 19 08:20:07 MYFREEDOMBOX sudo[775617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=10001)
Aug 19 08:20:07 MYFREEDOMBOX sudo[775612]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:08 MYFREEDOMBOX /usr/bin/plinth[1384]: # plinth..dropin_is_valid(“zoph”, “/etc/apache2/conf-available/zoph-freedombox.conf”, false, unlink_invalid=true)
Aug 19 08:20:08 MYFREEDOMBOX sudo[775664]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions plinth dropin_is_valid --write-fd 31
Aug 19 08:20:08 MYFREEDOMBOX sudo[775664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:09 MYFREEDOMBOX sudo[775664]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:09 MYFREEDOMBOX /usr/bin/plinth[1384]: # apache..setup(old_version=1)
Aug 19 08:20:09 MYFREEDOMBOX sudo[775668]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions apache setup --write-fd 31
Aug 19 08:20:09 MYFREEDOMBOX sudo[775668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:13 MYFREEDOMBOX systemd[1]: Reloading apache2.service - The Apache HTTP Server…
Aug 19 08:20:13 MYFREEDOMBOX apachectl[775891]: AH00558: apache2: Could not reliably determine the server’s fully qualified domain name, using fe80::3eec:efff:fe00:5290%eno1. Set the ‘ServerName’ directive globally to suppress this message
Aug 19 08:20:13 MYFREEDOMBOX apache-error[774669]: [mpm_event:notice] [pid 3232:tid 3232] AH00493: SIGUSR1 received. Doing graceful restart
Aug 19 08:20:13 MYFREEDOMBOX systemd[1]: Reloaded apache2.service - The Apache HTTP Server.
Aug 19 08:20:13 MYFREEDOMBOX apache-error[774669]: AH00558: apache2: Could not reliably determine the server’s fully qualified domain name, using fe80::3eec:efff:fe00:5290%eno1. Set the ‘ServerName’ directive globally to suppress this message
Aug 19 08:20:13 MYFREEDOMBOX sudo[775668]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:13 MYFREEDOMBOX /usr/bin/plinth[1384]: # zoph..setup()
Aug 19 08:20:13 MYFREEDOMBOX sudo[775893]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions zoph setup --write-fd 31
Aug 19 08:20:13 MYFREEDOMBOX sudo[775893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:14 MYFREEDOMBOX apache-error[775892]: [ssl:error] [pid 3232:tid 3232] AH02218: ssl_stapling_init_cert: no OCSP URI in certificate and no SSLStaplingForceURL set [subject: CN=DDNSNAME.fbx.one / issuer: CN=E6,O=Let’s Encrypt,C=US / serial: 05A6E455571040E3E1BDB20114972F1D9A0B / notbefore: Aug 11 20:56:52 2025 GMT / notafter: Nov 9 20:56:51 2025 GMT]
Aug 19 08:20:14 MYFREEDOMBOX apache-error[775892]: [ssl:error] [pid 3232:tid 3232] AH02604: Unable to configure certificate DDNSNAME.fbx.one:443:0 for stapling
Aug 19 08:20:14 MYFREEDOMBOX apache-error[775892]: [ssl:warn] [pid 3232:tid 3232] AH01909: fe80::3eec:efff:fe00:5290%eno1:443:0 server certificate does NOT include an ID which matches the server name
Aug 19 08:20:14 MYFREEDOMBOX apache-error[775892]: [ssl:error] [pid 3232:tid 3232] AH02217: ssl_stapling_init_cert: can’t retrieve issuer certificate! [subject: CN=MYFREEDOMBOX.PRIVATEDOMAIN.net / issuer: CN=MYFREEDOMBOX.PRIVATEDOMAIN.net / serial: 7E54D9D1A1584D680CA39207FE9EB15F3B8D5339 / notbefore: Mar 13 21:26:43 2021 GMT / notafter: Mar 11 21:26:43 2031 GMT]
Aug 19 08:20:14 MYFREEDOMBOX apache-error[775892]: [ssl:error] [pid 3232:tid 3232] AH02604: Unable to configure certificate fe80::3eec:efff:fe00:5290%eno1:443:0 for stapling
Aug 19 08:20:14 MYFREEDOMBOX apache-error[775892]: [ssl:error] [pid 3232:tid 3232] AH02218: ssl_stapling_init_cert: no OCSP URI in certificate and no SSLStaplingForceURL set [subject: CN=DDNSHOSTNAME.PRIVATEDOMAIN.net / issuer: CN=R10,O=Let’s Encrypt,C=US / serial: 0692CC0B0268FBA46C4C9B4F3D3A3F653B19 / notbefore: Jul 29 00:01:23 2025 GMT / notafter: Oct 27 00:01:22 2025 GMT]
Aug 19 08:20:14 MYFREEDOMBOX apache-error[775892]: [ssl:error] [pid 3232:tid 3232] AH02604: Unable to configure certificate DDNSHOSTNAME.PRIVATEDOMAIN.net:443:0 for stapling
Aug 19 08:20:14 MYFREEDOMBOX apache-error[775892]: [mpm_event:notice] [pid 3232:tid 3232] AH00489: Apache/2.4.62 (Debian) mod_auth_pubtkt/0.13 OpenSSL/3.0.17 configured – resuming normal operations
Aug 19 08:20:14 MYFREEDOMBOX apache-error[775892]: [core:notice] [pid 3232:tid 3232] AH00094: Command line: ‘/usr/sbin/apache2’
Aug 19 08:20:14 MYFREEDOMBOX apache-error[775892]: [mpm_event:warn] [pid 3232:tid 3232] AH00488: long lost child came home! (pid 774670)
Aug 19 08:20:14 MYFREEDOMBOX sudo[775893]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:14 MYFREEDOMBOX /usr/bin/plinth[1384]: Error running action zoph..setup(): CalledProcessError(96, [“zoph”, “–config”, “import.enable”, “true”])
Stdout:
│ root is not a valid user
Action traceback:
╞ File “/usr/lib/python3/dist-packages/plinth/actions.py”, line 398, in _privileged_call
╞ return_values = func(*arguments[‘args’], **arguments[‘kwargs’])
╞ ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
╞ File “/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py”, line 55, in setup
╞ _zoph_configure(‘import.enable’, ‘true’)
╞ File “/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py”, line 45, in _zoph_configure
╞ subprocess.run([‘zoph’, ‘–config’, key, value], check=True)
╞ File “/usr/lib/python3.11/subprocess.py”, line 571, in run
╞ raise CalledProcessError(retcode, process.args,
Aug 19 08:20:14 MYFREEDOMBOX /usr/bin/plinth[1384]: Setup completed: zoph: Error updating app: {exception} Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96.
Aug 19 08:20:15 MYFREEDOMBOX /usr/bin/plinth[1384]: Error running setup - Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96.
Aug 19 08:20:15 MYFREEDOMBOX /usr/bin/plinth[1384]: Unable to complete setup: Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96.
Aug 19 08:20:15 MYFREEDOMBOX /usr/bin/plinth[1384]: Will try again in 10 seconds
Aug 19 08:20:20 MYFREEDOMBOX sshd[776007]: Received disconnect from 187.210.77.100 port 36228:11: Bye Bye [preauth]
Aug 19 08:20:20 MYFREEDOMBOX sshd[776007]: Disconnected from authenticating user root 187.210.77.100 port 36228 [preauth]
Aug 19 08:20:25 MYFREEDOMBOX /usr/bin/plinth[1384]: Running regular setup.
Aug 19 08:20:25 MYFREEDOMBOX /usr/bin/plinth[1384]: Running setup for apps, essential - False, selected apps - [‘zoph’]
Aug 19 08:20:26 MYFREEDOMBOX /usr/bin/plinth[1384]: Operation: zoph: Updating app: added
Aug 19 08:20:26 MYFREEDOMBOX /usr/bin/plinth[1384]: Operation: zoph: Updating app: running
Aug 19 08:20:26 MYFREEDOMBOX /usr/bin/plinth[1384]: Setup run: zoph
Aug 19 08:20:26 MYFREEDOMBOX /usr/bin/plinth[1384]: # plinth..update()
Aug 19 08:20:26 MYFREEDOMBOX sudo[776010]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions plinth update --write-fd 33
Aug 19 08:20:26 MYFREEDOMBOX sudo[776010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:30 MYFREEDOMBOX sudo[776010]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:30 MYFREEDOMBOX /usr/bin/plinth[1384]: # plinth..is_package_manager_busy()
Aug 19 08:20:30 MYFREEDOMBOX sudo[776388]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions plinth is_package_manager_busy --write-fd 33
Aug 19 08:20:30 MYFREEDOMBOX sudo[776388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:30 MYFREEDOMBOX sshd[776385]: Received disconnect from 210.79.142.221 port 52712:11: Bye Bye [preauth]
Aug 19 08:20:30 MYFREEDOMBOX sshd[776385]: Disconnected from authenticating user root 210.79.142.221 port 52712 [preauth]
Aug 19 08:20:31 MYFREEDOMBOX sudo[776388]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:32 MYFREEDOMBOX /usr/bin/plinth[1384]: App zoph does not need force upgrade
Aug 19 08:20:32 MYFREEDOMBOX /usr/bin/plinth[1384]: # zoph..pre_install()
Aug 19 08:20:32 MYFREEDOMBOX sudo[776395]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions zoph pre_install --write-fd 33
Aug 19 08:20:32 MYFREEDOMBOX sudo[776395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:35 MYFREEDOMBOX sudo[776395]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:37 MYFREEDOMBOX /usr/bin/plinth[1384]: # plinth..is_package_manager_busy()
Aug 19 08:20:37 MYFREEDOMBOX sudo[776408]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions plinth is_package_manager_busy --write-fd 33
Aug 19 08:20:37 MYFREEDOMBOX sudo[776408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:38 MYFREEDOMBOX sudo[776408]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:38 MYFREEDOMBOX /usr/bin/plinth[1384]: Running install for app - zoph, packages - [‘zoph’, ‘default-mysql-server’]
Aug 19 08:20:38 MYFREEDOMBOX /usr/bin/plinth[1384]: # plinth..update()
Aug 19 08:20:38 MYFREEDOMBOX sudo[776416]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions plinth update --write-fd 33
Aug 19 08:20:38 MYFREEDOMBOX sudo[776416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:42 MYFREEDOMBOX sudo[776416]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:42 MYFREEDOMBOX /usr/bin/plinth[1384]: # plinth..install(app_id=“zoph”, packages=[“zoph”, “default-mysql-server”], skip_recommends=false, force_configuration=null, reinstall=false, force_missing_configuration=false)
Aug 19 08:20:42 MYFREEDOMBOX sudo[776793]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions plinth install --write-fd 33
Aug 19 08:20:42 MYFREEDOMBOX sudo[776793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:43 MYFREEDOMBOX /usr/share/plinth/actions/actions[776795]: Initialized apps - apache, api, names, avahi, storage, backups, cockpit, firewall, config, datetime, diagnostics, dynamicdns, first_boot, help, letsencrypt, networks, power, privacy, security, snapshot, ssh, sso, upgrades, users, bepasty, bind, calibre, coturn, deluge, ejabberd, email, featherwiki, gitweb, gnome, ikiwiki, infinoted, janus, jsxc, kiwix, matrixsynapse, mediawiki, minetest, minidlna, miniflux, mumble, nextcloud, openvpn, pagekite, performance, privoxy, quassel, radicale, roundcube, rssbridge, samba, searx, shaarli, shadowsocks, shadowsocksserver, sharing, sogo, syncthing, tiddlywiki, tor, torproxy, transmission, ttrss, wireguard, wordpress, zoph
Aug 19 08:20:44 MYFREEDOMBOX dnsmasq-dhcp[1793]: DHCPREQUEST(eno2) 192.168.144.203 14:eb:b6:77:f0:a7
Aug 19 08:20:44 MYFREEDOMBOX dnsmasq-dhcp[1793]: DHCPACK(eno2) 192.168.144.203 14:eb:b6:77:f0:a7 TL-WPA7517
Aug 19 08:20:46 MYFREEDOMBOX sshd[776825]: error: kex_exchange_identification: read: Connection reset by peer
Aug 19 08:20:46 MYFREEDOMBOX sshd[776825]: Connection reset by 45.140.17.26 port 3441
Aug 19 08:20:47 MYFREEDOMBOX sudo[776793]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:48 MYFREEDOMBOX /usr/bin/plinth[1384]: # plinth..dropin_is_valid(“zoph”, “/etc/apache2/conf-available/zoph-freedombox.conf”, false, unlink_invalid=true)
Aug 19 08:20:48 MYFREEDOMBOX sudo[776845]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions plinth dropin_is_valid --write-fd 33
Aug 19 08:20:48 MYFREEDOMBOX sudo[776845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:49 MYFREEDOMBOX sudo[776845]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:49 MYFREEDOMBOX /usr/bin/plinth[1384]: # apache..setup(old_version=1)
Aug 19 08:20:49 MYFREEDOMBOX sudo[776849]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions apache setup --write-fd 33
Aug 19 08:20:49 MYFREEDOMBOX sudo[776849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:53 MYFREEDOMBOX Tor-fbxproxy[1987]: Possible compression bomb; abandoning stream.
Aug 19 08:20:53 MYFREEDOMBOX systemd[1]: Reloading apache2.service - The Apache HTTP Server…
Aug 19 08:20:53 MYFREEDOMBOX apachectl[777072]: AH00558: apache2: Could not reliably determine the server’s fully qualified domain name, using fe80::3eec:efff:fe00:5290%eno1. Set the ‘ServerName’ directive globally to suppress this message
Aug 19 08:20:53 MYFREEDOMBOX apache-error[775892]: [mpm_event:notice] [pid 3232:tid 3232] AH00493: SIGUSR1 received. Doing graceful restart
Aug 19 08:20:53 MYFREEDOMBOX systemd[1]: Reloaded apache2.service - The Apache HTTP Server.
Aug 19 08:20:53 MYFREEDOMBOX apache-error[775892]: AH00558: apache2: Could not reliably determine the server’s fully qualified domain name, using fe80::3eec:efff:fe00:5290%eno1. Set the ‘ServerName’ directive globally to suppress this message
Aug 19 08:20:53 MYFREEDOMBOX sudo[776849]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:53 MYFREEDOMBOX /usr/bin/plinth[1384]: # zoph..setup()
Aug 19 08:20:54 MYFREEDOMBOX sudo[777074]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions zoph setup --write-fd 33
Aug 19 08:20:54 MYFREEDOMBOX sudo[777074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
Aug 19 08:20:54 MYFREEDOMBOX Tor-fbxproxy[1987]: Possible compression bomb; abandoning stream.
Aug 19 08:20:54 MYFREEDOMBOX apache-error[777073]: [ssl:error] [pid 3232:tid 3232] AH02218: ssl_stapling_init_cert: no OCSP URI in certificate and no SSLStaplingForceURL set [subject: CN=DDNSNAME.fbx.one / issuer: CN=E6,O=Let’s Encrypt,C=US / serial: 05A6E455571040E3E1BDB20114972F1D9A0B / notbefore: Aug 11 20:56:52 2025 GMT / notafter: Nov 9 20:56:51 2025 GMT]
Aug 19 08:20:54 MYFREEDOMBOX apache-error[777073]: [ssl:error] [pid 3232:tid 3232] AH02604: Unable to configure certificate DDNSNAME.fbx.one:443:0 for stapling
Aug 19 08:20:54 MYFREEDOMBOX apache-error[777073]: [ssl:warn] [pid 3232:tid 3232] AH01909: fe80::3eec:efff:fe00:5290%eno1:443:0 server certificate does NOT include an ID which matches the server name
Aug 19 08:20:54 MYFREEDOMBOX apache-error[777073]: [ssl:error] [pid 3232:tid 3232] AH02217: ssl_stapling_init_cert: can’t retrieve issuer certificate! [subject: CN=MYFREEDOMBOX.PRIVATEDOMAIN.net / issuer: CN=MYFREEDOMBOX.PRIVATEDOMAIN.net / serial: 7E54D9D1A1584D680CA39207FE9EB15F3B8D5339 / notbefore: Mar 13 21:26:43 2021 GMT / notafter: Mar 11 21:26:43 2031 GMT]
Aug 19 08:20:54 MYFREEDOMBOX apache-error[777073]: [ssl:error] [pid 3232:tid 3232] AH02604: Unable to configure certificate fe80::3eec:efff:fe00:5290%eno1:443:0 for stapling
Aug 19 08:20:54 MYFREEDOMBOX apache-error[777073]: [ssl:error] [pid 3232:tid 3232] AH02218: ssl_stapling_init_cert: no OCSP URI in certificate and no SSLStaplingForceURL set [subject: CN=DDNSHOSTNAME.PRIVATEDOMAIN.net / issuer: CN=R10,O=Let’s Encrypt,C=US / serial: 0692CC0B0268FBA46C4C9B4F3D3A3F653B19 / notbefore: Jul 29 00:01:23 2025 GMT / notafter: Oct 27 00:01:22 2025 GMT]
Aug 19 08:20:54 MYFREEDOMBOX apache-error[777073]: [ssl:error] [pid 3232:tid 3232] AH02604: Unable to configure certificate DDNSHOSTNAME.PRIVATEDOMAIN.net:443:0 for stapling
Aug 19 08:20:54 MYFREEDOMBOX apache-error[777073]: [mpm_event:notice] [pid 3232:tid 3232] AH00489: Apache/2.4.62 (Debian) mod_auth_pubtkt/0.13 OpenSSL/3.0.17 configured – resuming normal operations
Aug 19 08:20:54 MYFREEDOMBOX apache-error[777073]: [core:notice] [pid 3232:tid 3232] AH00094: Command line: ‘/usr/sbin/apache2’
Aug 19 08:20:54 MYFREEDOMBOX apache-error[777073]: [mpm_event:warn] [pid 3232:tid 3232] AH00488: long lost child came home! (pid 775896)
Aug 19 08:20:54 MYFREEDOMBOX Tor-fbxproxy[1987]: Possible compression bomb; abandoning stream.
Aug 19 08:20:54 MYFREEDOMBOX sudo[777074]: pam_unix(sudo:session): session closed for user root
Aug 19 08:20:54 MYFREEDOMBOX /usr/bin/plinth[1384]: Error running action zoph..setup(): CalledProcessError(96, [“zoph”, “–config”, “import.enable”, “true”])
Stdout:
│ root is not a valid user
Action traceback:
╞ File “/usr/lib/python3/dist-packages/plinth/actions.py”, line 398, in _privileged_call
╞ return_values = func(*arguments[‘args’], **arguments[‘kwargs’])
╞ ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
╞ File “/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py”, line 55, in setup
╞ _zoph_configure(‘import.enable’, ‘true’)
╞ File “/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py”, line 45, in _zoph_configure
╞ subprocess.run([‘zoph’, ‘–config’, key, value], check=True)
╞ File “/usr/lib/python3.11/subprocess.py”, line 571, in run
╞ raise CalledProcessError(retcode, process.args,
Aug 19 08:20:54 MYFREEDOMBOX /usr/bin/plinth[1384]: Setup completed: zoph: Error updating app: {exception} Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96.
Aug 19 08:20:55 MYFREEDOMBOX /usr/bin/plinth[1384]: Error running setup - Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96.
Aug 19 08:20:55 MYFREEDOMBOX /usr/bin/plinth[1384]: Unable to complete setup: Command ‘[‘zoph’, ‘–config’, ‘import.enable’, ‘true’]’ returned non-zero exit status 96.
Aug 19 08:20:55 MYFREEDOMBOX /usr/bin/plinth[1384]: Will try again in 10 seconds
Aug 19 08:21:01 MYFREEDOMBOX dnsmasq-dhcp[1793]: DHCPREQUEST(eno2) 192.168.144.193 b4:b0:24:2f:99:24
Aug 19 08:21:01 MYFREEDOMBOX dnsmasq-dhcp[1793]: DHCPACK(eno2) 192.168.144.193 b4:b0:24:2f:99:24 TL-WPA7517
Aug 19 08:21:05 MYFREEDOMBOX /usr/bin/plinth[1384]: Running regular setup.
Aug 19 08:21:05 MYFREEDOMBOX /usr/bin/plinth[1384]: Running setup for apps, essential - False, selected apps - [‘zoph’]
Aug 19 08:21:05 MYFREEDOMBOX /usr/bin/plinth[1384]: Operation: zoph: Updating app: added
Aug 19 08:21:05 MYFREEDOMBOX /usr/bin/plinth[1384]: Operation: zoph: Updating app: running
Aug 19 08:21:05 MYFREEDOMBOX /usr/bin/plinth[1384]: Setup run: zoph

Sunil · August 19, 2025, 8:14pm

Aug 19 08:20:54 MYFREEDOMBOX /usr/bin/plinth[1384]: Error running action zoph..setup(): CalledProcessError(96, [“zoph”, “–config”, “import.enable”, “true”])
Stdout:
│ root is not a valid user
Action traceback:
╞ File “/usr/lib/python3/dist-packages/plinth/actions.py”, line 398, in _privileged_call
╞ return_values = func(*arguments[‘args’], **arguments[‘kwargs’])
╞ ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
╞ File “/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py”, line 55, in setup
╞ _zoph_configure(‘import.enable’, ‘true’)
╞ File “/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py”, line 45, in _zoph_configure
╞ subprocess.run([‘zoph’, ‘–config’, key, value], check=True)
╞ File “/usr/lib/python3.11/subprocess.py”, line 571, in run
╞ raise CalledProcessError(retcode, process.args,

This is the command that is failing. Could you please run the following on the command line and see what happens?

zoph --config import.enable true

joseph · August 19, 2025, 8:37pm

Thanks for asking this - I see how to decipher this from the log now. I’ve always just ignored it because, “too messy.”

$ zoph --config import.enable true $ sudo zoph --config import.enable true [sudo] password for joseph: root is not a valid user


This is getting run as root and failing (line #4 in the quoted log text).

User joseph has:

image501×396 33.7 KB

Sunil · August 19, 2025, 8:50pm

This does not seem like and issue with the user ‘joseph’. These commands are run as ‘root’ user when Plinth tries to perform updates. I will try to reproduce this problem on development setups.

Meanwhile, if possible, you can try to backup and uninstall Zoph to let other updates continue.

joseph · August 19, 2025, 9:18pm

#zoph --config import.enable true has exit value 96 for CLI user not valid when run as root.

$zoph --config import.enable true has exit value 0 when run as user joseph.

joseph · August 22, 2025, 12:46pm

I removed zoph and the Trixie upgrade completed overnight.

I will do a new zoph install now and see if that has the same update problem after upgrade.

joseph · August 22, 2025, 8:00pm

I have a blue notification for Distribution Update. When I click the “Go to Distribution Update” button from the notification it takes me to /plinth/sys/upgrades and presents an error message in the pink box at the top of the page.

When I navigate to System / Software Updates the error message will not be on the page.

Zoph installation just now seemed uncontroversial. I have not done the application setup yet.

Distribution Upgrade error message:

Traceback (most recent call last):
File “/usr/lib/python3/dist-packages/django/core/handlers/base.py”, line 197, in _get_response
response = wrapped_callback(request, *callback_args, **callback_kwargs)
File “/usr/lib/python3/dist-packages/django/views/generic/base.py”, line 105, in view
return self.dispatch(request, *args, **kwargs)
~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/lib/python3/dist-packages/django/views/generic/base.py”, line 144, in dispatch
return handler(request, *args, **kwargs)
File “/usr/lib/python3/dist-packages/django/views/generic/base.py”, line 228, in get
context = self.get_context_data(**kwargs)
File “/usr/lib/python3/dist-packages/plinth/modules/upgrades/views.py”, line 94, in get_context_data
context[‘status’] = distupgrade.get_status()
~~~~~~~~~~~~~~~~~~~~~~^^
File “/usr/lib/python3/dist-packages/plinth/modules/upgrades/distupgrade.py”, line 158, in get_status
current_codename = _get_sources_list_codename()
File “/usr/lib/python3/dist-packages/plinth/modules/upgrades/distupgrade.py”, line 119, in _get_sources_list_codename
dist = dist.removesuffix(‘-updates’)
^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘removesuffix’

Zoph Installation Journal Entries

Aug 22 12:54:13 mezquite addgroup[17027]: Adding group `zoph’ (GID 127) …
Aug 22 12:54:13 mezquite groupadd[17034]: group added to /etc/group: name=zoph, GID=127
Aug 22 12:54:13 mezquite groupadd[17034]: group added to /etc/gshadow: name=zoph
Aug 22 12:54:14 mezquite groupadd[17034]: new group: name=zoph, GID=127
Aug 22 12:54:14 mezquite mariadbd[1689]: 2025-08-22 12:54:14 5 [Warning] Access denied for user ‘zoph_rw’@‘localhost’ (using password: YES)
Aug 22 12:54:14 mezquite mariadbd[1689]: 2025-08-22 12:54:14 7 [Warning] Access denied for user ‘zoph_rw’@‘localhost’ (using password: YES)
Aug 22 12:54:51 mezquite /usr/bin/plinth[1542]: # plinth..dropin_is_valid(“zoph”, “/etc/apache2/conf-available/zoph-freedombox.conf”, false, unlink_invalid=true)
Aug 22 12:54:56 mezquite /usr/bin/plinth[1542]: # zoph..setup()
Aug 22 12:54:56 mezquite sudo[17762]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions zoph setup --write-fd 13
Aug 22 12:54:58 mezquite /usr/bin/plinth[1542]: Firewall ports opened - Zoph, [‘http’, ‘https’]
Aug 22 12:54:58 mezquite /usr/bin/plinth[1542]: # plinth..dropin_is_valid(“zoph”, “/etc/apache2/conf-available/zoph-freedombox.conf”, false, unlink_invalid=true)
Aug 22 12:54:58 mezquite /usr/bin/plinth[1542]: # apache..enable(“zoph”, “config”)
Aug 22 12:54:59 mezquite /usr/bin/plinth[1542]: # apache..enable(“zoph-freedombox”, “config”)
Aug 22 12:55:02 mezquite /usr/share/plinth/actions/actions[18103]: Initialized apps - apache, api, names, avahi, storage, backups, cockpit, firewall, config, datetime, diagnostics, dynamicdns, first_boot, help, letsencrypt, networks, power, privacy, security, snapshot, ssh, sso, upgrades, users, bepasty, bind, calibre, coturn, deluge, ejabberd, email, featherwiki, gitweb, gnome, ikiwiki, infinoted, janus, jsxc, kiwix, matrixsynapse, mediawiki, minetest, minidlna, miniflux, mumble, nextcloud, openvpn, pagekite, performance, privoxy, quassel, radicale, roundcube, rssbridge, samba, searx, shaarli, shadowsocks, shadowsocksserver, sharing, sogo, syncthing, tiddlywiki, tor, torproxy, transmission, ttrss, wireguard, wordpress, zoph
Aug 22 12:55:03 mezquite /usr/bin/plinth[1542]: Setup completed: zoph: App installed. None
Aug 22 12:55:05 mezquite /usr/bin/plinth[1542]: # zoph..is_configured()
Aug 22 12:55:05 mezquite sudo[18242]: plinth : PWD=/ ; USER=root ; COMMAND=/usr/share/plinth/actions/actions zoph is_configured --write-fd 18

joseph · August 25, 2025, 3:44am

Zoph seems to have installed okay. There’s a new issue, however. I’m just going to make a salsa issue for this.

Clicking on the Zoph tile to open the app management page throws this error:
Error:

Error loading page. Details

Error running action: zoph..get_configuration(): CalledProcessError(96, ["zoph", "--dump-config"])
Action traceback:
╞   File "/usr/lib/python3/dist-packages/plinth/actions.py", line 398, in _privileged_call
╞     return_values = func(*arguments['args'], **arguments['kwargs'])
╞   File "/usr/lib/python3/dist-packages/plinth/modules/zoph/privileged.py", line 34, in get_configuration
╞     process = subprocess.run(['zoph', '--dump-config'], stdout=subprocess.PIPE,
╞                              check=True)
╞   File "/usr/lib/python3.13/subprocess.py", line 577, in run
╞     raise CalledProcessError(retcode, process.args,
╞                              output=stdout, stderr=stderr)

Trying these manually I get these results
joseph@freedombox:~$ zoph --dump-config #run using my ordinary user with admin priveleges PHP Warning: parse_ini_file(/etc/zoph.ini): Failed to open stream: Permission denied in /usr/bin/zoph on line 183 PHP Fatal error: Uncaught TypeError: array_shift(): Argument #1 ($array) must be of type array, false given in /usr/bin/zoph:192 Stack trace: #0 /usr/bin/zoph(192): array_shift() #1 /usr/bin/zoph(76): loadINI() #2 /usr/bin/zoph(66): init() #3 {main} thrown in /usr/bin/zoph on line 192 joseph@freedombox:~$ sudo zoph --dump-config #running as root is invalid for zoph again root is not a valid user



  
      

      GitLab
  

  
    

Zoph on Trixie. Opening the app management page fails with an error. (#2538)...

  If you run /usr/bin/zoph as root it will fail with the message root is not a valid user and exit with a value of 96. This is...


  

  
    
    
  

  


FYI @KenW I think you are watching zoph progress.