Daily Usenet report: Jan 21 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Jan 21 04:19:08 newsfeed nnrpd[27903]: david.man.lodz.pl rejected Expiring process 27850
Jan 21 04:39:09 newsfeed pgpverify[28629]: pgpverify: verification failed
Jan 21 10:27:36 newsfeed nnrpd[8359]: SERVER perl filter not defined
Jan 21 11:55:16 newsfeed nnrpd[11286]: SERVER perl filter not defined
Jan 21 11:55:47 newsfeed overchan[11000]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	984914	97.5%	114.5 MB	97.0%
innd	11196	1.1%	1.8 MB	1.5%
nnrpd	7210	0.7%	746.9 KB	0.6%
innfeed	6588	0.7%	973.8 KB	0.8%
controlchan	5	0.0%	0.6 KB	0.0%
overchan	2	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	1009916	100.0%	118.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	952613	96.1%
Negative hits	21319	2.2%
Positive hits	17275	1.7%
Cache misses	131	0.0%
TOTAL: 4	991338	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.032	0.0%	251	0.000	35.984	937.000
article cleanup	00:00:14.437	0.0%	956384	0.000	0.015	0.047
article logging	00:00:26.507	0.0%	985114	0.000	0.027	0.081
article parse	00:33:21.716	2.3%	76008996	0.009	0.026	0.057
article write	00:08:02.087	0.6%	953142	0.139	0.506	3.754
artlog/artcncl	00:00:00.004	0.0%	243	0.000	0.016	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:21.751	0.1%	81849188	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.096	0.0%	243	0.000	4.510	259.000
hishave/artcncl	00:00:00.421	0.0%	251	0.000	1.677	18.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:51:17.554	3.6%	992854	0.309	3.100	9.000
history sync	00:00:00.026	0.0%	435	0.000	0.060	1.000
history write	01:10:18.970	4.9%	954201	0.874	4.421	17.251
hiswrite/artcncl	00:00:00.028	0.0%	8	0.000	3.500	11.000
idle	18:13:33.479	75.9%	55232159	0.291	1.188	10.074
nntp read	00:30:13.969	2.1%	75294172	0.011	0.024	0.041
overview write	00:00:06.193	0.0%	953142	0.000	0.006	0.038
perl filter	02:04:26.568	8.6%	954201	3.398	7.825	13.323
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:54.178	0.1%	1940559	0.028	0.059	0.154
TOTAL: 24:00:29.356	23:35:28.016	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.117	0.0%	36700	0.000	0.003	0.200
article prepare	00:00:00.005	0.0%	14316	0.000	0.000	0.250
article read	00:01:11.592	0.1%	3345	0.000	21.403	185.333
backlog stats	00:00:00.135	0.0%	230863	0.000	0.001	0.030
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.418	0.0%	138270	0.000	0.010	1.000
data write	00:00:06.123	0.0%	124089	0.000	0.049	1.000
idle	23:59:12.937	99.9%	230863	0.000	374.044	20520.000
status file	00:00:01.220	0.0%	215	0.000	5.674	243.000
TOTAL: 24:00:38.837	24:00:33.547	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.177	0.0%	55	3.218	3.218	3.218
idle	15:41:09.175	99.9%	362203	0.000	155.905	112537.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:12.453	0.0%	726813	0.000	0.017	0.556
readart	00:00:15.753	0.0%	683	0.000	23.064	85.000
TOTAL: 15:42:12.724	15:41:37.558	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
newgroup	1
pause	2
paused	2
reload	2
reserve	2
TOTAL: 12	191

Newsgroups created:

Group	Mode
free.speech	y
TOTAL	1

Control Channel:

