Daily Usenet report: Aug 3 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Aug 3 13:17:15 newsfeed nnrpd[18385]: SERVER perl filter not defined
Aug 3 16:50:30 newsfeed nnrpd[25840]: SERVER perl filter not defined
Aug 3 18:38:52 newsfeed nnrpd[29433]: SERVER perl filter not defined
Aug 3 20:41:18 newsfeed nnrpd[1352]: SERVER perl filter not defined
Aug 3 21:13:08 newsfeed nnrpd[2429]: SERVER perl filter not defined
Aug 3 21:18:09 newsfeed nnrpd[2541]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2913791	97.5%	341.7 MB	97.1%
innfeed	46318	1.5%	5.6 MB	1.6%
innd	24305	0.8%	4.1 MB	1.2%
nnrpd	5224	0.2%	564.4 KB	0.2%
TOTAL: 4	2989638	100.0%	351.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2908036	98.4%
Negative hits	30708	1.0%
Positive hits	16626	0.6%
Cache misses	169	0.0%
TOTAL: 4	2955539	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.061	0.0%	334	0.000	12.159	1199.000
article cleanup	00:00:38.440	0.0%	2913632	0.005	0.013	0.028
article logging	00:01:27.166	0.1%	2913952	0.018	0.030	0.259
article parse	01:07:37.482	4.7%	124046289	0.023	0.033	0.071
article write	00:28:31.554	2.0%	2909343	0.328	0.588	2.162
artlog/artcncl	00:00:00.002	0.0%	279	0.000	0.007	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:55.710	0.1%	141547726	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.760	0.0%	294	0.000	2.585	542.000
hishave/artcncl	00:00:00.055	0.0%	334	0.000	0.165	12.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:13:24.521	0.9%	2957267	0.024	0.272	4.479
history sync	00:00:00.012	0.0%	292	0.000	0.041	1.000
history write	00:23:36.128	1.6%	2910009	0.181	0.487	8.526
hiswrite/artcncl	00:00:00.003	0.0%	40	0.000	0.075	1.000
idle	14:35:03.042	60.8%	92088783	0.437	0.570	2.114
nntp read	00:53:27.162	3.7%	121743198	0.018	0.026	0.042
overview write	00:00:19.109	0.0%	2909343	0.001	0.007	0.014
perl filter	05:27:00.719	22.7%	2910009	4.884	6.742	10.201
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:14.177	0.4%	5877508	0.027	0.064	0.117
TOTAL: 24:00:12.022	23:19:20.103	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.357	0.0%	108827	0.000	0.003	0.100
article prepare	00:00:00.034	0.0%	112174	0.000	0.000	0.083
article read	00:00:45.779	0.1%	27963	0.000	1.637	523.000
backlog stats	00:00:00.285	0.0%	337773	0.000	0.001	0.021
callbacks	00:00:00.000	0.0%	9	0.000	0.000	0.000
data read	00:00:02.681	0.0%	183694	0.000	0.015	0.100
data write	00:00:19.189	0.0%	138690	0.000	0.138	3.580
idle	23:58:07.851	99.9%	337773	20.366	255.461	2103.606
status file	00:00:13.117	0.0%	15692	0.000	0.836	6.667
TOTAL: 23:59:44.138	23:59:29.293	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	15:24:50.047	99.9%	217482	0.000	255.148	257176.571
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:08.964	0.0%	435886	0.000	0.021	4.172
readart	00:00:01.229	0.0%	91	0.000	13.505	39.450
TOTAL: 15:25:29.424	15:25:00.240	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1547	2908925	2904314	659	3952	99%	301:48:17
2	news.nask.pl	2	16510	4482	11756	272	27%	23:24:39
3	news.icm.edu.pl	43	268	36	176	56	13%	16:48:40
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		1599	2925709	2908838	12591	4280	99%	342:01:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	860.6 GB	16.2 MB	20.2 MB	860.7 GB	99%	310.3 KB
2	news.nask.pl	21.9 MB	601.7 KB	754.1 KB	23.2 MB	94%	5.0 KB
3	news.icm.edu.pl	858.0 KB	60.5 KB	2.7 MB	3.6 MB	23%	40.0 KB
4	localhost	9.1 KB	0.0 KB	0.0 KB	9.1 KB	100%	1.5 KB
TOTAL: 4		860.7 GB	16.9 MB	23.7 MB	860.7 GB	99%	309.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 3 04:15:02 - 04:59:59	82381	2.8%	30.53	25.5 GB	3.0%	9898.23
Aug 3 05:00:00 - 05:59:59	94972	3.3%	26.38	30.7 GB	3.6%	8954.78
Aug 3 06:00:00 - 06:59:59	92051	3.2%	25.57	27.9 GB	3.2%	8117.34
Aug 3 07:00:00 - 07:59:59	95145	3.3%	26.43	29.3 GB	3.4%	8543.18
Aug 3 08:00:00 - 08:59:59	125964	4.3%	34.99	35.0 GB	4.1%	10194.85
Aug 3 09:00:00 - 09:59:59	115257	4.0%	32.02	30.1 GB	3.5%	8756.37
Aug 3 10:00:00 - 10:59:59	110217	3.8%	30.62	32.6 GB	3.8%	9493.39
Aug 3 11:00:00 - 11:59:59	124537	4.3%	34.59	37.7 GB	4.4%	10982.78
Aug 3 12:00:00 - 12:59:59	124741	4.3%	34.65	37.0 GB	4.3%	10788.66
Aug 3 13:00:00 - 13:59:59	133447	4.6%	37.07	38.1 GB	4.4%	11097.56
Aug 3 14:00:00 - 14:59:59	136273	4.7%	37.85	40.2 GB	4.7%	11699.84
Aug 3 15:00:00 - 15:59:59	126818	4.4%	35.23	39.3 GB	4.6%	11442.49
Aug 3 16:00:00 - 16:59:59	142858	4.9%	39.68	39.9 GB	4.6%	11630.70
Aug 3 17:00:00 - 17:59:59	141346	4.9%	39.26	39.8 GB	4.6%	11578.84
Aug 3 18:00:00 - 18:59:59	143773	4.9%	39.94	40.2 GB	4.7%	11703.43
Aug 3 19:00:00 - 19:59:59	137441	4.7%	38.18	39.3 GB	4.6%	11443.35
Aug 3 20:00:00 - 20:59:59	113202	3.9%	31.45	34.2 GB	4.0%	9955.08
Aug 3 21:00:00 - 21:59:59	129568	4.5%	35.99	39.3 GB	4.6%	11461.03
Aug 3 22:00:00 - 22:59:59	138941	4.8%	38.59	40.2 GB	4.7%	11720.77
Aug 3 23:00:00 - 23:59:59	133613	4.6%	37.11	39.2 GB	4.6%	11408.28
Aug 4 00:00:00 - 00:59:59	99999	3.4%	27.78	31.6 GB	3.7%	9198.04
Aug 4 01:00:00 - 01:59:59	132261	4.5%	36.74	39.0 GB	4.5%	11364.71
Aug 4 02:00:00 - 02:59:59	105739	3.6%	29.37	33.6 GB	3.9%	9775.64
Aug 4 03:00:00 - 03:59:59	99477	3.4%	27.63	30.9 GB	3.6%	8996.23
Aug 4 04:00:00 - 04:15:02	28881	1.0%	32.02	8.7 GB	1.0%	10135.79
TOTAL: 24:00:00	2908902	100.0%	33.67	859.2 GB	100.0%	10427.71

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4225	400	3400	5	420
2	news.nask.pl	329	44	221	16	48
3	news.icm.edu.pl	56	0	3	0	53
TOTAL: 3		4610	444	3624	21	521

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	177
alt.windows7.general	117
it.comp.os.win.windows10	64
it.tlc.cellulari.android	16
comp.mobile.android	14
comp.mobile.ipad	11
comp.sys.raspberry-pi	10
de.comp.lang.vbclassic	8
it.comp.os.win.windows8	5
it.comp.os.linux.ubuntu	5
hr.ponuda.hardver.komponente	4
uk.rec.cycling.moderated	4
alt.sex.telephones	3
it.comp.os.win.windows7	2
hr.rec.glazba.hifi	1
hr.alt.cellular.gsm.android	1
hr.ponuda.hardver.konfiguracije	1
nl.actueel	1
TOTAL: 18	444

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

