Daily Usenet report: Dec 2 04:15:03

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Dec 2 13:46:15 newsfeed nnrpd[25281]: SERVER perl filter not defined
Dec 2 13:46:43 newsfeed nnrpd[25294]: SERVER perl filter not defined
Dec 2 15:33:46 newsfeed nnrpd[28986]: SERVER perl filter not defined
Dec 2 22:30:02 newsfeed pgpverify[11051]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3330866	98.5%	388.1 MB	98.2%
innd	42635	1.3%	5.8 MB	1.5%
innfeed	4870	0.1%	689.4 KB	0.2%
nnrpd	4831	0.1%	502.6 KB	0.1%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3383206	100.0%	395.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3323430	96.9%
Negative hits	90510	2.6%
Positive hits	16441	0.5%
Cache misses	122	0.0%
TOTAL: 4	3430503	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.410	0.0%	586	0.000	4.113	837.000
article cleanup	00:00:46.788	0.1%	3330572	0.006	0.014	0.027
article logging	00:01:43.992	0.1%	3331143	0.016	0.031	1.251
article parse	01:21:29.136	5.7%	194745331	0.015	0.025	0.079
article write	00:27:10.885	1.9%	3308061	0.259	0.493	3.665
artlog/artcncl	00:00:00.008	0.0%	566	0.000	0.014	1.000
data move	00:05:59.151	0.4%	214631805	0.001	0.002	0.007
hisgrep/artcncl	00:00:00.343	0.0%	275	0.000	1.247	143.000
hishave/artcncl	00:00:00.214	0.0%	586	0.000	0.365	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:04.711	1.9%	3432460	0.101	0.473	8.657
history sync	00:00:00.016	0.0%	291	0.000	0.055	1.000
history write	00:41:01.621	2.8%	3325493	0.189	0.740	11.353
hiswrite/artcncl	00:00:00.447	0.0%	311	0.000	1.437	1.629
idle	11:45:17.993	49.0%	136734708	0.133	0.309	2.668
nntp read	01:28:56.912	6.2%	192035643	0.015	0.028	0.044
overview write	00:00:18.610	0.0%	3308061	0.001	0.006	0.014
perl filter	06:59:53.912	29.2%	3325493	5.292	7.576	9.614
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:26.355	0.4%	6651434	0.032	0.049	0.089
TOTAL: 24:00:17.887	23:25:13.504	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.178	0.0%	42031	0.000	0.004	0.250
article prepare	00:00:00.015	0.0%	46244	0.000	0.000	0.125
article read	00:00:28.532	0.0%	11564	0.000	2.467	325.667
backlog stats	00:00:00.218	0.0%	216635	0.000	0.001	0.033
callbacks	00:00:00.000	0.0%	12	0.000	0.000	0.000
data read	00:00:01.662	0.0%	115218	0.000	0.014	0.130
data write	00:00:07.990	0.0%	99589	0.000	0.080	0.583
idle	24:00:05.150	99.9%	216635	21.921	398.851	14177.000
status file	00:00:00.668	0.0%	892	0.000	0.749	8.000
TOTAL: 24:00:50.506	24:00:44.413	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:08:09.705	96.5%	3118	0.000	157.057	22264.176
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.148	0.0%	7036	0.000	0.021	0.500
readart	00:00:04.335	0.9%	69	0.000	62.826	1309.000
TOTAL: 00:08:27.304	00:08:14.188	97.4%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1366	3307119	3296524	4979	5616	99%	366:29:51
2	news.nask.pl	2	33498	10593	6031	16874	31%	24:00:14
3	news.icm.edu.pl	44	218	15	197	6	6%	15:31:24
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		1416	3340838	3307135	11207	22496	98%	406:01:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	942.8 GB	38.4 MB	149.5 MB	943.0 GB	99%	299.4 KB
2	news.nask.pl	62.9 MB	1.9 MB	185.9 MB	250.7 MB	25%	9.3 KB
3	news.icm.edu.pl	624.0 KB	134.3 KB	244.5 KB	1002.8 KB	62%	47.8 KB
4	localhost	5.2 KB	0.0 KB	0.0 KB	5.2 KB	100%	1.7 KB
TOTAL: 4		942.9 GB	40.4 MB	335.7 MB	943.2 GB	99%	297.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 2 04:15:03 - 04:59:59	108204	3.3%	40.12	30.2 GB	3.2%	11754.61
Dec 2 05:00:00 - 05:59:59	131697	4.0%	36.58	41.8 GB	4.4%	12166.20
Dec 2 06:00:00 - 06:59:59	130642	4.0%	36.29	40.6 GB	4.3%	11812.92
Dec 2 07:00:00 - 07:59:59	124187	3.8%	34.50	41.7 GB	4.4%	12151.20
Dec 2 08:00:00 - 08:59:59	119351	3.6%	33.15	40.5 GB	4.3%	11785.99
Dec 2 09:00:00 - 09:59:59	132987	4.0%	36.94	39.0 GB	4.1%	11355.66
Dec 2 10:00:00 - 10:59:59	151137	4.6%	41.98	41.2 GB	4.4%	12005.53
Dec 2 11:00:00 - 11:59:59	158324	4.8%	43.98	42.2 GB	4.5%	12278.86
Dec 2 12:00:00 - 12:59:59	143600	4.3%	39.89	38.7 GB	4.1%	11274.27
Dec 2 13:00:00 - 13:59:59	141950	4.3%	39.43	38.3 GB	4.1%	11155.57
Dec 2 14:00:00 - 14:59:59	136104	4.1%	37.81	38.8 GB	4.1%	11313.76
Dec 2 15:00:00 - 15:59:59	155649	4.7%	43.24	40.6 GB	4.3%	11836.29
Dec 2 16:00:00 - 16:59:59	166656	5.0%	46.29	40.3 GB	4.3%	11746.82
Dec 2 17:00:00 - 17:59:59	151708	4.6%	42.14	39.5 GB	4.2%	11506.77
Dec 2 18:00:00 - 18:59:59	143647	4.3%	39.90	38.6 GB	4.1%	11237.73
Dec 2 19:00:00 - 19:59:59	145598	4.4%	40.44	40.3 GB	4.3%	11738.05
Dec 2 20:00:00 - 20:59:59	148825	4.5%	41.34	40.3 GB	4.3%	11727.93
Dec 2 21:00:00 - 21:59:59	144728	4.4%	40.20	40.2 GB	4.3%	11713.12
Dec 2 22:00:00 - 22:59:59	147557	4.5%	40.99	40.6 GB	4.3%	11814.73
Dec 2 23:00:00 - 23:59:59	147194	4.5%	40.89	41.0 GB	4.4%	11928.62
Dec 3 00:00:00 - 00:59:59	106940	3.2%	29.71	31.9 GB	3.4%	9303.93
Dec 3 01:00:00 - 01:59:59	126766	3.8%	35.21	39.7 GB	4.2%	11551.19
Dec 3 02:00:00 - 02:59:59	124752	3.8%	34.65	37.9 GB	4.0%	11034.52
Dec 3 03:00:00 - 03:59:59	97564	3.0%	27.10	29.3 GB	3.1%	8537.26
Dec 3 04:00:00 - 04:15:02	21458	0.6%	23.79	7.5 GB	0.8%	8742.07
TOTAL: 23:59:59	3307225	100.0%	38.28	940.6 GB	100.0%	11415.69

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	17089	19	432	9	16629
2	newsfeed.pionier.net.pl	5981	157	4638	1	1185
3	news.icm.edu.pl	5	0	2	0	3
TOTAL: 3		23075	176	5072	10	17817

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	72
it.tlc.cellulari.android	36
it.comp.os.win.windows10	19
comp.mobile.ipad	18
uk.rec.cycling.moderated	10
de.comp.office-pakete.ms-office.outlook	4
rec.arts.tv.x-factor	4
comp.mobile.android	3
it.comp.os.linux.ubuntu	3
comp.sys.raspberry-pi	2
hr.rec.glazba.hifi	2
it.comp.os.win.windows7	1
hr.alt.cellular.gsm.android	1
alt.sex.telephones	1
TOTAL: 14	176

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16097
Binary in non-binary group	906
Scoring filter (8)	129
Scoring filter (9)	39
Too many newsgroups	13
Poison newsgroup	13
Scoring filter (10)	12
EMP (fsl)	11
EMP (phl)	11
Scoring filter (13)	7
HTML post	2
Scoring filter (12)	2
Too many newsgroups (low_xpost)	2
Scoring filter (11)	1
Scoring filter (17)	1
TOTAL: 15	17246

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	7904	2360	3571	563	25	35486	29%	23:57:16
2	icm	42616	123	25781	8039	0	0	0%	24:02:02
3	nask	11918	110	7374	2	1	0	0%	24:05:11
4	poznan	15993	95	10468	18	0	0	0%	24:05:07
5	okapi	0	0	0	0	0	2186	0%	24:00:02
TOTAL: 5		78431	2688	47194	8622	26	37672	3%	120:09:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	9.4 MB	3.2 MB	12.6 MB	0.1 KB/s	4.4 KB	23:57:16
2	icm	2.5 MB	1.9 GB	1.9 GB	22.8 KB/s	241.6 KB	24:02:02
3	nask	763.3 KB	4.5 KB	767.8 KB	0.0 KB/s	6.9 KB	24:05:11
4	poznan	378.8 KB	91.0 KB	469.7 KB	0.0 KB/s	4.2 KB	24:05:07
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		13.0 MB	1.9 GB	1.9 GB	4.6 KB/s	175.6 KB	120:09:38

