Daily Usenet report: Oct 9 04:15:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Oct 9 14:51:19 newsfeed nnrpd[13996]: SERVER perl filter not defined
Oct 9 15:21:21 newsfeed nnrpd[15003]: SERVER perl filter not defined
Oct 9 15:21:48 newsfeed nnrpd[15004]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1447016	97.2%	164.8 MB	96.7%
innd	27309	1.8%	3.9 MB	2.3%
nnrpd	7306	0.5%	762.9 KB	0.4%
innfeed	6520	0.4%	957.5 KB	0.5%
TOTAL: 4	1488151	100.0%	170.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1442379	93.1%
Negative hits	87789	5.7%
Positive hits	18548	1.2%
Cache misses	141	0.0%
TOTAL: 4	1548857	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.967	0.0%	265	0.000	33.838	578.000
article cleanup	00:00:24.257	0.0%	1446166	0.000	0.017	0.037
article logging	00:00:37.199	0.0%	1446363	0.004	0.026	0.210
article parse	00:52:17.643	3.6%	110927246	0.010	0.028	0.048
article write	00:13:14.920	0.9%	1428388	0.204	0.557	3.914
artlog/artcncl	00:00:00.003	0.0%	262	0.000	0.011	0.500
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:02:07.350	0.1%	119686273	0.000	0.001	0.007
hisgrep/artcncl	00:00:01.193	0.0%	251	0.000	4.753	39.000
hishave/artcncl	00:00:00.136	0.0%	265	0.000	0.513	21.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:13:21.101	5.1%	1549565	0.012	2.840	10.410
history sync	00:00:00.014	0.0%	299	0.000	0.047	1.000
history write	02:48:50.479	11.7%	1443223	0.050	7.019	25.866
hiswrite/artcncl	00:00:00.060	0.0%	14	0.000	4.286	17.000
idle	14:17:17.895	59.6%	77889410	0.401	0.660	5.655
nntp read	00:47:30.909	3.3%	109750359	0.012	0.026	0.040
overview write	00:00:09.191	0.0%	1428388	0.000	0.006	0.020
perl filter	03:02:47.371	12.7%	1443221	3.463	7.599	9.823
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:49.270	0.2%	2896474	0.031	0.058	0.135
TOTAL: 23:59:34.738	23:21:37.958	97.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.144	0.0%	39716	0.000	0.004	0.143
article prepare	00:00:00.004	0.0%	22040	0.000	0.000	0.250
article read	00:00:00.609	0.0%	5378	0.000	0.113	2.000
backlog stats	00:00:00.138	0.0%	247978	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.516	0.0%	156621	0.000	0.010	0.071
data write	00:00:05.472	0.0%	123830	0.000	0.044	9.000
idle	24:00:29.428	100.0%	247978	0.000	348.537	19423.000
status file	00:00:00.906	0.0%	200	0.000	4.530	219.000
TOTAL: 24:00:43.210	24:00:38.217	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:40:17.667	99.5%	4998	0.000	1924.303	600067.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:29.178	0.3%	72355	0.000	0.403	4.667
readart	00:00:00.785	0.0%	429	0.000	1.830	229.000
TOTAL: 02:41:05.987	02:40:47.630	99.8%	-	-	-	-

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	528	1443298	1424913	1289	17096	98%	257:48:28
2	news.nask.pl	2	19298	4340	14289	669	22%	24:29:56
3	news.icm.edu.pl	51	223	24	181	18	10%	16:45:07
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		585	1462822	1429280	15759	17783	97%	299:03:31

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	402.5 GB	17.0 MB	2.2 GB	404.8 GB	99%	294.3 KB
2	news.nask.pl	20.7 MB	1.7 MB	2.0 MB	24.4 MB	84%	5.0 KB
3	news.icm.edu.pl	737.2 KB	19.7 KB	3.6 MB	4.4 MB	16%	106.9 KB
4	localhost	7.5 KB	0.0 KB	0.0 KB	7.5 KB	100%	2.5 KB
TOTAL: 4		402.6 GB	18.8 MB	2.2 GB	404.8 GB	99%	293.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 9 04:15:02 - 04:59:59	37716	2.6%	13.98	13.1 GB	3.3%	5096.93
Oct 9 05:00:00 - 05:59:59	57724	4.0%	16.03	19.6 GB	4.9%	5721.94
Oct 9 06:00:00 - 06:59:59	64973	4.5%	18.05	21.4 GB	5.3%	6247.72
Oct 9 07:00:00 - 07:59:59	63648	4.5%	17.68	21.6 GB	5.4%	6276.95
Oct 9 08:00:00 - 08:59:59	74596	5.2%	20.72	22.2 GB	5.5%	6473.06
Oct 9 09:00:00 - 09:59:59	69215	4.8%	19.23	21.9 GB	5.4%	6369.27
Oct 9 10:00:00 - 10:59:59	80738	5.7%	22.43	22.9 GB	5.7%	6656.91
Oct 9 11:00:00 - 11:59:59	76200	5.3%	21.17	21.1 GB	5.2%	6134.76
Oct 9 12:00:00 - 12:59:59	70699	4.9%	19.64	19.7 GB	4.9%	5723.69
Oct 9 13:00:00 - 13:59:59	70515	4.9%	19.59	19.6 GB	4.9%	5696.92
Oct 9 14:00:00 - 14:59:59	58667	4.1%	16.30	15.5 GB	3.9%	4524.11
Oct 9 15:00:00 - 15:59:59	48563	3.4%	13.49	12.2 GB	3.0%	3546.63
Oct 9 16:00:00 - 16:59:59	47169	3.3%	13.10	11.8 GB	2.9%	3444.71
Oct 9 17:00:00 - 17:59:59	41657	2.9%	11.57	11.0 GB	2.7%	3211.33
Oct 9 18:00:00 - 18:59:59	57706	4.0%	16.03	17.6 GB	4.4%	5116.78
Oct 9 19:00:00 - 19:59:59	56842	4.0%	15.79	17.5 GB	4.3%	5088.95
Oct 9 20:00:00 - 20:59:59	64600	4.5%	17.94	20.4 GB	5.1%	5955.89
Oct 9 21:00:00 - 21:59:59	45904	3.2%	12.75	9.0 GB	2.2%	2634.49
Oct 9 22:00:00 - 22:59:59	34534	2.4%	9.59	4.7 GB	1.2%	1360.82
Oct 9 23:00:00 - 23:59:59	35381	2.5%	9.83	4.9 GB	1.2%	1427.33
Oct 10 00:00:00 - 00:59:59	52747	3.7%	14.65	11.5 GB	2.9%	3353.46
Oct 10 01:00:00 - 01:59:59	75763	5.3%	21.05	20.8 GB	5.2%	6063.08
Oct 10 02:00:00 - 02:59:59	63050	4.4%	17.51	16.2 GB	4.0%	4722.61
Oct 10 03:00:00 - 03:59:59	64075	4.5%	17.80	21.1 GB	5.2%	6132.77
Oct 10 04:00:00 - 04:15:03	16093	1.1%	17.82	5.3 GB	1.3%	6195.98
TOTAL: 24:00:01	1428775	100.0%	16.54	402.6 GB	100.0%	4885.70

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	17289	216	2341	0	14732
2	news.nask.pl	672	45	604	4	19
3	news.icm.edu.pl	18	0	2	0	16
TOTAL: 3		17979	261	2947	4	14767

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	82
alt.sex.telephones	40
it.tlc.cellulari.android	33
it.comp.os.win.windows7	29
it.comp.os.win.windows8	14
de.comp.lang.vbclassic	10
hr.alt.cellular.gsm.android	8
de.comm.provider.telefonie	8
comp.sys.raspberry-pi	7
hr.alt.cellular.gsm.iphone	7
comp.mobile.ipad	6
it.discussioni.energia	5
comp.mobile.android	4
uk.rec.cycling.moderated	3
hr.ponuda.hardver.komponente	3
rec.arts.tv.x-factor	2
TOTAL: 16	261

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

