Daily Usenet report: Jun 17 04:15:04

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Jun 17 11:01:43 newsfeed nnrpd[11071]: SERVER perl filter not defined
Jun 17 13:10:04 newsfeed pgpverify[15165]: pgpverify: verification failed
Jun 17 14:37:34 newsfeed nnrpd[17821]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2166750	98.1%	247.0 MB	97.6%
innd	25272	1.1%	3.9 MB	1.5%
nnrpd	10100	0.5%	1.0 MB	0.4%
innfeed	7079	0.3%	1.0 MB	0.4%
controlchan	3	0.0%	0.3 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2209205	100.0%	252.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2161269	91.2%
Negative hits	129574	5.5%
Positive hits	77166	3.3%
Cache misses	612	0.0%
TOTAL: 4	2368621	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:21:32.095	1.5%	38466	0.000	33.591	505.000
article cleanup	00:00:37.827	0.0%	2128356	0.000	0.018	3.069
article logging	00:01:29.363	0.1%	2128552	0.000	0.042	3.696
article parse	01:12:01.150	5.0%	50696722	0.018	0.085	4.272
article write	00:37:27.314	2.6%	2111169	0.376	1.064	340.000
artlog/artcncl	00:00:01.713	0.0%	38445	0.000	0.045	3.074
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:48.211	0.2%	61543301	0.001	0.003	0.121
hisgrep/artcncl	00:00:04.917	0.0%	305	0.000	16.121	283.000
hishave/artcncl	00:09:07.839	0.6%	38466	0.000	14.242	310.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	05:42:24.772	23.8%	2330644	0.135	8.815	53.061
history sync	00:00:00.077	0.0%	291	0.000	0.265	47.000
history write	07:00:36.133	29.2%	2123329	0.528	11.885	14844.750
hiswrite/artcncl	00:12:02.336	0.8%	38161	0.000	18.929	280.982
idle	01:26:28.843	6.0%	13299691	0.000	0.390	16.283
nntp read	00:35:00.230	2.4%	48857721	0.012	0.043	2.038
overview write	00:00:18.138	0.0%	2111169	0.000	0.009	0.360
perl filter	06:28:40.088	27.0%	2123326	4.883	10.983	74.390
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:10:04.085	0.7%	4271245	0.006	0.141	2.690
TOTAL: 24:00:07.924	24:00:45.131	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.178	0.0%	50071	0.000	0.024	18.480
article prepare	00:00:00.062	0.0%	39950	0.000	0.002	6.250
article read	00:00:20.739	0.0%	9610	0.000	2.158	188.857
backlog stats	00:00:01.225	0.0%	292569	0.000	0.004	4.305
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:17.212	0.0%	190220	0.000	0.090	37.000
data write	00:00:32.683	0.0%	149740	0.000	0.218	154.333
idle	23:57:09.000	99.8%	292569	0.000	294.730	30796.000
status file	00:00:11.355	0.0%	234	1.000	48.526	1179.000
TOTAL: 23:59:54.352	23:58:33.454	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	69:41:58.268	99.8%	400902	0.000	625.884	600079.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:03:18.441	0.1%	804492	0.000	0.247	18.936
readart	00:00:06.811	0.0%	683	0.000	9.972	216.000
TOTAL: 69:51:55.835	69:45:23.520	99.8%	-	-	-	-

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
newsmaster@carnet.hr	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	137	2115283	2092027	8770	14486	98%	543:06:19
2	news.nask.pl	2	17001	10574	5318	1109	62%	24:05:38
3	news.task.gda.pl	10	68701	8548	58590	1563	12%	46:43:39
4	news.icm.edu.pl	33	452	17	403	32	3%	21:00:49
5	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 5		185	2201439	2111168	73081	17190	95%	634:56:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	692.5 GB	174.8 MB	1.8 GB	694.4 GB	99%	345.7 KB
2	news.nask.pl	291.7 MB	25.2 MB	25.4 MB	342.2 MB	85%	30.0 KB
3	news.task.gda.pl	17.3 MB	357.5 KB	4.2 MB	21.8 MB	79%	2.2 KB
4	news.icm.edu.pl	189.2 KB	24.7 KB	542.2 KB	756.1 KB	25%	15.4 KB
5	localhost	2.7 KB	0.0 KB	0.0 KB	2.7 KB	100%	1.3 KB
TOTAL: 5		692.8 GB	200.4 MB	1.8 GB	694.8 GB	99%	342.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 17 04:15:04 - 04:59:59	48174	2.3%	17.87	17.9 GB	2.6%	6960.50
Jun 17 05:00:00 - 05:59:59	86500	4.1%	24.03	32.5 GB	4.7%	9465.09
Jun 17 06:00:00 - 06:59:59	73182	3.5%	20.33	25.6 GB	3.7%	7459.78
Jun 17 07:00:00 - 07:59:59	86177	4.1%	23.94	28.8 GB	4.2%	8395.01
Jun 17 08:00:00 - 08:59:59	103810	4.9%	28.84	35.9 GB	5.2%	10445.48
Jun 17 09:00:00 - 09:59:59	92549	4.4%	25.71	33.2 GB	4.8%	9673.49
Jun 17 10:00:00 - 10:59:59	105123	5.0%	29.20	37.8 GB	5.5%	11023.01
Jun 17 11:00:00 - 11:59:59	107660	5.1%	29.91	37.0 GB	5.3%	10772.05
Jun 17 12:00:00 - 12:59:59	106268	5.0%	29.52	35.7 GB	5.1%	10384.49
Jun 17 13:00:00 - 13:59:59	103137	4.9%	28.65	36.4 GB	5.2%	10588.35
Jun 17 14:00:00 - 14:59:59	112207	5.3%	31.17	35.5 GB	5.1%	10338.37
Jun 17 15:00:00 - 15:59:59	119573	5.7%	33.21	37.4 GB	5.4%	10889.87
Jun 17 16:00:00 - 16:59:59	99057	4.7%	27.52	31.4 GB	4.5%	9142.74
Jun 17 17:00:00 - 17:59:59	74066	3.5%	20.57	21.5 GB	3.1%	6265.80
Jun 17 18:00:00 - 18:59:59	67724	3.2%	18.81	21.0 GB	3.0%	6116.28
Jun 17 19:00:00 - 19:59:59	71134	3.4%	19.76	24.4 GB	3.5%	7108.68
Jun 17 20:00:00 - 20:59:59	76745	3.6%	21.32	25.6 GB	3.7%	7465.73
Jun 17 21:00:00 - 21:59:59	81532	3.9%	22.65	24.5 GB	3.5%	7132.85
Jun 17 22:00:00 - 22:59:59	61808	2.9%	17.17	19.4 GB	2.8%	5653.76
Jun 17 23:00:00 - 23:59:59	38498	1.8%	10.69	10.6 GB	1.5%	3077.84
Jun 18 00:00:00 - 00:59:59	89605	4.2%	24.89	27.9 GB	4.0%	8138.23
Jun 18 01:00:00 - 01:59:59	93439	4.4%	25.96	29.1 GB	4.2%	8468.75
Jun 18 02:00:00 - 02:59:59	88958	4.2%	24.71	25.4 GB	3.7%	7393.48
Jun 18 03:00:00 - 03:59:59	97349	4.6%	27.04	30.5 GB	4.4%	8893.40
Jun 18 04:00:00 - 04:15:03	26648	1.3%	29.51	7.9 GB	1.1%	9185.45
TOTAL: 23:59:59	2110923	100.0%	24.43	692.9 GB	100.0%	8408.81

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	14626	288	3742	3	10593
2	news.task.gda.pl	1557	1031	209	9	308
3	news.nask.pl	1168	27	1073	0	68
4	news.icm.edu.pl	22	0	2	0	20
TOTAL: 4		17373	1346	5026	12	10989

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.ports-bugs	84
mailing.freebsd.svn-ports-all	81
mailing.freebsd.svn-ports-head	81
uk.rec.cycling.moderated	76
uk.railway	63
mailing.freebsd.svn-src-all	56
fa.freebsd.cvs-all	56
mailing.openbsd.source-changes	51
mailing.openbsd.ports-changes	44
hr.alt.cellular.gsm.android	39
alt.politics.scorched-earth	37
gmane.linux.alsa.devel	36
alt.windows7.general	32
mailing.freebsd.svn-src-head	29
it.tlc.cellulari.android	29
mozilla.dev.gaia	27
comp.mobile.ipad	25
mailing.www.horde-general	24
mailing.freebsd.current	24
comp.sys.raspberry-pi	23
TOTAL: 90	1346

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

