Daily Usenet report for csiph.com: Mar 17 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Mar 17 12:10:03 csiph pgpverify[35235]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22197	51.1%	5.1 MB	65.2%
innd	16661	38.3%	2.0 MB	25.8%
innfeed	3212	7.4%	562.6 KB	7.0%
nnrpd	1324	3.0%	153.9 KB	1.9%
nocem	40	0.1%	4.2 KB	0.1%
controlchan	12	0.0%	2.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	43447	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	294068	57.9%
Negative hits	192232	37.8%
Do not exist	21716	4.3%
Cache misses	214	0.0%
TOTAL: 4	508230	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.383	0.0%	70	0.000	5.471	32.375
article cleanup	00:00:00.121	0.0%	19231	0.000	0.006	0.125
article logging	00:00:00.316	0.0%	19602	0.000	0.016	0.061
article parse	00:00:10.998	0.0%	150726	0.000	0.073	0.842
article write	00:02:30.062	0.2%	15624	0.120	9.605	71.673
artlog/artcncl	00:00:00.001	0.0%	53	0.000	0.019	1.000
artlog/artparse	00:00:00.030	0.0%	2551	0.000	0.012	0.105
data move	00:00:00.253	0.0%	571242	0.000	0.000	0.005
hisgrep/artcncl	00:00:00.012	0.0%	64	0.000	0.188	2.000
hishave/artcncl	00:00:00.003	0.0%	70	0.000	0.043	0.250
hishave/artparse	00:00:00.006	0.0%	2551	0.000	0.002	0.056
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:53.746	0.1%	507183	0.002	0.106	0.481
history sync	00:00:02.018	0.0%	390	0.000	5.174	80.500
history write	00:00:57.659	0.1%	19223	0.049	2.999	10.041
hiswrite/artcncl	00:00:00.032	0.0%	6	0.000	5.333	16.000
hiswrite/artparse	00:00:09.608	0.0%	2551	0.000	3.766	59.182
idle	23:53:00.073	99.4%	564977	55.474	152.183	700.207
nntp read	00:00:02.590	0.0%	576533	0.001	0.004	0.020
overview write	00:02:01.362	0.1%	15624	0.293	7.768	163.288
perl filter	00:00:33.364	0.0%	19188	1.261	1.739	4.019
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.279	0.0%	31282	0.000	0.009	0.060
TOTAL: 24:01:02.934	24:00:22.916	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.049	0.0%	15824	0.000	0.003	0.059
article prepare	00:00:00.002	0.0%	19364	0.000	0.000	0.003
article read	00:00:00.847	0.0%	1730	0.000	0.490	16.000
backlog stats	00:00:00.179	0.0%	331884	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.534	0.0%	255895	0.001	0.006	0.011
data write	00:00:01.656	0.0%	243200	0.002	0.007	0.012
idle	23:54:36.822	100.0%	331884	130.722	259.358	837.467
status file	00:00:00.350	0.0%	276	0.000	1.268	59.000
TOTAL: 23:54:48.208	23:54:41.439	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	18:41:49.972	99.9%	604	0.000	111440.351	600135.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.016	0.0%	1291	0.000	0.012	0.500
readart	00:00:00.734	0.0%	29	0.000	25.310	92.000
TOTAL: 18:42:44.301	18:41:50.722	99.9%	-	-	-	-

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
newsmaster@carnet.hr	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	csiph-out.news.weretis.net	3	31585	3661	27628	296	11%	49:37:12
2	csiph-out.feeder.erje.net	1440	77452	2929	74179	344	3%	118:40:44
3	news-out.mixmin.net	2	16620	1736	14117	767	10%	24:08:37
4	feeder.eternal-september.org	2	18155	1727	16262	166	9%	24:56:33
5	news-out.aioe.org	3	14823	1390	13397	36	9%	24:09:00
6	news.redatomik.org	2	14684	1310	13324	50	8%	23:59:41
7	newsfeed.fu-berlin.de	7	17320	838	16269	213	4%	28:55:21
8	news.freedyn.net	1	19621	791	16832	1998	4%	23:59:49
9	newsfeed.neva.ru	149	19438	573	18231	634	2%	71:01:06
10	newsfeeds.xmission.com	3	15656	452	15191	13	2%	25:35:00
11	csiph-out.feed.uzoreto.com	864	25363	230	24559	574	0%	71:07:55
12	news.fcku.it	9	1528	106	1418	4	6%	21:40:53
13	nntp.pasdenom.info	3	13177	58	13102	17	0%	23:58:10
14	news.swapon.de	6	1274	40	1234	0	3%	24:22:43
15	localhost	53	19	19	0	0	100%	00:00:10
16	xanadu-bbs.net	31	1435	3	357	1075	0%	19:28:28
17	news.bbs.nz	1	14471	3	14468	0	0%	23:59:54
18	news.samoylyk.net	2	14851	0	14851	0	0%	24:54:12
TOTAL: 18		2581	317472	15866	295419	6187	4%	624:35:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	14.9 MB	0.0 KB	8.9 MB	23.7 MB	62%	7.4 KB
2	csiph-out.news.weretis.net	11.5 MB	0.0 KB	8.0 MB	19.5 MB	58%	5.1 KB
3	news-out.mixmin.net	4.8 MB	0.0 KB	1.4 MB	6.1 MB	77%	2.5 KB
4	feeder.eternal-september.org	4.7 MB	2.5 KB	3.1 MB	7.8 MB	60%	4.2 KB
5	news.redatomik.org	3.6 MB	0.0 KB	1.4 MB	5.0 MB	72%	3.7 KB
6	news-out.aioe.org	3.4 MB	0.0 KB	159.6 KB	3.5 MB	95%	2.5 KB
7	news.freedyn.net	2.5 MB	0.0 KB	116.7 MB	119.1 MB	2%	43.7 KB
8	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	565.5 KB	2.6 MB	78%	2.5 KB
9	newsfeed.neva.ru	1.6 MB	51.0 KB	2.0 MB	3.7 MB	44%	3.1 KB
10	newsfeeds.xmission.com	1.6 MB	0.0 KB	1021.5 KB	2.6 MB	61%	5.7 KB
11	csiph-out.feed.uzoreto.com	736.8 KB	0.0 KB	22.0 MB	22.7 MB	3%	28.9 KB
12	news.fcku.it	202.0 KB	0.0 KB	6.0 KB	207.9 KB	97%	1.9 KB
13	nntp.pasdenom.info	131.1 KB	0.0 KB	38.2 KB	169.3 KB	77%	2.3 KB
14	news.swapon.de	106.3 KB	0.0 KB	0.0 KB	106.3 KB	100%	2.7 KB
15	localhost	26.4 KB	0.0 KB	0.0 KB	26.4 KB	100%	1.4 KB
16	news.bbs.nz	14.7 KB	0.0 KB	0.0 KB	14.7 KB	100%	4.9 KB
17	xanadu-bbs.net	6.2 KB	0.0 KB	83.1 MB	83.1 MB	0%	78.9 KB
18	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		51.8 MB	53.5 KB	248.3 MB	300.1 MB	17%	13.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 17 04:15:00 - 04:59:59	215	1.4%	0.08	1011.5 KB	1.9%	0.37
Mar 17 05:00:00 - 05:59:59	296	1.9%	0.08	965.1 KB	1.8%	0.27
Mar 17 06:00:00 - 06:59:59	294	1.9%	0.08	906.6 KB	1.7%	0.25
Mar 17 07:00:00 - 07:59:59	398	2.5%	0.11	1.7 MB	3.3%	0.47
Mar 17 08:00:00 - 08:59:59	559	3.6%	0.16	2.8 MB	5.4%	0.79
Mar 17 09:00:00 - 09:59:59	674	4.3%	0.19	2.4 MB	4.6%	0.67
Mar 17 10:00:00 - 10:59:59	710	4.5%	0.20	1.7 MB	3.3%	0.48
Mar 17 11:00:00 - 11:59:59	766	4.9%	0.21	3.0 MB	5.9%	0.85
Mar 17 12:00:00 - 12:59:59	687	4.4%	0.19	1.8 MB	3.6%	0.52
Mar 17 13:00:00 - 13:59:59	743	4.8%	0.21	2.7 MB	5.3%	0.77
Mar 17 14:00:00 - 14:59:59	808	5.2%	0.22	2.1 MB	4.1%	0.59
Mar 17 15:00:00 - 15:59:59	726	4.6%	0.20	2.5 MB	5.0%	0.72
Mar 17 16:00:00 - 16:59:59	842	5.4%	0.23	2.8 MB	5.4%	0.79
Mar 17 17:00:00 - 17:59:59	946	6.1%	0.26	3.3 MB	6.6%	0.95
Mar 17 18:00:00 - 18:59:59	988	6.3%	0.27	3.2 MB	6.2%	0.91
Mar 17 19:00:00 - 19:59:59	990	6.3%	0.28	3.1 MB	6.2%	0.89
Mar 17 20:00:00 - 20:59:59	862	5.5%	0.24	2.4 MB	4.7%	0.68
Mar 17 21:00:00 - 21:59:59	944	6.0%	0.26	2.7 MB	5.3%	0.76
Mar 17 22:00:00 - 22:59:59	745	4.8%	0.21	2.1 MB	4.2%	0.60
Mar 17 23:00:00 - 23:59:59	609	3.9%	0.17	2.1 MB	4.1%	0.60
Mar 18 00:00:00 - 00:59:59	538	3.4%	0.15	1.6 MB	3.1%	0.46
Mar 18 01:00:00 - 01:59:59	482	3.1%	0.13	1.4 MB	2.7%	0.38
Mar 18 02:00:00 - 02:59:59	354	2.3%	0.10	1.2 MB	2.3%	0.34
Mar 18 03:00:00 - 03:59:59	349	2.2%	0.10	1.4 MB	2.8%	0.40
Mar 18 04:00:00 - 04:15:00	93	0.6%	0.10	252.2 KB	0.5%	0.28
TOTAL: 24:00:00	15618	100.0%	0.18	51.0 MB	100.0%	0.60

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.freedyn.net	2031	23	0	2008
2	xanadu-bbs.net	1075	0	0	1075
3	news-out.mixmin.net	773	5	0	768
4	newsfeed.neva.ru	640	89	7	544
5	csiph-out.feed.uzoreto.com	576	6	0	570
6	newsfeed.fu-berlin.de	488	35	0	453
7	csiph-out.feeder.erje.net	345	47	0	298
8	csiph-out.news.weretis.net	285	81	0	204
9	feeder.eternal-september.org	159	46	1	112
10	news.redatomik.org	69	0	0	69
11	news-out.aioe.org	38	36	0	2
12	nntp.pasdenom.info	17	0	0	17
13	newsfeeds.xmission.com	15	1	0	14
14	news.swapon.de	11	0	0	11
15	news.fcku.it	4	4	0	0
TOTAL: 15		6526	373	8	6145

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	48
lada.talk	47
alt.windows7.general	39
gmane.comp.gis.openstreetmap.tagging	35
lada.auto	29
alt.survival	15
spline.eisfair	14
alt.computer.workshop	11
oesterreich.tratsch	10
alt.binaries.multimedia.ba	8
lada.movies	6
alt.politics.usa	6
mozilla.support.firefox	5
alt.politics	5
alt.magick.folk	5
alt.music.pink-floyd.publius	5
rocksolid.shared.news	5
mozilla.general	5
lada.soft	4
rocksolid.shared.general	4
TOTAL: 70	373

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2640
EMP (phn path)	275
EMP (phl)	64
Too many newsgroups	54
Binary: misplaced rar	49
Binary: misplaced par2	29
Binary Payload	21
User-issued cancel	18
User-issued spam cancel	14
HTML Multipart	12
EMP (phn nph)	7
Scoring filter (9)	3
Binary: misplaced nfo	3
Cancel for rejected article	1
Scoring filter (8)	1
Binary: misplaced nzb	1
TOTAL: 16	3192

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	xanadu-bbs.net	1034
	news.freedyn.net	938
	csiph-out.feed.uzoreto.com	290
	csiph-out.feeder.erje.net	160
	csiph-out.news.weretis.net	77
	feeder.eternal-september.org	23
	newsfeeds.xmission.com	13
	news.redatomik.org	11
	news-out.mixmin.net	4
	TOTAL: 9	2550
