Daily Usenet report: Jan 9 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jan 9 04:22:10 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Jan 9 09:44:21 newsfeed nnrpd[6102]: SERVER perl filter not defined
Jan 9 12:50:51 newsfeed nnrpd[12605]: SERVER perl filter not defined
Jan 9 13:09:50 newsfeed nnrpd[13264]: SERVER perl filter not defined
Jan 9 13:13:15 newsfeed nnrpd[13470]: SERVER perl filter not defined
Jan 9 13:15:33 newsfeed nnrpd[13553]: SERVER perl filter not defined
Jan 9 13:32:22 newsfeed nnrpd[14010]: SERVER perl filter not defined
Jan 9 15:50:10 newsfeed nnrpd[18812]: SERVER perl filter not defined
Jan 9 15:55:39 newsfeed nnrpd[19052]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3575462	98.7%	408.8 MB	98.4%
innd	38940	1.1%	5.6 MB	1.4%
innfeed	4842	0.1%	680.3 KB	0.2%
nnrpd	4838	0.1%	498.3 KB	0.1%
TOTAL: 4	3624082	100.0%	415.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3566878	96.2%
Negative hits	111593	3.0%
Positive hits	15047	0.4%
Cache misses	12476	0.3%
TOTAL: 4	3705994	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.133	0.0%	189	0.000	32.450	2447.000
article cleanup	00:00:50.705	0.1%	3575965	0.006	0.014	0.033
article logging	00:02:12.569	0.2%	3576696	0.017	0.037	1.113
article parse	01:31:51.423	6.4%	165238920	0.019	0.033	0.070
article write	00:39:16.846	2.7%	3557601	0.327	0.662	4.095
artlog/artcncl	00:00:00.007	0.0%	167	0.000	0.042	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:03:27.960	0.2%	185217876	0.001	0.001	0.002
hisgrep/artcncl	00:00:01.035	0.0%	172	0.000	6.017	627.000
hishave/artcncl	00:00:00.190	0.0%	189	0.000	1.005	141.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:30:19.479	2.1%	3710297	0.087	0.490	8.322
history sync	00:00:00.019	0.0%	292	0.000	0.065	1.000
history write	00:45:01.937	3.1%	3571053	0.097	0.757	9.385
hiswrite/artcncl	00:00:00.005	0.0%	17	0.000	0.294	1.000
idle	11:26:38.080	47.7%	85798220	0.315	0.480	0.896
nntp read	01:08:15.719	4.7%	162174679	0.017	0.025	0.037
overview write	00:00:23.418	0.0%	3557601	0.002	0.007	0.023
perl filter	07:02:59.341	29.4%	3571052	5.175	7.107	9.410
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:28.423	0.4%	7162909	0.037	0.054	0.108
TOTAL: 24:00:23.222	23:17:53.289	97.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.241	0.0%	63048	0.000	0.004	0.167
article prepare	00:00:00.023	0.0%	91070	0.000	0.000	0.250
article read	00:00:07.416	0.0%	22777	0.000	0.326	117.833
backlog stats	00:00:00.334	0.0%	295326	0.000	0.001	0.032
callbacks	00:00:00.000	0.0%	7	0.000	0.000	0.000
data read	00:00:02.272	0.0%	143984	0.000	0.016	0.130
data write	00:00:17.927	0.0%	154613	0.000	0.116	0.289
idle	23:59:17.960	100.0%	295326	17.076	292.416	2925.043
status file	00:00:00.717	0.0%	908	0.000	0.790	4.000
TOTAL: 23:59:57.487	23:59:46.890	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	55:59:19.109	99.9%	1461174	0.000	137.943	97856.435
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:01:05.030	0.0%	2923025	0.000	0.022	0.500
readart	00:00:00.811	0.0%	69	0.000	11.754	42.474
TOTAL: 56:02:17.973	56:00:24.950	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1139	3569490	3544760	19437	5293	99%	476:08:49
2	news.nask.pl	1	27406	11695	2822	12889	42%	23:50:40
3	news.icm.edu.pl	49	207	9	178	20	4%	15:47:28
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1198	3597111	3556472	22437	18202	98%	515:46:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	992.6 GB	107.8 MB	153.1 MB	992.9 GB	99%	293.3 KB
2	news.nask.pl	100.8 MB	1.9 MB	170.1 MB	272.8 MB	36%	11.4 KB
3	news.icm.edu.pl	97.6 KB	0.0 KB	1.1 MB	1.2 MB	7%	43.2 KB
4	localhost	13.0 KB	0.0 KB	0.0 KB	13.0 KB	100%	1.6 KB
TOTAL: 4		992.7 GB	109.7 MB	324.4 MB	993.1 GB	99%	291.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 9 04:15:02 - 04:59:59	126566	3.6%	46.91	32.0 GB	3.2%	12438.05
Jan 9 05:00:00 - 05:59:59	173830	4.9%	48.29	44.3 GB	4.5%	12892.54
Jan 9 06:00:00 - 06:59:59	174449	4.9%	48.46	44.9 GB	4.5%	13092.33
Jan 9 07:00:00 - 07:59:59	171309	4.8%	47.59	44.3 GB	4.5%	12916.79
Jan 9 08:00:00 - 08:59:59	166366	4.7%	46.21	43.9 GB	4.4%	12799.70
Jan 9 09:00:00 - 09:59:59	163040	4.6%	45.29	43.2 GB	4.4%	12578.14
Jan 9 10:00:00 - 10:59:59	167792	4.7%	46.61	43.8 GB	4.4%	12751.78
Jan 9 11:00:00 - 11:59:59	164272	4.6%	45.63	45.1 GB	4.6%	13143.83
Jan 9 12:00:00 - 12:59:59	145014	4.1%	40.28	40.9 GB	4.1%	11917.57
Jan 9 13:00:00 - 13:59:59	146541	4.1%	40.71	38.9 GB	3.9%	11334.99
Jan 9 14:00:00 - 14:59:59	143803	4.0%	39.95	39.5 GB	4.0%	11498.94
Jan 9 15:00:00 - 15:59:59	140010	3.9%	38.89	38.7 GB	3.9%	11286.74
Jan 9 16:00:00 - 16:59:59	140818	4.0%	39.12	38.3 GB	3.9%	11147.06
Jan 9 17:00:00 - 17:59:59	129448	3.6%	35.96	38.0 GB	3.8%	11059.19
Jan 9 18:00:00 - 18:59:59	120119	3.4%	33.37	37.1 GB	3.7%	10794.75
Jan 9 19:00:00 - 19:59:59	113693	3.2%	31.58	37.6 GB	3.8%	10962.48
Jan 9 20:00:00 - 20:59:59	134442	3.8%	37.34	41.5 GB	4.2%	12087.22
Jan 9 21:00:00 - 21:59:59	144983	4.1%	40.27	45.4 GB	4.6%	13216.40
Jan 9 22:00:00 - 22:59:59	148610	4.2%	41.28	45.0 GB	4.5%	13107.47
Jan 9 23:00:00 - 23:59:59	158245	4.4%	43.96	43.1 GB	4.4%	12565.58
Jan 10 00:00:00 - 00:59:59	122181	3.4%	33.94	33.4 GB	3.4%	9718.38
Jan 10 01:00:00 - 01:59:59	134876	3.8%	37.47	39.1 GB	3.9%	11393.62
Jan 10 02:00:00 - 02:59:59	152595	4.3%	42.39	44.1 GB	4.4%	12842.13
Jan 10 03:00:00 - 03:59:59	136597	3.8%	37.94	38.8 GB	3.9%	11302.58
Jan 10 04:00:00 - 04:15:03	36630	1.0%	40.56	10.4 GB	1.1%	12116.33
TOTAL: 24:00:01	3556229	100.0%	41.16	991.4 GB	100.0%	12031.99

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	13396	10	379	2	13005
2	newsfeed.pionier.net.pl	5648	206	4523	0	919
3	news.icm.edu.pl	22	0	0	0	22
TOTAL: 3		19066	216	4902	2	13946

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	82
it.tlc.cellulari.android	68
nl.comp.sys.android	22
it.comp.os.win.windows7	11
comp.mobile.android	10
it.discussioni.energia	8
it.comp.os.win.windows10	8
de.comp.office-pakete.ms-office.misc	4
alt.sex.telephones	1
de.comp.lang.perl	1
hr.alt.cellular.gsm.android	1
TOTAL: 11	216

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12104
Binary in non-binary group	583
EMP (phl)	325
Scoring filter (8)	103
Scoring filter (9)	69
Scoring filter (10)	14
HTML post	7
Scoring filter (12)	3
Scoring filter (11)	3
Scoring filter (13)	2
Scoring filter (17)	1
TOTAL: 11	13214

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	18468	146	16819	60	27	45893	0%	23:58:05
2	poznan	16781	106	11332	25	0	0	0%	23:54:56
3	icm	50651	52	24797	22335	0	1	0%	24:02:49
4	nask	6875	7	5268	1	0	0	0%	24:04:52
5	okapi	0	0	0	0	0	2400	0%	24:00:02
TOTAL: 5		92775	311	58216	22421	27	48294	0%	120:00:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.3 MB	5.7 GB	5.7 GB	68.9 KB/s	266.4 KB	24:02:49
2	panorama	539.7 KB	356.9 KB	896.5 KB	0.0 KB/s	4.4 KB	23:58:05
3	poznan	534.7 KB	113.5 KB	648.2 KB	0.0 KB/s	4.9 KB	23:54:56
4	nask	14.3 KB	20.0 KB	34.3 KB	0.0 KB/s	4.3 KB	24:04:52
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		2.3 MB	5.7 GB	5.7 GB	13.8 KB/s	262.4 KB	120:00:44

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	50	54.9 KB	24	0	00:00:22
2	212.51.203.199	2	19	35.6 KB	1	0	00:37:31
3	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 3		298	69	90.5 KB	25	8	56:02:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	680	50	54.9 KB	24	8	00:00:28
2	unresolved	2	19	35.6 KB	1	0	56:01:48
3	*.ap-media.pl	1	0	0.0 KB	0	0	00:00:01
TOTAL: 3		683	69	90.5 KB	25	8	56:02:17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.41.228	82.794	136.843	55:24:16
212.51.203.199	0.022	0.059	00:37:31
web.man.lodz.pl	1.015	7.113	00:00:22
mon.man.lodz.pl	0.954	6.586	00:00:03
isvcs01.man.lodz.pl	0.059	0.188	00:00:01
david.man.lodz.pl	0.280	2.163	00:00:01
ip-209-5.ap-media.pl	0.007	0.022	00:00:01
TOTAL: 7	85.131	152.974	56:02:17

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
ip-209-5.ap-media.pl	1
TOTAL: 3	385

NNRP client timeouts [Top 20]:

System	Conn	Peer
89.38.41.228	1	1
212.51.203.199	1	0
TOTAL: 2	2	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	28
2	pl.comp.os.ms-windows.winnt	22
3	pl.rec.radio.amatorskie	19
TOTAL: 3		69

Daily Usenet report

Jan 9 04:15:02 -- Jan 10 04:15:03