Daily Usenet report

Dec 21 00:01:02 -- Dec 22 00:01:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 21 00:53:56 www innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program nameLines%LinesSize%Size
inn 193342 85.2%42.4 MB 90.6%
innd 29355 12.9%3.6 MB 7.8%
innfeed 3981 1.8%743.0 KB 1.6%
nnrpd 316 0.1%25.0 KB 0.1%
rnews 3 0.0%0.4 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 226998 100.0%46.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 709505 62.4%
Negative hits 335704 29.5%
Do not exist 71344 6.3%
Cache misses 19950 1.8%
TOTAL: 4 1136503 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:49.469 0.1%142848 0.207 0.346 2.007
article cleanup00:00:01.125 0.0%69916 0.005 0.016 0.052
article logging00:00:03.344 0.0%70044 0.011 0.048 0.095
article parse00:00:07.559 0.0%406100 0.006 0.019 0.033
article write00:01:00.529 0.1%53282 0.802 1.136 2.186
artlog/artcncl00:00:01.765 0.0%123906 0.003 0.014 0.056
data move00:00:02.558 0.0%1428702 0.001 0.002 0.005
hisgrep/artcncl00:00:12.217 0.0%140737 0.024 0.087 1.005
hishave/artcncl00:00:01.279 0.0%142538 0.000 0.009 0.066
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:38.460 0.0%1007246 0.010 0.038 0.202
history sync00:00:00.060 0.0%297 0.000 0.202 1.000
history write00:00:39.139 0.0%69857 0.223 0.560 1.980
hiswrite/artcncl00:00:00.719 0.0%2119 0.000 0.339 2.500
idle23:38:01.231 98.4%1315295 26.600 64.686 3190.947
nntp read00:01:04.784 0.1%1340365 0.022 0.048 0.117
overview write00:03:33.225 0.2%53282 0.573 4.002 213.365
perl filter00:08:31.278 0.6%69857 3.599 7.319 17.216
python filter00:00:07.520 0.0%69857 0.067 0.108 0.207
site send00:00:19.407 0.0%107907 0.051 0.180 0.448
TOTAL: 24:00:33.02323:55:15.668 99.6%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.431 0.0%86722 0.000 0.005 0.047
article prepare00:00:00.116 0.0%637728 0.000 0.000 0.003
article read00:00:19.139 0.0%53022 0.199 0.361 1.438
backlog stats00:00:04.005 0.0%1735644 0.000 0.002 0.148
callbacks00:00:00.046 0.0%123 0.369 0.374 1.000
data read00:00:43.426 0.1%1308710 0.021 0.033 0.095
data write00:02:48.977 0.2%1231043 0.036 0.137 1.190
idle23:46:51.232 99.7%1735644 11.267 49.325 22295.148
newart/cb00:00:00.000 0.0%55 0.000 0.000 0.000
prepart/cb00:00:00.000 0.0%366 0.000 0.000 0.000
readart/cb00:00:00.043 0.0%119 0.000 0.361 0.364
status file00:00:00.504 0.0%509 0.000 0.990 13.000
TOTAL: 23:51:46.70923:50:47.919 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:11.609 87.7%10 15.000 1160.900 9770.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%37 0.000 0.054 0.125
TOTAL: 00:00:13.24100:00:11.611 87.7%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 3
logmode 2
lowmark 1
mode 141
name 2
pause 2
paused 2
reload 2
reserve 2
throttle 3
TOTAL: 12 167

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 24 85943 19801 64790 1352 23%45:40:06
2usenet.blueworldhosting.com 1342 33242 8464 21304 3474 25%21:36:22
3newsfeed.xs3.de 6 49503 4694 44455 354 9%21:33:08
4usenet.network 6 42853 4367 38163 323 10%22:11:07
5news.nntp4.net 10 51998 3837 47761 400 7%21:05:40
6usenet.goja.nl.eu.org 12 54239 2574 51243 422 4%19:22:55
7nntp.comgw.net 4 54862 2467 50372 2023 4%19:41:32
8endofthelinebbs.peers.news.panix.com 45 53352 2179 51049 124 4%23:56:22
9paganini.bofh.team 5 60421 1542 58188 691 2%19:50:20
10news.hispagatos.org 9 20519 1491 19014 14 7%19:04:48
11glockenspiel.complete.org 10 34198 513 33647 38 1%11:46:21
12endoftheline-out.news.chmurka.net 3 12187 498 11675 14 4%17:24:44
13outgoing.news.lysator.liu.se 2 9466 364 8953 149 3%17:11:52
14194.87.241.33 27 655 256 211 188 39%17:42:00
15i2pn.org 3 10258 233 10010 15 2%19:32:30
16news.netfront.net 26 8335 20 1284 7031 0%18:06:23
17news.samoylyk.net 2 8747 20 8726 1 0%18:59:07
18news.furie.org.uk 2 29043 16 29025 2 0%20:56:20
19news.bbs.nz 3 15617 3 15614 0 0%11:30:04
20localhost 24 0 0 0 0 0%00:00:00
21eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:03
22news.nk.ca 3 0 0 0 0 0%00:30:00
TOTAL: 22 1569 635438 53339 565484 16615 8%387:51:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net189.9 MB78.7 KB3.0 MB193.0 MB 98%9.3 KB
2usenet.blueworldhosting.com58.8 MB38.1 KB12.4 MB71.2 MB 82%6.1 KB
3newsfeed.xs3.de51.7 MB35.2 KB5.7 MB57.4 MB 90%11.6 KB
4news.nntp4.net48.1 MB88.2 KB7.9 MB56.0 MB 85%13.5 KB
5usenet.network35.3 MB162.8 KB1.5 MB37.0 MB 95%8.1 KB
6usenet.goja.nl.eu.org20.1 MB15.7 KB1.8 MB21.9 MB 91%7.5 KB
7nntp.comgw.net18.1 MB1.9 KB18.1 MB36.3 MB 49%8.3 KB
8endofthelinebbs.peers.news.panix.com15.7 MB86.3 KB609.7 KB16.4 MB 95%7.3 KB
9paganini.bofh.team13.1 MB87.8 KB6.8 MB20.0 MB 65%9.2 KB
10news.hispagatos.org5.1 MB75.9 KB7.5 KB5.2 MB 98%3.5 KB
11glockenspiel.complete.org5.0 MB0.0 KB282.4 KB5.3 MB 94%9.8 KB
12endoftheline-out.news.chmurka.net3.3 MB0.0 KB50.6 KB3.3 MB 98%6.6 KB
13i2pn.org2.3 MB0.0 KB29.2 KB2.3 MB 98%9.7 KB
14news.netfront.net1000.2 KB0.0 KB2.3 GB2.3 GB 0%340.4 KB
15outgoing.news.lysator.liu.se906.2 KB0.0 KB223.7 KB1.1 MB 80%2.2 KB
16194.87.241.33883.5 KB9.2 KB277.0 KB1.1 MB 75%2.6 KB
17news.furie.org.uk132.0 KB0.0 KB8.1 KB140.1 KB 94%7.8 KB
18news.samoylyk.net63.0 KB0.0 KB3.8 KB66.8 KB 94%3.2 KB
19news.bbs.nz13.6 KB0.0 KB0.0 KB13.6 KB100%4.5 KB
20localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
21eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
22news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 22469.5 MB679.9 KB2.3 GB2.8 GB 16%42.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 21 00:01:02 - 00:59:59 3249 6.1% 0.9234.9 MB 0.0% 10.10
Dec 21 01:00:00 - 01:59:59 3680 6.9% 1.0238.9 MB 33.3% 11.05
Dec 21 02:00:00 - 02:59:59 3846 7.2% 1.0739.6 MB 33.9% 11.25
Dec 21 03:00:00 - 03:59:59 3432 6.4% 0.9530.7 MB 26.3% 8.72
Dec 21 04:00:00 - 04:59:59 2954 5.5% 0.8226.8 MB 23.0% 7.62
Dec 21 05:00:00 - 05:59:59 2956 5.6% 0.8225.9 MB 22.2% 7.38
Dec 21 06:00:00 - 06:59:59 2999 5.6% 0.8326.4 MB 22.6% 7.50
Dec 21 07:00:00 - 07:59:59 3535 6.6% 0.9831.8 MB 27.3% 9.05
Dec 21 08:00:00 - 08:59:59 2835 5.3% 0.7924.2 MB 20.7% 6.88
Dec 21 09:00:00 - 09:59:59 2997 5.6% 0.8323.8 MB 20.4% 6.76
Dec 21 10:00:00 - 10:59:59 2464 4.6% 0.6819.5 MB 16.7% 5.55
Dec 21 11:00:00 - 11:59:59 301 0.6% 0.082.3 MB 2.0% 0.66
Dec 21 12:00:00 - 12:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Dec 21 13:00:00 - 13:59:59 5305 10.0% 1.4736.4 MB 31.2% 10.34
Dec 21 14:00:00 - 14:59:59 1686 3.2% 0.4711.7 MB 10.0% 3.32
Dec 21 15:00:00 - 15:59:59 1357 2.5% 0.3811.0 MB 9.4% 3.12
Dec 21 16:00:00 - 16:59:59 1161 2.2% 0.328.0 MB 6.8% 2.27
Dec 21 17:00:00 - 17:59:59 1037 1.9% 0.297.8 MB 6.7% 2.21
Dec 21 18:00:00 - 18:59:59 755 1.4% 0.215.0 MB 4.3% 1.42
Dec 21 19:00:00 - 19:59:59 935 1.8% 0.265.9 MB 5.1% 1.68
Dec 21 20:00:00 - 20:59:59 983 1.8% 0.277.4 MB 6.3% 2.09
Dec 21 21:00:00 - 21:59:59 1045 2.0% 0.299.7 MB 8.3% 2.75
Dec 21 22:00:00 - 22:59:59 1870 3.5% 0.5219.0 MB 16.3% 5.40
Dec 21 23:00:00 - 23:59:59 1820 3.4% 0.5119.4 MB 16.7% 5.53
Dec 22 00:00:00 - 00:01:01 37 0.1% 0.61395.6 KB 0.3% 6.49
TOTAL: 23:59:59 53239 100.0% 0.62116.7 MB 100.0% 1.38
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.netfront.net 7031 0 0 0 0 0 0 0 7031
2usenet.blueworldhosting.com 3527 37 0 2 0 0 0 0 3488
3nntp.comgw.net 2026 0 0 1 0 0 0 0 2025
4endofthelinebbs-out.news.weretis.net 1388 0 0 1 0 0 0 0 1387
5paganini.bofh.team 708 0 0 2 0 0 0 0 706
6usenet.goja.nl.eu.org 446 0 0 1 0 0 0 0 445
7news.nntp4.net 370 0 0 2 0 0 0 0 368
8newsfeed.xs3.de 350 0 0 1 0 0 0 0 349
9usenet.network 336 0 0 32 0 0 0 0 304
10194.87.241.33 187 0 0 6 0 0 0 0 181
11outgoing.news.lysator.liu.se 149 0 0 0 0 0 0 0 149
12endofthelinebbs.peers.news.panix.com 127 3 0 1 0 0 0 0 123
13glockenspiel.complete.org 54 0 0 0 0 0 0 0 54
14i2pn.org 17 0 0 0 0 0 0 0 17
15news.hispagatos.org 16 0 0 10 0 0 0 0 6
16endoftheline-out.news.chmurka.net 15 0 0 0 0 0 0 0 15
17news.furie.org.uk 2 0 0 0 0 0 0 0 2
18news.samoylyk.net 1 0 0 0 0 0 0 0 1
TOTAL: 18 16750 40 0 59 0 0 0 016651

