[DRBD-user] linstor-server 1.9.0 release

Rene Peinthor rene.peinthor at linbit.com
Thu Sep 24 07:30:33 CEST 2020


Sounds weird, I don't think we changed anything with the database
initialization.

Can you post your /etc/linstor/linstor.toml?

Cheers,
Rene

On Wed, Sep 23, 2020 at 6:54 PM Adam Goryachev <
mailinglists at websitemanagers.com.au> wrote:

> Hi,
>
> After upgrading on one of my test nodes I got some errors, so I thought to
> upgrade the controller, and now I am unable to start the controller.
>
> Here is what I get in the system log:
>
> Sep 24 02:33:40 castle systemd[1]: Started LINSTOR Controller Service.
> Sep 24 02:33:41 castle Controller[1093]: LINSTOR, Module Controller
> Sep 24 02:33:41 castle Controller[1093]: Version:            1.9.0
> (678acd24a8b9b73a735407cd79ca33a5e95eb2e2)
> Sep 24 02:33:41 castle Controller[1093]: Build time:
> 2020-09-23T10:27:49+00:00
> Sep 24 02:33:41 castle Controller[1093]: Java Version:       11
> Sep 24 02:33:41 castle Controller[1093]: Java VM:            Debian,
> Version 11.0.8+10-post-Debian-1deb10u1
> Sep 24 02:33:41 castle Controller[1093]: Operating system:   Linux,
> Version 4.19.0-10-amd64
> Sep 24 02:33:41 castle Controller[1093]: Environment:        amd64, 2
> processors, 1988 MiB memory reserved for allocations
> Sep 24 02:33:41 castle Controller[1093]: System components initialization
> in progress
> Sep 24 02:33:42 castle Controller[1093]: 02:33:42.355 [main] INFO
> LINSTOR/Controller - SYSTEM - ErrorReporter DB version 1 found.
> Sep 24 02:33:42 castle Controller[1093]: 02:33:42.359 [main] INFO
> LINSTOR/Controller - SYSTEM - Log directory set to:
> '/var/log/linstor-controller'
> Sep 24 02:33:42 castle Controller[1093]: 02:33:42.410 [main] WARN
> io.sentry.dsn.Dsn - *** Couldn't find a suitable DSN, Sentry operations
> will do nothing! See documentation: https://docs.sentry.io/clients/java/
> ***
> Sep 24 02:33:42 castle Controller[1093]: 02:33:42.434 [Main] INFO
> LINSTOR/Controller - SYSTEM - Loading API classes started.
> Sep 24 02:33:43 castle Controller[1093]: 02:33:43.046 [Main] INFO
> LINSTOR/Controller - SYSTEM - API classes loading finished: 611ms
> Sep 24 02:33:43 castle Controller[1093]: 02:33:43.047 [Main] INFO
> LINSTOR/Controller - SYSTEM - Dependency injection started.
> Sep 24 02:33:43 castle Controller[1093]: 02:33:43.065 [Main] INFO
> LINSTOR/Controller - SYSTEM - Attempting dynamic load of extension module
> "com.linbit.linstor.spacetracking.ControllerSpaceTrackingModule"
> Sep 24 02:33:43 castle Controller[1093]: 02:33:43.065 [Main] INFO
> LINSTOR/Controller - SYSTEM - Extension module
> "com.linbit.linstor.spacetracking.ControllerSpaceTrackingModule" is not
> installed
> Sep 24 02:33:43 castle Controller[1093]: WARNING: An illegal reflective
> access operation has occurred
> Sep 24 02:33:43 castle Controller[1093]: WARNING: Illegal reflective
> access by com.google.inject.internal.cglib.core.$ReflectUtils$1 (
> file:/usr/share/linstor-server/lib/guice-4.2.2.jar) to method
> java.lang.ClassLoader.defineClass(java.lang.String,byte[],int,int,java.security.ProtectionDomain)
> Sep 24 02:33:43 castle Controller[1093]: WARNING: Please consider
> reporting this to the maintainers of
> com.google.inject.internal.cglib.core.$ReflectUtils$1
> Sep 24 02:33:43 castle Controller[1093]: WARNING: Use
> --illegal-access=warn to enable warnings of further illegal reflective
> access operations
> Sep 24 02:33:43 castle Controller[1093]: WARNING: All illegal access
> operations will be denied in a future release
> Sep 24 02:33:44 castle Controller[1093]: 02:33:44.962 [Main] INFO
> LINSTOR/Controller - SYSTEM - Dependency injection finished: 1915ms
> Sep 24 02:33:45 castle Controller[1093]: 02:33:45.533 [Main] INFO
> LINSTOR/Controller - SYSTEM - Initializing authentication subsystem
> Sep 24 02:33:45 castle Controller[1093]: 02:33:45.736 [Main] INFO
> LINSTOR/Controller - SYSTEM - Starting service instance 'TimerEventService'
> of type TimerEventService
> Sep 24 02:33:45 castle Controller[1093]: 02:33:45.740 [Main] INFO
> LINSTOR/Controller - SYSTEM - Initializing the database connection pool
> Sep 24 02:33:46 castle Controller[1093]: 02:33:46.303 [Main] ERROR
> LINSTOR/Controller - SYSTEM - Database initialization error [Report number
> 5F6B78E5-00000-000000]
> Sep 24 02:33:46 castle Controller[1093]: 02:33:46.343 [Thread-2] INFO
> LINSTOR/Controller - SYSTEM - Shutdown in progress
> Sep 24 02:33:46 castle Controller[1093]: 02:33:46.344 [Thread-2] INFO
> LINSTOR/Controller - SYSTEM - Shutting down service instance
> 'TaskScheduleService' of type TaskScheduleService
> Sep 24 02:33:46 castle Controller[1093]: 02:33:46.345 [Thread-2] INFO
> LINSTOR/Controller - SYSTEM - Waiting for service instance
> 'TaskScheduleService' to complete shutdown
> Sep 24 02:33:46 castle Controller[1093]: 02:33:46.345 [Thread-2] INFO
> LINSTOR/Controller - SYSTEM - Shutting down service instance
> 'DatabaseService' of type DatabaseService
> Sep 24 02:33:46 castle Controller[1093]: 02:33:46.349 [Thread-2] INFO
> LINSTOR/Controller - SYSTEM - Waiting for service instance
> 'DatabaseService' to complete shutdown
> Sep 24 02:33:46 castle Controller[1093]: 02:33:46.350 [Thread-2] INFO
> LINSTOR/Controller - SYSTEM - Shutting down service instance
> 'TimerEventService' of type TimerEventService
> Sep 24 02:33:46 castle Controller[1093]: 02:33:46.351 [Thread-2] INFO
> LINSTOR/Controller - SYSTEM - Waiting for service instance
> 'TimerEventService' to complete shutdown
> Sep 24 02:33:46 castle Controller[1093]: 02:33:46.351 [Thread-2] INFO
> LINSTOR/Controller - SYSTEM - Shutdown complete
> Sep 24 02:33:46 castle systemd[1]: linstor-controller.service: Main
> process exited, code=exited, status=20/n/a
> Sep 24 02:33:46 castle systemd[1]: linstor-controller.service: Failed with
> result 'exit-code'.
>
> Here is /var/log/linstor-controller/ErrorReport-5F6B78E5-00000-000000.log
>
> ERROR REPORT 5F6B78E5-00000-000000
>
> ============================================================
>
> Application:                        LINBIT® LINSTOR
> Module:                             Controller
> Version:                            1.9.0
> Build ID:
> 678acd24a8b9b73a735407cd79ca33a5e95eb2e2
> Build time:                         2020-09-23T10:27:49+00:00
> Error time:                         2020-09-24 02:33:46
> Node:                               castle
>
> ============================================================
>
> Reported error:
> ===============
>
> Description:
>     Database initialization error
>
> Category:                           LinStorException
> Class name:                         SystemServiceStartException
> Class canonical name:               com.linbit.SystemServiceStartException
> Generated at:                       Method 'initialize', Source file
> 'DbConnectionPoolInitializer.java', Line #56
> Error message:                      Database initialization error
>
> Call backtrace:
>
>     Method                                   Native Class:Line number
>     initialize                               N
> com.linbit.linstor.dbcp.DbConnectionPoolInitializer:56
>     startSystemServices                      N
> com.linbit.linstor.core.ApplicationLifecycleManager:87
>     start                                    N
> com.linbit.linstor.core.Controller:337
>     main                                     N
> com.linbit.linstor.core.Controller:556
>
> Caused by:
> ==========
>
> Category:                           RuntimeException
> Class name:                         LinStorDBRuntimeException
> Class canonical name:
> com.linbit.linstor.LinStorDBRuntimeException
> Generated at:                       Method 'setTransactionIsolation',
> Source file 'DbConnectionPool.java', Line #485
>
> Error message:                      Failed to set transaction isolation
>
> Call backtrace:
>
>     Method                                   Native Class:Line number
>     setTransactionIsolation                  N
> com.linbit.linstor.dbcp.DbConnectionPool:485
>     migrate                                  N
> com.linbit.linstor.dbcp.DbConnectionPool:202
>     initialize                               N
> com.linbit.linstor.dbcp.DbConnectionPoolInitializer:50
>     startSystemServices                      N
> com.linbit.linstor.core.ApplicationLifecycleManager:87
>     start                                    N
> com.linbit.linstor.core.Controller:337
>     main                                     N
> com.linbit.linstor.core.Controller:556
>
> Caused by:
> ==========
>
> Category:                           Exception
> Class name:                         JdbcSQLException
> Class canonical name:               org.h2.jdbc.JdbcSQLException
> Generated at:                       Method 'getJdbcSQLException', Source
> file 'DbException.java', Line #357
>
> Error message:                      Wrong user name or password [28000-197]
>
> Call backtrace:
>
>     Method                                   Native Class:Line number
>     getJdbcSQLException                      N
> org.h2.message.DbException:357
>     get                                      N
> org.h2.message.DbException:179
>     get                                      N
> org.h2.message.DbException:155
>     get                                      N
> org.h2.message.DbException:144
>     validateUserAndPassword                  N
> org.h2.engine.Engine:341
>     createSessionAndValidate                 N
> org.h2.engine.Engine:165
>     createSession                            N
> org.h2.engine.Engine:140
>     createSession                            N      org.h2.engine.Engine:28
>     connectEmbeddedOrServer                  N
> org.h2.engine.SessionRemote:351
>     <init>                                   N
> org.h2.jdbc.JdbcConnection:124
>     <init>                                   N
> org.h2.jdbc.JdbcConnection:103
>     connect                                  N      org.h2.Driver:69
>     getConnection                            N
> java.sql.DriverManager:677
>     getConnection                            N
> java.sql.DriverManager:189
>     createConnection                         N
> org.apache.commons.dbcp2.DriverManagerConnectionFactory:123
>     makeObject                               N
> org.apache.commons.dbcp2.PoolableConnectionFactory:355
>     create                                   N
> org.apache.commons.pool2.impl.GenericObjectPool:889
>     borrowObject                             N
> org.apache.commons.pool2.impl.GenericObjectPool:424
>     borrowObject                             N
> org.apache.commons.pool2.impl.GenericObjectPool:349
>     getConnection                            N
> org.apache.commons.dbcp2.PoolingDataSource:134
>     setTransactionIsolation                  N
> com.linbit.linstor.dbcp.DbConnectionPool:477
>     migrate                                  N
> com.linbit.linstor.dbcp.DbConnectionPool:202
>     initialize                               N
> com.linbit.linstor.dbcp.DbConnectionPoolInitializer:50
>     startSystemServices                      N
> com.linbit.linstor.core.ApplicationLifecycleManager:87
>     start                                    N
> com.linbit.linstor.core.Controller:337
>     main                                     N
> com.linbit.linstor.core.Controller:556
>
> Finally I found another log in /var/lib/linstor/linstordb.trace.db
>
> 2020-09-24 02:33:45 database: wrong user or password; user: "LINSTOR"
> org.h2.message.DbException: Wrong user name or password [28000-197]
>         at org.h2.message.DbException.get(DbException.java:179)
>         at org.h2.message.DbException.get(DbException.java:155)
>         at org.h2.message.DbException.get(DbException.java:144)
>         at org.h2.engine.Engine.openSession(Engine.java:107)
>         at org.h2.engine.Engine.openSession(Engine.java:179)
>         at org.h2.engine.Engine.createSessionAndValidate(Engine.java:157)
>         at org.h2.engine.Engine.createSession(Engine.java:140)
>         at org.h2.engine.Engine.createSession(Engine.java:28)
>         at
> org.h2.engine.SessionRemote.connectEmbeddedOrServer(SessionRemote.java:351)
>         at org.h2.jdbc.JdbcConnection.<init>(JdbcConnection.java:124)
>         at org.h2.jdbc.JdbcConnection.<init>(JdbcConnection.java:103)
>         at org.h2.Driver.connect(Driver.java:69)
>         at
> java.sql/java.sql.DriverManager.getConnection(DriverManager.java:677)
>         at
> java.sql/java.sql.DriverManager.getConnection(DriverManager.java:189)
>         at
> org.apache.commons.dbcp2.DriverManagerConnectionFactory.createConnection(DriverManagerConnectionFactory.java:123)
>         at
> org.apache.commons.dbcp2.PoolableConnectionFactory.makeObject(PoolableConnectionFactory.java:355)
>         at
> org.apache.commons.pool2.impl.GenericObjectPool.create(GenericObjectPool.java:889)
>         at
> org.apache.commons.pool2.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:424)
>         at
> org.apache.commons.pool2.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:349)
>         at
> org.apache.commons.dbcp2.PoolingDataSource.getConnection(PoolingDataSource.java:134)
>         at
> com.linbit.linstor.dbcp.DbConnectionPool.setTransactionIsolation(DbConnectionPool.java:477)
>         at
> com.linbit.linstor.dbcp.DbConnectionPool.migrate(DbConnectionPool.java:202)
>         at
> com.linbit.linstor.dbcp.DbConnectionPoolInitializer.initialize(DbConnectionPoolInitializer.java:50)
>         at
> com.linbit.linstor.core.ApplicationLifecycleManager.startSystemServices(ApplicationLifecycleManager.java:87)
>         at com.linbit.linstor.core.Controller.start(Controller.java:337)
>         at com.linbit.linstor.core.Controller.main(Controller.java:556)
> Caused by: org.h2.jdbc.JdbcSQLException: Wrong user name or password
> [28000-197]
>         at
> org.h2.message.DbException.getJdbcSQLException(DbException.java:357)
>         ... 26 more
>
>
> Luckily, this is just a test environment, but I'd like to know if I messed
> up in my upgrade process somehow or this is a bug in the new version. I
> haven't downgraded or anything yet.
>
> Regards,
> Adam
>
>
> On 23/9/20 22:31, Rene Peinthor wrote:
>
> Hi!
>
> Here is a new minor Linstor release, this release contains more bug fixes
> than new features.
> Notable features are a new auto-unplace option, to reduce the
> replica-count of resources
> to the value set in the resource-group after a certain time. And
> error-reports can now be deleted
> from the REST-API (client).
>
> linstor-server 1.9.0
> --------------------
>  * Added auto-"unplace", automatically removes resources if they exceed
> the replica count if `DrbdOptions/auto-unplace` is set
>  * Added error-report deletion API
>  * Expose DRBD promotion_score and may_promote via REST-API and server
> sent events
>  * Prometheus metrics are now mostly cached and much faster to query
>  * Send SD_NOTIFY READY to systemd if services are ready
>  * OpenFlex resource are now correctly limited to 32 characters
>  * Fix etcd modify net-interface (incorrect serialization)
>  * Fix autoplacer also toggling diskless resources
>  * Fix snapshot-shipping with more than 1 volume definitions
>  * Fix zfs create options handling
>  * Fixed recaching of lvm-filter
>  * Fix missing client answer if drbd device doesn't become ready in
> reasonable time
>  * REST-API v1.4.0
>
> https://www.linbit.com/downloads/linstor/linstor-server-1.9.0.tar.gz
>
> Linstor PPA:
> https://launchpad.net/~linbit/+archive/ubuntu/linbit-drbd9-stack
>
> Cheers,
> Rene
>
> _______________________________________________
> Star us on GITHUB: https://github.com/LINBIT
> drbd-user mailing listdrbd-user at lists.linbit.comhttps://lists.linbit.com/mailman/listinfo/drbd-user
>
> _______________________________________________
> Star us on GITHUB: https://github.com/LINBIT
> drbd-user mailing list
> drbd-user at lists.linbit.com
> https://lists.linbit.com/mailman/listinfo/drbd-user
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.linbit.com/pipermail/drbd-user/attachments/20200924/05426d66/attachment-0001.htm>


More information about the drbd-user mailing list