Daily Usenet report: Jun 1 04:15:03

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Jun 1 07:04:03 newsfeed pgpverify[24043]: pgpverify: verification failed
Jun 1 09:15:33 newsfeed nnrpd[28567]: SERVER perl filter not defined
Jun 1 13:05:44 newsfeed nnrpd[4067]: SERVER perl filter not defined
Jun 1 16:14:21 newsfeed nnrpd[10857]: SERVER perl filter not defined
Jun 1 16:47:11 newsfeed nnrpd[12086]: SERVER perl filter not defined
Jun 1 17:18:33 newsfeed nnrpd[13168]: SERVER perl filter not defined
Jun 1 18:38:06 newsfeed nnrpd[15901]: SERVER perl filter not defined
Jun 1 20:33:41 newsfeed nnrpd[19832]: SERVER perl filter not defined
Jun 1 22:00:12 newsfeed nnrpd[22903]: SERVER perl filter not defined
Jun 1 22:05:20 newsfeed nnrpd[23004]: SERVER perl filter not defined
Jun 2 04:15:02 newsfeed nnrpd[3480]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2438019	98.8%	282.4 MB	98.4%
innd	20389	0.8%	3.4 MB	1.2%
nnrpd	7060	0.3%	747.5 KB	0.3%
innfeed	2535	0.1%	388.8 KB	0.1%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2468007	100.0%	286.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2432577	98.0%
Negative hits	34152	1.4%
Positive hits	15690	0.6%
Cache misses	136	0.0%
TOTAL: 4	2482555	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.988	0.0%	267	0.000	7.446	123.000
article cleanup	00:00:31.863	0.0%	2437799	0.004	0.013	0.026
article logging	00:01:16.609	0.1%	2437952	0.017	0.031	0.158
article parse	01:01:02.293	4.2%	119213913	0.018	0.031	0.089
article write	00:22:37.612	1.6%	2433621	0.408	0.558	1.921
artlog/artcncl	00:00:00.002	0.0%	212	0.000	0.009	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:45.232	0.2%	134607893	0.000	0.001	0.012
hisgrep/artcncl	00:00:00.747	0.0%	249	0.000	3.000	48.500
hishave/artcncl	00:00:00.132	0.0%	267	0.000	0.494	6.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:13:06.487	0.9%	2484142	0.070	0.317	9.254
history sync	00:00:00.017	0.0%	291	0.000	0.058	2.000
history write	00:20:42.453	1.4%	2434137	0.200	0.510	10.845
hiswrite/artcncl	00:00:00.015	0.0%	18	0.000	0.833	11.000
idle	14:42:50.074	61.3%	91306448	0.206	0.580	2.505
nntp read	00:54:50.689	3.8%	117315558	0.018	0.028	0.046
overview write	00:00:15.163	0.0%	2433621	0.001	0.006	0.047
perl filter	05:38:55.999	23.5%	2434137	6.405	8.355	13.871
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:39.014	0.3%	4902575	0.040	0.057	0.269
TOTAL: 24:00:07.870	23:23:36.389	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.151	0.0%	35345	0.000	0.004	0.133
article prepare	00:00:00.011	0.0%	36926	0.000	0.000	0.083
article read	00:00:00.993	0.0%	8969	0.000	0.111	1.000
backlog stats	00:00:00.205	0.0%	236534	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.988	0.0%	141610	0.000	0.014	0.397
data write	00:00:09.100	0.0%	106918	0.000	0.085	4.519
idle	23:59:39.772	100.0%	236534	87.848	365.190	2016.812
status file	00:00:00.626	0.0%	234	0.000	2.675	47.250
TOTAL: 23:59:58.615	23:59:52.846	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	13:57:52.754	99.9%	313513	0.000	160.353	450064.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:12.869	0.0%	629175	0.000	0.020	3.889
readart	00:00:04.370	0.0%	608	0.000	7.188	28.000
TOTAL: 13:58:49.168	13:58:09.993	99.9%	-	-	-	-

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
us-control@lists.killfile.org	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	1126	2433350	2428683	820	3847	99%	260:38:25
2	news.nask.pl	2	15982	4761	10947	274	29%	24:05:14
3	news.icm.edu.pl	59	250	30	166	54	12%	17:06:33
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		1197	2449591	2433483	11933	4175	99%	301:50:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	790.3 GB	19.1 MB	44.6 MB	790.4 GB	99%	340.7 KB
2	news.nask.pl	27.7 MB	930.5 KB	952.4 KB	29.5 MB	93%	6.0 KB
3	news.icm.edu.pl	842.8 KB	209.1 KB	2.3 MB	3.4 MB	24%	41.0 KB
4	localhost	13.2 KB	0.0 KB	0.0 KB	13.2 KB	100%	1.5 KB
TOTAL: 4		790.3 GB	20.2 MB	47.9 MB	790.4 GB	99%	340.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 1 04:15:03 - 04:59:59	61822	2.5%	22.92	24.9 GB	3.1%	9663.74
Jun 1 05:00:00 - 05:59:59	73096	3.0%	20.30	28.2 GB	3.6%	8202.93
Jun 1 06:00:00 - 06:59:59	89760	3.7%	24.93	32.5 GB	4.1%	9473.57
Jun 1 07:00:00 - 07:59:59	94735	3.9%	26.32	32.2 GB	4.1%	9373.99
Jun 1 08:00:00 - 08:59:59	97210	4.0%	27.00	34.0 GB	4.3%	9908.69
Jun 1 09:00:00 - 09:59:59	97421	4.0%	27.06	33.4 GB	4.2%	9733.31
Jun 1 10:00:00 - 10:59:59	85826	3.5%	23.84	30.8 GB	3.9%	8961.45
Jun 1 11:00:00 - 11:59:59	106082	4.4%	29.47	35.4 GB	4.5%	10318.99
Jun 1 12:00:00 - 12:59:59	121374	5.0%	33.72	37.1 GB	4.7%	10802.33
Jun 1 13:00:00 - 13:59:59	122212	5.0%	33.95	36.9 GB	4.7%	10742.98
Jun 1 14:00:00 - 14:59:59	122086	5.0%	33.91	37.0 GB	4.7%	10764.64
Jun 1 15:00:00 - 15:59:59	128713	5.3%	35.75	37.2 GB	4.7%	10846.77
Jun 1 16:00:00 - 16:59:59	124284	5.1%	34.52	36.7 GB	4.6%	10700.27
Jun 1 17:00:00 - 17:59:59	111345	4.6%	30.93	35.7 GB	4.5%	10385.16
Jun 1 18:00:00 - 18:59:59	106061	4.4%	29.46	33.0 GB	4.2%	9615.53
Jun 1 19:00:00 - 19:59:59	112699	4.6%	31.31	35.9 GB	4.5%	10461.98
Jun 1 20:00:00 - 20:59:59	108426	4.5%	30.12	34.8 GB	4.4%	10125.78
Jun 1 21:00:00 - 21:59:59	106544	4.4%	29.60	35.1 GB	4.4%	10226.27
Jun 1 22:00:00 - 22:59:59	93651	3.8%	26.01	30.3 GB	3.8%	8812.84
Jun 1 23:00:00 - 23:59:59	90857	3.7%	25.24	30.1 GB	3.8%	8768.62
Jun 2 00:00:00 - 00:59:59	82638	3.4%	22.95	24.8 GB	3.1%	7219.63
Jun 2 01:00:00 - 01:59:59	87338	3.6%	24.26	27.0 GB	3.4%	7877.83
Jun 2 02:00:00 - 02:59:59	96261	4.0%	26.74	31.1 GB	3.9%	9068.37
Jun 2 03:00:00 - 03:59:59	92157	3.8%	25.60	29.8 GB	3.8%	8671.87
Jun 2 04:00:00 - 04:15:02	20878	0.9%	23.15	6.6 GB	0.8%	7631.52
TOTAL: 23:59:59	2433476	100.0%	28.17	790.4 GB	100.0%	9592.43

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3981	187	3395	0	399
2	news.nask.pl	295	3	265	1	26
3	news.icm.edu.pl	55	0	2	0	53
TOTAL: 3		4331	190	3662	1	478

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	104
it.tlc.cellulari.android	24
it.comp.os.win.windows8	15
hr.alt.cellular.gsm.iphone	11
it.comp.os.win.windows7	9
uk.rec.cycling.moderated	8
hr.ponuda.hardver.komponente	5
comp.mobile.ipad	4
comp.mobile.android	3
comp.sys.mac.vintage	2
it.discussioni.energia	2
alt.sex.telephones	1
it.comp.os.linux.ubuntu	1
de.comm.software.mailreader	1
TOTAL: 14	190

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	239
EMP (md5)	43
EMP (fsl)	29
Too many newsgroups	11
HTML post	2
Scoring filter (8)	1
TOTAL: 6	325

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	46370	3486	31412	477	0	13	7%	24:02:53
2	icm	45513	872	28440	4233	1	0	1%	24:01:17
3	poznan	6982	121	4577	61	0	0	1%	23:56:33
4	okapi	0	0	0	0	0	2235	0%	24:00:00
5	nask	0	0	0	0	0	15675	0%	24:00:00
TOTAL: 5		98865	4479	64429	4771	1	17923	4%	120:00:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	16.2 MB	768.0 MB	784.2 MB	9.3 KB/s	157.3 KB	24:01:17
2	panorama	13.9 MB	1.9 MB	15.8 MB	0.2 KB/s	4.1 KB	24:02:53
3	poznan	8.2 MB	1.1 MB	9.3 MB	0.1 KB/s	52.1 KB	23:56:33
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		38.3 MB	771.0 MB	809.3 MB	1.9 KB/s	89.6 KB	120:00:43

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	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	396	589.2 KB	288	0	00:00:08
2	dmcs2.p.lodz.pl	3	160	333.3 KB	4	0	00:35:24
3	web.man.lodz.pl	288	32	39.9 KB	16	0	00:00:29
4	pc-212-51-218-225.p.lodz.pl	8	20	38.1 KB	3	0	03:07:17
5	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:00
TOTAL: 5		596	608	1000.5 KB	311	9	13:58:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	396	589.2 KB	288	0	00:00:08
2	*.p.lodz.pl	11	180	371.4 KB	7	0	03:42:41
3	*.man.lodz.pl	681	32	39.9 KB	16	9	00:00:34
4	*.toya.net.pl	38	0	0.0 KB	0	0	00:00:02
5	*.snarked.org	2	0	0.0 KB	0	0	00:00:02
6	unresolved	2	0	0.0 KB	0	0	10:15:18
TOTAL: 6		1022	608	1000.5 KB	311	9	13:58:48

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.195.96	18.777	26.876	10:15:18
pc-212-51-218-225.p.lodz.pl	0.035	0.098	03:07:17
dmcs2.p.lodz.pl	0.032	0.171	00:35:24
web.man.lodz.pl	1.040	3.620	00:00:29
pc-skrzep2.cm.p.lodz.pl	0.970	4.385	00:00:08
mon.man.lodz.pl	0.922	4.152	00:00:03
staticline41435.toya.net.pl	0.167	0.486	00:00:02
news.snarked.org	0.038	0.032	00:00:02
david.man.lodz.pl	0.307	1.218	00:00:01
isvcs01.man.lodz.pl	0.063	0.131	00:00:00
TOTAL: 10	22.351	41.169	13:58:48

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	38
185.48.195.96	2
news.snarked.org	2
TOTAL: 5	426

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	396	65.1%
2	pl	212	34.9%
TOTAL: 2		608	100.0%

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

Newsgroup		Count
1	lodman.info	396
2	pl.misc.kolej	100
3	pl.misc.elektronika	80
4	pl.comp.os.ms-windows.winnt	18
5	pl.comp.pecet	14
TOTAL: 5		608

Daily Usenet report

Jun 1 04:15:03 -- Jun 2 04:15:02