Daily Usenet report: Aug 21 04:15:02

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Aug 21 06:48:57 newsfeed overchan[25740]: input line too long, skipping
Aug 21 07:34:37 newsfeed overchan[25740]: input line too long, skipping
Aug 21 10:23:05 newsfeed overchan[25740]: input line too long, skipping
Aug 21 11:23:46 newsfeed nnrpd[27594]: SERVER perl filter not defined
Aug 21 11:24:30 newsfeed nnrpd[27679]: SERVER perl filter not defined
Aug 21 11:25:39 newsfeed nnrpd[28044]: SERVER perl filter not defined
Aug 21 15:14:09 newsfeed overchan[25740]: input line too long, skipping
Aug 21 16:34:25 newsfeed overchan[25740]: input line too long, skipping
Aug 21 17:39:15 newsfeed overchan[25740]: input line too long, skipping
Aug 21 19:11:55 newsfeed overchan[25740]: input line too long, skipping
Aug 21 19:41:00 newsfeed overchan[25740]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	668203	94.2%	73.0 MB	93.5%
innfeed	27064	3.8%	3.2 MB	4.1%
innd	9251	1.3%	1.4 MB	1.8%
nnrpd	4904	0.7%	524.0 KB	0.7%
overchan	9	0.0%	1.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	709432	100.0%	78.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	663853	99.7%
Negative hits	1645	0.2%
Positive hits	351	0.1%
Cache misses	39	0.0%
TOTAL: 4	665888	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.994	0.0%	105	0.000	38.038	286.000
article cleanup	00:00:10.226	0.0%	667028	0.008	0.015	0.022
article logging	00:00:36.391	0.0%	667406	0.042	0.055	0.085
article parse	00:18:00.131	1.3%	38284775	0.016	0.028	0.046
article write	00:07:59.123	0.6%	666587	0.524	0.719	0.980
artlog/artcncl	00:00:00.009	0.0%	105	0.000	0.086	1.000
data move	00:00:38.264	0.0%	42683313	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.473	0.0%	101	0.000	4.683	37.000
hishave/artcncl	00:00:00.227	0.0%	105	0.000	2.162	16.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:33:10.021	6.5%	668902	6.466	8.357	16.376
history sync	00:00:00.019	0.0%	290	0.000	0.066	0.500
history write	01:43:45.988	7.2%	666963	7.483	9.335	16.606
hiswrite/artcncl	00:00:00.048	0.0%	4	5.000	12.000	18.000
idle	18:26:07.259	76.8%	37506832	1.092	1.769	4.901
nntp read	00:27:36.121	1.9%	37938971	0.029	0.044	0.063
overview write	00:00:03.548	0.0%	666587	0.002	0.005	0.010
perl filter	01:06:48.059	4.6%	666963	4.354	6.009	7.962
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:39.930	0.0%	1347035	0.021	0.030	0.037
TOTAL: 24:00:03.640	23:45:39.831	99.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.114	0.0%	41200	0.000	0.003	0.014
article prepare	00:00:00.000	0.0%	1390	0.000	0.000	0.000
article read	00:00:01.362	0.0%	349	0.000	3.903	52.000
backlog stats	00:00:00.067	0.0%	87488	0.000	0.001	0.010
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.904	0.0%	45272	0.004	0.020	0.041
data write	00:00:02.080	0.0%	31629	0.028	0.066	0.148
idle	23:54:23.791	99.9%	87488	625.781	983.721	1767.367
status file	00:00:56.752	0.1%	8769	1.050	6.472	223.648
TOTAL: 23:55:29.557	23:55:25.070	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:23.666	59.5%	3062	0.000	7.729	556.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.281	0.7%	6751	0.000	0.042	1.316
readart	00:00:00.003	0.0%	28	0.000	0.107	0.500
TOTAL: 00:00:39.772	00:00:23.950	60.2%	-	-	-	-

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	186	667581	667237	0	344	99%	91:27:15
2	news.icm.edu.pl	38	385	59	229	97	15%	18:54:44
3	localhost	1439	3	3	0	0	100%	00:00:04
TOTAL: 3		1663	667969	667299	229	441	99%	110:22:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	276.5 GB	3.1 MB	3.9 MB	276.5 GB	99%	434.3 KB
2	news.icm.edu.pl	3.2 MB	126.1 KB	4.3 MB	7.6 MB	42%	50.2 KB
3	localhost	4.6 KB	0.0 KB	0.0 KB	4.6 KB	100%	1.5 KB
TOTAL: 3		276.5 GB	3.2 MB	8.1 MB	276.5 GB	99%	434.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 21 04:15:02 - 04:59:59	14776	2.2%	5.48	7.1 GB	2.5%	2741.86
Aug 21 05:00:00 - 05:59:59	24742	3.7%	6.87	11.4 GB	4.1%	3306.80
Aug 21 06:00:00 - 06:59:59	26935	4.0%	7.48	11.7 GB	4.2%	3417.79
Aug 21 07:00:00 - 07:59:59	25457	3.8%	7.07	11.8 GB	4.3%	3433.62
Aug 21 08:00:00 - 08:59:59	23979	3.6%	6.66	11.1 GB	4.0%	3247.10
Aug 21 09:00:00 - 09:59:59	26270	3.9%	7.30	11.3 GB	4.1%	3305.35
Aug 21 10:00:00 - 10:59:59	27582	4.1%	7.66	11.5 GB	4.2%	3355.08
Aug 21 11:00:00 - 11:59:59	28886	4.3%	8.02	11.9 GB	4.3%	3453.79
Aug 21 12:00:00 - 12:59:59	29160	4.4%	8.10	12.5 GB	4.5%	3644.50
Aug 21 13:00:00 - 13:59:59	33359	5.0%	9.27	12.8 GB	4.6%	3721.11
Aug 21 14:00:00 - 14:59:59	33561	5.0%	9.32	13.2 GB	4.8%	3840.87
Aug 21 15:00:00 - 15:59:59	32673	4.9%	9.08	13.4 GB	4.8%	3890.66
Aug 21 16:00:00 - 16:59:59	29618	4.4%	8.23	12.0 GB	4.3%	3507.02
Aug 21 17:00:00 - 17:59:59	30121	4.5%	8.37	12.2 GB	4.4%	3566.35
Aug 21 18:00:00 - 18:59:59	31217	4.7%	8.67	11.9 GB	4.3%	3452.24
Aug 21 19:00:00 - 19:59:59	29725	4.5%	8.26	11.7 GB	4.2%	3416.28
Aug 21 20:00:00 - 20:59:59	28402	4.3%	7.89	11.5 GB	4.2%	3362.18
Aug 21 21:00:00 - 21:59:59	27493	4.1%	7.64	10.3 GB	3.7%	3006.74
Aug 21 22:00:00 - 22:59:59	30309	4.5%	8.42	11.3 GB	4.1%	3289.48
Aug 21 23:00:00 - 23:59:59	27355	4.1%	7.60	10.5 GB	3.8%	3067.38
Aug 22 00:00:00 - 00:59:59	26790	4.0%	7.44	12.5 GB	4.5%	3628.46
Aug 22 01:00:00 - 01:59:59	26003	3.9%	7.22	11.7 GB	4.2%	3421.32
Aug 22 02:00:00 - 02:59:59	23267	3.5%	6.46	9.8 GB	3.5%	2845.12
Aug 22 03:00:00 - 03:59:59	23237	3.5%	6.45	9.5 GB	3.4%	2757.49
Aug 22 04:00:00 - 04:15:03	6363	1.0%	7.05	2.7 GB	1.0%	3092.14
TOTAL: 24:00:01	667280	100.0%	7.72	277.3 GB	100.0%	3365.26

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	722	279	55	10	378
2	news.icm.edu.pl	97	0	10	0	87
TOTAL: 2		819	279	65	10	465

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	205
it.comp.os.win.windows10	33
it.tlc.cellulari.android	20
comp.sys.raspberry-pi	10
nl.comp.os.linux.techniek	4
comp.mobile.android	3
it.discussioni.energia	2
de.comp.office-pakete.ms-office.excel	2
TOTAL: 8	279

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

