Daily Usenet report: Sep 17 04:15:02

Unknown entries from news log file:

First 50 / 159 lines (31.4%)

Sep 17 04:15:02 newsfeed nnrpd[29296]: web.man.lodz.pl rejected Flushing log and syslog files
Sep 17 04:15:02 newsfeed nnrpd[29302]: web.man.lodz.pl rejected Flushing log and syslog files
Sep 17 04:15:02 newsfeed nnrpd[29310]: web.man.lodz.pl rejected Flushing log and syslog files
Sep 17 10:30:41 newsfeed nnrpd[10437]: SERVER perl filter not defined
Sep 17 13:10:02 newsfeed pgpverify[15911]: pgpverify: verification failed
Sep 18 03:03:02 newsfeed nnrpd[12089]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12088]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12090]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12091]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12092]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12093]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12095]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12097]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12096]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12094]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12098]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12100]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12099]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12101]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12102]: 212.191.227.2 noperm ihave without permission
Sep 18 03:03:02 newsfeed nnrpd[12103]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12110]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12108]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12109]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12112]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12111]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12114]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12120]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12115]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12117]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12116]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12113]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:17 newsfeed nnrpd[12365]: 212.191.227.2 noperm ihave without permission
Sep 18 03:08:37 newsfeed nnrpd[12366]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12396]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12384]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12381]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12383]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12382]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12378]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12380]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12379]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12375]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12374]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12377]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12373]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:18 newsfeed nnrpd[12376]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:38 newsfeed nnrpd[12475]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:38 newsfeed nnrpd[12476]: 212.191.227.2 noperm ihave without permission
Sep 18 03:11:38 newsfeed nnrpd[12478]: 212.191.227.2 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2844096	98.4%	335.4 MB	98.2%
innd	35110	1.2%	4.9 MB	1.4%
nnrpd	5955	0.2%	625.1 KB	0.2%
innfeed	4847	0.2%	678.3 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2890012	100.0%	341.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2838100	96.4%
Negative hits	93196	3.2%
Positive hits	14037	0.5%
Cache misses	182	0.0%
TOTAL: 4	2945515	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.530	0.0%	231	0.000	15.281	516.000
article cleanup	00:00:42.759	0.0%	2845086	0.000	0.015	0.048
article logging	00:01:21.613	0.1%	2845558	0.015	0.029	0.703
article parse	01:19:55.276	5.5%	203159013	0.009	0.024	0.080
article write	00:24:59.755	1.7%	2828365	0.352	0.530	6.617
artlog/artcncl	00:00:00.003	0.0%	224	0.000	0.013	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:55.257	0.2%	220747679	0.000	0.001	0.006
hisgrep/artcncl	00:00:00.475	0.0%	219	0.000	2.169	16.000
hishave/artcncl	00:00:00.032	0.0%	231	0.000	0.139	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:29:52.201	2.1%	2948757	0.064	0.608	11.066
history sync	00:00:00.010	0.0%	291	0.000	0.034	1.000
history write	00:44:15.014	3.1%	2841144	0.151	0.934	12.121
hiswrite/artcncl	00:00:00.001	0.0%	12	0.000	0.083	1.000
idle	12:49:22.059	53.4%	144248302	0.130	0.320	3.661
nntp read	01:09:55.854	4.9%	200916370	0.013	0.021	0.040
overview write	00:00:15.511	0.0%	2828365	0.000	0.005	0.016
perl filter	05:57:27.010	24.8%	2841144	5.684	7.549	10.782
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:48.990	0.3%	5684147	0.027	0.051	0.100
TOTAL: 24:00:39.482	23:05:55.350	96.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.155	0.0%	52454	0.000	0.003	0.167
article prepare	00:00:00.008	0.0%	30192	0.000	0.000	0.028
article read	00:00:21.438	0.0%	7516	0.000	2.852	385.500
backlog stats	00:00:00.179	0.0%	188991	0.000	0.001	0.125
callbacks	00:00:00.000	0.0%	11	0.000	0.000	0.000
data read	00:00:01.239	0.0%	100338	0.000	0.012	0.100
data write	00:00:05.205	0.0%	85083	0.000	0.061	0.333
idle	23:59:42.631	100.0%	188991	14.711	457.073	7933.375
status file	00:00:00.620	0.0%	866	0.000	0.716	3.000
TOTAL: 24:00:17.324	24:00:11.475	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:17:50.107	79.7%	3547	0.000	4361.462	249868.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.164	0.0%	7751	0.000	0.021	0.500
readart	00:00:00.000	0.0%	28	0.000	0.000	0.000
TOTAL: 05:23:42.542	04:17:50.271	79.7%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

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	1270	2789112	2779376	5914	3822	99%	334:38:48
2	212.191.227.2	0	37727	37360	351	16	99%	03:36:00
3	news.nask.pl	2	26585	10259	3497	12829	38%	23:58:02
4	news.icm.edu.pl	44	340	11	292	37	3%	17:03:21
5	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 5		1318	2853765	2827007	10054	16704	99%	379:16:11

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	828.1 GB	28.6 MB	43.9 MB	828.2 GB	99%	312.0 KB
2	212.191.227.2	11.7 GB	94.5 KB	94.5 KB	11.7 GB	99%	329.0 KB
3	news.nask.pl	59.4 MB	1.4 MB	159.8 MB	220.6 MB	26%	9.8 KB
4	news.icm.edu.pl	476.5 KB	0.0 KB	1.5 MB	2.0 MB	23%	41.7 KB
5	localhost	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
TOTAL: 5		839.9 GB	30.1 MB	205.3 MB	840.2 GB	99%	309.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 17 04:15:02 - 04:59:59	86209	3.0%	31.95	28.0 GB	3.3%	10869.82
Sep 17 05:00:00 - 05:59:59	88714	3.1%	24.64	29.0 GB	3.5%	8443.69
Sep 17 06:00:00 - 06:59:59	104046	3.7%	28.90	35.3 GB	4.2%	10291.01
Sep 17 07:00:00 - 07:59:59	120640	4.3%	33.51	40.2 GB	4.8%	11718.99
Sep 17 08:00:00 - 08:59:59	128920	4.6%	35.81	39.4 GB	4.7%	11483.90
Sep 17 09:00:00 - 09:59:59	133986	4.7%	37.22	40.4 GB	4.8%	11758.33
Sep 17 10:00:00 - 10:59:59	140623	5.0%	39.06	38.9 GB	4.6%	11323.89
Sep 17 11:00:00 - 11:59:59	145307	5.1%	40.36	40.3 GB	4.8%	11736.14
Sep 17 12:00:00 - 12:59:59	142467	5.0%	39.57	39.1 GB	4.7%	11402.54
Sep 17 13:00:00 - 13:59:59	144438	5.1%	40.12	39.5 GB	4.7%	11505.25
Sep 17 14:00:00 - 14:59:59	143295	5.1%	39.80	38.7 GB	4.6%	11275.81
Sep 17 15:00:00 - 15:59:59	143282	5.1%	39.80	39.7 GB	4.7%	11563.47
Sep 17 16:00:00 - 16:59:59	150112	5.3%	41.70	41.0 GB	4.9%	11943.04
Sep 17 17:00:00 - 17:59:59	141757	5.0%	39.38	41.0 GB	4.9%	11949.91
Sep 17 18:00:00 - 18:59:59	133958	4.7%	37.21	41.3 GB	4.9%	12022.30
Sep 17 19:00:00 - 19:59:59	130093	4.6%	36.14	40.2 GB	4.8%	11702.98
Sep 17 20:00:00 - 20:59:59	141498	5.0%	39.30	41.0 GB	4.9%	11942.96
Sep 17 21:00:00 - 21:59:59	141187	5.0%	39.22	40.3 GB	4.8%	11725.68
Sep 17 22:00:00 - 22:59:59	135598	4.8%	37.67	41.4 GB	4.9%	12060.14
Sep 17 23:00:00 - 23:59:59	132628	4.7%	36.84	40.8 GB	4.9%	11884.55
Sep 18 00:00:00 - 00:59:59	97367	3.4%	27.05	30.3 GB	3.6%	8825.92
Sep 18 01:00:00 - 01:59:59	34844	1.2%	9.68	11.3 GB	1.3%	3284.67
Sep 18 02:00:00 - 02:59:59	26815	0.9%	7.45	8.7 GB	1.0%	2532.41
Sep 18 03:00:00 - 03:59:59	31608	1.1%	8.78	9.8 GB	1.2%	2844.35
Sep 18 04:00:00 - 04:15:02	7305	0.3%	8.10	2.3 GB	0.3%	2700.90
TOTAL: 24:00:00	2826697	100.0%	32.72	837.9 GB	100.0%	10168.54

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	13012	36	386	11	12579
2	newsfeed.pionier.net.pl	4104	201	3540	0	363
3	news.icm.edu.pl	37	0	0	0	37
4	212.191.227.2	22	0	16	0	6
TOTAL: 4		17175	237	3942	11	12985

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	129
it.comp.os.win.windows10	26
it.tlc.cellulari.android	24
comp.mobile.android	14
de.rec.sf.startrek	11
it.discussioni.energia	9
de.comp.office-pakete.ms-office.word	5
comp.mobile.ipad	5
uk.rec.cycling.moderated	4
comp.sys.mac.vintage	4
de.comp.lang.vbclassic	2
hr.ponuda.hardver.periferije	2
hr.ponuda.hardver.komponente	1
nl.comp.os.linux.techniek	1
TOTAL: 14	237

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

