Daily Usenet report

Oct 1 04:15:02 -- Oct 2 04:15:02

Unknown entries from news log file:

First 37 / 37 lines (100.0%)

Oct 1 04:37:11 newsfeed overchan[14772]: input line too long, skipping
Oct 1 05:22:41 newsfeed nnrpd[25431]: SERVER perl filter not defined
Oct 1 06:57:33 newsfeed overchan[14772]: input line too long, skipping
Oct 1 07:29:28 newsfeed overchan[14772]: input line too long, skipping
Oct 1 08:32:32 newsfeed nnrpd[22470]: SERVER perl filter not defined
Oct 1 12:48:53 newsfeed overchan[14772]: input line too long, skipping
Oct 1 12:55:48 newsfeed overchan[14772]: input line too long, skipping
Oct 1 13:46:16 newsfeed overchan[14772]: input line too long, skipping
Oct 1 13:47:02 newsfeed overchan[14772]: input line too long, skipping
Oct 1 13:50:56 newsfeed overchan[14772]: input line too long, skipping
Oct 1 14:02:02 newsfeed overchan[14772]: input line too long, skipping
Oct 1 14:03:25 newsfeed overchan[14772]: input line too long, skipping
Oct 1 14:42:12 newsfeed overchan[14772]: input line too long, skipping
Oct 1 14:48:07 newsfeed overchan[14772]: input line too long, skipping
Oct 1 14:49:48 newsfeed overchan[14772]: input line too long, skipping
Oct 1 14:52:26 newsfeed overchan[14772]: input line too long, skipping
Oct 1 14:56:20 newsfeed overchan[14772]: input line too long, skipping
Oct 1 15:00:11 newsfeed overchan[14772]: input line too long, skipping
Oct 1 15:03:03 newsfeed overchan[14772]: input line too long, skipping
Oct 1 15:36:20 newsfeed overchan[14772]: input line too long, skipping
Oct 1 15:37:25 newsfeed overchan[14772]: input line too long, skipping
Oct 1 16:21:48 newsfeed overchan[14772]: input line too long, skipping
Oct 1 16:25:44 newsfeed overchan[14772]: input line too long, skipping
Oct 1 16:33:35 newsfeed overchan[14772]: input line too long, skipping
Oct 1 17:07:15 newsfeed overchan[14772]: input line too long, skipping
Oct 1 18:09:47 newsfeed overchan[14772]: input line too long, skipping
Oct 1 19:48:38 newsfeed overchan[14772]: input line too long, skipping
Oct 1 19:50:59 newsfeed overchan[14772]: input line too long, skipping
Oct 1 20:00:08 newsfeed overchan[14772]: input line too long, skipping
Oct 1 20:30:16 newsfeed overchan[14772]: input line too long, skipping
Oct 1 20:46:52 newsfeed overchan[14772]: input line too long, skipping
Oct 1 21:49:57 newsfeed overchan[14772]: input line too long, skipping
Oct 1 22:47:57 newsfeed overchan[14772]: input line too long, skipping
Oct 2 00:44:54 newsfeed nnrpd[12754]: unable to get certificate from '/etc/news/cert.pem'
Oct 2 00:44:54 newsfeed nnrpd[12754]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Oct 2 00:51:53 newsfeed pgpverify[14314]: pgpverify: verification failed
Oct 2 01:32:12 newsfeed overchan[14772]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 535433 90.1%58.4 MB 89.1%
innfeed 46210 7.8%5.4 MB 8.3%
innd 8204 1.4%1.3 MB 2.0%
nnrpd 4230 0.7%424.7 KB 0.6%
overchan 34 0.0%6.0 KB 0.0%
controlchan 12 0.0%2.7 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 7 594124 100.0%65.6 MB100.0%

History cache:

