Daily Usenet report: Feb 11 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Feb 11 16:04:42 newsfeed overchan[8126]: input line too long, skipping
Feb 11 16:06:51 newsfeed overchan[8126]: input line too long, skipping
Feb 11 16:49:00 newsfeed overchan[8126]: input line too long, skipping
Feb 11 20:25:57 newsfeed overchan[8126]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2094687	97.8%	241.1 MB	97.5%
innfeed	27668	1.3%	3.3 MB	1.3%
innd	14293	0.7%	2.4 MB	1.0%
nnrpd	5062	0.2%	547.7 KB	0.2%
overchan	5	0.0%	0.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2141716	100.0%	247.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2073807	98.7%
Negative hits	16510	0.8%
Positive hits	11094	0.5%
Cache misses	48	0.0%
TOTAL: 4	2101459	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.040	0.0%	117	0.000	8.889	54.500
article cleanup	00:00:27.680	0.0%	2098393	0.007	0.013	0.019
article logging	00:01:28.777	0.1%	2098980	0.025	0.042	0.074
article parse	00:33:49.823	2.3%	60680401	0.020	0.033	0.041
article write	00:14:21.635	1.0%	2092950	0.172	0.412	0.893
artlog/artcncl	00:00:00.004	0.0%	117	0.000	0.034	0.333
data move	00:01:17.008	0.1%	71476218	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.167	0.0%	107	0.000	1.561	18.250
hishave/artcncl	00:00:00.113	0.0%	117	0.000	0.966	12.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:22:36.122	1.6%	2122753	0.018	0.639	4.965
history sync	00:00:00.017	0.0%	290	0.000	0.059	0.500
history write	00:18:14.463	1.3%	2094889	0.097	0.522	1.898
hiswrite/artcncl	00:00:00.001	0.0%	10	0.000	0.100	1.000
idle	17:51:19.322	74.4%	46473425	1.009	1.383	2.830
nntp read	00:51:09.380	3.6%	58922554	0.032	0.052	0.069
overview write	00:00:11.443	0.0%	2092950	0.003	0.005	0.007
perl filter	03:21:10.150	14.0%	2094889	3.446	5.762	7.253
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:31.378	0.3%	4204921	0.032	0.065	0.089
TOTAL: 24:00:01.108	23:40:38.523	98.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.125	0.0%	44305	0.000	0.003	0.012
article prepare	00:00:00.006	0.0%	11408	0.000	0.001	0.021
article read	00:00:11.979	0.0%	2852	0.286	4.200	43.150
backlog stats	00:00:00.132	0.0%	148963	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	4	0.000	0.000	0.000
data read	00:00:01.642	0.0%	76155	0.002	0.022	0.051
data write	00:00:10.430	0.0%	65112	0.071	0.160	0.398
idle	23:54:50.394	100.0%	148963	307.573	577.931	1071.529
status file	00:00:10.040	0.0%	9047	0.658	1.110	1.695
TOTAL: 23:55:33.183	23:55:24.748	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:12.490	0.0%	143589	0.000	0.087	0.643
idle	20:32:40.255	99.9%	146674	0.000	504.249	901139.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:11.190	0.0%	294065	0.000	0.038	1.333
readart	00:00:00.183	0.0%	59	0.000	3.102	86.000
TOTAL: 20:33:31.025	20:33:04.118	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	193

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	298	2088484	2087284	14	1186	99%	148:14:59
2	news.nask.pl	2	12726	1183	7320	4223	9%	23:54:46
3	news.icm.edu.pl	38	367	66	268	33	17%	21:12:19
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		339	2101577	2088533	7602	5442	99%	193:22:04

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	506.2 GB	12.3 MB	12.6 MB	506.3 GB	99%	254.2 KB
2	news.nask.pl	10.6 MB	11.4 MB	22.2 MB	44.2 MB	24%	8.4 KB
3	news.icm.edu.pl	1.7 MB	49.8 KB	809.9 KB	2.6 MB	67%	26.6 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		506.2 GB	23.7 MB	35.5 MB	506.3 GB	99%	253.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 11 04:15:02 - 04:59:59	53087	2.5%	19.68	11.1 GB	2.2%	4329.77
Feb 11 05:00:00 - 05:59:59	60632	2.9%	16.84	14.1 GB	2.8%	4110.51
Feb 11 06:00:00 - 06:59:59	116083	5.6%	32.25	23.9 GB	4.7%	6946.91
Feb 11 07:00:00 - 07:59:59	80331	3.8%	22.31	18.9 GB	3.7%	5500.76
Feb 11 08:00:00 - 08:59:59	73675	3.5%	20.47	21.8 GB	4.3%	6361.43
Feb 11 09:00:00 - 09:59:59	82528	4.0%	22.92	20.9 GB	4.1%	6087.12
Feb 11 10:00:00 - 10:59:59	87060	4.2%	24.18	20.7 GB	4.1%	6015.79
Feb 11 11:00:00 - 11:59:59	77475	3.7%	21.52	20.8 GB	4.1%	6052.95
Feb 11 12:00:00 - 12:59:59	60076	2.9%	16.69	15.7 GB	3.1%	4564.88
Feb 11 13:00:00 - 13:59:59	55355	2.7%	15.38	14.7 GB	2.9%	4283.58
Feb 11 14:00:00 - 14:59:59	88432	4.2%	24.56	24.4 GB	4.8%	7110.59
Feb 11 15:00:00 - 15:59:59	108198	5.2%	30.05	26.4 GB	5.2%	7680.99
Feb 11 16:00:00 - 16:59:59	106233	5.1%	29.51	27.2 GB	5.4%	7934.75
Feb 11 17:00:00 - 17:59:59	89294	4.3%	24.80	24.4 GB	4.8%	7120.96
Feb 11 18:00:00 - 18:59:59	87995	4.2%	24.44	22.4 GB	4.4%	6524.07
Feb 11 19:00:00 - 19:59:59	76757	3.7%	21.32	22.2 GB	4.4%	6459.79
Feb 11 20:00:00 - 20:59:59	81158	3.9%	22.54	21.5 GB	4.3%	6262.57
Feb 11 21:00:00 - 21:59:59	122832	5.9%	34.12	20.5 GB	4.0%	5962.86
Feb 11 22:00:00 - 22:59:59	114716	5.5%	31.87	25.0 GB	4.9%	7273.72
Feb 11 23:00:00 - 23:59:59	99400	4.8%	27.61	23.9 GB	4.7%	6956.28
Feb 12 00:00:00 - 00:59:59	65234	3.1%	18.12	15.3 GB	3.0%	4466.23
Feb 12 01:00:00 - 01:59:59	98008	4.7%	27.22	23.0 GB	4.6%	6704.05
Feb 12 02:00:00 - 02:59:59	98621	4.7%	27.39	22.6 GB	4.5%	6573.57
Feb 12 03:00:00 - 03:59:59	87258	4.2%	24.24	18.8 GB	3.7%	5467.08
Feb 12 04:00:00 - 04:15:02	18096	0.9%	20.06	5.5 GB	1.1%	6347.85
TOTAL: 24:00:00	2088534	100.0%	24.17	505.6 GB	100.0%	6135.70

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	4241	5	2449	1	1786
2	newsfeed.pionier.net.pl	1761	126	1054	2	579
3	news.icm.edu.pl	34	0	4	0	30
TOTAL: 3		6036	131	3507	3	2395

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	41
it.comp.os.win.windows10	40
comp.mobile.android	15
it.tlc.cellulari.android	9
comp.sys.raspberry-pi	6
uk.rec.cycling.moderated	6
nl.comp.os.linux.techniek	3
it.comp.os.win.windows7	3
it.discussioni.energia	3
de.rec.sf.startrek	3
de.comp.lang.php	1
de.comm.software.mailreader	1
TOTAL: 12	131

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
pl.sci.historia	1
bit.listserv.catholic	1
pl.praca.oferowana	1
TOTAL: 3	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1650
Binary in non-binary group	118
Scoring filter (8)	20
Scoring filter (9)	9
Scoring filter (10)	5
Scoring filter (11)	3
Too many newsgroups	2
TOTAL: 7	1807

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	575
	news.nask.pl	9
	news.icm.edu.pl	4
	TOTAL: 3	588
