Daily Usenet report: Jun 14 04:15:03

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Jun 14 09:19:52 newsfeed overchan[16481]: input line too long, skipping
Jun 14 09:20:06 newsfeed overchan[16481]: input line too long, skipping
Jun 14 15:15:33 newsfeed nnrpd[9099]: SERVER perl filter not defined
Jun 14 15:16:04 newsfeed nnrpd[9110]: SERVER perl filter not defined
Jun 14 15:16:42 newsfeed nnrpd[9112]: SERVER perl filter not defined
Jun 14 15:17:39 newsfeed nnrpd[9117]: SERVER perl filter not defined
Jun 14 15:18:53 newsfeed nnrpd[9151]: SERVER perl filter not defined
Jun 14 20:51:44 newsfeed overchan[16481]: input line too long, skipping
Jun 14 20:51:59 newsfeed overchan[16481]: input line too long, skipping
Jun 14 21:05:17 newsfeed overchan[16481]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2702720	97.6%	308.7 MB	97.3%
innfeed	39879	1.4%	4.7 MB	1.5%
innd	20921	0.8%	3.4 MB	1.1%
nnrpd	4764	0.2%	504.2 KB	0.2%
overchan	6	0.0%	1.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2768291	100.0%	317.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2619096	68.9%
Negative hits	1000124	26.3%
Positive hits	183008	4.8%
Cache misses	455	0.0%
TOTAL: 4	3802683	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.593	0.0%	191	0.000	8.340	66.400
article cleanup	00:00:30.946	0.0%	2699031	0.007	0.011	0.016
article logging	00:01:58.456	0.1%	2699630	0.025	0.044	0.173
article parse	00:39:27.509	2.7%	88797109	0.011	0.027	0.042
article write	00:16:32.631	1.1%	2622299	0.165	0.379	0.727
artlog/artcncl	00:00:00.005	0.0%	191	0.000	0.026	0.333
data move	00:02:11.661	0.2%	104253606	0.001	0.001	0.003
hisgrep/artcncl	00:00:00.233	0.0%	175	0.000	1.331	9.000
hishave/artcncl	00:00:00.054	0.0%	191	0.000	0.283	2.400
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:00:51.679	4.2%	3814172	0.092	0.957	4.454
history sync	00:00:00.012	0.0%	297	0.000	0.040	1.000
history write	01:43:13.538	7.2%	2624905	0.209	2.360	7.605
hiswrite/artcncl	00:00:00.064	0.0%	16	0.000	4.000	22.000
idle	14:57:17.712	62.3%	72010430	0.399	0.748	1.759
nntp read	00:47:32.533	3.3%	87327529	0.018	0.033	0.046
overview write	00:00:12.087	0.0%	2622299	0.003	0.005	0.008
perl filter	04:02:50.778	16.9%	2624905	3.062	5.551	8.189
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:18.882	0.4%	5587722	0.035	0.057	0.081
TOTAL: 24:00:06.867	23:38:00.373	98.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.465	0.0%	682341	0.000	0.002	0.005
article prepare	00:00:00.027	0.0%	133934	0.000	0.000	0.006
article read	00:01:44.963	0.1%	28369	0.250	3.700	118.235
backlog stats	00:00:01.208	0.0%	1953247	0.000	0.001	0.001
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:18.212	0.0%	1221150	0.006	0.015	0.022
data write	00:01:24.626	0.1%	929442	0.025	0.091	0.348
idle	23:46:34.873	99.7%	1953247	22.545	43.822	356.368
status file	00:00:16.597	0.0%	16825	0.408	0.986	13.217
TOTAL: 23:51:06.938	23:50:21.971	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:27.013	69.3%	3024	0.000	8.933	9786.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.226	0.6%	6616	0.000	0.034	0.500
readart	00:00:00.001	0.0%	8	0.000	0.125	0.250
TOTAL: 00:00:38.962	00:00:27.240	69.9%	-	-	-	-

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	1113	2625084	2527348	30787	66949	96%	439:34:22
2	news.nask.pl	3	186469	98224	78631	9614	52%	23:59:09
3	news.icm.edu.pl	45	243	47	194	2	19%	17:37:45
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1167	2811801	2625624	109612	76565	93%	481:11:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	605.0 GB	23.5 GB	23.5 GB	652.1 GB	92%	263.6 KB
2	news.nask.pl	33.2 GB	2.6 GB	2.6 GB	38.4 GB	86%	373.1 KB
3	news.icm.edu.pl	884.2 KB	22.1 KB	33.4 KB	939.6 KB	94%	19.2 KB
4	localhost	7.4 KB	0.0 KB	0.0 KB	7.4 KB	100%	1.5 KB
TOTAL: 4		638.2 GB	26.1 GB	26.2 GB	690.4 GB	92%	267.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 14 04:15:03 - 04:59:59	63733	2.4%	23.63	18.6 GB	2.9%	7244.50
Jun 14 05:00:00 - 05:59:59	121778	4.6%	33.83	32.9 GB	5.2%	9591.25
Jun 14 06:00:00 - 06:59:59	115611	4.4%	32.11	32.9 GB	5.1%	9572.32
Jun 14 07:00:00 - 07:59:59	66109	2.5%	18.36	19.5 GB	3.1%	5682.62
Jun 14 08:00:00 - 08:59:59	84865	3.2%	23.57	26.2 GB	4.1%	7627.24
Jun 14 09:00:00 - 09:59:59	104239	4.0%	28.96	30.1 GB	4.7%	8760.13
Jun 14 10:00:00 - 10:59:59	96704	3.7%	26.86	27.7 GB	4.3%	8054.59
Jun 14 11:00:00 - 11:59:59	84310	3.2%	23.42	24.5 GB	3.8%	7130.58
Jun 14 12:00:00 - 12:59:59	112791	4.3%	31.33	34.0 GB	5.3%	9892.11
Jun 14 13:00:00 - 13:59:59	180952	6.9%	50.26	32.9 GB	5.2%	9594.25
Jun 14 14:00:00 - 14:59:59	172584	6.6%	47.94	30.4 GB	4.8%	8856.06
Jun 14 15:00:00 - 15:59:59	125745	4.8%	34.93	27.7 GB	4.3%	8064.51
Jun 14 16:00:00 - 16:59:59	67255	2.6%	18.68	18.2 GB	2.8%	5287.67
Jun 14 17:00:00 - 17:59:59	75890	2.9%	21.08	21.0 GB	3.3%	6113.68
Jun 14 18:00:00 - 18:59:59	94186	3.6%	26.16	25.3 GB	4.0%	7371.56
Jun 14 19:00:00 - 19:59:59	98276	3.7%	27.30	24.7 GB	3.9%	7183.82
Jun 14 20:00:00 - 20:59:59	109014	4.2%	30.28	28.6 GB	4.5%	8330.57
Jun 14 21:00:00 - 21:59:59	90603	3.5%	25.17	21.9 GB	3.4%	6388.30
Jun 14 22:00:00 - 22:59:59	109232	4.2%	30.34	21.8 GB	3.4%	6362.48
Jun 14 23:00:00 - 23:59:59	147072	5.6%	40.85	26.2 GB	4.1%	7636.76
Jun 15 00:00:00 - 00:59:59	134836	5.1%	37.45	21.2 GB	3.3%	6184.66
Jun 15 01:00:00 - 01:59:59	146309	5.6%	40.64	31.2 GB	4.9%	9075.42
Jun 15 02:00:00 - 02:59:59	106998	4.1%	29.72	29.5 GB	4.6%	8602.63
Jun 15 03:00:00 - 03:59:59	90510	3.4%	25.14	24.1 GB	3.8%	7030.70
Jun 15 04:00:00 - 04:15:03	25770	1.0%	28.54	7.8 GB	1.2%	9044.36
TOTAL: 24:00:00	2625372	100.0%	30.39	638.9 GB	100.0%	7753.74

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	67288	269	66628	3	388
2	news.nask.pl	9863	24	7331	1	2507
3	news.icm.edu.pl	6	0	1	0	5
TOTAL: 3		77157	293	73960	4	2900

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	125
it.tlc.cellulari.android	67
it.comp.os.win.windows10	50
comp.sys.raspberry-pi	28
nl.comp.os.linux.techniek	12
comp.mobile.android	10
de.comp.lang.php	1
TOTAL: 7	293

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

