Daily Usenet report

Oct 17 04:15:03 -- Oct 18 04:15:02

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Oct 17 07:49:05 newsfeed nnrpd[17506]: SERVER perl filter not defined
Oct 17 10:04:26 newsfeed innfeed[1060]: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Oct 17 10:04:26 newsfeed innfeed[1060]: SM: storage method 'cnfs' failed initialization
Oct 17 10:04:26 newsfeed innfeed[1060]: SM: one or more storage methods failed initialization
Oct 17 10:04:26 newsfeed innfeed[1060]: Storage manager initialization failed -- it is OK after a change in storage methods
Oct 17 10:04:26 newsfeed innd: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Oct 17 10:04:26 newsfeed innd: SM: storage method 'cnfs' failed initialization
Oct 17 10:04:26 newsfeed innd: SM: one or more storage methods failed initialization
Oct 17 19:07:26 newsfeed overchan[16015]: input line too long, skipping
Oct 17 19:08:56 newsfeed overchan[16015]: input line too long, skipping
Oct 17 20:35:48 newsfeed nnrpd[20107]: SERVER perl filter not defined
Oct 17 21:11:50 newsfeed nnrpd[21486]: SERVER perl filter not defined
Oct 18 01:27:58 newsfeed nnrpd[30826]: SERVER perl filter not defined
Oct 18 03:07:30 newsfeed pgpverify[2150]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn1904749 97.8%213.6 MB 97.4%
innfeed 24588 1.3%2.9 MB 1.3%
innd 15334 0.8%2.4 MB 1.1%
nnrpd 3063 0.2%325.1 KB 0.1%
controlchan 6 0.0%0.5 KB 0.0%
overchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 71947745 100.0%219.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1851382 96.3%
Negative hits 54283 2.8%
Cache misses 15320 0.8%
Positive hits 2299 0.1%
TOTAL: 4 1923284 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.544 0.0%104 0.000 24.462 447.000
article cleanup00:00:27.008 0.0%1877046 0.000 0.014 0.020
article logging00:01:18.723 0.1%1877628 0.034 0.042 0.082
article parse00:38:21.222 4.2%115618004 0.015 0.020 0.030
article write00:13:45.173 1.5%1871431 0.211 0.441 0.715
artlog/artcncl00:00:00.000 0.0%102 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:02:58.025 0.3%126592029 0.001 0.001 0.002
hisgrep/artcncl00:00:00.592 0.0%74 0.000 8.000 280.000
hishave/artcncl00:00:00.141 0.0%104 0.000 1.356 5.579
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup01:09:54.848 7.7%1950339 0.126 2.151 5.897
history sync00:00:00.004 0.0%184 0.000 0.022 1.000
history write02:13:43.589 14.7%1875642 0.532 4.278 8.498
hiswrite/artcncl00:00:00.041 0.0%30 0.000 1.367 4.000
idle06:22:38.625 42.0%82696662 0.172 0.278 46101.615
nntp read00:40:39.759 4.5%114070963 0.015 0.021 0.030
overview write00:00:10.290 0.0%1871431 0.004 0.005 0.007
perl filter03:24:28.168 22.5%1875642 4.527 6.541 8.027
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:07.830 0.3%3759754 0.037 0.050 0.062
TOTAL: 15:10:03.11714:51:36.582 98.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.169 0.0%77806 0.000 0.002 0.012
article prepare00:00:00.000 0.0%4958 0.000 0.000 0.000
article read00:00:33.000 0.1%1235 0.000 26.721 458.700
backlog stats00:00:00.100 0.0%134507 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.570 0.0%74725 0.005 0.021 0.056
data write00:00:02.376 0.0%58314 0.011 0.041 0.089
idle15:02:19.965 99.9%134507 35.141 402.507 1434.014
status file00:00:07.331 0.0%8693 0.647 0.843 3.390
TOTAL: 15:03:14.18815:03:04.511100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:13.487 58.7%1991 0.000 6.774 29.222
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.121 0.5%4463 0.000 0.027 0.737
readart00:00:00.001 0.0%68 0.000 0.015 0.500
TOTAL: 00:00:22.96900:00:13.609 59.2%----

Control commands to INND:

CommandNumber
L 1
flush 19
flushlogs 2
go 2
logmode 2
mode 100
name 4
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 138

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
newsmaster@carnet.hr000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 332 19012401888734 10467 2039 99%319:03:43
2news.nask.pl 2 16603 7275 5803 3525 43%06:30:15
3news.icm.edu.pl 25 244 131 67 46 53%10:54:40
4localhost 5 4 4 0 0 100%00:00:00
TOTAL: 4 364 19180911896144 16337 5610 98%336:28:38
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl540.1 GB27.7 MB126.9 MB540.2 GB 99%299.6 KB
2news.nask.pl445.6 MB633.4 KB23.0 MB469.2 MB 94%44.5 KB
3news.icm.edu.pl2.1 MB52.3 KB891.5 KB3.0 MB 69%17.6 KB
4localhost5.8 KB0.0 KB0.0 KB5.8 KB100%1.5 KB
TOTAL: 4540.5 GB28.4 MB150.8 MB540.7 GB 99%298.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 17 04:15:03 - 04:59:59 86725 4.6% 32.1632.0 GB 0.0%12422.37
Oct 17 05:00:00 - 05:59:59 108426 5.7% 30.1240.5 GB 0.0%11808.41
Oct 17 06:00:00 - 06:59:59 102863 5.4% 28.5738.3 GB 0.0%11160.80
Oct 17 07:00:00 - 07:59:59 109616 5.8% 30.4540.4 GB 0.0%11768.52
Oct 17 08:00:00 - 08:59:59 108932 5.7% 30.2641.0 GB 0.0%11949.83
Oct 17 09:00:00 - 09:59:59 114360 6.0% 31.7733.6 GB 0.0%9795.39
Oct 17 10:00:00 - 10:59:59 13134 0.7% 3.652.0 GB 0.0% 568.85
Oct 17 11:00:00 - 11:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Oct 17 12:00:00 - 12:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Oct 17 13:00:00 - 13:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Oct 17 14:00:00 - 14:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Oct 17 15:00:00 - 15:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Oct 17 16:00:00 - 16:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Oct 17 17:00:00 - 17:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Oct 17 18:00:00 - 18:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Oct 17 19:00:00 - 19:59:59 90177 4.8% 25.0528.6 GB 36.3%8322.75
Oct 17 20:00:00 - 20:59:59 113493 6.0% 31.5336.1 GB 45.8%10505.46
Oct 17 21:00:00 - 21:59:59 125991 6.6% 35.0039.9 GB 50.6%11609.71
Oct 17 22:00:00 - 22:59:59 143537 7.6% 39.8734.9 GB 44.3%10155.60
Oct 17 23:00:00 - 23:59:59 173060 9.1% 48.0732.3 GB 41.0%9394.82
Oct 18 00:00:00 - 00:59:59 148377 7.8% 41.2228.0 GB 35.6%8158.71
Oct 18 01:00:00 - 01:59:59 176266 9.3% 48.9633.5 GB 42.5%9748.51
Oct 18 02:00:00 - 02:59:59 143001 7.5% 39.7238.0 GB 48.3%11072.63
Oct 18 03:00:00 - 03:59:59 112879 5.9% 31.3633.7 GB 42.9%9826.21
Oct 18 04:00:00 - 04:15:02 27542 1.5% 30.538.2 GB 10.5%9566.63
TOTAL: 23:59:59 1898379 100.0% 21.9778.7 GB 100.0% 955.40
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 3872 11 0 104 1 0 0 0 3756
2newsfeed.pionier.net.pl 2346 114 0 1363 0 0 0 0 869
3news.icm.edu.pl 50 0 0 5 0 0 0 0 45
TOTAL: 3 6268 125 0 1472 1 0 0 0 4670

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 63
it.comp.os.win.windows10 41
de.rec.sf.startrek 5
comp.sys.raspberry-pi 5
it.tlc.cellulari.android 4
comp.mobile.android 4
it.comp.os.win.windows7 2
alt.sex.telephones 1
TOTAL: 8 125

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

GroupsCount
bit.listserv.catholic 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3294
Binary in non-binary group 651
Scoring filter (9) 62
Scoring filter (8) 28
Scoring filter (10) 28
HTML post 9
Poison newsgroup 8
Too many newsgroups 2
Scoring filter (13) 1
TOTAL: 9 4083

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 306
news.nask.pl 277
news.icm.edu.pl 4
TOTAL: 3 587
TOTAL: 1 587

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 62454 644 47194 149 4 26666 1%15:15:43
2poznan 7265 60 6110 11 1 3156 0%11:58:32
3icm 17939 6 16252 62 0 359 0%14:57:34
4nask 6022 4 5357 0 0 0 0%14:57:36
5okapi 0 0 0 0 0 1204 0%15:05:22
TOTAL: 5 93680 714 74913 222 5 31385 0%72:14:47
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama2.0 MB450.4 KB2.5 MB0.0 KB/s3.2 KB15:15:43
2poznan439.8 KB33.5 KB473.4 KB0.0 KB/s6.7 KB11:58:32
3icm162.8 KB18.7 MB18.9 MB0.4 KB/s284.0 KB14:57:34
4nask7.6 KB0.0 KB7.6 KB0.0 KB/s1.9 KB14:57:36
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s015:05:22
TOTAL: 52.6 MB19.2 MB21.8 MB0.1 KB/s23.8 KB72:14:47
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 183 68113.1 KB 20 0 000:00:17
2isvcs01.man.lodz.pl 4 00.0 KB 0 4 000:00:01
TOTAL: 2 187 68113.1 KB 20 4 000:00:22

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 432 68113.1 KB 20 4 000:00:22
TOTAL: 1 432 68113.1 KB 20 4 000:00:22

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 0.724 3.260 0.00000:00:17
mon.man.lodz.pl 0.692 3.344 0.00000:00:03
isvcs01.man.lodz.pl 0.028 0.060 0.00000:00:01
david.man.lodz.pl 0.228 1.136 0.00000:00:00
TOTAL: 4 1.672 7.800 0.00000:00:22

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 184
david.man.lodz.pl 61
TOTAL: 2 245

Newsgroup request counts (by category):

CategoryCountPct
1pl 68100.0%
TOTAL: 1 68100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 46
2pl.comp.pecet 22
TOTAL: 2 68