ReasonCount%Count
Do not exist 530453 99.4%
Negative hits 2822 0.5%
Positive hits 397 0.1%
Cache misses 48 0.0%
TOTAL: 4 533720 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.721 0.0%118 0.000 14.585 55.000
article cleanup00:00:09.875 0.0%534123 0.013 0.018 0.031
article logging00:00:29.884 0.0%534672 0.039 0.056 0.067
article parse00:24:52.239 1.7%64824262 0.013 0.023 0.032
article write00:08:25.479 0.6%533891 0.629 0.947 1.395
artlog/artcncl00:00:00.001 0.0%118 0.000 0.008 0.200
data move00:00:54.216 0.1%69217594 0.001 0.001 0.001
hisgrep/artcncl00:00:00.631 0.0%115 0.000 5.487 27.000
hishave/artcncl00:00:00.077 0.0%118 0.000 0.653 4.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:32:20.029 2.2%537387 0.613 3.610 12.171
history sync00:00:00.046 0.0%291 0.000 0.158 1.000
history write00:48:04.364 3.3%534079 0.664 5.401 14.896
hiswrite/artcncl00:00:00.028 0.0%3 0.000 9.333 14.000
idle19:41:08.558 82.0%64234254 0.738 1.103 2.660
nntp read00:52:04.334 3.6%64514288 0.030 0.048 0.066
overview write00:00:03.502 0.0%533891 0.004 0.007 0.011
perl filter01:07:33.712 4.7%534079 5.058 7.590 9.277
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:33.690 0.0%1083422 0.022 0.031 0.037
TOTAL: 24:00:01.92823:36:42.386 98.4%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.136 0.0%55267 0.000 0.002 0.010
article prepare00:00:00.000 0.0%2076 0.000 0.000 0.000
article read00:00:07.896 0.0%518 0.000 15.243 415.000
backlog stats00:00:00.097 0.0%119936 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.276 0.0%60141 0.004 0.021 0.041
data write00:00:03.026 0.0%44610 0.016 0.068 0.117
idle23:53:18.258 99.9%119936 473.482 717.035 1566.078
status file00:00:53.327 0.1%16717 0.758 3.190 106.759
TOTAL: 23:54:30.56723:54:24.016100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:02:11.997 91.3%3051 0.000 43.264 38035.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.250 0.2%6718 0.000 0.037 1.000
readart00:00:00.003 0.0%19 0.000 0.158 1.000
TOTAL: 00:02:24.64400:02:12.250 91.4%----

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
us-control@lists.killfile.org000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 94 534587 534401 0 186 99%70:40:49
2news.icm.edu.pl 39 423 90 287 46 21%19:38:30
3localhost 1443 2 2 0 0 100%00:00:00
TOTAL: 3 1576 535012 534493 287 232 99%90:19:19
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl275.0 GB3.2 MB3.6 MB275.0 GB 99%539.4 KB
2news.icm.edu.pl3.8 MB48.2 KB1.2 MB5.0 MB 76%37.9 KB
3localhost5.7 KB0.0 KB0.0 KB5.7 KB100%2.8 KB
TOTAL: 3275.0 GB3.2 MB4.8 MB275.0 GB 99%539.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 1 04:15:02 - 04:59:59 14835 2.8% 5.508.8 GB 3.2%3420.27
Oct 1 05:00:00 - 05:59:59 21002 3.9% 5.8311.9 GB 4.3%3471.43
Oct 1 06:00:00 - 06:59:59 21052 3.9% 5.8511.3 GB 4.1%3286.15
Oct 1 07:00:00 - 07:59:59 19999 3.7% 5.569.8 GB 3.6%2865.53
Oct 1 08:00:00 - 08:59:59 20776 3.9% 5.7710.2 GB 3.7%2972.56
Oct 1 09:00:00 - 09:59:59 23053 4.3% 6.4011.4 GB 4.1%3331.83
Oct 1 10:00:00 - 10:59:59 23635 4.4% 6.5712.4 GB 4.5%3620.88
Oct 1 11:00:00 - 11:59:59 22950 4.3% 6.3812.0 GB 4.4%3498.27
Oct 1 12:00:00 - 12:59:59 21897 4.1% 6.0811.5 GB 4.2%3344.87
Oct 1 13:00:00 - 13:59:59 22937 4.3% 6.3712.2 GB 4.4%3546.95
Oct 1 14:00:00 - 14:59:59 22993 4.3% 6.3912.8 GB 4.6%3715.77
Oct 1 15:00:00 - 15:59:59 24686 4.6% 6.8613.5 GB 4.9%3935.41
Oct 1 16:00:00 - 16:59:59 20842 3.9% 5.7910.8 GB 3.9%3148.61
Oct 1 17:00:00 - 17:59:59 24582 4.6% 6.8311.6 GB 4.2%3375.19
Oct 1 18:00:00 - 18:59:59 22085 4.1% 6.1310.9 GB 3.9%3172.39
Oct 1 19:00:00 - 19:59:59 23821 4.5% 6.6212.0 GB 4.3%3486.86
Oct 1 20:00:00 - 20:59:59 23698 4.4% 6.5811.5 GB 4.2%3339.15
Oct 1 21:00:00 - 21:59:59 25605 4.8% 7.1112.3 GB 4.5%3594.61
Oct 1 22:00:00 - 22:59:59 23321 4.4% 6.4811.9 GB 4.3%3465.84
Oct 1 23:00:00 - 23:59:59 22559 4.2% 6.2711.4 GB 4.1%3311.47
Oct 2 00:00:00 - 00:59:59 23819 4.5% 6.6212.5 GB 4.5%3638.05
Oct 2 01:00:00 - 01:59:59 22247 4.2% 6.1811.3 GB 4.1%3305.53
Oct 2 02:00:00 - 02:59:59 18471 3.5% 5.1310.1 GB 3.7%2951.81
Oct 2 03:00:00 - 03:59:59 18332 3.4% 5.099.4 GB 3.4%2740.43
Oct 2 04:00:00 - 04:15:02 5337 1.0% 5.922.2 GB 0.8%2566.20
TOTAL: 24:00:00 534534 100.0% 6.19275.8 GB 100.0%3346.91
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 733 140 0 41 1 0 0 0 551
2news.icm.edu.pl 48 0 0 3 0 0 0 0 45
TOTAL: 2 781 140 0 44 1 0 0 0 596

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 88
it.tlc.cellulari.android 20
de.comp.office-pakete.ms-office.excel 10
nl.comp.os.linux.techniek 7
it.comp.os.win.windows10 7
comp.sys.raspberry-pi 7
comp.mobile.android 1
TOTAL: 7 140

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

