Daily Usenet report for news.niel.me

Feb 8 04:15:01 -- Feb 9 04:15:01

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Feb 8 08:30:03 rpi-studio innd: filter: Valid Cancel-Key 48kqVs/q0wlnqBs+F3I+6EKLR3iMv2KmPhx26rgVyf4= found. target=<slrns21ppk.1hsa.gerald.niel+spam@home.niel.me>
Feb 8 08:30:56 rpi-studio innd: filter: Valid Cancel-Key 6VDeXiuy0M4M2ElnegSxI0DEVlui5cxOVveqiBGULFY= found. target=<slrns21q3r.1hsa.gerald.niel+spam@home.niel.me>
Feb 8 10:30:29 rpi-studio innd: filter: Valid Cancel-Key QXF76MEyukPSOpLGrNz9b8ygbnU= found. target=<rvr08f$7tg$1@dont-email.me>
Feb 8 10:56:57 rpi-studio innd: filter: Valid Cancel-Key bO+a/W0n3tzG6LIs006MVmB+Ykc= found. target=<rvr1ru$6jq$1@dont-email.me>
Feb 8 11:41:26 rpi-studio innd: filter: Valid Cancel-Key ALpPMPKNzLD8dmYsiCSC7qdXgkU= found. target=<rvr48b$khk$1@dont-email.me>
Feb 8 12:20:23 rpi-studio innd: filter: Valid Cancel-Key Fdgsao0d5d/3vyS3a8DgjIJ3btM30Y+/LYSZ27u85l4= found. target=<slrns22743.24u0.gerald.niel+spam@home.niel.me>
Feb 8 12:26:00 rpi-studio innd: filter: Valid Cancel-Key ubz3/CD9Z2tmYOJL41Yd7yAMsyc= found. target=<i8ckohF7477U1@mid.individual.net>
Feb 8 12:27:47 rpi-studio innd: filter: Cannot load /etc/news/filter/cleanfeed/etc/cleanfeed.local: syntax error at (eval 25) line 295, at EOF
Feb 8 12:30:20 rpi-studio innd: filter: Valid Cancel-Key T92R+pZyx+0HBo60wbDt1jqFmNOcfbnTHe4rATbyf4M= found. target=<slrns21rr5.1lnk.gerald.niel+spam@home.niel.me>
Feb 8 13:11:08 rpi-studio innd: filter: Valid Cancel-Key 337r9MeLP/LkYjWiKjEg/61Q7mM= found. target=<slrns22ahe.c1n.gerald.niel+spam@news.gegeweb.org>
Feb 8 16:20:05 rpi-studio innd: filter: Valid Cancel-Key S7S8iz8wohUSCRcUi2Ksd4ariDA= found. target=<rvrkmt$b2k$1@dont-email.me>

Log entries by program:

