news.bbs.nz daily logs

Dec 1 23:58:01 -- Dec 2 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 129153 60.8%10.0 MB 38.2%
innd 34320 16.2%5.5 MB 21.0%
innfeed 25905 12.2%5.2 MB 19.8%
inn 21507 10.1%5.3 MB 20.0%
cnfsstat 1440 0.7%253.4 KB 0.9%
controlchan 5 0.0%0.8 KB 0.0%
TOTAL: 6 212330 100.0%26.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 274609 49.1%
Negative hits 262776 47.0%
Do not exist 20486 3.7%
Cache misses 848 0.2%
TOTAL: 4 558719 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.683 0.0%332 0.000 2.057 55.000
article cleanup00:00:00.209 0.0%20812 0.000 0.010 0.048
article logging00:00:00.546 0.0%21191 0.000 0.026 0.079
article parse00:00:00.583 0.0%59618 0.000 0.010 0.024
article write00:00:01.740 0.0%16492 0.000 0.106 4.658
artlog/artcncl00:00:00.004 0.0%332 0.000 0.012 0.013
artlog/artparse00:00:00.000 0.0%5 0.000 0.000 0.000
data move00:00:00.537 0.0%510958 0.000 0.001 0.012
hisgrep/artcncl00:00:00.036 0.0%332 0.000 0.108 28.000
hishave/artcncl00:00:00.011 0.0%332 0.000 0.033 9.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.923 0.0%562429 0.002 0.016 0.736
history sync00:00:00.024 0.0%375 0.000 0.064 0.667
history write00:00:07.366 0.0%20609 0.000 0.357 20.524
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:57:47.348 99.8%521857 93.431 165.308 570.849
nntp read00:00:04.642 0.0%524297 0.003 0.009 0.026
overview write00:00:42.500 0.0%16492 0.056 2.577 67.036
perl filter00:00:37.865 0.0%20596 1.067 1.838 6.480
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.811 0.0%32985 0.000 0.025 0.115
TOTAL: 24:00:58.73723:59:33.828 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.036 0.0%21487 0.000 0.002 0.023
article prepare00:00:00.002 0.0%10700 0.000 0.000 0.014
article read00:00:00.188 0.0%2162 0.000 0.087 6.700
backlog stats00:00:00.573 0.0%297244 0.000 0.002 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.467 0.0%249257 0.003 0.010 0.018
data write00:00:02.964 0.0%240681 0.005 0.012 0.025
idle23:53:07.203 99.9%297244 148.203 289.282 795.220
status file00:00:50.443 0.1%1838 0.400 27.445 1216.158
TOTAL: 23:54:20.80423:54:03.876100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle17:57:09.318 98.9%135860 0.000 475.705 95036.641
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.028 0.0%285031 0.000 0.007 1.000
readart00:00:01.140 0.0%6630 0.000 0.172 7.000
TOTAL: 18:08:46.89817:57:12.486 98.9%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de001011
TOTAL001011

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.snarked.org 33 19715 3289 14439 1987 16%33:08:37
2bbs-nz-out.news.weretis.net 6 33914 3156 30528 230 9%48:02:40
3bbsnz-out.feed.uzoreto.com 867 27318 2414 24183 721 8%71:19:54
4feeder.eternal-september.org 5 18712 1977 16639 96 10%24:34:26
5news-out.aioe.org 3 16736 1564 15151 21 9%25:01:16
6news.albasani.net 1 17571 1178 15970 423 6%23:49:42
7news.redatomik.org 3 15469 833 14583 53 5%23:26:04
8news-out.mixmin.net 2 17745 759 16554 432 4%23:41:10
9newsfeed.neva.ru 154 14762 655 13903 204 4%71:58:30
10news.swapon.de 11 1222 216 1006 0 17%22:28:49
11csiph.com 1 15152 133 15011 8 0%22:06:02
12agency-nz-out.news.mb-net.net 4 17182 62 17094 26 0%23:45:08
13news.samoylyk.net 2 12817 52 12752 13 0%17:29:24
14news.freedyn.net 2 17727 29 17678 20 0%24:11:04
15news.ausics.org 1 246029 11 246011 7 0%383:59:03
16feeder.awxg.com 2 13983 10 13965 8 0%23:59:42
17localhost 9 8 8 0 0 100%00:00:00
18nut.utcc.utoronto.ca 1 15860 0 15860 0 0%23:50:00
TOTAL: 18 1107 521922 16346 501327 4249 3%886:51:31
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1bbs-nz-out.news.weretis.net14.1 MB208.3 KB770.8 KB15.0 MB 93%4.5 KB
2news.snarked.org11.4 MB95.7 KB15.7 MB27.2 MB 41%5.3 KB
3bbsnz-out.feed.uzoreto.com7.0 MB75.6 KB2.3 MB9.4 MB 74%3.1 KB
4feeder.eternal-september.org5.5 MB152.7 KB308.5 KB6.0 MB 92%3.0 KB
5news-out.aioe.org4.0 MB32.4 KB107.1 KB4.2 MB 96%2.7 KB
6news.albasani.net4.0 MB156.8 KB822.1 KB4.9 MB 80%3.2 KB
7news.redatomik.org2.6 MB91.3 KB193.9 KB2.9 MB 90%3.3 KB
8news-out.mixmin.net2.6 MB25.6 KB762.1 KB3.3 MB 77%2.9 KB
9newsfeed.neva.ru1.6 MB83.0 KB3.2 MB4.9 MB 33%5.8 KB
10news.swapon.de563.3 KB0.0 KB0.0 KB563.3 KB100%2.6 KB
11csiph.com319.7 KB14.9 KB14.9 KB349.5 KB 91%2.5 KB
12agency-nz-out.news.mb-net.net182.7 KB92.8 KB117.7 KB393.2 KB 46%4.5 KB
13news.samoylyk.net151.8 KB28.3 KB28.3 KB208.5 KB 72%3.2 KB
14news.freedyn.net91.1 KB20.9 KB350.7 KB462.6 KB 19%9.4 KB
15feeder.awxg.com27.3 KB22.3 KB22.3 KB71.9 KB 37%4.0 KB
16news.ausics.org20.8 KB16.8 KB19.3 KB56.9 KB 36%3.2 KB
17localhost11.0 KB0.0 KB0.0 KB11.0 KB100%1.4 KB
18nut.utcc.utoronto.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1854.1 MB1.1 MB24.6 MB79.9 MB 67%4.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 1 23:58:01 - 23:59:59 38 0.2% 0.3265.8 KB 0.1% 0.55
Dec 2 00:00:00 - 00:59:59 714 4.3% 0.202.0 MB 3.8% 0.58
Dec 2 01:00:00 - 01:59:59 787 4.8% 0.223.4 MB 6.3% 0.97
Dec 2 02:00:00 - 02:59:59 776 4.7% 0.221.9 MB 3.5% 0.54
Dec 2 03:00:00 - 03:59:59 782 4.7% 0.222.4 MB 4.4% 0.68
Dec 2 04:00:00 - 04:59:59 875 5.3% 0.242.4 MB 4.4% 0.68
Dec 2 05:00:00 - 05:59:59 991 6.0% 0.283.6 MB 6.6% 1.01
Dec 2 06:00:00 - 06:59:59 997 6.1% 0.282.8 MB 5.2% 0.80
Dec 2 07:00:00 - 07:59:59 936 5.7% 0.262.5 MB 4.6% 0.70
Dec 2 08:00:00 - 08:59:59 945 5.7% 0.263.1 MB 5.8% 0.88
Dec 2 09:00:00 - 09:59:59 820 5.0% 0.232.3 MB 4.2% 0.64
Dec 2 10:00:00 - 10:59:59 881 5.3% 0.242.6 MB 4.9% 0.75
Dec 2 11:00:00 - 11:59:59 797 4.8% 0.223.1 MB 5.7% 0.88
Dec 2 12:00:00 - 12:59:59 717 4.4% 0.202.0 MB 3.7% 0.57
Dec 2 13:00:00 - 13:59:59 501 3.0% 0.141.6 MB 2.9% 0.44
Dec 2 14:00:00 - 14:59:59 412 2.5% 0.111.8 MB 3.4% 0.52
Dec 2 15:00:00 - 15:59:59 481 2.9% 0.131.3 MB 2.5% 0.38
Dec 2 16:00:00 - 16:59:59 348 2.1% 0.101.5 MB 2.7% 0.41
Dec 2 17:00:00 - 17:59:59 317 1.9% 0.091.5 MB 2.8% 0.43
Dec 2 18:00:00 - 18:59:59 637 3.9% 0.183.3 MB 6.1% 0.94
Dec 2 19:00:00 - 19:59:59 288 1.7% 0.081.2 MB 2.2% 0.35
Dec 2 20:00:00 - 20:59:59 413 2.5% 0.111.8 MB 3.4% 0.52
Dec 2 21:00:00 - 21:59:59 607 3.7% 0.172.2 MB 4.0% 0.61
Dec 2 22:00:00 - 22:59:59 669 4.1% 0.191.8 MB 3.4% 0.53
Dec 2 23:00:00 - 23:58:01 747 4.5% 0.211.9 MB 3.4% 0.55
TOTAL: 24:00:00 16476 100.0% 0.1954.0 MB 100.0% 0.64
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB30.1 GB 60.3% 0 0.26 2330.76
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB34.6 GB 69.2% 0 0.27 2289.45
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB369.9 MB 0.7% 0 0.0235555.56
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 GB30.5 GB 61.1% 0 0.55 1096.67
TOTAL: 10-500.0 GB95.7 GB 19.1% 1 0.36-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 2058 1 0 17 0 0 0 0 2040
2bbsnz-out.feed.uzoreto.com 739 16 0 6 0 0 0 0 717
3news-out.mixmin.net 496 0 0 14 0 0 0 0 482
4news.albasani.net 473 1 0 13 0 0 0 0 459
5bbs-nz-out.news.weretis.net 342 26 0 43 0 0 0 0 273
6newsfeed.neva.ru 214 1 0 10 0 0 0 0 203
7feeder.eternal-september.org 101 0 0 14 0 0 0 0 87
8news.redatomik.org 90 0 0 14 0 0 0 0 76
9news.swapon.de 71 0 0 0 0 0 0 0 71
10agency-nz-out.news.mb-net.net 28 1 0 17 0 0 0 0 10
11news-out.aioe.org 24 8 0 10 0 0 0 0 6
12news.freedyn.net 21 0 0 8 0 0 0 0 13
13news.samoylyk.net 20 0 0 20 0 0 0 0 0
14feeder.awxg.com 12 0 0 12 0 0 0 0 0
15csiph.com 9 0 0 9 0 0 0 0 0
16news.ausics.org 1 0 0 1 0 0 0 0 0
TOTAL: 16 4699 54 0 208 0 0 0 0 4437

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.music.pink-floyd.publius 19
gmane.comp.gis.openstreetmap.tagging 8
mozilla.dev.ui-alerts 4
gmane.linux.alsa.devel 3
mozilla.dev.apps.thunderbird 2
mozilla.support.bugzilla 2
mozilla.test 2
al.christnet.bible 2
biz.marketplace 1
mozilla.support.seamonkey 1
mozilla.dev.platform 1
mozilla.apis 1
sdf.general 1
mozilla.dev.builds 1
demos.local.statistics 1
alt.religion.christian.romman-catholic 1
alt.fan.snuffles 1
1.test 1
mozilla.dev.security.policy 1
south-wales.test 1
TOTAL: 20 54

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3390
EMP (phn path) 333
Binary: misplaced rar 99
EMP (phl) 85
Too many newsgroups 62
EMP (phn nph) 29
Binary: non-binary distribution 21
User-issued cancel 12
Binary: misplaced par2 6
User-issued spam cancel 4
HTML post 3
Binary Image: misplaced jpg 1
TOTAL: 12 4045

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
bbsnz-out.feed.uzoreto.com 6
TOTAL: 1 6
Including strange strings
bbs-nz-out.news.weretis.net 106
news.albasani.net 87
news.swapon.de 70
news.redatomik.org 38
news.snarked.org 24
news-out.mixmin.net 20
bbsnz-out.feed.uzoreto.com 17
newsfeed.neva.ru 10
news-out.aioe.org 5
feeder.eternal-september.org 2
TOTAL: 10 379
TOTAL: 2 385

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 14716 864 13817 35 0 0 5%23:59:48
2utnut 16474 703 15522 249 0 0 4%23:59:49
3news.freedyn.net 16650 97 16336 8 0 496 0%23:59:50
4awxg 16083 46 15999 2 0 0 0%23:59:41
5csiph.com 16580 30 16297 7 0 289 0%23:59:48
6news.redatomik.org 15670 17 15556 0 0 112 0%23:59:50
7news.snarked.org 13116 9 13037 9 0 74 0%23:59:50
8aioe 13890 9 13801 0 0 8 0%23:59:41
9weretis.net 12873 8 12749 1 0 3 0%23:59:50
10news-in.mixmin.net 15372 8 15360 0 0 0 0%23:59:50
11mb-net.net 16434 8 16404 0 0 13 0%23:59:50
12eternal-september 14197 8 14166 0 0 0 0%23:59:48
13news.samoylyk.net 14690 8 14615 0 0 40 0%23:59:50
14neva.ru 15513 7 15500 0 0 0 0%23:59:50
15news.albasani.net 16192 1 14704 483 0 4108 0%23:59:50
16news.uzoreto.com 13929 0 13921 8 0 0 0%23:59:46
17news.swapon.de 1306 0 1304 0 0 0 0%23:32:01
TOTAL: 17 243685 1823 239088 802 0 5143 0%407:28:52
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1utnut7.5 MB895.4 KB8.4 MB0.1 KB/s9.0 KB23:59:49
2news.ausics.org5.7 MB148.0 KB5.9 MB0.1 KB/s6.7 KB23:59:48
3news.freedyn.net369.7 KB29.9 KB399.5 KB0.0 KB/s3.8 KB23:59:50
4csiph.com139.7 KB96.0 KB235.7 KB0.0 KB/s6.4 KB23:59:48
5awxg138.5 KB5.4 KB143.9 KB0.0 KB/s3.0 KB23:59:41
6news.redatomik.org34.0 KB0.0 KB34.0 KB0.0 KB/s2.0 KB23:59:50
7aioe13.7 KB0.0 KB13.7 KB0.0 KB/s1.5 KB23:59:41
8news.snarked.org12.3 KB22.5 KB34.8 KB0.0 KB/s1.9 KB23:59:50
9weretis.net11.0 KB3.1 KB14.2 KB0.0 KB/s1.6 KB23:59:50
10news-in.mixmin.net11.0 KB0.0 KB11.0 KB0.0 KB/s1.4 KB23:59:50
11mb-net.net11.0 KB0.0 KB11.0 KB0.0 KB/s1.4 KB23:59:50
12eternal-september11.0 KB0.0 KB11.0 KB0.0 KB/s1.4 KB23:59:48
13news.samoylyk.net11.0 KB0.0 KB11.0 KB0.0 KB/s1.4 KB23:59:50
14neva.ru10.9 KB0.0 KB10.9 KB0.0 KB/s1.6 KB23:59:50
15news.albasani.net1.1 KB1.7 MB1.7 MB0.0 KB/s3.6 KB23:59:50
16news.uzoreto.com0.0 KB11.0 KB11.0 KB0.0 KB/s1.4 KB23:59:46
17news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:32:01
TOTAL: 1714.0 MB2.9 MB16.8 MB0.0 KB/s6.6 KB407:28:52
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.nz 388 520119.4 MB 3072 8 000:16:04
2vorlon.activateit.net.au 24 721956.9 KB 115 0 000:04:16
3amigacity.xyz 71 192401.5 KB 107 0 000:29:30
4160-3-236-103.cpe.cableone.net 3 36.8 KB 3 0 017:08:42
TOTAL: 4 486 611720.7 MB 3377 8 018:08:39

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.nz 388 520119.4 MB 3072 8 000:16:04
2*.activateit.net.au 24 721956.9 KB 115 0 000:04:16
3*.xyz 71 192401.5 KB 107 0 000:29:30
4*.cpe.cableone.net 3 36.8 KB 3 0 017:08:42
5unresolved 10 00.0 KB 0 0 000:10:03
TOTAL: 5 496 611720.7 MB 3377 8 018:08:39

