Daily Usenet report: Jun 25 04:15:07

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jun 25 11:38:29 newsfeed nnrpd[6175]: SERVER perl filter not defined
Jun 26 00:14:44 newsfeed pgpverify[30048]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1432199	97.1%	159.1 MB	96.5%
innd	25005	1.7%	3.6 MB	2.2%
nnrpd	10255	0.7%	1.0 MB	0.6%
innfeed	7048	0.5%	1.0 MB	0.6%
controlchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	1474509	100.0%	164.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1425218	90.2%
Negative hits	101049	6.4%
Positive hits	41502	2.6%
Cache misses	12911	0.8%
TOTAL: 4	1580680	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:37.264	0.0%	1849	0.000	20.154	594.000
article cleanup	00:00:21.312	0.0%	1429777	0.002	0.015	0.273
article logging	00:00:50.773	0.1%	1429982	0.014	0.036	1.018
article parse	00:47:10.258	3.3%	62195064	0.022	0.046	0.152
article write	00:17:28.515	1.2%	1411981	0.343	0.743	2.097
artlog/artcncl	00:00:00.051	0.0%	1849	0.000	0.028	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:04.666	0.1%	70603072	0.001	0.002	0.012
hisgrep/artcncl	00:00:02.008	0.0%	243	0.000	8.263	158.667
hishave/artcncl	00:00:11.855	0.0%	1849	0.000	6.412	267.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:00:04.361	12.5%	1580855	0.008	6.835	20.042
history sync	00:00:00.015	0.0%	291	0.000	0.052	1.000
history write	03:53:02.809	16.2%	1425420	0.245	9.810	41.980
hiswrite/artcncl	00:00:17.670	0.0%	1606	0.000	11.002	266.000
idle	11:11:35.643	46.6%	46625799	0.215	0.864	6.171
nntp read	00:37:17.578	2.6%	61249810	0.020	0.037	0.076
overview write	00:00:09.303	0.0%	1411981	0.000	0.007	0.026
perl filter	03:39:02.947	15.2%	1425414	6.064	9.220	18.942
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:36.044	0.3%	2867452	0.006	0.096	0.561
TOTAL: 23:59:39.203	23:34:53.072	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.174	0.0%	43481	0.000	0.004	0.100
article prepare	00:00:00.008	0.0%	45622	0.000	0.000	0.083
article read	00:00:21.697	0.0%	11043	0.000	1.965	170.556
backlog stats	00:00:00.384	0.0%	327169	0.000	0.001	0.396
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:02.241	0.0%	190785	0.000	0.012	1.154
data write	00:00:07.266	0.0%	164542	0.000	0.044	1.659
idle	23:59:22.314	100.0%	327169	0.000	263.969	11113.000
status file	00:00:03.641	0.0%	234	1.000	15.560	270.000
TOTAL: 24:00:05.230	23:59:57.725	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	49:50:58.016	100.0%	9781	0.000	18347.614	301407.833
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.423	0.0%	21939	0.000	0.019	4.000
readart	00:00:03.602	0.0%	552	0.000	6.525	35.808
TOTAL: 49:51:32.046	49:51:02.041	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
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	560	1431489	1401500	14953	15036	97%	243:54:10
2	news.nask.pl	3	18921	5769	11635	1517	30%	24:00:28
3	news.task.gda.pl	19	28808	5047	22532	1229	17%	43:50:03
4	news.icm.edu.pl	44	258	3	254	1	1%	17:53:27
5	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 5		628	1479477	1412320	49374	17783	95%	329:38:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	473.1 GB	131.5 MB	1.9 GB	475.1 GB	99%	351.7 KB
2	news.nask.pl	150.9 MB	17.7 MB	24.6 MB	193.2 MB	78%	27.2 KB
3	news.task.gda.pl	11.2 MB	93.9 KB	3.6 MB	14.9 MB	75%	2.4 KB
4	news.icm.edu.pl	133.5 KB	0.0 KB	17.0 KB	150.5 KB	88%	37.6 KB
5	localhost	1.4 KB	0.0 KB	0.0 KB	1.4 KB	100%	1.4 KB
TOTAL: 5		473.2 GB	149.3 MB	2.0 GB	475.3 GB	99%	348.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 25 04:15:07 - 04:59:59	58725	4.2%	21.81	19.3 GB	4.1%	7529.15
Jun 25 05:00:00 - 05:59:59	68204	4.8%	18.95	24.8 GB	5.2%	7219.91
Jun 25 06:00:00 - 06:59:59	50546	3.6%	14.04	19.9 GB	4.2%	5808.31
Jun 25 07:00:00 - 07:59:59	44955	3.2%	12.49	19.1 GB	4.0%	5554.99
Jun 25 08:00:00 - 08:59:59	48570	3.4%	13.49	18.2 GB	3.9%	5312.44
Jun 25 09:00:00 - 09:59:59	64008	4.5%	17.78	19.5 GB	4.1%	5667.53
Jun 25 10:00:00 - 10:59:59	58302	4.1%	16.20	15.8 GB	3.3%	4606.43
Jun 25 11:00:00 - 11:59:59	49972	3.5%	13.88	19.0 GB	4.0%	5537.32
Jun 25 12:00:00 - 12:59:59	66628	4.7%	18.51	19.5 GB	4.1%	5692.09
Jun 25 13:00:00 - 13:59:59	71031	5.0%	19.73	18.6 GB	3.9%	5403.70
Jun 25 14:00:00 - 14:59:59	69965	5.0%	19.43	18.8 GB	4.0%	5479.78
Jun 25 15:00:00 - 15:59:59	59467	4.2%	16.52	18.4 GB	3.9%	5370.86
Jun 25 16:00:00 - 16:59:59	69893	4.9%	19.41	18.5 GB	3.9%	5398.57
Jun 25 17:00:00 - 17:59:59	56097	4.0%	15.58	17.2 GB	3.6%	4998.36
Jun 25 18:00:00 - 18:59:59	51966	3.7%	14.44	18.1 GB	3.8%	5285.53
Jun 25 19:00:00 - 19:59:59	44034	3.1%	12.23	14.7 GB	3.1%	4276.51
Jun 25 20:00:00 - 20:59:59	34962	2.5%	9.71	10.4 GB	2.2%	3018.80
Jun 25 21:00:00 - 21:59:59	32594	2.3%	9.05	10.1 GB	2.1%	2939.67
Jun 25 22:00:00 - 22:59:59	28567	2.0%	7.94	9.6 GB	2.0%	2803.57
Jun 25 23:00:00 - 23:59:59	40649	2.9%	11.29	16.2 GB	3.4%	4730.89
Jun 26 00:00:00 - 00:59:59	76468	5.4%	21.24	28.8 GB	6.1%	8398.97
Jun 26 01:00:00 - 01:59:59	101620	7.2%	28.23	37.3 GB	7.9%	10863.20
Jun 26 02:00:00 - 02:59:59	81243	5.8%	22.57	30.8 GB	6.5%	8980.44
Jun 26 03:00:00 - 03:59:59	67518	4.8%	18.75	24.5 GB	5.2%	7132.69
Jun 26 04:00:00 - 04:15:03	16366	1.2%	18.12	5.8 GB	1.2%	6704.13
TOTAL: 23:59:56	1412350	100.0%	16.35	473.1 GB	100.0%	5741.70

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	15196	221	2894	6	12075
2	news.nask.pl	1560	10	1408	7	135
3	news.task.gda.pl	1242	946	53	1	242
4	news.icm.edu.pl	1	0	0	0	1
TOTAL: 4		17999	1177	4355	14	12453

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.ports-bugs	102
uk.railway	85
mailing.freebsd.svn-ports-all	52
mailing.freebsd.svn-ports-head	50
mailing.postfix.users	44
mailing.openbsd.misc	44
mailing.freebsd.svn-src-all	40
fa.freebsd.cvs-all	39
mozilla.dev.platform	35
mailing.freebsd.ports	35
mozilla.dev.identity	35
gmane.linux.alsa.devel	33
it.tlc.cellulari.android	33
mailing.gcc.gcc-cvs	26
alt.windows7.general	26
mailing.unix.dovecot	25
mailing.database.pgsql-general	25
mailing.openbsd.ports-changes	24
mozilla.dev.gaia	23
mozilla.dev.tree-management	22
TOTAL: 81	1177

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

