Daily Usenet Report

Mar 8 00:01:01 -- Mar 9 00:01:01

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Mar 8 06:13:22 newsfeed nnrpd[2828929]: CNFS: no magic cookie found for cycbuff CYCNGS3, initializing
Mar 8 06:13:22 newsfeed nnrpd[2828929]: CNFS: CNFSflushhead: attempted flush whilst read only
Mar 8 06:13:22 newsfeed nnrpd[2828929]: CNFS: CNFSshutdowncycbuff: flushing CYCNGS3
Mar 8 06:13:22 newsfeed nnrpd[2828929]: CNFS: CNFSflushhead: attempted flush whilst read only
Mar 8 06:13:22 newsfeed nnrpd[2828929]: SM: storage method 'cnfs' failed initialization
Mar 8 06:13:22 newsfeed nnrpd[2828929]: SM: one or more storage methods failed initialization
Mar 8 06:13:22 newsfeed nnrpd[2828929]: can't initialize storage method, one or more storage methods failed initialization
Mar 8 06:13:22 newsfeed nnrpd[2828929]: time 1 nntpwrite 0(1)
Mar 8 08:14:26 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Mar 9 00:00:01 newsfeed nnrpd[3122360]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
innd 10032 40.8%1.5 MB 25.1%
inn 9273 37.7%3.4 MB 58.4%
innfeed 4439 18.1%874.0 KB 14.6%
cnfsstat 531 2.2%80.5 KB 1.3%
nnrpd 269 1.1%23.1 KB 0.4%
nocem 38 0.2%4.6 KB 0.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 24583 100.0%5.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 129194 63.8%
Negative hits 64077 31.6%
Do not exist 9193 4.5%
Cache misses 52 0.0%
TOTAL: 4 202516 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.084 0.0%18 0.000 4.667 11.000
article cleanup00:00:00.098 0.0%9230 0.000 0.011 0.211
article logging00:00:00.448 0.0%9292 0.000 0.048 0.354
article parse00:00:00.240 0.0%14496 0.000 0.017 0.082
article write00:00:02.130 0.0%6521 0.000 0.327 3.727
artlog/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.446 0.0%198549 0.000 0.002 0.035
hisgrep/artcncl00:00:00.061 0.0%21 0.000 2.905 7.000
hishave/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
hishave/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:45.535 0.2%203720 0.094 0.813 5.641
history sync00:00:00.071 0.0%394 0.000 0.180 1.000
history write00:02:03.052 0.1%9230 3.247 13.332 112.083
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:51:31.084 99.1%191303 66.984 448.979 2186.511
nntp read00:00:03.849 0.0%200245 0.003 0.019 0.549
overview write00:06:24.092 0.4%6521 0.667 58.901 3264.661
perl filter00:00:34.003 0.0%9230 1.207 3.684 28.015
python filter00:00:01.540 0.0%9232 0.000 0.167 4.094
site send00:00:00.957 0.0%13095 0.000 0.073 1.571
TOTAL: 2324:03:27.690 99.9%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.015 0.0%6890 0.000 0.002 0.030
article prepare00:00:00.006 0.0%48134 0.000 0.000 0.007
article read00:00:00.685 0.0%4321 0.000 0.159 1.444
backlog stats00:00:00.412 0.0%268638 0.000 0.002 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.219 0.0%177762 0.002 0.012 0.048
data write00:00:03.329 0.0%174121 0.005 0.019 0.038
idle23:57:35.864100.0%268638 154.552 321.086 1696.287
status file00:00:00.432 0.0%347 1.000 1.245 10.000
TOTAL: 923:57:42.962100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:11:47.669 99.5%17 65.000 41627.588165874.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:03.779 0.5%78843 0.000 0.048 0.048
TOTAL: 300:11:51.448100.0%----

