Daily Usenet report: Dec 13 04:15:05

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Dec 13 10:13:53 newsfeed nnrpd[23739]: SERVER perl filter not defined
Dec 13 12:08:38 newsfeed nnrpd[27423]: SERVER perl filter not defined
Dec 13 12:20:37 newsfeed nnrpd[27809]: SERVER perl filter not defined
Dec 13 14:04:18 newsfeed nnrpd[31274]: SERVER perl filter not defined
Dec 13 15:42:54 newsfeed nnrpd[2149]: SERVER perl filter not defined
Dec 13 15:44:05 newsfeed nnrpd[2157]: SERVER perl filter not defined
Dec 13 21:58:33 newsfeed nnrpd[14331]: SERVER perl filter not defined
Dec 13 22:54:33 newsfeed nnrpd[16255]: SERVER perl filter not defined
Dec 13 23:37:54 newsfeed overchan[2210]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1911648	98.2%	216.7 MB	97.8%
innd	16831	0.9%	2.8 MB	1.3%
nnrpd	10659	0.5%	1.1 MB	0.5%
innfeed	7081	0.4%	1.1 MB	0.5%
overchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	1946220	100.0%	221.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1904242	94.4%
Negative hits	69549	3.4%
Positive hits	42763	2.1%
Cache misses	253	0.0%
TOTAL: 4	2016807	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:17.181	0.0%	582	0.000	29.521	562.000
article cleanup	00:00:29.224	0.0%	1912010	0.005	0.015	0.030
article logging	00:00:54.391	0.1%	1912150	0.013	0.028	0.321
article parse	01:04:50.877	4.5%	218194068	0.007	0.018	0.060
article write	00:15:40.220	1.1%	1904358	0.279	0.494	1.427
artlog/artcncl	00:00:00.008	0.0%	577	0.000	0.014	1.000
artlog/artparse	00:00:00.000	0.0%	10	0.000	0.000	0.000
data move	00:02:36.676	0.2%	231162944	0.000	0.001	0.002
hisgrep/artcncl	00:00:02.462	0.0%	272	0.000	9.051	289.000
hishave/artcncl	00:00:02.690	0.0%	582	0.000	4.622	19.476
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:36:50.976	10.9%	2018946	0.013	4.661	37.178
history sync	00:00:00.011	0.0%	293	0.000	0.038	1.000
history write	03:35:14.542	14.9%	1906176	0.879	6.775	40.914
hiswrite/artcncl	00:00:03.341	0.0%	310	0.000	10.777	51.000
idle	10:24:23.992	43.3%	144017203	0.145	0.260	0.889
nntp read	00:59:49.114	4.2%	216768161	0.008	0.017	0.034
overview write	00:00:10.975	0.0%	1904358	0.000	0.006	0.014
perl filter	04:13:31.341	17.6%	1906166	5.447	7.980	10.885
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:38.732	0.3%	3850303	0.010	0.057	0.084
TOTAL: 24:00:43.986	23:18:36.753	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.125	0.0%	41576	0.000	0.003	0.118
article prepare	00:00:00.016	0.0%	66994	0.000	0.000	0.050
article read	00:00:01.130	0.0%	15688	0.000	0.072	1.000
backlog stats	00:00:00.249	0.0%	329253	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.049	0.0%	193754	0.000	0.011	1.000
data write	00:00:06.629	0.0%	165385	0.000	0.040	2.000
idle	23:59:17.760	100.0%	329253	0.000	262.284	16862.000
status file	00:00:06.129	0.0%	231	0.000	26.532	5388.000
TOTAL: 23:59:41.702	23:59:34.087	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	51:52:57.325	100.0%	6940	0.000	26913.159	600067.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.552	0.0%	16484	0.000	0.033	3.556
readart	00:00:29.951	0.0%	623	0.000	48.075	920.233
TOTAL: 51:54:03.786	51:53:27.828	100.0%	-	-	-	-

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	841	1896575	1889464	1810	5301	99%	319:29:02
2	news.nask.pl	2	19348	6853	11287	1208	35%	23:53:30
3	news.task.gda.pl	14	30890	6371	23362	1157	20%	45:37:51
4	localhost	9	8	8	0	0	100%	00:00:01
5	news.icm.edu.pl	47	208	0	197	11	0%	16:08:08
TOTAL: 5		913	1947029	1902696	36656	7677	97%	405:08:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	657.2 GB	21.6 MB	41.2 MB	657.3 GB	99%	363.7 KB
2	news.nask.pl	30.8 MB	4.1 MB	4.3 MB	39.2 MB	78%	5.0 KB
3	news.task.gda.pl	14.0 MB	54.2 KB	3.7 MB	17.7 MB	78%	2.4 KB
4	localhost	11.8 KB	0.0 KB	0.0 KB	11.8 KB	100%	1.5 KB
5	news.icm.edu.pl	0.0 KB	13.5 KB	267.8 KB	281.3 KB	0%	25.6 KB
TOTAL: 5		657.3 GB	25.8 MB	49.4 MB	657.3 GB	99%	360.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 13 04:15:05 - 04:59:59	56482	3.0%	20.96	21.4 GB	3.3%	8324.62
Dec 13 05:00:00 - 05:59:59	76615	4.0%	21.28	28.5 GB	4.3%	8297.86
Dec 13 06:00:00 - 06:59:59	76620	4.0%	21.28	28.2 GB	4.3%	8201.60
Dec 13 07:00:00 - 07:59:59	75263	4.0%	20.91	26.8 GB	4.1%	7810.71
Dec 13 08:00:00 - 08:59:59	86849	4.6%	24.12	30.0 GB	4.6%	8733.70
Dec 13 09:00:00 - 09:59:59	68366	3.6%	18.99	25.3 GB	3.8%	7367.80
Dec 13 10:00:00 - 10:59:59	71793	3.8%	19.94	26.7 GB	4.1%	7785.39
Dec 13 11:00:00 - 11:59:59	81754	4.3%	22.71	29.3 GB	4.5%	8546.71
Dec 13 12:00:00 - 12:59:59	83032	4.4%	23.06	29.4 GB	4.5%	8565.67
Dec 13 13:00:00 - 13:59:59	81564	4.3%	22.66	29.5 GB	4.5%	8594.87
Dec 13 14:00:00 - 14:59:59	82373	4.3%	22.88	28.2 GB	4.3%	8212.06
Dec 13 15:00:00 - 15:59:59	77036	4.0%	21.40	27.0 GB	4.1%	7850.85
Dec 13 16:00:00 - 16:59:59	89487	4.7%	24.86	30.6 GB	4.7%	8926.31
Dec 13 17:00:00 - 17:59:59	72082	3.8%	20.02	25.4 GB	3.9%	7385.49
Dec 13 18:00:00 - 18:59:59	63054	3.3%	17.52	22.0 GB	3.4%	6415.44
Dec 13 19:00:00 - 19:59:59	71650	3.8%	19.90	24.0 GB	3.7%	7003.69
Dec 13 20:00:00 - 20:59:59	100637	5.3%	27.95	32.2 GB	4.9%	9367.08
Dec 13 21:00:00 - 21:59:59	98732	5.2%	27.43	27.3 GB	4.1%	7938.26
Dec 13 22:00:00 - 22:59:59	71970	3.8%	19.99	23.1 GB	3.5%	6730.05
Dec 13 23:00:00 - 23:59:59	89964	4.7%	24.99	28.0 GB	4.3%	8160.87
Dec 14 00:00:00 - 00:59:59	86232	4.5%	23.95	26.8 GB	4.1%	7810.08
Dec 14 01:00:00 - 01:59:59	73049	3.8%	20.29	25.3 GB	3.8%	7366.84
Dec 14 02:00:00 - 02:59:59	70631	3.7%	19.62	26.4 GB	4.0%	7691.02
Dec 14 03:00:00 - 03:59:59	77569	4.1%	21.55	28.1 GB	4.3%	8173.83
Dec 14 04:00:00 - 04:15:03	20468	1.1%	22.67	7.8 GB	1.2%	9077.39
TOTAL: 23:59:58	1903272	100.0%	22.03	657.3 GB	100.0%	7977.06

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5418	202	4659	2	555
2	news.nask.pl	1208	21	1157	1	29
3	news.task.gda.pl	1162	982	25	1	154
4	news.icm.edu.pl	11	0	1	0	10
TOTAL: 4		7799	1205	5842	4	748

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	103
mailing.freebsd.svn-ports-head	103
fa.freebsd.cvs-all	94
mailing.freebsd.svn-src-all	92
uk.railway	77
alt.windows7.general	47
mozilla.dev.tree-management	41
fr.usenet.abus.nocem	40
gmane.linux.alsa.devel	39
it.comp.os.win.windows8	39
alt.politics.scorched-earth	38
mailing.openbsd.misc	38
24hoursupport.helpdesk	36
mailing.freebsd.ports-bugs	31
mailing.gcc.gcc-cvs	26
mozilla.dev.gaia	25
mailing.freebsd.current	23
mailing.openbsd.source-changes	23
mailing.openbsd.ports-changes	18
mailing.unix.samba-technical	17
TOTAL: 68	1205

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

