Daily Usenet report: Oct 7 04:15:02

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Oct 7 08:26:25 newsfeed nnrpd[16544]: SERVER perl filter not defined
Oct 7 08:59:19 newsfeed nnrpd[17808]: SERVER perl filter not defined
Oct 7 09:24:39 newsfeed nnrpd[18682]: SERVER perl filter not defined
Oct 7 14:35:47 newsfeed nnrpd[29991]: SERVER perl filter not defined
Oct 7 14:39:46 newsfeed nnrpd[30196]: SERVER perl filter not defined
Oct 7 16:57:48 newsfeed overchan[6639]: input line too long, skipping
Oct 7 17:19:43 newsfeed overchan[6639]: input line too long, skipping
Oct 7 17:23:27 newsfeed overchan[6639]: input line too long, skipping
Oct 7 17:41:56 newsfeed overchan[6639]: input line too long, skipping
Oct 7 20:38:18 newsfeed nnrpd[10912]: SERVER perl filter not defined
Oct 7 20:40:31 newsfeed nnrpd[10997]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2603359	97.0%	302.5 MB	96.7%
innfeed	43292	1.6%	5.0 MB	1.6%
innd	32915	1.2%	4.8 MB	1.5%
nnrpd	4772	0.2%	503.2 KB	0.2%
overchan	5	0.0%	0.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2684344	100.0%	312.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2569379	94.4%
Negative hits	136283	5.0%
Positive hits	17295	0.6%
Cache misses	131	0.0%
TOTAL: 4	2723088	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.355	0.0%	206	0.000	6.578	50.000
article cleanup	00:00:31.377	0.0%	2604083	0.009	0.012	0.018
article logging	00:01:19.453	0.1%	2604666	0.024	0.031	0.134
article parse	01:11:51.076	5.0%	253426405	0.009	0.017	0.030
article write	00:23:58.744	1.7%	2579329	0.311	0.558	1.328
artlog/artcncl	00:00:00.004	0.0%	202	0.000	0.020	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:57.978	0.2%	271483384	0.000	0.001	0.001
hisgrep/artcncl	00:00:00.277	0.0%	204	0.000	1.358	48.000
hishave/artcncl	00:00:00.039	0.0%	206	0.000	0.189	1.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:39:32.354	2.7%	2758918	0.055	0.860	6.195
history sync	00:00:00.006	0.0%	290	0.000	0.021	0.500
history write	01:40:03.274	6.9%	2594819	0.248	2.314	9.964
hiswrite/artcncl	00:00:00.097	0.0%	2	1.000	48.500	96.000
idle	12:36:03.922	52.5%	184024431	0.171	0.247	0.500
nntp read	01:28:17.764	6.1%	251461643	0.012	0.021	0.029
overview write	00:00:12.076	0.0%	2579329	0.004	0.005	0.006
perl filter	05:07:51.987	21.4%	2594818	4.964	7.119	8.409
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:37.774	0.3%	5180222	0.032	0.042	0.052
TOTAL: 24:00:02.204	23:16:19.557	97.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.194	0.0%	77596	0.000	0.003	0.014
article prepare	00:00:00.003	0.0%	12696	0.000	0.000	0.010
article read	00:00:44.221	0.1%	3163	0.000	13.981	702.500
backlog stats	00:00:00.149	0.0%	186748	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.553	0.0%	96315	0.000	0.016	0.045
data write	00:00:06.620	0.0%	81096	0.016	0.082	0.383
idle	24:03:06.370	99.9%	186748	189.890	463.654	967.684
status file	00:00:14.949	0.0%	16603	0.433	0.900	12.433
TOTAL: 24:04:25.081	24:04:14.059	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:19.791	62.1%	3043	0.000	6.504	31.889
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.142	0.4%	6646	0.000	0.021	1.000
readart	00:00:00.000	0.0%	12	0.000	0.000	0.000
TOTAL: 00:00:31.862	00:00:19.933	62.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	1089	2589881	2570632	5771	13478	99%	386:56:59
2	news.nask.pl	3	21191	8046	2674	10471	37%	24:00:48
3	news.icm.edu.pl	47	396	25	313	58	6%	20:06:30
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		1147	2611475	2578710	8758	24007	98%	431:04:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	916.9 GB	1.1 GB	2.0 GB	920.1 GB	99%	373.3 KB
2	news.nask.pl	58.4 MB	26.4 MB	1.5 GB	1.6 GB	3%	89.9 KB
3	news.icm.edu.pl	1.2 MB	19.8 KB	1.3 MB	2.5 MB	46%	31.1 KB
4	localhost	11.0 KB	0.0 KB	0.0 KB	11.0 KB	100%	1.6 KB
TOTAL: 4		917.0 GB	1.1 GB	3.5 GB	921.7 GB	99%	371.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 7 04:15:02 - 04:59:59	72853	2.8%	27.00	26.3 GB	2.9%	10210.33
Oct 7 05:00:00 - 05:59:59	96936	3.8%	26.93	32.7 GB	3.6%	9536.13
Oct 7 06:00:00 - 06:59:59	102364	4.0%	28.43	36.0 GB	3.9%	10472.51
Oct 7 07:00:00 - 07:59:59	122404	4.7%	34.00	41.3 GB	4.5%	12024.03
Oct 7 08:00:00 - 08:59:59	112772	4.4%	31.33	38.2 GB	4.2%	11122.56
Oct 7 09:00:00 - 09:59:59	116901	4.5%	32.47	41.5 GB	4.5%	12081.60
Oct 7 10:00:00 - 10:59:59	120512	4.7%	33.48	41.1 GB	4.5%	11976.41
Oct 7 11:00:00 - 11:59:59	113137	4.4%	31.43	40.2 GB	4.4%	11718.05
Oct 7 12:00:00 - 12:59:59	114633	4.4%	31.84	41.1 GB	4.5%	11972.85
Oct 7 13:00:00 - 13:59:59	115142	4.5%	31.98	40.4 GB	4.4%	11773.79
Oct 7 14:00:00 - 14:59:59	107509	4.2%	29.86	39.3 GB	4.3%	11435.17
Oct 7 15:00:00 - 15:59:59	101332	3.9%	28.15	37.4 GB	4.1%	10906.96
Oct 7 16:00:00 - 16:59:59	110051	4.3%	30.57	39.0 GB	4.3%	11372.06
Oct 7 17:00:00 - 17:59:59	116097	4.5%	32.25	39.3 GB	4.3%	11437.83
Oct 7 18:00:00 - 18:59:59	116611	4.5%	32.39	40.3 GB	4.4%	11748.79
Oct 7 19:00:00 - 19:59:59	104603	4.1%	29.06	39.0 GB	4.2%	11351.10
Oct 7 20:00:00 - 20:59:59	109195	4.2%	30.33	41.0 GB	4.5%	11927.99
Oct 7 21:00:00 - 21:59:59	106022	4.1%	29.45	39.8 GB	4.3%	11601.64
Oct 7 22:00:00 - 22:59:59	102534	4.0%	28.48	37.2 GB	4.1%	10848.04
Oct 7 23:00:00 - 23:59:59	108883	4.2%	30.25	38.5 GB	4.2%	11214.66
Oct 8 00:00:00 - 00:59:59	76147	3.0%	21.15	25.7 GB	2.8%	7488.68
Oct 8 01:00:00 - 01:59:59	103652	4.0%	28.79	37.6 GB	4.1%	10949.59
Oct 8 02:00:00 - 02:59:59	110082	4.3%	30.58	39.6 GB	4.3%	11541.90
Oct 8 03:00:00 - 03:59:59	95690	3.7%	26.58	35.8 GB	3.9%	10435.88
Oct 8 04:00:00 - 04:15:02	22693	0.9%	25.16	8.6 GB	0.9%	9957.56
TOTAL: 24:00:00	2578755	100.0%	29.85	917.0 GB	100.0%	11128.55

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	13718	70	8538	0	5110
2	news.nask.pl	10632	18	436	4	10174
3	news.icm.edu.pl	52	0	2	0	50
TOTAL: 3		24402	88	8976	4	15334

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	23
it.comp.os.win.windows10	17
de.rec.sf.startrek	13
nl.comp.os.linux.techniek	10
comp.mobile.android	8
de.comp.lang.php	6
it.comp.os.win.windows7	5
it.tlc.cellulari.android	4
de.comp.lang.vbclassic	1
alt.sex.telephones	1
TOTAL: 10	88

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
Binary in non-binary group	11780
EMP (md5)	2829
Poison newsgroup	66
Scoring filter (9)	43
EMP (phl)	30
Scoring filter (8)	29
HTML post	5
Scoring filter (10)	5
Too many newsgroups	4
Scoring filter (12)	3
Scoring filter (14)	3
Scoring filter (13)	1
Scoring filter (11)	1
TOTAL: 13	14799

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	294
	newsfeed.pionier.net.pl	240
	TOTAL: 2	534
