news.bbs.geek.nz daily logs

Oct 1 23:58:02 -- Oct 2 23:58:02

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 53103 38.8%4.3 MB 20.9%
innd 30402 22.2%5.0 MB 24.2%
inn 28926 21.1%6.5 MB 31.6%
innfeed 23068 16.8%4.5 MB 22.0%
cnfsstat 1440 1.1%255.5 KB 1.2%
controlchan 9 0.0%1.2 KB 0.0%
TOTAL: 6 136948 100.0%20.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 279828 48.4%
Negative hits 270542 46.8%
Do not exist 26822 4.6%
Cache misses 985 0.2%
TOTAL: 4 578177 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.625 0.0%448 0.000 1.395 11.000
article cleanup00:00:00.264 0.0%28004 0.000 0.009 0.044
article logging00:00:00.743 0.0%28501 0.000 0.026 0.119
article parse00:00:01.003 0.0%93027 0.000 0.011 0.028
article write00:00:01.630 0.0%21512 0.000 0.076 0.267
artlog/artcncl00:00:00.006 0.0%439 0.000 0.014 0.083
data move00:00:00.677 0.0%556992 0.000 0.001 0.064
hisgrep/artcncl00:00:00.005 0.0%440 0.000 0.011 0.016
hishave/artcncl00:00:00.003 0.0%448 0.000 0.007 0.010
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:06.607 0.0%578744 0.001 0.011 0.636
history sync00:00:00.023 0.0%361 0.000 0.064 1.000
history write00:00:05.621 0.0%26856 0.016 0.209 14.185
hiswrite/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
idle23:57:49.654 99.8%571848 79.035 150.861 458.516
nntp read00:00:03.544 0.0%573605 0.003 0.006 0.038
overview write00:00:40.200 0.0%21512 0.052 1.869 66.026
perl filter00:00:51.855 0.1%26856 1.284 1.931 3.749
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.297 0.0%43026 0.006 0.030 0.108
TOTAL: 24:00:47.35823:59:43.757 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.101 0.0%54460 0.000 0.002 0.023
article prepare00:00:00.002 0.0%12716 0.000 0.000 0.009
article read00:00:00.232 0.0%3021 0.000 0.077 4.500
backlog stats00:00:00.334 0.0%353741 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.727 0.0%290749 0.001 0.006 0.011
data write00:00:03.135 0.0%277111 0.005 0.011 0.017
idle23:53:42.331 99.9%353741 96.071 243.179 610.443
status file00:00:30.789 0.0%1849 0.600 16.652 715.429
TOTAL: 23:54:35.14423:54:18.651100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%7 0.000 0.000 0.000
idle03:04:27.513 86.9%64902 0.000 170.527 75222.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:04:58.258 2.3%156599 0.000 1.905 19.651
readart00:00:03.809 0.0%14707 0.000 0.259 45.815
TOTAL: 03:32:21.03303:09:29.580 89.2%----

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
hun-mnt@news.sztaki.hu001011
moderator@dana.de001011
TOTAL002022

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.snarked.org 58 20187 5843 13814 530 28%61:39:51
2news-out.aioe.org 2 22268 3055 19048 165 13%26:47:09
3news-out.mixmin.net 17 23949 2912 19709 1328 12%32:47:26
4newsfeed.datemas.de 3 21448 2904 17940 604 13%22:39:05
5feeder.eternal-september.org 98 26193 2085 23274 834 7%64:25:19
6newsfeed.neva.ru 149 15718 1453 12774 1491 9%95:45:44
7news.redatomik.org 1 19085 1094 17896 95 5%22:59:42
8news.albasani.net 2 22197 747 21050 400 3%20:50:22
9csiph.com 4 16609 567 15989 53 3%18:58:59
10news.samoylyk.net 2 26667 253 25771 643 0%27:51:44
11news.swapon.de 2 1893 173 1713 7 9%18:55:36
12feeder.awxg.com 2 16723 103 16531 89 0%23:59:57
13agency-nz-out.news.mb-net.net 21 25916 83 25584 249 0%40:08:28
14news.freedyn.net 3 30739 66 30533 140 0%33:23:55
15localhost 2 1 1 0 0 100%00:00:00
TOTAL: 15 366 289593 21339 261626 6628 7%511:13:17
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.snarked.org26.2 MB349.4 KB1.9 MB28.5 MB 92%4.6 KB
2newsfeed.datemas.de11.2 MB614.4 KB2.0 MB13.9 MB 80%4.0 KB
3feeder.eternal-september.org10.5 MB746.3 KB4.6 MB15.9 MB 66%5.6 KB
4news-out.mixmin.net9.4 MB450.6 KB2.9 MB12.7 MB 73%3.1 KB
5news-out.aioe.org7.2 MB190.2 KB463.1 KB7.8 MB 91%2.5 KB
6newsfeed.neva.ru3.9 MB319.0 KB6.2 MB10.4 MB 37%3.6 KB
7news.albasani.net3.1 MB596.1 KB1.3 MB5.0 MB 62%4.5 KB
8news.redatomik.org2.7 MB399.5 KB443.9 KB3.5 MB 76%3.0 KB
9news.samoylyk.net1.6 MB983.3 KB19.5 MB22.1 MB 7%25.2 KB
10csiph.com1.5 MB196.7 KB201.8 KB1.9 MB 79%3.1 KB
11news.swapon.de851.4 KB228.3 KB365.1 KB1.4 MB 58%8.0 KB
12news.freedyn.net499.0 KB762.4 KB1.4 MB2.6 MB 18%12.8 KB
13agency-nz-out.news.mb-net.net433.7 KB406.2 KB11.6 MB12.4 MB 3%38.2 KB
14feeder.awxg.com305.5 KB419.0 KB455.2 KB1.2 MB 25%6.1 KB
15localhost3.2 KB0.0 KB0.0 KB3.2 KB100%3.2 KB
TOTAL: 1579.4 MB6.5 MB53.2 MB139.1 MB 57%5.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 1 23:58:02 - 23:59:59 18 0.1% 0.1534.0 KB 0.0% 0.29
Oct 2 00:00:00 - 00:59:59 870 4.0% 0.244.4 MB 5.5% 1.25
Oct 2 01:00:00 - 01:59:59 937 4.4% 0.263.0 MB 3.7% 0.85
Oct 2 02:00:00 - 02:59:59 1046 4.9% 0.294.2 MB 5.2% 1.20
Oct 2 03:00:00 - 03:59:59 1034 4.8% 0.293.9 MB 4.8% 1.10
Oct 2 04:00:00 - 04:59:59 1138 5.3% 0.324.9 MB 6.1% 1.39
Oct 2 05:00:00 - 05:59:59 1256 5.8% 0.355.0 MB 6.3% 1.43
Oct 2 06:00:00 - 06:59:59 1190 5.5% 0.334.7 MB 5.9% 1.34
Oct 2 07:00:00 - 07:59:59 1276 5.9% 0.355.5 MB 6.8% 1.55
Oct 2 08:00:00 - 08:59:59 1290 6.0% 0.365.5 MB 6.9% 1.56
Oct 2 09:00:00 - 09:59:59 1132 5.3% 0.314.0 MB 5.0% 1.13
Oct 2 10:00:00 - 10:59:59 968 4.5% 0.272.7 MB 3.4% 0.77
Oct 2 11:00:00 - 11:59:59 776 3.6% 0.222.9 MB 3.7% 0.84
Oct 2 12:00:00 - 12:59:59 665 3.1% 0.182.4 MB 3.0% 0.68
Oct 2 13:00:00 - 13:59:59 590 2.7% 0.162.1 MB 2.6% 0.59
Oct 2 14:00:00 - 14:59:59 442 2.1% 0.121.9 MB 2.4% 0.55
Oct 2 15:00:00 - 15:59:59 507 2.4% 0.141.9 MB 2.4% 0.55
Oct 2 16:00:00 - 16:59:59 385 1.8% 0.111.3 MB 1.7% 0.38
Oct 2 17:00:00 - 17:59:59 800 3.7% 0.223.7 MB 4.6% 1.04
Oct 2 18:00:00 - 18:59:59 539 2.5% 0.152.7 MB 3.4% 0.77
Oct 2 19:00:00 - 19:59:59 707 3.3% 0.202.1 MB 2.6% 0.60
Oct 2 20:00:00 - 20:59:59 941 4.4% 0.262.9 MB 3.6% 0.83
Oct 2 21:00:00 - 21:59:59 879 4.1% 0.242.2 MB 2.7% 0.62
Oct 2 22:00:00 - 22:59:59 1044 4.9% 0.293.5 MB 4.4% 1.01
Oct 2 23:00:00 - 23:58:02 1078 5.0% 0.312.8 MB 3.5% 0.82
TOTAL: 24:00:00 21508 100.0% 0.2580.2 MB 100.0% 0.95
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
BUFF01BIG850.0 GB20.5 GB 40.9% 0 0.45 1346.70
BUFF02BIG850.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF03ALT50.0 GB23.7 GB 47.3% 0 0.29 2124.04
BUFF04ALT50.0 GB1.6 MB 0.0% 0 0.00 0.00
BUFF05BIN50.0 GB252.8 MB 0.5% 0 0.0323295.02
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 GB9.3 GB 18.5% 0 0.80 759.60
TOTAL: 10-500.0 GB53.6 GB 10.7% 1 0.50-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news-out.mixmin.net 1468 0 0 99 0 0 0 0 1369
2newsfeed.neva.ru 1448 80 0 80 0 0 0 0 1288
3feeder.eternal-september.org 818 0 0 149 0 0 0 0 669
4newsfeed.datemas.de 750 135 0 107 0 0 0 0 508
5news.samoylyk.net 570 0 0 114 0 0 0 0 456
6news.snarked.org 533 76 0 79 0 0 0 0 378
7news.albasani.net 487 3 0 94 0 0 0 0 390
8agency-nz-out.news.mb-net.net 240 22 0 71 0 0 0 0 147
9news.redatomik.org 185 0 0 76 0 0 0 0 109
10news-out.aioe.org 170 20 0 60 0 0 0 0 90
11news.freedyn.net 115 17 0 71 0 0 0 0 27
12feeder.awxg.com 87 0 0 85 0 0 0 0 2
13csiph.com 61 0 0 59 0 0 0 0 2
14news.swapon.de 47 0 0 4 0 0 0 0 43
TOTAL: 14 6979 353 0 1148 0 0 0 0 5478

