Daily Usenet report: Mar 10 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Mar 10 09:02:00 newsfeed overchan[17387]: input line too long, skipping
Mar 10 09:02:25 newsfeed overchan[17387]: input line too long, skipping
Mar 10 09:02:51 newsfeed overchan[17387]: input line too long, skipping
Mar 10 13:51:23 newsfeed overchan[17387]: input line too long, skipping
Mar 10 14:23:58 newsfeed overchan[17387]: input line too long, skipping
Mar 10 14:25:35 newsfeed overchan[17387]: input line too long, skipping
Mar 10 14:26:02 newsfeed overchan[17387]: input line too long, skipping
Mar 10 23:32:47 newsfeed nnrpd[28284]: SERVER perl filter not defined
Mar 11 00:51:40 newsfeed nnrpd[31208]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3410192	97.7%	377.3 MB	97.2%
innfeed	48715	1.4%	5.8 MB	1.5%
innd	27511	0.8%	4.4 MB	1.1%
nnrpd	4808	0.1%	507.5 KB	0.1%
overchan	8	0.0%	1.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3491235	100.0%	387.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3374343	97.0%
Negative hits	95836	2.8%
Positive hits	10153	0.3%
Cache misses	122	0.0%
TOTAL: 4	3480454	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.281	0.0%	156	0.000	14.622	96.750
article cleanup	00:00:40.290	0.0%	3422785	0.008	0.012	0.018
article logging	00:02:27.550	0.2%	3423350	0.026	0.043	0.134
article parse	00:41:47.130	2.9%	81925805	0.018	0.031	0.045
article write	00:17:31.747	1.2%	3413812	0.106	0.308	0.483
artlog/artcncl	00:00:00.003	0.0%	145	0.000	0.021	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:45.051	0.1%	97812716	0.001	0.001	0.002
hisgrep/artcncl	00:00:01.295	0.0%	151	0.000	8.576	66.750
hishave/artcncl	00:00:00.074	0.0%	156	0.000	0.474	4.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:45:24.237	3.2%	3525615	0.036	0.773	6.515
history sync	00:00:00.014	0.0%	290	0.000	0.048	0.500
history write	02:13:31.652	9.3%	3418691	0.199	2.343	10.544
hiswrite/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
idle	13:46:32.586	57.4%	61709406	0.426	0.804	1.741
nntp read	00:47:47.219	3.3%	79426531	0.024	0.036	0.053
overview write	00:00:16.330	0.0%	3413812	0.003	0.005	0.007
perl filter	04:46:35.384	19.9%	3418690	2.106	5.030	7.012
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:15.394	0.4%	6844037	0.029	0.055	0.072
TOTAL: 24:00:02.317	23:30:38.237	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.135	0.0%	65788	0.000	0.002	0.010
article prepare	00:00:00.002	0.0%	11720	0.000	0.000	0.010
article read	00:01:13.463	0.1%	3017	1.722	24.350	168.588
backlog stats	00:00:00.143	0.0%	165020	0.000	0.001	0.005
callbacks	00:00:00.001	0.0%	91	0.000	0.011	1.000
data read	00:00:01.335	0.0%	85230	0.005	0.016	0.031
data write	00:00:03.585	0.0%	69070	0.018	0.052	0.113
idle	23:52:08.038	99.9%	165020	360.326	520.713	958.108
status file	00:00:15.108	0.0%	16375	0.625	0.923	2.177
TOTAL: 23:53:58.757	23:53:41.810	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:13:58.863	98.6%	3084	0.000	272.005	210286.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.230	0.0%	6909	0.000	0.033	0.684
readart	00:00:00.032	0.0%	84	0.000	0.381	10.500
TOTAL: 00:14:10.683	00:13:59.125	98.6%	-	-	-	-

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	1298	3400595	3391341	4040	5214	99%	422:23:37
2	news.nask.pl	2	14439	8874	1865	3700	61%	24:03:19
3	news.icm.edu.pl	38	250	16	213	21	6%	16:41:04
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		1341	3415286	3400233	6118	8935	99%	463:08:00

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	691.7 GB	26.6 MB	212.8 MB	691.9 GB	99%	213.6 KB
2	news.nask.pl	51.2 MB	528.7 KB	19.1 MB	70.8 MB	72%	5.8 KB
3	news.icm.edu.pl	620.4 KB	0.0 KB	709.2 KB	1.3 MB	46%	35.9 KB
4	localhost	3.1 KB	0.0 KB	0.0 KB	3.1 KB	100%	1.5 KB
TOTAL: 4		691.7 GB	27.1 MB	232.5 MB	692.0 GB	99%	212.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 10 04:15:02 - 04:59:59	96018	2.8%	35.59	16.8 GB	2.4%	6545.00
Mar 10 05:00:00 - 05:59:59	109090	3.2%	30.30	22.9 GB	3.3%	6658.57
Mar 10 06:00:00 - 06:59:59	129677	3.8%	36.02	32.8 GB	4.7%	9543.21
Mar 10 07:00:00 - 07:59:59	101902	3.0%	28.31	28.1 GB	4.1%	8176.73
Mar 10 08:00:00 - 08:59:59	123700	3.6%	34.36	34.2 GB	4.9%	9968.26
Mar 10 09:00:00 - 09:59:59	143781	4.2%	39.94	27.8 GB	4.0%	8097.58
Mar 10 10:00:00 - 10:59:59	144879	4.3%	40.24	25.1 GB	3.6%	7305.20
Mar 10 11:00:00 - 11:59:59	143342	4.2%	39.82	27.9 GB	4.0%	8129.30
Mar 10 12:00:00 - 12:59:59	142846	4.2%	39.68	28.8 GB	4.2%	8388.12
Mar 10 13:00:00 - 13:59:59	146369	4.3%	40.66	30.7 GB	4.4%	8948.63
Mar 10 14:00:00 - 14:59:59	147933	4.4%	41.09	29.8 GB	4.3%	8681.34
Mar 10 15:00:00 - 15:59:59	180589	5.3%	50.16	23.6 GB	3.4%	6875.97
Mar 10 16:00:00 - 16:59:59	169526	5.0%	47.09	27.2 GB	3.9%	7913.32
Mar 10 17:00:00 - 17:59:59	150194	4.4%	41.72	33.1 GB	4.8%	9654.37
Mar 10 18:00:00 - 18:59:59	177606	5.2%	49.34	33.0 GB	4.8%	9598.17
Mar 10 19:00:00 - 19:59:59	133023	3.9%	36.95	33.2 GB	4.8%	9679.90
Mar 10 20:00:00 - 20:59:59	159937	4.7%	44.43	33.3 GB	4.8%	9694.59
Mar 10 21:00:00 - 21:59:59	158324	4.7%	43.98	31.4 GB	4.5%	9145.01
Mar 10 22:00:00 - 22:59:59	157532	4.6%	43.76	31.1 GB	4.5%	9068.94
Mar 10 23:00:00 - 23:59:59	159037	4.7%	44.18	29.2 GB	4.2%	8510.36
Mar 11 00:00:00 - 00:59:59	124185	3.7%	34.50	24.3 GB	3.5%	7082.73
Mar 11 01:00:00 - 01:59:59	131199	3.9%	36.44	27.4 GB	4.0%	7972.68
Mar 11 02:00:00 - 02:59:59	137150	4.0%	38.10	30.3 GB	4.4%	8831.73
Mar 11 03:00:00 - 03:59:59	108459	3.2%	30.13	23.6 GB	3.4%	6881.29
Mar 11 04:00:00 - 04:15:02	24276	0.7%	26.91	6.6 GB	1.0%	7674.45
TOTAL: 24:00:00	3400574	100.0%	39.36	692.3 GB	100.0%	8401.42

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5635	136	3851	1	1647
2	news.nask.pl	3817	27	199	2	3589
3	news.icm.edu.pl	21	0	0	0	21
TOTAL: 3		9473	163	4050	3	5257

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	84
it.comp.os.win.windows10	26
comp.mobile.ipad	16
de.rec.sf.startrek	13
comp.mobile.android	10
it.tlc.cellulari.android	5
nl.comp.sys.android	3
nl.comp.os.linux.techniek	3
de.comp.lang.vbclassic	1
it.comp.os.win.windows7	1
uk.rec.cycling.moderated	1
TOTAL: 11	163

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3292
Binary in non-binary group	1292
EMP (phl)	43
Scoring filter (8)	27
Scoring filter (18)	21
Scoring filter (10)	5
Scoring filter (9)	4
HTML post	2
Too many newsgroups	1
Scoring filter (19)	1
EMP (fsl)	1
Scoring filter (12)	1
Scoring filter (16)	1
TOTAL: 13	4691

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	52293	1255	23213	323	218	41034	2%	23:59:57
2	poznan	14323	379	8444	25	0	0	2%	23:59:55
3	icm	22122	46	16091	1	0	0	0%	23:59:54
4	nask	3823	14	2522	0	0	0	0%	23:59:55
5	okapi	0	0	0	0	0	1027	0%	23:57:50
TOTAL: 5		92561	1694	50270	349	218	42061	1%	119:57:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	9.6 MB	2.3 KB	9.6 MB	0.1 KB/s	209.8 KB	23:59:54
2	panorama	5.5 MB	1.0 MB	6.5 MB	0.1 KB/s	4.2 KB	23:59:57
3	poznan	1.4 MB	449.3 KB	1.9 MB	0.0 KB/s	4.7 KB	23:59:55
4	nask	25.0 KB	0.0 KB	25.0 KB	0.0 KB/s	1.8 KB	23:59:55
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		16.6 MB	1.4 MB	18.0 MB	0.0 KB/s	9.0 KB	119:57:31