Control commands to innd:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 97
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 119

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 3 13660 1431 12148 81 10%49:02:11
2usenet.blueworldhosting.com 717 8905 876 6916 1113 9%23:48:16
3glockenspiel.complete.org 2 5865 719 5142 4 12%26:13:29
4usenet.goja.nl.eu.org 11 8712 552 7908 252 6%25:42:08
5news.hispagatos.org 5 8816 444 8158 214 5%24:26:22
6nntp.comgw.net 2 8780 437 7988 355 4%23:59:52
7news.corradoroberto.it 4 1156 410 746 0 35%22:32:28
8i2pn.org 2 8180 299 7712 169 3%23:59:46
9newsfeed.bofh.team 2 8963 270 8550 143 3%23:59:42
10news.samoylyk.net 1 6657 189 6451 17 2%23:59:50
11usenet.network 2 5703 183 5502 18 3%23:59:55
12newsfeed.xs3.de 2 5953 166 5763 24 2%23:59:23
13endofthelinebbs.peers.news.panix.com 24 6998 147 6846 5 2%23:55:00
14194.87.241.33 39 483 122 233 128 25%13:47:00
15news.furie.org.uk 2 3337 114 3223 0 3%23:59:49
16news.nntp4.net 2 7047 79 6920 48 1%23:59:15
17endoftheline-out.news.chmurka.net 2 3105 70 3035 0 2%23:59:49
18peering.usenet.ovh 1 8210 47 8145 18 0%23:59:58
19news.bbs.nz 1 7217 13 7203 1 0%23:59:38
20news.netfront.net 2 9988 8 9912 68 0%29:11:35
21outgoing.news.lysator.liu.se 2 4549 2 4547 0 0%23:59:26
22photonic.trudheim.com 4 1544 2 1542 0 0%23:23:16
23localhost 35 2 2 0 0 100%00:02:43
24news.nk.ca 2 0 0 0 0 0%00:20:12
TOTAL: 24 869 143830 6582 134590 2658 4%550:21:03
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net4.7 MB0.0 KB218.4 KB4.9 MB 95%3.3 KB
2glockenspiel.complete.org2.6 MB0.0 KB4.8 KB2.6 MB 99%3.6 KB
3usenet.blueworldhosting.com2.0 MB0.0 KB2.3 MB4.2 MB 46%2.2 KB
4news.corradoroberto.it1.8 MB0.0 KB0.0 KB1.8 MB100%4.6 KB
5usenet.network1.7 MB0.0 KB31.9 KB1.7 MB 98%8.7 KB
6nntp.comgw.net1.6 MB0.0 KB1.3 MB3.0 MB 55%3.8 KB
7usenet.goja.nl.eu.org1.6 MB0.0 KB399.6 KB1.9 MB 79%2.5 KB
8news.samoylyk.net1.5 MB0.0 KB28.9 KB1.5 MB 98%7.4 KB
9news.hispagatos.org1.1 MB0.0 KB461.9 KB1.5 MB 70%2.4 KB
10newsfeed.bofh.team799.2 KB0.0 KB242.2 KB1.0 MB 76%2.5 KB
11i2pn.org718.0 KB0.0 KB236.5 KB954.6 KB 75%2.0 KB
12194.87.241.33646.2 KB0.0 KB315.6 KB961.9 KB 67%3.8 KB
13newsfeed.xs3.de636.1 KB0.0 KB51.0 KB687.1 KB 92%3.6 KB
14news.furie.org.uk542.0 KB0.0 KB0.0 KB542.0 KB100%4.8 KB
15endofthelinebbs.peers.news.panix.com314.8 KB0.0 KB6.2 KB321.0 KB 98%2.1 KB
16endoftheline-out.news.chmurka.net205.7 KB0.0 KB0.0 KB205.7 KB100%2.9 KB
17news.nntp4.net200.8 KB0.0 KB99.4 KB300.2 KB 66%2.4 KB
18peering.usenet.ovh142.1 KB0.0 KB29.8 KB171.9 KB 82%2.6 KB
19news.netfront.net61.9 KB0.0 KB2.0 MB2.0 MB 2%27.5 KB
20news.bbs.nz26.3 KB0.0 KB0.7 KB27.0 KB 97%1.9 KB
21outgoing.news.lysator.liu.se11.5 KB0.0 KB0.0 KB11.5 KB100%5.7 KB
22photonic.trudheim.com6.6 KB0.0 KB0.0 KB6.6 KB100%3.3 KB
23localhost4.1 KB0.0 KB0.0 KB4.1 KB100%2.0 KB
24news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2422.6 MB0.0 KB7.7 MB30.3 MB 74%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 8 00:01:01 - 00:59:59 183 2.8% 0.05782.5 KB 3.5% 0.22
Mar 8 01:00:00 - 01:59:59 361 5.5% 0.101.0 MB 4.6% 0.29
Mar 8 02:00:00 - 02:59:59 314 4.8% 0.091.1 MB 5.1% 0.32
Mar 8 03:00:00 - 03:59:59 371 5.7% 0.101.0 MB 4.6% 0.29
Mar 8 04:00:00 - 04:59:59 360 5.5% 0.10904.0 KB 4.0% 0.25
Mar 8 05:00:00 - 05:59:59 274 4.2% 0.08590.8 KB 2.6% 0.16
Mar 8 06:00:00 - 06:59:59 263 4.0% 0.071.1 MB 4.9% 0.31
Mar 8 07:00:00 - 07:59:59 390 6.0% 0.111.3 MB 5.7% 0.36
Mar 8 08:00:00 - 08:59:59 362 5.6% 0.101.2 MB 5.3% 0.33
Mar 8 09:00:00 - 09:59:59 346 5.3% 0.101.2 MB 5.3% 0.33
Mar 8 10:00:00 - 10:59:59 343 5.3% 0.101.6 MB 7.2% 0.45
Mar 8 11:00:00 - 11:59:59 348 5.3% 0.10990.6 KB 4.4% 0.28
Mar 8 12:00:00 - 12:59:59 320 4.9% 0.091006.9 KB 4.5% 0.28
Mar 8 13:00:00 - 13:59:59 342 5.3% 0.101.1 MB 5.2% 0.32
Mar 8 14:00:00 - 14:59:59 355 5.5% 0.101.2 MB 5.3% 0.33
Mar 8 15:00:00 - 15:59:59 345 5.3% 0.101.1 MB 5.2% 0.33
Mar 8 16:00:00 - 16:59:59 287 4.4% 0.08939.2 KB 4.2% 0.26
Mar 8 17:00:00 - 17:59:59 241 3.7% 0.07944.5 KB 4.2% 0.26
Mar 8 18:00:00 - 18:59:59 133 2.0% 0.04719.3 KB 3.2% 0.20
Mar 8 19:00:00 - 19:59:59 138 2.1% 0.04529.8 KB 2.4% 0.15
Mar 8 20:00:00 - 20:59:59 125 1.9% 0.03508.8 KB 2.3% 0.14
Mar 8 21:00:00 - 21:59:59 92 1.4% 0.03401.3 KB 1.8% 0.11
Mar 8 22:00:00 - 22:59:59 95 1.5% 0.03364.2 KB 1.6% 0.10
Mar 8 23:00:00 - 23:59:59 114 1.8% 0.03611.6 KB 2.7% 0.17
Mar 9 00:00:00 - 00:01:01 5 0.1% 0.0822.5 KB 0.1% 0.37
TOTAL: 24:00:00 6507 100.0% 0.0821.9 MB 100.0% 0.26
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
CYCNGS1NGS1536.0 GB48.0 MB 0.0% 1 0.00 0.00
CYCNGS2NGS1536.0 GB104.8 GB 6.8% 0 0.4144955.10
CYCNGS3NGS1536.0 GB48.0 MB 0.0% 0 0.00 0.00
CYCTXT1TEXT5.0 GB4.1 GB 82.9% 0 0.03 2301.10
TOTAL: 4-4613.0 GB109.1 GB 2.4% 1 0.32-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1124 1 0 0 0 0 0 0 1123
2nntp.comgw.net 389 0 0 0 0 0 0 0 389
3usenet.goja.nl.eu.org 259 0 0 0 0 0 0 0 259
4news.hispagatos.org 214 13 0 0 0 0 0 0 201
5i2pn.org 167 0 0 0 0 0 0 0 167
6newsfeed.bofh.team 143 0 0 0 0 0 0 0 143
7194.87.241.33 124 0 0 0 0 0 0 0 124
8endofthelinebbs-out.news.weretis.net 96 0 0 0 0 0 0 0 96
9news.netfront.net 89 0 0 0 0 0 0 0 89
10news.nntp4.net 63 0 0 0 0 0 0 0 63
11newsfeed.xs3.de 26 0 0 0 0 0 0 0 26
12usenet.network 19 0 0 0 0 0 0 0 19
13news.samoylyk.net 17 0 0 0 0 0 0 0 17
14peering.usenet.ovh 17 0 0 0 0 0 0 0 17
15endofthelinebbs.peers.news.panix.com 8 0 0 0 0 0 0 0 8
16glockenspiel.complete.org 7 0 0 0 0 0 0 0 7
17news.bbs.nz 1 0 0 0 0 0 0 0 1
TOTAL: 17 2763 14 0 0 0 0 0 0 2749

