Daily Usenet report: Sep 16 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Sep 16 11:18:26 newsfeed overchan[1571257]: input line too long, skipping
Sep 16 11:27:09 newsfeed overchan[1571257]: input line too long, skipping
Sep 16 13:35:04 newsfeed overchan[1571257]: input line too long, skipping
Sep 16 13:40:12 newsfeed overchan[1571257]: input line too long, skipping
Sep 16 14:51:04 newsfeed overchan[1571257]: input line too long, skipping
Sep 16 15:43:50 newsfeed nnrpd[1607152]: SERVER perl filter not defined
Sep 16 16:40:08 newsfeed overchan[1571257]: input line too long, skipping
Sep 16 18:58:53 newsfeed overchan[1571257]: input line too long, skipping
Sep 16 20:43:36 newsfeed overchan[1571257]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	624131	94.7%	69.0 MB	94.0%
innfeed	22724	3.4%	2.7 MB	3.7%
innd	8926	1.4%	1.4 MB	1.8%
nnrpd	3361	0.5%	320.2 KB	0.4%
overchan	9	0.0%	1.6 KB	0.0%
TOTAL: 5	659151	100.0%	73.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	620034	99.7%
Negative hits	1899	0.3%
Positive hits	210	0.0%
Cache misses	17	0.0%
TOTAL: 4	622160	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.448	0.0%	72	0.000	6.222	55.000
article cleanup	00:00:04.131	0.0%	623530	0.004	0.007	0.011
article logging	00:00:12.977	0.0%	623941	0.013	0.021	0.027
article parse	00:06:48.830	0.5%	113023481	0.003	0.004	0.005
article write	00:04:22.540	0.3%	623382	0.253	0.421	0.841
artlog/artcncl	00:00:00.002	0.0%	71	0.000	0.028	1.000
data move	00:00:29.075	0.0%	117906722	0.000	0.000	0.000
hisgrep/artcncl	00:00:00.053	0.0%	70	0.000	0.757	13.000
hishave/artcncl	00:00:00.005	0.0%	72	0.000	0.069	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:47.262	0.2%	625626	0.151	0.267	0.823
history sync	00:00:00.013	0.0%	290	0.000	0.045	0.500
history write	00:03:35.777	0.2%	623527	0.242	0.346	1.616
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	22:32:59.292	94.0%	112820142	0.536	0.720	1.183
nntp read	00:22:06.681	1.5%	112713459	0.011	0.012	0.013
overview write	00:00:01.575	0.0%	623382	0.001	0.003	0.005
perl filter	00:31:31.882	2.2%	623527	2.243	3.034	3.790
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:16.000	0.0%	1256701	0.010	0.013	0.016
TOTAL: 24:00:06.454	23:45:16.543	99.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	30488	0.000	0.001	0.013
article prepare	00:00:00.002	0.0%	11490	0.000	0.000	0.062
article read	00:00:08.374	0.0%	2893	0.000	2.895	24.700
backlog stats	00:00:00.058	0.0%	85589	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	27	0.000	0.000	0.000
data read	00:00:00.364	0.0%	40828	0.000	0.009	0.034
data write	00:00:00.921	0.0%	31224	0.000	0.029	0.101
idle	23:56:03.881	100.0%	85589	597.268	1006.717	2252.264
status file	00:00:04.485	0.0%	7435	0.324	0.603	7.036
TOTAL: 23:56:20.096	23:56:18.120	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:50.461	87.6%	2778	0.000	18.165	10712.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.072	0.1%	5961	0.000	0.012	0.500
readart	00:00:00.003	0.0%	46	0.000	0.065	0.500
TOTAL: 00:00:57.598	00:00:50.536	87.7%	-	-	-	-

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	166	623758	623619	0	139	99%	84:06:29
2	news.icm.edu.pl	51	169	4	156	9	2%	14:22:53
3	localhost	1442	1	1	0	0	100%	00:00:00
TOTAL: 3		1659	623928	623624	156	148	99%	98:29:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	270.1 GB	308.8 KB	621.0 KB	270.1 GB	99%	454.0 KB
2	news.icm.edu.pl	319.7 KB	0.0 KB	102.0 KB	421.7 KB	75%	32.4 KB
3	localhost	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
TOTAL: 3		270.1 GB	308.8 KB	723.0 KB	270.1 GB	99%	454.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 16 04:15:02 - 04:59:59	18973	3.0%	7.03	10.2 GB	3.8%	3982.93
Sep 16 05:00:00 - 05:59:59	24061	3.9%	6.68	12.8 GB	4.7%	3715.76
Sep 16 06:00:00 - 06:59:59	24351	3.9%	6.76	12.4 GB	4.6%	3609.99
Sep 16 07:00:00 - 07:59:59	25223	4.0%	7.01	12.2 GB	4.5%	3567.63
Sep 16 08:00:00 - 08:59:59	21610	3.5%	6.00	11.3 GB	4.2%	3280.52
Sep 16 09:00:00 - 09:59:59	23163	3.7%	6.43	11.1 GB	4.1%	3233.36
Sep 16 10:00:00 - 10:59:59	24322	3.9%	6.76	11.9 GB	4.4%	3475.53
Sep 16 11:00:00 - 11:59:59	23544	3.8%	6.54	11.0 GB	4.1%	3217.30
Sep 16 12:00:00 - 12:59:59	25418	4.1%	7.06	10.6 GB	3.9%	3087.28
Sep 16 13:00:00 - 13:59:59	20517	3.3%	5.70	9.9 GB	3.7%	2897.27
Sep 16 14:00:00 - 14:59:59	27252	4.4%	7.57	10.1 GB	3.7%	2942.69
Sep 16 15:00:00 - 15:59:59	25377	4.1%	7.05	10.5 GB	3.9%	3053.80
Sep 16 16:00:00 - 16:59:59	27355	4.4%	7.60	10.2 GB	3.8%	2969.64
Sep 16 17:00:00 - 17:59:59	22252	3.6%	6.18	10.1 GB	3.7%	2936.82
Sep 16 18:00:00 - 18:59:59	26232	4.2%	7.29	10.6 GB	3.9%	3098.44
Sep 16 19:00:00 - 19:59:59	37730	6.1%	10.48	11.6 GB	4.3%	3380.14
Sep 16 20:00:00 - 20:59:59	26778	4.3%	7.44	10.4 GB	3.9%	3039.78
Sep 16 21:00:00 - 21:59:59	25960	4.2%	7.21	11.3 GB	4.2%	3286.10
Sep 16 22:00:00 - 22:59:59	28789	4.6%	8.00	11.6 GB	4.3%	3365.44
Sep 16 23:00:00 - 23:59:59	27188	4.4%	7.55	10.4 GB	3.9%	3038.60
Sep 17 00:00:00 - 00:59:59	37209	6.0%	10.34	11.6 GB	4.3%	3372.55
Sep 17 01:00:00 - 01:59:59	25701	4.1%	7.14	11.7 GB	4.3%	3395.67
Sep 17 02:00:00 - 02:59:59	24549	3.9%	6.82	12.3 GB	4.6%	3596.75
Sep 17 03:00:00 - 03:59:59	23492	3.8%	6.53	11.4 GB	4.2%	3319.66
Sep 17 04:00:00 - 04:15:02	6454	1.0%	7.16	3.4 GB	1.3%	3982.89
TOTAL: 24:00:00	623500	100.0%	7.22	270.8 GB	100.0%	3285.98

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	550	134	3	2	411
2	news.icm.edu.pl	10	0	0	0	10
TOTAL: 2		560	134	3	2	421

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	97
it.comp.os.win.windows10	11
it.tlc.cellulari.android	11
comp.sys.raspberry-pi	6
alt.comp.software.seamonkey	4
comp.mobile.android	2
it.discussioni.energia	1
de.rec.sf.startrek	1
nl.actueel	1
TOTAL: 9	134

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7
Binary in non-binary group	2
TOTAL: 2	9

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	411
	news.icm.edu.pl	1
	TOTAL: 2	412
