news.bbs.geek.nz daily logs

Jun 15 23:58:01 -- Jun 16 23:58:01

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 98606 51.6%8.2 MB 32.0%
inn 33799 17.7%7.3 MB 28.3%
innd 33683 17.6%5.4 MB 21.2%
innfeed 23469 12.3%4.5 MB 17.5%
cnfsstat 1440 0.8%249.5 KB 1.0%
controlchan 5 0.0%0.8 KB 0.0%
TOTAL: 6 191002 100.0%25.6 MB100.0%

History cache:

ReasonCount%Count
Negative hits 326797 48.7%
Positive hits 312525 46.6%
Do not exist 31551 4.7%
Cache misses 360 0.1%
TOTAL: 4 671233 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.540 0.0%197 0.000 2.741 24.500
article cleanup00:00:00.349 0.0%33111 0.000 0.011 0.045
article logging00:00:00.943 0.0%33619 0.000 0.028 0.077
article parse00:00:00.932 0.0%82476 0.000 0.011 0.028
article write00:00:03.180 0.0%23740 0.013 0.134 6.244
artlog/artcncl00:00:00.001 0.0%184 0.000 0.005 0.056
data move00:00:00.472 0.0%613105 0.000 0.001 0.004
hisgrep/artcncl00:00:00.241 0.0%194 0.000 1.242 9.000
hishave/artcncl00:00:00.080 0.0%197 0.000 0.406 4.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:01:23.321 0.1%680562 0.003 0.122 0.933
history sync00:00:00.118 0.0%362 0.000 0.326 23.500
history write00:03:32.859 0.2%31903 0.036 6.672 26.948
hiswrite/artcncl00:00:00.009 0.0%3 0.000 3.000 9.000
idle23:52:01.565 99.4%626305 75.095 137.188 405.363
nntp read00:00:05.407 0.0%633138 0.004 0.009 0.019
overview write00:00:43.902 0.1%23740 0.051 1.849 56.579
perl filter00:01:13.612 0.1%31903 1.178 2.307 23.384
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.036 0.0%47481 0.000 0.022 0.098
TOTAL: 24:00:47.90423:59:08.567 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.083 0.0%32418 0.000 0.003 0.014
article prepare00:00:00.000 0.0%10406 0.000 0.000 0.000
article read00:00:00.418 0.0%2411 0.000 0.173 1.827
backlog stats00:00:00.683 0.0%348689 0.000 0.002 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.871 0.0%300799 0.003 0.010 0.017
data write00:00:03.332 0.0%291374 0.005 0.011 0.024
idle23:52:50.476 99.9%348689 125.537 246.553 628.667
status file00:00:29.313 0.0%1979 0.385 14.812 659.293
TOTAL: 23:53:46.97623:53:27.176100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%1 0.000 0.000 0.000
idle20:41:58.966 82.2%111915 0.000 665.853 64151.755
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite04:19:37.628 17.2%968295 0.000 16.088 33210.571
readart00:00:10.151 0.0%11495 0.000 0.883 48.944
TOTAL: 25:11:31.76825:01:46.745 99.4%----

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org001011
TOTAL001011

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.datemas.de 3 25177 8301 15890 986 32%24:18:09
2news.snarked.org 58 27787 6387 16321 5079 22%60:11:55
3news.redatomik.org 3 28928 2397 26410 121 8%32:12:21
4feeder.eternal-september.org 124 30726 1662 28629 435 5%67:45:38
5csiph.com 3 23983 1389 22498 96 5%24:29:29
6newsfeed.neva.ru 157 19927 1369 17837 721 6%89:30:35
7news-out.mixmin.net 20 25866 1196 23968 702 4%32:19:50
8news.albasani.net 1 26309 329 25583 397 1%25:23:54
9news-out.aioe.org 1 16652 231 16368 53 1%15:18:11
10news.samoylyk.net 1 31725 218 31117 390 0%24:47:27
11news.freedyn.net 3 29740 151 29420 169 0%30:09:48
12feeder.awxg.com 2 20824 110 20614 100 0%23:59:29
13news.swapon.de 13 1781 86 1679 16 4%28:17:02
14agency-nz-out.news.mb-net.net 28 25257 60 25087 110 0%36:55:59
15localhost 4 3 3 0 0 100%00:00:00
TOTAL: 15 421 334685 23889 301421 9375 7%515:39:47
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.datemas.de24.3 MB520.4 KB2.5 MB27.3 MB 89%3.0 KB
2news.snarked.org24.1 MB581.6 KB33.6 MB58.2 MB 41%5.2 KB
3news.redatomik.org6.1 MB537.6 KB544.2 KB7.1 MB 85%2.9 KB
4feeder.eternal-september.org5.7 MB649.6 KB3.1 MB9.4 MB 60%4.6 KB
5news-out.mixmin.net4.7 MB515.1 KB1.7 MB6.8 MB 68%3.7 KB
6newsfeed.neva.ru3.5 MB211.6 KB2.6 MB6.3 MB 55%3.1 KB
7csiph.com3.2 MB329.5 KB341.2 KB3.8 MB 82%2.7 KB
8news.albasani.net1.8 MB766.6 KB1.4 MB3.9 MB 46%5.6 KB
9news.samoylyk.net1.5 MB496.6 KB2.4 MB4.4 MB 34%7.5 KB
10news.freedyn.net1.3 MB388.9 KB692.6 KB2.3 MB 54%7.4 KB
11news-out.aioe.org601.1 KB134.9 KB205.9 KB941.9 KB 63%3.3 KB
12feeder.awxg.com331.0 KB415.3 KB418.9 KB1.1 MB 28%5.5 KB
13agency-nz-out.news.mb-net.net262.7 KB415.0 KB891.7 KB1.5 MB 16%9.2 KB
14news.swapon.de230.0 KB40.1 KB40.8 KB310.9 KB 73%3.0 KB
15localhost5.4 KB0.0 KB0.0 KB5.4 KB100%1.8 KB
TOTAL: 1577.7 MB5.9 MB50.2 MB133.8 MB 58%4.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 15 23:58:01 - 23:59:59 27 0.1% 0.2372.5 KB 0.1% 0.61
Jun 16 00:00:00 - 00:59:59 1158 4.9% 0.322.9 MB 3.7% 0.82
Jun 16 01:00:00 - 01:59:59 1310 5.5% 0.364.3 MB 5.6% 1.23
Jun 16 02:00:00 - 02:59:59 1502 6.3% 0.424.5 MB 5.8% 1.29
Jun 16 03:00:00 - 03:59:59 1530 6.5% 0.425.2 MB 6.7% 1.49
Jun 16 04:00:00 - 04:59:59 1516 6.4% 0.424.9 MB 6.3% 1.41
Jun 16 05:00:00 - 05:59:59 1227 5.2% 0.343.5 MB 4.5% 0.99
Jun 16 06:00:00 - 06:59:59 1406 5.9% 0.396.3 MB 8.1% 1.80
Jun 16 07:00:00 - 07:59:59 1173 4.9% 0.333.2 MB 4.1% 0.91
Jun 16 08:00:00 - 08:59:59 1268 5.3% 0.354.6 MB 5.9% 1.30
Jun 16 09:00:00 - 09:59:59 1053 4.4% 0.293.3 MB 4.3% 0.94
Jun 16 10:00:00 - 10:59:59 870 3.7% 0.243.1 MB 4.0% 0.89
Jun 16 11:00:00 - 11:59:59 613 2.6% 0.172.2 MB 2.8% 0.61
Jun 16 12:00:00 - 12:59:59 555 2.3% 0.151.7 MB 2.1% 0.47
Jun 16 13:00:00 - 13:59:59 406 1.7% 0.111.6 MB 2.0% 0.45
Jun 16 14:00:00 - 14:59:59 436 1.8% 0.121.8 MB 2.2% 0.50
Jun 16 15:00:00 - 15:59:59 469 2.0% 0.132.4 MB 3.1% 0.68
Jun 16 16:00:00 - 16:59:59 605 2.6% 0.172.7 MB 3.5% 0.77
Jun 16 17:00:00 - 17:59:59 521 2.2% 0.142.0 MB 2.6% 0.57
Jun 16 18:00:00 - 18:59:59 863 3.6% 0.242.8 MB 3.5% 0.78
Jun 16 19:00:00 - 19:59:59 1122 4.7% 0.313.0 MB 3.9% 0.85
Jun 16 20:00:00 - 20:59:59 1095 4.6% 0.303.3 MB 4.3% 0.95
Jun 16 21:00:00 - 21:59:59 1009 4.3% 0.282.7 MB 3.5% 0.77
Jun 16 22:00:00 - 22:59:59 1068 4.5% 0.302.9 MB 3.7% 0.81
Jun 16 23:00:00 - 23:58:01 909 3.8% 0.262.9 MB 3.8% 0.86
TOTAL: 24:00:00 23711 100.0% 0.2777.9 MB 100.0% 0.92
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB17.7 GB 35.4% 0 0.34 1782.28
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB20.6 GB 41.3% 0 0.37 1633.39
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB221.4 MB 0.4% 0 0.0417211.69
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 GB3.0 GB 6.0% 0 0.80 761.25
TOTAL: 10-500.0 GB41.5 GB 8.3% 1 0.49-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.snarked.org 5239 54 0 118 0 0 0 0 5067
2newsfeed.datemas.de 1367 144 0 91 0 0 0 0 1132
3newsfeed.neva.ru 734 128 0 68 2 0 0 0 536
4news-out.mixmin.net 693 0 0 112 0 0 0 0 581
5feeder.eternal-september.org 437 0 0 147 0 0 0 0 290
6news.samoylyk.net 413 0 0 112 0 0 0 0 301
7news.albasani.net 382 5 0 96 0 0 0 0 281
8news.redatomik.org 138 0 0 92 0 0 0 0 46
9news.freedyn.net 122 8 0 68 0 0 0 0 46
10agency-nz-out.news.mb-net.net 110 11 0 65 0 0 0 0 34
11feeder.awxg.com 90 2 0 87 0 0 0 0 1
12csiph.com 86 0 0 81 0 0 0 0 5
13news-out.aioe.org 70 3 0 62 0 0 0 0 5
14news.swapon.de 23 0 0 17 0 0 0 0 6
TOTAL: 14 9904 355 0 1216 2 0 0 0 8331

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 130
mozilla.support.seamonkey 26
mozilla.dev.platform 25
gmane.linux.alsa.devel 22
gmane.comp.gis.openstreetmap.tagging 22
gmane.linux.kernel.renesas-soc 17
gmane.linux.kernel 8
mpc.lists.freebsd.pf 8
gmane.linux.network 7
eagle.support.eng 7
mozilla.dev.ui-alerts 7
mozilla.dev.builds 5
argonet.cfc 5
mpc.lists.freebsd.ports.bugs 5
mozilla.tools.taskcluster 4
mpc.lists.freebsd.gecko 4
mpc.lists.nanog 4
eagle.suggest.eng 4
mpc.lists.freebsd.gnome 3
gmane.linux.alsa.user 3
TOTAL: 49 355

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
uk.legal.moderated 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6929
EMP (phn path) 627
EMP (phn nph) 132
EMP (phl) 58
Too many newsgroups 35
User-issued cancel 19
Binary: non-binary distribution 12
Poison newsgroup 5
HTML post 4
Too many newsgroups (low_xpost) 1
TOTAL: 10 7822

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.datemas.de 403
news.redatomik.org 42
news-out.mixmin.net 17
news.snarked.org 16
news.albasani.net 15
newsfeed.neva.ru 6
news.swapon.de 5
csiph.com 2
news-out.aioe.org 1
feeder.awxg.com 1
TOTAL: 11 509
TOTAL: 1 509

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 21713 842 20759 111 1 0 3%23:58:49
2awxg 23209 101 23078 8 0 11 0%23:59:45
3news.snarked.org 17144 18 17019 0 0 17 0%23:59:30
4neva.ru 21411 6 21400 0 0 31 0%23:59:14
5news.freedyn.net 23703 6 23574 1 0 13 0%23:59:20
6mb-net.net 23647 3 23598 0 0 80 0%23:59:46
7news-in.mixmin.net 20716 3 20700 0 0 0 0%23:59:24
8news.redatomik.org 21390 3 21379 0 0 5 0%23:59:41
9aioe 20418 3 20296 0 0 0 0%23:59:46
10csiph.com 22295 3 22255 1 0 0 0%23:59:14
11news.samoylyk.net 21542 3 21513 0 0 66 0%23:58:52
12newsfeed.datemas.de 15517 1 15471 0 0 108 0%23:59:32
13news.albasani.net 23212 0 19442 1340 0 8379 0%23:59:45
14eternal-september 19978 0 19903 0 0 245 0%23:59:46
15news.swapon.de 2094 0 2094 0 0 0 0%23:58:51
TOTAL: 15 297989 992 292481 1461 1 8955 0%359:51:15
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org3.7 MB9.5 MB13.2 MB0.2 KB/s14.2 KB23:58:49
2awxg469.6 KB38.6 KB508.2 KB0.0 KB/s4.7 KB23:59:45
3news.snarked.org38.0 KB0.0 KB38.0 KB0.0 KB/s2.1 KB23:59:30
4neva.ru14.9 KB0.0 KB14.9 KB0.0 KB/s2.5 KB23:59:14
5news.freedyn.net12.7 KB5.9 KB18.6 KB0.0 KB/s2.7 KB23:59:20
6mb-net.net6.1 KB0.0 KB6.1 KB0.0 KB/s2.0 KB23:59:46
7news-in.mixmin.net5.4 KB0.0 KB5.4 KB0.0 KB/s1.8 KB23:59:24
8news.redatomik.org5.4 KB0.0 KB5.4 KB0.0 KB/s1.8 KB23:59:41
9aioe5.4 KB0.0 KB5.4 KB0.0 KB/s1.8 KB23:59:46
10csiph.com5.4 KB4.5 KB9.9 KB0.0 KB/s2.5 KB23:59:14
11news.samoylyk.net5.4 KB0.0 KB5.4 KB0.0 KB/s1.8 KB23:58:52
12newsfeed.datemas.de2.0 KB0.0 KB2.0 KB0.0 KB/s2.0 KB23:59:32
13news.albasani.net0.0 KB4.2 MB4.2 MB0.0 KB/s3.2 KB23:59:45
14eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
15news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:51
TOTAL: 154.3 MB13.7 MB18.0 MB0.0 KB/s7.5 KB359:51:15
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.geek.nz 323 707023.4 MB 3298 3 000:11:09
2kosh.activateit.net.au 24 13351.8 MB 169 0 000:05:42
3mtrlpq2314w-lp140-04-70-53-246-1.dsl.bell.ca 821 11577.6 MB 930 0 009:54:09
TOTAL: 31168 956232.7 MB 4397 3 025:11:19

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.geek.nz 323 707023.4 MB 3298 3 000:11:09
2*.activateit.net.au 24 13351.8 MB 169 0 000:05:42
3*.dsl.bell.ca 821 11577.6 MB 930 0 009:54:09
4*.cpe.cableone.net 1 00.0 KB 0 0 015:00:16
TOTAL: 41169 956232.7 MB 4397 3 025:11:19

