news.bbs.nz daily logs

Feb 25 23:58:01 -- Feb 26 23:58:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 26 09:35:56 news controlchan[23983]: skipping newgroup free.metaphysics y dale@dalekelly.org (would be created): Empty description

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 128589 56.1%9.9 MB 34.3%
innfeed 47232 20.6%7.9 MB 27.4%
innd 30002 13.1%4.8 MB 16.7%
inn 21972 9.6%6.0 MB 20.7%
cnfsstat 1440 0.6%256.7 KB 0.9%
controlchan 3 0.0%0.3 KB 0.0%
TOTAL: 6 229238 100.0%29.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 257654 51.4%
Negative hits 220955 44.1%
Do not exist 21288 4.2%
Cache misses 1333 0.3%
TOTAL: 4 501230 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.033 0.0%72 0.000 0.458 14.000
article cleanup00:00:00.177 0.0%21647 0.000 0.008 0.034
article logging00:00:00.691 0.0%21926 0.000 0.032 0.329
article parse00:00:00.707 0.0%64047 0.000 0.011 0.036
article write00:00:01.488 0.0%17892 0.013 0.083 0.486
artlog/artcncl00:00:00.001 0.0%71 0.000 0.014 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.679 0.0%463832 0.000 0.001 0.009
hisgrep/artcncl00:00:00.001 0.0%66 0.000 0.015 1.000
hishave/artcncl00:00:00.000 0.0%72 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.213 0.0%506517 0.001 0.018 1.068
history sync00:00:00.053 0.0%371 0.000 0.143 14.667
history write00:00:07.331 0.0%21516 0.012 0.341 20.172
hiswrite/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
idle23:58:03.016 99.8%476899 101.491 180.925 386.652
nntp read00:00:03.407 0.0%476504 0.002 0.007 0.035
overview write00:00:42.425 0.0%17892 0.016 2.371 67.748
perl filter00:00:46.190 0.1%21491 1.435 2.149 6.017
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.013 0.0%35785 0.000 0.028 0.098
TOTAL: 24:00:53.94923:59:56.425 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.568 0.0%531004 0.000 0.001 0.019
article prepare00:00:00.000 0.0%10108 0.000 0.000 0.000
article read00:00:00.148 0.0%2234 0.000 0.066 2.857
backlog stats00:00:00.550 0.0%559595 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.416 0.0%409162 0.002 0.006 0.014
data write00:00:05.137 0.0%393813 0.008 0.013 0.032
idle23:51:18.942 99.9%559595 48.274 153.466 530.469
status file00:01:25.993 0.1%9043 1.131 9.509 633.261
TOTAL: 23:53:19.76423:52:53.754100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%2 0.000 0.000 0.000
idle01:17:06.544 88.8%136903 0.000 33.794 75353.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:01:35.783 1.8%290662 0.000 0.330 19.867
readart00:00:01.347 0.0%8827 0.000 0.153 18.000
TOTAL: 01:26:52.94101:18:43.674 90.6%----

Control commands to INND:

CommandNumber
L 1
flush 4
flushlogs 2
go 2
logmode 2
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 168

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
dale@dalekelly.org100011
TOTAL100011

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1bbs-nz-out.news.weretis.net 5 35027 4051 30755 221 11%45:32:30
2bbsnz-out.feed.uzoreto.com 863 29162 3544 23362 2256 12%71:57:08
3news.snarked.org 25 15279 2522 12571 186 16%28:55:38
4feeder.eternal-september.org 3 20973 2379 18197 397 11%25:01:18
5news-out.aioe.org 1 17770 1976 15747 47 11%24:01:33
6news.albasani.net 1 21343 913 20173 257 4%27:41:45
7news.redatomik.org 3 17151 788 16331 32 4%24:32:15
8i2pn.org 2 19369 550 18780 39 2%23:59:57
9news.freedyn.net 5 15624 547 14887 190 3%17:38:08
10newsfeed.xs3.de 3 18036 294 17719 23 1%23:55:01
11news.swapon.de 5 1401 146 1254 1 10%21:59:42
12csiph.com 2 17318 108 17190 20 0%17:11:36
13news.samoylyk.net 2 17684 46 17632 6 0%23:59:50
14localhost 6 5 1 4 0 20%00:00:00
15nut.utcc.utoronto.ca 1 17577 0 17576 1 0%23:49:59
TOTAL: 15 927 263719 17865 242178 3676 6%400:16:20
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1bbs-nz-out.news.weretis.net19.0 MB282.3 KB1.3 MB20.6 MB 92%4.9 KB
2bbsnz-out.feed.uzoreto.com9.3 MB76.1 KB14.9 MB24.3 MB 38%4.3 KB
3news.snarked.org8.0 MB141.7 KB1.1 MB9.2 MB 86%3.5 KB
4feeder.eternal-september.org6.1 MB123.2 KB1.9 MB8.1 MB 75%3.0 KB
5news-out.aioe.org4.9 MB19.9 KB399.0 KB5.3 MB 92%2.7 KB
6news.albasani.net3.3 MB113.3 KB581.2 KB3.9 MB 82%3.5 KB
7i2pn.org3.1 MB18.1 KB112.8 KB3.2 MB 96%5.6 KB
8news.redatomik.org2.4 MB675.9 KB761.9 KB3.8 MB 62%4.7 KB
9news.freedyn.net1.5 MB81.2 KB445.0 KB2.1 MB 74%2.9 KB
10newsfeed.xs3.de1.3 MB46.4 KB92.1 KB1.4 MB 90%4.6 KB
11news.swapon.de328.7 KB0.0 KB1.7 KB330.4 KB 99%2.2 KB
12csiph.com262.7 KB77.2 KB99.2 KB439.0 KB 59%3.4 KB
13news.samoylyk.net133.0 KB60.1 KB61.9 KB255.0 KB 52%4.9 KB
14localhost1.6 KB0.0 KB0.0 KB1.6 KB100%1.6 KB
15nut.utcc.utoronto.ca0.0 KB0.0 KB2.4 KB2.4 KB 0%2.4 KB
TOTAL: 1559.6 MB1.7 MB21.7 MB83.0 MB 71%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 25 23:58:01 - 23:59:59 25 0.1% 0.2154.1 KB 0.1% 0.45
Feb 26 00:00:00 - 00:59:59 793 4.4% 0.222.6 MB 4.3% 0.74
Feb 26 01:00:00 - 01:59:59 783 4.4% 0.222.5 MB 4.1% 0.71
Feb 26 02:00:00 - 02:59:59 937 5.2% 0.262.6 MB 4.2% 0.73
Feb 26 03:00:00 - 03:59:59 981 5.5% 0.274.1 MB 6.8% 1.18
Feb 26 04:00:00 - 04:59:59 986 5.5% 0.272.7 MB 4.4% 0.76
Feb 26 05:00:00 - 05:59:59 1138 6.4% 0.324.1 MB 6.7% 1.16
Feb 26 06:00:00 - 06:59:59 965 5.4% 0.273.4 MB 5.7% 0.98
Feb 26 07:00:00 - 07:59:59 959 5.4% 0.272.8 MB 4.5% 0.79
Feb 26 08:00:00 - 08:59:59 1108 6.2% 0.314.0 MB 6.6% 1.15
Feb 26 09:00:00 - 09:59:59 999 5.6% 0.283.4 MB 5.6% 0.96
Feb 26 10:00:00 - 10:59:59 864 4.8% 0.242.8 MB 4.6% 0.79
Feb 26 11:00:00 - 11:59:59 742 4.1% 0.212.1 MB 3.4% 0.59
Feb 26 12:00:00 - 12:59:59 605 3.4% 0.171.9 MB 3.1% 0.54
Feb 26 13:00:00 - 13:59:59 591 3.3% 0.162.2 MB 3.6% 0.62
Feb 26 14:00:00 - 14:59:59 524 2.9% 0.152.0 MB 3.2% 0.56
Feb 26 15:00:00 - 15:59:59 498 2.8% 0.142.9 MB 4.8% 0.84
Feb 26 16:00:00 - 16:59:59 449 2.5% 0.121.3 MB 2.1% 0.37
Feb 26 17:00:00 - 17:59:59 474 2.6% 0.131.5 MB 2.4% 0.42
Feb 26 18:00:00 - 18:59:59 380 2.1% 0.111.5 MB 2.6% 0.44
Feb 26 19:00:00 - 19:59:59 382 2.1% 0.111.4 MB 2.4% 0.41
Feb 26 20:00:00 - 20:59:59 537 3.0% 0.151.5 MB 2.4% 0.42
Feb 26 21:00:00 - 21:59:59 735 4.1% 0.202.3 MB 3.8% 0.66
Feb 26 22:00:00 - 22:59:59 734 4.1% 0.201.9 MB 3.2% 0.55
Feb 26 23:00:00 - 23:58:02 710 4.0% 0.203.2 MB 5.3% 0.95
TOTAL: 24:00:01 17899 100.0% 0.2160.7 MB 100.0% 0.72
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB32.1 GB 64.1% 0 0.33 1857.30
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB36.7 GB 73.4% 0 0.32 1891.56
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB380.6 MB 0.7% 0 0.0320096.62
BUFF06BIN50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF07BIN50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF08CTCALL50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF09CTCALL50.0 GB1.6 MB 0.0% 1 0.00 0.00
BUFF10CTCALL50.0 GB34.4 GB 68.9% 0 0.54 1124.98
TOTAL: 10-500.0 GB103.5 GB 20.7% 1 0.39-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1bbsnz-out.feed.uzoreto.com 2247 46 0 5 0 0 0 0 2196
2feeder.eternal-september.org 379 0 0 16 0 0 0 0 363
3news.albasani.net 379 0 0 11 0 0 0 0 368
4news.freedyn.net 270 4 0 14 0 0 0 0 252
5bbs-nz-out.news.weretis.net 264 66 0 26 0 0 0 0 172
6news.snarked.org 185 9 0 13 0 0 0 0 163
7news.redatomik.org 99 0 0 13 0 0 0 0 86
8news-out.aioe.org 54 40 0 6 0 0 0 0 8
9news.swapon.de 41 0 0 0 0 0 0 0 41
10i2pn.org 36 4 0 5 0 0 0 0 27
11csiph.com 23 0 0 12 0 0 0 0 11
12newsfeed.xs3.de 20 0 0 10 0 0 0 0 10
13news.samoylyk.net 5 0 0 4 0 0 0 0 1
14nut.utcc.utoronto.ca 1 0 0 0 0 0 0 0 1
TOTAL: 14 4003 169 0 135 0 0 0 0 3699

