news.bbs.nz daily logs

Aug 26 23:58:01 -- Aug 27 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 126090 60.0%9.9 MB 37.2%
innd 35038 16.7%5.6 MB 21.2%
innfeed 26332 12.5%5.2 MB 19.7%
inn 21290 10.1%5.5 MB 20.9%
cnfsstat 1440 0.7%255.1 KB 0.9%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 210191 100.0%26.5 MB100.0%

History cache:

ReasonCount%Count
Negative hits 285905 48.6%
Positive hits 281562 47.9%
Do not exist 20475 3.5%
Cache misses 160 0.0%
TOTAL: 4 588102 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.200 0.0%80 0.000 2.500 46.000
article cleanup00:00:00.185 0.0%20795 0.000 0.009 0.039
article logging00:00:00.568 0.0%21091 0.000 0.027 0.292
article parse00:00:00.634 0.0%58978 0.000 0.011 0.033
article write00:00:01.303 0.0%16821 0.000 0.077 1.271
artlog/artcncl00:00:00.001 0.0%80 0.000 0.013 0.167
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:00.393 0.0%560412 0.000 0.001 0.007
hisgrep/artcncl00:00:00.001 0.0%77 0.000 0.013 0.167
hishave/artcncl00:00:00.001 0.0%80 0.000 0.013 0.111
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.480 0.0%590335 0.001 0.011 0.574
history sync00:00:00.017 0.0%372 0.000 0.046 0.667
history write00:00:05.109 0.0%20541 0.000 0.249 16.260
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle23:48:07.342 99.8%571422 89.847 149.955 417.353
nntp read00:00:03.563 0.0%573074 0.003 0.006 0.025
overview write00:00:39.272 0.0%16821 0.032 2.335 58.881
perl filter00:00:41.572 0.0%20538 1.325 2.024 12.333
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.886 0.0%33642 0.003 0.026 0.085
TOTAL: 23:50:57.14623:49:47.527 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.056 0.0%23635 0.000 0.002 0.022
article prepare00:00:00.000 0.0%7746 0.000 0.000 0.000
article read00:00:00.108 0.0%1785 0.000 0.061 2.462
backlog stats00:00:00.310 0.0%310410 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.682 0.0%259257 0.001 0.006 0.012
data write00:00:03.019 0.0%247739 0.005 0.012 0.019
idle23:54:00.646 99.9%310410 151.320 277.184 635.790
status file00:00:23.006 0.0%1978 0.900 11.631 520.286
TOTAL: 23:54:45.98223:54:28.827100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:03:26.993 96.7%129141 0.000 140.985 40472.208
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.378 0.0%271162 0.000 0.005 0.600
readart00:00:00.795 0.0%6520 0.000 0.122 3.642
TOTAL: 05:13:57.10105:03:29.166 96.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
1news.snarked.org 32 19211 3323 14421 1467 17%32:25:28
2bbs-nz-out.news.weretis.net 9 35112 3254 31496 362 9%47:47:43
3bbsnz-out.feed.uzoreto.com 1117 27268 2165 24366 737 7%95:18:33
4feeder.eternal-september.org 7 19828 2014 17707 107 10%26:09:21
5news-out.aioe.org 5 17046 1953 15055 38 11%24:42:23
6news.redatomik.org 4 16717 1084 15517 116 6%23:47:38
7news-out.mixmin.net 2 17674 999 16270 405 5%23:38:39
8newsfeed.neva.ru 140 12086 676 11219 191 5%72:28:29
9news.albasani.net 1 15049 614 14108 327 4%20:36:11
10csiph.com 2 15668 208 15442 18 1%23:59:35
11newsfeed.datemas.de 2 16799 165 16588 46 0%23:59:29
12news.swapon.de 6 1309 85 1224 0 6%20:37:05
13news.samoylyk.net 1 15704 38 15642 24 0%23:59:57
14agency-nz-out.news.mb-net.net 5 17513 32 17463 18 0%23:17:49
15feeder.awxg.com 1 13687 18 13654 15 0%23:59:56
16news.freedyn.net 1 16921 10 16897 14 0%22:32:53
17news.ausics.org 2 59018 4 59007 7 0%94:11:27
18localhost 29 28 4 24 0 14%00:00:00
TOTAL: 18 1366 336638 16646 316100 3892 4%623:32:36
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1bbs-nz-out.news.weretis.net15.0 MB149.4 KB1015.8 KB16.2 MB 92%4.6 KB
2news.snarked.org11.4 MB83.6 KB12.4 MB23.9 MB 47%5.1 KB
3bbsnz-out.feed.uzoreto.com6.3 MB75.7 KB2.9 MB9.2 MB 68%3.3 KB
4feeder.eternal-september.org5.4 MB109.4 KB366.8 KB5.8 MB 92%2.8 KB
5news-out.aioe.org4.9 MB33.5 KB234.6 KB5.1 MB 94%2.6 KB
6news.redatomik.org3.5 MB58.7 KB302.0 KB3.8 MB 90%3.3 KB
7news-out.mixmin.net3.0 MB58.6 KB923.4 KB4.0 MB 75%2.9 KB
8news.albasani.net1.8 MB65.1 KB650.6 KB2.5 MB 71%2.7 KB
9newsfeed.neva.ru1.7 MB54.6 KB683.8 KB2.4 MB 70%2.9 KB
10newsfeed.datemas.de627.3 KB52.6 KB132.7 KB812.6 KB 77%3.9 KB
11csiph.com583.0 KB84.7 KB84.7 KB752.4 KB 77%3.3 KB
12news.swapon.de242.5 KB0.0 KB0.0 KB242.5 KB100%2.9 KB
13feeder.awxg.com121.8 KB49.7 KB49.7 KB221.2 KB 55%6.7 KB
14news.samoylyk.net115.4 KB95.3 KB95.3 KB306.0 KB 37%4.9 KB
15agency-nz-out.news.mb-net.net113.0 KB56.4 KB69.8 KB239.2 KB 47%4.8 KB
16news.freedyn.net44.7 KB37.3 KB42.7 KB124.7 KB 35%5.2 KB
17news.ausics.org10.3 KB27.0 KB27.0 KB64.3 KB 15%5.8 KB
18localhost5.2 KB0.0 KB0.0 KB5.2 KB100%1.3 KB
TOTAL: 1854.8 MB1.1 MB19.9 MB75.7 MB 72%3.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 26 23:58:01 - 23:59:59 30 0.2% 0.2580.2 KB 0.1% 0.67
Aug 27 00:00:00 - 00:59:59 764 4.5% 0.211.9 MB 3.4% 0.53
Aug 27 01:00:00 - 01:59:59 756 4.5% 0.212.2 MB 4.0% 0.63
Aug 27 02:00:00 - 02:59:59 875 5.2% 0.242.6 MB 4.7% 0.74
Aug 27 03:00:00 - 03:59:59 844 5.0% 0.232.6 MB 4.8% 0.75
Aug 27 04:00:00 - 04:59:59 953 5.6% 0.263.7 MB 6.7% 1.07
Aug 27 05:00:00 - 05:59:59 936 5.5% 0.262.6 MB 4.7% 0.75
Aug 27 06:00:00 - 06:59:59 1000 5.9% 0.283.7 MB 6.6% 1.04
Aug 27 07:00:00 - 07:59:59 1038 6.1% 0.292.9 MB 5.3% 0.84
Aug 27 08:00:00 - 08:59:59 908 5.4% 0.253.4 MB 6.1% 0.97
Aug 27 09:00:00 - 09:59:59 700 4.1% 0.191.9 MB 3.4% 0.54
Aug 27 10:00:00 - 10:59:59 589 3.5% 0.162.4 MB 4.3% 0.68
Aug 27 11:00:00 - 11:59:59 531 3.1% 0.151.6 MB 2.8% 0.44
Aug 27 12:00:00 - 12:59:59 428 2.5% 0.121.9 MB 3.4% 0.54
Aug 27 13:00:00 - 13:59:59 370 2.2% 0.101.8 MB 3.3% 0.52
Aug 27 14:00:00 - 14:59:59 299 1.8% 0.081.1 MB 1.9% 0.30
Aug 27 15:00:00 - 15:59:59 348 2.1% 0.101.5 MB 2.7% 0.42
Aug 27 16:00:00 - 16:59:59 405 2.4% 0.111.9 MB 3.4% 0.53
Aug 27 17:00:00 - 17:59:59 428 2.5% 0.121.2 MB 2.1% 0.34
Aug 27 18:00:00 - 18:59:59 593 3.5% 0.161.8 MB 3.2% 0.51
Aug 27 19:00:00 - 19:59:59 706 4.2% 0.202.2 MB 3.9% 0.61
Aug 27 20:00:00 - 20:59:59 806 4.8% 0.222.3 MB 4.1% 0.64
Aug 27 21:00:00 - 21:59:59 955 5.6% 0.273.2 MB 5.8% 0.92
Aug 27 22:00:00 - 22:59:59 877 5.2% 0.242.8 MB 5.0% 0.78
Aug 27 23:00:00 - 23:58:01 792 4.7% 0.232.4 MB 4.3% 0.71
TOTAL: 24:00:00 16931 100.0% 0.2055.6 MB 100.0% 0.66
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB28.0 GB 56.1% 0 0.25 2399.79
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB32.3 GB 64.6% 0 0.30 2034.42
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB358.2 MB 0.7% 0 0.0225600.00
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 GB26.1 GB 52.2% 0 0.57 1062.10
TOTAL: 10-500.0 GB86.8 GB 17.4% 1 0.36-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 1514 1 0 22 0 0 0 0 1491
2bbsnz-out.feed.uzoreto.com 740 13 0 6 0 0 0 0 721
3news.albasani.net 496 0 0 16 0 0 0 0 480
4news-out.mixmin.net 427 0 0 19 0 0 0 0 408
5bbs-nz-out.news.weretis.net 400 17 0 40 0 0 0 0 343
6news.redatomik.org 207 0 0 13 0 0 0 0 194
7newsfeed.neva.ru 192 12 0 17 0 0 0 0 163
8feeder.eternal-september.org 100 0 0 12 0 0 0 0 88
9newsfeed.datemas.de 45 10 0 15 0 0 0 0 20
10news-out.aioe.org 43 22 0 10 0 0 0 0 11
11news.samoylyk.net 22 0 0 22 0 0 0 0 0
12news.swapon.de 20 0 0 0 0 0 0 0 20
13agency-nz-out.news.mb-net.net 19 1 0 13 0 0 0 0 5
14feeder.awxg.com 18 0 0 17 0 0 0 0 1
15csiph.com 17 0 0 16 0 0 0 0 1
16news.freedyn.net 12 0 0 11 0 0 0 0 1
17news.ausics.org 7 0 0 7 0 0 0 0 0
TOTAL: 17 4279 76 0 256 0 0 0 0 3947