Backlog files shrunk by innfeed:

Server	Size
panorama	2.4 MB
okapi	488.3 KB
TOTAL: 2	2.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	56	61.8 KB	25	0	00:00:24
2	lze200.lze.lodz.pl	4	13	31.5 KB	5	0	00:07:14
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		295	69	93.2 KB	30	3	00:08:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	675	56	61.8 KB	25	3	00:00:29
2	*.lze.lodz.pl	4	13	31.5 KB	5	0	00:07:14
3	*.dynamic.chello.pl	1	0	0.0 KB	0	0	00:00:10
4	*.146.23.241.nat.umts.dynamic.t-mobile.pl	2	0	0.0 KB	0	0	00:00:32
TOTAL: 4		682	69	93.2 KB	30	3	00:08:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.020	0.058	00:07:14
188.146.23.241.nat.umts.dynamic.t-mobile.pl	0.071	0.050	00:00:32
web.man.lodz.pl	1.066	3.915	00:00:24
89-74-195-204.dynamic.chello.pl	0.000	0.023	00:00:10
mon.man.lodz.pl	1.028	3.991	00:00:03
david.man.lodz.pl	0.356	1.057	00:00:01
isvcs01.man.lodz.pl	0.010	0.056	00:00:00
TOTAL: 7	2.551	9.150	00:08:26

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
188.146.23.241.nat.umts.dynamic.t-mobile.pl	2
89-74-195-204.dynamic.chello.pl	1
TOTAL: 4	387

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.os.ms-windows.winnt	52
2	pl.comp.pecet	6
3	pl.misc.telefonia.gsm	6
4	pl.rec.kuchnia	5
TOTAL: 4		69

Daily Usenet report

Dec 2 04:15:03 -- Dec 3 04:15:02