Daily Usenet report for news.niel.me

Feb 10 04:15:02 -- Feb 11 04:15:01

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Feb 10 09:08:32 rpi-studio innd: filter: Valid Cancel-Key W9XfKJDfc5mBI2+L5N0IBGS2KaE= found. target=<i8hii8F5soaU1@mid.individual.net>
Feb 10 09:32:29 rpi-studio innd: filter: Valid Cancel-Key i17pGqGYxW5XFnzSZ7Xf8lQKLdE= found. target=<s005l7$28j$1@dont-email.me>
Feb 10 11:56:19 rpi-studio innd: filter: Valid Cancel-Key Wnm9H/vfWgf/KOzUMZ5yMXSoq0M= found. target=<s00dq1$rr8$2@dont-email.me>
Feb 10 14:12:53 rpi-studio innd: filter: Valid Cancel-Key 8LkA/qxabNPJIYBrgc8Qq5vB1dKE9S2RqWIuSNWFozk= found. target=<slrns27mse.uio.gerald.niel+spam@home.niel.me>
Feb 10 17:08:57 rpi-studio innd: filter: Valid Cancel-Key /3l/RNPuYhZK98vVspYUfMonNJ0= found. target=<s010b0$c7v$1@dont-email.me>
Feb 10 17:12:24 rpi-studio innd: filter: Valid Cancel-Key ac/nYB5w9uAAI+Q8TS2gDOa29tY= found. target=<s00v5r$3bv$2@dont-email.me>
Feb 11 00:28:28 rpi-studio innd: filter: Valid Cancel-Key YSBovCUKHNHnG66GD4YXZW3AhdI= found. target=<s01pt8$sab$4@pasdenom.info>
Feb 11 00:38:01 rpi-studio innd: filter: Valid Cancel-Key TFz0F7WWbB/UjgXFw46BqhHBw+k= found. target=<s01oct$bhq$1@dont-email.me>

Log entries by program:

Program nameLines%LinesSize%Size
inn 5324 42.8%868.6 KB 47.8%
nnrpd 3361 27.0%344.7 KB 19.0%
innd 2260 18.2%374.1 KB 20.6%
innfeed 1439 11.6%225.6 KB 12.4%
nocem 47 0.4%5.5 KB 0.3%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 6 12433 100.0%1.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 19320 61.0%
Negative hits 7173 22.7%
Do not exist 5160 16.3%
Cache misses 11 0.0%
TOTAL: 4 31664 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.075 0.0%45 0.000 1.667 9.000
article cancel00:00:00.020 0.0%88 0.000 0.227 1.000
article cleanup00:00:00.097 0.0%5147 0.000 0.019 0.111
article logging00:00:00.376 0.0%5186 0.000 0.073 0.273
article parse00:00:00.858 0.0%8928 0.018 0.096 0.267
article write00:00:02.989 0.0%4694 0.263 0.637 1.579
artlog/artcncl00:00:00.006 0.0%88 0.000 0.068 1.000
artlog/artcncl/perl00:00:00.001 0.0%45 0.000 0.022 0.167
data move00:00:00.096 0.0%35682 0.000 0.003 0.019
hisgrep/artcncl00:00:00.005 0.0%65 0.000 0.077 0.500
hisgrep/artcncl/perl00:00:00.000 0.0%45 0.000 0.000 0.000
hisgrep/perl00:00:00.007 0.0%66 0.000 0.106 1.000
hishave/artcncl00:00:00.001 0.0%88 0.000 0.011 0.500
hishave/artcncl/perl00:00:00.001 0.0%45 0.000 0.022 0.167
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.471 0.0%31589 0.000 0.015 0.053
history sync00:00:00.049 0.0%412 0.000 0.119 1.333
history write00:00:01.139 0.0%5147 0.000 0.221 0.891
hiswrite/artcncl00:00:00.003 0.0%23 0.000 0.130 0.667
idle24:00:33.386 99.9%36173 986.758 2389.445 13345.067
nntp read00:00:01.238 0.0%35285 0.000 0.035 0.092
overview write00:00:02.824 0.0%4694 0.105 0.602 4.750
perl filter00:00:21.767 0.0%5147 2.628 4.229 13.286
python filter00:00:00.010 0.0%5169 0.000 0.002 0.091
site send00:00:01.628 0.0%9434 0.000 0.173 0.432
TOTAL: 24:01:18.63624:01:07.047100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.061 0.0%4772 0.000 0.013 0.111
article prepare00:00:00.002 0.0%1056 0.000 0.002 0.028
article read00:00:00.036 0.0%142 0.000 0.254 8.000
backlog stats00:00:00.120 0.0%62705 0.000 0.002 0.012
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.851 0.0%35453 0.000 0.024 0.061
data write00:00:02.342 0.0%30657 0.000 0.076 0.149
idle23:50:45.378100.0%62705 574.081 1369.036 6523.283
status file00:00:00.434 0.0%192 1.000 2.260 7.000
TOTAL: 23:50:52.24623:50:49.224100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.003 0.0%11 0.000 0.273 0.500
idle04:10:40.742 99.9%2380 19.200 6319.639 43683.189
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.344 0.0%7915 0.000 0.043 0.838
readart00:00:00.110 0.0%237 0.000 0.464 4.000
TOTAL: 04:10:57.70704:10:41.199 99.9%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 149
name 2
pause 2
paused 2
reload 4
reserve 2
TOTAL: 11 173

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 1 4422 3000 1359 63 67%22:51:11
2news.nntp4.net 4 4619 854 3511 254 18%21:37:53
3feed-out.gegeweb.org 3 3475 449 3026 0 12%23:59:17
4gegeweb-out.news.tnetconsulting.net 2 4283 120 4148 15 2%23:58:44
5usenet.pasdenom.info 2 2286 90 2196 0 3%23:59:59
6localhost 52 22 22 0 0 100%00:00:00
7i2pn.org 1 0 0 0 0 0%00:00:00
TOTAL: 7 65 19107 4535 14240 332 23%116:27:04
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org10.0 MB0.0 KB236.8 KB10.2 MB 97%3.4 KB
2news.nntp4.net5.0 MB0.0 KB632.0 KB5.6 MB 89%5.2 KB
3feed-out.gegeweb.org1.3 MB0.0 KB0.0 KB1.3 MB100%2.9 KB
4gegeweb-out.news.tnetconsulting.net431.3 KB0.0 KB58.6 KB489.8 KB 88%3.6 KB
5usenet.pasdenom.info193.8 KB0.0 KB0.0 KB193.8 KB100%2.2 KB
6localhost47.9 KB0.0 KB0.0 KB47.9 KB100%2.2 KB
7i2pn.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 716.9 MB0.0 KB927.4 KB17.8 MB 94%3.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 10 04:15:02 - 04:59:59 75 1.6% 0.03260.3 KB 1.4% 0.10
Feb 10 05:00:00 - 05:59:59 106 2.3% 0.03404.3 KB 2.2% 0.11
Feb 10 06:00:00 - 06:59:59 110 2.3% 0.03406.4 KB 2.2% 0.11
Feb 10 07:00:00 - 07:59:59 104 2.2% 0.03742.0 KB 4.1% 0.21
Feb 10 08:00:00 - 08:59:59 111 2.4% 0.03481.2 KB 2.6% 0.13
Feb 10 09:00:00 - 09:59:59 135 2.9% 0.04467.5 KB 2.6% 0.13
Feb 10 10:00:00 - 10:59:59 146 3.1% 0.04453.6 KB 2.5% 0.13
Feb 10 11:00:00 - 11:59:59 177 3.8% 0.05508.5 KB 2.8% 0.14
Feb 10 12:00:00 - 12:59:59 177 3.8% 0.05556.7 KB 3.1% 0.15
Feb 10 13:00:00 - 13:59:59 178 3.8% 0.051.1 MB 6.4% 0.32
Feb 10 14:00:00 - 14:59:59 230 4.9% 0.06583.6 KB 3.2% 0.16
Feb 10 15:00:00 - 15:59:59 247 5.3% 0.07802.1 KB 4.4% 0.22
Feb 10 16:00:00 - 16:59:59 246 5.2% 0.07734.2 KB 4.0% 0.20
Feb 10 17:00:00 - 17:59:59 281 6.0% 0.08902.9 KB 5.0% 0.25
Feb 10 18:00:00 - 18:59:59 274 5.8% 0.08758.5 KB 4.2% 0.21
Feb 10 19:00:00 - 19:59:59 284 6.0% 0.081.6 MB 9.0% 0.45
Feb 10 20:00:00 - 20:59:59 340 7.2% 0.091.2 MB 6.9% 0.35
Feb 10 21:00:00 - 21:59:59 296 6.3% 0.081.1 MB 6.5% 0.33
Feb 10 22:00:00 - 22:59:59 278 5.9% 0.081.2 MB 6.5% 0.33
Feb 10 23:00:00 - 23:59:59 252 5.4% 0.071.2 MB 6.7% 0.34
Feb 11 00:00:00 - 00:59:59 232 4.9% 0.06856.0 KB 4.7% 0.24
Feb 11 01:00:00 - 01:59:59 151 3.2% 0.04393.3 KB 2.2% 0.11
Feb 11 02:00:00 - 02:59:59 116 2.5% 0.03404.2 KB 2.2% 0.11
Feb 11 03:00:00 - 03:59:59 125 2.7% 0.03716.8 KB 3.9% 0.20
Feb 11 04:00:00 - 04:15:01 24 0.5% 0.03131.4 KB 0.7% 0.15
TOTAL: 23:59:59 4695 100.0% 0.0517.8 MB 100.0% 0.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 273 0 0 0 0 0 0 0 273
2i2pn.org 110 0 0 0 0 0 0 0 110
3news-out.aioe.org 95 1 0 0 0 0 0 0 94
4gegeweb-out.news.tnetconsulting.net 16 0 0 0 0 0 0 0 16
5feed-out.gegeweb.org 2 0 0 0 0 0 0 0 2
TOTAL: 5 496 1 0 0 0 0 0 0 495

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 165
EMP (phl) 103
Bad path (news.giganews.com.POSTED) 95
EMP (md5) 67
EMP (phn nph) 4
Cancel of non-existing ID <9b83e70a-0e06-4c19-a96b-38b1e593a941o@googlegroups.com> 1
Cancel of non-existing ID <0eaef3ed-f0ee-46df-ac08-a98d314f5a74n@googlegroups.com> 1
Cancel of non-existing ID <30d4dbf9-dda4-4a65-8243-6ee6cbeb514fn@googlegroups.com> 1
Cancel of non-existing ID <a45cec04-b1fb-4286-87c1-8da5c90c52fbn@googlegroups.com> 1
Cancel of non-existing ID <4b5e1277-e015-46f0-804f-6750fbb26f47n@googlegroups.com> 1
Cancel of non-existing ID <18ceb08a-e90f-48df-acef-57cac18ca563n@googlegroups.com> 1
Cancel of non-existing ID <7f89d8d4-e47e-4338-bcdd-5c0a11ef5444n@googlegroups.com> 1
Cancel of non-existing ID <20460b02-9df6-4e59-9b7f-551c1fa8a3c0n@googlegroups.com> 1
Cancel of non-existing ID <da106dfc-b6cb-4476-8a6b-2f62927ba5f5n@googlegroups.com> 1
Cancel of non-existing ID <56c6a9ef-09b4-42c0-a68a-7f805ce9a93dn@googlegroups.com> 1
Cancel of non-existing ID <f1be0993-1dbd-4a22-b16f-7013dcb9b07en@googlegroups.com> 1
Cancel of non-existing ID <7221839e-f79f-43d3-9749-8995febc3f4do@googlegroups.com> 1
Cancel of non-existing ID <fa5d9d8d-51f4-4494-9cc7-99d485082edeo@googlegroups.com> 1
Cancel of non-existing ID <a260dbf0-348f-40db-a706-dcf7a34a5375o@googlegroups.com> 1
Cancel of non-existing ID <49d17521-ef3c-475c-b6c1-812a15003957n@googlegroups.com> 1
TOTAL: 26 455

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com2802929
nocem@aioe.org1401414
TOTAL: 24204343

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 30
news.nntp4.net 8
feed-out.gegeweb.org 2
TOTAL: 3 40
TOTAL: 1 40

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 7952 82 4144 16 0 19 1%20:36:51
2news.tnetconsulting.net 8232 68 4623 4 0 16 0%23:59:53
3news.nntp4.net 3960 25 3751 0 0 0 0%23:59:54
4news.gegeweb.org 4246 24 4034 0 0 0 0%23:59:54
5news.pasdenom.info 4986 22 3964 2 0 54 0%23:59:54
6news.aioe.org 1547 21 1295 1 0 0 1%23:25:44
TOTAL: 6 30923 242 21811 23 0 89 0%140:02:10
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org273.9 KB31.6 KB305.5 KB0.0 KB/s3.1 KB20:36:51
2news.tnetconsulting.net249.6 KB11.5 KB261.1 KB0.0 KB/s3.6 KB23:59:53
3news.nntp4.net57.3 KB0.0 KB57.3 KB0.0 KB/s2.3 KB23:59:54
4news.gegeweb.org51.6 KB0.0 KB51.6 KB0.0 KB/s2.2 KB23:59:54
5news.pasdenom.info49.0 KB21.8 KB70.8 KB0.0 KB/s3.0 KB23:59:54
6news.aioe.org47.1 KB0.8 KB47.9 KB0.0 KB/s2.2 KB23:25:44
TOTAL: 6728.5 KB65.7 KB794.2 KB0.0 KB/s3.0 KB140:02:10
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mbp-gniel.niel.me 26 203544.4 KB 85 22 004:10:11
TOTAL: 1 26 203544.4 KB 85 22 004:10:57

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.niel.me 26 203544.4 KB 85 22 004:10:11
2*.ch1.censys-scanner.com 2 00.0 KB 0 0 000:00:00
3*.gegeweb.eu 288 00.0 KB 0 0 000:00:41
4unresolved 13 00.0 KB 0 0 000:00:03
TOTAL: 4 329 203544.4 KB 85 22 004:10:57

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 1.588 1.232 0.00004:10:11
mail.gegeweb.eu 7.868 8.410 2.51400:00:41
88.165.5.23 0.208 0.129 0.00000:00:01
64.225.29.170 0.157 0.194 0.00000:00:01
scanner-06.ch1.censys-scanner.com 0.075 0.044 0.00000:00:00
TOTAL: 5 9.896 10.009 2.51404:10:57

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
64.225.29.170 7
88.165.5.23 6
scanner-06.ch1.censys-scanner.com 2
TOTAL: 4 303

