Daily Usenet report: Mar 1 04:15:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Mar 1 07:00:04 newsfeed pgpverify[23949]: pgpverify: verification failed
Mar 1 07:04:01 newsfeed pgpverify[24142]: pgpverify: verification failed
Mar 1 14:09:19 newsfeed overchan[2353]: input line too long, skipping
Mar 2 01:28:34 newsfeed pgpverify[28393]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	900503	97.4%	102.6 MB	96.8%
innd	10215	1.1%	1.7 MB	1.6%
nnrpd	7396	0.8%	782.2 KB	0.7%
innfeed	6777	0.7%	962.2 KB	0.9%
controlchan	9	0.0%	1.3 KB	0.0%
pgpverify	3	0.0%	0.3 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	924904	100.0%	106.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	879023	96.6%
Positive hits	16009	1.8%
Negative hits	14445	1.6%
Cache misses	264	0.0%
TOTAL: 4	909741	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.760	0.0%	381	0.000	22.992	478.000
article cleanup	00:00:12.490	0.0%	882366	0.000	0.014	0.040
article logging	00:00:22.231	0.0%	900413	0.000	0.025	0.071
article parse	00:27:47.489	1.9%	66935340	0.009	0.025	0.040
article write	00:06:48.006	0.5%	878958	0.127	0.464	3.688
artlog/artcncl	00:00:00.003	0.0%	317	0.000	0.009	1.000
artlog/artparse	00:00:00.000	0.0%	39	0.000	0.000	0.000
data move	00:01:13.257	0.1%	71979109	0.000	0.001	0.002
hisgrep/artcncl	00:00:02.062	0.0%	350	0.000	5.891	281.000
hishave/artcncl	00:00:00.241	0.0%	381	0.000	0.633	15.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:35:01.369	2.4%	909976	0.145	2.309	8.719
history sync	00:00:00.018	0.0%	390	0.000	0.046	1.000
history write	01:30:01.671	6.3%	879594	1.213	6.141	24.707
hiswrite/artcncl	00:00:00.057	0.0%	31	0.000	1.839	17.000
idle	18:40:27.954	77.8%	51266205	0.321	1.311	7.800
nntp read	00:27:00.839	1.9%	66324220	0.013	0.024	0.035
overview write	00:00:05.516	0.0%	878958	0.000	0.006	0.031
perl filter	01:46:01.141	7.4%	879555	3.614	7.232	9.912
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:39.455	0.1%	1793073	0.013	0.055	0.110
TOTAL: 24:00:20.864	23:36:52.559	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.113	0.0%	35160	0.000	0.003	1.000
article prepare	00:00:00.002	0.0%	14248	0.000	0.000	0.050
article read	00:00:00.305	0.0%	3415	0.000	0.089	1.000
backlog stats	00:00:00.125	0.0%	242085	0.000	0.001	0.016
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.281	0.0%	146927	0.000	0.009	0.064
data write	00:00:08.155	0.0%	121078	0.000	0.067	6.147
idle	23:59:46.170	100.0%	242085	0.000	356.842	25385.000
status file	00:00:01.411	0.0%	194	0.000	7.273	287.000
TOTAL: 24:00:02.872	23:59:57.562	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:28:39.535	99.8%	4387	0.000	2853.780	377759.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.334	0.0%	10536	0.000	0.032	3.778
readart	00:00:00.192	0.0%	315	0.000	0.610	103.000
TOTAL: 03:29:02.545	03:28:40.061	99.8%	-	-	-	-

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
moderator@dana.de	0	0	0	1	0	0
us-control@lists.killfile.org	0	0	0	1	0	0
TOTAL	0	0	0	3	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	391	878646	875446	154	3046	99%	178:09:39
2	news.nask.pl	2	16134	3016	12790	328	18%	24:02:12
3	news.icm.edu.pl	47	293	25	190	78	8%	17:05:05
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		441	895073	878487	13134	3452	98%	219:16:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	216.0 GB	11.3 MB	64.1 MB	216.1 GB	99%	257.9 KB
2	news.nask.pl	12.4 MB	941.7 KB	961.1 KB	14.2 MB	86%	4.4 KB
3	news.icm.edu.pl	717.6 KB	90.1 KB	3.2 MB	4.0 MB	17%	39.4 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		216.0 GB	12.3 MB	68.2 MB	216.1 GB	99%	256.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 1 04:15:01 - 04:59:59	42254	4.8%	15.66	12.5 GB	5.8%	4850.49
Mar 1 05:00:00 - 05:59:59	58101	6.6%	16.14	17.9 GB	8.3%	5216.96
Mar 1 06:00:00 - 06:59:59	31590	3.6%	8.78	8.8 GB	4.1%	2564.87
Mar 1 07:00:00 - 07:59:59	44851	5.1%	12.46	14.1 GB	6.5%	4114.01
Mar 1 08:00:00 - 08:59:59	62598	7.1%	17.39	20.0 GB	9.2%	5822.22
Mar 1 09:00:00 - 09:59:59	66421	7.6%	18.45	20.7 GB	9.6%	6035.77
Mar 1 10:00:00 - 10:59:59	42331	4.8%	11.76	11.4 GB	5.3%	3308.13
Mar 1 11:00:00 - 11:59:59	23516	2.7%	6.53	5.2 GB	2.4%	1518.84
Mar 1 12:00:00 - 12:59:59	12880	1.5%	3.58	1.6 GB	0.7%	462.07
Mar 1 13:00:00 - 13:59:59	15042	1.7%	4.18	1.9 GB	0.9%	552.95
Mar 1 14:00:00 - 14:59:59	29230	3.3%	8.12	2.1 GB	1.0%	623.04
Mar 1 15:00:00 - 15:59:59	20152	2.3%	5.60	2.0 GB	0.9%	585.61
Mar 1 16:00:00 - 16:59:59	15390	1.8%	4.28	2.0 GB	0.9%	570.59
Mar 1 17:00:00 - 17:59:59	17632	2.0%	4.90	2.2 GB	1.0%	648.02
Mar 1 18:00:00 - 18:59:59	13558	1.5%	3.77	1.6 GB	0.8%	474.52
Mar 1 19:00:00 - 19:59:59	14299	1.6%	3.97	1.7 GB	0.8%	493.99
Mar 1 20:00:00 - 20:59:59	14407	1.6%	4.00	1.7 GB	0.8%	503.81
Mar 1 21:00:00 - 21:59:59	13469	1.5%	3.74	1.7 GB	0.8%	500.04
Mar 1 22:00:00 - 22:59:59	14726	1.7%	4.09	1.7 GB	0.8%	499.78
Mar 1 23:00:00 - 23:59:59	14580	1.7%	4.05	1.8 GB	0.8%	521.23
Mar 2 00:00:00 - 00:59:59	53564	6.1%	14.88	13.3 GB	6.2%	3880.36
Mar 2 01:00:00 - 01:59:59	87233	9.9%	24.23	23.8 GB	11.0%	6931.51
Mar 2 02:00:00 - 02:59:59	82128	9.3%	22.81	22.7 GB	10.5%	6625.80
Mar 2 03:00:00 - 03:59:59	75096	8.5%	20.86	20.1 GB	9.3%	5847.12
Mar 2 04:00:00 - 04:15:02	13645	1.6%	15.13	3.5 GB	1.6%	4069.55
TOTAL: 24:00:01	878693	100.0%	10.17	216.1 GB	100.0%	2623.19

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	21082	148	2492	4	18438
2	news.nask.pl	333	2	317	5	9
3	news.icm.edu.pl	78	0	2	0	76
TOTAL: 3		21493	150	2811	9	18523

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	90
it.tlc.cellulari.android	18
it.comp.os.win.windows7	13
comp.sys.raspberry-pi	9
it.comp.os.win.windows8	8
hr.ponuda.hardver.komponente	5
de.rec.sf.startrek	2
comp.mobile.ipad	2
it.comp.virtualizzazione	1
alt.sex.telephones	1
comp.mobile.android	1
TOTAL: 11	150

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

