Daily Usenet report: May 6 04:15:02

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

May 6 05:38:00 newsfeed overchan[24465]: input line too long, skipping
May 6 22:24:07 newsfeed overchan[24465]: input line too long, skipping
May 6 22:28:10 newsfeed overchan[24465]: input line too long, skipping
May 6 22:31:22 newsfeed overchan[24465]: input line too long, skipping
May 6 22:38:45 newsfeed overchan[24465]: input line too long, skipping
May 7 02:04:52 newsfeed nnrpd[11018]: SERVER perl filter not defined
May 7 02:28:53 newsfeed overchan[24465]: input line too long, skipping
May 7 02:30:29 newsfeed overchan[24465]: input line too long, skipping
May 7 02:30:47 newsfeed overchan[24465]: input line too long, skipping
May 7 02:32:10 newsfeed overchan[24465]: input line too long, skipping
May 7 02:32:54 newsfeed overchan[24465]: input line too long, skipping
May 7 02:39:59 newsfeed overchan[24465]: input line too long, skipping
May 7 02:40:32 newsfeed overchan[24465]: input line too long, skipping
May 7 02:47:11 newsfeed nnrpd[12545]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1996297	96.6%	236.3 MB	96.4%
innfeed	45664	2.2%	5.4 MB	2.2%
innd	19356	0.9%	3.0 MB	1.2%
nnrpd	4743	0.2%	498.0 KB	0.2%
overchan	13	0.0%	2.0 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2066074	100.0%	245.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1973706	97.3%
Negative hits	44159	2.2%
Positive hits	10537	0.5%
Cache misses	130	0.0%
TOTAL: 4	2028532	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.060	0.0%	185	0.000	21.946	748.000
article cleanup	00:00:27.276	0.0%	1996472	0.009	0.014	0.019
article logging	00:01:23.051	0.1%	1996898	0.030	0.042	0.215
article parse	00:46:52.125	3.3%	146684401	0.013	0.019	0.050
article write	00:23:53.444	1.7%	1986714	0.379	0.722	4.087
artlog/artcncl	00:00:00.001	0.0%	177	0.000	0.006	0.200
data move	00:03:03.666	0.2%	159297813	0.001	0.001	0.002
hisgrep/artcncl	00:00:01.606	0.0%	172	0.000	9.337	449.000
hishave/artcncl	00:00:00.146	0.0%	185	0.000	0.789	7.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:52:36.336	3.7%	2049452	0.197	1.540	7.541
history sync	00:00:00.019	0.0%	290	0.000	0.066	0.500
history write	02:03:12.750	8.6%	1991945	0.496	3.711	12.632
hiswrite/artcncl	00:00:00.095	0.0%	13	5.625	7.308	10.000
idle	14:16:18.750	59.5%	116209428	0.137	0.442	0.600
nntp read	01:05:56.100	4.6%	145145150	0.018	0.027	0.040
overview write	00:00:10.732	0.0%	1986714	0.004	0.005	0.007
perl filter	03:41:40.321	15.4%	1991945	5.919	6.677	8.168
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:18.423	0.2%	4007459	0.040	0.050	0.067
TOTAL: 24:00:02.890	23:18:58.901	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.226	0.0%	87360	0.000	0.003	0.010
article prepare	00:00:00.024	0.0%	73292	0.000	0.000	0.013
article read	00:01:11.755	0.1%	18307	0.143	3.920	122.600
backlog stats	00:00:00.204	0.0%	239129	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:02.438	0.0%	130539	0.006	0.019	0.060
data write	00:00:16.353	0.0%	100127	0.026	0.163	0.413
idle	23:51:26.191	99.9%	239129	78.399	359.163	791.545
status file	00:00:16.064	0.0%	16278	0.600	0.987	7.325
TOTAL: 23:53:26.669	23:53:13.255	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:31:05.202	99.8%	66185	0.000	136.968	10035.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.633	0.0%	132966	0.000	0.020	1.333
readart	00:00:00.001	0.0%	14	0.000	0.071	0.500
TOTAL: 02:31:22.486	02:31:07.836	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	876	1985893	1980645	1528	3720	99%	324:35:35
2	news.nask.pl	2	15706	5459	4249	5998	34%	24:04:13
3	news.icm.edu.pl	48	268	4	256	8	1%	16:45:38
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		929	2001869	1986110	6033	9726	99%	365:25:26

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	599.2 GB	22.1 MB	27.6 MB	599.3 GB	99%	316.7 KB
2	news.nask.pl	30.2 MB	2.4 MB	34.0 MB	66.6 MB	45%	6.0 KB
3	news.icm.edu.pl	74.3 KB	38.6 KB	181.1 KB	294.0 KB	25%	24.5 KB
4	localhost	2.5 KB	0.0 KB	0.0 KB	2.5 KB	100%	1.2 KB
TOTAL: 4		599.2 GB	24.6 MB	61.8 MB	599.3 GB	99%	314.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 6 04:15:02 - 04:59:59	94360	4.8%	34.97	29.2 GB	4.9%	11332.82
May 6 05:00:00 - 05:59:59	121420	6.1%	33.73	38.9 GB	6.5%	11321.09
May 6 06:00:00 - 06:59:59	99526	5.0%	27.65	33.0 GB	5.5%	9619.98
May 6 07:00:00 - 07:59:59	103873	5.2%	28.85	34.3 GB	5.7%	9997.61
May 6 08:00:00 - 08:59:59	62490	3.1%	17.36	19.9 GB	3.3%	5791.71
May 6 09:00:00 - 09:59:59	65629	3.3%	18.23	19.8 GB	3.3%	5765.92
May 6 10:00:00 - 10:59:59	67493	3.4%	18.75	18.9 GB	3.2%	5511.29
May 6 11:00:00 - 11:59:59	67067	3.4%	18.63	19.9 GB	3.3%	5793.14
May 6 12:00:00 - 12:59:59	64412	3.2%	17.89	19.1 GB	3.2%	5567.99
May 6 13:00:00 - 13:59:59	65053	3.3%	18.07	18.5 GB	3.1%	5386.84
May 6 14:00:00 - 14:59:59	66068	3.3%	18.35	19.4 GB	3.2%	5647.93
May 6 15:00:00 - 15:59:59	64563	3.3%	17.93	18.9 GB	3.2%	5517.47
May 6 16:00:00 - 16:59:59	69704	3.5%	19.36	19.8 GB	3.3%	5780.21
May 6 17:00:00 - 17:59:59	65938	3.3%	18.32	19.4 GB	3.2%	5659.26
May 6 18:00:00 - 18:59:59	63842	3.2%	17.73	19.9 GB	3.3%	5790.28
May 6 19:00:00 - 19:59:59	64650	3.3%	17.96	19.9 GB	3.3%	5807.87
May 6 20:00:00 - 20:59:59	66567	3.4%	18.49	19.7 GB	3.3%	5750.70
May 6 21:00:00 - 21:59:59	59651	3.0%	16.57	17.7 GB	3.0%	5145.92
May 6 22:00:00 - 22:59:59	64067	3.2%	17.80	18.7 GB	3.1%	5436.74
May 6 23:00:00 - 23:59:59	62776	3.2%	17.44	18.3 GB	3.1%	5316.47
May 7 00:00:00 - 00:59:59	113819	5.7%	31.62	32.1 GB	5.4%	9350.16
May 7 01:00:00 - 01:59:59	134610	6.8%	37.39	37.7 GB	6.3%	10993.99
May 7 02:00:00 - 02:59:59	138760	7.0%	38.54	39.8 GB	6.6%	11585.26
May 7 03:00:00 - 03:59:59	113151	5.7%	31.43	36.4 GB	6.1%	10613.39
May 7 04:00:00 - 04:15:02	26441	1.3%	29.31	8.9 GB	1.5%	10375.57
TOTAL: 24:00:00	1985930	100.0%	22.99	598.2 GB	100.0%	7260.17

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	6119	6	980	5	5128
2	newsfeed.pionier.net.pl	4063	89	3550	3	421
3	news.icm.edu.pl	8	0	2	0	6
TOTAL: 3		10190	95	4532	8	5555

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	30
it.comp.os.win.windows10	25
it.tlc.cellulari.android	20
de.comp.office-pakete.ms-office.misc	5
it.comp.os.win.windows7	4
alt.sex.telephones	2
nl.comp.sys.android	1
nl.wetenschap.regulier	1
nl.comp.3d-printen	1
nl.gezondheid.alternatief-genezen	1
nl.actueel	1
it.comp.os.win.windows8	1
comp.sys.raspberry-pi	1
de.comp.lang.vbclassic	1
nl.comp.os.linux.techniek	1
TOTAL: 15	95

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

