Daily Usenet report: Sep 21 04:15:03

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Sep 21 04:39:06 newsfeed pgpverify[27831]: pgpverify: verification failed
Sep 21 09:14:11 newsfeed nnrpd[4982]: SERVER perl filter not defined
Sep 21 19:34:23 newsfeed nnrpd[25463]: SERVER perl filter not defined
Sep 21 19:35:13 newsfeed nnrpd[25506]: SERVER perl filter not defined
Sep 21 19:36:36 newsfeed nnrpd[25540]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	765388	96.6%	88.7 MB	96.0%
innd	13706	1.7%	2.1 MB	2.3%
nnrpd	6845	0.9%	696.7 KB	0.7%
innfeed	6366	0.8%	904.2 KB	1.0%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	792309	100.0%	92.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	761690	94.4%
Negative hits	29168	3.6%
Positive hits	15735	2.0%
Cache misses	114	0.0%
TOTAL: 4	806707	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:23.941	0.0%	1660	0.000	14.422	378.000
article cleanup	00:00:14.703	0.0%	763292	0.000	0.019	0.070
article logging	00:00:25.769	0.0%	763421	0.000	0.034	0.178
article parse	00:24:49.357	1.7%	57764029	0.006	0.026	0.065
article write	00:08:26.864	0.6%	756258	0.158	0.670	6.038
artlog/artcncl	00:00:00.040	0.0%	1569	0.000	0.025	1.000
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:01:10.065	0.1%	62266344	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.672	0.0%	330	0.000	2.036	172.000
hishave/artcncl	00:00:06.255	0.0%	1660	0.000	3.768	159.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:45:27.592	3.2%	806086	0.319	3.384	9.608
history sync	00:00:00.027	0.0%	388	0.000	0.070	2.000
history write	01:29:52.335	6.2%	761314	2.103	7.083	18.385
hiswrite/artcncl	00:00:12.575	0.0%	1330	0.000	9.455	19.800
idle	18:45:32.308	78.2%	43581260	0.442	1.550	10.552
nntp read	00:25:11.134	1.7%	57237637	0.010	0.026	0.050
overview write	00:00:05.161	0.0%	756258	0.000	0.007	0.041
perl filter	01:34:49.536	6.6%	761310	3.709	7.473	13.825
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:48.648	0.1%	1549028	0.018	0.070	0.172
TOTAL: 23:59:34.237	23:38:36.982	98.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.119	0.0%	36564	0.000	0.003	0.200
article prepare	00:00:00.005	0.0%	12942	0.000	0.000	0.250
article read	00:00:00.279	0.0%	3172	0.000	0.088	1.000
backlog stats	00:00:00.125	0.0%	217167	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.429	0.0%	136222	0.000	0.010	1.000
data write	00:00:04.119	0.0%	101356	0.000	0.041	73.000
idle	24:00:05.480	100.0%	217167	0.000	397.876	21106.000
status file	00:00:00.157	0.0%	185	0.000	0.849	6.000
TOTAL: 24:00:16.890	24:00:11.713	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.016	0.0%	2	8.000	8.000	8.000
idle	00:53:19.149	99.4%	14301	0.000	223.701	450433.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.739	0.0%	30215	0.000	0.024	1.667
readart	00:00:00.456	0.0%	376	0.000	1.213	239.000
TOTAL: 00:53:36.976	00:53:20.360	99.5%	-	-	-	-

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
nl-admin@nic.surfnet.nl	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	318	760666	753892	195	6579	99%	161:32:40
2	news.nask.pl	2	16637	2622	13668	347	15%	25:22:59
3	news.icm.edu.pl	41	338	30	202	106	8%	16:55:00
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		366	777645	756548	14065	7032	97%	203:50:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	194.1 GB	8.3 MB	622.1 MB	194.7 GB	99%	268.5 KB
2	news.nask.pl	10.3 MB	873.6 KB	1.1 MB	12.3 MB	84%	4.2 KB
3	news.icm.edu.pl	993.0 KB	59.4 KB	6.5 MB	7.5 MB	12%	56.6 KB
4	localhost	5.7 KB	0.0 KB	0.0 KB	5.7 KB	100%	1.4 KB
TOTAL: 4		194.1 GB	9.2 MB	629.7 MB	194.7 GB	99%	267.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 21 04:15:03 - 04:59:59	46190	6.1%	17.13	12.6 GB	6.5%	4918.18
Sep 21 05:00:00 - 05:59:59	54233	7.2%	15.06	15.0 GB	7.7%	4373.00
Sep 21 06:00:00 - 06:59:59	62363	8.2%	17.32	17.8 GB	9.2%	5199.00
Sep 21 07:00:00 - 07:59:59	54503	7.2%	15.14	15.6 GB	8.0%	4544.89
Sep 21 08:00:00 - 08:59:59	61543	8.1%	17.10	18.1 GB	9.3%	5286.43
Sep 21 09:00:00 - 09:59:59	8664	1.1%	2.41	1.5 GB	0.8%	443.36
Sep 21 10:00:00 - 10:59:59	10941	1.4%	3.04	1.8 GB	0.9%	526.09
Sep 21 11:00:00 - 11:59:59	12166	1.6%	3.38	2.2 GB	1.1%	632.01
Sep 21 12:00:00 - 12:59:59	12856	1.7%	3.57	2.2 GB	1.1%	644.96
Sep 21 13:00:00 - 13:59:59	12381	1.6%	3.44	2.0 GB	1.0%	585.34
Sep 21 14:00:00 - 14:59:59	12423	1.6%	3.45	1.9 GB	1.0%	567.39
Sep 21 15:00:00 - 15:59:59	13803	1.8%	3.83	2.1 GB	1.1%	625.89
Sep 21 16:00:00 - 16:59:59	14436	1.9%	4.01	1.9 GB	1.0%	544.99
Sep 21 17:00:00 - 17:59:59	12940	1.7%	3.59	2.1 GB	1.1%	620.35
Sep 21 18:00:00 - 18:59:59	15866	2.1%	4.41	2.6 GB	1.3%	758.53
Sep 21 19:00:00 - 19:59:59	17942	2.4%	4.98	2.6 GB	1.3%	762.11
Sep 21 20:00:00 - 20:59:59	18393	2.4%	5.11	2.4 GB	1.2%	706.82
Sep 21 21:00:00 - 21:59:59	16758	2.2%	4.66	2.4 GB	1.3%	707.87
Sep 21 22:00:00 - 22:59:59	14851	2.0%	4.13	2.2 GB	1.2%	653.07
Sep 21 23:00:00 - 23:59:59	13749	1.8%	3.82	2.3 GB	1.2%	672.69
Sep 22 00:00:00 - 00:59:59	52607	7.0%	14.61	14.8 GB	7.6%	4300.13
Sep 22 01:00:00 - 01:59:59	75928	10.0%	21.09	22.8 GB	11.8%	6647.73
Sep 22 02:00:00 - 02:59:59	69158	9.1%	19.21	21.9 GB	11.3%	6389.15
Sep 22 03:00:00 - 03:59:59	56419	7.5%	15.67	18.3 GB	9.4%	5335.54
Sep 22 04:00:00 - 04:15:02	15532	2.1%	17.22	4.7 GB	2.4%	5437.44
TOTAL: 23:59:59	756645	100.0%	8.76	194.2 GB	100.0%	2357.29

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6710	174	1712	4	4820
2	news.nask.pl	339	59	270	2	8
3	news.icm.edu.pl	125	0	2	0	123
TOTAL: 3		7174	233	1984	6	4951

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	62
alt.sex.telephones	44
comp.mobile.ipad	26
comp.mobile.android	20
it.tlc.cellulari.android	19
comp.sys.raspberry-pi	18
it.comp.os.win.windows8	13
nl.comp.os.linux.techniek	8
it.comp.os.win.windows7	6
rec.arts.tv.x-factor	3
de.rec.sf.startrek	3
hr.ponuda.hardver.komponente	3
de.comm.provider.telefonie	2
it.tlc.cellulari.iphone	1
it.comp.os.linux.ubuntu	1
uk.rec.cycling.moderated	1
it.discussioni.energia	1
hr.ponuda.hardver.konfiguracije	1
hr.alt.cellular.gsm.iphone	1
TOTAL: 19	233

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

