news.bbs.nz daily logs

Nov 2 23:58:01 -- Nov 3 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 190796 70.6%15.9 MB 50.8%
innd 32377 12.0%5.2 MB 16.7%
innfeed 24534 9.1%4.7 MB 15.1%
inn 21195 7.8%5.2 MB 16.5%
cnfsstat 1440 0.5%254.1 KB 0.8%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 270343 100.0%31.3 MB100.0%

History cache:

ReasonCount%Count
Negative hits 279561 49.8%
Positive hits 260582 46.5%
Do not exist 20448 3.6%
Cache misses 273 0.0%
TOTAL: 4 560864 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.151 0.0%44 0.000 3.432 29.667
article cleanup00:00:00.209 0.0%20802 0.000 0.010 0.055
article logging00:00:01.498 0.0%21153 0.000 0.071 2.994
article parse00:00:00.646 0.0%61768 0.000 0.010 0.038
article write00:00:01.320 0.0%16870 0.000 0.078 0.540
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.755 0.0%533612 0.000 0.001 0.018
hisgrep/artcncl00:00:00.054 0.0%44 0.000 1.227 17.667
hishave/artcncl00:00:00.001 0.0%44 0.000 0.023 0.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.808 0.0%564863 0.001 0.017 0.941
history sync00:00:00.024 0.0%365 0.000 0.066 0.667
history write00:00:06.030 0.0%20574 0.009 0.293 15.309
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:57:44.095 99.8%544501 91.739 158.428 485.757
nntp read00:00:04.395 0.0%546148 0.003 0.008 0.029
overview write00:00:39.952 0.0%16870 0.032 2.368 65.423
perl filter00:00:40.843 0.0%20574 1.043 1.985 14.117
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.944 0.0%33740 0.004 0.028 0.179
TOTAL: 24:00:55.52823:59:30.725 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.061 0.0%21647 0.000 0.003 0.038
article prepare00:00:00.001 0.0%5276 0.000 0.000 0.036
article read00:00:00.071 0.0%1252 0.000 0.057 1.857
backlog stats00:00:00.474 0.0%293807 0.000 0.002 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.984 0.0%243591 0.001 0.008 0.017
data write00:00:02.818 0.0%230652 0.003 0.012 0.027
idle23:55:18.757100.0%293807 162.219 293.113 717.329
status file00:00:07.518 0.0%1856 0.500 4.051 134.111
TOTAL: 23:55:47.85123:55:31.684100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle25:50:28.497 99.5%155220 0.000 599.333 77060.414
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.252 0.0%475157 0.000 0.009 0.400
readart00:00:18.842 0.0%18943 0.000 0.995 18.000
TOTAL: 25:57:50.56625:50:51.591 99.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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.snarked.org 32 20754 4066 14918 1770 19%35:01:00
2news-out.aioe.org 4 16919 2205 14676 38 13%25:29:40
3feeder.eternal-september.org 3 18325 2190 15956 179 11%23:11:23
4bbsnz-out.feed.uzoreto.com 864 27495 2162 24704 629 7%71:06:51
5bbs-nz-out.news.weretis.net 8 32517 1789 30483 245 5%43:50:24
6news.albasani.net 1 18610 1271 17081 258 6%24:40:37
7newsfeed.neva.ru 143 11014 1012 9742 260 9%71:44:00
8news-out.mixmin.net 1 17995 884 16713 398 4%24:15:42
9news.redatomik.org 2 16776 469 16281 26 2%23:59:42
10news.swapon.de 6 1645 260 1385 0 15%26:47:42
11csiph.com 2 17099 251 16840 8 1%23:59:55
12news.freedyn.net 2 19068 68 18959 41 0%24:07:46
13news.samoylyk.net 1 16950 63 16869 18 0%23:59:57
14agency-nz-out.news.mb-net.net 6 18170 61 18078 31 0%23:01:14
15feeder.awxg.com 1 15473 10 15453 10 0%23:59:56
16localhost 3 2 2 0 0 100%00:00:00
TOTAL: 16 1079 268812 16763 248138 3911 6%489:15:49
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org14.8 MB54.4 KB16.8 MB31.7 MB 46%5.6 KB
2bbsnz-out.feed.uzoreto.com7.1 MB73.7 KB5.7 MB12.9 MB 55%4.7 KB
3feeder.eternal-september.org6.0 MB150.5 KB752.4 KB6.9 MB 87%3.0 KB
4bbs-nz-out.news.weretis.net5.7 MB413.9 KB1.2 MB7.2 MB 78%3.6 KB
5news-out.aioe.org5.3 MB22.1 KB286.8 KB5.6 MB 94%2.6 KB
6news.albasani.net4.7 MB313.8 KB723.5 KB5.7 MB 82%3.8 KB
7news-out.mixmin.net2.6 MB120.1 KB898.3 KB3.6 MB 72%2.9 KB
8news.redatomik.org2.4 MB44.0 KB71.1 KB2.6 MB 95%5.3 KB
9newsfeed.neva.ru2.2 MB31.7 KB1.0 MB3.2 MB 67%2.6 KB
10csiph.com777.5 KB21.0 KB23.3 KB821.8 KB 94%3.2 KB
11news.swapon.de644.7 KB0.0 KB0.0 KB644.7 KB100%2.5 KB
12agency-nz-out.news.mb-net.net294.6 KB144.4 KB212.1 KB651.1 KB 45%7.1 KB
13news.freedyn.net253.0 KB130.9 KB190.1 KB574.0 KB 44%5.3 KB
14news.samoylyk.net204.8 KB269.6 KB274.0 KB748.4 KB 27%9.2 KB
15feeder.awxg.com30.7 KB38.3 KB38.3 KB107.4 KB 28%5.4 KB
16localhost2.8 KB0.0 KB0.0 KB2.8 KB100%1.4 KB
TOTAL: 1653.0 MB1.8 MB28.0 MB82.8 MB 63%4.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 2 23:58:01 - 23:59:59 27 0.2% 0.2369.0 KB 0.1% 0.58
Nov 3 00:00:00 - 00:59:59 876 5.2% 0.243.0 MB 5.6% 0.85
Nov 3 01:00:00 - 01:59:59 777 4.6% 0.222.7 MB 5.0% 0.76
Nov 3 02:00:00 - 02:59:59 882 5.2% 0.242.4 MB 4.4% 0.67
Nov 3 03:00:00 - 03:59:59 863 5.1% 0.242.8 MB 5.3% 0.81
Nov 3 04:00:00 - 04:59:59 859 5.1% 0.242.4 MB 4.5% 0.69
Nov 3 05:00:00 - 05:59:59 967 5.7% 0.273.2 MB 5.9% 0.90
Nov 3 06:00:00 - 06:59:59 1059 6.3% 0.293.2 MB 6.1% 0.92
Nov 3 07:00:00 - 07:59:59 1123 6.7% 0.313.2 MB 6.0% 0.90
Nov 3 08:00:00 - 08:59:59 954 5.7% 0.273.0 MB 5.6% 0.84
Nov 3 09:00:00 - 09:59:59 926 5.5% 0.263.9 MB 7.3% 1.11
Nov 3 10:00:00 - 10:59:59 816 4.8% 0.232.3 MB 4.4% 0.66
Nov 3 11:00:00 - 11:59:59 791 4.7% 0.222.3 MB 4.3% 0.65
Nov 3 12:00:00 - 12:59:59 637 3.8% 0.182.4 MB 4.5% 0.68
Nov 3 13:00:00 - 13:59:59 555 3.3% 0.151.6 MB 2.9% 0.44
Nov 3 14:00:00 - 14:59:59 452 2.7% 0.131.4 MB 2.6% 0.39
Nov 3 15:00:00 - 15:59:59 387 2.3% 0.111.4 MB 2.6% 0.39
Nov 3 16:00:00 - 16:59:59 426 2.5% 0.121.3 MB 2.4% 0.37
Nov 3 17:00:00 - 17:59:59 278 1.6% 0.081.4 MB 2.6% 0.40
Nov 3 18:00:00 - 18:59:59 344 2.0% 0.101.4 MB 2.6% 0.39
Nov 3 19:00:00 - 19:59:59 305 1.8% 0.08865.9 KB 1.6% 0.24
Nov 3 20:00:00 - 20:59:59 467 2.8% 0.131.4 MB 2.6% 0.40
Nov 3 21:00:00 - 21:59:59 573 3.4% 0.161.6 MB 3.0% 0.46
Nov 3 22:00:00 - 22:59:59 759 4.5% 0.212.2 MB 4.2% 0.64
Nov 3 23:00:00 - 23:58:01 753 4.5% 0.222.1 MB 3.9% 0.61
TOTAL: 24:00:00 16856 100.0% 0.2053.3 MB 100.0% 0.63
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB29.5 GB 59.0% 0 0.28 2135.66
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB33.9 GB 67.9% 0 0.32 1873.66
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB367.2 MB 0.7% 0 0.0231111.11
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 GB29.2 GB 58.4% 0 0.49 1241.32
TOTAL: 10-500.0 GB93.0 GB 18.6% 1 0.36-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 1770 7 0 16 0 0 0 0 1747
2bbsnz-out.feed.uzoreto.com 634 12 0 11 0 0 0 0 611
3news-out.mixmin.net 438 0 0 20 0 0 0 0 418
4news.albasani.net 352 0 0 18 0 0 0 0 334
5bbs-nz-out.news.weretis.net 331 67 0 39 0 0 0 0 225
6newsfeed.neva.ru 263 17 0 12 0 0 0 0 234
7feeder.eternal-september.org 195 0 0 19 0 0 0 0 176
8news.swapon.de 93 0 0 0 0 0 0 0 93
9news-out.aioe.org 53 34 0 8 0 0 0 0 11
10news.redatomik.org 51 0 0 16 0 0 0 0 35
11news.freedyn.net 42 2 0 12 0 0 0 0 28
12agency-nz-out.news.mb-net.net 28 7 0 15 0 0 0 0 6
13news.samoylyk.net 20 0 0 19 0 0 0 0 1
14feeder.awxg.com 13 0 0 13 0 0 0 0 0
15csiph.com 11 0 0 10 0 0 0 0 1
TOTAL: 15 4294 146 0 228 0 0 0 0 3920

