usenet.goja.nl.eu.org Daily Usenet report

Apr 14 01:00:09 -- Apr 15 00:04:54

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Apr 14 11:28:53 goja innd[43164]: filter: No Cancel-Key[sha1:PC7sSphO0oKzX3ZiRhvxHqoWlrw= sha256:zyGsz+hPY49JVnc24nZDvxAeQJAGMF2YbKxNJnCBYYo=] matches Cancel-Lock[sha1:RNlK9cFvetahLitzS3MdRFw0+4c= sha256:sWZAKwlAgjECzrVvL8TQp/hXIMGBMQfShynFFn7V71I=] target=<10rhmtj$hh53$1@gwaiyur.mb-net.net>
Apr 14 15:19:27 goja innd[43164]: filter: No Cancel-Key[sha1:VF19iTuEOFxx3tnR8zsCTt0u8Ik= sha256:UjrKwKi04Il2vzQPYQxPBL709XvgVw3w5vW467U9ric=] matches Cancel-Lock[sha1:WAS8BjKcEE7Qqymb95Y4hbrlhN4= sha256:/4Uj4CCpLSsKO9WgzxrA8EjkUbxl6z3FSzDjOaY/Pmk=] target=<10rle7m$tfve$1@gwaiyur.mb-net.net>
Apr 14 22:59:52 goja pgpverify[48]: pgpverify: verification failed
Apr 15 00:03:00 goja news2mail[51048]: begin
Apr 15 00:03:00 goja news2mail[832]: end

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 15067 46.4%1.5 MB 41.5%
innd 13291 40.9%1.6 MB 45.4%
innfeed 4069 12.5%474.5 KB 12.8%
nocem 44 0.1%5.8 KB 0.2%
controlchan 12 0.0%2.6 KB 0.1%
inn 10 0.0%2.2 KB 0.1%
news2mail 2 0.0%0.3 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 8 32496 100.0%3.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 80137 63.7%
Negative hits 32013 25.4%
Do not exist 12865 10.2%
Cache misses 792 0.6%
TOTAL: 4 125807 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.011 0.0%17 0.000 0.647 1.000
article cancel00:00:00.000 0.0%35 0.000 0.000 0.000
article cleanup00:00:00.175 0.0%12870 0.000 0.014 0.134
article logging00:00:00.225 0.0%12883 0.000 0.017 0.074
article parse00:00:00.543 0.0%28856 0.000 0.019 0.053
article write00:00:03.259 0.0%10956 0.056 0.297 1.547
artlog/artcncl/perl00:00:00.000 0.0%17 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.203 0.0%120490 0.000 0.002 0.017
hisgrep/artcncl/perl00:00:00.000 0.0%17 0.000 0.000 0.000
hisgrep/perl00:00:00.006 0.0%41 0.000 0.146 2.000
hishave/artcncl/perl00:00:00.000 0.0%17 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:00:01.004 0.0%125790 0.002 0.008 0.023
history sync00:00:00.227 0.0%394 0.000 0.576 7.500
history write00:00:01.538 0.0%12865 0.037 0.120 0.405
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle22:22:45.279 97.2%126461 331.008 637.076 1633.549
nntp read00:00:07.584 0.0%128683 0.011 0.059 0.143
overview write00:00:03.389 0.0%10956 0.093 0.309 1.689
perl filter00:00:35.757 0.0%12865 2.026 2.779 4.016
python filter00:00:00.929 0.0%12865 0.012 0.072 0.181
site send00:00:02.730 0.0%21955 0.015 0.124 0.472
TOTAL: 2422:23:42.859 97.3%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.156 0.0%11776 0.000 0.013 0.339
article prepare00:00:00.063 0.0%103072 0.000 0.001 0.038
article read00:00:01.231 0.0%10396 0.019 0.118 0.560
backlog stats00:00:00.537 0.0%247774 0.000 0.002 0.042
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:57.127 0.1%150903 0.016 0.379 1.882
data write00:00:07.978 0.0%141550 0.012 0.056 0.336
idle23:01:01.231 99.7%247774 147.312 334.423 1044.817
status file00:00:00.663 0.0%705 0.000 0.940 13.500
TOTAL: 923:02:08.986 99.8%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle23:16:39.780 98.0%22656 77.119 3698.790 9998.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:29:06.956 2.0%24615644 0.000 0.071 0.500
readart00:00:00.237 0.0%280 0.000 0.846 1.331
TOTAL: 423:45:46.973100.0%----

Control commands to innd:

CommandNumber
flush 6
flushlogs 2
go 2
logmode 2
lowmark 1
mode 144
name 2
pause 2
paused 2
reload 693
reserve 2
TOTAL: 11 858

