Daily Usenet report for news.niel.me

Jul 15 04:15:01 -- Jul 16 04:15:01

Unknown entries from news log file:

First 50 / 185 lines (27.0%)

Jul 15 17:00:04 rpi-studio pgpverify[30726]: pgpverify: verification failed
Jul 15 17:00:05 rpi-studio pgpverify[30741]: pgpverify: verification failed
Jul 16 03:28:17 rpi-studio nnrpd[20478]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:28:32 rpi-studio nnrpd[20483]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:28:47 rpi-studio nnrpd[20486]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:29:03 rpi-studio nnrpd[20494]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:29:18 rpi-studio nnrpd[20497]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:29:35 rpi-studio nnrpd[20502]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:29:50 rpi-studio nnrpd[20514]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:30:06 rpi-studio nnrpd[20526]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:30:22 rpi-studio nnrpd[20532]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:30:37 rpi-studio nnrpd[20535]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:30:52 rpi-studio nnrpd[20681]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:31:07 rpi-studio nnrpd[20696]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:31:22 rpi-studio nnrpd[20701]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:31:37 rpi-studio nnrpd[20704]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:31:52 rpi-studio nnrpd[20709]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:32:07 rpi-studio nnrpd[20717]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:32:22 rpi-studio nnrpd[20723]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:32:38 rpi-studio nnrpd[20726]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:32:53 rpi-studio nnrpd[20731]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:33:08 rpi-studio nnrpd[20736]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:33:24 rpi-studio nnrpd[20746]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:33:39 rpi-studio nnrpd[20749]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:33:54 rpi-studio nnrpd[20754]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:34:10 rpi-studio nnrpd[20767]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:34:25 rpi-studio nnrpd[20772]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:34:40 rpi-studio nnrpd[20777]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:34:55 rpi-studio nnrpd[20781]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:35:11 rpi-studio nnrpd[20791]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:35:27 rpi-studio nnrpd[20794]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:35:42 rpi-studio nnrpd[20800]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:35:57 rpi-studio nnrpd[20804]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:36:12 rpi-studio nnrpd[20811]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:36:28 rpi-studio nnrpd[20814]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:36:43 rpi-studio nnrpd[20819]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:36:58 rpi-studio nnrpd[20825]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:37:13 rpi-studio nnrpd[20833]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:37:29 rpi-studio nnrpd[20836]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:37:44 rpi-studio nnrpd[20842]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:37:59 rpi-studio nnrpd[20845]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:38:14 rpi-studio nnrpd[20852]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:38:29 rpi-studio nnrpd[20855]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:38:44 rpi-studio nnrpd[20860]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:39:00 rpi-studio nnrpd[20865]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:39:15 rpi-studio nnrpd[20871]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:39:30 rpi-studio nnrpd[20876]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:39:45 rpi-studio nnrpd[20880]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:40:00 rpi-studio nnrpd[20887]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 16 03:40:15 rpi-studio nnrpd[20893]: 2a0e:97c0:370::1 noperm ihave without permission

Log entries by program:

Program nameLines%LinesSize%Size
inn 4637 38.3%849.0 KB 44.4%
nnrpd 2941 24.3%325.4 KB 17.0%
innd 2747 22.7%427.5 KB 22.3%
innfeed 1762 14.6%308.5 KB 16.1%
controlchan 13 0.1%2.2 KB 0.1%
pgpverify 2 0.0%0.3 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 7 12103 100.0%1.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 22847 60.7%
Negative hits 10216 27.1%
Do not exist 4568 12.1%
Cache misses 12 0.0%
TOTAL: 4 37643 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.019 0.0%17 0.000 1.118 5.000
article cancel00:00:00.004 0.0%17 0.000 0.235 1.000
article cleanup00:00:00.103 0.0%4596 0.000 0.022 0.300
article logging00:00:00.297 0.0%4604 0.000 0.065 0.231
article parse00:00:00.777 0.0%15781 0.000 0.049 0.172
article write00:00:01.873 0.0%3807 0.143 0.492 1.083
artlog/artcncl00:00:00.002 0.0%17 0.000 0.118 1.000
artlog/artcncl/perl00:00:00.001 0.0%17 0.000 0.059 0.333
data move00:00:00.231 0.0%44745 0.000 0.005 0.018
hisgrep/artcncl00:00:00.000 0.0%17 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%17 0.000 0.000 0.000
hisgrep/perl00:00:00.002 0.0%18 0.000 0.111 1.000
hishave/artcncl00:00:00.000 0.0%17 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.001 0.0%17 0.000 0.059 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.603 0.0%37737 0.000 0.016 0.038
history sync00:00:00.064 0.0%412 0.000 0.155 0.667
history write00:00:00.878 0.0%4579 0.000 0.192 0.643
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:57:41.726 99.8%47412 491.838 1819.407 6191.887
nntp read00:00:01.251 0.0%46408 0.005 0.027 0.052
overview write00:00:01.044 0.0%3807 0.037 0.274 2.647
perl filter00:00:15.019 0.0%4579 1.326 3.280 9.714
python filter00:00:00.017 0.0%4579 0.000 0.004 0.042
site send00:00:01.307 0.0%7738 0.000 0.169 0.500
TOTAL: 24:01:08.91023:58:05.219 99.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.044 0.0%4056 0.000 0.011 0.105
article prepare00:00:00.003 0.0%968 0.000 0.003 0.125
article read00:00:00.031 0.0%138 0.000 0.225 2.333
backlog stats00:00:00.243 0.0%53050 0.000 0.005 0.022
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.695 0.0%30746 0.000 0.023 0.073
data write00:00:01.432 0.0%27685 0.008 0.052 0.098
idle23:50:16.955100.0%53050 843.797 1617.662 4520.366
status file00:00:00.411 0.0%204 1.000 2.015 7.000
TOTAL: 23:50:23.22923:50:19.814100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:09.243 8.3%1986 10.600 34.866 2465.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.200 0.0%5015 0.000 0.040 1.000
readart00:00:00.215 0.0%572 0.376 0.376 0.376
TOTAL: 00:13:52.62500:01:09.658 8.4%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org002202
TOTAL002202

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.glou.org 3 3588 903 2626 59 25%27:15:41
2feed-out.gegeweb.org 4 2404 858 1546 0 35%24:56:23
3news.nntp4.net 3 4468 655 3287 526 14%24:27:10
4i2pn.org 2 4675 617 3927 131 13%28:04:47
5news.samoylyk.net 1 3803 346 3457 0 9%24:23:50
6news.tnetconsulting.net 0 2866 179 2666 21 6%16:04:13
7gegeweb-out.news.tnetconsulting.net 2 1242 104 1132 6 8%10:08:24
8nntp.terraraq.uk 2 293 21 272 0 7%03:14:45
9usenet.pasdenom.info 3 346 16 330 0 4%05:26:13
10localhost 25 0 0 0 0 0%00:00:00
TOTAL: 10 45 23685 3699 19243 743 15%164:01:26
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.glou.org5.8 MB62.7 KB312.0 KB6.1 MB 94%6.5 KB
2feed-out.gegeweb.org3.9 MB0.0 KB0.0 KB3.9 MB100%4.7 KB
3news.nntp4.net3.2 MB66.1 KB2.1 MB5.4 MB 59%4.7 KB
4i2pn.org2.0 MB67.6 KB431.5 KB2.5 MB 80%3.4 KB
5news.samoylyk.net1.9 MB0.0 KB0.0 KB1.9 MB100%5.8 KB
6news.tnetconsulting.net1.1 MB62.7 KB118.5 KB1.3 MB 86%6.7 KB
7gegeweb-out.news.tnetconsulting.net528.2 KB0.0 KB14.2 KB542.4 KB 97%4.9 KB
8nntp.terraraq.uk85.8 KB0.0 KB0.0 KB85.8 KB100%4.1 KB
9usenet.pasdenom.info82.0 KB0.0 KB0.0 KB82.0 KB100%5.1 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1018.7 MB259.0 KB3.0 MB21.9 MB 85%5.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 15 04:15:01 - 04:59:59 88 2.3% 0.03661.7 KB 3.2% 0.25
Jul 15 05:00:00 - 05:59:59 106 2.8% 0.03434.2 KB 2.1% 0.12
Jul 15 06:00:00 - 06:59:59 64 1.7% 0.02397.0 KB 1.9% 0.11
Jul 15 07:00:00 - 07:59:59 196 5.1% 0.05688.9 KB 3.3% 0.19
Jul 15 08:00:00 - 08:59:59 138 3.6% 0.04628.8 KB 3.0% 0.17
Jul 15 09:00:00 - 09:59:59 111 2.9% 0.03441.6 KB 2.1% 0.12
Jul 15 10:00:00 - 10:59:59 106 2.8% 0.03410.2 KB 2.0% 0.11
Jul 15 11:00:00 - 11:59:59 119 3.1% 0.03387.9 KB 1.9% 0.11
Jul 15 12:00:00 - 12:59:59 200 5.3% 0.061.2 MB 6.0% 0.35
Jul 15 13:00:00 - 13:59:59 171 4.5% 0.05880.3 KB 4.2% 0.24
Jul 15 14:00:00 - 14:59:59 235 6.2% 0.07932.3 KB 4.4% 0.26
Jul 15 15:00:00 - 15:59:59 239 6.3% 0.071.6 MB 7.9% 0.46
Jul 15 16:00:00 - 16:59:59 149 3.9% 0.04822.1 KB 3.9% 0.23
Jul 15 17:00:00 - 17:59:59 210 5.5% 0.061.7 MB 8.1% 0.47
Jul 15 18:00:00 - 18:59:59 200 5.3% 0.06789.9 KB 3.8% 0.22
Jul 15 19:00:00 - 19:59:59 174 4.6% 0.05616.9 KB 2.9% 0.17
Jul 15 20:00:00 - 20:59:59 254 6.7% 0.071.1 MB 5.2% 0.30
Jul 15 21:00:00 - 21:59:59 201 5.3% 0.061.6 MB 7.8% 0.45
Jul 15 22:00:00 - 22:59:59 149 3.9% 0.04680.4 KB 3.2% 0.19
Jul 15 23:00:00 - 23:59:59 133 3.5% 0.04646.7 KB 3.1% 0.18
Jul 16 00:00:00 - 00:59:59 154 4.0% 0.041.1 MB 5.3% 0.31
Jul 16 01:00:00 - 01:59:59 138 3.6% 0.04783.9 KB 3.7% 0.22
Jul 16 02:00:00 - 02:59:59 120 3.2% 0.03565.8 KB 2.7% 0.16
Jul 16 03:00:00 - 03:59:59 130 3.4% 0.041.6 MB 8.1% 0.47
Jul 16 04:00:00 - 04:15:01 22 0.6% 0.0262.6 KB 0.3% 0.07
TOTAL: 24:00:00 3807 100.0% 0.0420.5 MB 100.0% 0.24
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 559 0 0 2 0 0 0 0 557
2i2pn.org 119 0 0 3 0 0 0 0 116
3news.glou.org 57 0 0 1 0 0 0 0 56
4gegeweb-out.news.tnetconsulting.net 25 0 0 1 0 0 0 0 24
5nntp.terraraq.uk 16 0 0 1 0 0 0 0 15
62001:41d0:2:abb2::119 9 0 0 9 0 0 0 0 0
7feed-out.gegeweb.org 8 0 0 0 0 0 0 0 8
8usenet.pasdenom.info 2 0 0 0 0 0 0 0 2
9news.tnetconsulting.net 1 0 0 0 0 0 0 0 1
TOTAL: 9 796 0 0 17 0 0 0 0 779

