Daily Usenet Report

Jan 29 00:01:02 -- Jan 30 00:01:01

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Jan 29 08:18:20 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Jan 29 15:00:08 newsfeed pgpverify[1056630]: pgpverify: verification failed
Jan 29 15:00:15 newsfeed pgpverify[1056688]: pgpverify: verification failed
Jan 29 15:01:16 newsfeed pgpverify[1057063]: pgpverify: verification failed
Jan 29 15:01:18 newsfeed pgpverify[1057119]: pgpverify: verification failed
Jan 29 15:02:20 newsfeed pgpverify[1057378]: pgpverify: verification failed
Jan 29 15:02:24 newsfeed pgpverify[1057425]: pgpverify: verification failed
Jan 30 00:00:01 newsfeed nnrpd[1195482]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 13969 38.2%1.8 MB 23.5%
inn 9947 27.2%3.8 MB 50.1%
innd 7694 21.0%1.1 MB 14.5%
innfeed 4433 12.1%843.9 KB 11.0%
cnfsstat 432 1.2%63.1 KB 0.8%
nocem 67 0.2%7.5 KB 0.1%
controlchan 7 0.0%0.5 KB 0.0%
pgpverify 6 0.0%0.9 KB 0.0%
TOTAL: 8 36555 100.0%7.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 135246 65.9%
Negative hits 60242 29.3%
Do not exist 9776 4.8%
Cache misses 18 0.0%
TOTAL: 4 205282 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.152 0.0%43 0.000 3.535 8.000
article cleanup00:00:00.107 0.0%9843 0.000 0.011 0.165
article logging00:00:00.454 0.0%9943 0.000 0.046 0.120
article parse00:00:00.224 0.0%14329 0.000 0.016 0.060
article write00:00:02.353 0.0%7533 0.000 0.312 4.167
artlog/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.975 0.0%197718 0.000 0.005 0.157
hisgrep/artcncl00:00:00.117 0.0%49 0.000 2.388 6.000
hishave/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
hishave/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:48.093 0.2%206646 0.097 0.813 5.188
history sync00:00:00.085 0.0%404 0.000 0.210 1.000
history write00:02:22.355 0.2%9843 3.200 14.463 147.895
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:44:30.480 98.8%196211 80.428 435.605 3190.202
nntp read00:00:04.676 0.0%199457 0.002 0.023 0.699
overview write00:09:35.208 0.7%7533 0.800 76.358 11166.333
perl filter00:00:38.121 0.0%9843 1.560 3.873 66.470
python filter00:00:02.357 0.0%9843 0.000 0.239 3.889
site send00:00:01.124 0.0%15136 0.000 0.074 0.226
TOTAL: 24:01:07.64924:00:06.881 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.023 0.0%8376 0.000 0.003 0.071
article prepare00:00:00.011 0.0%56376 0.000 0.000 0.005
article read00:00:00.658 0.0%5097 0.000 0.129 1.600
backlog stats00:00:00.465 0.0%305097 0.000 0.002 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.653 0.0%201776 0.000 0.013 0.042
data write00:00:03.595 0.0%195891 0.000 0.018 0.032
idle23:58:25.905100.0%305097 132.755 282.880 1991.056
status file00:00:01.382 0.0%409 0.929 3.379 125.571
TOTAL: 23:58:48.24823:58:34.692100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:18:40.854 61.1%8358 0.500 134.106 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.658 0.3%95512 0.000 0.049 1.000
TOTAL: 00:30:34.98400:18:45.512 61.3%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org000600
TOTAL000600

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1glockenspiel.complete.org 2 6647 1520 5119 8 22%23:59:14
2endofthelinebbs-out.news.weretis.net 4 16550 1413 14905 232 8%49:25:34
3usenet.blueworldhosting.com 288 9755 1173 7572 1010 12%23:45:42
4usenet.goja.nl.eu.org 10 8694 789 7670 235 9%22:05:39
5nntp.comgw.net 2 9332 407 8756 169 4%23:58:13
6news.corradoroberto.it 11 1433 352 1081 0 24%24:29:59
7i2pn.org 1 9090 313 8722 55 3%23:59:54
8newsfeed.bofh.team 1 9610 276 9253 81 2%23:59:54
9usenet.network 1 6246 250 5968 28 4%23:59:57
10endoftheline-out.news.chmurka.net 1 3822 213 3609 0 5%23:59:58
11194.87.241.33 48 775 183 417 175 23%16:36:50
12newsfeed.xs3.de 2 7095 163 6812 120 2%23:59:27
13news.nntp4.net 2 7476 137 7298 41 1%23:57:49
14news.samoylyk.net 4 7626 136 7444 46 1%23:53:32
15news.furie.org.uk 2 3944 49 3894 1 1%23:59:52
16endofthelinebbs.peers.news.panix.com 22 8277 48 8219 10 0%23:57:11
17news.hispagatos.org 5 9418 42 9333 43 0%24:26:21
18news.netfront.net 1 7572 27 7504 41 0%23:49:03
19news.bbs.nz 1 7512 11 7500 1 0%23:59:40
20outgoing.news.lysator.liu.se 2 4815 5 4808 2 0%23:59:27
21localhost 28 0 0 0 0 0%00:00:01
22news.nk.ca 2 0 0 0 0 0%00:20:06
TOTAL: 22 440 145689 7507 135884 2298 5%496:43:23
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1glockenspiel.complete.org4.8 MB0.0 KB18.8 KB4.9 MB 99%3.3 KB
2endofthelinebbs-out.news.weretis.net4.7 MB0.0 KB494.9 KB5.2 MB 90%3.2 KB
3usenet.blueworldhosting.com3.2 MB0.0 KB2.1 MB5.3 MB 60%2.5 KB
4usenet.goja.nl.eu.org2.2 MB0.0 KB393.1 KB2.6 MB 85%2.6 KB
5usenet.network2.0 MB0.0 KB36.2 KB2.0 MB 98%7.4 KB
6news.corradoroberto.it1.6 MB0.0 KB0.0 KB1.6 MB100%4.6 KB
7nntp.comgw.net1.2 MB0.0 KB384.4 KB1.6 MB 76%2.8 KB
8newsfeed.bofh.team1.1 MB0.0 KB165.8 KB1.2 MB 86%3.5 KB
9endoftheline-out.news.chmurka.net969.8 KB0.0 KB0.0 KB969.8 KB100%4.6 KB
10i2pn.org858.6 KB0.0 KB77.5 KB936.1 KB 91%2.5 KB
11newsfeed.xs3.de560.8 KB0.0 KB343.8 KB904.7 KB 61%3.2 KB
12194.87.241.33549.7 KB0.0 KB454.4 KB1004.0 KB 54%2.8 KB
13news.samoylyk.net547.9 KB0.0 KB122.4 KB670.2 KB 81%3.7 KB
14news.nntp4.net376.2 KB0.0 KB122.6 KB498.8 KB 75%2.8 KB
15news.furie.org.uk209.4 KB0.0 KB0.8 KB210.1 KB 99%4.2 KB
16endofthelinebbs.peers.news.panix.com173.2 KB0.0 KB11.0 KB184.2 KB 94%3.2 KB
17news.hispagatos.org145.2 KB0.0 KB82.8 KB228.1 KB 63%2.7 KB
18news.netfront.net78.9 KB0.0 KB143.7 KB222.5 KB 35%3.3 KB
19news.bbs.nz24.1 KB3.5 KB0.0 KB27.7 KB 87%2.3 KB
20outgoing.news.lysator.liu.se15.5 KB0.0 KB9.4 KB24.9 KB 62%3.6 KB
21localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
22news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2225.2 MB3.5 KB4.9 MB30.1 MB 83%3.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 29 00:01:02 - 00:59:59 142 1.9% 0.04371.4 KB 1.4% 0.10
Jan 29 01:00:00 - 01:59:59 240 3.2% 0.07735.6 KB 2.9% 0.20
Jan 29 02:00:00 - 02:59:59 331 4.4% 0.091006.5 KB 3.9% 0.28
Jan 29 03:00:00 - 03:59:59 393 5.2% 0.11992.1 KB 3.8% 0.28
Jan 29 04:00:00 - 04:59:59 464 6.2% 0.131.1 MB 4.5% 0.32
Jan 29 05:00:00 - 05:59:59 374 5.0% 0.101.2 MB 4.6% 0.33
Jan 29 06:00:00 - 06:59:59 390 5.2% 0.111.1 MB 4.4% 0.31
Jan 29 07:00:00 - 07:59:59 409 5.4% 0.111.4 MB 5.6% 0.40
Jan 29 08:00:00 - 08:59:59 395 5.2% 0.111.4 MB 5.7% 0.41
Jan 29 09:00:00 - 09:59:59 531 7.1% 0.152.0 MB 7.9% 0.57
Jan 29 10:00:00 - 10:59:59 482 6.4% 0.131.5 MB 6.1% 0.44
Jan 29 11:00:00 - 11:59:59 411 5.5% 0.111.3 MB 5.3% 0.38
Jan 29 12:00:00 - 12:59:59 388 5.2% 0.111.1 MB 4.5% 0.32
Jan 29 13:00:00 - 13:59:59 326 4.3% 0.091017.1 KB 3.9% 0.28
Jan 29 14:00:00 - 14:59:59 369 4.9% 0.101.6 MB 6.4% 0.46
Jan 29 15:00:00 - 15:59:59 450 6.0% 0.121.8 MB 7.2% 0.52
Jan 29 16:00:00 - 16:59:59 311 4.1% 0.091004.4 KB 3.9% 0.28
Jan 29 17:00:00 - 17:59:59 312 4.1% 0.091.3 MB 5.2% 0.38
Jan 29 18:00:00 - 18:59:59 180 2.4% 0.05817.7 KB 3.2% 0.23
Jan 29 19:00:00 - 19:59:59 117 1.6% 0.03442.5 KB 1.7% 0.12
Jan 29 20:00:00 - 20:59:59 83 1.1% 0.02341.1 KB 1.3% 0.09
Jan 29 21:00:00 - 21:59:59 129 1.7% 0.04444.1 KB 1.7% 0.12
Jan 29 22:00:00 - 22:59:59 149 2.0% 0.04650.2 KB 2.5% 0.18
Jan 29 23:00:00 - 23:59:59 145 1.9% 0.04539.7 KB 2.1% 0.15
Jan 30 00:00:00 - 00:01:01 9 0.1% 0.1514.3 KB 0.1% 0.23
TOTAL: 23:59:59 7530 100.0% 0.0925.2 MB 100.0% 0.30
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
CYCNGS1NGS1536.0 GB48.0 MB 0.0% 1 0.00 0.00
CYCNGS2NGS1536.0 GB102.9 GB 6.7% 0 0.4739786.13
CYCTXT1TEXT5.0 GB4.0 GB 80.5% 0 0.03 1896.34
TOTAL: 3-3077.0 GB106.9 GB 3.5% 1 0.31-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 1025 0 0 0 0 0 0 0 1025
2endofthelinebbs-out.news.weretis.net 290 0 0 0 0 0 0 0 290
3usenet.goja.nl.eu.org 250 0 0 0 0 0 0 0 250
4194.87.241.33 175 0 0 0 0 0 0 0 175
5nntp.comgw.net 173 0 0 0 0 0 0 0 173
6newsfeed.xs3.de 121 0 0 0 0 0 0 0 121
7newsfeed.bofh.team 85 0 0 0 0 0 0 0 85
8i2pn.org 53 0 0 0 0 0 0 0 53
9news.netfront.net 48 0 0 0 0 0 0 0 48
10news.samoylyk.net 47 0 0 0 0 0 0 0 47
11news.hispagatos.org 43 29 0 0 0 0 0 0 14
12news.nntp4.net 41 0 0 0 0 0 0 0 41
13usenet.network 29 0 0 0 0 0 0 0 29
14endofthelinebbs.peers.news.panix.com 17 0 0 0 0 0 0 0 17
15glockenspiel.complete.org 9 0 0 0 0 0 0 0 9
16outgoing.news.lysator.liu.se 2 0 0 0 0 0 0 0 2
17news.furie.org.uk 1 0 0 0 0 0 0 0 1
TOTAL: 17 2409 29 0 0 0 0 0 0 2380

