Daily Usenet report for csiph.com: Feb 1 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 1 06:04:04 csiph pgpverify[78625]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	51678	45.5%	9.9 MB	62.4%
nnrpd	33391	29.4%	2.8 MB	17.5%
innd	25179	22.2%	2.6 MB	16.4%
innfeed	3340	2.9%	597.8 KB	3.7%
nocem	65	0.1%	6.4 KB	0.0%
controlchan	15	0.0%	2.0 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	113669	100.0%	15.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	596186	56.3%
Negative hits	410964	38.8%
Do not exist	50590	4.8%
Cache misses	663	0.1%
TOTAL: 4	1058403	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.472	0.0%	237	0.000	1.992	13.000
article cleanup	00:00:00.311	0.0%	50385	0.000	0.006	0.033
article logging	00:00:00.650	0.0%	50932	0.000	0.013	0.046
article parse	00:00:02.473	0.0%	217554	0.003	0.011	0.071
article write	00:01:13.991	0.1%	29211	0.256	2.533	8.965
artlog/artcncl	00:00:00.003	0.0%	190	0.000	0.016	0.100
artlog/artparse	00:00:00.006	0.0%	306	0.000	0.020	0.333
data move	00:00:00.371	0.0%	1043460	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.109	0.0%	232	0.000	0.470	12.000
hishave/artcncl	00:00:00.007	0.0%	237	0.000	0.030	0.429
hishave/artparse	00:00:00.000	0.0%	302	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:27.464	0.0%	1059432	0.002	0.026	0.219
history sync	00:00:03.254	0.0%	340	0.000	9.571	74.000
history write	00:02:30.757	0.2%	50344	0.109	2.995	14.760
hiswrite/artcncl	00:00:00.015	0.0%	5	0.000	3.000	4.000
hiswrite/artparse	00:00:00.643	0.0%	302	0.000	2.129	27.000
idle	23:40:34.525	99.3%	1049115	47.084	81.244	243.416
nntp read	00:00:03.520	0.0%	1064385	0.001	0.003	0.005
overview write	00:02:21.622	0.2%	29211	1.429	4.848	37.520
perl filter	00:02:17.783	0.2%	50309	1.750	2.739	4.478
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.529	0.0%	58490	0.000	0.009	0.028
TOTAL: 23:50:44.320	23:49:38.505	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.090	0.0%	29495	0.000	0.003	0.026
article prepare	00:00:00.005	0.0%	33608	0.000	0.000	0.009
article read	00:00:00.706	0.0%	4031	0.000	0.175	1.690
backlog stats	00:00:00.335	0.0%	692119	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.530	0.0%	502093	0.001	0.005	0.011
data write	00:00:03.331	0.0%	483870	0.003	0.007	0.013
idle	23:52:27.040	100.0%	692119	69.157	124.180	319.445
status file	00:00:00.270	0.0%	328	0.000	0.823	20.000
TOTAL: 23:52:48.144	23:52:34.307	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:15.251	0.0%	47130	0.000	0.324	0.878
idle	60:11:01.037	99.9%	80061	67.250	2706.199	600225.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:09.903	0.0%	207061	0.000	0.048	20.379
readart	00:00:35.955	0.0%	16289	0.000	2.207	8.000
TOTAL: 60:13:17.993	60:12:02.146	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	145
newgroup	1
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	166

Newsgroups created:

Group	Mode
free.binaries.3d.daz-studio	y
TOTAL	1

Control Channel:

Sender	newgroup	Other	Bad PGP	DoIt	OK
bofh-control@killfile.org	0	1	0	0	1
ignore@domain.com	3	0	0	3	3
us-control@lists.killfile.org	0	0	1	0	0
user@domain.com	5	0	0	5	5
TOTAL	8	1	1	8	9

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	34483	6407	25331	2745	18%	23:44:52
2	feeder.eternal-september.org	6	46420	4380	38984	3056	9%	25:09:06
3	csiph-out.news.weretis.net	7	63843	3371	60000	472	5%	48:28:43
4	csiph-out.feeder.erje.net	1152	107460	3151	102887	1422	2%	95:01:31
5	news-out.aioe.org	5	28182	2801	25348	33	9%	24:17:53
6	news-out.mixmin.net	4	32848	2756	28243	1849	8%	24:17:49
7	newsfeed.neva.ru	141	62701	2379	53610	6712	3%	72:10:09
8	newsfeed.fu-berlin.de	55	31379	1343	29808	228	4%	33:29:27
9	news.redatomik.org	5	28944	843	27999	102	2%	24:19:22
10	news.fcku.it	1	10427	508	9916	3	4%	25:01:33
11	xanadu-bbs.net	1	49544	478	44227	4839	0%	24:15:26
12	newsfeeds.xmission.com	3	30731	429	30280	22	1%	25:08:10
13	news.freedyn.net	7	32844	297	32335	212	0%	24:17:37
14	news.swapon.de	4	2446	165	2277	4	6%	23:30:36
15	au2pb.net	3	37443	127	37287	29	0%	31:38:08
16	nntp.pasdenom.info	6	29818	18	29783	17	0%	24:08:01
17	news.bbs.geek.nz	3	32403	2	32401	0	0%	27:54:08
18	localhost	34	0	0	0	0	0%	00:00:00
TOTAL: 18		1725	661916	29455	610716	21745	4%	576:52:31

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	19.2 MB	0.0 KB	27.7 MB	46.9 MB	40%	5.2 KB
2	csiph-out.feeder.erje.net	14.0 MB	43.3 KB	4.8 MB	18.8 MB	74%	4.2 KB
3	feeder.eternal-september.org	11.2 MB	43.0 KB	35.8 MB	47.0 MB	23%	6.5 KB
4	csiph-out.news.weretis.net	10.3 MB	7.4 KB	10.0 MB	20.3 MB	50%	5.4 KB
5	news-out.mixmin.net	9.4 MB	7.1 KB	3.6 MB	13.0 MB	72%	2.9 KB
6	news-out.aioe.org	6.8 MB	0.0 KB	61.8 KB	6.8 MB	99%	2.5 KB
7	newsfeed.neva.ru	6.7 MB	594.2 KB	58.2 MB	65.5 MB	10%	7.4 KB
8	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	554.7 KB	3.4 MB	84%	2.2 KB
9	news.redatomik.org	1.8 MB	0.0 KB	1.1 MB	2.9 MB	61%	3.2 KB
10	newsfeeds.xmission.com	1.7 MB	0.0 KB	757.5 KB	2.4 MB	69%	5.5 KB
11	xanadu-bbs.net	1.2 MB	13.5 KB	45.7 MB	46.9 MB	2%	9.0 KB
12	news.freedyn.net	1.1 MB	0.0 KB	1.7 MB	2.8 MB	40%	5.6 KB
13	news.fcku.it	893.5 KB	0.0 KB	136.1 KB	1.0 MB	86%	2.0 KB
14	news.swapon.de	432.9 KB	0.0 KB	12.4 KB	445.3 KB	97%	2.6 KB
15	au2pb.net	388.0 KB	0.0 KB	75.2 KB	463.2 KB	83%	3.0 KB
16	nntp.pasdenom.info	33.2 KB	0.0 KB	35.6 KB	68.8 KB	48%	2.0 KB
17	news.bbs.geek.nz	3.6 KB	0.0 KB	0.0 KB	3.6 KB	100%	1.8 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		87.9 MB	708.6 KB	190.2 MB	278.8 MB	31%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 1 04:15:00 - 04:59:59	391	1.3%	0.14	1.3 MB	1.5%	0.50
Feb 1 05:00:00 - 05:59:59	613	2.1%	0.17	2.3 MB	2.6%	0.65
Feb 1 06:00:00 - 06:59:59	542	1.8%	0.15	2.0 MB	2.3%	0.58
Feb 1 07:00:00 - 07:59:59	753	2.6%	0.21	2.0 MB	2.2%	0.56
Feb 1 08:00:00 - 08:59:59	1356	4.6%	0.38	4.4 MB	5.0%	1.24
Feb 1 09:00:00 - 09:59:59	1528	5.2%	0.42	5.0 MB	5.7%	1.42
Feb 1 10:00:00 - 10:59:59	1432	4.9%	0.40	4.7 MB	5.3%	1.32
Feb 1 11:00:00 - 11:59:59	1477	5.0%	0.41	4.0 MB	4.5%	1.13
Feb 1 12:00:00 - 12:59:59	1310	4.5%	0.36	3.4 MB	3.9%	0.97
Feb 1 13:00:00 - 13:59:59	1407	4.8%	0.39	3.8 MB	4.3%	1.07
Feb 1 14:00:00 - 14:59:59	1742	5.9%	0.48	4.6 MB	5.3%	1.31
Feb 1 15:00:00 - 15:59:59	1893	6.5%	0.53	5.3 MB	6.1%	1.52
Feb 1 16:00:00 - 16:59:59	2030	6.9%	0.56	6.5 MB	7.4%	1.84
Feb 1 17:00:00 - 17:59:59	1676	5.7%	0.47	4.8 MB	5.5%	1.38
Feb 1 18:00:00 - 18:59:59	1572	5.4%	0.44	4.5 MB	5.2%	1.29
Feb 1 19:00:00 - 19:59:59	1534	5.2%	0.43	4.3 MB	4.9%	1.21
Feb 1 20:00:00 - 20:59:59	1460	5.0%	0.41	4.2 MB	4.8%	1.20
Feb 1 21:00:00 - 21:59:59	1541	5.3%	0.43	4.2 MB	4.8%	1.19
Feb 1 22:00:00 - 22:59:59	1263	4.3%	0.35	3.7 MB	4.2%	1.05
Feb 1 23:00:00 - 23:59:59	1072	3.7%	0.30	3.4 MB	3.9%	0.98
Feb 2 00:00:00 - 00:59:59	787	2.7%	0.22	2.6 MB	3.0%	0.74
Feb 2 01:00:00 - 01:59:59	668	2.3%	0.19	2.2 MB	2.5%	0.62
Feb 2 02:00:00 - 02:59:59	586	2.0%	0.16	1.9 MB	2.2%	0.54
Feb 2 03:00:00 - 03:59:59	518	1.8%	0.14	1.8 MB	2.1%	0.52
Feb 2 04:00:00 - 04:15:00	160	0.5%	0.18	764.7 KB	0.9%	0.85
TOTAL: 24:00:00	29311	100.0%	0.34	87.5 MB	100.0%	1.04

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	6682	1453	22	0	5207
2	xanadu-bbs.net	4808	33	1	0	4774
3	feeder.eternal-september.org	3072	160	4	0	2908
4	usenet.blueworldhosting.com	2739	1099	0	6	1634
5	news-out.mixmin.net	1860	4	4	0	1852
6	csiph-out.feeder.erje.net	1443	725	9	6	703
7	newsfeed.fu-berlin.de	598	59	0	0	539
8	csiph-out.news.weretis.net	511	315	4	0	192
9	news.freedyn.net	215	1	0	0	214
10	news.redatomik.org	107	0	0	0	107
11	news-out.aioe.org	59	29	0	0	30
12	news.swapon.de	23	0	0	0	23
13	newsfeeds.xmission.com	19	6	0	0	13
14	au2pb.net	19	18	1	0	0
15	nntp.pasdenom.info	17	13	0	0	4
16	news.fcku.it	5	0	0	0	5
TOTAL: 16		22177	3915	45	12	18205

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	931
mailing.openssl.dev	146
ut.test	141
mailing.freebsd.pkg-fallout	136
mailing.freebsd.svn-ports-all	118
lada.talk	115
mailing.freebsd.svn-ports-head	113
lada.auto	103
it-alt.sport.calcio.juventus	73
mailing.freebsd.ports-bugs	69
mailing.openbsd.tech	57
fa.openbsd.tech	57
24hoursupport.helpdesk	50
lada.commerce	49
alt.survival	48
gmane.linux.kernel	47
pld.cvs.commit	46
mailing.openssl.cvs	45
mailing.openbsd.source-changes	44
mailing.openbsd.misc	38
TOTAL: 375	3915

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

