Daily Usenet report: Sep 14 04:15:05

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Sep 14 12:34:10 newsfeed overchan[14034]: input line too long, skipping
Sep 14 13:02:11 newsfeed nnrpd[1642]: SERVER perl filter not defined
Sep 14 13:03:19 newsfeed nnrpd[1654]: SERVER perl filter not defined
Sep 14 13:04:47 newsfeed nnrpd[1681]: SERVER perl filter not defined
Sep 14 13:06:33 newsfeed nnrpd[1900]: SERVER perl filter not defined
Sep 14 15:02:38 newsfeed nnrpd[6106]: SERVER perl filter not defined
Sep 14 15:59:21 newsfeed overchan[14034]: input line too long, skipping
Sep 14 16:05:44 newsfeed overchan[14034]: input line too long, skipping
Sep 14 16:11:16 newsfeed overchan[14034]: input line too long, skipping
Sep 14 16:14:44 newsfeed overchan[14034]: input line too long, skipping
Sep 14 16:20:31 newsfeed overchan[14034]: input line too long, skipping
Sep 14 16:23:01 newsfeed nnrpd[9026]: SERVER perl filter not defined
Sep 14 16:31:23 newsfeed nnrpd[9362]: SERVER perl filter not defined
Sep 14 18:20:02 newsfeed nnrpd[13366]: SERVER perl filter not defined
Sep 14 22:55:13 newsfeed nnrpd[23377]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2614928	96.7%	303.9 MB	96.3%
innd	44442	1.6%	6.3 MB	2.0%
innfeed	41245	1.5%	4.9 MB	1.5%
nnrpd	4861	0.2%	505.8 KB	0.2%
overchan	6	0.0%	1.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2705483	100.0%	315.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2545819	76.0%
Negative hits	665888	19.9%
Positive hits	136783	4.1%
Cache misses	656	0.0%
TOTAL: 4	3349146	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.201	0.0%	214	0.000	28.977	383.000
article cleanup	00:00:42.358	0.0%	2614086	0.012	0.016	0.022
article logging	00:01:56.743	0.1%	2614739	0.033	0.045	0.191
article parse	01:19:29.289	5.5%	165168076	0.018	0.029	0.042
article write	00:24:52.208	1.7%	2532225	0.464	0.589	1.003
artlog/artcncl	00:00:00.001	0.0%	195	0.000	0.005	0.056
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:03:25.655	0.2%	182333109	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.557	0.0%	206	0.000	2.704	21.000
hishave/artcncl	00:00:00.971	0.0%	214	0.000	4.537	49.263
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:38:25.287	2.7%	3364777	0.084	0.685	4.666
history sync	00:00:00.020	0.0%	290	0.000	0.069	1.000
history write	01:35:30.361	6.6%	2558530	0.466	2.240	7.799
hiswrite/artcncl	00:00:00.002	0.0%	8	0.000	0.250	1.000
idle	11:31:59.263	48.1%	108582383	0.222	0.382	0.611
nntp read	01:28:50.048	6.2%	163398501	0.023	0.033	0.041
overview write	00:00:15.684	0.0%	2532225	0.004	0.006	0.008
perl filter	06:01:55.229	25.1%	2558528	7.602	8.487	9.491
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:43.546	0.3%	5265518	0.039	0.054	0.070
TOTAL: 24:00:01.681	23:12:13.423	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.174	0.0%	391539	0.000	0.003	0.009
article prepare	00:00:00.034	0.0%	73244	0.000	0.000	0.015
article read	00:02:07.454	0.1%	17683	0.441	7.208	271.333
backlog stats	00:00:00.839	0.0%	1096467	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	7	0.000	0.000	0.000
data read	00:00:13.335	0.0%	714892	0.008	0.019	0.053
data write	00:00:57.771	0.1%	528068	0.026	0.109	0.385
idle	23:49:16.390	99.7%	1096467	35.524	78.212	839.497
status file	00:00:16.604	0.0%	16041	0.682	1.035	10.984
TOTAL: 23:53:32.790	23:52:53.601	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:47:27.705	99.3%	3144	0.000	905.759	600063.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.209	0.0%	7058	0.000	0.030	3.000
readart	00:00:00.005	0.0%	78	0.000	0.064	0.500
TOTAL: 00:47:49.199	00:47:27.919	99.3%	-	-	-	-

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	1136	2553672	2467016	15850	70806	96%	424:08:29
2	news.nask.pl	2	141801	64986	65717	11098	45%	24:19:01
3	news.icm.edu.pl	49	251	36	208	7	14%	16:27:49
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		1197	2695733	2532047	81775	81911	93%	464:55:19

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	834.7 GB	16.9 GB	20.6 GB	872.2 GB	95%	360.4 KB
2	news.nask.pl	18.8 GB	467.2 MB	1.5 GB	20.8 GB	90%	287.0 KB
3	news.icm.edu.pl	1.1 MB	0.0 KB	85.7 KB	1.1 MB	92%	27.4 KB
4	localhost	13.8 KB	0.0 KB	0.0 KB	13.8 KB	100%	1.5 KB
TOTAL: 4		853.5 GB	17.4 GB	22.2 GB	893.0 GB	95%	358.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 14 04:15:05 - 04:59:59	85006	3.4%	31.54	29.6 GB	3.5%	11519.40
Sep 14 05:00:00 - 05:59:59	107698	4.3%	29.92	38.6 GB	4.5%	11247.38
Sep 14 06:00:00 - 06:59:59	107963	4.3%	29.99	36.4 GB	4.3%	10604.61
Sep 14 07:00:00 - 07:59:59	104372	4.1%	28.99	35.1 GB	4.1%	10231.12
Sep 14 08:00:00 - 08:59:59	96474	3.8%	26.80	31.3 GB	3.7%	9117.60
Sep 14 09:00:00 - 09:59:59	95149	3.8%	26.43	30.3 GB	3.5%	8816.72
Sep 14 10:00:00 - 10:59:59	113164	4.5%	31.43	35.7 GB	4.2%	10389.48
Sep 14 11:00:00 - 11:59:59	119331	4.7%	33.15	38.2 GB	4.5%	11132.67
Sep 14 12:00:00 - 12:59:59	112889	4.5%	31.36	37.3 GB	4.4%	10855.92
Sep 14 13:00:00 - 13:59:59	112538	4.4%	31.26	37.8 GB	4.4%	11021.00
Sep 14 14:00:00 - 14:59:59	102670	4.1%	28.52	35.2 GB	4.1%	10258.60
Sep 14 15:00:00 - 15:59:59	107672	4.3%	29.91	36.8 GB	4.3%	10717.86
Sep 14 16:00:00 - 16:59:59	94056	3.7%	26.13	32.8 GB	3.9%	9567.41
Sep 14 17:00:00 - 17:59:59	109746	4.3%	30.48	38.0 GB	4.5%	11059.22
Sep 14 18:00:00 - 18:59:59	93861	3.7%	26.07	31.8 GB	3.7%	9263.11
Sep 14 19:00:00 - 19:59:59	86472	3.4%	24.02	28.2 GB	3.3%	8218.88
Sep 14 20:00:00 - 20:59:59	105346	4.2%	29.26	34.4 GB	4.0%	10032.51
Sep 14 21:00:00 - 21:59:59	101413	4.0%	28.17	34.8 GB	4.1%	10127.25
Sep 14 22:00:00 - 22:59:59	110594	4.4%	30.72	36.2 GB	4.2%	10553.87
Sep 14 23:00:00 - 23:59:59	118918	4.7%	33.03	37.8 GB	4.4%	11010.31
Sep 15 00:00:00 - 00:59:59	92829	3.7%	25.79	30.7 GB	3.6%	8951.32
Sep 15 01:00:00 - 01:59:59	118542	4.7%	32.93	41.4 GB	4.9%	12054.58
Sep 15 02:00:00 - 02:59:59	121249	4.8%	33.68	44.0 GB	5.2%	12809.29
Sep 15 03:00:00 - 03:59:59	88655	3.5%	24.63	31.2 GB	3.7%	9088.66
Sep 15 04:00:00 - 04:15:02	25609	1.0%	28.39	9.3 GB	1.1%	10778.03
TOTAL: 23:59:57	2532216	100.0%	29.31	853.0 GB	100.0%	10352.59

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	71165	183	53858	3	17121
2	news.nask.pl	11345	9	1700	2	9634
3	news.icm.edu.pl	7	0	0	0	7
TOTAL: 3		82517	192	55558	5	26762

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	107
it.comp.os.win.windows10	37
it.tlc.cellulari.android	27
it.comp.os.win.windows7	8
comp.mobile.android	6
hr.ponuda.hardver.komponente	1
hr.rec.glazba.hifi	1
de.comp.office-pakete.ms-office.excel	1
alt.sex.telephones	1
it.comp.virtualizzazione	1
de.comp.lang.perl	1
uk.rec.cycling.moderated	1
TOTAL: 12	192

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	21790
EMP (md5)	4068
Scoring filter (8)	104
Scoring filter (11)	49
Scoring filter (10)	37
EMP (phl)	26
Scoring filter (9)	23
HTML post	7
Too many newsgroups	2
Scoring filter (13)	1
TOTAL: 10	26107

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	354
	news.nask.pl	299
	TOTAL: 2	653
