Daily Usenet report: Dec 10 04:15:05

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Dec 10 07:58:38 newsfeed nnrpd[1881]: SERVER perl filter not defined
Dec 10 08:02:36 newsfeed nnrpd[2000]: SERVER perl filter not defined
Dec 10 08:04:11 newsfeed nnrpd[2004]: SERVER perl filter not defined
Dec 10 15:29:23 newsfeed nnrpd[16639]: SERVER perl filter not defined
Dec 10 15:35:50 newsfeed nnrpd[16799]: SERVER perl filter not defined
Dec 11 00:26:55 newsfeed overchan[2210]: input line too long, skipping
Dec 11 01:24:03 newsfeed nnrpd[4206]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1955492	98.3%	221.0 MB	97.8%
innd	17379	0.9%	2.9 MB	1.3%
nnrpd	9679	0.5%	1008.4 KB	0.4%
innfeed	7044	0.4%	1.1 MB	0.5%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	1989595	100.0%	225.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1947460	94.0%
Negative hits	79864	3.9%
Positive hits	44357	2.1%
Cache misses	708	0.0%
TOTAL: 4	2072389	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:31.652	0.0%	946	0.000	33.459	1100.000
article cleanup	00:00:30.308	0.0%	1954320	0.006	0.016	0.035
article logging	00:00:57.963	0.1%	1954848	0.013	0.030	0.468
article parse	01:07:44.137	4.7%	224527268	0.007	0.018	0.047
article write	00:16:09.857	1.1%	1947333	0.329	0.498	1.046
artlog/artcncl	00:00:00.021	0.0%	940	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:03:35.093	0.2%	237872175	0.000	0.001	0.034
hisgrep/artcncl	00:00:10.325	0.0%	766	0.000	13.479	302.000
hishave/artcncl	00:00:03.432	0.0%	946	0.000	3.628	282.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:11:36.463	9.1%	2074011	0.007	3.807	32.901
history sync	00:00:00.011	0.0%	293	0.000	0.038	1.000
history write	04:27:54.413	18.6%	1949343	0.415	8.246	70.763
hiswrite/artcncl	00:00:01.981	0.0%	180	0.000	11.006	110.500
idle	09:37:16.905	40.1%	141417458	0.127	0.245	1.791
nntp read	01:04:12.293	4.5%	223026238	0.007	0.017	0.035
overview write	00:00:11.181	0.0%	1947333	0.001	0.006	0.016
perl filter	04:24:35.533	18.4%	1949341	5.186	8.144	10.580
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:44.650	0.3%	3934987	0.032	0.057	0.104
TOTAL: 24:00:05.256	23:19:16.218	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.132	0.0%	40322	0.000	0.003	0.167
article prepare	00:00:00.017	0.0%	68756	0.000	0.000	0.050
article read	00:00:00.969	0.0%	16191	0.000	0.060	3.829
backlog stats	00:00:00.192	0.0%	309057	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.028	0.0%	188467	0.000	0.011	0.062
data write	00:00:05.049	0.0%	153806	0.000	0.033	34.000
idle	23:59:59.915	100.0%	309057	0.000	279.560	18005.000
status file	00:00:01.094	0.0%	214	0.000	5.112	150.000
TOTAL: 24:00:16.744	24:00:09.396	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	67:08:57.198	100.0%	556832	0.000	434.130	450774.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:17.241	0.0%	1115853	0.000	0.015	8.778
readart	00:00:01.156	0.0%	467	0.000	2.475	27.333
TOTAL: 67:10:17.799	67:09:15.595	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	904	1940878	1933445	2736	4697	99%	362:32:50
2	news.nask.pl	3	19190	7181	11035	974	37%	24:10:31
3	news.task.gda.pl	13	32358	6389	24660	1309	19%	44:10:22
4	localhost	7	6	6	0	0	100%	00:00:01
5	news.icm.edu.pl	44	224	5	217	2	2%	16:59:24
TOTAL: 5		971	1992656	1947026	38648	6982	97%	447:53:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	708.8 GB	34.1 MB	41.3 MB	708.9 GB	99%	383.5 KB
2	news.nask.pl	32.7 MB	2.9 MB	3.0 MB	38.6 MB	84%	4.9 KB
3	news.task.gda.pl	14.4 MB	30.9 KB	4.0 MB	18.4 MB	78%	2.5 KB
4	news.icm.edu.pl	482.7 KB	0.0 KB	25.4 KB	508.1 KB	94%	72.6 KB
5	localhost	9.3 KB	0.0 KB	0.0 KB	9.3 KB	100%	1.5 KB
TOTAL: 5		708.8 GB	37.1 MB	48.3 MB	708.9 GB	99%	380.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 10 04:15:05 - 04:59:59	59334	3.0%	22.02	22.2 GB	3.1%	8627.23
Dec 10 05:00:00 - 05:59:59	81585	4.2%	22.66	31.0 GB	4.4%	9020.48
Dec 10 06:00:00 - 06:59:59	66850	3.4%	18.57	25.4 GB	3.6%	7385.77
Dec 10 07:00:00 - 07:59:59	76866	3.9%	21.35	28.4 GB	4.0%	8271.03
Dec 10 08:00:00 - 08:59:59	65286	3.4%	18.14	23.7 GB	3.3%	6912.10
Dec 10 09:00:00 - 09:59:59	84296	4.3%	23.42	29.8 GB	4.2%	8667.26
Dec 10 10:00:00 - 10:59:59	78103	4.0%	21.70	27.3 GB	3.9%	7951.32
Dec 10 11:00:00 - 11:59:59	84516	4.3%	23.48	29.9 GB	4.2%	8715.13
Dec 10 12:00:00 - 12:59:59	89363	4.6%	24.82	31.6 GB	4.5%	9204.78
Dec 10 13:00:00 - 13:59:59	87945	4.5%	24.43	31.7 GB	4.5%	9232.89
Dec 10 14:00:00 - 14:59:59	88072	4.5%	24.46	31.9 GB	4.5%	9280.62
Dec 10 15:00:00 - 15:59:59	84148	4.3%	23.37	30.4 GB	4.3%	8846.87
Dec 10 16:00:00 - 16:59:59	96545	5.0%	26.82	32.5 GB	4.6%	9464.96
Dec 10 17:00:00 - 17:59:59	85093	4.4%	23.64	31.7 GB	4.5%	9225.43
Dec 10 18:00:00 - 18:59:59	83300	4.3%	23.14	30.5 GB	4.3%	8875.15
Dec 10 19:00:00 - 19:59:59	79442	4.1%	22.07	30.5 GB	4.3%	8888.75
Dec 10 20:00:00 - 20:59:59	82675	4.2%	22.97	31.0 GB	4.4%	9036.25
Dec 10 21:00:00 - 21:59:59	81990	4.2%	22.77	31.0 GB	4.4%	9029.39
Dec 10 22:00:00 - 22:59:59	77771	4.0%	21.60	28.7 GB	4.0%	8345.11
Dec 10 23:00:00 - 23:59:59	89206	4.6%	24.78	32.2 GB	4.5%	9386.58
Dec 11 00:00:00 - 00:59:59	74820	3.8%	20.78	26.9 GB	3.8%	7823.83
Dec 11 01:00:00 - 01:59:59	81759	4.2%	22.71	28.1 GB	4.0%	8176.56
Dec 11 02:00:00 - 02:59:59	73037	3.8%	20.29	27.0 GB	3.8%	7857.13
Dec 11 03:00:00 - 03:59:59	75169	3.9%	20.88	28.1 GB	4.0%	8175.65
Dec 11 04:00:00 - 04:15:03	19865	1.0%	22.00	7.4 GB	1.0%	8593.79
TOTAL: 23:59:58	1947036	100.0%	22.54	708.6 GB	100.0%	8599.66

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5188	231	4009	3	945
2	news.task.gda.pl	1322	1103	15	0	204
3	news.nask.pl	1004	9	954	5	36
4	news.icm.edu.pl	2	0	0	0	2
TOTAL: 4		7516	1343	4978	8	1187

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	111
mailing.freebsd.svn-ports-head	107
uk.railway	77
it.tlc.cellulari.android	64
24hoursupport.helpdesk	56
fa.freebsd.cvs-all	51
mailing.freebsd.svn-src-all	50
mailing.freebsd.ports-bugs	47
alt.politics.scorched-earth	46
gmane.linux.alsa.devel	44
mailing.database.pgsql-general	43
mailing.unix.samba-technical	42
mozilla.dev.platform	34
alt.windows7.general	34
mailing.freebsd.svn-src-head	34
mailing.gcc.gcc-cvs	33
mailing.postfix.users	33
gnu.hurd.bug	29
fr.rec.jeux.cartes.poker	27
mailing.unix.samba	26
TOTAL: 84	1343

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	465
EMP (fsl)	172
HTML post	12
Attached HTML file	5
EMP (md5)	2
Scoring filter (12)	1
TOTAL: 6	657

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	57807	9141	26839	3569	15	0	15%	24:00:49
2	panorama	42305	3690	36069	595	1	0	8%	24:03:38
3	task	1962	61	999	5	0	0	3%	20:56:05
4	poznan	16409	57	13398	14	0	0	0%	24:06:29
5	nask	20119	18	13557	1	0	0	0%	23:58:21
6	okapi	0	0	0	0	0	2955	0%	24:00:00
TOTAL: 6		138602	12967	90862	4184	16	2955	9%	141:05:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	45.7 MB	170.5 MB	216.2 MB	2.6 KB/s	17.4 KB	24:00:49
2	panorama	18.9 MB	2.1 MB	21.0 MB	0.2 KB/s	5.0 KB	24:03:38
3	poznan	300.1 KB	191.7 KB	491.9 KB	0.0 KB/s	6.9 KB	24:06:29
4	task	125.1 KB	8.2 KB	133.3 KB	0.0 KB/s	2.0 KB	20:56:05
5	nask	75.1 KB	1.9 KB	77.0 KB	0.0 KB/s	4.1 KB	23:58:21
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		65.1 MB	172.8 MB	237.9 MB	0.5 KB/s	14.2 KB	141:05:22

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	350	548.7 KB	288	0	00:00:13
2	web.man.lodz.pl	288	62	52.9 KB	28	0	00:00:24
3	lze200.lze.lodz.pl	4	33	59.6 KB	5	0	00:46:23
4	212.191.78.190	230	20	38.3 KB	12	0	35:43:22
5	ip-176.105.135.22.tvsat364.lodz.pl	12	2	3.6 KB	2	0	03:52:29
6	kujawiak.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 6		828	467	703.2 KB	335	6	67:10:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	350	548.7 KB	288	0	00:00:13
2	*.man.lodz.pl	679	62	52.9 KB	28	6	00:00:32
3	*.lze.lodz.pl	4	33	59.6 KB	5	0	00:46:23
4	unresolved	232	20	38.3 KB	12	0	35:43:39
5	*.105.135.22.tvsat364.lodz.pl	12	2	3.6 KB	2	0	03:52:29
6	*.lodz.mm.pl	8	0	0.0 KB	0	0	01:13:30
7	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:53
8	*.181.14.62.static.jazztel.es	2	0	0.0 KB	0	0	25:32:25
9	*.toya.net.pl	95	0	0.0 KB	0	0	00:00:08
TOTAL: 9		1321	467	703.2 KB	335	6	67:10:17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.464	2.652	35:43:22
240.181.14.62.static.jazztel.es	27.724	38.144	25:32:25
ip-176.105.135.22.tvsat364.lodz.pl	0.020	0.144	03:52:29
host-89-228-165-199.lodz.mm.pl	0.020	0.092	01:13:30
lze200.lze.lodz.pl	0.012	0.036	00:46:23
ech84.neoplus.adsl.tpnet.pl	0.004	0.004	00:00:53
web.man.lodz.pl	0.628	3.688	00:00:24
212.51.203.199	0.000	0.028	00:00:16
vegas.cm.p.lodz.pl	0.556	3.896	00:00:13
staticline41435.toya.net.pl	0.208	1.108	00:00:08
mon.man.lodz.pl	0.444	3.224	00:00:05
david.man.lodz.pl	0.176	1.140	00:00:02
kujawiak.man.lodz.pl	0.020	0.088	00:00:01
?	0.000	0.004	00:00:00
TOTAL: 14	30.276	54.248	67:10:17

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	289
david.man.lodz.pl	96
staticline41435.toya.net.pl	95
host-89-228-165-199.lodz.mm.pl	8
240.181.14.62.static.jazztel.es	2
212.51.203.199	2
ech84.neoplus.adsl.tpnet.pl	1
TOTAL: 7	493

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	5	0
240.181.14.62.static.jazztel.es	2	1
lze200.lze.lodz.pl	1	0
TOTAL: 3	8	3

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	350	74.9%
2	pl	117	25.1%
TOTAL: 2		467	100.0%

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

Newsgroup		Count
1	lodman.info	350
2	pl.comp.pecet	74
3	pl.comp.os.ms-windows.winnt	12
4	pl.soc.prawo	7
5	pl.comp.os.linux	7
6	pl.misc.kolej	5
7	pl.news.admin	5
8	pl.misc.telefonia.gsm	4
9	pl.rec.rowery	2
10	pl.misc.dieta	1
TOTAL: 10		467

Daily Usenet report

Dec 10 04:15:05 -- Dec 11 04:15:03