Daily Usenet report for csiph.com: Jun 8 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	46868	55.1%	9.0 MB	67.7%
innd	22834	26.9%	2.5 MB	18.8%
nnrpd	12017	14.1%	1.3 MB	9.4%
innfeed	3214	3.8%	555.8 KB	4.1%
nocem	61	0.1%	6.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	84995	100.0%	13.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	550417	58.4%
Negative hits	344762	36.6%
Do not exist	45956	4.9%
Cache misses	640	0.1%
TOTAL: 4	941775	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.215	0.0%	216	0.000	5.625	49.000
article cleanup	00:00:00.264	0.0%	45379	0.000	0.006	0.024
article logging	00:00:00.659	0.0%	45894	0.000	0.014	0.104
article parse	00:00:02.058	0.0%	164849	0.004	0.012	0.083
article write	00:05:07.613	0.4%	26291	0.341	11.700	31.056
artlog/artcncl	00:00:00.004	0.0%	176	0.000	0.023	0.333
artlog/artparse	00:00:00.011	0.0%	785	0.000	0.014	1.000
data move	00:00:00.308	0.0%	876651	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.163	0.0%	215	0.000	0.758	13.000
hishave/artcncl	00:00:00.002	0.0%	216	0.000	0.009	0.167
hishave/artparse	00:00:00.002	0.0%	191	0.000	0.010	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:22.828	0.0%	944554	0.002	0.024	0.275
history sync	00:00:02.816	0.0%	317	0.000	8.883	192.500
history write	00:01:30.858	0.1%	45933	0.099	1.978	13.631
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.521	0.0%	191	0.000	2.728	72.000
idle	23:48:42.444	99.2%	895012	61.472	95.778	312.148
nntp read	00:00:02.914	0.0%	914536	0.002	0.003	0.005
overview write	00:01:42.778	0.1%	26291	0.802	3.909	33.000
perl filter	00:01:54.028	0.1%	45249	1.622	2.520	3.581
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.441	0.0%	52636	0.000	0.008	0.020
TOTAL: 24:00:28.738	23:59:31.927	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.078	0.0%	26398	0.000	0.003	0.028
article prepare	00:00:00.007	0.0%	35760	0.000	0.000	0.009
article read	00:00:02.820	0.0%	4953	0.000	0.569	7.423
backlog stats	00:00:00.262	0.0%	615092	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.238	0.0%	448927	0.002	0.005	0.010
data write	00:00:03.056	0.0%	434151	0.003	0.007	0.012
idle	23:53:12.162	100.0%	615092	85.770	139.804	419.698
status file	00:00:00.605	0.0%	298	0.000	2.030	163.000
TOTAL: 23:53:29.810	23:53:21.228	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.058	0.0%	1852	0.021	0.031	0.043
idle	36:42:38.127	99.5%	362519	81.046	364.555	600155.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:06.369	0.0%	939336	0.000	0.007	0.100
readart	00:10:21.127	0.5%	270915	0.000	2.293	58.000
TOTAL: 36:54:12.425	36:53:05.681	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	31071	6276	22110	2685	20%	23:44:53
2	csiph-out.feeder.erje.net	1152	144070	4686	130870	8514	3%	94:56:56
3	feeder.eternal-september.org	1	39016	4453	33549	1014	11%	23:59:56
4	csiph-out.news.weretis.net	3	61133	2389	57943	801	3%	46:31:57
5	news-out.aioe.org	3	25138	1942	23177	19	7%	24:09:51
6	newsfeed.neva.ru	140	62956	1854	55008	6094	2%	71:45:43
7	news-out.mixmin.net	3	28932	1319	27375	238	4%	24:11:56
8	newsfeed.fu-berlin.de	77	28520	1132	27147	241	3%	36:48:58
9	newsfeeds.xmission.com	1	25152	550	24557	45	2%	20:58:05
10	news.freedyn.net	4	28407	490	27816	101	1%	24:09:59
11	news.fcku.it	1	10848	339	10487	22	3%	25:24:14
12	au2pb.net	1	28644	204	28387	53	0%	24:12:48
13	news.redatomik.org	1	23753	202	23547	4	0%	22:26:07
14	news.swapon.de	2	2179	95	2073	11	4%	25:17:46
15	xanadu-bbs.net	15	794	43	738	13	5%	24:11:38
16	nntp.pasdenom.info	3	27844	36	27797	11	0%	24:08:25
17	localhost	48	14	14	0	0	100%	00:00:00
18	news.bbs.geek.nz	1	26613	6	26607	0	0%	23:59:46
TOTAL: 18		1744	595084	26030	549188	19866	4%	560:58:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	18.6 MB	235.7 KB	38.8 MB	57.7 MB	32%	4.5 KB
2	usenet.blueworldhosting.com	17.5 MB	0.0 KB	24.9 MB	42.4 MB	41%	4.9 KB
3	feeder.eternal-september.org	12.4 MB	5.6 KB	11.9 MB	24.3 MB	50%	4.6 KB
4	csiph-out.news.weretis.net	7.1 MB	6.0 KB	9.5 MB	16.6 MB	42%	5.3 KB
5	news-out.mixmin.net	6.3 MB	7.2 KB	883.4 KB	7.2 MB	87%	4.7 KB
6	newsfeed.neva.ru	5.1 MB	38.9 KB	51.5 MB	56.7 MB	9%	7.3 KB
7	news-out.aioe.org	4.6 MB	1.8 KB	62.2 KB	4.6 MB	98%	2.4 KB
8	newsfeed.fu-berlin.de	2.5 MB	1.4 KB	738.1 KB	3.2 MB	77%	2.4 KB
9	news.freedyn.net	1.8 MB	1.2 KB	834.2 KB	2.6 MB	68%	4.5 KB
10	newsfeeds.xmission.com	1.4 MB	0.0 KB	697.1 KB	2.1 MB	67%	3.6 KB
11	news.fcku.it	656.5 KB	0.0 KB	325.2 KB	981.7 KB	66%	2.7 KB
12	au2pb.net	518.1 KB	0.0 KB	630.4 KB	1.1 MB	45%	4.5 KB
13	news.redatomik.org	491.1 KB	0.0 KB	81.2 KB	572.3 KB	85%	2.8 KB
14	news.swapon.de	234.6 KB	1.1 KB	20.0 KB	255.7 KB	91%	2.4 KB
15	xanadu-bbs.net	97.2 KB	0.0 KB	104.7 KB	202.0 KB	48%	3.6 KB
16	nntp.pasdenom.info	72.8 KB	3.1 KB	22.6 KB	98.5 KB	73%	2.1 KB
17	localhost	18.9 KB	0.0 KB	0.0 KB	18.9 KB	100%	1.3 KB
18	news.bbs.geek.nz	7.3 KB	0.0 KB	0.0 KB	7.3 KB	100%	1.2 KB
TOTAL: 18		79.3 MB	301.9 KB	141.0 MB	220.6 MB	35%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 8 04:15:00 - 04:59:59	424	1.6%	0.16	1.9 MB	2.4%	0.72
Jun 8 05:00:00 - 05:59:59	693	2.6%	0.19	2.6 MB	3.3%	0.75
Jun 8 06:00:00 - 06:59:59	826	3.1%	0.23	2.7 MB	3.4%	0.78
Jun 8 07:00:00 - 07:59:59	1047	4.0%	0.29	2.8 MB	3.5%	0.79
Jun 8 08:00:00 - 08:59:59	1164	4.4%	0.32	3.9 MB	4.9%	1.11
Jun 8 09:00:00 - 09:59:59	1342	5.1%	0.37	3.6 MB	4.5%	1.02
Jun 8 10:00:00 - 10:59:59	1315	5.0%	0.37	4.2 MB	5.3%	1.20
Jun 8 11:00:00 - 11:59:59	1421	5.4%	0.39	4.2 MB	5.3%	1.20
Jun 8 12:00:00 - 12:59:59	1411	5.4%	0.39	3.7 MB	4.6%	1.05
Jun 8 13:00:00 - 13:59:59	1430	5.4%	0.40	4.0 MB	5.0%	1.14
Jun 8 14:00:00 - 14:59:59	1547	5.9%	0.43	4.7 MB	5.9%	1.35
Jun 8 15:00:00 - 15:59:59	1613	6.1%	0.45	5.7 MB	7.1%	1.62
Jun 8 16:00:00 - 16:59:59	1534	5.8%	0.43	4.9 MB	6.2%	1.40
Jun 8 17:00:00 - 17:59:59	1226	4.7%	0.34	3.2 MB	3.9%	0.90
Jun 8 18:00:00 - 18:59:59	1329	5.1%	0.37	3.6 MB	4.5%	1.03
Jun 8 19:00:00 - 19:59:59	1449	5.5%	0.40	4.3 MB	5.4%	1.23
Jun 8 20:00:00 - 20:59:59	1271	4.8%	0.35	3.5 MB	4.3%	0.99
Jun 8 21:00:00 - 21:59:59	1220	4.6%	0.34	3.7 MB	4.6%	1.04
Jun 8 22:00:00 - 22:59:59	971	3.7%	0.27	3.2 MB	4.0%	0.92
Jun 8 23:00:00 - 23:59:59	754	2.9%	0.21	2.5 MB	3.1%	0.71
Jun 9 00:00:00 - 00:59:59	599	2.3%	0.17	1.7 MB	2.2%	0.50
Jun 9 01:00:00 - 01:59:59	481	1.8%	0.13	1.5 MB	1.9%	0.43
Jun 9 02:00:00 - 02:59:59	537	2.0%	0.15	1.7 MB	2.1%	0.48
Jun 9 03:00:00 - 03:59:59	505	1.9%	0.14	1.5 MB	1.9%	0.43
Jun 9 04:00:00 - 04:15:00	189	0.7%	0.21	555.9 KB	0.7%	0.62
TOTAL: 24:00:00	26298	100.0%	0.30	80.0 MB	100.0%	0.95

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	csiph-out.feeder.erje.net	8564	537	21	0	8006
2	newsfeed.neva.ru	6069	1505	4	0	4560
3	usenet.blueworldhosting.com	2685	1287	0	0	1398
4	feeder.eternal-september.org	1058	135	3	0	920
5	csiph-out.news.weretis.net	851	285	4	0	562
6	newsfeed.fu-berlin.de	603	21	1	0	581
7	news-out.mixmin.net	253	0	2	0	251
8	news.freedyn.net	101	0	1	0	100
9	au2pb.net	54	47	0	0	7
10	newsfeeds.xmission.com	52	42	0	0	10
11	news.swapon.de	26	0	1	0	25
12	news-out.aioe.org	25	15	1	0	9
13	news.fcku.it	23	0	0	0	23
14	xanadu-bbs.net	13	0	0	0	13
15	nntp.pasdenom.info	11	2	2	2	5
16	news.redatomik.org	6	0	0	0	6
TOTAL: 16		20394	3876	40	2	16476

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	1092
mailing.freebsd.pkg-fallout	237
lada.talk	222
mailing.freebsd.svn-src-all	138
lada.auto	128
fa.freebsd.cvs-all	118
mailing.freebsd.ports-bugs	73
mailing.freebsd.svn-src-stable	69
ut.test	67
mailing.unix.spamassassin-talk	66
mailing.freebsd.svn-src-head	65
mailing.freebsd.svn-ports-all	56
mailing.gcc.gcc-cvs	54
mailing.freebsd.svn-ports-head	53
mpc.lists.nanog	47
it-alt.sport.calcio.juventus	44
alt.windows7.general	43
lada.mobile	42
alt.survival	39
mailing.freebsd.svn-src-stable-10	38
TOTAL: 256	3876

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10951
EMP (phn path)	2122
PFM bot misc	754
EMP (phl)	746
EMP (phn nph)	89
Too many newsgroups	67
HTML Multipart	55
Scoring filter (10)	49
Scoring filter (17)	24
Scoring filter (18)	22
Scoring filter (9)	22
Scoring filter (21)	20
Scoring filter (15)	18
HTML post	17
Binary Payload	15
Scoring filter (19)	13
Scoring filter (12)	12
Scoring filter (13)	12
User-issued cancel	11
Scoring filter (8)	9
TOTAL: 44	15092

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	53	0	57	57
TOTAL: 1	53	0	57	57

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	85
	usenet.blueworldhosting.com	2
	TOTAL: 2	87
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	usenet.blueworldhosting.com	1
	TOTAL: 2	5
