news.bbs.geek.nz daily logs

Apr 12 23:58:01 -- Apr 13 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 134360 62.9%10.6 MB 40.5%
innd 28767 13.5%4.8 MB 18.2%
inn 25763 12.1%5.9 MB 22.8%
innfeed 23236 10.9%4.6 MB 17.5%
cnfsstat 1440 0.7%254.2 KB 1.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 213567 100.0%26.1 MB100.0%

History cache:

ReasonCount%Count
Negative hits 278878 49.5%
Positive hits 259682 46.1%
Do not exist 24742 4.4%
Cache misses 125 0.0%
TOTAL: 4 563427 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.192 0.0%165 0.000 1.164 23.000
article cleanup00:00:00.216 0.0%25046 0.000 0.009 0.030
article logging00:00:00.633 0.0%25621 0.000 0.025 0.081
article parse00:00:00.755 0.0%84658 0.000 0.009 0.027
article write00:00:01.541 0.0%20046 0.020 0.077 0.689
artlog/artcncl00:00:00.001 0.0%157 0.000 0.006 1.000
data move00:00:00.456 0.0%567517 0.000 0.001 0.009
hisgrep/artcncl00:00:00.003 0.0%162 0.000 0.019 1.000
hishave/artcncl00:00:00.000 0.0%165 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.147 0.0%565584 0.002 0.016 0.649
history sync00:00:00.014 0.0%365 0.000 0.038 0.667
history write00:00:07.490 0.0%24822 0.021 0.302 17.239
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle23:57:38.961 99.8%576791 97.294 149.550 312.096
nntp read00:00:04.286 0.0%578999 0.002 0.007 0.026
overview write00:00:38.997 0.0%20046 0.048 1.945 35.933
perl filter00:00:42.259 0.0%24822 1.128 1.702 7.687
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.063 0.0%40092 0.000 0.027 0.148
TOTAL: 24:00:56.36823:59:26.014 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.070 0.0%26686 0.000 0.003 0.032
article prepare00:00:00.001 0.0%8056 0.000 0.000 0.004
article read00:00:00.221 0.0%1899 0.000 0.116 3.400
backlog stats00:00:00.565 0.0%317774 0.000 0.002 0.006
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:02.555 0.0%266743 0.004 0.010 0.016
data write00:00:03.204 0.0%251710 0.007 0.013 0.028
idle23:53:12.680 99.9%317774 169.064 270.610 566.945
status file00:00:20.150 0.0%1860 0.385 10.833 449.000
TOTAL: 23:53:57.66423:53:39.446100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle42:32:42.928 98.7%198999 0.000 769.667 74114.585
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:03:05.660 0.1%475650 0.000 0.390 5.718
readart00:01:21.582 0.1%63744 0.000 1.280 12.204
TOTAL: 43:06:23.29942:37:10.170 98.9%----

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 39 24807 6674 15617 2516 26%42:18:10
2newsfeed.datemas.de 3 21787 3268 17796 723 14%24:05:51
3feeder.eternal-september.org 5 23955 2450 21337 168 10%25:54:27
4news.albasani.net 1 19177 1599 17046 532 8%21:58:22
5news-out.aioe.org 3 13698 1590 11987 121 11%14:55:31
6news.redatomik.org 1 18938 1575 17338 25 8%24:07:05
7newsfeed.neva.ru 152 15045 1486 13034 525 9%75:36:06
8news-out.mixmin.net 4 17861 530 17080 251 2%23:00:32
9csiph.com 1 13499 411 13078 10 3%16:36:44
10news.swapon.de 2 1597 147 1449 1 9%23:51:41
11agency-nz-out.news.mb-net.net 6 22940 89 22799 52 0%24:43:43
12feeder.awxg.com 1 17626 87 17524 15 0%23:59:57
13news.samoylyk.net 1 15261 58 15184 19 0%17:23:39
14news.freedyn.net 2 23718 55 23639 24 0%27:12:06
15localhost 5 4 4 0 0 100%00:00:00
16news.ausics.org 5 75076 2 75065 9 0%83:59:12
TOTAL: 16 231 324989 20025 299973 4991 6%469:43:06
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org22.9 MB105.6 KB49.3 MB72.3 MB 31%8.1 KB
2newsfeed.datemas.de10.0 MB546.0 KB3.1 MB13.6 MB 73%3.5 KB
3feeder.eternal-september.org6.7 MB49.4 KB854.2 KB7.6 MB 88%3.0 KB
4news.albasani.net4.3 MB80.2 KB971.7 KB5.4 MB 80%2.6 KB
5newsfeed.neva.ru3.7 MB103.0 KB1.6 MB5.4 MB 68%2.8 KB
6news-out.aioe.org3.6 MB49.1 KB434.8 KB4.1 MB 88%2.5 KB
7news.redatomik.org3.5 MB61.6 KB77.1 KB3.6 MB 96%2.3 KB
8news-out.mixmin.net2.0 MB56.6 KB479.6 KB2.5 MB 79%3.3 KB
9csiph.com1.2 MB23.3 KB24.7 KB1.3 MB 96%3.1 KB
10news.swapon.de365.2 KB4.1 KB4.1 KB373.5 KB 97%2.5 KB
11feeder.awxg.com274.4 KB44.1 KB51.8 KB370.4 KB 74%3.6 KB
12agency-nz-out.news.mb-net.net249.7 KB75.4 KB250.7 KB575.8 KB 43%4.1 KB
13news.freedyn.net151.5 KB528.9 KB578.1 KB1.2 MB 12%15.9 KB
14news.samoylyk.net140.4 KB554.7 KB641.3 KB1.3 MB 10%17.4 KB
15localhost10.9 KB0.0 KB0.0 KB10.9 KB100%2.7 KB
16news.ausics.org5.5 KB32.7 KB32.7 KB71.0 KB 7%6.5 KB
TOTAL: 1659.2 MB2.3 MB58.3 MB119.8 MB 49%4.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 12 23:58:01 - 23:59:59 39 0.2% 0.3396.8 KB 0.2% 0.81
Apr 13 00:00:00 - 00:59:59 1062 5.3% 0.292.7 MB 4.6% 0.78
Apr 13 01:00:00 - 01:59:59 970 4.8% 0.272.9 MB 4.8% 0.81
Apr 13 02:00:00 - 02:59:59 1009 5.0% 0.282.4 MB 4.1% 0.69
Apr 13 03:00:00 - 03:59:59 1169 5.8% 0.323.3 MB 5.6% 0.94
Apr 13 04:00:00 - 04:59:59 1106 5.5% 0.312.8 MB 4.6% 0.78
Apr 13 05:00:00 - 05:59:59 1071 5.3% 0.302.8 MB 4.7% 0.79
Apr 13 06:00:00 - 06:59:59 929 4.6% 0.262.8 MB 4.8% 0.81
Apr 13 07:00:00 - 07:59:59 946 4.7% 0.262.7 MB 4.5% 0.77
Apr 13 08:00:00 - 08:59:59 1120 5.6% 0.314.0 MB 6.8% 1.15
Apr 13 09:00:00 - 09:59:59 908 4.5% 0.252.8 MB 4.6% 0.78
Apr 13 10:00:00 - 10:59:59 708 3.5% 0.202.7 MB 4.6% 0.77
Apr 13 11:00:00 - 11:59:59 588 2.9% 0.161.6 MB 2.7% 0.46
Apr 13 12:00:00 - 12:59:59 439 2.2% 0.121.6 MB 2.7% 0.45
Apr 13 13:00:00 - 13:59:59 517 2.6% 0.142.0 MB 3.4% 0.57
Apr 13 14:00:00 - 14:59:59 449 2.2% 0.121.5 MB 2.5% 0.43
Apr 13 15:00:00 - 15:59:59 444 2.2% 0.121.5 MB 2.4% 0.41
Apr 13 16:00:00 - 16:59:59 486 2.4% 0.141.8 MB 3.0% 0.50
Apr 13 17:00:00 - 17:59:59 599 3.0% 0.172.0 MB 3.3% 0.55
Apr 13 18:00:00 - 18:59:59 771 3.8% 0.212.7 MB 4.5% 0.76
Apr 13 19:00:00 - 19:59:59 927 4.6% 0.262.4 MB 4.0% 0.68
Apr 13 20:00:00 - 20:59:59 856 4.3% 0.242.6 MB 4.4% 0.74
Apr 13 21:00:00 - 21:59:59 959 4.8% 0.272.3 MB 4.0% 0.67
Apr 13 22:00:00 - 22:59:59 1005 5.0% 0.282.7 MB 4.5% 0.76
Apr 13 23:00:00 - 23:58:01 954 4.8% 0.272.8 MB 4.7% 0.82
TOTAL: 24:00:00 20031 100.0% 0.2359.3 MB 100.0% 0.70
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB25.1 GB 50.1% 0 0.27 2230.33
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB29.0 GB 58.0% 0 0.31 1976.21
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB315.7 MB 0.6% 0 0.0318436.21
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 GB19.7 GB 39.3% 0 0.65 939.42
TOTAL: 10-500.0 GB74.0 GB 14.8% 1 0.40-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 2436 40 0 29 0 0 0 0 2367
2newsfeed.datemas.de 1028 190 0 15 0 0 0 0 823
3news.albasani.net 658 5 0 18 0 0 0 0 635
4newsfeed.neva.ru 527 63 0 23 0 0 0 0 441
5news-out.mixmin.net 333 0 0 20 0 0 0 0 313
6feeder.eternal-september.org 164 0 0 17 0 0 0 0 147
7news-out.aioe.org 153 66 0 20 0 0 0 0 67
8news.redatomik.org 113 0 0 15 0 0 0 0 98
9agency-nz-out.news.mb-net.net 52 21 0 18 0 0 0 0 13
10news.swapon.de 29 0 0 0 0 0 0 0 29
11news.freedyn.net 28 10 0 7 0 0 0 0 11
12news.samoylyk.net 21 0 0 17 0 0 0 0 4
13feeder.awxg.com 20 2 0 13 0 0 0 0 5
14csiph.com 12 0 0 11 0 0 0 0 1
15news.ausics.org 1 0 0 1 0 0 0 0 0
TOTAL: 15 5575 397 0 224 0 0 0 0 4954

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.annunci.immobiliari 53
mozilla.dev.security.policy 50
it.annunci.varie 50
gmane.linux.kernel 49
it.annunci.commerciali 48
gmane.linux.alsa.devel 46
es.test 12
mozilla.support.seamonkey 9
mpc.lists.nanog 8
mozilla.dev.ui-alerts 6
mozilla.tools.taskcluster 5
gmane.linux.ports.arm.mediatek 5
gmane.comp.gis.openstreetmap.tagging 4
mozilla.support.calendar 4
mozilla.wishlist 4
mpc.lists.freebsd.ports.bugs 4
mozilla.tools 4
mozilla.dev.platform 4
eagle.support.eng 3
mpc.lists.freebsd.x11 3
TOTAL: 40 397

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3302
Binary: misplaced rar 406
EMP (phn path) 221
EMP (phn nph) 152
EMP (phl) 135
Too many newsgroups 41
Binary: misplaced par2 37
Binary: non-binary distribution 22
User-issued cancel 20
Binary Image: misplaced jpg 13
Binary: misplaced 008 3
Binary: misplaced PAR2 3
Binary: misplaced 006 3
Binary: misplaced 009 2
Binary: misplaced 002 2
Cancel for rejected article 2
Binary: misplaced 012 2
Binary: misplaced 023 2
Binary: misplaced 005 2
Binary: misplaced 037 1
TOTAL: 28 4379

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.datemas.de 295
news.albasani.net 117
news.redatomik.org 89
news.swapon.de 29
news-out.mixmin.net 25
news-out.aioe.org 13
newsfeed.neva.ru 3
news.snarked.org 2
agency-nz-out.news.mb-net.net 1
feeder.awxg.com 1
TOTAL: 10 575
TOTAL: 1 575

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 18289 882 17277 46 4 182 4%23:59:47
2csiph.com 20062 50 19425 2 0 19 0%23:59:47
3awxg 19710 32 19674 0 0 0 0%23:59:45
4news.snarked.org 13485 16 13370 4 0 48 0%23:59:45
5news.freedyn.net 20120 9 19963 5 0 32 0%23:59:46
6aioe 17096 8 17020 0 0 9 0%23:59:43
7eternal-september 17256 4 17222 0 0 2 0%23:59:46
8news.redatomik.org 18376 4 18358 0 0 0 0%23:59:43
9news.samoylyk.net 18237 4 18135 1 0 0 0%23:59:35
10news-in.mixmin.net 18403 4 18372 0 0 0 0%23:59:43
11mb-net.net 19982 2 19917 0 0 59 0%23:59:46
12news.albasani.net 19162 2 16555 840 0 6205 0%23:59:46
13neva.ru 18141 2 18111 2 0 0 0%23:59:46
14newsfeed.datemas.de 16856 0 16724 1 0 258 0%23:59:46
15news.swapon.de 1487 0 1466 0 0 28 0%23:52:20
TOTAL: 15 256662 1019 251589 901 4 6842 0%359:48:44
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org5.4 MB861.1 KB6.2 MB0.1 KB/s6.9 KB23:59:47
2awxg127.0 KB0.0 KB127.0 KB0.0 KB/s4.0 KB23:59:45
3csiph.com123.0 KB3.7 KB126.7 KB0.0 KB/s2.4 KB23:59:47
4news.snarked.org41.2 KB12.7 KB53.9 KB0.0 KB/s2.7 KB23:59:45
5news.freedyn.net31.1 KB51.7 KB82.9 KB0.0 KB/s5.9 KB23:59:46
6aioe18.9 KB0.0 KB18.9 KB0.0 KB/s2.4 KB23:59:43
7eternal-september10.9 KB0.0 KB10.9 KB0.0 KB/s2.7 KB23:59:46
8news.redatomik.org10.9 KB0.0 KB10.9 KB0.0 KB/s2.7 KB23:59:43
9news.samoylyk.net10.9 KB2.0 KB13.0 KB0.0 KB/s2.6 KB23:59:35
10news-in.mixmin.net10.9 KB0.0 KB10.9 KB0.0 KB/s2.7 KB23:59:43
11mb-net.net6.3 KB0.0 KB6.3 KB0.0 KB/s3.1 KB23:59:46
12news.albasani.net5.7 KB3.0 MB3.0 MB0.0 KB/s3.6 KB23:59:46
13neva.ru4.7 KB6.3 KB10.9 KB0.0 KB/s2.7 KB23:59:46
14newsfeed.datemas.de0.0 KB4.0 KB4.0 KB0.0 KB/s4.0 KB23:59:46
15news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:52:20
TOTAL: 155.8 MB3.9 MB9.7 MB0.0 KB/s5.2 KB359:48:44
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp130-05-70-55-70-56.dsl.bell.ca 11 2444885.0 MB 58 0 010:19:16
267-61-82-242.cpe.cableone.net 8 623423.1 MB 1036 0 1601:34:51
3agency.bbs.nz 386 558918.0 MB 3307 4 000:13:26
4vorlon.activateit.net.au 23 10621.4 MB 141 0 000:05:11
5103.39.238.13 1 4458.3 KB 6 0 000:00:22
6avon.news.bbs.nz 4 22.2 KB 1 0 000:01:01
TOTAL: 6 433 37379127.7 MB 4549 4 1643:06:16

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 11 2444885.0 MB 58 0 010:19:16
2*.cpe.cableone.net 11 623423.1 MB 1036 0 1632:00:09
3*.bbs.nz 386 558918.0 MB 3307 4 000:13:26
4*.activateit.net.au 23 10621.4 MB 141 0 000:05:11
5unresolved 7 4458.3 KB 6 0 000:26:38
6*.news.bbs.nz 4 22.2 KB 1 0 000:01:01
7*.hsd1.or.comcast.net 24 00.0 KB 0 0 000:00:30
TOTAL: 7 466 37379127.7 MB 4549 4 1643:06:16