Groups	Count
pl.praca.oferowana	7
bit.listserv.catholic	1
TOTAL: 2	8

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4818
Binary in non-binary group	183
Scoring filter (8)	49
Scoring filter (9)	22
Poison newsgroup	18
Scoring filter (10)	9
EMP (phl)	8
HTML post	8
Too many newsgroups	7
Scoring filter (13)	2
Scoring filter (11)	2
EMP (fsl)	2
Scoring filter (12)	1
Too many newsgroups (low_xpost)	1
Angle-bracket bot	1
TOTAL: 15	5131

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	339
	news.nask.pl	85
	TOTAL: 2	424
TOTAL: 1		424

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	38393	13250	16529	3319	0	0	34%	23:59:57
2	panorama	59004	586	31085	287	8	55608	0%	23:59:57
3	poznan	7741	170	5124	39	0	0	2%	23:59:57
4	nask	8770	5	5690	179	0	0	0%	23:58:56
5	okapi	0	0	0	0	0	1097	0%	23:57:50
TOTAL: 5		113908	14011	58428	3824	8	56705	12%	119:56:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	2.3 GB	643.0 MB	3.0 GB	36.1 KB/s	188.2 KB	23:59:57
2	panorama	2.1 MB	1.2 MB	3.3 MB	0.0 KB/s	3.8 KB	23:59:57
3	poznan	603.9 KB	109.9 KB	713.8 KB	0.0 KB/s	3.4 KB	23:59:57
4	nask	135.2 KB	65.9 MB	66.0 MB	0.8 KB/s	367.3 KB	23:58:56
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		2.3 GB	710.2 MB	3.0 GB	7.4 KB/s	178.8 KB	119:56:37

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	14	11.6 KB	6	0	00:00:21
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	14	11.6 KB	6	2	02:31:21

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	674	14	11.6 KB	6	2	00:00:26
2	unresolved	2	0	0.0 KB	0	0	02:30:55
TOTAL: 2		676	14	11.6 KB	6	2	02:31:21

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.194.49	3.396	4.556	02:30:55
web.man.lodz.pl	1.340	3.716	00:00:21
mon.man.lodz.pl	1.032	3.856	00:00:04
david.man.lodz.pl	0.328	1.272	00:00:01
isvcs01.man.lodz.pl	0.016	0.028	00:00:00
TOTAL: 5	6.112	13.428	02:31:21

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.194.49	2
TOTAL: 3	386

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	8
2	pl.comp.pecet	6
TOTAL: 2		14

Daily Usenet report

May 6 04:15:02 -- May 7 04:15:02