View Issue Details

IDProjectCategoryView StatusLast Update
0000333My infrastructureGeneralpublic2025-12-02 13:13
Reporterdvl Assigned Todvl  
PrioritynormalSeverityminorReproducibilityhave not tried
Status assignedResolutionopen 
Summary0000333: Password for user reading_dev
DescriptionFrom /var/log/freshports/freshports-daemon.log:

Oct 21 17:00:07 dev-ingress01 freshports[93171]: Password for user reading_dev: 
Oct 21 17:00:07 dev-ingress01 freshports[93171]: psql: error: connection to server at "pg03.int.unixathome.org" (10.55.0.34), port 5432 failed: fe_sendauth: no password supplied
Oct 21 17:00:07 dev-ingress01 freshports[93171]: Password for user reading_dev: 
Oct 21 17:00:07 dev-ingress01 freshports[93171]: psql: error: connection to server at "pg03.int.unixathome.org" (10.55.0.34), port 5432 failed: fe_sendauth: no password supplied
Oct 21 17:00:07 dev-ingress01 freshports[93171]: missing-port-categories.sh finished with exit status '0' and ''
Steps To ReproduceHas also occurred on:

* stage-ingress01
* r720-02-ingress01
* test-ingress01
TagsNo tags attached.

Activities

dvl

2025-11-27 13:24

manager   ~0000496

Last edited: 2025-11-27 13:25

The problem arises on multiple hosts at about the same time. I suspect it is commit processing related.

Nov 25 20:00:06 dvl-ingress01 freshports[16576]: removing /var/db/ingress/message-queues/incoming/2025.11.25.19.57.38.000000.0680fef58a3816c8c1860146f7351ee8b0081542.xml

https://www.freshports.org/commit.php?message_id=0680fef58a3816c8c1860146f7351ee8b0081542

x11/salut
MOVED
x11/Makefile


Nov 17 14:45:07 dev-ingress01 freshports[13449]: processing /var/db/ingress/message-queues/incoming/2025.11.17.14.42.17.000000.af4ba95daf75cf1b1624dd57038cfaa3ed2753e7.xml

https://www.freshports.org/commit.php?message_id=af4ba95daf75cf1b1624dd57038cfaa3ed2753e7

/base/head/usr.sbin/freebsd-update/freebsd-update.sh


Need more data...

dvl

2025-11-27 17:30

manager   ~0000497

This problem first arose on Oct 14 - it would be nice to have all the logs back to then. I've change log retention to 50 days.

Now, in the meantime, I think I can look at snapshots...?

dvl

2025-11-27 17:37

manager   ~0000498

We don't keep those snapshots very long:

[17:31 r730-01 dvl /jails/dvl-ingress01/jails/.zfs/snapshot] % ls -l
total 7
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_09:00:00_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_10:00:08_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_11:00:00_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_12:00:05_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_13:00:04_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_13:30:01_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_13:45:05_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_14:00:01_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_14:00:01_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_14:15:04_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_14:30:03_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_14:45:06_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_15:00:04_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.16 18:36 autosnap_2025-09-17_15:00:04_hourly/
[17:31 r730-01 dvl /jails/dvl-ingress01/jails/.zfs/snapshot] % 


AND THOSE don't look like they are recent either.

Looking over here, same problem:

[17:36 r730-01 dvl /jails/dev-ingress01/jails/.zfs/snapshot] % ls -l                                             
total 9
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_09:00:06_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_10:00:08_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_11:00:02_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_12:00:00_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_13:00:04_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_13:30:01_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_13:45:05_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_14:00:05_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_14:00:05_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_14:15:04_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_14:30:03_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_14:45:06_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_15:00:03_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_15:00:03_hourly/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_15:15:05_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_15:30:02_frequently/
drwxr-xr-x  3 root wheel 3 2025.09.11 18:23 autosnap_2025-09-17_15:45:05_frequently/


Let's list the snapshots instead of using ls.

