Daily Usenet report: Jan 30 04:15:04

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jan 30 09:35:35 newsfeed nnrpd[29762]: SERVER perl filter not defined
Jan 30 10:04:45 newsfeed nnrpd[30566]: SERVER perl filter not defined
Jan 30 10:09:35 newsfeed nnrpd[30799]: SERVER perl filter not defined
Jan 30 17:16:33 newsfeed nnrpd[12504]: SERVER perl filter not defined
Jan 30 19:39:37 newsfeed nnrpd[17122]: SERVER perl filter not defined
Jan 30 19:42:33 newsfeed nnrpd[17192]: SERVER perl filter not defined
Jan 30 20:24:51 newsfeed nnrpd[18531]: SERVER perl filter not defined
Jan 30 22:00:04 newsfeed pgpverify[21801]: pgpverify: verification failed
Jan 31 00:52:54 newsfeed nnrpd[27438]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1375837	97.4%	157.3 MB	96.8%
innfeed	13851	1.0%	2.1 MB	1.3%
innd	12248	0.9%	2.1 MB	1.3%
nnrpd	10661	0.8%	1.1 MB	0.7%
controlchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1412599	100.0%	162.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1367200	93.8%
Negative hits	47164	3.2%
Positive hits	42398	2.9%
Cache misses	1323	0.1%
TOTAL: 4	1458085	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:12.610	0.0%	330	0.000	38.212	406.000
article cleanup	00:00:19.711	0.0%	1374800	0.003	0.014	0.037
article logging	00:00:42.074	0.0%	1375018	0.010	0.031	0.298
article parse	00:45:47.051	3.2%	120698107	0.010	0.023	0.070
article write	00:10:57.587	0.8%	1365994	0.245	0.481	1.476
artlog/artcncl	00:00:00.013	0.0%	312	0.000	0.042	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:01:56.549	0.1%	128667238	0.000	0.001	0.003
hisgrep/artcncl	00:00:03.288	0.0%	258	0.000	12.744	222.000
hishave/artcncl	00:00:01.991	0.0%	330	0.000	6.033	93.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:45:34.278	15.7%	1458979	0.739	9.277	18.189
history sync	00:00:00.253	0.0%	290	0.000	0.872	234.000
history write	04:15:44.998	17.8%	1368271	2.652	11.215	24.731
hiswrite/artcncl	00:00:00.484	0.0%	72	0.000	6.722	95.000
idle	10:37:05.840	44.3%	95492905	0.192	0.400	3.171
nntp read	00:43:55.109	3.1%	119728224	0.014	0.022	0.040
overview write	00:00:08.423	0.0%	1365994	0.000	0.006	0.020
perl filter	03:00:45.915	12.6%	1368269	4.801	7.927	11.640
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:49.698	0.2%	2773730	0.009	0.061	0.089
TOTAL: 23:59:42.699	23:26:05.872	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.763	0.0%	262979	0.000	0.003	0.095
article prepare	00:00:00.097	0.0%	228756	0.000	0.000	0.050
article read	00:05:17.280	0.4%	57396	0.000	5.528	217.000
backlog stats	00:00:00.633	0.0%	833783	0.000	0.001	1.000
callbacks	00:00:00.005	0.0%	459	0.000	0.011	0.053
data read	00:00:05.041	0.0%	423207	0.000	0.012	1.000
data write	00:00:31.755	0.0%	428686	0.000	0.074	4.000
idle	23:53:58.025	99.6%	833783	0.000	103.190	5133.000
status file	00:00:01.178	0.0%	236	0.000	4.992	132.667
TOTAL: 24:00:12.423	23:59:54.777	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	65:49:09.001	100.0%	43413	0.000	5458.020	210055.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.690	0.0%	89203	0.000	0.019	9.556
readart	00:00:01.507	0.0%	592	0.000	2.546	54.167
TOTAL: 65:49:49.357	65:49:12.198	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	235	1361986	1354574	2080	5332	99%	175:55:02
2	news.task.gda.pl	15	30963	6015	23576	1372	19%	45:45:34
3	news.nask.pl	2	19013	5900	11012	2101	31%	23:59:25
4	localhost	9	8	8	0	0	100%	00:00:01
5	news.icm.edu.pl	47	327	1	316	10	0%	16:49:36
TOTAL: 5		308	1412297	1366498	36984	8815	96%	262:29:38

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	387.2 GB	29.6 MB	70.6 MB	387.3 GB	99%	298.6 KB
2	news.nask.pl	31.6 MB	6.2 MB	6.7 MB	44.5 MB	71%	5.7 KB
3	news.task.gda.pl	12.5 MB	56.5 KB	3.9 MB	16.5 MB	75%	2.3 KB
4	news.icm.edu.pl	23.1 KB	34.4 KB	232.9 KB	290.3 KB	7%	26.4 KB
5	localhost	11.6 KB	0.0 KB	0.0 KB	11.6 KB	100%	1.5 KB
TOTAL: 5		387.2 GB	35.9 MB	81.5 MB	387.3 GB	99%	295.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 30 04:15:04 - 04:59:59	66643	4.9%	24.72	16.4 GB	4.2%	6367.10
Jan 30 05:00:00 - 05:59:59	77813	5.7%	21.61	21.8 GB	5.6%	6355.54
Jan 30 06:00:00 - 06:59:59	61487	4.5%	17.08	16.8 GB	4.3%	4896.71
Jan 30 07:00:00 - 07:59:59	41010	3.0%	11.39	15.1 GB	3.9%	4399.04
Jan 30 08:00:00 - 08:59:59	43654	3.2%	12.13	17.0 GB	4.4%	4940.85
Jan 30 09:00:00 - 09:59:59	39552	2.9%	10.99	15.1 GB	3.9%	4401.85
Jan 30 10:00:00 - 10:59:59	49449	3.6%	13.74	15.6 GB	4.0%	4530.79
Jan 30 11:00:00 - 11:59:59	73447	5.4%	20.40	18.7 GB	4.8%	5442.89
Jan 30 12:00:00 - 12:59:59	49590	3.6%	13.78	17.7 GB	4.6%	5144.70
Jan 30 13:00:00 - 13:59:59	45504	3.3%	12.64	16.2 GB	4.2%	4724.87
Jan 30 14:00:00 - 14:59:59	50758	3.7%	14.10	17.9 GB	4.6%	5203.56
Jan 30 15:00:00 - 15:59:59	45348	3.3%	12.60	16.4 GB	4.2%	4766.22
Jan 30 16:00:00 - 16:59:59	42968	3.1%	11.94	10.7 GB	2.8%	3115.38
Jan 30 17:00:00 - 17:59:59	45395	3.3%	12.61	9.1 GB	2.4%	2650.06
Jan 30 18:00:00 - 18:59:59	48921	3.6%	13.59	9.0 GB	2.3%	2607.18
Jan 30 19:00:00 - 19:59:59	44316	3.2%	12.31	9.0 GB	2.3%	2616.77
Jan 30 20:00:00 - 20:59:59	41634	3.0%	11.56	9.3 GB	2.4%	2704.06
Jan 30 21:00:00 - 21:59:59	32650	2.4%	9.07	9.5 GB	2.5%	2763.91
Jan 30 22:00:00 - 22:59:59	33840	2.5%	9.40	9.4 GB	2.4%	2747.95
Jan 30 23:00:00 - 23:59:59	37002	2.7%	10.28	9.3 GB	2.4%	2709.26
Jan 31 00:00:00 - 00:59:59	72856	5.3%	20.24	18.5 GB	4.8%	5384.43
Jan 31 01:00:00 - 01:59:59	101449	7.4%	28.18	26.2 GB	6.8%	7642.05
Jan 31 02:00:00 - 02:59:59	107821	7.9%	29.95	28.4 GB	7.3%	8279.10
Jan 31 03:00:00 - 03:59:59	90642	6.6%	25.18	27.4 GB	7.1%	7984.80
Jan 31 04:00:00 - 04:15:03	22755	1.7%	25.20	6.8 GB	1.8%	7899.17
TOTAL: 23:59:59	1366504	100.0%	15.82	387.1 GB	100.0%	4698.45

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5519	203	4424	4	888
2	news.nask.pl	2128	10	2074	7	37
3	news.task.gda.pl	1363	1084	29	0	250
4	news.icm.edu.pl	11	0	2	0	9
TOTAL: 4		9021	1297	6529	11	1184

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	120
mailing.freebsd.svn-ports-head	119
mailing.freebsd.ports-bugs	62
mailing.unix.samba	57
alt.windows7.general	50
mailing.openbsd.source-changes	48
alt.politics.scorched-earth	46
gmane.linux.alsa.devel	42
it.tlc.cellulari.android	39
uk.railway	37
mailing.postfix.users	35
mailing.gcc.gcc-cvs	34
mozilla.dev.tree-management	34
mailing.unix.samba-technical	31
mailing.freebsd.ports	26
24hoursupport.helpdesk	26
fa.freebsd.cvs-all	25
mailing.database.pgsql-general	24
mailing.freebsd.svn-src-all	24
uk.rec.cycling.moderated	23
TOTAL: 85	1297

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