NNRP unrecognized commands (by host):

SystemConn
64.225.29.170 36
scanner-06.ch1.censys-scanner.com 1
TOTAL: 2 37

NNRP unrecognized commands (by command):

CommandCount
User-Agent: Mozilla/5.0 (Windows NT 10.0... 6
Host: 185.73.232.189:119 6
Connection: keep-alive 6
Accept: */* 6
Accept-Encoding: gzip, deflate 6
GET /stalker_portal/c/version.js HTTP/1.... 1
EHLO www.censys.io 1
GET /stalker_portal/c/ HTTP/1.1 1
GET /client_area/ HTTP/1.1 1
GET /streaming/clients_live.php HTTP/1.1 1
GET /system_api.php HTTP/1.1 1
GET /c/version.js HTTP/1.1 1
TOTAL: 12 37

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1fr 162 79.8%
2news 23 11.3%
3grisbi 18 8.9%
TOTAL: 3 203100.0%

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

NewsgroupCount
1fr.comp.sys.mac.communication 52
2fr.comp.os.mac-os.x 47
3fr.usenet.forums.evolution 41
4news.software.nntp 21
5grisbi.user.french 13
6fr.comp.mail 12
7fr.comp.usenet.lecteurs-de-news 8
8grisbi.devel 3
9news.answers 2
10fr.comp.lang.c 2
11grisbi.test 1
12grisbi.bugsreports 1
TOTAL: 12 203
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me