Daily Usenet report: May 4 04:15:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

May 4 10:28:48 newsfeed nnrpd[6090]: SERVER perl filter not defined
May 4 11:50:05 newsfeed pgpverify[8989]: pgpverify: verification failed
May 4 13:59:23 newsfeed nnrpd[12559]: message repeated 3 times: [ wireless.ics.p.lodz.pl group alt.test.pl 0]
May 4 15:27:18 newsfeed nnrpd[16711]: SERVER perl filter not defined
May 4 15:48:31 newsfeed nnrpd[17419]: SERVER perl filter not defined
May 4 16:59:18 newsfeed nnrpd[19885]: SERVER perl filter not defined
May 4 17:10:54 newsfeed nnrpd[20340]: SERVER perl filter not defined
May 4 17:53:18 newsfeed nnrpd[21760]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2229456	96.4%	248.6 MB	95.8%
innd	47088	2.0%	6.4 MB	2.5%
innfeed	32125	1.4%	3.9 MB	1.5%
nnrpd	5026	0.2%	532.9 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2313699	100.0%	259.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2225792	97.1%
Negative hits	52304	2.3%
Positive hits	14890	0.6%
Cache misses	132	0.0%
TOTAL: 4	2293118	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.899	0.0%	218	0.000	22.472	1141.000
article cleanup	00:00:32.416	0.0%	2227185	0.005	0.015	0.032
article logging	00:01:08.803	0.1%	2227709	0.015	0.031	0.647
article parse	01:16:16.029	5.3%	188122905	0.013	0.024	0.078
article write	00:24:30.890	1.7%	2194852	0.395	0.670	2.697
artlog/artcncl	00:00:00.002	0.0%	216	0.000	0.009	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:02:31.674	0.2%	203280617	0.000	0.001	0.005
hisgrep/artcncl	00:00:01.325	0.0%	199	0.000	6.658	107.909
hishave/artcncl	00:00:00.083	0.0%	218	0.000	0.381	9.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:31:34.894	2.2%	2294247	0.076	0.826	14.033
history sync	00:00:00.011	0.0%	295	0.000	0.037	2.000
history write	00:43:26.874	3.0%	2223696	0.197	1.172	60.973
hiswrite/artcncl	00:00:00.038	0.0%	19	0.000	2.000	16.000
idle	13:39:07.424	56.9%	140474390	0.102	0.350	1.763
nntp read	01:12:02.470	5.0%	186409479	0.014	0.023	0.036
overview write	00:00:12.970	0.0%	2194852	0.000	0.006	0.014
perl filter	05:09:21.763	21.5%	2223696	5.464	8.347	12.067
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:09.021	0.3%	4421193	0.038	0.056	0.084
TOTAL: 23:59:15.682	23:05:01.586	96.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.227	0.0%	93739	0.000	0.002	0.111
article prepare	00:00:00.015	0.0%	49004	0.000	0.000	0.083
article read	00:01:02.630	0.1%	12241	0.000	5.116	1964.000
backlog stats	00:00:00.186	0.0%	279264	0.000	0.001	0.019
callbacks	00:00:00.000	0.0%	32	0.000	0.000	0.000
data read	00:00:01.596	0.0%	140747	0.000	0.011	0.085
data write	00:00:09.011	0.0%	130366	0.000	0.069	0.356
idle	23:58:35.012	99.9%	279264	45.609	309.080	1159.943
newart/cb	00:00:00.000	0.0%	0	0.000	0.000	0.000
prepart/cb	00:00:00.000	0.0%	0	0.000	0.000	0.000
readart/cb	00:00:00.000	0.0%	0	0.000	0.000	0.000
status file	00:00:10.533	0.0%	10314	0.000	1.021	200.833
TOTAL: 24:00:10.105	23:59:59.210	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:57:05.302	99.8%	4622	0.000	5414.388	600144.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:27.916	0.1%	70887	0.000	0.394	1.000
readart	00:00:04.523	0.0%	275	0.000	16.447	23.566
TOTAL: 06:57:52.927	06:57:37.741	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
admin@openwatcom.news-admin.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	966	2195933	2192128	402	3403	99%	273:08:06
2	news.nask.pl	6	44106	4391	10790	28925	9%	24:30:33
3	news.icm.edu.pl	40	253	38	206	9	15%	15:42:27
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		1019	2240298	2196563	11398	32337	98%	313:21:06

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	771.8 GB	27.5 MB	28.6 MB	771.9 GB	99%	368.6 KB
2	news.nask.pl	133.0 MB	2.7 MB	6.3 GB	6.5 GB	2%	203.1 KB
3	news.icm.edu.pl	1.3 MB	12.3 KB	121.3 KB	1.4 MB	90%	31.1 KB
4	localhost	8.2 KB	0.0 KB	0.0 KB	8.2 KB	100%	1.4 KB
TOTAL: 4		771.9 GB	30.3 MB	6.3 GB	778.3 GB	99%	366.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 4 04:15:02 - 04:59:59	53734	2.4%	19.92	22.6 GB	2.9%	8770.42
May 4 05:00:00 - 05:59:59	77569	3.5%	21.55	32.9 GB	4.3%	9572.11
May 4 06:00:00 - 06:59:59	71690	3.3%	19.91	25.5 GB	3.3%	7414.48
May 4 07:00:00 - 07:59:59	74573	3.4%	20.71	24.2 GB	3.1%	7050.63
May 4 08:00:00 - 08:59:59	100986	4.6%	28.05	33.4 GB	4.3%	9726.45
May 4 09:00:00 - 09:59:59	85319	3.9%	23.70	27.8 GB	3.6%	8084.79
May 4 10:00:00 - 10:59:59	93892	4.3%	26.08	31.9 GB	4.1%	9303.74
May 4 11:00:00 - 11:59:59	97988	4.5%	27.22	32.3 GB	4.2%	9406.97
May 4 12:00:00 - 12:59:59	83142	3.8%	23.09	28.1 GB	3.6%	8197.47
May 4 13:00:00 - 13:59:59	82230	3.7%	22.84	28.9 GB	3.7%	8414.40
May 4 14:00:00 - 14:59:59	76728	3.5%	21.31	26.0 GB	3.4%	7559.38
May 4 15:00:00 - 15:59:59	80140	3.6%	22.26	26.6 GB	3.4%	7745.30
May 4 16:00:00 - 16:59:59	85597	3.9%	23.78	28.4 GB	3.7%	8265.43
May 4 17:00:00 - 17:59:59	82126	3.7%	22.81	33.3 GB	4.3%	9711.77
May 4 18:00:00 - 18:59:59	97591	4.4%	27.11	36.2 GB	4.7%	10536.64
May 4 19:00:00 - 19:59:59	103537	4.7%	28.76	32.1 GB	4.2%	9338.49
May 4 20:00:00 - 20:59:59	90821	4.1%	25.23	33.5 GB	4.3%	9756.09
May 4 21:00:00 - 21:59:59	105911	4.8%	29.42	37.9 GB	4.9%	11027.97
May 4 22:00:00 - 22:59:59	109412	5.0%	30.39	38.0 GB	4.9%	11057.05
May 4 23:00:00 - 23:59:59	112875	5.1%	31.35	39.6 GB	5.1%	11520.05
May 5 00:00:00 - 00:59:59	91596	4.2%	25.44	32.5 GB	4.2%	9463.16
May 5 01:00:00 - 01:59:59	107016	4.9%	29.73	38.9 GB	5.0%	11325.39
May 5 02:00:00 - 02:59:59	113086	5.1%	31.41	39.9 GB	5.2%	11632.14
May 5 03:00:00 - 03:59:59	96452	4.4%	26.79	33.5 GB	4.3%	9757.29
May 5 04:00:00 - 04:15:02	22371	1.0%	24.80	8.2 GB	1.1%	9499.38
TOTAL: 24:00:00	2196382	100.0%	25.42	771.9 GB	100.0%	9367.51

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	29003	3	289	7	28704
2	newsfeed.pionier.net.pl	3846	96	3198	3	549
3	news.icm.edu.pl	9	0	1	0	8
TOTAL: 3		32858	99	3488	10	29261

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	41
it.comp.os.win.windows10	18
it.tlc.cellulari.android	16
comp.mobile.android	6
gmane.comp.gis.openstreetmap.announce	3
uk.rec.cycling.moderated	3
it.comp.os.linux.ubuntu	2
comp.sys.raspberry-pi	2
it.comp.os.win.windows7	2
de.rec.sf.startrek	2
hr.ponuda.hardver.komponente	1
it.comp.os.win.windows8	1
hr.alt.cellular.gsm.android	1
alt.sex.telephones	1
TOTAL: 14	99

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

