Daily Usenet report: Dec 7 04:15:04

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Dec 7 06:24:55 newsfeed overchan[10480]: input line too long, skipping
Dec 7 06:52:16 newsfeed overchan[10480]: input line too long, skipping
Dec 7 09:58:18 newsfeed nnrpd[12149]: SERVER perl filter not defined
Dec 7 10:57:27 newsfeed nnrpd[14344]: SERVER perl filter not defined
Dec 7 11:02:15 newsfeed nnrpd[14469]: SERVER perl filter not defined
Dec 7 11:07:25 newsfeed nnrpd[14726]: SERVER perl filter not defined
Dec 7 14:43:27 newsfeed nnrpd[22490]: SERVER perl filter not defined
Dec 7 15:40:19 newsfeed overchan[10480]: input line too long, skipping
Dec 7 15:49:26 newsfeed nnrpd[24932]: SERVER perl filter not defined
Dec 7 16:40:45 newsfeed nnrpd[26838]: SERVER perl filter not defined
Dec 7 17:18:15 newsfeed nnrpd[28457]: SERVER perl filter not defined
Dec 7 17:20:12 newsfeed nnrpd[28527]: SERVER perl filter not defined
Dec 7 17:20:32 newsfeed overchan[10480]: input line too long, skipping
Dec 7 19:17:55 newsfeed overchan[10480]: input line too long, skipping
Dec 8 00:06:42 newsfeed nnrpd[10969]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4133268	98.2%	464.5 MB	97.9%
innfeed	41854	1.0%	5.0 MB	1.0%
innd	27049	0.6%	4.5 MB	0.9%
nnrpd	4812	0.1%	507.5 KB	0.1%
overchan	6	0.0%	1.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	4206990	100.0%	474.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	4107051	98.4%
Negative hits	55206	1.3%
Positive hits	10376	0.2%
Cache misses	194	0.0%
TOTAL: 4	4172827	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:12.857	0.0%	213	0.000	60.362	2531.500
article cleanup	00:00:45.789	0.1%	4133510	0.009	0.011	0.020
article logging	00:02:19.386	0.2%	4134191	0.025	0.034	0.058
article parse	00:53:45.555	3.7%	87545141	0.027	0.037	0.059
article write	00:21:27.863	1.5%	4120780	0.154	0.313	0.603
artlog/artcncl	00:00:00.005	0.0%	212	0.000	0.024	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:16.312	0.2%	103424716	0.001	0.001	0.008
hisgrep/artcncl	00:00:01.323	0.0%	189	0.000	7.000	200.500
hishave/artcncl	00:00:00.610	0.0%	213	0.000	2.864	78.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:15:51.959	9.4%	4191349	0.483	1.945	7.149
history sync	00:00:00.009	0.0%	290	0.000	0.031	0.500
history write	05:40:09.488	23.6%	4124810	2.154	4.948	12.403
hiswrite/artcncl	00:00:00.328	0.0%	24	0.000	13.667	193.000
idle	07:24:03.650	30.8%	50361494	0.132	0.529	1.398
nntp read	00:46:36.720	3.2%	84008230	0.024	0.033	0.054
overview write	00:00:18.597	0.0%	4120780	0.003	0.005	0.008
perl filter	06:02:22.361	25.2%	4124804	3.236	5.271	8.927
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:58.363	0.4%	8260843	0.033	0.043	0.072
TOTAL: 24:00:06.441	23:36:11.175	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.148	0.0%	61746	0.000	0.002	0.015
article prepare	00:00:00.005	0.0%	19210	0.000	0.000	0.015
article read	00:02:45.000	0.2%	4868	1.467	33.895	197.261
backlog stats	00:00:00.150	0.0%	166622	0.000	0.001	0.004
callbacks	00:00:00.001	0.0%	81	0.000	0.012	0.500
data read	00:00:01.382	0.0%	89209	0.003	0.015	0.042
data write	00:00:03.898	0.0%	70828	0.019	0.055	0.158
idle	23:51:29.607	99.8%	166622	269.400	515.476	1044.190
status file	00:00:15.234	0.0%	15255	0.600	0.999	4.900
TOTAL: 23:54:45.266	23:54:35.425	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:25.117	67.8%	3080	0.000	8.155	248.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.223	0.6%	6740	0.000	0.033	0.500
readart	00:00:00.002	0.0%	28	0.000	0.071	0.500
TOTAL: 00:00:37.031	00:00:25.342	68.4%	-	-	-	-

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	473	4117808	4108902	384	8522	99%	548:30:59
2	news.nask.pl	2	16171	10792	1113	4266	66%	24:22:46
3	news.icm.edu.pl	40	319	42	264	13	13%	18:31:01
4	localhost	11	10	10	0	0	100%	00:00:01
TOTAL: 4		526	4134308	4119746	1761	12801	99%	591:24:47

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	850.2 GB	41.5 MB	51.8 MB	850.3 GB	99%	216.6 KB
2	news.nask.pl	62.1 MB	1.4 MB	19.1 MB	82.5 MB	75%	5.6 KB
3	news.icm.edu.pl	1.5 MB	95.8 KB	555.5 KB	2.1 MB	69%	39.3 KB
4	localhost	14.4 KB	0.0 KB	0.0 KB	14.4 KB	100%	1.4 KB
TOTAL: 4		850.3 GB	43.0 MB	71.4 MB	850.4 GB	99%	215.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 7 04:15:04 - 04:59:59	87744	2.1%	32.55	33.4 GB	3.9%	12973.65
Dec 7 05:00:00 - 05:59:59	113906	2.8%	31.64	43.7 GB	5.2%	12741.69
Dec 7 06:00:00 - 06:59:59	118844	2.9%	33.01	41.9 GB	4.9%	12198.64
Dec 7 07:00:00 - 07:59:59	130727	3.2%	36.31	42.3 GB	5.0%	12312.05
Dec 7 08:00:00 - 08:59:59	269542	6.5%	74.87	28.6 GB	3.4%	8334.41
Dec 7 09:00:00 - 09:59:59	263049	6.4%	73.07	36.0 GB	4.2%	10487.82
Dec 7 10:00:00 - 10:59:59	254522	6.2%	70.70	37.7 GB	4.4%	10972.20
Dec 7 11:00:00 - 11:59:59	213927	5.2%	59.42	36.8 GB	4.3%	10723.61
Dec 7 12:00:00 - 12:59:59	127014	3.1%	35.28	39.2 GB	4.6%	11431.10
Dec 7 13:00:00 - 13:59:59	165965	4.0%	46.10	39.6 GB	4.7%	11525.89
Dec 7 14:00:00 - 14:59:59	274953	6.7%	76.38	33.2 GB	3.9%	9656.59
Dec 7 15:00:00 - 15:59:59	225913	5.5%	62.75	29.1 GB	3.4%	8473.06
Dec 7 16:00:00 - 16:59:59	219042	5.3%	60.84	30.4 GB	3.6%	8842.91
Dec 7 17:00:00 - 17:59:59	231709	5.6%	64.36	29.6 GB	3.5%	8630.70
Dec 7 18:00:00 - 18:59:59	219682	5.3%	61.02	32.3 GB	3.8%	9411.55
Dec 7 19:00:00 - 19:59:59	137001	3.3%	38.06	35.6 GB	4.2%	10375.83
Dec 7 20:00:00 - 20:59:59	100632	2.4%	27.95	30.8 GB	3.6%	8978.82
Dec 7 21:00:00 - 21:59:59	148078	3.6%	41.13	35.5 GB	4.2%	10352.14
Dec 7 22:00:00 - 22:59:59	168631	4.1%	46.84	35.4 GB	4.2%	10314.76
Dec 7 23:00:00 - 23:59:59	152712	3.7%	42.42	37.4 GB	4.4%	10886.55
Dec 8 00:00:00 - 00:59:59	119817	2.9%	33.28	29.6 GB	3.5%	8608.32
Dec 8 01:00:00 - 01:59:59	134707	3.3%	37.42	39.2 GB	4.6%	11406.97
Dec 8 02:00:00 - 02:59:59	121195	2.9%	33.67	36.0 GB	4.2%	10491.40
Dec 8 03:00:00 - 03:59:59	95029	2.3%	26.40	28.5 GB	3.4%	8313.99
Dec 8 04:00:00 - 04:15:04	25292	0.6%	27.98	7.6 GB	0.9%	8847.95
TOTAL: 24:00:00	4119633	100.0%	47.68	849.4 GB	100.0%	10308.69

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	8892	180	8226	1	485
2	news.nask.pl	4518	20	485	3	4010
3	news.icm.edu.pl	13	0	3	0	10
TOTAL: 3		13423	200	8714	4	4505

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	142
it.comp.os.win.windows10	24
comp.mobile.android	16
it.tlc.cellulari.android	6
comp.mobile.ipad	2
nl.comp.os.linux.techniek	2
comp.sys.raspberry-pi	2
it.comp.os.linux.ubuntu	2
it.comp.os.win.windows7	2
de.comp.office-pakete.ms-office.excel	1
alt.sex.fetish.wrestling.male	1
TOTAL: 11	200

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3503
Binary in non-binary group	203
Scoring filter (9)	24
EMP (phl)	24
Scoring filter (8)	22
HTML post	13
Scoring filter (13)	9
Scoring filter (16)	8
Too many newsgroups	6
Scoring filter (10)	4
Scoring filter (11)	2
Scoring filter (18)	2
TOTAL: 12	3820

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	364
	news.nask.pl	315
	TOTAL: 2	679