Unwanted newsgroups [Top 20]:

NewsgroupCount
gmane.comp.gis.openstreetmap.tagging 31
alt.music.pink-floyd.publius 26
gmane.linux.alsa.devel 19
gmane.linux.kernel 14
gmane.linux.bios.flashrom 8
mozilla.dev.ui-alerts 7
mozilla.dev.security.policy 6
mozilla.dev.platform 5
mozilla.support.seamonkey 4
nemo.abuse 4
fido7.ru.android 3
alt.religion.christian.romman-catholic 2
mozilla.support.bugzilla 2
mozilla.support.calendar 2
alt.music.france 1
alt.music.female-vocalists 1
gmane.comp.freedesktop.xorg.drivers.intel 1
alt.bestjobsusa.chicago.jobs 1
alt.personals.d 1
mozilla.tools.marionette 1
TOTAL: 27 146

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2829
EMP (phn path) 364
EMP (phl) 133
Too many newsgroups 70
EMP (phn nph) 60
User-issued cancel 43
Binary Image: misplaced jpg 15
User-issued spam cancel 15
Binary: non-binary distribution 7
Binary: misplaced par2 6
Binary: misplaced 005 3
Binary: misplaced 015 2
Too many newsgroups (low_xpost) 2
Binary: misplaced PAR2 1
Binary: misplaced sfv 1
Binary: misplaced 016 1
Binary: misplaced 054 1
Binary: misplaced 421 1
Binary: misplaced 058 1
Binary: misplaced 256 1
TOTAL: 29 3565

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.swapon.de 93
news.albasani.net 88
bbs-nz-out.news.weretis.net 81
news-out.mixmin.net 49
news.redatomik.org 22
news-out.aioe.org 11
bbsnz-out.feed.uzoreto.com 5
feeder.eternal-september.org 3
newsfeed.neva.ru 2
news.snarked.org 1
TOTAL: 10 355
TOTAL: 1 355

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 15528 674 14823 31 0 0 4%23:59:45
2awxg 16535 32 16492 0 0 0 0%23:59:42
3news.freedyn.net 16929 6 16777 0 0 5 0%23:59:46
4news.snarked.org 12735 3 12660 1 0 3 0%23:59:49
5aioe 13949 2 13839 0 0 0 0%23:59:31
6weretis.net 14800 2 14638 0 0 68 0%23:59:49
7neva.ru 15493 2 15480 0 0 0 0%23:59:49
8news.redatomik.org 16288 2 16240 0 0 7 0%23:59:49
9news-in.mixmin.net 15663 2 15650 0 0 0 0%23:59:49
10news.samoylyk.net 15587 2 15482 0 0 27 0%23:59:49
11mb-net.net 16827 2 16792 0 0 0 0%23:59:41
12news.albasani.net 16775 2 14943 478 0 4660 0%23:59:49
13csiph.com 16680 2 16597 1 0 0 0%23:59:46
14eternal-september 14237 2 14160 0 0 54 0%23:59:49
15news.uzoreto.com 14518 1 14515 1 0 20 0%23:59:48
16news.swapon.de 1383 0 1383 0 0 0 0%23:52:58
TOTAL: 16 233927 736 230471 512 0 4844 0%383:49:29
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org6.7 MB1.5 MB8.1 MB0.1 KB/s11.8 KB23:59:45
2awxg77.1 KB0.0 KB77.1 KB0.0 KB/s2.4 KB23:59:42
3news.freedyn.net16.5 KB0.0 KB16.5 KB0.0 KB/s2.7 KB23:59:46
4news.snarked.org4.4 KB2.3 KB6.6 KB0.0 KB/s1.7 KB23:59:49
5aioe2.8 KB0.0 KB2.8 KB0.0 KB/s1.4 KB23:59:31
6weretis.net2.8 KB0.0 KB2.8 KB0.0 KB/s1.4 KB23:59:49
7neva.ru2.8 KB0.0 KB2.8 KB0.0 KB/s1.4 KB23:59:49
8news.redatomik.org2.8 KB0.0 KB2.8 KB0.0 KB/s1.4 KB23:59:49
9news-in.mixmin.net2.8 KB0.0 KB2.8 KB0.0 KB/s1.4 KB23:59:49
10news.samoylyk.net2.8 KB0.0 KB2.8 KB0.0 KB/s1.4 KB23:59:49
11mb-net.net2.8 KB0.0 KB2.8 KB0.0 KB/s1.4 KB23:59:41
12news.albasani.net2.8 KB938.1 KB940.9 KB0.0 KB/s2.0 KB23:59:49
13csiph.com2.8 KB1.8 KB4.6 KB0.0 KB/s1.5 KB23:59:46
14eternal-september2.8 KB0.0 KB2.8 KB0.0 KB/s1.4 KB23:59:49
15news.uzoreto.com1.4 KB1.4 KB2.8 KB0.0 KB/s1.4 KB23:59:48
16news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:52:58
TOTAL: 166.8 MB2.4 MB9.2 MB0.0 KB/s7.5 KB383:49:29
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ool-4578089a.dyn.optonline.net 2 1099514.8 MB 699 0 002:44:33
2agency.bbs.nz 387 558219.3 MB 3156 2 000:15:06
3vorlon.activateit.net.au 24 11611.7 MB 121 0 000:05:21
4160-3-236-103.cpe.cableone.net 4 65429.6 KB 1 0 022:33:32
5amigacity.xyz 72 3567.9 KB 24 0 28800:13:49
TOTAL: 5 489 1783836.3 MB 4001 2 28825:57:43

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dyn.optonline.net 2 1099514.8 MB 699 0 002:44:33
2*.bbs.nz 387 558219.3 MB 3156 2 000:15:06
3*.activateit.net.au 24 11611.7 MB 121 0 000:05:21
4*.cpe.cableone.net 4 65429.6 KB 1 0 022:33:32
5*.xyz 72 3567.9 KB 24 0 28800:13:49
6unresolved 6 00.0 KB 0 0 000:05:18
TOTAL: 6 495 1783836.3 MB 4001 2 28825:57:43