Groups	Count
pl.sci.historia	4
pl.praca.oferowana	3
christnet.philosophy	1
pl.sci.matematyka	1
bit.listserv.catholic	1
TOTAL: 5	10

INND Perl filter [Top 20]:

Reason	Count
EMP (phl)	26238
EMP (md5)	1638
PFM bot misc	214
Binary in non-binary group	191
Scoring filter (8)	119
Poison newsgroup	65
Scoring filter (11)	59
Scoring filter (12)	58
Scoring filter (9)	49
Scoring filter (10)	40
Scoring filter (13)	21
Scoring filter (14)	16
HTML post	8
Attached HTML file	4
Scoring filter (15)	4
Scoring filter (16)	3
Scoring filter (21)	2
Scoring filter (18)	2
Scoring filter (19)	2
Scoring filter (17)	1
TOTAL: 21	28735

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad command received
	news.nask.pl	18
	TOTAL: 1	18
TOTAL: 1		18

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	40671	1980	20614	7383	0	0	4%	23:55:48
2	panorama	64396	1505	45162	576	64	46324	2%	23:56:07
3	nask	20206	107	11240	4	0	0	0%	23:56:59
4	poznan	6680	97	4207	21	0	0	1%	24:00:52
5	okapi	0	0	0	0	0	2392	0%	24:00:02
TOTAL: 5		131953	3689	81223	7984	64	48716	2%	119:49:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	15.3 MB	2.8 MB	18.1 MB	0.2 KB/s	8.9 KB	23:56:07
2	icm	12.0 MB	2.1 GB	2.1 GB	25.5 KB/s	234.3 KB	23:55:48
3	nask	344.4 KB	9.0 KB	353.4 KB	0.0 KB/s	3.2 KB	23:56:59
4	poznan	301.6 KB	943.9 KB	1.2 MB	0.0 KB/s	10.6 KB	24:00:52
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		27.9 MB	2.1 GB	2.1 GB	5.1 KB/s	189.6 KB	119:49:48

