Daily Usenet report: Jan 10 04:15:03

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jan 10 09:31:55 newsfeed nnrpd[23925]: SERVER perl filter not defined
Jan 10 09:33:03 newsfeed nnrpd[23928]: SERVER perl filter not defined
Jan 10 20:48:55 newsfeed nnrpd[14923]: message repeated 2 times: [ 178.43.208.241.ipv4.supernova.orange.pl group lodman.info 0]
Jan 10 22:12:18 newsfeed nnrpd[17699]: message repeated 4 times: [ 212.191.78.59 group ab.arnet 0]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3174396	98.5%	366.3 MB	98.3%
innd	37921	1.2%	5.4 MB	1.4%
innfeed	4852	0.2%	670.7 KB	0.2%
nnrpd	4841	0.2%	497.0 KB	0.1%
TOTAL: 4	3222010	100.0%	372.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3166379	96.1%
Negative hits	102803	3.1%
Positive hits	14563	0.4%
Cache misses	11416	0.3%
TOTAL: 4	3295161	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.864	0.0%	288	0.000	13.417	256.000
article cleanup	00:00:46.463	0.1%	3172670	0.007	0.015	0.025
article logging	00:02:05.520	0.1%	3173389	0.020	0.040	1.083
article parse	01:32:04.243	6.4%	161241105	0.019	0.034	0.068
article write	00:42:40.190	3.0%	3152821	0.416	0.812	4.821
artlog/artcncl	00:00:00.005	0.0%	251	0.000	0.020	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:42.024	0.3%	179964692	0.001	0.001	0.003
hisgrep/artcncl	00:00:01.447	0.0%	246	0.000	5.882	94.273
hishave/artcncl	00:00:00.059	0.0%	288	0.000	0.205	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:31:02.111	2.2%	3297026	0.114	0.565	7.653
history sync	00:00:00.017	0.0%	292	0.000	0.058	1.000
history write	00:44:59.893	3.1%	3168284	0.258	0.852	10.582
hiswrite/artcncl	00:00:00.032	0.0%	42	0.000	0.762	20.000
idle	11:33:23.590	48.2%	86761703	0.333	0.480	1.296
nntp read	01:04:49.691	4.5%	158547638	0.017	0.025	0.039
overview write	00:00:21.489	0.0%	3152821	0.002	0.007	0.015
perl filter	06:55:53.010	28.9%	3168284	6.604	7.876	9.648
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:36.611	0.5%	6369055	0.029	0.062	0.089
TOTAL: 23:59:23.396	23:18:30.259	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.295	0.0%	73875	0.000	0.004	0.133
article prepare	00:00:00.050	0.0%	158828	0.000	0.000	0.062
article read	00:00:33.270	0.0%	39825	0.000	0.835	129.714
backlog stats	00:00:00.500	0.0%	409959	0.000	0.001	0.024
callbacks	00:00:00.000	0.0%	86	0.000	0.000	0.000
data read	00:00:02.843	0.0%	182706	0.000	0.016	0.103
data write	00:00:25.463	0.0%	231310	0.000	0.110	0.710
idle	23:58:13.671	99.9%	409959	16.489	210.493	2200.552
status file	00:00:00.711	0.0%	919	0.000	0.774	4.000
TOTAL: 23:59:27.885	23:59:16.803	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:12:56.896	98.9%	8792	0.000	497.827	450059.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.739	0.1%	78797	0.000	0.060	1.500
readart	00:00:02.909	0.1%	75	0.000	38.787	528.000
TOTAL: 01:13:47.339	01:13:04.544	99.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	856	3166953	3143068	18009	5876	99%	456:44:05
2	news.nask.pl	2	28094	10832	3171	14091	38%	24:07:02
3	news.icm.edu.pl	45	202	15	183	4	7%	14:19:25
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		906	3195251	3153917	21363	19971	98%	495:10:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	987.4 GB	228.9 MB	507.3 MB	988.1 GB	99%	329.0 KB
2	news.nask.pl	150.6 MB	1.0 MB	263.4 MB	415.1 MB	36%	17.1 KB
3	news.icm.edu.pl	363.5 KB	13.0 KB	72.5 KB	449.0 KB	80%	23.6 KB
4	localhost	4.4 KB	0.0 KB	0.0 KB	4.4 KB	100%	2.2 KB
TOTAL: 4		987.5 GB	230.0 MB	770.8 MB	988.5 GB	99%	326.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 10 04:15:03 - 04:59:59	105712	3.4%	39.20	31.9 GB	3.2%	12389.16
Jan 10 05:00:00 - 05:59:59	143719	4.6%	39.92	43.4 GB	4.4%	12643.02
Jan 10 06:00:00 - 06:59:59	144896	4.6%	40.25	43.9 GB	4.4%	12780.16
Jan 10 07:00:00 - 07:59:59	141737	4.5%	39.37	45.2 GB	4.6%	13164.10
Jan 10 08:00:00 - 08:59:59	155876	4.9%	43.30	44.1 GB	4.5%	12855.98
Jan 10 09:00:00 - 09:59:59	142641	4.5%	39.62	42.8 GB	4.3%	12457.85
Jan 10 10:00:00 - 10:59:59	129242	4.1%	35.90	43.1 GB	4.4%	12562.32
Jan 10 11:00:00 - 11:59:59	140005	4.4%	38.89	42.5 GB	4.3%	12387.94
Jan 10 12:00:00 - 12:59:59	147708	4.7%	41.03	42.0 GB	4.3%	12219.77
Jan 10 13:00:00 - 13:59:59	128537	4.1%	35.70	40.6 GB	4.1%	11837.53
Jan 10 14:00:00 - 14:59:59	133645	4.2%	37.12	39.5 GB	4.0%	11513.48
Jan 10 15:00:00 - 15:59:59	128993	4.1%	35.83	42.7 GB	4.3%	12443.24
Jan 10 16:00:00 - 16:59:59	140429	4.5%	39.01	43.4 GB	4.4%	12650.07
Jan 10 17:00:00 - 17:59:59	138776	4.4%	38.55	43.0 GB	4.4%	12533.47
Jan 10 18:00:00 - 18:59:59	138773	4.4%	38.55	43.6 GB	4.4%	12708.19
Jan 10 19:00:00 - 19:59:59	120472	3.8%	33.46	40.1 GB	4.1%	11674.44
Jan 10 20:00:00 - 20:59:59	133657	4.2%	37.13	43.5 GB	4.4%	12659.50
Jan 10 21:00:00 - 21:59:59	135355	4.3%	37.60	43.8 GB	4.4%	12752.98
Jan 10 22:00:00 - 22:59:59	141433	4.5%	39.29	45.1 GB	4.6%	13125.88
Jan 10 23:00:00 - 23:59:59	144538	4.6%	40.15	42.4 GB	4.3%	12339.56
Jan 11 00:00:00 - 00:59:59	98998	3.1%	27.50	33.3 GB	3.4%	9709.33
Jan 11 01:00:00 - 01:59:59	113812	3.6%	31.61	38.5 GB	3.9%	11217.63
Jan 11 02:00:00 - 02:59:59	99412	3.2%	27.61	33.2 GB	3.4%	9656.80
Jan 11 03:00:00 - 03:59:59	83557	2.6%	23.21	27.5 GB	2.8%	8015.61
Jan 11 04:00:00 - 04:15:04	21623	0.7%	23.92	7.2 GB	0.7%	8360.08
TOTAL: 24:00:01	3153546	100.0%	36.50	986.3 GB	100.0%	11970.27

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	14267	15	347	1	13904
2	newsfeed.pionier.net.pl	6328	136	4037	0	2155
3	news.icm.edu.pl	4	0	1	0	3
TOTAL: 3		20599	151	4385	1	16062

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	58
it.tlc.cellulari.android	33
it.comp.os.win.windows10	14
comp.mobile.android	11
it.discussioni.energia	10
comp.sys.raspberry-pi	6
nl.comp.sys.android	5
de.comp.office-pakete.ms-office.misc	4
it.comp.os.win.windows7	3
it.comp.os.win.windows8	2
comp.mobile.ipad	1
alt.sex.telephones	1
alt.os.linux.opensuse	1
it.comp.os.linux.ubuntu	1
de.comp.lang.perl	1
TOTAL: 15	151

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12906
Binary in non-binary group	2161
Scoring filter (8)	96
EMP (phl)	88
Scoring filter (9)	46
Scoring filter (10)	26
HTML post	10
Scoring filter (12)	2
Too many newsgroups	2
Scoring filter (13)	2
Scoring filter (11)	2
Poison newsgroup	2
TOTAL: 12	15343

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	14069	945	10999	452	238	61104	6%	23:57:48
2	icm	65470	62	24159	37930	0	181	0%	24:02:46
3	poznan	14784	54	10187	22	0	0	0%	23:59:45
4	nask	7569	18	5803	0	0	1	0%	23:54:52
5	okapi	0	0	0	0	0	2919	0%	24:00:00
TOTAL: 5		101892	1079	51148	38404	238	64205	1%	119:55:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	2.9 MB	9.9 GB	9.9 GB	120.2 KB/s	273.9 KB	24:02:46
2	panorama	2.7 MB	1.5 MB	4.2 MB	0.0 KB/s	3.1 KB	23:57:48
3	poznan	518.5 KB	89.8 KB	608.3 KB	0.0 KB/s	8.0 KB	23:59:45
4	nask	31.3 KB	0.0 KB	31.3 KB	0.0 KB/s	1.7 KB	23:54:52
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		6.1 MB	9.9 GB	9.9 GB	24.1 KB/s	263.7 KB	119:55:11

