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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	28591	65.9%	6.1 MB	75.5%
innd	10793	24.9%	1.4 MB	17.1%
innfeed	2906	6.7%	487.1 KB	5.9%
nnrpd	920	2.1%	103.2 KB	1.2%
nocem	153	0.4%	17.8 KB	0.2%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	43364	100.0%	8.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	352524	53.1%
Negative hits	285180	42.9%
Do not exist	24942	3.8%
Cache misses	1798	0.3%
TOTAL: 4	664444	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:46.397	0.1%	3198	0.000	14.508	163.000
article cleanup	00:00:00.137	0.0%	24724	0.000	0.006	0.035
article logging	00:00:00.515	0.0%	25263	0.000	0.020	0.536
article parse	00:00:01.501	0.0%	74011	0.000	0.020	0.290
article write	00:03:24.029	0.2%	21015	0.637	9.709	135.894
artlog/artcncl	00:00:00.039	0.0%	3038	0.000	0.013	0.022
artlog/artparse	00:00:00.003	0.0%	313	0.000	0.010	1.000
data move	00:00:00.139	0.0%	629319	0.000	0.000	0.001
hisgrep/artcncl	00:00:05.460	0.0%	2877	0.000	1.898	14.000
hishave/artcncl	00:00:04.738	0.0%	3198	0.000	1.482	3.013
hishave/artparse	00:00:00.002	0.0%	313	0.000	0.006	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:57.132	0.1%	669525	0.001	0.085	0.402
history sync	00:00:01.025	0.0%	365	0.000	2.808	49.500
history write	00:01:07.963	0.1%	24706	0.200	2.751	10.328
hiswrite/artcncl	00:00:01.027	0.0%	321	0.000	3.199	6.889
hiswrite/artparse	00:00:00.865	0.0%	313	0.000	2.764	21.600
idle	23:48:44.319	99.2%	616826	46.738	138.977	492.203
nntp read	00:00:02.721	0.0%	635463	0.001	0.004	0.023
overview write	00:04:20.713	0.3%	21015	0.420	12.406	160.409
perl filter	00:00:42.416	0.0%	24704	0.964	1.717	4.899
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.387	0.0%	42179	0.000	0.009	0.030
TOTAL: 24:00:56.130	24:00:21.528	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.068	0.0%	21111	0.000	0.003	0.035
article prepare	00:00:00.001	0.0%	15092	0.000	0.000	0.042
article read	00:00:07.165	0.0%	1629	0.000	4.398	240.800
backlog stats	00:00:00.228	0.0%	381430	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.797	0.0%	297044	0.001	0.006	0.035
data write	00:00:01.869	0.0%	279006	0.001	0.007	0.013
idle	23:53:53.590	100.0%	381430	72.410	225.555	703.783
status file	00:00:01.143	0.0%	280	0.000	4.082	76.000
TOTAL: 23:54:15.096	23:54:05.861	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	31:09:11.944	100.0%	727	115.000	154266.773	600227.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.011	0.0%	1739	0.000	0.006	0.167
readart	00:00:00.668	0.0%	78	0.000	8.564	48.000
TOTAL: 31:09:51.129	31:09:12.623	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	11	64921	4744	59673	504	7%	73:16:45
2	news-out.mixmin.net	4	20408	2922	16813	673	14%	24:11:12
3	news.redatomik.org	3	20275	2778	17436	61	13%	24:02:44
4	csiph-out.feeder.erje.net	1441	106301	2662	103030	609	2%	118:41:57
5	feeder.eternal-september.org	1	22618	2266	20168	184	10%	23:19:44
6	news-out.aioe.org	4	21237	1927	19244	66	9%	24:14:45
7	newsfeed.neva.ru	145	18360	1254	15691	1415	6%	71:38:55
8	newsfeed.fu-berlin.de	9	19458	1054	18207	197	5%	25:04:45
9	news.freedyn.net	4	22208	711	21254	243	3%	24:09:34
10	news.fcku.it	6	3023	400	2605	18	13%	23:29:40
11	nntp.pasdenom.info	3	18942	62	18844	36	0%	23:57:40
12	news.swapon.de	4	1627	59	1568	0	3%	23:13:45
13	newsfeeds.xmission.com	3	4226	36	4190	0	0%	07:43:34
14	localhost	39	9	9	0	0	100%	00:00:00
15	news.bbs.nz	2	18276	2	18274	0	0%	23:59:46
16	xanadu-bbs.net	26	490	0	489	1	0%	18:36:07
TOTAL: 16		1705	362379	20886	337486	4007	5%	529:40:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	15.4 MB	10.8 KB	4.9 MB	20.3 MB	75%	4.0 KB
2	csiph-out.feeder.erje.net	13.8 MB	30.9 KB	11.2 MB	25.0 MB	55%	7.8 KB
3	news.redatomik.org	9.1 MB	0.0 KB	1.7 MB	10.8 MB	84%	3.9 KB
4	news-out.mixmin.net	7.9 MB	8.1 KB	1.9 MB	9.8 MB	80%	2.8 KB
5	feeder.eternal-september.org	6.0 MB	2.9 KB	2.7 MB	8.7 MB	69%	3.6 KB
6	news-out.aioe.org	4.5 MB	2.8 KB	282.6 KB	4.7 MB	94%	2.4 KB
7	newsfeed.neva.ru	3.7 MB	2.5 KB	5.1 MB	8.8 MB	41%	3.4 KB
8	news.freedyn.net	2.7 MB	1.6 KB	870.7 KB	3.6 MB	76%	3.8 KB
9	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	521.8 KB	3.1 MB	83%	2.5 KB
10	news.fcku.it	667.7 KB	0.0 KB	116.2 KB	783.9 KB	85%	1.9 KB
11	news.swapon.de	154.5 KB	0.0 KB	0.0 KB	154.5 KB	100%	2.6 KB
12	nntp.pasdenom.info	131.4 KB	0.0 KB	68.4 KB	199.8 KB	65%	2.0 KB
13	newsfeeds.xmission.com	110.7 KB	0.0 KB	0.0 KB	110.7 KB	100%	3.1 KB
14	localhost	11.9 KB	0.0 KB	0.0 KB	11.9 KB	100%	1.3 KB
15	news.bbs.nz	6.9 KB	0.0 KB	0.0 KB	6.9 KB	100%	3.4 KB
16	xanadu-bbs.net	0.0 KB	0.0 KB	2.5 KB	2.5 KB	0%	2.5 KB
TOTAL: 16		66.8 MB	59.5 KB	29.3 MB	96.1 MB	69%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 30 04:15:00 - 04:59:59	339	1.6%	0.13	1.1 MB	1.6%	0.41
Jan 30 05:00:00 - 05:59:59	418	2.0%	0.12	2.2 MB	3.3%	0.62
Jan 30 06:00:00 - 06:59:59	446	2.1%	0.12	1.8 MB	2.7%	0.51
Jan 30 07:00:00 - 07:59:59	496	2.4%	0.14	1.8 MB	2.7%	0.51
Jan 30 08:00:00 - 08:59:59	632	3.0%	0.18	1.7 MB	2.5%	0.48
Jan 30 09:00:00 - 09:59:59	783	3.7%	0.22	2.2 MB	3.2%	0.62
Jan 30 10:00:00 - 10:59:59	952	4.5%	0.26	3.2 MB	4.8%	0.91
Jan 30 11:00:00 - 11:59:59	795	3.8%	0.22	2.5 MB	3.8%	0.72
Jan 30 12:00:00 - 12:59:59	941	4.5%	0.26	2.9 MB	4.4%	0.83
Jan 30 13:00:00 - 13:59:59	981	4.7%	0.27	2.9 MB	4.3%	0.81
Jan 30 14:00:00 - 14:59:59	975	4.6%	0.27	2.9 MB	4.4%	0.83
Jan 30 15:00:00 - 15:59:59	1290	6.1%	0.36	4.1 MB	6.1%	1.17
Jan 30 16:00:00 - 16:59:59	1141	5.4%	0.32	3.5 MB	5.3%	1.01
Jan 30 17:00:00 - 17:59:59	1140	5.4%	0.32	4.4 MB	6.6%	1.26
Jan 30 18:00:00 - 18:59:59	1197	5.7%	0.33	3.6 MB	5.3%	1.01
Jan 30 19:00:00 - 19:59:59	2113	10.1%	0.59	6.9 MB	10.2%	1.95
Jan 30 20:00:00 - 20:59:59	1555	7.4%	0.43	4.3 MB	6.4%	1.22
Jan 30 21:00:00 - 21:59:59	917	4.4%	0.25	2.5 MB	3.8%	0.72
Jan 30 22:00:00 - 22:59:59	912	4.3%	0.25	2.8 MB	4.1%	0.79
Jan 30 23:00:00 - 23:59:59	745	3.5%	0.21	2.5 MB	3.7%	0.71
Jan 31 00:00:00 - 00:59:59	699	3.3%	0.19	2.0 MB	3.0%	0.57
Jan 31 01:00:00 - 01:59:59	626	3.0%	0.17	1.9 MB	2.9%	0.54
Jan 31 02:00:00 - 02:59:59	427	2.0%	0.12	1.4 MB	2.1%	0.40
Jan 31 03:00:00 - 03:59:59	389	1.9%	0.11	1.7 MB	2.5%	0.47
Jan 31 04:00:00 - 04:15:00	86	0.4%	0.10	262.7 KB	0.4%	0.29
TOTAL: 24:00:00	20995	100.0%	0.24	66.9 MB	100.0%	0.79

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	1418	142	1	1275
2	news-out.mixmin.net	678	2	2	674
3	newsfeed.fu-berlin.de	661	24	0	637
4	csiph-out.feeder.erje.net	627	74	8	545
5	csiph-out.news.weretis.net	521	180	4	337
6	news.freedyn.net	242	22	1	219
7	feeder.eternal-september.org	192	45	1	146
8	news.redatomik.org	77	0	0	77
9	news-out.aioe.org	68	47	1	20
10	nntp.pasdenom.info	37	1	0	36
11	news.fcku.it	18	16	0	2
12	news.swapon.de	14	0	0	14
13	newsfeeds.xmission.com	4	0	0	4
14	xanadu-bbs.net	1	0	0	1
TOTAL: 14		4558	553	18	3987

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	67
it-alt.sport.calcio.juventus	65
lada.auto	40
gmane.linux.alsa.devel	38
gmane.comp.gis.openstreetmap.tagging	24
alt.computer.workshop	24
spline.eisfair	23
alt.bbs.synchronet	18
mozilla.dev.platform	17
alt.music.pink-floyd.publius	16
alt.survival	16
alt.windows7.general	14
it-alt.comp.software.mozilla	11
oesterreich.tratsch	11
alt.binaries.documentaries	8
alt.politics	8
at.jobs	7
alt.politics.usa	7
lada.commerce	6
gmane.linux.kernel	6
TOTAL: 99	553

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2331
EMP (phn path)	524
Too many newsgroups	67
User-issued cancel	42
User-issued spam cancel	34
HTML Multipart	25
EMP (phl)	24
EMP (phn nph)	22
Binary: misplaced nfo	17
Binary: misplaced par2	15
Scoring filter (10)	11
Scoring filter (12)	5
Binary: misplaced rar	5
Binary Payload	3
Scoring filter (9)	3
Too many newsgroups (meow)	2
Scoring filter (14)	2
Scoring filter (8)	1
Scoring filter (11)	1
Scoring filter (13)	1
TOTAL: 22	3137

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	144	0	1753	1753
TOTAL: 1	144	5	1753	1753

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	TOTAL: 1	50
Huge articles
	csiph-out.feeder.erje.net	193
	csiph-out.news.weretis.net	55
	feeder.eternal-september.org	24
	news.redatomik.org	15
	news-out.mixmin.net	14
	news.freedyn.net	6
	newsfeeds.xmission.com	3
	newsfeed.fu-berlin.de	2
	news.fcku.it	1
	TOTAL: 9	313