[17:36 r730-01 dvl /jails/dev-ingress01/jails/.zfs/snapshot] % zfs list -t snapshot -r data02/jails/dvl-ingress01
NAME                                                                 USED  AVAIL  REFER  MOUNTPOINT
data02/jails/dvl-ingress01@mkjail-202509051453                      1.54G      -  5.04G  -
data02/jails/dvl-ingress01@autosnap_2025-11-21_00:00:14_daily        197M      -  5.27G  -
data02/jails/dvl-ingress01@autosnap_2025-11-22_00:00:00_daily       2.73M      -  5.27G  -
data02/jails/dvl-ingress01@autosnap_2025-11-23_00:00:00_daily       2.32M      -  5.27G  -
data02/jails/dvl-ingress01@autosnap_2025-11-24_00:00:09_daily       25.7M      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-25_00:00:06_daily       25.6M      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-26_00:00:00_daily       2.44M      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_00:00:06_daily       1.95M      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_11:00:05_hourly      1.62M      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_12:00:04_hourly      1.60M      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_13:00:09_hourly         0B      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_13:00:09_frequently     0B      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_13:15:06_frequently   452K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_13:30:00_frequently   384K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_13:45:10_frequently   292K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_14:00:02_hourly       160K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_14:00:02_frequently   160K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_14:15:05_frequently   412K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_14:30:08_frequently   376K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_14:45:10_frequently   396K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_15:00:09_hourly         0B      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_15:00:09_frequently     0B      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_15:15:07_frequently   424K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_15:30:02_frequently   372K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_15:45:06_frequently   372K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_16:00:03_hourly         0B      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_16:00:03_frequently     0B      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_16:15:04_frequently   420K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_16:30:02_frequently   372K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_16:45:04_frequently   388K      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_17:00:06_hourly         0B      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_17:00:06_frequently     0B      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_17:15:06_frequently  1.23M      -  5.28G  -
data02/jails/dvl-ingress01@autosnap_2025-11-27_17:30:03_frequently   476K      -  5.27G  -


OK, that looks better. Where are they on disk? Am I in the wrong place?

dvl

2025-11-27 17:39

manager   ~0000499

I was in the wrong directory. /jails/dev-ingress01/jails is the jails directory inside the jail.

[17:36 r730-01 dvl /jails/dev-ingress01/jails/.zfs/snapshot] % zfs get mountpoint data02/jails/dvl-ingress01
NAME                        PROPERTY    VALUE                 SOURCE
data02/jails/dvl-ingress01  mountpoint  /jails/dvl-ingress01  inherited from data02/jails


I want this directory:

[17:38 r730-01 dvl /jails/dev-ingress01/jails/.zfs/snapshot] % cd /jails/dvl-ingress01
[17:38 r730-01 dvl /jails/dvl-ingress01] % cd .zfs/snapshot
[17:38 r730-01 dvl /jails/dvl-ingress01/.zfs/snapshot] % ls -l
total 289
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-21_00:00:14_daily/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-22_00:00:00_daily/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-23_00:00:00_daily/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-24_00:00:09_daily/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-25_00:00:06_daily/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-26_00:00:00_daily/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_00:00:06_daily/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_11:00:05_hourly/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_12:00:04_hourly/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_13:00:09_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_13:00:09_hourly/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_13:15:06_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_13:30:00_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_13:45:10_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_14:00:02_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_14:00:02_hourly/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_14:15:05_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_14:30:08_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_14:45:10_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_15:00:09_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_15:00:09_hourly/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_15:15:07_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_15:30:02_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_15:45:06_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_16:00:03_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_16:00:03_hourly/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_16:15:04_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_16:30:02_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_16:45:04_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_17:00:06_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_17:00:06_hourly/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_17:15:06_frequently/
drwxr-xr-x  19 root wheel 23 2025.09.17 19:44 autosnap_2025-11-27_17:30:03_frequently/
drwxr-xr-x  19 root wheel 23 2025.04.11 11:38 mkjail-202509051453/

dvl

2025-11-27 17:50

