Daily Usenet report for csiph.com: Sep 22 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 22 06:30:03 csiph pgpverify[27751]: pgpverify: verification failed
Sep 22 06:30:03 csiph controlchan[21349]: Cannot send mail: (<1569133801.29456@panix.com>)

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19594	45.8%	4.8 MB	61.3%
innd	19299	45.2%	2.4 MB	30.4%
innfeed	3276	7.7%	590.2 KB	7.4%
nnrpd	510	1.2%	56.1 KB	0.7%
nocem	44	0.1%	5.1 KB	0.1%
controlchan	13	0.0%	2.6 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	42737	100.0%	7.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	255596	55.7%
Negative hits	184275	40.1%
Do not exist	19032	4.1%
Cache misses	225	0.0%
TOTAL: 4	459128	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.524	0.0%	94	0.000	5.574	63.500
article cleanup	00:00:00.110	0.0%	17427	0.000	0.006	0.062
article logging	00:00:00.263	0.0%	17787	0.000	0.015	0.082
article parse	00:00:02.844	0.0%	102124	0.000	0.028	0.124
article write	00:00:11.519	0.0%	13897	0.134	0.829	12.308
artlog/artcncl	00:00:00.001	0.0%	80	0.000	0.013	0.250
artlog/artparse	00:00:00.021	0.0%	1672	0.000	0.013	0.250
data move	00:00:00.147	0.0%	478919	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.017	0.0%	85	0.000	0.200	1.250
hishave/artcncl	00:00:00.007	0.0%	94	0.000	0.074	0.750
hishave/artparse	00:00:00.003	0.0%	1669	0.000	0.002	0.077
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:09.577	0.0%	459190	0.001	0.021	0.141
history sync	00:00:00.758	0.0%	379	0.000	2.000	9.000
history write	00:00:17.578	0.0%	17424	0.058	1.009	5.135
hiswrite/artcncl	00:00:00.001	0.0%	9	0.000	0.111	1.000
hiswrite/artparse	00:00:01.862	0.0%	1666	0.000	1.118	5.182
idle	23:58:50.096	99.8%	484876	97.361	178.046	513.850
nntp read	00:00:02.007	0.0%	483996	0.001	0.004	0.025
overview write	00:00:31.134	0.0%	13897	0.118	2.240	349.291
perl filter	00:00:28.867	0.0%	17421	1.133	1.657	3.457
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.212	0.0%	27834	0.000	0.008	0.042
TOTAL: 24:01:06.990	24:00:37.548	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.038	0.0%	13913	0.000	0.003	0.024
article prepare	00:00:00.004	0.0%	23234	0.000	0.000	0.005
article read	00:00:00.467	0.0%	2449	0.000	0.191	7.143
backlog stats	00:00:00.161	0.0%	308964	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.112	0.0%	232631	0.001	0.005	0.011
data write	00:00:01.408	0.0%	221527	0.001	0.006	0.014
idle	23:54:56.935	100.0%	308964	146.564	278.663	708.116
status file	00:00:00.234	0.0%	257	0.000	0.911	4.600
TOTAL: 23:55:06.988	23:55:00.359	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	15:32:35.729	100.0%	437	58.500	128045.146	600171.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.009	0.0%	1017	0.000	0.009	0.167
readart	00:00:00.494	0.0%	42	0.000	11.762	47.000
TOTAL: 15:32:50.259	15:32:36.232	100.0%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
ba-mod@panix.com	0	0	1	1	0	1
TOTAL	0	0	1	1	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	12785	1814	10961	10	14%	23:19:00
2	feeder.eternal-september.org	2	16158	1678	14388	92	10%	26:35:48
3	news.freedyn.net	2	17844	1663	14251	1930	9%	24:09:03
4	csiph-out.feeder.erje.net	1440	66662	1661	64549	452	2%	118:42:53
5	csiph-out.news.weretis.net	4	26671	1635	24917	119	6%	46:51:54
6	csiph-out.news.xs4all.nl	864	16517	1491	13596	1430	9%	71:26:41
7	news-out.aioe.org	1	13199	1344	11848	7	10%	23:59:58
8	news-out.mixmin.net	2	14174	918	13101	155	6%	23:59:15
9	newsfeed.fu-berlin.de	11	12346	709	11609	28	5%	24:42:18
10	newsfeed.neva.ru	151	15196	620	14041	535	4%	71:50:45
11	csiph-out.feed.uzoreto.com	864	22791	194	22189	408	0%	71:21:20
12	news.fcku.it	11	1291	76	1215	0	5%	21:04:51
13	nntp.pasdenom.info	2	9159	39	9111	9	0%	23:58:59
14	news.swapon.de	6	1215	21	1194	0	1%	23:25:39
15	news.samoylyk.net	1	13146	15	13131	0	0%	23:59:46
16	localhost	46	14	14	0	0	100%	00:00:02
17	news.bbs.nz	2	12638	5	12633	0	0%	23:59:46
18	xanadu-bbs.net	33	380	0	380	0	0%	22:54:20
TOTAL: 18		3444	272186	13897	253114	5175	5%	666:22:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	11.4 MB	50.1 KB	14.4 MB	25.9 MB	44%	12.5 KB
2	newsfeeds.xmission.com	5.4 MB	0.0 KB	360.7 KB	5.7 MB	93%	3.2 KB
3	csiph-out.news.weretis.net	5.1 MB	0.0 KB	2.9 MB	8.0 MB	63%	4.7 KB
4	feeder.eternal-september.org	4.7 MB	0.0 KB	1.4 MB	6.1 MB	77%	3.5 KB
5	news.freedyn.net	4.7 MB	12.2 KB	134.5 MB	139.1 MB	3%	39.7 KB
6	csiph-out.news.xs4all.nl	4.2 MB	42.6 KB	10.3 MB	14.6 MB	28%	5.1 KB
7	news-out.aioe.org	3.1 MB	0.0 KB	37.7 KB	3.2 MB	98%	2.4 KB
8	news-out.mixmin.net	2.7 MB	0.0 KB	319.3 KB	3.0 MB	89%	2.9 KB
9	newsfeed.fu-berlin.de	1.7 MB	0.0 KB	82.6 KB	1.8 MB	95%	2.5 KB
10	newsfeed.neva.ru	1.6 MB	0.0 KB	1.6 MB	3.2 MB	49%	2.8 KB
11	csiph-out.feed.uzoreto.com	747.0 KB	32.3 KB	7.7 MB	8.4 MB	8%	14.3 KB
12	news.fcku.it	146.3 KB	0.0 KB	0.0 KB	146.3 KB	100%	1.9 KB
13	nntp.pasdenom.info	85.9 KB	0.0 KB	16.1 KB	102.1 KB	84%	2.1 KB
14	news.samoylyk.net	54.8 KB	0.0 KB	0.0 KB	54.8 KB	100%	3.7 KB
15	news.swapon.de	44.3 KB	0.0 KB	0.0 KB	44.3 KB	100%	2.1 KB
16	news.bbs.nz	22.1 KB	0.0 KB	0.0 KB	22.1 KB	100%	4.4 KB
17	localhost	17.9 KB	0.0 KB	0.0 KB	17.9 KB	100%	1.3 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		45.7 MB	137.3 KB	173.5 MB	219.4 MB	20%	11.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 22 04:15:00 - 04:59:59	200	1.4%	0.07	613.5 KB	1.3%	0.23
Sep 22 05:00:00 - 05:59:59	271	1.9%	0.08	914.7 KB	1.9%	0.25
Sep 22 06:00:00 - 06:59:59	498	3.6%	0.14	2.3 MB	5.0%	0.65
Sep 22 07:00:00 - 07:59:59	571	4.1%	0.16	2.5 MB	5.4%	0.71
Sep 22 08:00:00 - 08:59:59	630	4.5%	0.17	2.6 MB	5.7%	0.74
Sep 22 09:00:00 - 09:59:59	631	4.5%	0.18	2.1 MB	4.6%	0.60
Sep 22 10:00:00 - 10:59:59	680	4.9%	0.19	2.3 MB	4.9%	0.64
Sep 22 11:00:00 - 11:59:59	564	4.1%	0.16	1.8 MB	3.9%	0.51
Sep 22 12:00:00 - 12:59:59	579	4.2%	0.16	1.6 MB	3.5%	0.46
Sep 22 13:00:00 - 13:59:59	700	5.0%	0.19	1.8 MB	4.0%	0.52
Sep 22 14:00:00 - 14:59:59	754	5.4%	0.21	2.5 MB	5.4%	0.71
Sep 22 15:00:00 - 15:59:59	764	5.5%	0.21	2.0 MB	4.4%	0.57
Sep 22 16:00:00 - 16:59:59	774	5.6%	0.21	2.3 MB	5.0%	0.65
Sep 22 17:00:00 - 17:59:59	915	6.6%	0.25	3.5 MB	7.7%	1.01
Sep 22 18:00:00 - 18:59:59	827	5.9%	0.23	3.0 MB	6.5%	0.85
Sep 22 19:00:00 - 19:59:59	860	6.2%	0.24	2.6 MB	5.7%	0.74
Sep 22 20:00:00 - 20:59:59	690	5.0%	0.19	1.9 MB	4.0%	0.53
Sep 22 21:00:00 - 21:59:59	546	3.9%	0.15	1.4 MB	2.9%	0.38
Sep 22 22:00:00 - 22:59:59	596	4.3%	0.17	2.0 MB	4.4%	0.57
Sep 22 23:00:00 - 23:59:59	463	3.3%	0.13	1.6 MB	3.4%	0.44
Sep 23 00:00:00 - 00:59:59	320	2.3%	0.09	908.8 KB	1.9%	0.25
Sep 23 01:00:00 - 01:59:59	315	2.3%	0.09	870.0 KB	1.8%	0.24
Sep 23 02:00:00 - 02:59:59	320	2.3%	0.09	1.3 MB	2.7%	0.36
Sep 23 03:00:00 - 03:59:59	297	2.1%	0.08	884.4 KB	1.9%	0.25
Sep 23 04:00:00 - 04:15:00	153	1.1%	0.17	921.3 KB	2.0%	1.02
TOTAL: 24:00:00	13918	100.0%	0.16	46.0 MB	100.0%	0.54

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.freedyn.net	1949	30	1	0	1918
2	csiph-out.news.xs4all.nl	1470	25	2	1	1442
3	newsfeed.neva.ru	544	40	0	0	504
4	csiph-out.feeder.erje.net	460	67	2	0	391
5	csiph-out.feed.uzoreto.com	409	11	1	0	397
6	newsfeed.fu-berlin.de	337	13	0	0	324
7	news-out.mixmin.net	166	0	0	0	166
8	csiph-out.news.weretis.net	138	65	0	0	73
9	feeder.eternal-september.org	86	12	0	0	74
10	news-out.aioe.org	16	6	0	0	10
11	newsfeeds.xmission.com	11	2	0	0	9
12	nntp.pasdenom.info	8	0	0	0	8
13	news.swapon.de	3	0	0	0	3
TOTAL: 13		5597	271	6	1	5319

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.bbs.synchronet	36
alt.windows7.general	21
lada.talk	19
alt.binaries.pictures.purebeauty	17
mozilla.general	17
mozilla.support.thunderbird	14
mozilla.support.firefox	12
alt.pl.nieruchomosci.gielda	11
alt.binaries.ebooks	11
mozilla.support.seamonkey	10
alt.politics	5
mozilla.dev.security.policy	5
oesterreich.tratsch	5
lada.test	5
eagle.userchat.eng	5
it-alt.comp.software.mozilla	4
rocksolid.shared.test	4
alt.politics.usa	4
it-alt.sport.calcio.juventus	4
rocksolid.shared.i2p	3
TOTAL: 65	271

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2862
Too many newsgroups	141
EMP (phl)	60
EMP (phn path)	60
EMP (phn nph)	51
User-issued cancel	32
Binary: misplaced rar	21
Binary Payload	20
HTML Multipart	14
User-issued spam cancel	4
Scoring filter (9)	3
Scoring filter (10)	2
Scoring filter (12)	2
Too many newsgroups (meow)	2
HTML Image Tags	1
Scoring filter (15)	1
Scoring filter (16)	1
Scoring filter (11)	1
HTML post	1
TOTAL: 19	3279

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	1122
	csiph-out.feeder.erje.net	267
	csiph-out.news.xs4all.nl	147
	csiph-out.feed.uzoreto.com	90
	csiph-out.news.weretis.net	38
	newsfeeds.xmission.com	6
	feeder.eternal-september.org	4
	news-out.mixmin.net	3
	TOTAL: 8	1677