NNRP auth users [Top 20]:

UserConn
amigacity 72
amybbs 24
malcolmlewis 4
TOTAL: 3 100

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
160-3-236-103.cpe.cableone.net 0.328 0.116 0.01122:33:32
ool-4578089a.dyn.optonline.net 1.976 2.392 0.00002:44:33
agency.bbs.nz 28.828 6.536 0.00000:15:06
amigacity.xyz 4.060 0.768 0.25200:13:49
vorlon.activateit.net.au 1.488 0.476 0.09400:05:21
118.149.164.65 0.188 0.024 0.00000:03:05
118.149.189.150 0.112 0.024 0.00000:02:03
107.170.218.244 0.048 0.016 0.00000:00:09
? 1.128 0.144 0.00000:00:02
TOTAL: 9 38.156 10.496 0.35725:57:43

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.164.65 3
118.149.189.150 2
107.170.218.244 1
TOTAL: 3 6

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

SystemConnPeer
ool-4578089a.dyn.optonline.net 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1alt 36320 84.8%
2rec 2762 6.4%
3comp 830 1.9%
4sci 666 1.6%
5soc 630 1.5%
6linux 604 1.4%
7uk 571 1.3%
8talk 175 0.4%
9misc 146 0.3%
10nz 76 0.2%
11news 37 0.1%
12microsoft 14 0.0%
13mozilla 12 0.0%
14humanities 7 0.0%
15aus 1 0.0%
TOTAL: 15 42851100.0%

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

