Daily Usenet report: Dec 18 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Dec 18 09:07:19 newsfeed nnrpd[26278]: SERVER perl filter not defined
Dec 18 09:08:04 newsfeed nnrpd[26342]: SERVER perl filter not defined
Dec 18 11:57:12 newsfeed nnrpd[19079]: SERVER perl filter not defined
Dec 18 15:07:23 newsfeed overchan[12827]: input line too long, skipping
Dec 18 15:25:17 newsfeed nnrpd[18650]: SERVER perl filter not defined
Dec 18 17:46:29 newsfeed overchan[12827]: input line too long, skipping
Dec 18 18:50:56 newsfeed overchan[12827]: input line too long, skipping
Dec 18 20:25:47 newsfeed overchan[12827]: input line too long, skipping
Dec 18 20:54:47 newsfeed overchan[12827]: input line too long, skipping
Dec 18 23:50:38 newsfeed nnrpd[31451]: SERVER perl filter not defined
Dec 19 00:55:54 newsfeed overchan[12827]: input line too long, skipping
Dec 19 00:58:54 newsfeed overchan[12827]: input line too long, skipping
Dec 19 01:07:58 newsfeed overchan[12827]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	727265	92.2%	79.4 MB	91.4%
innfeed	47212	6.0%	5.6 MB	6.4%
innd	9755	1.2%	1.5 MB	1.7%
nnrpd	4210	0.5%	426.3 KB	0.5%
overchan	9	0.0%	1.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	788452	100.0%	86.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	722545	99.5%
Negative hits	3614	0.5%
Positive hits	246	0.0%
Cache misses	24	0.0%
TOTAL: 4	726429	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.840	0.0%	84	0.000	10.000	105.000
article cleanup	00:00:09.876	0.0%	727185	0.009	0.014	0.020
article logging	00:00:33.293	0.0%	727710	0.036	0.046	0.169
article parse	00:17:14.780	1.2%	51600590	0.016	0.020	0.029
article write	00:07:25.209	0.5%	727007	0.414	0.612	3.646
artlog/artcncl	00:00:00.002	0.0%	84	0.000	0.024	1.000
data move	00:00:43.427	0.1%	56402048	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.195	0.0%	82	0.000	2.378	27.000
hishave/artcncl	00:00:00.005	0.0%	84	0.000	0.060	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:12:28.321	0.9%	731124	0.374	1.024	27.310
history sync	00:00:00.022	0.0%	290	0.000	0.076	1.000
history write	00:15:50.903	1.1%	727165	0.508	1.308	42.938
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	21:20:50.704	88.9%	51064708	0.827	1.505	1.974
nntp read	00:29:25.484	2.0%	51283602	0.030	0.034	0.043
overview write	00:00:03.940	0.0%	727007	0.002	0.005	0.008
perl filter	01:04:31.083	4.5%	727165	4.036	5.324	7.427
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:35.448	0.0%	1467380	0.020	0.024	0.033
TOTAL: 24:00:05.903	23:49:53.532	99.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.128	0.0%	51407	0.000	0.002	0.013
article prepare	00:00:00.003	0.0%	5970	0.000	0.001	0.071
article read	00:00:19.307	0.0%	1490	0.000	12.958	940.000
backlog stats	00:00:00.101	0.0%	119896	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.950	0.0%	58002	0.000	0.016	0.039
data write	00:00:02.463	0.0%	44718	0.025	0.055	0.096
idle	23:47:21.469	99.4%	119896	465.019	714.298	1116.909
status file	00:07:49.097	0.5%	16791	0.764	27.937	2821.444
TOTAL: 23:55:39.931	23:55:33.518	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:40.886	78.0%	3059	0.000	13.366	1153.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.294	0.6%	6700	0.000	0.044	2.158
readart	00:00:00.000	0.0%	12	0.000	0.000	0.000
TOTAL: 00:00:52.392	00:00:41.180	78.6%	-	-	-	-

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	245	726536	726380	0	156	99%	93:51:47
2	news.icm.edu.pl	49	211	22	168	21	10%	13:53:47
3	localhost	1436	5	5	0	0	100%	00:00:00
TOTAL: 3		1730	726752	726407	168	177	99%	107:45:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	285.0 GB	1.2 MB	1.6 MB	285.0 GB	99%	411.4 KB
2	news.icm.edu.pl	906.1 KB	22.8 KB	289.9 KB	1.2 MB	74%	28.3 KB
3	localhost	9.0 KB	0.0 KB	0.0 KB	9.0 KB	100%	1.8 KB
TOTAL: 3		285.0 GB	1.2 MB	1.9 MB	285.0 GB	99%	411.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 18 04:15:02 - 04:59:59	20467	2.8%	7.59	7.8 GB	2.7%	3028.07
Dec 18 05:00:00 - 05:59:59	28595	3.9%	7.94	11.6 GB	4.0%	3365.63
Dec 18 06:00:00 - 06:59:59	29547	4.1%	8.21	10.9 GB	3.8%	3172.08
Dec 18 07:00:00 - 07:59:59	31924	4.4%	8.87	11.0 GB	3.8%	3199.14
Dec 18 08:00:00 - 08:59:59	33548	4.6%	9.32	11.3 GB	4.0%	3303.37
Dec 18 09:00:00 - 09:59:59	30899	4.3%	8.58	10.3 GB	3.6%	2996.43
Dec 18 10:00:00 - 10:59:59	30138	4.1%	8.37	10.5 GB	3.7%	3056.07
Dec 18 11:00:00 - 11:59:59	26414	3.6%	7.34	11.7 GB	4.1%	3413.55
Dec 18 12:00:00 - 12:59:59	26991	3.7%	7.50	11.8 GB	4.1%	3426.21
Dec 18 13:00:00 - 13:59:59	28955	4.0%	8.04	12.1 GB	4.2%	3510.60
Dec 18 14:00:00 - 14:59:59	29372	4.0%	8.16	13.2 GB	4.6%	3836.00
Dec 18 15:00:00 - 15:59:59	26957	3.7%	7.49	12.1 GB	4.2%	3515.41
Dec 18 16:00:00 - 16:59:59	29401	4.0%	8.17	12.3 GB	4.3%	3585.79
Dec 18 17:00:00 - 17:59:59	30716	4.2%	8.53	13.3 GB	4.7%	3878.02
Dec 18 18:00:00 - 18:59:59	29725	4.1%	8.26	12.0 GB	4.2%	3484.04
Dec 18 19:00:00 - 19:59:59	29981	4.1%	8.33	12.6 GB	4.4%	3659.34
Dec 18 20:00:00 - 20:59:59	35753	4.9%	9.93	13.0 GB	4.5%	3774.59
Dec 18 21:00:00 - 21:59:59	33703	4.6%	9.36	13.8 GB	4.8%	4025.49
Dec 18 22:00:00 - 22:59:59	28427	3.9%	7.90	12.4 GB	4.3%	3602.09
Dec 18 23:00:00 - 23:59:59	32489	4.5%	9.02	12.1 GB	4.2%	3528.09
Dec 19 00:00:00 - 00:59:59	34992	4.8%	9.72	12.8 GB	4.5%	3732.80
Dec 19 01:00:00 - 01:59:59	31556	4.3%	8.77	11.6 GB	4.1%	3388.17
Dec 19 02:00:00 - 02:59:59	28981	4.0%	8.05	10.7 GB	3.7%	3110.90
Dec 19 03:00:00 - 03:59:59	29397	4.0%	8.17	12.5 GB	4.4%	3649.59
Dec 19 04:00:00 - 04:15:02	7553	1.0%	8.37	2.8 GB	1.0%	3245.61
TOTAL: 24:00:00	726481	100.0%	8.41	286.0 GB	100.0%	3470.67

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	679	129	19	5	526
2	news.icm.edu.pl	21	0	1	0	20
TOTAL: 2		700	129	20	5	546

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	67
it.tlc.cellulari.android	16
nl.comp.os.linux.techniek	14
it.comp.os.win.windows10	11
comp.sys.raspberry-pi	7
comp.mobile.android	5
comp.mobile.ipad	5
de.rec.sf.startrek	2
it.comp.os.win.windows7	1
de.comp.lang.vbclassic	1
TOTAL: 10	129

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

