Daily Usenet report: Dec 13 04:15:03

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Dec 13 07:14:54 newsfeed overchan[5254]: input line too long, skipping
Dec 13 10:33:51 newsfeed nnrpd[20150]: SERVER perl filter not defined
Dec 13 11:24:40 newsfeed nnrpd[22090]: SERVER perl filter not defined
Dec 13 15:08:39 newsfeed nnrpd[30769]: SERVER perl filter not defined
Dec 13 16:06:17 newsfeed overchan[5254]: input line too long, skipping
Dec 13 18:31:14 newsfeed overchan[5254]: input line too long, skipping
Dec 13 18:49:04 newsfeed overchan[1066]: input line too long, skipping
Dec 13 19:25:46 newsfeed overchan[1066]: input line too long, skipping
Dec 13 20:46:48 newsfeed overchan[1066]: input line too long, skipping
Dec 13 23:50:35 newsfeed overchan[1066]: input line too long, skipping
Dec 13 23:51:07 newsfeed overchan[1066]: input line too long, skipping
Dec 13 23:53:29 newsfeed overchan[1066]: input line too long, skipping
Dec 13 23:56:07 newsfeed overchan[1066]: input line too long, skipping
Dec 13 23:56:45 newsfeed overchan[1066]: input line too long, skipping
Dec 14 00:22:07 newsfeed nnrpd[16874]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2868739	96.5%	329.6 MB	96.3%
innd	61852	2.1%	7.9 MB	2.3%
innfeed	37503	1.3%	4.4 MB	1.3%
nnrpd	4769	0.2%	496.1 KB	0.1%
overchan	12	0.0%	1.8 KB	0.0%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	2972877	100.0%	342.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2837416	96.2%
Negative hits	99551	3.4%
Positive hits	11675	0.4%
Cache misses	326	0.0%
TOTAL: 4	2948968	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.230	0.0%	264	0.000	23.598	519.000
article cleanup	00:00:33.428	0.0%	2867573	0.008	0.012	0.016
article logging	00:01:48.630	0.1%	2868296	0.029	0.038	0.080
article parse	00:59:20.323	4.1%	100493790	0.023	0.035	0.047
article write	00:25:04.342	1.7%	2812975	0.227	0.535	1.358
artlog/artcncl	00:00:00.008	0.0%	263	0.000	0.030	0.333
data move	00:03:53.507	0.3%	117209284	0.001	0.002	0.005
hisgrep/artcncl	00:00:02.569	0.0%	252	0.000	10.194	295.000
hishave/artcncl	00:00:00.435	0.0%	264	0.000	1.648	23.778
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:05:06.632	8.7%	2973768	0.605	2.524	6.095
history sync	00:00:00.012	0.0%	289	0.000	0.042	1.000
history write	04:51:10.753	20.2%	2859296	1.142	6.110	17.525
hiswrite/artcncl	00:00:00.088	0.0%	12	0.000	7.333	32.000
idle	09:11:13.640	38.3%	66294322	0.090	0.499	1.095
nntp read	00:53:57.626	3.7%	98401574	0.025	0.033	0.043
overview write	00:00:14.182	0.0%	2812975	0.004	0.005	0.007
perl filter	05:04:43.725	21.2%	2859286	4.631	6.395	7.661
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:34.447	0.3%	5658714	0.035	0.048	0.065
TOTAL: 24:00:06.334	23:41:50.577	98.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.214	0.0%	72829	0.000	0.003	0.014
article prepare	00:00:00.017	0.0%	58198	0.000	0.000	0.008
article read	00:01:21.797	0.1%	14526	0.341	5.631	192.889
backlog stats	00:00:00.236	0.0%	255481	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.979	0.0%	124866	0.000	0.016	0.031
data write	00:00:17.596	0.0%	124404	0.025	0.141	0.291
idle	23:52:08.877	99.9%	255481	67.911	336.342	961.090
status file	00:00:12.875	0.0%	13143	0.567	0.980	40.548
TOTAL: 23:54:17.396	23:54:03.591	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:25.360	60.8%	3040	0.000	8.342	9772.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.189	0.5%	6692	0.000	0.028	3.000
readart	00:00:00.000	0.0%	30	0.000	0.000	0.000
TOTAL: 00:00:41.731	00:00:25.549	61.2%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	150
name	3
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	195

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	527	2811875	2802532	1440	7903	99%	516:38:25
2	news.nask.pl	6	57058	9740	1932	45386	17%	24:05:41
3	news.icm.edu.pl	37	234	26	196	12	11%	16:53:07
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		575	2869171	2812302	3568	53301	98%	557:37:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	939.9 GB	42.1 MB	63.9 MB	940.0 GB	99%	350.7 KB
2	news.nask.pl	51.9 MB	1.8 MB	1.2 GB	1.2 GB	4%	23.2 KB
3	news.icm.edu.pl	528.2 KB	81.4 KB	214.5 KB	824.0 KB	64%	21.7 KB
4	localhost	6.6 KB	0.0 KB	0.0 KB	6.6 KB	100%	1.7 KB
TOTAL: 4		940.0 GB	44.0 MB	1.2 GB	941.3 GB	99%	344.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 13 04:15:03 - 04:59:59	43939	1.6%	16.29	16.1 GB	1.7%	6277.00
Dec 13 05:00:00 - 05:59:59	101075	3.6%	28.08	37.5 GB	4.0%	10910.02
Dec 13 06:00:00 - 06:59:59	117534	4.2%	32.65	41.3 GB	4.4%	12028.36
Dec 13 07:00:00 - 07:59:59	112324	4.0%	31.20	45.8 GB	4.9%	13346.03
Dec 13 08:00:00 - 08:59:59	116625	4.1%	32.40	45.2 GB	4.8%	13172.77
Dec 13 09:00:00 - 09:59:59	113324	4.0%	31.48	36.4 GB	3.9%	10604.60
Dec 13 10:00:00 - 10:59:59	124861	4.4%	34.68	39.6 GB	4.2%	11540.52
Dec 13 11:00:00 - 11:59:59	122618	4.4%	34.06	42.3 GB	4.5%	12308.81
Dec 13 12:00:00 - 12:59:59	110878	3.9%	30.80	41.7 GB	4.4%	12140.03
Dec 13 13:00:00 - 13:59:59	113706	4.0%	31.59	40.9 GB	4.4%	11927.16
Dec 13 14:00:00 - 14:59:59	121153	4.3%	33.65	41.7 GB	4.4%	12145.82
Dec 13 15:00:00 - 15:59:59	113156	4.0%	31.43	38.9 GB	4.1%	11335.97
Dec 13 16:00:00 - 16:59:59	112961	4.0%	31.38	37.0 GB	3.9%	10771.05
Dec 13 17:00:00 - 17:59:59	128401	4.6%	35.67	39.6 GB	4.2%	11535.73
Dec 13 18:00:00 - 18:59:59	119964	4.3%	33.32	40.0 GB	4.3%	11647.10
Dec 13 19:00:00 - 19:59:59	132739	4.7%	36.87	43.6 GB	4.6%	12700.03
Dec 13 20:00:00 - 20:59:59	131318	4.7%	36.48	45.5 GB	4.8%	13264.66
Dec 13 21:00:00 - 21:59:59	139106	4.9%	38.64	35.6 GB	3.8%	10379.05
Dec 13 22:00:00 - 22:59:59	159369	5.7%	44.27	35.4 GB	3.8%	10309.28
Dec 13 23:00:00 - 23:59:59	105073	3.7%	29.19	35.6 GB	3.8%	10364.86
Dec 14 00:00:00 - 00:59:59	97885	3.5%	27.19	32.8 GB	3.5%	9541.49
Dec 14 01:00:00 - 01:59:59	112976	4.0%	31.38	39.3 GB	4.2%	11454.61
Dec 14 02:00:00 - 02:59:59	127195	4.5%	35.33	42.5 GB	4.5%	12380.34
Dec 14 03:00:00 - 03:59:59	110360	3.9%	30.66	36.3 GB	3.9%	10559.27
Dec 14 04:00:00 - 04:15:02	26013	0.9%	28.84	9.6 GB	1.0%	11183.77
TOTAL: 23:59:59	2814553	100.0%	32.58	940.3 GB	100.0%	11411.47

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	45438	41	655	2	44740
2	newsfeed.pionier.net.pl	8463	122	7583	3	755
3	news.icm.edu.pl	14	0	5	0	9
TOTAL: 3		53915	163	8243	5	45504

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	74
comp.mobile.android	35
it.tlc.cellulari.android	19
comp.mobile.ipad	11
it.comp.os.win.windows10	9
it.comp.os.win.windows7	6
it.comp.os.linux.ubuntu	3
nl.comp.os.linux.techniek	3
uk.rec.cycling.moderated	2
it.discussioni.energia	1
TOTAL: 10	163

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
EMP (phl)	41377
EMP (md5)	2948
Binary in non-binary group	356
Scoring filter (9)	33
Scoring filter (8)	28
Scoring filter (10)	10
HTML post	10
Too many newsgroups	4
Scoring filter (13)	1
TOTAL: 9	44767

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	562
	news.nask.pl	163
	news.icm.edu.pl	2
	TOTAL: 3	727