NewsgroupCount
1alt.home.repair 6790
2alt.fan.rush-limbaugh 6221
3alt.global-warming 1517
4alt.comp.os.windows-10 1068
5rec.arts.tv 1027
6alt.fan.jai-maharaj 925
7alt.fan.gene-scott 882
8alt.russian.z1 874
9alt.politics.org.un 696
10alt.clearing.o5 623
11alt.comp.freeware 589
12alt.religion.christian.roman-catholic 571
13alt.gossip.celebrities 546
14alt.humor.puns 530
15alt.politics.republicans 525
16alt.politics.economics 507
17alt.politics.uk 483
18alt.conspiracy.jfk 463
19alt.folklore.computers 418
20alt.recovery.aa 375
21alt.politics.democrats 354
22alt.religion.buddhism.nichiren 325
23rec.food.cooking 304
24uk.d-i-y 299
25alt.life.sucks 267
26rec.arts.drwho 262
27alt.guns 255
28alt.fan.cecil-adams 248
29alt.christnet.theology 245
30alt.horror 242
31alt.fan.barry-manilow 230
32alt.conspiracy 213
33comp.lang.c 210
34alt.prophecies.nostradamus 208
35alt.happy.valley 207
36sci.physics 204
37comp.os.linux.advocacy 186
38alt.drugs.ecstasy 168
39alt.politics.liberalism 168
40rec.arts.sf.written 167
41alt.fan.rush-limbaugh.tv-show 164
42alt.religion.christian.east-orthodox 162
43linux.debian.bugs.dist 162
44alt.drugs.diazepam 154
45alt.messianic 154
46alt.os.linux.ubuntu 150
47alt.drugs.dealers.playground.bert-hoff 150
48linux.debian.bugs.rc 148
49rec.sport.pro-wrestling 148
50alt.culture.hawaii 144
51alt.food.fast-food 140
52alt.education.management 134
53linux.debian.changes.devel 130
54alt.politics.media 124
55alt.guitar.amps 121
56soc.culture.polish 120
57sci.electronics.design 113
58soc.retirement 111
59alt.obituaries 111
60alt.politics.trump 110
61alt.drugs.valium 107
62talk.origins 105
63alt.philosophy.taoism 105
64alt.radio.talk.dr-laura 99
65alt.jobs 99
66rec.sport.football.college 99
67uk.legal 91
68alt.france 91
69alt.os.linux 91
70sci.lang 88
71alt.politics.usa.republican 86
72alt.politics.elections 85
73alt.education.research 84
74alt.law-enforcement 84
75alt.os.linux.debian 84
76alt.fan.states.wyoming 82
77alt.fan.states.montana 82
78alt.dbs.directv 82
79alt.politics.immigration 78
80alt.religion.vaisnava 77
81rec.boats 76
82alt.desert-storm 74
83comp.lang.python 74
84alt.fan.regular-guys 74
85alt.dreams.castaneda 73
86alt.nl.zeurdoos 72
87alt.politics.usa.constitution 71
88alt.os.linux.mint 70
89alt.pl.praca.oferowana 70
90alt.privacy.anon-server.stats 68
91alt.coast-guard 67
92uk.radio.amateur 66
93alt.politics.obama 65
94nz.comp 65
95alt.journalism 63
96alt.rush-limbaugh 63
97alt.free.newsservers 62
98soc.culture.israel 62
99alt.quotations 62
100talk.politics.guns 61
TOTAL: 818 42851