news.bbs.geek.nz daily logs

Apr 3 23:58:01 -- Apr 4 23:58:02

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 134290 63.4%11.1 MB 42.5%
innd 27435 13.0%4.5 MB 17.4%
inn 25501 12.0%5.9 MB 22.5%
innfeed 23022 10.9%4.4 MB 16.7%
cnfsstat 1440 0.7%240.4 KB 0.9%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 211689 100.0%26.2 MB100.0%

History cache:

ReasonCount%Count
Negative hits 272713 52.1%
Positive hits 226192 43.2%
Do not exist 24229 4.6%
Cache misses 93 0.0%
TOTAL: 4 523227 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.176 0.0%192 0.000 0.917 33.000
article cleanup00:00:00.180 0.0%24688 0.000 0.007 0.053
article logging00:00:00.722 0.0%25217 0.000 0.029 0.438
article parse00:00:00.726 0.0%54668 0.000 0.013 0.031
article write00:00:01.532 0.0%19687 0.009 0.078 0.665
artlog/artcncl00:00:00.004 0.0%184 0.000 0.022 0.125
data move00:00:00.404 0.0%500562 0.000 0.001 0.018
hisgrep/artcncl00:00:00.002 0.0%168 0.000 0.012 0.333
hishave/artcncl00:00:00.001 0.0%192 0.000 0.005 0.167
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:07.799 0.0%526954 0.001 0.015 0.835
history sync00:00:00.019 0.0%378 0.000 0.050 1.000
history write00:00:07.362 0.0%24354 0.020 0.302 19.493
hiswrite/artcncl00:00:00.001 0.0%24 0.000 0.042 1.000
idle23:56:43.087 99.7%508019 107.744 169.685 439.865
nntp read00:00:02.977 0.0%508081 0.002 0.006 0.016
overview write00:02:05.468 0.1%19687 0.064 6.373 547.369
perl filter00:00:46.366 0.1%24354 1.367 1.904 5.164
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.089 0.0%39374 0.000 0.028 0.078
TOTAL: 24:00:51.01223:59:57.915 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.067 0.0%24786 0.000 0.003 0.019
article prepare00:00:00.001 0.0%6070 0.000 0.000 0.013
article read00:00:00.165 0.0%1428 0.000 0.116 15.000
backlog stats00:00:00.283 0.0%294211 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.567 0.0%242585 0.001 0.006 0.012
data write00:00:02.964 0.0%227465 0.004 0.013 0.020
idle23:53:53.534100.0%294211 183.658 292.421 626.662
status file00:00:16.128 0.0%1834 0.667 8.794 389.583
TOTAL: 23:54:34.97823:54:14.709100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle21:21:05.410 98.6%150565 0.000 510.513 94359.104
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:52.534 0.1%334951 0.000 0.157 1.978
readart00:00:02.839 0.0%16066 0.000 0.177 14.050
TOTAL: 21:39:33.51621:22:00.783 98.6%----

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
1newsfeed.datemas.de 2 20976 5988 13991 997 28%24:01:32
2csiph.com 2 19338 4404 14912 22 22%24:07:56
3feeder.eternal-september.org 37 21910 2282 19133 495 10%35:11:54
4news-out.aioe.org 3 16626 1852 14737 37 11%20:48:59
5news-out.mixmin.net 8 20160 1531 18067 562 7%26:58:57
6news.redatomik.org 3 21400 1448 19810 142 6%27:17:20
7newsfeed.neva.ru 154 9474 1228 5958 2288 12%82:04:44
8news.swapon.de 2 1680 243 1430 7 14%28:49:08
9news.albasani.net 1 13139 178 12869 92 1%13:01:04
10feeder.awxg.com 1 16974 154 16796 24 0%23:59:56
11agency-nz-out.news.mb-net.net 12 22618 130 22383 105 0%30:22:13
12news.samoylyk.net 2 17220 125 17059 36 0%22:34:31
13news.freedyn.net 6 23024 40 22939 45 0%27:00:43
14localhost 5 4 4 0 0 100%00:00:00
TOTAL: 14 238 224543 19607 200084 4852 8%386:18:57
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.datemas.de19.1 MB512.6 KB5.6 MB25.2 MB 75%3.7 KB
2csiph.com14.0 MB93.6 KB110.8 KB14.2 MB 98%3.3 KB
3feeder.eternal-september.org7.9 MB798.2 KB3.8 MB12.4 MB 63%4.6 KB
4news-out.mixmin.net4.9 MB144.5 KB1.2 MB6.3 MB 78%3.1 KB
5news.redatomik.org4.7 MB91.6 KB319.2 KB5.1 MB 92%3.3 KB
6news-out.aioe.org4.6 MB32.4 KB274.7 KB4.9 MB 93%2.7 KB
7newsfeed.neva.ru3.6 MB59.7 KB7.3 MB11.0 MB 33%3.2 KB
8news.albasani.net1.2 MB127.4 KB285.8 KB1.6 MB 74%5.9 KB
9news.samoylyk.net1.1 MB570.3 KB666.1 KB2.3 MB 47%14.7 KB
10news.swapon.de786.3 KB0.0 KB196.6 KB982.9 KB 80%3.9 KB
11agency-nz-out.news.mb-net.net562.2 KB200.1 KB496.7 KB1.2 MB 44%5.4 KB
12feeder.awxg.com517.5 KB60.8 KB125.0 KB703.3 KB 73%4.0 KB
13news.freedyn.net410.1 KB557.6 KB634.9 KB1.6 MB 25%18.9 KB
14localhost12.8 KB0.0 KB0.0 KB12.8 KB100%3.2 KB
TOTAL: 1463.4 MB3.2 MB20.9 MB87.5 MB 72%3.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 3 23:58:01 - 23:59:59 23 0.1% 0.1946.0 KB 0.1% 0.39
Apr 4 00:00:00 - 00:59:59 946 4.8% 0.263.4 MB 5.2% 0.95
Apr 4 01:00:00 - 01:59:59 908 4.6% 0.252.9 MB 4.5% 0.82
Apr 4 02:00:00 - 02:59:59 1039 5.3% 0.293.0 MB 4.6% 0.84
Apr 4 03:00:00 - 03:59:59 1001 5.1% 0.283.5 MB 5.4% 0.99
Apr 4 04:00:00 - 04:59:59 1202 6.1% 0.334.0 MB 6.1% 1.13
Apr 4 05:00:00 - 05:59:59 1007 5.1% 0.283.1 MB 4.9% 0.90
Apr 4 06:00:00 - 06:59:59 1068 5.4% 0.303.4 MB 5.3% 0.97
Apr 4 07:00:00 - 07:59:59 977 5.0% 0.273.0 MB 4.6% 0.85
Apr 4 08:00:00 - 08:59:59 1038 5.3% 0.293.2 MB 4.9% 0.91
Apr 4 09:00:00 - 09:59:59 1036 5.3% 0.293.7 MB 5.8% 1.06
Apr 4 10:00:00 - 10:59:59 834 4.2% 0.232.9 MB 4.4% 0.82
Apr 4 11:00:00 - 11:59:59 629 3.2% 0.172.4 MB 3.7% 0.67
Apr 4 12:00:00 - 12:59:59 501 2.5% 0.141.5 MB 2.4% 0.43
Apr 4 13:00:00 - 13:59:59 494 2.5% 0.141.8 MB 2.8% 0.51
Apr 4 14:00:00 - 14:59:59 399 2.0% 0.111.9 MB 2.9% 0.54
Apr 4 15:00:00 - 15:59:59 424 2.1% 0.121.5 MB 2.4% 0.43
Apr 4 16:00:00 - 16:59:59 440 2.2% 0.121.5 MB 2.3% 0.42
Apr 4 17:00:00 - 17:59:59 512 2.6% 0.141.8 MB 2.7% 0.50
Apr 4 18:00:00 - 18:59:59 728 3.7% 0.202.5 MB 3.8% 0.70
Apr 4 19:00:00 - 19:59:59 880 4.5% 0.242.5 MB 3.9% 0.72
Apr 4 20:00:00 - 20:59:59 895 4.5% 0.252.9 MB 4.5% 0.82
Apr 4 21:00:00 - 21:59:59 937 4.8% 0.263.2 MB 5.0% 0.92
Apr 4 22:00:00 - 22:59:59 883 4.5% 0.252.3 MB 3.6% 0.66
Apr 4 23:00:00 - 23:58:02 923 4.7% 0.272.9 MB 4.5% 0.85
TOTAL: 24:00:01 19724 100.0% 0.2364.7 MB 100.0% 0.77
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB24.8 GB 49.7% 0 0.32 1895.83
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB28.8 GB 57.6% 0 0.29 2127.27
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB313.5 MB 0.6% 0 0.0319669.03
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.2 GB 38.4% 0 0.67 912.07
TOTAL: 10-500.0 GB73.1 GB 14.6% 1 0.40-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 2368 59 0 38 0 0 0 0 2271
2newsfeed.datemas.de 1351 179 0 27 0 0 0 0 1145
3news-out.mixmin.net 647 0 0 32 0 0 0 0 615
4feeder.eternal-september.org 532 0 0 45 0 0 0 0 487
5news.redatomik.org 177 0 0 30 0 0 0 0 147
6news.albasani.net 133 3 0 33 0 0 0 0 97
7agency-nz-out.news.mb-net.net 103 57 0 23 0 0 0 0 23
8news-out.aioe.org 64 30 0 16 0 0 0 0 18
9news.freedyn.net 52 5 0 24 0 0 0 0 23
10news.swapon.de 51 0 0 1 0 0 0 0 50
11news.samoylyk.net 43 0 0 35 0 0 0 0 8
12feeder.awxg.com 33 4 0 24 0 0 0 0 5
13csiph.com 26 0 0 17 0 0 0 0 9
14news.ausics.org 1 0 0 1 0 0 0 0 0
TOTAL: 14 5581 337 0 346 0 0 0 0 4898

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.annunci.immobiliari 50
it.annunci.varie 47
it.annunci.commerciali 42
mpc.lists.nanog 34
gmane.linux.alsa.devel 34
mpc.lists.freebsd.x11 16
mpc.lists.freebsd.office 15
mozilla.dev.security.policy 14
gmane.comp.gis.openstreetmap.tagging 13
gmane.linux.kernel 12
mozilla.support.seamonkey 9
mozilla.dev.ui-alerts 7
mozilla.support.bugzilla 5
alt.fan.snuffles 3
south-wales.test 3
nemo.abuse 3
mozilla.dev.servo 2
mozilla.moss 2
mozilla.tools.taskcluster 2
phl.jobs 1
TOTAL: 43 337

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3867
EMP (phn path) 191
EMP (phn nph) 147
Too many newsgroups 40
Binary: misplaced rar 36
EMP (phl) 22
Binary: non-binary distribution 15
Binary: misplaced par2 14
User-issued cancel 9
EMP (fsl) 7
Cancel for rejected article 5
Binary Image: misplaced png 4
Binary: misplaced 005 3
Binary: misplaced 002 2
Binary: misplaced 004 1
Binary: misplaced 020 1
U2 violation - invalid distribution 1
Binary: misplaced 028 1
Binary: misplaced 029 1
Binary: misplaced 023 1
TOTAL: 21 4369

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.datemas.de 345
news-out.mixmin.net 68
news.swapon.de 46
news.redatomik.org 36
news-out.aioe.org 14
newsfeed.neva.ru 7
news.albasani.net 5
feeder.awxg.com 3
feeder.eternal-september.org 3
news.samoylyk.net 2
TOTAL: 10 529
TOTAL: 1 529

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 18013 413 17554 45 1 1 2%23:59:41
2awxg 19340 23 19316 1 0 0 0%23:59:21
3news.freedyn.net 19835 8 19668 4 0 23 0%23:59:39
4aioe 16462 6 16389 1 0 0 0%23:59:40
5neva.ru 18049 4 18038 1 0 0 0%23:59:40
6eternal-september 16721 4 16697 0 0 4 0%23:59:40
7news.redatomik.org 18270 4 18261 0 0 18 0%23:59:40
8csiph.com 15388 4 15306 5 0 0 0%23:59:40
9news.samoylyk.net 17957 4 17872 0 0 75 0%23:59:40
10news.swapon.de 1429 3 1416 0 0 10 0%23:55:17
11newsfeed.datemas.de 13727 3 13646 0 0 81 0%23:59:40
12news-in.mixmin.net 17387 3 17345 0 0 88 0%23:59:40
13mb-net.net 19600 3 19570 0 0 98 0%23:59:39
14news.albasani.net 19058 1 16933 921 0 4759 0%23:59:40
15news.snarked.org 0 0 0 0 0 19642 0%23:59:45
TOTAL: 15 231236 483 228011 978 1 24799 0%359:50:22
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org6.0 MB864.2 KB6.8 MB0.1 KB/s15.2 KB23:59:41
2awxg78.1 KB9.7 KB87.8 KB0.0 KB/s3.7 KB23:59:21
3news.freedyn.net20.7 KB162.4 KB183.1 KB0.0 KB/s15.3 KB23:59:39
4aioe16.7 KB5.4 KB22.0 KB0.0 KB/s3.1 KB23:59:40
5eternal-september12.8 KB0.0 KB12.8 KB0.0 KB/s3.2 KB23:59:40
6news.redatomik.org12.8 KB0.0 KB12.8 KB0.0 KB/s3.2 KB23:59:40
7csiph.com12.8 KB301.3 KB314.1 KB0.0 KB/s34.9 KB23:59:40
8news.samoylyk.net12.8 KB0.0 KB12.8 KB0.0 KB/s3.2 KB23:59:40
9neva.ru10.7 KB4.3 KB14.9 KB0.0 KB/s3.0 KB23:59:40
10news-in.mixmin.net10.1 KB0.0 KB10.1 KB0.0 KB/s3.4 KB23:59:40
11mb-net.net10.1 KB0.0 KB10.1 KB0.0 KB/s3.4 KB23:59:39
12news.swapon.de9.6 KB0.0 KB9.6 KB0.0 KB/s3.2 KB23:55:17
13newsfeed.datemas.de8.5 KB0.0 KB8.5 KB0.0 KB/s2.8 KB23:59:40
14news.albasani.net4.3 KB4.1 MB4.1 MB0.0 KB/s4.5 KB23:59:40
15news.snarked.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
TOTAL: 156.2 MB5.4 MB11.6 MB0.0 KB/s8.1 KB359:50:22
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
167-61-82-242.cpe.cableone.net 7 818330.9 MB 1065 0 1401:50:37
2agency.bbs.geek.nz 388 611323.4 MB 3379 4 000:12:50
3vorlon.activateit.net.au 24 12171.7 MB 126 0 000:05:26
467-60-219-146.cpe.cableone.net 3 16.4 KB 1 0 019:14:03
TOTAL: 4 422 1551456.0 MB 4571 4 1421:39:26

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cpe.cableone.net 10 818430.9 MB 1066 0 1421:04:41
2*.bbs.geek.nz 388 611323.4 MB 3379 4 000:12:50
3*.activateit.net.au 24 12171.7 MB 126 0 000:05:26
4unresolved 7 00.0 KB 0 0 000:16:25
TOTAL: 4 429 1551456.0 MB 4571 4 1421:39:26

