Daily Usenet report: Jul 31 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Jul 31 10:54:26 newsfeed nnrpd[251139]: SERVER perl filter not defined
Jul 31 12:29:55 newsfeed nnrpd[255433]: SERVER perl filter not defined
Jul 31 12:42:05 newsfeed nnrpd[255979]: SERVER perl filter not defined
Jul 31 15:22:31 newsfeed nnrpd[263581]: SERVER perl filter not defined
Aug 1 02:00:02 newsfeed pgpverify[292380]: pgpverify: /usr/bin/gpg1: no such file
Aug 1 02:04:02 newsfeed pgpverify[292436]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	194936	82.6%	23.0 MB	82.6%
innfeed	31211	13.2%	3.7 MB	13.2%
nnrpd	7702	3.3%	858.4 KB	3.0%
innd	2249	1.0%	341.6 KB	1.2%
controlchan	33	0.0%	7.7 KB	0.0%
pgpverify	2	0.0%	0.2 KB	0.0%
overchan	1	0.0%	0.0 KB	0.0%
TOTAL: 7	236134	100.0%	27.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	189747	99.3%
Negative hits	1164	0.6%
Positive hits	154	0.1%
Cache misses	35	0.0%
TOTAL: 4	191100	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.070	0.0%	39	0.000	27.436	115.000
article cleanup	00:00:01.073	0.0%	195361	0.000	0.005	0.012
article logging	00:00:03.979	0.0%	195419	0.000	0.020	0.031
article parse	00:00:05.530	0.0%	617953	0.000	0.009	0.200
article write	00:00:17.262	0.0%	195222	0.000	0.088	18.000
artlog/artcncl	00:00:00.001	0.0%	39	0.000	0.026	1.000
data move	00:00:00.719	0.0%	741064	0.000	0.001	0.067
hisgrep/artcncl	00:00:00.377	0.0%	39	0.000	9.667	18.000
hishave/artcncl	00:00:00.249	0.0%	39	0.000	6.385	15.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:32:41.022	2.3%	196694	2.000	9.970	18.682
history sync	00:00:00.011	0.0%	388	0.000	0.028	0.500
history write	00:32:52.840	2.3%	195329	2.000	10.100	19.500
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	22:51:01.993	95.1%	544839	11.423	150.984	23112.346
nntp read	00:00:07.306	0.0%	497151	0.000	0.015	1.000
overview write	00:00:00.489	0.0%	195222	0.000	0.003	0.008
perl filter	00:03:04.656	0.2%	195329	0.661	0.945	82.000
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:06.614	0.0%	478432	0.000	0.014	0.500
TOTAL: 24:00:56.136	24:00:25.191	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.197	0.0%	173642	0.000	0.001	0.014
article prepare	00:00:00.004	0.0%	49560	0.000	0.000	0.007
article read	00:00:31.806	0.0%	12378	0.133	2.570	32.182
backlog stats	00:00:00.085	0.0%	231695	0.000	0.000	0.010
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:01.233	0.0%	155492	0.000	0.008	0.039
data write	00:00:01.019	0.0%	68535	0.000	0.015	0.109
idle	23:55:58.985	99.9%	231695	44.431	371.864	4694.617
status file	00:00:05.463	0.0%	11136	0.300	0.491	1.810
TOTAL: 23:56:44.086	23:56:38.792	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:54.924	72.9%	4203	0.000	13.068	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.185	0.2%	10351	0.000	0.018	0.500
TOTAL: 00:01:15.385	00:00:55.109	73.1%	-	-	-	-

Control commands to INND:

Command	Number
flush	27
flushlogs	2
go	2
logmode	2
lowmark	1
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Control Channel:

Sender	Other	Bad PGP	OK
bofh-control@killfile.org	1	0	1
moderator@dana.de	1	1	1
us-control@lists.killfile.org	0	1	0
TOTAL	2	2	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	37	194798	194675	0	123	99%	27:12:17
2	news.icm.edu.pl	42	167	33	118	16	19%	10:36:41
3	localhost	5	4	4	0	0	100%	00:00:01
TOTAL: 3		84	194969	194712	118	139	99%	37:48:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	2.0 GB	250.2 KB	545.2 KB	2.0 GB	99%	10.6 KB
2	news.icm.edu.pl	487.2 KB	184.3 KB	202.6 KB	874.0 KB	55%	17.8 KB
3	localhost	6.3 KB	0.0 KB	0.0 KB	6.3 KB	100%	1.6 KB
TOTAL: 3		2.0 GB	434.5 KB	747.7 KB	2.0 GB	99%	10.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 31 04:15:02 - 04:59:59	36517	18.8%	13.53	445.5 MB	22.0%	169.07
Jul 31 05:00:00 - 05:59:59	18795	9.7%	5.22	141.4 MB	7.0%	40.22
Jul 31 06:00:00 - 06:59:59	11938	6.1%	3.32	94.2 MB	4.7%	26.79
Jul 31 07:00:00 - 07:59:59	6791	3.5%	1.89	64.3 MB	3.2%	18.30
Jul 31 08:00:00 - 08:59:59	5785	3.0%	1.61	68.0 MB	3.4%	19.33
Jul 31 09:00:00 - 09:59:59	7134	3.7%	1.98	77.8 MB	3.8%	22.14
Jul 31 10:00:00 - 10:59:59	8646	4.4%	2.40	84.1 MB	4.2%	23.93
Jul 31 11:00:00 - 11:59:59	5063	2.6%	1.41	61.5 MB	3.0%	17.50
Jul 31 12:00:00 - 12:59:59	2936	1.5%	0.82	70.8 MB	3.5%	20.15
Jul 31 13:00:00 - 13:59:59	3	0.0%	0.00	32.1 KB	0.0%	0.01
Jul 31 14:00:00 - 14:59:59	2	0.0%	0.00	19.5 KB	0.0%	0.01
Jul 31 15:00:00 - 15:59:59	2	0.0%	0.00	15.1 KB	0.0%	0.00
Jul 31 16:00:00 - 16:59:59	7	0.0%	0.00	75.3 KB	0.0%	0.02
Jul 31 17:00:00 - 17:59:59	6058	3.1%	1.68	78.4 MB	3.9%	22.30
Jul 31 18:00:00 - 18:59:59	14652	7.5%	4.07	147.3 MB	7.3%	41.90
Jul 31 19:00:00 - 19:59:59	10299	5.3%	2.86	92.2 MB	4.6%	26.22
Jul 31 20:00:00 - 20:59:59	8514	4.4%	2.37	88.1 MB	4.4%	25.07
Jul 31 21:00:00 - 21:59:59	7234	3.7%	2.01	62.7 MB	3.1%	17.82
Jul 31 22:00:00 - 22:59:59	6447	3.3%	1.79	50.1 MB	2.5%	14.26
Jul 31 23:00:00 - 23:59:59	5412	2.8%	1.50	59.3 MB	2.9%	16.88
Aug 1 00:00:00 - 00:59:59	6690	3.4%	1.86	76.0 MB	3.8%	21.63
Aug 1 01:00:00 - 01:59:59	3965	2.0%	1.10	42.7 MB	2.1%	12.15
Aug 1 02:00:00 - 02:59:59	4562	2.3%	1.27	51.1 MB	2.5%	14.54
Aug 1 03:00:00 - 03:59:59	14723	7.6%	4.09	142.8 MB	7.1%	40.61
Aug 1 04:00:00 - 04:15:02	2525	1.3%	2.80	24.9 MB	1.2%	28.28
TOTAL: 24:00:00	194700	100.0%	2.25	2.0 GB	100.0%	23.98

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	181	99	17	7	58
2	news.icm.edu.pl	16	0	15	0	1
TOTAL: 2		197	99	32	7	59

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	55
comp.sys.raspberry-pi	11
alt.comp.software.firefox	6
comp.mobile.android	5
comp.mobile.ipad	5
eternal-september.test	4
eternal-september.talk	2
fr.misc.actualite.covid19	2
aioe.test	2
fido7.ru.linux.redhat	1
uk.radio.amateur.moderated	1
fido7.real.siberian.valenok	1
grisbi.test	1
it.tlc.cellulari.android	1
comp.infosystems.gemini	1
comp.sys.mac.vintage	1
TOTAL: 16	99

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
christnet.bible	7
TOTAL: 1	7

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	1
TOTAL: 1	1

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	58
	TOTAL: 1	58
