Daily Usenet report: Apr 10 04:15:01

Unknown entries from news log file:

First 44 / 44 lines (100.0%)

Apr 10 09:13:35 newsfeed nnrpd[31275]: SERVER perl filter not defined
Apr 10 11:27:14 newsfeed nnrpd[5850]: SERVER perl filter not defined
Apr 10 12:46:35 newsfeed innfeed[1097]: CNFS: no magic cookie found for cycbuff BUF03, initializing
Apr 10 12:46:35 newsfeed innfeed[1097]: CNFS: CNFSflushhead: attempted flush whilst read only
Apr 10 12:46:35 newsfeed innfeed[1097]: CNFS: CNFSshutdowncycbuff: flushing BUF03
Apr 10 12:46:35 newsfeed innfeed[1097]: CNFS: CNFSflushhead: attempted flush whilst read only
Apr 10 12:46:35 newsfeed innfeed[1097]: SM: storage method 'cnfs' failed initialization
Apr 10 12:46:35 newsfeed innfeed[1097]: SM: one or more storage methods failed initialization
Apr 10 12:46:35 newsfeed innfeed[1097]: Storage manager initialization failed -- it is OK after a change in storage methods
Apr 10 12:46:35 newsfeed innd: CNFS: no magic cookie found for cycbuff BUF03, initializing
Apr 10 12:46:35 newsfeed innd: CNFS: no magic cookie found for cycbuff BUF04, initializing
Apr 10 12:46:35 newsfeed innd: CNFS: Mismatch 3: read BUF04 for cycbuff BUF05
Apr 10 12:46:35 newsfeed innd: SM: storage method 'cnfs' failed initialization
Apr 10 12:46:35 newsfeed innd: SM: one or more storage methods failed initialization
Apr 10 15:57:29 newsfeed nnrpd[31106]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.bainaries.pictures.erotica.amateur.female 0]
Apr 10 15:58:36 newsfeed nnrpd[31106]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 15:59:13 newsfeed nnrpd[31106]: message repeated 5 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur 1]
Apr 10 16:03:55 newsfeed overchan[31095]: input line too long, skipping
Apr 10 16:03:55 newsfeed overchan[31095]: message repeated 2 times: [ input line too long, skipping]
Apr 10 16:03:55 newsfeed overchan[31095]: input line too long, skipping
Apr 10 16:03:55 newsfeed overchan[31095]: input line too long, skipping
Apr 10 16:03:55 newsfeed overchan[31095]: input line too long, skipping
Apr 10 16:03:55 newsfeed overchan[31095]: input line too long, skipping
Apr 10 16:03:56 newsfeed overchan[31095]: input line too long, skipping
Apr 10 16:03:56 newsfeed overchan[31095]: input line too long, skipping
Apr 10 16:03:56 newsfeed overchan[31095]: input line too long, skipping
Apr 10 16:03:56 newsfeed overchan[31095]: input line too long, skipping
Apr 10 16:03:56 newsfeed overchan[31095]: input line too long, skipping
Apr 10 16:13:43 newsfeed nnrpd[32115]: SERVER perl filter not defined
Apr 10 16:18:10 newsfeed nnrpd[32365]: SERVER perl filter not defined
Apr 10 16:21:58 newsfeed nnrpd[32443]: SERVER perl filter not defined
Apr 10 17:59:11 newsfeed nnrpd[10777]: SERVER perl filter not defined
Apr 10 18:26:42 newsfeed overchan[1052]: input line too long, skipping
Apr 10 20:47:54 newsfeed nnrpd[17077]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 20:47:57 newsfeed nnrpd[17077]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 20:48:17 newsfeed nnrpd[17077]: message repeated 3 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 20:49:03 newsfeed nnrpd[17077]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 20:49:44 newsfeed nnrpd[17077]: message repeated 3 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 20:49:55 newsfeed nnrpd[17077]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 20:50:20 newsfeed nnrpd[17077]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 20:50:35 newsfeed nnrpd[17186]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 20:51:21 newsfeed nnrpd[17186]: message repeated 4 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 20:51:44 newsfeed nnrpd[17186]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur.female 1]
Apr 10 20:53:25 newsfeed nnrpd[17224]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2104759	98.4%	245.9 MB	98.2%
innd	25882	1.2%	3.5 MB	1.4%
nnrpd	4378	0.2%	460.3 KB	0.2%
innfeed	3583	0.2%	485.8 KB	0.2%
overchan	15	0.0%	1.5 KB	0.0%
controlchan	8	0.0%	0.6 KB	0.0%
TOTAL: 6	2138625	100.0%	250.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2085918	90.9%
Negative hits	198557	8.7%
Positive hits	10781	0.5%
Cache misses	114	0.0%
TOTAL: 4	2295370	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.840	0.0%	211	0.000	22.938	1241.000
article cleanup	00:00:28.187	0.0%	2064241	0.006	0.014	0.025
article logging	00:01:10.305	0.1%	2064786	0.017	0.034	0.170
article parse	01:01:19.982	5.1%	174331317	0.013	0.021	0.056
article write	00:20:42.061	1.7%	2051659	0.185	0.605	2.353
artlog/artcncl	00:00:00.002	0.0%	203	0.000	0.010	0.250
artlog/artparse	00:00:01.064	0.0%	23576	0.000	0.045	1.000
data move	00:03:40.906	0.3%	188198963	0.000	0.001	0.003
hisgrep/artcncl	00:00:02.219	0.0%	168	0.000	13.208	268.500
hishave/artcncl	00:00:00.167	0.0%	211	0.000	0.791	6.667
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:57:04.813	4.8%	2295452	0.102	1.492	6.385
history sync	00:00:00.008	0.0%	266	0.000	0.030	1.000
history write	01:25:34.876	7.1%	2085932	0.252	2.462	7.662
hiswrite/artcncl	00:00:00.045	0.0%	43	0.000	1.047	20.000
idle	10:04:14.098	50.3%	120369616	0.219	0.301	60060.000
nntp read	01:08:49.354	5.7%	172727061	0.016	0.024	0.036
overview write	00:00:11.425	0.0%	2051659	0.002	0.006	0.012
perl filter	04:26:57.281	22.2%	2062356	1.044	7.766	9.897
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:23.872	0.3%	4132459	0.029	0.049	0.087
TOTAL: 20:01:19.474	19:33:45.505	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.140	0.0%	31446	0.000	0.004	0.125
article prepare	00:00:00.012	0.0%	35778	0.000	0.000	0.050
article read	00:00:06.531	0.0%	8969	0.000	0.728	115.778
backlog stats	00:00:00.128	0.0%	163258	0.000	0.001	0.030
callbacks	00:00:00.000	0.0%	35	0.000	0.000	0.000
data read	00:00:01.742	0.0%	94315	0.000	0.018	0.143
data write	00:00:06.515	0.0%	69447	0.000	0.094	0.435
idle	20:00:31.470	100.0%	163258	98.704	441.212	30100.000
status file	00:00:01.142	0.0%	1246	0.000	0.917	4.000
TOTAL: 20:00:52.968	20:00:47.680	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:11:49.915	99.6%	3058	0.000	3763.870	450396.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:08.273	0.1%	67720	0.000	0.122	0.500
readart	00:00:23.659	0.2%	278	0.000	85.104	276.548
TOTAL: 03:12:40.660	03:12:21.847	99.8%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	23
flushlogs	2
go	2
logmode	2
mode	138
name	10
pause	2
paused	2
reload	2
reserve	2
shutdown	6
TOTAL: 12	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	863	2079831	2048054	6135	25642	98%	366:41:22
2	news.nask.pl	3	26877	13913	2477	10487	51%	19:14:21
3	news.icm.edu.pl	37	272	21	233	18	7%	14:18:41
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		911	2106987	2061995	8845	36147	97%	400:14:24

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	703.2 GB	13.6 MB	5.9 GB	709.2 GB	99%	358.6 KB
2	news.nask.pl	88.2 MB	434.7 KB	97.2 MB	185.9 MB	47%	7.8 KB
3	news.icm.edu.pl	295.5 KB	12.0 KB	260.3 KB	567.7 KB	52%	14.6 KB
4	localhost	9.8 KB	0.0 KB	0.0 KB	9.8 KB	100%	1.4 KB
TOTAL: 4		703.3 GB	14.0 MB	6.0 GB	709.4 GB	99%	354.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 10 04:15:01 - 04:59:59	78723	3.8%	29.17	28.2 GB	0.0%	10959.97
Apr 10 05:00:00 - 05:59:59	105076	5.1%	29.19	38.2 GB	0.0%	11113.12
Apr 10 06:00:00 - 06:59:59	98164	4.7%	27.27	37.7 GB	0.0%	10993.83
Apr 10 07:00:00 - 07:59:59	102476	5.0%	28.47	37.5 GB	0.0%	10917.87
Apr 10 08:00:00 - 08:59:59	107480	5.2%	29.86	30.2 GB	25.7%	8791.47
Apr 10 09:00:00 - 09:59:59	109368	5.3%	30.38	38.6 GB	32.9%	11244.40
Apr 10 10:00:00 - 10:59:59	106140	5.1%	29.48	37.1 GB	31.6%	10801.84
Apr 10 11:00:00 - 11:59:59	71344	3.5%	19.82	24.1 GB	20.5%	7011.81
Apr 10 12:00:00 - 12:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Apr 10 13:00:00 - 13:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Apr 10 14:00:00 - 14:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Apr 10 15:00:00 - 15:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Apr 10 16:00:00 - 16:59:59	88153	4.3%	24.49	28.0 GB	23.9%	8162.72
Apr 10 17:00:00 - 17:59:59	115773	5.6%	32.16	37.4 GB	31.9%	10908.01
Apr 10 18:00:00 - 18:59:59	121073	5.9%	33.63	40.4 GB	34.4%	11778.52
Apr 10 19:00:00 - 19:59:59	108892	5.3%	30.25	38.0 GB	32.3%	11067.38
Apr 10 20:00:00 - 20:59:59	120685	5.8%	33.52	38.6 GB	32.8%	11232.58
Apr 10 21:00:00 - 21:59:59	119997	5.8%	33.33	38.0 GB	32.4%	11069.36
Apr 10 22:00:00 - 22:59:59	112273	5.4%	31.19	37.6 GB	32.0%	10941.32
Apr 10 23:00:00 - 23:59:59	107054	5.2%	29.74	35.8 GB	30.4%	10417.80
Apr 11 00:00:00 - 00:59:59	89568	4.3%	24.88	31.1 GB	26.5%	9066.91
Apr 11 01:00:00 - 01:59:59	86200	4.2%	23.94	30.5 GB	26.0%	8896.06
Apr 11 02:00:00 - 02:59:59	99639	4.8%	27.68	34.6 GB	29.5%	10089.03
Apr 11 03:00:00 - 03:59:59	95822	4.6%	26.62	34.2 GB	29.1%	9958.76
Apr 11 04:00:00 - 04:15:03	23911	1.2%	26.48	8.7 GB	7.4%	10095.44
TOTAL: 24:00:02	2067811	100.0%	23.93	117.5 GB	100.0%	1425.61

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	25919	92	1718	0	24109
2	news.nask.pl	10810	27	180	6	10597
3	news.icm.edu.pl	13	0	1	0	12
TOTAL: 3		36742	119	1899	6	34718

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	47
it.comp.os.win.windows10	21
it.tlc.cellulari.android	20
comp.mobile.ipad	16
comp.mobile.android	5
comp.sys.raspberry-pi	5
alt.sex.telephones	2
de.rec.sf.startrek	2
it.comp.os.win.windows7	1
TOTAL: 9	119

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

