Daily Usenet Report

Mar 7 00:01:02 -- Mar 8 00:01:01

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Mar 7 04:52:58 newsfeed nnrpd[2037036]: localhost auth: program exited with status 8
Mar 7 04:53:12 newsfeed nnrpd[2037036]: localhost auth: program exited with status 8
Mar 7 04:53:21 newsfeed nnrpd[2037036]: localhost auth: program exited with status 8
Mar 7 04:53:41 newsfeed nnrpd[2037036]: localhost auth: program exited with status 8
Mar 7 04:57:42 newsfeed nnrpd[2042073]: newsfeed.endofthelinebbs.com auth: program exited with status 8
Mar 7 04:57:57 newsfeed nnrpd[2042073]: newsfeed.endofthelinebbs.com auth: program exited with status 8
Mar 8 00:00:01 newsfeed nnrpd[2544247]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
innd 16060 42.0%2.1 MB 27.4%
inn 16056 42.0%4.6 MB 59.2%
innfeed 4432 11.6%882.0 KB 11.2%
nnrpd 1196 3.1%97.0 KB 1.2%
cnfsstat 432 1.1%68.0 KB 0.9%
nocem 58 0.2%6.9 KB 0.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 38235 100.0%7.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 190763 65.9%
Negative hits 74837 25.8%
Do not exist 15934 5.5%
Cache misses 8068 2.8%
TOTAL: 4 289602 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.197 0.0%37 0.000 5.324 12.000
article cleanup00:00:00.176 0.0%15958 0.000 0.011 0.233
article logging00:00:00.725 0.0%16018 0.000 0.045 0.308
article parse00:00:00.316 0.0%22769 0.000 0.014 0.058
article write00:00:03.101 0.0%7438 0.000 0.417 15.583
artlog/artcncl00:00:00.000 0.0%5 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:00.902 0.0%227257 0.000 0.004 0.575
hisgrep/artcncl00:00:00.143 0.0%38 0.000 3.763 8.500
hishave/artcncl00:00:00.002 0.0%5 0.000 0.400 1.000
hishave/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.005 0.0%1 5.000 5.000 5.000
history lookup00:03:04.995 0.2%290834 0.102 0.636 3.959
history sync00:00:00.058 0.0%401 0.000 0.145 0.667
history write00:02:33.629 0.2%15926 3.168 9.646 89.125
hiswrite/artcncl00:00:00.003 0.0%1 3.000 3.000 3.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:40:15.487 99.2%213658 55.888 398.841 1961.788
nntp read00:00:05.217 0.0%228891 0.003 0.023 0.235
overview write00:03:24.326 0.2%7438 0.750 27.471 1249.722
perl filter00:00:36.902 0.0%15925 0.680 2.317 27.819
python filter00:00:02.120 0.0%15926 0.000 0.133 3.176
site send00:00:01.507 0.0%14924 0.000 0.101 2.092
TOTAL: 2323:50:09.811 99.9%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.020 0.0%8343 0.000 0.002 0.028
article prepare00:00:00.011 0.0%62823 0.000 0.000 0.004
article read00:00:01.958 0.0%4943 0.000 0.396 48.769
backlog stats00:00:00.593 0.0%303558 0.000 0.002 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:03.245 0.0%205383 0.002 0.016 0.066
data write00:00:04.066 0.0%202152 0.011 0.020 0.038
idle23:57:40.333100.0%303558 133.663 284.164 1336.141
status file00:00:00.517 0.0%338 1.000 1.530 18.000
TOTAL: 923:57:50.743100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:20:28.999 99.1%65 157.500 18907.677163995.750
newnews00:00:00.000 0.0%1 0.000 0.000 0.000
nntpwrite00:00:04.072 0.3%79178 0.000 0.051 0.080
nntpwrite/newnews00:00:06.555 0.5%544326 0.012 0.012 0.012
TOTAL: 400:20:39.626100.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 4 29164 2876 24589 1699 9%47:41:35
2usenet.blueworldhosting.com 720 15664 812 13635 1217 5%23:46:28
3news.corradoroberto.it 11 1691 517 1051 123 30%24:19:28
4usenet.goja.nl.eu.org 12 14464 512 13461 491 3%22:29:58
5usenet.network 1 9878 468 8970 440 4%23:59:57
6glockenspiel.complete.org 2 6667 382 6174 111 5%19:12:39
7nntp.comgw.net 1 15023 305 14095 623 2%23:59:56
8endoftheline-out.news.chmurka.net 1 6670 264 6282 124 3%23:59:57
9i2pn.org 2 9012 259 8596 157 2%23:59:28
10newsfeed.bofh.team 2 15821 236 15165 420 1%23:58:50
11194.87.241.33 47 764 162 427 175 21%15:51:19
12endofthelinebbs.peers.news.panix.com 33 13725 151 11544 2030 1%25:06:56
13news.nntp4.net 22 8385 129 8189 67 1%23:49:31
14news.samoylyk.net 2 9787 116 9644 27 1%24:54:38
15news.hispagatos.org 5 16652 106 16003 543 0%23:10:18
16newsfeed.xs3.de 1 12235 63 11925 247 0%23:59:56
17news.netfront.net 1 10455 23 10304 128 0%23:43:29
18peering.usenet.ovh 2 11806 14 11456 336 0%23:59:24
19news.bbs.nz 1 10009 12 9913 84 0%23:59:43
20news.furie.org.uk 2 3951 12 3937 2 0%23:58:59
21photonic.trudheim.com 4 1810 8 1797 5 0%24:18:49
22outgoing.news.lysator.liu.se 2 7519 1 7509 9 0%23:59:55
23localhost 35 1 1 0 0 100%00:01:10
24news.nk.ca 2 0 0 0 0 0%00:20:19
TOTAL: 24 915 231153 7429 214666 9058 3%538:42:42
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net7.9 MB13.3 KB1.9 MB9.8 MB 80%2.2 KB
2usenet.network4.8 MB10.5 KB309.4 KB5.1 MB 93%5.7 KB
3news.corradoroberto.it2.9 MB0.0 KB73.3 KB3.0 MB 97%4.8 KB
4usenet.blueworldhosting.com2.1 MB0.0 KB1.9 MB4.0 MB 51%2.0 KB
5glockenspiel.complete.org1.5 MB10.5 KB313.7 KB1.8 MB 82%3.7 KB
6usenet.goja.nl.eu.org1.5 MB10.6 KB520.4 KB2.0 MB 73%2.0 KB
7endoftheline-out.news.chmurka.net1.2 MB0.0 KB86.6 KB1.3 MB 93%3.3 KB
8i2pn.org1.1 MB7.8 KB217.1 KB1.4 MB 83%3.3 KB
9nntp.comgw.net1009.4 KB10.5 KB1.1 MB2.1 MB 46%2.3 KB
10news.samoylyk.net811.3 KB10.5 KB61.8 KB883.6 KB 91%6.2 KB
11newsfeed.bofh.team699.6 KB7.9 KB401.3 KB1.1 MB 63%1.7 KB
12endofthelinebbs.peers.news.panix.com587.4 KB10.5 KB1.3 MB1.8 MB 31%0.9 KB
13194.87.241.33454.1 KB0.0 KB459.3 KB913.4 KB 49%2.7 KB
14news.nntp4.net425.7 KB2.7 KB254.8 KB683.2 KB 62%3.5 KB
15news.hispagatos.org300.1 KB7.9 KB769.1 KB1.1 MB 27%1.7 KB
16newsfeed.xs3.de242.6 KB10.5 KB185.0 KB438.1 KB 55%1.4 KB
17news.furie.org.uk134.6 KB10.6 KB0.0 KB145.1 KB 92%10.4 KB
18photonic.trudheim.com104.4 KB10.5 KB1.9 KB116.8 KB 89%9.0 KB
19news.netfront.net74.9 KB10.7 KB2.4 MB2.4 MB 3%16.5 KB
20peering.usenet.ovh41.6 KB10.5 KB222.1 KB274.3 KB 15%0.8 KB
21news.bbs.nz32.9 KB10.5 KB255.8 KB299.2 KB 10%3.1 KB
22outgoing.news.lysator.liu.se1.7 KB10.6 KB20.5 KB32.8 KB 5%3.3 KB
23localhost1.3 KB0.0 KB0.0 KB1.3 KB100%1.3 KB
24news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2427.7 MB176.7 KB12.6 MB40.5 MB 68%2.5 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 7 00:01:02 - 00:59:59 141 1.9% 0.04426.8 KB 1.5% 0.12
Mar 7 01:00:00 - 01:59:59 303 4.1% 0.081015.5 KB 3.5% 0.28
Mar 7 02:00:00 - 02:59:59 383 5.1% 0.11983.7 KB 3.4% 0.27
Mar 7 03:00:00 - 03:59:59 376 5.0% 0.101.2 MB 4.4% 0.35
Mar 7 04:00:00 - 04:59:59 406 5.4% 0.111.7 MB 6.1% 0.49
Mar 7 05:00:00 - 05:59:59 384 5.1% 0.111.5 MB 5.1% 0.41
Mar 7 06:00:00 - 06:59:59 387 5.2% 0.111.3 MB 4.6% 0.37
Mar 7 07:00:00 - 07:59:59 394 5.3% 0.111.4 MB 4.9% 0.40
Mar 7 08:00:00 - 08:59:59 500 6.7% 0.142.6 MB 9.1% 0.74
Mar 7 09:00:00 - 09:59:59 587 7.9% 0.162.7 MB 9.4% 0.76
Mar 7 10:00:00 - 10:59:59 413 5.5% 0.111.5 MB 5.4% 0.44
Mar 7 11:00:00 - 11:59:59 354 4.7% 0.101.2 MB 4.2% 0.34
Mar 7 12:00:00 - 12:59:59 435 5.8% 0.121.4 MB 4.8% 0.39
Mar 7 13:00:00 - 13:59:59 367 4.9% 0.101.3 MB 4.6% 0.37
Mar 7 14:00:00 - 14:59:59 291 3.9% 0.08930.4 KB 3.2% 0.26
Mar 7 15:00:00 - 15:59:59 308 4.1% 0.091.4 MB 4.8% 0.38
Mar 7 16:00:00 - 16:59:59 261 3.5% 0.07834.3 KB 2.9% 0.23
Mar 7 17:00:00 - 17:59:59 283 3.8% 0.081.1 MB 4.0% 0.32
Mar 7 18:00:00 - 18:59:59 196 2.6% 0.05837.2 KB 2.9% 0.23
Mar 7 19:00:00 - 19:59:59 142 1.9% 0.04523.0 KB 1.8% 0.15
Mar 7 20:00:00 - 20:59:59 144 1.9% 0.04948.1 KB 3.3% 0.26
Mar 7 21:00:00 - 21:59:59 147 2.0% 0.04807.9 KB 2.8% 0.22
Mar 7 22:00:00 - 22:59:59 112 1.5% 0.03599.0 KB 2.1% 0.17
Mar 7 23:00:00 - 23:59:59 142 1.9% 0.04431.2 KB 1.5% 0.12
Mar 8 00:00:00 - 00:01:01 6 0.1% 0.1012.2 KB 0.0% 0.20
TOTAL: 23:59:59 7462 100.0% 0.0928.4 MB 100.0% 0.34
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.5136572.41
CYCTXT1TEXT5.0 GB4.1 GB 82.9% 0 0.05 1304.81
TOTAL: 3-3077.0 GB109.0 GB 3.5% 1 0.41-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1endofthelinebbs.peers.news.panix.com 2033 0 0 2 0 0 0 0 2031
2endofthelinebbs-out.news.weretis.net 1511 0 0 3 0 0 0 0 1508
3usenet.blueworldhosting.com 1236 0 0 0 0 0 0 0 1236
4nntp.comgw.net 630 0 0 2 0 0 0 0 628
5usenet.goja.nl.eu.org 494 0 0 2 0 0 0 0 492
6news.hispagatos.org 467 14 0 1 0 0 0 0 452
7usenet.network 443 0 0 2 0 0 0 0 441
8newsfeed.bofh.team 427 0 0 1 0 0 0 0 426
9peering.usenet.ovh 338 0 0 2 0 0 0 0 336
10newsfeed.xs3.de 247 0 0 2 0 0 0 0 245
11194.87.241.33 168 0 0 0 0 0 0 0 168
12i2pn.org 161 0 0 1 0 0 0 0 160
13news.corradoroberto.it 123 0 0 0 0 0 0 0 123
14endoftheline-out.news.chmurka.net 122 0 0 0 0 0 0 0 122
15news.netfront.net 111 0 0 2 0 0 0 0 109
16news.nntp4.net 29 0 0 1 0 0 0 0 28
17glockenspiel.complete.org 24 0 0 2 0 0 0 0 22
18news.samoylyk.net 13 0 0 2 0 0 0 0 11
19photonic.trudheim.com 5 0 0 2 0 0 0 0 3
20news.bbs.nz 3 0 0 2 0 0 0 0 1
21news.furie.org.uk 2 0 0 2 0 0 0 0 0
22outgoing.news.lysator.liu.se 2 0 0 2 0 0 0 0 0
TOTAL: 22 8589 14 0 33 0 0 0 0 8542

