Daily Usenet report: Feb 22 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Feb 22 09:03:38 newsfeed nnrpd[6186]: SERVER perl filter not defined
Feb 22 09:05:33 newsfeed nnrpd[6275]: SERVER perl filter not defined
Feb 22 12:13:40 newsfeed nnrpd[12762]: SERVER perl filter not defined
Feb 22 12:23:37 newsfeed pgpverify[13126]: pgpverify: verification failed
Feb 22 12:33:37 newsfeed nnrpd[13483]: SERVER perl filter not defined
Feb 22 12:37:32 newsfeed nnrpd[13583]: SERVER perl filter not defined
Feb 22 13:17:21 newsfeed nnrpd[15048]: SERVER perl filter not defined
Feb 22 13:18:08 newsfeed nnrpd[15052]: SERVER perl filter not defined
Feb 22 13:32:06 newsfeed nnrpd[15499]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2403888	97.0%	276.4 MB	96.5%
innfeed	45299	1.8%	5.3 MB	1.9%
innd	25187	1.0%	4.1 MB	1.4%
nnrpd	5042	0.2%	524.4 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2479420	100.0%	286.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2399412	98.3%
Negative hits	24375	1.0%
Positive hits	17366	0.7%
Cache misses	108	0.0%
TOTAL: 4	2441261	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.031	0.0%	170	0.000	23.712	910.000
article cleanup	00:00:34.738	0.0%	2403465	0.005	0.014	0.026
article logging	00:01:16.728	0.1%	2404193	0.013	0.032	0.543
article parse	01:15:41.407	5.3%	178507930	0.013	0.025	0.077
article write	00:24:26.437	1.7%	2394577	0.334	0.612	4.056
artlog/artcncl	00:00:00.006	0.0%	166	0.000	0.036	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:06:15.215	0.4%	194099605	0.001	0.002	0.008
hisgrep/artcncl	00:00:00.658	0.0%	135	0.000	4.874	239.000
hishave/artcncl	00:00:00.073	0.0%	170	0.000	0.429	14.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:39:31.719	2.7%	2442096	0.161	0.971	18.300
history sync	00:00:00.027	0.0%	292	0.000	0.092	2.000
history write	00:51:45.498	3.6%	2400276	0.291	1.294	45.397
hiswrite/artcncl	00:00:00.041	0.0%	35	0.000	1.171	17.000
idle	12:28:04.169	51.9%	133238090	0.102	0.337	0.725
nntp read	01:36:43.726	6.7%	176626950	0.016	0.033	0.055
overview write	00:00:15.256	0.0%	2394577	0.001	0.006	0.016
perl filter	05:43:08.750	23.8%	2400276	5.821	8.578	11.651
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:04.462	0.4%	5010328	0.038	0.061	0.111
TOTAL: 24:00:15.976	23:12:52.941	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.102	0.0%	404573	0.000	0.003	0.053
article prepare	00:00:00.037	0.0%	121308	0.000	0.000	0.125
article read	00:00:45.251	0.1%	30032	0.000	1.507	333.333
backlog stats	00:00:00.730	0.0%	981656	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:07.533	0.0%	579947	0.000	0.013	0.108
data write	00:00:29.838	0.0%	382455	0.000	0.078	0.334
idle	23:58:49.256	99.9%	981656	13.805	87.942	1200.750
status file	00:00:13.414	0.0%	16735	0.333	0.802	2.500
TOTAL: 24:00:54.993	24:00:27.161	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:07:50.959	99.8%	18468	0.000	1195.092	450029.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.767	0.0%	39004	0.000	0.020	0.909
readart	00:00:13.281	0.1%	770	0.000	17.248	308.000
TOTAL: 06:08:25.552	06:08:05.007	99.9%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
control@usenet.org.uk	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	1156	2399827	2391058	271	8498	99%	319:28:56
2	news.nask.pl	2	16937	3243	13335	359	19%	23:10:01
3	news.icm.edu.pl	37	281	57	202	22	20%	16:27:15
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1204	2417053	2394366	13808	8879	99%	359:06:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	766.3 GB	19.7 MB	1.2 GB	767.5 GB	99%	335.4 KB
2	news.nask.pl	12.3 MB	1019.3 KB	1.1 MB	14.4 MB	85%	4.1 KB
3	news.icm.edu.pl	1.2 MB	42.9 KB	1.0 MB	2.3 MB	53%	29.5 KB
4	localhost	15.8 KB	0.0 KB	0.0 KB	15.8 KB	100%	2.0 KB
TOTAL: 4		766.3 GB	20.7 MB	1.2 GB	767.6 GB	99%	334.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 22 04:15:02 - 04:59:59	69869	2.9%	25.90	24.8 GB	3.2%	9637.01
Feb 22 05:00:00 - 05:59:59	84078	3.5%	23.36	30.1 GB	3.9%	8771.74
Feb 22 06:00:00 - 06:59:59	89872	3.8%	24.96	30.5 GB	4.0%	8879.79
Feb 22 07:00:00 - 07:59:59	88411	3.7%	24.56	30.0 GB	3.9%	8746.38
Feb 22 08:00:00 - 08:59:59	88452	3.7%	24.57	29.0 GB	3.8%	8435.83
Feb 22 09:00:00 - 09:59:59	108677	4.5%	30.19	34.0 GB	4.4%	9915.58
Feb 22 10:00:00 - 10:59:59	110985	4.6%	30.83	30.3 GB	4.0%	8823.36
Feb 22 11:00:00 - 11:59:59	103322	4.3%	28.70	33.8 GB	4.4%	9850.78
Feb 22 12:00:00 - 12:59:59	122176	5.1%	33.94	34.8 GB	4.5%	10131.98
Feb 22 13:00:00 - 13:59:59	107787	4.5%	29.94	34.0 GB	4.4%	9889.47
Feb 22 14:00:00 - 14:59:59	92884	3.9%	25.80	32.3 GB	4.2%	9414.57
Feb 22 15:00:00 - 15:59:59	92509	3.9%	25.70	30.6 GB	4.0%	8907.24
Feb 22 16:00:00 - 16:59:59	114230	4.8%	31.73	34.4 GB	4.5%	10009.48
Feb 22 17:00:00 - 17:59:59	117092	4.9%	32.53	33.9 GB	4.4%	9864.55
Feb 22 18:00:00 - 18:59:59	111969	4.7%	31.10	33.7 GB	4.4%	9818.29
Feb 22 19:00:00 - 19:59:59	105847	4.4%	29.40	35.4 GB	4.6%	10311.01
Feb 22 20:00:00 - 20:59:59	94479	3.9%	26.24	32.0 GB	4.2%	9314.28
Feb 22 21:00:00 - 21:59:59	95250	4.0%	26.46	32.0 GB	4.2%	9320.70
Feb 22 22:00:00 - 22:59:59	90363	3.8%	25.10	29.8 GB	3.9%	8666.79
Feb 22 23:00:00 - 23:59:59	93154	3.9%	25.88	29.5 GB	3.9%	8600.15
Feb 23 00:00:00 - 00:59:59	80333	3.4%	22.31	24.7 GB	3.2%	7196.56
Feb 23 01:00:00 - 01:59:59	114879	4.8%	31.91	34.7 GB	4.5%	10106.04
Feb 23 02:00:00 - 02:59:59	103733	4.3%	28.81	33.7 GB	4.4%	9826.07
Feb 23 03:00:00 - 03:59:59	91266	3.8%	25.35	30.8 GB	4.0%	8966.91
Feb 23 04:00:00 - 04:15:02	22491	0.9%	24.93	7.2 GB	0.9%	8412.77
TOTAL: 24:00:00	2394108	100.0%	27.71	765.9 GB	100.0%	9295.74

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	9179	425	2842	1	5911
2	news.nask.pl	413	13	343	2	55
3	news.icm.edu.pl	22	0	2	0	20
TOTAL: 3		9614	438	3187	3	5986

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	278
it.comp.os.win.windows10	56
comp.mobile.ipad	22
it.tlc.cellulari.android	20
comp.mobile.android	18
comp.sys.raspberry-pi	16
it.comp.os.win.windows7	16
it.comp.os.win.windows8	8
uk.rec.cycling.moderated	2
it.comp.os.linux.ubuntu	1
it.comp.dotnet	1
TOTAL: 11	438

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	5136
Binary in non-binary group	91
EMP (md5)	24
HTML post	4
Too many newsgroups	2
Scoring filter (8)	1
TOTAL: 6	5258

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	250419	7207	192058	18977	0	2220	2%	23:54:41
2	panorama	196173	2461	164670	1106	6	233981	1%	24:00:23
3	poznan	5342	125	3215	3	0	0	2%	24:00:14
4	okapi	0	0	0	0	0	2761	0%	24:00:00
5	nask	0	0	0	0	0	185531	0%	24:00:00
TOTAL: 5		451934	9793	359943	20086	6	424493	2%	119:55:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.0 GB	5.2 GB	6.2 GB	75.5 KB/s	248.1 KB	23:54:41
2	panorama	16.0 MB	64.1 MB	80.1 MB	0.9 KB/s	23.0 KB	24:00:23
3	poznan	379.4 KB	8.7 KB	388.0 KB	0.0 KB/s	3.0 KB	24:00:14
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		1.1 GB	5.2 GB	6.3 GB	15.2 KB/s	220.2 KB	119:55:18