manager   ~0000500

Looking back in snapshots, I find data for this occurrence:

Nov 17 14:52:27 dev-ingress01 sshd-session[46393]: Read error from remote host 10.8.1.220 port 54513: Operation timed out
Nov 17 15:00:30 dev-ingress01 freshports[13449]: Password for user reading_dev: 
Nov 17 15:00:30 dev-ingress01 freshports[13449]: psql: error: connection to server at "pg03.int.unixathome.org" (10.55.0.34), port 5432 failed: fe_sendauth: no password supplied
Nov 17 15:00:30 dev-ingress01 freshports[13449]: Password for user reading_dev: 
Nov 17 15:00:30 dev-ingress01 freshports[13449]: psql: error: connection to server at "pg03.int.unixathome.org" (10.55.0.34), port 5432 failed: fe_sendauth: no password supplied
Nov 17 15:00:30 dev-ingress01 freshports[13449]: missing-port-categories.sh finished with exit status '0' and ''


Which corresponds to this from this file:

[17:48 r730-01 dvl /jails/dev-ingress01/.zfs/snapshot/autosnap_2025-11-21_00:00:14_daily/var/log/freshports] % sudo less freshports-daemon.log.3


Nov 17 15:00:08 dev-ingress01 freshports[13449]: removing /var/db/ingress/message-queues/incoming/2025.11.17.14.58.26.000000.c4c689ff952921d66fff1eaa9d478200e40c01f9.xml
Nov 17 15:00:08 dev-ingress01 freshports[13449]: removal completed
Nov 17 15:00:08 dev-ingress01 freshports[13449]: yes, there is a job waiting
Nov 17 15:00:30 dev-ingress01 freshports[13449]: process_moved.sh finished with exit status '0' and ''
Nov 17 15:00:30 dev-ingress01 freshports[13449]: Password for user reading_dev: 
Nov 17 15:00:30 dev-ingress01 freshports[13449]: psql: error: connection to server at "pg03.int.unixathome.org" (10.55.0.34), port 5432 failed: fe_sendauth: no password supplied
Nov 17 15:00:30 dev-ingress01 freshports[13449]: Password for user reading_dev: 
Nov 17 15:00:30 dev-ingress01 freshports[13449]: psql: error: connection to server at "pg03.int.unixathome.org" (10.55.0.34), port 5432 failed: fe_sendauth: no password supplied
Nov 17 15:00:30 dev-ingress01 freshports[13449]: missing-port-categories.sh finished with exit status '0' and ''
Nov 17 15:00:30 dev-ingress01 freshports[13449]: job-waiting.pl finishes normally


That commit is https://www.freshports.org/commit.php?message_id=c4c689ff952921d66fff1eaa9d478200e40c01f9 and corresponds to:

security/radamsa
MOVED
security/Makefile

dvl

2025-11-27 17:58

manager   ~0000501

I also found https://www.freshports.org/commit.php?message_id=b043c72cd36217446610d9f24745120c5cc8f2d7

which is for security/vuxml

dvl

2025-12-01 12:32

manager   ~0000502

Last edited: 2025-12-01 12:35

The following log output was preceded by

Nov 30 22:00:05 dvl-ingress01 freshports[16175]: removing /var/db/ingress/message-queues/incoming/2025.11.30.21.57.25.000000.dd09ed909741e10978fe7927b0989d6fdbbcfb02.xml


That was security/vuxml: https://cgit.freebsd.org/ports/commit/?id=dd09ed909741e10978fe7927b0989d6fdbbcfb02

