Daily Usenet report: Oct 15 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Oct 15 10:16:16 newsfeed nnrpd[29922]: SERVER perl filter not defined
Oct 15 10:16:20 newsfeed nnrpd[29923]: SERVER perl filter not defined
Oct 15 10:24:47 newsfeed nnrpd[30213]: SERVER perl filter not defined
Oct 15 10:38:44 newsfeed nnrpd[30657]: SERVER perl filter not defined
Oct 15 10:42:06 newsfeed nnrpd[30740]: SERVER perl filter not defined
Oct 15 15:26:11 newsfeed nnrpd[8230]: SERVER perl filter not defined
Oct 15 15:26:21 newsfeed nnrpd[8261]: SERVER perl filter not defined
Oct 15 17:00:06 newsfeed pgpverify[11452]: pgpverify: verification failed
Oct 15 23:53:11 newsfeed nnrpd[26175]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2136138	97.7%	245.3 MB	97.3%
innfeed	27205	1.2%	3.3 MB	1.3%
innd	17487	0.8%	3.1 MB	1.2%
nnrpd	5034	0.2%	531.0 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2185868	100.0%	252.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2129519	98.0%
Negative hits	25378	1.2%
Positive hits	16972	0.8%
Cache misses	186	0.0%
TOTAL: 4	2172055	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:15.274	0.0%	341	0.000	44.792	466.250
article cleanup	00:00:35.024	0.0%	2136399	0.005	0.016	0.034
article logging	00:01:01.170	0.1%	2137010	0.014	0.029	0.387
article parse	01:08:02.336	4.7%	284880470	0.006	0.014	0.055
article write	00:27:33.034	1.9%	2132486	0.273	0.775	4.492
artlog/artcncl	00:00:00.013	0.0%	338	0.000	0.038	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:04:21.701	0.3%	299498747	0.000	0.001	0.005
hisgrep/artcncl	00:00:03.590	0.0%	314	0.000	11.433	241.200
hishave/artcncl	00:00:00.506	0.0%	341	0.000	1.484	434.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:18:47.112	1.3%	2175397	0.062	0.518	6.866
history sync	00:00:00.015	0.0%	292	0.000	0.051	2.000
history write	00:49:50.695	3.5%	2133075	0.203	1.402	24.274
hiswrite/artcncl	00:00:00.016	0.0%	27	0.000	0.593	11.000
idle	14:04:02.466	58.6%	216450899	0.138	0.234	0.652
nntp read	01:22:44.973	5.7%	283237008	0.008	0.018	0.037
overview write	00:00:11.915	0.0%	2132486	0.001	0.006	0.015
perl filter	04:33:18.027	19.0%	2133075	4.935	7.688	10.699
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:47.010	0.3%	4327929	0.031	0.052	0.099
TOTAL: 24:00:11.826	22:54:34.877	95.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.284	0.0%	125093	0.000	0.002	0.071
article prepare	00:00:00.023	0.0%	83762	0.000	0.000	0.042
article read	00:01:02.146	0.1%	20945	0.000	2.967	1230.000
backlog stats	00:00:00.214	0.0%	318988	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	7	0.000	0.000	0.000
data read	00:00:01.888	0.0%	178691	0.000	0.011	0.074
data write	00:00:14.159	0.0%	120829	0.000	0.117	1.186
idle	23:57:59.188	99.9%	318988	39.699	270.478	2402.846
status file	00:00:06.366	0.0%	8521	0.000	0.747	2.667
TOTAL: 23:59:34.474	23:59:24.268	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:52:04.266	99.9%	9061	0.000	1934.032	450074.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.313	0.0%	19156	0.000	0.016	0.667
readart	00:00:05.557	0.0%	219	0.000	25.374	121.786
TOTAL: 04:52:23.081	04:52:10.136	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
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	788	2131168	2127468	289	3411	99%	260:30:38
2	news.nask.pl	1	16283	3562	12412	309	21%	22:42:06
3	news.icm.edu.pl	46	421	31	214	176	7%	18:43:18
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		844	2147880	2131069	12915	3896	99%	301:56:02

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	691.5 GB	10.6 MB	13.7 MB	691.5 GB	99%	340.3 KB
2	news.nask.pl	11.4 MB	760.2 KB	862.2 KB	13.0 MB	87%	3.4 KB
3	news.icm.edu.pl	713.6 KB	13.1 KB	4.2 MB	4.9 MB	14%	24.2 KB
4	localhost	11.5 KB	0.0 KB	0.0 KB	11.5 KB	100%	1.4 KB
TOTAL: 4		691.5 GB	11.3 MB	18.7 MB	691.5 GB	99%	339.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 15 04:15:02 - 04:59:59	84322	4.0%	31.25	23.4 GB	3.4%	9076.06
Oct 15 05:00:00 - 05:59:59	75810	3.6%	21.06	24.6 GB	3.6%	7157.77
Oct 15 06:00:00 - 06:59:59	67122	3.1%	18.64	24.9 GB	3.6%	7258.72
Oct 15 07:00:00 - 07:59:59	94830	4.4%	26.34	31.3 GB	4.5%	9130.59
Oct 15 08:00:00 - 08:59:59	92206	4.3%	25.61	30.9 GB	4.5%	8996.10
Oct 15 09:00:00 - 09:59:59	91918	4.3%	25.53	30.3 GB	4.4%	8832.13
Oct 15 10:00:00 - 10:59:59	81532	3.8%	22.65	28.1 GB	4.1%	8196.00
Oct 15 11:00:00 - 11:59:59	112381	5.3%	31.22	33.1 GB	4.8%	9631.35
Oct 15 12:00:00 - 12:59:59	98449	4.6%	27.35	30.4 GB	4.4%	8865.77
Oct 15 13:00:00 - 13:59:59	87181	4.1%	24.22	29.7 GB	4.3%	8645.65
Oct 15 14:00:00 - 14:59:59	98277	4.6%	27.30	32.0 GB	4.6%	9316.77
Oct 15 15:00:00 - 15:59:59	101260	4.8%	28.13	31.5 GB	4.6%	9175.01
Oct 15 16:00:00 - 16:59:59	100956	4.7%	28.04	31.0 GB	4.5%	9026.43
Oct 15 17:00:00 - 17:59:59	102868	4.8%	28.57	30.7 GB	4.4%	8930.15
Oct 15 18:00:00 - 18:59:59	88656	4.2%	24.63	29.7 GB	4.3%	8647.76
Oct 15 19:00:00 - 19:59:59	83708	3.9%	23.25	27.1 GB	3.9%	7892.67
Oct 15 20:00:00 - 20:59:59	84440	4.0%	23.46	26.2 GB	3.8%	7621.50
Oct 15 21:00:00 - 21:59:59	114614	5.4%	31.84	34.2 GB	4.9%	9963.98
Oct 15 22:00:00 - 22:59:59	101829	4.8%	28.29	32.2 GB	4.7%	9366.00
Oct 15 23:00:00 - 23:59:59	91889	4.3%	25.52	31.2 GB	4.5%	9080.74
Oct 16 00:00:00 - 00:59:59	57774	2.7%	16.05	21.0 GB	3.0%	6116.10
Oct 16 01:00:00 - 01:59:59	77705	3.6%	21.58	27.6 GB	4.0%	8042.50
Oct 16 02:00:00 - 02:59:59	61877	2.9%	17.19	22.3 GB	3.2%	6494.15
Oct 16 03:00:00 - 03:59:59	64941	3.0%	18.04	22.7 GB	3.3%	6622.07
Oct 16 04:00:00 - 04:15:02	14729	0.7%	16.33	5.1 GB	0.7%	5921.51
TOTAL: 24:00:00	2131274	100.0%	24.67	691.1 GB	100.0%	8387.32

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3956	232	3021	1	702
2	news.nask.pl	391	10	301	9	71
3	news.icm.edu.pl	177	0	2	0	175
TOTAL: 3		4524	242	3324	10	948

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	86
it.comp.os.win.windows10	52
nl.actueel	24
it.tlc.cellulari.android	17
comp.mobile.android	14
uk.rec.cycling.moderated	14
it.comp.os.win.windows7	9
nl.comp.sys.android	6
de.comp.lang.vbclassic	5
de.rec.sf.startrek	3
hr.alt.cellular.gsm.iphone	3
it.discussioni.energia	2
comp.mobile.ipad	2
hr.ponuda.hardver.komponente	2
alt.sex.telephones	1
de.comm.provider.telefonie	1
rec.arts.tv.x-factor	1
TOTAL: 17	242

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	167
EMP (md5)	164
Too many newsgroups	5
HTML post	1
TOTAL: 4	337

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	63606	1601	46546	1387	21	75584	2%	23:59:19
2	icm	61782	566	27235	16677	0	5	0%	24:05:26
3	poznan	5820	63	3650	22	0	0	1%	24:02:34
4	nask	0	0	0	0	0	13631	0%	24:00:01
5	okapi	0	0	0	0	0	2688	0%	24:00:01
TOTAL: 5		131208	2230	77431	18086	21	91908	1%	120:07:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	25.2 MB	3.7 GB	3.8 GB	45.4 KB/s	228.5 KB	24:05:26
2	panorama	5.5 MB	15.2 MB	20.6 MB	0.2 KB/s	7.1 KB	23:59:19
3	poznan	227.5 KB	55.4 KB	282.8 KB	0.0 KB/s	3.3 KB	24:02:34
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		30.9 MB	3.7 GB	3.8 GB	9.2 KB/s	195.0 KB	120:07:21