Including strange strings
	newsfeed.fu-berlin.de	306
	csiph-out.news.xs4all.nl	26
	news-out.aioe.org	9
	csiph-out.news.weretis.net	6
	csiph-out.feeder.erje.net	5
	news.swapon.de	3
	news-out.mixmin.net	3
	news.freedyn.net	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 9	360
No colon-space in header
	csiph-out.feed.uzoreto.com	1
	csiph-out.news.xs4all.nl	1
	csiph-out.feeder.erje.net	1
	TOTAL: 3	3
TOTAL: 4		2085

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	erje	14905	1340	10478	227	0	0	8%	23:50:10
2	xanadu-bbs	16699	1065	12853	0	0	0	6%	23:59:53
3	fu-berlin.de	13052	603	12449	0	0	0	4%	23:59:22
4	news.samoylyk.net	14247	591	13203	0	0	0	4%	23:50:10
5	news.freedyn.net	12364	579	11523	17	0	0	4%	23:50:10
6	news.bbs.nz	25504	229	13008	10	0	0	0%	23:52:38
7	usenet.pasdenom.info	14438	129	13121	626	1	0	0%	23:59:55
8	aioe	11782	74	11341	1	0	0	0%	23:58:50
9	newsfeed.xs4all.nl	12518	48	11773	7	0	33	0%	23:56:38
10	neva.ru	12664	25	12369	111	0	0	0%	23:59:55
11	news.mixmin.net	12589	19	12216	0	0	0	0%	23:59:06
12	news.uzoreto.com	13440	16	13203	35	0	0	0%	23:51:59
13	eternal-september	12059	15	11658	1	0	0	0%	23:59:55
14	weretis.net	11763	15	11489	1	0	0	0%	23:59:48
15	xmission	14841	14	11444	1	0	0	0%	23:59:55
16	news.swapon.de	1282	14	1252	0	0	0	1%	23:50:35
17	news.fcku.it	1152	0	1149	0	0	0	0%	19:47:59
18	news.redatomik.org	0	0	0	0	0	13232	0%	23:50:10
TOTAL: 18		215299	4776	184529	1037	1	13265	2%	426:37:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.6 MB	0.0 KB	9.6 MB	0.1 KB/s	9.2 KB	23:59:53
2	fu-berlin.de	8.4 MB	0.0 KB	8.4 MB	0.1 KB/s	14.3 KB	23:59:22
3	news.samoylyk.net	8.1 MB	0.0 KB	8.1 MB	0.1 KB/s	14.0 KB	23:50:10
4	news.freedyn.net	8.1 MB	21.6 KB	8.1 MB	0.1 KB/s	13.9 KB	23:50:10
5	erje	3.5 MB	1.0 MB	4.6 MB	0.1 KB/s	3.0 KB	23:50:10
6	news.bbs.nz	493.8 KB	31.4 KB	525.3 KB	0.0 KB/s	2.2 KB	23:52:38
7	usenet.pasdenom.info	287.5 KB	8.5 MB	8.8 MB	0.1 KB/s	11.9 KB	23:59:55
8	newsfeed.xs4all.nl	254.8 KB	15.6 KB	270.4 KB	0.0 KB/s	4.9 KB	23:56:38
9	aioe	140.0 KB	3.0 KB	143.0 KB	0.0 KB/s	1.9 KB	23:58:50
10	news.uzoreto.com	79.4 KB	232.9 KB	312.4 KB	0.0 KB/s	6.1 KB	23:51:59
11	neva.ru	36.7 KB	228.5 KB	265.2 KB	0.0 KB/s	2.0 KB	23:59:55
12	news.mixmin.net	26.0 KB	0.0 KB	26.0 KB	0.0 KB/s	1.4 KB	23:59:06
13	eternal-september	21.9 KB	0.7 KB	22.6 KB	0.0 KB/s	1.4 KB	23:59:55
14	weretis.net	18.7 KB	1.2 KB	19.9 KB	0.0 KB/s	1.2 KB	23:59:48
15	xmission	18.0 KB	1.4 KB	19.4 KB	0.0 KB/s	1.3 KB	23:59:55
16	news.swapon.de	18.0 KB	0.0 KB	18.0 KB	0.0 KB/s	1.3 KB	23:50:35
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	19:47:59
18	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:10
TOTAL: 18		39.0 MB	10.1 MB	49.1 MB	0.0 KB/s	8.6 KB	426:37:08

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	208-71-239-109.cust.centrio.cz	2	17	35.6 KB	2	0	0	00:34:33
2	71-82-166-98.dhcp.mdsn.wi.charter.com	47	17	25.5 KB	16	14	12	12:17:48
3	a131033.upc-a.chello.nl	6	8	24.5 KB	2	0	0	00:40:12
TOTAL: 3		55	42	85.6 KB	20	14	12	15:32:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cust.centrio.cz	2	17	35.6 KB	2	0	0	00:34:33
2	*.dhcp.mdsn.wi.charter.com	47	17	25.5 KB	16	14	12	12:17:48
3	*.upc-a.chello.nl	6	8	24.5 KB	2	0	0	00:40:12
4	unresolved	1	0	0.0 KB	0	0	0	00:00:01
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
6	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:13
TOTAL: 6		61	42	85.6 KB	20	14	12	15:32:49

