search

tronprotocol / java-tron

Java implementation of the Tron whitepaper
GNU Lesser General Public License v3.0
3.74k stars 1.41k forks source link

ERROR [main] [DB](LevelDbDataSourceImpl.java:155) Database account corrupted #5780

Closed mercy1230 closed 7 months ago

mercy1230 commented 7 months ago

kkkkk

15:09:16.091 INFO  [main] [app](FullNode.java:69) not in debug mode, it will check energy time
15:09:19.599 ERROR [main] [DB](LevelDbDataSourceImpl.java:155) Database account corrupted, please delete database directory(output-directory/database) and restart.
org.fusesource.leveldbjni.internal.NativeDB$DBException: Corruption: 12646 missing files; e.g.: /output-directory/database/account/354491281.sst
    at org.fusesource.leveldbjni.internal.NativeDB.checkStatus(NativeDB.java:200)
    at org.fusesource.leveldbjni.internal.NativeDB.open(NativeDB.java:218)
    at org.fusesource.leveldbjni.JniDBFactory.open(JniDBFactory.java:168)
    at org.tron.common.storage.leveldb.LevelDbDataSourceImpl.openDatabase(LevelDbDataSourceImpl.java:146)
    at org.tron.common.storage.leveldb.LevelDbDataSourceImpl.initDB(LevelDbDataSourceImpl.java:125)
    at org.tron.common.storage.leveldb.LevelDbDataSourceImpl.<init>(LevelDbDataSourceImpl.java:96)
    at org.tron.core.db.TronStoreWithRevoking.<init>(TronStoreWithRevoking.java:64)
    at org.tron.core.store.AccountStore.<init>(AccountStore.java:42)
    at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
    at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
    at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
    at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
    at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:211)
    at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:117)
    at org.springframework.beans.factory.support.ConstructorResolver.instantiate(ConstructorResolver.java:311)
    at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:296)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:1372)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:1222)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:582)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:542)
    at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:335)
    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234)
    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:333)
    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:208)
    at org.springframework.beans.factory.config.DependencyDescriptor.resolveCandidate(DependencyDescriptor.java:276)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1389)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1309)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.resolveFieldValue(AutowiredAnnotationBeanPostProcessor.java:656)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:639)
    at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:119)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessProperties(AutowiredAnnotationBeanPostProcessor.java:399)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1431)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:619)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:542)
    at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:335)
    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234)
    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:333)
    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:208)
    at org.springframework.beans.factory.config.DependencyDescriptor.resolveCandidate(DependencyDescriptor.java:276)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1389)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1309)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.resolveFieldValue(AutowiredAnnotationBeanPostProcessor.java:656)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:639)
    at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:119)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessProperties(AutowiredAnnotationBeanPostProcessor.java:399)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1431)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:619)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:542)
    at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:335)
    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234)
    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:333)
    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:208)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:953)
    at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:918)
    at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:583)
    at org.tron.program.FullNode.main(FullNode.java:80)
15:11:33.775 INFO  [main] [app](FullNode.java:55) Full node running.
mercy1230 commented 7 months ago

When I tried to upgrade FullNode 4.7.4, this error occurred, indicating that the database was damaged;

But when I roll back to the previous version 4.7.2, it can start normally.

Why？

endiaoekoe commented 7 months ago

When I tried to upgrade FullNode 4.7.4, this error occurred, indicating that the database was damaged;

But when I roll back to the previous version 4.7.2, it can start normally.

Why？

hi, @mercy1230 the error messages just give the methods to solve this 'start failed' problem. I recommend you can delete the database directory(output-directory/database) and restart. then it may start normally. as for the reason why it started well when rolling back to the previous version, can you provide more error messages during node start up, so it will be better to investigate the root cause?

halibobo1205 commented 7 months ago

We made a corruption change for leveldb in release 4.7.2, this is PR: #5223. If you restart with 4.7.2, you should get the error again. Could you please provide the detailed log information after the startup of the next two versions? By the way, is the node down? Normally this error is only possible if the node is down.

mercy1230 commented 7 months ago

We made a corruption change for leveldb in release 4.7.2, this is PR: #5223. If you restart with 4.7.2, you should get the error again. Could you please provide the detailed log information after the startup of the next two versions? By the way, is the node down? Normally this error is only possible if the node is down.

Thanks For Answer. The FullNode version running is 4.7.2. Because mandatory update to 4.7.4, After kill -15 FullNode, and run version 4.7.4 . this error occurred.

mercy1230 commented 7 months ago

We made a corruption change for leveldb in release 4.7.2, this is PR: #5223. If you restart with 4.7.2, you should get the error again. Could you please provide the detailed log information after the startup of the next two versions? By the way, is the node down? Normally this error is only possible if the node is down.

VERSION 4.7.4 STARTUP ERROR MESSAGE AS BELOW:

