Daily Usenet report for csiph.com: Jul 2 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jul 2 22:45:02 csiph pgpverify[50749]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	44442	40.0%	3.9 MB	27.0%
inn	40389	36.4%	7.6 MB	52.4%
innd	22975	20.7%	2.5 MB	16.9%
innfeed	3164	2.9%	551.9 KB	3.7%
nocem	40	0.0%	3.8 KB	0.0%
controlchan	3	0.0%	0.3 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	111014	100.0%	14.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	452385	57.0%
Negative hits	290990	36.7%
Do not exist	39247	4.9%
Cache misses	10896	1.4%
TOTAL: 4	793518	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.000	0.0%	476	0.000	4.202	25.000
article cleanup	00:00:00.273	0.0%	38567	0.000	0.007	0.022
article logging	00:00:00.561	0.0%	39106	0.000	0.014	0.230
article parse	00:00:01.355	0.0%	133666	0.002	0.010	0.029
article write	00:00:46.815	0.1%	21661	0.245	2.161	22.814
artlog/artcncl	00:00:00.008	0.0%	465	0.000	0.017	0.056
artlog/artparse	00:00:00.010	0.0%	827	0.000	0.012	0.500
data move	00:00:00.254	0.0%	757962	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.032	0.0%	475	0.000	0.067	18.000
hishave/artcncl	00:00:00.003	0.0%	476	0.000	0.006	0.250
hishave/artparse	00:00:00.000	0.0%	193	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:06.334	0.0%	795388	0.001	0.008	0.127
history sync	00:00:01.046	0.0%	359	0.000	2.914	14.000
history write	00:00:25.158	0.0%	39184	0.086	0.642	4.683
hiswrite/artcncl	00:00:00.008	0.0%	1	8.000	8.000	8.000
hiswrite/artparse	00:00:00.135	0.0%	193	0.000	0.699	8.000
idle	23:55:19.676	99.6%	772307	71.736	111.510	277.665
nntp read	00:00:02.649	0.0%	778148	0.001	0.003	0.006
overview write	00:01:22.536	0.1%	21661	0.338	3.810	70.744
perl filter	00:01:31.033	0.1%	38507	1.115	2.364	3.881
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.394	0.0%	43358	0.000	0.009	0.033
TOTAL: 24:00:52.949	23:59:40.280	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.061	0.0%	21997	0.000	0.003	0.014
article prepare	00:00:00.002	0.0%	28380	0.000	0.000	0.014
article read	00:00:01.169	0.0%	3045	0.000	0.384	5.222
backlog stats	00:00:00.237	0.0%	512017	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.910	0.0%	369430	0.001	0.005	0.009
data write	00:00:02.560	0.0%	352648	0.003	0.007	0.014
idle	23:53:57.817	100.0%	512017	100.811	168.037	559.233
status file	00:00:00.216	0.0%	276	0.000	0.783	3.000
TOTAL: 23:54:19.079	23:54:03.972	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.670	0.0%	17534	0.000	0.095	0.125
idle	91:01:45.308	99.0%	74230	59.000	4414.729	600052.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:02:11.294	0.0%	149823	0.000	0.876	330.858
readart	00:00:26.663	0.0%	27076	0.000	0.985	74.000
TOTAL: 91:57:09.985	91:04:24.935	99.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	139
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	159

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	usenet.blueworldhosting.com	288	24978	5109	18169	1700	20%	23:44:49
2	csiph-out.feeder.erje.net	1152	123886	4182	111417	8287	3%	94:57:40
3	csiph-out.news.weretis.net	3	50060	3521	43421	3118	7%	46:19:18
4	feeder.eternal-september.org	4	32116	2063	29288	765	6%	24:37:44
5	news-out.aioe.org	3	21222	1794	19392	36	8%	24:09:51
6	news-out.mixmin.net	3	24399	1152	22753	494	4%	24:08:54
7	newsfeed.fu-berlin.de	26	22246	1108	20818	320	4%	27:00:56
8	newsfeed.neva.ru	147	62576	874	59431	2271	1%	71:59:27
9	news.fcku.it	1	12736	695	11909	132	5%	34:01:46
10	newsfeeds.xmission.com	1	21012	499	20495	18	2%	24:22:29
11	news.freedyn.net	3	24795	415	23913	467	1%	24:09:12
12	news.redatomik.org	2	41670	221	41443	6	0%	44:52:14
13	au2pb.net	1	26380	100	26260	20	0%	24:12:49
14	news.swapon.de	5	1743	47	1695	1	2%	24:41:29
15	nntp.pasdenom.info	3	20716	28	20677	11	0%	23:57:44
16	localhost	53	21	21	0	0	100%	00:00:01
17	xanadu-bbs.net	13	836	19	808	9	2%	30:10:39
18	news.bbs.geek.nz	2	23397	15	23382	0	0%	23:59:50
TOTAL: 18		1710	534789	21863	495271	17655	4%	591:26:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	18.1 MB	44.9 KB	23.3 MB	41.4 MB	43%	3.4 KB
2	usenet.blueworldhosting.com	14.7 MB	0.0 KB	19.6 MB	34.3 MB	42%	5.2 KB
3	csiph-out.news.weretis.net	9.4 MB	0.0 KB	35.6 MB	45.0 MB	20%	6.9 KB
4	feeder.eternal-september.org	5.6 MB	5.7 KB	7.2 MB	12.8 MB	43%	4.6 KB
5	news-out.mixmin.net	5.0 MB	0.0 KB	1.5 MB	6.4 MB	77%	4.0 KB
6	news-out.aioe.org	4.4 MB	0.0 KB	83.0 KB	4.5 MB	98%	2.5 KB
7	newsfeed.neva.ru	3.0 MB	20.6 KB	14.3 MB	17.4 MB	17%	5.7 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	787.6 KB	3.3 MB	76%	2.4 KB
9	newsfeeds.xmission.com	1.5 MB	0.0 KB	965.7 KB	2.4 MB	61%	4.8 KB
10	news.freedyn.net	1.3 MB	0.0 KB	4.2 MB	5.5 MB	24%	6.4 KB
11	news.fcku.it	1.3 MB	72.0 KB	4.8 MB	6.2 MB	21%	7.7 KB
12	news.redatomik.org	558.7 KB	0.0 KB	691.7 KB	1.2 MB	44%	5.5 KB
13	au2pb.net	269.7 KB	5.7 KB	53.2 KB	328.7 KB	82%	2.7 KB
14	news.swapon.de	106.0 KB	0.0 KB	427.8 KB	533.8 KB	19%	11.1 KB
15	nntp.pasdenom.info	57.1 KB	0.0 KB	17.7 KB	74.8 KB	76%	1.9 KB
16	xanadu-bbs.net	39.2 KB	0.0 KB	18.4 KB	57.6 KB	68%	2.1 KB
17	localhost	36.7 KB	0.0 KB	0.0 KB	36.7 KB	100%	1.7 KB
18	news.bbs.geek.nz	18.5 KB	0.0 KB	0.0 KB	18.5 KB	100%	1.2 KB
TOTAL: 18		68.0 MB	149.0 KB	113.4 MB	181.5 MB	37%	4.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 2 04:15:00 - 04:59:59	601	2.8%	0.22	2.5 MB	3.7%	0.94
Jul 2 05:00:00 - 05:59:59	558	2.6%	0.15	2.1 MB	3.1%	0.60
Jul 2 06:00:00 - 06:59:59	640	3.0%	0.18	2.5 MB	3.8%	0.72
Jul 2 07:00:00 - 07:59:59	795	3.7%	0.22	2.8 MB	4.2%	0.80
Jul 2 08:00:00 - 08:59:59	981	4.5%	0.27	3.9 MB	5.7%	1.10
Jul 2 09:00:00 - 09:59:59	951	4.4%	0.26	2.6 MB	3.9%	0.74
Jul 2 10:00:00 - 10:59:59	1040	4.8%	0.29	3.0 MB	4.4%	0.85
Jul 2 11:00:00 - 11:59:59	1123	5.2%	0.31	3.8 MB	5.6%	1.07
Jul 2 12:00:00 - 12:59:59	1089	5.0%	0.30	3.7 MB	5.5%	1.04
Jul 2 13:00:00 - 13:59:59	1030	4.8%	0.29	3.8 MB	5.6%	1.07
Jul 2 14:00:00 - 14:59:59	1104	5.1%	0.31	3.3 MB	5.0%	0.95
Jul 2 15:00:00 - 15:59:59	1247	5.8%	0.35	3.5 MB	5.1%	0.98
Jul 2 16:00:00 - 16:59:59	1221	5.6%	0.34	3.4 MB	5.0%	0.96
Jul 2 17:00:00 - 17:59:59	1147	5.3%	0.32	3.1 MB	4.7%	0.89
Jul 2 18:00:00 - 18:59:59	1130	5.2%	0.31	3.8 MB	5.6%	1.07
Jul 2 19:00:00 - 19:59:59	940	4.3%	0.26	2.5 MB	3.7%	0.71
Jul 2 20:00:00 - 20:59:59	1049	4.8%	0.29	2.9 MB	4.3%	0.83
Jul 2 21:00:00 - 21:59:59	1100	5.1%	0.31	2.7 MB	4.1%	0.78
Jul 2 22:00:00 - 22:59:59	934	4.3%	0.26	2.9 MB	4.4%	0.83
Jul 2 23:00:00 - 23:59:59	757	3.5%	0.21	2.3 MB	3.4%	0.65
Jul 3 00:00:00 - 00:59:59	536	2.5%	0.15	1.6 MB	2.4%	0.45
Jul 3 01:00:00 - 01:59:59	674	3.1%	0.19	1.8 MB	2.7%	0.52
Jul 3 02:00:00 - 02:59:59	413	1.9%	0.11	1.2 MB	1.8%	0.34
Jul 3 03:00:00 - 03:59:59	486	2.2%	0.14	1.3 MB	1.9%	0.36
Jul 3 04:00:00 - 04:15:00	114	0.5%	0.13	310.3 KB	0.5%	0.34
TOTAL: 24:00:00	21660	100.0%	0.25	67.2 MB	100.0%	0.80

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	8329	263	10	8056
2	csiph-out.news.weretis.net	3223	174	0	3049
3	newsfeed.neva.ru	2269	333	4	1932
4	usenet.blueworldhosting.com	1709	536	0	1173
5	feeder.eternal-september.org	808	35	3	770
6	newsfeed.fu-berlin.de	730	30	0	700
7	news-out.mixmin.net	512	3	0	509
8	news.freedyn.net	466	0	0	466
9	news.fcku.it	103	0	0	103
10	news-out.aioe.org	50	34	0	16
11	au2pb.net	24	19	0	5
12	newsfeeds.xmission.com	14	8	0	6
13	nntp.pasdenom.info	11	5	0	6
14	news.swapon.de	8	0	0	8
15	xanadu-bbs.net	8	0	0	8
16	news.redatomik.org	1	0	0	1
TOTAL: 16		18265	1440	17	16808

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	176
mailing.freebsd.pkg-fallout	159
ut.test	68
it-alt.sport.calcio.juventus	61
lada.talk	52
mailing.freebsd.svn-ports-all	51
mailing.freebsd.svn-ports-head	50
mailing.freebsd.ports-bugs	48
alt.windows7.general	34
gmane.comp.gis.openstreetmap.tagging	32
lada.auto	23
lada.photo	22
mozilla.support.thunderbird	19
alt.politics.scorched-earth	19
spline.eisfair	17
fa.netbsd.bugs	16
mailing.openssl.cvs	15
mailing.gcc.gcc-cvs	13
mozilla.general	13
mailing.unix.dovecot	12
TOTAL: 191	1440

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10829
EMP (phn path)	1862
EMP (phl)	693
PFM bot misc	559
Binary: misplaced txt	536
User-issued cancel	301
EMP (phn nph)	192
Too many newsgroups	68
Binary Payload	54
Binary: misplaced TXT	52
Binary: misplaced doc	33
Scoring filter (18)	25
Scoring filter (21)	18
Scoring filter (8)	17
Scoring filter (14)	14
Scoring filter (23)	14
HTML Multipart	13
Scoring filter (19)	13
Scoring filter (10)	13
Scoring filter (9)	10
TOTAL: 50	15400

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	35	0	36	36
TOTAL: 1	35	0	36	36

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	42
	usenet.blueworldhosting.com	1
	TOTAL: 2	43
