Daily Usenet report: Mar 2 04:15:04

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Mar 2 07:24:35 newsfeed pgpverify[11382]: pgpverify: verification failed
Mar 2 17:30:19 newsfeed nnrpd[31111]: unable to get certificate from '/etc/news/cert.pem'
Mar 2 17:30:19 newsfeed nnrpd[31111]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Mar 3 01:30:05 newsfeed pgpverify[14500]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1513618	97.9%	174.7 MB	97.4%
innd	14389	0.9%	2.4 MB	1.3%
nnrpd	10694	0.7%	1.1 MB	0.6%
innfeed	7145	0.5%	1.1 MB	0.6%
controlchan	6	0.0%	0.8 KB	0.0%
pgpverify	2	0.0%	0.4 KB	0.0%
TOTAL: 6	1545854	100.0%	179.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1507081	93.3%
Negative hits	62950	3.9%
Positive hits	44091	2.7%
Cache misses	1528	0.1%
TOTAL: 4	1615650	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:24.061	0.0%	660	0.000	36.456	349.000
article cleanup	00:00:25.892	0.0%	1513754	0.003	0.017	0.038
article logging	00:00:50.823	0.1%	1513930	0.012	0.034	0.498
article parse	00:55:45.017	3.9%	99705192	0.012	0.034	0.078
article write	00:16:08.049	1.1%	1506853	0.260	0.642	1.977
artlog/artcncl	00:00:00.015	0.0%	617	0.000	0.024	1.000
artlog/artparse	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:02:25.452	0.2%	108733246	0.001	0.001	0.035
hisgrep/artcncl	00:00:03.321	0.0%	572	0.000	5.806	105.500
hishave/artcncl	00:00:04.003	0.0%	660	0.000	6.065	132.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:33:34.306	10.7%	1616869	0.574	5.699	13.162
history sync	00:00:00.122	0.0%	293	0.000	0.416	106.000
history write	03:37:49.148	15.1%	1508525	2.376	8.664	26.823
hiswrite/artcncl	00:00:00.674	0.0%	88	0.000	7.659	187.000
idle	11:34:34.746	48.2%	64368057	0.235	0.647	1.662
nntp read	00:45:21.335	3.1%	98541305	0.014	0.028	0.041
overview write	00:00:10.619	0.0%	1506853	0.000	0.007	0.022
perl filter	03:37:12.773	15.1%	1508520	5.493	8.639	10.592
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:28.199	0.2%	3063295	0.026	0.068	0.107
TOTAL: 24:00:37.269	23:28:18.555	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.197	0.0%	49607	0.000	0.004	0.200
article prepare	00:00:00.019	0.0%	65338	0.000	0.000	0.125
article read	00:00:02.521	0.0%	15817	0.000	0.159	2.000
backlog stats	00:00:00.351	0.0%	376111	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.518	0.0%	213590	0.000	0.012	1.000
data write	00:00:10.983	0.0%	196831	0.000	0.056	24.000
idle	23:59:53.660	100.0%	376111	0.000	229.703	15893.000
status file	00:00:01.407	0.0%	227	0.000	6.198	237.000
TOTAL: 24:00:20.138	24:00:11.656	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	46:51:59.255	100.0%	7024	0.000	24020.395	450330.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.633	0.0%	16226	0.000	0.039	7.714
readart	00:00:00.105	0.0%	339	0.000	0.310	18.333
TOTAL: 46:52:48.146	46:51:59.993	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	189

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
hun-mnt@news.sztaki.hu	0	0	0	1	0	0
moderator@dana.de	0	0	0	1	0	0
TOTAL	0	0	0	2	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	723	1499991	1492989	2039	4963	99%	342:05:04
2	news.task.gda.pl	16	34084	7546	25442	1096	22%	47:34:16
3	news.nask.pl	2	20269	5908	13490	871	29%	24:05:34
4	news.icm.edu.pl	41	278	13	260	5	4%	17:54:45
5	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 5		783	1554622	1506456	41231	6935	96%	431:39:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	465.8 GB	112.3 MB	146.0 MB	466.1 GB	99%	326.2 KB
2	news.nask.pl	31.6 MB	3.4 MB	3.5 MB	38.5 MB	82%	5.8 KB
3	news.task.gda.pl	14.3 MB	66.7 KB	2.9 MB	17.3 MB	82%	2.1 KB
4	news.icm.edu.pl	205.8 KB	60.6 KB	136.2 KB	402.7 KB	51%	22.4 KB
5	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		465.9 GB	115.8 MB	152.5 MB	466.1 GB	99%	323.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 2 04:15:04 - 04:59:59	68405	4.5%	25.37	21.3 GB	4.6%	8277.68
Mar 2 05:00:00 - 05:59:59	90022	6.0%	25.01	28.7 GB	6.2%	8351.03
Mar 2 06:00:00 - 06:59:59	86298	5.7%	23.97	28.5 GB	6.1%	8291.69
Mar 2 07:00:00 - 07:59:59	94295	6.3%	26.19	31.2 GB	6.7%	9073.83
Mar 2 08:00:00 - 08:59:59	97349	6.5%	27.04	31.7 GB	6.8%	9241.18
Mar 2 09:00:00 - 09:59:59	83585	5.6%	23.22	29.7 GB	6.4%	8639.53
Mar 2 10:00:00 - 10:59:59	47806	3.2%	13.28	17.3 GB	3.7%	5032.96
Mar 2 11:00:00 - 11:59:59	37698	2.5%	10.47	12.1 GB	2.6%	3527.42
Mar 2 12:00:00 - 12:59:59	43813	2.9%	12.17	13.5 GB	2.9%	3940.29
Mar 2 13:00:00 - 13:59:59	46844	3.1%	13.01	14.8 GB	3.2%	4298.47
Mar 2 14:00:00 - 14:59:59	34841	2.3%	9.68	10.0 GB	2.1%	2914.12
Mar 2 15:00:00 - 15:59:59	30569	2.0%	8.49	8.7 GB	1.9%	2522.71
Mar 2 16:00:00 - 16:59:59	38405	2.6%	10.67	11.4 GB	2.4%	3313.66
Mar 2 17:00:00 - 17:59:59	33748	2.2%	9.37	9.7 GB	2.1%	2836.16
Mar 2 18:00:00 - 18:59:59	25678	1.7%	7.13	6.7 GB	1.4%	1964.60
Mar 2 19:00:00 - 19:59:59	24792	1.6%	6.89	6.7 GB	1.4%	1951.63
Mar 2 20:00:00 - 20:59:59	48477	3.2%	13.47	13.6 GB	2.9%	3955.26
Mar 2 21:00:00 - 21:59:59	65101	4.3%	18.08	14.2 GB	3.0%	4132.97
Mar 2 22:00:00 - 22:59:59	80774	5.4%	22.44	20.5 GB	4.4%	5970.17
Mar 2 23:00:00 - 23:59:59	54443	3.6%	15.12	15.1 GB	3.2%	4393.35
Mar 3 00:00:00 - 00:59:59	73128	4.9%	20.31	22.5 GB	4.8%	6545.34
Mar 3 01:00:00 - 01:59:59	96145	6.4%	26.71	30.8 GB	6.6%	8958.22
Mar 3 02:00:00 - 02:59:59	89149	5.9%	24.76	28.9 GB	6.2%	8404.39
Mar 3 03:00:00 - 03:59:59	91563	6.1%	25.43	30.6 GB	6.6%	8911.17
Mar 3 04:00:00 - 04:15:03	22990	1.5%	25.46	7.8 GB	1.7%	9004.60
TOTAL: 23:59:59	1505918	100.0%	17.43	465.6 GB	100.0%	5651.23

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5102	226	4369	0	507
2	news.task.gda.pl	1088	805	34	6	243
3	news.nask.pl	888	33	828	0	27
4	news.icm.edu.pl	5	0	2	0	3
TOTAL: 4		7083	1064	5233	6	780

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	145
mailing.freebsd.svn-ports-head	133
mailing.freebsd.ports-bugs	93
uk.railway	81
comp.mobile.ipad	73
alt.windows7.general	39
it.tlc.cellulari.android	34
mailing.freebsd.svn-src-all	33
fa.freebsd.cvs-all	33
it.comp.os.win.windows8	30
alt.politics.scorched-earth	29
mailing.freebsd.current	25
mailing.freebsd.svn-src-head	21
comp.mobile.android	19
gmane.linux.alsa.devel	16
mailing.openbsd.source-changes	16
mailing.unix.samba	15
mailing.freebsd.ports	14
fr.usenet.abus.nocem	14
mailing.unix.dovecot	13
TOTAL: 64	1064

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