15:03:51.170 INFO  [main] [app](Args.java:1627) ************************ Net config ************************
15:03:51.170 INFO  [main] [app](Args.java:1628) P2P version: 11111
15:03:51.171 INFO  [main] [app](Args.java:1629) LAN IP: 
15:03:51.171 INFO  [main] [app](Args.java:1630) External IP: 
15:03:51.171 INFO  [main] [app](Args.java:1631) Listen port: 18888
15:03:51.171 INFO  [main] [app](Args.java:1632) Node ipv6 enable: false
15:03:51.171 INFO  [main] [app](Args.java:1633) Discover enable: true
15:03:51.171 INFO  [main] [app](Args.java:1634) Active node size: 0
15:03:51.171 INFO  [main] [app](Args.java:1635) Passive node size: 0
15:03:51.171 INFO  [main] [app](Args.java:1636) FastForward node size: 2
15:03:51.171 INFO  [main] [app](Args.java:1637) FastForward node number: 3
15:03:51.171 INFO  [main] [app](Args.java:1638) Seed node size: 29
15:03:51.171 INFO  [main] [app](Args.java:1639) Max connection: 30
15:03:51.171 INFO  [main] [app](Args.java:1640) Min connection: 9
15:03:51.171 INFO  [main] [app](Args.java:1641) Min active connection: 3
15:03:51.171 INFO  [main] [app](Args.java:1642) Max connection with same IP: 2
15:03:51.171 INFO  [main] [app](Args.java:1643) Solidity threads: 8
15:03:51.171 INFO  [main] [app](Args.java:1644) Trx reference block: solid
15:03:51.171 INFO  [main] [app](Args.java:1645) Open full tcp disconnect: true
15:03:51.172 INFO  [main] [app](Args.java:1646) Node detect enable: false
15:03:51.172 INFO  [main] [app](Args.java:1647) Node effective check enable: false
15:03:51.172 INFO  [main] [app](Args.java:1648) Rate limiter global qps: 50000
15:03:51.172 INFO  [main] [app](Args.java:1649) Rate limiter global ip qps: 10000
15:03:51.172 INFO  [main] [app](Args.java:1650) Rate limiter global api qps: 1000
15:03:51.172 INFO  [main] [app](Args.java:1651) ************************ Backup config ************************
15:03:51.172 INFO  [main] [app](Args.java:1652) Backup priority: 8
15:03:51.172 INFO  [main] [app](Args.java:1653) Backup listen port: 10001
15:03:51.172 INFO  [main] [app](Args.java:1654) Backup listen keepAliveInterval: 3000
15:03:51.172 INFO  [main] [app](Args.java:1655) Backup member size: 0
15:03:51.172 INFO  [main] [app](Args.java:1656) ************************ Code version *************************
15:03:51.172 INFO  [main] [app](Args.java:1657) Code version : 4.7.4
15:03:51.172 INFO  [main] [app](Args.java:1658) Version code: 18260
15:03:51.172 INFO  [main] [app](Args.java:1659) ************************ DB config *************************
15:03:51.172 INFO  [main] [app](Args.java:1660) DB engine : LEVELDB
15:03:51.172 INFO  [main] [app](Args.java:1661) Snapshot max flush count: 1
15:03:51.173 INFO  [main] [app](Args.java:1662) ***************************************************************
15:03:51.173 INFO  [main] [app](Args.java:1663) ************************ shutDown config *************************
15:03:51.173 INFO  [main] [app](Args.java:1664) ShutDown blockTime  : null
15:03:51.173 INFO  [main] [app](Args.java:1665) ShutDown blockHeight : -1
15:03:51.173 INFO  [main] [app](Args.java:1666) ShutDown blockCount : -1
15:03:51.173 INFO  [main] [app](Args.java:1667) ***************************************************************
15:03:51.173 INFO  [main] [app](Args.java:1668) 

15:03:51.173 INFO  [main] [app](FullNode.java:69) not in debug mode, it will check energy time
15:03:54.702 ERROR [main] [DB](LevelDbDataSourceImpl.java:155) Database account corrupted, please delete database directory(output-directory/database) and restart.
org.fusesource.leveldbjni.internal.NativeDB$DBException: Corruption: 12646 missing files; e.g.: /output-directory/database/account/354491281.sst
    at org.fusesource.leveldbjni.internal.NativeDB.checkStatus(NativeDB.java:200)
    at org.fusesource.leveldbjni.internal.NativeDB.open(NativeDB.java:218)
    at org.fusesource.leveldbjni.JniDBFactory.open(JniDBFactory.java:168)
    at org.tron.common.storage.leveldb.LevelDbDataSourceImpl.openDatabase(LevelDbDataSourceImpl.java:146)
    at org.tron.common.storage.leveldb.LevelDbDataSourceImpl.initDB(LevelDbDataSourceImpl.java:125)
    at org.tron.common.storage.leveldb.LevelDbDataSourceImpl.<init>(LevelDbDataSourceImpl.java:96)
    at org.tron.core.db.TronStoreWithRevoking.<init>(TronStoreWithRevoking.java:64)
    at org.tron.core.store.AccountStore.<init>(AccountStore.java:42)
    at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
    at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
    at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
    at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
    at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:211)
    at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:117)
    at org.springframework.beans.factory.support.ConstructorResolver.instantiate(ConstructorResolver.java:311)
    at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:296)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:1372)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:1222)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:582)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:542)
    at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:335)
    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234)
    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:333)
    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:208)
    at org.springframework.beans.factory.config.DependencyDescriptor.resolveCandidate(DependencyDescriptor.java:276)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1389)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1309)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.resolveFieldValue(AutowiredAnnotationBeanPostProcessor.java:656)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:639)
    at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:119)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessProperties(AutowiredAnnotationBeanPostProcessor.java:399)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1431)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:619)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:542)
    at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:335)
    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234)
    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:333)
    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:208)
    at org.springframework.beans.factory.config.DependencyDescriptor.resolveCandidate(DependencyDescriptor.java:276)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1389)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1309)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.resolveFieldValue(AutowiredAnnotationBeanPostProcessor.java:656)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:639)
    at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:119)
    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessProperties(AutowiredAnnotationBeanPostProcessor.java:399)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1431)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:619)
    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:542)
    at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:335)
    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234)
    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:333)
    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:208)
    at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:953)
    at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:918)
    at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:583)
    at org.tron.program.FullNode.main(FullNode.java:80)