Unwanted newsgroups [Top 20]:

NewsgroupCount
gmane.linux.alsa.devel 64
mpc.lists.freebsd.ports.bugs 57
it.annunci.immobiliari 38
it.annunci.varie 37
it.annunci.commerciali 36
ut.test 36
mpc.lists.nanog 23
gmane.linux.kernel.renesas-soc 8
mozilla.test 5
mozilla.dev.l10n.sr 5
mozilla.dev.ui-alerts 4
mpc.lists.freebsd.wireless 3
mozilla.dev.security.policy 3
mpc.lists.freebsd.pf 3
mozilla.support.seamonkey 3
gmane.comp.gis.openstreetmap.tagging 2
fido7.ru.android 2
mozilla.dev.l10n.web 2
mozilla.dev.media 2
gmane.linux.kernel 2
TOTAL: 37 353

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3532
EMP (phn path) 705
Binary Image: misplaced jpg 388
EMP (phn nph) 233
EMP (phl) 49
Too many newsgroups 27
Binary: non-binary distribution 23
User-issued cancel 15
Binary: misplaced par2 2
Binary: misplaced 028 1
Binary: misplaced 009 1
Binary: misplaced 052 1
Binary: misplaced 008 1
Binary: misplaced zip 1
Binary: misplaced 026 1
Binary: misplaced 004 1
Binary: misplaced 019 1
TOTAL: 17 4982

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.mixmin.net 188
newsfeed.datemas.de 95
news.redatomik.org 90
news.albasani.net 54
news.swapon.de 40
news-out.aioe.org 14
newsfeed.neva.ru 7
news.snarked.org 5
agency-nz-out.news.mb-net.net 2
feeder.eternal-september.org 1
TOTAL: 10 496
TOTAL: 1 496

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.ausics.org 19200 2002 17126 71 1 0 10%23:59:46
2news.snarked.org 42716 93 15310 34 1 23203 0%23:59:44
3awxg 20972 89 20834 3 0 11 0%23:59:42
4news.freedyn.net 21871 35 21406 5 0 298 0%23:59:47
5csiph.com 21142 27 20772 0 0 227 0%23:59:47
6aioe 17389 8 17240 0 0 20 0%23:59:47
7news.redatomik.org 20540 8 20299 9 0 229 0%23:59:47
8mb-net.net 21599 5 21374 0 0 277 0%23:59:44
9eternal-september 18665 2 18401 0 0 146 0%23:59:47
10news.samoylyk.net 19009 1 18944 1 0 0 0%23:59:32
11news-in.mixmin.net 17873 1 17855 0 0 0 0%23:59:47
12news.albasani.net 21453 0 19500 720 0 8507 0%23:59:47
13news.swapon.de 1894 0 1893 0 0 0 0%23:58:31
14neva.ru 19603 0 19592 1 0 1 0%23:59:47
15newsfeed.datemas.de 18511 0 18459 0 0 120 0%23:59:47
TOTAL: 15 302437 2271 269005 844 2 33039 0%359:55:02
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.ausics.org23.3 MB2.3 MB25.6 MB0.3 KB/s12.6 KB23:59:46
2news.snarked.org831.2 KB96.1 KB927.3 KB0.0 KB/s7.3 KB23:59:44
3awxg461.9 KB7.9 KB469.8 KB0.0 KB/s5.1 KB23:59:42
4news.freedyn.net147.3 KB29.4 KB176.7 KB0.0 KB/s4.4 KB23:59:47
5csiph.com136.1 KB0.0 KB136.1 KB0.0 KB/s5.0 KB23:59:47
6mb-net.net50.4 KB0.0 KB50.4 KB0.0 KB/s10.1 KB23:59:44
7news.redatomik.org26.6 KB32.7 KB59.3 KB0.0 KB/s3.5 KB23:59:47
8aioe13.7 KB0.0 KB13.7 KB0.0 KB/s1.7 KB23:59:47
9news.samoylyk.net3.2 KB9.9 KB13.1 KB0.0 KB/s6.5 KB23:59:32
10news-in.mixmin.net3.2 KB0.0 KB3.2 KB0.0 KB/s3.2 KB23:59:47
11eternal-september2.2 KB0.0 KB2.2 KB0.0 KB/s1.1 KB23:59:47
12news.albasani.net0.0 KB3.3 MB3.3 MB0.0 KB/s4.7 KB23:59:47
13news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:31
14neva.ru0.0 KB3.2 KB3.2 KB0.0 KB/s3.2 KB23:59:47
15newsfeed.datemas.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:47
TOTAL: 1524.9 MB5.8 MB30.7 MB0.0 KB/s10.1 KB359:55:02
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1agency.bbs.geek.nz 248 583144.3 MB 2159 0 000:07:35
267-61-21-181.cpe.cableone.net 3 581836.0 MB 522 0 601:55:48
3163.47.16.169 24 8811.2 MB 126 0 000:04:31
460.234.244.55 7 1448.3 KB 2 1 000:20:54
TOTAL: 4 282 1254481.5 MB 2809 1 603:44:17

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbs.geek.nz 248 583144.3 MB 2159 0 000:07:35
2*.cpe.cableone.net 5 581836.0 MB 522 0 602:37:08
3unresolved 34 8951.2 MB 128 1 000:59:31
TOTAL: 3 287 1254481.5 MB 2809 1 603:44:17