Groups	Count
fr.comp.lang.php	4
fr.bienvenue	1
fr.comp.applications.libres	1
TOTAL: 3	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	372
EMP (fsl)	215
EMP (md5)	6
HTML post	3
Scoring filter (11)	2
TOTAL: 5	598

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	70484	6621	35989	6572	1	25	9%	24:04:28
2	panorama	50550	2526	46609	321	0	0	4%	23:58:50
3	poznan	15051	109	12757	52	0	0	0%	24:01:33
4	nask	23351	42	15618	1	0	0	0%	24:05:30
5	task	1956	2	1040	7	0	1	0%	21:48:12
6	okapi	0	0	0	0	0	2179	0%	24:00:01
TOTAL: 6		161392	9300	112013	6953	1	2205	5%	141:58:34

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	994.2 MB	1.6 GB	2.5 GB	30.8 KB/s	202.4 KB	24:04:28
2	panorama	15.9 MB	1.4 MB	17.3 MB	0.2 KB/s	6.2 KB	23:58:50
3	poznan	459.0 KB	137.7 KB	596.7 KB	0.0 KB/s	3.7 KB	24:01:33
4	nask	357.3 KB	1.9 KB	359.2 KB	0.0 KB/s	8.4 KB	24:05:30
5	task	3.4 KB	101.7 KB	105.1 KB	0.0 KB/s	11.7 KB	21:48:12
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		1010.8 MB	1.6 GB	2.6 GB	5.3 KB/s	165.4 KB	141:58:34

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	vegas.cm.p.lodz.pl	288	288	404.7 KB	288	00:00:16
2	web.man.lodz.pl	288	44	36.4 KB	21	00:00:24
3	212.191.78.190	247	4	8.0 KB	2	40:31:32
4	ip-176.105.135.22.tvsat364.lodz.pl	2	2	4.3 KB	1	00:28:44
5	robi.retsat1.com.pl	8	1	1.3 KB	1	00:10:03
TOTAL: 5		833	339	454.7 KB	313	46:52:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.cm.p.lodz.pl	288	288	404.7 KB	288	00:00:16
2	*.man.lodz.pl	672	44	36.4 KB	21	00:00:33
3	unresolved	247	4	8.0 KB	2	40:31:32
4	*.105.135.22.tvsat364.lodz.pl	2	2	4.3 KB	1	00:28:44
5	*.retsat1.com.pl	8	1	1.3 KB	1	00:10:03
6	*.pa.nsw.optusnet.com.au	262	0	0.0 KB	0	01:11:28
7	*.wroclaw.mm.pl	30	0	0.0 KB	0	04:28:54
8	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	00:00:21
9	*.toya.net.pl	51	0	0.0 KB	0	00:00:50
TOTAL: 9		1561	339	454.7 KB	313	46:52:46

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.696	3.152	40:31:32
host-89-231-50-15.wroclaw.mm.pl	0.056	0.360	04:28:54
pa49-181-247-18.pa.nsw.optusnet.com.au	0.716	3.228	01:11:28
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.028	00:28:44
robi.retsat1.com.pl	0.168	0.136	00:10:03
staticline41435.toya.net.pl	0.128	0.688	00:00:50
web.man.lodz.pl	0.640	3.796	00:00:24
ecb247.neoplus.adsl.tpnet.pl	0.000	0.016	00:00:21
vegas.cm.p.lodz.pl	0.712	3.612	00:00:16
mon.man.lodz.pl	0.608	4.020	00:00:07
david.man.lodz.pl	0.236	1.272	00:00:01
TOTAL: 11	3.968	20.308	46:52:46

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
pa49-181-247-18.pa.nsw.optusnet.com.au	262
david.man.lodz.pl	96
staticline41435.toya.net.pl	51
host-89-231-50-15.wroclaw.mm.pl	30
ecb247.neoplus.adsl.tpnet.pl	1
TOTAL: 6	728

NNRP client timeouts [Top 20]:

System	Conn	Peer
pa49-181-247-18.pa.nsw.optusnet.com.au	5	0
TOTAL: 1	5	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	289	85.3%
2	pl	50	14.7%
TOTAL: 2		339	100.0%

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

Newsgroup		Count
1	lodman.info	289
2	pl.comp.pecet	34
3	pl.comp.os.ms-windows.winnt	6
4	pl.misc.kolej	4
5	pl.internet.polip	4
6	pl.soc.prawo	2
TOTAL: 6		339

Daily Usenet report

Mar 2 04:15:04 -- Mar 3 04:15:03