Daily Usenet report for csiph.com: Aug 4 04:15:01

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

Aug 4 09:50:05 csiph pgpverify[39620]: pgpverify: verification failed
Aug 4 22:51:51 csiph nnrpd[73116]: au2pb.net noperm ihave without permission
Aug 4 22:52:25 csiph nnrpd[73118]: au2pb.net noperm ihave without permission
Aug 4 22:52:55 csiph nnrpd[73145]: au2pb.net noperm ihave without permission
Aug 4 22:53:25 csiph nnrpd[73281]: au2pb.net noperm ihave without permission
Aug 4 22:53:55 csiph nnrpd[73313]: au2pb.net noperm ihave without permission
Aug 4 22:54:25 csiph nnrpd[73316]: au2pb.net noperm ihave without permission
Aug 4 22:54:56 csiph nnrpd[73340]: au2pb.net noperm ihave without permission
Aug 4 22:55:27 csiph nnrpd[73372]: au2pb.net noperm ihave without permission
Aug 4 22:55:58 csiph nnrpd[73396]: au2pb.net noperm ihave without permission
Aug 4 22:56:28 csiph nnrpd[73397]: au2pb.net noperm ihave without permission
Aug 4 22:56:59 csiph nnrpd[73409]: au2pb.net noperm ihave without permission
Aug 4 22:57:30 csiph nnrpd[73425]: au2pb.net noperm ihave without permission
Aug 4 22:58:00 csiph nnrpd[73430]: au2pb.net noperm ihave without permission
Aug 4 22:58:31 csiph nnrpd[73454]: au2pb.net noperm ihave without permission
Aug 4 22:59:01 csiph nnrpd[73456]: au2pb.net noperm ihave without permission
Aug 4 22:59:32 csiph nnrpd[73481]: au2pb.net noperm ihave without permission
Aug 4 23:00:02 csiph nnrpd[73580]: au2pb.net noperm ihave without permission
Aug 4 23:00:32 csiph nnrpd[73787]: au2pb.net noperm ihave without permission
Aug 4 23:01:02 csiph nnrpd[73988]: au2pb.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	47414	57.8%	6.1 MB	67.7%
nnrpd	32400	39.5%	2.6 MB	29.0%
innfeed	1095	1.3%	159.4 KB	1.7%
innd	1061	1.3%	140.2 KB	1.5%
controlchan	7	0.0%	0.7 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	81978	100.0%	9.0 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	62939	42.2%
Do not exist	46004	30.9%
Positive hits	35986	24.2%
Cache misses	4067	2.7%
TOTAL: 4	148996	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.304	0.0%	287	0.000	1.059	65.000
article cleanup	00:00:00.267	0.0%	46109	0.000	0.006	0.020
article logging	00:00:00.603	0.0%	46220	0.000	0.013	0.035
article parse	00:00:02.048	0.0%	302641	0.002	0.007	0.013
article write	00:00:11.818	0.0%	41490	0.176	0.285	0.493
artlog/artcncl	00:00:00.004	0.0%	287	0.000	0.014	0.500
data move	00:00:00.176	0.0%	417288	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.006	0.0%	267	0.000	0.022	1.000
hishave/artcncl	00:00:00.000	0.0%	287	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.703	0.0%	148957	0.000	0.005	0.034
history sync	00:00:00.442	0.0%	374	0.000	1.182	4.667
history write	00:00:09.592	0.0%	46099	0.069	0.208	0.855
hiswrite/artcncl	00:00:00.001	0.0%	20	0.000	0.050	0.143
idle	23:30:12.221	99.9%	394682	18.622	214.381	869.547
nntp read	00:00:01.196	0.0%	392527	0.001	0.003	0.007
overview write	00:00:09.368	0.0%	41490	0.114	0.226	1.511
perl filter	00:00:01.758	0.0%	46099	0.012	0.038	0.066
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.695	0.0%	82981	0.000	0.008	0.019
TOTAL: 23:31:04.050	23:30:51.202	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.085	0.0%	41583	0.000	0.002	0.016
article prepare	00:00:00.000	0.0%	6656	0.000	0.000	0.000
article read	00:00:00.761	0.0%	1639	0.000	0.464	68.000
backlog stats	00:00:00.061	0.0%	208494	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.864	0.0%	128350	0.001	0.007	0.019
data write	00:00:01.036	0.0%	105726	0.000	0.010	0.035
idle	23:33:58.057	100.0%	208494	108.476	406.909	1537.211
status file	00:00:00.055	0.0%	189	0.000	0.291	1.000
TOTAL: 23:34:03.480	23:34:00.919	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.154	0.0%	6369	0.012	0.024	0.040
idle	53:45:13.825	100.0%	38368	0.000	5043.626	600322.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.559	0.0%	77842	0.000	0.007	0.367
readart	00:00:00.129	0.0%	129	0.000	1.000	38.000
TOTAL: 53:46:40.699	53:45:14.667	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	148
pause	2
paused	2
reload	2
reserve	2
shutdown	9
TOTAL: 11	176

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	xanadu-bbs.net	3	46966	32905	10502	3559	70%	23:56:11
2	nntp.pasdenom.info	10	24358	5346	18110	902	21%	27:03:31
3	au2pb.net	6	4041	1930	1900	211	47%	04:56:16
4	feeder.eternal-september.org	12	2910	1924	981	5	66%	23:15:20
5	localhost	48	7129	0	7129	0	0%	00:00:57
TOTAL: 5		79	85404	42105	38622	4677	49%	79:12:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	xanadu-bbs.net	203.5 MB	68.3 KB	99.0 MB	302.5 MB	67%	8.5 KB
2	nntp.pasdenom.info	18.1 MB	106.1 KB	3.8 MB	22.0 MB	82%	3.6 KB
3	au2pb.net	18.0 MB	0.0 KB	697.1 KB	18.7 MB	96%	9.0 KB
4	feeder.eternal-september.org	3.3 MB	8.5 KB	8.5 KB	3.3 MB	99%	1.8 KB
5	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		242.9 MB	182.9 KB	103.5 MB	346.5 MB	70%	7.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 4 04:15:01 - 04:59:59	813	1.9%	0.30	5.9 MB	2.5%	2.26
Aug 4 05:00:00 - 05:59:59	1079	2.6%	0.30	7.3 MB	3.0%	2.07
Aug 4 06:00:00 - 06:59:59	1286	3.0%	0.36	6.5 MB	2.7%	1.85
Aug 4 07:00:00 - 07:59:59	2020	4.8%	0.56	8.7 MB	3.6%	2.46
Aug 4 08:00:00 - 08:59:59	1882	4.4%	0.52	7.0 MB	2.9%	1.98
Aug 4 09:00:00 - 09:59:59	2243	5.3%	0.62	9.9 MB	4.1%	2.82
Aug 4 10:00:00 - 10:59:59	2114	5.0%	0.59	16.4 MB	6.8%	4.67
Aug 4 11:00:00 - 11:59:59	2030	4.8%	0.56	16.2 MB	6.7%	4.61
Aug 4 12:00:00 - 12:59:59	2048	4.8%	0.57	8.4 MB	3.5%	2.39
Aug 4 13:00:00 - 13:59:59	2063	4.9%	0.57	8.7 MB	3.6%	2.47
Aug 4 14:00:00 - 14:59:59	2512	5.9%	0.70	13.1 MB	5.4%	3.74
Aug 4 15:00:00 - 15:59:59	2439	5.8%	0.68	10.2 MB	4.2%	2.90
Aug 4 16:00:00 - 16:59:59	2260	5.3%	0.63	9.3 MB	3.9%	2.65
Aug 4 17:00:00 - 17:59:59	2360	5.6%	0.66	11.5 MB	4.8%	3.27
Aug 4 18:00:00 - 18:59:59	1981	4.7%	0.55	9.2 MB	3.8%	2.61
Aug 4 19:00:00 - 19:59:59	2308	5.5%	0.64	10.1 MB	4.2%	2.87
Aug 4 20:00:00 - 20:59:59	1899	4.5%	0.53	7.0 MB	2.9%	1.99
Aug 4 21:00:00 - 21:59:59	2036	4.8%	0.57	12.4 MB	5.1%	3.53
Aug 4 22:00:00 - 22:59:59	1415	3.3%	0.39	5.4 MB	2.2%	1.53
Aug 4 23:00:00 - 23:59:59	1483	3.5%	0.41	29.0 MB	12.0%	8.24
Aug 5 00:00:00 - 00:59:59	1080	2.6%	0.30	7.4 MB	3.1%	2.11
Aug 5 01:00:00 - 01:59:59	882	2.1%	0.24	5.0 MB	2.1%	1.42
Aug 5 02:00:00 - 02:59:59	906	2.1%	0.25	5.6 MB	2.3%	1.59
Aug 5 03:00:00 - 03:59:59	881	2.1%	0.24	8.3 MB	3.4%	2.35
Aug 5 04:00:00 - 04:15:00	288	0.7%	0.32	3.1 MB	1.3%	3.56
TOTAL: 23:59:59	42308	100.0%	0.49	241.6 MB	100.0%	2.86

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	3621	3545	4	72
2	nntp.pasdenom.info	941	924	1	16
3	au2pb.net	231	224	0	7
4	feeder.eternal-september.org	23	0	5	18
TOTAL: 4		4816	4693	10	113

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	860
lada.talk	331
news.sunnyusenet.com	319
mailing.freebsd.pkg-fallout	194
alt.politics	139
lada.auto	138
ut.test	119
aus.jobs	96
att.jobs	96
alt.job	96
at.jobs	96
alt.survival	88
alt.politics.scorched-earth	68
mailing.freebsd.svn-src-all	67
mpc.lists.freebsd.ports.bugs	67
mailing.freebsd.ports-bugs	67
fa.freebsd.cvs-all	65
mailing.gcc.gcc-cvs	63
pld.cvs.commit	59
mailing.freebsd.svn-src-head	57
TOTAL: 293	4693

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	xanadu-bbs.net	72
	feeder.eternal-september.org	18
	nntp.pasdenom.info	16
	au2pb.net	7
	TOTAL: 4	113
