Daily Usenet report for csiph.com: Aug 9 04:15:00

Unknown entries from news log file:

First 50 / 2760 lines (1.8%)

Aug 9 04:57:11 csiph nnrpd[60719]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 04:57:41 csiph nnrpd[60869]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 04:58:11 csiph nnrpd[60893]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 04:58:41 csiph nnrpd[60895]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 04:59:12 csiph nnrpd[60903]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 04:59:42 csiph nnrpd[60921]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:00:12 csiph nnrpd[60963]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:00:42 csiph nnrpd[60987]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:01:12 csiph nnrpd[60994]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:01:43 csiph nnrpd[61018]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:02:13 csiph nnrpd[61020]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:02:44 csiph nnrpd[61046]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:03:15 csiph nnrpd[61049]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:03:46 csiph nnrpd[61076]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:04:17 csiph nnrpd[61140]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:04:47 csiph nnrpd[61164]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:05:17 csiph nnrpd[61177]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:05:47 csiph nnrpd[61248]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:06:17 csiph nnrpd[61409]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:06:47 csiph nnrpd[61562]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:07:19 csiph nnrpd[61675]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:07:49 csiph nnrpd[62019]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:08:19 csiph nnrpd[62127]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:08:49 csiph nnrpd[62128]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:09:20 csiph nnrpd[62294]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:09:51 csiph nnrpd[62302]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:10:22 csiph nnrpd[62340]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:10:56 csiph nnrpd[62346]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:11:26 csiph nnrpd[62395]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:11:56 csiph nnrpd[62398]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:12:26 csiph nnrpd[62424]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:12:56 csiph nnrpd[62425]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:13:26 csiph nnrpd[62449]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:13:58 csiph nnrpd[62450]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:14:29 csiph nnrpd[62474]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:15:00 csiph nnrpd[62475]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:15:31 csiph nnrpd[62500]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:16:02 csiph nnrpd[62510]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:16:33 csiph nnrpd[62511]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:17:04 csiph nnrpd[62535]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:17:34 csiph nnrpd[62536]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:18:04 csiph nnrpd[62561]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:18:34 csiph nnrpd[62562]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:19:05 csiph nnrpd[62586]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:19:35 csiph nnrpd[62722]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:20:05 csiph nnrpd[62756]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:20:35 csiph nnrpd[62757]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:21:05 csiph nnrpd[62781]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:21:35 csiph nnrpd[62785]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission
Aug 9 05:22:06 csiph nnrpd[62833]: 64-136-202-186.dyn.everestkc.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	49382	52.1%	4.5 MB	41.7%
inn	36152	38.2%	5.2 MB	48.6%
innd	7325	7.7%	818.3 KB	7.5%
innfeed	1835	1.9%	247.4 KB	2.3%
controlchan	11	0.0%	0.8 KB	0.0%
TOTAL: 5	94705	100.0%	10.7 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	134741	50.1%
Positive hits	93361	34.7%
Do not exist	34497	12.8%
Cache misses	6293	2.3%
TOTAL: 4	268892	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.699	0.0%	1651	0.000	1.029	68.000
article cleanup	00:00:00.244	0.0%	34234	0.000	0.007	0.031
article logging	00:00:00.467	0.0%	34299	0.000	0.014	0.038
article parse	00:00:01.098	0.0%	115497	0.003	0.010	0.027
article write	00:00:06.097	0.0%	26301	0.125	0.232	0.394
artlog/artcncl	00:00:00.012	0.0%	1556	0.000	0.008	1.000
artlog/artparse	00:00:00.001	0.0%	155	0.000	0.006	0.250
data move	00:00:00.131	0.0%	329359	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.031	0.0%	1343	0.000	0.023	0.333
hishave/artcncl	00:00:00.005	0.0%	1651	0.000	0.003	0.004
hishave/artparse	00:00:00.000	0.0%	155	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:00.879	0.0%	268465	0.000	0.003	0.009
history sync	00:00:00.470	0.0%	377	0.000	1.247	10.000
history write	00:00:08.135	0.0%	34193	0.077	0.238	1.279
hiswrite/artcncl	00:00:00.047	0.0%	308	0.000	0.153	2.000
hiswrite/artparse	00:00:00.040	0.0%	154	0.000	0.260	2.000
idle	23:49:15.942	99.9%	339899	93.695	252.298	653.262
nntp read	00:00:01.147	0.0%	331589	0.001	0.003	0.008
overview write	00:00:10.220	0.0%	26301	0.069	0.389	12.050
perl filter	00:00:41.338	0.0%	34193	0.030	1.209	3.202
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.518	0.0%	52602	0.000	0.010	0.028
TOTAL: 23:51:01.146	23:50:28.521	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.063	0.0%	26205	0.000	0.002	0.024
article prepare	00:00:00.001	0.0%	3806	0.000	0.000	0.021
article read	00:00:00.477	0.0%	947	0.000	0.504	68.000
backlog stats	00:00:00.043	0.0%	179099	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:00.800	0.0%	132691	0.000	0.006	0.014
data write	00:00:00.992	0.0%	108207	0.002	0.009	0.021
idle	23:14:26.427	100.0%	179099	68.443	467.152	1420.495
status file	00:00:00.075	0.0%	206	0.000	0.364	1.000
TOTAL: 23:14:32.010	23:14:28.878	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.408	0.0%	14775	0.000	0.028	0.064
idle	101:07:44.943	99.9%	57064	0.000	6379.941	600274.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.742	0.0%	115429	0.000	0.006	0.333
readart	00:00:00.095	0.0%	79	0.000	1.203	44.000
TOTAL: 101:14:48.691	101:07:46.188	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	3
logmode	2
mode	151
pause	3
paused	3
reload	10
reserve	2
rmgroup	7
shutdown	9
TOTAL: 12	197

