Daily Usenet report: Dec 15 04:15:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Dec 15 12:51:40 newsfeed pgpverify[23518]: pgpverify: verification failed
Dec 15 12:51:40 newsfeed controlchan[2644]: skipping newgroup m control@usenet.org.uk (pgpverify failed) in <makegroup-uk.radio.amateur.moderated-20151215114905$7fc9@weathertop.principate.org.uk>
Dec 15 12:57:14 newsfeed nnrpd[23762]: SERVER perl filter not defined
Dec 15 13:04:00 newsfeed nnrpd[24069]: SERVER perl filter not defined
Dec 15 14:49:58 newsfeed nnrpd[27627]: SERVER perl filter not defined
Dec 15 15:52:54 newsfeed nnrpd[29770]: SERVER perl filter not defined
Dec 15 17:00:06 newsfeed pgpverify[32205]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2634280	97.3%	302.1 MB	96.9%
innfeed	44837	1.7%	5.3 MB	1.7%
innd	21434	0.8%	3.7 MB	1.2%
nnrpd	5500	0.2%	589.8 KB	0.2%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	2	0.0%	0.4 KB	0.0%
TOTAL: 6	2706057	100.0%	311.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2627346	98.3%
Negative hits	27804	1.0%
Positive hits	17131	0.6%
Cache misses	112	0.0%
TOTAL: 4	2672393	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.334	0.0%	217	0.000	15.364	338.000
article cleanup	00:00:42.467	0.0%	2633657	0.006	0.016	0.028
article logging	00:01:17.185	0.1%	2634272	0.014	0.029	0.919
article parse	01:19:04.678	5.5%	269277135	0.006	0.018	0.085
article write	00:28:56.002	2.0%	2629008	0.304	0.660	4.214
artlog/artcncl	00:00:00.003	0.0%	214	0.000	0.014	0.333
data move	00:04:50.558	0.3%	286267567	0.000	0.001	0.008
hisgrep/artcncl	00:00:00.231	0.0%	185	0.000	1.249	22.000
hishave/artcncl	00:00:00.079	0.0%	217	0.000	0.364	10.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:28:02.933	1.9%	2674424	0.098	0.629	13.036
history sync	00:00:00.008	0.0%	291	0.000	0.027	2.000
history write	00:45:39.729	3.2%	2629452	0.187	1.042	35.907
hiswrite/artcncl	00:00:00.082	0.0%	32	0.000	2.562	37.000
idle	13:08:47.382	54.8%	188149823	0.077	0.252	0.379
nntp read	01:26:14.131	6.0%	267197890	0.009	0.019	0.040
overview write	00:00:15.347	0.0%	2629008	0.001	0.006	0.014
perl filter	05:21:15.036	22.3%	2629452	4.750	7.330	10.013
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:55.048	0.3%	5316080	0.028	0.056	0.100
TOTAL: 24:00:13.824	23:10:04.233	96.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.266	0.0%	103809	0.000	0.003	0.125
article prepare	00:00:00.016	0.0%	48520	0.000	0.000	0.083
article read	00:01:04.494	0.1%	12099	0.000	5.331	724.000
backlog stats	00:00:00.212	0.0%	275979	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	9	0.000	0.000	0.000
data read	00:00:01.749	0.0%	156689	0.000	0.011	0.108
data write	00:00:12.073	0.0%	99708	0.000	0.121	2.037
idle	23:58:15.562	99.9%	275979	76.521	312.689	1111.268
status file	00:00:11.958	0.0%	15600	0.125	0.767	18.583
TOTAL: 23:59:58.080	23:59:46.330	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:35:47.390	99.9%	4126	0.000	5755.548	600188.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.064	0.0%	10360	0.000	0.103	14.091
readart	00:00:10.271	0.0%	624	0.000	16.460	47.000
TOTAL: 06:36:20.356	06:35:58.725	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	1180	2627824	2623144	545	4135	99%	321:40:43
2	news.nask.pl	2	18409	5488	12448	473	29%	24:49:40
3	news.icm.edu.pl	45	269	51	168	50	18%	17:34:17
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		1232	2646506	2628687	13161	4658	99%	364:04:40

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	791.6 GB	13.8 MB	23.9 MB	791.6 GB	99%	316.0 KB
2	news.nask.pl	22.9 MB	1.2 MB	1.3 MB	25.3 MB	90%	4.4 KB
3	news.icm.edu.pl	1.3 MB	40.2 KB	872.6 KB	2.2 MB	59%	22.6 KB
4	localhost	6.0 KB	0.0 KB	0.0 KB	6.0 KB	100%	1.5 KB
TOTAL: 4		791.6 GB	15.1 MB	26.0 MB	791.7 GB	99%	315.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 15 04:15:02 - 04:59:59	60491	2.3%	22.42	20.3 GB	2.6%	7905.61
Dec 15 05:00:00 - 05:59:59	89024	3.4%	24.73	30.6 GB	3.9%	8902.09
Dec 15 06:00:00 - 06:59:59	111984	4.3%	31.11	35.0 GB	4.4%	10196.08
Dec 15 07:00:00 - 07:59:59	119511	4.5%	33.20	36.0 GB	4.5%	10472.05
Dec 15 08:00:00 - 08:59:59	122236	4.6%	33.95	36.0 GB	4.6%	10493.50
Dec 15 09:00:00 - 09:59:59	120600	4.6%	33.50	35.7 GB	4.5%	10399.00
Dec 15 10:00:00 - 10:59:59	121507	4.6%	33.75	36.0 GB	4.5%	10472.34
Dec 15 11:00:00 - 11:59:59	124492	4.7%	34.58	35.0 GB	4.4%	10200.29
Dec 15 12:00:00 - 12:59:59	113940	4.3%	31.65	34.0 GB	4.3%	9903.39
Dec 15 13:00:00 - 13:59:59	122644	4.7%	34.07	35.4 GB	4.5%	10312.86
Dec 15 14:00:00 - 14:59:59	115295	4.4%	32.03	35.3 GB	4.5%	10288.95
Dec 15 15:00:00 - 15:59:59	109205	4.2%	30.33	35.7 GB	4.5%	10387.12
Dec 15 16:00:00 - 16:59:59	119689	4.6%	33.25	35.3 GB	4.5%	10285.23
Dec 15 17:00:00 - 17:59:59	128686	4.9%	35.75	35.9 GB	4.5%	10451.08
Dec 15 18:00:00 - 18:59:59	124881	4.8%	34.69	34.4 GB	4.3%	10008.75
Dec 15 19:00:00 - 19:59:59	151115	5.7%	41.98	37.4 GB	4.7%	10906.75
Dec 15 20:00:00 - 20:59:59	113627	4.3%	31.56	32.9 GB	4.2%	9588.86
Dec 15 21:00:00 - 21:59:59	98572	3.7%	27.38	30.3 GB	3.8%	8837.96
Dec 15 22:00:00 - 22:59:59	90645	3.4%	25.18	29.1 GB	3.7%	8467.79
Dec 15 23:00:00 - 23:59:59	94674	3.6%	26.30	29.7 GB	3.8%	8650.59
Dec 16 00:00:00 - 00:59:59	68265	2.6%	18.96	23.9 GB	3.0%	6957.49
Dec 16 01:00:00 - 01:59:59	95560	3.6%	26.54	30.7 GB	3.9%	8932.67
Dec 16 02:00:00 - 02:59:59	95980	3.7%	26.66	30.2 GB	3.8%	8794.15
Dec 16 03:00:00 - 03:59:59	89797	3.4%	24.94	28.1 GB	3.6%	8180.59
Dec 16 04:00:00 - 04:15:02	26385	1.0%	29.25	8.1 GB	1.0%	9451.09
TOTAL: 24:00:00	2628805	100.0%	30.43	791.0 GB	100.0%	9599.27

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4672	179	3761	4	728
2	news.nask.pl	540	17	440	5	78
3	news.icm.edu.pl	51	0	4	0	47
TOTAL: 3		5263	196	4205	9	853

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	90
it.tlc.cellulari.android	39
comp.sys.raspberry-pi	17
de.rec.sf.startrek	16
it.comp.os.win.windows10	10
it.comp.os.win.windows7	9
it.comp.os.win.windows8	5
rec.arts.tv.x-factor	4
comp.mobile.ipad	2
de.comp.office-pakete.ms-office.excel	2
hr.rec.glazba.hifi	1
comp.mobile.android	1
TOTAL: 12	196

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