15:04:40.688 INFO  [main] [app](FullNode.java:53) Full node running.
15:04:40.945 WARN  [main] [app](LocalWitnesses.java:104) PrivateKey
15:04:41.399 WARN  [getIp] [net](NetUtil.java:111) Fail to get ipv6 by https://v6.ident.me, cause:Network is unreachable (connect failed)
15:04:41.400 WARN  [getIp] [net](NetUtil.java:111) Fail to get ipv6 by http://6.ipw.cn/, cause:Network is unreachable (connect failed)
15:04:41.401 INFO  [main] [app](Args.java:1555) External IP wasn't set, using ipv4 from libp2p
15:04:41.406 INFO  [main] [app](Args.java:1626)
mercy1230 commented 7 months ago

We made a corruption change for leveldb in release 4.7.2, this is PR: #5223. If you restart with 4.7.2, you should get the error again. Could you please provide the detailed log information after the startup of the next two versions? By the way, is the node down? Normally this error is only possible if the node is down.

VERSION 4.7.2 RUN SUCCESSFUL MESSAGE : 

15:11:34.427 INFO  [main] [app](Args.java:1650) ************************ Net config ************************
15:11:34.427 INFO  [main] [app](Args.java:1651) P2P version: 11111
15:11:34.427 INFO  [main] [app](Args.java:1652) Bind IP: 
15:11:34.427 INFO  [main] [app](Args.java:1653) External IP: 
15:11:34.427 INFO  [main] [app](Args.java:1654) Listen port: 18888
15:11:34.427 INFO  [main] [app](Args.java:1655) Node ipv6 enable: false
15:11:34.427 INFO  [main] [app](Args.java:1656) Discover enable: true
15:11:34.427 INFO  [main] [app](Args.java:1657) Active node size: 0
15:11:34.427 INFO  [main] [app](Args.java:1658) Passive node size: 0
15:11:34.427 INFO  [main] [app](Args.java:1659) FastForward node size: 2
15:11:34.427 INFO  [main] [app](Args.java:1660) FastForward node number: 3
15:11:34.427 INFO  [main] [app](Args.java:1661) Seed node size: 30
15:11:34.427 INFO  [main] [app](Args.java:1662) Max connection: 30
15:11:34.427 INFO  [main] [app](Args.java:1663) Min connection: 8
15:11:34.427 INFO  [main] [app](Args.java:1664) Min active connection: 3
15:11:34.427 INFO  [main] [app](Args.java:1665) Max connection with same IP: 2
15:11:34.427 INFO  [main] [app](Args.java:1666) Solidity threads: 8
15:11:34.427 INFO  [main] [app](Args.java:1667) Trx reference block: solid
15:11:34.428 INFO  [main] [app](Args.java:1668) Open full tcp disconnect: true
15:11:34.428 INFO  [main] [app](Args.java:1669) Node detect enable: false
15:11:34.428 INFO  [main] [app](Args.java:1670) Node effective check enable: false
15:11:34.428 INFO  [main] [app](Args.java:1671) Rate limiter global qps: 50000
15:11:34.428 INFO  [main] [app](Args.java:1672) Rate limiter global ip qps: 10000
15:11:34.428 INFO  [main] [app](Args.java:1673) ************************ Backup config ************************
15:11:34.428 INFO  [main] [app](Args.java:1674) Backup priority: 8
15:11:34.428 INFO  [main] [app](Args.java:1675) Backup listen port: 10001
15:11:34.428 INFO  [main] [app](Args.java:1676) Backup listen keepAliveInterval: 3000
15:11:34.428 INFO  [main] [app](Args.java:1677) Backup member size: 0
15:11:34.428 INFO  [main] [app](Args.java:1678) ************************ Code version *************************
15:11:34.428 INFO  [main] [app](Args.java:1679) Code version : 4.7.2
15:11:34.429 INFO  [main] [app](Args.java:1680) Version code: 18031
15:11:34.429 INFO  [main] [app](Args.java:1681) ************************ DB config *************************
15:11:34.429 INFO  [main] [app](Args.java:1682) DB engine : LEVELDB
15:11:34.429 INFO  [main] [app](Args.java:1683) Snapshot max flush count: 1
15:11:34.429 INFO  [main] [app](Args.java:1684) ***************************************************************
15:11:34.429 INFO  [main] [app](Args.java:1685) ************************ shutDown config *************************
15:11:34.429 INFO  [main] [app](Args.java:1686) ShutDown blockTime  : null
15:11:34.429 INFO  [main] [app](Args.java:1687) ShutDown blockHeight : -1
15:11:34.429 INFO  [main] [app](Args.java:1688) ShutDown blockCount : -1
15:11:34.429 INFO  [main] [app](Args.java:1689) ***************************************************************
15:11:34.429 INFO  [main] [app](Args.java:1690) 