Backlog files shrunk by innfeed:

Server	Size
panorama	2.9 MB
okapi	488.3 KB
TOTAL: 2	3.3 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	13	105	233.4 KB	10	0	04:06:36
2	212.51.203.199	2	60	157.4 KB	1	0	00:30:00
3	web.man.lodz.pl	288	44	40.5 KB	17	0	00:00:29
4	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 4		311	209	431.3 KB	28	8	04:52:22

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	13	105	233.4 KB	10	0	04:06:36
2	unresolved	5	60	157.4 KB	1	0	00:45:01
3	*.man.lodz.pl	680	44	40.5 KB	17	8	00:00:34
4	*.toya.net.pl	26	0	0.0 KB	0	0	00:00:01
5	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:08
TOTAL: 5		726	209	431.3 KB	28	8	04:52:22

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.051	0.184	04:06:36
212.51.203.199	0.010	0.027	00:30:00
185.48.192.96	0.317	0.409	00:15:00
web.man.lodz.pl	0.967	3.985	00:00:29
host227.elsnet.pl	0.010	0.020	00:00:08
mon.man.lodz.pl	0.842	3.292	00:00:03
staticline41435.toya.net.pl	0.095	0.328	00:00:01
173.0.15.165	0.003	0.016	00:00:01
david.man.lodz.pl	0.254	1.009	00:00:00
isvcs01.man.lodz.pl	0.050	0.107	00:00:00
TOTAL: 10	2.599	9.377	04:52:22

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	26
185.48.192.96	2
host227.elsnet.pl	2
173.0.15.165	1
TOTAL: 6	415

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.elektronika	91
2	pl.rec.radio.amatorskie	60
3	pl.comp.pecet	32
4	pl.misc.kolej	14
5	pl.comp.os.ms-windows.winnt	12
TOTAL: 5		209

Daily Usenet report

Oct 15 04:15:02 -- Oct 16 04:15:02