Daily Usenet report: Dec 12 04:15:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Dec 12 10:46:10 newsfeed overchan[472195]: input line too long, skipping
Dec 12 14:09:59 newsfeed nnrpd[499934]: SERVER perl filter not defined
Dec 12 17:09:03 newsfeed overchan[472195]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	891076	96.1%	97.4 MB	95.5%
innfeed	21941	2.4%	2.6 MB	2.6%
innd	11043	1.2%	1.7 MB	1.6%
nnrpd	3294	0.4%	311.5 KB	0.3%
overchan	3	0.0%	0.6 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	927358	100.0%	102.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	886398	99.7%
Negative hits	2542	0.3%
Positive hits	193	0.0%
Cache misses	41	0.0%
TOTAL: 4	889174	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.222	0.0%	70	0.000	31.743	201.500
article cleanup	00:00:05.730	0.0%	890917	0.005	0.006	0.009
article logging	00:00:19.080	0.0%	891208	0.015	0.021	0.029
article parse	00:09:06.846	0.6%	152637735	0.003	0.004	0.006
article write	00:06:14.138	0.4%	890821	0.204	0.420	0.656
artlog/artcncl	00:00:00.000	0.0%	69	0.000	0.000	0.000
data move	00:00:37.649	0.0%	159386589	0.000	0.000	0.000
hisgrep/artcncl	00:00:00.276	0.0%	68	0.000	4.059	36.000
hishave/artcncl	00:00:00.008	0.0%	70	0.000	0.114	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:05:10.051	0.4%	893677	0.143	0.347	3.654
history sync	00:00:00.011	0.0%	290	0.000	0.038	0.500
history write	00:14:59.890	1.0%	890912	0.226	1.010	7.693
hiswrite/artcncl	00:00:00.001	0.0%	2	0.000	0.500	1.000
idle	21:50:32.268	91.0%	150816279	0.399	0.521	0.849
nntp read	00:29:44.665	2.1%	152087471	0.010	0.012	0.015
overview write	00:00:02.447	0.0%	890821	0.001	0.003	0.005
perl filter	00:43:13.120	3.0%	890912	2.181	2.911	3.562
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:22.403	0.0%	1791196	0.010	0.013	0.017
TOTAL: 24:00:00.662	23:40:30.805	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.047	0.0%	28276	0.000	0.002	0.014
article prepare	00:00:00.000	0.0%	5088	0.000	0.000	0.000
article read	00:00:12.239	0.0%	1302	0.000	9.400	38.333
backlog stats	00:00:00.042	0.0%	80698	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	34	0.000	0.000	0.000
data read	00:00:00.368	0.0%	37878	0.000	0.010	0.028
data write	00:00:00.804	0.0%	28952	0.000	0.028	0.070
idle	23:55:54.659	100.0%	80698	649.899	1067.618	2299.840
status file	00:00:04.265	0.0%	7108	0.324	0.600	14.805
TOTAL: 23:56:14.625	23:56:12.424	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:17.487	75.9%	2723	0.000	6.422	10015.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.080	0.3%	5740	0.000	0.014	0.500
readart	00:00:00.000	0.0%	2	0.000	0.000	0.000
TOTAL: 00:00:23.054	00:00:17.567	76.2%	-	-	-	-

Control commands to INND:

Command	Number
flush	27
flushlogs	2
go	2
logmode	2
lowmark	1
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	358	890396	890311	0	85	99%	109:46:13
2	news.icm.edu.pl	45	308	135	162	11	43%	15:01:15
3	localhost	1442	1	1	0	0	100%	00:00:00
TOTAL: 3		1845	890705	890447	162	96	99%	124:47:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	370.9 GB	345.5 KB	1.3 MB	370.9 GB	99%	436.8 KB
2	news.icm.edu.pl	3.8 MB	0.0 KB	127.0 KB	3.9 MB	96%	27.3 KB
3	localhost	1.5 KB	0.0 KB	0.0 KB	1.5 KB	100%	1.5 KB
TOTAL: 3		370.9 GB	345.5 KB	1.5 MB	370.9 GB	99%	436.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 12 04:15:02 - 04:59:59	19866	2.2%	7.36	10.9 GB	2.9%	4239.64
Dec 12 05:00:00 - 05:59:59	28616	3.2%	7.95	15.3 GB	4.1%	4458.34
Dec 12 06:00:00 - 06:59:59	30381	3.4%	8.44	16.2 GB	4.4%	4715.76
Dec 12 07:00:00 - 07:59:59	30015	3.4%	8.34	16.5 GB	4.5%	4815.51
Dec 12 08:00:00 - 08:59:59	28857	3.2%	8.02	14.4 GB	3.9%	4187.99
Dec 12 09:00:00 - 09:59:59	29432	3.3%	8.18	15.0 GB	4.0%	4370.37
Dec 12 10:00:00 - 10:59:59	40585	4.6%	11.27	15.8 GB	4.3%	4608.47
Dec 12 11:00:00 - 11:59:59	52233	5.9%	14.51	16.7 GB	4.5%	4870.68
Dec 12 12:00:00 - 12:59:59	36118	4.1%	10.03	17.6 GB	4.7%	5126.40
Dec 12 13:00:00 - 13:59:59	34267	3.8%	9.52	17.8 GB	4.8%	5177.40
Dec 12 14:00:00 - 14:59:59	32723	3.7%	9.09	16.0 GB	4.3%	4661.02
Dec 12 15:00:00 - 15:59:59	35439	4.0%	9.84	16.7 GB	4.5%	4875.62
Dec 12 16:00:00 - 16:59:59	29166	3.3%	8.10	13.3 GB	3.6%	3880.91
Dec 12 17:00:00 - 17:59:59	46471	5.2%	12.91	12.9 GB	3.5%	3768.59
Dec 12 18:00:00 - 18:59:59	59024	6.6%	16.40	13.5 GB	3.6%	3934.99
Dec 12 19:00:00 - 19:59:59	38985	4.4%	10.83	14.4 GB	3.9%	4188.86
Dec 12 20:00:00 - 20:59:59	38621	4.3%	10.73	14.6 GB	3.9%	4240.64
Dec 12 21:00:00 - 21:59:59	36252	4.1%	10.07	14.5 GB	3.9%	4236.83
Dec 12 22:00:00 - 22:59:59	41444	4.7%	11.51	13.9 GB	3.7%	4037.91
Dec 12 23:00:00 - 23:59:59	53421	6.0%	14.84	14.9 GB	4.0%	4337.70
Dec 13 00:00:00 - 00:59:59	43096	4.8%	11.97	17.2 GB	4.6%	5018.45
Dec 13 01:00:00 - 01:59:59	38639	4.3%	10.73	19.0 GB	5.1%	5526.85
Dec 13 02:00:00 - 02:59:59	33987	3.8%	9.44	17.4 GB	4.7%	5064.37
Dec 13 03:00:00 - 03:59:59	26193	2.9%	7.28	12.9 GB	3.5%	3761.59
Dec 13 04:00:00 - 04:15:02	6790	0.8%	7.53	3.8 GB	1.0%	4457.64
TOTAL: 24:00:00	890621	100.0%	10.31	371.3 GB	100.0%	4506.65

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	376	43	5	4	324
2	news.icm.edu.pl	11	0	0	0	11
TOTAL: 2		387	43	5	4	335

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	24
comp.sys.raspberry-pi	6
alt.comp.software.firefox	3
comp.mobile.android	3
alt.comp.software.seamonkey	3
it.comp.os.win.windows10	2
it.comp.os.linux.ubuntu	1
nl.actueel	1
TOTAL: 8	43

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

Groups	Count
pl.praca.oferowana	4
TOTAL: 1	4

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	33
Binary in non-binary group	7
Too many newsgroups	3
Scoring filter (10)	1
TOTAL: 4	44

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	291
	TOTAL: 1	291
TOTAL: 1		291

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	17195	690	8451	37	0	0	4%	23:59:56
2	panorama	18984	115	7042	151	62	20970	0%	23:59:55
3	nask	0	0	0	0	0	6376	0%	23:57:47
4	okapi	0	0	0	0	0	932	0%	23:57:47
5	poznan	160	0	129	5	0	0	0%	02:02:42
TOTAL: 5		36339	805	15622	193	62	28278	2%	97:58:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	4.8 MB	158.7 KB	5.0 MB	0.1 KB/s	7.0 KB	23:59:56
2	panorama	302.7 KB	1.8 MB	2.1 MB	0.0 KB/s	8.1 KB	23:59:55
3	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:47
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:47
5	poznan	0.0 KB	136.6 KB	136.6 KB	0.0 KB/s	27.3 KB	02:02:42
TOTAL: 5		5.1 MB	2.1 MB	7.2 MB	0.0 KB/s	7.4 KB	97:58:07

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	2	1.6 KB	1	0	00:00:09
2	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 2		289	2	1.6 KB	1	1	00:00:23

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	289	2	1.6 KB	1	1	00:00:09
2	unresolved	101	0	0.0 KB	0	0	00:00:13
TOTAL: 2		390	2	1.6 KB	1	1	00:00:23

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
104.152.52.190	0.000	0.008	00:00:10
web.man.lodz.pl	0.820	1.897	00:00:09
80.111.142.213	0.020	0.020	00:00:02
212.51.192.185	0.236	0.574	00:00:01
isvcs01.man.lodz.pl	0.000	0.014	00:00:00
TOTAL: 5	1.076	2.513	00:00:23

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
104.152.52.190	1
TOTAL: 3	101

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	2	100.0%
TOTAL: 1		2	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	2
TOTAL: 1		2

Daily Usenet report

Dec 12 04:15:02 -- Dec 13 04:15:02