Nov 30 22:00:06 dvl-ingress01 freshports[16175]: grep: //.pgpass: No such file or directory
Nov 30 22:00:06 dvl-ingress01 freshports[16175]: Password for user reading_dvl: 
Nov 30 22:00:06 dvl-ingress01 freshports[16175]: psql: error: connection to server at "pg01.int.unixathome.org" (10.55.0.150), port 5432 failed: fe_sendauth: no password supplied
Nov 30 22:00:06 dvl-ingress01 freshports[16175]: Password for user reading_dvl: 
Nov 30 22:00:06 dvl-ingress01 freshports[16175]: psql: error: connection to server at "pg01.int.unixathome.org" (10.55.0.150), port 5432 failed: fe_sendauth: no password supplied
Nov 30 22:00:06 dvl-ingress01 freshports[16175]: missing-port-categories.sh finished with exit status '0' and ''
Nov 30 22:00:06 dvl-ingress01 missing-port-categories.sh[26343]: This is debug code for the periodic issue: failed: fe_sendauth: no password supplied
Nov 30 22:00:06 dvl-ingress01 missing-port-categories.sh[26349]: Checking for the reading_dvl user password
Nov 30 22:00:06 dvl-ingress01 missing-port-categories.sh[26365]: done checking for the user password
Nov 30 22:00:06 dvl-ingress01 missing-port-categories.sh[26375]: who am i? uid=10001(freshports) gid=10001(freshports) groups=10001(freshports)


2nd instance:

Nov 30 22:00:17 dvl-ingress01 missing-port-categories.sh[27325]: This is debug code for the periodic issue: failed: fe_sendauth: no password supplied
Nov 30 22:00:17 dvl-ingress01 missing-port-categories.sh[27329]: Checking for the reading_dvl user password
Nov 30 22:00:17 dvl-ingress01 missing-port-categories.sh[27334]: /var/db/freshports/.pgpass
Nov 30 22:00:17 dvl-ingress01 missing-port-categories.sh[27338]: done checking for the user password
Nov 30 22:00:17 dvl-ingress01 missing-port-categories.sh[27343]: who am i? uid=10001(freshports) gid=10001(freshports) groups=10001(freshports)

dvl

2025-12-01 12:42

manager   ~0000503

See also:

https://cgit.freebsd.org/ports/commit/?id=0680fef58a3816c8c1860146f7351ee8b0081542

Which involves MOVED

Nov 25 20:00:03 dvl-ingress01 freshports[16576]: /usr/local/bin/perl /usr/local/libexec/freshports/load_xml_into_db.pl /var/db/ingress/message-queues/incoming/2025.11.25.19.57.38.000000.0680fef58a3816c8c186014
6f7351ee8b0081542.xml /var/db/freshports/message-queues/spooling/2025.11.25.19.57.38.000000.0680fef58a3816c8c1860146f7351ee8b0081542.log /var/db/freshports/message-queues/spooling/2025.11.25.19.57.38.000000.06
80fef58a3816c8c1860146f7351ee8b0081542.errors
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: XML loading finished
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: result=0
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: drwxrwxr-x  4 freshports freshports  266 Nov 25 19:48 /var/db/freshports/message-queues/recent/
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: drwxrwxr-x  2 ingress    freshports    4 Nov 25 20:00 /var/db/ingress/message-queues/incoming/
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: -rw-rw-r--  1 ingress    ingress    1062 Nov 25 20:00 /var/db/ingress/message-queues/incoming/2025.11.25.19.57.38.000000.0680fef58a3816c8c1860146f7351ee8b0081542.xml
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: removing /var/db/ingress/message-queues/incoming/2025.11.25.19.57.38.000000.0680fef58a3816c8c1860146f7351ee8b0081542.xml
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: removal completed
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: yes, there is a job waiting
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: grep: //.pgpass: No such file or directory
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: Password for user reading_dvl: 
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: psql: error: connection to server at "pg01.int.unixathome.org" (10.55.0.150), port 5432 failed: fe_sendauth: no password supplied
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: Password for user reading_dvl: 
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: psql: error: connection to server at "pg01.int.unixathome.org" (10.55.0.150), port 5432 failed: fe_sendauth: no password supplied
Nov 25 20:00:06 dvl-ingress01 freshports[16576]: missing-port-categories.sh finished with exit status '0' and ''
Nov 25 20:00:15 dvl-ingress01 freshports[16576]: process_moved.sh finished with exit status '0' and ''
Nov 25 20:00:15 dvl-ingress01 freshports[16576]: job-waiting.pl finishes normally
/