Including strange strings
	newsfeed.fu-berlin.de	308
	news.redatomik.org	19
	news.swapon.de	11
	csiph-out.news.weretis.net	11
	news.freedyn.net	6
	news-out.mixmin.net	4
	news-out.aioe.org	2
	newsfeed.neva.ru	2
	csiph-out.feed.uzoreto.com	2
	feeder.eternal-september.org	1
	TOTAL: 12	368
TOTAL: 3		2963

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17619	757	14858	0	0	4%	23:59:55
2	news.samoylyk.net	17175	669	14938	10	218	3%	23:59:55
3	news.freedyn.net	15032	662	14039	58	0	4%	23:59:53
4	fu-berlin.de	14719	632	14087	0	0	4%	23:59:55
5	news.bbs.nz	29047	224	14718	9	0	0%	23:59:22
6	usenet.pasdenom.info	16720	106	14095	1359	14	0%	23:59:55
7	erje	12782	29	12573	5	0	0%	23:59:55
8	neva.ru	14457	29	14110	105	0	0%	23:59:37
9	aioe	13308	26	12711	1	0	0%	23:59:49
10	news.mixmin.net	13414	24	13035	0	0	0%	23:59:55
11	news.swapon.de	1297	21	1265	0	0	1%	23:28:23
12	xmission	16767	20	14416	1	0	0%	23:59:55
13	weretis.net	11691	20	11323	0	0	0%	23:59:55
14	eternal-september	13617	20	13174	0	0	0%	23:59:39
15	news.redatomik.org	14075	19	13601	0	8	0%	23:59:55
16	news.uzoreto.com	15333	14	15030	26	0	0%	23:59:38
17	news.fcku.it	1322	0	1309	0	0	0%	21:30:45
TOTAL: 17		238375	3272	209282	1574	240	1%	404:56:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	8.8 MB	0.0 KB	8.8 MB	0.1 KB/s	11.9 KB	23:59:55
2	news.freedyn.net	8.6 MB	109.2 KB	8.7 MB	0.1 KB/s	12.4 KB	23:59:53
3	news.samoylyk.net	8.5 MB	34.7 KB	8.6 MB	0.1 KB/s	12.9 KB	23:59:55
4	fu-berlin.de	8.5 MB	0.0 KB	8.5 MB	0.1 KB/s	13.7 KB	23:59:55
5	news.bbs.nz	499.4 KB	29.8 KB	529.2 KB	0.0 KB/s	2.3 KB	23:59:22
6	usenet.pasdenom.info	325.9 KB	9.7 MB	10.0 MB	0.1 KB/s	7.0 KB	23:59:55
7	erje	56.6 KB	6.8 KB	63.4 KB	0.0 KB/s	1.9 KB	23:59:55
8	neva.ru	49.0 KB	204.1 KB	253.1 KB	0.0 KB/s	1.9 KB	23:59:37
9	news.mixmin.net	43.2 KB	0.0 KB	43.2 KB	0.0 KB/s	1.8 KB	23:59:55
10	news.uzoreto.com	42.2 KB	154.5 KB	196.7 KB	0.0 KB/s	4.9 KB	23:59:38
11	aioe	39.7 KB	1.6 KB	41.3 KB	0.0 KB/s	1.5 KB	23:59:49
12	xmission	36.9 KB	2.1 KB	39.0 KB	0.0 KB/s	1.9 KB	23:59:55
13	news.swapon.de	30.1 KB	0.0 KB	30.1 KB	0.0 KB/s	1.4 KB	23:28:23
14	weretis.net	28.4 KB	0.0 KB	28.4 KB	0.0 KB/s	1.4 KB	23:59:55
15	eternal-september	28.1 KB	0.0 KB	28.1 KB	0.0 KB/s	1.4 KB	23:59:39
16	news.redatomik.org	26.5 KB	0.0 KB	26.5 KB	0.0 KB/s	1.4 KB	23:59:55
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:30:45
TOTAL: 17		35.6 MB	10.2 MB	45.8 MB	0.0 KB/s	9.7 KB	404:56:21

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	68-114-223-194.dhcp.mdsn.wi.charter.com	51	25	36.9 KB	21	19	14	12:10:26
2	109.239.71.208	2	2	2.6 KB	1	0	0	00:01:13
3	challenger.nielsenmedia.com	2	2	5.4 KB	1	0	0	00:55:12
TOTAL: 3		55	29	44.9 KB	23	19	14	18:42:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.mdsn.wi.charter.com	51	25	36.9 KB	21	19	14	12:10:26
2	unresolved	146	2	2.6 KB	1	0	0	00:01:27
3	*.nielsenmedia.com	2	2	5.4 KB	1	0	0	00:55:12
4	*.icpnet.pl	1	0	0.0 KB	0	0	0	00:30:01
5	*.shodan.io	1	0	0.0 KB	0	0	0	00:00:04
6	*.upc-a.chello.nl	35	0	0.0 KB	0	0	0	05:05:24
TOTAL: 6		236	29	44.9 KB	23	19	14	18:42:37

