Daily Usenet report for csiph.com: Aug 19 04:15:00

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Aug 19 21:10:03 csiph controlchan[8758]: Unknown "tags" control by robomod@news.nic.it
Aug 19 21:40:03 csiph controlchan[8758]: Unknown "tags" control by robomod@news.nic.it
Aug 19 22:10:03 csiph controlchan[8758]: Multiple control headers in article <pZjIu-27O-13@gated-at.bofh.it>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	55431	48.0%	7.7 MB	58.6%
nnrpd	33683	29.1%	2.8 MB	21.4%
innd	24576	21.3%	2.3 MB	17.6%
innfeed	1863	1.6%	318.0 KB	2.4%
controlchan	5	0.0%	0.4 KB	0.0%
TOTAL: 5	115558	100.0%	13.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	248142	44.9%
Negative hits	244258	44.2%
Do not exist	54573	9.9%
Cache misses	5643	1.0%
TOTAL: 4	552616	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.378	0.0%	236	0.000	1.602	114.000
article cleanup	00:00:00.330	0.0%	53443	0.000	0.006	0.022
article logging	00:00:00.651	0.0%	53656	0.000	0.012	0.040
article parse	00:00:03.343	0.0%	359881	0.000	0.009	0.023
article write	00:00:06.508	0.0%	27942	0.147	0.233	0.335
artlog/artcncl	00:00:00.004	0.0%	197	0.000	0.020	0.111
artlog/artparse	00:00:00.012	0.0%	1178	0.000	0.010	0.091
data move	00:00:00.291	0.0%	783818	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.004	0.0%	223	0.000	0.018	1.000
hishave/artcncl	00:00:00.000	0.0%	236	0.000	0.000	0.000
hishave/artparse	00:00:00.002	0.0%	1178	0.000	0.002	0.077
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.338	0.0%	552351	0.000	0.002	0.008
history sync	00:00:00.474	0.0%	355	0.000	1.335	2.500
history write	00:00:12.417	0.0%	53441	0.073	0.232	0.316
hiswrite/artcncl	00:00:00.004	0.0%	13	0.000	0.308	3.000
hiswrite/artparse	00:00:00.270	0.0%	1174	0.000	0.230	3.000
idle	23:47:58.329	99.8%	765143	18.209	111.977	402.645
nntp read	00:00:02.443	0.0%	764991	0.001	0.003	0.006
overview write	00:00:04.710	0.0%	27942	0.089	0.169	0.359
perl filter	00:01:55.220	0.1%	53441	0.996	2.156	5.015
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.522	0.0%	55887	0.000	0.009	0.022
TOTAL: 23:50:59.189	23:50:27.250	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.070	0.0%	28188	0.000	0.002	0.020
article prepare	00:00:00.001	0.0%	8444	0.000	0.000	0.011
article read	00:00:00.321	0.0%	1682	0.000	0.191	6.818
backlog stats	00:00:00.110	0.0%	308579	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.247	0.0%	234873	0.000	0.005	0.011
data write	00:00:01.774	0.0%	209049	0.003	0.008	0.018
idle	23:53:14.546	100.0%	308579	167.199	278.679	705.133
status file	00:00:00.073	0.0%	178	0.000	0.410	1.000
TOTAL: 23:53:24.177	23:53:18.142	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.679	0.0%	112824	0.000	0.024	1.000
idle	356:22:58.453	100.0%	144587	1.000	8873.401	600300.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.625	0.0%	290423	0.000	0.006	0.200
readart	00:00:00.099	0.0%	99	0.000	1.000	46.000
TOTAL: 356:25:11.730	356:23:02.856	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	148
pause	2
paused	2
reload	2
reserve	2
shutdown	3
TOTAL: 11	170

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	26	49651	6533	36597	6521	13%	30:02:54
2	au2pb.net	1	29032	5604	22539	889	19%	23:49:55
3	xanadu-bbs.net	2	54887	4954	41138	8795	9%	24:12:31
4	news-out.mixmin.net	5	31299	4718	24568	2013	15%	24:18:07
5	newsfeed.neva.ru	140	74015	3356	62499	8160	4%	72:59:29
6	newsfeeds.xmission.com	1	27135	2115	24759	261	7%	24:03:34
7	nntp.pasdenom.info	4	26205	443	25697	65	1%	21:52:01
8	news.swapon.de	1	1527	321	1204	2	21%	14:23:30
9	localhost	48	13848	0	13848	0	0%	00:00:56
TOTAL: 9		228	307599	28044	252849	26706	9%	235:42:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	19.1 MB	1.4 KB	71.7 MB	90.8 MB	21%	7.1 KB
2	xanadu-bbs.net	14.9 MB	0.0 KB	219.5 MB	234.4 MB	6%	17.5 KB
3	news-out.mixmin.net	14.6 MB	0.0 KB	6.0 MB	20.6 MB	70%	3.1 KB
4	au2pb.net	13.7 MB	0.0 KB	4.7 MB	18.4 MB	74%	2.9 KB
5	newsfeed.neva.ru	10.0 MB	29.3 KB	63.3 MB	73.3 MB	13%	6.5 KB
6	newsfeeds.xmission.com	6.1 MB	0.0 KB	2.3 MB	8.4 MB	72%	3.6 KB
7	nntp.pasdenom.info	968.0 KB	0.0 KB	510.4 KB	1.4 MB	65%	2.9 KB
8	news.swapon.de	756.4 KB	0.0 KB	57.3 KB	813.7 KB	92%	2.5 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		80.1 MB	30.7 KB	368.0 MB	448.1 MB	17%	8.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 19 04:15:00 - 04:59:59	395	1.4%	0.15	1.2 MB	1.4%	0.44
Aug 19 05:00:00 - 05:59:59	698	2.5%	0.19	2.0 MB	2.5%	0.57
Aug 19 06:00:00 - 06:59:59	929	3.3%	0.26	3.5 MB	4.3%	0.99
Aug 19 07:00:00 - 07:59:59	997	3.5%	0.28	2.8 MB	3.5%	0.80
Aug 19 08:00:00 - 08:59:59	1097	3.9%	0.30	2.9 MB	3.6%	0.83
Aug 19 09:00:00 - 09:59:59	1574	5.6%	0.44	4.9 MB	6.1%	1.39
Aug 19 10:00:00 - 10:59:59	1404	5.0%	0.39	3.7 MB	4.5%	1.04
Aug 19 11:00:00 - 11:59:59	1346	4.8%	0.37	3.7 MB	4.6%	1.06
Aug 19 12:00:00 - 12:59:59	1579	5.6%	0.44	5.9 MB	7.3%	1.68
Aug 19 13:00:00 - 13:59:59	1447	5.1%	0.40	3.8 MB	4.8%	1.09
Aug 19 14:00:00 - 14:59:59	1503	5.3%	0.42	3.9 MB	4.8%	1.10
Aug 19 15:00:00 - 15:59:59	1644	5.8%	0.46	4.5 MB	5.6%	1.28
Aug 19 16:00:00 - 16:59:59	1640	5.8%	0.46	4.3 MB	5.4%	1.23
Aug 19 17:00:00 - 17:59:59	1609	5.7%	0.45	4.4 MB	5.5%	1.26
Aug 19 18:00:00 - 18:59:59	1680	6.0%	0.47	4.4 MB	5.5%	1.26
Aug 19 19:00:00 - 19:59:59	1524	5.4%	0.42	4.0 MB	4.9%	1.13
Aug 19 20:00:00 - 20:59:59	1453	5.2%	0.40	3.8 MB	4.7%	1.07
Aug 19 21:00:00 - 21:59:59	1232	4.4%	0.34	3.4 MB	4.2%	0.97
Aug 19 22:00:00 - 22:59:59	1089	3.9%	0.30	3.5 MB	4.3%	0.98
Aug 19 23:00:00 - 23:59:59	754	2.7%	0.21	2.1 MB	2.6%	0.60
Aug 20 00:00:00 - 00:59:59	761	2.7%	0.21	2.1 MB	2.6%	0.61
Aug 20 01:00:00 - 01:59:59	565	2.0%	0.16	1.7 MB	2.1%	0.48
Aug 20 02:00:00 - 02:59:59	493	1.8%	0.14	1.6 MB	2.0%	0.45
Aug 20 03:00:00 - 03:59:59	558	2.0%	0.15	2.0 MB	2.4%	0.56
Aug 20 04:00:00 - 04:15:00	166	0.6%	0.18	507.9 KB	0.6%	0.56
TOTAL: 24:00:00	28137	100.0%	0.33	80.5 MB	100.0%	0.95

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8799	1050	0	7749
2	newsfeed.neva.ru	8286	1502	1	6783
3	feeder.eternal-september.org	6677	252	1	6424
4	news-out.mixmin.net	2072	11	0	2061
5	au2pb.net	858	803	0	55
6	newsfeeds.xmission.com	270	240	0	30
7	nntp.pasdenom.info	70	66	0	4
8	news.swapon.de	65	0	0	65
TOTAL: 8		27097	3924	2	23171

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	526
mailing.freebsd.ports-bugs	207
lada.auto	185
lada.talk	179
ut.test	178
mailing.freebsd.svn-ports-all	132
mailing.freebsd.svn-ports-head	130
alt.politics	112
mpc.lists.freebsd.ports.bugs	109
mailing.freebsd.pkg-fallout	97
alt.politics.scorched-earth	88
alt.job	83
alt.windows7.general	78
mailing.postfix.users	68
mozilla.dev.tree-alerts	57
mailing.freebsd.ruby	50
it-alt.sport.calcio.juventus	46
mailing.openbsd.source-changes	46
fa.openbsd.source-changes	46
lada.photo	42
TOTAL: 273	3924

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16644
EMP (phn path)	4034
EMP (phl)	720
PFM bot misc	168
HTML Multipart	75
Too many newsgroups	56
EMP (phn nph)	50
Binary Image: misplaced jpg	16
HTML post	10
U2 violation - invalid distribution	3
TOTAL: 10	21776

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	1042
	news-out.mixmin.net	56
	feeder.eternal-september.org	35
	newsfeeds.xmission.com	21
	au2pb.net	21
	nntp.pasdenom.info	3
	news.swapon.de	1
	TOTAL: 7	1179