dvl

2025-12-01 12:47

manager   ~0000504

Commits which affect only MOVED do not invoke missing-port-categories.sh

dvl

2025-12-02 12:57

manager   ~0000505

Last edited: 2025-12-02 13:05

Happened again on dev.

This one is:

* https://cgit.freebsd.org/ports/commit/?id=d7f4e84f286f30a1cf7cf94f80df751fb9eb5505
* security/vuxml

[13:00 dev-ingress01 dvl /var/log/freshports] % sudo less freshports-daemon.log.0
Dec  1 17:00:08 dev-ingress01 freshports[13150]: XML loading finished
Dec  1 17:00:08 dev-ingress01 freshports[13150]: result=0
Dec  1 17:00:08 dev-ingress01 freshports[13150]: drwxrwxr-x  5 freshports freshports 379 Dec  1 17:00 /var/db/freshports/message-queues/recent/
Dec  1 17:00:08 dev-ingress01 freshports[13150]: drwxrwxr-x  2 ingress    freshports   3 Dec  1 17:00 /var/db/ingress/message-queues/incoming/
Dec  1 17:00:08 dev-ingress01 freshports[13150]: -rw-rw-r--  1 ingress    freshports 872 Dec  1 17:00 /var/db/ingress/message-queues/incoming/2025.12.01.16.56.30.000000.d7f4e84f286f30a1cf7cf94f80df751fb9eb5505.xml
Dec  1 17:00:08 dev-ingress01 freshports[13150]: removing /var/db/ingress/message-queues/incoming/2025.12.01.16.56.30.000000.d7f4e84f286f30a1cf7cf94f80df751fb9eb5505.xml
Dec  1 17:00:08 dev-ingress01 freshports[13150]: removal completed
Dec  1 17:00:08 dev-ingress01 freshports[13150]: yes, there is a job waiting
Dec  1 17:00:08 dev-ingress01 freshports[13150]: Password for user reading_dev: 
Dec  1 17:00:08 dev-ingress01 freshports[13150]: psql: error: connection to server at "pg03.int.unixathome.org" (10.55.0.34), port 5432 failed: fe_sendauth: no password supplied
Dec  1 17:00:08 dev-ingress01 freshports[13150]: Password for user reading_dev: 
Dec  1 17:00:08 dev-ingress01 freshports[13150]: psql: error: connection to server at "pg03.int.unixathome.org" (10.55.0.34), port 5432 failed: fe_sendauth: no password supplied
Dec  1 17:00:08 dev-ingress01 freshports[13150]: missing-port-categories.sh finished with exit status '0' and ''

dvl

2025-12-02 13:13

manager   ~0000506

see also.... this shows the SQL being invoked.

