news.bbs.nz daily logs

Sep 28 23:58:01 -- Sep 29 23:58:02

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 135017 63.0%10.6 MB 40.7%
innd 31760 14.8%5.1 MB 19.5%
innfeed 24624 11.5%4.9 MB 18.9%
inn 21471 10.0%5.2 MB 19.9%
cnfsstat 1440 0.7%243.0 KB 0.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 214313 100.0%26.1 MB100.0%

History cache:

ReasonCount%Count
Negative hits 275633 49.7%
Positive hits 258454 46.6%
Do not exist 20814 3.8%
Cache misses 131 0.0%
TOTAL: 4 555032 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.087 0.0%44 0.000 1.977 21.000
article cleanup00:00:00.203 0.0%21063 0.000 0.010 0.050
article logging00:00:00.534 0.0%21428 0.000 0.025 0.079
article parse00:00:00.613 0.0%61986 0.000 0.010 0.046
article write00:00:02.152 0.0%16816 0.000 0.128 6.583
artlog/artcncl00:00:00.000 0.0%44 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.513 0.0%539564 0.000 0.001 0.008
hisgrep/artcncl00:00:00.001 0.0%43 0.000 0.023 1.000
hishave/artcncl00:00:00.000 0.0%44 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.204 0.0%560462 0.001 0.018 0.934
history sync00:00:00.030 0.0%364 0.000 0.082 0.500
history write00:00:06.401 0.0%21001 0.000 0.305 19.603
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:57:26.293 99.8%544832 87.918 158.299 512.211
nntp read00:00:04.778 0.0%550230 0.004 0.009 0.025
overview write00:00:40.422 0.0%16816 0.050 2.404 90.733
perl filter00:00:37.778 0.0%21000 1.000 1.799 16.353
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.219 0.0%33632 0.000 0.036 0.163
TOTAL: 24:00:50.97123:59:11.228 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.076 0.0%22723 0.000 0.003 0.034
article prepare00:00:00.001 0.0%6740 0.000 0.000 0.015
article read00:00:00.147 0.0%1573 0.000 0.093 5.000
backlog stats00:00:00.608 0.0%276447 0.000 0.002 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.413 0.0%240894 0.003 0.010 0.019
data write00:00:02.835 0.0%227227 0.006 0.012 0.023
idle23:54:55.491100.0%276447 186.343 311.436 891.314
status file00:00:10.222 0.0%1847 0.400 5.534 617.833
TOTAL: 23:55:29.65223:55:11.793100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.001 0.0%5 0.000 0.200 1.000
idle13:24:15.447 98.7%133007 0.000 362.804 75323.750
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.308 0.0%283245 0.000 0.008 0.333
readart00:00:04.174 0.0%8584 0.000 0.486 31.000
TOTAL: 13:35:05.31413:24:21.930 98.7%----

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1bbs-nz-out.news.weretis.net 4 34585 3477 30804 304 10%46:58:36
2news.snarked.org 30 20298 2953 15463 1882 14%30:45:49
3bbsnz-out.feed.uzoreto.com 576 24638 2464 21362 812 10%47:28:55
4news.albasani.net 1 16292 2104 13722 466 12%21:54:21
5feeder.eternal-september.org 1 18931 1761 17058 112 9%23:10:55
6news-out.mixmin.net 2 19925 1022 18620 283 5%25:36:27
7news-out.aioe.org 2 22314 902 21386 26 4%32:52:36
8news.redatomik.org 3 16614 890 15702 22 5%24:08:49
9newsfeed.neva.ru 144 12469 628 11661 180 5%71:52:52
10csiph.com 1 16546 141 16403 2 0%21:37:39
11news.swapon.de 4 1457 94 1363 0 6%24:55:46
12agency-nz-out.news.mb-net.net 5 19891 70 19807 14 0%24:52:17
13news.freedyn.net 1 15211 50 15143 18 0%17:48:10
14news.samoylyk.net 2 17381 43 17337 1 0%23:59:52
15feeder.awxg.com 1 15132 10 15120 2 0%23:59:57
16localhost 6 5 5 0 0 100%00:00:00
17news.ausics.org 2 24345 3 24340 2 0%37:01:07
TOTAL: 17 785 296034 16617 275291 4126 5%499:04:08
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1bbs-nz-out.news.weretis.net19.1 MB26.0 KB1.3 MB20.4 MB 93%5.5 KB
2news.snarked.org9.1 MB1.4 KB16.4 MB25.6 MB 35%5.4 KB
3bbsnz-out.feed.uzoreto.com7.0 MB2.5 KB6.3 MB13.3 MB 52%4.2 KB
4news.albasani.net6.5 MB9.5 KB888.0 KB7.4 MB 88%2.9 KB
5feeder.eternal-september.org4.5 MB8.3 KB870.3 KB5.4 MB 84%2.9 KB
6news-out.mixmin.net3.1 MB11.2 KB746.1 KB3.9 MB 80%3.0 KB
7news.redatomik.org2.8 MB5.4 KB34.5 KB2.9 MB 98%3.2 KB
8news-out.aioe.org2.5 MB33.3 KB220.5 KB2.7 MB 90%3.0 KB
9newsfeed.neva.ru1.7 MB28.0 KB1.1 MB2.8 MB 60%3.6 KB
10csiph.com358.5 KB4.3 KB5.7 KB368.6 KB 97%2.6 KB
11news.swapon.de229.3 KB0.0 KB0.0 KB229.3 KB100%2.4 KB
12agency-nz-out.news.mb-net.net225.1 KB38.3 KB62.1 KB325.5 KB 69%3.9 KB
13news.freedyn.net153.7 KB113.9 KB178.4 KB446.1 KB 34%6.6 KB
14news.samoylyk.net93.9 KB4.4 KB4.4 KB102.6 KB 91%2.3 KB
15feeder.awxg.com34.2 KB8.3 KB8.3 KB50.8 KB 67%4.2 KB
16news.ausics.org20.8 KB0.0 KB8.4 KB29.2 KB 71%5.8 KB
17localhost10.3 KB0.0 KB0.0 KB10.3 KB100%2.1 KB
TOTAL: 1757.5 MB294.6 KB28.1 MB85.9 MB 66%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 28 23:58:01 - 23:59:59 24 0.1% 0.2054.8 KB 0.1% 0.46
Sep 29 00:00:00 - 00:59:59 880 5.2% 0.242.4 MB 4.2% 0.68
Sep 29 01:00:00 - 01:59:59 913 5.4% 0.253.7 MB 6.4% 1.05
Sep 29 02:00:00 - 02:59:59 959 5.7% 0.272.4 MB 4.2% 0.69
Sep 29 03:00:00 - 03:59:59 922 5.5% 0.263.0 MB 5.1% 0.84
Sep 29 04:00:00 - 04:59:59 1053 6.3% 0.294.5 MB 7.8% 1.27
Sep 29 05:00:00 - 05:59:59 1012 6.0% 0.283.3 MB 5.7% 0.94
Sep 29 06:00:00 - 06:59:59 1004 6.0% 0.283.9 MB 6.8% 1.11
Sep 29 07:00:00 - 07:59:59 958 5.7% 0.273.4 MB 5.8% 0.96
Sep 29 08:00:00 - 08:59:59 869 5.2% 0.243.2 MB 5.6% 0.91
Sep 29 09:00:00 - 09:59:59 717 4.3% 0.202.4 MB 4.2% 0.68
Sep 29 10:00:00 - 10:59:59 610 3.6% 0.172.1 MB 3.7% 0.60
Sep 29 11:00:00 - 11:59:59 492 2.9% 0.141.5 MB 2.7% 0.43
Sep 29 12:00:00 - 12:59:59 461 2.7% 0.132.0 MB 3.5% 0.58
Sep 29 13:00:00 - 13:59:59 417 2.5% 0.121.8 MB 3.1% 0.51
Sep 29 14:00:00 - 14:59:59 389 2.3% 0.111.3 MB 2.2% 0.36
Sep 29 15:00:00 - 15:59:59 395 2.3% 0.111.2 MB 2.2% 0.35
Sep 29 16:00:00 - 16:59:59 297 1.8% 0.081.2 MB 2.0% 0.33
Sep 29 17:00:00 - 17:59:59 386 2.3% 0.111.1 MB 1.9% 0.31
Sep 29 18:00:00 - 18:59:59 587 3.5% 0.162.5 MB 4.4% 0.72
Sep 29 19:00:00 - 19:59:59 585 3.5% 0.161.7 MB 2.9% 0.47
Sep 29 20:00:00 - 20:59:59 764 4.5% 0.212.8 MB 4.9% 0.80
Sep 29 21:00:00 - 21:59:59 715 4.3% 0.202.1 MB 3.7% 0.61
Sep 29 22:00:00 - 22:59:59 709 4.2% 0.201.9 MB 3.3% 0.54
Sep 29 23:00:00 - 23:58:02 691 4.1% 0.202.1 MB 3.7% 0.62
TOTAL: 24:00:01 16809 100.0% 0.1957.6 MB 100.0% 0.68
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB28.8 GB 57.5% 0 0.30 1991.91
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB33.1 GB 66.3% 0 0.34 1801.25
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB363.0 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 GB27.6 GB 55.2% 0 0.53 1141.19
TOTAL: 10-500.0 GB89.9 GB 18.0% 1 0.38-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 1909 7 0 3 0 0 0 0 1899
2bbsnz-out.feed.uzoreto.com 833 23 0 1 0 0 0 0 809
3news.albasani.net 731 1 0 4 0 0 0 0 726
4bbs-nz-out.news.weretis.net 357 87 0 9 0 0 0 0 261
5news-out.mixmin.net 352 0 0 7 0 0 0 0 345
6newsfeed.neva.ru 180 8 0 4 0 0 0 0 168
7feeder.eternal-september.org 116 0 0 5 0 0 0 0 111
8news.redatomik.org 41 0 0 4 0 0 0 0 37
9news.freedyn.net 29 1 0 4 0 0 0 0 24
10news-out.aioe.org 25 16 0 5 0 0 0 0 4
11news.swapon.de 20 0 0 0 0 0 0 0 20
12agency-nz-out.news.mb-net.net 15 2 0 7 0 0 0 0 6
13feeder.awxg.com 4 0 0 4 0 0 0 0 0
14csiph.com 3 0 0 3 0 0 0 0 0
15news.samoylyk.net 2 0 0 2 0 0 0 0 0
TOTAL: 15 4617 145 0 62 0 0 0 0 4410

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.music.pink-floyd.publius 36
gmane.comp.gis.openstreetmap.tagging 23
gmane.linux.alsa.devel 10
mozilla.dev.ui-alerts 9
mozilla.dev.security.policy 8
alt.digicash.deadbeats 6
fido7.ru.android 6
mozilla.dev.platform 6
al.christnet.bible 3
eagle.support.ger 3
mozilla.support.seamonkey 3
it.annunci.varie 3
gmane.linux.bios.flashrom 2
alt.penthouse.sex.femdom 2
it.annunci.commerciali 2
demos.local.lists.cgp-russian 2
nemo.abuse 2
mozilla.dev.l10n 2
mozilla.test 1
ia.jobs 1
TOTAL: 35 145

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3355
EMP (phn path) 378
EMP (phl) 107
User-issued cancel 69
Binary Image: misplaced jpg 36
Binary: misplaced mp4 33
Too many newsgroups 29
Binary: misplaced par2 9
Binary: non-binary distribution 7
EMP (phn nph) 5
User-issued spam cancel 5
Binary: misplaced 004 3
Binary: misplaced 002 3
Binary: misplaced rar 1
Binary: misplaced obj 1
Too many newsgroups (low_xpost) 1
HTML post 1
Binary: misplaced 007 1
TOTAL: 18 4044

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.albasani.net 232
bbs-nz-out.news.weretis.net 42
news-out.mixmin.net 26
bbsnz-out.feed.uzoreto.com 21
news.swapon.de 20
news.redatomik.org 18
news-out.aioe.org 4
news.snarked.org 2
TOTAL: 8 365
TOTAL: 1 365

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 14959 631 14304 24 0 50 4%23:59:49
2awxg 16259 36 16212 4 0 0 0%23:59:27
3aioe 14244 10 14164 0 0 0 0%23:59:49
4csiph.com 16852 8 16684 0 0 0 0%23:59:49
5news.freedyn.net 16800 7 16757 0 0 0 0%23:59:49
6news.snarked.org 13832 5 13761 2 0 15 0%23:59:49
7news-in.mixmin.net 15230 5 15214 0 0 0 0%23:59:49
8weretis.net 12875 5 12865 0 0 0 0%23:59:45
9news.samoylyk.net 14969 5 14930 0 0 7 0%23:59:45
10news.redatomik.org 15906 5 15883 0 0 5 0%23:59:45
11mb-net.net 16810 4 16725 0 0 211 0%23:59:49
12neva.ru 15856 4 15845 1 0 0 0%23:59:49
13eternal-september 14715 4 14622 0 0 171 0%23:59:49
14news.swapon.de 1450 3 1427 0 0 19 0%23:50:49
15news.albasani.net 16273 2 13759 825 0 5398 0%23:59:48
16news.uzoreto.com 14244 1 14219 3 0 88 0%23:59:48
TOTAL: 16 231274 735 227371 859 0 5964 0%383:47:28
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org9.7 MB323.6 KB10.0 MB0.1 KB/s15.7 KB23:59:49
2awxg83.2 KB22.9 KB106.0 KB0.0 KB/s2.7 KB23:59:27
3aioe27.1 KB0.0 KB27.1 KB0.0 KB/s2.7 KB23:59:49
4csiph.com17.2 KB0.0 KB17.2 KB0.0 KB/s2.2 KB23:59:49
5news.freedyn.net14.9 KB0.0 KB14.9 KB0.0 KB/s2.1 KB23:59:49
6news.snarked.org10.3 KB9.0 KB19.3 KB0.0 KB/s2.8 KB23:59:49
7news-in.mixmin.net10.3 KB0.0 KB10.3 KB0.0 KB/s2.1 KB23:59:49
8weretis.net10.3 KB0.0 KB10.3 KB0.0 KB/s2.1 KB23:59:45
9news.samoylyk.net10.3 KB0.0 KB10.3 KB0.0 KB/s2.1 KB23:59:45
10news.redatomik.org10.3 KB0.0 KB10.3 KB0.0 KB/s2.1 KB23:59:45
11news.swapon.de6.4 KB0.0 KB6.4 KB0.0 KB/s2.1 KB23:50:49
12neva.ru5.9 KB4.4 KB10.3 KB0.0 KB/s2.1 KB23:59:49
13eternal-september5.9 KB0.0 KB5.9 KB0.0 KB/s1.5 KB23:59:49
14mb-net.net5.8 KB0.0 KB5.8 KB0.0 KB/s1.5 KB23:59:49
15news.albasani.net2.8 KB4.1 MB4.1 MB0.0 KB/s5.0 KB23:59:48
16news.uzoreto.com1.1 KB7.3 KB8.4 KB0.0 KB/s2.1 KB23:59:48
TOTAL: 169.9 MB4.4 MB14.4 MB0.0 KB/s9.2 KB383:47:28
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.nz 388 565719.7 MB 3190 4 000:15:29
2192.168.1.9 1 9485.0 MB 18 0 000:00:46
3vorlon.activateit.net.au 24 9041.3 MB 124 0 000:04:39
4160-3-245-118.cpe.cableone.net 2 88534.3 KB 2 0 012:30:46
560.234.68.122 6 1229.9 KB 1 1 000:22:51
6amigacity.xyz 71 1127.2 KB 9 0 28400:12:03
TOTAL: 6 492 762026.5 MB 3883 5 28413:34:57

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.nz 388 565719.7 MB 3190 4 000:15:29
2unresolved 19 9605.0 MB 19 1 000:31:57
3*.activateit.net.au 24 9041.3 MB 124 0 000:04:39
4*.cpe.cableone.net 2 88534.3 KB 2 0 012:30:46
5*.xyz 71 1127.2 KB 9 0 28400:12:03
TOTAL: 5 504 762026.5 MB 3883 5 28413:34:57

