Daily Usenet report: Dec 18 04:15:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Dec 18 13:22:25 newsfeed nnrpd[14278]: SERVER perl filter not defined
Dec 18 15:44:28 newsfeed nnrpd[18965]: SERVER perl filter not defined
Dec 18 18:13:33 newsfeed nnrpd[24520]: SERVER perl filter not defined
Dec 18 20:23:30 newsfeed nnrpd[28786]: SERVER perl filter not defined
Dec 18 22:01:40 newsfeed innfeed[10554]: icm:0 connect: Network is unreachable
Dec 18 22:09:47 newsfeed innfeed[10554]: icm:0 connect: Network is unreachable
Dec 19 00:10:04 newsfeed overchan[10555]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2195010	98.3%	249.3 MB	97.9%
innd	19710	0.9%	3.2 MB	1.3%
nnrpd	10712	0.5%	1.1 MB	0.4%
innfeed	7155	0.3%	1.1 MB	0.4%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	2232588	100.0%	254.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2185937	94.5%
Negative hits	85971	3.7%
Positive hits	41546	1.8%
Cache misses	272	0.0%
TOTAL: 4	2313726	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:23.014	0.0%	688	0.000	33.451	593.000
article cleanup	00:00:36.711	0.0%	2193874	0.002	0.017	0.032
article logging	00:01:07.731	0.1%	2194007	0.014	0.031	0.228
article parse	01:21:56.460	5.7%	157685538	0.008	0.031	0.087
article write	00:19:04.850	1.3%	2184387	0.263	0.524	0.962
artlog/artcncl	00:00:00.023	0.0%	685	0.000	0.034	1.000
artlog/artparse	00:00:00.000	0.0%	10	0.000	0.000	0.000
data move	00:08:34.005	0.6%	171561152	0.000	0.003	0.046
hisgrep/artcncl	00:00:01.151	0.0%	309	0.000	3.725	276.000
hishave/artcncl	00:00:03.060	0.0%	688	0.000	4.448	51.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:54:37.996	12.1%	2314217	0.013	4.528	11.708
history sync	00:00:00.009	0.0%	293	0.000	0.031	1.000
history write	04:32:09.216	18.9%	2186324	0.073	7.469	44.402
hiswrite/artcncl	00:00:02.481	0.0%	379	0.000	6.546	16.000
idle	07:13:00.767	30.1%	87306307	0.161	0.298	2.667
nntp read	01:02:26.398	4.3%	155972729	0.009	0.024	0.047
overview write	00:00:13.649	0.0%	2184387	0.000	0.006	0.029
perl filter	05:43:44.902	23.9%	2186314	5.455	9.434	21.369
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:28.676	0.3%	4406572	0.020	0.061	0.117
TOTAL: 23:59:47.961	23:22:31.099	97.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.132	0.0%	38608	0.000	0.003	0.167
article prepare	00:00:00.009	0.0%	29202	0.000	0.000	0.125
article read	00:00:05.034	0.0%	7094	0.000	0.710	205.000
backlog stats	00:00:00.235	0.0%	282178	0.000	0.001	0.021
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.226	0.0%	169833	0.000	0.013	1.000
data write	00:00:04.692	0.0%	138273	0.000	0.034	1.294
idle	23:58:46.975	100.0%	282178	0.000	305.931	15018.000
status file	00:00:00.890	0.0%	245	0.000	3.633	313.000
TOTAL: 23:59:08.486	23:59:00.193	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.830	0.0%	225	0.000	8.133	46.900
idle	52:45:42.396	100.0%	8022	0.000	23677.686	600141.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.435	0.0%	18344	0.000	0.024	1.900
readart	00:00:01.266	0.0%	484	0.000	2.616	53.667
TOTAL: 52:46:37.622	52:45:45.927	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	1186	2180198	2171716	1510	6972	99%	478:55:34
2	news.nask.pl	2	16920	6567	8954	1399	38%	23:52:23
3	news.task.gda.pl	13	30364	6529	22709	1126	21%	44:59:37
4	news.icm.edu.pl	39	391	51	324	16	13%	18:26:43
5	localhost	5	4	4	0	0	100%	00:00:01
TOTAL: 5		1245	2227877	2184867	33497	9513	98%	566:14:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	758.1 GB	557.4 MB	579.0 MB	759.2 GB	99%	365.4 KB
2	news.nask.pl	28.3 MB	4.0 MB	4.1 MB	36.4 MB	77%	4.7 KB
3	news.icm.edu.pl	16.5 MB	1.1 MB	1.7 MB	19.3 MB	85%	295.0 KB
4	news.task.gda.pl	14.3 MB	35.2 KB	3.4 MB	17.8 MB	80%	2.4 KB
5	localhost	4.5 KB	0.0 KB	0.0 KB	4.5 KB	100%	1.1 KB
TOTAL: 5		758.2 GB	562.5 MB	588.2 MB	759.3 GB	99%	362.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 18 04:15:02 - 04:59:59	60572	2.8%	22.45	23.6 GB	3.1%	9157.34
Dec 18 05:00:00 - 05:59:59	89545	4.1%	24.87	31.8 GB	4.2%	9269.26
Dec 18 06:00:00 - 06:59:59	91740	4.2%	25.48	33.0 GB	4.3%	9599.84
Dec 18 07:00:00 - 07:59:59	82470	3.8%	22.91	29.1 GB	3.8%	8483.36
Dec 18 08:00:00 - 08:59:59	87325	4.0%	24.26	30.1 GB	4.0%	8757.27
Dec 18 09:00:00 - 09:59:59	102037	4.7%	28.34	35.5 GB	4.7%	10350.06
Dec 18 10:00:00 - 10:59:59	92413	4.2%	25.67	28.0 GB	3.7%	8157.52
Dec 18 11:00:00 - 11:59:59	99018	4.5%	27.50	33.0 GB	4.4%	9606.66
Dec 18 12:00:00 - 12:59:59	88824	4.1%	24.67	29.9 GB	3.9%	8701.39
Dec 18 13:00:00 - 13:59:59	86462	4.0%	24.02	29.5 GB	3.9%	8598.98
Dec 18 14:00:00 - 14:59:59	98835	4.5%	27.45	35.1 GB	4.6%	10210.21
Dec 18 15:00:00 - 15:59:59	96153	4.4%	26.71	33.1 GB	4.4%	9634.41
Dec 18 16:00:00 - 16:59:59	91237	4.2%	25.34	30.3 GB	4.0%	8836.32
Dec 18 17:00:00 - 17:59:59	82076	3.8%	22.80	27.9 GB	3.7%	8119.99
Dec 18 18:00:00 - 18:59:59	95453	4.4%	26.51	33.3 GB	4.4%	9686.01
Dec 18 19:00:00 - 19:59:59	102636	4.7%	28.51	34.9 GB	4.6%	10157.56
Dec 18 20:00:00 - 20:59:59	100926	4.6%	28.04	34.3 GB	4.5%	9986.60
Dec 18 21:00:00 - 21:59:59	94793	4.3%	26.33	34.2 GB	4.5%	9955.49
Dec 18 22:00:00 - 22:59:59	100823	4.6%	28.01	35.6 GB	4.7%	10355.31
Dec 18 23:00:00 - 23:59:59	85125	3.9%	23.65	29.3 GB	3.9%	8542.50
Dec 19 00:00:00 - 00:59:59	64578	3.0%	17.94	22.6 GB	3.0%	6586.23
Dec 19 01:00:00 - 01:59:59	86417	4.0%	24.00	30.3 GB	4.0%	8828.17
Dec 19 02:00:00 - 02:59:59	98601	4.5%	27.39	35.6 GB	4.7%	10375.09
Dec 19 03:00:00 - 03:59:59	88091	4.0%	24.47	31.2 GB	4.1%	9076.18
Dec 19 04:00:00 - 04:15:03	18548	0.8%	20.54	6.7 GB	0.9%	7770.40
TOTAL: 24:00:01	2184698	100.0%	25.29	757.7 GB	100.0%	9195.16

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	7071	223	6165	0	683
2	news.nask.pl	1407	15	1367	1	24
3	news.task.gda.pl	1133	945	20	0	168
4	news.icm.edu.pl	16	0	5	0	11
TOTAL: 4		9627	1183	7557	1	886

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	122
mailing.freebsd.svn-ports-all	64
mailing.freebsd.svn-ports-head	61
alt.politics.scorched-earth	56
mailing.freebsd.ports-bugs	49
mailing.unix.samba	47
fa.freebsd.cvs-all	44
fr.usenet.abus.nocem	42
gmane.linux.alsa.devel	41
alt.windows7.general	41
mailing.freebsd.svn-src-all	41
mailing.freebsd.svn-src-head	35
mailing.freebsd.current	31
mailing.freebsd.ports	31
mozilla.dev.gaia	29
mailing.gcc.gcc-cvs	28
mozilla.dev.tree-management	27
it.comp.os.win.windows8	26
gnu.hurd.bug	24
mailing.database.pgsql-general	23
TOTAL: 88	1183

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	509
EMP (fsl)	129
Attached HTML file	94
EMP (md5)	5
HTML post	5
Scoring filter (12)	1
TOTAL: 6	743

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	39795	2531	34788	463	0	0	6%	23:59:26
2	icm	60697	2061	33135	2029	3	818	3%	24:00:05
3	task	1569	161	749	5	0	0	10%	21:30:17
4	poznan	15395	50	12899	21	0	0	0%	23:58:09
5	nask	16508	7	11597	0	0	0	0%	24:02:05
6	okapi	0	0	0	0	0	2659	0%	24:00:01
TOTAL: 6		133964	4810	93168	2518	3	3477	3%	141:30:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	16.5 MB	138.7 MB	155.2 MB	1.8 KB/s	38.8 KB	24:00:05
2	panorama	9.3 MB	1.8 MB	11.1 MB	0.1 KB/s	3.8 KB	23:59:26
3	task	326.9 KB	7.4 KB	334.3 KB	0.0 KB/s	2.0 KB	21:30:17
4	poznan	126.3 KB	38.2 KB	164.5 KB	0.0 KB/s	2.3 KB	23:58:09
5	nask	17.8 KB	0.0 KB	17.8 KB	0.0 KB/s	2.5 KB	24:02:05
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		26.2 MB	140.5 MB	166.7 MB	0.3 KB/s	23.3 KB	141:30:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	300	386.3 KB	288	0	0	00:00:14
2	212.191.78.190	320	89	164.2 KB	28	1	0	47:39:29
3	web.man.lodz.pl	288	58	47.0 KB	28	0	0	00:00:33
4	apn-95-40-28-161.dynamic.gprs.plus.pl	28	34	29.1 KB	4	0	4	00:36:40
5	ip-176.105.135.22.tvsat364.lodz.pl	3	2	2.4 KB	1	0	0	01:12:35
6	pc-tom.man.lodz.pl	2	1	1.1 KB	1	0	0	01:00:36
7	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	0	00:00:00
8	kujawiak.man.lodz.pl	1	0	0.0 KB	0	1	0	00:00:00
TOTAL: 8		932	484	630.1 KB	350	4	4	52:46:34

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	300	386.3 KB	288	0	0	00:00:14
2	unresolved	322	89	164.2 KB	28	1	0	47:39:37
3	*.man.lodz.pl	678	59	48.2 KB	29	3	0	01:01:17
4	*.dynamic.gprs.plus.pl	28	34	29.1 KB	4	0	4	00:36:40
5	*.105.135.22.tvsat364.lodz.pl	3	2	2.4 KB	1	0	0	01:12:35
6	*.retsat1.com.pl	1	0	0.0 KB	0	0	0	00:30:00
7	*.cbmm.lodz.pl	3	0	0.0 KB	0	0	0	00:00:10
8	*.dynamic.chello.pl	19	0	0.0 KB	0	0	0	01:26:20
9	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	0	00:16:04
10	*.181.14.62.static.jazztel.es	3	0	0.0 KB	0	0	0	00:03:20
11	*.toya.net.pl	98	0	0.0 KB	0	0	0	00:00:11
TOTAL: 11		1444	484	630.1 KB	350	4	4	52:46:34

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.696	5.748	47:39:29
89-76-202-175.dynamic.chello.pl	0.072	0.380	01:26:20
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.060	01:12:35
pc-tom.man.lodz.pl	0.004	0.036	01:00:36
apn-95-40-28-161.dynamic.gprs.plus.pl	0.136	0.480	00:36:40
terminal-3-200.retsat1.com.pl	0.008	0.024	00:30:00
ebt148.neoplus.adsl.tpnet.pl	0.008	0.012	00:16:04
219.181.14.62.static.jazztel.es	0.052	0.136	00:03:20
web.man.lodz.pl	0.604	5.676	00:00:33
vegas.cm.p.lodz.pl	0.664	5.604	00:00:14
staticline41435.toya.net.pl	0.216	1.688	00:00:11
router2.cbmm.lodz.pl	0.012	0.048	00:00:10
213.17.205.133	0.000	0.020	00:00:07
mon.man.lodz.pl	0.664	4.968	00:00:04
david.man.lodz.pl	0.188	1.604	00:00:01
83.238.117.139	0.008	0.012	00:00:00
isvcs01.man.lodz.pl	0.012	0.036	00:00:00
kujawiak.man.lodz.pl	0.004	0.024	00:00:00
TOTAL: 18	3.352	26.556	52:46:34

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	289
staticline41435.toya.net.pl	98
david.man.lodz.pl	96
89-76-202-175.dynamic.chello.pl	19
219.181.14.62.static.jazztel.es	3
router2.cbmm.lodz.pl	3
213.17.205.133	1
terminal-3-200.retsat1.com.pl	1
83.238.117.139	1
ebt148.neoplus.adsl.tpnet.pl	1
TOTAL: 10	512