15:11:34.429 INFO  [main] [app](FullNode.java:71) not in debug mode, it will check energy time
15:11:38.959 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB account open success with writeBufferSize 64 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.009 INFO  [main] [app](DefaultConfig.java:51) key-value data source created.
15:11:39.055 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB tmp open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.091 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB accountTrie open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.123 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB balance-trace open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.147 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB account-trace open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.368 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB properties open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.726 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB account-asset open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.327 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB block open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.396 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB witness open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.420 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB asset-issue open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.445 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB asset-issue-v2 open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.496 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB block-index open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.516 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB accountid-index open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.540 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB account-index open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.565 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB witness_schedule open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.599 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB votes open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.621 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB proposal open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.642 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB exchange open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.667 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB exchange-v2 open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.687 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB market_account open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.709 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB market_order open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.730 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB market_pair_price_to_order open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.749 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB market_pair_to_price open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.776 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB abi open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.815 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB code open success with writeBufferSize 64 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.849 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB contract open success with writeBufferSize 64 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.988 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB contract-state open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.080 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB DelegatedResource open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.390 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB DelegatedResourceAccountIndex open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.763 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB storage-row open success with writeBufferSize 64 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.787 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB nullifier open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.803 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB zkProof open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.879 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB IncrementalMerkleTree open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:42.098 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB delegation open success with writeBufferSize 64 M, cacheSize 32 M, maxOpenFiles 100.
15:11:42.117 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB block_KDB open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:42.146 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB common open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:43.099 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB trans open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:43.612 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB transactionRetStore open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:43.663 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB recent-block open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:43.783 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB recent-transaction open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.199 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB transactionHistoryStore open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.220 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB common-database open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.235 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB pbft-sign-data open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.276 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB tree-block-index open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.296 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB section-bloom open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.408 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB trans-cache open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.865 INFO  [main] [DB](SnapshotManager.java:512) checkpoint v1 recover success
15:12:03.716 INFO  [main] [DB](TxCacheDB.java:129) Load cache from recentTransactionStore, filter: 12375626, filter-fpp: 2.63141893507509E-5, cost: 17624 ms.
15:12:03.907 INFO  [main] [DB](Manager.java:521) Current headNum is: 60149976.
15:12:03.907 INFO  [main] [DB](Manager.java:523) Node type is: full.
halibobo1205 commented 7 months ago

But when I roll back to the previous version 4.7.2, it can start normally.

Is there this corruption detected log after startup?

image
mercy1230 commented 7 months ago

But when I roll back to the previous version 4.7.2, it can start normally.

Is there this corruption detected log after startup? image

image No。。 have not

mercy1230 commented 7 months ago

But when I roll back to the previous version 4.7.2, it can start normally.

Is there this corruption detected log after startup? image

image No。。 have not

image

forfreeday commented 7 months ago

@mercy1230 Can you provide the full log attachments for versions 4.7.2 and 4.7.4?

mercy1230 commented 7 months ago

@mercy1230 Can you provide the full log attachments for versions 4.7.2 and 4.7.4?

Attached is the complete log. 4.7.4 After the startup fails, there are no more logs behind (see above) 4.7.2 After successful startup, there are just some running log . I will attach more logs below of 4.7.2 successful:

SUCCESSFUL LOGS OF 4.7.2

15:11:34.427 INFO  [main] [app](Args.java:1650) ************************ Net config ************************
15:11:34.427 INFO  [main] [app](Args.java:1651) P2P version: 11111
15:11:34.427 INFO  [main] [app](Args.java:1652) Bind IP: 
15:11:34.427 INFO  [main] [app](Args.java:1653) External IP: 
15:11:34.427 INFO  [main] [app](Args.java:1654) Listen port: 18888
15:11:34.427 INFO  [main] [app](Args.java:1655) Node ipv6 enable: false
15:11:34.427 INFO  [main] [app](Args.java:1656) Discover enable: true
15:11:34.427 INFO  [main] [app](Args.java:1657) Active node size: 0
15:11:34.427 INFO  [main] [app](Args.java:1658) Passive node size: 0
15:11:34.427 INFO  [main] [app](Args.java:1659) FastForward node size: 2
15:11:34.427 INFO  [main] [app](Args.java:1660) FastForward node number: 3
15:11:34.427 INFO  [main] [app](Args.java:1661) Seed node size: 30
15:11:34.427 INFO  [main] [app](Args.java:1662) Max connection: 30
15:11:34.427 INFO  [main] [app](Args.java:1663) Min connection: 8
15:11:34.427 INFO  [main] [app](Args.java:1664) Min active connection: 3
15:11:34.427 INFO  [main] [app](Args.java:1665) Max connection with same IP: 2
15:11:34.427 INFO  [main] [app](Args.java:1666) Solidity threads: 8
15:11:34.427 INFO  [main] [app](Args.java:1667) Trx reference block: solid
15:11:34.428 INFO  [main] [app](Args.java:1668) Open full tcp disconnect: true
15:11:34.428 INFO  [main] [app](Args.java:1669) Node detect enable: false
15:11:34.428 INFO  [main] [app](Args.java:1670) Node effective check enable: false
15:11:34.428 INFO  [main] [app](Args.java:1671) Rate limiter global qps: 50000
15:11:34.428 INFO  [main] [app](Args.java:1672) Rate limiter global ip qps: 10000
15:11:34.428 INFO  [main] [app](Args.java:1673) ************************ Backup config ************************
15:11:34.428 INFO  [main] [app](Args.java:1674) Backup priority: 8
15:11:34.428 INFO  [main] [app](Args.java:1675) Backup listen port: 10001
15:11:34.428 INFO  [main] [app](Args.java:1676) Backup listen keepAliveInterval: 3000
15:11:34.428 INFO  [main] [app](Args.java:1677) Backup member size: 0
15:11:34.428 INFO  [main] [app](Args.java:1678) ************************ Code version *************************
15:11:34.428 INFO  [main] [app](Args.java:1679) Code version : 4.7.2
15:11:34.429 INFO  [main] [app](Args.java:1680) Version code: 18031
15:11:34.429 INFO  [main] [app](Args.java:1681) ************************ DB config *************************
15:11:34.429 INFO  [main] [app](Args.java:1682) DB engine : LEVELDB
15:11:34.429 INFO  [main] [app](Args.java:1683) Snapshot max flush count: 1
15:11:34.429 INFO  [main] [app](Args.java:1684) ***************************************************************
15:11:34.429 INFO  [main] [app](Args.java:1685) ************************ shutDown config *************************
15:11:34.429 INFO  [main] [app](Args.java:1686) ShutDown blockTime  : null
15:11:34.429 INFO  [main] [app](Args.java:1687) ShutDown blockHeight : -1
15:11:34.429 INFO  [main] [app](Args.java:1688) ShutDown blockCount : -1
15:11:34.429 INFO  [main] [app](Args.java:1689) ***************************************************************
15:11:34.429 INFO  [main] [app](Args.java:1690) 