GroupsCount
pl.praca.oferowana 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 42
Binary in non-binary group 4
Scoring filter (8) 1
TOTAL: 3 47

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 21142 123 14640 3 0 0 0%23:59:58
2panorama 42764 69 14951 94 0 40331 0%23:59:28
3poznan 69 1 63 0 0 0 1%05:31:16
4nask 0 0 0 0 0 10986 0%23:57:46
5okapi 0 0 0 0 0 829 0%23:57:46
TOTAL: 5 63975 193 29654 97 0 52146 0%101:26:14
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm466.2 KB6.3 KB472.5 KB0.0 KB/s3.7 KB23:59:58
2panorama289.2 KB547.7 KB836.9 KB0.0 KB/s5.1 KB23:59:28
3poznan4.4 KB0.0 KB4.4 KB0.0 KB/s4.4 KB05:31:16
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:46
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:46
TOTAL: 5759.8 KB554.0 KB1.3 MB0.0 KB/s4.5 KB101:26:14
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 295 1922.5 KB 9 1 000:00:19
2isvcs01.man.lodz.pl 1 00.0 KB 0 1 000:00:00
TOTAL: 2 296 1922.5 KB 9 2 000:02:24

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 584 1922.5 KB 9 2 000:00:23
2*.autoelektrika.cz 1 00.0 KB 0 0 000:01:54
3*.ch1.censys-scanner.com 2 00.0 KB 0 0 000:00:00
4unresolved 102 00.0 KB 0 0 000:00:06
TOTAL: 4 689 1922.5 KB 9 2 000:02:24

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.autoelektrika.cz 0.032 0.004 0.00000:01:54
web.man.lodz.pl 1.786 3.583 0.00000:00:19
80.111.142.213 0.017 0.054 0.00000:00:04
mon.man.lodz.pl 1.539 3.178 0.00000:00:04
212.51.192.185 0.445 1.029 0.00000:00:01
scanner-04.ch1.censys-scanner.com 0.017 0.027 0.00000:00:00
87.251.75.254 0.006 0.030 0.00000:00:00
isvcs01.man.lodz.pl 0.000 0.032 0.00000:00:00
? 0.007 0.007 0.00000:00:00
TOTAL: 9 3.849 7.944 0.00000:02:24

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
80.111.142.213 4
scanner-04.ch1.censys-scanner.com 2
87.251.75.254 2
mail.autoelektrika.cz 1
TOTAL: 6 393

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
87.251.75.254 2
scanner-04.ch1.censys-scanner.com 1
TOTAL: 2 3

NNRP unrecognized commands (by command):

CommandCount
#003 2
EHLO www.censys.io 1
TOTAL: 2 3

NNRP client timeouts [Top 20]:

SystemConnPeer
87.251.75.254 2 2
TOTAL: 1 2 3

Newsgroup request counts (by category):

CategoryCountPct
1pl 19100.0%
TOTAL: 1 19100.0%

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

NewsgroupCount
1pl.comp.pecet 12
2pl.comp.os.ms-windows.winnt 6
3pl.rec.mineraly 1
TOTAL: 3 19