NNRP auth users [Top 20]:

UserConn
amybbs 24
bpanther 8
paulh 4
malcolmlewis 3
TOTAL: 4 39

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-60-219-146.cpe.cableone.net 0.248 0.104 0.00730:25:17
mtrlpq2314w-lp130-05-70-55-70-56.dsl.bell.ca 3.236 7.244 0.00010:19:16
67-61-82-242.cpe.cableone.net 1.880 1.232 0.02201:34:51
118.149.158.24 0.352 0.056 0.00000:26:15
agency.bbs.nz 29.404 6.808 0.00000:13:26
vorlon.activateit.net.au 1.464 0.408 0.33200:05:11
avon.news.bbs.nz 0.312 0.020 0.01000:01:01
c-73-11-56-74.hsd1.or.comcast.net 1.260 0.224 0.00000:00:30
103.39.238.13 0.064 0.028 0.00300:00:22
? 1.204 0.156 0.00000:00:01
TOTAL: 10 39.424 16.280 0.37443:06:16

Curious NNRP server explorers [Top 20]:

SystemConn
c-73-11-56-74.hsd1.or.comcast.net 24
118.149.158.24 6
67-60-219-146.cpe.cableone.net 3
TOTAL: 3 33

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

SystemConnPeer
mtrlpq2314w-lp130-05-70-55-70-56.dsl.bell.ca 10 0
TOTAL: 1 10 0

