Daily Usenet report

Mar 27 15:00:05 -- Mar 28 15:00:01

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Mar 27 15:00:51 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Mar 28 10:11:01 colo-sc-1 nnrpd[21773]: CNFS: no magic cookie found for cycbuff TWO, initializing
Mar 28 10:11:01 colo-sc-1 nnrpd[21773]: CNFS: CNFSflushhead: attempted flush whilst read only
Mar 28 10:11:01 colo-sc-1 nnrpd[21773]: CNFS: CNFSshutdowncycbuff: flushing TWO
Mar 28 10:11:01 colo-sc-1 nnrpd[21773]: CNFS: CNFSflushhead: attempted flush whilst read only
Mar 28 10:11:02 colo-sc-1 nnrpd[21773]: SM: storage method 'cnfs' failed initialization
Mar 28 10:11:02 colo-sc-1 nnrpd[21773]: SM: one or more storage methods failed initialization
Mar 28 10:11:02 colo-sc-1 nnrpd[21773]: can't initialize storage method, one or more storage methods failed initialization
Mar 28 10:11:10 colo-sc-1 innfeed[21924]: CNFS: no magic cookie found for cycbuff TWO, initializing
Mar 28 10:11:10 colo-sc-1 innfeed[21924]: CNFS: CNFSflushhead: attempted flush whilst read only
Mar 28 10:11:10 colo-sc-1 innfeed[21924]: CNFS: CNFSshutdowncycbuff: flushing TWO
Mar 28 10:11:10 colo-sc-1 innfeed[21924]: CNFS: CNFSflushhead: attempted flush whilst read only
Mar 28 10:11:10 colo-sc-1 innfeed[21924]: SM: storage method 'cnfs' failed initialization
Mar 28 10:11:10 colo-sc-1 innfeed[21924]: SM: one or more storage methods failed initialization
Mar 28 10:11:10 colo-sc-1 innd: CNFS: no magic cookie found for cycbuff TWO, initializing
Mar 28 10:11:10 colo-sc-1 innd: CNFS: no magic cookie found for cycbuff THREE, initializing
Mar 28 10:11:10 colo-sc-1 innd: CNFS: no magic cookie found for cycbuff FOUR, initializing
Mar 28 10:11:10 colo-sc-1 innfeed[21924]: Storage manager initialization failed -- it is OK after a change in storage methods

Log entries by program:

Program nameLines%LinesSize%Size
inn 16452 61.5%1.8 MB 62.4%
nnrpd 7203 26.9%713.1 KB 24.3%
innd 2105 7.9%275.6 KB 9.4%
innfeed 764 2.9%88.5 KB 3.0%
cnfsstat 231 0.9%27.5 KB 0.9%
overchan 2 0.0%0.1 KB 0.0%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 7 26759 100.0%2.9 MB100.0%

History cache:

ReasonCount%Count
Negative hits 27386 49.2%
Do not exist 15769 28.3%
Positive hits 12547 22.5%
Cache misses 6 0.0%
TOTAL: 4 55708 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.015 0.0%51 0.000 0.294 7.000
article cleanup00:00:00.100 0.0%15904 0.000 0.006 0.034
article logging00:00:00.531 0.0%16252 0.000 0.033 0.111
article parse00:00:00.259 0.0%38326 0.000 0.007 0.039
article write00:00:08.981 0.0%14984 0.000 0.599 31.343
artlog/artcncl00:00:00.001 0.0%51 0.000 0.020 0.333
data move00:00:00.046 0.0%77849 0.000 0.001 0.006
hisgrep/artcncl00:00:00.000 0.0%33 0.000 0.000 0.000
hishave/artcncl00:00:00.003 0.0%51 0.000 0.059 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.738 0.0%56193 0.000 0.084 2.167
history sync00:00:00.022 0.0%345 0.000 0.064 1.000
history write00:00:06.026 0.0%15903 0.000 0.379 32.822
hiswrite/artcncl00:00:00.000 0.0%18 0.000 0.000 0.000
idle23:49:42.235 99.9%77300 423.944 1109.731 5130.538
nntp read00:00:00.649 0.0%77092 0.000 0.008 0.024
overview write00:00:00.051 0.0%14984 0.000 0.003 0.037
perl filter00:00:17.707 0.0%15903 0.821 1.113 8.256
python filter00:00:00.489 0.0%15903 0.000 0.031 0.080
site send00:00:02.159 0.0%44723 0.000 0.048 0.099
TOTAL: 23:50:45.99023:50:24.012100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.026 0.0%14720 0.000 0.002 0.029
article prepare00:00:00.000 0.0%0 0.000 0.000 0.000
article read00:00:00.000 0.0%0 0.000 0.000 0.000
backlog stats00:00:00.028 0.0%49073 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.254 0.0%29490 0.000 0.009 0.024
data write00:00:00.442 0.0%14812 0.000 0.030 0.084
idle23:47:01.668100.0%49073 945.857 1744.782 5576.833
status file00:00:02.238 0.0%148 0.000 15.122 1397.000
TOTAL: 23:47:06.64023:47:04.656100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:14:42.483 89.2%5752 152.750 153.422 337.750
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.260 0.0%12944 0.000 0.020 0.222
TOTAL: 00:16:29.36400:14:42.743 89.2%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reserve 2
shutdown 3
TOTAL: 11 170

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 5 12875 11140 1676 59 86%24:05:13
2news.nntp4.net 3 15770 3923 10955 892 24%23:57:40
3localhost 1 0 0 0 0 0%00:00:00
TOTAL: 3 9 28645 15063 12631 951 52%48:02:53
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org30.2 MB1.1 KB120.3 KB30.3 MB 99%2.8 KB
2news.nntp4.net12.9 MB0.0 KB8.8 MB21.7 MB 59%4.6 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 343.0 MB1.1 KB9.0 MB52.0 MB 82%3.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 27 15:00:05 - 15:59:59 785 5.2% 0.223.0 MB 7.0% 0.85
Mar 27 16:00:00 - 16:59:59 898 5.9% 0.252.7 MB 6.3% 0.77
Mar 27 17:00:00 - 17:59:59 778 5.1% 0.222.0 MB 4.7% 0.57
Mar 27 18:00:00 - 18:59:59 750 5.0% 0.212.1 MB 5.0% 0.61
Mar 27 19:00:00 - 19:59:59 879 5.8% 0.242.8 MB 6.5% 0.79
Mar 27 20:00:00 - 20:59:59 761 5.0% 0.212.3 MB 5.3% 0.65
Mar 27 21:00:00 - 21:59:59 650 4.3% 0.182.0 MB 4.6% 0.57
Mar 27 22:00:00 - 22:59:59 601 4.0% 0.171.8 MB 4.2% 0.51
Mar 27 23:00:00 - 23:59:59 526 3.5% 0.151.7 MB 3.9% 0.47
Mar 28 00:00:00 - 00:59:59 427 2.8% 0.121.5 MB 3.4% 0.41
Mar 28 01:00:00 - 01:59:59 366 2.4% 0.101.3 MB 3.0% 0.37
Mar 28 02:00:00 - 02:59:59 347 2.3% 0.101.3 MB 3.0% 0.37
Mar 28 03:00:00 - 03:59:59 266 1.8% 0.071.0 MB 2.3% 0.29
Mar 28 04:00:00 - 04:59:59 231 1.5% 0.06815.9 KB 1.9% 0.23
Mar 28 05:00:00 - 05:59:59 276 1.8% 0.08697.0 KB 1.6% 0.19
Mar 28 06:00:00 - 06:59:59 372 2.5% 0.10955.7 KB 2.2% 0.27
Mar 28 07:00:00 - 07:59:59 555 3.7% 0.151.4 MB 3.2% 0.39
Mar 28 08:00:00 - 08:59:59 657 4.3% 0.181.5 MB 3.4% 0.42
Mar 28 09:00:00 - 09:59:59 797 5.3% 0.221.8 MB 4.1% 0.50
Mar 28 10:00:00 - 10:59:59 824 5.4% 0.231.8 MB 4.2% 0.52
Mar 28 11:00:00 - 11:59:59 704 4.7% 0.201.6 MB 3.8% 0.46
Mar 28 12:00:00 - 12:59:59 943 6.2% 0.262.7 MB 6.4% 0.78
Mar 28 13:00:00 - 13:59:59 863 5.7% 0.242.2 MB 5.2% 0.63
Mar 28 14:00:00 - 15:00:01 871 5.8% 0.242.0 MB 4.7% 0.58
TOTAL: 23:59:56 15127 100.0% 0.1842.9 MB 100.0% 0.51
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
FOURDEFAULT1024.0 MB4.7 MB 0.5% 0 0.28 42.91
ONEDEFAULT1024.0 MB161.4 MB 15.8% 2 0.72 16.80
THREEDEFAULT1024.0 MB4.7 MB 0.5% 0 0.28 43.47
TWODEFAULT1024.0 MB4.7 MB 0.5% 0 0.28 42.64
TOTAL: 4-4.0 GB175.5 MB 4.3% 2 0.56-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 900 12 0 0 1 0 0 0 887
2news-out.aioe.org 375 0 0 1 0 0 0 0 374
TOTAL: 2 1275 12 0 1 1 0 0 0 1261

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.hardware 5
sybase.public.ng.migration.test 1
0.test 1
alt.books.marketplace 1
mozilla.dev.themes 1
lada.music 1
alt.ebook 1
intel.microprocessors.pentium 1
TOTAL: 8 12

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

GroupsCount
bit.listserv.catholic 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 506
EMP (phn path) 234
EMP (phl) 125
EMP (phn nph) 21
HTML Multipart 9
Too many newsgroups 4
HTML post 3
Binary: misplaced binary 2
Scoring filter 2
TOTAL: 9 906

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 316
news.nntp4.net 39
TOTAL: 2 355
TOTAL: 1 355

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1aioe 3844 0 3762 0 0 0 0%23:59:20
2nntp4 11144 0 11132 0 0 0 0%23:59:19
TOTAL: 2 14988 0 14894 0 0 0 0%47:58:39
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1aioe0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:20
2nntp40.0 KB0.0 KB0.0 KB0.0 KB/s023:59:19
TOTAL: 20.0 KB0.0 KB0.0 KB0.0 KB/s047:58:39
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1colo-vultr-2.gslin.com1438 00.0 KB 0 0 000:16:28
TOTAL: 11438 00.0 KB 0 0 000:16:28

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.gslin.com1438 00.0 KB 0 0 000:16:28
TOTAL: 11438 00.0 KB 0 0 000:16:28

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
colo-vultr-2.gslin.com 1.334 2.978 0.00000:16:28
scanner-08.ch1.censys-scanner.com 0.001 0.001 0.00000:00:00
? 0.001 0.000 0.00000:00:00
TOTAL: 3 1.336 2.979 0.00000:16:28

NNRP no permission clients:

SystemConn
scanner-08.ch1.censys-scanner.com 1
TOTAL: 1 1
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>