Daily Usenet report: May 24 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

May 24 11:29:33 newsfeed nnrpd[10815]: SERVER perl filter not defined
May 24 11:30:42 newsfeed nnrpd[10870]: SERVER perl filter not defined
May 24 11:33:44 newsfeed nnrpd[11051]: SERVER perl filter not defined
May 24 16:44:32 newsfeed nnrpd[21507]: SERVER perl filter not defined
May 24 16:45:04 newsfeed nnrpd[21556]: SERVER perl filter not defined
May 24 19:08:20 newsfeed nnrpd[26304]: SERVER perl filter not defined
May 24 20:04:33 newsfeed nnrpd[28221]: SERVER perl filter not defined
May 25 00:14:52 newsfeed nnrpd[4259]: SERVER perl filter not defined
May 25 01:07:27 newsfeed nnrpd[6024]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2134624	98.6%	249.3 MB	98.2%
innd	15340	0.7%	2.7 MB	1.0%
nnrpd	7418	0.3%	798.2 KB	0.3%
innfeed	6837	0.3%	1.0 MB	0.4%
TOTAL: 4	2164219	100.0%	253.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2128478	96.1%
Negative hits	53037	2.4%
Positive hits	33024	1.5%
Cache misses	203	0.0%
TOTAL: 4	2214742	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.229	0.0%	265	0.000	34.826	802.000
article cleanup	00:00:32.107	0.0%	2133278	0.003	0.015	0.029
article logging	00:01:02.888	0.1%	2133355	0.013	0.029	0.709
article parse	01:13:41.753	5.1%	186917149	0.006	0.024	0.075
article write	00:18:57.645	1.3%	2127984	0.279	0.535	1.410
artlog/artcncl	00:00:00.008	0.0%	252	0.000	0.032	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:46.974	0.2%	200348508	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.005	0.0%	200	0.000	5.025	201.000
hishave/artcncl	00:00:01.220	0.0%	265	0.000	4.604	311.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:41:12.519	15.4%	2215455	0.323	5.991	11.559
history sync	00:00:00.008	0.0%	293	0.000	0.027	1.000
history write	04:49:46.893	20.1%	2129312	3.273	8.165	22.496
hiswrite/artcncl	00:00:00.499	0.0%	65	0.000	7.677	104.000
idle	07:28:51.929	31.2%	118906591	0.119	0.226	0.880
nntp read	01:00:41.779	4.2%	185216606	0.009	0.020	0.046
overview write	00:00:12.709	0.0%	2127984	0.000	0.006	0.015
perl filter	04:35:56.210	19.2%	2129311	4.899	7.775	10.870
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:09.917	0.3%	4297821	0.015	0.058	0.092
TOTAL: 23:59:42.203	23:18:05.292	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.171	0.0%	41878	0.000	0.004	1.000
article prepare	00:00:00.019	0.0%	62562	0.000	0.000	0.036
article read	00:00:10.646	0.0%	15538	0.000	0.685	81.167
backlog stats	00:00:00.277	0.0%	322752	0.000	0.001	0.021
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.965	0.0%	178302	0.000	0.011	1.000
data write	00:00:12.360	0.0%	167727	0.000	0.074	16.000
idle	23:59:24.275	100.0%	322752	0.000	267.587	16878.000
status file	00:00:01.023	0.0%	223	0.000	4.587	112.667
TOTAL: 23:59:58.068	23:59:50.736	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	48:51:04.288	99.9%	1369953	0.000	128.372	28176.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:35.559	0.0%	2741727	0.000	0.013	0.500
readart	00:00:00.680	0.0%	410	0.000	1.659	16.769
TOTAL: 48:53:19.799	48:51:40.527	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	203	2123349	2118476	2016	2857	99%	258:58:19
2	news.task.gda.pl	19	24171	5279	17883	1009	21%	44:19:08
3	news.nask.pl	2	15168	5093	8644	1431	33%	24:13:55
4	localhost	10	9	9	0	0	100%	00:00:00
5	news.icm.edu.pl	48	218	3	202	13	1%	17:49:10
TOTAL: 5		282	2162915	2128860	28745	5310	98%	345:20:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	670.0 GB	22.8 MB	29.7 MB	670.0 GB	99%	331.2 KB
2	news.nask.pl	20.6 MB	4.1 MB	4.4 MB	29.1 MB	70%	4.6 KB
3	news.task.gda.pl	9.6 MB	44.2 KB	3.1 MB	12.8 MB	75%	2.1 KB
4	news.icm.edu.pl	75.0 KB	20.2 KB	549.4 KB	644.6 KB	11%	40.3 KB
5	localhost	13.2 KB	0.0 KB	0.0 KB	13.2 KB	100%	1.5 KB
TOTAL: 5		670.0 GB	27.0 MB	37.7 MB	670.0 GB	99%	329.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 24 04:15:02 - 04:59:59	58288	2.7%	21.60	21.9 GB	3.3%	8516.26
May 24 05:00:00 - 05:59:59	77900	3.7%	21.64	31.0 GB	4.6%	9019.89
May 24 06:00:00 - 06:59:59	79804	3.7%	22.17	29.6 GB	4.4%	8634.37
May 24 07:00:00 - 07:59:59	86793	4.1%	24.11	32.7 GB	4.9%	9518.35
May 24 08:00:00 - 08:59:59	83700	3.9%	23.25	32.0 GB	4.8%	9332.04
May 24 09:00:00 - 09:59:59	95219	4.5%	26.45	34.1 GB	5.1%	9923.80
May 24 10:00:00 - 10:59:59	81865	3.8%	22.74	28.5 GB	4.3%	8309.32
May 24 11:00:00 - 11:59:59	70016	3.3%	19.45	20.7 GB	3.1%	6038.12
May 24 12:00:00 - 12:59:59	60426	2.8%	16.79	17.8 GB	2.7%	5188.45
May 24 13:00:00 - 13:59:59	59147	2.8%	16.43	20.8 GB	3.1%	6067.09
May 24 14:00:00 - 14:59:59	74760	3.5%	20.77	25.8 GB	3.8%	7507.33
May 24 15:00:00 - 15:59:59	86633	4.1%	24.06	28.9 GB	4.3%	8413.38
May 24 16:00:00 - 16:59:59	88002	4.1%	24.45	29.1 GB	4.3%	8488.84
May 24 17:00:00 - 17:59:59	122666	5.8%	34.07	32.3 GB	4.8%	9398.18
May 24 18:00:00 - 18:59:59	107025	5.0%	29.73	27.5 GB	4.1%	7995.94
May 24 19:00:00 - 19:59:59	105910	5.0%	29.42	27.5 GB	4.1%	7997.11
May 24 20:00:00 - 20:59:59	103321	4.9%	28.70	26.6 GB	4.0%	7753.80
May 24 21:00:00 - 21:59:59	97639	4.6%	27.12	29.5 GB	4.4%	8586.69
May 24 22:00:00 - 22:59:59	98125	4.6%	27.26	27.9 GB	4.2%	8113.49
May 24 23:00:00 - 23:59:59	86482	4.1%	24.02	28.1 GB	4.2%	8187.59
May 25 00:00:00 - 00:59:59	69089	3.2%	19.19	24.4 GB	3.6%	7095.87
May 25 01:00:00 - 01:59:59	85442	4.0%	23.73	27.8 GB	4.1%	8095.52
May 25 02:00:00 - 02:59:59	112817	5.3%	31.34	29.5 GB	4.4%	8592.41
May 25 03:00:00 - 03:59:59	107927	5.1%	29.98	28.2 GB	4.2%	8216.60
May 25 04:00:00 - 04:15:04	30005	1.4%	33.19	7.9 GB	1.2%	9215.65
TOTAL: 24:00:02	2129001	100.0%	24.64	670.1 GB	100.0%	8131.93

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	2931	152	2570	0	209
2	news.nask.pl	1409	7	1378	3	21
3	news.task.gda.pl	1018	835	17	0	166
4	news.icm.edu.pl	13	0	1	0	12
TOTAL: 4		5371	994	3966	3	408

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	128
mailing.freebsd.svn-ports-head	124
uk.railway	100
mailing.freebsd.ports-bugs	75
alt.windows7.general	52
mailing.openbsd.source-changes	49
mailing.freebsd.svn-src-all	39
fa.freebsd.cvs-all	37
mailing.freebsd.svn-src-head	30
mailing.openbsd.misc	29
alt.politics.scorched-earth	29
fr.usenet.abus.nocem	27
mailing.freebsd.ports	26
mailing.gcc.gcc-cvs	20
it.tlc.cellulari.android	16
mozilla.dev.tree-management	14
24hoursupport.helpdesk	12
mailing.unix.proftpd-committers	12
de.rec.sf.startrek	10
mailing.unix.samba	10
TOTAL: 63	994

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