Huge articles
	usenet.blueworldhosting.com	68
	csiph-out.news.weretis.net	56
	feeder.eternal-september.org	30
	news-out.mixmin.net	10
	newsfeeds.xmission.com	8
	news.fcku.it	7
	news.freedyn.net	5
	xanadu-bbs.net	2
	news.redatomik.org	2
	newsfeed.fu-berlin.de	2
	TOTAL: 11	191
Including strange strings
	newsfeed.fu-berlin.de	352
	csiph-out.feeder.erje.net	44
	feeder.eternal-september.org	35
	csiph-out.news.weretis.net	28
	newsfeed.neva.ru	17
	news.swapon.de	15
	news-out.mixmin.net	8
	news-out.aioe.org	6
	newsfeeds.xmission.com	2
	news.redatomik.org	1
	TOTAL: 13	511
No colon-space in header
	csiph-out.feeder.erje.net	590
	usenet.blueworldhosting.com	4
	TOTAL: 2	594
TOTAL: 5		1388

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	47609	2453	22779	40	0	5%	23:50:04
2	erje	27463	1356	20145	26	50	4%	23:59:56
3	news.freedyn.net	29704	1137	24589	7	0	3%	23:53:36
4	xanadu-bbs	29509	999	25238	1	59	3%	23:59:56
5	fu-berlin.de	24903	858	24045	0	0	3%	23:59:56
6	usenet.pasdenom.info	33707	152	24822	1286	6	0%	23:59:56
7	au2pb	26979	110	25068	3	0	0%	23:59:56
8	weretis.net	23488	105	22142	28	0	0%	23:58:13
9	aioe	24578	101	21312	3	0	0%	23:53:58
10	news.redatomik.org	27990	80	24979	11	0	0%	23:57:00
11	xmission	29841	77	24577	2	0	0%	23:59:56
12	eternal-september	25213	21	20505	1	1	0%	23:59:56
13	news.mixmin.net	25926	19	23311	0	0	0%	23:59:56
14	bwh	20990	15	18687	0	51	0%	23:59:56
15	news.swapon.de	2295	2	2016	14	0	0%	23:55:03
16	news.fcku.it	3384	2	2959	0	0	0%	22:32:04
17	neva.ru	26651	2	23105	25	1	0%	23:59:52
TOTAL: 17		430230	7489	350279	1447	168	1%	405:59:14

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.0 MB	35.6 KB	10.0 MB	0.1 KB/s	9.0 KB	23:53:36
2	xanadu-bbs	9.6 MB	1.0 KB	9.6 MB	0.1 KB/s	9.8 KB	23:59:56
3	fu-berlin.de	9.3 MB	0.0 KB	9.3 MB	0.1 KB/s	11.1 KB	23:59:56
4	news.bbs.geek.nz	6.1 MB	118.8 KB	6.3 MB	0.1 KB/s	2.6 KB	23:50:04
5	erje	3.4 MB	172.5 KB	3.6 MB	0.0 KB/s	2.6 KB	23:59:56
6	usenet.pasdenom.info	364.4 KB	10.5 MB	10.9 MB	0.1 KB/s	7.7 KB	23:59:56
7	au2pb	274.2 KB	28.1 KB	302.3 KB	0.0 KB/s	2.7 KB	23:59:56
8	aioe	270.6 KB	5.9 KB	276.4 KB	0.0 KB/s	2.7 KB	23:53:58
9	weretis.net	255.0 KB	165.0 KB	420.0 KB	0.0 KB/s	3.2 KB	23:58:13
10	xmission	234.8 KB	4.1 KB	238.9 KB	0.0 KB/s	3.0 KB	23:59:56
11	news.redatomik.org	174.7 KB	19.3 KB	194.0 KB	0.0 KB/s	2.1 KB	23:57:00
12	eternal-september	36.7 KB	2.0 KB	38.7 KB	0.0 KB/s	1.8 KB	23:59:56
13	news.mixmin.net	36.0 KB	0.0 KB	36.0 KB	0.0 KB/s	1.9 KB	23:59:56
14	bwh	20.8 KB	0.0 KB	20.8 KB	0.0 KB/s	1.4 KB	23:59:56
15	news.fcku.it	7.9 KB	0.0 KB	7.9 KB	0.0 KB/s	4.0 KB	22:32:04
16	neva.ru	2.2 KB	43.9 KB	46.1 KB	0.0 KB/s	1.7 KB	23:59:52
17	news.swapon.de	1.8 KB	24.9 KB	26.7 KB	0.0 KB/s	1.7 KB	23:55:03
TOTAL: 17		40.1 MB	11.1 MB	51.2 MB	0.0 KB/s	5.9 KB	405:59:14

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	124	107101	374.3 MB	182	0	0	09:31:35
2	dhcp-089-099-203-254.chello.nl	23	17	25.1 KB	5	0	0	09:46:03
3	68-185-181-64.dhcp.mdsn.wi.charter.com	25	17	24.0 KB	14	14	14	05:18:14
4	challenger.nielsenmedia.com	17	6	10.9 KB	3	0	0	03:46:04
5	92-108-162-83.dynamic.upc.nl	2	5	7.4 KB	1	0	0	00:02:55
6	71.98.160.36	5	2	5.0 KB	1	0	0	01:33:56
TOTAL: 6		196	107148	374.4 MB	206	14	14	36:54:10

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	124	107101	374.3 MB	182	0	0	09:31:35
2	*.chello.nl	23	17	25.1 KB	5	0	0	09:46:03
3	*.dhcp.mdsn.wi.charter.com	25	17	24.0 KB	14	14	14	05:18:14
4	*.nielsenmedia.com	17	6	10.9 KB	3	0	0	03:46:04
5	*.dynamic.upc.nl	2	5	7.4 KB	1	0	0	00:02:55
6	unresolved	15	2	5.0 KB	1	0	0	08:29:16
TOTAL: 6		206	107148	374.4 MB	206	14	14	36:54:10

