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

Unknown entries from news log file:

First 50 / 2826 lines (1.8%)

Nov 16 04:15:16 csiph nnrpd[96475]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:15:47 csiph nnrpd[96479]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:16:18 csiph nnrpd[96505]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:16:49 csiph nnrpd[96506]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:17:19 csiph nnrpd[96532]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:17:50 csiph nnrpd[96533]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:18:21 csiph nnrpd[96559]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:18:51 csiph nnrpd[96568]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:19:22 csiph nnrpd[96583]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:19:52 csiph nnrpd[96595]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:20:23 csiph nnrpd[96632]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:20:53 csiph nnrpd[96665]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:21:24 csiph nnrpd[96666]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:21:55 csiph nnrpd[96692]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:22:26 csiph nnrpd[96714]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:22:57 csiph nnrpd[96741]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:23:28 csiph nnrpd[96742]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:23:59 csiph nnrpd[96768]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:24:30 csiph nnrpd[96769]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:25:00 csiph nnrpd[96815]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:25:32 csiph nnrpd[96818]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:26:02 csiph nnrpd[96844]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:26:32 csiph nnrpd[96845]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:27:03 csiph nnrpd[96867]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:27:34 csiph nnrpd[96872]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:28:05 csiph nnrpd[96877]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:28:36 csiph nnrpd[96899]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:29:07 csiph nnrpd[96900]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:29:38 csiph nnrpd[96926]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:30:08 csiph nnrpd[96937]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:30:38 csiph nnrpd[96963]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:31:09 csiph nnrpd[96970]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:31:40 csiph nnrpd[96996]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:32:11 csiph nnrpd[96997]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:32:42 csiph nnrpd[97025]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:33:13 csiph nnrpd[97182]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:33:44 csiph nnrpd[97208]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:34:14 csiph nnrpd[97209]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:34:45 csiph nnrpd[97234]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:35:16 csiph nnrpd[97246]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:35:47 csiph nnrpd[97256]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:36:18 csiph nnrpd[97276]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:36:49 csiph nnrpd[97277]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:37:20 csiph nnrpd[97303]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:37:51 csiph nnrpd[97318]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:38:21 csiph nnrpd[97358]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:38:52 csiph nnrpd[97359]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:39:22 csiph nnrpd[97385]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:39:53 csiph nnrpd[97386]: snorky.mixmin.net noperm ihave without permission
Nov 16 04:40:24 csiph nnrpd[97425]: snorky.mixmin.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	72206	44.6%	10.4 MB	55.6%
nnrpd	46625	28.8%	4.2 MB	22.1%
innd	41310	25.5%	3.9 MB	20.8%
innfeed	1850	1.1%	288.6 KB	1.5%
TOTAL: 4	161991	100.0%	18.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	271764	43.5%
Negative hits	261404	41.8%
Do not exist	77761	12.4%
Cache misses	14362	2.3%
TOTAL: 4	625291	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.607	0.0%	168	0.000	3.613	21.000
article cleanup	00:00:00.407	0.0%	50542	0.000	0.008	0.036
article logging	00:00:00.623	0.0%	51089	0.000	0.012	0.035
article parse	00:00:53.220	0.1%	4005191	0.005	0.013	0.105
article write	00:01:04.538	0.1%	28351	0.188	2.276	9.792
artlog/artcncl	00:00:00.002	0.0%	141	0.000	0.014	0.250
artlog/artparse	00:00:00.271	0.0%	20977	0.000	0.013	0.080
data move	00:00:04.028	0.0%	4461944	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.077	0.0%	161	0.000	0.478	9.000
hishave/artcncl	00:00:00.001	0.0%	168	0.000	0.006	0.125
hishave/artparse	00:00:00.065	0.0%	20977	0.000	0.003	0.022
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.866	0.0%	604211	0.001	0.008	0.109
history sync	00:00:02.550	0.0%	306	0.000	8.333	113.000
history write	00:01:03.659	0.1%	50526	0.068	1.260	10.452
hiswrite/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hiswrite/artparse	00:00:22.084	0.0%	20975	0.000	1.053	26.333
idle	23:52:24.936	99.5%	4400098	8.962	19.533	403.151
nntp read	00:00:17.181	0.0%	4406647	0.001	0.004	0.009
overview write	00:01:17.613	0.1%	28351	0.419	2.738	25.783
perl filter	00:01:35.002	0.1%	50526	1.015	1.880	3.593
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.556	0.0%	56702	0.000	0.010	0.042
TOTAL: 24:00:18.949	23:59:12.286	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.080	0.0%	28335	0.000	0.003	0.034
article prepare	00:00:00.002	0.0%	9054	0.000	0.000	0.008
article read	00:00:00.320	0.0%	1865	0.000	0.172	17.000
backlog stats	00:00:00.103	0.0%	334287	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.437	0.0%	247550	0.000	0.006	0.011
data write	00:00:01.702	0.0%	226939	0.003	0.007	0.016
idle	23:53:47.949	100.0%	334287	143.650	257.348	993.147
status file	00:00:00.083	0.0%	186	0.000	0.446	1.000
TOTAL: 23:53:56.081	23:53:51.676	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.863	0.0%	14600	0.000	0.059	0.500
idle	70:14:14.125	99.8%	54746	0.000	4618.678	600150.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.724	0.0%	110655	0.000	0.007	0.500
readart	00:00:00.036	0.0%	126	0.000	0.286	6.000
TOTAL: 70:24:03.589	70:14:15.748	99.8%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	146
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	165

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	au2pb.net	1	31353	7183	22829	1341	22%	24:15:07
2	feeder.eternal-september.org	5	46207	7093	34753	4361	15%	24:16:47
3	newsfeeds.xmission.com	1	29223	4933	24087	203	16%	23:26:53
4	news-out.mixmin.net	3	36572	3927	27980	4665	10%	24:17:52
5	newsfeed.neva.ru	143	54976	2981	41919	10076	5%	71:38:30
6	newsfeed.fu-berlin.de	66	36748	1429	34807	512	3%	38:47:07
7	nntp.pasdenom.info	3	29587	300	29098	189	1%	24:08:35
8	news.swapon.de	2	3390	264	3077	49	7%	30:09:33
9	xanadu-bbs.net	14	70147	263	48564	21320	0%	92:12:06
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		264	338203	28373	267114	42716	8%	353:12:30

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	20.1 MB	45.3 KB	33.7 MB	53.8 MB	37%	4.8 KB
2	au2pb.net	19.3 MB	1.8 KB	9.3 MB	28.6 MB	67%	3.4 KB
3	news-out.mixmin.net	14.1 MB	0.0 KB	10.1 MB	24.2 MB	58%	2.9 KB
4	newsfeeds.xmission.com	13.6 MB	36.6 KB	3.9 MB	17.5 MB	77%	3.5 KB
5	newsfeed.neva.ru	10.6 MB	61.4 KB	92.7 MB	103.4 MB	10%	8.1 KB
6	newsfeed.fu-berlin.de	3.3 MB	6.2 KB	1.4 MB	4.7 MB	70%	2.5 KB
7	xanadu-bbs.net	754.8 KB	1.1 KB	6.5 GB	6.5 GB	0%	318.0 KB
8	news.swapon.de	700.8 KB	0.0 KB	273.6 KB	974.4 KB	71%	3.1 KB
9	nntp.pasdenom.info	647.6 KB	1.8 KB	1.9 MB	2.6 MB	24%	5.4 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		83.1 MB	154.2 KB	6.7 GB	6.8 GB	1%	99.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 16 04:15:00 - 04:59:59	324	1.1%	0.12	1.1 MB	1.3%	0.40
Nov 16 05:00:00 - 05:59:59	575	2.0%	0.16	1.7 MB	2.0%	0.48
Nov 16 06:00:00 - 06:59:59	567	2.0%	0.16	1.5 MB	1.7%	0.41
Nov 16 07:00:00 - 07:59:59	827	2.9%	0.23	2.1 MB	2.5%	0.59
Nov 16 08:00:00 - 08:59:59	1058	3.7%	0.29	2.7 MB	3.2%	0.76
Nov 16 09:00:00 - 09:59:59	1642	5.8%	0.46	6.0 MB	7.2%	1.71
Nov 16 10:00:00 - 10:59:59	1390	4.9%	0.39	4.4 MB	5.3%	1.26
Nov 16 11:00:00 - 11:59:59	1177	4.2%	0.33	3.3 MB	4.0%	0.95
Nov 16 12:00:00 - 12:59:59	1266	4.5%	0.35	3.3 MB	4.0%	0.95
Nov 16 13:00:00 - 13:59:59	1506	5.3%	0.42	4.1 MB	4.9%	1.16
Nov 16 14:00:00 - 14:59:59	1467	5.2%	0.41	4.2 MB	5.0%	1.19
Nov 16 15:00:00 - 15:59:59	1668	5.9%	0.46	5.0 MB	6.0%	1.41
Nov 16 16:00:00 - 16:59:59	1758	6.2%	0.49	5.1 MB	6.1%	1.44
Nov 16 17:00:00 - 17:59:59	1726	6.1%	0.48	5.0 MB	6.1%	1.44
Nov 16 18:00:00 - 18:59:59	1639	5.8%	0.46	4.4 MB	5.3%	1.26
Nov 16 19:00:00 - 19:59:59	1734	6.1%	0.48	5.0 MB	6.0%	1.43
Nov 16 20:00:00 - 20:59:59	1674	5.9%	0.47	4.5 MB	5.4%	1.29
Nov 16 21:00:00 - 21:59:59	1449	5.1%	0.40	4.2 MB	5.0%	1.18
Nov 16 22:00:00 - 22:59:59	1245	4.4%	0.35	3.5 MB	4.2%	0.99
Nov 16 23:00:00 - 23:59:59	942	3.3%	0.26	2.9 MB	3.5%	0.82
Nov 17 00:00:00 - 00:59:59	754	2.7%	0.21	2.2 MB	2.7%	0.64
Nov 17 01:00:00 - 01:59:59	650	2.3%	0.18	2.2 MB	2.7%	0.63
Nov 17 02:00:00 - 02:59:59	618	2.2%	0.17	2.3 MB	2.8%	0.66
Nov 17 03:00:00 - 03:59:59	570	2.0%	0.16	2.1 MB	2.6%	0.61
Nov 17 04:00:00 - 04:15:00	125	0.4%	0.14	477.6 KB	0.6%	0.53
TOTAL: 24:00:00	28351	100.0%	0.33	83.3 MB	100.0%	0.99

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	21736	86	1	21649
2	newsfeed.neva.ru	10150	1568	3	8579
3	news-out.mixmin.net	4667	8	0	4659
4	feeder.eternal-september.org	4463	361	5	4097
5	au2pb.net	1328	1202	1	125
6	newsfeed.fu-berlin.de	882	45	4	833
7	newsfeeds.xmission.com	213	160	1	52
8	nntp.pasdenom.info	194	120	1	73
9	news.swapon.de	81	0	0	81
TOTAL: 9		43714	3550	16	40148

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	968
lada.auto	153
ut.test	124
mailing.freebsd.ports-bugs	109
lada.talk	81
alt.politics	78
alt.politics.scorched-earth	72
mozilla.general	67
alt.survival	52
mailing.openbsd.ports-changes	52
mailing.freebsd.svn-ports-all	51
mailing.gcc.gcc-cvs	50
mailing.freebsd.svn-src-all	49
fa.freebsd.cvs-all	49
mailing.freebsd.svn-src-head	48
mailing.freebsd.svn-ports-head	45
mailing.database.pgsql-general	42
mailing.openbsd.source-changes	38
fa.openbsd.tech	36
mailing.openbsd.tech	36
TOTAL: 285	3550

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12840
EMP (phn path)	3665
EMP (phl)	910
Binary: misplaced rar	411
EMP (phn nph)	242
PFM bot misc	180
User-issued spam cancel	103
HTML Multipart	96
Too many newsgroups	82
Binary: misplaced rev	30
Binary Image: misplaced jpg	22
Binary: misplaced par2	15
Binary Payload	12
HTML post	8
HTML Image Tags	2
Binary: misplaced srr	1
Binary: misplaced nfo	1
Binary: misplaced r18	1
EMP (fsl)	1
Too many newsgroups (low_xpost)	1
TOTAL: 22	18625

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	20657
	feeder.eternal-september.org	138
	news-out.mixmin.net	62
	au2pb.net	52
	newsfeeds.xmission.com	35
	nntp.pasdenom.info	25
	newsfeed.fu-berlin.de	5
	news.swapon.de	2
	TOTAL: 8	20976
