Daily Usenet report

May 12 04:15:01 -- May 13 04:15:02

Unknown entries from news log file:

First 23 / 23 lines (100.0%)

May 12 13:20:32 newsfeed overchan[1367]: input line too long, skipping
May 12 16:22:02 newsfeed overchan[1367]: input line too long, skipping
May 12 17:36:14 newsfeed overchan[1367]: input line too long, skipping
May 12 19:32:55 newsfeed overchan[1367]: input line too long, skipping
May 12 22:30:03 newsfeed pgpverify[963]: pgpverify: verification failed
May 12 22:30:03 newsfeed controlchan[1365]: skipping newgroup y moderator@dana.de (pgpverify failed) in <newgroup-de.comm.geraete.mobil-2022-05-12@dana.de>
May 12 22:45:02 newsfeed pgpverify[1771]: pgpverify: verification failed
May 12 22:45:02 newsfeed pgpverify[1782]: pgpverify: verification failed
May 12 22:45:02 newsfeed pgpverify[1797]: pgpverify: verification failed
May 12 22:45:02 newsfeed pgpverify[1807]: pgpverify: verification failed
May 12 22:45:02 newsfeed pgpverify[1816]: pgpverify: verification failed
May 12 22:45:02 newsfeed pgpverify[1825]: pgpverify: verification failed
May 12 22:45:02 newsfeed pgpverify[1834]: pgpverify: verification failed
May 12 22:45:02 newsfeed pgpverify[1843]: pgpverify: verification failed
May 12 22:45:02 newsfeed pgpverify[1852]: pgpverify: verification failed
May 12 22:45:02 newsfeed pgpverify[1860]: pgpverify: verification failed
May 12 22:45:02 newsfeed pgpverify[1868]: pgpverify: verification failed
May 12 23:15:01 newsfeed pgpverify[3192]: pgpverify: verification failed
May 12 23:15:01 newsfeed controlchan[1365]: skipping newgroup y moderator@dana.de (pgpverify failed) in <newgroup-de.markt-2022-05-12-1@dana.de>
May 12 23:15:01 newsfeed pgpverify[3235]: pgpverify: verification failed
May 12 23:15:01 newsfeed controlchan[1365]: skipping newgroup y moderator@dana.de (pgpverify failed) in <newgroup-de.comm.geraete.mobil-2022-05-12-1@dana.de>
May 12 23:35:14 newsfeed nnrpd[4176]: SERVER perl filter not defined
May 12 23:37:09 newsfeed nnrpd[4193]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn1042382 94.8%116.4 MB 94.2%
innfeed 42129 3.8%5.0 MB 4.0%
innd 11996 1.1%1.9 MB 1.5%
nnrpd 3360 0.3%314.0 KB 0.2%
controlchan 16 0.0%1.2 KB 0.0%
pgpverify 14 0.0%2.0 KB 0.0%
overchan 4 0.0%0.6 KB 0.0%
TOTAL: 71099901 100.0%123.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1037638 99.4%
Negative hits 5968 0.6%
Positive hits 237 0.0%
Cache misses 34 0.0%
TOTAL: 4 1043877 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.632 0.0%93 0.000 6.796 50.000
article cleanup00:00:06.603 0.0%1041845 0.004 0.006 0.010
article logging00:00:20.921 0.0%1042159 0.014 0.020 0.030
article parse00:10:38.365 0.7%151643490 0.004 0.004 0.005
article write00:05:03.082 0.4%1041605 0.156 0.291 0.502
artlog/artcncl00:00:00.001 0.0%92 0.000 0.011 0.143
data move00:01:55.657 0.1%158759862 0.001 0.001 0.001
hisgrep/artcncl00:00:00.119 0.0%90 0.000 1.322 26.000
hishave/artcncl00:00:00.045 0.0%93 0.000 0.484 5.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:19:21.796 1.3%1048141 0.107 1.108 6.716
history sync00:00:00.009 0.0%291 0.000 0.031 0.500
history write00:23:45.984 1.7%1041825 0.230 1.369 5.293
hiswrite/artcncl00:00:00.001 0.0%3 0.000 0.333 1.000
idle21:18:57.427 88.8%149860738 0.392 0.512 0.782
nntp read00:29:42.571 2.1%150993927 0.011 0.012 0.013
overview write00:00:03.327 0.0%1041605 0.002 0.003 0.006
perl filter00:51:52.975 3.6%1041825 2.112 2.988 3.892
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:24.779 0.0%2093829 0.009 0.012 0.015
TOTAL: 24:00:01.57123:42:14.294 98.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.083 0.0%40866 0.000 0.002 0.011
article prepare00:00:00.008 0.0%18696 0.000 0.000 0.014
article read00:00:11.598 0.0%4682 0.000 2.477 27.526
backlog stats00:00:00.105 0.0%99792 0.000 0.001 0.004
callbacks00:00:00.000 0.0%9 0.000 0.000 0.000
data read00:00:00.475 0.0%48026 0.000 0.010 0.028
data write00:00:01.066 0.0%37791 0.004 0.028 0.055
idle23:44:17.777 99.9%99792 592.420 856.359 1211.673
status file00:00:42.588 0.0%14482 0.317 2.941 183.667
TOTAL: 23:45:17.28823:45:13.700100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:15.090 65.8%2784 0.000 5.420 1861.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.086 0.4%5975 0.000 0.014 0.500
readart00:00:00.001 0.0%31 0.000 0.032 0.111
TOTAL: 00:00:22.91700:00:15.177 66.2%----