NNRP auth users [Top 20]:

UserConn
amybbs 24
crash 5
bpanther 3
malcolmlewis 2
TOTAL: 4 34

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
67-61-21-181.cpe.cableone.net 0.832 0.588 0.00901:55:48
67-60-219-146.cpe.cableone.net 0.056 0.008 0.00200:41:20
118.149.232.229 0.112 0.024 0.00000:24:01
60.234.244.55 0.460 0.076 0.01500:20:54
agency.bbs.geek.nz 17.056 3.376 0.00000:07:35
118.149.247.104 0.056 0.012 0.00000:05:01
118.149.143.234 0.060 0.012 0.00000:05:01
163.47.16.169 1.376 0.392 0.39300:04:31
? 1.260 0.140 0.00000:00:02
TOTAL: 9 21.268 4.628 0.41903:44:17

Curious NNRP server explorers [Top 20]:

SystemConn
67-60-219-146.cpe.cableone.net 2
118.149.247.104 1
118.149.232.229 1
118.149.143.234 1
TOTAL: 4 5

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
60.234.244.55 2
TOTAL: 1 2

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP TERMINATOR 2
TOTAL: 1 2

NNRP client timeouts [Top 20]:

SystemConnPeer
118.149.232.229 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1rec 4895 39.0%
2comp 2207 17.6%
3sci 1680 13.4%
4alt 1394 11.1%
5soc 1315 10.5%
6talk 320 2.6%
7uk 273 2.2%
8misc 214 1.7%
9news 160 1.3%
10nz 26 0.2%
11aus 24 0.2%
12humanities 13 0.1%
13agency 12 0.1%
14mozilla 8 0.1%
15eternal-september 1 0.0%
TOTAL: 15 12542100.0%

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