TOTAL: 1		534

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	60596	510	32402	146	43604	0%	23:59:57
2	poznan	12918	329	7565	51	1	2%	23:59:57
3	icm	24175	68	16983	1309	0	0%	23:59:54
4	nask	3973	6	2487	404	0	0%	23:59:57
5	okapi	0	0	0	0	994	0%	23:57:50
TOTAL: 5		101662	913	59437	1910	44599	0%	119:57:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	9.0 MB	1.9 MB	10.9 MB	0.1 KB/s	17.0 KB	23:59:57
2	icm	7.4 MB	552.6 MB	560.0 MB	6.6 KB/s	416.4 KB	23:59:54
3	poznan	1.3 MB	531.9 KB	1.8 MB	0.0 KB/s	4.8 KB	23:59:57
4	nask	269.2 KB	257.8 MB	258.1 MB	3.1 KB/s	644.5 KB	23:59:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		17.9 MB	812.8 MB	830.7 MB	2.0 KB/s	301.3 KB	119:57:35

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	12	9.5 KB	6	0	00:00:24
2	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 2		295	12	9.5 KB	6	7	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	679	12	9.5 KB	6	7	0	00:00:31
TOTAL: 1		679	12	9.5 KB	6	7	0	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.120	4.316	00:00:24
mon.man.lodz.pl	1.120	4.932	00:00:04
david.man.lodz.pl	0.292	1.604	00:00:01
isvcs01.man.lodz.pl	0.044	0.128	00:00:00
TOTAL: 4	2.576	10.980	00:00:31

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	12	100.0%
TOTAL: 1		12	100.0%

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

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

Daily Usenet report

Oct 7 04:15:02 -- Oct 8 04:15:02