TOTAL: 1		412

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	16737	2068	7349	156	0	0	12%	23:59:49
2	panorama	20980	205	8396	148	47	21747	0%	23:59:57
3	nask	0	0	0	0	0	6719	0%	23:57:48
4	okapi	0	0	0	0	0	633	0%	23:57:48
5	poznan	4	0	3	0	0	0	0%	00:40:05
TOTAL: 5		37721	2273	15748	304	47	29099	6%	96:35:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	8.5 MB	731.1 KB	9.2 MB	0.1 KB/s	4.2 KB	23:59:49
2	panorama	717.9 KB	515.0 KB	1.2 MB	0.0 KB/s	3.5 KB	23:59:57
3	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:48
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:48
5	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	00:40:05
TOTAL: 5		9.2 MB	1.2 MB	10.4 MB	0.0 KB/s	4.1 KB	96:35:27

NNRP readership statistics:

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

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	289	46	39.9 KB	21	1	00:00:10
2	unresolved	103	0	0.0 KB	0	0	00:00:04
3	*.ch1.censys-scanner.com	2	0	0.0 KB	0	0	00:00:00
4	*.96.149.12.ipv4.supernova.orange.pl	1	0	0.0 KB	0	0	00:00:42
TOTAL: 4		395	46	39.9 KB	21	1	00:00:57

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
217.96.149.12.ipv4.supernova.orange.pl	0.024	0.014	00:00:42
web.man.lodz.pl	0.937	1.485	00:00:09
80.111.142.213	0.000	0.035	00:00:01
212.51.192.185	0.248	0.453	00:00:01
194.165.16.71	0.014	0.005	00:00:00
scanner-04.ch1.censys-scanner.com	0.007	0.011	00:00:00
192.241.197.122	0.006	0.006	00:00:00
isvcs01.man.lodz.pl	0.010	0.005	00:00:00
?	0.000	0.006	00:00:00
TOTAL: 9	1.246	2.020	00:00:57

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
scanner-04.ch1.censys-scanner.com	2
194.165.16.71	2
192.241.197.122	1
217.96.149.12.ipv4.supernova.orange.pl	1
TOTAL: 6	106

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP unrecognized commands (by host):

System	Conn
194.165.16.71	2
192.241.197.122	1
scanner-04.ch1.censys-scanner.com	1
TOTAL: 3	4

NNRP unrecognized commands (by command):

Command	Count
#003	2
EHLO www.censys.io	1
MGLNDD_212.191.2.21_119	1
TOTAL: 3	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.71	2	2
TOTAL: 1	2	3

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Sep 16 04:15:02 -- Sep 17 04:15:02