Backlog files shrunk by innfeed:

Server	Size
panorama	5.2 MB
TOTAL: 1	5.2 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	19	650	1.3 MB	13	0	05:24:10
2	web.man.lodz.pl	288	84	84.1 KB	37	0	00:00:31
3	auriga.rz.izeto.pl	4	25	38.0 KB	4	0	00:08:30
4	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 4		319	759	1.5 MB	54	8	06:08:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	19	650	1.3 MB	13	0	05:24:10
2	*.man.lodz.pl	680	84	84.1 KB	37	8	00:00:36
3	*.rz.izeto.pl	4	25	38.0 KB	4	0	00:08:30
4	*.elsnet.pl	4	0	0.0 KB	0	0	00:00:18
5	unresolved	5	0	0.0 KB	0	0	00:34:48
TOTAL: 5		712	759	1.5 MB	54	8	06:08:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.169	0.443	05:24:10
89.38.41.25	0.952	1.069	00:34:41
auriga.rz.izeto.pl	0.013	0.071	00:08:30
web.man.lodz.pl	0.921	5.194	00:00:31
host227.elsnet.pl	0.007	0.071	00:00:18
196.52.22.25	0.015	0.028	00:00:04
mon.man.lodz.pl	1.043	4.469	00:00:03
198.20.87.98	0.008	0.015	00:00:02
david.man.lodz.pl	0.340	1.508	00:00:01
isvcs01.man.lodz.pl	0.053	0.140	00:00:00
?	0.008	0.038	00:00:00
TOTAL: 11	3.529	13.046	06:08:25

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
host227.elsnet.pl	4
196.52.22.25	2
89.38.41.25	2
198.20.87.98	1
TOTAL: 6	393

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	3	0
TOTAL: 1	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	734	96.7%
2	lodman	25	3.3%
TOTAL: 2		759	100.0%

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

Newsgroup		Count
1	pl.misc.elektronika	447
2	pl.misc.kolej	203
3	pl.comp.pecet	52
4	pl.comp.os.ms-windows.winnt	32
5	lodman.info	25
TOTAL: 5		759

Daily Usenet report

Feb 22 04:15:02 -- Feb 23 04:15:02