Including strange strings
	newsfeed.fu-berlin.de	420
	feeder.eternal-september.org	54
	news.swapon.de	37
	au2pb.net	15
	news-out.mixmin.net	13
	newsfeeds.xmission.com	7
	newsfeed.neva.ru	1
	TOTAL: 7	547
TOTAL: 2		21523

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	xanadu-bbs	40202	267	27121	705	1	24	0%	23:59:57
2	usenet.pasdenom.info	33119	217	27258	573	0	4	0%	23:59:57
3	au2pb	21966	204	20834	149	0	1	0%	23:59:57
4	fu-berlin.de	26569	29	26519	20	0	1	0%	23:59:56
5	xmission	30279	26	23101	1	0	0	0%	23:59:48
6	news.mixmin.net	25418	14	24301	0	0	1	0%	23:59:45
7	eternal-september	21573	6	20370	5	0	1	0%	23:59:57
8	neva.ru	26112	6	25135	39	0	1	0%	23:59:57
9	news.swapon.de	2445	0	2426	4	0	0	0%	23:59:57
TOTAL: 9		227683	769	197065	1496	1	33	0%	215:59:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	xanadu-bbs	1.5 MB	1.6 MB	3.1 MB	3.3 KB	23:59:57
2	au2pb	1019.2 KB	352.7 KB	1.3 MB	3.9 KB	23:59:57
3	usenet.pasdenom.info	889.2 KB	1.3 MB	2.2 MB	2.9 KB	23:59:57
4	fu-berlin.de	142.7 KB	26.4 KB	169.1 KB	3.5 KB	23:59:56
5	xmission	65.1 KB	1.8 KB	66.9 KB	2.5 KB	23:59:48
6	news.mixmin.net	63.1 KB	0.0 KB	63.1 KB	4.5 KB	23:59:45
7	neva.ru	56.6 KB	201.2 KB	257.8 KB	5.7 KB	23:59:57
8	eternal-september	22.8 KB	6.5 KB	29.3 KB	2.7 KB	23:59:57
9	news.swapon.de	0.0 KB	7.4 KB	7.4 KB	1.9 KB	23:59:57
TOTAL: 9		3.7 MB	3.5 MB	7.2 MB	3.3 KB	215:59:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	126	350.5 KB	110	0	0	04:16:35
TOTAL: 1		288	126	350.5 KB	110	0	0	70:23:35

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	371	126	350.5 KB	110	54:56:08
2	*.shodan.io	1	0	0.0 KB	0	00:00:00
3	*.bbox.io	42	0	0.0 KB	0	05:21:54
4	*.mixmin.net	2826	0	0.0 KB	0	00:27:12
5	*.chello.nl	32	0	0.0 KB	0	05:14:40
6	*.tampfl.dsl-w.verizon.net	13	0	0.0 KB	0	01:47:03
7	*.nielsenmedia.com	9	0	0.0 KB	0	01:36:01
8	*.154.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	01:00:33
TOTAL: 8		3296	126	350.5 KB	110	70:23:35

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.214	0.929	0.714	0.000	48:57:27
tempe0.bbox.io	3.262	0.713	0.000	05:21:54
dhcp-089-099-203-254.chello.nl	2.555	0.504	0.000	05:14:40
202.89.176.220	29.453	6.131	0.715	04:16:35
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1.102	0.227	0.000	01:47:03
63.142.120.8	4.133	0.704	0.000	01:42:03
challenger.nielsenmedia.com	0.714	0.171	0.000	01:36:01
h112.154.22.98.dynamic.ip.windstream.net	0.205	0.025	0.000	01:00:33
snorky.mixmin.net	234.884	47.551	0.000	00:27:12
69.160.37.54	2.038	0.399	0.000	00:00:02
census12.shodan.io	0.088	0.024	0.000	00:00:00
TOTAL: 11	279.363	57.163	0.715	70:23:35

