Daily Usenet report: Sep 18 04:15:02

Unknown entries from news log file:

First 50 / 45335 lines (0.1%)

Sep 18 04:15:02 newsfeed nnrpd[14680]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14687]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14684]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14686]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14683]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14678]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14689]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14681]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14682]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14688]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14677]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14676]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14685]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:02 newsfeed nnrpd[14679]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:48 newsfeed nnrpd[15085]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15086]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15088]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15087]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15089]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15090]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15091]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15098]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15092]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15094]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15093]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15096]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15095]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15099]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15100]: 212.191.227.2 noperm ihave without permission
Sep 18 04:15:52 newsfeed nnrpd[15097]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:38 newsfeed nnrpd[15109]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15110]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15111]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15112]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15113]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15115]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15114]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15116]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15118]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15119]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15117]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15121]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15120]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15122]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15124]: 212.191.227.2 noperm ihave without permission
Sep 18 04:16:42 newsfeed nnrpd[15123]: 212.191.227.2 noperm ihave without permission
Sep 18 04:17:28 newsfeed nnrpd[15128]: 212.191.227.2 noperm ihave without permission
Sep 18 04:17:33 newsfeed nnrpd[15129]: 212.191.227.2 noperm ihave without permission
Sep 18 04:17:33 newsfeed nnrpd[15131]: 212.191.227.2 noperm ihave without permission
Sep 18 04:17:33 newsfeed nnrpd[15132]: 212.191.227.2 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	232569	52.4%	24.5 MB	50.4%
inn	187628	42.3%	21.5 MB	44.2%
innd	18829	4.2%	2.1 MB	4.3%
innfeed	4819	1.1%	541.5 KB	1.1%
TOTAL: 4	443845	100.0%	48.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	184341	84.6%
Negative hits	31427	14.4%
Positive hits	2072	1.0%
Cache misses	21	0.0%
TOTAL: 4	217861	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.600	0.0%	132	0.000	12.121	242.000
article cleanup	00:00:02.593	0.0%	185063	0.000	0.014	0.333
article logging	00:00:05.207	0.0%	185488	0.000	0.028	1.000
article parse	00:04:10.612	0.3%	9084733	0.000	0.028	0.500
article write	00:01:31.124	0.1%	172652	0.000	0.528	83.100
artlog/artcncl	00:00:00.002	0.0%	113	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:09.772	0.0%	10042918	0.000	0.001	0.096
hisgrep/artcncl	00:00:00.272	0.0%	85	0.000	3.200	67.000
hishave/artcncl	00:00:00.547	0.0%	132	0.000	4.144	44.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:14:06.932	1.0%	217905	0.000	3.887	90.500
history sync	00:00:00.079	0.0%	1029	0.000	0.077	2.000
history write	00:15:03.888	1.0%	184424	0.263	4.901	84.600
hiswrite/artcncl	00:00:00.453	0.0%	47	0.000	9.638	21.000
idle	22:48:41.886	95.1%	5956611	0.217	13.787	1103.370
nntp read	00:03:17.430	0.2%	8981214	0.000	0.022	0.250
overview write	00:00:00.967	0.0%	172652	0.000	0.006	1.000
perl filter	00:19:42.554	1.4%	184424	0.833	6.412	25.000
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:20.511	0.0%	362706	0.000	0.057	0.333
TOTAL: 23:59:19.202	23:47:16.429	99.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.062	0.0%	22991	0.000	0.003	0.286
article prepare	00:00:00.005	0.0%	16966	0.000	0.000	0.036
article read	00:00:13.864	0.0%	4244	0.000	3.267	119.500
backlog stats	00:00:00.086	0.0%	106435	0.000	0.001	0.083
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:00.845	0.0%	61594	0.000	0.014	0.158
data write	00:00:02.974	0.0%	48696	0.000	0.061	0.920
idle	24:00:02.969	100.0%	106435	112.172	811.791	20067.000
status file	00:00:00.670	0.0%	921	0.000	0.727	2.000
TOTAL: 24:00:24.420	24:00:21.475	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:35:56.483	52.1%	142569	0.000	166.631	300420.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:05.749	0.0%	285941	0.000	0.020	21.926
readart	00:00:00.079	0.0%	101	0.000	0.782	78.000
TOTAL: 12:40:11.630	06:36:02.311	52.1%	-	-	-	-

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	26	152956	152577	32	347	99%	20:57:50
2	news.nask.pl	2	26877	14325	724	11828	53%	23:58:39
3	212.191.227.2	0	8042	7599	260	183	94%	02:34:40
4	news.icm.edu.pl	42	465	58	375	32	12%	18:38:15
5	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 5		72	188341	174560	1391	12390	92%	66:09:24

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	45.8 GB	2.0 MB	2.0 MB	45.8 GB	99%	313.9 KB
2	212.191.227.2	212.2 MB	1013.9 KB	1020.5 KB	214.2 MB	99%	28.2 KB
3	news.nask.pl	89.4 MB	535.0 KB	132.8 MB	222.8 MB	40%	8.7 KB
4	news.icm.edu.pl	4.0 MB	151.6 KB	1.2 MB	5.3 MB	75%	59.9 KB
5	localhost	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
TOTAL: 5		46.1 GB	3.6 MB	137.0 MB	46.2 GB	99%	259.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 18 04:15:02 - 04:59:59	1604	0.9%	0.59	43.2 MB	0.1%	16.40
Sep 18 05:00:00 - 05:59:59	2568	1.5%	0.71	57.8 MB	0.1%	16.45
Sep 18 06:00:00 - 06:59:59	3788	2.2%	1.05	80.7 MB	0.2%	22.95
Sep 18 07:00:00 - 07:59:59	367	0.2%	0.10	2.3 MB	0.0%	0.66
Sep 18 08:00:00 - 08:59:59	646	0.4%	0.18	5.7 MB	0.0%	1.63
Sep 18 09:00:00 - 09:59:59	500	0.3%	0.14	2.6 MB	0.0%	0.74
Sep 18 10:00:00 - 10:59:59	367	0.2%	0.10	1.8 MB	0.0%	0.52
Sep 18 11:00:00 - 11:59:59	466	0.3%	0.13	2.2 MB	0.0%	0.62
Sep 18 12:00:00 - 12:59:59	553	0.3%	0.15	3.9 MB	0.0%	1.10
Sep 18 13:00:00 - 13:59:59	657	0.4%	0.18	9.5 MB	0.0%	2.69
Sep 18 14:00:00 - 14:59:59	643	0.4%	0.18	2.1 MB	0.0%	0.60
Sep 18 15:00:00 - 15:59:59	745	0.4%	0.21	5.5 MB	0.0%	1.56
Sep 18 16:00:00 - 16:59:59	761	0.4%	0.21	6.9 MB	0.0%	1.97
Sep 18 17:00:00 - 17:59:59	745	0.4%	0.21	4.0 MB	0.0%	1.14
Sep 18 18:00:00 - 18:59:59	814	0.5%	0.23	2.9 MB	0.0%	0.82
Sep 18 19:00:00 - 19:59:59	904	0.5%	0.25	6.0 MB	0.0%	1.72
Sep 18 20:00:00 - 20:59:59	898	0.5%	0.25	4.9 MB	0.0%	1.39
Sep 18 21:00:00 - 21:59:59	806	0.5%	0.22	3.3 MB	0.0%	0.93
Sep 18 22:00:00 - 22:59:59	810	0.5%	0.23	3.5 MB	0.0%	1.00
Sep 18 23:00:00 - 23:59:59	872	0.5%	0.24	5.6 MB	0.0%	1.59
Sep 19 00:00:00 - 00:59:59	625	0.4%	0.17	3.2 MB	0.0%	0.90
Sep 19 01:00:00 - 01:59:59	638	0.4%	0.18	2.9 MB	0.0%	0.83
Sep 19 02:00:00 - 02:59:59	570	0.3%	0.16	2.5 MB	0.0%	0.71
Sep 19 03:00:00 - 03:59:59	120351	68.9%	33.43	35.9 GB	77.8%	10450.89
Sep 19 04:00:00 - 04:15:02	32976	18.9%	36.56	10.0 GB	21.7%	11627.47
TOTAL: 24:00:00	174674	100.0%	2.02	46.1 GB	100.0%	559.96

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	12256	23	114	1	12118
2	newsfeed.pionier.net.pl	356	0	347	0	9
3	212.191.227.2	197	3	180	0	14
4	news.icm.edu.pl	32	0	3	0	29
TOTAL: 4		12841	26	644	1	12170

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.mobile.android	10
rec.arts.tv.x-factor	5
de.rec.sf.startrek	4
comp.sys.raspberry-pi	3
it.tlc.cellulari.android	1
de.comp.office-pakete.ms-office.word	1
it.discussioni.energia	1
uk.railway	1
TOTAL: 8	26

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11417
EMP (phl)	130
Scoring filter (8)	83
Binary in non-binary group	61
Scoring filter (9)	33
Scoring filter (10)	10
Scoring filter (13)	7
Too many newsgroups	2
Scoring filter (12)	2
TOTAL: 9	11745

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	5562	1223	2515	516	7	18612	21%	23:56:54
2	icm	18761	223	14935	1902	0	0	1%	23:58:06
3	poznan	18610	98	13992	10	0	0	0%	24:07:21
4	nask	1313	15	950	0	0	0	1%	08:41:36
5	okapi	0	0	0	0	0	1691	0%	24:00:01
TOTAL: 5		44246	1559	32392	2428	7	20303	3%	104:43:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	6.3 MB	36.8 MB	43.1 MB	0.5 KB/s	25.4 KB	23:56:54
2	icm	922.3 KB	466.6 MB	467.5 MB	5.5 KB/s	225.3 KB	23:58:06
3	poznan	861.9 KB	37.3 KB	899.1 KB	0.0 KB/s	8.3 KB	24:07:21
4	nask	126.1 KB	0.0 KB	126.1 KB	0.0 KB/s	8.4 KB	08:41:36
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		8.2 MB	503.4 MB	511.6 MB	1.4 KB/s	131.4 KB	104:43:58