TOTAL: 1		679

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	49488	1960	21581	482	209	37168	3%	23:59:58
2	poznan	16285	770	9861	40	0	1	4%	23:59:43
3	icm	22954	83	18588	347	0	398	0%	23:59:58
4	okapi	0	0	0	0	0	1208	0%	23:57:50
5	nask	2101	0	1736	0	0	0	0%	22:25:45
TOTAL: 5		90828	2813	51766	869	209	38775	3%	118:23:14

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	12.3 MB	4.8 MB	17.1 MB	0.2 KB/s	7.2 KB	23:59:58
2	icm	7.2 MB	98.1 MB	105.3 MB	1.2 KB/s	250.8 KB	23:59:58
3	poznan	2.4 MB	393.4 KB	2.8 MB	0.0 KB/s	3.6 KB	23:59:43
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:25:45
TOTAL: 5		22.0 MB	103.2 MB	125.2 MB	0.3 KB/s	34.8 KB	118:23:14

Backlog files shrunk by innfeed:

Server	Size
panorama	976.7 KB
TOTAL: 1	976.7 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	28	26.4 KB	14	0	00:00:30
2	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	00:00:01
TOTAL: 2		298	28	26.4 KB	14	10	00:00:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	682	28	26.4 KB	14	10	0	00:00:36
TOTAL: 1		682	28	26.4 KB	14	10	0	00:00:36

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.224	7.040	00:00:30
mon.man.lodz.pl	1.004	7.100	00:00:03
isvcs01.man.lodz.pl	0.100	0.324	00:00:01
david.man.lodz.pl	0.352	2.364	00:00:01
TOTAL: 4	2.680	16.828	00:00:36

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

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.pecet	20
2	pl.comp.os.ms-windows.winnt	8
TOTAL: 2		28

Daily Usenet report

Dec 7 04:15:04 -- Dec 8 04:15:04