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:02 csiph pgpverify[70512]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	23927	54.3%	5.2 MB	68.6%
innd	10701	24.3%	1.3 MB	17.5%
nnrpd	6464	14.7%	558.3 KB	7.2%
innfeed	2888	6.6%	504.2 KB	6.5%
nocem	45	0.1%	5.2 KB	0.1%
controlchan	12	0.0%	2.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	44038	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	283096	57.2%
Negative hits	188387	38.1%
Do not exist	23144	4.7%
Cache misses	210	0.0%
TOTAL: 4	494837	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.228	0.0%	131	0.000	1.740	15.000
article cleanup	00:00:00.138	0.0%	22882	0.000	0.006	0.031
article logging	00:00:00.349	0.0%	23442	0.000	0.015	0.095
article parse	00:00:01.667	0.0%	61753	0.000	0.027	0.338
article write	00:00:08.056	0.0%	18059	0.193	0.446	4.236
artlog/artcncl	00:00:00.003	0.0%	115	0.000	0.026	0.333
artlog/artparse	00:00:00.007	0.0%	380	0.000	0.018	0.500
data move	00:00:00.158	0.0%	479113	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.020	0.0%	131	0.000	0.153	3.000
hishave/artcncl	00:00:00.005	0.0%	131	0.000	0.038	0.400
hishave/artparse	00:00:00.000	0.0%	380	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:21.653	0.0%	496610	0.003	0.044	0.298
history sync	00:00:01.089	0.0%	377	0.000	2.889	13.500
history write	00:00:33.702	0.0%	22882	0.191	1.473	7.699
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.880	0.0%	380	0.000	2.316	12.000
idle	23:53:57.985	99.5%	483467	96.166	177.960	564.233
nntp read	00:00:02.372	0.0%	485938	0.002	0.005	0.008
overview write	00:04:49.036	0.3%	18059	0.304	16.005	2124.416
perl filter	00:00:44.720	0.1%	22674	1.376	1.972	2.865
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.319	0.0%	36155	0.000	0.009	0.026
TOTAL: 24:01:34.332	24:00:42.387	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.044	0.0%	18870	0.000	0.002	0.024
article prepare	00:00:00.001	0.0%	20396	0.000	0.000	0.005
article read	00:00:00.517	0.0%	2635	0.000	0.196	10.500
backlog stats	00:00:00.200	0.0%	355841	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.783	0.0%	263451	0.002	0.007	0.019
data write	00:00:01.514	0.0%	248670	0.001	0.006	0.014
idle	23:54:08.933	100.0%	355841	130.676	241.818	695.599
status file	00:00:00.216	0.0%	269	0.000	0.803	7.000
TOTAL: 23:54:22.475	23:54:13.208	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	26:41:18.506	100.0%	6396	387.504	15021.655	600167.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.284	0.0%	13345	0.000	0.021	1.438
readart	00:00:02.617	0.0%	721	0.000	3.630	60.000
TOTAL: 26:41:57.692	26:41:21.407	100.0%	-	-	-	-

