Opened 7 years ago

Closed 7 years ago

#613 closed defect (not a bug)

logs

Reported by: DISABLED Owned by:
Priority: minor Milestone:
Component: api/i2cp Version: 0.8.13
Keywords: Cc:
Parent Tickets: Sensitive: no

Description

I2P version: 0.8.13-0
Java version: Sun Microsystems Inc. 1.6.0_22 (OpenJDK Runtime Environment 1.6.0_22-b22)
Wrapper version: 3.5.12
Server version: 5.1.15
Platform: Linux amd64 3.1.9-1.4-desktop
Processor: AMD Turion X2/Athlon X2/Sempron (Lion/Sable?, 65 nm) (athlon64)
Jbigi: Locally optimized native BigInteger? library loaded from file
Encoding: UTF-8
Charset: UTF-8

Critical Logs

28/02/12 5:18:16 CRIT 3/5? .client.ClientConnectionRunner?: Disconnecting the client ([SessionConfig?:

Destination: [Destination:
Hash: ciNYHzKZ-58iIEvenpc3QdcP~D38hxC4dpacnG2UsjY=
Certificate: [Certificate: type: Null certificate null payload]
PublicKey?: [PublicKey: size: 256]
SigningPublicKey?: [SigningPublicKey: size: 128]]
Signature: [Signature: size: 40]
Creation Date: Tue Feb 28 02:36:49 GMT 2012
Options: #: 40
[Dorg.mortbay.util.FileResource?.checkAliases] = [true]
[addressbook.nonce] = [3776720374635329498]
[http.agent] = [I2P]
[http.keepAlive] = [false]
[i2cp.closeIdleTime] = [300000]
[i2cp.closeOnIdle] = [true]
[i2cp.delayOpen] = [true]
[i2cp.dontPublishLeaseSet] = [true]
[i2cp.messageReliability] = [none]
[i2cp.newDestOnResume] = [true]
[i2cp.reduceIdleTime] = [180000]
[i2cp.reduceOnIdle] = [true]
[i2cp.reduceQuantity] = [1]
[i2cp.tcp.host] = [127.0.0.1]
[i2cp.tcp.port] = [7654]
[i2p.dir.base] = home/user/i2p
[i2p.streaming.connectDelay] = [1000]
[i2ptunnel.httpclient.jumpServers] = http://i2host.i2p/cgi-bin/i2hostjump?,http://stats.i2p/cgi-bin/jump.cgi?a=,http://i2jump.i2p/
[inbound.backupQuantity] = [0]
[inbound.length] = [3]
[inbound.lengthVariance] = [0]
[inbound.nickname] = [shared clients]
[inbound.quantity] = [2]
[javax.accessibility.assistive_technologies] = [org.GNOME.Accessibility.JavaBridge?]
[jetty.class.path] = home/user/i2p/lib/routerconsole.jar
[loggerFilenameOverride] = [logs/log-router-@.txt]
[networkaddress.cache.negative.ttl] = [300]
[networkaddress.cache.ttl] = [300]
[org.mortbay.http.Version.paranoid] = [true]
[org.mortbay.xml.XmlParser?.NotValidating?] = [true]
[outbound.backupQuantity] = [0]
[outbound.length] = [3]
[outbound.lengthVariance] = [0]
[outbound.nickname] = [shared clients]
[outbound.quantity] = [2]
[outproxyAuth] = [false]
[persistentClientKey] = [false]
[proxyAuth] = [false]
[router.consoleNonce] = [-129301324478199726]
[router.version] = [0.8.13]]) : Took too long to request leaseSet

28/02/12 5:18:16 CRIT 3/5? uter.client.RequestLeaseSetJob?: Failed to receive a leaseSet in the time allotted (120000): leaseSet request asking for [LeaseSet?:

Destination: null
EncryptionKey?: null
SigningKey?: null
Signature: null
Leases: #2
Lease (0): [Lease:
End Date: Tue Feb 28 04:26:14 GMT 2012
Gateway: [Hash: W4n10m6r37Y7OkD5iB6Z2wGLPz1Z52KAuc88Xd91Kmc=]
TunnelId?: 397615289]
Lease (1): [Lease:
End Date: Tue Feb 28 04:19:38 GMT 2012
Gateway: [Hash: LBJdX9ZXUqPFCRzYmT1cvIXc2BrSdMfEkoW2KoOa12s=]
TunnelId?: 403191445]] having received null succeeding? false expiring on 1330402696404 for ciNYHzKZ-58iIEvenpc3QdcP~D38hxC4dpacnG2UsjY=

