Daily Usenet report: Feb 5 04:15:04

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Feb 5 10:51:37 newsfeed nnrpd[6528]: SERVER perl filter not defined
Feb 5 11:36:22 newsfeed nnrpd[8179]: SERVER perl filter not defined
Feb 5 11:43:08 newsfeed nnrpd[8414]: SERVER perl filter not defined
Feb 5 22:00:04 newsfeed pgpverify[28517]: pgpverify: verification failed
Feb 6 04:15:03 newsfeed nnrpd[8600]: 212.191.78.190 rejected Flushing log and syslog files
Feb 6 04:15:03 newsfeed nnrpd[8599]: 212.191.78.190 rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1323673	97.6%	152.4 MB	97.1%
innd	14145	1.0%	2.3 MB	1.5%
nnrpd	11330	0.8%	1.1 MB	0.7%
innfeed	7020	0.5%	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	1356170	100.0%	156.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1317825	91.3%
Negative hits	80796	5.6%
Positive hits	45197	3.1%
Cache misses	251	0.0%
TOTAL: 4	1444069	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:13.979	0.0%	478	0.000	29.245	597.000
article cleanup	00:00:19.582	0.0%	1322606	0.001	0.015	0.035
article logging	00:00:43.426	0.1%	1322781	0.008	0.033	0.608
article parse	00:49:56.641	3.5%	147508179	0.004	0.020	0.085
article write	00:12:31.021	0.9%	1315495	0.237	0.571	2.025
artlog/artcncl	00:00:00.012	0.0%	460	0.000	0.026	1.000
artlog/artparse	00:00:00.002	0.0%	12	0.000	0.167	1.000
data move	00:02:09.539	0.1%	156316593	0.000	0.001	0.003
hisgrep/artcncl	00:00:03.007	0.0%	371	0.000	8.105	231.000
hishave/artcncl	00:00:02.989	0.0%	478	0.000	6.253	280.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:58:10.750	16.5%	1444317	1.779	9.894	15.407
history sync	00:00:00.017	0.0%	291	0.000	0.058	1.000
history write	04:17:01.813	17.9%	1318194	2.223	11.699	33.326
hiswrite/artcncl	00:00:01.183	0.0%	107	0.000	11.056	185.000
idle	10:05:12.408	42.0%	105423065	0.120	0.344	0.853
nntp read	00:47:39.716	3.3%	146542860	0.005	0.020	0.039
overview write	00:00:08.159	0.0%	1315495	0.000	0.006	0.019
perl filter	03:03:03.118	12.7%	1318182	3.777	8.332	10.536
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:37.963	0.2%	2677128	0.005	0.059	0.098
TOTAL: 23:59:44.938	23:19:55.325	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.167	0.0%	46158	0.000	0.004	0.143
article prepare	00:00:00.022	0.0%	58590	0.000	0.000	0.062
article read	00:00:01.397	0.0%	14225	0.000	0.098	1.800
backlog stats	00:00:00.268	0.0%	343921	0.000	0.001	0.027
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.387	0.0%	206826	0.000	0.012	1.000
data write	00:00:07.269	0.0%	175782	0.000	0.041	2.000
idle	24:00:27.886	100.0%	343921	0.000	251.302	30097.000
status file	00:00:00.611	0.0%	236	0.000	2.589	148.000
TOTAL: 24:00:47.216	24:00:40.007	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	62:05:22.648	100.0%	7543	0.000	29633.123	420354.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.213	0.0%	17845	0.000	0.124	31.444
readart	00:00:04.615	0.0%	663	0.000	6.961	167.000
TOTAL: 62:06:16.851	62:05:29.476	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

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	852	1308879	1302020	3009	3850	99%	309:17:11
2	news.task.gda.pl	11	33788	6632	25380	1776	19%	46:50:59
3	news.nask.pl	2	20001	6384	12157	1460	31%	23:57:54
4	news.icm.edu.pl	49	304	8	278	18	2%	18:26:30
5	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 5		918	1362975	1315047	40824	7104	96%	398:32:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	460.6 GB	17.8 MB	31.1 MB	460.7 GB	99%	369.9 KB
2	news.nask.pl	31.0 MB	4.9 MB	4.9 MB	40.8 MB	75%	5.3 KB
3	news.task.gda.pl	14.7 MB	79.4 KB	5.4 MB	20.2 MB	72%	2.5 KB
4	news.icm.edu.pl	373.2 KB	111.7 KB	471.1 KB	956.0 KB	39%	36.8 KB
5	localhost	4.2 KB	0.0 KB	0.0 KB	4.2 KB	100%	1.4 KB
TOTAL: 5		460.7 GB	22.9 MB	42.0 MB	460.7 GB	99%	365.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 5 04:15:04 - 04:59:59	57944	4.4%	21.49	22.6 GB	4.9%	8776.41
Feb 5 05:00:00 - 05:59:59	69227	5.3%	19.23	27.2 GB	5.9%	7920.77
Feb 5 06:00:00 - 06:59:59	74202	5.6%	20.61	29.8 GB	6.5%	8675.66
Feb 5 07:00:00 - 07:59:59	68950	5.2%	19.15	28.1 GB	6.1%	8180.59
Feb 5 08:00:00 - 08:59:59	67048	5.1%	18.62	25.9 GB	5.6%	7535.69
Feb 5 09:00:00 - 09:59:59	65963	5.0%	18.32	23.6 GB	5.1%	6887.47
Feb 5 10:00:00 - 10:59:59	51471	3.9%	14.30	18.7 GB	4.1%	5451.74
Feb 5 11:00:00 - 11:59:59	40490	3.1%	11.25	15.0 GB	3.3%	4362.14
Feb 5 12:00:00 - 12:59:59	39700	3.0%	11.03	13.1 GB	2.8%	3814.45
Feb 5 13:00:00 - 13:59:59	35961	2.7%	9.99	12.3 GB	2.7%	3585.22
Feb 5 14:00:00 - 14:59:59	40955	3.1%	11.38	14.6 GB	3.2%	4243.87
Feb 5 15:00:00 - 15:59:59	38667	2.9%	10.74	13.6 GB	3.0%	3974.55
Feb 5 16:00:00 - 16:59:59	46754	3.6%	12.99	15.4 GB	3.3%	4477.66
Feb 5 17:00:00 - 17:59:59	39028	3.0%	10.84	13.9 GB	3.0%	4035.76
Feb 5 18:00:00 - 18:59:59	38543	2.9%	10.71	13.7 GB	3.0%	4004.83
Feb 5 19:00:00 - 19:59:59	41039	3.1%	11.40	14.3 GB	3.1%	4162.83
Feb 5 20:00:00 - 20:59:59	34460	2.6%	9.57	10.7 GB	2.3%	3118.59
Feb 5 21:00:00 - 21:59:59	35369	2.7%	9.82	11.3 GB	2.5%	3290.71
Feb 5 22:00:00 - 22:59:59	41095	3.1%	11.42	13.2 GB	2.9%	3843.35
Feb 5 23:00:00 - 23:59:59	40671	3.1%	11.30	14.2 GB	3.1%	4148.17
Feb 6 00:00:00 - 00:59:59	67146	5.1%	18.65	22.1 GB	4.8%	6430.92
Feb 6 01:00:00 - 01:59:59	91413	6.9%	25.39	29.1 GB	6.3%	8485.77
Feb 6 02:00:00 - 02:59:59	82943	6.3%	23.04	27.4 GB	5.9%	7970.43
Feb 6 03:00:00 - 03:59:59	85330	6.5%	23.70	25.0 GB	5.4%	7283.51
Feb 6 04:00:00 - 04:15:04	21539	1.6%	23.83	6.0 GB	1.3%	6984.54
TOTAL: 24:00:00	1315908	100.0%	15.23	460.8 GB	100.0%	5592.13

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3996	377	2913	3	703
2	news.task.gda.pl	1775	1515	46	0	214
3	news.nask.pl	1516	6	1470	9	31
4	news.icm.edu.pl	18	0	2	0	16
TOTAL: 4		7305	1898	4431	12	964

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	325
mailing.freebsd.svn-ports-head	319
uk.railway	197
mozilla.dev.tree-management	72
fr.usenet.abus.nocem	67
mailing.freebsd.ports-bugs	66
24hoursupport.helpdesk	41
mailing.freebsd.ports	40
mozilla.dev.gaia	36
mailing.freebsd.svn-src-all	36
fa.freebsd.cvs-all	36
mailing.freebsd.svn-src-head	36
alt.windows7.general	34
it.tlc.cellulari.android	34
mailing.gcc.gcc-cvs	32
it.comp.os.win.windows8	31
gmane.linux.alsa.devel	30
mailing.unix.samba	30
mailing.database.pgsql-general	27
alt.politics.scorched-earth	24
TOTAL: 83	1898

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	582
EMP (fsl)	141
EMP (md5)	36
EMP (phl)	10
HTML post	5
Attached HTML file	1
Scoring filter (12)	1
Scoring filter (8)	1
TOTAL: 8	777

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	67423	8846	34074	2901	11	0	13%	23:53:17
2	panorama	47231	2407	43375	389	0	0	5%	24:01:29
3	poznan	14976	19	12724	15	0	0	0%	24:02:33
4	task	2021	14	1077	2	0	0	0%	22:08:35
5	nask	22677	9	15916	0	0	0	0%	24:00:54
6	okapi	0	0	0	0	0	2994	0%	24:00:02
TOTAL: 6		154328	11295	107166	3307	11	2994	7%	142:06:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	905.0 MB	290.0 MB	1.2 GB	14.2 KB/s	104.2 KB	23:53:17
2	panorama	14.5 MB	1.7 MB	16.2 MB	0.2 KB/s	5.9 KB	24:01:29
3	poznan	159.4 KB	41.5 KB	200.9 KB	0.0 KB/s	5.9 KB	24:02:33
4	task	33.2 KB	94.0 KB	127.2 KB	0.0 KB/s	8.0 KB	22:08:35
5	nask	29.2 KB	0.0 KB	29.2 KB	0.0 KB/s	3.2 KB	24:00:54
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		919.8 MB	291.8 MB	1.2 GB	2.4 KB/s	85.0 KB	142:06:50

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	vegas.cm.p.lodz.pl	288	312	458.5 KB	288	0	00:00:16
2	lze200.lze.lodz.pl	11	166	315.5 KB	12	0	02:24:50
3	web.man.lodz.pl	288	128	131.0 KB	58	0	00:00:26
4	ip-176.105.135.22.tvsat364.lodz.pl	3	25	45.1 KB	3	0	01:02:31
5	212.191.78.190	304	20	42.8 KB	14	0	48:00:48
6	router2.cbmm.lodz.pl	2	8	90.5 KB	4	1	00:04:45
7	proxy.filmschool.lodz.pl	3	2	4.5 KB	2	0	00:36:56
8	ech43.neoplus.adsl.tpnet.pl	7	2	2.8 KB	1	0	00:20:33
9	kujawiak.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 9		908	663	1.1 MB	382	3	62:06:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	312	458.5 KB	288	0	00:00:16
2	*.lze.lodz.pl	11	166	315.5 KB	12	0	02:24:50
3	*.man.lodz.pl	674	128	131.0 KB	58	2	00:00:33
4	*.105.135.22.tvsat364.lodz.pl	3	25	45.1 KB	3	0	01:02:31
5	unresolved	308	20	42.8 KB	14	0	48:01:03
6	*.cbmm.lodz.pl	2	8	90.5 KB	4	1	00:04:45
7	*.filmschool.lodz.pl	3	2	4.5 KB	2	0	00:36:56
8	*.neoplus.adsl.tpnet.pl	7	2	2.8 KB	1	0	00:20:33
9	*.pa.nsw.optusnet.com.au	93	0	0.0 KB	0	0	00:19:54
10	*.4web.pl	56	0	0.0 KB	0	0	09:13:53
11	*.206.15.62.static.jazztel.es	2	0	0.0 KB	0	0	00:00:19
12	*.toya.net.pl	135	0	0.0 KB	0	0	00:00:35
TOTAL: 12		1582	663	1.1 MB	382	3	62:06:15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.780	4.344	48:00:48
net133-43.4web.pl	0.144	0.800	09:13:53
lze200.lze.lodz.pl	0.056	0.236	02:24:50
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.072	01:02:31
proxy.filmschool.lodz.pl	0.004	0.048	00:36:56
ech43.neoplus.adsl.tpnet.pl	0.016	0.104	00:20:33
pa49-181-247-17.pa.nsw.optusnet.com.au	0.224	1.260	00:19:54
router2.cbmm.lodz.pl	0.008	0.044	00:04:45
staticline41435.toya.net.pl	0.276	1.932	00:00:35
web.man.lodz.pl	0.668	4.304	00:00:26
241.206.15.62.static.jazztel.es	0.016	0.024	00:00:19
vegas.cm.p.lodz.pl	0.684	5.152	00:00:16
212.51.203.199	0.008	0.024	00:00:11
mon.man.lodz.pl	0.564	3.940	00:00:04
78.133.64.131	0.008	0.028	00:00:03
david.man.lodz.pl	0.212	1.452	00:00:02
kujawiak.man.lodz.pl	0.000	0.044	00:00:00
?	0.000	0.012	00:00:00
TOTAL: 18	3.668	23.820	62:06:15

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	135
david.man.lodz.pl	96
pa49-181-247-17.pa.nsw.optusnet.com.au	93
net133-43.4web.pl	56
241.206.15.62.static.jazztel.es	2
78.133.64.131	2
212.51.203.199	2
TOTAL: 8	674

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
pa49-181-247-17.pa.nsw.optusnet.com.au	3	1
lze200.lze.lodz.pl	3	0
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 3	7	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	349	52.6%
2	lodman	314	47.4%
TOTAL: 2		663	100.0%

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

Newsgroup		Count
1	lodman.info	314
2	pl.comp.pecet	98
3	pl.comp.os.ms-windows.winnt	83
4	pl.misc.telefonia.gsm	74
5	pl.rec.kuchnia	34
6	pl.soc.prawo	23
7	pl.misc.kolej	14
8	pl.regionalne.lodz	11
9	pl.rec.rowery	3
10	pl.sci.chemia	3
11	pl.rec.dom	2
12	pl.rec.foto.cyfrowa	2
13	pl.comp.os.linux.sieci	2
TOTAL: 13		663

Daily Usenet report

Feb 5 04:15:04 -- Feb 6 04:15:04