Daily Usenet report: Feb 2 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Feb 2 15:41:57 newsfeed overchan[12875]: input line too long, skipping
Feb 2 15:48:55 newsfeed overchan[12875]: input line too long, skipping
Feb 2 15:51:02 newsfeed overchan[12875]: input line too long, skipping
Feb 2 23:00:03 newsfeed pgpverify[18772]: pgpverify: verification failed
Feb 3 02:25:26 newsfeed overchan[12875]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2140915	97.1%	248.2 MB	96.8%
innfeed	44118	2.0%	5.2 MB	2.0%
innd	15490	0.7%	2.5 MB	1.0%
nnrpd	5006	0.2%	539.3 KB	0.2%
overchan	5	0.0%	0.9 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	2205539	100.0%	256.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2129857	98.7%
Negative hits	17633	0.8%
Positive hits	9347	0.4%
Cache misses	44	0.0%
TOTAL: 4	2156881	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.458	0.0%	392	0.000	11.372	143.000
article cleanup	00:00:28.379	0.0%	2138393	0.010	0.013	0.018
article logging	00:01:33.504	0.1%	2138800	0.036	0.044	0.058
article parse	00:33:03.917	2.3%	75234771	0.016	0.026	0.037
article write	00:13:33.356	0.9%	2132910	0.239	0.381	0.645
artlog/artcncl	00:00:00.017	0.0%	391	0.000	0.043	1.000
data move	00:01:17.894	0.1%	86634652	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.237	0.0%	106	0.000	2.236	22.000
hishave/artcncl	00:00:01.672	0.0%	392	0.000	4.265	5.582
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:18:55.434	1.3%	2161284	0.013	0.525	6.032
history sync	00:00:00.016	0.0%	290	0.000	0.055	1.000
history write	00:41:38.466	2.9%	2134133	0.098	1.171	6.544
hiswrite/artcncl	00:00:01.699	0.0%	286	0.000	5.941	7.750
idle	17:17:45.602	72.1%	61260819	0.627	1.016	2.998
nntp read	00:55:09.172	3.8%	73427678	0.033	0.045	0.058
overview write	00:00:11.578	0.0%	2132910	0.003	0.005	0.008
perl filter	03:25:01.337	14.2%	2134133	4.282	5.764	7.477
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:47.757	0.3%	4280523	0.052	0.067	0.089
TOTAL: 24:00:00.937	23:33:34.495	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.121	0.0%	50158	0.000	0.002	0.019
article prepare	00:00:00.002	0.0%	7274	0.000	0.000	0.013
article read	00:00:13.970	0.0%	1803	0.000	7.748	194.000
backlog stats	00:00:00.112	0.0%	132827	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.709	0.0%	69293	0.004	0.025	0.574
data write	00:00:04.103	0.0%	55022	0.016	0.075	0.218
idle	23:52:34.841	99.9%	132827	363.759	647.119	1150.209
status file	00:00:17.051	0.0%	16399	0.625	1.040	1.533
TOTAL: 23:53:22.572	23:53:11.909	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:21.874	0.0%	54170	0.000	0.404	3.858
idle	22:29:54.546	99.9%	57244	0.000	1414.900	132440.357
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.363	0.0%	115176	0.000	0.038	1.421
readart	00:00:00.956	0.0%	45	0.000	21.244	573.000
TOTAL: 22:30:44.739	22:30:21.739	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	734	2134711	2132657	130	1924	99%	208:27:24
2	news.nask.pl	2	10114	1983	4634	3497	19%	23:34:45
3	news.icm.edu.pl	45	333	60	236	37	18%	17:40:40
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		782	2145158	2134700	5000	5458	99%	249:42:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	504.2 GB	13.3 MB	13.8 MB	504.2 GB	99%	247.7 KB
2	news.nask.pl	12.8 MB	11.2 MB	18.6 MB	42.5 MB	30%	7.9 KB
3	news.icm.edu.pl	1.7 MB	0.0 KB	2.1 MB	3.9 MB	44%	40.7 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		504.2 GB	24.5 MB	34.5 MB	504.3 GB	99%	247.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 2 04:15:02 - 04:59:59	31769	1.5%	11.78	8.4 GB	1.7%	3280.63
Feb 2 05:00:00 - 05:59:59	70212	3.3%	19.50	19.3 GB	3.8%	5625.76
Feb 2 06:00:00 - 06:59:59	86648	4.1%	24.07	23.4 GB	4.6%	6801.89
Feb 2 07:00:00 - 07:59:59	88475	4.1%	24.58	23.9 GB	4.7%	6971.17
Feb 2 08:00:00 - 08:59:59	59334	2.8%	16.48	14.4 GB	2.9%	4202.24
Feb 2 09:00:00 - 09:59:59	78419	3.7%	21.78	21.1 GB	4.2%	6135.86
Feb 2 10:00:00 - 10:59:59	90675	4.2%	25.19	23.2 GB	4.6%	6752.40
Feb 2 11:00:00 - 11:59:59	91755	4.3%	25.49	23.7 GB	4.7%	6889.45
Feb 2 12:00:00 - 12:59:59	88025	4.1%	24.45	22.6 GB	4.5%	6594.33
Feb 2 13:00:00 - 13:59:59	87489	4.1%	24.30	20.4 GB	4.0%	5932.87
Feb 2 14:00:00 - 14:59:59	92661	4.3%	25.74	15.2 GB	3.0%	4421.80
Feb 2 15:00:00 - 15:59:59	106359	5.0%	29.54	22.5 GB	4.5%	6565.91
Feb 2 16:00:00 - 16:59:59	86730	4.1%	24.09	23.3 GB	4.6%	6788.45
Feb 2 17:00:00 - 17:59:59	106702	5.0%	29.64	22.9 GB	4.5%	6675.74
Feb 2 18:00:00 - 18:59:59	107200	5.0%	29.78	23.7 GB	4.7%	6888.69
Feb 2 19:00:00 - 19:59:59	114483	5.4%	31.80	22.3 GB	4.4%	6506.94
Feb 2 20:00:00 - 20:59:59	111141	5.2%	30.87	23.8 GB	4.7%	6925.21
Feb 2 21:00:00 - 21:59:59	109426	5.1%	30.40	24.3 GB	4.8%	7078.92
Feb 2 22:00:00 - 22:59:59	100171	4.7%	27.83	23.2 GB	4.6%	6743.46
Feb 2 23:00:00 - 23:59:59	102810	4.8%	28.56	21.7 GB	4.3%	6332.03
Feb 3 00:00:00 - 00:59:59	58110	2.7%	16.14	13.6 GB	2.7%	3952.27
Feb 3 01:00:00 - 01:59:59	101891	4.8%	28.30	23.4 GB	4.6%	6817.78
Feb 3 02:00:00 - 02:59:59	61495	2.9%	17.08	16.3 GB	3.2%	4748.09
Feb 3 03:00:00 - 03:59:59	78308	3.7%	21.75	21.1 GB	4.2%	6135.74
Feb 3 04:00:00 - 04:15:02	24355	1.1%	27.00	6.6 GB	1.3%	7673.63
TOTAL: 24:00:00	2134643	100.0%	24.71	504.2 GB	100.0%	6119.51

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3549	9	2481	1	1058
2	newsfeed.pionier.net.pl	2296	148	1769	4	375
3	news.icm.edu.pl	36	0	0	0	36
TOTAL: 3		5881	157	4250	5	1469

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	79
comp.sys.raspberry-pi	23
it.comp.os.win.windows10	20
comp.mobile.android	16
it.tlc.cellulari.android	8
it.comp.os.win.windows7	4
it.comp.dotnet	1
nl.actueel	1
nl.wetenschap.regulier	1
nl.comp.3d-printen	1
nl.comp.sys.android	1
nl.comp.os.linux.techniek	1
nl.gezondheid.alternatief-genezen	1
TOTAL: 13	157

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