Program nameLines%LinesSize%Size
inn 5265 40.0%798.9 KB 45.4%
nnrpd 4876 37.0%477.3 KB 27.2%
innd 1777 13.5%304.2 KB 17.3%
innfeed 1200 9.1%172.6 KB 9.8%
nocem 45 0.3%4.8 KB 0.3%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 6 13165 100.0%1.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13086 52.2%
Negative hits 6872 27.4%
Do not exist 5116 20.4%
Cache misses 4 0.0%
TOTAL: 4 25078 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.038 0.0%35 0.000 1.086 2.000
article cancel00:00:00.016 0.0%72 0.000 0.222 1.000
article cleanup00:00:00.094 0.0%5123 0.000 0.018 0.118
article logging00:00:00.410 0.0%5156 0.000 0.080 0.917
article parse00:00:00.779 0.0%8686 0.000 0.090 0.261
article write00:00:03.448 0.0%4956 0.059 0.696 1.600
artlog/artcncl00:00:00.003 0.0%70 0.000 0.043 1.000
artlog/artcncl/perl00:00:00.003 0.0%35 0.000 0.086 1.000
data move00:00:00.080 0.0%28915 0.000 0.003 0.022
hisgrep/artcncl00:00:00.001 0.0%57 0.000 0.018 0.056
hisgrep/artcncl/perl00:00:00.000 0.0%35 0.000 0.000 0.000
hisgrep/perl00:00:00.003 0.0%35 0.000 0.086 1.000
hishave/artcncl00:00:00.005 0.0%72 0.000 0.069 0.667
hishave/artcncl/perl00:00:00.000 0.0%35 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.436 0.0%25064 0.000 0.017 0.070
history sync00:00:00.041 0.0%411 0.000 0.100 1.000
history write00:00:04.502 0.0%5123 0.051 0.879 60.750
hiswrite/artcncl00:00:00.001 0.0%15 0.000 0.067 0.500
idle24:00:28.923 99.9%29569 842.077 2922.957 20690.069
nntp read00:00:01.049 0.0%28575 0.000 0.037 0.094
overview write00:00:03.131 0.0%4956 0.111 0.632 8.700
perl filter00:00:20.491 0.0%5123 2.675 4.000 8.667
python filter00:00:00.013 0.0%5138 0.000 0.003 0.091
site send00:00:01.342 0.0%9950 0.000 0.135 0.350
TOTAL: 24:01:14.49724:01:04.809100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.050 0.0%4958 0.000 0.010 0.087
article prepare00:00:00.001 0.0%500 0.000 0.002 0.050
article read00:00:00.024 0.0%82 0.000 0.293 8.000
backlog stats00:00:00.078 0.0%45292 0.000 0.002 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.653 0.0%25541 0.000 0.026 0.064
data write00:00:01.697 0.0%20579 0.000 0.082 0.149
idle23:51:41.622100.0%45292 830.862 1896.618 8983.567
status file00:00:00.392 0.0%190 1.000 2.063 7.000
TOTAL: 23:51:46.77123:51:44.517100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.001 0.0%1 1.000 1.000 1.000
idle04:12:13.527 99.9%2830 19.200 5347.536101052.611
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.288 0.0%11402 0.000 0.025 0.273
readart00:00:00.257 0.0%288 0.000 0.892 4.000
TOTAL: 04:12:30.82804:12:14.073 99.9%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 148
name 2
pause 2
paused 2
reload 10
reserve 2
TOTAL: 11 178

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 3 4960 3577 1322 61 72%24:53:04
2feed-out.gegeweb.org 3 3761 780 2981 0 20%23:55:10
3news.nntp4.net 5 2044 311 1644 89 15%07:51:08
4gegeweb-out.news.tnetconsulting.net 1 2093 170 1916 7 8%13:32:52
5usenet.pasdenom.info 2 624 36 588 0 5%08:25:25
6localhost 48 15 15 0 0 100%00:00:00
TOTAL: 6 62 13497 4889 8451 157 36%78:37:39
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org11.5 MB0.0 KB212.5 KB11.7 MB 98%3.3 KB
2feed-out.gegeweb.org2.3 MB0.0 KB0.0 KB2.3 MB100%3.0 KB
3news.nntp4.net1.9 MB0.0 KB291.8 KB2.1 MB 86%5.5 KB
4gegeweb-out.news.tnetconsulting.net1.1 MB0.0 KB33.0 KB1.1 MB 97%6.6 KB
5usenet.pasdenom.info84.2 KB0.0 KB0.0 KB84.2 KB100%2.3 KB
6localhost41.6 KB0.0 KB0.0 KB41.6 KB100%2.8 KB
TOTAL: 616.9 MB0.0 KB537.3 KB17.4 MB 96%3.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 8 04:15:01 - 04:59:59 92 1.9% 0.03264.0 KB 1.5% 0.10
Feb 8 05:00:00 - 05:59:59 93 1.9% 0.03246.6 KB 1.4% 0.07
Feb 8 06:00:00 - 06:59:59 93 1.9% 0.03314.0 KB 1.7% 0.09
Feb 8 07:00:00 - 07:59:59 111 2.2% 0.03360.7 KB 2.0% 0.10
Feb 8 08:00:00 - 08:59:59 146 2.9% 0.04396.8 KB 2.2% 0.11
Feb 8 09:00:00 - 09:59:59 128 2.6% 0.04406.6 KB 2.3% 0.11
Feb 8 10:00:00 - 10:59:59 144 2.9% 0.04470.4 KB 2.6% 0.13
Feb 8 11:00:00 - 11:59:59 196 4.0% 0.05582.3 KB 3.2% 0.16
Feb 8 12:00:00 - 12:59:59 172 3.5% 0.05549.1 KB 3.1% 0.15
Feb 8 13:00:00 - 13:59:59 173 3.5% 0.05547.2 KB 3.0% 0.15
Feb 8 14:00:00 - 14:59:59 249 5.0% 0.071.5 MB 8.6% 0.43
Feb 8 15:00:00 - 15:59:59 339 6.8% 0.09908.7 KB 5.1% 0.25
Feb 8 16:00:00 - 16:59:59 218 4.4% 0.06922.0 KB 5.1% 0.26
Feb 8 17:00:00 - 17:59:59 273 5.5% 0.081.3 MB 7.2% 0.36
Feb 8 18:00:00 - 18:59:59 341 6.9% 0.09988.7 KB 5.5% 0.27
Feb 8 19:00:00 - 19:59:59 315 6.4% 0.091007.9 KB 5.6% 0.28
Feb 8 20:00:00 - 20:59:59 246 5.0% 0.07726.0 KB 4.0% 0.20
Feb 8 21:00:00 - 21:59:59 283 5.7% 0.08909.1 KB 5.1% 0.25
Feb 8 22:00:00 - 22:59:59 306 6.2% 0.091.5 MB 8.8% 0.44
Feb 8 23:00:00 - 23:59:59 270 5.4% 0.07883.3 KB 4.9% 0.25
Feb 9 00:00:00 - 00:59:59 229 4.6% 0.06864.2 KB 4.8% 0.24
Feb 9 01:00:00 - 01:59:59 202 4.1% 0.061010.8 KB 5.6% 0.28
Feb 9 02:00:00 - 02:59:59 181 3.7% 0.05577.4 KB 3.2% 0.16
Feb 9 03:00:00 - 03:59:59 119 2.4% 0.03434.4 KB 2.4% 0.12
Feb 9 04:00:00 - 04:15:01 39 0.8% 0.04160.7 KB 0.9% 0.18
TOTAL: 24:00:00 4958 100.0% 0.0617.5 MB 100.0% 0.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news-out.aioe.org 93 2 0 0 0 0 0 0 91
2news.nntp4.net 90 0 0 0 0 0 0 0 90
3gegeweb-out.news.tnetconsulting.net 19 0 0 0 0 0 0 0 19
TOTAL: 3 202 2 0 0 0 0 0 0 200

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.religion.christian.roman-catholic 1
news.test 1
TOTAL: 2 2