15:11:34.429 INFO  [main] [app](FullNode.java:71) not in debug mode, it will check energy time
15:11:38.959 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB account open success with writeBufferSize 64 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.009 INFO  [main] [app](DefaultConfig.java:51) key-value data source created.
15:11:39.055 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB tmp open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.091 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB accountTrie open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.123 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB balance-trace open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.147 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB account-trace open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.368 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB properties open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:39.726 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB account-asset open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.327 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB block open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.396 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB witness open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.420 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB asset-issue open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.445 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB asset-issue-v2 open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.496 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB block-index open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.516 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB accountid-index open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.540 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB account-index open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.565 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB witness_schedule open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.599 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB votes open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.621 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB proposal open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.642 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB exchange open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.667 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB exchange-v2 open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.687 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB market_account open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.709 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB market_order open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.730 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB market_pair_price_to_order open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.749 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB market_pair_to_price open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.776 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB abi open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.815 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB code open success with writeBufferSize 64 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.849 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB contract open success with writeBufferSize 64 M, cacheSize 32 M, maxOpenFiles 100.
15:11:40.988 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB contract-state open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.080 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB DelegatedResource open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.390 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB DelegatedResourceAccountIndex open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.763 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB storage-row open success with writeBufferSize 64 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.787 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB nullifier open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.803 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB zkProof open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:41.879 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB IncrementalMerkleTree open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:42.098 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB delegation open success with writeBufferSize 64 M, cacheSize 32 M, maxOpenFiles 100.
15:11:42.117 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB block_KDB open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:42.146 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB common open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:43.099 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB trans open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:43.612 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB transactionRetStore open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:43.663 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB recent-block open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:43.783 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB recent-transaction open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.199 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB transactionHistoryStore open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.220 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB common-database open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.235 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB pbft-sign-data open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.276 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB tree-block-index open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.296 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB section-bloom open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.408 INFO  [main] [DB](LevelDbDataSourceImpl.java:147) DB trans-cache open success with writeBufferSize 16 M, cacheSize 32 M, maxOpenFiles 100.
15:11:44.865 INFO  [main] [DB](SnapshotManager.java:512) checkpoint v1 recover success
15:12:03.716 INFO  [main] [DB](TxCacheDB.java:129) Load cache from recentTransactionStore, filter: 12375626, filter-fpp: 2.63141893507509E-5, cost: 17624 ms.
15:12:03.907 INFO  [main] [DB](Manager.java:521) Current headNum is: 60149976.
15:12:03.907 INFO  [main] [DB](Manager.java:523) Node type is: full.
15:12:04.308 INFO  [main] [o.r.Reflections](Reflections.java:232) Reflections took 387 ms to scan 1 urls, producing 489 keys and 2815 values 
15:12:04.445 INFO  [main] [o.s.b.f.a.AutowiredAnnotationBeanPostProcessor](AutowiredAnnotationBeanPostProcessor.java:478) Autowired annotation is not supported on static fields: private static final java.util.concurrent.ConcurrentHashMap org.tron.core.config.args.Args.solidityContractLogTriggerMap
15:12:04.445 INFO  [main] [o.s.b.f.a.AutowiredAnnotationBeanPostProcessor](AutowiredAnnotationBeanPostProcessor.java:478) Autowired annotation is not supported on static fields: private static final java.util.concurrent.ConcurrentHashMap org.tron.core.config.args.Args.solidityContractEventTriggerMap
15:12:04.962 INFO  [main] [app](FullNode.java:143) ********register application shutdown hook********
15:12:04.964 INFO  [main] [API](FullNodeHttpApiService.java:322) init zk param begin
15:12:05.699 INFO  [main] [API](FullNodeHttpApiService.java:343) init zk param done
15:12:05.905 INFO  [main] [API](RpcApiService.java:266) RpcApiService has started, listening on 50051
15:12:05.911 INFO  [main] [o.e.j.u.log](Log.java:170) Logging initialized @32463ms to org.eclipse.jetty.util.log.Slf4jLog
15:12:05.999 INFO  [main] [o.e.j.s.Server](Server.java:375) jetty-9.4.49.v20220914; built: 2022-09-14T01:07:36.601Z; git: 4231a3b2e4cb8548a412a789936d640a97b1aa0a; jvm 1.8.0_202-b08
15:12:06.026 INFO  [main] [o.e.j.s.session](DefaultSessionIdManager.java:334) DefaultSessionIdManager workerName=node0
15:12:06.026 INFO  [main] [o.e.j.s.session](DefaultSessionIdManager.java:339) No SessionScavenger set, using defaults
15:12:06.028 INFO  [main] [o.e.j.s.session](HouseKeeper.java:132) node0 Scavenging every 600000ms
15:12:06.044 INFO  [main] [o.e.j.s.h.ContextHandler](ContextHandler.java:921) Started o.e.j.s.ServletContextHandler@62ba3045{/,null,AVAILABLE}
15:12:06.053 INFO  [main] [o.e.j.s.AbstractConnector](AbstractConnector.java:333) Started ServerConnector@49c37918{HTTP/1.1, (http/1.1)}{0.0.0.0:8090}
15:12:06.054 INFO  [main] [o.e.j.s.Server](Server.java:415) Started @32605ms
15:12:06.062 INFO  [main] [API](RpcApiServiceOnSolidity.java:139) RpcApiServiceOnSolidity started, listening on 50061
15:12:06.063 INFO  [main] [o.e.j.s.Server](Server.java:375) jetty-9.4.49.v20220914; built: 2022-09-14T01:07:36.601Z; git: 4231a3b2e4cb8548a412a789936d640a97b1aa0a; jvm 1.8.0_202-b08
15:12:06.066 INFO  [main] [o.e.j.s.session](DefaultSessionIdManager.java:334) DefaultSessionIdManager workerName=node0
15:12:06.066 INFO  [main] [o.e.j.s.session](DefaultSessionIdManager.java:339) No SessionScavenger set, using defaults
15:12:06.067 INFO  [main] [o.e.j.s.session](HouseKeeper.java:132) node0 Scavenging every 600000ms
15:12:06.068 INFO  [main] [o.e.j.s.h.ContextHandler](ContextHandler.java:921) Started o.e.j.s.ServletContextHandler@485547ac{/,null,AVAILABLE}
15:12:06.070 INFO  [main] [o.e.j.s.AbstractConnector](AbstractConnector.java:333) Started ServerConnector@75f446df{HTTP/1.1, (http/1.1)}{0.0.0.0:8091}
15:12:06.070 INFO  [main] [o.e.j.s.Server](Server.java:415) Started @32622ms
15:12:06.074 INFO  [main] [API](RpcApiServiceOnPBFT.java:139) RpcApiServiceOnPBFT started, listening on 50071
15:12:06.075 WARN  [main] [o.e.j.s.h.ContextHandler](ContextHandler.java:1667) o.e.j.s.ServletContextHandler@397f925e{/,null,STOPPED} contextPath ends with /
15:12:06.076 INFO  [main] [o.e.j.s.Server](Server.java:375) jetty-9.4.49.v20220914; built: 2022-09-14T01:07:36.601Z; git: 4231a3b2e4cb8548a412a789936d640a97b1aa0a; jvm 1.8.0_202-b08
15:12:06.077 INFO  [main] [o.e.j.s.session](DefaultSessionIdManager.java:334) DefaultSessionIdManager workerName=node0
15:12:06.077 INFO  [main] [o.e.j.s.session](DefaultSessionIdManager.java:339) No SessionScavenger set, using defaults
15:12:06.078 INFO  [main] [o.e.j.s.session](HouseKeeper.java:132) node0 Scavenging every 600000ms
15:12:06.079 INFO  [main] [o.e.j.s.h.ContextHandler](ContextHandler.java:921) Started o.e.j.s.ServletContextHandler@397f925e{/walletpbft,null,AVAILABLE}
15:12:06.087 INFO  [main] [o.e.j.s.AbstractConnector](AbstractConnector.java:333) Started ServerConnector@b1f36e5{HTTP/1.1, (http/1.1)}{0.0.0.0:8092}
15:12:06.087 INFO  [main] [o.e.j.s.Server](Server.java:415) Started @32639ms
15:12:06.350 WARN  [main] [net](NetUtil.java:100) Fail to get ipv6 by https://v6.ident.me, cause:Network is unreachable (connect failed)
15:12:06.356 INFO  [main] [net](NodePersistService.java:63) Read node from store: 30 nodes
15:12:06.357 INFO  [main] [net](TronNetService.java:192) Local ipv4:
15:12:06.357 INFO  [main] [net](TronNetService.java:193) Local ipv6: null
15:12:06.379 INFO  [PeerServer] [net](PeerServer.java:56) TCP listener started, bind port 18888
15:12:06.381 INFO  [main] [net](PublishService.java:106) Dns publish service is disable
15:12:06.383 INFO  [main] [net](P2pService.java:31) P2p service started
15:12:06.394 INFO  [main] [net](RelayService.java:76) Fast forward config, isWitness: false, keySize: 0, fastForwardNodes: 2
15:12:06.394 INFO  [main] [net](EffectiveCheckService.java:59) EffectiveCheckService is disabled
15:12:06.394 INFO  [main] [net](TronNetService.java:98) Net service start successfully
15:12:06.394 WARN  [main] [app](LocalWitnesses.java:104) PrivateKey
15:12:06.395 INFO  [DiscoverServer] [net](DiscoverServer.java:71) Discovery server started, bind port 18888
15:12:06.395 WARN  [main] [consensus](ConsensusService.java:73) Witness xxxxxxx is not in witnessStore.
15:12:06.398 INFO  [main] [consensus](ConsensusService.java:84) consensus service start success
15:12:06.584 INFO  [pool-61-thread-1] [net](ConnPoolService.java:188) Connect to peer /3.225.171.164:18888
15:12:06.587 INFO  [pool-61-thread-1] [net](ConnPoolService.java:188) Connect to peer /54.82.161.39:18888
15:12:06.587 INFO  [pool-61-thread-1] [net](ConnPoolService.java:188) Connect to peer /54.167.11.177:18888
forfreeday commented 7 months ago