NNRP auth users [Top 20]:

UserConn
amybbs 24
bpanther 7
malcolmlewis 3
TOTAL: 3 34

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-60-219-146.cpe.cableone.net 0.316 0.076 0.01219:14:03
67-61-82-242.cpe.cableone.net 1.464 1.124 0.04301:50:37
agency.bbs.geek.nz 28.280 6.344 0.00000:12:50
118.149.215.107 0.172 0.032 0.00000:11:15
vorlon.activateit.net.au 1.408 0.400 0.18500:05:26
118.149.186.22 0.060 0.008 0.00000:05:01
94.161.87.72 0.056 0.008 0.00000:00:02
5.86.1.227 0.048 0.016 0.00000:00:02
94.161.84.158 0.044 0.012 0.00000:00:02
? 1.228 0.184 0.00000:00:02
TOTAL: 10 33.076 8.204 0.24021:39:26

Curious NNRP server explorers [Top 20]:

SystemConn
118.149.215.107 3
5.86.1.227 1
94.161.84.158 1
118.149.186.22 1
94.161.87.72 1
TOTAL: 5 7

NNRP gethostbyaddr failures [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1rec 4955 31.9%
2soc 2209 14.2%
3alt 1830 11.8%
4sci 1412 9.1%
5comp 1356 8.7%
6linux 1191 7.7%
7uk 1086 7.0%
8talk 773 5.0%
9misc 304 2.0%
10news 252 1.6%
11nz 64 0.4%
12mozilla 23 0.1%
13microsoft 21 0.1%
14humanities 15 0.1%
15perl 13 0.1%
16aus 10 0.1%
TOTAL: 16 15514100.0%

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

NewsgroupCount
1rec.arts.tv 1687
2alt.atheism 493
3soc.retirement 491
4rec.arts.drwho 490
5linux.debian.bugs.dist 419
6uk.legal 418
7alt.fan.rush-limbaugh 408
8uk.d-i-y 361
9sci.electronics.design 345
10talk.politics.guns 341
11soc.culture.china 310
12sci.physics 301
13rec.food.cooking 292
14talk.origins 290
15linux.debian.changes.devel 254
16alt.politics.trump 249
17rec.gambling.poker 243
18soc.culture.polish 238
19soc.culture.israel 225
20comp.lang.python 225
21news.lists.filters 217
22rec.boats 213
23sci.physics.relativity 205
24linux.debian.bugs.rc 200
25misc.survivalism 168
26rec.sport.football.college 168
27sci.math 163
28rec.drugs.cannabis 152
29rec.sport.pro-wrestling 143
30alt.comp.os.windows-10 131
31talk.politics.misc 116
32comp.os.linux.advocacy 114
33soc.culture.usa 108
34comp.lang.c 108
35rec.aquaria.marine.misc 105
36rec.arts.sf.written 102
37sci.logic 99
38soc.history.what-if 95
39rec.aviation.soaring 94
40comp.os.vms 92
41comp.sys.mac.apps 90
42rec.music.rock-pop-r+b.1950s 88
43rec.autos.sport.f1 79
44rec.sport.soccer 78
45alt.bible.prophecy 76
46uk.legal.moderated 72
47soc.culture.indian 71
48alt.politics 71
49rec.aviation.marketplace 70
50linux.debian.user 68
51sci.math.num-analysis 66
52rec.photo.digital 64
53soc.culture.singapore 63
54soc.culture.brazil 63
55uk.comp.sys.mac 62
56nz.general 61
57rec.sport.tennis 61
58soc.culture.bulgaria 60
59rec.sport.golf 59
60comp.protocols.time.ntp 56
61alt.test 56
62soc.genealogy.britain 55
63comp.mobile.ipad 54
64rec.music.classical.recordings 51
65uk.tech.digital-tv 50
66linux.gentoo.user 48
67comp.ai.philosophy 48
68misc.phone.mobile.iphone 48
69sci.lang 47
70rec.bicycles.tech 44
71comp.mobile.android 43
72alt.recovery.aa 42
73rec.radio.shortwave 42
74comp.sys.mac.system 42
75soc.culture.vietnamese 42
76alt.conspiracy 41
77comp.sys.cbm 39
78rec.arts.movies.current-films 38
79rec.woodworking 38
80comp.lang.c++ 37
81soc.penpals 36
82comp.arch 35
83news.admin.net-abuse.email 34
84sci.med.cardiology 33
85comp.lang.ada 32
86rec.arts.sf.fandom 32
87rec.sport.cricket 31
88alt.politics.usa 31
89alt.support.diabetes 31
90comp.lang.forth 30
91comp.sys.acorn.misc 29
92soc.history 28
93soc.culture.greek 28
94soc.genealogy.medieval 28
95comp.sys.mac.advocacy 28
96comp.soft-sys.sas 28
97soc.history.medieval 27
98alt.conspiracy.jfk 27
99rec.aviation.student 26
100misc.invest.stocks 26
TOTAL: 373 15514