Control channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
newgroups-request@fido7.org001101
TOTAL001101

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1goja-out.feeder.erje.net 1125 23602 6331 16546 725 26%100:22:51
2goja-out.news.weretis.net 3 10023 1511 8301 211 15%43:53:39
3newsfeed.bofh.team 11 13648 1444 11831 373 10%49:33:15
4newsfeed.endofthelinebbs.com 2 7221 542 6656 23 7%32:21:28
5news.samoylyk.net 1 9789 388 9390 11 3%39:14:47
6news.nntp4.net 1 8866 364 8199 303 4%26:02:32
7news.1d4.us 1 4691 192 4239 260 4%25:44:01
8news.chmurka.net 2 3055 144 2911 0 4%23:58:20
9news.quux.org 2 4808 97 4706 5 2%23:59:05
10pasdenom.info 2 3844 92 3744 8 2%23:57:34
11news.furie.org.uk 1 2222 68 2154 0 3%23:59:57
12nntp.terraraq.uk 2 3040 58 2982 0 1%23:58:53
13news.bbs.nz 1 5707 49 5658 0 0%23:59:51
14newsfeed.dotsrc.org 227 816 8 808 0 0%23:02:37
15goja-out.news.tnetconsulting.net 1 3584 4 3580 0 0%23:57:32
16localhost 1 0 0 0 0 0%00:00:00
TOTAL: 16 1383 104916 11292 91705 1919 10%508:06:22
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1goja-out.feeder.erje.net13.7 MB0.0 KB2.6 MB16.3 MB 84%2.4 KB
2newsfeed.bofh.team4.0 MB14.7 KB9.8 MB13.9 MB 29%7.8 KB
3goja-out.news.weretis.net3.8 MB13.5 KB476.4 KB4.3 MB 88%2.6 KB
4news.samoylyk.net1.8 MB14.6 KB15.6 KB1.9 MB 98%4.8 KB
5news.nntp4.net1.2 MB0.0 KB3.3 MB4.5 MB 26%7.0 KB
6newsfeed.endofthelinebbs.com1.2 MB0.0 KB88.0 KB1.3 MB 93%2.3 KB
7news.chmurka.net521.6 KB0.0 KB0.0 KB521.6 KB100%3.6 KB
8news.1d4.us397.6 KB0.0 KB412.9 KB810.6 KB 49%1.8 KB
9news.furie.org.uk281.2 KB0.0 KB0.0 KB281.2 KB100%4.1 KB
10news.quux.org274.6 KB0.0 KB15.0 KB289.6 KB 94%2.8 KB
11pasdenom.info251.1 KB0.0 KB9.4 KB260.5 KB 96%2.6 KB
12nntp.terraraq.uk230.1 KB0.0 KB0.0 KB230.1 KB100%4.0 KB
13news.bbs.nz94.8 KB0.0 KB0.0 KB94.8 KB100%1.9 KB
14newsfeed.dotsrc.org34.8 KB0.0 KB0.0 KB34.8 KB100%4.3 KB
15goja-out.news.tnetconsulting.net16.1 KB0.0 KB0.0 KB16.1 KB100%4.0 KB
16localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1627.8 MB42.8 KB16.7 MB44.6 MB 62%3.5 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 15 00:00:09 - 00:04:54 10 100.0% 0.0422.4 KB 100.0% 0.08
TOTAL: 00:04:45 10 100.0% 0.0422.4 KB 100.0% 0.08
Incoming articles
Incoming articles (size)

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 735
EMP (md5) 245
Binary: misplaced binary 209
Too many newsgroups (low_xpost) 85
Too many newsgroups 35
HTML Multipart 6
Too many newsgroups (meow) 4
Cancel of non-existing ID <10rl5si$20p2$1@dont-email.me> 1
Cancel of non-existing ID <10rl5v5$21dc$1@dont-email.me> 1
Cancel of non-existing ID <10rlf79$6gl$2@news.kraft-s.ru> 1
Cancel of non-existing ID <4l8diCeIyqXyE5xImxS-40FVmHE@jntp> 1
Cancel of non-existing ID <FFJRbme3v0-_wYLB0_KzLoa_2r0@jntp> 1
Cancel of non-existing ID <HEKKtjOh-DVEWYCJ4xBc322bpyU@jntp> 1
TOTAL: 13 1325

