Daily Usenet report: Aug 5 04:15:02

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Aug 5 04:54:48 newsfeed overchan[2109]: input line too long, skipping
Aug 5 04:55:10 newsfeed overchan[2109]: input line too long, skipping
Aug 5 04:57:06 newsfeed overchan[2109]: input line too long, skipping
Aug 5 10:05:44 newsfeed nnrpd[29484]: SERVER perl filter not defined
Aug 5 10:05:50 newsfeed nnrpd[29485]: SERVER perl filter not defined
Aug 5 11:04:18 newsfeed nnrpd[31627]: SERVER perl filter not defined
Aug 5 11:46:40 newsfeed nnrpd[710]: SERVER perl filter not defined
Aug 5 13:24:22 newsfeed overchan[2109]: input line too long, skipping
Aug 5 13:24:45 newsfeed overchan[2109]: input line too long, skipping
Aug 5 14:30:32 newsfeed overchan[2109]: input line too long, skipping
Aug 6 02:27:07 newsfeed overchan[2109]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1854039	96.4%	215.9 MB	96.2%
innfeed	46403	2.4%	5.4 MB	2.4%
innd	17137	0.9%	2.7 MB	1.2%
nnrpd	4807	0.3%	506.8 KB	0.2%
overchan	8	0.0%	1.5 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	1922395	100.0%	224.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1832688	96.8%
Negative hits	50222	2.7%
Positive hits	11002	0.6%
Cache misses	95	0.0%
TOTAL: 4	1894007	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.961	0.0%	163	0.000	24.301	457.000
article cleanup	00:00:30.822	0.0%	1853602	0.012	0.017	0.023
article logging	00:01:18.238	0.1%	1854297	0.030	0.042	0.138
article parse	00:52:41.681	3.7%	119157472	0.013	0.027	0.057
article write	00:16:21.030	1.1%	1844903	0.403	0.532	1.332
artlog/artcncl	00:00:00.007	0.0%	162	0.000	0.043	0.500
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:58.850	0.1%	130883773	0.001	0.001	0.003
hisgrep/artcncl	00:00:00.517	0.0%	161	0.000	3.211	97.000
hishave/artcncl	00:00:00.291	0.0%	163	0.000	1.785	28.800
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:30:58.731	2.2%	1912833	0.054	0.972	6.478
history sync	00:00:00.022	0.0%	290	0.000	0.076	0.500
history write	01:37:29.287	6.8%	1849594	0.338	3.162	10.376
hiswrite/artcncl	00:00:00.001	0.0%	2	0.000	0.500	1.000
idle	15:02:22.855	62.7%	92823085	0.216	0.583	1.437
nntp read	01:05:04.882	4.5%	117783714	0.020	0.033	0.051
overview write	00:00:10.895	0.0%	1844903	0.004	0.006	0.009
perl filter	03:51:06.429	16.0%	1849593	6.403	7.497	8.990
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:36.683	0.3%	3707910	0.040	0.058	0.084
TOTAL: 24:00:02.599	23:23:45.182	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.187	0.0%	56392	0.000	0.003	0.010
article prepare	00:00:00.003	0.0%	8976	0.000	0.000	0.010
article read	00:00:50.496	0.1%	2240	0.167	22.543	298.800
backlog stats	00:00:00.119	0.0%	168576	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	6	0.000	0.000	0.000
data read	00:00:01.823	0.0%	87114	0.007	0.021	0.056
data write	00:00:04.985	0.0%	70417	0.022	0.071	0.263
idle	23:53:27.572	99.9%	168576	324.149	510.201	843.121
status file	00:00:15.088	0.0%	16442	0.675	0.918	1.315
TOTAL: 23:54:53.467	23:54:40.273	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:45.447	89.3%	3083	0.000	34.203	9154.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.212	0.2%	6866	0.000	0.031	0.667
readart	00:00:00.001	0.0%	66	0.000	0.015	0.500
TOTAL: 00:01:58.134	00:01:45.660	89.4%	-	-	-	-

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	637	1844950	1838413	2617	3920	99%	282:48:49
2	news.nask.pl	2	15621	6754	4150	4717	43%	23:50:27
3	news.icm.edu.pl	45	235	16	197	22	6%	16:17:46
4	localhost	5	4	4	0	0	100%	00:00:01
TOTAL: 4		689	1860810	1845187	6964	8659	99%	322:57:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	572.0 GB	18.4 MB	19.4 MB	572.1 GB	99%	325.6 KB
2	news.nask.pl	36.5 MB	799.9 KB	32.2 MB	69.5 MB	52%	6.2 KB
3	news.icm.edu.pl	318.1 KB	64.8 KB	1.1 MB	1.4 MB	21%	38.6 KB
4	localhost	6.0 KB	0.0 KB	0.0 KB	6.0 KB	100%	1.5 KB
TOTAL: 4		572.1 GB	19.3 MB	52.7 MB	572.1 GB	99%	323.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 5 04:15:02 - 04:59:59	82423	4.5%	30.55	24.1 GB	4.2%	9372.16
Aug 5 05:00:00 - 05:59:59	114572	6.2%	31.83	33.2 GB	5.8%	9674.72
Aug 5 06:00:00 - 06:59:59	121185	6.6%	33.66	36.0 GB	6.3%	10500.26
Aug 5 07:00:00 - 07:59:59	126131	6.8%	35.04	37.5 GB	6.6%	10921.74
Aug 5 08:00:00 - 08:59:59	120507	6.5%	33.47	38.1 GB	6.7%	11089.42
Aug 5 09:00:00 - 09:59:59	114350	6.2%	31.76	35.8 GB	6.3%	10440.71
Aug 5 10:00:00 - 10:59:59	108191	5.9%	30.05	32.1 GB	5.6%	9343.58
Aug 5 11:00:00 - 11:59:59	105778	5.7%	29.38	32.6 GB	5.7%	9508.32
Aug 5 12:00:00 - 12:59:59	127689	6.9%	35.47	36.5 GB	6.4%	10642.87
Aug 5 13:00:00 - 13:59:59	88693	4.8%	24.64	25.4 GB	4.4%	7403.09
Aug 5 14:00:00 - 14:59:59	76920	4.2%	21.37	22.1 GB	3.9%	6450.25
Aug 5 15:00:00 - 15:59:59	53750	2.9%	14.93	14.1 GB	2.5%	4111.86
Aug 5 16:00:00 - 16:59:59	49409	2.7%	13.72	13.6 GB	2.4%	3956.95
Aug 5 17:00:00 - 17:59:59	36443	2.0%	10.12	11.5 GB	2.0%	3335.08
Aug 5 18:00:00 - 18:59:59	32039	1.7%	8.90	11.0 GB	1.9%	3214.50
Aug 5 19:00:00 - 19:59:59	37854	2.1%	10.52	12.7 GB	2.2%	3708.51
Aug 5 20:00:00 - 20:59:59	34533	1.9%	9.59	12.9 GB	2.3%	3752.48
Aug 5 21:00:00 - 21:59:59	36566	2.0%	10.16	12.0 GB	2.1%	3490.04
Aug 5 22:00:00 - 22:59:59	39739	2.2%	11.04	12.6 GB	2.2%	3670.64
Aug 5 23:00:00 - 23:59:59	39980	2.2%	11.11	12.7 GB	2.2%	3705.68
Aug 6 00:00:00 - 00:59:59	56845	3.1%	15.79	20.2 GB	3.5%	5888.36
Aug 6 01:00:00 - 01:59:59	69848	3.8%	19.40	25.6 GB	4.5%	7465.06
Aug 6 02:00:00 - 02:59:59	78273	4.2%	21.74	27.2 GB	4.8%	7930.93
Aug 6 03:00:00 - 03:59:59	73398	4.0%	20.39	25.4 GB	4.4%	7388.85
Aug 6 04:00:00 - 04:15:02	19366	1.0%	21.47	6.7 GB	1.2%	7766.20
TOTAL: 24:00:00	1844482	100.0%	21.35	571.9 GB	100.0%	6940.15

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	5035	5	274	4	4752
2	newsfeed.pionier.net.pl	4335	112	3729	3	491
3	news.icm.edu.pl	25	0	3	0	22
TOTAL: 3		9395	117	4006	7	5265

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	70
it.comp.os.win.windows10	14
it.tlc.cellulari.android	8
comp.mobile.android	6
nl.comp.os.linux.techniek	3
it.comp.os.win.windows7	3
comp.mobile.ipad	2
hr.ponuda.hardver.komponente	2
nl.gezondheid.alternatief-genezen	1
nl.actueel	1
de.rec.sf.startrek	1
it.comp.virtualizzazione	1
nl.wetenschap.regulier	1
nl.comp.3d-printen	1
de.comp.os.os2	1
de.comp.lang.php	1
nl.comp.sys.android	1
TOTAL: 17	117

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