Groups	Count
pl.sci.historia	12
christnet.bible.revelation	1
bit.listserv.catholic	1
TOTAL: 3	14

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	11968
EMP (fsl)	168
Attached HTML file	44
EMP (md5)	39
EMP (phl)	12
HTML post	8
Scoring filter (8)	3
TOTAL: 7	12242

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	63098	5235	33295	2701	4	1	8%	23:57:49
2	panorama	47566	2444	40865	829	1	0	5%	24:03:29
3	nask	22166	117	14693	0	0	0	0%	23:57:00
4	task	2051	23	1090	7	0	3	1%	20:57:59
5	poznan	11273	20	9759	27	0	0	0%	24:00:19
6	okapi	0	0	0	0	0	2959	0%	24:00:00
TOTAL: 6		146154	7839	99702	3564	5	2963	5%	140:56:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.2 GB	172.7 MB	1.4 GB	17.0 KB/s	185.0 KB	23:57:49
2	panorama	37.8 MB	15.7 MB	53.4 MB	0.6 KB/s	16.7 KB	24:03:29
3	nask	18.1 MB	0.0 KB	18.1 MB	0.2 KB/s	158.4 KB	23:57:00
4	poznan	88.1 KB	787.0 KB	875.1 KB	0.0 KB/s	18.6 KB	24:00:19
5	task	45.9 KB	16.5 KB	62.4 KB	0.0 KB/s	2.1 KB	20:57:59
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		1.3 GB	189.2 MB	1.5 GB	3.0 KB/s	135.3 KB	140:56:36

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	288	441.0 KB	288	0	00:00:09
2	212.191.78.190	300	149	278.5 KB	35	1	47:44:47
3	web.man.lodz.pl	288	108	115.8 KB	48	0	00:00:23
4	ip-176.105.135.22.tvsat364.lodz.pl	2	7	11.1 KB	1	0	01:01:10
TOTAL: 4		878	552	846.4 KB	372	1	49:51:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	288	441.0 KB	288	0	00:00:09
2	unresolved	300	149	278.5 KB	35	1	47:44:47
3	*.man.lodz.pl	672	108	115.8 KB	48	0	00:00:30
4	*.105.135.22.tvsat364.lodz.pl	2	7	11.1 KB	1	0	01:01:10
5	*.cbmm.lodz.pl	9	0	0.0 KB	0	0	00:19:30
6	*.neoplus.adsl.tpnet.pl	3	0	0.0 KB	0	0	00:10:02
7	*.181.14.62.static.jazztel.es	4	0	0.0 KB	0	0	00:11:59
8	*.internetdsl.tpnet.pl	7	0	0.0 KB	0	0	00:22:46
9	*.toya.net.pl	70	0	0.0 KB	0	0	00:00:33
TOTAL: 9		1355	552	846.4 KB	372	1	49:51:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	1.020	8.360	47:44:47
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.124	01:01:10
aqi146.internetdsl.tpnet.pl	0.056	0.100	00:22:46
router2.cbmm.lodz.pl	0.024	0.244	00:19:30
185.181.14.62.static.jazztel.es	0.196	0.388	00:11:59
ebq242.neoplus.adsl.tpnet.pl	0.008	0.080	00:10:02
staticline41435.toya.net.pl	0.416	2.000	00:00:33
web.man.lodz.pl	1.416	11.676	00:00:23
vegas.cm.p.lodz.pl	1.636	15.300	00:00:09
f-oper-v100.man.lodz.pl	1.176	10.952	00:00:06
david.man.lodz.pl	0.312	2.784	00:00:01
gcq193.internetdsl.tpnet.pl	0.004	0.100	00:00:00
TOTAL: 12	6.268	52.108	49:51:31

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	70
router2.cbmm.lodz.pl	9
aqi146.internetdsl.tpnet.pl	4
185.181.14.62.static.jazztel.es	4
ebq242.neoplus.adsl.tpnet.pl	3
gcq193.internetdsl.tpnet.pl	3
TOTAL: 8	477

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	2	0
185.181.14.62.static.jazztel.es	1	1
TOTAL: 2	3	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	288	52.2%
2	pl	264	47.8%
TOTAL: 2		552	100.0%

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

Newsgroup		Count
1	lodman.info	288
2	pl.comp.pecet	80
3	pl.misc.kolej	79
4	pl.rec.rowery	35
5	pl.comp.os.ms-windows.winnt	24
6	pl.regionalne.warszawa	17
7	pl.soc.prawo	13
8	pl.soc.dzieci	7
9	pl.comp.os.linux	4
10	pl.internet.polip	4
11	pl.rec.gory	1
TOTAL: 11		552

Daily Usenet report

Jun 25 04:15:07 -- Jun 26 04:15:03