Daily Usenet report: Sep 2 04:15:05

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Sep 2 11:06:53 newsfeed nnrpd[6349]: SERVER perl filter not defined
Sep 2 11:07:09 newsfeed nnrpd[6350]: SERVER perl filter not defined
Sep 2 12:00:11 newsfeed nnrpd[8157]: SERVER perl filter not defined
Sep 3 01:15:02 newsfeed pgpverify[2943]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2780726	98.3%	325.4 MB	98.1%
innd	37792	1.3%	5.1 MB	1.5%
nnrpd	4868	0.2%	508.2 KB	0.1%
innfeed	4584	0.2%	654.0 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2827974	100.0%	331.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2771590	95.4%
Negative hits	115007	4.0%
Positive hits	18060	0.6%
Cache misses	127	0.0%
TOTAL: 4	2904784	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.238	0.0%	446	0.000	7.260	169.000
article cleanup	00:00:42.077	0.0%	2780281	0.007	0.015	0.024
article logging	00:01:18.698	0.1%	2780881	0.014	0.028	0.402
article parse	01:19:33.678	5.5%	217113774	0.010	0.022	0.065
article write	00:32:38.966	2.3%	2759104	0.329	0.710	4.459
artlog/artcncl	00:00:00.010	0.0%	443	0.000	0.023	1.000
data move	00:03:02.266	0.2%	234921614	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.186	0.0%	137	0.000	1.358	93.000
hishave/artcncl	00:00:00.900	0.0%	446	0.000	2.018	66.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:24:37.788	1.7%	2908045	0.112	0.508	5.749
history sync	00:00:00.011	0.0%	291	0.000	0.038	1.000
history write	00:36:49.061	2.6%	2774784	0.197	0.796	10.345
hiswrite/artcncl	00:00:01.395	0.0%	309	0.000	4.515	20.000
idle	12:55:42.634	53.9%	155994430	0.213	0.298	0.934
nntp read	01:16:05.376	5.3%	214950137	0.012	0.021	0.041
overview write	00:00:15.335	0.0%	2759104	0.001	0.006	0.012
perl filter	05:54:52.886	24.6%	2774784	5.164	7.674	9.843
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:39.736	0.3%	5572615	0.035	0.050	0.091
TOTAL: 24:00:23.326	23:10:24.241	96.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.207	0.0%	54621	0.000	0.004	0.143
article prepare	00:00:00.023	0.0%	90946	0.000	0.000	0.018
article read	00:00:06.156	0.0%	22693	0.000	0.271	17.000
backlog stats	00:00:00.290	0.0%	326383	0.000	0.001	0.032
callbacks	00:00:00.000	0.0%	5	0.000	0.000	0.000
data read	00:00:02.188	0.0%	161537	0.000	0.014	0.100
data write	00:00:17.198	0.0%	166050	0.000	0.104	0.475
idle	23:59:33.161	100.0%	326383	15.991	264.637	4858.000
status file	00:00:01.816	0.0%	1635	0.000	1.111	498.000
TOTAL: 24:00:10.220	24:00:01.039	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:15:03.838	99.7%	19958	0.000	225.666	41062.909
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.847	0.0%	40692	0.000	0.021	0.500
readart	00:00:01.103	0.0%	104	0.000	10.606	33.781
TOTAL: 01:15:17.522	01:15:05.788	99.7%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	189

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1288	2758930	2744052	8251	6627	99%	355:39:33
2	news.nask.pl	2	33188	14566	4115	14507	43%	23:59:29
3	news.icm.edu.pl	44	237	12	224	1	5%	16:48:39
4	localhost	4	3	3	0	0	100%	00:00:01
TOTAL: 4		1338	2792358	2758633	12590	21135	98%	396:27:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	875.9 GB	232.7 MB	463.1 MB	876.6 GB	99%	334.1 KB
2	news.nask.pl	149.4 MB	3.2 MB	327.2 MB	479.8 MB	31%	16.9 KB
3	news.icm.edu.pl	865.6 KB	0.0 KB	321.8 KB	1.2 MB	72%	91.3 KB
4	localhost	5.7 KB	0.0 KB	0.0 KB	5.7 KB	100%	1.9 KB
TOTAL: 4		876.0 GB	235.9 MB	790.6 MB	877.0 GB	99%	330.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 2 04:15:05 - 04:59:59	88051	3.2%	32.67	29.8 GB	3.4%	11594.47
Sep 2 05:00:00 - 05:59:59	120333	4.4%	33.43	39.2 GB	4.5%	11412.48
Sep 2 06:00:00 - 06:59:59	113974	4.1%	31.66	36.9 GB	4.2%	10760.22
Sep 2 07:00:00 - 07:59:59	100380	3.6%	27.88	35.2 GB	4.0%	10257.94
Sep 2 08:00:00 - 08:59:59	110741	4.0%	30.76	37.8 GB	4.3%	10997.91
Sep 2 09:00:00 - 09:59:59	116702	4.2%	32.42	40.5 GB	4.6%	11809.31
Sep 2 10:00:00 - 10:59:59	117219	4.2%	32.56	38.6 GB	4.4%	11239.16
Sep 2 11:00:00 - 11:59:59	109948	4.0%	30.54	37.6 GB	4.3%	10963.52
Sep 2 12:00:00 - 12:59:59	109326	4.0%	30.37	37.2 GB	4.2%	10825.65
Sep 2 13:00:00 - 13:59:59	107196	3.9%	29.78	36.6 GB	4.2%	10652.20
Sep 2 14:00:00 - 14:59:59	79704	2.9%	22.14	29.1 GB	3.3%	8480.45
Sep 2 15:00:00 - 15:59:59	106807	3.9%	29.67	37.5 GB	4.3%	10908.57
Sep 2 16:00:00 - 16:59:59	100647	3.6%	27.96	35.7 GB	4.1%	10385.61
Sep 2 17:00:00 - 17:59:59	113215	4.1%	31.45	37.2 GB	4.3%	10835.26
Sep 2 18:00:00 - 18:59:59	104718	3.8%	29.09	34.9 GB	4.0%	10176.45
Sep 2 19:00:00 - 19:59:59	104393	3.8%	29.00	34.3 GB	3.9%	9979.08
Sep 2 20:00:00 - 20:59:59	148306	5.4%	41.20	40.4 GB	4.6%	11764.31
Sep 2 21:00:00 - 21:59:59	136070	4.9%	37.80	37.3 GB	4.3%	10871.10
Sep 2 22:00:00 - 22:59:59	130958	4.7%	36.38	38.8 GB	4.4%	11289.15
Sep 2 23:00:00 - 23:59:59	124936	4.5%	34.70	37.6 GB	4.3%	10959.39
Sep 3 00:00:00 - 00:59:59	108401	3.9%	30.11	30.5 GB	3.5%	8898.16
Sep 3 01:00:00 - 01:59:59	120771	4.4%	33.55	34.9 GB	4.0%	10164.01
Sep 3 02:00:00 - 02:59:59	125743	4.6%	34.93	33.1 GB	3.8%	9644.62
Sep 3 03:00:00 - 03:59:59	127525	4.6%	35.42	35.2 GB	4.0%	10250.49
Sep 3 04:00:00 - 04:15:02	32439	1.2%	35.96	9.0 GB	1.0%	10501.25
TOTAL: 23:59:57	2758503	100.0%	31.93	874.9 GB	100.0%	10618.53

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	14829	30	451	4	14344
2	newsfeed.pionier.net.pl	6942	203	5047	0	1692
3	news.icm.edu.pl	9	0	0	0	9
TOTAL: 3		21780	233	5498	4	16045

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	120
it.tlc.cellulari.android	40
uk.rec.cycling.moderated	19
it.comp.os.win.windows10	15
comp.mobile.android	14
comp.sys.raspberry-pi	14
comp.mobile.ipad	3
hr.alt.cellular.gsm.android	2
it.comp.os.win.windows7	2
it.comp.os.linux.ubuntu	2
de.rec.sf.startrek	1
alt.sex.telephones	1
TOTAL: 12	233

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