@mercy1230 Provide db.log for 4.7.2 and 4.7.4 again.

mercy1230 commented 7 months ago

@mercy1230 Provide db.log for 4.7.2 and 4.7.4 again.

It seems to be no db.log during the startup failure. I restarted for several times between 15:00-15:13

14:58:37.598 storage-row Compacting 1@2 + 5@3 files
14:58:37.643 storage-row Generated table #29946676@2: 69488 keys, 2124222 bytes
14:58:37.686 storage-row Generated table #29946677@2: 64949 keys, 2120191 bytes
14:58:37.733 storage-row Generated table #29946678@2: 79908 keys, 2124238 bytes
14:58:37.780 storage-row Generated table #29946679@2: 74773 keys, 2126270 bytes
14:58:37.828 storage-row Generated table #29946680@2: 82116 keys, 2128449 bytes
14:58:37.832 storage-row Generated table #29946681@2: 383 keys, 10683 bytes
14:58:37.832 storage-row Compacted 1@2 + 5@3 files => 10634053 bytes
14:58:37.836 storage-row compacted to: files[ 0 0 42 1088 13905 31 0 ]
14:58:37.848 storage-row Delete type=2 #29944891

14:58:37.850 storage-row Delete type=2 #29944890

14:58:37.851 storage-row Delete type=2 #29944894