NNRP auth users [Top 20]:

User	Conn
ohlandl	14
moussa	1
TOTAL: 2	15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	2.100	0.532	0.000	09:46:03
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	30.953	35.863	0.000	09:31:35
216.239.174.71	0.361	0.177	0.000	06:25:19
68-185-181-64.dhcp.mdsn.wi.charter.com	2.606	0.588	0.036	05:18:14
challenger.nielsenmedia.com	1.524	0.364	0.000	03:46:04
71.98.160.36	0.573	0.103	0.000	01:33:56
138.108.60.60	0.094	0.008	0.000	00:30:00
92-108-162-83.dynamic.upc.nl	0.220	0.048	0.000	00:02:55
TOTAL: 8	38.431	37.683	0.036	36:54:10

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.71	8
138.108.60.60	1
202.89.176.220	1
TOTAL: 3	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	17	0
challenger.nielsenmedia.com	7	1
68-185-181-64.dhcp.mdsn.wi.charter.com	6	0
71.98.160.36	2	0
138.108.60.60	1	0
TOTAL: 5	33	1

Newsgroup request counts (by category):

Category		Count	Pct
1	talk	107083	99.9%
2	comp	51	0.0%
3	alt	6	0.0%
4	rec	4	0.0%
5	uk	4	0.0%
TOTAL: 5		107148	100.0%

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

Newsgroup		Count
1	talk.politics.guns	107083
2	comp.sys.ibm.ps2.hardware	39
3	comp.lang.awk	8
4	alt.happy.valley	5
5	comp.mobile.ipad	3
6	rec.arts.tv	3
7	alt.slack	1
8	rec.arts.comics.strips	1
9	uk.rec.motorcycles	1
10	comp.lang.python	1
11	uk.sci.weather	1
12	uk.d-i-y	1
13	uk.comp.homebuilt	1
TOTAL: 13		107148

Daily Usenet report for csiph.com

Jun 8 04:15:00 -- Jun 9 04:15:00