Newsgroup request counts (by category):

CategoryCountPct
1alt 26455 70.8%
2rec 4283 11.5%
3soc 1607 4.3%
4comp 1297 3.5%
5sci 1265 3.4%
6linux 921 2.5%
7misc 470 1.3%
8uk 418 1.1%
9talk 238 0.6%
10news 162 0.4%
11mozilla 97 0.3%
12nz 76 0.2%
13humanities 48 0.1%
14microsoft 21 0.1%
15perl 11 0.0%
16aus 8 0.0%
17agency 2 0.0%
TOTAL: 17 37379100.0%

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

NewsgroupCount
1rec.arts.tv 1198
2alt.happy.valley 1000
3alt.guitar.amps 1000
4alt.home.repair 1000
5alt.free.newsservers 1000
6alt.jobs 1000
7alt.humor.puns 1000
8alt.gossip.celebrities 990
9alt.jobs.jobsearch 982
10alt.atheism 943
11alt.life.sucks 902
12alt.global-warming 896
13alt.messianic 758
14alt.music.michael-jackson 731
15alt.comp.os.windows-10 723
16alt.obituaries 717
17alt.fan.rush-limbaugh 690
18alt.bible.prophecy 658
19alt.jobs.offered 592
20alt.politics.trump 567
21alt.conspiracy 528
22alt.astronomy 517
23alt.politics.usa 513
24rec.arts.drwho 507
25alt.bumbling.idiots.the.fbi 500
26alt.christnet.christianlife 500
27alt.books.louis-lamour 500
28alt.baldspot 500
29alt.religion.christian 500
30alt.buddha.short.fat.guy 500
31alt.computer.workshop 500
32alt.assassination.jfk 500
33alt.prophecies.nostradamus 500
34alt.folklore.computers 500
35alt.os.linux 500
36alt.california 499
37alt.appalachian 499
38alt.comp.freeware 499
39alt.arts.poetry.comments 496
40alt.rush-limbaugh 464
41linux.debian.bugs.dist 314
42soc.culture.israel 311
43misc.survivalism 308
44soc.culture.china 260
45sci.electronics.design 252
46uk.d-i-y 231
47linux.debian.changes.devel 229
48sci.physics.relativity 226
49rec.food.cooking 209
50comp.lang.c++ 195
51sci.physics 169
52rec.arts.sf.written 162
53comp.lang.c 154
54news.lists.filters 140
55rec.sport.football.college 134
56soc.culture.polish 130
57sci.math 129
58alt.russian.z1 126
59soc.retirement 126
60rec.aquaria.marine.misc 120
61sci.logic 114
62rec.boats 110
63linux.debian.bugs.rc 110
64rec.sport.soccer 108
65comp.lang.python 104
66rec.sport.pro-wrestling 104
67talk.politics.guns 103
68soc.culture.brazil 98
69soc.culture.indian 98
70rec.autos.sport.f1 93
71comp.arch 91
72sci.electronics.repair 90
73soc.history.what-if 86
74alt.sixtyplus 85
75soc.penpals 83
76alt.conspiracy.jfk 82
77rec.crafts.metalworking 80
78rec.aviation.marketplace 80
79rec.arts.poems 76
80rec.gambling.poker 76
81nz.general 73
82alt.activism.death-penalty 72
83rec.drugs.cannabis 72
84rec.sport.tennis 72
85talk.origins 72
86alt.politics 71
87rec.arts.sf.fandom 68
88comp.ai.philosophy 62
89alt.test 62
90rec.sport.cricket 62
91rec.sport.golf 62
92uk.legal 61
93comp.lang.forth 60
94sci.military.naval 59
95comp.os.linux.advocacy 56
96rec.music.classical.recordings 56
97alt.skate.figure 55
98talk.politics.misc 55
99mozilla.general 53
100alt.os.linux.mageia 52
TOTAL: 428 37379