Bamboo Setup SqlServer Exception

[YildizNet App]

Hi,

Bamboo external db exception. help me ?

2019-06-10 21:52:52 Commons Daemon procrun stdout initialized
2019-06-10 21:53:24,575 INFO [localhost-startStop-1] [BootstrapLoaderListener] Starting BootstrapLoaderListener
2019-06-10 21:53:24,575 INFO [localhost-startStop-1] [lifecycle] *******************************
2019-06-10 21:53:24,575 INFO [localhost-startStop-1] [lifecycle] * Bamboo is starting up *
2019-06-10 21:53:24,575 INFO [localhost-startStop-1] [lifecycle] *******************************
2019-06-10 21:53:24,575 INFO [localhost-startStop-1] [ServletContextHolder] Setting servlet context: Bamboo
2019-06-10 21:53:24,637 INFO [localhost-startStop-1] [lifecycle] atlassian.org.osgi.framework.bootdelegation set to javax.servlet,javax.servlet.*,sun.*,com.sun.*,org.w3c.dom.*,org.apache.xerces.*,org.jboss.byteman.*,jdk.internal.reflect
2019-06-10 21:53:24,778 INFO [localhost-startStop-1] [BootstrapLoaderListener] App classloader is the same as web app classloader
2019-06-10 21:53:25,981 INFO [localhost-startStop-1] [lifecycle] Starting Bamboo 6.9.1 (build #60910 Tue Jun 04 09:21:13 EEST 2019) using Java 1.8.0_211 from Oracle Corporation
2019-06-10 21:53:25,981 INFO [localhost-startStop-1] [lifecycle] Real path of servlet context: C:\Program Files\Bamboo\atlassian-bamboo\
2019-06-10 21:53:26,122 INFO [localhost-startStop-1] [lifecycle] Bamboo home directory: D:\Atlassian\Bamboo
2019-06-10 21:53:26,122 INFO [localhost-startStop-1] [lifecycle] Default charset: windows-1252, file name ecoding: Cp1252
2019-06-10 21:53:26,137 INFO [localhost-startStop-1] [UpgradeLauncher] Upgrades not performed since the application has not been set up yet.
2019-06-10 21:53:26,153 INFO [localhost-startStop-1] [CompressingFilter] CompressingFilter has initialized
2019-06-10 21:53:26,809 INFO [localhost-startStop-1] [SpringObjectFactoryProxy] Deferring initialisation of com.atlassian.bamboo.plugin.xwork.SpringObjectFactoryProxy
2019-06-10 21:53:27,731 INFO [localhost-startStop-1] [Dispatchers] Dispatcher org.apache.struts2.dispatcher.Dispatcher initialised
2019-06-10 21:53:27,794 INFO [localhost-startStop-1] [SpringObjectFactoryProxy] Deferring initialisation of com.atlassian.bamboo.plugin.xwork.SpringObjectFactoryProxy
2019-06-10 21:53:28,465 INFO [localhost-startStop-1] [AccessLogFilter] AccessLogFilter initialized. Format is: <user> <url> <starting memory free (kb)> +- <difference in free mem (kb)> <query time (ms)>
2019-06-10 21:53:28,544 INFO [localhost-startStop-1] [SpringObjectFactoryProxy] Deferring initialisation of com.atlassian.bamboo.plugin.xwork.SpringObjectFactoryProxy
2019-06-10 21:53:28,622 INFO [localhost-startStop-1] [Dispatchers] Dispatcher org.apache.struts2.dispatcher.Dispatcher initialised
2019-06-10 21:53:28,669 INFO [localhost-startStop-1] [SpringObjectFactoryProxy] Deferring initialisation of com.atlassian.bamboo.plugin.xwork.SpringObjectFactoryProxy
2019-06-10 21:53:56,414 INFO [http-nio-8085-exec-1] [JohnsonFilter] The application is not yet setup. Redirecting request from '/start.action' to '/bootstrap/selectSetupStep.action'
2019-06-10 21:53:56,428 INFO [http-nio-8085-exec-2] [AccessLogFilter] 127.0.0.1 GET http://127.0.0.1:8085/bootstrap/selectSetupStep.action 585694kb
2019-06-10 21:53:56,756 INFO [http-nio-8085-exec-2] [SelectSetupStepAction] Current setup step is /setup/setupDatabase.action
2019-06-10 21:53:56,788 INFO [http-nio-8085-exec-3] [AccessLogFilter] 127.0.0.1 GET http://127.0.0.1:8085/setup/setupDatabase.action 445080kb
2019-06-10 21:53:57,723 INFO [http-nio-8085-exec-4] [AccessLogFilter] 127.0.0.1 GET http://127.0.0.1:8085/bootstrap/selectSetupStep.action 454932kb
2019-06-10 21:53:57,733 INFO [http-nio-8085-exec-4] [SelectSetupStepAction] Current setup step is /setup/setupDatabase.action
2019-06-10 21:53:57,919 WARN [http-nio-8085-exec-3] [TextProviderHelper] The first TextProvider in the ValueStack (com.atlassian.bamboo.ww2.actions.setup.SetupDatabaseAction) could not locate the message resource with key 'help.prefix'
2019-06-10 21:53:57,920 WARN [http-nio-8085-exec-3] [TextProviderHelper] The default value expression 'help.prefix' was evaluated and did not match a property. The literal value 'help.prefix' will be used.
2019-06-10 21:53:57,922 WARN [http-nio-8085-exec-3] [TextProviderHelper] The first TextProvider in the ValueStack (com.atlassian.bamboo.ww2.actions.setup.SetupDatabaseAction) could not locate the message resource with key 'db.mysql'
2019-06-10 21:53:57,922 WARN [http-nio-8085-exec-3] [TextProviderHelper] The default value expression 'db.mysql' was evaluated and did not match a property. The literal value 'db.mysql' will be used.
2019-06-10 21:54:12,597 INFO [http-nio-8085-exec-5] [AccessLogFilter] 127.0.0.1 POST http://127.0.0.1:8085/setup/chooseDatabaseType.action 568048kb
2019-06-10 21:54:12,706 INFO [http-nio-8085-exec-6] [AccessLogFilter] 127.0.0.1 GET http://127.0.0.1:8085/setup/setupDatabaseConnection.action?selectedDatabase=mssql 513189kb
2019-06-10 21:54:12,873 WARN [http-nio-8085-exec-6] [TextProviderHelper] The first TextProvider in the ValueStack (com.atlassian.bamboo.ww2.actions.setup.SetupDatabaseConnectionAction) could not locate the message resource with key 'help.prefix'
2019-06-10 21:54:12,874 WARN [http-nio-8085-exec-6] [TextProviderHelper] The default value expression 'help.prefix' was evaluated and did not match a property. The literal value 'help.prefix' will be used.
2019-06-10 21:54:12,875 WARN [http-nio-8085-exec-6] [TextProviderHelper] The first TextProvider in the ValueStack (com.atlassian.bamboo.ww2.actions.setup.SetupDatabaseConnectionAction) could not locate the message resource with key 'db.mysql'
2019-06-10 21:54:12,875 WARN [http-nio-8085-exec-6] [TextProviderHelper] The default value expression 'db.mysql' was evaluated and did not match a property. The literal value 'db.mysql' will be used.
2019-06-10 22:03:22,158 INFO [http-nio-8085-exec-9] [AccessLogFilter] 127.0.0.1 POST http://127.0.0.1:8085/setup/performSetupDatabaseConnection.action 439256kb
2019-06-10 22:03:22,260 INFO [performSetupDatabaseConnectionBackgroundThread] [SetupDatabaseConnectionAction] Validating database configuration...
2019-06-10 22:03:22,330 INFO [performSetupDatabaseConnectionBackgroundThread] [SetupDatabaseConnectionAction] Validating connection to url: [jdbc:sqlserver://10.0.0.100:1433;databaseName=BambooDB]
2019-06-10 22:03:22,726 INFO [performSetupDatabaseConnectionBackgroundThread] [SetupDatabaseConnectionAction] Database configuration is valid.
2019-06-10 22:03:22,726 INFO [performSetupDatabaseConnectionBackgroundThread] [SetupUtilityBean] Setting up database: mssql
2019-06-10 22:03:22,726 INFO [performSetupDatabaseConnectionBackgroundThread] [SetupUtilityBean] Bootstrapping database: mssql
2019-06-10 22:03:22,726 INFO [performSetupDatabaseConnectionBackgroundThread] [DefaultHibernateConfigurator] Saving bootstrap configuration...
2019-06-10 22:03:22,754 INFO [performSetupDatabaseConnectionBackgroundThread] [DefaultHibernateConfigurator] Refreshing Spring context...
2019-06-10 22:03:24,305 WARN [http-nio-8085-exec-9] [TokenHelper] Could not find token mapped to token name: token
2019-06-10 22:03:25,503 WARN [performSetupDatabaseConnectionBackgroundThread] [GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'mappingResources' being accessed! Ambiguous write methods found next to actually used [public void bucket.core.persistence.hibernate.ConfigurableLocalSessionFactoryBean.setMappingResources(bucket.core.persistence.hibernate.MappingResources)]: [public void org.springframework.orm.hibernate5.LocalSessionFactoryBean.setMappingResources(java.lang.String[])]
2019-06-10 22:03:26,596 INFO [http-nio-8085-exec-10] [AccessLogFilter] 127.0.0.1 POST http://127.0.0.1:8085/setup/performSetupDatabaseConnection.action 501373kb
2019-06-10 22:03:27,222 INFO [MLog-Init-Reporter] [MLog] MLog clients using slf4j logging.
2019-06-10 22:03:27,335 INFO [performSetupDatabaseConnectionBackgroundThread] [C3P0Registry] Initializing c3p0-0.9.5.3 [built 27-January-2019 00:11:37 -0800; debug? true; trace: 10]
2019-06-10 22:03:27,459 INFO [performSetupDatabaseConnectionBackgroundThread] [AbstractPoolBackedDataSource] Initializing c3p0 pool... com.mchange.v2.c3p0.PoolBackedDataSource@868677c6 [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@de2a3b1d [ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, contextClassLoaderSource -> caller, debugUnreturnedConnectionStackTraces -> false, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, forceSynchronousCheckins -> false, identityToken -> 2rvxxwa3iizgoo1dxdjmy|9923a18, idleConnectionTestPeriod -> 30, initialPoolSize -> 3, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 120, maxIdleTimeExcessConnections -> 0, maxPoolSize -> 100, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 3, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@976c09f7 [ description -> null, driverClass -> null, factoryClassLocation -> null, forceUseNamedDriverClass -> false, identityToken -> 2rvxxwa3iizgoo1dxdjmy|121f4e51, jdbcUrl -> jdbc:sqlserver://10.0.0.100:1433;databaseName=BambooDB, properties -> {handling_mode=DELAYED_ACQUISITION_AND_HOLD, user=******, password=******} ], preferredTestQuery -> null, privilegeSpawnedThreads -> false, propertyCycle -> 0, statementCacheNumDeferredCloseThreads -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false; userOverrides: {} ], dataSourceName -> null, extensions -> {}, factoryClassLocation -> null, identityToken -> 2rvxxwa3iizgoo1dxdjmy|257fc1c7, numHelperThreads -> 3 ]
2019-06-10 22:03:28,394 INFO [performSetupDatabaseConnectionBackgroundThread] [ResettableTableHiLoGenerator] Registering exportables...
2019-06-10 22:03:28,397 INFO [performSetupDatabaseConnectionBackgroundThread] [ResettableTableHiLoGenerator] Generator table: org.hibernate.mapping.Table(hibernate_unique_key)
2019-06-10 22:03:28,640 INFO [http-nio-8085-exec-11] [AccessLogFilter] 127.0.0.1 POST http://127.0.0.1:8085/setup/performSetupDatabaseConnection.action 400167kb
2019-06-10 22:03:29,172 WARN [performSetupDatabaseConnectionBackgroundThread] [cache] HHH90001006: Missing cache[default-update-timestamps-region] was created on-the-fly. The created cache will use a provider-specific default configuration: make sure you defined one. You can disable this warning by setting 'hibernate.cache.ehcache.missing_cache_strategy' to 'create'.
2019-06-10 22:03:29,176 WARN [performSetupDatabaseConnectionBackgroundThread] [cache] HHH90001006: Missing cache[default-query-results-region] was created on-the-fly. The created cache will use a provider-specific default configuration: make sure you defined one. You can disable this warning by setting 'hibernate.cache.ehcache.missing_cache_strategy' to 'create'.
2019-06-10 22:03:29,257 WARN [performSetupDatabaseConnectionBackgroundThread] [cache] HHH90001006: Missing cache[bucket.user.propertyset.BucketPropertySetItem] was created on-the-fly. The created cache will use a provider-specific default configuration: make sure you defined one. You can disable this warning by setting 'hibernate.cache.ehcache.missing_cache_strategy' to 'create'.
2019-06-10 22:03:29,886 ERROR [performSetupDatabaseConnectionBackgroundThread] [PojoEntityTuplizer] HHH000243: Setters of lazy classes cannot be final: com.atlassian.bamboo.plan.AbstractChain.notificationSet
2019-06-10 22:03:30,648 INFO [http-nio-8085-exec-12] [AccessLogFilter] 127.0.0.1 POST http://127.0.0.1:8085/setup/performSetupDatabaseConnection.action 515870kb
2019-06-10 22:03:30,971 ERROR [performSetupDatabaseConnectionBackgroundThread] [MetadataContext] HHH015007: Illegal argument on static metamodel field injection : com.atlassian.bamboo.resultsummary.AbstractResultsSummary_#testClassResults; expected type : org.hibernate.metamodel.internal.PluralAttributeImpl$SetAttributeImpl; encountered type : javax.persistence.metamodel.ListAttribute
2019-06-10 22:03:30,975 ERROR [performSetupDatabaseConnectionBackgroundThread] [MetadataContext] HHH015007: Illegal argument on static metamodel field injection : com.atlassian.bamboo.agent.elastic.server.ElasticImageConfigurationImpl_#subnetIds; expected type : org.hibernate.metamodel.internal.SingularAttributeImpl; encountered type : javax.persistence.metamodel.CollectionAttribute
2019-06-10 22:03:30,975 ERROR [performSetupDatabaseConnectionBackgroundThread] [MetadataContext] HHH015007: Illegal argument on static metamodel field injection : com.atlassian.bamboo.agent.elastic.server.ElasticImageConfigurationImpl_#availabilityZones; expected type : org.hibernate.metamodel.internal.SingularAttributeImpl; encountered type : javax.persistence.metamodel.CollectionAttribute
2019-06-10 22:03:30,988 ERROR [performSetupDatabaseConnectionBackgroundThread] [MetadataContext] HHH015007: Illegal argument on static metamodel field injection : com.atlassian.bamboo.project.DefaultProject_#allPlans; expected type : org.hibernate.metamodel.internal.PluralAttributeImpl$CollectionAttributeImpl; encountered type : javax.persistence.metamodel.ListAttribute
2019-06-10 22:03:31,032 ERROR [performSetupDatabaseConnectionBackgroundThread] [MetadataContext] HHH015007: Illegal argument on static metamodel field injection : com.atlassian.bamboo.commit.CommitImpl_#files; expected type : org.hibernate.metamodel.internal.PluralAttributeImpl$SetAttributeImpl; encountered type : javax.persistence.metamodel.ListAttribute
2019-06-10 22:03:31,054 ERROR [performSetupDatabaseConnectionBackgroundThread] [MetadataContext] HHH015007: Illegal argument on static metamodel field injection : com.atlassian.bamboo.resultsummary.tests.TestClassImpl_#testCases; expected type : org.hibernate.metamodel.internal.PluralAttributeImpl$SetAttributeImpl; encountered type : javax.persistence.metamodel.ListAttribute
2019-06-10 22:03:32,669 INFO [http-nio-8085-exec-14] [AccessLogFilter] 127.0.0.1 POST http://127.0.0.1:8085/setup/performSetupDatabaseConnection.action 425427kb
2019-06-10 22:03:32,859 INFO [performSetupDatabaseConnectionBackgroundThread] [BrokerService] Loaded the Bouncy Castle security provider.
2019-06-10 22:03:32,981 INFO [performSetupDatabaseConnectionBackgroundThread] [BrokerService] Using Persistence Adapter: KahaDBPersistenceAdapter[D:\Atlassian\Bamboo\jms-store\bamboo\KahaDB]
2019-06-10 22:03:33,085 INFO [performSetupDatabaseConnectionBackgroundThread] [MessageDatabase] Persistence store purged.
2019-06-10 22:03:33,904 INFO [performSetupDatabaseConnectionBackgroundThread] [PListStoreImpl] PListStore:[D:\Atlassian\Bamboo\jms-store\bamboo\tmp_storage] started
2019-06-10 22:03:34,038 INFO [performSetupDatabaseConnectionBackgroundThread] [BrokerService] Apache ActiveMQ 5.15.8 (bamboo, ID:SRV-CI-49273-1560193413911-0:1) is starting

[Daniel Santos]

Hi @YildizNet App

Thank you for raising this one to try clarifying this exception.

The exceptions you see are will cause no harm in your instance, they can be ignored. They happen due to an incorrect metamodel for some Bamboo autogenerated classes. You can see this in more details here: 

• [BAM-18777] Hibernate generates incorrect metamodel which causes error level log messages on start-up
  

Please let me know if you see any other errors after the ones shared and if your instance is working as expected.