Daily Usenet report: Nov 29 04:15:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Nov 29 04:20:29 newsfeed overchan[2656]: message repeated 19 times: [ input line too long, skipping]
Nov 29 16:14:50 newsfeed nnrpd[16577]: SERVER perl filter not defined
Nov 29 16:22:22 newsfeed nnrpd[16878]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2956780	97.9%	346.8 MB	97.5%
innfeed	36955	1.2%	4.4 MB	1.2%
innd	22263	0.7%	3.8 MB	1.1%
nnrpd	4989	0.2%	532.6 KB	0.1%
controlchan	6	0.0%	0.8 KB	0.0%
overchan	4	0.0%	0.4 KB	0.0%
TOTAL: 6	3020997	100.0%	355.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2951286	98.7%
Negative hits	25558	0.9%
Positive hits	13016	0.4%
Cache misses	96	0.0%
TOTAL: 4	2989956	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.787	0.0%	228	0.000	12.224	481.000
article cleanup	00:00:46.479	0.1%	2956355	0.006	0.016	0.029
article logging	00:01:18.548	0.1%	2956761	0.013	0.027	0.329
article parse	01:16:57.477	5.3%	272345309	0.006	0.017	0.075
article write	00:30:59.354	2.2%	2951709	0.304	0.630	2.093
artlog/artcncl	00:00:00.004	0.0%	195	0.000	0.021	0.500
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:03:48.502	0.3%	290816942	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.202	0.0%	187	0.000	1.080	72.000
hishave/artcncl	00:00:00.042	0.0%	228	0.000	0.184	9.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:32:27.456	2.3%	2991684	0.091	0.651	18.424
history sync	00:00:00.009	0.0%	291	0.000	0.031	1.000
history write	00:46:45.343	3.2%	2953049	0.160	0.950	46.947
hiswrite/artcncl	00:00:00.042	0.0%	41	0.000	1.024	11.000
idle	12:44:13.769	53.1%	191134218	0.093	0.240	0.510
nntp read	01:15:33.374	5.2%	269934500	0.008	0.017	0.036
overview write	00:00:16.780	0.0%	2951709	0.001	0.006	0.015
perl filter	05:41:49.731	23.7%	2953047	4.178	6.945	9.521
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:10.610	0.4%	5967423	0.025	0.062	0.101
TOTAL: 24:00:10.424	23:01:10.509	95.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.279	0.0%	108640	0.000	0.003	0.083
article prepare	00:00:00.007	0.0%	31150	0.000	0.000	0.250
article read	00:00:29.391	0.0%	7762	0.000	3.787	981.500
backlog stats	00:00:00.202	0.0%	244047	0.000	0.001	0.025
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.589	0.0%	143878	0.000	0.011	0.105
data write	00:00:05.579	0.0%	82890	0.000	0.067	1.303
idle	23:57:51.574	99.9%	244047	81.397	353.504	3190.158
status file	00:00:09.766	0.0%	12870	0.000	0.759	13.333
TOTAL: 23:58:48.426	23:58:38.387	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	08:48:16.389	99.9%	56844	0.000	557.603	600034.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:06.258	0.0%	236385	0.000	0.026	0.500
readart	00:00:02.035	0.0%	543	0.000	3.748	54.571
TOTAL: 08:48:41.981	08:48:24.682	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	148
name	2
newgroup	3
pause	2
paused	2
reload	2
reserve	2
TOTAL: 12	192

Newsgroups created:

Group	Mode
free.shqip.muzike	y
free.shqip.politike	y
free.shqip.sport	y
TOTAL	3

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
y.m@gmx.com	3	0	0	0	3	3
TOTAL	3	0	0	0	3	3

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	794	2952360	2948173	117	4070	99%	272:15:58
2	news.nask.pl	2	12873	3274	9178	421	25%	23:30:16
3	news.icm.edu.pl	37	383	65	168	150	16%	17:00:30
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		836	2965618	2951514	9463	4641	99%	312:46:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	794.8 GB	13.6 MB	126.0 MB	794.9 GB	99%	282.3 KB
2	news.nask.pl	11.0 MB	1006.1 KB	1.0 MB	13.0 MB	84%	3.6 KB
3	news.icm.edu.pl	3.3 MB	116.3 KB	10.4 MB	13.8 MB	23%	65.7 KB
4	localhost	1.8 KB	0.0 KB	0.0 KB	1.8 KB	100%	0.9 KB
TOTAL: 4		794.8 GB	14.7 MB	137.4 MB	795.0 GB	99%	282.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 29 04:15:02 - 04:59:59	65271	2.2%	24.19	19.8 GB	2.5%	7709.31
Nov 29 05:00:00 - 05:59:59	103289	3.5%	28.69	31.0 GB	3.9%	9014.89
Nov 29 06:00:00 - 06:59:59	106157	3.6%	29.49	32.6 GB	4.1%	9497.44
Nov 29 07:00:00 - 07:59:59	109334	3.7%	30.37	33.9 GB	4.3%	9887.26
Nov 29 08:00:00 - 08:59:59	118422	4.0%	32.90	36.7 GB	4.6%	10680.32
Nov 29 09:00:00 - 09:59:59	130166	4.4%	36.16	36.7 GB	4.6%	10695.06
Nov 29 10:00:00 - 10:59:59	119381	4.0%	33.16	32.5 GB	4.1%	9462.04
Nov 29 11:00:00 - 11:59:59	109900	3.7%	30.53	30.0 GB	3.8%	8732.98
Nov 29 12:00:00 - 12:59:59	115151	3.9%	31.99	35.0 GB	4.4%	10190.12
Nov 29 13:00:00 - 13:59:59	123320	4.2%	34.26	31.9 GB	4.0%	9291.71
Nov 29 14:00:00 - 14:59:59	128408	4.4%	35.67	33.6 GB	4.2%	9792.54
Nov 29 15:00:00 - 15:59:59	151196	5.1%	42.00	36.6 GB	4.6%	10648.79
Nov 29 16:00:00 - 16:59:59	141900	4.8%	39.42	36.4 GB	4.6%	10599.09
Nov 29 17:00:00 - 17:59:59	141100	4.8%	39.19	33.8 GB	4.3%	9850.60
Nov 29 18:00:00 - 18:59:59	123730	4.2%	34.37	31.8 GB	4.0%	9266.69
Nov 29 19:00:00 - 19:59:59	134309	4.6%	37.31	35.3 GB	4.4%	10290.07
Nov 29 20:00:00 - 20:59:59	139603	4.7%	38.78	35.5 GB	4.5%	10331.45
Nov 29 21:00:00 - 21:59:59	137431	4.7%	38.18	29.5 GB	3.7%	8579.53
Nov 29 22:00:00 - 22:59:59	152737	5.2%	42.43	35.7 GB	4.5%	10389.93
Nov 29 23:00:00 - 23:59:59	136521	4.6%	37.92	35.6 GB	4.5%	10368.88
Nov 30 00:00:00 - 00:59:59	93175	3.2%	25.88	27.8 GB	3.5%	8098.20
Nov 30 01:00:00 - 01:59:59	135954	4.6%	37.77	37.3 GB	4.7%	10868.77
Nov 30 02:00:00 - 02:59:59	121357	4.1%	33.71	31.7 GB	4.0%	9228.62
Nov 30 03:00:00 - 03:59:59	89787	3.0%	24.94	26.5 GB	3.3%	7721.48
Nov 30 04:00:00 - 04:15:05	23932	0.8%	26.44	7.3 GB	0.9%	8427.37
TOTAL: 24:00:03	2951531	100.0%	34.16	794.4 GB	100.0%	9640.61

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4419	352	2893	1	1173
2	news.nask.pl	485	15	410	3	57
3	news.icm.edu.pl	150	0	5	0	145
TOTAL: 3		5054	367	3308	4	1375

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	157
it.comp.os.win.windows10	75
comp.mobile.android	30
it.tlc.cellulari.android	26
comp.sys.raspberry-pi	21
comp.mobile.ipad	18
it.comp.os.win.windows8	11
nl.actueel	6
it.comp.os.win.windows7	5
uk.rec.cycling.moderated	5
hr.ponuda.hardver.komponente	4
de.rec.sf.startrek	2
de.comp.lang.vbclassic	2
hr.alt.cellular.gsm.iphone	2
hr.rec.glazba.hifi	1
rec.arts.tv.x-factor	1
hr.ponuda.hardver.periferije	1
TOTAL: 17	367

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

