Daily Usenet report: Oct 21 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Oct 21 04:39:10 newsfeed pgpverify[30096]: pgpverify: verification failed
Oct 21 17:56:42 newsfeed nnrpd[24108]: SERVER perl filter not defined
Oct 21 19:38:41 newsfeed nnrpd[27563]: SERVER perl filter not defined
Oct 21 23:10:35 newsfeed overchan[2600]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1639209	97.5%	186.5 MB	97.0%
innd	25233	1.5%	3.7 MB	1.9%
nnrpd	10321	0.6%	1.0 MB	0.5%
innfeed	7060	0.4%	1.0 MB	0.5%
controlchan	3	0.0%	0.4 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	1681828	100.0%	192.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1632113	91.4%
Negative hits	109582	6.1%
Positive hits	44008	2.5%
Cache misses	277	0.0%
TOTAL: 4	1785980	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:14.187	0.0%	371	0.000	38.240	739.000
article cleanup	00:00:20.402	0.0%	1638487	0.000	0.012	0.056
article logging	00:00:49.006	0.1%	1638633	0.007	0.030	0.758
article parse	00:45:28.936	3.2%	49831675	0.014	0.055	0.095
article write	00:15:33.664	1.1%	1620962	0.313	0.576	2.949
artlog/artcncl	00:00:00.005	0.0%	369	0.000	0.014	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:04:35.009	0.3%	59401904	0.000	0.005	0.192
hisgrep/artcncl	00:00:01.516	0.0%	283	0.000	5.357	238.000
hishave/artcncl	00:00:00.931	0.0%	371	0.000	2.509	84.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:00:45.899	8.4%	1787591	0.008	4.053	27.263
history sync	00:00:00.012	0.0%	291	0.000	0.041	2.000
history write	04:07:30.930	17.2%	1633767	0.167	9.090	181.728
hiswrite/artcncl	00:00:00.690	0.0%	88	0.000	7.841	264.000
idle	11:56:02.152	49.7%	29464154	0.183	1.458	4.740
nntp read	00:23:16.228	1.6%	48604582	0.012	0.029	0.116
overview write	00:00:09.746	0.0%	1620962	0.000	0.006	0.023
perl filter	04:06:05.873	17.1%	1633766	5.297	9.038	13.503
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:12.647	0.2%	3289194	0.031	0.059	0.090
TOTAL: 23:59:48.459	23:44:07.833	98.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.183	0.0%	47285	0.000	0.004	0.143
article prepare	00:00:00.011	0.0%	47954	0.000	0.000	0.042
article read	00:00:06.125	0.0%	11723	0.000	0.522	120.000
backlog stats	00:00:00.267	0.0%	359087	0.000	0.001	0.036
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.128	0.0%	200294	0.000	0.011	1.000
data write	00:00:09.605	0.0%	187317	0.000	0.051	5.000
idle	23:59:37.140	100.0%	359087	0.000	240.547	11316.000
status file	00:00:01.967	0.0%	233	0.000	8.442	144.500
TOTAL: 24:00:05.108	23:59:57.426	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	77:29:49.590	100.0%	635646	0.000	438.907	450707.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:19.283	0.0%	1273731	0.000	0.015	2.111
readart	00:00:02.186	0.0%	578	0.000	3.782	33.800
TOTAL: 77:31:24.743	77:30:11.059	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
nl-admin@nic.surfnet.nl	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	601	1624620	1607392	2063	15165	98%	308:00:35
2	news.task.gda.pl	13	32381	6628	24205	1548	20%	45:52:55
3	news.nask.pl	2	19889	6390	12699	800	32%	24:08:05
4	news.icm.edu.pl	48	258	7	230	21	2%	18:45:42
5	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 5		667	1677150	1620419	39197	17534	96%	396:47:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	564.8 GB	124.1 MB	1.5 GB	566.5 GB	99%	366.1 KB
2	news.nask.pl	154.3 MB	7.7 MB	7.9 MB	169.9 MB	90%	24.2 KB
3	news.task.gda.pl	13.3 MB	95.0 KB	4.7 MB	18.1 MB	73%	2.3 KB
4	news.icm.edu.pl	168.4 KB	0.0 KB	330.3 KB	498.7 KB	33%	17.8 KB
5	localhost	3.5 KB	0.0 KB	0.0 KB	3.5 KB	100%	1.7 KB
TOTAL: 5		565.0 GB	131.9 MB	1.6 GB	566.7 GB	99%	362.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 21 04:15:02 - 04:59:59	72978	4.5%	27.05	27.0 GB	4.8%	10490.75
Oct 21 05:00:00 - 05:59:59	93891	5.8%	26.08	34.8 GB	6.2%	10146.60
Oct 21 06:00:00 - 06:59:59	101809	6.3%	28.28	37.1 GB	6.6%	10815.15
Oct 21 07:00:00 - 07:59:59	98146	6.1%	27.26	35.4 GB	6.3%	10316.77
Oct 21 08:00:00 - 08:59:59	97492	6.0%	27.08	34.8 GB	6.2%	10136.34
Oct 21 09:00:00 - 09:59:59	81941	5.1%	22.76	29.8 GB	5.3%	8691.92
Oct 21 10:00:00 - 10:59:59	46102	2.8%	12.81	16.7 GB	3.0%	4856.99
Oct 21 11:00:00 - 11:59:59	44336	2.7%	12.32	16.2 GB	2.9%	4732.31
Oct 21 12:00:00 - 12:59:59	48523	3.0%	13.48	16.9 GB	3.0%	4919.24
Oct 21 13:00:00 - 13:59:59	44586	2.8%	12.38	14.6 GB	2.6%	4251.48
Oct 21 14:00:00 - 14:59:59	45138	2.8%	12.54	15.9 GB	2.8%	4626.24
Oct 21 15:00:00 - 15:59:59	40523	2.5%	11.26	13.8 GB	2.4%	4032.02
Oct 21 16:00:00 - 16:59:59	43383	2.7%	12.05	14.4 GB	2.6%	4207.69
Oct 21 17:00:00 - 17:59:59	46310	2.9%	12.86	14.5 GB	2.6%	4210.86
Oct 21 18:00:00 - 18:59:59	76150	4.7%	21.15	25.1 GB	4.4%	7324.82
Oct 21 19:00:00 - 19:59:59	95340	5.9%	26.48	30.6 GB	5.4%	8906.48
Oct 21 20:00:00 - 20:59:59	57168	3.5%	15.88	18.6 GB	3.3%	5409.64
Oct 21 21:00:00 - 21:59:59	43028	2.7%	11.95	14.2 GB	2.5%	4132.63
Oct 21 22:00:00 - 22:59:59	41440	2.6%	11.51	13.9 GB	2.5%	4039.68
Oct 21 23:00:00 - 23:59:59	40594	2.5%	11.28	13.6 GB	2.4%	3966.62
Oct 22 00:00:00 - 00:59:59	76413	4.7%	21.23	25.2 GB	4.5%	7346.54
Oct 22 01:00:00 - 01:59:59	92730	5.7%	25.76	32.1 GB	5.7%	9350.99
Oct 22 02:00:00 - 02:59:59	87363	5.4%	24.27	31.2 GB	5.5%	9085.66
Oct 22 03:00:00 - 03:59:59	82942	5.1%	23.04	30.4 GB	5.4%	8853.94
Oct 22 04:00:00 - 04:15:03	22839	1.4%	25.29	8.2 GB	1.5%	9555.26
TOTAL: 24:00:01	1621165	100.0%	18.76	565.2 GB	100.0%	6859.07

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	15301	228	3918	18	11137
2	news.task.gda.pl	1546	1213	56	0	277
3	news.nask.pl	807	9	747	19	32
4	news.icm.edu.pl	21	0	0	0	21
TOTAL: 4		17675	1450	4721	37	11467

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	140
mailing.freebsd.svn-ports-head	140
uk.railway	105
mailing.freebsd.ports-bugs	59
mailing.database.pgsql-general	45
mailing.freebsd.current	44
mailing.openbsd.ports-changes	42
mailing.openbsd.source-changes	42
mailing.freebsd.svn-src-all	40
fa.freebsd.cvs-all	38
alt.windows7.general	36
mozilla.dev.platform	35
mailing.unix.samba	33
mailing.freebsd.svn-src-head	32
it.comp.os.win.windows8	30
mailing.postfix.users	30
mailing.gcc.gcc-cvs	27
mailing.unix.samba-technical	27
mailing.unix.dovecot	27
mailing.freebsd.ports	27
TOTAL: 90	1450

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