Groups	Count
pl.praca.oferowana	8
pl.sci.matematyka	2
TOTAL: 2	10

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	83
Binary in non-binary group	3
Too many newsgroups	1
TOTAL: 3	87

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	17044	170	13402	9	2	0	0%	23:59:58
2	panorama	27509	2	13233	54	1	28001	0%	23:59:58
3	poznan	44	1	31	0	0	0	2%	04:20:49
4	nask	0	0	0	0	0	9835	0%	23:57:48
5	okapi	0	0	0	0	0	844	0%	23:57:48
TOTAL: 5		44597	173	26666	63	3	38680	0%	100:16:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	858.7 KB	20.7 KB	879.3 KB	4.9 KB	23:59:58
2	panorama	149.9 KB	339.7 KB	489.6 KB	8.7 KB	23:59:58
3	poznan	10.2 KB	0.0 KB	10.2 KB	10.2 KB	04:20:49
4	nask	0.0 KB	0.0 KB	0.0 KB	0	23:57:48
5	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:57:48
TOTAL: 5		1018.8 KB	360.3 KB	1.3 MB	5.8 KB	100:16:21

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	28	29.6 KB	14	0	00:00:24
2	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 2		291	28	29.6 KB	14	3	00:00:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	579	28	29.6 KB	14	3	00:00:30
2	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
3	unresolved	102	0	0.0 KB	0	0	00:00:06
4	*.threatsinkhole.com	1	0	0.0 KB	0	0	00:00:00
5	*.dynamic.chello.pl	1	0	0.0 KB	0	0	00:00:01
TOTAL: 5		684	28	29.6 KB	14	3	00:00:39

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.718	3.826	00:00:24
mon.man.lodz.pl	1.448	2.753	00:00:06
80.111.142.213	0.021	0.060	00:00:05
89-64-149-5.dynamic.chello.pl	0.000	0.009	00:00:01
212.51.192.185	0.453	0.959	00:00:01
isvcs01.man.lodz.pl	0.004	0.035	00:00:00
scan-141.security.ipip.net	0.003	0.005	00:00:00
91.241.19.109	0.020	0.017	00:00:00
winnti-scanner-victims-will-be-notified.threatsinkhole.com	0.004	0.004	00:00:00
TOTAL: 9	3.671	7.668	00:00:39

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	4
91.241.19.109	2
winnti-scanner-victims-will-be-notified.threatsinkhole.com	1
scan-141.security.ipip.net	1
89-64-149-5.dynamic.chello.pl	1
TOTAL: 7	393

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
91.241.19.109	2
TOTAL: 2	6

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
91.241.19.109	2	2
TOTAL: 1	2	3

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	16
2	pl.comp.pecet	12
TOTAL: 2		28

Daily Usenet report

Aug 21 04:15:02 -- Aug 22 04:15:03