Daily Usenet report: May 29 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

May 29 11:00:09 newsfeed nnrpd[19675]: SERVER perl filter not defined
May 29 11:41:37 newsfeed nnrpd[21007]: SERVER perl filter not defined
May 29 11:43:54 newsfeed nnrpd[21047]: SERVER perl filter not defined
May 29 12:50:53 newsfeed nnrpd[23361]: SERVER perl filter not defined
May 29 14:16:51 newsfeed nnrpd[26313]: SERVER perl filter not defined
May 29 15:40:29 newsfeed nnrpd[29097]: SERVER perl filter not defined
May 29 17:38:56 newsfeed nnrpd[628]: SERVER perl filter not defined
May 29 19:11:01 newsfeed nnrpd[3802]: SERVER perl filter not defined
May 29 19:11:53 newsfeed nnrpd[3803]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2089478	98.4%	236.4 MB	97.9%
innd	18880	0.9%	3.1 MB	1.3%
nnrpd	8292	0.4%	908.2 KB	0.4%
innfeed	6884	0.3%	1.0 MB	0.4%
TOTAL: 4	2123534	100.0%	241.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2084057	93.7%
Negative hits	98357	4.4%
Positive hits	40616	1.8%
Cache misses	223	0.0%
TOTAL: 4	2223253	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:14.026	0.0%	315	0.000	44.527	742.000
article cleanup	00:00:33.005	0.0%	2089882	0.006	0.016	0.029
article logging	00:01:02.740	0.1%	2090009	0.015	0.030	0.365
article parse	01:23:52.265	5.8%	169936453	0.007	0.030	0.084
article write	00:21:36.654	1.5%	2084152	0.347	0.622	1.596
artlog/artcncl	00:00:00.009	0.0%	312	0.000	0.029	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:06:42.223	0.5%	183602333	0.000	0.002	0.062
hisgrep/artcncl	00:00:01.713	0.0%	281	0.000	6.096	144.500
hishave/artcncl	00:00:01.730	0.0%	315	0.000	5.492	177.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:33:07.592	14.8%	2225118	0.011	5.747	14.550
history sync	00:00:00.010	0.0%	290	0.000	0.034	1.000
history write	05:24:10.708	22.5%	2085893	0.051	9.325	25.565
hiswrite/artcncl	00:00:00.352	0.0%	34	0.000	10.353	152.000
idle	06:08:08.550	25.6%	97559302	0.152	0.226	0.616
nntp read	01:06:10.333	4.6%	168311362	0.008	0.024	0.042
overview write	00:00:13.351	0.0%	2084152	0.000	0.006	0.019
perl filter	05:09:29.366	21.5%	2085890	5.610	8.902	12.144
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:02.038	0.3%	4221203	0.014	0.057	0.196
TOTAL: 24:00:43.873	23:19:26.665	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.178	0.0%	52886	0.000	0.003	0.091
article prepare	00:00:00.041	0.0%	103958	0.000	0.000	0.050
article read	00:00:06.138	0.0%	25519	0.000	0.241	21.455
backlog stats	00:00:00.348	0.0%	413466	0.000	0.001	0.029
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.632	0.0%	223119	0.000	0.012	0.069
data write	00:00:18.271	0.0%	226035	0.000	0.081	2.000
idle	23:59:34.545	100.0%	413466	0.000	208.904	15104.000
status file	00:00:01.100	0.0%	208	0.000	5.288	260.000
TOTAL: 24:00:15.639	24:00:03.253	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	19:44:18.624	99.9%	5260	0.000	13509.244	600146.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.337	0.0%	12655	0.000	0.027	1.632
readart	00:00:01.366	0.0%	540	0.000	2.530	34.067
TOTAL: 19:45:08.589	19:44:20.327	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1163	2076388	2069652	2994	3742	99%	432:00:37
2	news.task.gda.pl	10	32067	6980	23867	1220	21%	47:10:03
3	news.nask.pl	2	17778	6673	10304	801	37%	23:44:11
4	localhost	10	9	9	0	0	100%	00:00:01
5	news.icm.edu.pl	49	182	2	174	6	1%	15:42:24
TOTAL: 5		1234	2126424	2083316	37339	5769	97%	518:37:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	734.4 GB	15.2 MB	44.2 MB	734.5 GB	99%	371.5 KB
2	news.nask.pl	27.1 MB	2.4 MB	2.7 MB	32.2 MB	84%	4.4 KB
3	news.task.gda.pl	15.0 MB	38.7 KB	4.0 MB	19.1 MB	78%	2.4 KB
4	news.icm.edu.pl	62.5 KB	0.0 KB	239.0 KB	301.5 KB	20%	37.7 KB
5	localhost	26.7 KB	0.0 KB	0.0 KB	26.7 KB	100%	3.0 KB
TOTAL: 5		734.5 GB	17.6 MB	51.1 MB	734.5 GB	99%	368.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 29 04:15:02 - 04:59:59	52526	2.5%	19.47	21.6 GB	2.9%	8400.19
May 29 05:00:00 - 05:59:59	74886	3.6%	20.80	29.1 GB	4.0%	8466.53
May 29 06:00:00 - 06:59:59	75012	3.6%	20.84	29.5 GB	4.0%	8585.83
May 29 07:00:00 - 07:59:59	70895	3.4%	19.69	29.1 GB	4.0%	8478.26
May 29 08:00:00 - 08:59:59	68966	3.3%	19.16	28.8 GB	3.9%	8389.65
May 29 09:00:00 - 09:59:59	80015	3.8%	22.23	29.6 GB	4.0%	8627.09
May 29 10:00:00 - 10:59:59	86345	4.1%	23.98	31.5 GB	4.3%	9188.98
May 29 11:00:00 - 11:59:59	84850	4.1%	23.57	31.6 GB	4.3%	9192.42
May 29 12:00:00 - 12:59:59	84966	4.1%	23.60	29.9 GB	4.1%	8698.55
May 29 13:00:00 - 13:59:59	97391	4.7%	27.05	32.4 GB	4.4%	9425.86
May 29 14:00:00 - 14:59:59	94375	4.5%	26.22	32.5 GB	4.4%	9461.75
May 29 15:00:00 - 15:59:59	95496	4.6%	26.53	33.0 GB	4.5%	9617.06
May 29 16:00:00 - 16:59:59	94757	4.5%	26.32	32.7 GB	4.5%	9535.94
May 29 17:00:00 - 17:59:59	97042	4.7%	26.96	31.8 GB	4.3%	9266.41
May 29 18:00:00 - 18:59:59	92013	4.4%	25.56	30.0 GB	4.1%	8738.63
May 29 19:00:00 - 19:59:59	99749	4.8%	27.71	32.9 GB	4.5%	9584.84
May 29 20:00:00 - 20:59:59	107944	5.2%	29.98	31.7 GB	4.3%	9224.72
May 29 21:00:00 - 21:59:59	102099	4.9%	28.36	30.6 GB	4.2%	8905.38
May 29 22:00:00 - 22:59:59	88393	4.2%	24.55	29.4 GB	4.0%	8570.81
May 29 23:00:00 - 23:59:59	93287	4.5%	25.91	31.5 GB	4.3%	9178.02
May 30 00:00:00 - 00:59:59	72684	3.5%	20.19	26.6 GB	3.6%	7740.10
May 30 01:00:00 - 01:59:59	84284	4.0%	23.41	30.3 GB	4.1%	8819.12
May 30 02:00:00 - 02:59:59	86335	4.1%	23.98	31.1 GB	4.2%	9072.16
May 30 03:00:00 - 03:59:59	79260	3.8%	22.02	29.4 GB	4.0%	8560.49
May 30 04:00:00 - 04:15:04	19734	0.9%	21.83	7.3 GB	1.0%	8520.61
TOTAL: 24:00:02	2083304	100.0%	24.11	733.9 GB	100.0%	8906.61

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3851	203	3192	2	454
2	news.task.gda.pl	1185	1019	19	3	144
3	news.nask.pl	820	11	781	6	22
4	news.icm.edu.pl	6	0	0	0	6
TOTAL: 4		5862	1233	3992	11	626

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	104
mailing.freebsd.svn-ports-all	103
mailing.freebsd.svn-ports-head	100
mailing.freebsd.ports-bugs	71
mozilla.dev.tree-management	66
mailing.freebsd.current	49
alt.windows7.general	47
mailing.freebsd.svn-src-all	47
mailing.openbsd.source-changes	46
fa.freebsd.cvs-all	45
mailing.freebsd.svn-src-head	44
mailing.freebsd.ports	31
mailing.gcc.gcc-cvs	29
mailing.database.pgsql-general	29
fr.usenet.abus.nocem	28
alt.politics.scorched-earth	22
gnu.hurd.bug	19
mailing.openbsd.misc	19
mailing.unix.samba	18
it.tlc.cellulari.android	17
TOTAL: 76	1233

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