NoCeM on spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
nocem@eternal-september.org (misplaced_binary)1500300300
robot@pasdenom.info (spam2)16001717
robot@pasdenom.info (spam3)4001111
robot@pasdenom.info (spam4)10011
TOTAL: 43600329329

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
newsfeed.dotsrc.org 37
TOTAL: 1 37
TOTAL: 1 37

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.bbs.nz 15892 4865 6009 72 0 91 30%23:08:45
2news.chmurka.net 8280 4804 2622 2 0 0 58%23:07:18
3news.1d4.us 6927 3369 2657 6 0 13 48%23:04:33
4newsfeed.dotsrc.org 6651 2431 1045 2746 0 0 36%23:07:24
5news.furie.org.uk 1854 865 757 8 0 0 46%22:54:00
6nntp.terraraq.uk 2687 301 1580 243 0 47 11%22:58:56
7newsfeed.endofthelinebbs.com 10798 236 5514 51 0 351 2%23:05:54
8news.nntp4.net 13034 232 5919 4561 0 0 1%23:08:15
9pasdenom.info 8070 208 4506 83 0 25 2%23:06:23
10newsfeed.bofh.team 5034 169 4550 0 0 13 3%23:04:05
11news.quux.org 10089 122 5766 155 0 79 1%23:06:24
12news.samoylyk.net 9633 72 5765 19 0 0 0%23:07:37
13feeder.erje.net 4981 45 4413 63 0 10 0%23:06:02
14weretis.net 4624 43 4387 4 0 0 0%23:02:15
15tnetconsulting.net 10315 17 6113 90 0 173 0%23:03:47
16i2pn.org 0 0 0 0 0 6225 0%23:10:00
17peer.alt119.net 0 0 0 0 0 11023 0%23:08:36
TOTAL: 17 118869 17779 61603 8103 0 18050 14%392:30:14
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.bbs.nz10.9 MB106.0 KB11.0 MB0.1 KB/s2.3 KB23:08:45
2news.chmurka.net10.7 MB2.4 KB10.7 MB0.1 KB/s2.3 KB23:07:18
3news.1d4.us8.2 MB27.1 KB8.2 MB0.1 KB/s2.5 KB23:04:33
4newsfeed.dotsrc.org5.7 MB6.6 MB12.3 MB0.2 KB/s2.4 KB23:07:24
5news.furie.org.uk2.7 MB17.5 KB2.7 MB0.0 KB/s3.1 KB22:54:00
6nntp.terraraq.uk847.3 KB447.5 KB1.3 MB0.0 KB/s2.4 KB22:58:56
7pasdenom.info793.4 KB99.1 KB892.5 KB0.0 KB/s3.1 KB23:06:23
8newsfeed.endofthelinebbs.com451.0 KB120.7 KB571.7 KB0.0 KB/s2.0 KB23:05:54
9newsfeed.bofh.team435.6 KB0.0 KB435.6 KB0.0 KB/s2.6 KB23:04:05
10news.nntp4.net424.6 KB10.3 MB10.7 MB0.1 KB/s2.3 KB23:08:15
11news.quux.org377.8 KB239.6 KB617.3 KB0.0 KB/s2.2 KB23:06:24
12tnetconsulting.net163.3 KB202.1 KB365.4 KB0.0 KB/s3.4 KB23:03:47
13news.samoylyk.net154.1 KB24.4 KB178.4 KB0.0 KB/s2.0 KB23:07:37
14feeder.erje.net107.9 KB293.5 KB401.5 KB0.0 KB/s3.7 KB23:06:02
15weretis.net90.6 KB4.9 KB95.5 KB0.0 KB/s2.0 KB23:02:15
16i2pn.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:10:00
17peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:08:36
TOTAL: 1741.9 MB18.5 MB60.4 MB0.0 KB/s2.4 KB392:30:14
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 8 280580.9 KB 15 0 000:33:59
TOTAL: 1 8 280580.9 KB 15 0 000:33:59

NNRP connection statistics (by domain) [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 286 280580.9 KB 15 0 000:53:54
2*.stehlik.co.uk 448 00.0 KB 0 0 022:51:32
3*.167.censys-scanner.com 4 00.0 KB 0 0 000:00:13
4*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 4 739 280580.9 KB 15 0 023:45:40

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
toma-03.stehlik.co.uk 57.275 32.777 52.59822:51:32
66.61.98.95 1.727 1.277 99.90500:33:59
2a03:cfc0:8000:b::c303:dce8 34.450 18.819 0.00000:19:51
63.146.94.167.censys-scanner.com 0.310 0.103 0.00000:00:13
2a06:4882:9000::a8 0.099 0.023 0.00000:00:01
205.210.31.150 0.068 0.017 0.00000:00:00
o002.scanner.modat.io 0.088 0.029 0.00000:00:00
TOTAL: 7 94.017 53.045 152.50323:45:40

Curious NNRP explorers [Top 20]:

SystemConn
toma-03.stehlik.co.uk 448
2a03:cfc0:8000:b::c303:dce8 276
63.146.94.167.censys-scanner.com 4
205.210.31.150 1
2a06:4882:9000::a8 1
o002.scanner.modat.io 1
TOTAL: 6 731

NNRP client timeouts [Top 20]:

SystemConnPeer
63.146.94.167.censys-scanner.com 1 4
TOTAL: 1 1 6

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

HierarchyCountPct
1it 116 41.4%
2alt 58 20.7%
3soc 44 15.7%
4de 28 10.0%
5fr 16 5.7%
6nl 6 2.1%
7comp 4 1.4%
8rec 4 1.4%
9can 2 0.7%
10edm 2 0.7%
TOTAL: 10 280100.0%

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

NewsgroupCount
1it.test 106
2alt.slack 54
3soc.culture.jewish 44
4de.comm.software.mozilla.mailnews 28
5fr.misc.bavardages.linux 16
6it.comp.sicurezza.varie 8
7comp.lang.tcl 4
8nl.wetenschap 4
9rec.music.dylan 4
10alt.drwho.creative 2
11alt.religion.christian 2
12can.general 2
13edm.news.stats 2
14it.hobby.viaggi 2
15nl.taal 2
TOTAL: 15 280