NNRP auth users [Top 20]:

UserConn
amigacity 71
amybbs 24
crash 5
malcolmlewis 2
paulh 1
TOTAL: 5 103

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
160-3-245-118.cpe.cableone.net 0.212 0.064 0.00412:30:46
60.234.68.122 0.424 0.068 0.01500:22:51
agency.bbs.nz 30.992 7.716 0.00000:15:29
amigacity.xyz 4.304 0.828 0.31100:12:03
118.149.233.138 0.724 0.092 0.00000:08:15
vorlon.activateit.net.au 1.640 0.468 0.09400:04:39
192.168.1.9 0.264 0.280 0.00100:00:46
94.166.27.213 0.068 0.004 0.00000:00:03
? 0.992 0.100 0.00000:00:01
TOTAL: 9 39.620 9.620 0.42513:34:57

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.233.138 11
94.166.27.213 1
TOTAL: 2 12

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
60.234.68.122 1
TOTAL: 1 1

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP TERMINATOR 1
TOTAL: 1 1

Newsgroup request counts (by category):

CategoryCountPct
1alt 125229 94.8%
2rec 2881 2.2%
3comp 877 0.7%
4soc 663 0.5%
5uk 598 0.5%
6sci 539 0.4%
7linux 333 0.3%
8news 306 0.2%
9nz 303 0.2%
10talk 255 0.2%
11misc 121 0.1%
12microsoft 31 0.0%
13agency 21 0.0%
14mozilla 3 0.0%
15humanities 3 0.0%
16aus 3 0.0%
TOTAL: 16 132166100.0%

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

