Daily Usenet report: Aug 12 04:15:03

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Aug 12 08:02:21 newsfeed nnrpd[22055]: SERVER perl filter not defined
Aug 12 08:04:51 newsfeed nnrpd[22091]: SERVER perl filter not defined
Aug 12 08:12:08 newsfeed nnrpd[22361]: SERVER perl filter not defined
Aug 12 08:12:57 newsfeed nnrpd[22369]: SERVER perl filter not defined
Aug 12 09:54:28 newsfeed nnrpd[25536]: SERVER perl filter not defined
Aug 12 11:46:18 newsfeed nnrpd[29069]: SERVER perl filter not defined
Aug 12 15:25:00 newsfeed nnrpd[3555]: SERVER perl filter not defined
Aug 12 20:19:40 newsfeed nnrpd[12852]: SERVER perl filter not defined
Aug 12 20:20:36 newsfeed nnrpd[12896]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2329030	98.6%	264.0 MB	98.1%
innd	19400	0.8%	3.2 MB	1.2%
nnrpd	7306	0.3%	781.4 KB	0.3%
innfeed	6874	0.3%	1.0 MB	0.4%
TOTAL: 4	2362610	100.0%	269.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2323817	95.9%
Negative hits	63012	2.6%
Positive hits	35843	1.5%
Cache misses	183	0.0%
TOTAL: 4	2422855	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.712	0.0%	351	0.000	19.123	652.000
article cleanup	00:00:27.795	0.0%	2328545	0.002	0.012	0.024
article logging	00:01:07.963	0.1%	2328677	0.007	0.029	0.878
article parse	01:06:26.356	4.6%	118749935	0.008	0.034	0.079
article write	00:23:32.449	1.6%	2323505	0.257	0.608	1.885
artlog/artcncl	00:00:00.005	0.0%	342	0.000	0.015	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:02:12.417	0.2%	133506178	0.000	0.001	0.004
hisgrep/artcncl	00:00:01.014	0.0%	260	0.000	3.900	279.000
hishave/artcncl	00:00:01.484	0.0%	351	0.000	4.228	246.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:11:50.734	13.3%	2424078	0.491	4.748	16.219
history sync	00:00:00.010	0.0%	291	0.000	0.034	1.000
history write	04:28:21.949	18.6%	2325147	2.880	6.925	21.547
hiswrite/artcncl	00:00:00.827	0.0%	91	0.000	9.088	219.000
idle	08:28:31.163	35.3%	79860540	0.103	0.382	1.247
nntp read	00:41:43.532	2.9%	116881308	0.007	0.021	0.040
overview write	00:00:12.449	0.0%	2323505	0.000	0.005	0.013
perl filter	05:02:15.042	21.0%	2325145	3.629	7.800	10.370
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:31.597	0.2%	4692349	0.014	0.045	0.080
TOTAL: 24:00:00.767	23:30:23.498	97.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.161	0.0%	45341	0.000	0.004	0.143
article prepare	00:00:00.017	0.0%	70042	0.000	0.000	0.062
article read	00:00:01.661	0.0%	17241	0.000	0.096	1.000
backlog stats	00:00:00.210	0.0%	333627	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.930	0.0%	195382	0.000	0.010	1.000
data write	00:00:05.718	0.0%	171203	0.000	0.033	0.333
idle	23:59:23.072	100.0%	333627	0.000	258.861	15075.000
status file	00:00:01.720	0.0%	241	0.000	7.137	197.500
TOTAL: 23:59:40.698	23:59:34.489	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	30:25:40.520	99.9%	593429	0.000	184.589	600035.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:16.144	0.0%	1188854	0.000	0.014	1.333
readart	00:00:01.459	0.0%	518	0.000	2.817	64.909
TOTAL: 30:26:49.817	30:25:58.123	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	958	2318479	2313591	1546	3342	99%	394:00:38
2	news.task.gda.pl	18	27902	5482	21346	1074	19%	47:06:18
3	news.nask.pl	2	15411	4844	10001	566	31%	24:10:03
4	news.icm.edu.pl	43	275	10	162	103	3%	16:09:23
5	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 5		1031	2362076	2323936	33055	5085	98%	481:26:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	823.9 GB	20.8 MB	55.6 MB	824.0 GB	99%	372.9 KB
2	news.nask.pl	16.8 MB	1.4 MB	1.5 MB	19.8 MB	84%	3.7 KB
3	news.task.gda.pl	11.4 MB	39.0 KB	3.0 MB	14.5 MB	78%	2.3 KB
4	news.icm.edu.pl	784.0 KB	176.7 KB	5.4 MB	6.4 MB	12%	57.6 KB
5	localhost	16.7 KB	0.0 KB	0.0 KB	16.7 KB	100%	1.9 KB
TOTAL: 5		824.0 GB	22.5 MB	65.5 MB	824.1 GB	99%	371.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 12 04:15:03 - 04:59:59	74874	3.2%	27.76	25.7 GB	3.1%	9992.67
Aug 12 05:00:00 - 05:59:59	95034	4.1%	26.40	31.1 GB	3.8%	9068.55
Aug 12 06:00:00 - 06:59:59	86830	3.7%	24.12	33.8 GB	4.1%	9846.66
Aug 12 07:00:00 - 07:59:59	95376	4.1%	26.49	32.9 GB	4.0%	9572.84
Aug 12 08:00:00 - 08:59:59	96433	4.2%	26.79	34.0 GB	4.1%	9889.98
Aug 12 09:00:00 - 09:59:59	95520	4.1%	26.53	33.5 GB	4.1%	9746.19
Aug 12 10:00:00 - 10:59:59	107771	4.6%	29.94	35.0 GB	4.3%	10205.10
Aug 12 11:00:00 - 11:59:59	95769	4.1%	26.60	33.9 GB	4.1%	9881.89
Aug 12 12:00:00 - 12:59:59	94758	4.1%	26.32	33.6 GB	4.1%	9797.28
Aug 12 13:00:00 - 13:59:59	98089	4.2%	27.25	34.2 GB	4.2%	9975.07
Aug 12 14:00:00 - 14:59:59	101712	4.4%	28.25	36.9 GB	4.5%	10733.72
Aug 12 15:00:00 - 15:59:59	92767	4.0%	25.77	34.5 GB	4.2%	10039.95
Aug 12 16:00:00 - 16:59:59	102667	4.4%	28.52	35.1 GB	4.3%	10222.83
Aug 12 17:00:00 - 17:59:59	105116	4.5%	29.20	34.4 GB	4.2%	10017.49
Aug 12 18:00:00 - 18:59:59	91465	3.9%	25.41	30.8 GB	3.7%	8980.17
Aug 12 19:00:00 - 19:59:59	97874	4.2%	27.19	33.8 GB	4.1%	9853.58
Aug 12 20:00:00 - 20:59:59	85596	3.7%	23.78	32.3 GB	3.9%	9397.55
Aug 12 21:00:00 - 21:59:59	81526	3.5%	22.65	30.6 GB	3.7%	8905.18
Aug 12 22:00:00 - 22:59:59	92793	4.0%	25.78	34.1 GB	4.1%	9939.23
Aug 12 23:00:00 - 23:59:59	100175	4.3%	27.83	35.8 GB	4.3%	10413.90
Aug 13 00:00:00 - 00:59:59	86607	3.7%	24.06	31.8 GB	3.9%	9267.84
Aug 13 01:00:00 - 01:59:59	103155	4.4%	28.65	38.0 GB	4.6%	11079.41
Aug 13 02:00:00 - 02:59:59	110322	4.7%	30.64	40.2 GB	4.9%	11721.48
Aug 13 03:00:00 - 03:59:59	103509	4.5%	28.75	37.7 GB	4.6%	10986.76
Aug 13 04:00:00 - 04:15:03	27778	1.2%	30.76	9.6 GB	1.2%	11097.96
TOTAL: 24:00:00	2323516	100.0%	26.89	823.3 GB	100.0%	9992.19

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3451	218	2834	1	398
2	news.task.gda.pl	1043	831	25	0	187
3	news.nask.pl	575	17	534	0	24
4	news.icm.edu.pl	103	0	5	0	98
TOTAL: 4		5172	1066	3398	1	707

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	129
mailing.freebsd.svn-ports-all	43
fr.usenet.abus.nocem	42
mailing.freebsd.svn-ports-head	42
alt.windows7.general	38
mailing.openbsd.misc	37
mailing.www.horde-bugs	37
mozilla.dev.tree-management	35
gmane.linux.alsa.devel	33
mailing.unix.samba	33
mailing.unix.samba-technical	30
mailing.openbsd.ports-changes	30
mailing.unix.dovecot	28
mailing.gcc.gcc-cvs	23
mailing.openbsd.source-changes	23
mailing.freebsd.kde	23
mailing.freebsd.svn-src-all	21
24hoursupport.helpdesk	21
fa.freebsd.cvs-all	21
it.tlc.cellulari.android	21
TOTAL: 79	1066

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	286
EMP (fsl)	157
EMP (md5)	122
HTML post	8
TOTAL: 4	573

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	46714	3039	41523	766	0	0	6%	23:59:46
2	icm	57915	2749	27591	10605	7	8	4%	24:00:56
3	poznan	12254	89	10064	43	0	0	0%	23:59:23
4	task	1812	18	959	2	0	0	0%	21:49:47
5	nask	18553	13	12784	181	0	0	0%	24:06:12
6	okapi	0	0	0	0	0	2504	0%	24:00:00
TOTAL: 6		137248	5908	92921	11597	7	2512	4%	141:56:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	30.2 MB	2.2 GB	2.2 GB	27.0 KB/s	174.8 KB	24:00:56
2	panorama	8.4 MB	3.3 MB	11.7 MB	0.1 KB/s	3.1 KB	23:59:46
3	poznan	251.0 KB	74.9 KB	325.9 KB	0.0 KB/s	2.5 KB	23:59:23
4	task	49.2 KB	5.8 KB	55.0 KB	0.0 KB/s	2.7 KB	21:49:47
5	nask	29.0 KB	112.8 MB	112.8 MB	1.3 KB/s	595.3 KB	24:06:12
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		38.9 MB	2.3 GB	2.3 GB	4.8 KB/s	140.7 KB	141:56:04

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	376	675.0 KB	288	0	00:00:08
2	web.man.lodz.pl	288	90	77.4 KB	39	0	00:00:18
3	lze200.lze.lodz.pl	9	41	70.1 KB	7	0	01:33:11
4	212.191.196.1	2	11	69.1 KB	3	0	00:11:19
5	kujawiak.man.lodz.pl	9	0	0.0 KB	0	9	00:00:01
TOTAL: 5		596	518	891.7 KB	337	9	30:26:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	376	675.0 KB	288	0	00:00:08
2	*.man.lodz.pl	681	90	77.4 KB	39	9	00:00:30
3	*.lze.lodz.pl	9	41	70.1 KB	7	0	01:33:11
4	unresolved	3	11	69.1 KB	3	0	00:41:19
5	*.207.15.62.static.jazztel.es	3	0	0.0 KB	0	0	28:08:55
6	*.dsl.klmzmi.ameritech.net	1	0	0.0 KB	0	0	00:00:03
7	*.toya.net.pl	72	0	0.0 KB	0	0	00:02:40
TOTAL: 7		1057	518	891.7 KB	337	9	30:26:49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
190.207.15.62.static.jazztel.es	30.970	41.562	28:08:55
lze200.lze.lodz.pl	0.020	0.160	01:33:11
212.191.1.85	0.004	0.012	00:30:00
212.191.196.1	0.008	0.032	00:11:19
staticline41435.toya.net.pl	0.136	1.032	00:02:40
web.man.lodz.pl	0.472	4.116	00:00:18
vegas.cm.p.lodz.pl	0.600	4.180	00:00:08
f-oper-v100.man.lodz.pl	0.452	4.180	00:00:07
ppp-69-214-3-216.dsl.klmzmi.ameritech.net	0.000	0.008	00:00:03
david.man.lodz.pl	0.180	1.440	00:00:03
kujawiak.man.lodz.pl	0.052	0.156	00:00:01
?	0.004	0.012	00:00:00
TOTAL: 12	32.898	56.890	30:26:49

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	72
190.207.15.62.static.jazztel.es	3
ppp-69-214-3-216.dsl.klmzmi.ameritech.net	1
212.191.1.85	1
TOTAL: 6	461

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	0
190.207.15.62.static.jazztel.es	2	0
212.191.1.85	1	0
TOTAL: 3	5	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	376	72.6%
2	pl	142	27.4%
TOTAL: 2		518	100.0%

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

Newsgroup		Count
1	lodman.info	376
2	pl.comp.pecet	80
3	pl.comp.os.ms-windows.winnt	32
4	pl.regionalne.lodz	14
5	pl.comp.os.linux	12
6	pl.sci.filozofia	2
7	pl.misc.paranauki	2
TOTAL: 7		518

Daily Usenet report

Aug 12 04:15:03 -- Aug 13 04:15:03