Newsgroups removed:

Group
alt.barefoot
alt.barefoot.children
alt.binaries.movies.christian
alt.sex
alt.sex.magazines
alt.sex.phone
TOTAL: 6

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	16	32011	11952	17024	3035	37%	27:07:36
2	au2pb.net	3	25394	9412	14957	1025	37%	24:28:17
3	newsfeed.neva.ru	145	35751	4209	27689	3853	11%	71:24:32
4	nntp.pasdenom.info	7	24347	796	23463	88	3%	24:00:39
5	xanadu-bbs.net	0	525	185	317	23	35%	00:45:40
6	localhost	51	16087	0	16087	0	0%	00:01:00
TOTAL: 6		222	134115	26554	99537	8024	19%	147:47:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	31.8 MB	38.2 KB	17.0 MB	48.9 MB	65%	3.3 KB
2	au2pb.net	25.3 MB	1.6 KB	11.9 MB	37.2 MB	67%	3.6 KB
3	newsfeed.neva.ru	11.3 MB	807.9 KB	12.6 MB	24.7 MB	45%	3.1 KB
4	nntp.pasdenom.info	2.1 MB	5.5 KB	1.3 MB	3.4 MB	62%	3.9 KB
5	xanadu-bbs.net	811.2 KB	0.0 KB	496.3 KB	1.3 MB	62%	6.3 KB
6	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 6		71.2 MB	853.3 KB	43.3 MB	115.4 MB	61%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 9 04:15:00 - 04:59:59	464	1.8%	0.17	1.5 MB	2.2%	0.58
Aug 9 05:00:00 - 05:59:59	643	2.4%	0.18	2.7 MB	3.8%	0.77
Aug 9 06:00:00 - 06:59:59	844	3.2%	0.23	2.0 MB	2.9%	0.58
Aug 9 07:00:00 - 07:59:59	901	3.4%	0.25	2.2 MB	3.1%	0.63
Aug 9 08:00:00 - 08:59:59	948	3.6%	0.26	2.2 MB	3.1%	0.63
Aug 9 09:00:00 - 09:59:59	1180	4.5%	0.33	3.4 MB	4.8%	0.97
Aug 9 10:00:00 - 10:59:59	1278	4.8%	0.35	3.1 MB	4.5%	0.89
Aug 9 11:00:00 - 11:59:59	1243	4.7%	0.35	3.1 MB	4.4%	0.87
Aug 9 12:00:00 - 12:59:59	1155	4.4%	0.32	2.9 MB	4.2%	0.84
Aug 9 13:00:00 - 13:59:59	1128	4.3%	0.31	3.0 MB	4.2%	0.84
Aug 9 14:00:00 - 14:59:59	1404	5.3%	0.39	3.7 MB	5.3%	1.06
Aug 9 15:00:00 - 15:59:59	1379	5.2%	0.38	3.3 MB	4.6%	0.93
Aug 9 16:00:00 - 16:59:59	1251	4.7%	0.35	3.6 MB	5.1%	1.02
Aug 9 17:00:00 - 17:59:59	1355	5.1%	0.38	3.7 MB	5.3%	1.07
Aug 9 18:00:00 - 18:59:59	1443	5.5%	0.40	3.7 MB	5.2%	1.04
Aug 9 19:00:00 - 19:59:59	2633	10.0%	0.73	4.8 MB	6.8%	1.35
Aug 9 20:00:00 - 20:59:59	1375	5.2%	0.38	3.6 MB	5.2%	1.04
Aug 9 21:00:00 - 21:59:59	1533	5.8%	0.43	4.2 MB	6.0%	1.20
Aug 9 22:00:00 - 22:59:59	979	3.7%	0.27	2.8 MB	4.0%	0.81
Aug 9 23:00:00 - 23:59:59	898	3.4%	0.25	2.6 MB	3.6%	0.73
Aug 10 00:00:00 - 00:59:59	703	2.7%	0.20	2.1 MB	2.9%	0.58
Aug 10 01:00:00 - 01:59:59	545	2.1%	0.15	1.6 MB	2.3%	0.46
Aug 10 02:00:00 - 02:59:59	586	2.2%	0.16	3.0 MB	4.2%	0.84
Aug 10 03:00:00 - 03:59:59	414	1.6%	0.12	1.2 MB	1.7%	0.35
Aug 10 04:00:00 - 04:15:00	136	0.5%	0.15	360.2 KB	0.5%	0.40
TOTAL: 24:00:00	26418	100.0%	0.31	70.4 MB	100.0%	0.83

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	3894	976	34	2	2882
2	feeder.eternal-september.org	3091	321	6	0	2764
3	au2pb.net	1036	941	0	0	95
4	nntp.pasdenom.info	128	94	1	0	33
5	xanadu-bbs.net	23	23	0	0	0
TOTAL: 5		8172	2355	41	2	5774

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.pkg-fallout	200
fa.linux.kernel	185
lada.talk	112
alt.politics.scorched-earth	97
ut.test	95
pld.cvs.commit	82
mailing.freebsd.svn-ports-all	81
alt.politics	80
mailing.freebsd.svn-ports-head	80
mailing.freebsd.svn-src-all	64
fa.freebsd.cvs-all	62
alt.windows7.general	61
mailing.freebsd.ruby	47
mailing.freebsd.svn-src-head	43
mailing.freebsd.gnome	31
mpc.lists.freebsd.gnome	31
alt.survival	30
mpc.lists.freebsd.ports.bugs	30
mailing.freebsd.ports-bugs	30
lada.auto	29
TOTAL: 215	2355

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