Unwanted newsgroups [Top 20]:

NewsgroupCount
gmane.linux.alsa.devel 22
gmane.comp.gis.openstreetmap.tagging 13
gmane.comp.gis.openstreetmap.imports 7
alt.fr.os.windows.7 3
it.annunci.varie 3
mozilla.dev.ui-alerts 3
it.annunci.immobiliari 3
it.annunci.commerciali 3
mozilla.apis 1
gmane.linux.kernel 1
aol.neighborhood.ca.san-francisco.jobs 1
trollnet.cinema 1
mozilla.test 1
alt.survivalism 1
mozilla.dev.quality 1
demos.local.statistics 1
mozilla.support.seamonkey 1
aol.neighborhood.az.jobs 1
mozilla.mozillians 1
zz.unity.netlink 1
TOTAL: 27 76

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2633
EMP (phn path) 529
EMP (phl) 383
User-issued cancel 37
Too many newsgroups 36
Binary: misplaced par2 13
Binary: non-binary distribution 9
Binary: misplaced rar 3
Too many newsgroups (low_xpost) 2
HTML file attachment 2
Binary: misplaced JPG 1
TOTAL: 11 3648

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
bbsnz-out.feed.uzoreto.com 1
TOTAL: 1 1
Including strange strings
news.albasani.net 120
news.redatomik.org 89
bbs-nz-out.news.weretis.net 31
news-out.mixmin.net 21
news.swapon.de 20
newsfeed.datemas.de 4
news-out.aioe.org 3
news.snarked.org 3
bbsnz-out.feed.uzoreto.com 3
feeder.eternal-september.org 1
TOTAL: 11 296
No colon-space in header
bbsnz-out.feed.uzoreto.com 2
TOTAL: 1 2
TOTAL: 3 299

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 15573 1057 14481 34 1 12 6%23:59:47
2awxg 16525 37 16479 0 0 0 0%23:59:47
3news.freedyn.net 17165 9 16910 0 0 20 0%23:59:45
4weretis.net 13411 8 13236 3 0 0 0%23:59:24
5news.snarked.org 13565 7 13375 0 0 180 0%23:59:47
6aioe 14009 6 13930 0 0 0 0%23:59:47
7csiph.com 16743 6 16715 0 0 0 0%23:59:47
8news.redatomik.org 15757 5 15748 0 0 5 0%23:59:47
9neva.ru 16001 5 15991 0 0 0 0%23:59:47
10news.samoylyk.net 15643 5 15528 0 0 0 0%23:59:47
11eternal-september 14709 5 14686 0 0 0 0%23:59:47
12news-in.mixmin.net 15505 4 15491 0 0 0 0%23:59:42
13newsfeed.datemas.de 16887 4 16773 1 0 133 0%23:59:47
14news.albasani.net 18259 3 15466 596 0 6092 0%23:59:47
15mb-net.net 16975 3 16876 1 0 272 0%23:59:47
16news.uzoreto.com 14726 3 14716 2 0 0 0%23:59:03
17news.swapon.de 1266 2 1244 0 0 28 0%23:37:03
TOTAL: 17 252719 1169 247645 637 1 6742 0%407:32:21
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org8.7 MB325.1 KB9.0 MB0.1 KB/s8.5 KB23:59:47
2awxg117.8 KB0.0 KB117.8 KB0.0 KB/s3.2 KB23:59:47
3news.snarked.org19.7 KB0.0 KB19.7 KB0.0 KB/s2.8 KB23:59:47
4aioe15.8 KB0.0 KB15.8 KB0.0 KB/s2.6 KB23:59:47
5news.freedyn.net13.4 KB0.0 KB13.4 KB0.0 KB/s1.5 KB23:59:45
6weretis.net11.3 KB7.3 KB18.7 KB0.0 KB/s1.7 KB23:59:24
7csiph.com9.0 KB0.0 KB9.0 KB0.0 KB/s1.5 KB23:59:47
8news.redatomik.org6.0 KB0.0 KB6.0 KB0.0 KB/s1.2 KB23:59:47
9neva.ru6.0 KB0.0 KB6.0 KB0.0 KB/s1.2 KB23:59:47
10news.samoylyk.net6.0 KB0.0 KB6.0 KB0.0 KB/s1.2 KB23:59:47
11eternal-september6.0 KB0.0 KB6.0 KB0.0 KB/s1.2 KB23:59:47
12news-in.mixmin.net5.3 KB0.0 KB5.3 KB0.0 KB/s1.3 KB23:59:42
13newsfeed.datemas.de4.5 KB1.7 KB6.2 KB0.0 KB/s1.2 KB23:59:47
14news.uzoreto.com3.9 KB2.1 KB6.0 KB0.0 KB/s1.2 KB23:59:03
15news.albasani.net2.8 KB3.6 MB3.6 MB0.0 KB/s6.2 KB23:59:47
16mb-net.net2.8 KB1.7 KB4.5 KB0.0 KB/s1.1 KB23:59:47
17news.swapon.de2.1 KB0.0 KB2.1 KB0.0 KB/s1.0 KB23:37:03
TOTAL: 178.9 MB4.0 MB12.9 MB0.0 KB/s7.3 KB407:32:21
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.nz 410 514118.9 MB 3032 4 9600:17:00
2vorlon.activateit.net.au 24 9391.2 MB 130 0 000:04:51
360.234.68.122 3 412.4 KB 1 0 000:03:17
TOTAL: 3 437 608420.2 MB 3785 4 9605:13:50

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.nz 410 514118.9 MB 3032 4 9600:17:00
2*.activateit.net.au 24 9391.2 MB 130 0 000:04:51
3unresolved 14 412.4 KB 1 0 000:12:40
4*.cpe.cableone.net 2 00.0 KB 0 0 004:39:16
TOTAL: 4 450 608420.2 MB 3785 4 9605:13:50