Unwanted newsgroups [Top 20]:

NewsgroupCount
hispagatos.mastodon.tag.hacking 20
hispagatos.hacking.ctf 5
hispagatos.hacking.news 3
hispagatos.mastodon.tag.hackers 1
TOTAL: 4 29

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 1543
EMP (phr path) 317
EMP (md5) 195
Too many newsgroups 130
EMP (phn nph) 51
User-issued cancel 24
Too many newsgroups (meow) 14
Angle-bracket bot 3
HTML Multipart 3
TOTAL: 9 2280

NoCeM on Spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
i2pn2-nocem@i2pn2.org (spam)10101
robot@pasdenom.info (spam)10101
i2pn2-nocem@i2pn2.org (multipost)35003737
robot@pasdenom.info (spam2)18002525
robot@pasdenom.info (spam3)6001717
i2pn2-nocem@i2pn2.org (rmp)30077
TOTAL: 664028688

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 61
usenet.blueworldhosting.com 15
usenet.goja.nl.eu.org 10
endofthelinebbs.peers.news.panix.com 7
nntp.comgw.net 2
usenet.network 2
glockenspiel.complete.org 1
news.netfront.net 1
newsfeed.bofh.team 1
TOTAL: 9 100
TOTAL: 1 100

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nyheter.lysator.liu.se 8041 3610 3679 236 0 13 44%23:59:50
2news.netfront.net 12131 2196 5305 0 0 0 18%23:57:47
3endofthelinebbs.peers.news.panix.com 7809 927 6593 9 0 0 11%23:59:50
4news.furie.org.uk 5116 772 2797 28 0 0 15%23:59:50
5photonic.trudheim.com 9063 618 5100 1811 0 0 6%23:59:49
6usenet.goja.nl.eu.org 8386 380 6194 121 0 0 4%23:58:35
7news.corradoroberto.it 1492 159 832 1 0 11 10%21:18:47
8newsfeed.bofh.team 8667 150 7086 0 0 0 1%23:59:50
9news.bbs.nz 14002 149 6893 477 0 45 1%23:59:50
10news.nk.ca 9986 111 6763 472 0 29 1%23:59:50
11i2pn.org 8182 85 7047 63 0 21 1%23:59:32
12news.nntp4.net 9308 83 7300 5 0 0 0%23:59:29
13news.hispagatos.org 9384 71 7234 183 0 0 0%23:59:50
14usenet.network 8786 53 6688 18 0 0 0%23:59:34
15nntp.comgw.net 8009 53 6964 97 0 0 0%23:59:32
16news.weretis.net 7890 51 7347 130 0 0 0%23:59:41
17news.quux.org 5844 40 4933 464 0 0 0%23:59:25
18newsfeed.xs3.de 9531 21 7148 176 0 0 0%23:59:50
19news.tnetconsulting.net 9638 19 7303 206 0 5 0%23:59:50
20ddt.demos.su 78 16 61 0 0 0 20%07:39:59
21news.chmurka.net 4832 11 3424 0 0 0 0%23:59:50
22usenet.blueworldhosting.com 6834 10 6229 0 0 0 0%23:59:50
23news.samoylyk.net 11063 7 7265 20 0 746 0%23:59:50
24feed.opticnetworks.net 0 0 0 0 0 7530 0%23:59:50
TOTAL: 24 184072 9592 130185 4517 0 8400 5%556:50:00
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nyheter.lysator.liu.se10.9 MB1.9 MB12.9 MB0.2 KB/s3.4 KB23:59:50
2news.netfront.net7.7 MB0.0 KB7.7 MB0.1 KB/s3.6 KB23:57:47
3news.furie.org.uk4.6 MB144.4 KB4.7 MB0.1 KB/s6.0 KB23:59:50
4endofthelinebbs.peers.news.panix.com3.1 MB32.6 KB3.2 MB0.0 KB/s3.5 KB23:59:50
5photonic.trudheim.com2.5 MB5.0 MB7.6 MB0.1 KB/s3.2 KB23:59:49
6newsfeed.bofh.team954.5 KB0.0 KB954.5 KB0.0 KB/s6.4 KB23:59:50
7usenet.goja.nl.eu.org797.8 KB988.3 KB1.7 MB0.0 KB/s3.6 KB23:58:35
8news.bbs.nz661.3 KB1.7 MB2.4 MB0.0 KB/s3.8 KB23:59:50
9news.nk.ca556.4 KB1.9 MB2.4 MB0.0 KB/s4.2 KB23:59:50
10news.corradoroberto.it390.7 KB1.4 KB392.2 KB0.0 KB/s2.5 KB21:18:47
11i2pn.org338.9 KB366.0 KB704.9 KB0.0 KB/s4.8 KB23:59:32
12news.weretis.net281.3 KB1.0 MB1.3 MB0.0 KB/s7.4 KB23:59:41
13news.nntp4.net273.3 KB33.4 KB306.7 KB0.0 KB/s3.5 KB23:59:29
14news.hispagatos.org244.1 KB1.4 MB1.7 MB0.0 KB/s6.8 KB23:59:50
15usenet.network241.4 KB36.4 KB277.8 KB0.0 KB/s3.9 KB23:59:34
16news.quux.org206.0 KB1.7 MB1.9 MB0.0 KB/s3.8 KB23:59:25
17nntp.comgw.net142.1 KB786.4 KB928.6 KB0.0 KB/s6.2 KB23:59:32
18newsfeed.xs3.de95.6 KB1.1 MB1.2 MB0.0 KB/s6.0 KB23:59:50
19news.tnetconsulting.net69.2 KB1.4 MB1.5 MB0.0 KB/s6.7 KB23:59:50
20news.chmurka.net65.9 KB0.0 KB65.9 KB0.0 KB/s6.0 KB23:59:50
21ddt.demos.su45.4 KB0.0 KB45.4 KB0.0 KB/s2.8 KB07:39:59
22news.samoylyk.net45.2 KB42.7 KB87.9 KB0.0 KB/s3.3 KB23:59:50
23usenet.blueworldhosting.com40.5 KB0.0 KB40.5 KB0.0 KB/s4.0 KB23:59:50
24feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
TOTAL: 2434.2 MB19.6 MB53.8 MB0.0 KB/s3.9 KB556:50:00
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
12a0e:73c2:8000:802c::22783 00.0 KB 0 0 000:18:56
2167.94.138.49 3 00.0 KB 0 0 000:00:03
3147.185.132.72 1 00.0 KB 0 0 000:00:10
4198.235.24.42 1 00.0 KB 0 0 000:00:10
573.149.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
6azpdegc49.stretchoid.com 1 00.0 KB 0 0 000:00:00
7azpdssb45.stretchoid.com 1 00.0 KB 0 0 000:00:09
8localhost 1 00.0 KB 0 0 000:11:03
9ns5032159.ip-148-113-206.net 1 00.0 KB 0 0 000:00:00
TOTAL: 92793 00.0 KB 0 0 000:30:33

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1?2784 00.0 KB 0 0 000:29:59
2unresolved 5 00.0 KB 0 0 000:00:23
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.149.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
5*.ip-148-113-206.net 1 00.0 KB 0 0 000:00:00
TOTAL: 52793 00.0 KB 0 0 000:30:33