Backlog files shrunk by innfeed:

Server	Size
panorama	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	84	86.6 KB	37	0	00:00:25
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	84	86.6 KB	37	2	00:14:09

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	674	84	86.6 KB	37	2	00:00:30
2	*.man.tk-internet.pl	1	0	0.0 KB	0	0	00:10:31
3	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:03:05
4	*.census.shodan.io	1	0	0.0 KB	0	0	00:00:02
TOTAL: 4		678	84	86.6 KB	37	2	00:14:09

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
h121140.man.tk-internet.pl	0.020	0.012	00:10:31
bns90.neoplus.adsl.tpnet.pl	0.012	0.048	00:03:05
web.man.lodz.pl	1.472	6.128	00:00:25
mon.man.lodz.pl	1.332	5.824	00:00:03
mason.census.shodan.io	0.000	0.024	00:00:02
david.man.lodz.pl	0.384	1.852	00:00:01
isvcs01.man.lodz.pl	0.004	0.048	00:00:00
TOTAL: 7	3.224	13.936	00:14:09

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
bns90.neoplus.adsl.tpnet.pl	2
h121140.man.tk-internet.pl	1
mason.census.shodan.io	1
TOTAL: 5	388

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	66
2	pl.comp.pecet	18
TOTAL: 2		84

Daily Usenet report

Mar 10 04:15:02 -- Mar 11 04:15:02