NNRP auth users [Top 20]:

UserConn
amybbs 24
crash 2
malcolmlewis 2
TOTAL: 3 28

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
160-3-245-118.cpe.cableone.net 0.056 0.032 0.00304:39:16
agency.bbs.nz 29.152 6.280 0.00000:17:00
118.149.197.58 0.524 0.092 0.00000:09:11
vorlon.activateit.net.au 1.420 0.352 0.10900:04:51
60.234.68.122 0.176 0.040 0.00500:03:17
107.170.209.213 0.056 0.004 0.00000:00:09
94.164.97.24 0.052 0.008 0.00000:00:01
? 1.180 0.148 0.00000:00:01
TOTAL: 8 32.616 6.956 0.11705:13:50

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.197.58 9
160-3-245-118.cpe.cableone.net 2
94.164.97.24 1
107.170.209.213 1
TOTAL: 4 13

NNRP gethostbyaddr failures [Top 20]:

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

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 104599 94.8%
2rec 2380 2.2%
3soc 815 0.7%
4linux 645 0.6%
5uk 609 0.6%
6comp 559 0.5%
7sci 436 0.4%
8talk 136 0.1%
9misc 73 0.1%
10news 62 0.1%
11mozilla 39 0.0%
12microsoft 13 0.0%
13humanities 9 0.0%
14nz 5 0.0%
15agency 4 0.0%
16aus 4 0.0%
TOTAL: 16 110388100.0%

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

