Daily Usenet report: Jan 29 04:15:03

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Jan 29 09:39:03 newsfeed nnrpd[29000]: SERVER perl filter not defined
Jan 29 11:47:27 newsfeed nnrpd[800]: SERVER perl filter not defined
Jan 29 15:33:44 newsfeed nnrpd[8293]: SERVER perl filter not defined
Jan 29 15:35:16 newsfeed nnrpd[8484]: SERVER perl filter not defined
Jan 29 15:37:30 newsfeed nnrpd[8528]: SERVER perl filter not defined
Jan 29 15:38:18 newsfeed nnrpd[8529]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1016617	97.6%	114.3 MB	97.1%
innd	11536	1.1%	1.9 MB	1.6%
nnrpd	6723	0.6%	683.6 KB	0.6%
innfeed	6391	0.6%	915.8 KB	0.8%
TOTAL: 4	1041267	100.0%	117.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1010514	96.6%
Negative hits	19162	1.8%
Positive hits	16449	1.6%
Cache misses	171	0.0%
TOTAL: 4	1046296	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.315	0.0%	338	0.000	24.601	361.000
article cleanup	00:00:15.172	0.0%	1013852	0.000	0.015	0.054
article logging	00:00:27.986	0.0%	1016535	0.000	0.028	0.080
article parse	00:33:02.125	2.3%	68914683	0.007	0.029	0.059
article write	00:08:06.884	0.6%	1010260	0.112	0.482	6.341
artlog/artcncl	00:00:00.007	0.0%	325	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:18.612	0.1%	74639814	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.846	0.0%	323	0.000	5.715	288.000
hishave/artcncl	00:00:00.393	0.0%	338	0.000	1.163	71.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:46:25.909	3.2%	1046820	0.087	2.661	7.190
history sync	00:00:00.022	0.0%	429	0.000	0.051	1.000
history write	01:22:02.683	5.7%	1011244	0.711	4.868	20.404
hiswrite/artcncl	00:00:00.043	0.0%	15	0.000	2.867	11.000
idle	18:04:16.719	75.3%	47818278	0.257	1.360	8.710
nntp read	00:29:11.872	2.0%	68163118	0.008	0.026	0.039
overview write	00:00:06.476	0.0%	1010260	0.000	0.006	0.032
perl filter	02:11:50.200	9.2%	1011244	2.409	7.822	10.443
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:57.968	0.1%	2053285	0.015	0.057	0.130
TOTAL: 23:59:40.073	23:39:13.232	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.113	0.0%	32811	0.000	0.003	1.000
article prepare	00:00:00.001	0.0%	8280	0.000	0.000	0.250
article read	00:00:00.090	0.0%	2015	0.000	0.045	1.000
backlog stats	00:00:00.090	0.0%	193776	0.000	0.000	0.025
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.294	0.0%	129111	0.000	0.010	1.000
data write	00:00:02.733	0.0%	96674	0.000	0.028	1.000
idle	24:00:05.336	100.0%	193776	0.000	445.903	22100.000
status file	00:00:01.487	0.0%	197	0.000	7.548	259.000
TOTAL: 24:00:16.039	24:00:11.144	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.166	0.0%	15	0.000	11.067	12.769
idle	14:01:41.499	100.0%	11367	0.000	4442.817	600035.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.473	0.0%	24838	0.000	0.019	1.000
readart	00:00:03.526	0.0%	621	0.000	5.678	28.333
TOTAL: 14:02:04.098	14:01:45.664	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	461	1010323	1006956	198	3169	99%	204:35:12
2	news.nask.pl	2	16411	2753	13231	427	16%	23:41:14
3	news.icm.edu.pl	51	220	38	169	13	17%	16:21:09
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		521	1026960	1009753	13598	3609	98%	244:37:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	266.0 GB	10.1 MB	80.7 MB	266.1 GB	99%	276.2 KB
2	news.nask.pl	9.8 MB	1.2 MB	1.2 MB	12.2 MB	80%	3.9 KB
3	news.icm.edu.pl	1.1 MB	32.4 KB	428.9 KB	1.5 MB	70%	31.1 KB
4	localhost	8.7 KB	0.0 KB	0.0 KB	8.7 KB	100%	1.5 KB
TOTAL: 4		266.0 GB	11.3 MB	82.3 MB	266.1 GB	99%	275.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 29 04:15:03 - 04:59:59	58342	5.8%	21.63	17.3 GB	6.5%	6728.36
Jan 29 05:00:00 - 05:59:59	87784	8.7%	24.38	27.2 GB	10.2%	7925.95
Jan 29 06:00:00 - 06:59:59	84617	8.4%	23.50	27.5 GB	10.3%	8008.44
Jan 29 07:00:00 - 07:59:59	85839	8.5%	23.84	26.6 GB	10.0%	7742.65
Jan 29 08:00:00 - 08:59:59	86007	8.5%	23.89	28.1 GB	10.6%	8192.95
Jan 29 09:00:00 - 09:59:59	79124	7.8%	21.98	23.2 GB	8.7%	6771.79
Jan 29 10:00:00 - 10:59:59	17841	1.8%	4.96	4.1 GB	1.5%	1190.26
Jan 29 11:00:00 - 11:59:59	11174	1.1%	3.10	1.6 GB	0.6%	475.29
Jan 29 12:00:00 - 12:59:59	11761	1.2%	3.27	1.7 GB	0.7%	506.56
Jan 29 13:00:00 - 13:59:59	12959	1.3%	3.60	1.8 GB	0.7%	516.02
Jan 29 14:00:00 - 14:59:59	15124	1.5%	4.20	1.8 GB	0.7%	531.22
Jan 29 15:00:00 - 15:59:59	14251	1.4%	3.96	2.1 GB	0.8%	621.79
Jan 29 16:00:00 - 16:59:59	12766	1.3%	3.55	1.9 GB	0.7%	545.06
Jan 29 17:00:00 - 17:59:59	17123	1.7%	4.76	1.8 GB	0.7%	529.85
Jan 29 18:00:00 - 18:59:59	12677	1.3%	3.52	1.7 GB	0.7%	506.32
Jan 29 19:00:00 - 19:59:59	12478	1.2%	3.47	2.0 GB	0.8%	594.11
Jan 29 20:00:00 - 20:59:59	13915	1.4%	3.87	2.2 GB	0.8%	628.99
Jan 29 21:00:00 - 21:59:59	15686	1.6%	4.36	2.5 GB	0.9%	721.96
Jan 29 22:00:00 - 22:59:59	19778	2.0%	5.49	2.3 GB	0.9%	680.47
Jan 29 23:00:00 - 23:59:59	14024	1.4%	3.90	1.9 GB	0.7%	550.65
Jan 30 00:00:00 - 00:59:59	69028	6.8%	19.17	17.7 GB	6.7%	5163.89
Jan 30 01:00:00 - 01:59:59	95434	9.4%	26.51	25.5 GB	9.6%	7428.35
Jan 30 02:00:00 - 02:59:59	68492	6.8%	19.03	18.2 GB	6.8%	5287.72
Jan 30 03:00:00 - 03:59:59	85796	8.5%	23.83	23.2 GB	8.7%	6759.40
Jan 30 04:00:00 - 04:15:02	7996	0.8%	8.86	2.0 GB	0.8%	2337.98
TOTAL: 23:59:59	1010016	100.0%	11.69	266.1 GB	100.0%	3229.46

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5848	351	2216	3	3278
2	news.nask.pl	415	16	390	3	6
3	news.icm.edu.pl	13	0	3	0	10
TOTAL: 3		6276	367	2609	6	3294

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	251
comp.mobile.ipad	29
comp.mobile.android	21
it.tlc.cellulari.android	13
de.rec.sf.startrek	11
it.comp.os.win.windows7	7
uk.rec.cycling.moderated	6
it.comp.os.win.windows8	5
alt.sex.telephones	4
nl.wetenschap.regulier	3
hr.rec.glazba.hifi	3
hr.alt.cellular.gsm.iphone	3
hr.alt.cellular.gsm.android	2
hr.rec.sport.rukomet	2
de.comp.lang.php	1
comp.sys.raspberry-pi	1
de.comp.office-pakete.ms-office.excel	1
de.comp.lang.vbclassic	1
it.comp.os.linux.ubuntu	1
it.discussioni.energia	1
TOTAL: 21	367

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