Unwanted newsgroups [Top 20]:

NewsgroupCount
de.sci.announce 40
TOTAL: 1 40

INND Perl filter [Top 20]:

ReasonCount
Binary: misplaced binary 6916
EMP (md5) 4255
EMP (phn path) 2961
Too many newsgroups 457
EMP (phr path) 357
Subject (Buy Xanax) 152
EMP (phl) 83
Subject (Buy Tramadol) 52
EMP (fsl) 41
User-issued spam cancel 38
Subject (Buy Percocet) 16
Subject (lSDguY) 15
Subject (lSDguKrguKXguY) 15
User-issued cancel 15
Subject (lSDguYD) 14
Subject (lSDsmKTtlL) 14
Subject (lSDguYDguKfguYfguJr) 13
Subject (BuY Xanax) 12
EMP (phr nph) 10
Subject (lSDguKvguKfguKIg4Lih4Lix4LmI4LiZ4LiE4LiH4LiX4Li) 10
TOTAL: 739 16523

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
usenet.blueworldhosting.com 55
endofthelinebbs-out.news.weretis.net 44
endofthelinebbs.peers.news.panix.com 12
usenet.network 9
nntp.comgw.net 2
i2pn.org 1
news.hispagatos.org 1
usenet.goja.nl.eu.org 1
newsfeed.xs3.de 1
glockenspiel.complete.org 1
TOTAL: 10 127
TOTAL: 1 127

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 52923 51328 577 71 1243 2111 96%21:51:34
2nyheter.lysator.liu.se 53382 40754 10380 1571 168 720 76%21:51:35
3endofthelinebbs.peers.news.panix.com 54849 11411 41772 51 0 2 20%21:51:35
4usenet.network 59959 8418 39367 668 0 18 14%21:51:40
5news.furie.org.uk 30258 3608 22698 57 0 50 11%21:51:35
6news.hispagatos.org 59574 3475 44524 3650 0 496 5%21:51:40
7news.quux.org 55485 2050 49772 849 0 71 3%21:51:40
8news.nntp4.net 92066 1264 45684 267 0 35155 1%21:51:40
9news.bbs.nz 93084 1092 48683 3459 0 1832 1%21:51:40
10nntp.comgw.net 61284 812 51830 598 0 436 1%21:51:40
11paganini.bofh.team 61134 809 52420 7 0 524 1%21:51:40
12news.nk.ca 66573 779 40212 12175 1 612 1%21:51:40
13usenet.goja.nl.eu.org 58780 568 49475 508 0 960 0%21:51:37
14news.weretis.net 54439 450 51190 1599 0 21 0%21:51:35
15news.chmurka.net 28009 408 23632 54 0 0 1%21:51:40
16newsfeed.xs3.de 55711 284 47216 623 0 578 0%21:51:40
17usenet.blueworldhosting.com 42556 261 40682 1 0 19 0%21:49:14
18ddt.demos.su 805 257 535 0 0 1 31%19:55:39
19i2pn.org 71584 229 50134 2447 3 1281 0%21:51:40
20news.tnetconsulting.net 62105 73 50905 2257 0 2 0%21:51:35
21news.samoylyk.net 67443 26 52669 520 0 745 0%21:51:40
TOTAL: 211182003 128356 814357 31432 1415 45634 10%457:05:59
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net450.6 MB498.5 KB451.1 MB5.9 KB/s9.0 KB21:51:34
2nyheter.lysator.liu.se404.8 MB10.0 MB414.9 MB5.4 KB/s10.0 KB21:51:35
3endofthelinebbs.peers.news.panix.com125.8 MB505.9 KB126.3 MB1.6 KB/s11.3 KB21:51:35
4usenet.network79.8 MB4.5 MB84.3 MB1.1 KB/s9.5 KB21:51:40
5news.hispagatos.org42.6 MB29.6 MB72.2 MB0.9 KB/s10.4 KB21:51:40
6news.furie.org.uk27.7 MB280.0 KB28.0 MB0.4 KB/s7.8 KB21:51:35
7news.quux.org21.8 MB4.8 MB26.6 MB0.3 KB/s9.4 KB21:51:40
8news.nntp4.net11.5 MB740.6 KB12.3 MB0.2 KB/s8.2 KB21:51:40
9nntp.comgw.net8.4 MB4.2 MB12.7 MB0.2 KB/s9.2 KB21:51:40
10news.bbs.nz8.0 MB26.5 MB34.5 MB0.4 KB/s7.8 KB21:51:40
11paganini.bofh.team7.1 MB115.4 KB7.2 MB0.1 KB/s9.1 KB21:51:40
12usenet.goja.nl.eu.org5.2 MB2.9 MB8.0 MB0.1 KB/s7.7 KB21:51:37
13newsfeed.xs3.de3.9 MB3.7 MB7.6 MB0.1 KB/s8.6 KB21:51:40
14news.chmurka.net3.2 MB1.8 MB5.0 MB0.1 KB/s11.1 KB21:51:40
15news.weretis.net3.2 MB16.0 MB19.1 MB0.2 KB/s9.6 KB21:51:35
16news.nk.ca2.0 MB123.2 MB125.2 MB1.6 KB/s9.9 KB21:51:40
17i2pn.org902.7 KB20.2 MB21.1 MB0.3 KB/s8.1 KB21:51:40
18news.samoylyk.net760.4 KB3.8 MB4.6 MB0.1 KB/s8.6 KB21:51:40
19usenet.blueworldhosting.com514.9 KB3.3 KB518.2 KB0.0 KB/s2.0 KB21:49:14
20ddt.demos.su503.4 KB0.0 KB503.4 KB0.0 KB/s2.0 KB19:55:39
21news.tnetconsulting.net232.7 KB19.7 MB20.0 MB0.3 KB/s8.8 KB21:51:35
TOTAL: 211.2 GB273.1 MB1.4 GB0.9 KB/s9.5 KB457:05:59
Outgoing feeds (innfeed) by Volume

