Daily Usenet report: Feb 15 04:15:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Feb 15 10:25:14 newsfeed nnrpd[613]: SERVER perl filter not defined
Feb 15 10:26:37 newsfeed nnrpd[622]: SERVER perl filter not defined
Feb 15 10:50:04 newsfeed controlchan[2614]: Unknown "tags" control by robomod@news.nic.it
Feb 15 12:29:11 newsfeed nnrpd[4928]: SERVER perl filter not defined
Feb 15 13:17:21 newsfeed nnrpd[6644]: SERVER perl filter not defined
Feb 15 13:19:25 newsfeed nnrpd[6662]: SERVER perl filter not defined
Feb 15 13:20:40 newsfeed nnrpd[6735]: SERVER perl filter not defined
Feb 15 17:00:07 newsfeed pgpverify[14313]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3132854	98.5%	369.6 MB	98.2%
innd	38609	1.2%	5.4 MB	1.4%
nnrpd	5998	0.2%	643.0 KB	0.2%
innfeed	4669	0.1%	666.5 KB	0.2%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	3182135	100.0%	376.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3125994	96.2%
Negative hits	108038	3.3%
Positive hits	15283	0.5%
Cache misses	146	0.0%
TOTAL: 4	3249461	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.760	0.0%	388	0.000	9.691	538.000
article cleanup	00:00:45.075	0.1%	3130393	0.006	0.014	0.024
article logging	00:01:46.749	0.1%	3131163	0.018	0.034	0.664
article parse	01:26:53.570	6.0%	195277838	0.015	0.027	0.081
article write	00:53:04.447	3.7%	3111829	0.341	1.023	4.957
artlog/artcncl	00:00:00.004	0.0%	216	0.000	0.019	1.000
artlog/artparse	00:00:00.002	0.0%	157	0.000	0.013	1.000
data move	00:03:00.964	0.2%	214870328	0.000	0.001	0.004
hisgrep/artcncl	00:00:01.350	0.0%	346	0.000	3.902	65.133
hishave/artcncl	00:00:00.174	0.0%	388	0.000	0.448	7.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:34:56.268	2.4%	3250508	0.151	0.645	8.261
history sync	00:00:00.015	0.0%	294	0.000	0.051	2.000
history write	00:52:08.803	3.6%	3127219	0.254	1.001	10.613
hiswrite/artcncl	00:00:00.020	0.0%	42	0.000	0.476	4.000
idle	10:57:06.659	45.6%	122827690	0.181	0.321	0.870
nntp read	01:17:48.877	5.4%	192815155	0.015	0.024	0.041
overview write	00:00:18.921	0.0%	3111829	0.001	0.006	0.013
perl filter	07:00:59.998	29.2%	3127062	5.943	8.078	10.028
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:27.446	0.4%	6370887	0.030	0.051	0.084
TOTAL: 23:59:31.867	23:14:23.102	96.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.731	0.0%	206414	0.000	0.004	0.143
article prepare	00:00:00.133	0.0%	518216	0.000	0.000	0.028
article read	00:09:08.303	0.6%	129152	0.000	4.245	1131.500
backlog stats	00:00:00.874	0.0%	791771	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	16	0.000	0.000	0.000
data read	00:00:04.895	0.0%	347520	0.000	0.014	0.187
data write	00:01:05.832	0.1%	454282	0.000	0.145	0.223
idle	23:48:51.125	99.3%	791771	5.219	108.278	7541.750
status file	00:00:01.440	0.0%	1642	0.000	0.877	6.000
TOTAL: 23:59:33.066	23:59:13.333	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:08:08.802	96.1%	3426	0.000	142.674	19494.833
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.203	0.0%	7874	0.000	0.026	0.500
readart	00:00:00.396	0.1%	89	0.000	4.449	21.182
TOTAL: 00:08:28.607	00:08:09.401	96.2%	-	-	-	-

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
group-admin@isc.org	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	1178	3115256	3101605	8109	5542	99%	430:46:37
2	news.nask.pl	2	27836	11196	3440	13200	40%	24:01:33
3	news.icm.edu.pl	45	295	36	240	19	12%	17:36:43
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		1232	3143393	3112843	11789	18761	99%	472:24:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	988.9 GB	92.2 MB	561.6 MB	989.5 GB	99%	333.9 KB
2	news.nask.pl	58.7 MB	1.6 MB	210.3 MB	270.5 MB	21%	11.4 KB
3	news.icm.edu.pl	1.1 MB	140.6 KB	356.0 KB	1.6 MB	68%	29.0 KB
4	localhost	10.4 KB	0.0 KB	0.0 KB	10.4 KB	100%	1.7 KB
TOTAL: 4		988.9 GB	93.9 MB	772.2 MB	989.8 GB	99%	331.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 15 04:15:02 - 04:59:59	92127	3.0%	34.15	31.6 GB	3.2%	12286.96
Feb 15 05:00:00 - 05:59:59	126070	4.0%	35.02	41.9 GB	4.2%	12195.01
Feb 15 06:00:00 - 06:59:59	120602	3.9%	33.50	40.5 GB	4.1%	11793.90
Feb 15 07:00:00 - 07:59:59	128011	4.1%	35.56	42.9 GB	4.3%	12506.55
Feb 15 08:00:00 - 08:59:59	129320	4.2%	35.92	42.8 GB	4.3%	12468.46
Feb 15 09:00:00 - 09:59:59	117103	3.8%	32.53	39.1 GB	4.0%	11385.23
Feb 15 10:00:00 - 10:59:59	118741	3.8%	32.98	38.7 GB	3.9%	11280.76
Feb 15 11:00:00 - 11:59:59	133143	4.3%	36.98	42.0 GB	4.3%	12221.79
Feb 15 12:00:00 - 12:59:59	133627	4.3%	37.12	42.9 GB	4.4%	12509.14
Feb 15 13:00:00 - 13:59:59	129154	4.1%	35.88	42.8 GB	4.3%	12462.25
Feb 15 14:00:00 - 14:59:59	127363	4.1%	35.38	41.9 GB	4.2%	12196.13
Feb 15 15:00:00 - 15:59:59	123815	4.0%	34.39	40.8 GB	4.1%	11883.68
Feb 15 16:00:00 - 16:59:59	124910	4.0%	34.70	41.1 GB	4.2%	11985.72
Feb 15 17:00:00 - 17:59:59	134441	4.3%	37.34	42.9 GB	4.3%	12487.14
Feb 15 18:00:00 - 18:59:59	135127	4.3%	37.54	42.6 GB	4.3%	12411.03
Feb 15 19:00:00 - 19:59:59	130254	4.2%	36.18	42.0 GB	4.3%	12242.84
Feb 15 20:00:00 - 20:59:59	138466	4.4%	38.46	42.2 GB	4.3%	12298.82
Feb 15 21:00:00 - 21:59:59	152340	4.9%	42.32	41.3 GB	4.2%	12036.35
Feb 15 22:00:00 - 22:59:59	147974	4.8%	41.10	40.6 GB	4.1%	11836.64
Feb 15 23:00:00 - 23:59:59	144087	4.6%	40.02	42.5 GB	4.3%	12374.88
Feb 16 00:00:00 - 00:59:59	119949	3.9%	33.32	34.2 GB	3.5%	9953.37
Feb 16 01:00:00 - 01:59:59	135681	4.4%	37.69	41.3 GB	4.2%	12031.94
Feb 16 02:00:00 - 02:59:59	125545	4.0%	34.87	41.7 GB	4.2%	12137.83
Feb 16 03:00:00 - 03:59:59	111421	3.6%	30.95	36.9 GB	3.7%	10759.68
Feb 16 04:00:00 - 04:15:02	33851	1.1%	37.53	9.9 GB	1.0%	11512.42
TOTAL: 24:00:00	3113122	100.0%	36.03	987.2 GB	100.0%	11981.34

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	13529	34	380	4	13111
2	newsfeed.pionier.net.pl	5968	279	2944	1	2744
3	news.icm.edu.pl	19	0	7	0	12
TOTAL: 3		19516	313	3331	5	15867

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	177
it.tlc.cellulari.android	33
it.comp.os.win.windows10	25
comp.mobile.android	14
nl.comp.sys.android	14
uk.rec.cycling.moderated	13
de.comp.office-pakete.ms-office.misc	12
comp.sys.raspberry-pi	8
de.comp.office-pakete.ms-office.word	8
de.comm.provider.telefonie	3
it.comp.os.win.windows7	2
comp.mobile.ipad	2
nl.actueel	1
it.discussioni.energia	1
TOTAL: 14	313

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11721
Binary in non-binary group	2755
EMP (phl)	222
Scoring filter (9)	92
Poison newsgroup	67
Scoring filter (8)	62
Scoring filter (10)	13
Scoring filter (11)	3
Scoring filter (12)	3
Scoring filter (15)	1
HTML post	1
Too many newsgroups	1
TOTAL: 12	14941

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	148609	121713	19302	3824	10	48296	81%	24:04:50
2	panorama	38153	2490	31744	977	36	122597	6%	24:00:00
3	poznan	17010	172	10973	20	0	0	1%	24:02:59
4	nask	6690	48	4462	1	0	0	0%	23:57:21
5	okapi	0	0	0	0	0	2356	0%	24:00:00
TOTAL: 5		210462	124423	66481	4822	46	173249	59%	120:05:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	21.8 GB	17.5 MB	21.9 GB	264.3 KB/s	182.5 KB	24:04:50
2	panorama	8.3 MB	23.7 MB	32.0 MB	0.4 KB/s	9.4 KB	24:00:00
3	poznan	859.1 KB	398.9 KB	1.2 MB	0.0 KB/s	6.6 KB	24:02:59
4	nask	166.7 KB	2.3 KB	169.0 KB	0.0 KB/s	3.4 KB	23:57:21
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		21.8 GB	41.6 MB	21.9 GB	53.1 KB/s	177.6 KB	120:05:10