Groups	Count
pl.sci.matematyka	5
fr.comp.lang.php	3
pl.sci.historia	3
TOTAL: 3	11

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	340
EMP (fsl)	134
HTML post	8
EMP (md5)	5
Attached HTML file	4
Scoring filter (10)	2
Scoring filter (8)	2
Excessively crossposted test article	1
TOTAL: 8	496

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	63457	5979	29421	17113	12	7	9%	23:59:49
2	panorama	55221	2232	50265	403	1	0	4%	24:03:52
3	task	2111	102	1068	6	0	0	4%	21:54:23
4	poznan	16074	74	13335	28	0	0	0%	23:56:09
5	nask	19163	13	13680	0	0	0	0%	24:08:58
6	okapi	0	0	0	0	0	3162	0%	24:00:00
TOTAL: 6		156026	8400	107769	17550	13	3169	5%	142:03:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	193.7 MB	4.5 GB	4.7 GB	57.0 KB/s	213.2 KB	23:59:49
2	panorama	9.7 MB	2.9 MB	12.6 MB	0.1 KB/s	4.9 KB	24:03:52
3	task	229.1 KB	10.5 KB	239.6 KB	0.0 KB/s	2.2 KB	21:54:23
4	poznan	216.5 KB	49.0 KB	265.4 KB	0.0 KB/s	2.6 KB	23:56:09
5	nask	22.6 KB	0.0 KB	22.6 KB	0.0 KB/s	1.7 KB	24:08:58
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		203.8 MB	4.5 GB	4.7 GB	9.7 KB/s	190.3 KB	142:03:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	386	1.1 MB	288	0	00:00:13
2	web.man.lodz.pl	288	154	133.6 KB	57	0	00:00:30
3	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:02
TOTAL: 3		585	540	1.2 MB	345	9	19:45:06

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	386	1.1 MB	288	0	00:00:13
2	*.man.lodz.pl	681	154	133.6 KB	57	9	00:00:40
3	*.pa.nsw.optusnet.com.au	121	0	0.0 KB	0	0	00:19:43
4	*.cbmm.lodz.pl	2	0	0.0 KB	0	0	00:00:06
5	*.wroclaw.mm.pl	114	0	0.0 KB	0	0	18:51:40
6	*.105.135.22.tvsat364.lodz.pl	2	0	0.0 KB	0	0	00:30:10
7	*.broadband.greenlan.pl	1	0	0.0 KB	0	0	00:02:19
8	*.toya.net.pl	75	0	0.0 KB	0	0	00:00:12
TOTAL: 8		1284	540	1.2 MB	345	9	19:45:06

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
host-89-231-50-15.wroclaw.mm.pl	0.252	1.632	18:51:40
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.028	00:30:10
pa49-181-246-211.pa.nsw.optusnet.com.au	0.344	1.572	00:19:43
static-nat-9.broadband.greenlan.pl	0.036	0.012	00:02:19
web.man.lodz.pl	0.640	4.108	00:00:30
pc-skrzep2.cm.p.lodz.pl	0.764	3.816	00:00:13
staticline41435.toya.net.pl	0.216	0.984	00:00:12
mon.man.lodz.pl	0.632	3.696	00:00:06
router2.cbmm.lodz.pl	0.004	0.016	00:00:06
isvcs01.man.lodz.pl	0.064	0.160	00:00:02
david.man.lodz.pl	0.176	1.136	00:00:01
TOTAL: 11	3.128	17.160	19:45:06

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
pa49-181-246-211.pa.nsw.optusnet.com.au	121
host-89-231-50-15.wroclaw.mm.pl	114
david.man.lodz.pl	96
staticline41435.toya.net.pl	75
ip-176.105.135.22.tvsat364.lodz.pl	2
router2.cbmm.lodz.pl	2
static-nat-9.broadband.greenlan.pl	1
TOTAL: 8	699

NNRP client timeouts [Top 20]:

System	Conn	Peer
pa49-181-246-211.pa.nsw.optusnet.com.au	2	0
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 2	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	386	71.5%
2	pl	154	28.5%
TOTAL: 2		540	100.0%

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

Newsgroup		Count
1	lodman.info	386
2	pl.comp.pecet	144
3	pl.comp.os.ms-windows.winnt	10
TOTAL: 3		540

Daily Usenet report

May 29 04:15:02 -- May 30 04:15:04