Attachment 847704 Details for Bug 1050751 – installer log

installer log

rhq-installer.log (text/plain), 16.10 KB, created by Viet Nguyen on 2014-01-09 16:02:03 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Viet Nguyen

Created: 2014-01-09 16:02:03 UTC

Size: 16.10 KB

patch

obsolete

>20:32:28,345 INFO  [org.jboss.modules] JBoss Modules version 1.2.2.Final-redhat-1
>20:32:28,526 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] The server is preconfigured and ready for auto-install.
>20:32:28,626 INFO  [org.xnio] XNIO Version 3.0.7.GA-redhat-1
>20:32:28,637 INFO  [org.xnio.nio] XNIO NIO Implementation Version 3.0.7.GA-redhat-1
>20:32:28,647 INFO  [org.jboss.remoting] JBoss Remoting version 3.2.16.GA-redhat-1
>20:32:34,335 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] Installing into app server version [7.2.1.Final-redhat-10]
>20:32:34,620 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Database is supported: postgresql8.4(postgresql:8.4)
>20:32:34,663 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Unable to get server details for server [soaperf05.mw.lab.eng.bos.redhat.com]: ERROR: relation "rhq_server" does not exist
>  Position: 48
>20:32:34,665 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] This will be considered a new server: [name=soaperf05.mw.lab.eng.bos.redhat.com, address=10.16.90.16, port=7080, secureport=7443]
>20:32:34,709 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] The app server where the installation will go is found at: /qa/home/vnguyen/jon-server-3.2.0.GA/jbossas
>20:32:34,709 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] It looks like everything is OK and you can start the installation.
>20:32:35,725 INFO  [org.jboss.modules] JBoss Modules version 1.2.2.Final-redhat-1
>20:32:35,861 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] The server is preconfigured and ready for auto-install.
>20:32:35,965 INFO  [org.xnio] XNIO Version 3.0.7.GA-redhat-1
>20:32:35,976 INFO  [org.xnio.nio] XNIO NIO Implementation Version 3.0.7.GA-redhat-1
>20:32:35,986 INFO  [org.jboss.remoting] JBoss Remoting version 3.2.16.GA-redhat-1
>20:32:41,525 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] Installing into app server version [7.2.1.Final-redhat-10]
>20:32:41,878 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Database is supported: postgresql8.4(postgresql:8.4)
>20:32:41,907 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] Database setup log file directory: /qa/home/vnguyen/jon-server-3.2.0.GA/logs
>20:32:41,947 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] Database schema does not yet exist - it will now be created.
>20:32:42,824 INFO  [org.rhq.core.db.setup.DBSetup] {DBSetup.uninstall.views.dropped}[0] views dropped successfully.
>20:32:42,824 INFO  [org.rhq.core.db.setup.DBSetup] {DBSetup.uninstall.tables.dropped}[0] tables dropped successfully.
>20:32:42,825 INFO  [org.rhq.core.db.setup.DBSetup] {DBSetup.uninstall.views.failed}[0] views failed to drop.
>20:32:42,826 INFO  [org.rhq.core.db.setup.DBSetup] {DBSetup.uninstall.tables.failed}[169] tables failed to drop.
>20:32:57,338 INFO  [org.rhq.core.db.setup.DBSetup] {DBSetup.setup.tables.created}[160] tables created successfully
>20:32:57,339 INFO  [org.rhq.core.db.setup.DBSetup] {DBSetup.setup.indexes.created}[100] indexes created successfully
>20:32:57,340 INFO  [org.rhq.core.db.setup.DBSetup] {DBSetup.setup.views.created}[0] views created successfully
>20:32:59,336 INFO  [org.rhq.core.db.setup.DBSetup] {DBSetup.setup.tables.created}[0] tables created successfully
>20:32:59,337 INFO  [org.rhq.core.db.setup.DBSetup] {DBSetup.setup.indexes.created}[0] indexes created successfully
>20:32:59,337 INFO  [org.rhq.core.db.setup.DBSetup] {DBSetup.setup.views.created}[0] views created successfully
>20:32:59,401 INFO  [org.rhq.cassandra.schema.VersionManager] Preparing to check storage schema compatibility.
>20:32:59,402 INFO  [org.rhq.cassandra.schema.SessionManager] Initializing session for [username: paidoklk, cqlPort: 9142, nodes: [soaperf05.mw.lab.eng.bos.redhat.com]]
>20:32:59,685 INFO  [org.rhq.cassandra.schema.VersionManager] Completed storage schema compatibility check.
>20:32:59,686 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] Install RHQ schema along with updates to storage nodes.
>20:32:59,689 INFO  [org.rhq.cassandra.schema.VersionManager] Preparing to install storage schema
>20:32:59,689 INFO  [org.rhq.cassandra.schema.SessionManager] Initializing session for [username: paidoklk, cqlPort: 9142, nodes: [soaperf05.mw.lab.eng.bos.redhat.com]]
>20:32:59,726 INFO  [org.rhq.cassandra.schema.AbstractManager] Shutting down existing cluster connections
>20:32:59,726 INFO  [org.rhq.cassandra.schema.SessionManager] Shutting down cluster
>20:32:59,726 INFO  [org.rhq.cassandra.schema.SessionManager] Initializing session for [username: cassandra, cqlPort: 9142, nodes: [soaperf05.mw.lab.eng.bos.redhat.com]]
>20:33:00,861 INFO  [org.rhq.cassandra.schema.AbstractManager] Applying update file: org.rhq.cassandra.schema.UpdateFile@8f8acd0
>20:33:00,867 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    ALTER KEYSPACE system_auth WITH replication = {'class': 'SimpleStrategy', 'replication_factor': 1 }
>  
>20:33:01,262 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    CREATE KEYSPACE rhq WITH replication = {'class': 'SimpleStrategy', 'replication_factor': 1 }
>  
>20:33:01,460 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    GRANT ALL PERMISSIONS ON KEYSPACE  system_auth to paidoklk
>  
>20:33:01,475 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    GRANT ALL PERMISSIONS ON KEYSPACE  rhq to paidoklk
>  
>20:33:01,485 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>   CREATE TABLE rhq.schema_version (
>        version int,
>        time timestamp,
>        PRIMARY KEY (version, time)
>      ) WITH COMPACT STORAGE
>  
>20:33:01,692 INFO  [org.rhq.cassandra.schema.AbstractManager] Applied update file: org.rhq.cassandra.schema.UpdateFile@8f8acd0
>20:33:01,693 INFO  [org.rhq.cassandra.schema.AbstractManager] Shutting down existing cluster connections
>20:33:01,693 INFO  [org.rhq.cassandra.schema.SessionManager] Shutting down cluster
>20:33:01,718 INFO  [org.rhq.cassandra.schema.SessionManager] Initializing session for [username: paidoklk, cqlPort: 9142, nodes: [soaperf05.mw.lab.eng.bos.redhat.com]]
>20:33:02,385 INFO  [org.rhq.cassandra.schema.AbstractManager] Applying update file: org.rhq.cassandra.schema.UpdateFile@76749ebc
>20:33:02,389 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    ALTER USER cassandra NOSUPERUSER
>  
>20:33:02,397 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    ALTER USER cassandra WITH PASSWORD 'cd988b0e-11ca-415f-bb8c-0decc10d4f78'
>  
>20:33:02,554 INFO  [org.rhq.cassandra.schema.AbstractManager] Applied update file: org.rhq.cassandra.schema.UpdateFile@76749ebc
>20:33:02,727 INFO  [org.rhq.cassandra.schema.VersionManager] Installed storage schema version is 0
>20:33:02,727 INFO  [org.rhq.cassandra.schema.VersionManager] Required storage schema version is 1
>20:33:02,728 INFO  [org.rhq.cassandra.schema.VersionManager] Storage schema requires udpates. Updating from version 0 to version 1.
>20:33:02,728 INFO  [org.rhq.cassandra.schema.AbstractManager] Applying update file: org.rhq.cassandra.schema.UpdateFile@707cdc58
>20:33:02,733 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>      CREATE TABLE rhq.raw_metrics (
>        schedule_id int,
>        time timestamp,
>        value double,
>        PRIMARY KEY (schedule_id, time)
>      ) WITH COMPACT STORAGE;
>    
>20:33:02,913 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>      CREATE TABLE rhq.one_hour_metrics (
>        schedule_id int,
>        time timestamp,
>        type int,
>        value double,
>        PRIMARY KEY (schedule_id, time, type)
>      ) WITH COMPACT STORAGE;
>    
>20:33:03,080 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>      CREATE TABLE rhq.six_hour_metrics (
>        schedule_id int,
>        time timestamp,
>        type int,
>        value double,
>        PRIMARY KEY (schedule_id, time, type)
>      ) WITH COMPACT STORAGE;
>    
>20:33:03,331 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>      CREATE TABLE rhq.twenty_four_hour_metrics (
>        schedule_id int,
>        time timestamp,
>        type int,
>        value double,
>        PRIMARY KEY (schedule_id, time, type)
>      ) WITH COMPACT STORAGE;
>    
>20:33:03,496 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>      CREATE TABLE rhq.metrics_index (
>        bucket varchar,
>        time timestamp,
>        schedule_id int,
>        PRIMARY KEY ((bucket, time), schedule_id)
>      ) WITH COMPACT STORAGE;
>    
>20:33:03,711 INFO  [org.rhq.cassandra.schema.AbstractManager] Applied update file: org.rhq.cassandra.schema.UpdateFile@707cdc58
>20:33:03,720 INFO  [org.rhq.cassandra.schema.VersionManager] Storage schema update org.rhq.cassandra.schema.UpdateFile@707cdc58 applied.
>20:33:03,746 INFO  [org.rhq.cassandra.schema.TopologyManager] Applying topology updates...
>20:33:03,747 INFO  [org.rhq.cassandra.schema.TopologyManager] Starting to execute UpdateReplicationFactor task.
>20:33:03,757 INFO  [org.rhq.cassandra.schema.TopologyManager] No need to update replication factor. Replication factor already 1
>20:33:03,758 INFO  [org.rhq.cassandra.schema.TopologyManager] Successfully executed UpdateReplicationFactor task.
>20:33:03,758 INFO  [org.rhq.cassandra.schema.TopologyManager] Starting to execute UpdateGCGrace task.
>20:33:03,759 INFO  [org.rhq.cassandra.schema.AbstractManager] Applying update file: org.rhq.cassandra.schema.UpdateFile@1b8662b6
>20:33:03,767 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    ALTER COLUMNFAMILY rhq.metrics_index WITH gc_grace_seconds = 0
>  
>20:33:03,891 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    ALTER COLUMNFAMILY rhq.raw_metrics WITH gc_grace_seconds = 0
>  
>20:33:04,017 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    ALTER COLUMNFAMILY rhq.one_hour_metrics WITH gc_grace_seconds = 0
>  
>20:33:04,159 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    ALTER COLUMNFAMILY rhq.six_hour_metrics WITH gc_grace_seconds = 0
>  
>20:33:04,269 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    ALTER COLUMNFAMILY rhq.twenty_four_hour_metrics WITH gc_grace_seconds = 0
>  
>20:33:04,396 INFO  [org.rhq.cassandra.schema.AbstractManager] Statement: 
>
>    ALTER COLUMNFAMILY rhq.schema_version WITH gc_grace_seconds = 0
>  
>20:33:04,585 INFO  [org.rhq.cassandra.schema.AbstractManager] Applied update file: org.rhq.cassandra.schema.UpdateFile@1b8662b6
>20:33:04,585 INFO  [org.rhq.cassandra.schema.TopologyManager] Updated gc_grace_seconds to 0
>20:33:04,585 INFO  [org.rhq.cassandra.schema.TopologyManager] Successfully executed UpdateGCGrace task.
>20:33:04,586 INFO  [org.rhq.cassandra.schema.SessionManager] Shutting down cluster
>20:33:04,644 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Persisting to database new storage nodes for values specified in server configuration property [rhq.storage.nodes]
>20:33:04,716 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [ajp] to [${rhq.server.socket.binding.port.ajp:7009}]
>20:33:04,869 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [http] to [${rhq.server.socket.binding.port.http:7080}]
>20:33:04,929 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [https] to [${rhq.server.socket.binding.port.https:7443}]
>20:33:04,983 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [jacorb] to [${rhq.server.socket.binding.port.jacorb:2528}]
>20:33:05,035 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [jacorb-ssl] to [${rhq.server.socket.binding.port.jacorb-ssl:2529}]
>20:33:05,077 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [messaging] to [${rhq.server.socket.binding.port.messaging:4449}]
>20:33:05,110 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [messaging] to use interface [management]
>20:33:05,157 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [messaging-throughput] to [${rhq.server.socket.binding.port.messaging-throughput:4455}]
>20:33:05,197 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [messaging-throughput] to use interface [management]
>20:33:05,249 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [management-http] to [${jboss.management.http.port:6990}]
>20:33:05,293 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [management-https] to [${jboss.management.https.port:6443}]
>20:33:05,336 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [management-native] to [${jboss.management.native.port:6999}]
>20:33:05,370 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [remoting] to [${rhq.server.socket.binding.port.remoting:3447}]
>20:33:05,401 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [remoting] to use interface [management]
>20:33:05,431 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [txn-recovery-environment] to [${rhq.server.socket.binding.port.txn-recovery-environment:3712}]
>20:33:05,510 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Setting socket binding [txn-status-manager] to [${rhq.server.socket.binding.port.txn-status-manager:3713}]
>20:33:05,584 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Deployment scanner turned off.
>20:33:05,610 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Default transaction timeout set to 600 seconds.
>20:33:05,694 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Logging category org.rhq set to [${rhq.server.log-level:INFO}]
>20:33:06,753 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] New keystore created [/qa/home/vnguyen/jon-server-3.2.0.GA/jbossas/standalone/configuration/rhq.keystore] with cert domain name of [CN=10.16.90.16, OU=RHQ, O=rhq-project.org, C=US]
>20:33:06,765 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Creating https connector...
>20:33:07,032 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] https connector created.
>20:33:07,177 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Security domain [RHQDSSecurityDomain] created
>20:33:07,223 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Cache container [rhq] created
>20:33:07,267 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Local Cache [rhqCache] created
>20:33:07,314 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Deployed Postgres JDBC driver
>20:33:07,392 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Deployed Oracle JDBC driver for future use
>20:33:07,458 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Created datasources
>20:33:07,673 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Enabled datasources
>20:33:07,815 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] JMS queue [AlertConditionQueue] created
>20:33:07,883 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] JMS queue [DriftChangesetQueue] created
>20:33:08,026 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] JMS queue [DriftFileQueue] created
>20:33:08,056 INFO  [org.rhq.enterprise.server.installer.ServerInstallUtil] Mail service has been configured.
>20:33:08,207 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] Installing RHQ EAR startup subsystem extension
>20:33:14,049 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] Will now ask the app server to reload its configuration
>20:33:14,086 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] App server has been successfully asked to reload its configuration
>20:33:14,135 ERROR [org.jboss.remoting.remote.connection] JBREM000200: Remote connection failed: java.io.IOException: JBREM000202: Abrupt close on Remoting connection 22b3ea59 to soaperf05.mw.lab.eng.bos.redhat.com/10.16.90.16:6999
>20:33:15,302 INFO  [org.rhq.enterprise.server.installer.InstallerServiceImpl] Installing RHQ EAR subsystem
>20:34:13,484 INFO  [org.rhq.enterprise.server.installer.Installer] Installation is complete. The server should be ready shortly.

Actions: View

Attachments on bug 1050751: 847457 | 847458 | 847459 | 847460 | 847704