Daily Usenet report: Jun 30 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Jun 30 09:08:57 newsfeed nnrpd[9869]: SERVER perl filter not defined
Jun 30 10:50:49 newsfeed nnrpd[13668]: SERVER perl filter not defined
Jun 30 17:34:59 newsfeed overchan[30939]: input line too long, skipping
Jun 30 18:44:11 newsfeed overchan[30939]: input line too long, skipping
Jun 30 18:58:41 newsfeed overchan[30939]: input line too long, skipping
Jun 30 19:04:09 newsfeed overchan[30939]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2584766	97.3%	286.6 MB	96.9%
innfeed	46523	1.8%	5.6 MB	1.9%
innd	18392	0.7%	3.1 MB	1.0%
nnrpd	5553	0.2%	597.0 KB	0.2%
overchan	5	0.0%	0.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2655240	100.0%	295.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2475176	70.2%
Negative hits	894871	25.4%
Positive hits	157527	4.5%
Cache misses	326	0.0%
TOTAL: 4	3527900	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.181	0.0%	159	0.000	7.428	66.000
article cleanup	00:00:36.499	0.0%	2586106	0.009	0.014	0.019
article logging	00:02:02.163	0.1%	2586666	0.034	0.047	0.161
article parse	00:49:38.379	3.4%	124464029	0.010	0.024	0.035
article write	00:17:44.278	1.2%	2496989	0.229	0.426	1.096
artlog/artcncl	00:00:00.006	0.0%	154	0.000	0.039	1.000
data move	00:02:11.942	0.2%	139895271	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.448	0.0%	157	0.000	2.854	23.000
hishave/artcncl	00:00:00.016	0.0%	159	0.000	0.101	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:16:29.088	1.1%	3553092	0.045	0.278	2.868
history sync	00:00:00.023	0.0%	292	0.000	0.079	1.000
history write	01:29:51.827	6.2%	2499062	0.206	2.158	13.899
hiswrite/artcncl	00:00:00.001	0.0%	2	0.000	0.500	1.000
idle	14:29:39.142	60.4%	104463242	0.268	0.499	3.730
nntp read	01:14:48.822	5.2%	122985616	0.020	0.036	0.055
overview write	00:00:13.374	0.0%	2496989	0.004	0.005	0.008
perl filter	04:35:37.709	19.1%	2499059	4.127	6.618	9.211
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:06.260	0.4%	5269516	0.044	0.070	0.101
TOTAL: 24:00:03.949	23:25:01.158	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.252	0.0%	487601	0.000	0.003	0.008
article prepare	00:00:00.010	0.0%	48382	0.000	0.000	0.005
article read	00:00:12.363	0.0%	10354	0.000	1.194	101.833
backlog stats	00:00:00.948	0.0%	1485701	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:14.969	0.0%	924648	0.003	0.016	0.026
data write	00:00:47.991	0.1%	667187	0.021	0.072	0.162
idle	23:49:13.695	99.8%	1485701	30.519	57.719	966.601
status file	00:00:13.946	0.0%	17087	0.478	0.816	1.408
TOTAL: 23:51:28.853	23:50:45.174	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:49.363	74.3%	3346	0.000	14.753	8919.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.227	0.3%	7516	0.000	0.030	1.000
readart	00:00:00.019	0.0%	70	0.000	0.271	5.500
TOTAL: 00:01:06.397	00:00:49.609	74.7%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	689	2493550	2389782	18739	85029	95%	294:53:42
2	news.nask.pl	2	160864	104213	52122	4529	64%	24:11:19
3	news.icm.edu.pl	41	394	61	291	42	15%	18:58:50
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		735	2654810	2494058	71152	89600	93%	338:03:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	599.6 GB	29.7 GB	29.7 GB	659.1 GB	90%	279.3 KB
2	news.nask.pl	35.6 GB	804.3 MB	817.7 MB	37.2 GB	95%	358.3 KB
3	news.icm.edu.pl	817.0 KB	8.9 KB	1.6 MB	2.4 MB	32%	24.3 KB
4	localhost	2.6 KB	0.0 KB	0.0 KB	2.6 KB	100%	1.3 KB
TOTAL: 4		635.2 GB	30.5 GB	30.5 GB	696.3 GB	91%	282.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 30 04:15:02 - 04:59:59	97448	3.9%	36.12	26.5 GB	4.2%	10295.13
Jun 30 05:00:00 - 05:59:59	130294	5.2%	36.19	34.2 GB	5.4%	9966.49
Jun 30 06:00:00 - 06:59:59	49072	2.0%	13.63	14.7 GB	2.3%	4272.21
Jun 30 07:00:00 - 07:59:59	15798	0.6%	4.39	6.3 GB	1.0%	1837.79
Jun 30 08:00:00 - 08:59:59	20289	0.8%	5.64	7.4 GB	1.2%	2161.88
Jun 30 09:00:00 - 09:59:59	108182	4.3%	30.05	26.8 GB	4.2%	7811.05
Jun 30 10:00:00 - 10:59:59	116962	4.7%	32.49	29.9 GB	4.7%	8722.49
Jun 30 11:00:00 - 11:59:59	114600	4.6%	31.83	30.3 GB	4.8%	8818.59
Jun 30 12:00:00 - 12:59:59	143945	5.8%	39.98	32.7 GB	5.1%	9518.20
Jun 30 13:00:00 - 13:59:59	131592	5.3%	36.55	33.2 GB	5.2%	9661.30
Jun 30 14:00:00 - 14:59:59	146352	5.9%	40.65	33.8 GB	5.3%	9852.50
Jun 30 15:00:00 - 15:59:59	84137	3.4%	23.37	23.6 GB	3.7%	6883.49
Jun 30 16:00:00 - 16:59:59	124563	5.0%	34.60	31.4 GB	4.9%	9134.73
Jun 30 17:00:00 - 17:59:59	96744	3.9%	26.87	25.9 GB	4.1%	7540.05
Jun 30 18:00:00 - 18:59:59	126594	5.1%	35.16	32.3 GB	5.1%	9421.96
Jun 30 19:00:00 - 19:59:59	120196	4.8%	33.39	31.2 GB	4.9%	9085.03
Jun 30 20:00:00 - 20:59:59	105139	4.2%	29.21	27.2 GB	4.3%	7912.26
Jun 30 21:00:00 - 21:59:59	122719	4.9%	34.09	33.0 GB	5.2%	9598.74
Jun 30 22:00:00 - 22:59:59	107921	4.3%	29.98	28.4 GB	4.5%	8279.62
Jun 30 23:00:00 - 23:59:59	68835	2.8%	19.12	19.6 GB	3.1%	5698.41
Jul 1 00:00:00 - 00:59:59	91483	3.7%	25.41	22.7 GB	3.6%	6600.08
Jul 1 01:00:00 - 01:59:59	121768	4.9%	33.82	32.6 GB	5.1%	9493.55
Jul 1 02:00:00 - 02:59:59	110696	4.4%	30.75	24.0 GB	3.8%	6998.31
Jul 1 03:00:00 - 03:59:59	116810	4.7%	32.45	22.6 GB	3.6%	6585.29
Jul 1 04:00:00 - 04:15:03	22317	0.9%	24.71	5.0 GB	0.8%	5805.60
TOTAL: 24:00:01	2494456	100.0%	28.87	635.2 GB	100.0%	7709.32

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	85407	121	84884	1	401
2	news.nask.pl	4702	24	2640	3	2035
3	news.icm.edu.pl	47	0	1	0	46
TOTAL: 3		90156	145	87525	4	2482

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.tlc.cellulari.android	54
uk.railway	36
comp.mobile.android	28
comp.sys.raspberry-pi	7
uk.net.providers.gradwell	3
it.comp.os.win.windows10	3
nl.comp.sys.android	2
de.comp.office-pakete.ms-office.outlook	2
it.comp.os.win.windows7	2
nl.comp.os.linux.techniek	1
nl.actueel	1
nl.wetenschap.regulier	1
hr.alt.cellular.gsm.android	1
de.rec.sf.startrek	1
nl.comp.3d-printen	1
it.comp.os.win.windows8	1
nl.gezondheid.alternatief-genezen	1
TOTAL: 17	145

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