Groups	Count
pl.praca.oferowana	2
hr.alt.hackers	1
bit.listserv.catholic	1
TOTAL: 3	4

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2156
Binary in non-binary group	94
Scoring filter (8)	21
Scoring filter (9)	9
Scoring filter (10)	8
EMP (fsl)	3
Scoring filter (16)	3
HTML post	3
Too many newsgroups	2
Scoring filter (14)	2
Scoring filter (11)	1
TOTAL: 11	2302

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	345
	news.nask.pl	248
	news.icm.edu.pl	5
	TOTAL: 3	598
TOTAL: 1		598

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	293713	12604	222930	16	0	0	4%	23:59:57
2	panorama	364733	5617	330071	568	15	347765	1%	23:59:57
3	poznan	12513	393	7594	20	0	1	3%	23:59:38
4	icm	411886	82	326372	13925	0	2	0%	23:59:57
5	okapi	0	0	0	0	0	1177	0%	23:57:50
TOTAL: 5		1082845	18696	886967	14529	15	348945	1%	119:57:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	4.6 GB	5.8 MB	4.6 GB	55.6 KB/s	380.7 KB	23:59:57
2	panorama	1.5 GB	66.6 MB	1.6 GB	19.2 KB/s	268.6 KB	23:59:57
3	icm	8.5 MB	5.0 GB	5.0 GB	61.1 KB/s	376.9 KB	23:59:57
4	poznan	1.9 MB	80.1 KB	2.0 MB	0.0 KB/s	5.0 KB	23:59:38
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		6.1 GB	5.1 GB	11.2 GB	27.2 KB/s	353.6 KB	119:57:19

Backlog files shrunk by innfeed:

Server	Size
panorama	976.7 KB
TOTAL: 1	976.7 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	8	8.9 KB	3	0	00:00:20
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 2		293	8	8.9 KB	3	5	00:00:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	677	8	8.9 KB	3	5	00:00:26
2	*.binaryedge.ninja	1	0	0.0 KB	0	0	00:00:01
3	unresolved	2	0	0.0 KB	0	0	00:00:10
TOTAL: 3		680	8	8.9 KB	3	5	00:00:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.496	4.196	00:00:20
198.199.94.163	0.004	0.004	00:00:09
mon.man.lodz.pl	1.228	3.420	00:00:03
min-05-14-22071-uk-d-prod.binaryedge.ninja	0.000	0.020	00:00:01
david.man.lodz.pl	0.376	1.176	00:00:01
isvcs01.man.lodz.pl	0.016	0.096	00:00:00
71.6.146.186	0.008	0.000	00:00:00
TOTAL: 7	3.128	8.912	00:00:38

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
198.199.94.163	1
71.6.146.186	1
min-05-14-22071-uk-d-prod.binaryedge.ninja	1
TOTAL: 5	387

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	8
TOTAL: 1		8

Daily Usenet report

Jun 14 04:15:03 -- Jun 15 04:15:03