Daily Usenet report: Apr 25 04:15:03

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Apr 25 14:57:58 newsfeed nnrpd[19082]: SERVER perl filter not defined
Apr 25 15:40:11 newsfeed nnrpd[20494]: SERVER perl filter not defined
Apr 25 15:44:02 newsfeed nnrpd[20683]: SERVER perl filter not defined
Apr 25 16:22:13 newsfeed nnrpd[21884]: SERVER perl filter not defined
Apr 25 18:44:57 newsfeed nnrpd[26728]: SERVER perl filter not defined
Apr 25 18:56:12 newsfeed nnrpd[27043]: SERVER perl filter not defined
Apr 26 01:45:12 newsfeed innfeed[9863]: nask:0 connect: Network is unreachable
Apr 26 01:53:19 newsfeed innfeed[9863]: nask:0 connect: Network is unreachable

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1172018	97.7%	132.7 MB	97.1%
innd	12339	1.0%	2.1 MB	1.5%
nnrpd	7671	0.6%	816.6 KB	0.6%
innfeed	7101	0.6%	1.1 MB	0.8%
TOTAL: 4	1199129	100.0%	136.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1166360	93.4%
Negative hits	44377	3.6%
Positive hits	37714	3.0%
Cache misses	265	0.0%
TOTAL: 4	1248716	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.591	0.0%	238	0.000	36.097	583.000
article cleanup	00:00:20.546	0.0%	1170811	0.002	0.018	0.038
article logging	00:00:35.933	0.0%	1170987	0.003	0.031	0.389
article parse	00:57:24.919	4.0%	109753213	0.003	0.031	0.086
article write	00:12:01.248	0.8%	1165668	0.195	0.619	1.347
artlog/artcncl	00:00:00.004	0.0%	237	0.000	0.017	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:04:32.532	0.3%	117571114	0.000	0.002	0.140
hisgrep/artcncl	00:00:01.346	0.0%	211	0.000	6.379	240.000
hishave/artcncl	00:00:00.861	0.0%	238	0.000	3.618	432.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:58:18.136	8.2%	1249911	0.010	5.679	14.992
history sync	00:00:00.027	0.0%	291	0.000	0.093	12.000
history write	02:42:16.603	11.3%	1167475	0.181	8.340	18.601
hiswrite/artcncl	00:00:00.122	0.0%	27	0.000	4.519	43.000
idle	13:23:46.818	55.8%	78422582	0.236	0.615	2.686
nntp read	00:51:48.197	3.6%	108830209	0.005	0.029	0.062
overview write	00:00:08.181	0.0%	1165668	0.000	0.007	0.019
perl filter	03:06:31.532	13.0%	1167475	3.747	9.586	12.720
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:39.244	0.2%	2366299	0.028	0.067	0.177
TOTAL: 23:59:25.220	23:20:34.840	97.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.131	0.0%	35245	0.000	0.004	0.143
article prepare	00:00:00.005	0.0%	25296	0.000	0.000	0.125
article read	00:00:03.234	0.0%	6201	0.000	0.522	267.500
backlog stats	00:00:00.206	0.0%	271013	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.826	0.0%	159744	0.000	0.011	0.067
data write	00:00:05.253	0.0%	134339	0.000	0.039	1.000
idle	23:59:30.132	100.0%	271013	0.000	318.694	17629.000
status file	00:00:00.414	0.0%	227	0.000	1.824	30.000
TOTAL: 23:59:47.877	23:59:41.201	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	07:43:04.244	99.9%	5599	0.000	4962.358	450052.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.470	0.0%	13467	0.000	0.035	5.222
readart	00:00:01.819	0.0%	530	0.000	3.432	69.667
TOTAL: 07:43:40.620	07:43:06.533	99.9%	-	-	-	-

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	662	1160717	1157142	562	3013	99%	265:44:13
2	news.task.gda.pl	12	28545	5800	21312	1433	20%	48:20:49
3	news.nask.pl	3	17263	3449	13119	695	19%	23:53:43
4	localhost	7	6	6	0	0	100%	00:00:00
5	news.icm.edu.pl	50	271	2	247	22	0%	17:54:25
TOTAL: 5		734	1206802	1166399	35240	5163	96%	355:53:10

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	428.0 GB	64.8 MB	69.2 MB	428.1 GB	99%	386.9 KB
2	news.nask.pl	13.5 MB	1.9 MB	2.1 MB	17.6 MB	76%	4.3 KB
3	news.task.gda.pl	13.0 MB	73.3 KB	4.8 MB	17.9 MB	72%	2.5 KB
4	news.icm.edu.pl	43.1 KB	0.0 KB	408.8 KB	451.9 KB	9%	18.8 KB
5	localhost	10.6 KB	0.0 KB	0.0 KB	10.6 KB	100%	1.8 KB
TOTAL: 5		428.0 GB	66.8 MB	76.6 MB	428.1 GB	99%	383.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 25 04:15:03 - 04:59:59	50008	4.3%	18.54	19.3 GB	4.5%	7516.78
Apr 25 05:00:00 - 05:59:59	38362	3.3%	10.66	18.2 GB	4.2%	5286.75
Apr 25 06:00:00 - 06:59:59	53413	4.6%	14.84	21.9 GB	5.1%	6384.66
Apr 25 07:00:00 - 07:59:59	55534	4.8%	15.43	21.9 GB	5.1%	6373.35
Apr 25 08:00:00 - 08:59:59	43213	3.7%	12.00	16.8 GB	3.9%	4894.11
Apr 25 09:00:00 - 09:59:59	41002	3.5%	11.39	16.2 GB	3.8%	4719.46
Apr 25 10:00:00 - 10:59:59	37775	3.2%	10.49	15.6 GB	3.6%	4535.39
Apr 25 11:00:00 - 11:59:59	41361	3.5%	11.49	16.2 GB	3.8%	4719.51
Apr 25 12:00:00 - 12:59:59	36758	3.2%	10.21	13.9 GB	3.2%	4037.05
Apr 25 13:00:00 - 13:59:59	44742	3.8%	12.43	15.7 GB	3.7%	4582.37
Apr 25 14:00:00 - 14:59:59	53325	4.6%	14.81	14.7 GB	3.4%	4283.94
Apr 25 15:00:00 - 15:59:59	47717	4.1%	13.25	15.1 GB	3.5%	4404.45
Apr 25 16:00:00 - 16:59:59	44127	3.8%	12.26	15.8 GB	3.7%	4595.00
Apr 25 17:00:00 - 17:59:59	49107	4.2%	13.64	15.4 GB	3.6%	4491.15
Apr 25 18:00:00 - 18:59:59	43650	3.7%	12.12	15.6 GB	3.7%	4558.02
Apr 25 19:00:00 - 19:59:59	33267	2.9%	9.24	11.9 GB	2.8%	3469.93
Apr 25 20:00:00 - 20:59:59	34242	2.9%	9.51	11.8 GB	2.8%	3438.32
Apr 25 21:00:00 - 21:59:59	31723	2.7%	8.81	12.5 GB	2.9%	3629.84
Apr 25 22:00:00 - 22:59:59	34682	3.0%	9.63	13.0 GB	3.1%	3800.61
Apr 25 23:00:00 - 23:59:59	37602	3.2%	10.45	14.8 GB	3.5%	4317.43
Apr 26 00:00:00 - 00:59:59	55690	4.8%	15.47	20.0 GB	4.7%	5836.71
Apr 26 01:00:00 - 01:59:59	74765	6.4%	20.77	25.8 GB	6.0%	7511.62
Apr 26 02:00:00 - 02:59:59	82751	7.1%	22.99	29.0 GB	6.8%	8457.52
Apr 26 03:00:00 - 03:59:59	81944	7.0%	22.76	29.3 GB	6.9%	8537.92
Apr 26 04:00:00 - 04:15:03	19703	1.7%	21.82	7.2 GB	1.7%	8411.32
TOTAL: 24:00:00	1166463	100.0%	13.50	427.8 GB	100.0%	5191.93

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3161	178	2616	3	364
2	news.task.gda.pl	1414	1123	36	4	251
3	news.nask.pl	721	13	682	0	26
4	news.icm.edu.pl	22	0	0	0	22
TOTAL: 4		5318	1314	3334	7	663

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	135
mailing.freebsd.svn-ports-head	130
mailing.freebsd.svn-src-all	68
mailing.freebsd.ports-bugs	62
uk.railway	61
fa.freebsd.cvs-all	57
mailing.gcc.gcc-cvs	44
mailing.freebsd.svn-src-head	42
mailing.openbsd.source-changes	39
mailing.openbsd.ports-changes	37
gmane.linux.kernel.firewire.devel	37
alt.windows7.general	35
alt.politics.scorched-earth	33
24hoursupport.helpdesk	33
mozilla.dev.tree-management	33
mailing.unix.samba	30
mozilla.dev.platform	29
comp.mobile.android	26
it.tlc.cellulari.android	24
fr.usenet.abus.nocem	23
TOTAL: 74	1314

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	223
EMP (fsl)	212
EMP (md5)	36
Attached HTML file	7
HTML post	4
Scoring filter (8)	4
TOTAL: 6	486

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	57111	3093	29767	1775	4	7	5%	23:59:58
2	panorama	36101	1057	33735	221	0	0	2%	24:02:46
3	poznan	10357	49	8985	14	0	0	0%	23:59:40
4	task	2292	30	1180	1	0	0	1%	22:40:49
5	nask	22963	24	15154	0	0	266	0%	24:01:10
6	okapi	0	0	0	0	0	2746	0%	24:00:00
TOTAL: 6		128824	4253	88821	2011	4	3019	3%	142:44:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	345.6 MB	143.4 MB	489.0 MB	5.8 KB/s	102.9 KB	23:59:58
2	panorama	4.7 MB	808.9 KB	5.5 MB	0.1 KB/s	4.4 KB	24:02:46
3	poznan	128.5 KB	43.6 KB	172.1 KB	0.0 KB/s	2.7 KB	23:59:40
4	nask	65.3 KB	0.0 KB	65.3 KB	0.0 KB/s	2.7 KB	24:01:10
5	task	63.8 KB	1.3 KB	65.0 KB	0.0 KB/s	2.1 KB	22:40:49
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		350.6 MB	144.2 MB	494.8 MB	1.0 KB/s	80.9 KB	142:44:23

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	348	628.4 KB	288	0	00:00:13
2	web.man.lodz.pl	288	106	113.0 KB	48	0	00:00:23
3	lze200.lze.lodz.pl	2	53	262.6 KB	4	0	00:27:24
4	ip-176.105.135.22.tvsat364.lodz.pl	10	22	36.1 KB	5	1	02:01:13
5	staticline-31-183-28-38.toya.net.pl	8	1	1.1 KB	1	0	00:04:14
6	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 6		601	530	1.0 MB	346	6	07:43:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	348	628.4 KB	288	0	00:00:13
2	*.man.lodz.pl	673	106	113.0 KB	48	5	00:00:30
3	*.lze.lodz.pl	2	53	262.6 KB	4	0	00:27:24
4	*.105.135.22.tvsat364.lodz.pl	10	22	36.1 KB	5	1	02:01:13
5	*.toya.net.pl	104	1	1.1 KB	1	0	00:04:23
6	*.net.pulawy.pl	1	0	0.0 KB	0	0	00:03:20
7	*.pa.nsw.optusnet.com.au	32	0	0.0 KB	0	0	00:00:58
8	*.cbmm.lodz.pl	1	0	0.0 KB	0	0	00:00:01
9	*.wroclaw.mm.pl	32	0	0.0 KB	0	0	04:52:13
10	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:10:51
11	*.206.15.62.static.jazztel.es	2	0	0.0 KB	0	0	00:02:28
TOTAL: 11		1147	530	1.0 MB	346	6	07:43:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
host-89-231-50-15.wroclaw.mm.pl	0.088	0.348	04:52:13
ip-176.105.135.22.tvsat364.lodz.pl	0.032	0.112	02:01:13
lze200.lze.lodz.pl	0.012	0.044	00:27:24
eck90.neoplus.adsl.tpnet.pl	0.004	0.024	00:10:51
staticline-31-183-28-38.toya.net.pl	0.216	0.124	00:04:14
h232176.net.pulawy.pl	0.120	0.012	00:03:20
160.206.15.62.static.jazztel.es	0.044	0.096	00:02:28
pa49-181-247-107.pa.nsw.optusnet.com.au	0.052	0.336	00:00:58
web.man.lodz.pl	0.700	3.508	00:00:23
pc-skrzep2.cm.p.lodz.pl	0.588	3.392	00:00:13
staticline41435.toya.net.pl	0.180	1.080	00:00:09
mon.man.lodz.pl	0.624	3.092	00:00:04
router2.cbmm.lodz.pl	0.000	0.012	00:00:01
david.man.lodz.pl	0.204	0.972	00:00:01
isvcs01.man.lodz.pl	0.024	0.076	00:00:00
TOTAL: 15	2.888	13.228	07:43:38

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	96
david.man.lodz.pl	92
pa49-181-247-107.pa.nsw.optusnet.com.au	32
host-89-231-50-15.wroclaw.mm.pl	32
eck90.neoplus.adsl.tpnet.pl	2
160.206.15.62.static.jazztel.es	2
h232176.net.pulawy.pl	1
router2.cbmm.lodz.pl	1
TOTAL: 9	546

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	349	65.8%
2	pl	181	34.2%
TOTAL: 2		530	100.0%

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

Newsgroup		Count
1	lodman.info	348
2	pl.comp.pecet	72
3	pl.comp.os.ms-windows.winnt	38
4	pl.misc.telefonia.gsm	28
5	pl.soc.prawo	15
6	pl.comp.os.linux.sieci	12
7	pl.internet.polip	9
8	pl.soc.dzieci	7
9	lodman.test	1
TOTAL: 9		530

Daily Usenet report

Apr 25 04:15:03 -- Apr 26 04:15:03