Daily Usenet report: Nov 17 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Nov 17 07:53:57 newsfeed nnrpd[28662]: SERVER perl filter not defined
Nov 17 08:13:21 newsfeed nnrpd[29379]: SERVER perl filter not defined
Nov 17 08:49:32 newsfeed nnrpd[30610]: SERVER perl filter not defined
Nov 17 09:35:17 newsfeed nnrpd[32194]: SERVER perl filter not defined
Nov 17 10:17:02 newsfeed nnrpd[1135]: SERVER perl filter not defined
Nov 17 13:10:02 newsfeed pgpverify[6915]: pgpverify: verification failed
Nov 17 13:10:04 newsfeed controlchan[2234]: Unknown "reopen" control by robomod@news.nic.it
Nov 17 13:51:04 newsfeed nnrpd[8243]: SERVER perl filter not defined
Nov 17 15:12:57 newsfeed nnrpd[11020]: SERVER perl filter not defined
Nov 18 01:39:17 newsfeed nnrpd[32081]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1809548	98.0%	206.5 MB	97.6%
innd	22203	1.2%	3.4 MB	1.6%
nnrpd	7841	0.4%	838.8 KB	0.4%
innfeed	6560	0.4%	964.0 KB	0.4%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1846157	100.0%	211.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1805318	95.7%
Negative hits	61525	3.3%
Positive hits	19001	1.0%
Cache misses	177	0.0%
TOTAL: 4	1886021	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:12.857	0.0%	396	0.000	32.467	1000.000
article cleanup	00:00:28.598	0.0%	1809327	0.004	0.016	0.030
article logging	00:00:48.114	0.1%	1809543	0.011	0.027	0.509
article parse	00:55:37.920	3.9%	162615575	0.005	0.021	0.060
article write	00:13:54.794	1.0%	1798486	0.262	0.464	1.713
artlog/artcncl	00:00:00.007	0.0%	390	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:26.122	0.2%	173613418	0.000	0.001	0.014
hisgrep/artcncl	00:00:02.321	0.0%	339	0.000	6.847	64.000
hishave/artcncl	00:00:00.187	0.0%	396	0.000	0.472	39.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:05:40.265	4.6%	1886259	0.008	2.089	8.949
history sync	00:00:00.014	0.0%	294	0.000	0.048	2.000
history write	02:55:45.048	12.2%	1805784	0.059	5.840	33.347
hiswrite/artcncl	00:00:00.237	0.0%	57	0.000	4.158	19.000
idle	13:40:04.480	56.9%	109888421	0.272	0.448	2.299
nntp read	00:52:02.646	3.6%	161101835	0.007	0.019	0.039
overview write	00:00:10.701	0.0%	1798486	0.000	0.006	0.016
perl filter	03:34:03.635	14.9%	1805783	4.037	7.113	9.215
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:17.601	0.2%	3630439	0.024	0.054	0.098
TOTAL: 24:00:22.501	23:24:35.547	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.117	0.0%	33489	0.000	0.003	0.111
article prepare	00:00:00.006	0.0%	25452	0.000	0.000	0.083
article read	00:00:00.333	0.0%	6020	0.000	0.055	1.000
backlog stats	00:00:00.118	0.0%	226621	0.000	0.001	0.020
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.398	0.0%	144114	0.000	0.010	1.000
data write	00:00:03.550	0.0%	113155	0.000	0.031	10.000
idle	23:59:56.540	100.0%	226621	0.000	381.238	14069.000
status file	00:00:00.388	0.0%	184	0.000	2.109	74.667
TOTAL: 24:00:07.150	24:00:02.450	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.049	0.0%	44	1.114	1.114	1.114
idle	19:54:56.541	99.9%	170506	0.000	420.493	208903.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:29.023	0.0%	702009	0.000	0.041	2.364
readart	00:00:09.362	0.0%	1001	0.000	9.353	72.000
TOTAL: 19:56:09.007	19:55:34.975	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newsmaster@carnet.hr	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	721	1805105	1793741	1246	10118	99%	303:53:29
2	news.nask.pl	4	19545	4719	14127	699	24%	24:45:04
3	news.icm.edu.pl	44	339	151	156	32	44%	16:44:22
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		779	1824998	1798620	15529	10849	98%	345:22:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	507.5 GB	29.6 MB	988.2 MB	508.5 GB	99%	295.6 KB
2	news.nask.pl	25.1 MB	1.8 MB	2.1 MB	29.0 MB	86%	5.5 KB
3	news.icm.edu.pl	12.6 MB	202.3 KB	577.6 KB	13.4 MB	94%	74.9 KB
4	localhost	13.3 KB	0.0 KB	0.0 KB	13.3 KB	100%	1.5 KB
TOTAL: 4		507.6 GB	31.6 MB	990.9 MB	508.6 GB	99%	294.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 17 04:15:02 - 04:59:59	48010	2.7%	17.79	14.6 GB	2.9%	5687.05
Nov 17 05:00:00 - 05:59:59	74103	4.1%	20.58	21.4 GB	4.2%	6231.70
Nov 17 06:00:00 - 06:59:59	78063	4.3%	21.68	23.1 GB	4.6%	6725.32
Nov 17 07:00:00 - 07:59:59	75225	4.2%	20.90	22.6 GB	4.4%	6571.38
Nov 17 08:00:00 - 08:59:59	74693	4.2%	20.75	22.0 GB	4.3%	6421.55
Nov 17 09:00:00 - 09:59:59	75911	4.2%	21.09	22.8 GB	4.5%	6638.64
Nov 17 10:00:00 - 10:59:59	81340	4.5%	22.59	23.4 GB	4.6%	6814.34
Nov 17 11:00:00 - 11:59:59	81514	4.5%	22.64	23.2 GB	4.6%	6752.50
Nov 17 12:00:00 - 12:59:59	78966	4.4%	21.93	22.9 GB	4.5%	6658.87
Nov 17 13:00:00 - 13:59:59	81151	4.5%	22.54	23.5 GB	4.6%	6852.96
Nov 17 14:00:00 - 14:59:59	79757	4.4%	22.15	23.3 GB	4.6%	6773.33
Nov 17 15:00:00 - 15:59:59	91144	5.1%	25.32	24.5 GB	4.8%	7128.50
Nov 17 16:00:00 - 16:59:59	97897	5.4%	27.19	23.9 GB	4.7%	6947.28
Nov 17 17:00:00 - 17:59:59	50591	2.8%	14.05	13.2 GB	2.6%	3851.17
Nov 17 18:00:00 - 18:59:59	80860	4.5%	22.46	23.9 GB	4.7%	6962.62
Nov 17 19:00:00 - 19:59:59	78038	4.3%	21.68	23.6 GB	4.7%	6876.28
Nov 17 20:00:00 - 20:59:59	72553	4.0%	20.15	19.4 GB	3.8%	5654.87
Nov 17 21:00:00 - 21:59:59	65169	3.6%	18.10	16.6 GB	3.3%	4845.20
Nov 17 22:00:00 - 22:59:59	70251	3.9%	19.51	19.9 GB	3.9%	5785.09
Nov 17 23:00:00 - 23:59:59	84404	4.7%	23.45	23.2 GB	4.6%	6752.80
Nov 18 00:00:00 - 00:59:59	44919	2.5%	12.48	11.0 GB	2.2%	3207.89
Nov 18 01:00:00 - 01:59:59	73602	4.1%	20.45	20.6 GB	4.1%	5995.51
Nov 18 02:00:00 - 02:59:59	75759	4.2%	21.04	21.8 GB	4.3%	6354.38
Nov 18 03:00:00 - 03:59:59	68339	3.8%	18.98	18.7 GB	3.7%	5434.98
Nov 18 04:00:00 - 04:15:02	15842	0.9%	17.56	4.4 GB	0.9%	5105.72
TOTAL: 24:00:00	1798101	100.0%	20.81	507.4 GB	100.0%	6157.44

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	10299	303	2908	1	7087
2	news.nask.pl	726	64	628	2	32
3	news.icm.edu.pl	32	0	7	0	25
TOTAL: 3		11057	367	3543	3	7144

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	120
alt.sex.telephones	44
comp.mobile.android	35
it.comp.os.win.windows8	35
it.tlc.cellulari.android	28
it.comp.os.win.windows7	19
nl.comp.os.linux.techniek	14
de.comm.provider.telefonie	13
comp.sys.raspberry-pi	10
uk.rec.cycling.moderated	10
hr.ponuda.hardver.komponente	8
de.comp.lang.perl	7
de.comp.lang.vbclassic	6
rec.arts.tv.x-factor	3
it.comp.os.linux.ubuntu	3
hr.alt.cellular.gsm.iphone	3
hr.ponuda.hardver.periferije	2
hr.alt.cellular.gsm.android	2
hr.rec.glazba.hifi	2
de.comp.lang.php	1
TOTAL: 22	367

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	6892
EMP (md5)	23
HTML post	6
Scoring filter (9)	3
EMP (fsl)	2
Scoring filter (8)	1
TOTAL: 6	6927

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	34128	2541	30560	303	0	7%	23:53:34
2	icm	39207	2280	28655	782	21	5%	24:00:03
3	nask	26686	301	16240	47	0	1%	23:59:45
4	poznan	6603	78	4422	23	1	1%	24:00:18
5	okapi	0	0	0	0	3068	0%	24:00:01
TOTAL: 5		106624	5200	79877	1155	3090	4%	119:53:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	108.8 MB	112.8 MB	221.6 MB	2.6 KB/s	74.1 KB	24:00:03
2	nask	32.6 MB	10.9 MB	43.5 MB	0.5 KB/s	127.9 KB	23:59:45
3	panorama	14.0 MB	1.5 MB	15.4 MB	0.2 KB/s	5.6 KB	23:53:34
4	poznan	781.5 KB	43.6 KB	825.0 KB	0.0 KB/s	8.2 KB	24:00:18
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		156.1 MB	125.2 MB	281.3 MB	0.7 KB/s	45.3 KB	119:53:41

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	348	825.9 KB	288	0	0	00:00:07
2	pc-212-51-218-225.p.lodz.pl	68	331	1.4 MB	20	0	0	09:04:26
3	lze200.lze.lodz.pl	10	185	334.1 KB	9	0	0	01:30:35
4	web.man.lodz.pl	288	80	486.3 KB	37	0	0	00:00:25
5	78.9.28.230	4	24	20.7 KB	2	0	1	00:09:03
6	pc-212-51-207-190.p.lodz.pl	2	11	9.7 KB	1	0	2	00:03:50
7	pc26.zsk.p.lodz.pl	7	8	8.7 KB	5	2	0	00:16:36
8	212.51.203.199	2	6	9.6 KB	1	0	0	00:03:20
9	wireless.ics.p.lodz.pl	2	3	3.0 KB	2	1	0	00:04:50
10	staticline-31-182-69-227.toya.net.pl	4	2	4.8 KB	2	0	0	00:09:55
11	pc24.zsk.p.lodz.pl	5	2	1.8 KB	2	1	0	00:08:01
12	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	0	00:00:00
13	91.192.122.130	1	0	0.0 KB	0	0	1	00:07:16
TOTAL: 13		686	1000	3.1 MB	369	9	4	19:56:08

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	348	825.9 KB	288	0	0	00:00:07
2	*.p.lodz.pl	70	342	1.4 MB	21	0	2	09:08:17
3	*.lze.lodz.pl	10	185	334.1 KB	9	0	0	01:30:35
4	*.man.lodz.pl	677	80	486.3 KB	37	5	0	00:00:32
5	unresolved	7	30	30.3 KB	3	0	2	00:19:40
6	*.zsk.p.lodz.pl	12	10	10.5 KB	7	3	0	00:24:38
7	*.ics.p.lodz.pl	2	3	3.0 KB	2	1	0	00:04:50
8	*.toya.net.pl	70	2	4.8 KB	2	0	0	00:10:01
9	*.elsnet.pl	16	0	0.0 KB	0	0	0	00:04:13
10	*.206.15.62.static.jazztel.es	2	0	0.0 KB	0	0	0	08:13:12
TOTAL: 10		1154	1000	3.1 MB	369	9	4	19:56:08

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.266	0.780	09:04:26
252.206.15.62.static.jazztel.es	7.761	10.901	08:13:12
lze200.lze.lodz.pl	0.046	0.182	01:30:35
pc26.zsk.p.lodz.pl	0.320	0.215	00:16:36
staticline-31-182-69-227.toya.net.pl	0.014	0.035	00:09:55
78.9.28.230	0.034	0.037	00:09:03
pc24.zsk.p.lodz.pl	0.704	0.252	00:08:01
91.192.122.130	0.042	0.023	00:07:16
wireless.ics.p.lodz.pl	0.510	0.200	00:04:50
host227.elsnet.pl	0.049	0.174	00:04:13
pc-212-51-207-190.p.lodz.pl	0.307	0.182	00:03:50
212.51.203.199	0.009	0.022	00:03:20
web.man.lodz.pl	0.934	3.668	00:00:25
pc-skrzep2.cm.p.lodz.pl	0.963	3.487	00:00:07
staticline41435.toya.net.pl	0.180	0.838	00:00:06
mon.man.lodz.pl	0.871	3.503	00:00:04
david.man.lodz.pl	0.314	1.103	00:00:01
isvcs01.man.lodz.pl	0.016	0.083	00:00:00
?	0.003	0.046	00:00:00
TOTAL: 19	13.343	25.731	19:56:08

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	66
host227.elsnet.pl	16
252.206.15.62.static.jazztel.es	2
TOTAL: 5	468

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	4
TOTAL: 1	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
252.206.15.62.static.jazztel.es	1	1
TOTAL: 1	1	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	626	62.6%
2	lodman	350	35.0%
3	lodz	24	2.4%
TOTAL: 3		1000	100.0%

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

Newsgroup		Count
1	lodman.info	348
2	pl.misc.elektronika	225
3	pl.comp.pecet	125
4	pl.misc.kolej	106
5	pl.rec.kuchnia	69
6	pl.misc.telefonia.gsm	29
7	pl.test	24
8	lodz.test	24
9	pl.rec.dom	20
10	pl.comp.os.ms-windows.winnt	12
11	pl.rec.radio.amatorskie	6
12	pl.regionalne.lodz	6
13	pl.internet.polip	4
14	lodman.test	2
TOTAL: 14		1000

Daily Usenet report

Nov 17 04:15:02 -- Nov 18 04:15:02