Groups	Count
pl.praca.oferowana	2
bit.listserv.catholic	1
christnet.prayer	1
TOTAL: 3	4

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1759
Binary in non-binary group	90
EMP (phl)	27
Scoring filter (8)	15
Too many newsgroups	10
Scoring filter (9)	9
Scoring filter (16)	2
Scoring filter (14)	2
Scoring filter (11)	2
Scoring filter (13)	1
Scoring filter (17)	1
HTML post	1
TOTAL: 12	1919

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	371
	news.nask.pl	184
	news.icm.edu.pl	5
	TOTAL: 3	560
TOTAL: 1		560

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	208221	7605	159751	13	0	4	3%	23:59:58
2	panorama	225470	361	196164	100	3	294991	0%	23:59:58
3	poznan	10448	250	6438	17	0	0	2%	23:59:58
4	icm	339058	81	272958	3605	0	8	0%	23:59:58
5	okapi	0	0	0	0	0	789	0%	23:57:50
TOTAL: 5		783197	8297	635311	3735	3	295792	1%	119:57:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	2.8 GB	4.5 MB	2.8 GB	33.5 KB/s	380.0 KB	23:59:58
2	panorama	26.3 MB	17.7 MB	44.0 MB	0.5 KB/s	97.7 KB	23:59:58
3	poznan	1.2 MB	198.9 KB	1.4 MB	0.0 KB/s	5.2 KB	23:59:58
4	icm	189.9 KB	1.3 GB	1.3 GB	15.7 KB/s	367.8 KB	23:59:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		2.8 GB	1.3 GB	4.1 GB	10.0 KB/s	357.1 KB	119:57:42

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
TOTAL: 1	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	70	67.2 KB	33	0	00:00:22
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:01
TOTAL: 2		290	70	67.2 KB	33	2	00:01:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	674	70	67.2 KB	33	2	00:00:28
2	*.versanet.de	188	0	0.0 KB	0	0	00:00:09
3	*.lightspeed.rlghnc.sbcglobal.net	1	0	0.0 KB	0	0	00:00:27
TOTAL: 3		863	70	67.2 KB	33	2	00:01:05

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
69-220-153-24.lightspeed.rlghnc.sbcglobal.net	0.012	0.012	00:00:27
web.man.lodz.pl	1.456	3.840	00:00:22
i59f67c51.versanet.de	0.960	2.572	00:00:09
mon.man.lodz.pl	1.336	4.028	00:00:03
isvcs01.man.lodz.pl	0.012	0.028	00:00:01
david.man.lodz.pl	0.476	1.088	00:00:01
?	0.024	0.052	00:00:00
TOTAL: 7	4.276	11.620	00:01:05

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
i59f67c51.versanet.de	188
david.man.lodz.pl	96
69-220-153-24.lightspeed.rlghnc.sbcglobal.net	1
TOTAL: 4	573

NNRP gethostbyaddr failures [Top 20]:

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

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Jun 30 04:15:02 -- Jul 1 04:15:03