NNRP auth users [Top 20]:

UserConn
amigacity 71
amybbs 24
malcolmlewis 3
TOTAL: 3 98

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
160-3-236-103.cpe.cableone.net 0.204 0.072 0.00917:08:42
amigacity.xyz 4.472 0.912 0.25100:29:30
agency.bbs.nz 29.592 7.168 0.00000:16:04
118.149.162.210 0.672 0.072 0.00000:10:03
vorlon.activateit.net.au 1.504 0.460 0.06400:04:16
? 1.800 0.216 0.00000:00:02
TOTAL: 6 38.244 8.900 0.32418:08:39

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.162.210 10
TOTAL: 1 10

NNRP gethostbyaddr failures [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1alt 187409 97.0%
2rec 2218 1.1%
3soc 667 0.3%
4sci 659 0.3%
5linux 604 0.3%
6comp 600 0.3%
7uk 491 0.3%
8talk 254 0.1%
9misc 154 0.1%
10microsoft 26 0.0%
11mozilla 17 0.0%
12nz 12 0.0%
13news 9 0.0%
14humanities 8 0.0%
15aus 2 0.0%
TOTAL: 15 193130100.0%

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

NewsgroupCount
1alt.usenet.kooks 175618
2alt.usenet.offline-reader.forte-agent 5969
3alt.war 1863
4alt.video.digital-tv 549
5alt.vietnam.veterans 468
6rec.arts.tv 436
7alt.visa.us 348
8rec.arts.drwho 268
9alt.war.civil.usa 260
10alt.video.tape-trading 217
11alt.vacation.las-vegas 210
12comp.os.linux.advocacy 208
13uk.d-i-y 197
14linux.debian.bugs.dist 193
15alt.comp.os.windows-10 180
16rec.food.cooking 168
17rec.arts.sf.written 163
18talk.politics.guns 162
19alt.war.terrorism 155
20alt.war.nuclear 144
21rec.games.miniatures.misc 142
22linux.debian.changes.devel 141
23linux.debian.bugs.rc 135
24uk.legal 131
25soc.retirement 126
26soc.culture.polish 124
27sci.electronics.design 122
28alt.politics.trump 118
29rec.sport.football.college 116
30rec.sport.tennis 111
31rec.sport.pro-wrestling 106
32alt.usenet.offline-reader.forte-agent.modified 100
33alt.walmart 97
34sci.physics 91
35sci.physics.relativity 85
36alt.vampyres 82
37soc.history.living 77
38alt.video.dvd 74
39alt.war.iraq 73
40alt.visa.us.marriage-based 72
41alt.usenet.legends.lester-mosley 71
42rec.boats 67
43sci.med.cardiology 60
44rec.gambling.poker 59
45alt.video.laserdisc 59
46alt.usenet.offline-reader.xananews 58
47talk.origins 57
48alt.usenet.legends 55
49rec.bicycles.tech 54
50comp.lang.c 49
51comp.lang.python 49
52sci.military.naval 48
53misc.survivalism 48
54sci.logic 47
55alt.usenet.kooks.gregory-hall 47
56sci.math 46
57sci.math.num-analysis 44
58rec.games.backgammon 42
59uk.radio.amateur 41
60soc.culture.israel 39
61soc.culture.indian 39
62soc.culture.china 38
63alt.usenet.newbies 37
64rec.music.rock-pop-r+b.1950s 36
65uk.legal.moderated 36
66alt.wankers.malicious 35
67comp.sys.mac.system 35
68comp.lang.prolog 35
69uk.tech.digital-tv 34
70alt.video.dvd.authoring 33
71sci.lang 33
72misc.health.diabetes 33
73alt.videos.bootlegs 32
74alt.os.linux.ubuntu 31
75comp.mobile.android 31
76rec.sport.golf 30
77uk.rec.cycling 27
78rec.arts.tv.uk.coronation-st 25
79soc.genealogy.medieval 25
80linux.debian.user 24
81misc.immigration.usa 24
82rec.games.pinball 24
83microsoft.public.windowsxp.general 22
84rec.music.beatles 22
85rec.arts.sf.fandom 21
86rec.outdoors.rv-travel 21
87comp.os.vms 20
88alt.vacation.rentals 20
89alt.usenet.peering 20
90alt.test 19
91alt.visa.australia 18
92rec.music.classical.recordings 18
93talk.bizarre 18
94rec.photo.digital 17
95alt.video.ptv.replaytv 17
96soc.culture.brazil 16
97sci.astro.amateur 16
98alt.usenet.news-server-comparison 16
99misc.news.internet.discuss 16
100comp.ai.philosophy 16
TOTAL: 388 193130