Daily Usenet report: Sep 22 04:15:03

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Sep 22 09:19:06 newsfeed nnrpd[22986]: SERVER perl filter not defined
Sep 22 10:33:28 newsfeed nnrpd[25837]: SERVER perl filter not defined
Sep 22 15:22:13 newsfeed nnrpd[4304]: SERVER perl filter not defined
Sep 22 15:49:59 newsfeed overchan[10983]: input line too long, skipping
Sep 22 17:30:55 newsfeed overchan[10983]: input line too long, skipping
Sep 22 17:48:00 newsfeed overchan[10983]: input line too long, skipping
Sep 22 18:06:21 newsfeed overchan[10983]: input line too long, skipping
Sep 22 18:16:04 newsfeed overchan[10983]: input line too long, skipping
Sep 22 18:23:02 newsfeed overchan[10983]: input line too long, skipping
Sep 22 18:32:42 newsfeed overchan[10983]: input line too long, skipping
Sep 22 21:49:29 newsfeed overchan[10983]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2797624	96.3%	328.9 MB	96.0%
innd	57804	2.0%	8.0 MB	2.3%
innfeed	45251	1.6%	5.3 MB	1.5%
nnrpd	4780	0.2%	496.7 KB	0.1%
overchan	9	0.0%	1.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2905469	100.0%	342.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2775253	88.7%
Negative hits	301237	9.6%
Positive hits	36784	1.2%
Cache misses	16070	0.5%
TOTAL: 4	3129344	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.580	0.0%	169	0.000	9.349	75.125
article cleanup	00:00:48.206	0.1%	2797358	0.013	0.017	0.022
article logging	00:01:58.139	0.1%	2797908	0.033	0.042	0.068
article parse	01:20:14.521	5.6%	162883935	0.017	0.030	0.050
article write	00:25:39.557	1.8%	2739382	0.426	0.562	1.246
artlog/artcncl	00:00:00.003	0.0%	168	0.000	0.018	0.333
data move	00:06:09.342	0.4%	180164024	0.001	0.002	0.005
hisgrep/artcncl	00:00:00.719	0.0%	164	0.000	4.384	58.125
hishave/artcncl	00:00:00.089	0.0%	169	0.000	0.527	11.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:22:16.121	1.5%	3132848	0.021	0.426	3.655
history sync	00:00:00.020	0.0%	290	0.000	0.069	1.000
history write	01:19:26.856	5.5%	2778512	0.180	1.716	6.700
hiswrite/artcncl	00:00:00.011	0.0%	5	0.000	2.200	8.000
idle	11:38:44.604	48.5%	98919559	0.276	0.424	0.763
nntp read	01:40:57.522	7.0%	160657682	0.025	0.038	0.052
overview write	00:00:17.913	0.0%	2739382	0.004	0.007	0.009
perl filter	06:25:25.470	26.8%	2778512	7.105	8.323	10.023
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:15.428	0.4%	5501060	0.041	0.057	0.080
TOTAL: 24:00:03.992	23:27:16.101	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.241	0.0%	61844	0.000	0.004	0.013
article prepare	00:00:00.005	0.0%	10754	0.000	0.000	0.050
article read	00:00:49.399	0.1%	2678	0.000	18.446	434.125
backlog stats	00:00:00.173	0.0%	163844	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:02.308	0.0%	90085	0.004	0.026	0.055
data write	00:00:03.590	0.0%	69513	0.014	0.052	0.183
idle	23:53:10.079	99.9%	163844	286.468	524.829	1141.561
status file	00:00:19.229	0.0%	15733	0.650	1.222	9.125
TOTAL: 23:54:43.186	23:54:25.024	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:16.034	74.3%	3047	0.000	24.954	17622.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.190	0.2%	6760	0.000	0.028	0.667
readart	00:00:00.005	0.0%	46	0.000	0.109	0.500
TOTAL: 00:01:42.309	00:01:16.229	74.5%	-	-	-	-

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	1023	2789951	2728554	30874	30523	97%	403:16:31
2	news.nask.pl	2	41135	10723	2779	27633	26%	24:05:35
3	news.icm.edu.pl	49	257	23	230	4	8%	17:14:12
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		1078	2831346	2739303	33883	58160	96%	444:36:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	882.4 GB	2.6 GB	4.7 GB	889.6 GB	99%	338.1 KB
2	news.nask.pl	199.6 MB	30.7 MB	4.0 GB	4.2 GB	4%	114.7 KB
3	news.icm.edu.pl	485.7 KB	0.0 KB	78.7 KB	564.5 KB	86%	20.9 KB
4	localhost	4.2 KB	0.0 KB	0.0 KB	4.2 KB	100%	1.4 KB
TOTAL: 4		882.5 GB	2.6 GB	8.6 GB	893.8 GB	98%	335.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 22 04:15:03 - 04:59:59	96560	3.5%	35.80	30.7 GB	3.5%	11944.75
Sep 22 05:00:00 - 05:59:59	136807	5.0%	38.00	41.0 GB	4.7%	11943.74
Sep 22 06:00:00 - 06:59:59	124273	4.5%	34.52	38.7 GB	4.4%	11275.81
Sep 22 07:00:00 - 07:59:59	131581	4.8%	36.55	40.3 GB	4.6%	11735.73
Sep 22 08:00:00 - 08:59:59	121940	4.5%	33.87	37.4 GB	4.2%	10883.55
Sep 22 09:00:00 - 09:59:59	123595	4.5%	34.33	36.4 GB	4.1%	10602.35
Sep 22 10:00:00 - 10:59:59	133574	4.9%	37.10	39.2 GB	4.4%	11417.35
Sep 22 11:00:00 - 11:59:59	112682	4.1%	31.30	35.7 GB	4.1%	10411.50
Sep 22 12:00:00 - 12:59:59	100865	3.7%	28.02	33.0 GB	3.7%	9611.57
Sep 22 13:00:00 - 13:59:59	108592	4.0%	30.16	36.8 GB	4.2%	10732.07
Sep 22 14:00:00 - 14:59:59	71855	2.6%	19.96	24.9 GB	2.8%	7253.35
Sep 22 15:00:00 - 15:59:59	47362	1.7%	13.16	15.3 GB	1.7%	4464.76
Sep 22 16:00:00 - 16:59:59	71467	2.6%	19.85	23.0 GB	2.6%	6685.27
Sep 22 17:00:00 - 17:59:59	113162	4.1%	31.43	36.8 GB	4.2%	10730.73
Sep 22 18:00:00 - 18:59:59	132438	4.8%	36.79	42.1 GB	4.8%	12276.88
Sep 22 19:00:00 - 19:59:59	134204	4.9%	37.28	42.6 GB	4.8%	12403.41
Sep 22 20:00:00 - 20:59:59	138556	5.1%	38.49	44.1 GB	5.0%	12835.21
Sep 22 21:00:00 - 21:59:59	137967	5.0%	38.32	40.7 GB	4.6%	11857.78
Sep 22 22:00:00 - 22:59:59	130987	4.8%	36.39	38.8 GB	4.4%	11300.29
Sep 22 23:00:00 - 23:59:59	117303	4.3%	32.58	40.0 GB	4.5%	11652.59
Sep 23 00:00:00 - 00:59:59	98324	3.6%	27.31	35.2 GB	4.0%	10250.25
Sep 23 01:00:00 - 01:59:59	114636	4.2%	31.84	42.5 GB	4.8%	12372.74
Sep 23 02:00:00 - 02:59:59	114148	4.2%	31.71	42.0 GB	4.8%	12227.17
Sep 23 03:00:00 - 03:59:59	97834	3.6%	27.18	35.0 GB	4.0%	10204.55
Sep 23 04:00:00 - 04:15:02	28028	1.0%	31.07	9.3 GB	1.1%	10832.31
TOTAL: 23:59:59	2738740	100.0%	31.70	881.6 GB	100.0%	10699.76

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	30877	162	18420	2	12293
2	news.nask.pl	27835	41	511	5	27278
3	news.icm.edu.pl	4	0	0	0	4
TOTAL: 3		58716	203	18931	7	39575

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	85
it.tlc.cellulari.android	32
it.comp.os.win.windows10	24
comp.sys.raspberry-pi	16
de.rec.sf.startrek	15
de.comp.office-pakete.ms-office.word	12
comp.mobile.android	9
it.comp.os.win.windows7	7
it.comp.os.linux.ubuntu	2
hr.rec.glazba.hifi	1
TOTAL: 10	203

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	35291
EMP (md5)	3640
Scoring filter (8)	28
Scoring filter (9)	24
Scoring filter (10)	18
Too many newsgroups	13
HTML post	7
Scoring filter (11)	2
Too many newsgroups (low_xpost)	1
Scoring filter (12)	1
TOTAL: 10	39025

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	334
	news.nask.pl	216
	TOTAL: 2	550