Groups	Count
uk.radio.amateur.moderated	10
alt.culture.china	2
TOTAL: 2	12

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13348
EMP (phn path)	2321
EMP (phl)	807
PFM bot misc	220
Binary: misplaced rar	177
EMP (phn nph)	120
Too many newsgroups	52
HTML Multipart	48
User-issued cancel	39
Scoring filter (22)	24
Too many newsgroups (meow)	23
Binary Payload	22
Scoring filter (20)	19
Scoring filter (13)	12
Scoring filter (11)	11
Scoring filter (23)	10
Scoring filter (16)	8
Scoring filter (9)	7
Scoring filter (14)	7
Scoring filter (17)	5
TOTAL: 36	17321

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	58	0	60	60
TOTAL: 1	58	0	60	60

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	19
	usenet.blueworldhosting.com	10
	TOTAL: 2	29
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	usenet.blueworldhosting.com	1
	TOTAL: 2	5
Huge articles
	usenet.blueworldhosting.com	80
	csiph-out.news.weretis.net	68
	xanadu-bbs.net	68
	feeder.eternal-september.org	37
	news-out.mixmin.net	16
	newsfeeds.xmission.com	12
	news.freedyn.net	9
	news.redatomik.org	8
	news.fcku.it	3
	newsfeed.fu-berlin.de	1
	TOTAL: 10	302