TOTAL: 1		58

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	98689	500	69039	10827	4	93688	0%	23:59:51
2	icm	5533	73	4750	4	0	0	1%	18:51:49
3	poznan	32	18	14	0	0	14	56%	06:11:06
4	nask	0	0	0	0	0	84985	0%	23:50:01
5	okapi	0	0	0	0	0	493	0%	23:50:01
TOTAL: 5		104254	591	73803	10831	4	179180	0%	96:42:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	1.7 MB	78.8 MB	80.5 MB	1.0 KB/s	7.3 KB	23:59:51
2	poznan	258.4 KB	0.0 KB	258.4 KB	0.0 KB/s	14.4 KB	06:11:06
3	icm	179.3 KB	29.6 KB	208.9 KB	0.0 KB/s	2.7 KB	18:51:49
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
TOTAL: 5		2.1 MB	78.8 MB	80.9 MB	0.2 KB/s	7.3 KB	96:42:48

Backlog files shrunk by innfeed:

Server	Size
panorama	5.2 MB
TOTAL: 1	5.2 MB

NNRP readership statistics:

System		Conn	Post	Elapsed
1	zl-ams-nl-gp1-wk117b.internet-census.org	1	0	00:00:10
2	206.168.34.113	3	0	00:00:05
3	205.210.31.227	1	0	00:00:10
4	isvcs01.man.lodz.pl	4	4	00:00:00
5	162.142.125.205	3	0	00:00:03
6	172-232-220-31.ip.linodeusercontent.com	1	0	00:00:00
7	212.51.192.185	96	0	00:00:01
8	web.man.lodz.pl	288	0	00:00:08
9	198.235.24.112	1	0	00:00:10
10	71.6.134.230	1	0	00:00:00
11	80.111.142.213	4	0	00:00:04
12	167.94.138.37	3	0	00:00:07
13	nms2.man.lodz.pl	1440	0	00:00:13
TOTAL: 13		1846	4	00:01:15

NNRP readership statistics (by domain):

System		Conn	Post	Elapsed
1	*.ip.linodeusercontent.com	1	0	00:00:00
2	*.man.lodz.pl	1732	4	00:00:22
3	*.internet-census.org	1	0	00:00:10
4	unresolved	112	0	00:00:42
TOTAL: 4		1846	4	00:01:15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
nms2.man.lodz.pl	4.027	8.610	00:00:13
zl-ams-nl-gp1-wk117b.internet-census.org	0.006	0.006	00:00:10
198.235.24.112	0.003	0.003	00:00:10
205.210.31.227	0.007	0.000	00:00:10
web.man.lodz.pl	0.851	1.785	00:00:08
167.94.138.37	0.012	0.023	00:00:07
206.168.34.113	0.007	0.016	00:00:05
80.111.142.213	0.082	0.048	00:00:04
162.142.125.205	0.015	0.019	00:00:03
212.51.192.185	0.246	0.590	00:00:01
71.6.134.230	0.004	0.007	00:00:00
isvcs01.man.lodz.pl	0.020	0.028	00:00:00
172-232-220-31.ip.linodeusercontent.com	0.007	0.004	00:00:00
?	0.000	0.006	00:00:00
TOTAL: 14	5.287	11.145	00:01:15

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP unrecognized commands (by host):

System	Conn
162.142.125.205	1
206.168.34.113	1
167.94.138.37	1
TOTAL: 3	3

NNRP unrecognized commands (by command):

Command	Count
EHLO www.censys.io	3
TOTAL: 1	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
162.142.125.205	1	2
206.168.34.113	1	2
167.94.138.37	1	2
TOTAL: 3	3	6

Daily Usenet report

Jul 31 04:15:02 -- Aug 1 04:15:02