Unwanted newsgroups [Top 20]:

NewsgroupCount
hispagatos.mastodon.tag.hacking 13
alt.sports.college.pac10 1
TOTAL: 2 14

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 1633
Too many newsgroups 350
EMP (phr path) 334
EMP (md5) 250
Too many newsgroups (meow) 25
Binary: misplaced binary 22
Too many newsgroups (low_xpost) 22
Angle-bracket bot 20
User-issued spam cancel 19
User-issued cancel 8
HTML Multipart 4
TOTAL: 11 2687

NoCeM on spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
robot@pasdenom.info (spam3)5003535
robot@pasdenom.info (spam2)17002222
robot@pasdenom.info (spam4)5001414
i2pn2-nocem@i2pn2.org (multipost)8001111
i2pn2-nocem@i2pn2.org (rmp)20055
TOTAL: 537008787

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
localhost 6
TOTAL: 1 6
Including strange strings
nntp.comgw.net 18
usenet.blueworldhosting.com 11
endofthelinebbs-out.news.weretis.net 10
usenet.goja.nl.eu.org 5
glockenspiel.complete.org 4
endofthelinebbs.peers.news.panix.com 3
newsfeed.xs3.de 2
news.netfront.net 2
news.nntp4.net 2
news.hispagatos.org 1
TOTAL: 14 62
TOTAL: 2 68

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nyheter.lysator.liu.se 6946 2829 3554 117 0 0 40%23:59:53
2news.netfront.net 10526 1985 4504 5 0 39 18%23:59:53
3news.furie.org.uk 3733 672 2252 6 0 0 18%23:59:53
4photonic.trudheim.com 7326 626 4251 1618 0 1 8%23:59:51
5usenet.goja.nl.eu.org 7055 425 5221 3 0 0 6%23:59:53
6news.nntp4.net 7398 405 5597 1 0 32 5%23:59:53
7endofthelinebbs.peers.news.panix.com 6892 342 6146 7 0 0 4%23:58:32
8peering.usenet.ovh 7310 290 6165 36 0 0 3%23:59:52
9news.corradoroberto.it 895 204 430 0 0 2 22%23:12:37
10news.bbs.nz 12061 127 5885 481 0 139 1%23:59:53
11news.hispagatos.org 7756 117 5930 14 0 0 1%23:57:19
12i2pn.org 7257 110 6039 41 0 70 1%23:59:33
13usenet.network 8386 107 5932 12 0 0 1%23:58:13
14newsfeed.bofh.team 7568 106 6105 0 0 0 1%23:59:41
15nntp.comgw.net 6656 102 5961 3 0 0 1%23:59:36
16news.nk.ca 8901 80 5843 472 0 41 0%23:59:53
17news.weretis.net 6818 61 6365 73 0 0 0%23:59:49
18news.quux.org 5258 51 4392 327 0 0 0%23:59:53
19news.tnetconsulting.net 8399 41 6299 152 0 56 0%23:58:41
20newsfeed.xs3.de 8194 37 6124 154 0 0 0%23:58:09
21news.samoylyk.net 8184 17 6118 70 0 9 0%23:59:53
22news.chmurka.net 3956 12 2822 0 0 0 0%23:59:53
23ddt.demos.su 28 4 24 0 0 0 14%03:40:06
24usenet.blueworldhosting.com 5987 2 5386 0 0 5 0%23:58:12
25feed.opticnetworks.net 0 0 0 0 0 6502 0%23:59:53
TOTAL: 25 163490 8752 117345 3592 0 6896 5%578:38:54
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net8.3 MB15.7 KB8.3 MB0.1 KB/s4.3 KB23:59:53
2nyheter.lysator.liu.se7.9 MB906.0 KB8.8 MB0.1 KB/s3.1 KB23:59:53
3news.furie.org.uk3.1 MB32.5 KB3.1 MB0.0 KB/s4.7 KB23:59:53
4photonic.trudheim.com2.2 MB3.7 MB5.9 MB0.1 KB/s2.7 KB23:59:51
5endofthelinebbs.peers.news.panix.com1.4 MB24.0 KB1.5 MB0.0 KB/s4.3 KB23:58:32
6peering.usenet.ovh1.3 MB176.9 KB1.5 MB0.0 KB/s4.7 KB23:59:52
7usenet.goja.nl.eu.org717.1 KB23.0 KB740.1 KB0.0 KB/s1.7 KB23:59:53
8news.nntp4.net678.7 KB20.0 KB698.7 KB0.0 KB/s1.7 KB23:59:53
9news.corradoroberto.it615.9 KB0.0 KB615.9 KB0.0 KB/s3.0 KB23:12:37
10news.quux.org487.1 KB776.4 KB1.2 MB0.0 KB/s3.3 KB23:59:53
11news.nk.ca454.0 KB1.3 MB1.7 MB0.0 KB/s3.2 KB23:59:53
12news.bbs.nz401.5 KB1.3 MB1.7 MB0.0 KB/s2.8 KB23:59:53
13usenet.network391.2 KB102.2 KB493.4 KB0.0 KB/s4.1 KB23:58:13
14news.samoylyk.net356.8 KB159.9 KB516.7 KB0.0 KB/s5.9 KB23:59:53
15i2pn.org322.1 KB264.3 KB586.4 KB0.0 KB/s3.9 KB23:59:33
16news.weretis.net278.5 KB752.7 KB1.0 MB0.0 KB/s7.7 KB23:59:49
17news.hispagatos.org271.8 KB79.9 KB351.7 KB0.0 KB/s2.7 KB23:57:19
18newsfeed.bofh.team254.9 KB0.0 KB254.9 KB0.0 KB/s2.4 KB23:59:41
19news.tnetconsulting.net196.2 KB575.5 KB771.7 KB0.0 KB/s4.0 KB23:58:41
20nntp.comgw.net194.5 KB343.2 KB537.7 KB0.0 KB/s5.1 KB23:59:36
21newsfeed.xs3.de105.9 KB704.9 KB810.8 KB0.0 KB/s4.2 KB23:58:09
22news.chmurka.net55.0 KB0.0 KB55.0 KB0.0 KB/s4.6 KB23:59:53
23ddt.demos.su9.1 KB0.0 KB9.1 KB0.0 KB/s2.3 KB03:40:06
24usenet.blueworldhosting.com4.1 KB0.0 KB4.1 KB0.0 KB/s2.1 KB23:58:12
25feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 2529.9 MB11.1 MB41.0 MB0.0 KB/s3.4 KB578:38:54
Outgoing feeds (innfeed) by volume