Backlog files shrunk by innfeed:

Server	Size
panorama	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	100	293.7 KB	43	0	00:00:17
2	37.248.152.196	2	1	0.8 KB	1	0	00:01:24
3	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 3		291	101	294.5 KB	44	1	12:40:06

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	673	100	293.7 KB	43	1	00:00:21
2	unresolved	1044	1	0.8 KB	1	0	07:41:42
3	*.pionier.net.pl	44295	0	0.0 KB	0	0	04:58:02
TOTAL: 3		46012	101	294.5 KB	44	1	12:40:06

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.227.2	3.104	13.444	07:31:30
newsfeed.pionier.net.pl	132.405	592.312	04:58:02
89.38.40.46	0.204	0.371	00:08:47
37.248.152.196	0.036	0.039	00:01:24
web.man.lodz.pl	1.020	4.401	00:00:17
mon.man.lodz.pl	0.937	3.627	00:00:02
david.man.lodz.pl	0.292	1.229	00:00:00
isvcs01.man.lodz.pl	0.000	0.012	00:00:00
TOTAL: 8	137.998	615.435	12:40:06

Curious NNRP server explorers [Top 20]:

System	Conn
newsfeed.pionier.net.pl	44295
212.191.227.2	1040
mon.man.lodz.pl	288
david.man.lodz.pl	96
89.38.40.46	2
TOTAL: 5	45721

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	100	99.0%
2	lodman	1	1.0%
TOTAL: 2		101	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	80
2	pl.comp.os.ms-windows.winnt	20
3	lodman.test	1
TOTAL: 3		101

Daily Usenet report

Sep 18 04:15:02 -- Sep 19 04:15:02