Daily Usenet report: May 20 04:15:03

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

May 20 22:00:04 newsfeed pgpverify[29146]: pgpverify: verification failed
May 21 00:07:50 newsfeed nnrpd[949]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1850287	98.4%	216.1 MB	97.9%
innd	16319	0.9%	2.7 MB	1.2%
nnrpd	7189	0.4%	756.4 KB	0.3%
innfeed	7036	0.4%	1.1 MB	0.5%
controlchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1880833	100.0%	220.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1842494	94.1%
Negative hits	72227	3.7%
Positive hits	39467	2.0%
Cache misses	3968	0.2%
TOTAL: 4	1958156	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.560	0.0%	310	0.000	30.839	1428.000
article cleanup	00:00:26.728	0.0%	1849403	0.000	0.014	0.027
article logging	00:00:52.706	0.1%	1849515	0.009	0.028	0.443
article parse	01:00:59.083	4.2%	190076445	0.006	0.019	0.089
article write	00:18:36.432	1.3%	1842912	0.259	0.606	2.652
artlog/artcncl	00:00:00.005	0.0%	302	0.000	0.017	1.000
artlog/artparse	00:00:00.000	0.0%	7	0.000	0.000	0.000
data move	00:02:45.092	0.2%	201942418	0.000	0.001	0.017
hisgrep/artcncl	00:00:01.418	0.0%	260	0.000	5.454	33.000
hishave/artcncl	00:00:00.289	0.0%	310	0.000	0.932	17.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:15:27.995	9.4%	1960433	1.256	4.146	12.115
history sync	00:00:00.010	0.0%	294	0.000	0.034	2.000
history write	03:44:04.718	15.6%	1844866	4.162	7.288	16.838
hiswrite/artcncl	00:00:00.162	0.0%	50	0.000	3.240	19.000
idle	10:52:26.422	45.3%	142218666	0.145	0.275	1.178
nntp read	00:58:30.527	4.1%	188691259	0.009	0.019	0.043
overview write	00:00:11.321	0.0%	1842912	0.000	0.006	0.017
perl filter	03:56:45.077	16.4%	1844859	4.449	7.700	10.805
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:51.650	0.3%	3742757	0.011	0.062	0.089
TOTAL: 23:59:33.342	23:15:09.195	96.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.195	0.0%	56960	0.000	0.003	0.083
article prepare	00:00:00.007	0.0%	35698	0.000	0.000	0.062
article read	00:00:01.007	0.0%	8720	0.000	0.115	1.500
backlog stats	00:00:00.242	0.0%	358038	0.000	0.001	0.012
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.210	0.0%	208224	0.000	0.011	0.066
data write	00:00:07.541	0.0%	170289	0.000	0.044	2.947
idle	23:59:31.404	100.0%	358038	0.000	241.235	9945.000
status file	00:00:00.297	0.0%	209	0.000	1.421	24.000
TOTAL: 23:59:51.846	23:59:42.903	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	10:40:13.421	99.8%	298908	0.000	128.513	450041.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:10.281	0.0%	599627	0.000	0.017	10.625
readart	00:00:01.617	0.0%	378	0.000	4.278	88.000
TOTAL: 10:41:15.817	10:40:25.319	99.9%	-	-	-	-

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
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	763	1841746	1831751	6197	3798	99%	282:32:01
2	news.nask.pl	4	17380	6016	9985	1379	34%	23:50:47
3	news.task.gda.pl	16	29710	5789	22637	1284	19%	44:45:28
4	localhost	2	1	1	0	0	100%	00:00:00
5	news.icm.edu.pl	46	234	0	224	10	0%	17:17:28
TOTAL: 5		831	1889071	1843557	39043	6471	97%	368:25:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	556.4 GB	44.9 MB	92.1 MB	556.6 GB	99%	317.9 KB
2	news.nask.pl	24.7 MB	4.1 MB	4.2 MB	32.9 MB	74%	4.6 KB
3	news.task.gda.pl	12.9 MB	40.0 KB	3.9 MB	16.9 MB	76%	2.4 KB
4	localhost	3.4 KB	0.0 KB	0.0 KB	3.4 KB	100%	3.4 KB
5	news.icm.edu.pl	0.0 KB	0.0 KB	374.7 KB	374.7 KB	0%	37.5 KB
TOTAL: 5		556.5 GB	49.0 MB	100.6 MB	556.6 GB	99%	315.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 20 04:15:03 - 04:59:59	76408	4.1%	28.33	19.9 GB	3.6%	7751.29
May 20 05:00:00 - 05:59:59	103942	5.6%	28.87	28.0 GB	5.0%	8155.24
May 20 06:00:00 - 06:59:59	105364	5.7%	29.27	27.2 GB	4.9%	7910.17
May 20 07:00:00 - 07:59:59	69396	3.8%	19.28	24.3 GB	4.4%	7092.20
May 20 08:00:00 - 08:59:59	69509	3.8%	19.31	26.0 GB	4.7%	7565.40
May 20 09:00:00 - 09:59:59	72123	3.9%	20.03	26.1 GB	4.7%	7611.66
May 20 10:00:00 - 10:59:59	77706	4.2%	21.59	27.9 GB	5.0%	8118.52
May 20 11:00:00 - 11:59:59	70215	3.8%	19.50	24.9 GB	4.5%	7264.51
May 20 12:00:00 - 12:59:59	84878	4.6%	23.58	27.8 GB	5.0%	8102.21
May 20 13:00:00 - 13:59:59	110736	6.0%	30.76	27.9 GB	5.0%	8123.07
May 20 14:00:00 - 14:59:59	107917	5.9%	29.98	27.3 GB	4.9%	7953.81
May 20 15:00:00 - 15:59:59	104621	5.7%	29.06	24.4 GB	4.4%	7115.32
May 20 16:00:00 - 16:59:59	108926	5.9%	30.26	26.2 GB	4.7%	7644.59
May 20 17:00:00 - 17:59:59	107734	5.8%	29.93	27.5 GB	4.9%	8023.83
May 20 18:00:00 - 18:59:59	97113	5.3%	26.98	23.6 GB	4.2%	6872.38
May 20 19:00:00 - 19:59:59	73791	4.0%	20.50	16.9 GB	3.0%	4908.54
May 20 20:00:00 - 20:59:59	35666	1.9%	9.91	13.9 GB	2.5%	4053.32
May 20 21:00:00 - 21:59:59	39421	2.1%	10.95	15.1 GB	2.7%	4408.71
May 20 22:00:00 - 22:59:59	37863	2.1%	10.52	13.6 GB	2.4%	3953.44
May 20 23:00:00 - 23:59:59	35444	1.9%	9.85	13.6 GB	2.4%	3957.99
May 21 00:00:00 - 00:59:59	35811	1.9%	9.95	13.1 GB	2.4%	3829.28
May 21 01:00:00 - 01:59:59	56160	3.0%	15.60	20.8 GB	3.7%	6049.47
May 21 02:00:00 - 02:59:59	64237	3.5%	17.84	24.5 GB	4.4%	7132.63
May 21 03:00:00 - 03:59:59	77883	4.2%	21.63	28.5 GB	5.1%	8305.03
May 21 04:00:00 - 04:15:02	20512	1.1%	22.74	7.6 GB	1.4%	8870.93
TOTAL: 23:59:59	1843376	100.0%	21.34	556.8 GB	100.0%	6757.62

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3893	158	3146	1	588
2	news.nask.pl	1415	12	1371	1	31
3	news.task.gda.pl	1291	1102	26	0	163
4	news.icm.edu.pl	10	0	0	0	10
TOTAL: 4		6609	1272	4543	2	792

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.ports-bugs	158
mailing.freebsd.svn-ports-all	107
mailing.freebsd.svn-ports-head	106
uk.railway	64
alt.windows7.general	51
mailing.unix.samba	49
mozilla.dev.platform	47
fr.usenet.abus.nocem	42
mailing.gcc.gcc-cvs	40
gmane.linux.alsa.devel	40
mailing.freebsd.svn-src-all	36
mailing.freebsd.svn-src-head	34
fa.freebsd.cvs-all	32
mailing.postfix.users	26
mailing.freebsd.ports	26
mailing.openbsd.source-changes	26
comp.mobile.android	26
mailing.openbsd.ports-changes	18
gnu.hurd.bug	17
it.comp.os.win.windows8	17
TOTAL: 71	1272

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	470
EMP (fsl)	147
Attached HTML file	27
EMP (md5)	23
Too many newsgroups (low_xpost)	3
HTML post	2
Scoring filter (9)	1
TOTAL: 7	673

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	62226	2472	54553	429	0	0	3%	23:59:33
2	icm	57966	2190	30740	3684	3	6	3%	23:59:58
3	nask	19381	55	12212	0	0	0	0%	23:55:27
4	task	1775	38	906	20	0	0	2%	21:41:06
5	poznan	13841	30	11642	13	0	0	0%	24:04:07
6	okapi	0	0	0	0	0	2549	0%	24:00:00
TOTAL: 6		155189	4785	110053	4146	3	2555	3%	141:40:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	69.7 MB	972.6 MB	1.0 GB	12.4 KB/s	181.7 KB	23:59:58
2	panorama	10.6 MB	1.7 MB	12.3 MB	0.1 KB/s	4.3 KB	23:59:33
3	nask	123.8 KB	0.0 KB	123.8 KB	0.0 KB/s	2.3 KB	23:55:27
4	task	91.8 KB	39.6 KB	131.3 KB	0.0 KB/s	2.3 KB	21:41:06
5	poznan	77.7 KB	75.9 KB	153.6 KB	0.0 KB/s	3.6 KB	24:04:07
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		80.6 MB	974.5 MB	1.0 GB	2.1 KB/s	121.0 KB	141:40:11

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	287	287	368.6 KB	287	0	00:00:12
2	lze200.lze.lodz.pl	2	67	118.1 KB	6	0	00:22:58
3	web.man.lodz.pl	289	17	17.0 KB	9	1	00:00:30
4	212.51.203.199	2	6	6.9 KB	1	0	00:04:56
5	178.219.98.184	2	1	1.2 KB	1	0	00:02:06
TOTAL: 5		582	378	511.7 KB	304	1	10:41:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	287	287	368.6 KB	287	0	00:00:12
2	*.lze.lodz.pl	2	67	118.1 KB	6	0	00:22:58
3	*.man.lodz.pl	673	17	17.0 KB	9	1	00:00:37
4	unresolved	5	7	8.0 KB	2	0	00:07:32
5	*.pa.nsw.optusnet.com.au	28	0	0.0 KB	0	0	00:00:48
6	*.cbmm.lodz.pl	2	0	0.0 KB	0	0	00:30:02
7	*.191.14.62.static.jazztel.es	2	0	0.0 KB	0	0	09:38:53
8	*.toya.net.pl	63	0	0.0 KB	0	0	00:00:10
TOTAL: 8		1062	378	511.7 KB	304	1	10:41:15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
242.191.14.62.static.jazztel.es	13.956	22.164	09:38:53
router2.cbmm.lodz.pl	0.004	0.032	00:30:02
lze200.lze.lodz.pl	0.012	0.068	00:22:58
212.51.203.199	0.012	0.024	00:04:56
178.219.98.184	0.040	0.088	00:02:06
pa49-181-247-19.pa.nsw.optusnet.com.au	0.048	0.392	00:00:48
web.man.lodz.pl	0.612	4.920	00:00:30
194.181.86.89	0.028	0.016	00:00:29
pc-skrzep2.cm.p.lodz.pl	0.600	3.944	00:00:12
staticline41435.toya.net.pl	0.132	1.032	00:00:10
mon.man.lodz.pl	0.644	4.172	00:00:05
david.man.lodz.pl	0.208	1.408	00:00:01
TOTAL: 12	16.296	38.260	10:41:15

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	63
pa49-181-247-19.pa.nsw.optusnet.com.au	28
242.191.14.62.static.jazztel.es	2
router2.cbmm.lodz.pl	2
194.181.86.89	1
TOTAL: 7	480

NNRP client timeouts [Top 20]:

System	Conn	Peer
router2.cbmm.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	288	76.2%
2	pl	86	22.8%
3	alt	4	1.1%
TOTAL: 3		378	100.0%

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

Newsgroup		Count
1	lodman.info	288
2	pl.comp.os.linux	21
3	pl.rec.dom	20
4	pl.comp.pecet	13
5	pl.comp.os.ms-windows.winnt	12
6	pl.misc.telefonia.gsm	7
7	pl.rec.radio.amatorskie	6
8	pl.rec.kuchnia	6
9	alt.pl.comp.os.linux.newbie	4
10	pl.rec.mineraly	1
TOTAL: 10		378

Daily Usenet report

May 20 04:15:03 -- May 21 04:15:02