[13:08 dev-ingress01 dvl /var/log/freshports] % sudo less freshports.log.0
Dec  1 17:00:04 dev-ingress01 FreshPorts[50596]: Finished running check_git.sh (/usr/local/libexec/freshports) 
Dec  1 17:00:04 dev-ingress01 FreshPorts[50596]: flag '/var/db/ingress/signals/check_git' not set.  no work for check_git.sh (/usr/local/libexec/freshports) 
Dec  1 17:00:06 dev-ingress01 FreshPorts[53191]: applying special processing to /ports/head/security/vuxml/vuln/2025.xml 
Dec  1 17:00:08 dev-ingress01 FreshPorts[53829]: running job-waiting.pl 
Dec  1 17:00:08 dev-ingress01 FreshPorts[53829]: starting ./job-waiting.pl (/usr/local/libexec/freshports) 
Dec  1 17:00:08 dev-ingress01 FreshPorts[53829]: running ./job-waiting.pl as user = 'freshports' (/usr/local/libexec/freshports) 
Dec  1 17:00:08 dev-ingress01 FreshPorts[53829]: checking jobs for freshports (/usr/local/libexec/freshports) 
Dec  1 17:00:08 dev-ingress01 FreshPorts[53829]: /var/db/freshports/signals/check_port_categories exists.  About to run missing-port-categories.sh via system() (/usr/local/libexec/freshports) 
Dec  1 17:00:08 dev-ingress01 FreshPorts[53829]: details are: /usr/local/libexec/freshports/missing-port-categories.sh >> /var/log/freshports/jobs_waiting.log (/usr/local/libexec/freshports) 
Dec  1 17:00:08 dev-ingress01 missing-port-categories.sh[53835]: Checking for ports without ports_categories entries
Dec  1 17:00:08 dev-ingress01 missing-port-categories.sh[53841]: /usr/local/bin/psql -h pg03.int.unixathome.org -q --pset t -d freshports.dev --user reading_dev -c select count(id) from ports_active PA WHERE NOT EXISTS (SELECT port_id, category_id from ports_categories PC where PC.port_id = PA.id and PC.category_id = PA.category_id)
Dec  1 17:00:08 dev-ingress01 missing-port-categories.sh[53860]: found this many invalid entries:
Dec  1 17:00:08 dev-ingress01 FreshPorts[53829]: missing-port-categories.sh finished with exit status '0' and '' (/usr/local/libexec/freshports) 
Dec  1 17:00:08 dev-ingress01 FreshPorts[53829]: Finished running missing-port-categories.sh (/usr/local/libexec/freshports) 
Dec  1 17:00:08 dev-ingress01 FreshPorts[53829]: /var/db/freshports/signals/vuxml exists.  About to run process_vuxml.sh via system() (/usr/local/libexec/freshports) 
Dec  1 17:00:08 dev-ingress01 FreshPorts[53829]: details are: /usr/local/libexec/freshports/process_vuxml.sh >> /var/log/freshports/jobs_waiting.log (/usr/local/libexec/freshports) 
Dec  1 17:00:08 dev-ingress01 process_vuxml.sh[53887]: vuxml starts
Dec  1 17:00:08 dev-ingress01 process_vuxml.sh[53894]: vuxml processing begins
Dec  1 17:00:08 dev-ingress01 process_vuxml.sh[53899]: process_vuxml.pl begins on /jails/freshports/usr/ports/security/vuxml/vuln.xml
Dec  1 17:00:08 dev-ingress01 process_vuxml.sh[53906]: there is often a delay before the next message
Dec  1 17:00:31 dev-ingress01 process_vuxml_import_temp_file.py[55892]: Starting up
Dec  1 17:00:31 dev-ingress01 process_vuxml_import_temp_file.py[55892]: copying in from /tmp/freshports_vuxml_processing_vuE2m
Dec  1 17:00:31 dev-ingress01 process_vuxml_import_temp_file.py[55892]: copying completed. 6134 rows copied.
Dec  1 17:00:31 dev-ingress01 process_vuxml_import_temp_file.py[55892]: Finishing

Issue History

Date Modified Username Field Change
2025-10-22 11:46 dvl New Issue
2025-11-12 12:42 dvl Assigned To => dvl
2025-11-12 12:42 dvl Status new => assigned
2025-11-27 13:24 dvl Note Added: 0000496
2025-11-27 13:25 dvl Note Edited: 0000496
2025-11-27 17:30 dvl Note Added: 0000497
2025-11-27 17:37 dvl Note Added: 0000498
2025-11-27 17:39 dvl Note Added: 0000499
2025-11-27 17:50 dvl Note Added: 0000500
2025-11-27 17:58 dvl Note Added: 0000501
2025-12-01 12:32 dvl Note Added: 0000502
2025-12-01 12:35 dvl Note Edited: 0000502
2025-12-01 12:42 dvl Note Added: 0000503
2025-12-01 12:47 dvl Note Added: 0000504
2025-12-02 12:57 dvl Note Added: 0000505
2025-12-02 13:05 dvl Note Edited: 0000505
2025-12-02 13:13 dvl Note Added: 0000506