Daily Usenet report: Sep 1 04:15:03

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Sep 1 10:53:11 newsfeed nnrpd[13615]: SERVER perl filter not defined
Sep 1 11:07:51 newsfeed nnrpd[14004]: SERVER perl filter not defined
Sep 1 11:33:43 newsfeed nnrpd[14887]: SERVER perl filter not defined
Sep 1 11:34:58 newsfeed nnrpd[14888]: SERVER perl filter not defined
Sep 1 18:00:21 newsfeed nnrpd[26966]: SERVER perl filter not defined
Sep 1 18:00:50 newsfeed nnrpd[26969]: SERVER perl filter not defined
Sep 2 01:32:16 newsfeed pgpverify[8790]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2114768	98.4%	241.0 MB	97.9%
innd	20457	1.0%	3.3 MB	1.3%
nnrpd	7065	0.3%	742.2 KB	0.3%
innfeed	6899	0.3%	1.0 MB	0.4%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2149193	100.0%	246.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2109642	94.8%
Negative hits	78917	3.5%
Positive hits	35893	1.6%
Cache misses	226	0.0%
TOTAL: 4	2224678	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.432	0.0%	360	0.000	26.200	583.000
article cleanup	00:00:28.772	0.0%	2115169	0.002	0.014	0.026
article logging	00:01:07.011	0.1%	2115324	0.011	0.032	0.668
article parse	01:18:39.377	5.5%	84718788	0.021	0.056	0.098
article write	00:26:36.169	1.8%	2108213	0.465	0.757	3.086
artlog/artcncl	00:00:00.002	0.0%	351	0.000	0.006	0.500
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:02:26.373	0.2%	97930715	0.000	0.001	0.006
hisgrep/artcncl	00:00:01.382	0.0%	279	0.000	4.953	252.000
hishave/artcncl	00:00:01.670	0.0%	360	0.000	4.639	265.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:41:59.703	15.4%	2226853	0.074	5.981	18.785
history sync	00:00:00.010	0.0%	292	0.000	0.034	1.000
history write	04:57:50.164	20.7%	2111633	0.041	8.463	28.425
hiswrite/artcncl	00:00:00.313	0.0%	81	0.000	3.864	26.000
idle	06:26:05.874	26.8%	48150929	0.134	0.481	1.729
nntp read	00:43:30.961	3.0%	82988412	0.014	0.031	0.046
overview write	00:00:12.583	0.0%	2108213	0.000	0.006	0.015
perl filter	05:51:23.500	24.4%	2111631	6.454	9.984	12.105
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:32.643	0.2%	4267497	0.022	0.050	0.071
TOTAL: 24:00:46.486	23:34:05.939	98.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.199	0.0%	51076	0.000	0.004	1.000
article prepare	00:00:00.021	0.0%	87734	0.000	0.000	0.036
article read	00:00:05.312	0.0%	21554	0.000	0.246	66.650
backlog stats	00:00:00.248	0.0%	353185	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.452	0.0%	214911	0.000	0.011	1.000
data write	00:00:06.701	0.0%	178666	0.000	0.038	1.000
idle	23:59:55.769	100.0%	353185	0.000	244.619	6018.000
status file	00:00:00.804	0.0%	241	0.000	3.336	103.000
TOTAL: 24:00:19.004	24:00:11.506	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.336	0.0%	68	4.941	4.941	4.941
idle	03:01:19.679	99.7%	58912	0.000	184.677	122624.800
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.828	0.0%	119624	0.000	0.015	1.667
readart	00:00:00.425	0.0%	438	0.000	0.970	52.000
TOTAL: 03:01:49.158	03:01:22.268	99.8%	-	-	-	-

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
moderator@dana.de	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	1021	2103877	2096952	1842	5083	99%	426:06:11
2	news.task.gda.pl	13	27136	5668	20587	881	20%	44:46:07
3	news.nask.pl	2	15074	4822	9311	941	31%	23:28:50
4	news.icm.edu.pl	46	274	9	245	20	3%	18:53:42
5	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 5		1089	2146367	2107457	31985	6925	98%	513:14:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	807.5 GB	29.1 MB	417.0 MB	808.0 GB	99%	403.0 KB
2	news.nask.pl	30.2 MB	2.1 MB	2.3 MB	34.5 MB	87%	6.1 KB
3	news.task.gda.pl	10.9 MB	26.5 KB	2.1 MB	13.0 MB	83%	2.0 KB
4	news.icm.edu.pl	353.0 KB	232.7 KB	442.6 KB	1.0 MB	34%	35.5 KB
5	localhost	9.1 KB	0.0 KB	0.0 KB	9.1 KB	100%	1.5 KB
TOTAL: 5		807.6 GB	31.5 MB	421.7 MB	808.0 GB	99%	400.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 1 04:15:03 - 04:59:59	54636	2.6%	20.26	24.0 GB	3.0%	9312.21
Sep 1 05:00:00 - 05:59:59	70643	3.4%	19.62	32.2 GB	4.0%	9372.12
Sep 1 06:00:00 - 06:59:59	72500	3.4%	20.14	32.7 GB	4.0%	9511.91
Sep 1 07:00:00 - 07:59:59	81059	3.8%	22.52	33.6 GB	4.2%	9786.77
Sep 1 08:00:00 - 08:59:59	76128	3.6%	21.15	31.7 GB	3.9%	9243.94
Sep 1 09:00:00 - 09:59:59	82066	3.9%	22.80	34.6 GB	4.3%	10085.93
Sep 1 10:00:00 - 10:59:59	99995	4.7%	27.78	36.9 GB	4.6%	10752.02
Sep 1 11:00:00 - 11:59:59	78105	3.7%	21.70	30.3 GB	3.7%	8813.59
Sep 1 12:00:00 - 12:59:59	85825	4.1%	23.84	34.8 GB	4.3%	10122.23
Sep 1 13:00:00 - 13:59:59	109070	5.2%	30.30	34.5 GB	4.3%	10052.99
Sep 1 14:00:00 - 14:59:59	99864	4.7%	27.74	33.3 GB	4.1%	9695.64
Sep 1 15:00:00 - 15:59:59	97874	4.6%	27.19	34.8 GB	4.3%	10122.49
Sep 1 16:00:00 - 16:59:59	105596	5.0%	29.33	36.2 GB	4.5%	10551.81
Sep 1 17:00:00 - 17:59:59	96106	4.6%	26.70	35.7 GB	4.4%	10403.28
Sep 1 18:00:00 - 18:59:59	93153	4.4%	25.88	36.5 GB	4.5%	10630.61
Sep 1 19:00:00 - 19:59:59	83222	3.9%	23.12	31.4 GB	3.9%	9145.37
Sep 1 20:00:00 - 20:59:59	75252	3.6%	20.90	28.5 GB	3.5%	8299.28
Sep 1 21:00:00 - 21:59:59	65869	3.1%	18.30	23.9 GB	3.0%	6974.85
Sep 1 22:00:00 - 22:59:59	64710	3.1%	17.98	25.5 GB	3.2%	7437.94
Sep 1 23:00:00 - 23:59:59	88272	4.2%	24.52	36.0 GB	4.5%	10474.14
Sep 2 00:00:00 - 00:59:59	84105	4.0%	23.36	33.0 GB	4.1%	9623.91
Sep 2 01:00:00 - 01:59:59	106724	5.1%	29.65	39.7 GB	4.9%	11557.03
Sep 2 02:00:00 - 02:59:59	106317	5.0%	29.53	39.8 GB	4.9%	11580.42
Sep 2 03:00:00 - 03:59:59	103799	4.9%	28.83	38.0 GB	4.7%	11070.62
Sep 2 04:00:00 - 04:15:03	26413	1.3%	29.25	9.4 GB	1.2%	10973.32
TOTAL: 24:00:00	2107303	100.0%	24.39	806.9 GB	100.0%	9793.24

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5213	186	2623	2	2402
2	news.nask.pl	973	25	889	2	57
3	news.task.gda.pl	910	699	20	1	190
4	news.icm.edu.pl	18	0	4	0	14
TOTAL: 4		7114	910	3536	5	2663

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	105
mailing.freebsd.svn-ports-head	105
uk.railway	56
mailing.freebsd.ports-bugs	55
mailing.postfix.users	48
fr.rec.loisirs-creatifs	40
24hoursupport.helpdesk	29
mailing.freebsd.svn-src-all	24
fa.freebsd.cvs-all	24
mailing.freebsd.svn-src-head	24
it.tlc.cellulari.android	23
it.comp.os.win.windows7	21
it.comp.os.win.windows8	21
comp.mobile.ipad	21
alt.politics.scorched-earth	20
mailing.gcc.gcc-cvs	19
mailing.openbsd.source-changes	19
cl.politik.atom-presseschau	18
uk.rec.cycling.moderated	17
fr.comp.os.android	17
TOTAL: 70	910

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