Groups	Count
pl.sci.matematyka	4
pl.praca.oferowana	1
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	21
Scoring filter (9)	1
Binary in non-binary group	1
TOTAL: 3	23

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	40687	451	12232	218	1	38665	1%	23:59:39
2	icm	21555	325	12597	21	0	0	1%	23:56:28
3	poznan	12	0	11	0	0	0	0%	01:56:39
4	okapi	0	0	0	0	0	868	0%	23:50:01
5	nask	0	0	0	0	0	8920	0%	23:54:17
TOTAL: 5		62254	776	24840	239	1	48453	1%	97:37:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	panorama	1.3 MB	853.1 KB	2.2 MB	3.3 KB	23:59:39
2	icm	980.0 KB	101.4 KB	1.1 MB	3.1 KB	23:56:28
3	poznan	0.0 KB	0.0 KB	0.0 KB	0	01:56:39
4	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:50:01
5	nask	0.0 KB	0.0 KB	0.0 KB	0	23:54:17
TOTAL: 5		2.3 MB	954.5 KB	3.2 MB	3.3 KB	97:37:04

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	12	12.8 KB	6	0	00:00:43
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 2		293	12	12.8 KB	6	5	00:00:52

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	581	12	12.8 KB	6	5	00:00:47
2	*.sfj.censys-scanner.com	1	0	0.0 KB	0	0	00:00:00
3	unresolved	101	0	0.0 KB	0	0	00:00:04
4	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
TOTAL: 4		684	12	12.8 KB	6	5	00:00:52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.547	3.015	00:00:43
mon.man.lodz.pl	1.330	2.333	00:00:03
80.111.142.213	0.013	0.040	00:00:03
212.51.192.185	0.464	0.762	00:00:01
worker-18.sfj.censys-scanner.com	0.000	0.017	00:00:00
scan-141.security.ipip.net	0.008	0.004	00:00:00
isvcs01.man.lodz.pl	0.031	0.072	00:00:00
45.93.201.126	0.000	0.017	00:00:00
TOTAL: 8	3.393	6.260	00:00:52

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	4
45.93.201.126	1
worker-18.sfj.censys-scanner.com	1
scan-141.security.ipip.net	1
TOTAL: 6	391

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
worker-18.sfj.censys-scanner.com	1	1
45.93.201.126	1	1
TOTAL: 2	2	2

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Dec 18 04:15:02 -- Dec 19 04:15:02