Sender	newgroup	Bad PGP	DoIt	OK
nl-admin@nic.surfnet.nl	0	1	0	0
sealteam6@osama-is-dead.net	1	0	1	1
TOTAL	1	1	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	410	953219	950171	204	2844	99%	193:32:14
2	news.nask.pl	2	16675	2088	14211	376	12%	23:21:52
3	news.icm.edu.pl	48	307	39	228	40	12%	19:29:03
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		463	970203	952300	14643	3260	98%	236:23:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	268.0 GB	26.5 MB	98.1 MB	268.1 GB	99%	295.0 KB
2	news.nask.pl	7.8 MB	997.1 KB	1.1 MB	9.8 MB	79%	4.1 KB
3	news.icm.edu.pl	1.1 MB	0.0 KB	901.0 KB	2.0 MB	55%	25.9 KB
4	localhost	3.7 KB	0.0 KB	0.0 KB	3.7 KB	100%	1.9 KB
TOTAL: 4		268.0 GB	27.5 MB	100.0 MB	268.1 GB	99%	294.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 21 04:15:02 - 04:59:59	44104	4.6%	16.35	17.3 GB	6.5%	6739.50
Jan 21 05:00:00 - 05:59:59	56065	5.9%	15.57	20.6 GB	7.7%	6009.67
Jan 21 06:00:00 - 06:59:59	71303	7.5%	19.81	27.6 GB	10.3%	8045.87
Jan 21 07:00:00 - 07:59:59	82178	8.6%	22.83	29.3 GB	10.9%	8547.26
Jan 21 08:00:00 - 08:59:59	84376	8.9%	23.44	28.6 GB	10.7%	8333.15
Jan 21 09:00:00 - 09:59:59	63523	6.7%	17.65	22.1 GB	8.2%	6427.12
Jan 21 10:00:00 - 10:59:59	11063	1.2%	3.07	2.2 GB	0.8%	645.46
Jan 21 11:00:00 - 11:59:59	10190	1.1%	2.83	2.4 GB	0.9%	704.43
Jan 21 12:00:00 - 12:59:59	10979	1.2%	3.05	2.7 GB	1.0%	786.96
Jan 21 13:00:00 - 13:59:59	9524	1.0%	2.65	2.1 GB	0.8%	617.86
Jan 21 14:00:00 - 14:59:59	9130	1.0%	2.54	2.1 GB	0.8%	601.13
Jan 21 15:00:00 - 15:59:59	10116	1.1%	2.81	2.0 GB	0.8%	592.24
Jan 21 16:00:00 - 16:59:59	9168	1.0%	2.55	1.9 GB	0.7%	552.78
Jan 21 17:00:00 - 17:59:59	12855	1.3%	3.57	1.8 GB	0.7%	524.85
Jan 21 18:00:00 - 18:59:59	13051	1.4%	3.63	1.4 GB	0.5%	415.20
Jan 21 19:00:00 - 19:59:59	21968	2.3%	6.10	2.0 GB	0.7%	571.85
Jan 21 20:00:00 - 20:59:59	51810	5.4%	14.39	6.3 GB	2.4%	1843.46
Jan 21 21:00:00 - 21:59:59	60110	6.3%	16.70	7.3 GB	2.7%	2138.92
Jan 21 22:00:00 - 22:59:59	51822	5.4%	14.39	6.9 GB	2.6%	1995.85
Jan 21 23:00:00 - 23:59:59	8757	0.9%	2.43	1.4 GB	0.5%	418.20
Jan 22 00:00:00 - 00:59:59	64676	6.8%	17.97	18.3 GB	6.8%	5337.92
Jan 22 01:00:00 - 01:59:59	72884	7.7%	20.25	21.7 GB	8.1%	6329.93
Jan 22 02:00:00 - 02:59:59	60025	6.3%	16.67	19.5 GB	7.3%	5678.68
Jan 22 03:00:00 - 03:59:59	43114	4.5%	11.98	14.1 GB	5.2%	4092.80
Jan 22 04:00:00 - 04:15:03	19908	2.1%	22.05	6.3 GB	2.4%	7364.39
TOTAL: 24:00:01	952699	100.0%	11.03	268.2 GB	100.0%	3254.53

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	31569	265	1848	3	29453
2	news.nask.pl	369	9	336	5	19
3	news.icm.edu.pl	34	0	0	0	34
TOTAL: 3		31972	274	2184	8	29506

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	166
comp.mobile.android	16
comp.mobile.ipad	13
uk.rec.cycling.moderated	12
it.tlc.cellulari.android	10
de.rec.sf.startrek	9
it.comp.os.win.windows8	8
uk.current-events.charlie-hebdo	7
it.comp.os.win.windows7	6
nl.comp.os.linux.techniek	5
hr.ponuda.hardver.komponente	4
hr.alt.cellular.gsm.android	3
hr.rec.sport.rukomet	3
hr.alt.cellular.gsm.iphone	3
alt.sex.telephones	2
hr.soc.humanitarne-akcije	2
comp.sys.raspberry-pi	1
it.politica.m5s	1
nl.comp.sys.android	1
de.comm.software.mailreader	1
TOTAL: 21	274

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

