Daily Usenet report for csiph.com: Jan 16 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jan 16 11:12:18 csiph pgpverify[49732]: pgpverify: verification failed
Jan 16 21:48:12 csiph pgpverify[77087]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	23150	60.3%	5.0 MB	70.9%
innd	11667	30.4%	1.4 MB	20.3%
innfeed	2938	7.6%	550.9 KB	7.7%
nnrpd	643	1.7%	70.0 KB	1.0%
controlchan	14	0.0%	2.3 KB	0.0%
nocem	4	0.0%	0.4 KB	0.0%
pgpverify	2	0.0%	0.3 KB	0.0%
TOTAL: 7	38418	100.0%	7.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	304160	55.1%
Negative hits	225072	40.8%
Do not exist	22561	4.1%
Cache misses	409	0.1%
TOTAL: 4	552202	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.127	0.0%	34	0.000	3.735	18.000
article cleanup	00:00:00.119	0.0%	22342	0.000	0.005	0.035
article logging	00:00:00.407	0.0%	22848	0.000	0.018	0.210
article parse	00:00:01.811	0.0%	66327	0.000	0.027	0.643
article write	00:00:28.188	0.0%	17767	0.190	1.587	44.638
artlog/artcncl	00:00:00.000	0.0%	34	0.000	0.000	0.000
artlog/artparse	00:00:00.002	0.0%	296	0.000	0.007	0.500
data move	00:00:00.132	0.0%	527991	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.037	0.0%	34	0.000	1.088	3.625
hishave/artcncl	00:00:00.006	0.0%	34	0.000	0.176	1.000
hishave/artparse	00:00:00.000	0.0%	295	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:43.109	0.0%	553617	0.002	0.078	0.820
history sync	00:00:01.220	0.0%	376	0.000	3.245	27.333
history write	00:00:57.355	0.1%	22340	0.016	2.567	13.884
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.259	0.0%	295	0.000	4.268	79.500
idle	23:56:49.152	99.7%	530976	91.688	162.360	458.183
nntp read	00:00:02.159	0.0%	536384	0.002	0.004	0.019
overview write	00:00:44.118	0.1%	17767	0.158	2.483	135.507
perl filter	00:00:37.111	0.0%	22335	1.044	1.662	2.891
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.325	0.0%	35540	0.000	0.009	0.030
TOTAL: 24:01:01.687	24:00:26.637	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.039	0.0%	17649	0.000	0.002	0.044
article prepare	00:00:00.002	0.0%	16896	0.000	0.000	0.003
article read	00:00:00.471	0.0%	2238	0.000	0.210	11.667
backlog stats	00:00:00.181	0.0%	331927	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.215	0.0%	254786	0.001	0.005	0.010
data write	00:00:01.526	0.0%	239283	0.000	0.006	0.014
idle	23:44:57.081	100.0%	331927	156.822	257.578	726.349
status file	00:00:00.156	0.0%	253	0.000	0.617	2.250
TOTAL: 23:45:11.990	23:45:00.671	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	21:46:11.335	100.0%	438	0.000	178929.989	600172.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.005	0.0%	1006	0.000	0.005	0.167
readart	00:00:00.707	0.0%	33	0.000	21.424	111.000
TOTAL: 21:46:36.185	21:46:12.047	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	3
logmode	2
mode	139
pause	3
paused	3
reload	4
reserve	2
TOTAL: 10	164

Control Channel:

Sender	Other	Bad PGP	OK
control@usenet.org.uk	0	1	0
linux-admin@bofh.it	1	1	1
TOTAL	1	2	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	11	55654	5359	49764	531	9%	73:44:07
2	csiph-out.feeder.erje.net	1440	91182	2632	87135	1415	2%	118:51:58
3	feeder.eternal-september.org	2	19451	2217	16906	328	11%	23:24:12
4	news-out.aioe.org	3	17386	1680	15657	49	9%	24:09:01
5	news-out.mixmin.net	3	18553	1676	16142	735	9%	24:08:24
6	news.redatomik.org	2	13687	1481	12172	34	10%	23:59:44
7	newsfeed.fu-berlin.de	9	17550	1073	16230	247	6%	25:41:32
8	newsfeed.neva.ru	148	21393	998	19032	1363	4%	71:59:34
9	news.freedyn.net	2	19013	260	18641	112	1%	23:59:46
10	news.fcku.it	13	1994	238	1749	7	11%	22:18:22
11	newsfeeds.xmission.com	1	16920	174	16742	4	1%	23:59:53
12	news.swapon.de	3	1539	45	1494	0	2%	24:52:15
13	nntp.pasdenom.info	2	16524	29	16491	4	0%	23:59:24
14	localhost	33	7	7	0	0	100%	00:00:01
15	news.bbs.nz	3	27462	5	27457	0	0%	34:49:52
16	xanadu-bbs.net	22	516	0	514	2	0%	22:54:34
TOTAL: 16		1697	338831	17874	316126	4831	5%	562:52:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	15.7 MB	1.6 KB	11.2 MB	26.9 MB	58%	4.7 KB
2	csiph-out.feeder.erje.net	12.3 MB	1.3 KB	12.1 MB	24.4 MB	50%	6.2 KB
3	feeder.eternal-september.org	6.1 MB	0.0 KB	3.1 MB	9.2 MB	66%	3.7 KB
4	news-out.mixmin.net	5.3 MB	1.5 KB	1.7 MB	7.0 MB	75%	3.0 KB
5	news.redatomik.org	4.0 MB	0.0 KB	54.3 KB	4.1 MB	98%	2.8 KB
6	news-out.aioe.org	3.9 MB	0.0 KB	255.3 KB	4.2 MB	94%	2.5 KB
7	newsfeed.neva.ru	2.7 MB	0.0 KB	4.7 MB	7.4 MB	36%	3.2 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	638.3 KB	3.1 MB	79%	2.4 KB
9	news.freedyn.net	964.3 KB	0.0 KB	430.6 KB	1.4 MB	69%	3.7 KB
10	newsfeeds.xmission.com	572.2 KB	1.4 KB	301.8 KB	875.4 KB	65%	4.9 KB
11	news.fcku.it	406.8 KB	0.0 KB	8.4 KB	415.2 KB	97%	1.7 KB
12	news.swapon.de	125.2 KB	0.0 KB	0.0 KB	125.2 KB	100%	2.8 KB
13	nntp.pasdenom.info	54.1 KB	0.0 KB	3.0 KB	57.1 KB	94%	1.7 KB
14	news.bbs.nz	15.3 KB	0.0 KB	0.0 KB	15.3 KB	100%	3.1 KB
15	localhost	10.1 KB	0.0 KB	0.0 KB	10.1 KB	100%	1.4 KB
16	xanadu-bbs.net	0.0 KB	0.0 KB	5.2 KB	5.2 KB	0%	2.6 KB
TOTAL: 16		54.5 MB	5.9 KB	34.4 MB	89.0 MB	61%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 16 04:15:00 - 04:59:59	288	1.6%	0.11	944.5 KB	1.7%	0.35
Jan 16 05:00:00 - 05:59:59	393	2.2%	0.11	1.6 MB	3.0%	0.46
Jan 16 06:00:00 - 06:59:59	394	2.2%	0.11	1.2 MB	2.2%	0.34
Jan 16 07:00:00 - 07:59:59	518	2.9%	0.14	1.8 MB	3.2%	0.50
Jan 16 08:00:00 - 08:59:59	660	3.7%	0.18	2.4 MB	4.5%	0.69
Jan 16 09:00:00 - 09:59:59	645	3.6%	0.18	1.7 MB	3.2%	0.49
Jan 16 10:00:00 - 10:59:59	703	4.0%	0.20	1.8 MB	3.3%	0.51
Jan 16 11:00:00 - 11:59:59	921	5.2%	0.26	2.7 MB	5.0%	0.77
Jan 16 12:00:00 - 12:59:59	789	4.4%	0.22	2.0 MB	3.7%	0.56
Jan 16 13:00:00 - 13:59:59	976	5.5%	0.27	2.5 MB	4.7%	0.72
Jan 16 14:00:00 - 14:59:59	851	4.8%	0.24	2.3 MB	4.2%	0.65
Jan 16 15:00:00 - 15:59:59	1021	5.8%	0.28	3.0 MB	5.6%	0.85
Jan 16 16:00:00 - 16:59:59	1168	6.6%	0.32	3.8 MB	7.1%	1.08
Jan 16 17:00:00 - 17:59:59	1094	6.2%	0.30	3.1 MB	5.8%	0.89
Jan 16 18:00:00 - 18:59:59	1107	6.2%	0.31	3.2 MB	5.9%	0.91
Jan 16 19:00:00 - 19:59:59	1042	5.9%	0.29	3.1 MB	5.8%	0.89
Jan 16 20:00:00 - 20:59:59	936	5.3%	0.26	2.7 MB	5.1%	0.78
Jan 16 21:00:00 - 21:59:59	829	4.7%	0.23	2.6 MB	4.8%	0.74
Jan 16 22:00:00 - 22:59:59	915	5.2%	0.25	2.7 MB	4.9%	0.76
Jan 16 23:00:00 - 23:59:59	706	4.0%	0.20	2.4 MB	4.5%	0.69
Jan 17 00:00:00 - 00:59:59	484	2.7%	0.13	1.6 MB	3.0%	0.46
Jan 17 01:00:00 - 01:59:59	417	2.3%	0.12	1.3 MB	2.4%	0.37
Jan 17 02:00:00 - 02:59:59	449	2.5%	0.12	1.4 MB	2.6%	0.41
Jan 17 03:00:00 - 03:59:59	348	2.0%	0.10	1.6 MB	3.0%	0.45
Jan 17 04:00:00 - 04:15:00	92	0.5%	0.10	463.1 KB	0.8%	0.51
TOTAL: 24:00:00	17746	100.0%	0.21	54.0 MB	100.0%	0.64

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	1422	92	1	1329
2	newsfeed.neva.ru	1340	175	0	1165
3	newsfeed.fu-berlin.de	746	13	0	733
4	news-out.mixmin.net	744	0	1	743
5	csiph-out.news.weretis.net	536	185	1	350
6	feeder.eternal-september.org	334	34	0	300
7	news.freedyn.net	112	1	0	111
8	news-out.aioe.org	53	48	0	5
9	news.redatomik.org	47	1	0	46
10	news.swapon.de	15	0	0	15
11	newsfeeds.xmission.com	8	0	0	8
12	news.fcku.it	7	6	0	1
13	nntp.pasdenom.info	4	0	0	4
14	xanadu-bbs.net	2	0	0	2
TOTAL: 14		5370	555	3	4812

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	117
alt.music.pink-floyd.publius	63
lada.soft	42
gmane.comp.gis.openstreetmap.tagging	41
it-alt.sport.calcio.juventus	24
gmane.linux.alsa.devel	21
lada.auto	18
alt.bbs.synchronet	17
spline.eisfair	12
alt.survival	12
mozilla.general	9
alt.slack.goathead	8
mozilla.support.firefox	8
alt.windows7.general	7
mozilla.dev.security.policy	7
free.it.religioni.scientology	6
alt.politics	6
alt.support.chronic.pain	5
uk.rec.radio.cb	5
alt.binaries.batman	5
TOTAL: 99	555

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2855
EMP (phn path)	304
Binary: misplaced par2	294
Binary: misplaced nfo	205
Too many newsgroups	129
EMP (phl)	85
User-issued cancel	41
HTML Multipart	20
Scoring filter (8)	17
Scoring filter (10)	16
Binary: misplaced rar	11
Scoring filter (11)	8
Scoring filter (9)	7
Binary Payload	6
HTML Image Tags	2
Too many newsgroups (meow)	2
U2 violation - invalid distribution	1
Scoring filter (14)	1
Binary: misplaced arj	1
Scoring filter (13)	1
TOTAL: 21	4007

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	3
	TOTAL: 1	3
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	146
	csiph-out.news.weretis.net	114
	feeder.eternal-september.org	17
	newsfeeds.xmission.com	8
	news-out.mixmin.net	6
	newsfeed.fu-berlin.de	2
	news.freedyn.net	2
	TOTAL: 7	295
