Daily Usenet report: Jan 10 04:15:04

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jan 10 08:46:25 newsfeed nnrpd[27430]: SERVER perl filter not defined
Jan 10 09:50:46 newsfeed nnrpd[29632]: SERVER perl filter not defined
Jan 10 12:24:44 newsfeed innfeed[2229]: task:0 connect: Network is unreachable
Jan 10 12:24:44 newsfeed innfeed[2229]: nask:0 connect: Network is unreachable
Jan 10 12:24:44 newsfeed innfeed[2229]: icm:0 connect: Network is unreachable
Jan 10 14:06:47 newsfeed nnrpd[5788]: SERVER perl filter not defined
Jan 10 15:30:26 newsfeed nnrpd[8489]: SERVER perl filter not defined
Jan 10 16:41:19 newsfeed nnrpd[10778]: SERVER perl filter not defined
Jan 11 01:36:00 newsfeed nnrpd[28908]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2320675	98.3%	272.6 MB	98.0%
innd	19648	0.8%	3.2 MB	1.2%
nnrpd	12585	0.5%	1.3 MB	0.5%
innfeed	7128	0.3%	1.1 MB	0.4%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 5	2360037	100.0%	278.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2286266	86.7%
Negative hits	261096	9.9%
Cache misses	50422	1.9%
Positive hits	39080	1.5%
TOTAL: 4	2636864	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.341	0.0%	254	0.000	28.902	704.000
article cleanup	00:00:32.568	0.0%	2320228	0.003	0.014	0.027
article logging	00:01:07.517	0.1%	2320455	0.012	0.029	0.333
article parse	01:17:12.886	5.4%	153278987	0.006	0.030	0.082
article write	00:18:01.964	1.3%	2285315	0.254	0.473	1.166
artlog/artcncl	00:00:00.005	0.0%	250	0.000	0.020	1.000
artlog/artparse	00:00:00.000	0.0%	9	0.000	0.000	0.000
data move	00:05:29.607	0.4%	167357108	0.001	0.002	0.015
hisgrep/artcncl	00:00:00.966	0.0%	232	0.000	4.164	178.000
hishave/artcncl	00:00:00.505	0.0%	254	0.000	1.988	18.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:41:31.594	11.2%	2638246	0.020	3.673	11.085
history sync	00:00:00.011	0.0%	290	0.000	0.038	1.000
history write	03:55:11.513	16.3%	2287297	0.260	6.170	21.092
hiswrite/artcncl	00:00:00.092	0.0%	22	0.000	4.182	14.000
idle	08:29:13.481	35.4%	93462852	0.157	0.327	18.346
nntp read	01:14:10.047	5.2%	151595478	0.007	0.029	0.050
overview write	00:00:14.722	0.0%	2285315	0.000	0.006	0.016
perl filter	05:19:50.844	22.2%	2287288	4.562	8.390	11.674
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:51.272	0.3%	4608670	0.016	0.063	0.172
TOTAL: 23:59:34.938	23:27:36.935	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.144	0.0%	38108	0.000	0.004	0.167
article prepare	00:00:00.011	0.0%	45894	0.000	0.000	0.250
article read	00:00:02.609	0.0%	10716	0.000	0.243	130.333
backlog stats	00:00:00.228	0.0%	291745	0.000	0.001	0.015
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.003	0.0%	172345	0.000	0.012	1.000
data write	00:00:04.551	0.0%	146418	0.000	0.031	0.167
idle	23:57:46.904	100.0%	291745	0.000	295.693	15789.000
status file	00:00:01.752	0.0%	280	0.000	6.257	266.000
TOTAL: 23:58:05.319	23:57:58.202	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	77:05:22.377	100.0%	220804	0.000	1256.872	900240.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:07.428	0.0%	444532	0.000	0.017	1.083
readart	00:00:01.720	0.0%	521	0.000	3.301	56.769
TOTAL: 77:06:56.096	77:05:31.525	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	1024	2326324	2267881	25699	32744	97%	392:28:46
2	news.nask.pl	4	17014	7851	8211	952	46%	23:26:00
3	news.task.gda.pl	16	29533	6949	21442	1142	23%	39:11:40
4	localhost	7	6	6	0	0	100%	00:00:01
5	news.icm.edu.pl	44	277	4	266	7	1%	18:23:15
TOTAL: 5		1095	2373154	2282691	55618	34845	96%	473:29:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	670.1 GB	3.3 GB	3.4 GB	676.8 GB	99%	308.5 KB
2	news.nask.pl	26.5 MB	2.5 MB	2.5 MB	31.5 MB	84%	3.7 KB
3	news.task.gda.pl	14.4 MB	68.4 KB	3.5 MB	17.9 MB	80%	2.3 KB
4	news.icm.edu.pl	176.0 KB	9.8 KB	66.8 KB	252.5 KB	69%	23.0 KB
5	localhost	8.8 KB	0.0 KB	0.0 KB	8.8 KB	100%	1.5 KB
TOTAL: 5		670.2 GB	3.3 GB	3.4 GB	676.9 GB	99%	306.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 10 04:15:04 - 04:59:59	61634	2.7%	22.86	22.3 GB	3.3%	8684.94
Jan 10 05:00:00 - 05:59:59	85019	3.7%	23.62	28.8 GB	4.3%	8381.38
Jan 10 06:00:00 - 06:59:59	86705	3.8%	24.08	29.0 GB	4.3%	8461.22
Jan 10 07:00:00 - 07:59:59	81591	3.6%	22.66	26.8 GB	4.0%	7817.28
Jan 10 08:00:00 - 08:59:59	91390	4.0%	25.39	29.9 GB	4.5%	8700.36
Jan 10 09:00:00 - 09:59:59	82050	3.6%	22.79	29.7 GB	4.4%	8646.58
Jan 10 10:00:00 - 10:59:59	83328	3.6%	23.15	28.1 GB	4.2%	8171.36
Jan 10 11:00:00 - 11:59:59	85580	3.7%	23.77	27.3 GB	4.1%	7952.92
Jan 10 12:00:00 - 12:59:59	87113	3.8%	24.20	26.5 GB	4.0%	7732.86
Jan 10 13:00:00 - 13:59:59	87584	3.8%	24.33	27.3 GB	4.1%	7962.33
Jan 10 14:00:00 - 14:59:59	81096	3.5%	22.53	25.1 GB	3.7%	7308.54
Jan 10 15:00:00 - 15:59:59	84840	3.7%	23.57	22.6 GB	3.4%	6587.42
Jan 10 16:00:00 - 16:59:59	90617	4.0%	25.17	23.8 GB	3.5%	6924.36
Jan 10 17:00:00 - 17:59:59	106529	4.7%	29.59	30.1 GB	4.5%	8768.67
Jan 10 18:00:00 - 18:59:59	91837	4.0%	25.51	26.2 GB	3.9%	7636.24
Jan 10 19:00:00 - 19:59:59	103959	4.5%	28.88	28.3 GB	4.2%	8241.32
Jan 10 20:00:00 - 20:59:59	82563	3.6%	22.93	21.8 GB	3.3%	6363.49
Jan 10 21:00:00 - 21:59:59	98704	4.3%	27.42	26.6 GB	4.0%	7755.56
Jan 10 22:00:00 - 22:59:59	108477	4.7%	30.13	31.7 GB	4.7%	9228.13
Jan 10 23:00:00 - 23:59:59	102255	4.5%	28.40	30.4 GB	4.5%	8856.90
Jan 11 00:00:00 - 00:59:59	108060	4.7%	30.02	26.5 GB	4.0%	7725.57
Jan 11 01:00:00 - 01:59:59	131153	5.7%	36.43	32.6 GB	4.9%	9493.40
Jan 11 02:00:00 - 02:59:59	119120	5.2%	33.09	31.9 GB	4.7%	9282.36
Jan 11 03:00:00 - 03:59:59	114512	5.0%	31.81	30.0 GB	4.5%	8729.54
Jan 11 04:00:00 - 04:15:03	29570	1.3%	32.75	7.6 GB	1.1%	8783.17
TOTAL: 23:59:59	2285286	100.0%	26.45	671.0 GB	100.0%	8143.22

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	32916	252	31972	2	690
2	news.task.gda.pl	1174	1008	39	1	126
3	news.nask.pl	1042	18	917	1	106
4	news.icm.edu.pl	7	0	1	0	6
TOTAL: 4		35139	1278	32929	4	928

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	97
mailing.freebsd.svn-ports-all	88
mailing.freebsd.svn-ports-head	86
mailing.freebsd.ports-bugs	57
mailing.openbsd.source-changes	54
alt.politics.scorched-earth	47
mailing.gcc.gcc-cvs	44
gmane.linux.alsa.devel	37
fa.freebsd.cvs-all	37
mailing.unix.spamassassin-talk	36
mailing.freebsd.svn-src-all	36
mailing.database.pgsql-general	35
mailing.openbsd.ports-changes	33
mailing.postfix.users	33
it.tlc.cellulari.android	33
mailing.openbsd.misc	30
it.comp.os.win.windows8	25
mailing.unix.samba-technical	24
mailing.freebsd.svn-src-head	24
alt.windows7.general	23
TOTAL: 83	1278

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	420
EMP (fsl)	96
Attached HTML file	91
EMP (phl)	52
EMP (md5)	27
HTML post	4
Scoring filter (10)	1
Scoring filter (9)	1
TOTAL: 8	692

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	58253	4436	30974	1990	9	76	7%	23:50:31
2	panorama	40124	4019	33239	778	0	0	10%	24:00:00
3	poznan	18330	116	14771	66	0	0	0%	24:00:00
4	task	2401	34	1305	3	0	2	1%	20:49:51
5	nask	14551	12	10190	0	0	9	0%	24:05:30
6	okapi	0	0	0	0	0	2695	0%	24:00:01
TOTAL: 6		133659	8617	90479	2837	9	2782	6%	140:45:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	235.0 MB	224.2 MB	459.1 MB	5.5 KB/s	73.2 KB	23:50:31
2	panorama	12.1 MB	2.7 MB	14.9 MB	0.2 KB/s	3.2 KB	24:00:00
3	poznan	306.8 KB	126.6 KB	433.4 KB	0.0 KB/s	2.4 KB	24:00:00
4	task	81.4 KB	7.3 KB	88.8 KB	0.0 KB/s	2.4 KB	20:49:51
5	nask	30.5 KB	0.0 KB	30.5 KB	0.0 KB/s	2.5 KB	24:05:30
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		247.5 MB	227.0 MB	474.5 MB	1.0 KB/s	42.4 KB	140:45:53

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	360	516.6 KB	288	0	0	00:00:31
2	web.man.lodz.pl	288	114	117.6 KB	51	0	0	00:00:28
3	212.191.78.190	305	37	69.8 KB	20	0	0	47:31:27
4	ip-176.105.135.22.tvsat364.lodz.pl	4	4	7.6 KB	3	0	0	01:41:10
5	staticline-31-182-72-119.toya.net.pl	3	3	7.2 KB	1	0	2	00:34:01
6	91-218-63-138.neotrix.info	6	1	1.5 KB	1	0	0	00:16:17
7	router2.cbmm.lodz.pl	2	1	1.4 KB	1	0	0	00:08:12
8	pc-vicki.man.lodz.pl	2	1	1.7 KB	1	0	0	01:00:10
9	kujawiak.man.lodz.pl	6	0	0.0 KB	0	6	0	00:00:01
TOTAL: 9		904	521	723.4 KB	366	6	2	77:06:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	360	516.6 KB	288	0	0	00:00:31
2	*.man.lodz.pl	680	115	119.2 KB	52	6	0	01:00:48
3	unresolved	307	37	69.8 KB	20	0	0	47:32:06
4	*.105.135.22.tvsat364.lodz.pl	4	4	7.6 KB	3	0	0	01:41:10
5	*.toya.net.pl	131	3	7.2 KB	1	0	2	00:34:35
6	*.cbmm.lodz.pl	2	1	1.4 KB	1	0	0	00:08:12
7	*.neotrix.info	6	1	1.5 KB	1	0	0	00:16:17
8	*.pa.nsw.optusnet.com.au	437	0	0.0 KB	0	0	0	00:42:01
9	*.wroclaw.mm.pl	48	0	0.0 KB	0	0	0	07:45:56
10	*.181.14.62.static.jazztel.es	16	0	0.0 KB	0	0	0	17:25:15
TOTAL: 10		1919	521	723.4 KB	366	6	2	77:06:54

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.752	2.896	47:31:27
117.181.14.62.static.jazztel.es	11.700	16.836	17:25:15
host-89-231-50-15.wroclaw.mm.pl	0.124	0.432	07:45:56
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.048	01:41:10
pc-vicki.man.lodz.pl	0.004	0.020	01:00:10
pa49-181-246-38.pa.nsw.optusnet.com.au	0.864	3.156	00:39:16
staticline-31-182-72-119.toya.net.pl	0.032	0.040	00:34:01
91-218-63-138.neotrix.info	0.092	0.080	00:16:17
router2.cbmm.lodz.pl	0.004	0.020	00:08:12
pa49-181-246-35.pa.nsw.optusnet.com.au	0.208	0.960	00:02:45
82.160.216.250	0.012	0.016	00:00:39
staticline41435.toya.net.pl	0.336	1.220	00:00:33
vegas.cm.p.lodz.pl	0.756	2.688	00:00:31
web.man.lodz.pl	0.728	2.548	00:00:28
mon.man.lodz.pl	0.616	2.520	00:00:05
david.man.lodz.pl	0.228	0.912	00:00:01
kujawiak.man.lodz.pl	0.040	0.064	00:00:01
TOTAL: 17	16.500	34.456	77:06:54