Rnews connections rejected:

ReasonConn
400 running scanspool 3
TOTAL: 1 3

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 3 00.0 KB 0 0 000:00:01
2*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
3*.149.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
4unresolved 1 00.0 KB 3 0 000:00:09
TOTAL: 4 6 00.0 KB 3 0 000:00:13

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
192.241.196.109 0.089 0.052 0.00000:00:09
clever.monitoring.internet-measurement.com 0.106 0.073 0.00000:00:01
2607:ff10:c8:594::6 0.361 0.147 0.00000:00:01
31.149.216.162.bc.googleusercontent.com 0.103 0.047 0.00000:00:00
TOTAL: 4 0.659 0.319 0.00000:00:13

Curious NNRP server explorers [Top 20]:

SystemConn
2607:ff10:c8:594::6 3
192.241.196.109 1
31.149.216.162.bc.googleusercontent.com 1
clever.monitoring.internet-measurement.com 1
TOTAL: 4 6

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
2607:ff10:c8:594::6 7
31.149.216.162.bc.googleusercontent.com 3
TOTAL: 2 10

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
GET / HTTP/1.1 2
Accept-Encoding: gzip 1
#026#003#001 1
User-Agent: Expanse, a Palo Alto Network... 1
#023#003#023#001#023#002#001 1
Host: 192.138.210.162:119 1
Accept: */* 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
Host: [2604:880:52:292::1]:119 1
TOTAL: 9 10

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1dfw 3 60.0%
2news 2 40.0%
TOTAL: 2 5100.0%

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

NewsgroupCount
1dfw.forsale 3
2news.answers 1
3news.admin.net-abuse.usenet 1
TOTAL: 3 5