Groups	Count
pl.sci.matematyka	5
bit.listserv.catholic	1
TOTAL: 2	6

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9734
Binary in non-binary group	315
Scoring filter (8)	259
Scoring filter (9)	144
Scoring filter (10)	66
Scoring filter (17)	15
Excessively crossposted test article	14
Scoring filter (12)	12
HTML post	11
Scoring filter (11)	9
EMP (phl)	7
Scoring filter (15)	3
TOTAL: 12	10589

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	281
	newsfeed.pionier.net.pl	268
	news.icm.edu.pl	2
	TOTAL: 3	551
TOTAL: 1		551

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	13321	2073	8927	411	105	19951	15%	20:03:33
2	poznan	18146	55	13761	9	0	1	0%	19:43:08
3	icm	31453	36	22413	4950	0	4	0%	19:43:08
4	nask	6241	5	3547	1227	0	0	0%	19:33:08
5	okapi	0	0	0	0	0	2455	0%	19:48:51
TOTAL: 5		69161	2169	48648	6597	105	22411	3%	98:51:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	12.7 MB	5.7 MB	18.4 MB	0.3 KB/s	7.6 KB	20:03:33
2	poznan	383.4 KB	61.0 KB	444.3 KB	0.0 KB/s	6.9 KB	19:43:08
3	icm	340.4 KB	936.0 MB	936.3 MB	13.5 KB/s	192.3 KB	19:43:08
4	nask	7.1 KB	460.7 MB	460.7 MB	6.7 KB/s	382.9 KB	19:33:08
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	19:48:51
TOTAL: 5		13.5 MB	1.4 GB	1.4 GB	4.1 KB/s	165.4 KB	98:51:48