NewsgroupCount
1rec.arts.tv 1173
2comp.lang.python 699
3alt.atheism 504
4rec.food.cooking 490
5comp.os.linux.advocacy 480
6rec.sport.tennis 415
7alt.comp.os.windows-10 394
8sci.physics.relativity 378
9soc.retirement 367
10sci.math 294
11sci.electronics.design 291
12uk.radio.amateur 268
13sci.physics 230
14rec.sport.football.college 207
15rec.arts.sf.written 205
16rec.sport.pro-wrestling 178
17rec.outdoors.rv-travel 171
18comp.lang.c 164
19rec.autos.sport.f1 156
20rec.arts.drwho 152
21rec.sport.soccer 149
22rec.bicycles.tech 141
23news.lists.filters 141
24talk.politics.guns 141
25soc.culture.polish 138
26soc.culture.china 132
27alt.bible 126
28alt.politics 126
29comp.sys.mac.system 117
30misc.survivalism 117
31comp.lang.c++ 116
32rec.boats 110
33rec.music.classical.recordings 103
34rec.crafts.metalworking 92
35sci.logic 87
36talk.politics.misc 83
37talk.origins 80
38sci.math.num-analysis 78
39soc.culture.bulgaria 77
40rec.gambling.poker 76
41comp.sys.apple2 71
42soc.culture.usa 71
43alt.recovery.aa 65
44comp.os.vms 64
45soc.penpals 60
46alt.test 59
47rec.photo.digital 56
48rec.music.dylan 54
49rec.arts.movies.current-films 53
50sci.lang 53
51comp.lang.fortran 52
52comp.sys.mac.advocacy 52
53misc.health.alternative 51
54rec.arts.tv.uk.coronation-st 50
55soc.culture.romanian 49
56soc.culture.singapore 49
57sci.med.cardiology 49
58comp.mobile.android 48
59soc.culture.israel 43
60soc.culture.indian 43
61rec.aviation.soaring 40
62alt.astronomy 40
63sci.astro.amateur 39
64sci.military.naval 38
65comp.arch 36
66soc.history.medieval 35
67comp.sys.acorn.misc 35
68rec.music.classical.guitar 34
69rec.autos.tech 34
70sci.space.policy 32
71rec.radio.amateur.dx 32
72alt.support.diabetes 32
73rec.arts.sf.tv 31
74rec.radio.amateur.misc 30
75rec.sport.golf 30
76rec.aquaria.marine.misc 30
77rec.radio.info 27
78soc.history.what-if 27
79nz.general 26
80soc.genealogy.benelux 26
81rec.games.video.sony 26
82soc.culture.vietnamese 25
83rec.games.trivia 25
84comp.lang.forth 25
85comp.soft-sys.sas 24
86rec.arts.disney.parks 23
87rec.games.pinball 23
88comp.os.linux.misc 22
89soc.culture.brazil 22
90rec.gardens.edible 22
91rec.audio.pro 21
92sci.astro 20
93rec.sport.cricket 20
94comp.soft-sys.math.scilab 20
95soc.culture.quebec 18
96rec.arts.movies.past-films 18
97rec.aviation.marketplace 18
98comp.lang.javascript 17
99rec.audio.opinion 16
100rec.gambling.lottery 16
TOTAL: 326 12542