Groups	Count
pl.sci.matematyka	6
pl.sci.historia	5
TOTAL: 2	11

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	645
EMP (fsl)	216
Attached HTML file	78
EMP (md5)	16
HTML post	7
Scoring filter (8)	1
Scoring filter (12)	1
TOTAL: 7	964

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	265773	36208	206835	17942	488	13	13%	23:59:57
2	panorama	43114	2192	39145	373	2	0	5%	24:01:30
3	poznan	13974	27	11699	15	0	0	0%	24:02:32
4	nask	20345	14	13941	0	0	0	0%	24:00:27
5	task	2315	5	1231	5	0	5	0%	21:39:01
6	okapi	0	0	0	0	0	2669	0%	24:00:01
TOTAL: 6		345521	38446	272851	18335	490	2687	11%	141:43:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	6.0 GB	2.4 GB	8.4 GB	101.8 KB/s	162.3 KB	23:59:57
2	panorama	13.2 MB	1.3 MB	14.5 MB	0.2 KB/s	5.8 KB	24:01:30
3	poznan	195.0 KB	25.1 KB	220.1 KB	0.0 KB/s	5.2 KB	24:02:32
4	task	98.6 KB	13.1 KB	111.7 KB	0.0 KB/s	11.2 KB	21:39:01
5	nask	33.0 KB	0.0 KB	33.0 KB	0.0 KB/s	2.4 KB	24:00:27
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		6.0 GB	2.4 GB	8.4 GB	17.3 KB/s	155.1 KB	141:43:28

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	360	533.7 KB	288	0	00:00:15
2	web.man.lodz.pl	288	150	155.4 KB	64	0	00:00:24
3	212.191.78.190	323	72	135.4 KB	35	2	47:23:08
4	078088067110.sieradz.vectranet.pl	4	6	8.8 KB	1	0	00:05:37
5	router2.cbmm.lodz.pl	1	4	7.4 KB	1	0	00:06:38
6	kujawiak.man.lodz.pl	6	0	0.0 KB	0	6	00:00:02
TOTAL: 6		910	592	840.6 KB	389	8	65:49:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	360	533.7 KB	288	0	00:00:15
2	*.man.lodz.pl	678	150	155.4 KB	64	6	00:00:33
3	unresolved	323	72	135.4 KB	35	2	47:23:08
4	*.sieradz.vectranet.pl	4	6	8.8 KB	1	0	00:05:37
5	*.cbmm.lodz.pl	1	4	7.4 KB	1	0	00:06:38
6	*.wroclaw.mm.pl	92	0	0.0 KB	0	0	15:20:41
7	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:23
8	*.206.15.62.static.jazztel.es	2	0	0.0 KB	0	0	02:52:23
9	*.toya.net.pl	27	0	0.0 KB	0	0	00:00:07
TOTAL: 9		1416	592	840.6 KB	389	8	65:49:48

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.784	4.612	47:23:08
host-89-231-50-15.wroclaw.mm.pl	0.268	1.220	15:20:41
241.206.15.62.static.jazztel.es	2.212	2.232	02:52:23
router2.cbmm.lodz.pl	0.004	0.016	00:06:38
078088067110.sieradz.vectranet.pl	0.012	0.056	00:05:37
web.man.lodz.pl	0.572	4.216	00:00:24
eby12.neoplus.adsl.tpnet.pl	0.008	0.008	00:00:23
vegas.cm.p.lodz.pl	0.636	4.176	00:00:15
staticline41435.toya.net.pl	0.080	0.364	00:00:07
mon.man.lodz.pl	0.684	4.008	00:00:04
kujawiak.man.lodz.pl	0.036	0.092	00:00:02
david.man.lodz.pl	0.196	1.340	00:00:01
TOTAL: 12	5.492	22.340	65:49:48

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
host-89-231-50-15.wroclaw.mm.pl	92
staticline41435.toya.net.pl	27
241.206.15.62.static.jazztel.es	2
eby12.neoplus.adsl.tpnet.pl	1
TOTAL: 6	506

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	366	61.8%
2	pl	226	38.2%
TOTAL: 2		592	100.0%

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

Newsgroup		Count
1	lodman.info	360
2	pl.comp.pecet	92
3	pl.comp.os.ms-windows.winnt	58
4	pl.misc.kolej	43
5	pl.comp.os.linux	14
6	pl.soc.prawo	8
7	pl.regionalne.lodz	7
8	lodman.test	6
9	pl.regionalne.warszawa	2
10	pl.rec.gory	1
11	pl.rec.rowery	1
TOTAL: 11		592

Daily Usenet report

Jan 30 04:15:04 -- Jan 31 04:15:03