Daily Usenet report

Aug 8 04:15:02 -- Aug 9 04:15:03

Unknown entries from news log file:

First 39 / 39 lines (100.0%)

Aug 8 10:52:11 newsfeed innfeed[1024]: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Aug 8 10:52:11 newsfeed innfeed[1024]: SM: storage method 'cnfs' failed initialization
Aug 8 10:52:11 newsfeed innfeed[1024]: SM: one or more storage methods failed initialization
Aug 8 10:52:11 newsfeed innfeed[1024]: Storage manager initialization failed -- it is OK after a change in storage methods
Aug 8 10:52:11 newsfeed innd: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Aug 8 10:52:11 newsfeed innd: SM: storage method 'cnfs' failed initialization
Aug 8 10:52:11 newsfeed innd: SM: one or more storage methods failed initialization
Aug 8 11:00:33 newsfeed innfeed[1006]: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Aug 8 11:00:33 newsfeed innfeed[1006]: SM: storage method 'cnfs' failed initialization
Aug 8 11:00:33 newsfeed innfeed[1006]: SM: one or more storage methods failed initialization
Aug 8 11:00:33 newsfeed innfeed[1006]: Storage manager initialization failed -- it is OK after a change in storage methods
Aug 8 11:00:33 newsfeed innd: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Aug 8 11:00:33 newsfeed innd: SM: storage method 'cnfs' failed initialization
Aug 8 11:00:33 newsfeed innd: SM: one or more storage methods failed initialization
Aug 8 11:05:58 newsfeed innfeed[1003]: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Aug 8 11:05:58 newsfeed innfeed[1003]: SM: storage method 'cnfs' failed initialization
Aug 8 11:05:58 newsfeed innfeed[1003]: SM: one or more storage methods failed initialization
Aug 8 11:05:58 newsfeed innfeed[1003]: Storage manager initialization failed -- it is OK after a change in storage methods
Aug 8 11:05:58 newsfeed innd: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Aug 8 11:05:58 newsfeed innd: SM: storage method 'cnfs' failed initialization
Aug 8 11:05:58 newsfeed innd: SM: one or more storage methods failed initialization
Aug 8 11:19:32 newsfeed innfeed[1013]: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Aug 8 11:19:32 newsfeed innfeed[1013]: SM: storage method 'cnfs' failed initialization
Aug 8 11:19:32 newsfeed innfeed[1013]: SM: one or more storage methods failed initialization
Aug 8 11:19:32 newsfeed innfeed[1013]: Storage manager initialization failed -- it is OK after a change in storage methods
Aug 8 11:19:32 newsfeed innd: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Aug 8 11:19:32 newsfeed innd: SM: storage method 'cnfs' failed initialization
Aug 8 11:19:32 newsfeed innd: SM: one or more storage methods failed initialization
Aug 8 11:29:49 newsfeed innfeed[1016]: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Aug 8 11:29:49 newsfeed innfeed[1016]: SM: storage method 'cnfs' failed initialization
Aug 8 11:29:49 newsfeed innfeed[1016]: SM: one or more storage methods failed initialization
Aug 8 11:29:49 newsfeed innfeed[1016]: Storage manager initialization failed -- it is OK after a change in storage methods
Aug 8 11:29:49 newsfeed innd: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Aug 8 11:29:49 newsfeed innd: SM: storage method 'cnfs' failed initialization
Aug 8 11:29:49 newsfeed innd: SM: one or more storage methods failed initialization
Aug 8 16:00:33 newsfeed nnrpd[14006]: SERVER perl filter not defined
Aug 8 16:26:51 newsfeed nnrpd[14997]: SERVER perl filter not defined
Aug 8 18:18:57 newsfeed overchan[3301]: input line too long, skipping
Aug 8 18:50:57 newsfeed overchan[3301]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2197111 97.2%250.3 MB 96.9%
innfeed 39039 1.7%4.6 MB 1.8%
innd 18294 0.8%2.8 MB 1.1%
nnrpd 4933 0.2%528.9 KB 0.2%
overchan 8 0.0%1.1 KB 0.0%
controlchan 7 0.0%0.5 KB 0.0%
TOTAL: 62259392 100.0%258.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2182537 89.2%
Positive hits 180399 7.4%
Negative hits 81963 3.4%
Cache misses 795 0.0%
TOTAL: 4 2445694 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.820 0.0%176 0.000 10.341 75.000
article cleanup00:00:24.950 0.0%2188965 0.007 0.011 0.017
article logging00:01:28.003 0.1%2189557 0.028 0.040 0.134
article parse00:37:16.900 2.7%92125995 0.014 0.024 0.042
article write00:15:25.109 1.1%2182446 0.177 0.424 0.799
artlog/artcncl00:00:00.003 0.0%176 0.000 0.017 0.500
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:03:18.281 0.2%104944038 0.001 0.002 0.004
hisgrep/artcncl00:00:00.966 0.0%170 0.000 5.682 59.091
hishave/artcncl00:00:00.061 0.0%176 0.000 0.347 1.654
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:44:39.614 3.2%2459661 0.181 1.089 5.689
history sync00:00:00.016 0.0%281 0.000 0.057 1.000
history write01:15:28.266 5.4%2184360 0.374 2.073 7.192
hiswrite/artcncl00:00:00.007 0.0%6 0.000 1.167 7.000
idle15:14:40.408 65.8%76698964 0.485 0.716 46129.308
nntp read00:58:21.060 4.2%90691936 0.023 0.039 0.067
overview write00:00:10.105 0.0%2182446 0.002 0.005 0.007
perl filter03:34:54.588 15.5%2184358 4.042 5.903 8.672
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:09.925 0.3%4627132 0.035 0.054 0.081
TOTAL: 23:10:02.47322:50:20.082 98.6%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.208 0.0%487109 0.000 0.002 0.007
article prepare00:00:00.086 0.0%330556 0.000 0.000 0.002
article read00:01:34.842 0.1%79807 0.266 1.188 34.467
backlog stats00:00:01.624 0.0%2036262 0.000 0.001 0.002
callbacks00:00:00.000 0.0%3 0.000 0.000 0.000
data read00:00:14.969 0.0%997354 0.005 0.015 0.031
data write00:03:08.784 0.2%1063703 0.027 0.177 0.336
idle23:04:21.891 99.6%2036262 26.816 40.791 500.655
status file00:00:12.574 0.0%16408 0.363 0.766 1.290
TOTAL: 23:10:27.98523:09:35.978 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:14.316 52.0%3006 0.000 4.762 192.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.236 0.9%6671 0.000 0.035 0.667
readart00:00:00.001 0.0%14 0.000 0.071 0.500
TOTAL: 00:00:27.54300:00:14.553 52.8%----