NNRP unrecognized commands (by host):

System	Conn
89-76-202-175.dynamic.chello.pl	19
TOTAL: 1	19

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	19
TOTAL: 1	19

NNRP client timeouts [Top 20]:

System	Conn	Peer
89-76-202-175.dynamic.chello.pl	8	8
212.191.78.190	2	0
pc-tom.man.lodz.pl	2	0
219.181.14.62.static.jazztel.es	1	1
terminal-3-200.retsat1.com.pl	1	0
213.17.205.133	1	1
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 7	16	11

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	310	64.0%
2	pl	145	30.0%
3	lodz	27	5.6%
4	lodzman	2	0.4%
TOTAL: 4		484	100.0%

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

Newsgroup		Count
1	lodman.info	300
2	pl.misc.kolej	43
3	pl.comp.pecet	32
4	lodz.test	27
5	pl.comp.os.ms-windows.winnt	22
6	pl.soc.prawo	21
7	pl.regionalne.warszawa	15
8	lodman.test	10
9	pl.comp.os.linux	5
10	pl.regionalne.lodz	5
11	lodzman.test	2
12	pl.rec.gory	1
13	pl.rec.rowery	1
TOTAL: 13		484

Daily Usenet report

Dec 18 04:15:02 -- Dec 19 04:15:03