Groups	Count
pl.sci.historia	34
pl.sci.matematyka	2
bit.listserv.catholic	1
TOTAL: 3	37

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	10920
EMP (fsl)	172
Attached HTML file	80
EMP (phl)	75
EMP (md5)	38
HTML post	33
Scoring filter (8)	2
TOTAL: 7	11320

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	49357	3116	43341	883	1	0	6%	24:04:48
2	icm	66600	1932	36616	5893	4	11	2%	23:51:26
3	poznan	14124	26	11685	12	0	0	0%	24:02:48
4	nask	23194	21	15743	74	0	0	0%	24:07:12
5	task	2407	9	1278	5	0	0	0%	21:55:26
6	okapi	0	0	0	0	0	2644	0%	24:00:01
TOTAL: 6		155682	5104	108663	6867	5	2655	3%	142:01:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	15.8 MB	2.5 GB	2.5 GB	30.7 KB/s	337.3 KB	23:51:26
2	panorama	13.4 MB	6.5 MB	19.9 MB	0.2 KB/s	5.1 KB	24:04:48
3	nask	104.1 KB	46.0 MB	46.1 MB	0.5 KB/s	496.6 KB	24:07:12
4	poznan	73.9 KB	32.6 KB	106.5 KB	0.0 KB/s	2.8 KB	24:02:48
5	task	13.2 KB	11.6 KB	24.7 KB	0.0 KB/s	1.8 KB	21:55:26
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		29.4 MB	2.6 GB	2.6 GB	5.3 KB/s	226.1 KB	142:01:41

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	vegas.cm.p.lodz.pl	288	288	367.9 KB	288	0	00:00:10
2	212.191.78.190	283	133	257.8 KB	36	2	44:50:54
3	web.man.lodz.pl	288	118	160.2 KB	53	0	00:00:22
4	lze200.lze.lodz.pl	5	37	60.3 KB	7	0	00:44:30
5	212.160.68.149	2	1	1.8 KB	1	0	00:00:03
6	router2.cbmm.lodz.pl	2	1	1.9 KB	1	0	00:04:23
TOTAL: 6		868	578	849.8 KB	386	2	77:31:22

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	288	367.9 KB	288	0	00:00:10
2	unresolved	287	134	259.6 KB	37	2	44:51:09
3	*.man.lodz.pl	673	118	160.2 KB	53	0	00:00:29
4	*.lze.lodz.pl	5	37	60.3 KB	7	0	00:44:30
5	*.cbmm.lodz.pl	2	1	1.9 KB	1	0	00:04:23
6	*.sm-rozstaje.pl	1	0	0.0 KB	0	0	00:00:28
7	*.191.14.62.static.jazztel.es	3	0	0.0 KB	0	0	21:43:22
8	*.lodz.mm.pl	50	0	0.0 KB	0	0	08:13:09
9	*.internetdsl.tpnet.pl	3	0	0.0 KB	0	0	00:00:00
10	*.105.135.22.tvsat364.lodz.pl	6	0	0.0 KB	0	0	01:53:31
11	*.toya.net.pl	58	0	0.0 KB	0	0	00:00:05
TOTAL: 11		1376	578	849.8 KB	386	2	77:31:22

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.660	3.596	44:50:54
16.191.14.62.static.jazztel.es	33.380	52.352	21:43:22
host-89-228-165-199.lodz.mm.pl	0.096	0.632	08:13:09
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.084	01:53:31
lze200.lze.lodz.pl	0.012	0.084	00:44:30
router2.cbmm.lodz.pl	0.000	0.032	00:04:23
47-z.sm-rozstaje.pl	0.000	0.020	00:00:28
web.man.lodz.pl	0.616	3.576	00:00:22
212.51.203.199	0.008	0.024	00:00:12
vegas.cm.p.lodz.pl	0.636	3.676	00:00:10
f-oper-v100.man.lodz.pl	0.604	3.508	00:00:05
staticline41435.toya.net.pl	0.140	0.696	00:00:05
212.160.68.149	0.000	0.016	00:00:03
david.man.lodz.pl	0.176	1.100	00:00:01
gcq193.internetdsl.tpnet.pl	0.000	0.040	00:00:00
?	0.000	0.016	00:00:00
TOTAL: 16	36.336	69.452	77:31:22

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	289
david.man.lodz.pl	96
staticline41435.toya.net.pl	58
host-89-228-165-199.lodz.mm.pl	50
ip-176.105.135.22.tvsat364.lodz.pl	6
gcq193.internetdsl.tpnet.pl	3
16.191.14.62.static.jazztel.es	3
212.51.203.199	2
47-z.sm-rozstaje.pl	1
TOTAL: 9	508

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.191.78.190	2	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
TOTAL: 2	4	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	289	50.0%
2	lodman	289	50.0%
TOTAL: 2		578	100.0%

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

Newsgroup		Count
1	lodman.info	288
2	pl.comp.os.ms-windows.winnt	78
3	pl.rec.rowery	46
4	pl.comp.pecet	44
5	pl.misc.kolej	44
6	pl.misc.telefonia.gsm	25
7	pl.comp.os.linux	22
8	pl.regionalne.warszawa	13
9	pl.soc.prawo	4
10	pl.rec.kuchnia	3
11	pl.regionalne.lodz	3
12	pl.comp.os.linux.sieci	3
13	pl.rec.dom	2
14	pl.praca.dyskusje	1
15	lodman.test	1
16	pl.rec.gory	1
TOTAL: 16		578

Daily Usenet report

Oct 21 04:15:02 -- Oct 22 04:15:03