Daily Usenet report: Jun 27 04:15:02

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Jun 27 10:31:05 newsfeed nnrpd[22667]: SERVER perl filter not defined
Jun 27 11:27:07 newsfeed overchan[7352]: input line too long, skipping
Jun 27 11:33:53 newsfeed overchan[7352]: input line too long, skipping
Jun 27 11:50:58 newsfeed overchan[7352]: input line too long, skipping
Jun 27 11:53:53 newsfeed nnrpd[25615]: SERVER perl filter not defined
Jun 27 11:59:33 newsfeed overchan[7352]: input line too long, skipping
Jun 27 12:08:46 newsfeed overchan[7352]: input line too long, skipping
Jun 27 13:26:10 newsfeed nnrpd[28981]: SERVER perl filter not defined
Jun 27 13:26:23 newsfeed nnrpd[28997]: SERVER perl filter not defined
Jun 27 14:41:05 newsfeed overchan[7352]: input line too long, skipping
Jun 27 19:35:27 newsfeed nnrpd[10001]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2895988	97.4%	341.4 MB	97.2%
innfeed	45820	1.5%	5.4 MB	1.5%
innd	26003	0.9%	4.1 MB	1.2%
nnrpd	4788	0.2%	500.5 KB	0.1%
overchan	7	0.0%	1.0 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2972607	100.0%	351.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2842636	74.7%
Negative hits	874936	23.0%
Positive hits	86940	2.3%
Cache misses	341	0.0%
TOTAL: 4	3804853	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.088	0.0%	175	0.000	23.360	449.000
article cleanup	00:00:36.316	0.0%	2897181	0.010	0.013	0.017
article logging	00:02:05.495	0.1%	2897795	0.027	0.043	0.814
article parse	01:03:37.864	4.4%	218558670	0.012	0.017	0.027
article write	00:21:24.750	1.5%	2867220	0.252	0.448	0.795
artlog/artcncl	00:00:00.006	0.0%	172	0.000	0.035	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:24.146	0.2%	237014804	0.001	0.001	0.005
hisgrep/artcncl	00:00:00.911	0.0%	145	0.000	6.283	44.316
hishave/artcncl	00:00:00.572	0.0%	175	0.000	3.269	49.700
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:17:49.384	1.2%	3841354	0.013	0.278	2.807
history sync	00:00:00.014	0.0%	290	0.000	0.048	0.500
history write	01:42:22.715	7.1%	2874932	0.150	2.137	9.874
hiswrite/artcncl	00:00:00.205	0.0%	30	0.000	6.833	12.000
idle	13:24:09.013	55.8%	138030489	0.261	0.350	0.621
nntp read	01:09:51.521	4.9%	216447762	0.015	0.019	0.027
overview write	00:00:14.553	0.0%	2867220	0.003	0.005	0.007
perl filter	05:12:32.350	21.7%	2874932	4.407	6.523	7.552
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:34.515	0.3%	5871345	0.035	0.047	0.067
TOTAL: 24:00:01.790	23:22:48.418	97.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.555	0.0%	178564	0.000	0.003	0.014
article prepare	00:00:00.000	0.0%	8008	0.000	0.000	0.000
article read	00:01:04.451	0.1%	1969	0.000	32.733	584.667
backlog stats	00:00:00.395	0.0%	574446	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:05.274	0.0%	375590	0.007	0.014	0.059
data write	00:00:08.591	0.0%	239424	0.016	0.036	0.071
idle	23:51:25.429	99.9%	574446	41.791	149.510	851.537
status file	00:00:13.198	0.0%	15778	0.611	0.836	2.062
TOTAL: 23:53:18.193	23:52:57.893	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.712	61.6%	3068	0.000	6.099	177.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.148	0.5%	6794	0.000	0.022	1.000
readart	00:00:00.001	0.0%	54	0.000	0.019	0.500
TOTAL: 00:00:30.381	00:00:18.861	62.1%	-	-	-	-

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	828	2865307	2781149	61674	22484	97%	438:28:31
2	news.nask.pl	2	94885	84290	3120	7475	88%	24:08:04
3	news.icm.edu.pl	46	237	25	209	3	10%	17:20:28
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		882	2960434	2865469	65003	29962	96%	479:57:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	866.3 GB	6.4 GB	6.5 GB	879.2 GB	98%	328.8 KB
2	news.nask.pl	24.6 GB	1.4 MB	43.2 MB	24.7 GB	99%	281.7 KB
3	news.icm.edu.pl	401.4 KB	0.0 KB	139.8 KB	541.2 KB	74%	19.3 KB
4	localhost	7.1 KB	0.0 KB	0.0 KB	7.1 KB	100%	1.4 KB
TOTAL: 4		890.9 GB	6.4 GB	6.5 GB	903.8 GB	98%	327.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 27 04:15:02 - 04:59:59	94182	3.3%	34.91	28.6 GB	3.2%	11132.08
Jun 27 05:00:00 - 05:59:59	130349	4.5%	36.21	41.3 GB	4.6%	12033.84
Jun 27 06:00:00 - 06:59:59	132898	4.6%	36.92	41.6 GB	4.7%	12111.54
Jun 27 07:00:00 - 07:59:59	161897	5.7%	44.97	39.3 GB	4.4%	11442.46
Jun 27 08:00:00 - 08:59:59	144112	5.0%	40.03	42.9 GB	4.8%	12504.92
Jun 27 09:00:00 - 09:59:59	143286	5.0%	39.80	44.4 GB	5.0%	12941.65
Jun 27 10:00:00 - 10:59:59	134889	4.7%	37.47	44.7 GB	5.0%	13005.65
Jun 27 11:00:00 - 11:59:59	132249	4.6%	36.74	41.9 GB	4.7%	12204.30
Jun 27 12:00:00 - 12:59:59	130651	4.6%	36.29	41.9 GB	4.7%	12214.24
Jun 27 13:00:00 - 13:59:59	127534	4.5%	35.43	40.5 GB	4.6%	11793.59
Jun 27 14:00:00 - 14:59:59	129767	4.5%	36.05	41.6 GB	4.7%	12109.95
Jun 27 15:00:00 - 15:59:59	127316	4.4%	35.37	40.7 GB	4.6%	11846.57
Jun 27 16:00:00 - 16:59:59	87495	3.1%	24.30	28.3 GB	3.2%	8253.66
Jun 27 17:00:00 - 17:59:59	88778	3.1%	24.66	28.9 GB	3.2%	8408.57
Jun 27 18:00:00 - 18:59:59	110023	3.8%	30.56	35.1 GB	3.9%	10217.47
Jun 27 19:00:00 - 19:59:59	124981	4.4%	34.72	39.2 GB	4.4%	11403.99
Jun 27 20:00:00 - 20:59:59	111739	3.9%	31.04	35.0 GB	3.9%	10180.38
Jun 27 21:00:00 - 21:59:59	83376	2.9%	23.16	27.3 GB	3.1%	7955.52
Jun 27 22:00:00 - 22:59:59	106246	3.7%	29.51	31.4 GB	3.5%	9143.43
Jun 27 23:00:00 - 23:59:59	108043	3.8%	30.01	31.8 GB	3.6%	9276.91
Jun 28 00:00:00 - 00:59:59	104685	3.7%	29.08	31.5 GB	3.5%	9164.55
Jun 28 01:00:00 - 01:59:59	108367	3.8%	30.10	33.6 GB	3.8%	9782.41
Jun 28 02:00:00 - 02:59:59	109292	3.8%	30.36	36.2 GB	4.1%	10541.53
Jun 28 03:00:00 - 03:59:59	103913	3.6%	28.86	32.8 GB	3.7%	9566.19
Jun 28 04:00:00 - 04:15:02	29149	1.0%	32.32	9.3 GB	1.0%	10856.19
TOTAL: 24:00:00	2865217	100.0%	33.16	889.8 GB	100.0%	10798.59

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	22896	231	21963	1	701
2	news.nask.pl	7699	36	273	4	7386
3	news.icm.edu.pl	4	0	0	0	4
TOTAL: 3		30599	267	22236	5	8091

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	138
it.comp.os.win.windows10	51
comp.mobile.android	26
it.tlc.cellulari.android	17
de.comm.software.mailreader	7
comp.sys.raspberry-pi	7
nl.comp.os.linux.techniek	4
hr.ponuda.hardver.komponente	4
comp.mobile.ipad	3
it.comp.os.linux.ubuntu	2
de.comp.lang.perl	2
de.comp.lang.php	2
it.discussioni.energia	2
it.tlc.cellulari.iphone	2
TOTAL: 14	267

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