Including strange strings
	newsfeed.fu-berlin.de	451
	csiph-out.feeder.erje.net	18
	csiph-out.news.weretis.net	17
	news.redatomik.org	15
	news.swapon.de	14
	news-out.mixmin.net	9
	feeder.eternal-september.org	6
	newsfeed.neva.ru	3
	news-out.aioe.org	2
	TOTAL: 9	535
TOTAL: 3		898

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	22858	693	20300	0	0	3%	23:59:54
2	news.freedyn.net	20422	693	19584	3	0	3%	23:59:54
3	fu-berlin.de	19758	621	19137	0	0	3%	23:59:54
4	news.bbs.nz	39299	200	20147	14	1	0%	23:59:54
5	usenet.pasdenom.info	21379	101	19583	1248	53	0%	23:59:54
6	xmission	24792	16	20018	0	0	0%	23:59:54
7	news.redatomik.org	17749	14	17494	0	8	0%	23:59:54
8	erje	17897	14	17734	1	0	0%	23:59:54
9	neva.ru	19000	11	18771	94	0	0%	23:59:49
10	aioe	17491	11	17179	0	81	0%	23:59:50
11	news.swapon.de	1685	9	1658	0	0	0%	23:50:16
12	weretis.net	15601	9	15397	0	0	0%	23:59:54
13	eternal-september	18229	8	17888	7	0	0%	23:59:45
14	news.mixmin.net	17455	8	17219	0	0	0%	23:59:54
15	news.fcku.it	2241	0	2237	0	0	0%	23:06:56
TOTAL: 15		275856	2408	244346	1367	143	0%	358:55:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.9 MB	6.7 KB	7.9 MB	0.1 KB/s	11.6 KB	23:59:54
2	xanadu-bbs	7.9 MB	0.0 KB	7.9 MB	0.1 KB/s	11.7 KB	23:59:54
3	fu-berlin.de	7.7 MB	0.0 KB	7.7 MB	0.1 KB/s	12.7 KB	23:59:54
4	news.bbs.nz	530.9 KB	81.2 KB	612.1 KB	0.0 KB/s	2.9 KB	23:59:54
5	usenet.pasdenom.info	205.2 KB	8.7 MB	8.9 MB	0.1 KB/s	6.8 KB	23:59:54
6	news.redatomik.org	30.1 KB	0.0 KB	30.1 KB	0.0 KB/s	2.2 KB	23:59:54
7	aioe	28.5 KB	0.0 KB	28.5 KB	0.0 KB/s	2.6 KB	23:59:50
8	xmission	25.3 KB	0.0 KB	25.3 KB	0.0 KB/s	1.6 KB	23:59:54
9	erje	19.2 KB	1.5 KB	20.7 KB	0.0 KB/s	1.4 KB	23:59:54
10	neva.ru	17.2 KB	235.5 KB	252.7 KB	0.0 KB/s	2.4 KB	23:59:49
11	news.swapon.de	11.9 KB	0.0 KB	11.9 KB	0.0 KB/s	1.3 KB	23:50:16
12	weretis.net	11.9 KB	0.0 KB	11.9 KB	0.0 KB/s	1.3 KB	23:59:54
13	eternal-september	11.0 KB	31.0 KB	42.0 KB	0.0 KB/s	2.8 KB	23:59:45
14	news.mixmin.net	11.0 KB	0.0 KB	11.0 KB	0.0 KB/s	1.4 KB	23:59:54
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:06:56
TOTAL: 15		24.4 MB	9.0 MB	33.5 MB	0.0 KB/s	9.1 KB	358:55:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	21	39	63.8 KB	9	1	1	05:16:15
2	a131033.upc-a.chello.nl	73	22	35.8 KB	6	0	0	15:57:38
3	71-82-226-171.dhcp.stpt.wi.charter.com	29	17	24.4 KB	13	8	7	08:24:55
TOTAL: 3		123	78	124.0 KB	28	9	8	31:09:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.ph.ph.cox.net	21	39	63.8 KB	9	1	1	05:16:15
2	*.upc-a.chello.nl	73	22	35.8 KB	6	0	0	15:57:38
3	*.dhcp.stpt.wi.charter.com	29	17	24.4 KB	13	8	7	08:24:55
4	*.census.shodan.io	1	0	0.0 KB	0	0	0	00:00:10
5	*.cust.centrio.cz	3	0	0.0 KB	0	0	0	01:00:36
6	unresolved	3	0	0.0 KB	0	0	0	00:00:03
7	*.nielsenmedia.com	1	0	0.0 KB	0	0	0	00:30:09
TOTAL: 7		131	78	124.0 KB	28	9	8	31:09:48

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
ctomczyk	3
kev009	1
TOTAL: 3	11

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	5.739	0.903	0.000	15:57:38
71-82-226-171.dhcp.stpt.wi.charter.com	2.650	0.463	0.021	08:24:55
ip72-222-200-117.ph.ph.cox.net	2.057	0.307	0.002	05:16:15
208-71-239-109.cust.centrio.cz	0.194	0.047	0.008	01:00:36
challenger.nielsenmedia.com	0.081	0.000	0.000	00:30:09
pirate.census.shodan.io	0.074	0.008	0.000	00:00:10
94.166.237.88	0.136	0.024	0.000	00:00:02
122.228.19.79	0.071	0.008	0.000	00:00:00
TOTAL: 8	11.002	1.760	0.031	31:09:48

Curious NNRP server explorers [Top 20]:

System	Conn
208-71-239-109.cust.centrio.cz	3
94.166.237.88	2
challenger.nielsenmedia.com	1
pirate.census.shodan.io	1
122.228.19.79	1
TOTAL: 5	8

NNRP unrecognized commands (by host):

System	Conn
122.228.19.79	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

Command	Count
Host: 69.160.37.58:119	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
Connection: keep-alive	1
Accept: /	1
GET / HTTP/1.1	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-82-226-171.dhcp.stpt.wi.charter.com	8	0
ip72-222-200-117.ph.ph.cox.net	5	0
208-71-239-109.cust.centrio.cz	2	0
challenger.nielsenmedia.com	1	0
TOTAL: 4	16	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	77
2	comp.unix.bsd.netbsd.misc	1
TOTAL: 2		78

Daily Usenet report for csiph.com

Jan 30 04:15:00 -- Jan 31 04:15:00