Backlog files shrunk by innfeed:

Server	Size
panorama	5.2 MB
okapi	488.4 KB
TOTAL: 2	5.7 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	66	66.4 KB	30	0	00:00:22
2	178.43.208.241.ipv4.supernova.orange.pl	3	7	13.0 KB	2	0	00:02:35
3	212.191.78.59	1	2	12.8 KB	1	0	00:04:21
4	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 4		294	75	92.2 KB	33	2	01:13:47

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	674	66	66.4 KB	30	2	00:00:27
2	*.43.208.241.ipv4.supernova.orange.pl	3	7	13.0 KB	2	0	00:02:35
3	unresolved	4	2	12.8 KB	1	0	00:40:42
4	*.208-100-26.static.steadfastdns.net	2	0	0.0 KB	0	0	00:00:01
5	*.cm.p.lodz.pl	1	0	0.0 KB	0	0	00:30:00
TOTAL: 5		684	75	92.2 KB	33	2	01:13:47

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.41.228	0.316	0.620	00:35:09
pc-mstarzak.cm.p.lodz.pl	0.007	0.026	00:30:00
212.191.78.59	0.268	0.162	00:04:21
178.43.208.241.ipv4.supernova.orange.pl	0.049	0.072	00:02:35
212.191.78.54	0.000	0.030	00:01:11
web.man.lodz.pl	0.988	6.994	00:00:22
mon.man.lodz.pl	0.958	6.656	00:00:03
ip230.208-100-26.static.steadfastdns.net	0.010	0.043	00:00:01
david.man.lodz.pl	0.372	2.143	00:00:01
isvcs01.man.lodz.pl	0.007	0.056	00:00:00
TOTAL: 10	2.975	16.802	01:13:47

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
ip230.208-100-26.static.steadfastdns.net	2
89.38.41.228	2
212.191.78.54	1
pc-mstarzak.cm.p.lodz.pl	1
TOTAL: 6	390

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-mstarzak.cm.p.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	66	88.0%
2	lodman	7	9.3%
3	alc	2	2.7%
TOTAL: 3		75	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	40
2	pl.comp.pecet	26
3	lodman.info	7
4	alc.general	2
TOTAL: 4		75

Daily Usenet report

Jan 10 04:15:03 -- Jan 11 04:15:04