Backlog files shrunk by innfeed:

Server	Size
panorama	1.4 MB
okapi	488.3 KB
TOTAL: 2	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	pc-tom.man.lodz.pl	21	133	28.6 MB	67	0	0	01:26:25
2	web.man.lodz.pl	239	100	96.6 KB	26	0	0	00:00:21
3	ban35.neoplus.adsl.tpnet.pl	21	43	51.9 KB	8	0	9	01:12:46
4	staticline-31-183-6-34.toya.net.pl	8	2	2.0 KB	2	0	2	00:33:00
5	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	0	00:00:00
TOTAL: 5		296	278	28.7 MB	103	7	11	03:12:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	584	233	28.7 MB	93	7	0	01:26:52
2	*.neoplus.adsl.tpnet.pl	21	43	51.9 KB	8	0	9	01:12:46
3	*.toya.net.pl	8	2	2.0 KB	2	0	2	00:33:00
4	*.dyn.optonline.net	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 4		614	278	28.7 MB	103	7	11	03:12:39

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-tom.man.lodz.pl	0.488	0.480	01:26:25
ban35.neoplus.adsl.tpnet.pl	0.132	0.184	01:12:46
staticline-31-183-6-34.toya.net.pl	0.028	0.100	00:33:00
web.man.lodz.pl	1.241	3.017	00:00:21
mon.man.lodz.pl	0.840	2.209	00:00:03
david.man.lodz.pl	0.340	0.764	00:00:01
ool-457809ae.dyn.optonline.net	0.036	0.004	00:00:00
isvcs01.man.lodz.pl	0.060	0.046	00:00:00
TOTAL: 8	3.165	6.804	03:12:39

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	235
david.man.lodz.pl	82
ool-457809ae.dyn.optonline.net	1
TOTAL: 3	318

NNRP client timeouts [Top 20]:

System	Conn	Peer
ban35.neoplus.adsl.tpnet.pl	2	1
staticline-31-183-6-34.toya.net.pl	1	1
pc-tom.man.lodz.pl	1	0
TOTAL: 3	4	2

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	107	41.5%
2	pl	103	39.9%
3	lodz	35	13.6%
4	lodman	13	5.0%
TOTAL: 4		258	100.0%

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

Newsgroup		Count
1	alt.binaries.pictures.erotica.amateur.female	63
2	pl.comp.os.ms-windows.winnt	52
3	pl.comp.pecet	51
4	alt.binaries.pictures.erotica.amateur	44
5	lodz.test	35
6	lodman.test	10
7	lodman.info	3
TOTAL: 7		258

Daily Usenet report

Apr 10 04:15:01 -- Apr 11 04:15:03