Unwanted newsgroups [Top 20]:

NewsgroupCount
gmane.linux.alsa.devel 24
mozilla.support.seamonkey 23
mozilla.dev.security.policy 15
gmane.comp.gis.openstreetmap.tagging 11
gmane.linux.kernel 11
mozilla.dev.platform 9
mozilla.dev.ui-alerts 9
mozilla.dev.apps.thunderbird 6
gmane.linux.kernel.stable 4
mozilla.dev.geolocation 4
alt.music.pink-floyd.publius 4
alt.fr.os.windows.7 3
eagle.userchat.eng 3
gmane.comp.video.dri.devel 2
mozilla.tools.treeherder 2
aioe.helpdesk 2
alt.politics.conservative 2
rocksolid.shared.general 2
gmane.linux.bios.flashrom 2
jobs.misc.jobs 2
TOTAL: 46 169

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3062
EMP (phn nph) 133
EMP (phn path) 75
Too many newsgroups 73
User-issued cancel 22
EMP (phl) 16
Binary: non-binary distribution 9
User-issued spam cancel 3
MID-Bot 1
U2 violation - invalid distribution 1
Cancel for rejected article 1
TOTAL: 11 3396

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
bbsnz-out.feed.uzoreto.com 1
TOTAL: 1 1
Including strange strings
news.albasani.net 117
news.redatomik.org 66
bbs-nz-out.news.weretis.net 43
news.swapon.de 39
news-out.aioe.org 8
bbsnz-out.feed.uzoreto.com 4
news.snarked.org 1
TOTAL: 7 278
TOTAL: 2 279

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 16772 675 16077 17 3 0 4%23:59:44
2utnut 17898 484 17207 206 1 0 2%23:59:39
3csiph.com 528445 289 17486 5 0 503937 0%23:59:50
4awxg 17489 38 17442 2 1 0 0%23:59:50
5newsfeed.xs3.de 17610 2 17577 2 0 253 0%23:59:49
6news.snarked.org 15302 1 15276 0 0 0 0%23:59:46
7news.samoylyk.net 16741 1 16718 0 0 10 0%23:59:50
8news.redatomik.org 16564 1 16539 0 0 60 0%23:59:50
9aioe 15112 1 15020 0 0 1 0%23:59:50
10news.freedyn.net 17320 1 17313 0 0 1 0%23:59:50
11weretis.net 13139 1 13135 0 0 0 0%23:59:50
12eternal-september 15295 1 15276 0 0 1 0%23:59:48
13i2pn.org 17441 1 17437 0 0 3 0%23:59:49
14neva.ru 16796 1 16774 0 0 1 0%23:59:50
15news.uzoreto.com 14212 0 14210 1 0 1 0%23:59:44
16news.albasani.net 18966 0 16049 660 1 6189 0%23:59:29
17news-in.mixmin.net 2764 0 0 0 0 20120 0%23:59:50
18mb-net.net 0 0 0 0 0 17893 0%23:59:50
19news.swapon.de 1396 0 1396 0 0 0 0%23:30:10
TOTAL: 19 779262 1497 260932 893 6 548470 0%455:26:18
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1utnut10.5 MB957.7 KB11.4 MB0.1 KB/s16.9 KB23:59:39
2news.ausics.org9.4 MB55.4 KB9.5 MB0.1 KB/s14.0 KB23:59:44
3csiph.com1.0 MB43.9 KB1.0 MB0.0 KB/s3.6 KB23:59:50
4awxg96.2 KB2.7 KB98.9 KB0.0 KB/s2.5 KB23:59:50
5newsfeed.xs3.de3.1 KB37.0 KB40.1 KB0.0 KB/s10.0 KB23:59:49
6news.snarked.org1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:59:46
7news.samoylyk.net1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:59:50
8news.redatomik.org1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:59:50
9aioe1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:59:50
10news.freedyn.net1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:59:50
11weretis.net1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:59:50
12eternal-september1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:59:48
13i2pn.org1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:59:49
14neva.ru1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:59:50
15news.uzoreto.com0.0 KB1.6 KB1.6 KB0.0 KB/s1.6 KB23:59:44
16news.albasani.net0.0 KB1.7 MB1.7 MB0.0 KB/s2.6 KB23:59:29
17news-in.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
18mb-net.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
19news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:30:10
TOTAL: 1921.0 MB2.7 MB23.7 MB0.0 KB/s10.2 KB455:26:18
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.nz 386 637122.4 MB 3348 0 800:15:33
2vorlon.activateit.net.au 23 11711.7 MB 157 0 000:05:26
3amigacity.xyz 70 2496.1 MB 100 0 000:32:41
4103.39.238.13 1 5771.8 KB 8 0 000:00:25
5121.99.184.11 10 1368.1 KB 3 1 000:26:02
TOTAL: 5 490 786130.3 MB 3616 1 801:26:45

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.nz 386 637122.4 MB 3348 0 800:15:33
2*.activateit.net.au 23 11711.7 MB 157 0 000:05:26
3*.xyz 70 2496.1 MB 100 0 000:32:41
4unresolved 23 70140.0 KB 11 1 000:33:02
TOTAL: 4 502 786130.3 MB 3616 1 801:26:45