NNRP auth users [Top 20]:

User	Conn
ohlandl	12
ctomczyk	2
TOTAL: 2	14

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71-82-166-98.dhcp.mdsn.wi.charter.com	4.009	0.709	0.025	12:17:48
ip72-222-200-117.ph.ph.cox.net	0.458	0.066	0.000	02:00:13
a131033.upc-a.chello.nl	0.527	0.079	0.000	00:40:12
208-71-239-109.cust.centrio.cz	0.230	0.033	0.004	00:34:33
5.87.94.221	0.061	0.017	0.000	00:00:01
scan-141.security.ipip.net	0.072	0.008	0.000	00:00:01
TOTAL: 6	5.357	0.912	0.029	15:32:49

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	4
5.87.94.221	1
scan-141.security.ipip.net	1
TOTAL: 3	6

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
User-Agent: HTTP Banner Detection (https...	1
Host: 69.160.37.58:119	1
GET / HTTP/1.1	1
Connection: close	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-82-166-98.dhcp.mdsn.wi.charter.com	10	0
ip72-222-200-117.ph.ph.cox.net	4	0
208-71-239-109.cust.centrio.cz	1	0
TOTAL: 3	15	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	25
2	comp.lang.javascript	17
TOTAL: 2		42

Daily Usenet report for csiph.com

Sep 22 04:15:00 -- Sep 23 04:15:00