Router Logs:
29/02/12 13:56:25 WARN [receiver 4/5] net.i2p.router.JobQueue? : Dropping job due to overload! # ready jobs: 116: job = HandleFloodfillDatabaseLookupMessageJob?: Job 10442437: Handle Database Lookup Message
29/02/12 13:56:25 WARN [receiver 2/5] net.i2p.router.JobQueue? : Dropping job due to overload! # ready jobs: 114: job = HandleFloodfillDatabaseLookupMessageJob?: Job 10442429: Handle Database Lookup Message
29/02/12 13:56:25 WARN [receiver 1/5] net.i2p.router.JobQueue? : Dropping job due to overload! # ready jobs: 115: job = HandleFloodfillDatabaseLookupMessageJob?: Job 10442426: Handle Database Lookup Message
29/02/12 13:56:25 WARN [receiver 3/5] net.i2p.router.JobQueue? : Dropping job due to overload! # ready jobs: 115: job = HandleFloodfillDatabaseLookupMessageJob?: Job 10442425: Handle Database Lookup Message
29/02/12 13:56:25 WARN [P reader 2/4] net.i2p.router.JobQueue? : Dropping job due to overload! # ready jobs: 116: job = HandleFloodfillDatabaseLookupMessageJob?: Job 10442404: Handle Database Lookup Message
29/02/12 13:56:25 WARN [receiver 1/5] net.i2p.router.JobQueue? : Dropping job due to overload! # ready jobs: 117: job = HandleFloodfillDatabaseLookupMessageJob?: Job 10442401: Handle Database Lookup Message
29/02/12 13:56:25 WARN [receiver 4/5] net.i2p.router.JobQueue? : Dropping job due to overload! # ready jobs: 116: job = HandleFloodfillDatabaseLookupMessageJob?: Job 10442390: Handle Database Lookup Message
29/02/12 13:56:24 WARN [P reader 4/4] net.i2p.router.JobQueue? : Dropping job due to overload! # ready jobs: 104: job = HandleFloodfillDatabaseLookupMessageJob?: Job 10442365: Handle Database Lookup Message
29/02/12 13:56:24 WARN [receiver 2/5] net.i2p.router.JobQueue? : Dropping job due to overload! # ready jobs: 102: job = HandleFloodfillDatabaseLookupMessageJob?: Job 10442362: Handle Database Lookup Message
29/02/12 13:56:24 WARN [receiver 5/5] net.i2p.router.JobQueue? : Dropping job due to overload! # ready jobs: 103: job = HandleFloodfillDatabaseLookupMessageJob?: Job 10442347: Handle Database Lookup Message