Control commands to INND:

CommandNumber
L 1
flush 26
flushlogs 2
go 2
logmode 2
mode 147
name 3
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 191

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1256 21884372182404 3968 2065 99%368:23:47
2news.nask.pl 7 184793 6084 174319 4390 3%22:45:55
3news.icm.edu.pl 22 625 348 237 40 55%19:57:23
4localhost 3 2 2 0 0 100%00:00:00
TOTAL: 4 1288 23738572188838 178524 6495 92%411:07:05
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl623.3 GB70.1 MB118.6 MB623.5 GB 99%299.3 KB
2news.nask.pl248.7 MB731.9 MB743.3 MB1.7 GB 14%168.5 KB
3news.icm.edu.pl6.4 MB0.0 KB731.6 KB7.1 MB 90%18.9 KB
4localhost2.7 KB0.0 KB0.0 KB2.7 KB100%1.4 KB
TOTAL: 4623.5 GB802.0 MB862.6 MB625.2 GB 99%298.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 8 04:15:02 - 04:59:59 63277 2.9% 23.4518.8 GB 3.0%7322.09
Aug 8 05:00:00 - 05:59:59 92703 4.2% 25.7529.1 GB 4.7%8487.03
Aug 8 06:00:00 - 06:59:59 99756 4.6% 27.7129.5 GB 4.7%8594.76
Aug 8 07:00:00 - 07:59:59 71818 3.3% 19.9520.4 GB 3.3%5947.22
Aug 8 08:00:00 - 08:59:59 90001 4.1% 25.0028.5 GB 4.6%8308.14
Aug 8 09:00:00 - 09:59:59 66572 3.0% 18.4923.6 GB 3.8%6882.81
Aug 8 10:00:00 - 10:59:59 57353 2.6% 15.9317.7 GB 2.8%5152.41
Aug 8 11:00:00 - 11:59:59 6426 0.3% 1.782.2 GB 0.4% 647.05
Aug 8 12:00:00 - 12:59:59 87629 4.0% 24.3429.5 GB 4.7%8589.84
Aug 8 13:00:00 - 13:59:59 66520 3.0% 18.4821.3 GB 3.4%6200.85
Aug 8 14:00:00 - 14:59:59 92069 4.2% 25.5728.7 GB 4.6%8371.06
Aug 8 15:00:00 - 15:59:59 104011 4.7% 28.8932.4 GB 5.2%9431.54
Aug 8 16:00:00 - 16:59:59 76142 3.5% 21.1523.1 GB 3.7%6723.01
Aug 8 17:00:00 - 17:59:59 90335 4.1% 25.0926.7 GB 4.3%7788.66
Aug 8 18:00:00 - 18:59:59 131771 6.0% 36.6037.2 GB 6.0%10827.71
Aug 8 19:00:00 - 19:59:59 99180 4.5% 27.5528.0 GB 4.5%8158.79
Aug 8 20:00:00 - 20:59:59 86768 4.0% 24.1023.9 GB 3.8%6959.55
Aug 8 21:00:00 - 21:59:59 144963 6.6% 40.2736.8 GB 5.9%10724.94
Aug 8 22:00:00 - 22:59:59 142518 6.5% 39.5933.2 GB 5.3%9669.48
Aug 8 23:00:00 - 23:59:59 111663 5.1% 31.0223.5 GB 3.8%6844.70
Aug 9 00:00:00 - 00:59:59 71298 3.3% 19.8017.4 GB 2.8%5058.56
Aug 9 01:00:00 - 01:59:59 110354 5.0% 30.6529.8 GB 4.8%8669.62
Aug 9 02:00:00 - 02:59:59 117132 5.3% 32.5430.0 GB 4.8%8726.52
Aug 9 03:00:00 - 03:59:59 84277 3.8% 23.4125.2 GB 4.0%7333.36
Aug 9 04:00:00 - 04:15:03 25255 1.2% 27.977.4 GB 1.2%8619.96
TOTAL: 24:00:01 2189791 100.0% 25.34624.0 GB 100.0%7572.72
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 4553 8 0 2941 2 0 0 0 1602
2newsfeed.pionier.net.pl 2535 222 0 1691 1 0 0 0 621
3news.icm.edu.pl 56 0 0 1 0 0 0 0 55
TOTAL: 3 7144 230 0 4633 3 0 0 0 2278

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 154
it.tlc.cellulari.android 28
comp.mobile.android 14
comp.sys.raspberry-pi 11
it.comp.os.win.windows7 6
it.comp.os.linux.ubuntu 4
it.comp.os.win.windows10 3
uk.rec.cycling.moderated 3
hr.ponuda.hardver.komponente 3
de.comp.lang.vbclassic 2
de.comm.software.mailreader 1
comp.sys.mac.vintage 1
TOTAL: 12 230

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