Groups	Count
pl.praca.oferowana	5
pl.sci.historia	2
soc.history.war.vietnam	1
bit.listserv.catholic	1
TOTAL: 4	9

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	184
EMP (md5)	35
Too many newsgroups	14
EMP (fsl)	5
HTML post	1
TOTAL: 5	239

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	56602	2000	27950	784	31	72991	3%	24:00:04
2	icm	53033	472	28522	7664	0	21	0%	23:53:00
3	poznan	7692	55	5247	29	1	0	0%	23:56:29
4	nask	0	0	0	0	0	13487	0%	24:00:00
5	okapi	0	0	0	0	0	2708	0%	24:00:00
TOTAL: 5		117327	2527	61719	8477	32	89207	2%	119:49:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	14.7 MB	1.8 GB	1.9 GB	22.7 KB/s	239.8 KB	23:53:00
2	panorama	8.3 MB	4.2 MB	12.5 MB	0.1 KB/s	4.6 KB	24:00:04
3	poznan	168.0 KB	51.2 KB	219.2 KB	0.0 KB/s	2.6 KB	23:56:29
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		23.2 MB	1.9 GB	1.9 GB	4.6 KB/s	178.5 KB	119:49:33

Backlog files shrunk by innfeed:

Server	Size
panorama	976.8 KB
TOTAL: 1	976.8 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	dmcs2.p.lodz.pl	7	454	1.1 MB	6	0	0	02:46:03
2	web.man.lodz.pl	288	64	63.4 KB	27	0	0	00:00:30
3	pc-212-51-218-225.p.lodz.pl	6	39	86.8 KB	4	0	0	00:58:36
4	ip-176.105.135.22.tvsat364.lodz.pl	5	37	55.1 KB	3	0	0	00:21:11
5	lze200.lze.lodz.pl	6	25	61.3 KB	5	0	0	00:27:45
6	staticline-31-183-128-96.toya.net.pl	5	5	4.5 KB	3	0	2	00:59:27
7	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	0	00:00:00
TOTAL: 7		321	624	1.4 MB	48	4	2	06:36:20

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.p.lodz.pl	13	493	1.2 MB	10	0	0	03:44:40
2	*.man.lodz.pl	676	64	63.4 KB	27	4	0	00:00:35
3	*.105.135.22.tvsat364.lodz.pl	5	37	55.1 KB	3	0	0	00:21:11
4	*.lze.lodz.pl	6	25	61.3 KB	5	0	0	00:27:45
5	*.toya.net.pl	105	5	4.5 KB	3	0	2	00:59:34
6	*.sieradz.vectranet.pl	2	0	0.0 KB	0	0	0	00:01:20
7	unresolved	6	0	0.0 KB	0	0	0	00:00:22
8	*.elsnet.pl	6	0	0.0 KB	0	0	0	00:02:03
9	*.dynamic.chello.pl	3	0	0.0 KB	0	0	0	00:58:35
10	*.centertel.pl	1	0	0.0 KB	0	0	0	00:00:10
TOTAL: 10		823	624	1.4 MB	48	4	2	06:36:20

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
dmcs2.p.lodz.pl	0.069	0.191	02:46:03
staticline-31-183-128-96.toya.net.pl	0.047	0.068	00:59:27
pc-212-51-218-225.p.lodz.pl	0.019	0.064	00:58:36
89-64-2-100.dynamic.chello.pl	0.008	0.041	00:58:35
lze200.lze.lodz.pl	0.026	0.074	00:27:45
ip-176.105.135.22.tvsat364.lodz.pl	0.023	0.063	00:21:11
host227.elsnet.pl	0.016	0.060	00:02:03
088156077175.sieradz.vectranet.pl	0.097	0.053	00:01:20
web.man.lodz.pl	0.949	3.173	00:00:30
195.26.22.130	0.004	0.032	00:00:11
public-gprs351058.centertel.pl	0.000	0.010	00:00:10
185.48.193.14	0.004	0.004	00:00:10
staticline41435.toya.net.pl	0.299	1.105	00:00:07
mon.man.lodz.pl	0.916	2.889	00:00:03
david.man.lodz.pl	0.249	0.971	00:00:00
212.191.87.58	0.009	0.009	00:00:00
isvcs01.man.lodz.pl	0.031	0.044	00:00:00
85.89.169.81	0.004	0.013	00:00:00
TOTAL: 18	2.770	8.864	06:36:20

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	100
david.man.lodz.pl	96
host227.elsnet.pl	6
89-64-2-100.dynamic.chello.pl	3
185.48.193.14	2
195.26.22.130	2
088156077175.sieradz.vectranet.pl	2
212.191.87.58	1
85.89.169.81	1
public-gprs351058.centertel.pl	1
TOTAL: 11	502

NNRP client timeouts [Top 20]:

System	Conn	Peer
dmcs2.p.lodz.pl	1	0
staticline-31-183-128-96.toya.net.pl	1	0
pc-212-51-218-225.p.lodz.pl	1	1
89-64-2-100.dynamic.chello.pl	1	0
TOTAL: 4	4	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	619	99.2%
2	lodz	5	0.8%
TOTAL: 2		624	100.0%

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

Newsgroup		Count
1	pl.misc.elektronika	353
2	pl.misc.kolej	140
3	pl.comp.os.ms-windows.winnt	51
4	pl.soc.prawo	33
5	pl.comp.pecet	26
6	pl.news.admin	7
7	pl.regionalne.lodz	7
8	lodz.test	5
9	pl.misc.telefonia.gsm	2
TOTAL: 9		624

Daily Usenet report

Dec 15 04:15:02 -- Dec 16 04:15:02