NewsgroupCount
1alt.messianic 24252
2alt.nl.zeurdoos 10126
3alt.music.michael-jackson 7839
4alt.music.prayforthesoulofbetty 6472
5alt.military.retired 5356
6alt.music.pink-floyd 4398
7alt.military 4010
8alt.music.makers.soloact 3236
9alt.mn.test 2465
10alt.news-media 2115
11alt.med.cfs 1825
12alt.msdos.batch.nt 1534
13alt.nl.taal.crypto 1381
14alt.magick 1209
15alt.nl.gaycafe 1165
16alt.movies.kubrick 1069
17alt.mindcontrol 886
18alt.men.politics 879
19alt.mountain-bike 834
20alt.madcrew 745
21alt.mens-rights 681
22alt.military.police 664
23alt.music.u2 631
24rec.arts.tv 628
25alt.music.s-mclachlan 605
26alt.motorcycle.sportbike 601
27alt.music.suede 575
28alt.music.judas-priest 561
29alt.my.job.sucks 523
30rec.arts.drwho 468
31alt.mexico 451
32alt.music.lyrics 437
33alt.niggers 423
34alt.mythology 403
35alt.music.yes 385
36alt.msdos.batch 379
37alt.magick.marketplace 377
38alt.magick.tantra 347
39alt.menwhoswallow 332
40alt.marketplace.books 295
41alt.movies 292
42alt.marketing.online.ebay 290
43alt.nl.radio.zendamateur.gelicentieerd 278
44alt.marketplace.online.ebay 253
45uk.d-i-y 239
46alt.music.home-studio 221
47alt.music.polkas 218
48alt.messianic.yeshua 217
49alt.music.chapel-hill 213
50alt.music.prince 213
51alt.med.veterinary 212
52alt.native 209
53linux.debian.bugs.dist 207
54rec.food.cooking 199
55uk.legal 194
56alt.military.uk 193
57alt.meditation 187
58alt.no.test 182
59alt.music.canadian 174
60alt.music 172
61comp.lang.c 164
62linux.debian.changes.devel 162
63soc.culture.polish 156
64alt.music.lyrics.spanish 149
65alt.mag.playboy 144
66alt.music.rush 141
67alt.media.bbc 140
68alt.magic 137
69alt.music.heather-nova 135
70alt.news.admin.net-abuse.checkmate 134
71alt.nocem.misc 133
72alt.music.canada 132
73alt.comp.os.windows-10 124
74alt.new-hampshire 123
75alt.music.progressive 119
76alt.military.psywar 119
77alt.music.oasis 117
78rec.sport.football.college 116
79alt.missing-adults 116
80alt.masturbation.female 114
81alt.nl.test 113
82alt.music.nirvana 110
83alt.movies.silent 109
84alt.nuke.the.usa 107
85alt.misc 107
86rec.sport.tennis 107
87alt.music.gangsta.rap 106
88alt.music.midi 104
89alt.marketplace.collectables 104
90alt.minion 103
91alt.military.retired.politics 103
92uk.radio.amateur 102
93alt.motorcycles.harley 101
94alt.new-england 101
95alt.music.mike-keneally 100
96rec.gambling.poker 99
97alt.nl.ftp.verzoek 99
98rec.sport.pro-wrestling 96
99alt.math.recreational 95
100alt.music.saxophone 92
TOTAL: 955 110388