Groups	Count
pl.praca.oferowana	4
bit.listserv.catholic	1
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7030
Binary in non-binary group	326
EMP (phl)	35
Scoring filter (9)	32
Scoring filter (8)	29
Poison newsgroup	6
HTML post	6
Scoring filter (13)	6
Excessively crossposted test article	4
Scoring filter (10)	1
Too many newsgroups	1
EMP (fsl)	1
Scoring filter (11)	1
TOTAL: 13	7478

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	412
	news.nask.pl	200
	news.icm.edu.pl	1
	TOTAL: 3	613
TOTAL: 1		613

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	53413	691	26144	286	16	151759	1%	23:59:58
2	poznan	15644	290	9978	41	0	0	1%	23:59:57
3	nask	46970	96	45174	0	0	0	0%	23:59:58
4	icm	149426	59	136262	51	0	0	0%	23:59:58
5	okapi	0	0	0	0	0	2614	0%	23:57:50
TOTAL: 5		265453	1136	217558	378	16	154373	0%	119:57:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	24.4 MB	0.0 KB	24.4 MB	0.3 KB/s	259.9 KB	23:59:58
2	icm	3.7 MB	15.1 MB	18.8 MB	0.2 KB/s	174.8 KB	23:59:58
3	panorama	2.1 MB	758.4 KB	2.8 MB	0.0 KB/s	3.0 KB	23:59:58
4	poznan	1.7 MB	129.9 KB	1.8 MB	0.0 KB/s	5.6 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		31.8 MB	15.9 MB	47.8 MB	0.1 KB/s	32.3 KB	119:57:41

Backlog files shrunk by innfeed:

Server	Size
panorama	3.3 MB
TOTAL: 1	3.3 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	54	47.1 KB	25	0	00:00:23
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 2		293	54	47.1 KB	25	5	00:00:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	54	47.1 KB	25	5	00:00:29
2	*.census.shodan.io	1	0	0.0 KB	0	0	00:00:00
TOTAL: 2		677	54	47.1 KB	25	5	00:00:29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.256	4.352	00:00:23
mon.man.lodz.pl	0.972	4.004	00:00:03
david.man.lodz.pl	0.316	1.380	00:00:01
isvcs01.man.lodz.pl	0.028	0.104	00:00:00
ninja.census.shodan.io	0.004	0.020	00:00:00
TOTAL: 5	2.576	9.860	00:00:29

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	95
ninja.census.shodan.io	1
TOTAL: 3	384

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	28
2	pl.comp.os.ms-windows.winnt	24
3	pl.internet.polip	2
TOTAL: 3		54

Daily Usenet report

Jun 27 04:15:02 -- Jun 28 04:15:02