Groups	Count
pl.sci.matematyka	6
pl.praca.oferowana	1
TOTAL: 2	7

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4192
Binary in non-binary group	171
Scoring filter (11)	72
Scoring filter (8)	37
EMP (phl)	31
Poison newsgroup	24
Scoring filter (9)	18
HTML post	7
Scoring filter (10)	7
Scoring filter (12)	4
Too many newsgroups	3
EMP (fsl)	2
Scoring filter (14)	1
TOTAL: 13	4569

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	417
	news.nask.pl	275
	news.icm.edu.pl	3
	TOTAL: 3	695
TOTAL: 1		695

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	42061	628	13740	291	10	41827	1%	23:59:57
2	icm	25493	125	16899	521	0	0	0%	23:59:57
3	poznan	11271	62	6690	40	0	0	0%	23:59:57
4	nask	8411	5	5226	2	0	0	0%	23:59:57
5	okapi	0	0	0	0	0	1456	0%	23:57:50
TOTAL: 5		87236	820	42555	854	10	43283	0%	119:57:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	9.8 MB	1.1 MB	10.9 MB	0.1 KB/s	12.2 KB	23:59:57
2	icm	425.8 KB	251.0 MB	251.4 MB	3.0 KB/s	398.5 KB	23:59:57
3	poznan	238.5 KB	851.6 KB	1.1 MB	0.0 KB/s	10.7 KB	23:59:57
4	nask	43.3 KB	179.8 KB	223.1 KB	0.0 KB/s	31.9 KB	23:59:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		10.5 MB	253.1 MB	263.6 MB	0.6 KB/s	161.2 KB	119:57:38

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	66	68.2 KB	29	0	00:00:23
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:01
TOTAL: 2		292	66	68.2 KB	29	4	00:01:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	66	68.2 KB	29	4	00:00:30
2	*.play-internet.pl	2	0	0.0 KB	0	0	00:01:27
TOTAL: 2		678	66	68.2 KB	29	4	00:01:57

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
user-94-254-135-20.play-internet.pl	0.020	0.036	00:01:27
web.man.lodz.pl	1.468	4.520	00:00:23
mon.man.lodz.pl	1.228	4.404	00:00:04
isvcs01.man.lodz.pl	0.040	0.088	00:00:01
david.man.lodz.pl	0.460	1.412	00:00:01
TOTAL: 5	3.216	10.460	00:01:57

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
user-94-254-135-20.play-internet.pl	2
TOTAL: 3	386

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	46
2	pl.comp.pecet	20
TOTAL: 2		66

Daily Usenet report

Aug 5 04:15:02 -- Aug 6 04:15:02