Huge articles
	usenet.blueworldhosting.com	87
	news.freedyn.net	40
	csiph-out.news.weretis.net	29
	news-out.mixmin.net	13
	feeder.eternal-september.org	10
	newsfeeds.xmission.com	5
	newsfeed.fu-berlin.de	3
	news.fcku.it	3
	au2pb.net	1
	news.redatomik.org	1
	TOTAL: 11	193
Including strange strings
	newsfeed.fu-berlin.de	397
	csiph-out.feeder.erje.net	45
	csiph-out.news.weretis.net	35
	feeder.eternal-september.org	17
	news-out.aioe.org	13
	news-out.mixmin.net	10
	newsfeed.neva.ru	8
	news.swapon.de	7
	usenet.blueworldhosting.com	4
	news.fcku.it	1
	TOTAL: 10	537
No colon-space in header
	csiph-out.feeder.erje.net	627
	usenet.blueworldhosting.com	8
	TOTAL: 2	635
TOTAL: 4		1408

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	24643	1354	19892	3	1	5%	23:59:48
2	news.bbs.geek.nz	39632	1266	19403	14	0	3%	23:59:07
3	xanadu-bbs	24046	1064	20552	0	0	4%	23:59:33
4	fu-berlin.de	20309	914	19394	0	0	4%	23:59:42
5	usenet.pasdenom.info	28569	219	20126	1287	324	0%	23:59:43
6	aioe	20264	199	17750	13	0	0%	23:59:37
7	news.redatomik.org	22260	120	20264	24	42	0%	23:59:48
8	weretis.net	17853	109	16496	18	0	0%	23:59:43
9	au2pb	22163	105	20480	1	14	0%	23:59:48
10	xmission	24545	40	20112	5	0	0%	23:59:48
11	news.mixmin.net	21445	33	19111	0	0	0%	23:59:48
12	bwh	16993	27	15326	0	0	0%	23:59:11
13	neva.ru	23972	24	19387	108	0	0%	23:59:48
14	eternal-september	20480	24	17296	0	0	0%	23:59:48
15	news.swapon.de	1961	15	1810	10	0	0%	23:51:36
16	erje	19645	7	17315	95	0	0%	23:59:40
17	news.fcku.it	1642	0	1454	0	0	0%	21:59:10
TOTAL: 17		350422	5520	286168	1578	381	1%	405:45:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.5 MB	14.7 KB	10.5 MB	0.1 KB/s	7.9 KB	23:59:48
2	xanadu-bbs	9.6 MB	0.0 KB	9.6 MB	0.1 KB/s	9.3 KB	23:59:33
3	fu-berlin.de	9.2 MB	0.0 KB	9.2 MB	0.1 KB/s	10.3 KB	23:59:42
4	news.bbs.geek.nz	3.1 MB	33.5 KB	3.1 MB	0.0 KB/s	2.5 KB	23:59:07
5	usenet.pasdenom.info	832.4 KB	10.0 MB	10.8 MB	0.1 KB/s	7.4 KB	23:59:43
6	aioe	471.7 KB	42.1 KB	513.7 KB	0.0 KB/s	2.4 KB	23:59:37
7	news.redatomik.org	398.7 KB	100.6 KB	499.4 KB	0.0 KB/s	3.5 KB	23:59:48
8	weretis.net	272.1 KB	88.2 KB	360.3 KB	0.0 KB/s	2.8 KB	23:59:43
9	au2pb	246.0 KB	3.8 KB	249.7 KB	0.0 KB/s	2.4 KB	23:59:48
10	xmission	83.2 KB	10.3 KB	93.5 KB	0.0 KB/s	2.1 KB	23:59:48
11	news.mixmin.net	70.4 KB	0.0 KB	70.4 KB	0.0 KB/s	2.1 KB	23:59:48
12	bwh	51.4 KB	0.0 KB	51.4 KB	0.0 KB/s	1.9 KB	23:59:11
13	neva.ru	48.1 KB	453.9 KB	502.0 KB	0.0 KB/s	3.8 KB	23:59:48
14	eternal-september	41.2 KB	0.0 KB	41.2 KB	0.0 KB/s	1.7 KB	23:59:48
15	news.swapon.de	17.7 KB	26.9 KB	44.6 KB	0.0 KB/s	1.8 KB	23:51:36
16	erje	8.3 KB	410.0 KB	418.2 KB	0.0 KB/s	4.1 KB	23:59:40
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:59:10
TOTAL: 17		34.8 MB	11.2 MB	46.0 MB	0.0 KB/s	6.6 KB	405:45:38

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	287	34	60.1 KB	28	0	0	05:27:10
2	tempe0.bbox.io	11	28	47.8 KB	2	0	0	04:27:13
3	68-185-181-64.dhcp.mdsn.wi.charter.com	44	27	45.8 KB	19	21	19	11:06:04
4	dhcp-089-099-203-254.chello.nl	31	23	42.4 KB	7	0	1	07:23:57
5	mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	125	17	48.8 KB	16	0	0	00:42:21
TOTAL: 5		498	129	244.9 KB	72	21	20	91:57:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	299	34	60.1 KB	28	0	0	67:02:06
2	*.bbox.io	11	28	47.8 KB	2	0	0	04:27:13
3	*.dhcp.mdsn.wi.charter.com	44	27	45.8 KB	19	21	19	11:06:04
4	*.chello.nl	31	23	42.4 KB	7	0	1	07:23:57
5	*.dsl.bell.ca	125	17	48.8 KB	16	0	0	00:42:21
6	*.nielsenmedia.com	9	0	0.0 KB	0	0	0	01:15:22
TOTAL: 6		519	129	244.9 KB	72	21	20	91:57:05