Groups	Count
pl.praca.oferowana	4
bit.listserv.catholic	1
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	898
EMP (phl)	63
Binary in non-binary group	43
Scoring filter (8)	24
Scoring filter (9)	20
Too many newsgroups	5
Scoring filter (11)	4
Scoring filter (10)	2
Scoring filter (16)	1
Scoring filter (12)	1
TOTAL: 10	1061

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	375
	news.nask.pl	33
	TOTAL: 2	408
TOTAL: 1		408

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	40519	536	12389	247	7	37207	1%	23:59:58
2	icm	20523	81	14057	333	0	0	0%	23:59:46
3	poznan	3067	22	2019	1	0	0	0%	23:55:25
4	nask	8050	0	6987	0	0	0	0%	23:59:58
5	okapi	0	0	0	0	0	995	0%	23:57:51
TOTAL: 5		72159	639	35452	581	7	38202	0%	119:52:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	1.8 MB	949.7 KB	2.8 MB	0.0 KB/s	3.6 KB	23:59:58
2	icm	244.1 KB	108.2 MB	108.4 MB	1.3 KB/s	268.2 KB	23:59:46
3	poznan	98.6 KB	11.4 KB	110.0 KB	0.0 KB/s	4.8 KB	23:55:25
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		2.2 MB	109.1 MB	111.3 MB	0.3 KB/s	93.4 KB	119:52:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	web.man.lodz.pl	288	32	151.9 KB	14	00:00:20
2	az1-ss13.a2hosting.com	4	2	1.5 KB	2	00:00:25
TOTAL: 2		292	34	153.4 KB	16	22:30:43

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	576	32	151.9 KB	14	00:00:23
2	*.a2hosting.com	4	2	1.5 KB	2	00:00:25
3	unresolved	99	0	0.0 KB	0	00:00:02
4	*.consolidated.net	31	0	0.0 KB	0	22:29:52
TOTAL: 4		710	34	153.4 KB	16	22:30:43

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
client-66-116-21-26.consolidated.net	4.452	9.592	22:29:52
az1-ss13.a2hosting.com	0.072	0.076	00:00:25
web.man.lodz.pl	1.784	4.752	00:00:20
mon.man.lodz.pl	1.164	4.396	00:00:03
185.156.177.123	0.008	0.052	00:00:01
212.51.192.185	0.424	1.340	00:00:01
TOTAL: 6	7.904	20.208	22:30:43

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
client-66-116-21-26.consolidated.net	31
185.156.177.123	3
TOTAL: 4	418

NNRP unrecognized commands (by host):

System	Conn
185.156.177.123	3
TOTAL: 1	3

NNRP unrecognized commands (by command):

Command	Count
#003	3
TOTAL: 1	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
client-66-116-21-26.consolidated.net	11	0
185.156.177.123	1	1
TOTAL: 2	12	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	32	94.1%
2	lodzman	2	5.9%
TOTAL: 2		34	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	22
2	pl.comp.os.ms-windows.winnt	10
3	lodzman.test	2
TOTAL: 3		34

Daily Usenet report

Feb 2 04:15:02 -- Feb 3 04:15:02