Groups	Count
pl.praca.oferowana	5
pl.sci.matematyka	4
christnet.philosophy	1
bit.listserv.catholic	1
TOTAL: 4	11

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11897
EMP (phl)	221
Binary in non-binary group	204
Scoring filter (8)	108
Scoring filter (9)	36
Scoring filter (10)	19
Scoring filter (13)	10
Too many newsgroups	8
Scoring filter (12)	4
Scoring filter (11)	3
HTML post	1
Too many test groups in crosspost	1
EMP (fsl)	1
TOTAL: 13	12513

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	25375	1167	22434	394	26	28472	4%	24:02:55
2	icm	32419	395	20220	5253	0	0	1%	23:59:05
3	poznan	16132	202	10034	23	0	0	1%	23:57:19
4	nask	6933	18	4563	2	0	0	0%	23:58:29
5	okapi	0	0	0	0	0	1381	0%	24:00:03
TOTAL: 5		80859	1782	57251	5672	26	29853	2%	119:57:51

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	5.7 MB	1.3 GB	1.3 GB	15.7 KB/s	239.8 KB	23:59:05
2	panorama	5.7 MB	11.8 MB	17.5 MB	0.2 KB/s	11.5 KB	24:02:55
3	poznan	1.3 MB	74.6 KB	1.4 MB	0.0 KB/s	6.2 KB	23:57:19
4	nask	178.3 KB	10.6 KB	189.0 KB	0.0 KB/s	9.4 KB	23:58:29
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 5		12.9 MB	1.3 GB	1.3 GB	3.2 KB/s	184.4 KB	119:57:51

Backlog files shrunk by innfeed:

Server	Size
panorama	2.4 MB
TOTAL: 1	2.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	292	28	21.3 KB	14	0	00:00:21
2	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:02
TOTAL: 2		293	28	21.3 KB	14	1	05:23:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	678	28	21.3 KB	14	1	00:00:28
2	unresolved	210	0	0.0 KB	0	0	05:23:13
TOTAL: 2		888	28	21.3 KB	14	1	05:23:42

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.227.2	0.622	2.696	05:23:13
web.man.lodz.pl	0.798	5.341	00:00:21
mon.man.lodz.pl	0.915	4.414	00:00:03
isvcs01.man.lodz.pl	0.010	0.014	00:00:02
david.man.lodz.pl	0.299	1.386	00:00:01
TOTAL: 5	2.644	13.851	05:23:42

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	289
212.191.227.2	210
david.man.lodz.pl	96
TOTAL: 3	595

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	28	100.0%
TOTAL: 1		28	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	18
2	pl.comp.pecet	10
TOTAL: 2		28

Daily Usenet report

Sep 17 04:15:02 -- Sep 18 04:15:02