NNRP auth users [Top 20]:

User	Conn
moussa	287
ohlandl	19
TOTAL: 2	306

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.246.166.30	1.479	1.127	0.000	60:37:49
68-185-181-64.dhcp.mdsn.wi.charter.com	5.090	0.996	0.052	11:06:04
dhcp-089-099-203-254.chello.nl	3.545	0.623	0.000	07:23:57
202.89.176.220	28.305	7.444	0.796	05:27:10
tempe0.bbox.io	1.290	0.228	0.000	04:27:13
challenger.nielsenmedia.com	0.721	0.172	0.000	01:15:22
71.98.160.36	0.196	0.055	0.000	00:54:26
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	15.343	3.960	0.000	00:42:21
196.52.16.50	0.676	1.130	0.000	00:02:39
TOTAL: 9	56.645	15.735	0.848	91:57:05

Curious NNRP server explorers [Top 20]:

System	Conn
challenger.nielsenmedia.com	9
216.246.166.30	8
71.98.160.36	3
196.52.16.50	1
TOTAL: 4	21

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-181-64.dhcp.mdsn.wi.charter.com	10	0
tempe0.bbox.io	7	0
challenger.nielsenmedia.com	2	3
71.98.160.36	1	0
TOTAL: 4	20	3

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	112	86.8%
2	alt	6	4.7%
3	rec	5	3.9%
4	uk	4	3.1%
5	aus	1	0.8%
6	sci	1	0.8%
TOTAL: 6		129	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	105
2	comp.os.cpm	4
3	alt.atheism	2
4	comp.sys.ibm.pc.hardware.storage	2
5	rec.food.cooking	2
6	rec.outdoors.rv-travel	1
7	alt.support.diabetes	1
8	alt.abortion	1
9	uk.legal	1
10	rec.arts.tv	1
11	alt.obituaries	1
12	comp.ai.philosophy	1
13	uk.rec.sheds	1
14	sci.physics	1
15	aus.cars	1
16	rec.boats	1
17	uk.comp.homebuilt	1
18	alt.happy.valley	1
19	uk.media.radio.archers	1
TOTAL: 19		129

Daily Usenet report for csiph.com

Jul 2 04:15:00 -- Jul 3 04:15:00