Control commands to INND:

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

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.feeder.erje.net	1152	76462	4709	70243	1510	6%	94:59:43
2	csiph-out.news.weretis.net	5	36881	3752	32801	328	10%	48:34:00
3	feeder.eternal-september.org	5	20519	2431	17764	324	11%	24:37:30
4	news-out.mixmin.net	3	19304	1687	16889	728	8%	24:11:42
5	news.redatomik.org	2	14883	1354	13472	57	9%	19:21:25
6	newsfeed.fu-berlin.de	16	17493	1085	16162	246	6%	26:41:14
7	newsfeed.neva.ru	153	23710	899	21061	1750	3%	72:00:48
8	news-out.aioe.org	3	17077	677	16382	18	3%	23:10:20
9	newsfeeds.xmission.com	2	14726	613	14110	3	4%	18:59:48
10	news.fcku.it	2	7491	512	6778	201	6%	23:59:04
11	news.swapon.de	6	1724	62	1661	1	3%	23:33:58
12	nntp.pasdenom.info	3	16396	36	16360	0	0%	23:58:27
13	news.bbs.geek.nz	2	17821	14	17807	0	0%	23:59:21
14	localhost	49	13	13	0	0	100%	00:00:01
15	news.freedyn.net	2	19981	9	19953	19	0%	25:01:55
16	xanadu-bbs.net	26	510	0	510	0	0%	22:14:24
TOTAL: 16		1431	304991	17853	281953	5185	5%	495:23:40

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.2 MB	10.8 MB	30.0 MB	64%	4.9 KB
2	csiph-out.news.weretis.net	12.5 MB	12.5 MB	24.9 MB	50%	6.3 KB
3	feeder.eternal-september.org	6.8 MB	1.7 MB	8.5 MB	79%	3.1 KB
4	news-out.mixmin.net	5.0 MB	1.3 MB	6.4 MB	78%	2.7 KB
5	news.redatomik.org	3.0 MB	445.4 KB	3.4 MB	87%	2.5 KB
6	newsfeed.neva.ru	2.6 MB	4.6 MB	7.3 MB	36%	2.8 KB
7	newsfeed.fu-berlin.de	2.6 MB	543.7 KB	3.1 MB	82%	2.4 KB
8	news-out.aioe.org	2.2 MB	42.1 KB	2.2 MB	98%	3.2 KB
9	newsfeeds.xmission.com	1.9 MB	184.2 KB	2.0 MB	91%	3.4 KB
10	news.fcku.it	1.3 MB	6.9 MB	8.2 MB	16%	11.8 KB
11	news.swapon.de	138.2 KB	80.3 KB	218.5 KB	63%	3.5 KB
12	news.freedyn.net	95.8 KB	205.4 KB	301.3 KB	31%	10.8 KB
13	nntp.pasdenom.info	62.6 KB	0.0 KB	62.6 KB	100%	1.7 KB
14	news.bbs.geek.nz	33.3 KB	0.0 KB	33.3 KB	100%	2.4 KB
15	localhost	21.5 KB	0.0 KB	21.5 KB	100%	1.7 KB
16	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		57.4 MB	39.3 MB	96.6 MB	59%	4.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 17 04:15:00 - 04:59:59	241	1.3%	0.09	714.7 KB	1.2%	0.26
Mar 17 05:00:00 - 05:59:59	473	2.6%	0.13	2.2 MB	3.9%	0.64
Mar 17 06:00:00 - 06:59:59	488	2.7%	0.14	1.5 MB	2.6%	0.42
Mar 17 07:00:00 - 07:59:59	576	3.2%	0.16	2.4 MB	4.1%	0.67
Mar 17 08:00:00 - 08:59:59	706	3.9%	0.20	2.0 MB	3.4%	0.56
Mar 17 09:00:00 - 09:59:59	781	4.3%	0.22	3.1 MB	5.4%	0.89
Mar 17 10:00:00 - 10:59:59	729	4.0%	0.20	2.1 MB	3.7%	0.60
Mar 17 11:00:00 - 11:59:59	827	4.6%	0.23	2.9 MB	5.1%	0.83
Mar 17 12:00:00 - 12:59:59	1035	5.7%	0.29	3.7 MB	6.4%	1.05
Mar 17 13:00:00 - 13:59:59	925	5.1%	0.26	2.9 MB	5.0%	0.81
Mar 17 14:00:00 - 14:59:59	856	4.7%	0.24	2.5 MB	4.3%	0.71
Mar 17 15:00:00 - 15:59:59	1193	6.6%	0.33	4.5 MB	7.9%	1.29
Mar 17 16:00:00 - 16:59:59	1038	5.8%	0.29	2.9 MB	5.0%	0.82
Mar 17 17:00:00 - 17:59:59	1034	5.7%	0.29	2.8 MB	4.9%	0.81
Mar 17 18:00:00 - 18:59:59	1034	5.7%	0.29	3.2 MB	5.6%	0.92
Mar 17 19:00:00 - 19:59:59	931	5.2%	0.26	2.9 MB	5.1%	0.84
Mar 17 20:00:00 - 20:59:59	989	5.5%	0.27	2.9 MB	5.1%	0.83
Mar 17 21:00:00 - 21:59:59	864	4.8%	0.24	2.3 MB	3.9%	0.64
Mar 17 22:00:00 - 22:59:59	765	4.2%	0.21	2.1 MB	3.6%	0.59
Mar 17 23:00:00 - 23:59:59	662	3.7%	0.18	2.2 MB	3.8%	0.63
Mar 18 00:00:00 - 00:59:59	628	3.5%	0.17	1.9 MB	3.4%	0.55
Mar 18 01:00:00 - 01:59:59	438	2.4%	0.12	1.3 MB	2.3%	0.38
Mar 18 02:00:00 - 02:59:59	376	2.1%	0.10	1.1 MB	1.9%	0.31
Mar 18 03:00:00 - 03:59:59	384	2.1%	0.11	1.4 MB	2.4%	0.39
Mar 18 04:00:00 - 04:15:00	79	0.4%	0.09	227.2 KB	0.4%	0.25
TOTAL: 24:00:00	18052	100.0%	0.21	57.8 MB	100.0%	0.68