TOTAL: 1		653

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	178339	13123	114511	15	0	0	7%	23:59:57
2	panorama	215845	1795	186988	501	16	205172	0%	23:59:57
3	poznan	14562	250	8751	61	0	0	1%	23:59:55
4	icm	220757	51	198033	1960	0	0	0%	23:59:42
5	okapi	0	0	0	0	0	1759	0%	23:52:53
TOTAL: 5		629503	15219	508283	2537	16	206931	2%	119:52:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	4.4 GB	4.8 MB	4.4 GB	53.6 KB/s	352.5 KB	23:59:57
2	panorama	8.0 MB	4.9 MB	12.9 MB	0.2 KB/s	5.7 KB	23:59:57
3	icm	7.2 MB	684.5 MB	691.8 MB	8.2 KB/s	352.2 KB	23:59:42
4	poznan	1.1 MB	1.6 MB	2.7 MB	0.0 KB/s	8.9 KB	23:59:55
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:52:53
TOTAL: 5		4.4 GB	695.9 MB	5.1 GB	12.4 KB/s	301.6 KB	119:52:24

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
TOTAL: 1	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	78	77.7 KB	36	0	00:00:27
2	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:01
TOTAL: 2		297	78	77.7 KB	36	9	00:47:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	681	78	77.7 KB	36	9	00:00:37
2	*.ostnet.pl	5	0	0.0 KB	0	0	00:47:10
TOTAL: 2		686	78	77.7 KB	36	9	00:47:48

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
static62133157114.ostnet.pl	0.076	0.112	00:36:52
clif140243.ostnet.pl	0.184	0.080	00:10:17
web.man.lodz.pl	1.444	5.480	00:00:27
mon.man.lodz.pl	1.244	5.668	00:00:06
david.man.lodz.pl	0.436	1.680	00:00:02
isvcs01.man.lodz.pl	0.068	0.176	00:00:01
TOTAL: 6	3.452	13.196	00:47:48

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
static62133157114.ostnet.pl	3
clif140243.ostnet.pl	2
TOTAL: 4	389

NNRP client timeouts [Top 20]:

System	Conn	Peer
static62133157114.ostnet.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Sep 14 04:15:05 -- Sep 15 04:15:02