INND Perl filter [Top 20]:

ReasonCount
Bad path (news.giganews.com.POSTED) 81
EMP (phl) 66
Too many newsgroups 13
EMP (md5) 6
HTML Multipart 1
TOTAL: 5 167

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com3403434
nocem@aioe.org3033
TOTAL: 23703737

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 33
TOTAL: 1 33
TOTAL: 1 33

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tnetconsulting.net 8534 46 4902 4 0 31 0%23:59:54
2news.gegeweb.org 4311 32 4127 1 0 0 0%23:59:55
3news.aioe.org 1402 19 1249 1 0 0 1%22:33:23
4news.pasdenom.info 5241 17 4191 1 0 1 0%23:59:55
5news.nntp4.net 1271 5 1221 0 0 0 0%06:31:14
TOTAL: 5 20759 119 15690 7 0 32 0%101:04:21
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.gegeweb.org490.8 KB1.2 KB492.0 KB0.0 KB/s14.9 KB23:59:55
2news.tnetconsulting.net208.8 KB6.0 KB214.8 KB0.0 KB/s4.3 KB23:59:54
3news.pasdenom.info48.7 KB1.1 KB49.9 KB0.0 KB/s2.8 KB23:59:55
4news.aioe.org48.3 KB1.2 KB49.5 KB0.0 KB/s2.5 KB22:33:23
5news.nntp4.net14.2 KB0.0 KB14.2 KB0.0 KB/s2.8 KB06:31:14
TOTAL: 5810.8 KB9.5 KB820.3 KB0.0 KB/s6.5 KB101:04:21
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mbp-gniel.niel.me 57 168489.8 KB 91 15 004:08:49
288.165.5.23 7 6298.2 KB 1 0 000:02:40
TOTAL: 2 64 230587.9 KB 92 15 004:12:30

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.niel.me 57 168489.8 KB 91 15 004:08:49
2unresolved 8 6298.2 KB 1 0 000:02:50
3*.w90-2.abo.wanadoo.fr 1 00.0 KB 0 0 000:00:00
4*.w92-154.abo.wanadoo.fr 1 00.0 KB 0 0 000:00:10
5*.gegeweb.eu 288 00.0 KB 0 0 000:00:38
TOTAL: 5 355 230587.9 KB 92 15 004:12:30

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mbp-gniel.niel.me 2.615 2.135 0.00004:08:49
88.165.5.23 0.230 0.205 0.00000:02:40
mail.gegeweb.eu 8.116 8.159 2.55900:00:38
lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr 0.017 0.034 0.00000:00:10
192.241.221.93 0.022 0.033 0.00000:00:09
amontsouris-654-1-131-44.w90-2.abo.wanadoo.fr 0.029 0.029 0.00000:00:00
TOTAL: 6 11.029 10.595 2.55904:12:30

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
amontsouris-654-1-131-44.w90-2.abo.wanadoo.fr 1
lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr 1
192.241.221.93 1
TOTAL: 4 291

NNRP client timeouts [Top 20]:

SystemConnPeer
mbp-gniel.niel.me 2 0
TOTAL: 1 2 0

Newsgroup request counts (by category):

CategoryCountPct
1fr 128 55.7%
2local 67 29.1%
3news 27 11.7%
4grisbi 8 3.5%
TOTAL: 4 230100.0%

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

NewsgroupCount
1local.gegeweb.support 62
2fr.comp.os.mac-os.x 61
3fr.usenet.forums.evolution 39
4fr.comp.lang.perl 16
5news.software.nntp 14
6news.admin.peering 7
7fr.comp.os.linux.configuration 5
8local.test 5
9grisbi.bugsreports 5
10news.answers 3
11news.software.readers 3
12fr.comp.mail 2
13fr.comp.sys.mac.communication 2
14fr.comp.usenet.lecteurs-de-news 2
15grisbi.user.french 2
16fr.comp.usenet.serveurs 1
17grisbi.test 1
TOTAL: 17 230
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me