Sites sending bad articles:

Server		Total	Group	Other
1	newsfeed.neva.ru	1753	153	1600
2	csiph-out.feeder.erje.net	1530	115	1415
3	news-out.mixmin.net	759	9	750
4	newsfeed.fu-berlin.de	683	36	647
5	csiph-out.news.weretis.net	340	135	205
6	feeder.eternal-september.org	324	102	222
7	news.fcku.it	220	1	219
8	news.redatomik.org	86	0	86
9	news.swapon.de	20	0	20
10	news-out.aioe.org	19	16	3
11	news.freedyn.net	18	1	17
12	newsfeeds.xmission.com	7	0	7
13	nntp.pasdenom.info	1	0	1
TOTAL: 13		5760	568	5192

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.windows7.general	88
lada.talk	52
mpc.lists.freebsd.ports.bugs	42
lada.auto	42
it-alt.sport.calcio.juventus	29
alt.survival	25
gmane.comp.gis.openstreetmap.tagging	20
alt.bbs.synchronet	16
alt.politics.usa	15
oesterreich.tratsch	15
lada.kitchen	15
free.it.religioni.scientology	14
spline.eisfair	13
mozilla.support.thunderbird	13
spline.eisfair.dev	12
mpc.lists.freebsd.office	12
alt.slack.goathead	11
mozilla.support.firefox	9
mozilla.support.seamonkey	5
lada.photo	5
TOTAL: 97	568

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3587
EMP (phn path)	292
HTML Multipart	39
Too many newsgroups	30
User-issued cancel	25
Binary Payload	16
Scoring filter (9)	11
EMP (phn nph)	9
Scoring filter (14)	6
EMP (phl)	6
HTML post	5
Scoring filter (8)	5
Scoring filter (13)	5
Scoring filter (10)	3
Binary: misplaced par2	2
Scoring filter (12)	1
Too many newsgroups (low_xpost)	1
Binary: misplaced nzb	1
TOTAL: 18	4044

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	29	0	30	30
TOTAL: 1	29	6	30	30

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	208
	TOTAL: 1	208
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	csiph-out.feeder.erje.net	170
	csiph-out.news.weretis.net	115
	news.fcku.it	68
	feeder.eternal-september.org	11
	news-out.mixmin.net	5
	newsfeeds.xmission.com	5
	news.redatomik.org	4
	newsfeed.fu-berlin.de	1
	news.swapon.de	1
	TOTAL: 9	380
Including strange strings
	newsfeed.fu-berlin.de	437
	news-out.mixmin.net	30
	news.redatomik.org	25
	csiph-out.feeder.erje.net	25
	news.swapon.de	19
	csiph-out.news.weretis.net	11
	newsfeed.neva.ru	5
	feeder.eternal-september.org	5
	news-out.aioe.org	1
	newsfeeds.xmission.com	1
	TOTAL: 11	560