TOTAL: 1		550

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44001	844	16794	208	5	44062	1%	23:59:28
2	poznan	15799	361	9621	26	0	1	2%	23:59:56
3	icm	23319	85	20231	600	0	0	0%	23:59:54
4	nask	3983	5	3217	0	0	0	0%	23:59:10
5	okapi	0	0	0	0	0	1941	0%	23:57:49
TOTAL: 5		87102	1295	49863	834	5	46004	1%	119:56:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	7.0 MB	191.2 MB	198.3 MB	2.4 KB/s	296.4 KB	23:59:54
2	panorama	3.9 MB	1.3 MB	5.2 MB	0.1 KB/s	5.0 KB	23:59:28
3	poznan	1.4 MB	289.3 KB	1.7 MB	0.0 KB/s	4.5 KB	23:59:56
4	nask	143.6 KB	0.0 KB	143.6 KB	0.0 KB/s	28.7 KB	23:59:10
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:49
TOTAL: 5		12.5 MB	192.8 MB	205.3 MB	0.5 KB/s	98.7 KB	119:56:17

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	46	46.1 KB	22	0	00:00:31
2	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 2		291	46	46.1 KB	22	3	00:01:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	675	46	46.1 KB	22	3	00:00:44
2	*.census.shodan.io	1	0	0.0 KB	0	0	00:00:03
3	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:52
TOTAL: 3		677	46	46.1 KB	22	3	00:01:41

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
aaws227.neoplus.adsl.tpnet.pl	0.036	0.028	00:00:52
web.man.lodz.pl	1.504	3.948	00:00:31
mon.man.lodz.pl	1.304	3.560	00:00:09
battery.census.shodan.io	0.000	0.016	00:00:03
david.man.lodz.pl	0.408	1.164	00:00:03
isvcs01.man.lodz.pl	0.032	0.032	00:00:00
TOTAL: 6	3.284	8.748	00:01:41

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
battery.census.shodan.io	1
aaws227.neoplus.adsl.tpnet.pl	1
TOTAL: 4	386

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	42
2	pl.comp.os.ms-windows.winnt	4
TOTAL: 2		46

Daily Usenet report

Sep 22 04:15:03 -- Sep 23 04:15:02