Daily Usenet report: Jun 24 04:15:05

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jun 24 11:44:41 newsfeed nnrpd[23244]: SERVER perl filter not defined
Jun 24 13:09:41 newsfeed nnrpd[26019]: SERVER perl filter not defined
Jun 24 13:37:51 newsfeed nnrpd[26948]: SERVER perl filter not defined
Jun 24 22:18:49 newsfeed nnrpd[10847]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1466610	97.2%	165.6 MB	96.6%
innd	25742	1.7%	3.7 MB	2.2%
nnrpd	10165	0.7%	1.0 MB	0.6%
innfeed	6883	0.5%	1003.6 KB	0.6%
TOTAL: 4	1509400	100.0%	171.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1461141	88.8%
Negative hits	96442	5.9%
Positive hits	78192	4.8%
Cache misses	8811	0.5%
TOTAL: 4	1644586	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:13:37.563	0.9%	37032	0.000	22.077	619.000
article cleanup	00:00:20.997	0.0%	1429255	0.000	0.015	0.216
article logging	00:00:51.138	0.1%	1429402	0.012	0.036	0.717
article parse	00:45:44.475	3.2%	55473703	0.023	0.049	0.147
article write	00:21:50.325	1.5%	1411347	0.426	0.928	3.843
artlog/artcncl	00:00:01.036	0.0%	37022	0.000	0.028	1.128
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:56.340	0.2%	63830961	0.002	0.003	0.012
hisgrep/artcncl	00:00:02.191	0.0%	258	0.000	8.492	250.000
hishave/artcncl	00:06:05.876	0.4%	37032	0.000	9.880	134.109
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:20:50.694	13.9%	1608976	0.207	7.490	18.399
history sync	00:00:00.013	0.0%	291	0.000	0.045	1.000
history write	04:11:52.126	17.5%	1425503	0.051	10.601	38.994
hiswrite/artcncl	00:07:21.050	0.5%	36774	0.000	11.994	34.966
idle	10:12:24.035	42.5%	38434583	0.169	0.956	6.651
nntp read	00:39:25.423	2.7%	54463491	0.026	0.043	0.096
overview write	00:00:09.725	0.0%	1411347	0.000	0.007	0.036
perl filter	03:41:45.336	15.4%	1425503	6.056	9.334	15.572
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:25.739	0.3%	2877288	0.009	0.092	0.521
TOTAL: 23:59:47.105	23:49:44.082	99.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.216	0.0%	54751	0.000	0.004	0.080
article prepare	00:00:00.025	0.0%	80496	0.000	0.000	0.042
article read	00:00:43.755	0.1%	19834	0.000	2.206	537.000
backlog stats	00:00:00.309	0.0%	410373	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.916	0.0%	227632	0.000	0.013	1.431
data write	00:00:13.074	0.0%	219097	0.000	0.060	1.720
idle	23:59:10.431	99.9%	410373	0.000	210.419	18470.000
status file	00:00:02.554	0.0%	222	1.000	11.505	304.000
TOTAL: 24:00:24.008	24:00:13.280	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	74:23:52.175	100.0%	651184	0.000	411.300	219460.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:33.863	0.0%	1304999	0.000	0.026	2.333
readart	00:00:07.601	0.0%	620	0.000	12.260	142.500
TOTAL: 74:25:56.456	74:24:33.639	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	598	1425306	1401398	8256	15652	98%	286:27:32
2	news.task.gda.pl	12	67842	5570	60963	1309	8%	45:19:09
3	news.nask.pl	2	19178	4632	13620	926	24%	24:15:53
4	news.icm.edu.pl	43	278	8	242	28	2%	18:16:15
5	localhost	5	4	4	0	0	100%	00:00:01
TOTAL: 5		660	1512608	1411612	83081	17915	93%	374:18:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	475.9 GB	85.3 MB	2.1 GB	478.0 GB	99%	353.7 KB
2	news.nask.pl	117.2 MB	18.7 MB	18.9 MB	154.7 MB	75%	28.5 KB
3	news.task.gda.pl	12.1 MB	262.4 KB	4.0 MB	16.3 MB	74%	2.4 KB
4	news.icm.edu.pl	507.6 KB	25.6 KB	1.9 MB	2.4 MB	20%	69.4 KB
5	localhost	5.2 KB	0.0 KB	0.0 KB	5.2 KB	100%	1.3 KB
TOTAL: 5		476.0 GB	104.3 MB	2.1 GB	478.2 GB	99%	350.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 24 04:15:05 - 04:59:59	58190	4.1%	21.59	17.7 GB	3.7%	6875.22
Jun 24 05:00:00 - 05:59:59	59604	4.2%	16.56	20.4 GB	4.3%	5941.03
Jun 24 06:00:00 - 06:59:59	49722	3.5%	13.81	17.5 GB	3.7%	5109.55
Jun 24 07:00:00 - 07:59:59	43424	3.1%	12.06	14.7 GB	3.1%	4289.20
Jun 24 08:00:00 - 08:59:59	50168	3.6%	13.94	17.1 GB	3.6%	4991.91
Jun 24 09:00:00 - 09:59:59	70389	5.0%	19.55	19.1 GB	4.0%	5560.53
Jun 24 10:00:00 - 10:59:59	60415	4.3%	16.78	18.0 GB	3.8%	5233.38
Jun 24 11:00:00 - 11:59:59	52758	3.7%	14.65	19.1 GB	4.0%	5557.26
Jun 24 12:00:00 - 12:59:59	58460	4.1%	16.24	18.9 GB	4.0%	5494.82
Jun 24 13:00:00 - 13:59:59	53808	3.8%	14.95	18.5 GB	3.9%	5377.94
Jun 24 14:00:00 - 14:59:59	60273	4.3%	16.74	19.6 GB	4.1%	5703.08
Jun 24 15:00:00 - 15:59:59	52280	3.7%	14.52	19.0 GB	4.0%	5521.97
Jun 24 16:00:00 - 16:59:59	52373	3.7%	14.55	19.1 GB	4.0%	5564.98
Jun 24 17:00:00 - 17:59:59	51638	3.7%	14.34	18.9 GB	4.0%	5497.99
Jun 24 18:00:00 - 18:59:59	44111	3.1%	12.25	14.5 GB	3.1%	4232.50
Jun 24 19:00:00 - 19:59:59	32809	2.3%	9.11	10.1 GB	2.1%	2939.05
Jun 24 20:00:00 - 20:59:59	26621	1.9%	7.39	7.9 GB	1.7%	2304.69
Jun 24 21:00:00 - 21:59:59	26101	1.8%	7.25	8.5 GB	1.8%	2462.14
Jun 24 22:00:00 - 22:59:59	29495	2.1%	8.19	9.8 GB	2.0%	2840.43
Jun 24 23:00:00 - 23:59:59	46603	3.3%	12.95	18.0 GB	3.8%	5237.84
Jun 25 00:00:00 - 00:59:59	90798	6.4%	25.22	31.6 GB	6.6%	9217.78
Jun 25 01:00:00 - 01:59:59	100807	7.1%	28.00	35.5 GB	7.5%	10328.33
Jun 25 02:00:00 - 02:59:59	111311	7.9%	30.92	36.8 GB	7.7%	10732.67
Jun 25 03:00:00 - 03:59:59	103728	7.3%	28.81	36.7 GB	7.7%	10703.96
Jun 25 04:00:00 - 04:15:07	25646	1.8%	28.28	9.1 GB	1.9%	10468.66
TOTAL: 24:00:02	1411532	100.0%	16.34	475.9 GB	100.0%	5776.01

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	15780	312	2715	7	12746
2	news.task.gda.pl	1312	940	144	0	228
3	news.nask.pl	936	5	893	7	31
4	news.icm.edu.pl	28	0	2	0	26
TOTAL: 4		18056	1257	3754	14	13031

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	144
mailing.freebsd.svn-ports-all	71
mailing.freebsd.svn-ports-head	71
gmane.linux.alsa.devel	58
mailing.freebsd.svn-src-all	54
fa.freebsd.cvs-all	53
mailing.freebsd.ports-bugs	53
mailing.www.horde-bugs	48
mozilla.dev.tree-management	38
mailing.unix.dovecot	35
alt.windows7.general	35
mailing.database.pgsql-general	30
mailing.freebsd.svn-src-head	30
uk.rec.cycling.moderated	28
mailing.gcc.gcc-cvs	27
mailing.unix.samba-technical	27
mailing.unix.samba	27
comp.mobile.ipad	25
it.comp.os.win.windows7	22
hr.alt.cellular.gsm.android	21
TOTAL: 77	1257

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	12581
EMP (fsl)	173
EMP (md5)	47
Attached HTML file	46
EMP (phl)	22
Cancel in forbidden group	12
Scoring filter (12)	1
HTML post	1
Scoring filter (9)	1
TOTAL: 9	12884

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	68974	8753	33586	8388	2	124	12%	24:07:52
2	panorama	56961	2177	51094	670	0	0	3%	23:53:26
3	nask	23510	134	16197	2	0	0	0%	23:56:59
4	task	2654	22	1389	3	0	0	0%	21:29:52
5	poznan	10441	15	9160	10	0	0	0%	24:00:21
6	okapi	0	0	0	0	0	3156	0%	24:00:00
TOTAL: 6		162540	11101	111426	9073	2	3280	6%	141:28:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	2.2 GB	2.3 GB	4.5 GB	54.4 KB/s	275.9 KB	24:07:52
2	nask	30.1 MB	2.5 MB	32.6 MB	0.4 KB/s	245.7 KB	23:56:59
3	panorama	27.2 MB	32.1 MB	59.3 MB	0.7 KB/s	21.3 KB	23:53:26
4	task	53.9 KB	5.1 KB	59.0 KB	0.0 KB/s	2.4 KB	21:29:52
5	poznan	44.5 KB	27.7 KB	72.2 KB	0.0 KB/s	2.9 KB	24:00:21
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		2.2 GB	2.4 GB	4.6 GB	9.5 KB/s	239.1 KB	141:28:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	324	500.7 KB	288	0	00:00:11
2	212.191.78.190	288	142	288.4 KB	14	1	47:58:17
3	web.man.lodz.pl	288	66	84.4 KB	31	0	00:00:20
4	lze200.lze.lodz.pl	5	45	97.1 KB	6	0	00:33:36
5	212.191.196.1	2	17	23.1 KB	3	0	00:21:56
6	router2.cbmm.lodz.pl	5	16	44.1 KB	4	0	00:27:43
7	ebp52.neoplus.adsl.tpnet.pl	3	10	17.5 KB	2	0	00:02:29
8	kujawiak.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 8		882	620	1.0 MB	348	4	74:25:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	324	500.7 KB	288	0	00:00:11
2	unresolved	290	159	311.5 KB	17	1	48:20:14
3	*.man.lodz.pl	674	66	84.4 KB	31	3	00:00:27
4	*.lze.lodz.pl	5	45	97.1 KB	6	0	00:33:36
5	*.cbmm.lodz.pl	5	16	44.1 KB	4	0	00:27:43
6	*.neoplus.adsl.tpnet.pl	3	10	17.5 KB	2	0	00:02:29
7	*.internetdsl.tpnet.pl	4	0	0.0 KB	0	0	00:00:00
8	*.181.14.62.static.jazztel.es	1	0	0.0 KB	0	0	25:00:51
9	*.toya.net.pl	74	0	0.0 KB	0	0	00:00:20
TOTAL: 9		1344	620	1.0 MB	348	4	74:25:54

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	1.132	8.828	47:58:17
185.181.14.62.static.jazztel.es	51.416	68.928	25:00:51
lze200.lze.lodz.pl	0.032	0.624	00:33:36
router2.cbmm.lodz.pl	0.028	0.228	00:27:43
212.191.196.1	0.016	0.172	00:21:56
ebp52.neoplus.adsl.tpnet.pl	0.012	0.056	00:02:29
web.man.lodz.pl	1.580	10.424	00:00:20
staticline41435.toya.net.pl	0.288	2.220	00:00:20
vegas.cm.p.lodz.pl	1.864	21.396	00:00:11
f-oper-v100.man.lodz.pl	0.816	8.248	00:00:04
david.man.lodz.pl	0.308	2.060	00:00:01
gcq193.internetdsl.tpnet.pl	0.036	0.088	00:00:00
kujawiak.man.lodz.pl	0.024	0.160	00:00:00
?	0.004	0.032	00:00:00
TOTAL: 14	57.556	123.464	74:25:54

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	287
david.man.lodz.pl	96
staticline41435.toya.net.pl	74
gcq193.internetdsl.tpnet.pl	4
185.181.14.62.static.jazztel.es	1
TOTAL: 5	462

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	334	53.9%
2	pl	286	46.1%
TOTAL: 2		620	100.0%

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

Newsgroup		Count
1	lodman.info	334
2	pl.rec.rowery	81
3	pl.regionalne.warszawa	40
4	pl.comp.pecet	36
5	pl.rec.kuchnia	34
6	pl.comp.os.ms-windows.winnt	29
7	pl.regionalne.lodz	28
8	pl.internet.polip	10
9	pl.soc.prawo	8
10	pl.comp.bazy-danych	7
11	pl.misc.paranauki	6
12	pl.rec.dom	2
13	pl.rec.gory	2
14	pl.praca.dyskusje	1
15	pl.sci.chemia	1
16	pl.comp.os.linux	1
TOTAL: 16		620

Daily Usenet report

Jun 24 04:15:05 -- Jun 25 04:15:07