Including strange strings
	newsfeed.fu-berlin.de	386
	csiph-out.news.weretis.net	34
	feeder.eternal-september.org	27
	news-out.aioe.org	25
	news-out.mixmin.net	23
	csiph-out.feeder.erje.net	20
	news.swapon.de	19
	news.redatomik.org	5
	newsfeed.neva.ru	4
	news.fcku.it	2
	TOTAL: 12	547
TOTAL: 4		883

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	33888	1390	27613	5	0	4%	23:59:49
2	news.bbs.geek.nz	53776	1341	26965	14	5	2%	23:59:49
3	xanadu-bbs	31947	1146	27679	9	7	3%	23:59:49
4	fu-berlin.de	27544	943	26600	0	0	3%	23:59:49
5	aioe	27184	580	23125	44	0	2%	23:59:49
6	erje	31325	496	25431	151	1	1%	23:59:49
7	usenet.pasdenom.info	37177	291	27587	1415	2	0%	23:59:46
8	au2pb	29293	150	28084	16	1	0%	23:59:49
9	news.redatomik.org	33344	134	27309	13	400	0%	23:59:49
10	neva.ru	30899	35	25360	180	1	0%	23:59:44
11	xmission	34719	27	27808	3	3	0%	23:59:49
12	news.mixmin.net	28463	17	25259	0	0	0%	23:59:49
13	weretis.net	24788	14	24213	12	1	0%	23:59:49
14	eternal-september	28929	11	23459	0	0	0%	23:59:48
15	news.fcku.it	4901	9	4142	0	0	0%	22:43:41
16	news.swapon.de	2729	1	2421	3	0	0%	23:49:03
17	bwh	23027	0	21775	0	1	0%	23:59:49
TOTAL: 17		483933	6585	394830	1865	422	1%	406:29:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.1 MB	16.9 KB	10.1 MB	0.1 KB/s	7.4 KB	23:59:49
2	xanadu-bbs	9.4 MB	28.0 KB	9.4 MB	0.1 KB/s	8.3 KB	23:59:49
3	fu-berlin.de	8.7 MB	0.0 KB	8.7 MB	0.1 KB/s	9.4 KB	23:59:49
4	news.bbs.geek.nz	3.7 MB	65.2 KB	3.8 MB	0.0 KB/s	2.9 KB	23:59:49
5	erje	1.8 MB	610.6 KB	2.4 MB	0.0 KB/s	3.7 KB	23:59:49
6	aioe	1.7 MB	189.8 KB	1.9 MB	0.0 KB/s	3.1 KB	23:59:49
7	usenet.pasdenom.info	1.2 MB	9.5 MB	10.7 MB	0.1 KB/s	6.4 KB	23:59:46
8	au2pb	656.3 KB	106.1 KB	762.4 KB	0.0 KB/s	4.6 KB	23:59:49
9	news.redatomik.org	460.5 KB	76.8 KB	537.3 KB	0.0 KB/s	3.7 KB	23:59:49
10	neva.ru	126.8 KB	862.1 KB	988.9 KB	0.0 KB/s	4.6 KB	23:59:44
11	xmission	90.3 KB	11.8 KB	102.2 KB	0.0 KB/s	3.4 KB	23:59:49
12	weretis.net	65.6 KB	173.6 KB	239.3 KB	0.0 KB/s	9.2 KB	23:59:49
13	news.mixmin.net	65.4 KB	0.0 KB	65.4 KB	0.0 KB/s	3.8 KB	23:59:49
14	eternal-september	64.3 KB	0.0 KB	64.3 KB	0.0 KB/s	5.8 KB	23:59:48
15	news.fcku.it	30.7 KB	0.0 KB	30.7 KB	0.0 KB/s	3.4 KB	22:43:41
16	news.swapon.de	1.8 KB	4.7 KB	6.6 KB	0.0 KB/s	1.6 KB	23:49:03
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:49
TOTAL: 17		38.1 MB	11.5 MB	49.6 MB	0.0 KB/s	6.0 KB	406:29:50

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	80	252.1 KB	69	04:33:50
2	challenger.nielsenmedia.com	4	5	9.0 KB	2	01:00:24
3	138.108.60.60	2	3	5.3 KB	1	00:59:18
4	pool-71-98-160-84.tampfl.dsl-w.verizon.net	4	2	3.7 KB	1	01:37:43
TOTAL: 4		298	90	270.1 KB	73	60:13:14

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	312	83	257.4 KB	70	42:28:21
2	*.nielsenmedia.com	4	5	9.0 KB	2	01:00:24
3	*.tampfl.dsl-w.verizon.net	4	2	3.7 KB	1	01:37:43
4	*.bbox.io	11	0	0.0 KB	0	04:15:57
5	*.chello.nl	28	0	0.0 KB	0	10:50:46
TOTAL: 5		359	90	270.1 KB	73	60:13:14

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71.94.21.184	1.495	2.054	0.000	24:06:46
162.212.88.221	1.050	0.439	0.000	12:47:34
dhcp-089-099-203-254.chello.nl	2.275	0.494	0.000	10:50:46
202.89.176.220	29.316	6.895	0.758	04:33:50
tempe0.bbox.io	1.052	0.213	0.000	04:15:57
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.396	0.086	0.000	01:37:43
challenger.nielsenmedia.com	0.461	0.083	0.000	01:00:24
138.108.60.60	0.212	0.031	0.000	00:59:18
196.52.22.57	0.633	1.512	0.000	00:00:50
206.217.197.145	0.131	0.108	0.000	00:00:01
TOTAL: 10	37.021	11.915	0.758	60:13:14

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	28
162.212.88.221	16
tempe0.bbox.io	11
71.94.21.184	4
206.217.197.145	1
196.52.22.57	1
TOTAL: 6	61

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	4
TOTAL: 1	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	19	0
tempe0.bbox.io	7	0
162.212.88.221	4	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3	0
challenger.nielsenmedia.com	2	0
71.94.21.184	1	0
138.108.60.60	1	0
TOTAL: 7	37	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	90	100.0%
TOTAL: 1		90	100.0%

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

Newsgroup		Count
1	comp.arch	29
2	comp.os.vms	12
3	comp.lang.awk	10
4	comp.os.msdos.djgpp	8
5	comp.os.cpm	8
6	comp.sys.ibm.pc.games.action	5
7	comp.arch.embedded	4
8	comp.os.os2.apps	3
9	comp.os.qnx	2
10	comp.ai.fuzzy	1
11	comp.ai.philosophy	1
12	comp.ai.neural-nets	1
13	comp.os.os2.announce	1
14	comp.os.msdos.4dos	1
15	comp.os.linux.networking	1
16	comp.answers	1
17	comp.sys.ibm.pc.hardware.storage	1
18	comp.sys.ibm.as400.misc	1
TOTAL: 18		90

Daily Usenet report for csiph.com

Feb 1 04:15:00 -- Feb 2 04:15:00