Curious NNRP server explorers [Top 20]:

System	Conn
pa49-181-246-38.pa.nsw.optusnet.com.au	338
mon.man.lodz.pl	288
staticline41435.toya.net.pl	128
pa49-181-246-35.pa.nsw.optusnet.com.au	99
david.man.lodz.pl	96
host-89-231-50-15.wroclaw.mm.pl	48
117.181.14.62.static.jazztel.es	16
82.160.216.250	2
TOTAL: 8	1015

NNRP unrecognized commands (by host):

System	Conn
82.160.216.250	3
TOTAL: 1	3

NNRP unrecognized commands (by command):

Command	Count
#032	1
#003	1
#004	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-vicki.man.lodz.pl	2	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
117.181.14.62.static.jazztel.es	1	1
pa49-181-246-38.pa.nsw.optusnet.com.au	1	0
staticline-31-182-72-119.toya.net.pl	1	0
TOTAL: 5	7	4

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	363	69.7%
2	pl	157	30.1%
3	lodz	1	0.2%
TOTAL: 3		521	100.0%

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

Newsgroup		Count
1	lodman.info	360
2	pl.comp.os.ms-windows.winnt	60
3	pl.comp.pecet	54
4	pl.rec.rowery	14
5	pl.regionalne.warszawa	10
6	pl.misc.kolej	9
7	pl.soc.prawo	4
8	lodman.test	3
9	pl.rec.gory	2
10	pl.sci.medycyna	1
11	lodz.ogloszenia	1
12	pl.soc.dzieci	1
13	pl.misc.dieta	1
14	pl.sci.chemia	1
TOTAL: 14		521

Daily Usenet report

Jan 10 04:15:04 -- Jan 11 04:15:03