Groups	Count
pl.sci.matematyka	2
fr.comp.securite	1
pl.sci.historia	1
TOTAL: 3	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	419
EMP (fsl)	132
HTML post	23
EMP (md5)	21
Attached HTML file	2
Scoring filter (8)	1
TOTAL: 6	598

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	59468	6444	28447	5529	11	10	10%	23:56:33
2	panorama	44746	4072	36932	559	0	0	9%	23:53:38
3	poznan	16437	87	13022	38	0	0	0%	24:06:28
4	nask	21769	35	14241	5	0	0	0%	23:58:20
5	task	2284	6	1210	1	0	3	0%	22:17:28
6	okapi	0	0	0	0	0	2528	0%	24:00:02
TOTAL: 6		144704	10644	93852	6132	11	2541	7%	142:12:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	30.7 MB	961.8 MB	992.6 MB	11.8 KB/s	84.9 KB	23:56:33
2	panorama	20.6 MB	2.8 MB	23.4 MB	0.3 KB/s	5.2 KB	23:53:38
3	poznan	546.4 KB	74.7 KB	621.0 KB	0.0 KB/s	5.0 KB	24:06:28
4	nask	149.0 KB	544.9 KB	693.9 KB	0.0 KB/s	17.3 KB	23:58:20
5	task	17.9 KB	2.4 KB	20.3 KB	0.0 KB/s	2.9 KB	22:17:28
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		52.0 MB	965.3 MB	1017.3 MB	2.0 KB/s	62.1 KB	142:12:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	362	540.0 KB	288	0	0	00:00:12
2	212.191.78.190	331	86	183.5 KB	43	1	0	47:07:01
3	lze200.lze.lodz.pl	7	79	168.1 KB	10	0	0	00:48:25
4	web.man.lodz.pl	288	64	65.6 KB	28	0	0	00:00:26
5	89-72-43-125.dynamic.chello.pl	3	15	12.4 KB	1	0	3	00:04:26
6	176.221.120.236	5	9	7.1 KB	2	0	1	00:30:37
7	ip-176.105.135.22.tvsat364.lodz.pl	7	5	7.6 KB	3	0	0	02:38:58
8	router2.cbmm.lodz.pl	2	3	63.2 KB	2	0	0	00:03:26
9	xdsl.104.c74.petrotel.pl	2	0	0.0 KB	0	0	1	00:39:52
10	kujawiak.man.lodz.pl	7	0	0.0 KB	0	7	0	00:00:01
TOTAL: 10		940	623	1.0 MB	377	8	5	51:54:02

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	362	540.0 KB	288	0	0	00:00:12
2	unresolved	336	95	190.6 KB	45	1	1	47:37:38
3	*.lze.lodz.pl	7	79	168.1 KB	10	0	0	00:48:25
4	*.man.lodz.pl	680	64	65.6 KB	28	7	0	00:00:33
5	*.dynamic.chello.pl	3	15	12.4 KB	1	0	3	00:04:26
6	*.105.135.22.tvsat364.lodz.pl	7	5	7.6 KB	3	0	0	02:38:58
7	*.cbmm.lodz.pl	2	3	63.2 KB	2	0	0	00:03:26
8	*.104.c74.petrotel.pl	2	0	0.0 KB	0	0	1	00:39:52
9	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	0	00:00:22
10	*.toya.net.pl	81	0	0.0 KB	0	0	0	00:00:07
TOTAL: 10		1407	623	1.0 MB	377	8	5	51:54:02

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.756	4.064	47:07:01
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.096	02:38:58
lze200.lze.lodz.pl	0.024	0.124	00:48:25
xdsl.104.c74.petrotel.pl	0.032	0.040	00:39:52
176.221.120.236	0.088	0.060	00:30:37
89-72-43-125.dynamic.chello.pl	0.024	0.028	00:04:26
router2.cbmm.lodz.pl	0.004	0.024	00:03:26
web.man.lodz.pl	0.612	4.124	00:00:26
ebp53.neoplus.adsl.tpnet.pl	0.000	0.016	00:00:22
vegas.cm.p.lodz.pl	0.624	4.028	00:00:12
staticline41435.toya.net.pl	0.184	1.000	00:00:07
mon.man.lodz.pl	0.672	3.536	00:00:04
david.man.lodz.pl	0.220	1.172	00:00:01
kujawiak.man.lodz.pl	0.052	0.088	00:00:01
?	0.004	0.012	00:00:00
TOTAL: 15	3.304	18.412	51:54:02

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	289
david.man.lodz.pl	96
staticline41435.toya.net.pl	81
ebp53.neoplus.adsl.tpnet.pl	1
TOTAL: 4	467

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	3	0
xdsl.104.c74.petrotel.pl	1	0
TOTAL: 2	4	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	362	58.1%
2	pl	237	38.0%
3	lodz	22	3.5%
4	lodzman	2	0.3%
TOTAL: 4		623	100.0%

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

Newsgroup		Count
1	lodman.info	362
2	pl.comp.pecet	67
3	pl.misc.kolej	34
4	pl.soc.prawo	25
5	pl.news.admin	24
6	lodz.test	22
7	pl.comp.os.ms-windows.winnt	20
8	pl.misc.telefonia.gsm	18
9	pl.regionalne.lodz	13
10	pl.regionalne.warszawa	11
11	pl.rec.gory	9
12	pl.comp.os.linux	7
13	pl.rec.kuchnia	3
14	lodzman.test	2
15	pl.comp.os.linux.sieci	2
16	pl.rec.rowery	2
17	pl.praca.dyskusje	1
18	pl.sci.chemia	1
TOTAL: 18		623

Daily Usenet report

Dec 13 04:15:05 -- Dec 14 04:15:03