TOTAL: 1		727

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	46346	950	23432	224	5	47944	2%	23:53:26
2	poznan	15983	404	9432	31	0	0	2%	23:55:58
3	icm	35838	74	20024	12043	0	0	0%	23:55:58
4	nask	3501	20	2885	0	0	0	0%	23:55:58
5	okapi	0	0	0	0	0	1248	0%	23:53:52
TOTAL: 5		101668	1448	55773	12298	5	49192	1%	119:35:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	9.3 MB	4.2 GB	4.2 GB	51.5 KB/s	366.0 KB	23:55:58
2	panorama	4.0 MB	954.2 KB	5.0 MB	0.1 KB/s	4.3 KB	23:53:26
3	poznan	1.4 MB	298.7 KB	1.7 MB	0.0 KB/s	3.9 KB	23:55:58
4	nask	65.5 KB	0.0 KB	65.5 KB	0.0 KB/s	3.3 KB	23:55:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:53:52
TOTAL: 5		14.8 MB	4.2 GB	4.2 GB	10.3 KB/s	323.1 KB	119:35:12

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	30	24.6 KB	15	0	00:00:21
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:05
TOTAL: 2		292	30	24.6 KB	15	4	00:00:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	30	24.6 KB	15	4	00:00:31
2	unresolved	1	0	0.0 KB	0	0	00:00:09
TOTAL: 2		677	30	24.6 KB	15	4	00:00:41

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.156	3.704	00:00:21
107.170.198.181	0.000	0.012	00:00:09
isvcs01.man.lodz.pl	0.036	0.080	00:00:05
mon.man.lodz.pl	0.992	3.380	00:00:03
david.man.lodz.pl	0.292	1.164	00:00:00
TOTAL: 5	2.476	8.340	00:00:41

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
107.170.198.181	1
TOTAL: 3	385

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Dec 13 04:15:03 -- Dec 14 04:15:02