TOTAL: 4		1152

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	32989	1054	16334	20	8	3%	23:59:52
2	news.freedyn.net	19858	811	17225	2	7	4%	23:59:53
3	xanadu-bbs	21519	667	17379	0	3	3%	23:59:53
4	fu-berlin.de	16775	624	16146	0	4	3%	23:59:53
5	usenet.pasdenom.info	21018	242	16450	1326	14	1%	23:59:53
6	aioe	16254	71	15139	0	842	0%	23:59:45
7	weretis.net	15106	33	12952	5	2	0%	23:59:53
8	erje	13983	28	13198	2	5	0%	23:59:52
9	news.redatomik.org	17143	22	15898	0	3	0%	23:59:53
10	xmission	18417	16	16587	1	3	0%	23:59:53
11	news.swapon.de	1823	14	1754	0	0	0%	23:42:36
12	eternal-september	15932	14	14867	0	4	0%	23:59:48
13	news.mixmin.net	16169	13	15482	0	5	0%	23:59:49
14	neva.ru	16692	10	16235	129	0	0%	23:59:50
15	news.fcku.it	1106	0	1060	0	2	0%	22:30:42
TOTAL: 15		244784	3619	206706	1485	902	1%	358:11:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.2 MB	4.4 KB	8.2 MB	0.1 KB/s	10.3 KB	23:59:53
2	xanadu-bbs	7.7 MB	0.0 KB	7.7 MB	0.1 KB/s	11.8 KB	23:59:53
3	fu-berlin.de	7.6 MB	0.0 KB	7.6 MB	0.1 KB/s	12.5 KB	23:59:53
4	news.bbs.geek.nz	3.0 MB	73.7 KB	3.1 MB	0.0 KB/s	3.0 KB	23:59:52
5	usenet.pasdenom.info	703.4 KB	8.7 MB	9.4 MB	0.1 KB/s	6.2 KB	23:59:53
6	aioe	162.8 KB	0.0 KB	162.8 KB	0.0 KB/s	2.3 KB	23:59:45
7	weretis.net	73.9 KB	16.9 KB	90.8 KB	0.0 KB/s	2.4 KB	23:59:53
8	erje	57.2 KB	4.0 KB	61.2 KB	0.0 KB/s	2.0 KB	23:59:52
9	news.swapon.de	51.7 KB	0.0 KB	51.7 KB	0.0 KB/s	3.7 KB	23:42:36
10	news.redatomik.org	45.2 KB	0.0 KB	45.2 KB	0.0 KB/s	2.1 KB	23:59:53
11	xmission	26.9 KB	3.1 KB	30.0 KB	0.0 KB/s	1.8 KB	23:59:53
12	eternal-september	24.1 KB	0.0 KB	24.1 KB	0.0 KB/s	1.7 KB	23:59:48
13	neva.ru	21.6 KB	320.9 KB	342.5 KB	0.0 KB/s	2.5 KB	23:59:50
14	news.mixmin.net	21.5 KB	0.0 KB	21.5 KB	0.0 KB/s	1.7 KB	23:59:49
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:30:42
TOTAL: 15		27.7 MB	9.1 MB	36.8 MB	0.0 KB/s	7.4 KB	358:11:25

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	49	43	145.8 KB	38	0	0	00:43:37
2	213.55.176.131	1	29	51.9 KB	2	0	0	00:33:19
3	96-42-18-226.dhcp.mdsn.wi.charter.com	25	19	34.4 KB	14	13	8	04:27:42
4	a131033.upc-a.chello.nl	71	16	29.8 KB	8	0	0	16:04:25
TOTAL: 4		146	107	261.9 KB	62	13	8	26:41:56

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	51	72	197.7 KB	40	0	0	01:16:58
2	*.dhcp.mdsn.wi.charter.com	25	19	34.4 KB	14	13	8	04:27:42
3	*.upc-a.chello.nl	71	16	29.8 KB	8	0	0	16:04:25
4	*.ph.ph.cox.net	12	0	0.0 KB	0	0	0	04:52:46
5	*.shodan.io	1	0	0.0 KB	0	0	0	00:00:02
TOTAL: 5		160	107	261.9 KB	62	13	8	26:41:56

NNRP auth users [Top 20]:

User	Conn
moussa	49
ohlandl	8
TOTAL: 2	57

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	5.896	1.135	0.000	16:04:25
ip72-222-200-59.ph.ph.cox.net	1.294	0.185	0.000	04:52:46
96-42-18-226.dhcp.mdsn.wi.charter.com	2.626	0.417	0.029	04:27:42
141.168.117.108	5.510	1.088	0.170	00:43:37
213.55.176.131	0.152	0.076	0.000	00:33:19
census6.shodan.io	0.056	0.008	0.000	00:00:02
71.6.146.130	0.086	0.016	0.000	00:00:00
TOTAL: 7	15.620	2.925	0.199	26:41:56

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-59.ph.ph.cox.net	12
71.6.146.130	1
census6.shodan.io	1
TOTAL: 3	14

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-59.ph.ph.cox.net	7	0
96-42-18-226.dhcp.mdsn.wi.charter.com	4	0
213.55.176.131	1	0
TOTAL: 3	12	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	74	69.2%
2	news	22	20.6%
3	alt	11	10.3%
TOTAL: 3		107	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	40
2	news.admin.peering	22
3	comp.os.vms	13
4	comp.ai.philosophy	9
5	alt.usenet.peering	7
6	comp.os.os2.apps	5
7	alt.comp.freeware	4
8	comp.sys.ibm.pc.games.action	3
9	comp.arch	2
10	comp.arch.embedded	1
11	comp.sys.ibm.as400.misc	1
TOTAL: 11		107

Daily Usenet report for csiph.com

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