NNRP auth users [Top 20]:

UserConn
amybbs 24
malcolmlewis 1
TOTAL: 2 25

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-61-218-236.cpe.cableone.net 0.088 0.064 0.00315:00:16
mtrlpq2314w-lp140-04-70-53-246-1.dsl.bell.ca 131.168 23.744 0.00009:54:09
agency.bbs.geek.nz 23.996 5.932 0.00000:11:09
kosh.activateit.net.au 1.652 0.448 0.15800:05:42
? 0.824 0.084 0.00000:00:01
TOTAL: 5 157.728 30.272 0.16125:11:19

Curious NNRP server explorers [Top 20]:

SystemConn
67-61-218-236.cpe.cableone.net 1
TOTAL: 1 1

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

SystemConnPeer
mtrlpq2314w-lp140-04-70-53-246-1.dsl.bell.ca 34 34
TOTAL: 1 34 34

Newsgroup request counts (by category):

CategoryCountPct
1rec 3792 39.7%
2alt 1550 16.2%
3comp 1457 15.2%
4soc 1061 11.1%
5sci 633 6.6%
6misc 283 3.0%
7talk 260 2.7%
8uk 170 1.8%
9news 129 1.3%
1024hoursupport 121 1.3%
11cakewalk 42 0.4%
12nz 14 0.1%
13linux 14 0.1%
14fr 12 0.1%
15humanities 10 0.1%
16aus 10 0.1%
17tx 3 0.0%
18can 1 0.0%
TOTAL: 18 9562100.0%

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

