Daily Usenet report

May 16 04:15:01 -- May 17 04:15:02

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

May 16 13:21:24 newsfeed innfeed[1599]: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
May 16 13:21:24 newsfeed innfeed[1599]: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
May 16 13:21:24 newsfeed innfeed[1599]: SM: storage method 'cnfs' failed initialization
May 16 13:21:24 newsfeed innfeed[1599]: SM: storage method 'cnfs' failed initialization
May 16 13:21:24 newsfeed innfeed[1599]: SM: one or more storage methods failed initialization
May 16 13:21:24 newsfeed innfeed[1599]: SM: one or more storage methods failed initialization
May 16 13:21:24 newsfeed innfeed[1599]: Storage manager initialization failed -- it is OK after a change in storage methods
May 16 13:21:24 newsfeed innfeed[1599]: Storage manager initialization failed -- it is OK after a change in storage methods
May 16 13:21:24 newsfeed innd: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
May 16 13:21:24 newsfeed innd: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
May 16 13:21:24 newsfeed innd: SM: storage method 'cnfs' failed initialization
May 16 13:21:24 newsfeed innd: SM: one or more storage methods failed initialization
May 16 13:21:24 newsfeed innd: SM: storage method 'cnfs' failed initialization
May 16 13:21:24 newsfeed innd: SM: one or more storage methods failed initialization

Log entries by program:

Program nameLines%LinesSize%Size
inn 229720 84.4%25.4 MB 86.4%
innd 24526 9.0%2.3 MB 7.9%
nnrpd 16476 6.1%1.5 MB 5.2%
innfeed 1296 0.5%158.9 KB 0.5%
overchan 4 0.0%0.5 KB 0.0%
controlchan 4 0.0%0.2 KB 0.0%
TOTAL: 6 272026 100.0%29.4 MB100.0%

History cache:

ReasonCount%Count
Do not exist 454748 99.7%
Negative hits 1036 0.2%
Positive hits 104 0.0%
Cache misses 6 0.0%
TOTAL: 4 455894 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.042 0.0%88 0.000 0.477 5.000
article cleanup00:00:02.506 0.0%455484 0.002 0.006 0.012
article logging00:00:09.708 0.0%455510 0.011 0.021 0.034
article parse00:00:23.262 0.0%1332110 0.009 0.017 0.027
article write00:01:57.152 0.1%455476 0.171 0.257 0.543
artlog/artcncl00:00:00.000 0.0%26 0.000 0.000 0.000
data move00:00:01.934 0.0%1974158 0.000 0.001 0.002
hisgrep/artcncl00:00:00.016 0.0%114 0.000 0.140 0.400
hishave/artcncl00:00:00.000 0.0%26 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:01:00.668 0.0%456600 0.030 0.133 0.523
history sync00:00:00.036 0.0%724 0.000 0.050 0.500
history write00:01:13.158 0.0%455480 0.071 0.161 0.423
idle47:27:09.870 99.5%1566458 57.221 109.055 221.572
nntp read00:00:23.420 0.0%1241166 0.010 0.019 0.030
overview write00:00:01.382 0.0%455476 0.001 0.003 0.008
perl filter00:07:59.648 0.3%455480 0.561 1.053 1.400
python filter00:00:00.106 0.0%455480 0.000 0.000 0.002
site send00:00:11.524 0.0%922892 0.005 0.012 0.019
TOTAL: 47:41:37.03047:40:34.432100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.028 0.0%11950 0.000 0.002 0.050
article prepare00:00:00.000 0.0%920 0.000 0.000 0.000
article read00:00:00.012 0.0%230 0.000 0.052 1.000
backlog stats00:00:00.014 0.0%75002 0.000 0.000 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.366 0.0%34476 0.000 0.011 0.047
data write00:00:01.350 0.0%22422 0.000 0.060 0.147
idle47:42:57.436100.0%75002 911.276 2290.305 6018.020
status file00:00:00.112 0.0%346 0.000 0.324 1.000
TOTAL: 47:43:00.21647:42:59.318100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.032 0.0%228 0.036 0.140 0.250
idle01:32:07.132 98.7%27164 0.000 203.473 27553.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.458 0.0%102356 0.000 0.024 0.500
readart00:00:03.362 0.1%17908 0.000 0.188 0.188
TOTAL: 01:33:20.08001:32:12.984 98.8%----

