Daily Usenet report: May 21 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

May 21 10:01:42 newsfeed overchan[2456]: input line too long, skipping
May 21 10:56:08 newsfeed nnrpd[4227]: SERVER perl filter not defined
May 21 11:09:01 newsfeed nnrpd[4751]: SERVER perl filter not defined
May 21 11:10:01 newsfeed nnrpd[4760]: SERVER perl filter not defined
May 21 11:11:08 newsfeed nnrpd[4822]: SERVER perl filter not defined
May 21 11:23:42 newsfeed nnrpd[5178]: SERVER perl filter not defined
May 21 12:19:44 newsfeed nnrpd[7101]: SERVER perl filter not defined
May 21 14:29:46 newsfeed overchan[2551]: input line too long, skipping
May 21 17:16:16 newsfeed overchan[2551]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1388483	98.0%	156.6 MB	97.5%
innd	14374	1.0%	2.3 MB	1.4%
nnrpd	7876	0.6%	827.0 KB	0.5%
innfeed	6395	0.5%	940.8 KB	0.6%
overchan	3	0.0%	0.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1417132	100.0%	160.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1343307	97.2%
Negative hits	21704	1.6%
Positive hits	17007	1.2%
Cache misses	182	0.0%
TOTAL: 4	1382200	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.084	0.0%	535	0.000	2.026	80.000
article cleanup	00:00:16.410	0.0%	1347380	0.002	0.012	0.035
article logging	00:00:43.883	0.1%	1386403	0.014	0.032	0.068
article parse	00:33:11.658	2.3%	65977231	0.017	0.030	0.048
article write	00:12:10.470	0.8%	1342839	0.086	0.544	1.596
artlog/artcncl	00:00:00.008	0.0%	456	0.000	0.018	1.000
artlog/artparse	00:00:00.003	0.0%	76	0.000	0.039	1.000
data move	00:00:58.285	0.1%	74506062	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.290	0.0%	416	0.000	0.697	17.000
hishave/artcncl	00:00:00.021	0.0%	535	0.000	0.039	1.400
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:04:08.127	0.3%	1383009	0.028	0.179	1.473
history sync	00:00:00.018	0.0%	289	0.000	0.062	1.000
history write	00:16:17.720	1.1%	1344437	0.114	0.727	8.226
hiswrite/artcncl	00:00:00.017	0.0%	119	0.000	0.143	1.000
idle	19:05:27.037	79.6%	59664331	0.346	1.152	4.614
nntp read	00:26:39.280	1.9%	65197931	0.015	0.025	0.034
overview write	00:00:08.685	0.0%	1342839	0.000	0.006	0.019
perl filter	03:02:39.323	12.7%	1344361	1.953	8.152	12.018
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:53.426	0.2%	2718822	0.039	0.064	0.148
TOTAL: 23:58:14.640	23:45:35.745	99.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.132	0.0%	33179	0.000	0.004	0.200
article prepare	00:00:00.005	0.0%	15400	0.000	0.000	0.062
article read	00:00:00.203	0.0%	3715	0.000	0.055	1.000
backlog stats	00:00:00.128	0.0%	183375	0.000	0.001	0.038
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.206	0.0%	102037	0.000	0.012	0.120
data write	00:00:02.490	0.0%	69637	0.000	0.036	0.222
idle	23:58:55.470	100.0%	183375	0.000	470.814	17249.000
status file	00:00:00.241	0.0%	294	0.000	0.820	2.000
TOTAL: 23:59:04.503	23:58:59.875	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.009	0.0%	38	0.200	0.237	0.242
idle	05:52:30.890	99.9%	52730	0.000	401.117	450030.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.012	0.0%	107528	0.000	0.019	1.556
readart	00:00:00.537	0.0%	456	0.000	1.178	28.000
TOTAL: 05:52:56.493	05:52:33.448	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	746	1344944	1339970	731	4243	99%	158:43:18
2	news.nask.pl	3	16956	4169	12531	256	24%	23:38:42
3	news.icm.edu.pl	50	425	95	217	113	22%	16:53:27
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		806	1362331	1344240	13479	4612	98%	199:15:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	433.2 GB	21.5 MB	167.8 MB	433.4 GB	99%	338.1 KB
2	news.nask.pl	24.7 MB	885.2 KB	987.1 KB	26.6 MB	93%	6.1 KB
3	news.icm.edu.pl	19.0 MB	1.9 MB	18.9 MB	39.8 MB	47%	195.7 KB
4	localhost	9.2 KB	0.0 KB	0.0 KB	9.2 KB	100%	1.5 KB
TOTAL: 4		433.3 GB	24.2 MB	187.7 MB	433.5 GB	99%	337.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 21 04:15:02 - 04:59:59	55010	4.1%	20.39	19.2 GB	4.4%	7474.79
May 21 05:00:00 - 05:59:59	57803	4.3%	16.06	21.8 GB	5.0%	6350.25
May 21 06:00:00 - 06:59:59	33018	2.5%	9.17	14.3 GB	3.3%	4173.45
May 21 07:00:00 - 07:59:59	32785	2.4%	9.11	14.8 GB	3.4%	4314.48
May 21 08:00:00 - 08:59:59	39567	2.9%	10.99	14.0 GB	3.2%	4080.59
May 21 09:00:00 - 09:59:59	36872	2.7%	10.24	13.2 GB	3.0%	3850.96
May 21 10:00:00 - 10:59:59	51477	3.8%	14.30	14.0 GB	3.2%	4082.76
May 21 11:00:00 - 11:59:59	103461	7.7%	28.74	13.7 GB	3.1%	3977.85
May 21 12:00:00 - 12:59:59	31912	2.4%	8.86	11.9 GB	2.7%	3473.73
May 21 13:00:00 - 13:59:59	31085	2.3%	8.63	12.0 GB	2.8%	3496.42
May 21 14:00:00 - 14:59:59	32512	2.4%	9.03	13.5 GB	3.1%	3945.09
May 21 15:00:00 - 15:59:59	39355	2.9%	10.93	14.3 GB	3.3%	4154.89
May 21 16:00:00 - 16:59:59	74386	5.5%	20.66	16.9 GB	3.9%	4932.15
May 21 17:00:00 - 17:59:59	77046	5.7%	21.40	17.3 GB	4.0%	5034.04
May 21 18:00:00 - 18:59:59	47079	3.5%	13.08	12.3 GB	2.8%	3576.08
May 21 19:00:00 - 19:59:59	36950	2.7%	10.26	15.5 GB	3.6%	4503.80
May 21 20:00:00 - 20:59:59	38365	2.9%	10.66	15.0 GB	3.5%	4364.17
May 21 21:00:00 - 21:59:59	40224	3.0%	11.17	15.8 GB	3.6%	4606.12
May 21 22:00:00 - 22:59:59	41518	3.1%	11.53	15.9 GB	3.7%	4642.42
May 21 23:00:00 - 23:59:59	40058	3.0%	11.13	15.5 GB	3.6%	4513.67
May 22 00:00:00 - 00:59:59	82626	6.1%	22.95	28.0 GB	6.5%	8165.26
May 22 01:00:00 - 01:59:59	104521	7.8%	29.03	33.9 GB	7.8%	9871.79
May 22 02:00:00 - 02:59:59	111322	8.3%	30.92	35.1 GB	8.1%	10234.91
May 22 03:00:00 - 03:59:59	80117	6.0%	22.25	27.1 GB	6.2%	7881.03
May 22 04:00:00 - 04:15:03	25317	1.9%	28.04	8.8 GB	2.0%	10177.74
TOTAL: 24:00:01	1344386	100.0%	15.56	433.9 GB	100.0%	5265.80

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	43246	233	2775	8	40230
2	news.nask.pl	282	9	235	22	16
3	news.icm.edu.pl	113	0	9	0	104
TOTAL: 3		43641	242	3019	30	40350

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	134
it.tlc.cellulari.android	50
comp.mobile.android	24
comp.sys.raspberry-pi	6
hr.ponuda.hardver.periferije	6
it.comp.os.win.windows7	5
de.comp.lang.php	4
hr.alt.cellular.gsm.android	3
alt.sex.telephones	2
hr.ponuda.hardver.komponente	2
hr.rec.glazba.hifi	2
nl.actueel	1
de.comp.lang.vbclassic	1
hr.alt.cellular.gsm.iphone	1
it.comp.os.win.windows8	1
TOTAL: 15	242

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