NNRP auth users [Top 20]:

UserConn
amigacity 70
amybbs 24
crash 7
TOTAL: 3 101

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
amigacity.xyz 3.896 0.860 0.19800:32:41
121.99.184.11 0.620 0.084 0.01900:26:02
agency.bbs.nz 27.644 6.544 0.00000:15:33
118.149.243.251 0.404 0.016 0.00000:06:07
vorlon.activateit.net.au 1.384 0.328 0.11400:05:26
103.39.238.13 0.060 0.016 0.00300:00:25
106.75.65.85 0.264 0.028 0.00000:00:17
104.131.146.64 0.048 0.008 0.00000:00:09
? 1.400 0.180 0.00000:00:01
TOTAL: 9 35.720 8.064 0.33401:26:45

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.243.251 6
106.75.65.85 5
104.131.146.64 1
TOTAL: 3 12

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 18
TOTAL: 1 18

NNRP unrecognized commands (by host):

SystemConn
106.75.65.85 4
121.99.184.11 3
TOTAL: 2 7

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP TERMINATOR 3
GET / HTTP/1.0 1
Accept: */* 1
Host: 219.89.83.33:119 1
USER anonymous 1
TOTAL: 5 7

Newsgroup request counts (by category):

CategoryCountPct
1rec 3044 38.7%
2comp 921 11.7%
3sci 795 10.1%
4soc 675 8.6%
5uk 612 7.8%
6alt 574 7.3%
7linux 483 6.1%
8talk 478 6.1%
9misc 138 1.8%
10news 80 1.0%
11nz 23 0.3%
12mozilla 14 0.2%
13microsoft 12 0.2%
14humanities 7 0.1%
15aus 3 0.0%
16agency 2 0.0%
TOTAL: 16 7861100.0%

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

NewsgroupCount
1rec.arts.tv 769
2talk.politics.guns 334
3comp.lang.c 292
4rec.arts.drwho 259
5uk.d-i-y 254
6rec.food.cooking 248
7rec.sport.tennis 248
8alt.comp.os.windows-10 221
9rec.games.miniatures.misc 210
10rec.arts.sf.written 203
11soc.culture.polish 170
12rec.sport.football.college 169
13linux.debian.bugs.dist 169
14rec.boats 161
15sci.electronics.design 160
16linux.debian.changes.devel 131
17sci.physics 128
18talk.origins 121
19alt.conspiracy.jfk 118
20alt.politics.trump 116
21sci.logic 115
22soc.retirement 103
23uk.legal 101
24soc.culture.israel 99
25sci.math 97
26sci.physics.relativity 95
27rec.gambling.poker 92
28comp.lang.c++ 90
29linux.debian.bugs.rc 80
30uk.tech.digital-tv 76
31news.lists.filters 73
32misc.phone.mobile.iphone 71
33uk.legal.moderated 68
34comp.lang.python 67
35rec.bicycles.tech 64
36soc.culture.china 61
37uk.tech.broadcast 59
38rec.music.rock-pop-r+b.1950s 51
39rec.audio.opinion 47
40comp.sys.raspberry-pi 45
41sci.med.cardiology 44
42comp.unix.programmer 38
43rec.photo.digital 38
44rec.aviation.soaring 37
45soc.culture.indian 34
46soc.culture.greek 33
47rec.woodworking 32
48rec.arts.sf.tv 31
49rec.games.pinball 31
50comp.mobile.android 30
51comp.arch 29
52alt.test 29
53misc.survivalism 28
54rec.sport.pro-wrestling 28
55alt.os.linux.ubuntu 27
56comp.os.linux.advocacy 27
57comp.lang.tcl 27
58comp.lang.forth 26
59alt.books.reviews 24
60rec.music.classical.recordings 24
61alt.conspiracy 23
62nz.general 21
63sci.electronics.repair 21
64soc.culture.usa 21
65sci.military.naval 20
66rec.arts.movies.current-films 20
67uk.rec.cycling 19
68comp.sys.apple2 19
69comp.sys.ibm.ps2.hardware 19
70sci.lang 18
71comp.sys.mac.system 18
72rec.music.dylan 18
73sci.bio.paleontology 18
74rec.outdoors.rv-travel 17
75comp.lang.ada 17
76comp.sys.ibm.pc.games.action 17
77linux.debian.devel 16
78soc.culture.german 16
79linux.debian.l10n.french 15
80comp.theory 14
81uk.radio.amateur 14
82comp.os.linux.misc 13
83talk.politics.misc 13
84rec.gambling.lottery 13
85comp.lang.prolog 13
86linux.debian.user.french 12
87rec.games.trivia 12
88rec.sport.golf 12
89rec.sport.cricket 12
90linux.debian.user 12
91sci.anthropology.paleo 11
92comp.lang.fortran 11
93misc.taxes.moderated 11
94linux.debian.devel.release 10
95sci.med.pharmacy 10
96rec.sport.soccer 10
97soc.penpals 10
98sci.astro.amateur 9
99mozilla.support.thunderbird 9
100linux.debian.user.german 9
TOTAL: 332 7861