NNRP auth users [Top 20]:

UserConn
getlist 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
2a0e:73c2:8000:802c::2 9.987 37.975 0.00000:18:56
localhost 178.803 372.493 0.07800:11:03
198.235.24.42 0.000 0.016 0.00000:00:10
147.185.132.72 0.005 0.011 0.00000:00:10
azpdssb45.stretchoid.com 0.014 0.003 0.00000:00:09
167.94.138.49 0.019 0.056 0.00000:00:03
ns5032159.ip-148-113-206.net 0.005 0.011 0.00000:00:00
azpdegc49.stretchoid.com 0.003 0.013 0.00000:00:00
73.149.216.162.bc.googleusercontent.com 0.004 0.012 0.00000:00:00
TOTAL: 9 188.840 410.590 0.07800:30:33

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

SystemConn
73.149.216.162.bc.googleusercontent.com 4
167.94.138.49 1
azpdegc49.stretchoid.com 1
ns5032159.ip-148-113-206.net 1
TOTAL: 4 7

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

CommandCount
Accept-Encoding: gzip 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
Host: 144.172.126.95:119 1
MGLNDD_144.172.126.95_119 1
SSH-2.0-Go 1
User-Agent: Expanse, a Palo Alto Network... 1
TOTAL: 7 7

NNRP client timeouts [Top 20]:

SystemConnPeer
167.94.138.49 1 2
TOTAL: 1 1 2