Including strange strings
	newsfeed.fu-berlin.de	430
	csiph-out.news.weretis.net	20
	news.swapon.de	15
	news-out.mixmin.net	15
	news.redatomik.org	13
	csiph-out.feeder.erje.net	7
	news-out.aioe.org	4
	newsfeed.neva.ru	1
	TOTAL: 8	505
TOTAL: 4		848

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19266	585	17161	0	0	3%	23:59:47
2	news.freedyn.net	17645	554	16924	4	0	3%	23:59:47
3	fu-berlin.de	16565	499	16066	0	0	3%	23:59:47
4	news.bbs.nz	33631	458	16748	20	0	1%	23:57:05
5	erje	17236	217	14375	447	0	1%	23:59:47
6	usenet.pasdenom.info	18329	91	16499	1127	0	0%	23:59:47
7	aioe	15116	44	14787	2	0	0%	23:57:05
8	xmission	20922	13	16968	2	0	0%	23:59:47
9	news.redatomik.org	15968	9	15739	9	4	0%	23:59:47
10	neva.ru	16199	9	15930	109	0	0%	23:59:31
11	news.swapon.de	1554	9	1533	0	0	0%	23:53:50
12	news.mixmin.net	15591	9	15379	0	0	0%	23:59:31
13	eternal-september	15153	8	14900	0	0	0%	23:59:47
14	weretis.net	11847	7	11711	1	2	0%	23:52:29
15	news.fcku.it	1701	0	1694	0	0	0%	22:09:47
TOTAL: 15		236723	2512	206414	1721	6	1%	357:47:34

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.3 MB	0.0 KB	6.3 MB	0.1 KB/s	11.1 KB	23:59:47
2	news.freedyn.net	6.3 MB	4.5 KB	6.3 MB	0.1 KB/s	11.5 KB	23:59:47
3	fu-berlin.de	6.1 MB	0.0 KB	6.1 MB	0.1 KB/s	12.5 KB	23:59:47
4	news.bbs.nz	1.1 MB	171.7 KB	1.3 MB	0.0 KB/s	2.8 KB	23:57:05
5	erje	568.6 KB	1.4 MB	2.0 MB	0.0 KB/s	3.0 KB	23:59:47
6	usenet.pasdenom.info	237.8 KB	7.1 MB	7.3 MB	0.1 KB/s	6.1 KB	23:59:47
7	aioe	128.4 KB	3.2 KB	131.6 KB	0.0 KB/s	2.9 KB	23:57:05
8	news.redatomik.org	30.9 KB	20.0 KB	50.8 KB	0.0 KB/s	2.8 KB	23:59:47
9	xmission	23.9 KB	6.9 KB	30.7 KB	0.0 KB/s	2.0 KB	23:59:47
10	news.mixmin.net	19.9 KB	0.0 KB	19.9 KB	0.0 KB/s	2.2 KB	23:59:31
11	news.swapon.de	14.3 KB	0.0 KB	14.3 KB	0.0 KB/s	1.6 KB	23:53:50
12	neva.ru	13.1 KB	319.3 KB	332.3 KB	0.0 KB/s	2.8 KB	23:59:31
13	eternal-september	12.5 KB	0.0 KB	12.5 KB	0.0 KB/s	1.6 KB	23:59:47
14	weretis.net	10.1 KB	26.6 KB	36.7 KB	0.0 KB/s	4.6 KB	23:52:29
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:09:47
TOTAL: 15		20.9 MB	9.0 MB	29.9 MB	0.0 KB/s	7.2 KB	357:47:34

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	54	20	35.7 KB	8	0	0	11:44:44
2	71-82-226-171.dhcp.stpt.wi.charter.com	29	11	17.8 KB	10	7	6	07:34:58
3	208-71-239-109.cust.centrio.cz	4	2	3.1 KB	2	0	0	01:26:40
TOTAL: 3		87	33	56.5 KB	20	7	6	21:46:35

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	54	20	35.7 KB	8	0	0	11:44:44
2	*.dhcp.stpt.wi.charter.com	29	11	17.8 KB	10	7	6	07:34:58
3	*.cust.centrio.cz	4	2	3.1 KB	2	0	0	01:26:40
4	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	01:00:10
5	*.sfj.corp.censys.io	2	0	0.0 KB	0	0	0	00:00:01
TOTAL: 5		93	33	56.5 KB	20	7	6	21:46:35

NNRP auth users [Top 20]:

User	Conn
ohlandl	6
ctomczyk	4
TOTAL: 2	10

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	4.158	0.751	0.000	11:44:44
71-82-226-171.dhcp.stpt.wi.charter.com	2.541	0.415	0.014	07:34:58
208-71-239-109.cust.centrio.cz	0.406	0.055	0.010	01:26:40
ip72-222-200-117.ph.ph.cox.net	0.226	0.029	0.000	01:00:10
worker-18.sfj.corp.censys.io	0.143	0.016	0.000	00:00:01
TOTAL: 5	7.474	1.266	0.024	21:46:35

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	4
worker-18.sfj.corp.censys.io	2
TOTAL: 2	6

NNRP unrecognized commands (by host):

System	Conn
worker-18.sfj.corp.censys.io	2
TOTAL: 1	2

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
Host: 69.160.37.58:119	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-82-226-171.dhcp.stpt.wi.charter.com	10	0
ip72-222-200-117.ph.ph.cox.net	2	0
208-71-239-109.cust.centrio.cz	2	0
TOTAL: 3	14	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	31
2	comp.lang.javascript	2
TOTAL: 2		33

Daily Usenet report for csiph.com

Jan 16 04:15:00 -- Jan 17 04:15:00