Groups	Count
pl.praca.oferowana	28
pl.sci.historia	2
TOTAL: 2	30

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	1132
EMP (phl)	66
EMP (md5)	38
HTML post	14
Scoring filter (9)	1
TOTAL: 5	1251

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	47893	3141	29434	357	0	6%	23:50:01
2	icm	6800	102	4413	37	22331	1%	23:51:22
3	poznan	6256	83	4084	27	0	1%	23:50:00
4	nask	4609	9	2526	87	11660	0%	23:50:03
5	okapi	0	0	0	0	3034	0%	24:00:04
TOTAL: 5		65558	3335	40457	508	37025	5%	119:21:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	21.7 MB	2.0 MB	23.7 MB	0.3 KB/s	6.9 KB	23:50:01
2	poznan	6.6 MB	1.3 MB	7.9 MB	0.1 KB/s	73.8 KB	23:50:00
3	icm	830.0 KB	851.4 KB	1.6 MB	0.0 KB/s	12.1 KB	23:51:22
4	nask	32.8 KB	21.3 MB	21.3 MB	0.3 KB/s	227.2 KB	23:50:03
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:04
TOTAL: 5		29.1 MB	25.4 MB	54.5 MB	0.1 KB/s	14.5 KB	119:21:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	340	553.5 KB	288	0	00:00:07
2	pc-212-51-218-225.p.lodz.pl	8	55	186.2 KB	7	0	02:15:23
3	lze200.lze.lodz.pl	15	33	63.3 KB	6	0	02:12:00
4	web.man.lodz.pl	288	28	29.3 KB	13	0	00:00:29
5	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 5		605	456	832.4 KB	314	6	05:52:56

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	340	553.5 KB	288	0	00:00:07
2	*.p.lodz.pl	8	55	186.2 KB	7	0	02:15:23
3	*.lze.lodz.pl	15	33	63.3 KB	6	0	02:12:00
4	*.man.lodz.pl	833	28	29.3 KB	13	6	00:00:36
5	*.elsnet.pl	3	0	0.0 KB	0	0	00:00:58
6	unresolved	4	0	0.0 KB	0	0	01:23:44
7	*.toya.net.pl	65	0	0.0 KB	0	0	00:00:03
TOTAL: 7		1216	456	832.4 KB	314	6	05:52:56

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.030	0.078	02:15:23
lze200.lze.lodz.pl	0.081	0.116	02:12:00
185.48.195.96	2.502	3.768	01:23:44
host227.elsnet.pl	0.014	0.023	00:00:58
web.man.lodz.pl	1.037	2.923	00:00:29
pc-skrzep2.cm.p.lodz.pl	1.051	2.800	00:00:07
mon.man.lodz.pl	1.601	3.810	00:00:05
staticline41435.toya.net.pl	0.252	0.617	00:00:03
david.man.lodz.pl	0.381	0.822	00:00:01
isvcs01.man.lodz.pl	0.061	0.035	00:00:00
TOTAL: 10	7.010	14.992	05:52:56

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	443
david.man.lodz.pl	96
staticline41435.toya.net.pl	65
185.48.195.96	4
host227.elsnet.pl	3
TOTAL: 5	611

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	2	0
lze200.lze.lodz.pl	2	0
TOTAL: 2	4	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	340	74.6%
2	pl	116	25.4%
TOTAL: 2		456	100.0%

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

Newsgroup		Count
1	lodman.info	340
2	pl.misc.kolej	29
3	pl.misc.elektronika	26
4	pl.comp.pecet	26
5	pl.comp.os.ms-windows.winnt	22
6	pl.misc.telefonia.gsm	13
TOTAL: 6		456

Daily Usenet report

May 21 04:15:02 -- May 22 04:15:03