Control commands to INND:

CommandNumber
L 1
flush 27
flushlogs 2
go 2
logmode 2
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 193

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de0001100
TOTAL0001100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 404 10415561041381 0 175 99%117:04:40
2news.icm.edu.pl 42 249 22 162 65 8%14:41:59
3localhost 1443 2 2 0 0 100%00:00:00
TOTAL: 3 1889 10418071041405 162 240 99%131:46:39
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl355.0 GB1.5 MB1.9 MB355.0 GB 99%357.4 KB
2news.icm.edu.pl1.5 MB17.4 KB1.4 MB2.9 MB 51%34.2 KB
3localhost3.1 KB0.0 KB0.0 KB3.1 KB100%1.5 KB
TOTAL: 3355.0 GB1.5 MB3.3 MB355.0 GB 99%357.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 12 04:15:01 - 04:59:59 23386 2.2% 8.669.1 GB 2.6%3540.13
May 12 05:00:00 - 05:59:59 31029 3.0% 8.6212.2 GB 3.4%3548.50
May 12 06:00:00 - 06:59:59 30638 2.9% 8.5112.8 GB 3.6%3722.58
May 12 07:00:00 - 07:59:59 28627 2.7% 7.9513.5 GB 3.8%3944.79
May 12 08:00:00 - 08:59:59 31094 3.0% 8.6413.7 GB 3.9%3993.95
May 12 09:00:00 - 09:59:59 27783 2.7% 7.7212.7 GB 3.6%3710.90
May 12 10:00:00 - 10:59:59 30232 2.9% 8.4013.4 GB 3.8%3898.84
May 12 11:00:00 - 11:59:59 31517 3.0% 8.7514.9 GB 4.2%4339.11
May 12 12:00:00 - 12:59:59 34357 3.3% 9.5415.2 GB 4.3%4430.90
May 12 13:00:00 - 13:59:59 32755 3.1% 9.1015.7 GB 4.4%4568.35
May 12 14:00:00 - 14:59:59 32270 3.1% 8.9614.3 GB 4.0%4162.75
May 12 15:00:00 - 15:59:59 47347 4.5% 13.1515.2 GB 4.3%4415.82
May 12 16:00:00 - 16:59:59 81732 7.8% 22.7016.7 GB 4.7%4869.38
May 12 17:00:00 - 17:59:59 71260 6.8% 19.7916.6 GB 4.7%4841.31
May 12 18:00:00 - 18:59:59 45311 4.3% 12.5915.6 GB 4.4%4553.11
May 12 19:00:00 - 19:59:59 29854 2.9% 8.2914.6 GB 4.1%4242.07
May 12 20:00:00 - 20:59:59 47679 4.6% 13.2415.2 GB 4.3%4424.56
May 12 21:00:00 - 21:59:59 58988 5.7% 16.3914.8 GB 4.1%4298.11
May 12 22:00:00 - 22:59:59 76693 7.4% 21.3017.5 GB 4.9%5106.55
May 12 23:00:00 - 23:59:59 59947 5.8% 16.6517.5 GB 4.9%5097.41
May 13 00:00:00 - 00:59:59 67450 6.5% 18.7415.4 GB 4.3%4499.13
May 13 01:00:00 - 01:59:59 36371 3.5% 10.1015.1 GB 4.3%4406.78
May 13 02:00:00 - 02:59:59 36555 3.5% 10.1515.6 GB 4.4%4542.04
May 13 03:00:00 - 03:59:59 37326 3.6% 10.3714.7 GB 4.1%4288.46
May 13 04:00:00 - 04:15:02 11534 1.1% 12.793.7 GB 1.0%4304.62
TOTAL: 24:00:01 1041735 100.0% 12.06355.8 GB 100.0%4318.20
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 488 152 0 18 6 0 0 0 312
2news.icm.edu.pl 66 0 0 2 0 0 0 0 64
TOTAL: 2 554 152 0 20 6 0 0 0 376

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 69
it.tlc.cellulari.android 38
alt.comp.software.seamonkey 15
it.comp.os.win.windows10 11
alt.comp.software.firefox 6
de.rec.sf.startrek 5
comp.mobile.android 4
de.comp.lang.php 3
it.discussioni.energia 1
TOTAL: 9 152

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
pl.praca.oferowana 3
pl.sci.historia 3
TOTAL: 2 6

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 52
Binary in non-binary group 10
Too many newsgroups 1
TOTAL: 3 63

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 312
news.icm.edu.pl 1
TOTAL: 2 313
TOTAL: 1 313

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 15740 3951 6012 209 0 0 25%23:50:00
2panorama 31993 137 7608 93 19 33361 0%23:59:18
3poznan 7 0 7 0 0 0 0%00:54:50
4okapi 0 0 0 0 0 904 0%23:50:04
5nask 0 0 0 0 0 7426 0%23:50:04
TOTAL: 5 47740 4088 13627 302 19 41691 8%96:24:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm14.5 MB713.1 KB15.2 MB0.2 KB/s3.7 KB23:50:00
2panorama525.0 KB363.9 KB888.9 KB0.0 KB/s3.9 KB23:59:18
3poznan0.0 KB0.0 KB0.0 KB0.0 KB/s000:54:50
4okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:04
5nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:04
TOTAL: 515.1 MB1.1 MB16.1 MB0.0 KB/s3.8 KB96:24:16
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 289 2219.7 KB 10 0 000:00:10
2212.90.61.180 2 813.4 KB 1 0 000:00:02
3isvcs01.man.lodz.pl 2 00.0 KB 0 2 000:00:00
TOTAL: 3 293 3033.2 KB 11 2 000:00:22

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 291 2219.7 KB 10 2 000:00:10
2unresolved 106 813.4 KB 1 0 000:00:09
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4*.internet-measurement.com 1 00.0 KB 0 0 000:00:02
TOTAL: 4 399 3033.2 KB 11 2 000:00:22

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 0.974 1.598 0.00000:00:10
212.90.61.180 0.036 0.026 0.00000:00:02
80.111.142.213 0.009 0.030 0.00000:00:02
monitoring.internet-measurement.com 0.000 0.008 0.00000:00:02
50.31.21.10 0.011 0.004 0.00000:00:02
23.129.64.145 0.000 0.006 0.00000:00:01
212.51.192.185 0.325 0.404 0.00000:00:01
45.154.255.139 0.000 0.007 0.00000:00:00
scanner-05.ch1.censys-scanner.com 0.011 0.000 0.00000:00:00
isvcs01.man.lodz.pl 0.013 0.008 0.00000:00:00
TOTAL: 10 1.379 2.091 0.00000:00:22

Curious NNRP server explorers [Top 20]:

SystemConn
212.51.192.185 96
80.111.142.213 4
50.31.21.10 2
45.154.255.139 1
23.129.64.145 1
scanner-05.ch1.censys-scanner.com 1
monitoring.internet-measurement.com 1
TOTAL: 7 106

NNRP unrecognized commands (by host):

SystemConn
scanner-05.ch1.censys-scanner.com 1
TOTAL: 1 1

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 1
TOTAL: 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 22 73.3%
2lodman 8 26.7%
TOTAL: 2 30100.0%

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

NewsgroupCount
1pl.comp.pecet 14
2lodman.info 8
3pl.comp.os.ms-windows.winnt 8
TOTAL: 3 30