Unwanted newsgroups [Top 20]:

NewsgroupCount
hispagatos.mastodon.tag.hacking 14
TOTAL: 1 14

Perl filter (innd) [Top 20]:

ReasonCount
User-issued cancel 5810
EMP (phn path) 1874
EMP (phr path) 336
Too many newsgroups 193
EMP (md5) 130
Binary: misplaced binary 61
Angle-bracket bot 36
Too many newsgroups (meow) 20
EMP (phl) 16
HTML Multipart 3
HTML post 1
MI5 Filter 1
TOTAL: 12 8481

NoCeM on spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
i2pn2-nocem@i2pn2.org (spam)20303
i2pn2-nocem@i2pn2.org (multipost)41004848
robot@pasdenom.info (spam2)9001111
i2pn2-nocem@i2pn2.org (rmp)10055
robot@pasdenom.info (spam4)20022
TOTAL: 555036669

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
usenet.blueworldhosting.com 21
nntp.comgw.net 12
endofthelinebbs-out.news.weretis.net 8
usenet.network 5
newsfeed.bofh.team 3
endofthelinebbs.peers.news.panix.com 3
i2pn.org 2
endoftheline-out.news.chmurka.net 2
usenet.goja.nl.eu.org 2
news.hispagatos.org 1
TOTAL: 11 60
No colon-space in header field
usenet.blueworldhosting.com 1
TOTAL: 1 1
TOTAL: 2 61

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nyheter.lysator.liu.se 7831 3384 3612 459 0 0 43%23:59:53
2news.netfront.net 11899 2205 5231 0 0 65 18%23:59:53
3news.furie.org.uk 4542 1208 2404 32 0 0 26%23:59:53
4photonic.trudheim.com 8487 740 5068 1646 0 2 8%23:58:33
5peering.usenet.ovh 8334 598 6792 53 0 13 7%23:57:58
6endofthelinebbs.peers.news.panix.com 7851 561 6885 3 0 0 7%23:59:53
7usenet.goja.nl.eu.org 8397 401 6424 12 0 21 4%23:59:32
8news.nntp4.net 8616 359 6883 0 0 55 4%23:59:53
9news.corradoroberto.it 1039 235 499 0 0 0 22%22:39:56
10news.bbs.nz 13674 195 6686 561 0 190 1%23:59:13
11usenet.network 9206 180 6457 30 0 19 1%23:59:53
12news.hispagatos.org 8986 166 7142 30 0 14 1%23:59:53
13newsfeed.bofh.team 8544 164 7043 0 0 17 1%23:59:53
14news.weretis.net 7852 158 7027 269 0 15 2%23:59:12
15nntp.comgw.net 7843 134 6997 15 0 17 1%23:59:53
16i2pn.org 8418 127 6994 54 0 145 1%23:59:53
17news.quux.org 6115 124 5077 371 0 3 2%23:59:53
18news.nk.ca 10406 110 6741 487 0 99 1%23:59:53
19newsfeed.xs3.de 9480 92 7078 210 0 21 0%23:59:53
20news.tnetconsulting.net 9770 75 6993 379 0 68 0%23:59:16
21news.samoylyk.net 10083 44 7148 106 0 113 0%23:59:53
22news.chmurka.net 4445 7 3122 0 0 16 0%23:59:25
23usenet.blueworldhosting.com 7230 2 6417 0 0 2 0%23:59:53
24ddt.demos.su 20 0 20 0 0 0 0%03:30:06
25feed.opticnetworks.net 0 0 0 0 0 7456 0%23:59:53
TOTAL: 25 189068 11269 134740 4717 0 8351 5%578:01:19
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net11.7 MB0.0 KB11.7 MB0.1 KB/s5.4 KB23:59:53
2nyheter.lysator.liu.se11.1 MB4.8 MB15.9 MB0.2 KB/s4.2 KB23:59:53
3news.furie.org.uk7.5 MB204.0 KB7.7 MB0.1 KB/s6.4 KB23:59:53
4peering.usenet.ovh4.7 MB205.0 KB4.9 MB0.1 KB/s7.8 KB23:57:58
5endofthelinebbs.peers.news.panix.com3.8 MB4.3 KB3.8 MB0.0 KB/s6.9 KB23:59:53
6photonic.trudheim.com3.8 MB4.7 MB8.4 MB0.1 KB/s3.6 KB23:58:33
7news.nntp4.net1.4 MB0.0 KB1.4 MB0.0 KB/s4.1 KB23:59:53
8news.quux.org1.1 MB2.5 MB3.7 MB0.0 KB/s7.6 KB23:59:53
9usenet.goja.nl.eu.org1.1 MB320.6 KB1.4 MB0.0 KB/s3.6 KB23:59:32
10news.bbs.nz1.0 MB3.6 MB4.6 MB0.1 KB/s6.2 KB23:59:13
11newsfeed.bofh.team983.6 KB0.0 KB983.6 KB0.0 KB/s6.0 KB23:59:53
12usenet.network945.4 KB60.2 KB1005.5 KB0.0 KB/s4.8 KB23:59:53
13news.weretis.net942.4 KB3.6 MB4.5 MB0.1 KB/s10.8 KB23:59:12
14news.hispagatos.org914.4 KB307.9 KB1.2 MB0.0 KB/s6.2 KB23:59:53
15nntp.comgw.net696.8 KB508.1 KB1.2 MB0.0 KB/s8.1 KB23:59:53
16newsfeed.xs3.de673.7 KB1.4 MB2.1 MB0.0 KB/s7.0 KB23:59:53
17news.corradoroberto.it642.2 KB0.0 KB642.2 KB0.0 KB/s2.7 KB22:39:56
18news.samoylyk.net612.6 KB657.6 KB1.2 MB0.0 KB/s8.5 KB23:59:53
19i2pn.org434.2 KB318.1 KB752.2 KB0.0 KB/s4.2 KB23:59:53
20news.tnetconsulting.net425.6 KB3.0 MB3.4 MB0.0 KB/s7.7 KB23:59:16
21news.nk.ca269.8 KB3.2 MB3.4 MB0.0 KB/s5.9 KB23:59:53
22news.chmurka.net18.3 KB0.0 KB18.3 KB0.0 KB/s2.6 KB23:59:25
23usenet.blueworldhosting.com2.5 KB0.0 KB2.5 KB0.0 KB/s1.2 KB23:59:53
24ddt.demos.su0.0 KB0.0 KB0.0 KB0.0 KB/s003:30:06
25feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 2554.8 MB29.2 MB84.0 MB0.0 KB/s5.4 KB578:01:19
Outgoing feeds (innfeed) by volume

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 11 00.0 KB 2 0 000:00:36
2*localhost 4 00.0 KB 0 0 000:19:35
TOTAL: 2 15 00.0 KB 2 0 000:20:11