Groups	Count
pl.sci.historia	9
TOTAL: 1	9

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	354
EMP (md5)	74
Attached HTML file	4
Too many newsgroups	3
HTML post	2
Scoring filter (9)	1
TOTAL: 6	438

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	35851	1557	33435	155	0	4%	23:54:35
2	icm	49284	660	32481	685	7	1%	24:05:31
3	nask	21309	33	13639	475	1	0%	23:52:04
4	poznan	4044	14	2791	10	0	0%	23:55:38
5	okapi	0	0	0	0	1654	0%	24:00:00
TOTAL: 5		110488	2264	82346	1325	1662	2%	119:47:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	9.7 MB	59.1 MB	68.8 MB	0.8 KB/s	52.4 KB	24:05:31
2	panorama	8.0 MB	507.3 KB	8.5 MB	0.1 KB/s	5.1 KB	23:54:35
3	nask	1.6 MB	268.2 MB	269.8 MB	3.2 KB/s	543.9 KB	23:52:04
4	poznan	41.8 KB	26.4 KB	68.2 KB	0.0 KB/s	2.8 KB	23:55:38
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		19.3 MB	327.9 MB	347.2 MB	0.8 KB/s	99.1 KB	119:47:48

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	288	429.8 KB	288	00:00:07
2	web.man.lodz.pl	288	22	20.3 KB	10	00:00:21
3	ip-176.105.135.22.tvsat364.lodz.pl	1	4	4.7 KB	1	00:30:29
4	ip-176.105.131.121.tvsat364.lodz.pl	2	1	1.7 KB	1	00:37:04
5	ool-2f14e145.dyn.optonline.net	1	0	0.0 KB	3	00:00:00
TOTAL: 5		580	315	456.4 KB	303	03:29:02

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.cm.p.lodz.pl	288	288	429.8 KB	288	00:00:07
2	*.man.lodz.pl	671	22	20.3 KB	10	00:00:27
3	*.105.135.22.tvsat364.lodz.pl	1	4	4.7 KB	1	00:30:29
4	*.105.131.121.tvsat364.lodz.pl	2	1	1.7 KB	1	00:37:04
5	*.dyn.optonline.net	1	0	0.0 KB	3	00:00:00
6	*.snarked.org	2	0	0.0 KB	0	00:00:02
7	unresolved	1	0	0.0 KB	0	02:16:19
8	*.elsnet.pl	2	0	0.0 KB	0	00:00:22
9	*.toya.net.pl	120	0	0.0 KB	0	00:04:08
TOTAL: 9		1088	315	456.4 KB	303	03:29:02

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.87.58	0.018	0.026	02:16:19
ip-176.105.131.121.tvsat364.lodz.pl	0.006	0.027	00:37:04
ip-176.105.135.22.tvsat364.lodz.pl	0.003	0.014	00:30:29
staticline41435.toya.net.pl	0.439	1.382	00:04:08
host227.elsnet.pl	0.012	0.018	00:00:22
web.man.lodz.pl	0.980	3.459	00:00:21
pc-skrzep2.cm.p.lodz.pl	1.033	3.544	00:00:07
mon.man.lodz.pl	1.002	3.364	00:00:04
news.snarked.org	0.040	0.025	00:00:02
david.man.lodz.pl	0.320	1.118	00:00:01
TOTAL: 10	3.853	12.977	03:29:02

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	120
david.man.lodz.pl	95
news.snarked.org	2
host227.elsnet.pl	2
212.191.87.58	1
TOTAL: 6	508

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	289	59.6%
2	lodz	165	34.0%
3	pl	26	5.4%
4	lodzman	5	1.0%
TOTAL: 4		485	100.0%

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

Newsgroup		Count
1	lodman.info	289
2	lodz.test	163
3	pl.comp.pecet	18
4	lodzman.test	5
5	pl.comp.os.ms-windows.winnt	4
6	pl.regionalne.lodz	4
7	lodz.ogloszenia	2
TOTAL: 7		485

Daily Usenet report

Mar 1 04:15:01 -- Mar 2 04:15:02