Control commands to INND:

CommandNumber
flush 54
flushlogs 4
go 4
logmode 4
lowmark 2
mode 300
name 6
pause 4
paused 4
reload 4
reserve 4
TOTAL: 11 390

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 118 459320 459314 2 4 99%73:07:58
2news.icm.edu.pl 66 90 8 78 4 8%12:33:50
3localhost 2 0 0 0 0 0%00:00:00
TOTAL: 3 186 459410 459322 80 8 99%85:41:48
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl4.3 GB41.8 KB0.0 KB4.3 GB 99%9.9 KB
2news.icm.edu.pl497.2 KB0.0 KB224.7 KB721.9 KB 68%60.2 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 34.3 GB41.8 KB224.7 KB4.3 GB 99%9.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 16 04:15:01 - 04:59:59 6056 2.6% 2.2448.4 MB 2.2% 18.36
May 16 05:00:00 - 05:59:59 8219 3.6% 2.2869.2 MB 3.1% 19.68
May 16 06:00:00 - 06:59:59 6898 3.0% 1.9269.0 MB 3.1% 19.62
May 16 07:00:00 - 07:59:59 12164 5.3% 3.38126.5 MB 5.7% 35.99
May 16 08:00:00 - 08:59:59 10691 4.7% 2.97109.7 MB 4.9% 31.22
May 16 09:00:00 - 09:59:59 11316 4.9% 3.14109.7 MB 4.9% 31.21
May 16 10:00:00 - 10:59:59 10911 4.8% 3.03110.6 MB 5.0% 31.46
May 16 11:00:00 - 11:59:59 10584 4.6% 2.94109.3 MB 4.9% 31.10
May 16 12:00:00 - 12:59:59 10421 4.5% 2.8996.9 MB 4.4% 27.57
May 16 13:00:00 - 13:59:59 11330 4.9% 3.1597.0 MB 4.4% 27.60
May 16 14:00:00 - 14:59:59 9357 4.1% 2.6096.6 MB 4.3% 27.47
May 16 15:00:00 - 15:59:59 7332 3.2% 2.0474.0 MB 3.3% 21.06
May 16 16:00:00 - 16:59:59 8367 3.6% 2.3285.8 MB 3.9% 24.40
May 16 17:00:00 - 17:59:59 9926 4.3% 2.7690.3 MB 4.1% 25.69
May 16 18:00:00 - 18:59:59 10395 4.5% 2.89107.4 MB 4.8% 30.55
May 16 19:00:00 - 19:59:59 9472 4.1% 2.63110.4 MB 5.0% 31.40
May 16 20:00:00 - 20:59:59 8261 3.6% 2.2987.1 MB 3.9% 24.79
May 16 21:00:00 - 21:59:59 9276 4.0% 2.5897.4 MB 4.4% 27.71
May 16 22:00:00 - 22:59:59 8744 3.8% 2.4389.3 MB 4.0% 25.41
May 16 23:00:00 - 23:59:59 8968 3.9% 2.4982.9 MB 3.7% 23.57
May 17 00:00:00 - 00:59:59 8072 3.5% 2.2478.0 MB 3.5% 22.20
May 17 01:00:00 - 01:59:59 10102 4.4% 2.81104.6 MB 4.7% 29.76
May 17 02:00:00 - 02:59:59 8885 3.9% 2.4774.1 MB 3.3% 21.09
May 17 03:00:00 - 03:59:59 11402 5.0% 3.1779.6 MB 3.6% 22.64
May 17 04:00:00 - 04:15:02 2541 1.1% 2.8222.3 MB 1.0% 25.36
TOTAL: 24:00:01 229690 100.0% 2.662.2 GB 100.0% 26.39
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 15 0 0 2 0 0 0 0 13
2news.icm.edu.pl 2 0 0 0 0 0 0 0 2
TOTAL: 2 17 0 0 2 0 0 0 0 15

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 4
TOTAL: 1 4

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 13
TOTAL: 1 13
TOTAL: 1 13

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 22414 210 11708 18 0 0 0%47:42:40
2poznan 8 0 8 0 0 0 0%01:43:42
TOTAL: 2 22422 210 11716 18 0 0 0%49:26:22
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm655.8 KB130.7 KB786.6 KB0.0 KB/s3.4 KB47:42:40
2poznan0.0 KB0.0 KB0.0 KB0.0 KB/s001:43:42
TOTAL: 2655.8 KB130.7 KB786.6 KB0.0 KB/s3.4 KB49:26:22
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1syn-066-061-096-239.res.spectrum.com 4 1790419.5 MB 2 0 001:26:15
2172.16.2.4 574 49.5 KB 2 0 000:00:34
TOTAL: 2 578 1790819.5 MB 4 0 001:33:20

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.res.spectrum.com 4 1790419.5 MB 2 0 001:26:15
2unresolved 798 49.5 KB 2 0 000:02:48
3*.man.lodz.pl2876 00.0 KB 0 0 000:00:31
4*.184.239.229.ipv4.supernova.orange.pl 2 00.0 KB 0 0 000:03:40
5*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:03
6*.stretchoid.com 2 00.0 KB 0 0 000:00:00
TOTAL: 63684 1790819.5 MB 4 0 001:33:20

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
syn-066-061-096-239.res.spectrum.com 1.022 2.434 0.00001:26:15
79.184.239.229.ipv4.supernova.orange.pl 0.046 0.018 0.00000:03:40
23.28.134.110 0.052 0.112 0.00000:00:51
172.16.2.4 2.440 4.452 0.00000:00:34
nms1.man.lodz.pl 11.214 19.936 0.00000:00:31
206.168.34.67 0.104 0.010 0.00000:00:29
135.237.125.155 0.006 0.014 0.00000:00:19
162.142.125.118 0.130 0.046 0.00000:00:16
80.111.142.213 0.046 0.062 0.00000:00:07
bubbly.monitoring.internet-measurement.com 0.008 0.018 0.00000:00:03
147.185.132.19 0.008 0.014 0.00000:00:03
205.210.31.155 0.004 0.008 0.00000:00:03
212.51.192.185 0.794 1.308 0.00000:00:02
azpdwgzchlzq.stretchoid.com 0.010 0.022 0.00000:00:00
TOTAL: 14 15.884 28.454 0.00001:33:20

Curious NNRP server explorers [Top 20]:

SystemConn
nms1.man.lodz.pl 2876
212.51.192.185 192
23.28.134.110 10
80.111.142.213 8
162.142.125.118 6
135.237.125.155 2
bubbly.monitoring.internet-measurement.com 2
azpdwgzchlzq.stretchoid.com 2
147.185.132.19 2
205.210.31.155 2
79.184.239.229.ipv4.supernova.orange.pl 2
206.168.34.67 2
TOTAL: 12 3106

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
azpdwgzchlzq.stretchoid.com 2
206.168.34.67 2
162.142.125.118 2
TOTAL: 3 6

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
EHLO www.censys.io 4
MGLNDD_212.191.2.21_119 2
TOTAL: 2 6

NNRP client timeouts [Top 20]:

SystemConnPeer
162.142.125.118 2 4
TOTAL: 1 2 8

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1lodman 17904100.0%
2pl 4 0.0%
TOTAL: 2 17908100.0%

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

NewsgroupCount
1lodman.info 17904
2pl.comp.os.ms-windows.winnt 4
TOTAL: 2 17908