Daily Usenet report: Dec 16 04:15:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Dec 16 16:51:00 newsfeed nnrpd[20508]: SERVER perl filter not defined
Dec 16 17:05:32 newsfeed nnrpd[21124]: SERVER perl filter not defined
Dec 16 20:30:36 newsfeed nnrpd[28184]: SERVER perl filter not defined
Dec 17 01:24:02 newsfeed nnrpd[5896]: message repeated 2 times: [ staticline24983.toya.net.pl group lodz.test 0]
Dec 17 01:29:39 newsfeed nnrpd[6142]: message repeated 4 times: [ staticline24983.toya.net.pl group lodz.test 0]
Dec 17 01:55:03 newsfeed nnrpd[7033]: message repeated 2 times: [ staticline24983.toya.net.pl group lodz.test 0]
Dec 17 02:01:48 newsfeed nnrpd[7232]: message repeated 4 times: [ staticline24983.toya.net.pl group lodz.test 0]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2400302	97.3%	272.4 MB	96.8%
innfeed	41994	1.7%	5.0 MB	1.8%
innd	19707	0.8%	3.4 MB	1.2%
nnrpd	5448	0.2%	586.0 KB	0.2%
TOTAL: 4	2467451	100.0%	281.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2394275	96.2%
Negative hits	70513	2.8%
Positive hits	23681	1.0%
Cache misses	185	0.0%
TOTAL: 4	2488654	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.406	0.0%	239	0.000	26.803	2015.000
article cleanup	00:00:40.104	0.0%	2400154	0.006	0.017	0.040
article logging	00:01:12.255	0.1%	2400662	0.016	0.030	0.946
article parse	01:18:18.993	5.4%	276365870	0.007	0.017	0.085
article write	00:29:15.684	2.0%	2395102	0.321	0.733	4.638
artlog/artcncl	00:00:00.002	0.0%	238	0.000	0.008	1.000
data move	00:06:11.932	0.4%	292680372	0.000	0.001	0.008
hisgrep/artcncl	00:00:00.735	0.0%	212	0.000	3.467	284.000
hishave/artcncl	00:00:00.074	0.0%	239	0.000	0.310	2.143
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:36:32.302	2.5%	2490510	0.053	0.880	17.447
history sync	00:00:00.015	0.0%	290	0.000	0.052	2.000
history write	00:51:05.294	3.5%	2395756	0.202	1.279	31.749
hiswrite/artcncl	00:00:00.034	0.0%	27	0.000	1.259	11.000
idle	13:03:06.454	54.4%	196746456	0.081	0.239	0.489
nntp read	01:37:33.521	6.8%	274500540	0.010	0.021	0.043
overview write	00:00:14.010	0.0%	2395102	0.001	0.006	0.016
perl filter	05:03:34.425	21.1%	2395756	4.970	7.603	12.589
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:39.849	0.3%	4857285	0.019	0.058	0.088
TOTAL: 24:00:17.598	23:12:32.089	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.317	0.0%	121730	0.000	0.003	0.059
article prepare	00:00:00.012	0.0%	77450	0.000	0.000	0.125
article read	00:01:53.208	0.1%	19363	0.000	5.847	906.000
backlog stats	00:00:00.214	0.0%	318026	0.000	0.001	0.020
callbacks	00:00:00.000	0.0%	19	0.000	0.000	0.000
data read	00:00:02.064	0.0%	183224	0.000	0.011	0.067
data write	00:00:13.634	0.0%	118681	0.000	0.115	1.343
idle	23:57:37.740	99.8%	318026	32.785	271.229	1266.125
status file	00:00:12.714	0.0%	15650	0.000	0.812	34.167
TOTAL: 24:00:12.340	23:59:59.903	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	15:18:43.496	99.9%	272761	0.000	202.094	600035.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:09.479	0.0%	546864	0.000	0.017	0.500
readart	00:00:01.649	0.0%	249	0.000	6.622	236.000
TOTAL: 15:19:30.312	15:18:54.624	99.9%	-	-	-	-

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	982	2401427	2389757	7046	4624	99%	310:29:29
2	news.nask.pl	2	17671	5077	12175	419	28%	24:06:57
3	news.icm.edu.pl	47	164	37	117	10	22%	13:57:05
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		1035	2419265	2394874	19338	5053	98%	348:33:31

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	790.4 GB	212.4 MB	264.3 MB	790.8 GB	99%	346.3 KB
2	news.nask.pl	24.5 MB	1.2 MB	1.3 MB	27.0 MB	90%	5.0 KB
3	news.icm.edu.pl	934.2 KB	41.4 KB	136.2 KB	1.1 MB	84%	23.7 KB
4	localhost	4.9 KB	0.0 KB	0.0 KB	4.9 KB	100%	1.6 KB
TOTAL: 4		790.4 GB	213.7 MB	265.7 MB	790.9 GB	99%	345.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 16 04:15:02 - 04:59:59	72583	3.0%	26.90	23.3 GB	3.0%	9063.50
Dec 16 05:00:00 - 05:59:59	93754	3.9%	26.04	31.0 GB	3.9%	9039.16
Dec 16 06:00:00 - 06:59:59	93174	3.9%	25.88	33.6 GB	4.2%	9777.76
Dec 16 07:00:00 - 07:59:59	98782	4.1%	27.44	35.5 GB	4.5%	10344.02
Dec 16 08:00:00 - 08:59:59	101554	4.2%	28.21	35.6 GB	4.5%	10371.97
Dec 16 09:00:00 - 09:59:59	99449	4.2%	27.62	35.1 GB	4.4%	10226.80
Dec 16 10:00:00 - 10:59:59	103238	4.3%	28.68	34.0 GB	4.3%	9907.20
Dec 16 11:00:00 - 11:59:59	95486	4.0%	26.52	33.6 GB	4.3%	9788.84
Dec 16 12:00:00 - 12:59:59	89309	3.7%	24.81	32.4 GB	4.1%	9444.72
Dec 16 13:00:00 - 13:59:59	63065	2.6%	17.52	24.4 GB	3.1%	7117.19
Dec 16 14:00:00 - 14:59:59	90235	3.8%	25.07	32.4 GB	4.1%	9435.55
Dec 16 15:00:00 - 15:59:59	102705	4.3%	28.53	35.0 GB	4.4%	10186.94
Dec 16 16:00:00 - 16:59:59	109588	4.6%	30.44	35.1 GB	4.4%	10213.46
Dec 16 17:00:00 - 17:59:59	100592	4.2%	27.94	34.5 GB	4.4%	10039.45
Dec 16 18:00:00 - 18:59:59	111573	4.7%	30.99	34.4 GB	4.4%	10026.99
Dec 16 19:00:00 - 19:59:59	109777	4.6%	30.49	32.7 GB	4.1%	9537.39
Dec 16 20:00:00 - 20:59:59	111327	4.6%	30.92	33.4 GB	4.2%	9723.20
Dec 16 21:00:00 - 21:59:59	102139	4.3%	28.37	31.4 GB	4.0%	9133.79
Dec 16 22:00:00 - 22:59:59	116579	4.9%	32.38	34.0 GB	4.3%	9912.40
Dec 16 23:00:00 - 23:59:59	116741	4.9%	32.43	34.7 GB	4.4%	10109.27
Dec 17 00:00:00 - 00:59:59	83211	3.5%	23.11	26.8 GB	3.4%	7819.53
Dec 17 01:00:00 - 01:59:59	106759	4.5%	29.66	33.5 GB	4.2%	9756.85
Dec 17 02:00:00 - 02:59:59	99299	4.1%	27.58	32.9 GB	4.2%	9582.85
Dec 17 03:00:00 - 03:59:59	98557	4.1%	27.38	31.8 GB	4.0%	9258.09
Dec 17 04:00:00 - 04:15:02	25029	1.0%	27.75	8.7 GB	1.1%	10123.07
TOTAL: 24:00:00	2394505	100.0%	27.71	789.9 GB	100.0%	9586.77

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5062	169	3996	7	890
2	news.nask.pl	485	8	399	5	73
3	news.icm.edu.pl	12	0	2	0	10
TOTAL: 3		5559	177	4397	12	973

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	91
it.tlc.cellulari.android	25
it.comp.os.win.windows10	17
comp.sys.raspberry-pi	8
de.rec.sf.startrek	6
it.comp.os.win.windows8	6
comp.mobile.android	5
it.comp.os.win.windows7	4
hr.ponuda.hardver.komponente	3
it.comp.os.linux.ubuntu	2
de.comp.office-pakete.ms-office.misc	2
hr.alt.cellular.gsm.iphone	2
de.comp.lang.vbclassic	1
hr.ponuda.hardver.konfiguracije	1
alt.sex.telephones	1
hr.rec.glazba.hifi	1
de.comp.office-pakete.ms-office.word	1
nl.comp.os.linux.techniek	1
TOTAL: 18	177

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