NewsgroupCount
1alt.recovery.aa 62063
2alt.quotations 9926
3alt.fan.rush-limbaugh 7100
4alt.home.repair 6943
5alt.radio.talk.dr-laura 4972
6alt.radio.talk 3447
7alt.recovery.catholicism 1740
8alt.politics.uk 1579
9alt.psychology 1516
10alt.messianic 1407
11alt.politics.org.un 1306
12alt.humor.puns 1268
13alt.global-warming 1104
14alt.radio.networks.cbc 848
15alt.religion.angels 795
16alt.politics.republicans 697
17rec.arts.tv 692
18alt.politics.democrats 623
19alt.gossip.celebrities 490
20alt.recovery.addiction.alcoholism 473
21alt.life.sucks 463
22alt.philosophy.zen 441
23alt.os.linux.ubuntu 423
24alt.jobs 421
25alt.radio.digital 400
26alt.politics.economics 398
27alt.guitar.amps 393
28alt.philosophy.taoism 390
29alt.os.linux 384
30alt.os.linux.mint 324
31alt.folklore.computers 321
32alt.pl.praca.oferowana 296
33alt.radio 265
34alt.psychology.personality 265
35uk.d-i-y 264
36rec.arts.sf.written 253
37alt.politics.liberalism 248
38alt.recovery.from-12-steps 242
39alt.privacy.anon-server 242
40alt.os.linux.slackware 239
41rec.sport.pro-wrestling 234
42alt.religion.apparitions 228
43alt.politics.media 221
44alt.rap 208
45rec.food.cooking 207
46alt.happy.valley 206
47alt.prophecies.nostradamus 205
48rec.sport.football.college 203
49alt.nl.zeurdoos 201
50uk.legal 195
51alt.horror 193
52alt.politics.homosexuality 188
53rec.boats 183
54alt.radio.scanner 183
55rec.arts.drwho 177
56alt.free.newsservers 176
57alt.politics.usa.republican 172
58alt.food.fast-food 171
59nz.comp 167
60alt.jobs.jobsearch 163
61comp.os.linux.advocacy 162
62comp.lang.python 161
63alt.radio.broadcasting 149
64alt.obituaries 141
65alt.politics.elections 141
66alt.religion.asatru 139
67alt.politics.obama 135
68alt.politics.socialism 128
69alt.recovery.na 128
70nz.general 126
71rec.sport.tennis 125
72alt.privacy.anon-server.stats 125
73talk.origins 124
74comp.sys.raspberry-pi 119
75alt.rectum.nearly.killed-um 116
76alt.journalism 115
77alt.music.prayforthesoulofbetty 113
78alt.railroad 113
79alt.comp.os.windows-10 112
80alt.france 112
81alt.fan.rush-limbaugh.tv-show 111
82soc.retirement 111
83alt.psychology.jung 110
84sci.physics.relativity 109
85news.software.readers 105
86alt.games.microsoft.flight-sim 103
87alt.bbs.ads 102
88alt.bbs.mystic 101
89rec.drugs.cannabis 100
90talk.politics.guns 100
91alt.politics.clinton 93
92alt.religion.apologetics 92
93linux.debian.changes.devel 92
94linux.debian.bugs.dist 88
95alt.music.makers.soloact 87
96sci.math 85
97soc.culture.china 84
98soc.culture.polish 83
99alt.native 79
100rec.sport.golf 78
TOTAL: 866 132166