Groups	Count
it.scienza.astronomia	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4531
EMP (phl)	697
EMP (phn path)	141
PFM bot misc	80
Too many newsgroups	37
Binary Image: misplaced jpg	26
HTML Multipart	21
HTML post	4
HTML Image Tags	2
Binary: misplaced r13	1
Binary: misplaced PAR2	1
Binary Image: misplaced gif	1
Binary: misplaced nfo	1
Binary: misplaced r21	1
HTML file attachment	1
Binary: misplaced par2	1
Binary: misplaced sfv	1
Binary: misplaced r14	1
Binary: misplaced r09	1
TOTAL: 19	5549

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	au2pb.net	79
	feeder.eternal-september.org	50
	nntp.pasdenom.info	30
	TOTAL: 3	159
Including strange strings
	feeder.eternal-september.org	41
	newsfeed.neva.ru	12
	au2pb.net	10
	nntp.pasdenom.info	3
	TOTAL: 4	66
TOTAL: 2		225

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	au2pb	17582	17	17017	27	1	0	0%	23:58:22
2	xanadu-bbs	27362	9	26203	1	0	3	0%	23:58:22
3	neva.ru	22402	2	22049	3	0	2	0%	23:58:17
4	usenet.pasdenom.info	29494	2	24692	899	0	260	0%	23:58:21
5	eternal-september	14199	1	13882	0	0	1	0%	23:58:20
6	xmission	0	0	0	0	0	26251	0%	23:58:24
TOTAL: 6		111039	31	103843	930	1	26517	0%	143:50:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	259.0 KB	57.5 KB	316.5 KB	7.2 KB	23:58:22
2	xanadu-bbs	56.5 KB	1.7 KB	58.2 KB	5.8 KB	23:58:22
3	usenet.pasdenom.info	17.0 KB	1.6 MB	1.6 MB	1.9 KB	23:58:21
4	neva.ru	7.0 KB	30.2 KB	37.2 KB	7.4 KB	23:58:17
5	eternal-september	1.8 KB	0.0 KB	1.8 KB	1.8 KB	23:58:20
6	xmission	0.0 KB	0.0 KB	0.0 KB	0	23:58:24
TOTAL: 6		341.2 KB	1.7 MB	2.0 MB	2.2 KB	143:50:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	75	217.3 KB	71	04:23:59
2	tempe0.bbox.io	297	3	5.9 KB	2	49:18:10
3	dhcp-089-099-203-254.chello.nl	42	1	2.1 KB	1	19:00:54
TOTAL: 3		627	79	225.3 KB	74	101:14:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	420	75	217.3 KB	71	30:53:37
2	*.bbox.io	297	3	5.9 KB	2	49:18:10
3	*.chello.nl	42	1	2.1 KB	1	19:00:54
4	*.dyn.everestkc.net	2760	0	0.0 KB	0	00:14:42
5	*.tampfl.dsl-w.verizon.net	16	0	0.0 KB	0	01:46:19
6	*.br.br.cox.net	2	0	0.0 KB	0	00:00:33
TOTAL: 6		3537	79	225.3 KB	74	101:14:17

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
tempe0.bbox.io	23.808	3.986	0.000	49:18:10
108.175.241.207	7.723	2.041	0.000	26:29:36
dhcp-089-099-203-254.chello.nl	3.413	0.559	0.000	19:00:54
202.89.176.220	27.931	5.562	0.695	04:23:59
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1.079	0.215	0.000	01:46:19
64-136-202-186.dyn.everestkc.net	221.726	37.039	0.000	00:14:42
ip70-186-187-149.br.br.cox.net	0.157	0.016	0.000	00:00:33
69.160.37.54	1.861	0.317	0.000	00:00:02
TOTAL: 8	287.698	49.735	0.695	101:14:17

Curious NNRP server explorers [Top 20]:

System	Conn
64-136-202-186.dyn.everestkc.net	2760
108.175.241.207	103
69.160.37.54	25
pool-71-98-160-84.tampfl.dsl-w.verizon.net	16
162.212.88.198	4
ip70-186-187-149.br.br.cox.net	2
TOTAL: 6	2910

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	33	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3	0
tempe0.bbox.io	3	0
TOTAL: 3	39	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	25
2	comp.os.vms	12
3	comp.sys.ibm.pc.hardware.storage	10
4	comp.os.linux.networking	9
5	comp.os.msdos.misc	7
6	comp.os.os2.advocacy	5
7	comp.arch.embedded	5
8	comp.sys.ibm.ps2.hardware	4
9	comp.arch.fpga	1
10	comp.sys.ibm.pc.games.action	1
TOTAL: 10		79

Daily Usenet report for csiph.com

Aug 9 04:15:00 -- Aug 10 04:15:00