Groups	Count
pl.sci.historia	4
TOTAL: 1	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	13634
EMP (phl)	890
HTML post	25
EMP (md5)	15
EMP (fsl)	4
TOTAL: 5	14568

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	40546	1815	37571	282	0	4%	23:57:19
2	icm	39849	798	30586	1746	4	2%	24:05:34
3	nask	27628	545	16794	253	0	1%	24:01:42
4	poznan	5895	54	3981	14	0	0%	24:01:11
5	okapi	0	0	0	0	2528	0%	24:00:02
TOTAL: 5		113918	3212	88932	2295	2532	2%	120:05:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	134.7 MB	414.2 MB	548.8 MB	6.5 KB/s	220.9 KB	24:05:34
2	nask	74.6 MB	62.3 MB	137.0 MB	1.6 KB/s	175.7 KB	24:01:42
3	panorama	9.7 MB	1.3 MB	10.9 MB	0.1 KB/s	5.3 KB	23:57:19
4	poznan	229.8 KB	216.5 KB	446.3 KB	0.0 KB/s	6.6 KB	24:01:11
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		219.2 MB	478.0 MB	697.1 MB	1.7 KB/s	129.6 KB	120:05:48

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	Rej	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	314	703.6 KB	288	0	0	00:00:07
2	web.man.lodz.pl	288	64	58.4 KB	28	0	0	00:00:26
3	lze200.lze.lodz.pl	2	29	63.6 KB	2	0	0	00:09:21
4	staticline-31-182-69-227.toya.net.pl	6	14	19.6 KB	4	0	1	00:50:07
5	212.191.5.118	4	3	3.0 KB	2	0	0	00:06:25
6	212.51.203.199	2	3	3.3 KB	1	0	0	00:30:27
7	aasc203.neoplus.adsl.tpnet.pl	2	2	3.5 KB	1	0	0	01:00:05
8	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	0	00:00:00
TOTAL: 8		595	429	855.1 KB	326	3	1	02:41:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	314	703.6 KB	288	0	0	00:00:07
2	*.man.lodz.pl	675	64	58.4 KB	28	3	0	00:00:31
3	*.lze.lodz.pl	2	29	63.6 KB	2	0	0	00:09:21
4	*.toya.net.pl	97	14	19.6 KB	4	0	1	00:52:17
5	unresolved	6	6	6.4 KB	3	0	0	00:36:52
6	*.neoplus.adsl.tpnet.pl	2	2	3.5 KB	1	0	0	01:00:05
7	*.181.14.62.static.jazztel.es	2	0	0.0 KB	0	0	0	00:01:30
8	*.105.135.22.tvsat364.lodz.pl	2	0	0.0 KB	0	0	0	00:00:18
TOTAL: 8		1074	429	855.1 KB	326	3	1	02:41:05

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
aasc203.neoplus.adsl.tpnet.pl	0.045	0.019	01:00:05
staticline-31-182-69-227.toya.net.pl	0.022	0.090	00:50:07
212.51.203.199	0.018	0.031	00:30:27
lze200.lze.lodz.pl	0.004	0.055	00:09:21
212.191.5.118	0.323	0.146	00:06:25
staticline-31-182-134-54.toya.net.pl	0.011	0.011	00:02:01
98.181.14.62.static.jazztel.es	0.023	0.075	00:01:30
web.man.lodz.pl	1.152	3.815	00:00:26
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.027	00:00:18
staticline41435.toya.net.pl	0.314	1.194	00:00:07
pc-skrzep2.cm.p.lodz.pl	1.057	3.871	00:00:07
mon.man.lodz.pl	0.920	3.845	00:00:03
david.man.lodz.pl	0.305	1.216	00:00:01
isvcs01.man.lodz.pl	0.017	0.045	00:00:00
TOTAL: 14	4.215	14.440	02:41:05

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	90
98.181.14.62.static.jazztel.es	2
ip-176.105.135.22.tvsat364.lodz.pl	2
staticline-31-182-134-54.toya.net.pl	1
TOTAL: 6	479

NNRP client timeouts [Top 20]:

System	Conn	Peer
aasc203.neoplus.adsl.tpnet.pl	2	0
staticline-31-182-69-227.toya.net.pl	1	0
212.51.203.199	1	0
TOTAL: 3	4	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	328	76.5%
2	pl	99	23.1%
3	lodz	2	0.5%
TOTAL: 3		429	100.0%

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

Newsgroup		Count
1	lodman.info	314
2	pl.comp.pecet	62
3	pl.misc.telefonia.gsm	23
4	lodman.test	14
5	pl.comp.os.ms-windows.winnt	8
6	pl.rec.radio.amatorskie	3
7	pl.rec.sport.tenis	3
8	lodz.ogloszenia	2
TOTAL: 8		429

Daily Usenet report

Oct 9 04:15:02 -- Oct 10 04:15:03