14:58:37.852 storage-row Delete type=2 #29945496

14:58:37.852 storage-row Delete type=2 #29944892

14:58:37.854 storage-row Delete type=2 #29944893

14:59:27.568 tmp Level-0 table #6277177: started
14:59:27.655 tmp Level-0 table #6277177: 5302156 bytes OK
14:59:27.659 tmp Delete type=0 #6277173

15:00:24.363 tmp Compacting 1@1 + 1@2 files
15:00:24.392 tmp Generated table #6277178@1: 784 keys, 98264 bytes
15:00:24.392 tmp Compacted 1@1 + 1@2 files => 98264 bytes
15:00:24.395 tmp compacted to: files[ 0 0 1 0 0 0 0 ]
15:00:24.395 tmp Delete type=2 #6277175

15:00:24.396 tmp Delete type=2 #6277177

15:01:05.857 storage-row Compacting 1@3 + 12@4 files
15:01:05.899 storage-row Generated table #29946682@3: 64069 keys, 2117500 bytes
15:01:05.946 storage-row Generated table #29946683@3: 72270 keys, 2121837 bytes
15:01:05.991 storage-row Generated table #29946684@3: 74030 keys, 2123951 bytes
15:01:06.047 storage-row Generated table #29946685@3: 108103 keys, 2137098 bytes
15:01:06.114 storage-row Generated table #29946686@3: 152110 keys, 2133493 bytes
15:01:06.178 storage-row Generated table #29946687@3: 152002 keys, 2133485 bytes
15:01:06.241 storage-row Generated table #29946688@3: 151194 keys, 2133292 bytes
15:01:06.305 storage-row Generated table #29946689@3: 146583 keys, 2130236 bytes
15:01:06.369 storage-row Generated table #29946690@3: 150341 keys, 2133483 bytes
15:01:06.420 storage-row Generated table #29946691@3: 97417 keys, 2127197 bytes
15:01:06.433 storage-row Generated table #29946692@3: 17 keys, 717 bytes
15:01:06.433 storage-row Compacted 1@3 + 12@4 files => 21292289 bytes
15:01:06.437 storage-row compacted to: files[ 0 0 42 1087 13904 31 0 ]
15:01:06.448 storage-row Delete type=2 #29945063

15:01:06.448 storage-row Delete type=2 #29945735

15:01:06.449 storage-row Delete type=2 #29940382

15:01:06.450 storage-row Delete type=2 #29945064

15:01:06.450 storage-row Delete type=2 #29945061

15:01:06.452 storage-row Delete type=2 #29945062

15:01:06.452 storage-row Delete type=2 #29945060

15:01:06.453 storage-row Delete type=2 #29940383

15:01:06.454 storage-row Delete type=2 #29940386

15:01:06.455 storage-row Delete type=2 #29940387

15:01:06.456 storage-row Delete type=2 #29940385

15:01:06.457 storage-row Delete type=2 #29941752

15:01:06.457 storage-row Delete type=2 #29940384

15:01:57.619 tmp Level-0 table #6277180: started
15:01:57.704 tmp Level-0 table #6277180: 5312117 bytes OK
15:01:57.708 tmp Delete type=0 #6277176

15:11:38.313 account Recovering log #372572835
15:11:38.684 account Level-0 table #372576402: started
15:11:38.913 account Level-0 table #372576402: 11302221 bytes OK
15:11:38.942 account Delete type=3 #372454270

15:11:38.946 account Delete type=0 #372572835

15:11:39.016 tmp Recovering log #6277179
15:11:39.030 tmp Level-0 table #6277182: started
15:11:39.044 tmp Level-0 table #6277182: 869699 bytes OK
15:11:39.054 tmp Delete type=3 #6275711

15:11:39.055 tmp Delete type=0 #6277179

15:11:39.076 accountTrie Recovering log #2294
15:11:39.079 accountTrie Level-0 table #2296: started
15:11:39.079 accountTrie Level-0 table #2296: 0 bytes OK
15:11:39.091 accountTrie Delete type=3 #2292

15:11:39.091 accountTrie Delete type=0 #2294

15:11:39.107 balance-trace Recovering log #1601
15:11:39.111 balance-trace Level-0 table #1603: started
15:11:39.111 balance-trace Level-0 table #1603: 0 bytes OK
15:11:39.122 balance-trace Delete type=0 #1601

15:11:39.123 balance-trace Delete type=3 #1599

15:11:39.130 account-trace Recovering log #1601
15:11:39.136 account-trace Level-0 table #1603: started
15:11:39.136 account-trace Level-0 table #1603: 0 bytes OK
15:11:39.146 account-trace Delete type=0 #1601

15:11:39.147 account-trace Delete type=3 #1599

