Daily Usenet report: Nov 4 04:15:03

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Nov 4 08:14:33 newsfeed nnrpd[13816]: SERVER perl filter not defined
Nov 4 11:25:50 newsfeed nnrpd[20032]: SERVER perl filter not defined
Nov 4 11:50:05 newsfeed pgpverify[20900]: pgpverify: verification failed
Nov 4 12:04:08 newsfeed nnrpd[21295]: SERVER perl filter not defined
Nov 4 12:12:30 newsfeed nnrpd[21595]: SERVER perl filter not defined
Nov 4 13:39:46 newsfeed nnrpd[24439]: SERVER perl filter not defined
Nov 4 14:02:30 newsfeed nnrpd[25170]: SERVER perl filter not defined
Nov 4 16:36:00 newsfeed nnrpd[30434]: SERVER perl filter not defined
Nov 4 23:00:55 newsfeed overchan[2136]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2230277	98.5%	252.5 MB	98.1%
innd	18032	0.8%	3.0 MB	1.2%
nnrpd	9145	0.4%	955.2 KB	0.4%
innfeed	6963	0.3%	1.1 MB	0.4%
controlchan	3	0.0%	0.5 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	2264422	100.0%	257.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2224405	94.4%
Negative hits	92189	3.9%
Positive hits	39344	1.7%
Cache misses	359	0.0%
TOTAL: 4	2356297	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:17.943	0.0%	435	0.000	41.248	1296.000
article cleanup	00:00:28.306	0.0%	2230925	0.003	0.013	0.027
article logging	00:01:02.149	0.1%	2231091	0.013	0.028	0.387
article parse	01:07:19.916	4.7%	60345492	0.019	0.067	0.095
article write	00:20:21.352	1.4%	2224352	0.277	0.549	1.264
artlog/artcncl	00:00:00.008	0.0%	433	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:46.168	0.1%	73134217	0.000	0.001	0.007
hisgrep/artcncl	00:00:03.672	0.0%	335	0.000	10.961	268.000
hishave/artcncl	00:00:03.038	0.0%	435	0.000	6.984	280.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:33:33.750	14.8%	2358319	0.006	5.433	40.511
history sync	00:00:00.187	0.0%	292	0.000	0.640	109.000
history write	05:51:40.928	24.4%	2226494	0.038	9.477	195.222
hiswrite/artcncl	00:00:01.024	0.0%	100	0.000	10.240	226.000
idle	06:24:18.458	26.7%	24022459	0.229	0.960	2.440
nntp read	00:33:14.594	2.3%	58493771	0.013	0.034	0.043
overview write	00:00:13.553	0.0%	2224352	0.001	0.006	0.018
perl filter	05:43:31.677	23.8%	2226494	5.040	9.257	11.400
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:13.305	0.3%	4491702	0.022	0.056	0.085
TOTAL: 24:00:46.748	23:42:10.028	98.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.165	0.0%	42986	0.000	0.004	0.250
article prepare	00:00:00.014	0.0%	44288	0.000	0.000	0.125
article read	00:00:01.906	0.0%	10659	0.000	0.179	22.000
backlog stats	00:00:00.264	0.0%	316766	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.317	0.0%	182835	0.000	0.013	1.000
data write	00:00:08.028	0.0%	167261	0.000	0.048	1.000
idle	23:58:29.694	99.9%	316766	0.000	272.471	18063.000
status file	00:00:30.510	0.0%	227	0.000	134.405	7356.000
TOTAL: 23:59:20.174	23:59:12.898	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:25.909	0.0%	65197	0.387	0.397	4.693
idle	35:39:07.257	99.9%	121160	0.000	1059.320	450050.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.544	0.0%	244813	0.000	0.019	4.500
readart	00:00:22.566	0.0%	65645	0.000	0.344	299.500
TOTAL: 35:40:47.470	35:40:00.276	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
admin@openwatcom.news-admin.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	764	2215786	2209577	2948	3261	99%	419:38:50
2	news.task.gda.pl	10	31873	6976	23408	1489	21%	45:28:37
3	news.nask.pl	3	16605	5862	8885	1858	35%	24:06:08
4	news.icm.edu.pl	38	312	22	283	7	7%	20:54:24
5	localhost	8	7	7	0	0	100%	00:00:02
TOTAL: 5		823	2264583	2222444	35524	6615	98%	510:08:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	793.6 GB	29.4 MB	64.9 MB	793.7 GB	99%	376.1 KB
2	news.nask.pl	30.8 MB	4.7 MB	4.8 MB	40.3 MB	76%	5.3 KB
3	news.task.gda.pl	14.2 MB	88.9 KB	4.6 MB	18.9 MB	75%	2.3 KB
4	news.icm.edu.pl	740.3 KB	16.4 KB	137.3 KB	894.0 KB	82%	30.8 KB
5	localhost	9.4 KB	0.0 KB	0.0 KB	9.4 KB	100%	1.3 KB
TOTAL: 5		793.6 GB	34.1 MB	74.5 MB	793.7 GB	99%	373.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 4 04:15:03 - 04:59:59	57653	2.6%	21.38	21.7 GB	2.7%	8436.80
Nov 4 05:00:00 - 05:59:59	93997	4.2%	26.11	34.5 GB	4.3%	10046.86
Nov 4 06:00:00 - 06:59:59	94170	4.2%	26.16	36.0 GB	4.5%	10475.58
Nov 4 07:00:00 - 07:59:59	93428	4.2%	25.95	37.1 GB	4.7%	10796.55
Nov 4 08:00:00 - 08:59:59	94353	4.2%	26.21	36.5 GB	4.6%	10626.21
Nov 4 09:00:00 - 09:59:59	93671	4.2%	26.02	35.4 GB	4.5%	10300.94
Nov 4 10:00:00 - 10:59:59	95765	4.3%	26.60	35.2 GB	4.4%	10257.96
Nov 4 11:00:00 - 11:59:59	97894	4.4%	27.19	36.9 GB	4.7%	10758.50
Nov 4 12:00:00 - 12:59:59	87665	3.9%	24.35	32.6 GB	4.1%	9485.29
Nov 4 13:00:00 - 13:59:59	90732	4.1%	25.20	32.3 GB	4.1%	9402.91
Nov 4 14:00:00 - 14:59:59	73943	3.3%	20.54	25.5 GB	3.2%	7418.80
Nov 4 15:00:00 - 15:59:59	97530	4.4%	27.09	33.3 GB	4.2%	9708.03
Nov 4 16:00:00 - 16:59:59	83681	3.8%	23.24	28.1 GB	3.5%	8176.39
Nov 4 17:00:00 - 17:59:59	91612	4.1%	25.45	32.7 GB	4.1%	9539.10
Nov 4 18:00:00 - 18:59:59	97897	4.4%	27.19	35.2 GB	4.4%	10251.39
Nov 4 19:00:00 - 19:59:59	93119	4.2%	25.87	32.5 GB	4.1%	9472.32
Nov 4 20:00:00 - 20:59:59	102766	4.6%	28.55	35.6 GB	4.5%	10358.15
Nov 4 21:00:00 - 21:59:59	106376	4.8%	29.55	35.9 GB	4.5%	10452.64
Nov 4 22:00:00 - 22:59:59	103487	4.7%	28.75	35.5 GB	4.5%	10350.52
Nov 4 23:00:00 - 23:59:59	101462	4.6%	28.18	34.7 GB	4.4%	10100.63
Nov 5 00:00:00 - 00:59:59	82266	3.7%	22.85	27.9 GB	3.5%	8137.41
Nov 5 01:00:00 - 01:59:59	79699	3.6%	22.14	26.8 GB	3.4%	7818.25
Nov 5 02:00:00 - 02:59:59	96347	4.3%	26.76	33.3 GB	4.2%	9695.98
Nov 5 03:00:00 - 03:59:59	88025	4.0%	24.45	29.9 GB	3.8%	8705.27
Nov 5 04:00:00 - 04:15:03	25569	1.2%	28.32	8.8 GB	1.1%	10188.93
TOTAL: 24:00:00	2223107	100.0%	25.73	793.8 GB	100.0%	9633.83

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3388	304	2604	2	478
2	news.nask.pl	1886	33	1784	3	66
3	news.task.gda.pl	1458	1204	41	3	210
4	news.icm.edu.pl	5	0	0	0	5
TOTAL: 4		6737	1541	4429	8	759

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	147
mailing.freebsd.svn-ports-head	146
uk.railway	127
mozilla.dev.tree-management	83
it.tlc.cellulari.android	64
alt.windows7.general	60
mailing.freebsd.svn-src-all	51
fa.freebsd.cvs-all	50
mailing.freebsd.ports-bugs	47
mailing.unix.samba-technical	44
mailing.database.pgsql-general	38
mailing.gcc.gcc-cvs	36
mailing.freebsd.svn-src-head	34
alt.politics.scorched-earth	30
mailing.freebsd.current	29
24hoursupport.helpdesk	29
mailing.unix.samba	28
gmane.linux.alsa.devel	27
mailing.openbsd.source-changes	26
mailing.freebsd.ports	24
TOTAL: 81	1541

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