Groups	Count
pl.sci.historia	3
bit.listserv.catholic	1
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	856
EMP (md5)	75
EMP (fsl)	26
Too many newsgroups	7
HTML post	3
TOTAL: 5	967

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	52687	1557	29238	730	5	77294	2%	24:00:24
2	icm	42970	1212	25948	3346	0	18	2%	24:02:36
3	poznan	4858	39	3294	12	0	0	0%	24:00:57
4	nask	0	0	0	0	0	15039	0%	23:55:46
5	okapi	0	0	0	0	0	1491	0%	23:55:46
TOTAL: 5		100515	2808	58480	4088	5	93842	2%	119:55:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	21.4 MB	976.2 MB	997.6 MB	11.8 KB/s	224.1 KB	24:02:36
2	panorama	5.6 MB	6.0 MB	11.7 MB	0.1 KB/s	5.2 KB	24:00:24
3	poznan	123.7 KB	29.6 KB	153.3 KB	0.0 KB/s	3.0 KB	24:00:57
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:55:46
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:55:46
TOTAL: 5		27.2 MB	982.3 MB	1009.4 MB	2.4 KB/s	149.9 KB	119:55:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	212.191.87.58	9	502	1.8 MB	4	2	0	02:13:12
2	web.man.lodz.pl	288	34	32.6 KB	15	0	0	00:00:30
3	78.9.185.49	1	7	6.9 KB	1	0	0	00:01:07
4	staticline-31-182-75-5.toya.net.pl	3	0	0.0 KB	0	0	2	00:25:44
TOTAL: 4		301	543	1.9 MB	20	2	2	08:48:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	15	509	1.9 MB	5	2	0	06:53:10
2	*.man.lodz.pl	672	34	32.6 KB	15	0	0	00:00:34
3	*.elsnet.pl	4	0	0.0 KB	0	0	0	00:00:12
4	*.toya.net.pl	26	0	0.0 KB	0	0	2	01:10:30
5	*.adsl.inetia.pl	2	0	0.0 KB	0	0	0	00:44:13
TOTAL: 5		719	543	1.9 MB	20	2	2	08:48:41

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.194.99	3.087	3.431	04:38:50
212.191.87.58	0.507	0.430	02:13:12
staticline-31-182-82-106.toya.net.pl	0.000	0.018	00:30:01
178-37-243-175.adsl.inetia.pl	0.007	0.017	00:30:00
staticline-31-182-75-5.toya.net.pl	0.024	0.064	00:25:44
staticline-31-183-143-187.toya.net.pl	0.007	0.010	00:14:43
77-253-89-231.adsl.inetia.pl	0.004	0.017	00:14:13
78.9.185.49	0.005	0.011	00:01:07
web.man.lodz.pl	0.917	4.245	00:00:30
host227.elsnet.pl	0.004	0.068	00:00:12
mon.man.lodz.pl	0.938	4.090	00:00:03
staticline41435.toya.net.pl	0.081	0.238	00:00:01
david.man.lodz.pl	0.293	1.250	00:00:00
TOTAL: 13	5.874	13.889	08:48:41

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	21
185.48.194.99	5
host227.elsnet.pl	4
staticline-31-183-143-187.toya.net.pl	1
77-253-89-231.adsl.inetia.pl	1
staticline-31-182-82-106.toya.net.pl	1
178-37-243-175.adsl.inetia.pl	1
TOTAL: 9	418

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.191.87.58	4	2
185.48.194.99	1	0
staticline-31-182-82-106.toya.net.pl	1	0
178-37-243-175.adsl.inetia.pl	1	0
TOTAL: 4	7	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	536	98.7%
2	lodz	7	1.3%
TOTAL: 2		543	100.0%

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

Newsgroup		Count
1	pl.test	502
2	pl.comp.pecet	18
3	pl.comp.os.ms-windows.winnt	16
4	lodz.test	7
TOTAL: 4		543

Daily Usenet report

Nov 29 04:15:02 -- Nov 30 04:15:05