15:11:39.164 properties Recovering log #22824
15:11:39.291 properties Level-0 table #22828: started
15:11:39.354 properties Level-0 table #22828: 1886997 bytes OK
15:11:39.365 properties Delete type=0 #22824

15:11:39.368 properties Delete type=3 #22820
forfreeday commented 7 months ago

@mercy1230 Currently these logs are regular logs, it would be more helpful to troubleshoot this issue if you can provide the full logs, and if you can, provide the full file attachments for the full tron.log and db.log, especially the log files after executing kill -15.

mercy1230 commented 7 months ago

Currently these logs are regular logs, it would be more helpful to troubleshoot this issue if you can provide the full logs, and if you can, provide the full file attachments for the full tron.log and db.log, especially the log files after executing kill -15.

OK，Can I Send these logs to your email？

forfreeday commented 7 months ago

@mercy1230 You can add it directly to this reply.

mercy1230 commented 7 months ago

@mercy1230 You can add it directly to this reply. please check.

before and ater kill -15 FullNode

is it OK？

forfreeday commented 7 months ago

@mercy1230 What kind of data are you using, lite data or full data?

mercy1230 commented 7 months ago

@mercy1230 What kind of data are you using, lite data or full data?

Full data

forfreeday commented 7 months ago

Based on the current logs, it is not possible to tell if the started database is working properly, and we have tried to reproduce it, but the exception does not appear.

julitabarelkowska commented 7 months ago

15:09:16.091 INFO [main] app not in debug mode, it will check energy time 15:09:19.599 ERROR [main] DB Database account corrupted, please delete database directory(output-directory/database) and restart. org.fusesource.leveldbjni.internal.NativeDB$DBException: Corruption: 12646 missing files; e.g.: /output-directory/database/account/354491281.sst at org.fusesource.leveldbjni.internal.NativeDB.checkStatus(NativeDB.java:200) at org.fusesource.leveldbjni.internal.NativeDB.open(NativeDB.java:218) at org.fusesource.leveldbjni.JniDBFactory.open(JniDBFactory.java:168) at org.tron.common.storage.leveldb.LevelDbDataSourceImpl.openDatabase(LevelDbDataSourceImpl.java:146) at org.tron.common.storage.leveldb.LevelDbDataSourceImpl.initDB(LevelDbDataSourceImpl.java:125) at org.tron.common.storage.leveldb.LevelDbDataSourceImpl.(LevelDbDataSourceImpl.java:96) at org.tron.core.db.TronStoreWithRevoking.(TronStoreWithRevoking.java:64) at org.tron.core.store.AccountStore.(AccountStore.java:42) at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) at java.lang.reflect.Constructor.newInstance(Constructor.java:423) at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:211) at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:117) at org.springframework.beans.factory.support.ConstructorResolver.instantiate(ConstructorResolver.java:311) at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:296) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:1372) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:1222) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:582) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:542) at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:335) at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234) at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:333) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:208) at org.springframework.beans.factory.config.DependencyDescriptor.resolveCandidate(DependencyDescriptor.java:276) at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1389) at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1309) at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.resolveFieldValue(AutowiredAnnotationBeanPostProcessor.java:656) at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:639) at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:119) at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessProperties(AutowiredAnnotationBeanPostProcessor.java:399) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1431) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:619) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:542) at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:335) at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234) at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:333) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:208) at org.springframework.beans.factory.config.DependencyDescriptor.resolveCandidate(DependencyDescriptor.java:276) at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1389) at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1309) at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.resolveFieldValue(AutowiredAnnotationBeanPostProcessor.java:656) at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:639) at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:119) at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessProperties(AutowiredAnnotationBeanPostProcessor.java:399) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1431) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:619) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:542) at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:335) at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234) at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:333) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:208) at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:953) at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:918) at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:583) at org.tron.program.FullNode.main(FullNode.java:80) 15:11:33.775 INFO [main] app Full node running.

forfreeday commented 7 months ago

@julitabarelkowska Can you describe the exact problem?

mercy1230 commented 7 months ago

@julitabarelkowska Can you describe the exact problem?

Based on the current logs, it is not possible to tell if the started database is working properly, and we have tried to reproduce it, but the exception does not appear.

OK，tomorrow i 'll delete the output dir, download the snapshot and try again

I'm just wondering, why 4.7.2 could start success, but after update to 4.7.4, it couldn't start，using the same database.

The logs I posted earlier is indeed the entire one without missing. except 4.7.4 has error logs while start failed , 4.7.2 startup success, so no errors are logged

anyhow，thanks for answering

forfreeday commented 7 months ago

@mercy1230

  1. "kill -15"、"kill -9" commands will not cause any damage to the database.
  2. If the database is damaged due to other reasons (such as node failure), both versions 4.7.2 and 4.7.4 will not be able to start up.
  3. In the event of a database exception, both versions 4.7.2 and 4.7.4 will not function properly, although they exhibit different behaviors.
    1. In version 4.7.2, the data repair functionality has been removed. The database initialization fails during the startup process. Although the TRON service can still run, it will no longer synchronize blocks.You can find more details about version 4.7.2 at the following link: GreatVoyage-v4.7.2 Release
    2. In version 4.7.4, when the repair process fails, the program will exit directly. Therefore, in reality, both versions of the program are unable to function properly.
vivian1912 commented 7 months ago

@mercy1230 Thanks for your contribution to java-tron. May I ask if this issue has been resolved? And do you have any other questions?

vivian1912 commented 7 months ago

@mercy1230 This issue will be closed as no update for a long time. Please feel free to re-open it if you still see the issue, thanks.