INND Perl filter [Top 20]:

ReasonCount
EMP (phn nph) 280
Too many newsgroups 223
Bad path (news.giganews.com.POSTED) 138
EMP (md5) 62
EMP (phl) 61
EMP (phn path) 4
HTML Multipart 2
Cancel of non-existing ID <u8so8h$vrh$2@cabale.usenet-fr.net> 1
TOTAL: 8 771

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
feed-out.gegeweb.org 8
TOTAL: 1 8
TOTAL: 1 8

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.terraraq.uk 2711 38 2159 26 0 0 1%23:59:57
2news.nntp4.net 3570 38 3047 1 0 0 1%23:59:51
3news.tnetconsulting.net 5779 34 3757 13 0 69 0%23:59:45
4i2pn.org 5401 34 3203 10 0 110 0%23:59:29
5news.samoylyk.net 4663 25 3424 15 0 83 0%23:59:57
6news.gegeweb.org 3111 7 2937 1 0 1 0%23:59:57
7news.aioe.org 0 0 0 0 0 3184 0%23:59:56
8news.pasdenom.info 2995 0 2844 0 0 0 0%23:59:19
TOTAL: 8 28230 176 21371 66 0 3447 0%191:58:11
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.nntp4.net736.2 KB55.1 KB791.3 KB0.0 KB/s20.3 KB23:59:51
2nntp.terraraq.uk522.7 KB1.3 MB1.8 MB0.0 KB/s28.7 KB23:59:57
3news.tnetconsulting.net419.4 KB111.8 KB531.1 KB0.0 KB/s11.3 KB23:59:45
4news.samoylyk.net189.8 KB174.2 KB364.0 KB0.0 KB/s9.1 KB23:59:57
5i2pn.org94.8 KB127.3 KB222.1 KB0.0 KB/s5.0 KB23:59:29
6news.gegeweb.org17.0 KB4.6 KB21.5 KB0.0 KB/s2.7 KB23:59:57
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
8news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:19
TOTAL: 81.9 MB1.7 MB3.7 MB0.0 KB/s15.6 KB191:58:11
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
12a0e:e701:1154:0:e08b:f712:62b9:b828 1 43254.3 KB 8 0 000:10:44
TOTAL: 1 1 43254.3 KB 8 0 000:13:51

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 184 43254.3 KB 8 0 000:11:31
2*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
3unresolved 7 00.0 KB 0 0 000:00:13
4*.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:01
5*.scan.bufferover.run 1 00.0 KB 0 0 000:00:00
6*.gegeweb.eu 285 00.0 KB 0 0 000:02:03
TOTAL: 6 479 43254.3 KB 8 0 000:13:51