Groups	Count
fr.comp.securite	9
pl.praca.oferowana	1
pl.sci.matematyka	1
pl.sci.historia	1
TOTAL: 4	12

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	10406
EMP (fsl)	268
HTML post	53
EMP (md5)	40
Cancel in forbidden group	22
Scoring filter (9)	1
TOTAL: 6	10790

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	60344	4229	35970	2630	1	1165	7%	23:59:54
2	panorama	48002	2316	43821	636	0	0	4%	24:04:50
3	poznan	17970	78	16399	43	0	0	0%	23:58:44
4	task	2291	49	1307	6	0	4	2%	22:00:50
5	nask	13264	1	11742	0	0	0	0%	24:00:29
6	okapi	0	0	0	0	0	3070	0%	24:00:02
TOTAL: 6		141871	6673	109239	3315	1	4239	4%	142:04:49

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	325.2 MB	178.5 MB	503.7 MB	6.0 KB/s	75.2 KB	23:59:54
2	panorama	9.9 MB	5.8 MB	15.7 MB	0.2 KB/s	5.4 KB	24:04:50
3	poznan	242.6 KB	74.0 KB	316.6 KB	0.0 KB/s	2.6 KB	23:58:44
4	task	97.1 KB	17.5 KB	114.6 KB	0.0 KB/s	2.1 KB	22:00:50
5	nask	2.2 KB	0.0 KB	2.2 KB	0.0 KB/s	2.2 KB	24:00:29
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		335.4 MB	184.4 MB	519.8 MB	1.0 KB/s	53.3 KB	142:04:49

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	287	311	481.7 KB	287	0	00:00:24
2	web.man.lodz.pl	288	184	216.2 KB	50	0	00:00:56
3	212.191.78.190	274	81	170.1 KB	44	0	42:54:25
4	lze200.lze.lodz.pl	3	64	104.0 KB	5	0	01:12:08
5	212.191.196.1	2	17	70.0 KB	3	0	01:09:53
6	ip-176.105.135.22.tvsat364.lodz.pl	4	12	30.0 KB	2	0	01:41:56
7	eci130.neoplus.adsl.tpnet.pl	3	12	19.5 KB	3	0	00:00:48
8	pc-vicki.man.lodz.pl	2	1	1.2 KB	1	0	01:00:04
9	212.51.203.199	2	1	1.4 KB	1	0	00:00:37
10	kujawiak.man.lodz.pl	2	0	0.0 KB	0	2	00:00:01
TOTAL: 10		867	683	1.1 MB	396	2	69:51:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	287	311	481.7 KB	287	0	00:00:24
2	*.man.lodz.pl	675	185	217.4 KB	51	2	01:01:24
3	unresolved	278	99	241.5 KB	48	0	44:04:56
4	*.lze.lodz.pl	3	64	104.0 KB	5	0	01:12:08
5	*.neoplus.adsl.tpnet.pl	3	12	19.5 KB	3	0	00:00:48
6	*.105.135.22.tvsat364.lodz.pl	4	12	30.0 KB	2	0	01:41:56
7	*.207.15.62.static.jazztel.es	4	0	0.0 KB	0	0	10:23:32
8	*.181.14.62.static.jazztel.es	8	0	0.0 KB	0	0	11:25:20
9	*.toya.net.pl	69	0	0.0 KB	0	0	00:01:04
TOTAL: 9		1331	683	1.1 MB	396	2	69:51:37

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	1.836	16.212	42:54:25
249.181.14.62.static.jazztel.es	89.373	163.586	11:25:20
120.207.15.62.static.jazztel.es	21.141	32.762	10:23:32
ip-176.105.135.22.tvsat364.lodz.pl	0.020	0.384	01:41:56
lze200.lze.lodz.pl	0.020	0.464	01:12:08
212.191.196.1	0.060	0.676	01:09:53
pc-vicki.man.lodz.pl	0.048	1.068	01:00:04
staticline41435.toya.net.pl	0.816	9.264	00:01:04
web.man.lodz.pl	3.012	35.656	00:00:56
eci130.neoplus.adsl.tpnet.pl	0.020	0.356	00:00:48
212.51.203.199	0.000	0.064	00:00:37
vegas.cm.p.lodz.pl	2.496	33.392	00:00:24
f-oper-v100.man.lodz.pl	2.064	24.908	00:00:15
david.man.lodz.pl	0.384	5.192	00:00:06
kujawiak.man.lodz.pl	0.032	0.164	00:00:01
?	0.000	0.020	00:00:00
TOTAL: 16	121.322	324.168	69:51:37

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	287
david.man.lodz.pl	96
staticline41435.toya.net.pl	69
249.181.14.62.static.jazztel.es	8
120.207.15.62.static.jazztel.es	4
TOTAL: 5	464

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
249.181.14.62.static.jazztel.es	4	0
120.207.15.62.static.jazztel.es	3	0
lze200.lze.lodz.pl	2	0
pc-vicki.man.lodz.pl	2	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
212.191.196.1	1	0
TOTAL: 6	14	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	360	52.7%
2	lodman	323	47.3%
TOTAL: 2		683	100.0%

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

Newsgroup		Count
1	lodman.info	323
2	pl.comp.pecet	218
3	pl.rec.rowery	32
4	pl.rec.kuchnia	21
5	pl.regionalne.lodz	19
6	pl.misc.kolej	15
7	pl.misc.dieta	11
8	pl.regionalne.warszawa	10
9	pl.soc.prawo	8
10	pl.rec.dom	6
11	pl.rec.gory	6
12	pl.praca.dyskusje	5
13	pl.listserv.chomor-l	3
14	pl.internet.polip	2
15	pl.comp.bazy-danych	2
16	pl.sci.medycyna	1
17	pl.rec.radio.amatorskie	1
TOTAL: 17		683

Daily Usenet report

Jun 17 04:15:04 -- Jun 18 04:15:03