Groups	Count
pl.praca.oferowana	10
pl.sci.historia	1
bit.listserv.catholic	1
TOTAL: 3	12

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	430
EMP (fsl)	17
Too many newsgroups	7
EMP (md5)	5
HTML post	5
Attached HTML file	1
TOTAL: 6	465

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	68370	1826	39942	653	39	79235	2%	23:59:45
2	icm	57230	109	28545	15705	0	20	0%	23:59:20
3	poznan	7237	86	4931	44	0	0	1%	24:06:28
4	nask	0	0	0	0	0	13299	0%	24:00:04
5	okapi	0	0	0	0	0	2498	0%	24:00:04
TOTAL: 5		132837	2021	73418	16402	39	95052	1%	120:05:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	8.4 MB	3.8 MB	12.2 MB	0.1 KB/s	5.0 KB	23:59:45
2	icm	6.7 MB	3.8 GB	3.8 GB	46.7 KB/s	255.1 KB	23:59:20
3	poznan	315.3 KB	89.8 KB	405.1 KB	0.0 KB/s	3.1 KB	24:06:28
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:04
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:04
TOTAL: 5		15.4 MB	3.8 GB	3.9 GB	9.4 KB/s	219.7 KB	120:05:41

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
okapi	488.3 KB
TOTAL: 2	2.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	108	109.9 KB	45	0	0	00:00:33
2	acin201.neoplus.adsl.tpnet.pl	6	102	99.7 KB	2	0	1	00:51:25
3	lze200.lze.lodz.pl	4	29	47.4 KB	6	0	0	00:16:38
4	staticline24983.toya.net.pl	25	9	7.6 KB	2	0	3	00:46:35
5	auriga.rz.izeto.pl	1	1	1.7 KB	1	0	0	00:01:29
6	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	0	00:00:00
7	staticline-31-183-44-167.toya.net.pl	1	0	0.0 KB	0	0	1	00:01:59
TOTAL: 7		328	249	266.2 KB	56	3	5	15:19:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	675	108	109.9 KB	45	3	0	00:00:38
2	*.neoplus.adsl.tpnet.pl	6	102	99.7 KB	2	0	1	00:51:25
3	*.lze.lodz.pl	4	29	47.4 KB	6	0	0	00:16:38
4	*.toya.net.pl	115	9	7.6 KB	2	0	4	00:48:55
5	*.rz.izeto.pl	1	1	1.7 KB	1	0	0	00:01:29
6	unresolved	2	0	0.0 KB	0	0	0	13:17:03
7	*.dynamic.chello.pl	4	0	0.0 KB	0	0	0	00:03:02
8	*.elsnet.pl	2	0	0.0 KB	0	0	0	00:00:16
TOTAL: 8		809	249	266.2 KB	56	3	5	15:19:30

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.14	15.122	18.541	13:17:03
acin201.neoplus.adsl.tpnet.pl	0.061	0.089	00:51:25
staticline24983.toya.net.pl	0.121	0.296	00:46:35
lze200.lze.lodz.pl	0.004	0.069	00:16:38
89-64-6-0.dynamic.chello.pl	0.007	0.056	00:03:02
staticline-31-183-44-167.toya.net.pl	0.031	0.024	00:01:59
auriga.rz.izeto.pl	0.002	0.014	00:01:29
web.man.lodz.pl	1.060	3.230	00:00:33
host227.elsnet.pl	0.003	0.016	00:00:16
staticline17008.toya.net.pl	0.015	0.048	00:00:10
staticline41435.toya.net.pl	0.273	0.955	00:00:08
mon.man.lodz.pl	0.897	3.163	00:00:02
staticline-31-183-159-102.toya.net.pl	0.003	0.012	00:00:02
david.man.lodz.pl	0.311	0.999	00:00:00
isvcs01.man.lodz.pl	0.014	0.040	00:00:00
TOTAL: 15	17.924	27.552	15:19:30

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	84
staticline17008.toya.net.pl	4
89-64-6-0.dynamic.chello.pl	4
host227.elsnet.pl	2
185.48.193.14	2
staticline-31-183-159-102.toya.net.pl	1
TOTAL: 8	481

NNRP client timeouts [Top 20]:

System	Conn	Peer
acin201.neoplus.adsl.tpnet.pl	1	0
185.48.193.14	1	0
TOTAL: 2	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	137	55.0%
2	lodz	111	44.6%
3	lodman	1	0.4%
TOTAL: 3		249	100.0%

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

Newsgroup		Count
1	lodz.test	111
2	pl.comp.pecet	74
3	pl.comp.os.ms-windows.winnt	58
4	pl.internet.polip	2
5	pl.misc.telefonia.gsm	2
6	lodman.info	1
7	pl.rec.dom	1
TOTAL: 7		249

Daily Usenet report

Dec 16 04:15:02 -- Dec 17 04:15:02