NNRP connection statistics (by domain) [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1*localhost 4 00.0 KB 0 0 000:11:27
2unresolved 4 00.0 KB 4 0 000:00:20
3*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
TOTAL: 3 10 00.0 KB 4 0 000:11:51

NNRP auth users [Top 20]:

UserConn
0.013 system 0.013 idle 0.000 elapsed 0.001 1
getlist 1
TOTAL: 2 2

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
localhost 181.355 369.282 0.09000:11:27
205.210.31.237 0.000 0.015 0.00000:00:10
205.210.31.139 0.013 0.027 0.00000:00:10
pruitt.probe.onyphe.net 0.004 0.011 0.00000:00:02
147.45.112.186 0.006 0.009 0.00000:00:00
callum.probe.onyphe.net 0.000 0.024 0.00000:00:00
185.165.191.26 0.000 0.015 0.00000:00:00
TOTAL: 7 181.378 369.383 0.09000:11:51

Curious NNRP explorers [Top 20]:

SystemConn
localhost 4
147.45.112.186 1
185.165.191.26 1
205.210.31.139 1
205.210.31.237 1
callum.probe.onyphe.net 1
pruitt.probe.onyphe.net 1
TOTAL: 7 10

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

SystemConn
callum.probe.onyphe.net 2
147.45.112.186 1
TOTAL: 2 3

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

CommandCount
#003 1
#026#003#003#001?#001 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
TOTAL: 3 3

NNRP client timeouts [Top 20]:

SystemConnPeer
147.45.112.186 1 1
TOTAL: 1 1 1

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

HierarchyCountPct
1news 4100.0%
TOTAL: 1 4100.0%

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

NewsgroupCount
1news.software.nntp 4
TOTAL: 1 4