Groups	Count
pl.sci.matematyka	6
TOTAL: 1	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	4689
EMP (md5)	119
Scoring filter (10)	9
Attached HTML file	1
TOTAL: 4	4818

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	37128	1031	35243	182	0	0	2%	24:01:02
2	icm	33623	499	24222	1195	1	1	1%	24:02:03
3	nask	23713	294	13958	16	0	0	1%	24:02:13
4	poznan	3287	4	2338	10	0	0	0%	23:57:27
5	okapi	0	0	0	0	0	1762	0%	24:00:00
TOTAL: 5		97751	1828	75761	1403	1	1763	1%	120:02:45

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	35.7 MB	5.9 MB	41.6 MB	0.5 KB/s	137.3 KB	24:02:13
2	icm	8.9 MB	211.2 MB	220.1 MB	2.6 KB/s	133.0 KB	24:02:03
3	panorama	4.1 MB	581.2 KB	4.6 MB	0.1 KB/s	3.9 KB	24:01:02
4	poznan	15.8 KB	21.5 KB	37.3 KB	0.0 KB/s	2.7 KB	23:57:27
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		48.7 MB	217.6 MB	266.3 MB	0.6 KB/s	84.4 KB	120:02:45

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	316	482.9 KB	288	0	00:00:07
2	web.man.lodz.pl	286	60	55.1 KB	28	0	00:00:20
3	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 3		578	376	538.0 KB	316	4	00:53:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	316	482.9 KB	288	0	00:00:07
2	*.man.lodz.pl	673	60	55.1 KB	28	4	00:00:26
3	*.181.14.62.static.jazztel.es	8	0	0.0 KB	0	0	00:53:01
4	*.toya.net.pl	8	0	0.0 KB	0	0	00:00:00
TOTAL: 4		977	376	538.0 KB	316	4	00:53:36

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
162.181.14.62.static.jazztel.es	0.671	0.910	00:53:01
web.man.lodz.pl	1.081	4.399	00:00:20
pc-skrzep2.cm.p.lodz.pl	1.018	4.379	00:00:07
mon.man.lodz.pl	1.116	4.254	00:00:04
david.man.lodz.pl	0.416	1.299	00:00:01
staticline41435.toya.net.pl	0.041	0.101	00:00:00
isvcs01.man.lodz.pl	0.050	0.144	00:00:00
TOTAL: 7	4.393	15.486	00:53:36

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	95
staticline41435.toya.net.pl	8
162.181.14.62.static.jazztel.es	8
TOTAL: 4	399

NNRP client timeouts [Top 20]:

System	Conn	Peer
162.181.14.62.static.jazztel.es	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	316	84.0%
2	pl	60	16.0%
TOTAL: 2		376	100.0%

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

Newsgroup		Count
1	lodman.info	316
2	pl.comp.os.ms-windows.winnt	36
3	pl.comp.pecet	24
TOTAL: 3		376

Daily Usenet report

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