Including strange strings
	news-out.mixmin.net	62
	news.swapon.de	62
	feeder.eternal-september.org	60
	au2pb.net	12
	xanadu-bbs.net	9
	newsfeeds.xmission.com	8
	newsfeed.neva.ru	2
	nntp.pasdenom.info	1
	TOTAL: 8	216
TOTAL: 2		1395

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	34112	355	26397	931	3	1%	23:59:43
2	au2pb	23768	261	22034	206	1	1%	23:59:43
3	xanadu-bbs	24819	130	23065	28	10	0%	23:59:44
4	fu-berlin.de	20769	44	20703	22	0	0%	18:06:54
5	news.mixmin.net	24076	22	23227	15	1	0%	23:59:43
6	neva.ru	25628	18	24441	60	103	0%	23:59:43
7	xmission	31591	10	25726	1	0	0%	23:59:44
8	eternal-september	22440	8	21245	3	1	0%	23:59:43
9	news.swapon.de	2189	1	2172	2	0	0%	23:59:43
TOTAL: 9		209392	849	189010	1268	119	0%	210:04:40

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1.2 MB	2.5 MB	3.6 MB	2.9 KB	23:59:43
2	au2pb	1015.5 KB	803.8 KB	1.8 MB	3.9 KB	23:59:43
3	xanadu-bbs	685.6 KB	69.7 KB	755.3 KB	4.8 KB	23:59:44
4	fu-berlin.de	311.8 KB	27.1 KB	338.9 KB	5.1 KB	18:06:54
5	news.mixmin.net	99.7 KB	49.0 KB	148.7 KB	4.0 KB	23:59:43
6	neva.ru	64.4 KB	310.6 KB	375.0 KB	4.8 KB	23:59:43
7	eternal-september	45.2 KB	3.9 KB	49.1 KB	4.5 KB	23:59:43
8	xmission	20.8 KB	39.9 KB	60.7 KB	5.5 KB	23:59:44
9	news.swapon.de	1.2 KB	5.5 KB	6.7 KB	2.2 KB	23:59:43
TOTAL: 9		3.3 MB	3.7 MB	7.1 MB	3.4 KB	210:04:40

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	97	302.6 KB	75	04:07:10
2	dhcp-089-099-203-254.chello.nl	21	1	2.3 KB	1	07:50:13
3	50.188.193.178.dynamic.wline.res.cust.swisscom.ch	2	1	1.4 KB	1	01:00:05
TOTAL: 3		311	99	306.3 KB	77	356:25:00

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	678	97	302.6 KB	75	345:30:54
2	*.chello.nl	21	1	2.3 KB	1	07:50:13
3	*.188.193.178.dynamic.wline.res.cust.swisscom.ch	2	1	1.4 KB	1	01:00:05
4	*.tampfl.dsl-w.verizon.net	6	0	0.0 KB	0	01:33:42
5	*.nielsenmedia.com	1	0	0.0 KB	0	00:30:01
6	*.shodan.io	1	0	0.0 KB	0	00:00:01
TOTAL: 6		709	99	306.3 KB	77	356:25:00

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
108.175.241.207	28.772	9.981	0.000	319:47:46
162.212.88.198	0.668	0.496	0.000	21:15:14
dhcp-089-099-203-254.chello.nl	1.742	0.315	0.000	07:50:13
202.89.176.220	27.955	5.642	0.698	04:07:10
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.435	0.056	0.000	01:33:42
50.188.193.178.dynamic.wline.res.cust.swisscom.ch	0.224	0.024	0.000	01:00:05
challenger.nielsenmedia.com	0.075	0.030	0.000	00:30:01
71.94.21.184	0.104	0.017	0.000	00:20:40
69.160.37.54	1.869	0.290	0.000	00:00:01
census9.shodan.io	0.096	0.008	0.000	00:00:01
TOTAL: 10	61.940	16.859	0.698	356:25:00

Curious NNRP server explorers [Top 20]:

System	Conn
108.175.241.207	355
69.160.37.54	25
162.212.88.198	8
pool-71-98-160-84.tampfl.dsl-w.verizon.net	6
71.94.21.184	2
challenger.nielsenmedia.com	1
census9.shodan.io	1
TOTAL: 7	398

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	2
TOTAL: 1	2

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	13	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3	0
50.188.193.178.dynamic.wline.res.cust.swisscom.ch	2	0
challenger.nielsenmedia.com	1	0
TOTAL: 4	19	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.os.vms	62
2	comp.arch	14
3	comp.sys.ibm.as400.misc	5
4	comp.archives.ms-windows.discuss	5
5	comp.os.os2.apps	4
6	comp.sys.ibm.pc.hardware.chips	3
7	comp.sys.ibm.ps2.hardware	2
8	comp.arch.storage	1
9	comp.unix.aix	1
10	comp.sys.ibm.pc.games.action	1
11	comp.answers	1
TOTAL: 11		99

Daily Usenet report for csiph.com

Aug 19 04:15:00 -- Aug 20 04:15:00