Groups	Count
pl.sci.historia	6
bit.listserv.catholic	1
pl.sci.matematyka	1
TOTAL: 3	8

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	714
EMP (md5)	45
HTML post	14
EMP (fsl)	2
Scoring filter (9)	1
TOTAL: 5	776

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	34805	980	33093	101	0	2%	23:55:01
2	icm	40615	933	31597	179	917	2%	23:52:56
3	nask	26746	105	17366	1248	1510	0%	23:52:47
4	poznan	2719	12	1941	7	0	0%	23:56:22
5	okapi	0	0	0	0	2464	0%	23:56:44
TOTAL: 5		104885	2030	83997	1535	4891	1%	119:33:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	216.5 MB	29.3 MB	245.8 MB	2.9 KB/s	226.4 KB	23:52:56
2	panorama	3.4 MB	476.9 KB	3.8 MB	0.0 KB/s	3.6 KB	23:55:01
3	nask	731.6 KB	486.6 MB	487.3 MB	5.8 KB/s	368.8 KB	23:52:47
4	poznan	34.8 KB	12.5 KB	47.4 KB	0.0 KB/s	2.5 KB	23:56:22
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:56:44
TOTAL: 5		220.6 MB	516.4 MB	737.0 MB	1.8 KB/s	211.7 KB	119:33:50

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	312	673.8 KB	288	0	00:00:06
2	bursztyn.p.lodz.pl	1	215	37.9 MB	4	0	00:32:18
3	lze200.lze.lodz.pl	7	86	153.1 KB	10	0	01:23:31
4	web.man.lodz.pl	288	70	66.4 KB	29	0	00:00:21
5	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 5		586	683	38.8 MB	331	2	15:42:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	312	673.8 KB	288	0	00:00:06
2	*.p.lodz.pl	1	215	37.9 MB	4	0	00:32:18
3	*.lze.lodz.pl	7	86	153.1 KB	10	0	01:23:31
4	*.man.lodz.pl	674	70	66.4 KB	29	2	00:00:26
5	unresolved	6	0	0.0 KB	0	0	13:43:35
6	*.toya.net.pl	68	0	0.0 KB	0	0	00:02:14
TOTAL: 6		1044	683	38.8 MB	331	2	15:42:12

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.192.95	15.733	23.235	13:43:28
lze200.lze.lodz.pl	0.047	0.164	01:23:31
bursztyn.p.lodz.pl	0.043	0.313	00:32:18
staticline41435.toya.net.pl	0.255	1.328	00:02:14
web.man.lodz.pl	0.982	5.626	00:00:21
pc-skrzep2.cm.p.lodz.pl	0.959	5.621	00:00:06
212.51.203.199	0.009	0.036	00:00:06
mon.man.lodz.pl	0.982	5.634	00:00:04
david.man.lodz.pl	0.336	1.834	00:00:01
isvcs01.man.lodz.pl	0.011	0.043	00:00:00
?	0.007	0.048	00:00:00
TOTAL: 11	19.364	43.882	15:42:12

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	68
185.48.192.95	4
212.51.203.199	2
TOTAL: 5	458

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	1
TOTAL: 1	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	312	45.7%
2	alt	215	31.5%
3	pl	156	22.8%
TOTAL: 3		683	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	alt.binaries.pictures.sex	119
3	alt.binaries.pictures.erotica.groupsex	90
4	pl.comp.pecet	46
5	pl.comp.os.ms-windows.winnt	43
6	pl.misc.telefonia.gsm	29
7	pl.rec.dom	24
8	pl.news.admin	11
9	alt.binaries.pictures.groupsex	4
10	pl.internet.polip	3
11	alt.binaries.bisexuals	2
TOTAL: 11		683

Daily Usenet report

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