Groups	Count
pl.praca.oferowana	3
bit.listserv.catholic	1
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12702
Binary in non-binary group	2249
Scoring filter (8)	180
Poison newsgroup	130
EMP (phl)	112
Scoring filter (10)	26
Scoring filter (9)	17
Scoring filter (13)	10
Too many newsgroups	9
Scoring filter (11)	6
Adult group ECP	2
Scoring filter (23)	1
TOTAL: 12	15444

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	21112	777	17673	36	9	36135	3%	24:00:04
2	poznan	21322	181	14021	15	0	0	0%	24:02:47
3	icm	56938	154	27116	21570	0	0	0%	24:04:33
4	nask	7943	33	5063	0	0	0	0%	23:50:25
5	okapi	0	0	0	0	0	2100	0%	24:00:02
TOTAL: 5		107315	1145	63873	21621	9	38235	1%	119:57:51

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	8.9 MB	504.8 KB	9.4 MB	0.1 KB/s	11.9 KB	24:00:04
2	icm	2.4 MB	5.2 GB	5.2 GB	63.3 KB/s	252.6 KB	24:04:33
3	nask	1.2 MB	0.0 KB	1.2 MB	0.0 KB/s	36.4 KB	23:50:25
4	poznan	957.8 KB	58.4 KB	1016.2 KB	0.0 KB/s	5.2 KB	24:02:47
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		13.4 MB	5.2 GB	5.2 GB	12.7 KB/s	241.6 KB	119:57:51

Backlog files shrunk by innfeed:

Server	Size
panorama	2.9 MB
TOTAL: 1	2.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	72	86.4 KB	31	0	00:00:20
2	lze200.lze.lodz.pl	6	32	62.2 KB	4	0	00:16:42
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		297	104	148.5 KB	35	3	01:15:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	675	72	86.4 KB	31	3	00:00:25
2	*.lze.lodz.pl	6	32	62.2 KB	4	0	00:16:42
3	unresolved	2	0	0.0 KB	0	0	00:58:09
TOTAL: 3		683	104	148.5 KB	35	3	01:15:17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.73	0.889	1.330	00:58:09
lze200.lze.lodz.pl	0.027	0.091	00:16:42
web.man.lodz.pl	1.056	4.199	00:00:20
mon.man.lodz.pl	0.922	3.554	00:00:03
david.man.lodz.pl	0.300	1.137	00:00:01
isvcs01.man.lodz.pl	0.022	0.043	00:00:00
TOTAL: 6	3.216	10.354	01:15:17

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.193.73	2
TOTAL: 3	386

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	104	100.0%
TOTAL: 1		104	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	64
2	pl.comp.pecet	25
3	pl.misc.telefonia.gsm	13
4	pl.rec.kuchnia	2
TOTAL: 4		104

Daily Usenet report

Sep 2 04:15:05 -- Sep 3 04:15:02