Daily Usenet report: Aug 19 04:15:04

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Aug 19 05:11:36 newsfeed nnrpd[15562]: SERVER perl filter not defined
Aug 19 10:06:57 newsfeed nnrpd[25027]: SERVER perl filter not defined
Aug 19 10:10:53 newsfeed pgpverify[25257]: pgpverify: verification failed
Aug 19 11:57:00 newsfeed nnrpd[28482]: SERVER perl filter not defined
Aug 19 13:57:48 newsfeed nnrpd[32281]: SERVER perl filter not defined
Aug 19 16:00:25 newsfeed nnrpd[3904]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2368687	98.6%	266.7 MB	98.2%
innd	18173	0.8%	3.1 MB	1.1%
nnrpd	7516	0.3%	803.9 KB	0.3%
innfeed	7054	0.3%	1.0 MB	0.4%
controlchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2401432	100.0%	271.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2365071	94.4%
Negative hits	100036	4.0%
Positive hits	37325	1.5%
Cache misses	3501	0.1%
TOTAL: 4	2505933	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.623	0.0%	271	0.000	31.819	533.000
article cleanup	00:00:31.571	0.0%	2369626	0.004	0.013	0.025
article logging	00:01:04.631	0.1%	2369805	0.011	0.027	0.665
article parse	01:21:10.204	5.6%	89796510	0.023	0.054	0.107
article write	00:24:39.357	1.7%	2365405	0.413	0.625	1.880
artlog/artcncl	00:00:00.006	0.0%	255	0.000	0.024	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:05.223	0.1%	104082055	0.001	0.001	0.005
hisgrep/artcncl	00:00:02.117	0.0%	244	0.000	8.676	259.000
hishave/artcncl	00:00:01.401	0.0%	271	0.000	5.170	277.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:28:24.915	14.5%	2508191	0.354	4.986	13.847
history sync	00:00:00.012	0.0%	292	0.000	0.041	1.000
history write	04:52:13.893	20.3%	2367403	0.086	7.406	29.050
hiswrite/artcncl	00:00:00.547	0.0%	27	0.000	20.259	273.000
idle	06:23:52.610	26.6%	50768044	0.154	0.454	1.679
nntp read	00:44:26.809	3.1%	87863096	0.015	0.030	0.054
overview write	00:00:14.071	0.0%	2365405	0.001	0.006	0.016
perl filter	06:12:27.498	25.9%	2367403	6.382	9.440	11.429
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:50.778	0.3%	4769048	0.011	0.048	0.077
TOTAL: 24:00:47.358	23:35:14.266	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.141	0.0%	38221	0.000	0.004	0.143
article prepare	00:00:00.013	0.0%	37428	0.000	0.000	0.083
article read	00:00:00.317	0.0%	8780	0.000	0.036	1.000
backlog stats	00:00:00.185	0.0%	268678	0.000	0.001	0.024
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.781	0.0%	167759	0.000	0.011	0.105
data write	00:00:03.460	0.0%	132880	0.000	0.026	0.500
idle	24:00:11.815	100.0%	268678	0.000	321.618	11321.000
status file	00:00:00.533	0.0%	214	0.000	2.491	79.000
TOTAL: 24:00:24.060	24:00:18.245	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	20:44:13.264	99.9%	326042	0.000	228.968	600071.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:09.661	0.0%	654279	0.000	0.015	0.667
readart	00:00:02.377	0.0%	575	0.000	4.134	85.167
TOTAL: 20:45:11.956	20:44:25.302	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
control@usenet.org.uk	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	532	2361577	2350999	8133	2445	99%	368:09:12
2	news.nask.pl	2	15375	6986	7838	551	45%	24:28:34
3	news.task.gda.pl	13	29870	6171	22499	1200	20%	46:55:18
4	news.icm.edu.pl	45	344	27	278	39	7%	19:34:39
5	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 5		598	2407171	2364188	38748	4235	98%	459:07:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	835.3 GB	12.5 MB	62.5 MB	835.4 GB	99%	372.2 KB
2	news.nask.pl	24.2 MB	1.3 MB	1.4 MB	26.9 MB	89%	3.7 KB
3	news.task.gda.pl	12.5 MB	32.2 KB	3.5 MB	16.0 MB	77%	2.2 KB
4	news.icm.edu.pl	1011.6 KB	0.0 KB	736.3 KB	1.7 MB	57%	26.5 KB
5	localhost	13.2 KB	0.0 KB	0.0 KB	13.2 KB	100%	2.6 KB
TOTAL: 5		835.3 GB	13.9 MB	68.1 MB	835.4 GB	99%	369.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 19 04:15:04 - 04:59:59	70749	3.0%	26.24	25.0 GB	3.0%	9740.44
Aug 19 05:00:00 - 05:59:59	101943	4.3%	28.32	35.4 GB	4.2%	10321.06
Aug 19 06:00:00 - 06:59:59	97423	4.1%	27.06	33.9 GB	4.1%	9866.44
Aug 19 07:00:00 - 07:59:59	99903	4.2%	27.75	34.0 GB	4.1%	9894.75
Aug 19 08:00:00 - 08:59:59	102758	4.3%	28.54	33.6 GB	4.0%	9788.96
Aug 19 09:00:00 - 09:59:59	107422	4.5%	29.84	35.7 GB	4.3%	10396.07
Aug 19 10:00:00 - 10:59:59	91643	3.9%	25.46	32.3 GB	3.9%	9421.43
Aug 19 11:00:00 - 11:59:59	82002	3.5%	22.78	29.2 GB	3.5%	8502.72
Aug 19 12:00:00 - 12:59:59	108637	4.6%	30.18	37.3 GB	4.5%	10867.50
Aug 19 13:00:00 - 13:59:59	119866	5.1%	33.30	38.7 GB	4.6%	11266.24
Aug 19 14:00:00 - 14:59:59	123019	5.2%	34.17	38.4 GB	4.6%	11192.00
Aug 19 15:00:00 - 15:59:59	102000	4.3%	28.33	36.3 GB	4.4%	10583.86
Aug 19 16:00:00 - 16:59:59	105906	4.5%	29.42	36.2 GB	4.3%	10552.98
Aug 19 17:00:00 - 17:59:59	108142	4.6%	30.04	38.0 GB	4.6%	11064.36
Aug 19 18:00:00 - 18:59:59	91487	3.9%	25.41	32.7 GB	3.9%	9512.12
Aug 19 19:00:00 - 19:59:59	99989	4.2%	27.77	35.6 GB	4.3%	10367.41
Aug 19 20:00:00 - 20:59:59	99475	4.2%	27.63	36.3 GB	4.4%	10582.71
Aug 19 21:00:00 - 21:59:59	88366	3.7%	24.55	34.3 GB	4.1%	9976.74
Aug 19 22:00:00 - 22:59:59	87002	3.7%	24.17	34.0 GB	4.1%	9895.23
Aug 19 23:00:00 - 23:59:59	89790	3.8%	24.94	34.4 GB	4.1%	10010.08
Aug 20 00:00:00 - 00:59:59	75868	3.2%	21.07	29.7 GB	3.6%	8653.47
Aug 20 01:00:00 - 01:59:59	77848	3.3%	21.62	29.2 GB	3.5%	8508.42
Aug 20 02:00:00 - 02:59:59	105126	4.4%	29.20	39.4 GB	4.7%	11481.00
Aug 20 03:00:00 - 03:59:59	102941	4.4%	28.59	36.4 GB	4.4%	10606.77
Aug 20 04:00:00 - 04:15:03	24731	1.0%	27.39	8.8 GB	1.0%	10175.06
TOTAL: 23:59:59	2364036	100.0%	27.36	834.8 GB	100.0%	10131.74

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	2573	218	1690	0	665
2	news.task.gda.pl	1201	970	17	0	214
3	news.nask.pl	589	22	512	1	54
4	news.icm.edu.pl	33	0	0	0	33
TOTAL: 4		4396	1210	2219	1	966

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	102
mailing.freebsd.svn-ports-head	98
uk.railway	94
mailing.freebsd.ports-bugs	59
mailing.freebsd.svn-src-all	57
fa.freebsd.cvs-all	54
gmane.linux.alsa.devel	53
mailing.freebsd.svn-src-head	51
mailing.postfix.users	42
mailing.gcc.gcc-cvs	33
alt.windows7.general	29
comp.sys.raspberry-pi	29
alt.politics.scorched-earth	28
fr.usenet.abus.nocem	28
mozilla.dev.identity	25
mailing.database.pgsql-general	21
mailing.openbsd.source-changes	21
it.tlc.cellulari.android	21
mailing.unix.dovecot	20
hr.alt.cellular.gsm.android	19
TOTAL: 84	1210

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	522
EMP (fsl)	192
EMP (md5)	50
Attached HTML file	21
HTML post	2
TOTAL: 5	787

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	52358	3567	29362	1821	2	0	6%	24:07:05
2	panorama	39101	3013	34330	872	0	0	7%	23:59:46
3	poznan	14870	44	12694	39	0	0	0%	23:59:22
4	task	2183	8	1181	7	0	0	0%	23:03:57
5	nask	16393	3	12083	0	0	0	0%	23:56:12
6	okapi	0	0	0	0	0	2635	0%	24:00:01
TOTAL: 6		124905	6635	89650	2739	2	2635	5%	143:06:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	45.9 MB	83.0 MB	128.9 MB	1.5 KB/s	24.5 KB	24:07:05
2	panorama	8.5 MB	3.1 MB	11.6 MB	0.1 KB/s	3.1 KB	23:59:46
3	poznan	145.5 KB	73.2 KB	218.7 KB	0.0 KB/s	2.6 KB	23:59:22
4	task	21.4 KB	10.0 KB	31.4 KB	0.0 KB/s	2.1 KB	23:03:57
5	nask	6.9 KB	0.0 KB	6.9 KB	0.0 KB/s	2.3 KB	23:56:12
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		54.5 MB	86.2 MB	140.8 MB	0.3 KB/s	15.4 KB	143:06:23

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	348	826.9 KB	288	0	00:00:10
2	web.man.lodz.pl	288	126	120.3 KB	57	0	00:00:18
3	212.191.196.1	3	44	93.3 KB	2	0	01:03:04
4	lze200.lze.lodz.pl	10	32	58.7 KB	8	0	01:28:11
5	router2.cbmm.lodz.pl	10	15	202.1 KB	1	0	01:07:41
6	ip-176.105.135.22.tvsat364.lodz.pl	7	10	20.8 KB	5	0	02:01:49
7	kujawiak.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 7		611	575	1.3 MB	361	5	20:45:11

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	348	826.9 KB	288	0	00:00:10
2	*.man.lodz.pl	677	126	120.3 KB	57	5	00:00:33
3	unresolved	4	44	93.3 KB	2	0	01:19:56
4	*.lze.lodz.pl	10	32	58.7 KB	8	0	01:28:11
5	*.cbmm.lodz.pl	10	15	202.1 KB	1	0	01:07:41
6	*.105.135.22.tvsat364.lodz.pl	7	10	20.8 KB	5	0	02:01:49
7	*.207.15.62.static.jazztel.es	6	0	0.0 KB	0	0	14:05:20
8	*.neoplus.adsl.tpnet.pl	4	0	0.0 KB	0	0	00:41:01
9	*.internetdsl.tpnet.pl	5	0	0.0 KB	0	0	00:00:01
10	*.toya.net.pl	68	0	0.0 KB	0	0	00:00:24
TOTAL: 10		1079	575	1.3 MB	361	5	20:45:11

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
190.207.15.62.static.jazztel.es	12.629	20.641	11:37:56
200.207.15.62.static.jazztel.es	3.288	5.196	02:27:23
ip-176.105.135.22.tvsat364.lodz.pl	0.016	0.132	02:01:49
lze200.lze.lodz.pl	0.028	0.216	01:28:11
router2.cbmm.lodz.pl	0.028	0.200	01:07:41
212.191.196.1	0.012	0.064	01:03:04
ama30.neoplus.adsl.tpnet.pl	0.024	0.032	00:30:00
212.51.192.24	0.000	0.008	00:16:52
aoi104.neoplus.adsl.tpnet.pl	0.012	0.032	00:10:15
ebw60.neoplus.adsl.tpnet.pl	0.000	0.020	00:00:44
staticline41435.toya.net.pl	0.076	0.468	00:00:21
web.man.lodz.pl	0.748	5.056	00:00:18
f-oper-v100.man.lodz.pl	0.620	5.552	00:00:12
vegas.cm.p.lodz.pl	0.716	5.124	00:00:10
david.man.lodz.pl	0.216	1.752	00:00:02
staticline-31-183-3-34.toya.net.pl	0.060	0.508	00:00:02
staticline-31-183-13-132.toya.net.pl	0.044	0.240	00:00:01
gcq193.internetdsl.tpnet.pl	0.008	0.100	00:00:01
kujawiak.man.lodz.pl	0.028	0.108	00:00:00
staticline-31-182-95-195.toya.net.pl	0.004	0.016	00:00:00
TOTAL: 20	18.557	45.465	20:45:11

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline-31-183-3-34.toya.net.pl	28
staticline41435.toya.net.pl	25
staticline-31-183-13-132.toya.net.pl	14
gcq193.internetdsl.tpnet.pl	5
190.207.15.62.static.jazztel.es	3
200.207.15.62.static.jazztel.es	3
aoi104.neoplus.adsl.tpnet.pl	2
212.51.192.24	1
ama30.neoplus.adsl.tpnet.pl	1
staticline-31-182-95-195.toya.net.pl	1
ebw60.neoplus.adsl.tpnet.pl	1
TOTAL: 13	468

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	4	0
ip-176.105.135.22.tvsat364.lodz.pl	3	0
ama30.neoplus.adsl.tpnet.pl	1	0
190.207.15.62.static.jazztel.es	1	0
router2.cbmm.lodz.pl	1	1
212.191.196.1	1	0
TOTAL: 6	11	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	348	60.5%
2	pl	227	39.5%
TOTAL: 2		575	100.0%

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

Newsgroup		Count
1	lodman.info	348
2	pl.comp.pecet	110
3	pl.comp.os.ms-windows.winnt	40
4	pl.misc.paranauki	38
5	pl.regionalne.lodz	26
6	pl.soc.dzieci	5
7	pl.misc.dieta	3
8	pl.rec.kuchnia	3
9	pl.internet.polip	2
TOTAL: 9		575

Daily Usenet report

Aug 19 04:15:04 -- Aug 20 04:15:03