Wrapper.log
2012/02/28 05:18:21 | CRIT 3/5? uter.client.RequestLeaseSetJob?: Failed to receive a leaseSet in the time allotted (120000): leaseSet request asking for [LeaseSet?:
2012/02/28 05:18:21 | Destination: null
2012/02/28 05:18:21 | EncryptionKey?: null
2012/02/28 05:18:21 | SigningKey?: null
2012/02/28 05:18:21 | Signature: null
2012/02/28 05:18:21 | Leases: #2
2012/02/28 05:18:21 | Lease (0): [Lease:
2012/02/28 05:18:21 | End Date: Tue Feb 28 04:26:14 GMT 2012
2012/02/28 05:18:21 | Gateway: [Hash: W4n10m6r37Y7OkD5iB6Z2wGLPz1Z52KAuc88Xd91Kmc=]
2012/02/28 05:18:21 | TunnelId?: 397615289]
2012/02/28 05:18:21 | Lease (1): [Lease:
2012/02/28 05:18:21 | End Date: Tue Feb 28 04:19:38 GMT 2012
2012/02/28 05:18:21 | Gateway: [Hash: LBJdX9ZXUqPFCRzYmT1cvIXc2BrSdMfEkoW2KoOa12s=]
2012/02/28 05:18:21 | TunnelId?: 403191445]] having received null succeeding? false expiring on 1330402696404 for ciNYHzKZ-58iIEvenpc3QdcP~D38hxC4dpacnG2UsjY=
2012/02/28 05:18:21 | CRIT 3/5? .client.ClientConnectionRunner?: Disconnecting the client ([SessionConfig?:
2012/02/28 05:18:21 | Destination: [Destination:
2012/02/28 05:18:21 | Hash: ciNYHzKZ-58iIEvenpc3QdcP~D38hxC4dpacnG2UsjY=
2012/02/28 05:18:21 | Certificate: [Certificate: type: Null certificate null payload]
2012/02/28 05:18:21 | PublicKey?: [PublicKey: size: 256]
2012/02/28 05:18:21 | SigningPublicKey?: [SigningPublicKey: size: 128]]
2012/02/28 05:18:21 | Signature: [Signature: size: 40]
2012/02/28 05:18:21 | Creation Date: Tue Feb 28 02:36:49 GMT 2012
2012/02/28 05:18:21 | Options: #: 40
2012/02/28 05:18:21 | [Dorg.mortbay.util.FileResource?.checkAliases] = [true]
2012/02/28 05:18:21 | [addressbook.nonce] = [3776720374635329498]
2012/02/28 05:18:21 | [http.agent] = [I2P]
2012/02/28 05:18:21 | [http.keepAlive] = [false]
2012/02/28 05:18:21 | [i2cp.closeIdleTime] = [300000]
2012/02/28 05:18:21 | [i2cp.closeOnIdle] = [true]
2012/02/28 05:18:21 | [i2cp.delayOpen] = [true]
2012/02/28 05:18:21 | [i2cp.dontPublishLeaseSet] = [true]
2012/02/28 05:18:21 | [i2cp.messageReliability] = [none]
2012/02/28 05:18:21 | [i2cp.newDestOnResume] = [true]
2012/02/28 05:18:21 | [i2cp.reduceIdleTime] = [180000]
2012/02/28 05:18:21 | [i2cp.reduceOnIdle] = [true]
2012/02/28 05:18:21 | [i2cp.reduceQuantity] = [1]
2012/02/28 05:18:21 | [i2cp.tcp.host] = [127.0.0.1]
2012/02/28 05:18:21 | [i2cp.tcp.port] = [7654]
2012/02/28 05:18:21 | [i2p.dir.base] = home/user/i2p
2012/02/28 05:18:21 | [i2p.streaming.connectDelay] = [1000]
2012/02/28 05:18:21 | [i2ptunnel.httpclient.jumpServers] = http://i2host.i2p/cgi-bin/i2hostjump?,http://stats.i2p/cgi-bin/jump.cgi?a=,http://i2jump.i2p/
2012/02/28 05:18:21 | [inbound.backupQuantity] = [0]
2012/02/28 05:18:21 | [inbound.length] = [3]
2012/02/28 05:18:21 | [inbound.lengthVariance] = [0]
2012/02/28 05:18:21 | [inbound.nickname] = [shared clients]
2012/02/28 05:18:21 | [inbound.quantity] = [2]
2012/02/28 05:18:21 | [javax.accessibility.assistive_technologies] = [org.GNOME.Accessibility.JavaBridge?]
2012/02/28 05:18:21 | [jetty.class.path] = home/user/i2p/lib/routerconsole.jar
2012/02/28 05:18:21 | [loggerFilenameOverride] = [logs/log-router-@.txt]
2012/02/28 05:18:21 | [networkaddress.cache.negative.ttl] = [300]
2012/02/28 05:18:21 | [networkaddress.cache.ttl] = [300]
2012/02/28 05:18:21 | [org.mortbay.http.Version.paranoid] = [true]
2012/02/28 05:18:21 | [org.mortbay.xml.XmlParser?.NotValidating?] = [true]
2012/02/28 05:18:21 | [outbound.backupQuantity] = [0]
2012/02/28 05:18:21 | [outbound.length] = [3]
2012/02/28 05:18:21 | [outbound.lengthVariance] = [0]
2012/02/28 05:18:21 | [outbound.nickname] = [shared clients]
2012/02/28 05:18:21 | [outbound.quantity] = [2]
2012/02/28 05:18:21 | [outproxyAuth] = [false]
2012/02/28 05:18:21 | [persistentClientKey] = [false]
2012/02/28 05:18:21 | [proxyAuth] = [false]
2012/02/28 05:18:21 | [router.consoleNonce] = [-129301324478199726]
2012/02/28 05:18:21 | [router.version] = [0.8.13]]) : Took too long to request leaseSet
2012/02/29 01:00:00 | 29-feb-2012 0:00:00 org.mortbay.util.RolloverFileOutputStream? removeOldFiles
2012/02/29 01:00:00 | INFO: Log age 2011_12_01.request.log
2012/02/29 14:00:48 | java.io.IOException: Inactivity timeout
2012/02/29 14:00:48 | at net.i2p.client.streaming.Connection$ActivityTimer?.timeReached(Connection.java:965)
2012/02/29 14:00:48 | at net.i2p.util.SimpleTimer2$TimedEvent?.run(SimpleTimer2.java:240)
2012/02/29 14:00:48 | at java.util.concurrent.Executors$RunnableAdapter?.call(Executors.java:471)
2012/02/29 14:00:48 | at java.util.concurrent.FutureTask?$Sync.innerRun(FutureTask?.java:334)
2012/02/29 14:00:48 | at java.util.concurrent.FutureTask?.run(FutureTask?.java:166)
2012/02/29 14:00:48 | at java.util.concurrent.ScheduledThreadPoolExecutor?$ScheduledFutureTask?.access$101(ScheduledThreadPoolExecutor?.java:165)
2012/02/29 14:00:48 | at java.util.concurrent.ScheduledThreadPoolExecutor?$ScheduledFutureTask?.run(ScheduledThreadPoolExecutor?.java:266)
2012/02/29 14:00:48 | at java.util.concurrent.ThreadPoolExecutor?.runWorker(ThreadPoolExecutor?.java:1110)
2012/02/29 14:00:48 | at java.util.concurrent.ThreadPoolExecutor?$Worker.run(ThreadPoolExecutor?.java:603)
2012/02/29 14:00:48 | at java.lang.Thread.run(Thread.java:679)

Subtickets

Change History (1)

comment:1 Changed 7 years ago by zzz

Component: unspecifiedapi/i2cp
Milestone: 0.9
Resolution: not a bug
Status: newclosed

You router is overloaded, it doesn't have enough CPU time.

If you think there is a bug, please reopen the ticket with a detailed description of what you think is wrong.

Note: See TracTickets for help on using tickets.