Groups	Count
pl.sci.matematyka	3
pl.sci.historia	3
TOTAL: 2	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	604
EMP (md5)	4
Scoring filter (8)	2
Scoring filter (10)	1
TOTAL: 4	611

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	33531	1241	31353	148	0	3%	23:57:10
2	icm	37202	372	30459	211	36	0%	23:50:37
3	nask	21589	71	14320	0	3	0%	23:59:48
4	poznan	3397	15	2432	11	0	0%	24:07:24
5	okapi	0	0	0	0	2932	0%	24:00:01
TOTAL: 5		95719	1699	78564	370	2971	1%	119:55:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	4.5 MB	489.2 KB	5.0 MB	0.1 KB/s	3.7 KB	23:57:10
2	icm	3.3 MB	23.1 MB	26.4 MB	0.3 KB/s	46.3 KB	23:50:37
3	nask	377.2 KB	0.0 KB	377.2 KB	0.0 KB/s	5.3 KB	23:59:48
4	poznan	181.0 KB	42.7 KB	223.7 KB	0.0 KB/s	8.6 KB	24:07:24
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		8.3 MB	23.6 MB	31.9 MB	0.1 KB/s	15.8 KB	119:55:00

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	213	337	470.3 KB	213	0	09:21:15
2	web.man.lodz.pl	288	126	125.7 KB	55	0	00:00:24
3	lze200.lze.lodz.pl	2	84	156.8 KB	5	0	00:35:47
4	pc-212-51-218-225.p.lodz.pl	18	63	143.7 KB	10	0	03:15:01
5	dmcs2.p.lodz.pl	1	11	27.0 KB	2	0	00:30:42
6	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 6		528	621	923.5 KB	285	6	14:02:03

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	213	337	470.3 KB	213	0	09:21:15
2	*.man.lodz.pl	678	126	125.7 KB	55	6	00:00:31
3	*.lze.lodz.pl	2	84	156.8 KB	5	0	00:35:47
4	*.p.lodz.pl	19	74	170.7 KB	12	0	03:45:44
5	*.shodan.io	1	0	0.0 KB	0	0	00:00:00
6	unresolved	6	0	0.0 KB	0	0	00:18:42
7	*.toya.net.pl	45	0	0.0 KB	0	0	00:00:02
TOTAL: 7		964	621	923.5 KB	285	6	14:02:03

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-skrzep2.cm.p.lodz.pl	0.713	2.639	09:21:15
pc-212-51-218-225.p.lodz.pl	0.074	0.246	03:15:01
lze200.lze.lodz.pl	0.012	0.067	00:35:47
dmcs2.p.lodz.pl	0.005	0.017	00:30:42
89.38.41.61	0.221	0.316	00:14:17
185.48.194.196	0.165	0.157	00:04:25
web.man.lodz.pl	1.045	3.994	00:00:24
mon.man.lodz.pl	1.049	3.784	00:00:04
staticline41435.toya.net.pl	0.186	0.531	00:00:02
david.man.lodz.pl	0.372	1.186	00:00:01
isvcs01.man.lodz.pl	0.043	0.085	00:00:00
census12.shodan.io	0.004	0.013	00:00:00
TOTAL: 12	3.889	13.035	14:02:03

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	45
89.38.41.61	4
185.48.194.196	2
census12.shodan.io	1
TOTAL: 6	436

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-skrzep2.cm.p.lodz.pl	16	0
pc-212-51-218-225.p.lodz.pl	4	0
dmcs2.p.lodz.pl	1	0
TOTAL: 3	21	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	337	54.3%
2	pl	284	45.7%
TOTAL: 2		621	100.0%

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

Newsgroup		Count
1	lodman.info	337
2	pl.comp.pecet	99
3	pl.misc.elektronika	66
4	pl.comp.os.ms-windows.winnt	44
5	pl.misc.telefonia.gsm	42
6	pl.rec.kuchnia	17
7	pl.misc.kolej	8
8	pl.rec.dom	8
TOTAL: 8		621

Daily Usenet report

Jan 29 04:15:03 -- Jan 30 04:15:02