NNRP auth users [Top 20]:

User	Conn
ohlandl	14
ctomczyk	2
TOTAL: 2	16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
68-114-223-194.dhcp.mdsn.wi.charter.com	4.704	0.927	0.037	12:10:26
a131033.upc-a.chello.nl	2.832	0.466	0.000	05:05:24
challenger.nielsenmedia.com	0.227	0.016	0.000	00:55:12
c134-208.icpnet.pl	0.072	0.008	0.000	00:30:01
109.239.71.208	0.185	0.024	0.008	00:01:13
93.186.197.189	9.584	2.020	0.000	00:00:14
census11.shodan.io	0.065	0.016	0.000	00:00:04
TOTAL: 7	17.669	3.477	0.045	18:42:37

Curious NNRP server explorers [Top 20]:

System	Conn
93.186.197.189	144
a131033.upc-a.chello.nl	35
c134-208.icpnet.pl	1
census11.shodan.io	1
TOTAL: 4	181

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-114-223-194.dhcp.mdsn.wi.charter.com	10	0
challenger.nielsenmedia.com	1	0
c134-208.icpnet.pl	1	0
TOTAL: 3	12	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	25
2	comp.lang.javascript	2
3	comp.lang.awk	2
TOTAL: 3		29

Daily Usenet report for csiph.com

Mar 17 04:15:00 -- Mar 18 04:15:00