TOTAL: 1		113

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	39507	57	31394	1550	0	0%	21:00:08
2	au2pb	3554	19	3337	47	5	0%	05:13:43
3	xanadu-bbs	9739	3	9422	1	0	0%	23:52:45
4	eternal-september	37729	3	36269	3	1	0%	23:59:30
5	neva.ru	0	0	0	0	5816	0%	05:42:31
6	xmission	0	0	0	0	4615	0%	05:42:32
TOTAL: 6		90529	82	80422	1601	10437	0%	85:31:09

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	au2pb	1.2 MB	4.5 MB	5.7 MB	0.3 KB/s	88.6 KB	05:13:43
2	usenet.pasdenom.info	291.3 KB	55.9 MB	56.2 MB	0.8 KB/s	35.8 KB	21:00:08
3	xanadu-bbs	194.4 KB	1.3 KB	195.7 KB	0.0 KB/s	48.9 KB	23:52:45
4	eternal-september	16.7 KB	20.5 KB	37.2 KB	0.0 KB/s	6.2 KB	23:59:30
5	neva.ru	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	05:42:31
6	xmission	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	05:42:32
TOTAL: 6		1.7 MB	60.4 MB	62.1 MB	0.2 KB/s	37.8 KB	85:31:09

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	119	309.2 KB	102	04:35:57
2	tempe0.bbox.io	38	9	22.0 KB	6	05:00:23
3	dhcp-089-099-203-254.chello.nl	51	1	1.7 KB	1	20:52:17
TOTAL: 3		377	129	332.9 KB	109	53:46:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	321	119	309.2 KB	102	26:40:01
2	*.bbox.io	38	9	22.0 KB	6	05:00:23
3	*.chello.nl	51	1	1.7 KB	1	20:52:17
4	*.net	19	0	0.0 KB	0	00:00:10
5	*.nielsenmedia.com	9	0	0.0 KB	0	01:13:37
6	*.stu.neva.ru	1	0	0.0 KB	0	00:00:04
TOTAL: 6		439	129	332.9 KB	109	53:46:36

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
162.212.88.198	0.663	0.416	0.000	22:04:02
dhcp-089-099-203-254.chello.nl	4.068	0.649	0.000	20:52:17
tempe0.bbox.io	3.098	0.341	0.000	05:00:23
202.89.176.220	29.552	4.837	0.687	04:35:57
challenger.nielsenmedia.com	0.675	0.103	0.000	01:13:37
au2pb.net	1.441	0.204	0.000	00:00:10
goblin.stu.neva.ru	0.093	0.008	0.000	00:00:04
69.160.37.54	1.931	0.293	0.000	00:00:02
TOTAL: 8	41.521	6.851	0.687	53:46:36

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
au2pb.net	19
challenger.nielsenmedia.com	9
162.212.88.198	8
goblin.stu.neva.ru	1
TOTAL: 5	62

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	38	0
tempe0.bbox.io	8	0
challenger.nielsenmedia.com	2	0
TOTAL: 3	48	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	126	97.7%
2	alt	3	2.3%
TOTAL: 2		129	100.0%

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

Newsgroup		Count
1	comp.arch	52
2	comp.arch.embedded	29
3	comp.os.vms	8
4	comp.sys.ibm.pc.games.action	7
5	comp.ai.neural-nets	7
6	comp.os.os2.ecomstation	4
7	comp.os.os2.advocacy	4
8	alt.unix.wizards	3
9	comp.arch.fpga	3
10	comp.sys.ibm.ps2.hardware	3
11	comp.unix.bsd.freebsd.misc	2
12	comp.unix.bsd.netbsd.misc	2
13	comp.ai.shells	2
14	comp.ai.edu	1
15	comp.ai.fuzzy	1
16	comp.unix.bsd.misc	1
TOTAL: 16		129

Daily Usenet report for csiph.com

Aug 4 04:15:01 -- Aug 5 04:15:00