NNRP auth users [Top 20]:

UserConn
nelgin 10
getlist 1
TOTAL: 2 11

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
localhost 188.518 378.872 1.77600:19:35
newsfeed.endofthelinebbs.com 0.004 0.019 0.13400:00:27
206.168.34.47 0.041 0.053 0.00000:00:17
147.185.132.150 0.007 0.011 0.00000:00:10
167.94.145.106 0.019 0.035 0.00000:00:04
147.185.132.40 0.008 0.017 0.00000:00:03
147.45.112.187 0.004 0.014 0.00000:00:00
TOTAL: 7 188.601 379.021 1.91000:20:39

Curious NNRP explorers [Top 20]:

SystemConn
206.168.34.47 5
localhost 4
167.94.145.106 3
147.185.132.150 1
147.185.132.40 1
147.45.112.187 1
newsfeed.endofthelinebbs.com 1
TOTAL: 7 16

NNRP no permission clients [Top 20]:

SystemConn
localhost 4
newsfeed.endofthelinebbs.com 2
TOTAL: 2 6

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

SystemConn
206.168.34.47 2
147.45.112.187 1
167.94.145.106 1
TOTAL: 3 4

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

CommandCount
EHLO www.censys.io 2
#003 1
#026#003#001 1
TOTAL: 3 4

NNRP client timeouts [Top 20]:

SystemConnPeer
147.45.112.187 1 1
167.94.145.106 1 2
206.168.34.47 1 3
TOTAL: 3 3 7

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

HierarchyCountPct
1news 2100.0%
TOTAL: 1 2100.0%

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

NewsgroupCount
1news.answers 1
2news.software.nntp 1
TOTAL: 2 2