Curious NNRP server explorers [Top 20]:

System	Conn
snorky.mixmin.net	2826
63.142.120.8	50
tempe0.bbox.io	42
dhcp-089-099-203-254.chello.nl	32
69.160.37.54	25
pool-71-98-160-84.tampfl.dsl-w.verizon.net	13
challenger.nielsenmedia.com	9
162.212.88.214	8
h112.154.22.98.dynamic.ip.windstream.net	2
census12.shodan.io	1
TOTAL: 10	3008

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	8	0
challenger.nielsenmedia.com	3	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3	0
h112.154.22.98.dynamic.ip.windstream.net	2	0
TOTAL: 4	16	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	66
2	comp.arch.embedded	14
3	comp.sys.ibm.pc.games.action	12
4	comp.ai.neural-nets	5
5	comp.sys.ibm.pc.games.war-historical	5
6	comp.os.vms	5
7	comp.sys.ibm.as400.misc	4
8	comp.sys.ibm.pc.games.rpg	3
9	comp.os.os2.ecomstation	2
10	comp.sys.ibm.pc.hardware.storage	2
11	comp.os.os2.advocacy	2
12	comp.sys.ibm.pc.hardware.chips	1
13	comp.ai.nat-lang	1
14	comp.benchmarks	1
15	comp.ai.philosophy	1
16	comp.ai.edu	1
17	comp.os.linux.networking	1
TOTAL: 17		126

Daily Usenet report for csiph.com

Nov 16 04:15:00 -- Nov 17 04:15:00