Groups	Count
pl.sci.historia	4
fr.comp.applications.libres	1
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	2195
EMP (fsl)	180
Attached HTML file	81
EMP (md5)	46
HTML post	2
Scoring filter (9)	1
Scoring filter (8)	1
TOTAL: 7	2506

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	66878	15673	29928	2528	1	19	23%	23:59:59
2	panorama	52031	2738	47672	801	0	0	5%	24:05:28
3	task	1980	132	979	10	0	0	6%	21:36:51
4	poznan	12323	51	10500	30	0	0	0%	24:04:09
5	nask	17551	5	12684	20	0	0	0%	24:01:38
6	okapi	0	0	0	0	0	2102	0%	24:00:02
TOTAL: 6		150763	18599	101763	3389	1	2121	12%	141:48:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	864.8 MB	864.3 MB	1.7 GB	20.5 KB/s	97.3 KB	23:59:59
2	panorama	8.5 MB	2.9 MB	11.5 MB	0.1 KB/s	3.3 KB	24:05:28
3	task	278.9 KB	22.2 KB	301.0 KB	0.0 KB/s	2.1 KB	21:36:51
4	poznan	148.2 KB	54.0 KB	202.2 KB	0.0 KB/s	2.5 KB	24:04:09
5	nask	10.4 KB	25.1 MB	25.1 MB	0.3 KB/s	1.0 MB	24:01:38
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		873.7 MB	892.4 MB	1.7 GB	3.5 KB/s	82.2 KB	141:48:07

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	350	590.5 KB	288	0	00:00:07
2	web.man.lodz.pl	288	84	76.5 KB	35	0	00:00:24
3	ip-176.105.135.22.tvsat364.lodz.pl	2	3	4.4 KB	2	0	00:12:02
4	kujawiak.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 4		584	437	671.4 KB	325	6	03:01:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	350	590.5 KB	288	0	00:00:07
2	*.man.lodz.pl	678	84	76.5 KB	35	6	00:00:32
3	*.105.135.22.tvsat364.lodz.pl	2	3	4.4 KB	2	0	00:12:02
4	*.adsl.inetia.pl	1	0	0.0 KB	0	0	00:00:48
5	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:22
6	*.207.15.62.static.jazztel.es	5	0	0.0 KB	0	0	02:47:49
7	*.toya.net.pl	45	0	0.0 KB	0	0	00:00:04
TOTAL: 7		1020	437	671.4 KB	325	6	03:01:48

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
116.207.15.62.static.jazztel.es	3.192	4.116	02:47:49
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.036	00:12:02
81-219-114-10.adsl.inetia.pl	0.004	0.012	00:00:48
web.man.lodz.pl	0.700	5.412	00:00:24
ecc101.neoplus.adsl.tpnet.pl	0.004	0.016	00:00:22
vegas.cm.p.lodz.pl	0.728	4.952	00:00:07
f-oper-v100.man.lodz.pl	0.688	5.120	00:00:05
staticline41435.toya.net.pl	0.104	0.836	00:00:04
david.man.lodz.pl	0.248	1.652	00:00:01
kujawiak.man.lodz.pl	0.028	0.120	00:00:00
TOTAL: 10	5.700	22.272	03:01:48

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	45
116.207.15.62.static.jazztel.es	5
81-219-114-10.adsl.inetia.pl	1
ecc101.neoplus.adsl.tpnet.pl	1
TOTAL: 6	436

NNRP client timeouts [Top 20]:

System	Conn	Peer
116.207.15.62.static.jazztel.es	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	350	80.1%
2	pl	87	19.9%
TOTAL: 2		437	100.0%

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

Newsgroup		Count
1	lodman.info	350
2	pl.comp.pecet	50
3	pl.comp.os.ms-windows.winnt	34
4	pl.regionalne.lodz	3
TOTAL: 4		437

Daily Usenet report

Sep 1 04:15:03 -- Sep 2 04:15:03