2019.10.10 17:07:11 INFO  web[][o.s.p.ProcessEntryPoint] Starting web
2019.10.10 17:07:11 INFO  web[][o.a.t.u.n.NioSelectorPool] Using a shared selector for servlet write/read
2019.10.10 17:07:11 INFO  web[][o.e.p.PluginsService] no modules loaded
2019.10.10 17:07:11 INFO  web[][o.e.p.PluginsService] loaded plugin [org.elasticsearch.join.ParentJoinPlugin]
2019.10.10 17:07:11 INFO  web[][o.e.p.PluginsService] loaded plugin [org.elasticsearch.percolator.PercolatorPlugin]
2019.10.10 17:07:11 INFO  web[][o.e.p.PluginsService] loaded plugin [org.elasticsearch.transport.Netty4Plugin]
2019.10.10 17:07:12 INFO  web[][o.s.s.e.EsClientProvider] Connected to local Elasticsearch: [127.0.0.1:9001]
2019.10.10 17:07:12 INFO  web[][o.s.s.p.LogServerVersion] SonarQube Server / 7.9.1.27448 / e0c45c97bb2e45d52629f3422ac91718020f6db9
2019.10.10 17:07:12 INFO  web[][o.sonar.db.Database] Create JDBC data source for jdbc:postgresql://localhost/sonarqube
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerFileSystemImpl] SonarQube home: /home/sonar/sonarqube-7.9.1
2019.10.10 17:07:13 INFO  web[][o.s.s.u.SystemPasscodeImpl] System authentication by passcode is disabled
2019.10.10 17:07:13 INFO  web[][o.s.s.p.d.m.h.MigrationHistoryTableImpl] Creating table schema_migrations
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin Git / 1.8.0.1574 / aec3dc8f5228aabd218e1cd31ac6e6515a43715d
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin GitHub Authentication for SonarQube / 1.5.0.870 / 153f7c7af7a264adb0fcbe5fee87bdd140a6a3a1
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin JaCoCo / 1.0.2.475 / b79a4724f3a9af1051266b4f8ca0460977295ead
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin LDAP / 2.2.0.608 / 79dc3fa4393a29667673c70182f3016288b548b7
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SAML 2.0 Authentication for SonarQube / 1.1.0.181 / 16fcb5be2d3bcfee8920b29cd758d8b957a18acb
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarC# / 7.15.0.8572 / e0ad49e38a28a8fc333ba746fc998e48678f6a8b
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarCSS / 1.1.1.1010 / 365e21fd0cb9035669fc59f6fec7c8fd28a7303b
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarFlex / 2.5.1.1831 / a0c44437f6abb0feec76edd073f91fec64db2a6c
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarGo / 1.1.1.2000 / 40d55921c7a63b67386a053490d17b6723a46cd5
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarHTML / 3.1.0.1615 / 4181edb5eff5605bec82dc0aa15ecd70eaa5857f
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarJS / 5.2.1.7778 / 49f34eaeaad59868d4353d89b1fc5c02bbe51976
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarJava / 5.13.1.18282 / 568f8ed2349f48e250a9329895b9a870100dfbeb
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarKotlin / 1.5.0.315 / 4ff3a145a58f3f84f1b39846a205a129d742e993
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarPHP / 3.2.0.4868 / ec66bd5f8490677eb0ebae82aa17c2a5d9c0e5e7
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarPython / 1.14.1.3143 / eed7b315b6116fe462a19c771013bf3891c92a97
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarRuby / 1.5.0.315 / 4ff3a145a58f3f84f1b39846a205a129d742e993
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarScala / 1.5.0.315 / 4ff3a145a58f3f84f1b39846a205a129d742e993
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarTS / 1.9.0.3766 / 4a4080b78001a78d758d1d0fa0190fb9496b6f57
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarVB / 7.15.0.8572 / e0ad49e38a28a8fc333ba746fc998e48678f6a8b
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin SonarXML / 2.0.1.2020 / c5b84004face582d56f110e24c29bf9c6a679e69
2019.10.10 17:07:13 INFO  web[][o.s.s.p.ServerPluginRepository] Deploy plugin Svn / 1.9.0.1295 / 942e075773975354e32691a60bfd968065703e04
2019.10.10 17:07:13 INFO  web[][o.s.s.p.d.m.c.PostgresCharsetHandler] Verify that database charset supports UTF8
2019.10.10 17:07:13 INFO  web[][o.s.s.p.d.m.AutoDbMigration] Automatically perform DB migration on fresh install
2019.10.10 17:07:13 INFO  web[][DbMigrations] Executing DB migrations...
2019.10.10 17:07:13 INFO  web[][DbMigrations] #1 'Create initial schema'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1 'Create initial schema': success | time=764ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #2 'Populate initial schema'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #2 'Populate initial schema': success | time=75ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1153 'Populate columns USERS.EXTERNAL_IDENTITY_*'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 users processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1153 'Populate columns USERS.EXTERNAL_IDENTITY_*': success | time=18ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1200 'Create table PERM_TPL_CHARACTERISTICS'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1200 'Create table PERM_TPL_CHARACTERISTICS': success | time=19ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1205 'Drop index resource_index_rid from RESOURCE_INDEX'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1205 'Drop index resource_index_rid from RESOURCE_INDEX': success | time=4ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1207 'Drop unused columns on PROJECT_MEASURES'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1207 'Drop unused columns on PROJECT_MEASURES': success | time=5ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1208 'Add columns SNAPSHOTS.*COMPONENT_UUID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1208 'Add columns SNAPSHOTS.*COMPONENT_UUID': success | time=4ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1209 'Populate column SNAPSHOTS.*COMPONENT_UUID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1209 'Populate column SNAPSHOTS.*COMPONENT_UUID': success | time=4ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1210 'Create temporary indices for migration 1211'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1210 'Create temporary indices for migration 1211': success | time=15ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1211 'Clean orphan rows in SNAPSHOTS'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 snapshots processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1211 'Clean orphan rows in SNAPSHOTS': success | time=8ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1212 'Drop temporary indices for migration 1211'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1212 'Drop temporary indices for migration 1211': success | time=6ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1213 'Make column SNAPSHOTS.UUID not nullable'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1213 'Make column SNAPSHOTS.UUID not nullable': success | time=20ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1214 'Drop columns SNAPSHOTS.SNAPSHOT_*_ID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1214 'Drop columns SNAPSHOTS.SNAPSHOT_*_ID': success | time=10ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1215 'Drop columns SNAPSHOTS.*_ID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1215 'Drop columns SNAPSHOTS.*_ID': success | time=6ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1216 'Add column PROJECT_MEASURES.COMPONENT_UUID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1216 'Add column PROJECT_MEASURES.COMPONENT_UUID': success | time=4ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1217 'Populate column PROJECT_MEASURES.COMPONENT_UUID'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 measures processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1217 'Populate column PROJECT_MEASURES.COMPONENT_UUID': success | time=5ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1218 'Delete orphan measures without component'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 measures processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1218 'Delete orphan measures without component': success | time=4ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1219 'Make column PROJECT_MEASURES.COMPONENT_UUID not nullable'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1219 'Make column PROJECT_MEASURES.COMPONENT_UUID not nullable': success | time=2ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1220 'Drop column PROJECT_MEASURES.PROJECT_ID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1220 'Drop column PROJECT_MEASURES.PROJECT_ID': success | time=3ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1221 'Add index measures_component_uuid'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1221 'Add index measures_component_uuid': success | time=5ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1222 'Drop columns USERS.REMEMBER_TOKEN_*'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1222 'Drop columns USERS.REMEMBER_TOKEN_*': success | time=3ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1223 'Clean orphan rows and fix incorrect data in table PROJECTS'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 developers with incorrect project_uuid processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 snapshots with inconsistent scope or qualifier processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 usurper root components processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 snapshots with incorrect root processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1223 'Clean orphan rows and fix incorrect data in table PROJECTS': success | time=8ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1224 'Add columns PROJECTS.*_UUID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1224 'Add columns PROJECTS.*_UUID': success | time=8ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1225 'Populate columns PROJECTS.*_UUID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1225 'Populate columns PROJECTS.*_UUID': success | time=13ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1226 'Clean orphan rows in table PROJECTS'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphan projects processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1226 'Clean orphan rows in table PROJECTS': success | time=9ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1227 'Drop index projects_uuid'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1227 'Drop index projects_uuid': success | time=7ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1228 'Make columns PROJECTS.*_UUID not nullable'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1228 'Make columns PROJECTS.*_UUID not nullable': success | time=12ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1229 'Recreate index projects_uuid'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1229 'Recreate index projects_uuid': success | time=9ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1230 'Drop index projects_root_id'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1230 'Drop index projects_root_id': success | time=7ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1231 'Drop columns PROJECTS.*_ID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1231 'Drop columns PROJECTS.*_ID': success | time=5ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1232 'Add column SNAPSHOTS.UUID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1232 'Add column SNAPSHOTS.UUID': success | time=4ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1233 'Populate column SNAPSHOTS.UUID'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 snapshots processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1233 'Populate column SNAPSHOTS.UUID': success | time=5ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1234 'Make column SNAPSHOTS.UUID not nullable'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1234 'Make column SNAPSHOTS.UUID not nullable': success | time=3ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1235 'Add unique index analyses_uuid'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1235 'Add unique index analyses_uuid': success | time=6ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1236 'Add column CE_ACTIVITY.ANALYSIS_UUID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1236 'Add column CE_ACTIVITY.ANALYSIS_UUID': success | time=2ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1237 'Populate column CE_ACTIVITY.ANALYSIS_UUID'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 ce_activity processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1237 'Populate column CE_ACTIVITY.ANALYSIS_UUID': success | time=4ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1238 'Drop column CE_ACTIVITY.SNAPSHOT_ID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1238 'Drop column CE_ACTIVITY.SNAPSHOT_ID': success | time=2ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1239 'Add columns DUPLICATION_INDEX.*_UUID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1239 'Add columns DUPLICATION_INDEX.*_UUID': success | time=3ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1240 'Populate columns DUPLICATION_INDEX.*_UUID'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 component uuid of duplications_index entries processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 analysis uuid of duplications_index entries processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1240 'Populate columns DUPLICATION_INDEX.*_UUID': success | time=4ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1241 'Clean orphan rows in table DUPLICATION_INDEX'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 duplications index rows without component processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 duplications index rows without analysis processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1241 'Clean orphan rows in table DUPLICATION_INDEX': success | time=3ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1242 'Make columns DUPLICATION_INDEX.*_UUID not nullable'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1242 'Make columns DUPLICATION_INDEX.*_UUID not nullable': success | time=7ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1243 'Drop index duplications_index_sid'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1243 'Drop index duplications_index_sid': success | time=3ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1244 'Drop columns DUPLICATION_INDEX.*SNAPSHOT_ID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1244 'Drop columns DUPLICATION_INDEX.*SNAPSHOT_ID': success | time=3ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1246 'Add column RULES_PROFILES.LAST_USED'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1246 'Add column RULES_PROFILES.LAST_USED': success | time=2ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1247 'Populate column RULES_PROFILES.LAST_USED'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1247 'Populate column RULES_PROFILES.LAST_USED': success | time=3ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1248 'Add column EVENTS.ANALYSIS_UUID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1248 'Add column EVENTS.ANALYSIS_UUID': success | time=3ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1249 'Populate column EVENTS.ANALYSIS_UUID'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 analysis uuid of root component events processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1249 'Populate column EVENTS.ANALYSIS_UUID': success | time=2ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1250 'Clean events without analysis_uuid'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 no analysis uuid events processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1250 'Clean events without analysis_uuid': success | time=2ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1251 'Clean events without snapshot_id'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 no snapshot id events processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1251 'Clean events without snapshot_id': success | time=4ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1252 'Make column EVENTS.ANALYSIS_UUID not nullable'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1252 'Make column EVENTS.ANALYSIS_UUID not nullable': success | time=10ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1253 'Drop index events_snapshot_id'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1253 'Drop index events_snapshot_id': success | time=5ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1254 'Drop columns EVENTS.SNAPSHOT_ID'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1254 'Drop columns EVENTS.SNAPSHOT_ID': success | time=5ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1256 'Add column PROJECTS.UUID_PATH'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1256 'Add column PROJECTS.UUID_PATH': success | time=5ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1257 'Populate column PROJECTS.UUID_PATH'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphan components processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1257 'Populate column PROJECTS.UUID_PATH': success | time=9ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1258 'Make column PROJECTS.UUID_PATH not nullable'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1258 'Make column PROJECTS.UUID_PATH not nullable': success | time=7ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1259 'Remove password of non local users'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 none local users with password processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1259 'Remove password of non local users': success | time=9ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1260 'Add column ACTIVITIES.PROFILE_KEY'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1260 'Add column ACTIVITIES.PROFILE_KEY': success | time=7ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1261 'Populate column ACTIVITIES.PROFILE_KEY'...
2019.10.10 17:07:14 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 activities processed (0 items/sec)
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1261 'Populate column ACTIVITIES.PROFILE_KEY': success | time=7ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1262 'Make column ACTIVITIES.PROFILE_KEY not nullable'...
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1262 'Make column ACTIVITIES.PROFILE_KEY not nullable': success | time=4ms
2019.10.10 17:07:14 INFO  web[][DbMigrations] #1263 'Add column RULES_PROFILES.USER_UPDATED_AT'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1263 'Add column RULES_PROFILES.USER_UPDATED_AT': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1264 'Populate column RULES_PROFILES.USER_UPDATED_AT'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 quality profiles processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1264 'Populate column RULES_PROFILES.USER_UPDATED_AT': success | time=8ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1265 'Add column PROJECT_MEASURES.ANALYSIS_UUID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1265 'Add column PROJECT_MEASURES.ANALYSIS_UUID': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1266 'Add index measures_analysis_metric'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1266 'Add index measures_analysis_metric': success | time=8ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1267 'Populate column PROJECT_MEASURES.ANALYSIS_UUID'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 measures processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1267 'Populate column PROJECT_MEASURES.ANALYSIS_UUID': success | time=10ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1268 'Clean orphan measures'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 measures processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1268 'Clean orphan measures': success | time=7ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1269 'Temporary drop of index measures_analysis_metric'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1269 'Temporary drop of index measures_analysis_metric': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1270 'Make column PROJECT_MEASURES.ANALYSIS_UUID not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1270 'Make column PROJECT_MEASURES.ANALYSIS_UUID not nullable': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1271 'Restore index measures_analysis_metric'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1271 'Restore index measures_analysis_metric': success | time=10ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1272 'Delete snapshots but the one of root components'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 snapshots processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1272 'Delete snapshots but the one of root components': success | time=7ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1273 'Drop indices on tree columns of table SNAPSHOTS'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1273 'Drop indices on tree columns of table SNAPSHOTS': success | time=13ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1274 'Drop tree columns of table SNAPSHOTS'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1274 'Drop tree columns of table SNAPSHOTS': success | time=7ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1275 'Drop index measures_sid_metric'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1275 'Drop index measures_sid_metric': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1276 'Drop column PROJECT_MEASURES.SNAPSHOT_ID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1276 'Drop column PROJECT_MEASURES.SNAPSHOT_ID': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1277 'Add columns PROJECTS.B_*'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1277 'Add columns PROJECTS.B_*': success | time=7ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1300 'Delete project dashboards and widgets'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 delete widget properties of project dashboards processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 delete project dashboards processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1300 'Delete project dashboards and widgets': success | time=11ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1301 'Drop column DASHBOARDS.IS_GLOBAL'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1301 'Drop column DASHBOARDS.IS_GLOBAL': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1302 'Create table CE_TASK_INPUT'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1302 'Create table CE_TASK_INPUT': success | time=14ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1303 'Clear CE_QUEUE content'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1303 'Clear CE_QUEUE content': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1304 'Shrink column PROJECTS.MODULE_UUID_PATH'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1304 'Shrink column PROJECTS.MODULE_UUID_PATH': success | time=38ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1307 'Add columns CE_ACTIVITY.ERROR_*'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1307 'Add columns CE_ACTIVITY.ERROR_*': success | time=10ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1309 'Create table CE_SCANNER_CONTEXT'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1309 'Create table CE_SCANNER_CONTEXT': success | time=13ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1310 'Create table INTERNAL_PROPERTIES'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1310 'Create table INTERNAL_PROPERTIES': success | time=13ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1311 'Move views config from PROPERTIES to INTERNAL_PROPERTIES'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1311 'Move views config from PROPERTIES to INTERNAL_PROPERTIES': success | time=10ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1312 'Create table PROPERTIES2'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1312 'Create table PROPERTIES2': success | time=21ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1313 'Populate table PROPERTIES2'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 copy data from table properties into table properties2 processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1313 'Populate table PROPERTIES2': success | time=9ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1314 'Drop table PROPERTIES'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1314 'Drop table PROPERTIES': success | time=10ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1315 'Rename table PROPERTIES2 to PROPERTIES'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1315 'Rename table PROPERTIES2 to PROPERTIES': success | time=15ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1316 'Create table QPROFILE_CHANGES'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1316 'Create table QPROFILE_CHANGES': success | time=14ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1317 'Populate table QPROFILE_CHANGES'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 activities processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1317 'Populate table QPROFILE_CHANGES': success | time=8ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1318 'Drop table ACTIVITIES'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1318 'Drop table ACTIVITIES': success | time=10ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1319 'Create table RULE_REPOSITORIES'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1319 'Create table RULE_REPOSITORIES': success | time=15ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1400 'Create table ORGANIZATIONS'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1400 'Create table ORGANIZATIONS': success | time=19ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1401 'Create default organization'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1401 'Create default organization': success | time=15ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1402 'Delete permission shareDashboard'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 1 group_roles processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 user_roles processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1402 'Delete permission shareDashboard': success | time=13ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1403 'Add column GROUPS.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1403 'Add column GROUPS.ORGANIZATION_UUID': success | time=7ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1404 'Add column USERS.IS_ROOT'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1404 'Add column USERS.IS_ROOT': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1405 'Populate column USERS.IS_ROOT'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 1 Users with System Administer permission as root processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1405 'Populate column USERS.IS_ROOT': success | time=16ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1406 'Make column USERS.IS_ROOT not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1406 'Make column USERS.IS_ROOT not nullable': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1407 'Populate column GROUPS.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 2 groups processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1407 'Populate column GROUPS.ORGANIZATION_UUID': success | time=15ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1408 'Make column GROUPS.ORGANIZATION_UUID not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1408 'Make column GROUPS.ORGANIZATION_UUID not nullable': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1409 'Add column USER_ROLES.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1409 'Add column USER_ROLES.ORGANIZATION_UUID': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1410 'Populate column USER_ROLES.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 user_roles processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1410 'Populate column USER_ROLES.ORGANIZATION_UUID': success | time=9ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1411 'Make column USER_ROLES.ORGANIZATION_UUID not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1411 'Make column USER_ROLES.ORGANIZATION_UUID not nullable': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1412 'Add column PERMISSION_TEMPLATES.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1412 'Add column PERMISSION_TEMPLATES.ORGANIZATION_UUID': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1413 'Populate column PERMISSION_TEMPLATES.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 permission_templates processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1413 'Populate column PERMISSION_TEMPLATES.ORGANIZATION_UUID': success | time=10ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1414 'Make column PERMISSION_TEMPLATES.ORGANIZATION_UUID not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1414 'Make column PERMISSION_TEMPLATES.ORGANIZATION_UUID not nullable': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1415 'Add column GROUP_ROLES.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1415 'Add column GROUP_ROLES.ORGANIZATION_UUID': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1416 'Populate column GROUP_ROLES.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 6 group_roles processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1416 'Populate column GROUP_ROLES.ORGANIZATION_UUID': success | time=14ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1417 'Make column GROUP_ROLES.ORGANIZATION_UUID not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1417 'Make column GROUP_ROLES.ORGANIZATION_UUID not nullable': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1418 'Add ORGANIZATION_UUID to index uniq_group_roles'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1418 'Add ORGANIZATION_UUID to index uniq_group_roles': success | time=13ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1419 'Update qualigate conditions on coverage'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1419 'Update qualigate conditions on coverage': success | time=10ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1420 'Drop tables related to dashboards'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.v.v.DropRelatedDashboardTables] Removing tables [widget_properties, widgets, active_dashboards, dashboards]
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1420 'Drop tables related to dashboards': success | time=22ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1421 'Drop tables related to measure filters'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.v.v.DropMeasureFiltersTables] Removing tables [measure_filters, measure_filter_favourites]
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1421 'Drop tables related to measure filters': success | time=14ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1422 'Drop tables related to issue filters'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.v.v.DropIssueFiltersTables] Removing tables [issue_filters, issue_filter_favourites]
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1422 'Drop tables related to issue filters': success | time=13ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1423 'Create table WEBHOOK_DELIVERIES'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1423 'Create table WEBHOOK_DELIVERIES': success | time=25ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1500 'Add Events.UUID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1500 'Add Events.UUID': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1501 'Populate Events.UUID'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 events processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1501 'Populate Events.UUID': success | time=7ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1502 'Make Events.UUID not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1502 'Make Events.UUID not nullable': success | time=11ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1503 'Add PROJECTS.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1503 'Add PROJECTS.ORGANIZATION_UUID': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1504 'Populate PROJECTS.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 project trees processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 components processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1504 'Populate PROJECTS.ORGANIZATION_UUID': success | time=14ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1505 'Make PROJECTS.ORGANIZATION_UUID not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1505 'Make PROJECTS.ORGANIZATION_UUID not nullable': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1506 'Add index on PROJECTS.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1506 'Add index on PROJECTS.ORGANIZATION_UUID': success | time=8ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1507 'Drop table RESOURCE_INDEX'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1507 'Drop table RESOURCE_INDEX': success | time=29ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1508 'Add columns ORGANIZATIONS.DEFAULT_PERM_TEMPLATE_*'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1508 'Add columns ORGANIZATIONS.DEFAULT_PERM_TEMPLATE_*': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1509 'Populate columns ORGANIZATIONS.DEFAULT_PERM_TEMPLATE_*'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1509 'Populate columns ORGANIZATIONS.DEFAULT_PERM_TEMPLATE_*': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1510 'Add ORGANIZATIONS.GUARDED'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1510 'Add ORGANIZATIONS.GUARDED': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1511 'Populate ORGANIZATIONS.GUARDED'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 1 organizations processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1511 'Populate ORGANIZATIONS.GUARDED': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1512 'Make ORGANIZATIONS.GUARDED not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1512 'Make ORGANIZATIONS.GUARDED not nullable': success | time=4ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1513 'Make default organization guarded'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1513 'Make default organization guarded': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1514 'Delete some entries in PROPERTIES'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 useless settings processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1514 'Delete some entries in PROPERTIES': success | time=4ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1515 'Unset user root flags'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1515 'Unset user root flags': success | time=2ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1516 'Add ORGANIZATIONS.USER_ID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1516 'Add ORGANIZATIONS.USER_ID': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1517 'Delete PROJECT_MEASURES rows having no value'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 measures processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1517 'Delete PROJECT_MEASURES rows having no value': success | time=2ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1518 'Make index on ORGANIZATIONS.KEE unique'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1518 'Make index on ORGANIZATIONS.KEE unique': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1600 'Add PROJECTS.TAGS'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1600 'Add PROJECTS.TAGS': success | time=2ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1601 'Set PROJECTS.COPY_COMPONENT_UUID on local views'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1601 'Set PROJECTS.COPY_COMPONENT_UUID on local views': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1602 'Add RULES_PROFILES.ORGANIZATION_UUID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1602 'Add RULES_PROFILES.ORGANIZATION_UUID': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1603 'Set RULES_PROFILES.ORGANIZATION_UUID to default'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1603 'Set RULES_PROFILES.ORGANIZATION_UUID to default': success | time=2ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1604 'Make RULES_PROFILES.ORGANIZATION_UUID not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1604 'Make RULES_PROFILES.ORGANIZATION_UUID not nullable': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1605 'Drop unique index on RULES_PROFILES.KEE'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1605 'Drop unique index on RULES_PROFILES.KEE': success | time=2ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1606 'Make RULES_PROFILES.ORGANIZATION_UUID and KEE unique'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1606 'Make RULES_PROFILES.ORGANIZATION_UUID and KEE unique': success | time=4ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1607 'Create ORGANIZATION_MEMBERS table'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1607 'Create ORGANIZATION_MEMBERS table': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1608 'Populate ORGANIZATION_MEMBERS table'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 1 default organization members processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 non default organization members processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1608 'Populate ORGANIZATION_MEMBERS table': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1609 'Drop unique index on RULES_PROFILES.ORGANIZATION_UUID and KEE'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1609 'Drop unique index on RULES_PROFILES.ORGANIZATION_UUID and KEE': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1610 'Make RULES_PROFILES.KEE unique'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1610 'Make RULES_PROFILES.KEE unique': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1611 'Clean LOADED_TEMPLATES rows without type'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1611 'Clean LOADED_TEMPLATES rows without type': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1612 'Extend size of column LOADED_TEMPLATES.TEMPLATE_TYPE'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1612 'Extend size of column LOADED_TEMPLATES.TEMPLATE_TYPE': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1613 'Add index LOADED_TEMPLATES_TYPE'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1613 'Add index LOADED_TEMPLATES_TYPE': success | time=9ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1614 'Upgrade loaded template entries for quality profiles'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 loaded templates for quality profiles processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1614 'Upgrade loaded template entries for quality profiles': success | time=9ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1615 'Create table RULES_METADATA'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1615 'Create table RULES_METADATA': success | time=14ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1616 'Populate table RULES_METADATA'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rules metadata processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1616 'Populate table RULES_METADATA': success | time=9ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1617 'Drop metadata columns from RULES'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1617 'Drop metadata columns from RULES': success | time=7ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1618 'Make index on ORGANIZATIONS.KEE unique'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1618 'Make index on ORGANIZATIONS.KEE unique': success | time=10ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1619 'Restore 'sonar-users' group'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1619 'Restore 'sonar-users' group': success | time=9ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1620 'Delete 'sonar.defaultGroup' setting'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1620 'Delete 'sonar.defaultGroup' setting': success | time=6ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1621 'Set all users into 'sonar-users' group'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 users processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1621 'Set all users into 'sonar-users' group': success | time=9ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1622 'Create 'Members' group in each organization'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1622 'Create 'Members' group in each organization': success | time=5ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1623 'Set organization members into 'Members' group'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 users not belonging to 'members' group processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1623 'Set organization members into 'Members' group': success | time=8ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1624 'Add ORGANIZATIONS.DEFAULT_GROUP_ID'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1624 'Add ORGANIZATIONS.DEFAULT_GROUP_ID': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1625 'Populate column ORGANIZATIONS.DEFAULT_GROUP_ID'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 1 organizations processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1625 'Populate column ORGANIZATIONS.DEFAULT_GROUP_ID': success | time=7ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1626 'Clean orphan rows in table GROUPS_USERS'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphan user group membership processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1626 'Clean orphan rows in table GROUPS_USERS': success | time=2ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1627 'Delete permission templates linked to removed users'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 permission templates linked to removed users processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1627 'Delete permission templates linked to removed users': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1628 'Add columns CE_QUEUE.WORKER_UUID and EXECUTION_COUNT'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1628 'Add columns CE_QUEUE.WORKER_UUID and EXECUTION_COUNT': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1629 'Make CE_QUEUE.EXECUTION_COUNT not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1629 'Make CE_QUEUE.EXECUTION_COUNT not nullable': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1630 'Add columns CE_ACTIVITY.WORKER_UUID and EXECUTION_COUNT'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1630 'Add columns CE_ACTIVITY.WORKER_UUID and EXECUTION_COUNT': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1631 'Make columns CE_ACTIVITY.EXECUTION_COUNT not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1631 'Make columns CE_ACTIVITY.EXECUTION_COUNT not nullable': success | time=3ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1632 'Make PROJECTS.PROJECT_UUID not nullable'...
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1632 'Make PROJECTS.PROJECT_UUID not nullable': success | time=10ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1633 'Purge rows with null PROJECTS.PROJECT_UUID'...
2019.10.10 17:07:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows in projects without project_uuid processed (0 items/sec)
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1633 'Purge rows with null PROJECTS.PROJECT_UUID': success | time=7ms
2019.10.10 17:07:15 INFO  web[][DbMigrations] #1634 'Make PROJECTS.PROJECT_UUID not nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1634 'Make PROJECTS.PROJECT_UUID not nullable': success | time=13ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1635 'Add column PROJECTS.PRIVATE'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1635 'Add column PROJECTS.PRIVATE': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1636 'Populate column PROJECTS.PRIVATE'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 component tree without private flag processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1636 'Populate column PROJECTS.PRIVATE': success | time=7ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1637 'Make column PROJECTS.PRIVATE not nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1637 'Make column PROJECTS.PRIVATE not nullable': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1638 'Add column ORGANIZATIONS.NEW_PROJECT_PRIVATE'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1638 'Add column ORGANIZATIONS.NEW_PROJECT_PRIVATE': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1639 'Set ORGANIZATIONS.NEW_PROJECT_PRIVATE to false'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1639 'Set ORGANIZATIONS.NEW_PROJECT_PRIVATE to false': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1640 'Make column ORGANIZATIONS.NEW_PROJECT_PRIVATE not nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1640 'Make column ORGANIZATIONS.NEW_PROJECT_PRIVATE not nullable': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1641 'Make components private based on permissions'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 component trees to be made private processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 public component trees to clean permissions of processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 users of private component tree without user permission processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 users of private component tree without codeviewer permission processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 groups of private component tree without user permission processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 groups of private component tree without codeviewer permission processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1641 'Make components private based on permissions': success | time=12ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1642 'Support private project in default permission template'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1642 'Support private project in default permission template': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1643 'Drop user and codeviewer perms to AnyOne in permission templates'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 permission templates with useless permissions to group AnyOne processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1643 'Drop user and codeviewer perms to AnyOne in permission templates': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1644 'Add index on active_rule_parameters.active_rule_id'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1644 'Add index on active_rule_parameters.active_rule_id': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1700 'Drop table AUTHORS'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1700 'Drop table AUTHORS': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1701 'Clean orphans from USER_ROLES'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows without component processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows for non-root component processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1701 'Clean orphans from USER_ROLES': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1702 'Clean orphans from GROUP_ROLES'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows without component processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows for non-root component processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1702 'Clean orphans from GROUP_ROLES': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1703 'Populate EVENTS.COMPONENT_UUID'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 events without component_uuid processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 delete orphan events processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1703 'Populate EVENTS.COMPONENT_UUID': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1704 'Drop index EVENTS_COMPONENT_UUID'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1704 'Drop index EVENTS_COMPONENT_UUID': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1705 'Make EVENTS.COMPONENT_UUID not nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1705 'Make EVENTS.COMPONENT_UUID not nullable': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1706 'Recreate index EVENTS_COMPONENT_UUID'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1706 'Recreate index EVENTS_COMPONENT_UUID': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1707 'Ensure ISSUE.PROJECT_UUID is consistent'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 issues with inconsistent project_uuid processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1707 'Ensure ISSUE.PROJECT_UUID is consistent': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1708 'Clean orphans from PROJECT_LINKS'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphan project links processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1708 'Clean orphans from PROJECT_LINKS': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1709 'Clean orphans from SETTINGS'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphan properties processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1709 'Clean orphans from SETTINGS': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1710 'Clean orphans from MANUAL_MEASURES'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphan properties processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1710 'Clean orphans from MANUAL_MEASURES': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1711 'Drop index MANUAL_MEASURES.COMPONENT_UUID'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1711 'Drop index MANUAL_MEASURES.COMPONENT_UUID': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1712 'Make MANUAL_MEASURES.COMPONENT_UUID not nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1712 'Make MANUAL_MEASURES.COMPONENT_UUID not nullable': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1713 'Recreate index MANUAL_MEASURES.COMPONENT_UUID'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1713 'Recreate index MANUAL_MEASURES.COMPONENT_UUID': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1714 'Purge developer data'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 purged project copies processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 purged developers processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1714 'Purge developer data': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1715 'Add rules_profiles.is_built_in'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1715 'Add rules_profiles.is_built_in': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1716 'Set rules_profiles.is_built_in to false'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rules_profiles processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1716 'Set rules_profiles.is_built_in to false': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1717 'Make rules_profiles.is_built_in not null'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1717 'Make rules_profiles.is_built_in not null': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1718 'Delete unused loaded_templates on quality profiles'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 loaded_templates processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1718 'Delete unused loaded_templates on quality profiles': success | time=8ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1719 'Create table default_qprofiles'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1719 'Create table default_qprofiles': success | time=16ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1720 'Populate table default_qprofiles'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 default_qprofiles processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1720 'Populate table default_qprofiles': success | time=12ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1721 'Drop rules_profiles.is_default'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1721 'Drop rules_profiles.is_default': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1722 'Create table qprofiles'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1722 'Create table qprofiles': success | time=24ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1723 'Populate table qprofiles'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 org_qprofiles processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1723 'Populate table qprofiles': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1724 'Drop columns organization_uuid and parent_kee from rules_profiles'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1724 'Drop columns organization_uuid and parent_kee from rules_profiles': success | time=7ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1725 'Mark rules_profiles.is_built_in to true for default organization'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1725 'Mark rules_profiles.is_built_in to true for default organization': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1726 'Update org_qprofiles to reference built-in profiles'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1726 'Update org_qprofiles to reference built-in profiles': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1727 'Delete rules_profiles orphans'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.v.v.DeleteOrphansFromRulesProfiles] Deleting orphans from rules_profiles
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.v.v.DeleteOrphansFromRulesProfiles] Deleting orphans from active_rules
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.v.v.DeleteOrphansFromRulesProfiles] Deleting orphans from active_rule_parameters
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.v.v.DeleteOrphansFromRulesProfiles] Deleting orphans from qprofile_changes
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1727 'Delete rules_profiles orphans': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1728 'Rename column qprofile_changes.qprofile_key to qprofile_changes.rules_profile_uuid'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1728 'Rename column qprofile_changes.qprofile_key to qprofile_changes.rules_profile_uuid': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1729 'Add index on qprofile_changes.rules_profile_uuid'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1729 'Add index on qprofile_changes.rules_profile_uuid': success | time=7ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1730 'Add USERS.ONBOARDED'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1730 'Add USERS.ONBOARDED': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1731 'Populate USERS.ONBOARDED'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1731 'Populate USERS.ONBOARDED': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1732 'Make USERS.ONBOARDED not nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1732 'Make USERS.ONBOARDED not nullable': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1733 'Create table es_queue'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1733 'Create table es_queue': success | time=12ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1734 'Add index on es_queue.created_at'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1734 'Add index on es_queue.created_at': success | time=8ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1735 'Delete sonar.ce.workerCount setting'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1735 'Delete sonar.ce.workerCount setting': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1801 'Create table CE task characteristics'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1801 'Create table CE task characteristics': success | time=17ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1802 'Delete leak settings on views'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 properties processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1802 'Delete leak settings on views': success | time=8ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1803 'Fix empty USERS.EXTERNAL_IDENTITY and USERS.EXTERNAL_IDENTITY_PROVIDER'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 users without external identity information processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1803 'Fix empty USERS.EXTERNAL_IDENTITY and USERS.EXTERNAL_IDENTITY_PROVIDER': success | time=7ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1804 'Add rules.plugin_key'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1804 'Add rules.plugin_key': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1805 'Create table plugins'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1805 'Create table plugins': success | time=18ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1806 'Create table project_branches'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1806 'Create table project_branches': success | time=7ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1807 'Add on project_branches key'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1807 'Add on project_branches key': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1808 'Add branch column to projects table'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1808 'Add branch column to projects table': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1809 'Populate project_branches with existing main branches'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 projects processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1809 'Populate project_branches with existing main branches': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1810 'Add ce_activity.error_type'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1810 'Add ce_activity.error_type': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1811 'Create table qprofile_edit_users'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1811 'Create table qprofile_edit_users': success | time=12ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1812 'Create table qprofile_edit_groups'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1812 'Create table qprofile_edit_groups': success | time=13ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1814 'Purge table properties'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1814 'Purge table properties': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1830 'Copy deprecated server ID'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1830 'Copy deprecated server ID': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1831 'Add webhook_deliveries.analysis_uuid'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1831 'Add webhook_deliveries.analysis_uuid': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1832 'Create table ANALYSIS_PROPERTIES'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1832 'Create table ANALYSIS_PROPERTIES': success | time=12ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1833 'Cleanup disabled users'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 deactivated users processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1833 'Cleanup disabled users': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1834 'Set WEBHOOK_DELIVERIES.CE_TASK_UUID as nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1834 'Set WEBHOOK_DELIVERIES.CE_TASK_UUID as nullable': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1835 'Populate WEBHOOK_DELIVERIES.ANALYSIS_UUID'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 webhook_deliveries processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1835 'Populate WEBHOOK_DELIVERIES.ANALYSIS_UUID': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1836 'Migrate 'previous_analysis' leak periods to 'previous_version''...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 leak periods processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1836 'Migrate 'previous_analysis' leak periods to 'previous_version'': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1837 'Drop old licenses'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 old license properties processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1837 'Drop old licenses': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1930 'Add QUALITY_GATES.IS_BUILT_IN'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1930 'Add QUALITY_GATES.IS_BUILT_IN': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1931 'Populate QUALITY_GATES.IS_BUILT_IN'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 quality_gates processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1931 'Populate QUALITY_GATES.IS_BUILT_IN': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1932 'Make QUALITY_GATES.IS_BUILT_IN not null'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1932 'Make QUALITY_GATES.IS_BUILT_IN not null': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1933 'Remove quality gates loaded templates'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 delete loaded templates for quality gate processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1933 'Remove quality gates loaded templates': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1934 'Rename quality gate "SonarQube way" to "Sonar way"'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 quality gates processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1934 'Rename quality gate "SonarQube way" to "Sonar way"': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1935 'Drop LOADED_TEMPLATES table'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1935 'Drop LOADED_TEMPLATES table': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1936 'Delete person measures'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 snapshots processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1936 'Delete person measures': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1937 'Drop index on project_measures.person_id'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1937 'Drop index on project_measures.person_id': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1938 'Create table live_measures'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1938 'Create table live_measures': success | time=11ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1939 'Add temporary index on SNAPSHOTS.ISLAST'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1939 'Add temporary index on SNAPSHOTS.ISLAST': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1940 ' Create temporary table LIVE_MEASURES_P'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1940 ' Create temporary table LIVE_MEASURES_P': success | time=10ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1941 'Populate table live_measures'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1941 'Populate table live_measures': success | time=16ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1942 'Add live_measures.metric_id index'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1942 'Add live_measures.metric_id index': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1943 'Drop temporary table LIVE_MEASURES_P'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1943 'Drop temporary table LIVE_MEASURES_P': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1944 'Drop temporary index on SNAPSHOTS.ISLAST'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1944 'Drop temporary index on SNAPSHOTS.ISLAST': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1945 'Delete file measures'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 files processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1945 'Delete file measures': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1946 'Create ORG_QUALITY_GATES table'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1946 'Create ORG_QUALITY_GATES table': success | time=12ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1947 'Add ORGANIZATIONS.DEFAULT_QUALITY_GATE_UUID'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1947 'Add ORGANIZATIONS.DEFAULT_QUALITY_GATE_UUID': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1948 'Create QUALITY_GATES.UUID'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1948 'Create QUALITY_GATES.UUID': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1949 'Populate QUALITY_GATES.UUID'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 quality gates processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1949 'Populate QUALITY_GATES.UUID': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1950 'Make QUALITY_GATES.UUID not nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1950 'Make QUALITY_GATES.UUID not nullable': success | time=7ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1951 'Drop unique index on QUALITY_GATES.NAME'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1951 'Drop unique index on QUALITY_GATES.NAME': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1952 'Create builtin quality gate if required'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1952 'Create builtin quality gate if required': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1953 'Populate ORG_QUALITY_GATES table'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 1 organizations processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1953 'Populate ORG_QUALITY_GATES table': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1954 'Populate default quality gate on organization'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 1 organizations processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1954 'Populate default quality gate on organization': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1955 'Associate existing quality gates to default organization'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 quality gates processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1955 'Associate existing quality gates to default organization': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1956 'Read 'sonar.qualitygate' setting and set the value to default organization'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.v.v.ReadGlobalSonarQualityGateSettingToDefaultOrg] No default quality gate set
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1956 'Read 'sonar.qualitygate' setting and set the value to default organization': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1957 'Delete 'sonar.qualitygate' setting at global level'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1957 'Delete 'sonar.qualitygate' setting at global level': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1958 'Make ORGANIZATIONS.DEFAULT_QUALITY_GATE_UUID not nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1958 'Make ORGANIZATIONS.DEFAULT_QUALITY_GATE_UUID not nullable': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1959 'Add users.homepage_type and users.homepage_parameter'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #1959 'Add users.homepage_type and users.homepage_parameter': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2000 'Delete settings defined in sonar.properties from PROPERTIES table'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2000 'Delete settings defined in sonar.properties from PROPERTIES table': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2001 'Add scope to rules'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2001 'Add scope to rules': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2002 'Set rules scope to MAIN'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rules processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2002 'Set rules scope to MAIN': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2003 'Make scope not nullable in rules'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2003 'Make scope not nullable in rules': success | time=1ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2004 'Use rule id in QPROFILE_CHANGES'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2004 'Use rule id in QPROFILE_CHANGES': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2005 'Create table DEPRECATED_RULE_KEYS'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2005 'Create table DEPRECATED_RULE_KEYS': success | time=17ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2006 'Clean orphans in Compute Engine child tables'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphans rows in ce_task_input processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphans rows in ce_scanner_context processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphans rows in ce_task_characteristics processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2006 'Clean orphans in Compute Engine child tables': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2007 'Update PERMISSION_TEMPLATES.KEYS '...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 permission templates processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2007 'Update PERMISSION_TEMPLATES.KEYS ': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2008 'Make scope not nullable in rules'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2008 'Make scope not nullable in rules': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2009 'Create table PROJECT_LINKS2'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2009 'Create table PROJECT_LINKS2': success | time=7ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2010 'Populate table PROJECT_LINKS2'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 project links processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2010 'Populate table PROJECT_LINKS2': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2011 'Drop table PROJECT_LINKS'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2011 'Drop table PROJECT_LINKS': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2012 'Rename table PROJECT_LINKS2 to PROJECT_LINKS'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2012 'Rename table PROJECT_LINKS2 to PROJECT_LINKS': success | time=8ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2013 'Create WEBHOOKS Table'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2013 'Create WEBHOOKS Table': success | time=18ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2014 'Migrate webhooks from SETTINGS table to WEBHOOKS table'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2014 'Migrate webhooks from SETTINGS table to WEBHOOKS table': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2015 'Add webhook key to WEBHOOK_DELIVERIES table'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2015 'Add webhook key to WEBHOOK_DELIVERIES table': success | time=15ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2016 'Increase branch type size in PROJECT_BRANCHES'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2016 'Increase branch type size in PROJECT_BRANCHES': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2017 'Add key_type column in PROJECT_BRANCHES'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2017 'Add key_type column in PROJECT_BRANCHES': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2018 'Fill key_type column in PROJECT_BRANCHES'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 branches processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2018 'Fill key_type column in PROJECT_BRANCHES': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2019 'Make key_type not nullable in PROJECT_BRANCHES'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2019 'Make key_type not nullable in PROJECT_BRANCHES': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2020 'Replace index in PROJECT_BRANCHES'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2020 'Replace index in PROJECT_BRANCHES': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2021 'Add pull_request_data in PROJECT_BRANCHES'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2021 'Add pull_request_data in PROJECT_BRANCHES': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2022 'Clean broken project to QG references'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2022 'Clean broken project to QG references': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2023 'Delete measures of project copies'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2023 'Delete measures of project copies': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2100 'Increase size of USERS.CRYPTED_PASSWORD'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2100 'Increase size of USERS.CRYPTED_PASSWORD': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2101 'Add HASH_METHOD to table users'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2101 'Add HASH_METHOD to table users': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2102 'Populate HASH_METHOD on table users'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2102 'Populate HASH_METHOD on table users': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2103 'Add isExternal boolean to rules'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2103 'Add isExternal boolean to rules': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2104 'Create ALM_APP_INSTALLS table'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2104 'Create ALM_APP_INSTALLS table': success | time=18ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2105 'Add LINE_HASHES_VERSION to table FILE_SOURCES'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2105 'Add LINE_HASHES_VERSION to table FILE_SOURCES': success | time=1ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2106 'Create PROJECT_MAPPINGS table'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2106 'Create PROJECT_MAPPINGS table': success | time=18ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2107 'Add UUID on table USERS'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2107 'Add UUID on table USERS': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2108 'Populate USERS.UUID with USERS.LOGIN'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 1 users processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2108 'Populate USERS.UUID with USERS.LOGIN': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2109 'Add EXTERNAL_ID on table users'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2109 'Add EXTERNAL_ID on table users': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2110 'Rename EXTERNAL_IDENTITY to EXTERNAL_LOGIN on table users'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2110 'Rename EXTERNAL_IDENTITY to EXTERNAL_LOGIN on table users': success | time=2ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2111 'Update null values from external columns and login of users'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 1 users processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2111 'Update null values from external columns and login of users': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2112 'Fix duplication in EXTERNAL_ID and EXTERNAL_LOGIN on table users'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 users having duplicated values in EXTERNAL_LOGIN processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2112 'Fix duplication in EXTERNAL_ID and EXTERNAL_LOGIN on table users': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2113 'Makes same columns of table users not nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2113 'Makes same columns of table users not nullable': success | time=14ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2114 'Add unique indexes on table users'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2114 'Add unique indexes on table users': success | time=14ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2115 'Add ORGANIZATION_UUID on table users'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2115 'Add ORGANIZATION_UUID on table users': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2116 'Populate ORGANIZATION_UUID in table users'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 users processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2116 'Populate ORGANIZATION_UUID in table users': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2117 'Drop USER_ID from table organizations'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2117 'Drop USER_ID from table organizations': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2118 'Rename USER_LOGIN TO USER_UUID on table QPROFILE_CHANGES'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2118 'Rename USER_LOGIN TO USER_UUID on table QPROFILE_CHANGES': success | time=3ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2119 'Rename LOGIN TO USER_UUID on table USER_TOKENS'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2119 'Rename LOGIN TO USER_UUID on table USER_TOKENS': success | time=8ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2120 'Rename USER_LOGIN TO USER_UUID on table MANUAL_MEASURES'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2120 'Rename USER_LOGIN TO USER_UUID on table MANUAL_MEASURES': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2121 'Rename NOTE_USER_LOGIN TO NOTE_USER_UUID on table RULES_METADATA'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2121 'Rename NOTE_USER_LOGIN TO NOTE_USER_UUID on table RULES_METADATA': success | time=4ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2122 'Rename SUBMITTER_LOGIN TO SUBMITTER_UUID on table CE_QUEUE'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2122 'Rename SUBMITTER_LOGIN TO SUBMITTER_UUID on table CE_QUEUE': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2123 'Rename SUBMITTER_LOGIN TO SUBMITTER_UUID on table CE_ACTIVITY'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2123 'Rename SUBMITTER_LOGIN TO SUBMITTER_UUID on table CE_ACTIVITY': success | time=58ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2124 'Add FILE_SOURCE.LINE_COUNT'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2124 'Add FILE_SOURCE.LINE_COUNT': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2125 'Populate FILE_SOURCE.LINE_COUNT'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 file sources processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2125 'Populate FILE_SOURCE.LINE_COUNT': success | time=7ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2126 'Make FILE_SOURCE.LINE_COUNT not nullable'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2126 'Make FILE_SOURCE.LINE_COUNT not nullable': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2127 'Purge orphans for Compute Engine'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2127 'Purge orphans for Compute Engine': success | time=10ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2128 'Purge duplicate rules_parameters and their orphans'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2128 'Purge duplicate rules_parameters and their orphans': success | time=7ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2129 'Add unique index on rule_id + name on rules_parameters'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2129 'Add unique index on rule_id + name on rules_parameters': success | time=8ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2200 'Populate PROJECT_BRANCHES with existing main application branches'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 applications processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2200 'Populate PROJECT_BRANCHES with existing main application branches': success | time=8ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2201 'Add 'securityhotspotadmin' permission to all permission templates groups already having 'issueadmin''...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 permission templates groups roles processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2201 'Add 'securityhotspotadmin' permission to all permission templates groups already having 'issueadmin'': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2202 'Add 'securityhotspotadmin' permission to all permission templates users already having 'issueadmin''...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 permission templates users roles processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2202 'Add 'securityhotspotadmin' permission to all permission templates users already having 'issueadmin'': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2203 'Add 'securityhotspotadmin' permission to all groups already having 'issueadmin''...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 group roles processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2203 'Add 'securityhotspotadmin' permission to all groups already having 'issueadmin'': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2204 'Add 'securityhotspotadmin' permission to all users already having 'issueadmin''...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 user roles processed (0 items/sec)
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2204 'Add 'securityhotspotadmin' permission to all users already having 'issueadmin'': success | time=7ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2205 'Add 'from hotspot' flag to issues'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2205 'Add 'from hotspot' flag to issues': success | time=5ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2206 'Add SUBSCRIPTION column to ORGANIZATIONS table'...
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2206 'Add SUBSCRIPTION column to ORGANIZATIONS table': success | time=6ms
2019.10.10 17:07:16 INFO  web[][DbMigrations] #2207 'Populate SUBSCRIPTION in ORGANIZATIONS'...
2019.10.10 17:07:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 1 organizations processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2207 'Populate SUBSCRIPTION in ORGANIZATIONS': success | time=9ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2208 'Add rules.security_standards'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2208 'Add rules.security_standards': success | time=6ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2209 'Fix missing quality profiles on organizations'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2209 'Fix missing quality profiles on organizations': success | time=3ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2210 'Add 'securityhotspotadmin' permission to templates characteristics already having 'issueadmin''...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 permission templates characteristics processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2210 'Add 'securityhotspotadmin' permission to templates characteristics already having 'issueadmin'': success | time=6ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2211 'Set SUBSCRIPTION not nullable in ORGANIZATIONS'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2211 'Set SUBSCRIPTION not nullable in ORGANIZATIONS': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2212 'Add index on ORGANIZATION_MEMBERS'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2212 'Add index on ORGANIZATION_MEMBERS': success | time=5ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2213 'Create table to store project ALM bindings'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2213 'Create table to store project ALM bindings': success | time=20ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2300 'Populate null values of IS_EXTERNAL in RULES'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rules processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2300 'Populate null values of IS_EXTERNAL in RULES': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2301 'Add IS_ADHOC column to RULES table'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2301 'Add IS_ADHOC column to RULES table': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2302 'Populate IS_AD_HOC in RULES'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rules processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2302 'Populate IS_AD_HOC in RULES': success | time=3ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2303 'Set IS_EXTERNAL and IS_AD_HOC not nullable in RULES'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2303 'Set IS_EXTERNAL and IS_AD_HOC not nullable in RULES': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2304 'Add ad hoc related columns in RULES_METADATA'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2304 'Add ad hoc related columns in RULES_METADATA': success | time=5ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2312 'Add CE_QUEUE.MAIN_COMPONENT_UUID 1/3'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2312 'Add CE_QUEUE.MAIN_COMPONENT_UUID 1/3': success | time=17ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2313 'Add CE_ACTIVITY.MAIN_COMPONENT_UUID 1/3'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2313 'Add CE_ACTIVITY.MAIN_COMPONENT_UUID 1/3': success | time=14ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2314 'Populate CE_QUEUE.MAIN_COMPONENT_UUID 2/3'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 queued tasks of branches processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 queued tasks of PRs processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 queued tasks of never analyzed branches processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 queued tasks of never analyzed PRs processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 queued tasks of main and deprecated branches processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2314 'Populate CE_QUEUE.MAIN_COMPONENT_UUID 2/3': success | time=23ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2315 'Populate CE_ACTIVITY.MAIN_COMPONENT_UUID 2/3'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 Archived tasks of branches processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 Archived tasks of PRs processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 Archived tasks of main and deprecated branches processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2315 'Populate CE_ACTIVITY.MAIN_COMPONENT_UUID 2/3': success | time=14ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2316 'Add CE_ACTIVITY.MAIN_LAST_KEY 1/3'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2316 'Add CE_ACTIVITY.MAIN_LAST_KEY 1/3': success | time=27ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2317 'Populate CE_ACTIVITY.MAIN_LAST_KEY 2/3'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows of ce_activity processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2317 'Populate CE_ACTIVITY.MAIN_LAST_KEY 2/3': success | time=6ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2318 'Finalize CE_QUEUE.MAIN_COMPONENT_UUID 3/3'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2318 'Finalize CE_QUEUE.MAIN_COMPONENT_UUID 3/3': success | time=18ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2319 'Finalize CE_ACTIVITY.MAIN_COMPONENT_UUID 3/3'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2319 'Finalize CE_ACTIVITY.MAIN_COMPONENT_UUID 3/3': success | time=19ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2320 'Finalize CE_ACTIVITY.MAIN_LAST_KEY 3/3'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2320 'Finalize CE_ACTIVITY.MAIN_LAST_KEY 3/3': success | time=53ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2321 'Increase organization key and name length'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2321 'Increase organization key and name length': success | time=9ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2322 'Create table CE_TASK_MESSAGE'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2322 'Create table CE_TASK_MESSAGE': success | time=22ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2323 'Clean orphans from deleted branches and PRs in CE_* tables'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphans of deleted branch/pr processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2323 'Clean orphans from deleted branches and PRs in CE_* tables': success | time=3ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2324 'Create new creator permissions for applications and portfolios'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.v.v.CreateApplicationsAndPortfoliosCreatorPermissions] There is no default template for views. Skipping adding applications and portfolios creator permissions.
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.v.v.CreateApplicationsAndPortfoliosCreatorPermissions] Unable to find the default template [null] for views. Skipping adding applications and portfolios creator permissions.
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2324 'Create new creator permissions for applications and portfolios': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2325 'Add default templates for applications and portfolios'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2325 'Add default templates for applications and portfolios': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2326 'Create new creator permissions for applications and portfolios'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.v.v.CreateApplicationsAndPortfoliosCreatorPermissions] There is no default template for views. Skipping adding applications and portfolios creator permissions.
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.v.v.CreateApplicationsAndPortfoliosCreatorPermissions] Unable to find the default template [null] for views. Skipping adding applications and portfolios creator permissions.
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2326 'Create new creator permissions for applications and portfolios': success | time=1ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2327 'Populate default template permissions on organizations'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 organizations processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2327 'Populate default template permissions on organizations': success | time=3ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2328 'Add portfolio and application creator permissions on sonar-administrators group'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2328 'Add portfolio and application creator permissions on sonar-administrators group': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2329 'Drop column DEFAULT_PERM_TEMPLATE_VIEW on organizations'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2329 'Drop column DEFAULT_PERM_TEMPLATE_VIEW on organizations': success | time=3ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2400 'Add column IS_OWNER_USER in ALM_APP_INSTALLS'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2400 'Add column IS_OWNER_USER in ALM_APP_INSTALLS': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2401 'Create ORGANIZATION_ALM_BINDINGS table'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2401 'Create ORGANIZATION_ALM_BINDINGS table': success | time=18ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2402 'Add column USER_EXTERNAL_ID in ALM_APP_INSTALLS'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2402 'Add column USER_EXTERNAL_ID in ALM_APP_INSTALLS': success | time=7ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2403 'Set IS_OWNER_USER not nullable in ALM_APP_INSTALLS'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2403 'Set IS_OWNER_USER not nullable in ALM_APP_INSTALLS': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2404 'Add table EVENT_COMPONENT_CHANGES'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2404 'Add table EVENT_COMPONENT_CHANGES': success | time=21ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2500 'Create table USER_PROPERTIES'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2500 'Create table USER_PROPERTIES': success | time=10ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2501 'Add index in table USER_PROPERTIES'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2501 'Add index in table USER_PROPERTIES': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2502 'Archive module properties in a new project level property'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 module level properties processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2502 'Archive module properties in a new project level property': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2503 'Delete useless 'sonar.dbcleaner.cleanDirectory' property'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2503 'Delete useless 'sonar.dbcleaner.cleanDirectory' property': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2504 'Delete useless module and folder level measures'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 components processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2504 'Delete useless module and folder level measures': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2505 'Fix the direction values of certain metrics (prepare for migration of conditions)'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2505 'Fix the direction values of certain metrics (prepare for migration of conditions)': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2506 'Migrate quality gate conditions using warning, period and no more supported operations'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.v.v.MigrateNoMoreUsedQualityGateConditions] 0 custom quality gates have been loaded
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.v.v.MigrateNoMoreUsedQualityGateConditions] 0 conditions have been removed
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.v.v.MigrateNoMoreUsedQualityGateConditions] 0 conditions have been updated
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2506 'Migrate quality gate conditions using warning, period and no more supported operations': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2507 'Delete sonar.onboardingTutorial.showToNewUsers from settings'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 useless onboarding settings processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2507 'Delete sonar.onboardingTutorial.showToNewUsers from settings': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2508 'Add unique index on EXTERNAL_LOGIN and EXTERNAL_IDENTITY_PROVIDER columns in table USERS'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2508 'Add unique index on EXTERNAL_LOGIN and EXTERNAL_IDENTITY_PROVIDER columns in table USERS': success | time=5ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2600 'Drop elasticsearch index 'tests''...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2600 'Drop elasticsearch index 'tests'': success | time=3ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2601 'Delete lines with DATA_TYPE='TEST' from table FILES_SOURCE'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 file sources processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2601 'Delete lines with DATA_TYPE='TEST' from table FILES_SOURCE': success | time=3ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2602 'Add column LAST_CONNECTION_DATE to USERS table'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2602 'Add column LAST_CONNECTION_DATE to USERS table': success | time=1ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2603 'Add column LAST_USED_DATE to USER_TOKENS table'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2603 'Add column LAST_USED_DATE to USER_TOKENS table': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2604 'Add baseline columns in PROJECT_BRANCHES'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2604 'Add baseline columns in PROJECT_BRANCHES': success | time=1ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2606 'Drop DATA_TYPE column from FILE_SOURCES table'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2606 'Drop DATA_TYPE column from FILE_SOURCES table': success | time=10ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2607 'Add MEMBERS_SYNC_ENABLED column to ORGANIZATIONS_ALM_BINDING table'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2607 'Add MEMBERS_SYNC_ENABLED column to ORGANIZATIONS_ALM_BINDING table': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2608 'Delete favorites on not supported components'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 favourites processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2608 'Delete favorites on not supported components': success | time=5ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2609 'Delete exceeding favorites when there are more than 100 for a user'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.v.v.DeleteFavoritesExceedingOneHundred] Deleting favourites exceeding one hundred elements for 0 users
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2609 'Delete exceeding favorites when there are more than 100 for a user': success | time=3ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2610 'Truncate ES_QUEUE table content'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2610 'Truncate ES_QUEUE table content': success | time=18ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2611 'Add SNAPSHOTS.BUILD_STRING'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2611 'Add SNAPSHOTS.BUILD_STRING': success | time=5ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2700 'Drop overall subscriptions on notifications about new and resolved issues'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 subscriptions processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2700 'Drop overall subscriptions on notifications about new and resolved issues': success | time=6ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2701 'Add index to org_qprofile.parent_uuid'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2701 'Add index to org_qprofile.parent_uuid': success | time=8ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2702 'Add column webhooks.secret'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2702 'Add column webhooks.secret': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2703 'Add security fields to Elasticsearch indices'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2703 'Add security fields to Elasticsearch indices': success | time=11ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2704 'Add InternalComponentProperties table'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2704 'Add InternalComponentProperties table': success | time=18ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2705 'Add column snapshots.revision'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2705 'Add column snapshots.revision': success | time=2ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2706 'Migrate revision from analysis_properties to snapshots.revision'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 migrate revision from analysis_properties to snapshots processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2706 'Migrate revision from analysis_properties to snapshots.revision': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2707 'Update statuses of Security Hotspots'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 security hotspots processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2707 'Update statuses of Security Hotspots': success | time=12ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2708 'Remove orphans from PROJECT_BRANCHES'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 orphans in table PROJECT_BRANCHES processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2708 'Remove orphans from PROJECT_BRANCHES': success | time=6ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2800 'Truncate environment variables and system properties from existing scanner reports'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 truncate scanner context content processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2800 'Truncate environment variables and system properties from existing scanner reports': success | time=6ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2801 'populate install version and install date internal properties'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2801 'populate install version and install date internal properties': success | time=12ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2802 'Migrate property 'sonar.pullrequest.provider' value from VSTS to Azure DevOps'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 PR provider properties processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2802 'Migrate property 'sonar.pullrequest.provider' value from VSTS to Azure DevOps': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2803 'Remove quality gate conditions on Security Review Rating'...
2019.10.10 17:07:17 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 quality gate conditions on security review rating processed (0 items/sec)
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2803 'Remove quality gate conditions on Security Review Rating': success | time=4ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2804 'Reindex issues and rules to take into account latest categories definition'...
2019.10.10 17:07:17 INFO  web[][DbMigrations] #2804 'Reindex issues and rules to take into account latest categories definition': success | time=3ms
2019.10.10 17:07:17 INFO  web[][DbMigrations] Executed DB migrations: success | time=3816ms
2019.10.10 17:07:17 INFO  web[][o.s.s.p.Platform] Database has been automatically updated
2019.10.10 17:07:17 INFO  web[][o.s.s.p.w.MasterServletFilter] Initializing servlet filter org.sonar.server.ws.WebServiceFilter@4207a24d [pattern=UrlPattern{inclusions=[/api/system/migrate_db.*, ...], exclusions=[/api/properties*, ...]}]
2019.10.10 17:07:18 WARN  web[][o.a.c.l.WebappClassLoaderBase] The web application [ROOT] appears to have started a thread named [SQ starter] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
 java.base@11.0.4/sun.reflect.generics.tree.ClassTypeSignature.accept(ClassTypeSignature.java:49)
 java.base@11.0.4/sun.reflect.annotation.AnnotationParser.parseSig(AnnotationParser.java:440)
 java.base@11.0.4/sun.reflect.annotation.AnnotationParser.parseAnnotation2(AnnotationParser.java:242)
 java.base@11.0.4/sun.reflect.annotation.AnnotationParser.parseAnnotations2(AnnotationParser.java:121)
 java.base@11.0.4/sun.reflect.annotation.AnnotationParser.parseAnnotations(AnnotationParser.java:73)
 java.base@11.0.4/java.lang.Class.createAnnotationData(Class.java:3758)
 java.base@11.0.4/java.lang.Class.annotationData(Class.java:3747)
 java.base@11.0.4/java.lang.Class.getAnnotation(Class.java:3652)
 app//org.sonar.api.utils.AnnotationUtils.getAnnotation(AnnotationUtils.java:46)
 app//org.sonar.api.config.PropertyDefinitions.addComponentFromAnnotationProperty(PropertyDefinitions.java:90)
 app//org.sonar.api.config.PropertyDefinitions.addComponent(PropertyDefinitions.java:81)
 app//org.sonar.core.platform.ComponentContainer.declareExtension(ComponentContainer.java:271)
 app//org.sonar.core.platform.ComponentContainer.addComponent(ComponentContainer.java:232)
 app//org.sonar.server.platform.platformlevel.PlatformLevel.add(PlatformLevel.java:129)
 app//org.sonar.server.platform.platformlevel.PlatformLevel$AddIf.ifAdd(PlatformLevel.java:187)
 app//org.sonar.server.platform.platformlevel.PlatformLevel$AddIfStartupLeader.ifAdd(PlatformLevel.java:198)
 app//org.sonar.server.platform.platformlevel.PlatformLevel.addIfStartupLeader(PlatformLevel.java:143)
 app//org.sonar.server.platform.platformlevel.PlatformLevel4.configureLevel(PlatformLevel4.java:235)
 app//org.sonar.server.platform.platformlevel.PlatformLevel.configure(PlatformLevel.java:74)
 app//org.sonar.server.platform.Platform.start(Platform.java:208)
 app//org.sonar.server.platform.Platform.startLevel34Containers(Platform.java:185)
 app//org.sonar.server.platform.Platform.access$500(Platform.java:46)
 app//org.sonar.server.platform.Platform$1.lambda$doRun$0(Platform.java:119)
 app//org.sonar.server.platform.Platform$1$$Lambda$1438/0x0000000100820440.run(Unknown Source)
 app//org.sonar.server.platform.Platform$AutoStarterRunnable.runIfNotAborted(Platform.java:371)
 app//org.sonar.server.platform.Platform$1.doRun(Platform.java:119)
 app//org.sonar.server.platform.Platform$AutoStarterRunnable.run(Platform.java:355)
 java.base@11.0.4/java.lang.Thread.run(Thread.java:834)
2019.10.10 17:07:18 INFO  web[][o.s.s.a.EmbeddedTomcat] HTTP connector enabled on port 9000
2019.10.10 17:07:18 INFO  web[][o.s.p.ProcessEntryPoint] Hard stopping process