GroupsCount
bit.listserv.catholic 1
pl.praca.oferowana 1
christnet.prayer 1
TOTAL: 3 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1349
Binary in non-binary group 213
Scoring filter (8) 35
Scoring filter (9) 32
EMP (phl) 29
Scoring filter (18) 6
Scoring filter (16) 6
Scoring filter (11) 3
HTML post 3
Too many newsgroups 2
Scoring filter (21) 1
Scoring filter (15) 1
Scoring filter (10) 1
Excessively crossposted test article 1
TOTAL: 14 1682

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 2
TOTAL: 1 2
Bad command received
news.nask.pl 38
TOTAL: 1 38
Including strange strings
newsfeed.pionier.net.pl 468
news.nask.pl 125
news.icm.edu.pl 1
TOTAL: 3 594
TOTAL: 3 634

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 373666 70212 177441 884 0 454 18%22:59:49
2panorama 249214 1729 219974 376 16 267203 0%23:18:27
3poznan 8140 57 5942 15 0 0 0%22:59:49
4icm 329821 26 251960 9205 0 1 0%22:59:49
5okapi 0 0 0 0 0 991 0%23:09:42
TOTAL: 5 960841 72024 655317 10480 16 268649 7%115:27:36
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask38.8 GB554.0 MB39.4 GB498.4 KB/s580.4 KB22:59:49
2panorama311.5 MB29.8 MB341.2 MB4.2 KB/s166.0 KB23:18:27
3icm4.5 MB4.6 GB4.6 GB58.6 KB/s525.8 KB22:59:49
4poznan259.8 KB78.9 KB338.7 KB0.0 KB/s4.7 KB22:59:49
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:09:42
TOTAL: 539.1 GB5.2 GB44.3 GB111.8 KB/s563.2 KB115:27:36
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama4.3 MB
TOTAL: 14.3 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 283 1416.5 KB 7 0 000:00:19
2isvcs01.man.lodz.pl 2 00.0 KB 0 2 000:00:01
TOTAL: 2 285 1416.5 KB 7 2 000:00:26

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 736 1416.5 KB 7 2 000:00:25
2*.dhcp.chtrptr.net 1 00.0 KB 0 0 000:00:01
TOTAL: 2 737 1416.5 KB 7 2 000:00:26

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.416 3.532 0.00000:00:19
mon.man.lodz.pl 1.444 3.540 0.00000:00:04
isvcs01.man.lodz.pl 0.008 0.032 0.00000:00:01
david.man.lodz.pl 0.340 0.772 0.00000:00:01
172-221-041-102.dhcp.chtrptr.net 0.004 0.000 0.00000:00:01
TOTAL: 5 3.212 7.876 0.00000:00:26

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 358
david.man.lodz.pl 93
172-221-041-102.dhcp.chtrptr.net 1
TOTAL: 3 452

Newsgroup request counts (by category):

CategoryCountPct
1pl 14100.0%
TOTAL: 1 14100.0%

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

NewsgroupCount
1pl.comp.pecet 8
2pl.comp.os.ms-windows.winnt 6
TOTAL: 2 14