Groups	Count
pl.sci.historia	3
TOTAL: 1	3

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	147
Binary in non-binary group	141
EMP (md5)	19
Attached HTML file	19
HTML post	4
TOTAL: 5	330

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	55082	3423	26708	9867	5	16	6%	23:59:59
2	panorama	44432	1653	39788	311	0	0	3%	23:59:35
3	nask	17007	39	11420	25	0	0	0%	24:05:26
4	poznan	11948	30	10178	18	0	0	0%	23:54:06
5	task	1292	28	685	2	0	0	2%	20:49:52
6	okapi	0	0	0	0	0	1620	0%	24:00:00
TOTAL: 6		129761	5173	88779	10223	5	1636	3%	140:48:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	406.8 MB	2.2 GB	2.6 GB	31.9 KB/s	207.3 KB	23:59:59
2	panorama	7.6 MB	1.2 MB	8.8 MB	0.1 KB/s	4.6 KB	23:59:35
3	poznan	84.9 KB	61.1 KB	145.9 KB	0.0 KB/s	3.0 KB	23:54:06
4	nask	80.2 KB	7.4 MB	7.4 MB	0.1 KB/s	119.2 KB	24:05:26
5	task	70.8 KB	2.6 KB	73.4 KB	0.0 KB/s	2.4 KB	20:49:52
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		414.7 MB	2.2 GB	2.6 GB	5.5 KB/s	180.0 KB	140:48:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	376	518.8 KB	288	0	00:00:13
2	web.man.lodz.pl	288	34	35.0 KB	16	0	00:00:36
3	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:01
TOTAL: 3		585	410	553.8 KB	304	9	48:53:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	376	518.8 KB	288	0	00:00:13
2	*.man.lodz.pl	681	34	35.0 KB	16	9	00:00:45
3	*.pa.nsw.optusnet.com.au	20	0	0.0 KB	0	0	00:00:36
4	unresolved	1	0	0.0 KB	0	0	00:00:42
5	*.191.14.62.static.jazztel.es	1	0	0.0 KB	0	0	48:49:11
6	*.neoplus.adsl.tpnet.pl	84	0	0.0 KB	0	0	00:00:11
7	*.105.135.22.tvsat364.lodz.pl	2	0	0.0 KB	0	0	00:01:34
8	*.toya.net.pl	38	0	0.0 KB	0	0	00:00:04
TOTAL: 8		1115	410	553.8 KB	304	9	48:53:19

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
242.191.14.62.static.jazztel.es	64.708	74.912	48:49:11
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.032	00:01:34
194.33.77.50	0.000	0.020	00:00:42
web.man.lodz.pl	0.648	5.428	00:00:36
pa49-181-246-239.pa.nsw.optusnet.com.au	0.068	0.332	00:00:36
pc-skrzep2.cm.p.lodz.pl	0.664	4.560	00:00:13
abss56.neoplus.adsl.tpnet.pl	0.156	1.192	00:00:11
mon.man.lodz.pl	0.540	4.416	00:00:06
staticline41435.toya.net.pl	0.084	0.568	00:00:04
isvcs01.man.lodz.pl	0.024	0.180	00:00:01
david.man.lodz.pl	0.192	1.424	00:00:01
TOTAL: 11	67.092	93.064	48:53:19

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
abss56.neoplus.adsl.tpnet.pl	84
staticline41435.toya.net.pl	38
pa49-181-246-239.pa.nsw.optusnet.com.au	20
ip-176.105.135.22.tvsat364.lodz.pl	2
242.191.14.62.static.jazztel.es	1
194.33.77.50	1
TOTAL: 8	530

NNRP client timeouts [Top 20]:

System	Conn	Peer
242.191.14.62.static.jazztel.es	2	2
TOTAL: 1	2	2

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	376	91.7%
2	pl	34	8.3%
TOTAL: 2		410	100.0%

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

Newsgroup		Count
1	lodman.info	376
2	pl.comp.pecet	20
3	pl.comp.os.ms-windows.winnt	14
TOTAL: 3		410

Daily Usenet report

May 24 04:15:02 -- May 25 04:15:04