Backlog files shrunk by innfeed:

Server	Size
panorama	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	dmcs2.p.lodz.pl	7	198	388.1 KB	4	0	01:51:51
2	web.man.lodz.pl	288	48	46.5 KB	21	0	00:00:29
3	pc-212-51-218-225.p.lodz.pl	10	23	47.6 KB	10	0	03:04:08
4	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 4		311	269	482.3 KB	35	6	06:57:52

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	19	221	435.7 KB	14	0	05:56:00
2	*.man.lodz.pl	678	48	46.5 KB	21	6	00:00:34
3	*.play-internet.pl	1	0	0.0 KB	0	0	00:30:11
4	unresolved	3	0	0.0 KB	0	0	00:02:46
5	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:22
6	*.ics.p.lodz.pl	1	0	0.0 KB	0	0	00:27:55
7	*.toya.net.pl	13	0	0.0 KB	0	0	00:00:01
TOTAL: 7		717	269	482.3 KB	35	6	06:57:52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.035	0.177	03:04:08
dmcs2.p.lodz.pl	0.056	0.180	01:51:51
pc-212-191-92-112.p.lodz.pl	0.010	0.023	01:00:00
user-164-126-163-13.play-internet.pl	0.008	0.021	00:30:11
wireless.ics.p.lodz.pl	0.195	0.095	00:27:55
89.38.40.162	0.077	0.125	00:02:45
web.man.lodz.pl	0.891	5.187	00:00:29
host227.elsnet.pl	0.006	0.032	00:00:22
mon.man.lodz.pl	0.979	4.987	00:00:03
196.52.18.35	0.000	0.027	00:00:01
staticline41435.toya.net.pl	0.046	0.212	00:00:01
david.man.lodz.pl	0.304	1.582	00:00:00
isvcs01.man.lodz.pl	0.031	0.115	00:00:00
TOTAL: 13	2.638	12.763	06:57:52

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	13
89.38.40.162	2
host227.elsnet.pl	2
pc-212-191-92-112.p.lodz.pl	2
user-164-126-163-13.play-internet.pl	1
wireless.ics.p.lodz.pl	1
196.52.18.35	1
TOTAL: 9	406

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	3	0
pc-212-191-92-112.p.lodz.pl	2	0
user-164-126-163-13.play-internet.pl	1	0
89.38.40.162	1	1
TOTAL: 4	7	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.elektronika	155
2	pl.misc.kolej	66
3	pl.comp.pecet	44
4	pl.comp.os.ms-windows.winnt	4
TOTAL: 4		269

Daily Usenet report

May 4 04:15:02 -- May 5 04:15:02