Groups	Count
pl.praca.oferowana	16
pl.sci.matematyka	4
bit.listserv.catholic	1
TOTAL: 3	21

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	145
EMP (md5)	52
HTML post	1
EMP (fsl)	1
Scoring filter (8)	1
Attached HTML file	1
TOTAL: 6	201

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	59780	1687	31374	677	27	75001	2%	24:00:42
2	icm	62105	463	25579	23927	0	57	0%	23:56:32
3	poznan	6882	85	4480	35	0	0	1%	24:01:13
4	okapi	0	0	0	0	0	2329	0%	24:00:00
5	nask	0	0	0	0	0	12500	0%	24:00:00
TOTAL: 5		128767	2235	61433	24639	27	89887	1%	119:58:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	14.6 MB	5.7 GB	5.8 GB	70.0 KB/s	247.4 KB	23:56:32
2	panorama	6.2 MB	12.3 MB	18.5 MB	0.2 KB/s	8.0 KB	24:00:42
3	poznan	5.9 MB	647.5 KB	6.5 MB	0.1 KB/s	55.8 KB	24:01:13
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		26.6 MB	5.8 GB	5.8 GB	14.0 KB/s	225.5 KB	119:58:27

Backlog files shrunk by innfeed:

Server	Size
panorama	976.7 KB
TOTAL: 1	976.7 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	lze200.lze.lodz.pl	15	57	113.5 KB	12	0	01:20:36
2	web.man.lodz.pl	288	26	25.1 KB	11	0	00:00:30
3	212.51.203.199	2	6	8.9 KB	1	0	00:02:30
4	89-70-245-0.dynamic.chello.pl	2	2	2.5 KB	2	0	00:30:32
5	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 5		313	91	150.1 KB	26	6	15:25:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lze.lodz.pl	15	57	113.5 KB	12	0	01:20:36
2	*.man.lodz.pl	678	26	25.1 KB	11	6	00:00:34
3	unresolved	11	6	8.9 KB	1	0	13:30:50
4	*.dynamic.chello.pl	2	2	2.5 KB	2	0	00:30:32
5	*.elsnet.pl	6	0	0.0 KB	0	0	00:00:21
6	*.toya.net.pl	55	0	0.0 KB	0	0	00:00:03
7	*.rz.izeto.pl	2	0	0.0 KB	0	0	00:02:30
TOTAL: 7		769	91	150.1 KB	26	6	15:25:29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.195.160	12.587	18.019	13:28:19
lze200.lze.lodz.pl	0.057	0.287	01:20:36
89-70-245-0.dynamic.chello.pl	0.090	0.080	00:30:32
212.51.203.199	0.008	0.052	00:02:30
auriga.rz.izeto.pl	0.004	0.041	00:02:30
web.man.lodz.pl	1.064	4.775	00:00:30
host227.elsnet.pl	0.025	0.099	00:00:21
staticline41435.toya.net.pl	0.187	0.970	00:00:03
mon.man.lodz.pl	1.044	5.377	00:00:03
david.man.lodz.pl	0.322	1.571	00:00:00
isvcs01.man.lodz.pl	0.048	0.115	00:00:00
?	0.009	0.027	00:00:00
TOTAL: 12	15.445	31.413	15:25:29

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	55
185.48.195.160	9
host227.elsnet.pl	6
auriga.rz.izeto.pl	2
TOTAL: 6	456

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.195.160	3	2
89-70-245-0.dynamic.chello.pl	1	0
TOTAL: 2	4	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	89	97.8%
2	lodman	2	2.2%
TOTAL: 2		91	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	40
2	pl.comp.pecet	16
3	pl.misc.telefonia.gsm	13
4	pl.rec.kuchnia	9
5	pl.rec.radio.amatorskie	6
6	pl.rec.dom	5
7	lodman.info	1
8	lodman.test	1
TOTAL: 8		91

Daily Usenet report

Aug 3 04:15:02 -- Aug 4 04:15:02