Daily Usenet report for news.niel.me

Jan 4 04:15:01 -- Jan 5 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 5 00:03:30 rpi-studio innd: filter: No Cancel-Key[sha256:BCeY7sdbPh7RdD+Jp0KIZpOp+eB+6SyYMBB+/+ZqBoY=] matches Cancel-Lock[sha256:z8hC5/MuwFVnp+YsIioAmBgyjHUte8+P1VtiYU33KKs=] target=<bosinjd9tofjkn4gc1qhpgk1pl6l8rrdp0@news.usenet.ovh>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2391 28.6%447.5 KB 33.5%
innd 2251 26.9%376.6 KB 28.1%
innfeed 1895 22.7%322.8 KB 24.1%
nnrpd 1822 21.8%190.7 KB 14.3%
controlchan 1 0.0%0.2 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 6 8361 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 12389 54.2%
Negative hits 8141 35.6%
Do not exist 2323 10.2%
Cache misses 1 0.0%
TOTAL: 4 22854 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.018 0.0%16 0.000 1.125 5.000
article cancel00:00:00.002 0.0%17 0.000 0.118 1.000
article cleanup00:00:00.050 0.0%2324 0.000 0.022 0.250
article logging00:00:00.164 0.0%2327 0.000 0.070 0.333
article parse00:00:00.290 0.0%5078 0.000 0.057 0.333
article write00:00:01.097 0.0%2024 0.000 0.542 1.231
artlog/artcncl00:00:00.000 0.0%17 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.001 0.0%16 0.000 0.062 1.000
data move00:00:00.127 0.0%24234 0.000 0.005 0.056
hisgrep/artcncl00:00:00.000 0.0%17 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%16 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%19 0.000 0.053 1.000
hishave/artcncl00:00:00.000 0.0%17 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%16 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.299 0.0%22831 0.000 0.013 0.047
history sync00:00:00.050 0.0%395 0.000 0.127 1.000
history write00:00:00.358 0.0%2324 0.000 0.154 0.833
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:50:54.720100.0%26074 1219.843 3292.733 20012.700
nntp read00:00:00.607 0.0%25261 0.000 0.024 0.091
overview write00:00:00.563 0.0%2024 0.000 0.278 5.200
perl filter00:00:10.587 0.0%2324 2.034 4.556 13.200
python filter00:00:00.006 0.0%2324 0.000 0.003 0.200
site send00:00:00.953 0.0%4094 0.000 0.233 0.667
TOTAL: 23:51:17.20723:51:09.893100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.017 0.0%2045 0.000 0.008 0.250
article prepare00:00:00.000 0.0%348 0.000 0.000 0.000
article read00:00:00.019 0.0%84 0.000 0.226 1.500
backlog stats00:00:00.159 0.0%29335 0.000 0.005 0.021
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.398 0.0%16037 0.000 0.025 0.065
data write00:00:00.739 0.0%14092 0.000 0.052 0.179
idle23:50:21.290100.0%29335 1312.179 2925.560 8397.836
status file00:00:01.680 0.0%222 0.000 7.568 70.000
TOTAL: 23:50:29.91723:50:24.302100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:43.383 30.4%1448 3.500 29.961 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.099 0.1%3214 0.000 0.031 0.200
TOTAL: 00:02:22.90800:00:43.482 30.4%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1gegeweb-out.news.tnetconsulting.net 3 1927 786 1093 48 40%26:13:05
2news.samoylyk.net 3 2345 509 1836 0 21%25:37:43
3feed-out.gegeweb.org 3 2004 384 1620 0 19%23:55:45
4usenet.pasdenom.info 5 2363 220 2131 12 9%43:07:32
5news.nntp4.net 3 4475 193 3969 313 4%47:21:45
6i2pn.org 3 2263 164 2099 0 7%26:51:01
7news.glou.org 2 1744 65 1670 9 3%23:53:33
8nntp.terraraq.uk 3 1800 35 1765 0 1%23:47:54
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 50 18921 2356 16183 382 12%240:48:18
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.samoylyk.net2.9 MB0.0 KB0.0 KB2.9 MB100%5.8 KB
2gegeweb-out.news.tnetconsulting.net2.4 MB0.0 KB488.5 KB2.9 MB 83%3.5 KB
3feed-out.gegeweb.org1.1 MB0.0 KB0.0 KB1.1 MB100%3.0 KB
4news.nntp4.net959.9 KB0.0 KB2.0 MB2.9 MB 32%5.9 KB
5usenet.pasdenom.info615.9 KB0.0 KB16.2 KB632.1 KB 97%2.7 KB
6i2pn.org419.0 KB0.0 KB0.0 KB419.0 KB100%2.6 KB
7news.glou.org255.3 KB0.0 KB28.2 KB283.5 KB 90%3.8 KB
8nntp.terraraq.uk109.1 KB0.0 KB0.0 KB109.1 KB100%3.1 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 98.7 MB0.0 KB2.5 MB11.2 MB 77%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 4 04:15:01 - 04:59:59 38 1.9% 0.01213.9 KB 2.7% 0.08
Jan 4 05:00:00 - 05:59:59 39 1.9% 0.01135.8 KB 1.7% 0.04
Jan 4 06:00:00 - 06:59:59 34 1.7% 0.01127.6 KB 1.6% 0.04
Jan 4 07:00:00 - 07:59:59 38 1.9% 0.0194.2 KB 1.2% 0.03
Jan 4 08:00:00 - 08:59:59 46 2.2% 0.01177.2 KB 2.3% 0.05
Jan 4 09:00:00 - 09:59:59 64 3.1% 0.02262.9 KB 3.3% 0.07
Jan 4 10:00:00 - 10:59:59 84 4.1% 0.02292.9 KB 3.7% 0.08
Jan 4 11:00:00 - 11:59:59 125 6.1% 0.03350.1 KB 4.5% 0.10
Jan 4 12:00:00 - 12:59:59 135 6.6% 0.04737.9 KB 9.4% 0.20
Jan 4 13:00:00 - 13:59:59 82 4.0% 0.02492.9 KB 6.3% 0.14
Jan 4 14:00:00 - 14:59:59 70 3.4% 0.02230.0 KB 2.9% 0.06
Jan 4 15:00:00 - 15:59:59 93 4.5% 0.03271.0 KB 3.4% 0.08
Jan 4 16:00:00 - 16:59:59 87 4.2% 0.02251.7 KB 3.2% 0.07
Jan 4 17:00:00 - 17:59:59 117 5.7% 0.03596.1 KB 7.6% 0.17
Jan 4 18:00:00 - 18:59:59 135 6.6% 0.04611.9 KB 7.8% 0.17
Jan 4 19:00:00 - 19:59:59 122 5.9% 0.03394.1 KB 5.0% 0.11
Jan 4 20:00:00 - 20:59:59 101 4.9% 0.03384.3 KB 4.9% 0.11
Jan 4 21:00:00 - 21:59:59 104 5.1% 0.03299.7 KB 3.8% 0.08
Jan 4 22:00:00 - 22:59:59 128 6.2% 0.04443.3 KB 5.6% 0.12
Jan 4 23:00:00 - 23:59:59 120 5.8% 0.03420.3 KB 5.3% 0.12
Jan 5 00:00:00 - 00:59:59 70 3.4% 0.02260.2 KB 3.3% 0.07
Jan 5 01:00:00 - 01:59:59 70 3.4% 0.02242.3 KB 3.1% 0.07
Jan 5 02:00:00 - 02:59:59 53 2.6% 0.01260.5 KB 3.3% 0.07
Jan 5 03:00:00 - 03:59:59 56 2.7% 0.02172.9 KB 2.2% 0.05
Jan 5 04:00:00 - 04:15:01 43 2.1% 0.05132.9 KB 1.7% 0.15
TOTAL: 24:00:00 2054 100.0% 0.027.7 MB 100.0% 0.09
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 218 0 0 0 0 0 0 0 218
2gegeweb-out.news.tnetconsulting.net 42 0 0 0 0 0 0 0 42
3news.glou.org 32 0 0 0 0 0 0 0 32
4usenet.pasdenom.info 10 0 0 0 0 0 0 0 10
5feed-out.gegeweb.org 2 0 0 0 0 0 0 0 2
TOTAL: 5 304 0 0 0 0 0 0 0 304

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 215
Bad path (news.giganews.com.POSTED) 42
EMP (phn path) 40
Cancels abusifs 2
Cancel of non-existing ID <ltspkbFke36U1@mid.individual.net> 1
Cancel of <vlb2rm$31gi$6@cabale.usenet-fr.net> without Cancel-Key 1
TOTAL: 6 301

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 2690 58 1838 3 0 6 2%23:57:58
2news.gegeweb.org 1856 15 1633 0 0 0 0%23:48:59
3news.tnetconsulting.net 2973 7 2042 3 0 13 0%23:58:00
4news.nntp4.net 2179 1 1951 0 0 0 0%23:58:00
5news.aioe.org 0 0 0 0 0 1521 0%24:00:00
6news.pasdenom.info 1706 0 1599 0 0 0 0%23:57:39
7nntp.terraraq.uk 1236 0 1200 0 0 0 0%23:57:39
8news.samoylyk.net 1844 0 1551 0 0 0 0%23:57:39
TOTAL: 8 14484 81 11814 6 0 1540 0%191:35:54
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org202.4 KB17.1 KB219.5 KB0.0 KB/s3.6 KB23:57:58
2news.gegeweb.org41.2 KB0.0 KB41.2 KB0.0 KB/s2.7 KB23:48:59
3news.tnetconsulting.net19.6 KB12.1 KB31.8 KB0.0 KB/s3.2 KB23:58:00
4news.nntp4.net4.0 KB0.0 KB4.0 KB0.0 KB/s4.0 KB23:58:00
5news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
6news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:39
7nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:39
8news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:39
TOTAL: 8267.2 KB29.2 KB296.4 KB0.0 KB/s3.4 KB191:35:54
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1162.250.122.68 2 00.0 KB 4 0 000:00:00
TOTAL: 1 2 00.0 KB 4 0 000:02:22

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 5 00.0 KB 0 0 000:00:04
2*.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:01
3*.stretchoid.com 1 00.0 KB 0 0 000:00:09
4*.gegeweb.eu 288 00.0 KB 0 0 000:01:38
5unresolved 7 00.0 KB 4 0 000:00:26
6*.149.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:01
TOTAL: 6 303 00.0 KB 4 0 000:02:22

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.gegeweb.eu 4.506 3.527 1.54300:01:38
147.185.132.73 0.013 0.009 0.00000:00:10
205.210.31.104 0.009 0.014 0.00000:00:10
azpdes80.stretchoid.com 0.033 0.017 0.00000:00:09
2a06:4882:9000::a8 0.036 0.004 0.00000:00:04
206.168.34.203 0.016 0.012 0.00000:00:02
41.210.203.35.bc.googleusercontent.com 0.059 0.013 0.00000:00:01
147.45.112.222 0.041 0.021 0.00000:00:01
91.238.181.34 0.035 0.016 0.00000:00:01
150.149.216.162.bc.googleusercontent.com 0.028 0.012 0.00000:00:01
2a06:4883:1000::e 0.049 0.012 0.00000:00:00
2a06:4882:1000::1c 0.026 0.026 0.00000:00:00
162.250.122.68 0.011 0.011 0.00000:00:00
TOTAL: 13 4.862 3.694 1.54300:02:22

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
2a06:4882:1000::1c 2
147.185.132.73 1
2a06:4882:9000::a8 1
206.168.34.203 1
91.238.181.34 1
azpdes80.stretchoid.com 1
147.45.112.222 1
150.149.216.162.bc.googleusercontent.com 1
2a06:4883:1000::e 1
41.210.203.35.bc.googleusercontent.com 1
2a06:4882:1000::20 1
205.210.31.104 1
TOTAL: 13 301

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
41.210.203.35.bc.googleusercontent.com 4
150.149.216.162.bc.googleusercontent.com 4
91.238.181.34 1
206.168.34.203 1
TOTAL: 4 10

NNRP unrecognized commands (by command):

CommandCount
Host: 45.13.105.36:563 2
Accept-Encoding: gzip 2
GET / HTTP/1.1 2
User-Agent: Expanse, a Palo Alto Network... 2
EHLO www.censys.io 1
#003 1
TOTAL: 6 10

NNRP client timeouts [Top 20]:

SystemConnPeer
91.238.181.34 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 10123100.0%
TOTAL: 1 10123100.0%

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

NewsgroupCount
1grisbi.bugsreports 8925
2grisbi.devel 1002
3grisbi.cvs 192
4grisbi.admin 4
TOTAL: 4 10123