NewsgroupCount
1rec.arts.tv 905
2rec.arts.drwho 724
3comp.os.linux.advocacy 531
4alt.comp.os.windows-10 306
5alt.home.repair 263
6rec.sport.tennis 262
7comp.lang.c 250
8soc.culture.israel 220
9rec.arts.sf.written 200
10comp.lang.python 180
11rec.food.cooking 176
12soc.retirement 153
13sci.electronics.design 148
14misc.survivalism 141
15sci.physics 132
16rec.sport.pro-wrestling 128
17alt.politics 124
1824hoursupport.helpdesk 121
19alt.politics.usa 111
20rec.sport.golf 106
21talk.politics.misc 106
22rec.sport.football.college 106
23alt.test 104
24soc.culture.china 101
25comp.mobile.android 99
26alt.survival 98
27rec.gambling.poker 97
28news.lists.filters 94
29alt.astronomy 93
30sci.math 92
31alt.windows7.general 91
32alt.philosophy 91
33alt.computer.workshop 90
34rec.boats 89
35talk.origins 82
36alt.bible.prophecy 81
37soc.culture.polish 75
38soc.culture.cuba 69
39rec.bicycles.tech 68
40rec.audio.pro 66
41sci.physics.relativity 64
42misc.test 64
43talk.politics.guns 62
44uk.comp.homebuilt 60
45rec.music.rock-pop-r+b.1960s 59
46rec.photo.digital 54
47soc.culture.bulgaria 52
48soc.penpals 51
49soc.culture.usa 51
50soc.culture.vietnamese 51
51soc.culture.indian 49
52rec.sport.soccer 48
53rec.music.makers.guitar.jazz 46
54uk.radio.amateur 45
55soc.culture.singapore 42
56cakewalk.audio 42
57rec.music.dylan 41
58rec.woodworking 40
59comp.lang.forth 37
60sci.med.pharmacy 36
61rec.music.classical.recordings 34
62sci.logic 33
63rec.music.classical.guitar 32
64rec.outdoors.rv-travel 31
65misc.health.diabetes 31
66rec.games.pinball 30
67rec.arts.comics.strips 30
68rec.arts.movies.current-films 28
69alt.marketplace 26
70uk.comp.os.linux 24
71sci.military.naval 24
72comp.os.linux.misc 23
73rec.music.beatles 23
74comp.arch.embedded 22
75comp.os.vms 22
76soc.culture.brazil 21
77rec.games.trivia 20
78rec.music.rock-pop-r+b.1950s 20
79rec.food.restaurants 20
80uk.tech.broadcast 20
81rec.radio.amateur.misc 18
82comp.lang.c++ 18
83comp.soft-sys.matlab 17
84rec.radio.amateur.dx 17
85comp.soft-sys.sas 17
86comp.sys.apple2 16
87comp.soft-sys.math.scilab 16
88sci.electronics.repair 16
89sci.crypt 16
90misc.health.alternative 15
91sci.physics.electromag 15
92rec.nude 15
93soc.men 15
94soc.genealogy.benelux 14
95rec.sport.cricket 14
96news.software.readers 14
97nz.general 14
98rec.arts.movies.past-films 14
99soc.culture.jewish 13
100linux.debian.bugs.dist 13
TOTAL: 336 9562