Backlog files shrunk by innfeed:

Server	Size
panorama	10.0 MB
TOTAL: 1	10.0 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	64	91.7 KB	29	0	00:00:23
2	lze200.lze.lodz.pl	2	14	33.6 KB	1	0	00:07:48
3	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 3		296	78	125.3 KB	30	6	00:08:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	966	64	91.7 KB	29	6	00:00:32
2	*.lze.lodz.pl	2	14	33.6 KB	1	0	00:07:48
3	*.dyn.optonline.net	1	0	0.0 KB	0	0	00:00:07
TOTAL: 3		969	78	125.3 KB	30	6	00:08:28

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.019	0.057	00:07:48
web.man.lodz.pl	1.099	6.065	00:00:23
mon.man.lodz.pl	1.987	10.730	00:00:07
ool-ae2c99f9.dyn.optonline.net	0.027	0.034	00:00:07
david.man.lodz.pl	0.287	1.697	00:00:01
isvcs01.man.lodz.pl	0.038	0.123	00:00:00
TOTAL: 6	3.457	18.706	00:08:28

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	576
david.man.lodz.pl	96
ool-ae2c99f9.dyn.optonline.net	1
TOTAL: 3	673

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	34
2	pl.comp.os.ms-windows.winnt	30
3	pl.misc.telefonia.gsm	14
TOTAL: 3		78

Daily Usenet report

Feb 15 04:15:02 -- Feb 16 04:15:02