TOTAL: 1		588

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	27541	1879	16440	484	0	0	6%	23:59:57
2	panorama	25904	198	10722	55	4	33361	0%	23:59:35
3	poznan	1914	15	1238	0	0	0	0%	23:48:53
4	nask	11553	7	9680	0	0	0	0%	23:59:56
5	okapi	0	0	0	0	0	1153	0%	23:57:51
TOTAL: 5		66912	2099	38080	539	4	34514	3%	119:46:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.2 GB	179.7 MB	1.4 GB	17.1 KB/s	624.7 KB	23:59:57
2	panorama	590.0 KB	190.6 KB	780.6 KB	0.0 KB/s	3.1 KB	23:59:35
3	poznan	41.2 KB	0.0 KB	41.2 KB	0.0 KB/s	2.7 KB	23:48:53
4	nask	16.2 KB	0.0 KB	16.2 KB	0.0 KB/s	2.3 KB	23:59:56
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		1.2 GB	179.9 MB	1.4 GB	3.4 KB/s	559.9 KB	119:46:12

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	web.man.lodz.pl	288	54	48.2 KB	24	00:00:20
2	az1-ss13.a2hosting.com	2	1	0.6 KB	1	00:00:12
TOTAL: 2		290	55	48.8 KB	25	20:33:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	576	54	48.2 KB	24	00:00:24
2	*.a2hosting.com	2	1	0.6 KB	1	00:00:12
3	*.consolidated.net	41	0	0.0 KB	0	20:32:51
4	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	00:00:00
5	unresolved	96	0	0.0 KB	0	00:00:01
6	*.security.ipip.net	1	0	0.0 KB	0	00:00:00
TOTAL: 6		717	55	48.8 KB	25	20:33:30

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
client-66-116-2-88.consolidated.net	10.968	24.756	20:32:51
web.man.lodz.pl	1.464	3.512	00:00:20
az1-ss13.a2hosting.com	0.040	0.024	00:00:12
mon.man.lodz.pl	1.236	3.476	00:00:03
212.51.192.185	0.448	1.064	00:00:01
scan-141.security.ipip.net	0.004	0.016	00:00:00
TOTAL: 6	14.160	32.848	20:33:30

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
client-66-116-2-88.consolidated.net	41
bds35.neoplus.adsl.tpnet.pl	1
scan-141.security.ipip.net	1
TOTAL: 5	427

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
User-Agent: HTTP Banner Detection (https...	1
Host: 212.191.2.21:119	1
Connection: close	1
GET / HTTP/1.1	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
client-66-116-2-88.consolidated.net	15	0
scan-141.security.ipip.net	1	1
TOTAL: 2	16	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	54	98.2%
2	lodman	1	1.8%
TOTAL: 2		55	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	40
2	pl.comp.pecet	14
3	lodman.info	1
TOTAL: 3		55

Daily Usenet report

Feb 11 04:15:02 -- Feb 12 04:15:02