NNRP auth users [Top 20]:

UserConn
mailman 285
gerald 1
TOTAL: 2 286

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
2a0e:e701:1154:0:e08b:f712:62b9:b828 0.199 0.164 0.00400:10:44
mail.gegeweb.eu 4.538 3.628 1.55800:02:03
2a0e:97c0:370::1 3.914 2.997 0.00000:00:47
146.70.134.94 0.016 0.008 0.00000:00:08
212.102.40.218 0.086 0.034 0.00000:00:03
terrific.monitoring.internet-measurement.com 0.027 0.023 0.00000:00:01
172-104-210-105.ip.linodeusercontent.com 0.023 0.030 0.00000:00:01
107.170.243.17 0.029 0.020 0.00000:00:01
reykjavik.scan.bufferover.run 0.059 0.016 0.00000:00:00
80.66.88.204 0.038 0.011 0.00000:00:00
80.66.88.211 0.008 0.019 0.00000:00:00
TOTAL: 11 8.937 6.950 1.56200:13:51

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 285
2a0e:97c0:370::1 183
212.102.40.218 3
reykjavik.scan.bufferover.run 1
107.170.243.17 1
80.66.88.204 1
terrific.monitoring.internet-measurement.com 1
172-104-210-105.ip.linodeusercontent.com 1
146.70.134.94 1
80.66.88.211 1
TOTAL: 10 478

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
80.66.88.204 1
107.170.243.17 1
TOTAL: 2 2

NNRP unrecognized commands (by command):

CommandCount
#003 1
MGLNDD_45.13.105.36_119 1
TOTAL: 2 2

NNRP client timeouts [Top 20]:

SystemConnPeer
80.66.88.204 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1fr 38 88.4%
2grisbi 3 7.0%
3news 2 4.7%
TOTAL: 3 43100.0%

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

NewsgroupCount
1fr.usenet.documents 16
2fr.usenet.forums.evolution 16
3fr.comp.os.bsd 4
4grisbi.bugsreports 2
5news.announce.newgroups 2
6fr.usenet.distribution 1
7grisbi.test 1
8fr.usenet.divers 1
TOTAL: 8 43