Groups	Count
pl.sci.historia	4
fr.comp.sys.mac.annonces	3
bit.listserv.catholic	1
TOTAL: 3	8

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	330
EMP (fsl)	172
EMP (md5)	33
Attached HTML file	28
EMP (phl)	20
HTML post	4
Scoring filter (8)	3
Scoring filter (12)	2
TOTAL: 8	592

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	57666	3865	31599	3721	1	5	6%	24:02:29
2	panorama	44224	2533	39858	643	1	0	5%	24:03:52
3	task	2752	203	1358	10	0	0	7%	21:07:43
4	poznan	13811	56	12142	31	0	0	0%	24:01:48
5	nask	17571	8	12936	5	0	0	0%	23:56:46
6	okapi	0	0	0	0	0	2918	0%	24:00:00
TOTAL: 6		136024	6665	97893	4410	2	2923	4%	141:12:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	34.2 MB	1.9 GB	1.9 GB	23.2 KB/s	264.2 KB	24:02:29
2	panorama	11.0 MB	3.9 MB	14.8 MB	0.2 KB/s	4.8 KB	24:03:52
3	task	416.8 KB	21.5 KB	438.4 KB	0.0 KB/s	2.1 KB	21:07:43
4	poznan	293.8 KB	60.6 KB	354.4 KB	0.0 KB/s	4.1 KB	24:01:48
5	nask	28.9 KB	1.9 MB	1.9 MB	0.0 KB/s	151.4 KB	23:56:46
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		45.9 MB	1.9 GB	1.9 GB	4.0 KB/s	182.6 KB	141:12:38

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	360	487.1 KB	288	0	00:00:16
2	212.191.78.190	179	178	332.2 KB	42	1	27:43:03
3	web.man.lodz.pl	288	66	63.1 KB	30	0	00:00:23
4	lze200.lze.lodz.pl	2	21	35.6 KB	2	0	00:09:57
5	ip-176.105.135.22.tvsat364.lodz.pl	9	10	15.1 KB	5	0	02:34:30
6	73.64-95.man.lodz.pl	1	2	2.7 KB	1	0	00:00:39
7	kujawiak.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 7		773	637	935.8 KB	368	7	35:40:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	360	487.1 KB	288	0	00:00:16
2	unresolved	179	178	332.2 KB	42	1	27:43:03
3	*.man.lodz.pl	682	66	63.1 KB	30	6	00:00:31
4	*.lze.lodz.pl	2	21	35.6 KB	2	0	00:09:57
5	*.105.135.22.tvsat364.lodz.pl	9	10	15.1 KB	5	0	02:34:30
6	*.64-95.man.lodz.pl	1	2	2.7 KB	1	0	00:00:39
7	*.191.14.62.static.jazztel.es	6	0	0.0 KB	0	0	05:11:39
8	*.toya.net.pl	83	0	0.0 KB	0	0	00:00:09
TOTAL: 8		1250	637	935.8 KB	368	7	35:40:46

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.460	2.860	27:43:03
16.191.14.62.static.jazztel.es	7.888	32.148	05:11:39
ip-176.105.135.22.tvsat364.lodz.pl	0.028	0.140	02:34:30
lze200.lze.lodz.pl	0.012	0.048	00:09:57
73.64-95.man.lodz.pl	0.004	0.028	00:00:39
web.man.lodz.pl	0.704	4.616	00:00:23
vegas.cm.p.lodz.pl	0.656	4.856	00:00:16
staticline41435.toya.net.pl	0.180	1.344	00:00:09
f-oper-v100.man.lodz.pl	0.568	4.584	00:00:05
kujawiak.man.lodz.pl	0.044	0.108	00:00:01
david.man.lodz.pl	0.200	1.508	00:00:01
TOTAL: 11	10.744	52.240	35:40:46

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	292
david.man.lodz.pl	96
staticline41435.toya.net.pl	83
16.191.14.62.static.jazztel.es	6
TOTAL: 4	477

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.191.78.190	4	0
ip-176.105.135.22.tvsat364.lodz.pl	3	0
TOTAL: 2	7	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	360	56.5%
2	pl	275	43.2%
3	alt	2	0.3%
TOTAL: 3		637	100.0%

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

Newsgroup		Count
1	lodman.info	360
2	pl.misc.kolej	102
3	pl.comp.pecet	38
4	pl.comp.os.ms-windows.winnt	28
5	pl.regionalne.lodz	23
6	pl.misc.telefonia.gsm	17
7	pl.rec.rowery	17
8	pl.rec.gory	13
9	pl.comp.os.linux	13
10	pl.soc.prawo	8
11	pl.regionalne.warszawa	8
12	pl.praca.dyskusje	6
13	pl.sci.medycyna	2
14	alt.pl.praca.oferowana	2
TOTAL: 14		637

Daily Usenet report

Nov 4 04:15:03 -- Nov 5 04:15:03