[MCOL-3841] 1.4.2 multi-node with gluster failing to start via postConfigure - gluster assign/unassign errors Created: 2020-02-27  Updated: 2023-10-25  Resolved: 2023-10-25

Status: Closed
Project: MariaDB ColumnStore
Component/s: Columnstore Select Handler
Affects Version/s: 1.4.2
Fix Version/s: Icebox

Type: Bug Priority: Major
Reporter: David Hill (Inactive) Assignee: Unassigned
Resolution: Fixed Votes: 0
Labels: None
Environment:

Amazon EC2 2UM/3PM gluster storage centos 8



 Description   

Setting up a 2um/3pm gluster system on Centos 8 and it failed to start via postConfigure.

Setup 5 EC2 instances with 1.4.2 installed and post-install

Installed gluster on the PMS following this centos 8 procedure

https://computingforgeeks.com/setup-glusterfs-storage-with-heketi-on-centos-server/

sudo gluster --version
glusterfs 6.7

sudo mcsadmin getso

getsoftwareinfo Thu Feb 27 17:44:29 2020

SoftwareVersion = 1.4.2
SoftwareRelease = 1

kicked of postConfigure on pm1

sudo postConfigure

This is the MariaDB ColumnStore System Configuration and Installation tool.
It will Configure the MariaDB ColumnStore System and will perform a Package
Installation of all of the Servers within the System that is being configured.

IMPORTANT: This tool requires to run on the Performance Module #1

Prompting instructions:

Press 'enter' to accept a value in (), if available or
Enter one of the options within [], if available, or
Enter a new value

===== Setup System Server Type Configuration =====

There are 2 options when configuring the System Server Type: single and multi

'single' - Single-Server install is used when there will only be 1 server configured
on the system. It can also be used for production systems, if the plan is
to stay single-server.

'multi' - Multi-Server install is used when you want to configure multiple servers now or
in the future. With Multi-Server install, you can still configure just 1 server
now and add on addition servers/modules in the future.

Select the type of System Server install [1=single, 2=multi] (2) >

===== Setup System Module Type Configuration =====

There are 2 options when configuring the System Module Type: separate and combined

'separate' - User and Performance functionality on separate servers.

'combined' - User and Performance functionality on the same server

Select the type of System Module Install [1=separate, 2=combined] (1) >

Seperate Server Installation will be performed.

NOTE: Local Query Feature allows the ability to query data from a single Performance
Module. Check MariaDB ColumnStore Admin Guide for additional information.

Enable Local Query feature? [y,n] >

NOTE: The MariaDB ColumnStore Schema Sync feature will replicate all of the
schemas and InnoDB tables across the User Module nodes. This feature can be enabled
or disabled, for example, if you wish to configure your own replication post installation.

MariaDB ColumnStore Schema Sync feature is Enabled, do you want to leave enabled? [y,n] >

NOTE: MariaDB ColumnStore Replication Feature is enabled

Enter System Name (columnstore-1) >

===== Setup Storage Configuration =====

----- Setup Performance Module DBRoot Data Storage Mount Configuration -----

Columnstore supports the following storage options...
1 - internal. This uses the linux VFS to access files and does
not manage the filesystem.
2 - external *. If you have other mountable filesystems you would
like ColumnStore to use & manage, select this option.
3 - GlusterFS * Note: glusterd service must be running and enabled on
all PMs.
4 - S3-compatible cloud storage *. Note: that should be configured
before running postConfigure (see storagemanager.cnf)

  • - This option enables data replication and server failover in a
    multi-node configuration.

These options are available on this system: [1, 2, 3, 4]
Select the type of data storage (1) > 3

===== Setup Memory Configuration =====

NOTE: Setting 'NumBlocksPct' to 70%
Setting 'TotalUmMemory' to 50%

===== Setup the Module Configuration =====

----- User Module Configuration -----

Enter number of User Modules [1,1024] (1) > 2

      • User Module #1 Configuration ***

Enter Nic Interface #1 Host Name (unassigned) > um1
Enter Nic Interface #1 IP Address or hostname of um1 (0.0.0.0) > 172.30.0.128
Enter Nic Interface #2 Host Name (unassigned) >

      • User Module #2 Configuration ***

Enter Nic Interface #1 Host Name (unassigned) > um2
Enter Nic Interface #1 IP Address or hostname of um2 (0.0.0.0) > 172.30.0.102
Enter Nic Interface #2 Host Name (unassigned) >

----- Performance Module Configuration -----

Enter number of Performance Modules [1,1024] (1) > 3

      • Parent OAM Module Performance Module #1 Configuration ***

Enter Nic Interface #1 Host Name (ip-172-30-0-250.ec2.internal) > pm1
Enter Nic Interface #1 IP Address or hostname of pm1 (0.0.0.0) > 172.30.0.250
Enter Nic Interface #2 Host Name (unassigned) >
Enter the list (Nx,Ny,Nz) or range (Nx-Nz) of DBRoot IDs assigned to module 'pm1' (1) >

      • Performance Module #2 Configuration ***

Enter Nic Interface #1 Host Name (unassigned) > pm2
Enter Nic Interface #1 IP Address or hostname of pm2 (0.0.0.0) > 172.30.0.204
Enter Nic Interface #2 Host Name (unassigned) >
Enter the list (Nx,Ny,Nz) or range (Nx-Nz) of DBRoot IDs assigned to module 'pm2' () > 2

      • Performance Module #3 Configuration ***

Enter Nic Interface #1 Host Name (unassigned) > pm3
Enter Nic Interface #1 IP Address or hostname of pm3 (0.0.0.0) > 172.30.0.229
Enter Nic Interface #2 Host Name (unassigned) >
Enter the list (Nx,Ny,Nz) or range (Nx-Nz) of DBRoot IDs assigned to module 'pm3' () > 3

Next step is to enter the password to access the other Servers.
This is either user password or you can default to using a ssh key
If using a user password, the password needs to be the same on all Servers.

Enter password, hit 'enter' to default to using a ssh key, or 'exit' >

----- Performing Install on 'um1 / um1' -----

Install log file is located here: /tmp/columnstore_tmp_files/um1_binary_install.log

----- Performing Install on 'um2 / um2' -----

Install log file is located here: /tmp/columnstore_tmp_files/um2_binary_install.log

----- Performing Install on 'pm2 / pm2' -----

Install log file is located here: /tmp/columnstore_tmp_files/pm2_binary_install.log

----- Performing Install on 'pm3 / pm3' -----

Install log file is located here: /tmp/columnstore_tmp_files/pm3_binary_install.log

===== Configuring MariaDB ColumnStore Data Redundancy Functionality =====

----- Setup Data Redundancy Copy Count Configuration -----

Setup the Number of Copies: This is the total number of copies of the data
in the system. At least 2, but not more than the number of PMs(3), are required.

Enter Number of Copies [2-3] (2) > 3

----- Setup Data Redundancy Network Configuration -----

'existing' - This is specified when using previously configured network devices. (NIC Interface #1)
No additional network configuration is required with this option.

'dedicated' - This is specified when it is desired for Data Redundancy traffic to use
a separate network than one previously configured for ColumnStore.
You will be prompted to provide Hostname and IP information for each PM.

Select the data redundancy network [1=existing, 2=dedicated] (1) >

----- Performing Data Redundancy Configuration -----

gluster peer probe 172.30.0.250
gluster peer probe 172.30.0.204
gluster peer probe 172.30.0.229
Gluster create and start volume dbroot1...DONE
Gluster create and start volume dbroot2...DONE
Gluster create and start volume dbroot3...DONE

----- Data Redundancy Configuration Complete -----

===== Checking MariaDB ColumnStore System Logging Functionality =====

The MariaDB ColumnStore system logging is setup and working on local server

MariaDB ColumnStore System Configuration and Installation is Completed

===== MariaDB ColumnStore System Startup =====

System Configuration is complete.
Performing System Installation.

----- Starting MariaDB ColumnStore on local server -----

MariaDB ColumnStore successfully started

MariaDB ColumnStore Database Platform Starting, please wait .............

TIMEOUT: ProcMon not responding to getSystemStatus FAILED

IMPORTANT: There was a system startup failed, once issue has been resolved, rerun postConfigure

MariaDB ColumnStore System failed to start, check log files in /var/log/mariadb/columnstore
Enter the following command to define MariaDB ColumnStore Alias Commands

. /etc/profile.d/columnstoreAlias.sh

Enter 'mariadb' to access the MariaDB ColumnStore SQL console
Enter 'mcsadmin' to access the MariaDB ColumnStore Admin console

NOTE: The MariaDB ColumnStore Alias Commands are in /etc/profile.d/columnstoreAlias

[centos@ip-172-30-0-250 ~]$ sudo ma
sudo: ma: command not found
[centos@ip-172-30-0-250 ~]$ sudo mcsadmin

MariaDB ColumnStore Admin Console
enter 'help' for list of commands
enter 'exit' to exit the MariaDB ColumnStore Command Console
use up/down arrows to recall commands

Active Alarm Counts: Critical = 0, Major = 0, Minor = 0, Warning = 0, Info = 0

Critical Active Alarms:

mcsadmin> getsystemi
getsysteminfo Thu Feb 27 17:12:41 2020

System columnstore-1

System and Module statuses

Component Status Last Status Change
------------ -------------------------- ------------------------
System FAILED Thu Feb 27 17:11:08 2020

Module um1 MAN_INIT Thu Feb 27 17:10:41 2020
Module um2 MAN_INIT Thu Feb 27 17:10:46 2020
Module pm1 MAN_INIT Thu Feb 27 17:10:36 2020
Module pm2 UP Thu Feb 27 17:09:36 2020
Module pm3 UP Thu Feb 27 17:09:36 2020

Active Parent OAM Performance Module is 'pm1'
Primary Front-End MariaDB ColumnStore Module is 'um1'
MariaDB ColumnStore Replication Feature is enabled

MariaDB ColumnStore Process statuses

Process Module Status Last Status Change Process ID
------------------ ------ --------------- ------------------------ ----------
ProcessMonitor um1 ACTIVE Thu Feb 27 17:10:17 2020 2789
ServerMonitor um1 ACTIVE Thu Feb 27 17:10:45 2020 3127
DBRMWorkerNode um1 INITIAL
ExeMgr um1 INITIAL
DDLProc um1 INITIAL
DMLProc um1 INITIAL
mysqld um1 ACTIVE Thu Feb 27 17:10:43 2020 3085

ProcessMonitor um2 ACTIVE Thu Feb 27 17:10:18 2020 2780
ServerMonitor um2 ACTIVE Thu Feb 27 17:10:49 2020 3136
DBRMWorkerNode um2 INITIAL
ExeMgr um2 INITIAL
DDLProc um2 INITIAL
DMLProc um2 INITIAL
mysqld um2 ACTIVE Thu Feb 27 17:10:47 2020 3094

ProcessMonitor pm1 ACTIVE Thu Feb 27 17:09:34 2020 3230
ProcessManager pm1 ACTIVE Thu Feb 27 17:10:56 2020 5542
DBRMControllerNode pm1 ACTIVE Thu Feb 27 17:10:50 2020 5478
ServerMonitor pm1 ACTIVE Thu Feb 27 17:10:51 2020 5517
DBRMWorkerNode pm1 ACTIVE Thu Feb 27 17:10:52 2020 5554
PrimProc pm1 ACTIVE Thu Feb 27 17:10:56 2020 5698
WriteEngineServer pm1 ACTIVE Thu Feb 27 17:10:57 2020 5752

ProcessMonitor pm2 ACTIVE Thu Feb 27 17:10:28 2020 2735
ProcessManager pm2 HOT_STANDBY Thu Feb 27 17:10:36 2020 2923
DBRMControllerNode pm2 INITIAL
ServerMonitor pm2 INITIAL
DBRMWorkerNode pm2 INITIAL
PrimProc pm2 INITIAL
WriteEngineServer pm2 INITIAL

ProcessMonitor pm3 ACTIVE Thu Feb 27 17:10:30 2020 2736
ProcessManager pm3 COLD_STANDBY Thu Feb 27 17:10:29 2020
DBRMControllerNode pm3 INITIAL
ServerMonitor pm3 INITIAL
DBRMWorkerNode pm3 INITIAL
PrimProc pm3 INITIAL
WriteEngineServer pm3 INITIAL

Active Alarm Counts: Critical = 0, Major = 0, Minor = 0, Warning = 0, Info = 0
mcsadmin> exit

sudo cat crit.log
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.797238 |0|0|0| C 18 CAL0000: *****MariaDB ColumnStore Process Restarting: ProcessManager, old PID = 3421
Feb 27 17:11:08 ip-172-30-0-250 ProcessManager[5542]: 08.048648 |0|0|0| C 17 CAL0000: startMgrProcessThread Exit with a failure, error returned from startSystemThread
[centos@ip-172-30-0-250 columnstore]$ sudo cat err.log
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.603804 |0|0|0| E 18 CAL0000: glusterAssign mount failure: dbroot: 1 error: 1
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.603903 |0|0|0| E 18 CAL0000: glusterAssign failed.
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.609714 |0|0|0| E 18 CAL0000: Error assigning gluster dbroot# 1
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.798631 |0|0|0| E 18 CAL0000: glusterUnassign mount failure: dbroot: 2 error: 32
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.802900 |0|0|0| E 18 CAL0000: glusterUnassign mount failure: dbroot: 3 error: 32
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.604827 |0|0|0| E 18 CAL0000: glusterAssign mount failure: dbroot: 1 error: 1
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.604918 |0|0|0| E 18 CAL0000: glusterAssign failed.
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.607492 |0|0|0| E 18 CAL0000: Error assigning gluster dbroot# 1
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.756645 |0|0|0| E 18 CAL0000: glusterUnassign mount failure: dbroot: 2 error: 32
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.761881 |0|0|0| E 18 CAL0000: glusterUnassign mount failure: dbroot: 3 error: 32
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.797238 |0|0|0| C 18 CAL0000: *****MariaDB ColumnStore Process Restarting: ProcessManager, old PID = 3421
Feb 27 17:11:08 ip-172-30-0-250 ProcessManager[5542]: 08.048648 |0|0|0| C 17 CAL0000: startMgrProcessThread Exit with a failure, error returned from startSystemThread

ll /var/lib/columnstore/
data/ data1/ data2/ data3/ gluster/ local/
[root@ip-172-30-0-250 ~]# ll /var/lib/columnstore/
total 0
drwxr-xr-x. 3 root root 18 Feb 4 17:12 data
drwxr-xr-x. 3 root root 47 Feb 27 17:09 data1
drwxr-xr-x 2 root root 6 Feb 27 17:00 data2
drwxr-xr-x 2 root root 6 Feb 27 17:00 data3
drwxr-xr-x 5 root root 48 Feb 27 17:08 gluster
drwxr-xr-x. 3 root root 51 Feb 27 17:10 local
[root@ip-172-30-0-250 ~]# cd /var/lib/columnstore/
[root@ip-172-30-0-250 columnstore]# cd gluster/
[root@ip-172-30-0-250 gluster]# ll
total 0
drwxr-xr-x 3 root root 24 Feb 27 17:08 brick1
drwxr-xr-x 3 root root 24 Feb 27 17:08 brick2
drwxr-xr-x 3 root root 24 Feb 27 17:09 brick3
[root@ip-172-30-0-250 gluster]# cat /etc/fstab
LABEL=root / xfs defaults 0 0



 Comments   
Comment by David Hill (Inactive) [ 2020-02-27 ]

Feb 27 17:09:06 ip-172-30-0-250 ProcessMonitor[3230]: 06.243322 |0|0|0| I 18 CAL0000: *********Process Monitor Started*********
Feb 27 17:09:06 ip-172-30-0-250 ProcessMonitor[3230]: 06.243344 |0|0|0| D 18 CAL0000:
Feb 27 17:09:06 ip-172-30-0-250 ProcessMonitor[3230]: 06.243360 |0|0|0| D 18 CAL0000: *********Process Monitor Started*********
Feb 27 17:09:06 ip-172-30-0-250 ProcessMonitor[3230]: 06.246157 |0|0|0| D 18 CAL0000: Message Thread started ..
Feb 27 17:09:06 ip-172-30-0-250 ProcessMonitor[3230]: 06.246453 |0|0|0| D 18 CAL0000: Cloud setting = unassigned
Feb 27 17:09:06 ip-172-30-0-250 ProcessMonitor[3230]: 06.246504 |0|0|0| D 18 CAL0000: PORTS: pm1_ProcessMonitor/8800
Feb 27 17:09:06 ip-172-30-0-250 ProcessMonitor[3230]: 06.250663 |0|0|0| D 18 CAL0000: ProcMon: Starting as ACTIVE Parent
Feb 27 17:09:06 ip-172-30-0-250 ProcessMonitor[3230]: 06.250772 |0|0|0| D 18 CAL0000: createDataDirs called
Feb 27 17:09:06 ip-172-30-0-250 ProcessMonitor[3230]: 06.271519 |0|0|0| D 18 CAL0000: checkDataMount called
Feb 27 17:09:06 ip-172-30-0-250 ProcessMonitor[3230]: 06.276228 |0|0|0| D 18 CAL0000: glusterAssign called : 1
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.603804 |0|0|0| E 18 CAL0000: glusterAssign mount failure: dbroot: 1 error: 1
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.603903 |0|0|0| E 18 CAL0000: glusterAssign failed.
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.609714 |0|0|0| E 18 CAL0000: Error assigning gluster dbroot# 1
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.609828 |0|0|0| D 18 CAL0000: unmountExtraDBroots called
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.704329 |0|0|0| D 18 CAL0000: flushInodeCache successful
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.707450 |0|0|0| D 18 CAL0000: glusterUnassign called: 2
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.798631 |0|0|0| E 18 CAL0000: glusterUnassign mount failure: dbroot: 2 error: 32
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.798756 |0|0|0| I 18 CAL0000: Gluster unassign gluster dbroot# 2
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.798843 |0|0|0| D 18 CAL0000: glusterUnassign called: 3
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.802900 |0|0|0| E 18 CAL0000: glusterUnassign mount failure: dbroot: 3 error: 32
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.802983 |0|0|0| I 18 CAL0000: Gluster unassign gluster dbroot# 3
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.803009 |0|0|0| D 18 CAL0000: unmountExtraDBroots finished
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.814998 |0|0|0| D 18 CAL0000: flushInodeCache successful
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.818712 |0|0|0| D 18 CAL0000: WAITING FOR mainResumeFlag to be set
Feb 27 17:09:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.822404 |0|0|0| D 18 CAL0000: statusControlThread Thread started ..
Feb 27 17:09:18 ip-172-30-0-250 ProcessMonitor[3230]: 18.818911 |0|0|0| D 18 CAL0000: WAITING FOR mainResumeFlag to be set
Feb 27 17:09:19 ip-172-30-0-250 ProcessMonitor[3230]: 19.819160 |0|0|0| D 18 CAL0000: WAITING FOR mainResumeFlag to be set
Feb 27 17:09:20 ip-172-30-0-250 ProcessMonitor[3230]: 20.819413 |0|0|0| D 18 CAL0000: WAITING FOR mainResumeFlag to be set
Feb 27 17:09:21 ip-172-30-0-250 ProcessMonitor[3230]: 21.819668 |0|0|0| D 18 CAL0000: WAITING FOR mainResumeFlag to be set
Feb 27 17:09:22 ip-172-30-0-250 ProcessMonitor[3230]: 22.819929 |0|0|0| D 18 CAL0000: WAITING FOR mainResumeFlag to be set
Feb 27 17:09:23 ip-172-30-0-250 ProcessMonitor[3230]: 23.820185 |0|0|0| D 18 CAL0000: WAITING FOR mainResumeFlag to be set
Feb 27 17:09:24 ip-172-30-0-250 ProcessMonitor[3230]: 24.820433 |0|0|0| D 18 CAL0000: WAITING FOR mainResumeFlag to be set
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.462978 |0|0|0| D 18 CAL0000: Process Status shared Memory allocated and Initialized
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.463184 |0|0|0| D 18 CAL0000: System/Module Status shared Memory allociated and Initialized
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.463672 |0|0|0| D 18 CAL0000: NIC Status shared Memory allociated and Initialized
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.464204 |0|0|0| D 18 CAL0000: Ext Device Status shared Memory allociated and Initialized
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.469517 |0|0|0| D 18 CAL0000: Dbroot Status shared Memory allociated and Initialized
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.501622 |0|0|0| D 18 CAL0000: statusControlThread Thread reading ProcStatusControl port
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.821405 |0|0|0| D 18 CAL0000: glusterctl called :1 2 3
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.833713 |0|0|0| D 18 CAL0000: Columnstore.xml Standby OAM updated : pm2:172.30.0.204
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.833770 |0|0|0| D 18 CAL0000: Set Standby Module = pm2
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.836049 |0|0|0| D 18 CAL0000: StatusUpdate of Process ProcessMonitor State = 1 PID = 3230
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.836940 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ProcessMonitor State = AUTO_OFFLINE
Feb 27 17:09:25 ip-172-30-0-250 ProcessMonitor[3230]: 25.837002 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ProcessMonitor State = AUTO_OFFLINE PID = 3230
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.612883 |0|0|0| D 18 CAL0000: SYSTEM STATUS = 9
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.613064 |0|0|0| D 18 CAL0000: checkSpecialProcessState on : ProcessManager
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.613092 |0|0|0| D 18 CAL0000: checkSpecialProcessState status return : 2
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.613138 |0|0|0| D 18 CAL0000: STOPPING Process: ProcessManager
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.613245 |0|0|0| D 18 CAL0000: StatusUpdate of Process ProcessManager State = 1 PID = 0
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.614462 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ProcessManager State = AUTO_OFFLINE
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.614521 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ProcessManager State = AUTO_OFFLINE PID = 0
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.616656 |0|0|0| D 18 CAL0000: Send SET Alarm ID 13 on device ProcessManager
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.617425 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 25 on device ProcessManager
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.747940 |0|0|0| D 18 CAL0000: Pkill Process just to make sure: ProcMgr*
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.748122 |0|0|0| D 18 CAL0000: STARTING Process: ProcessManager
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.748147 |0|0|0| D 18 CAL0000: Process location: /usr/bin/ProcMgr
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.750843 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 27 on device DBRM
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.756347 |0|0|0| D 18 CAL0000: Pkill Process just to make sure: ProcMgr*
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.756456 |0|0|0| D 18 CAL0000: StatusUpdate of Process ProcessManager State = 3 PID = 0
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.757036 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ProcessManager State = AUTO_INIT
Feb 27 17:09:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.757086 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ProcessManager State = AUTO_INIT PID = 0
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.775513 |0|0|0| D 18 CAL0000: StatusUpdate of Process ProcessManager State = 21 PID = 3421
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.776275 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ProcessManager State = PID_UPDATE
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.776330 |0|0|0| D 18 CAL0000: ProcessManager PID is 3421
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.776339 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ProcessManager State = PID_UPDATE PID = 3421
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.776606 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 21 on device ProcessManager
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.777270 |0|0|0| I 18 CAL0000:
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.777564 |0|0|0| D 18 CAL0000: Child Process Monitoring Thread started ..
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.778402 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 13 on device ProcessManager
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.778471 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ProcessMonitor State = ACTIVE
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.778525 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ProcessMonitor State = ACTIVE PID = 3230
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.779060 |0|0|0| D 18 CAL0000: processInitComplete Successfully Called
Feb 27 17:09:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.779489 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 25 on device ProcessManager
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.169300 |0|0|0| I 17 CAL0000:
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.169374 |0|0|0| I 17 CAL0000: *********Process Manager Started*********
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.170148 |0|0|0| D 17 CAL0000: Main: DBRootStorageType = DataRedundancy
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.170661 |0|0|0| I 17 CAL0000: Running Active
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.170701 |0|0|0| D 17 CAL0000: Running Active
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.171355 |0|0|0| D 17 CAL0000: Main Ethernet Port = eth0
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.171436 |0|0|0| D 17 CAL0000: updateWorkerNodeconfig Started
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.285786 |0|0|0| D 17 CAL0000: updateWorkerNodeconfig completed
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.287421 |0|0|0| D 17 CAL0000: pingDeviceThread launched
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.292073 |0|0|0| D 17 CAL0000: pingDeviceThread: DBRootStorageType = DataRedundancy
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.318412 |0|0|0| D 17 CAL0000: Set NIC um1 State = 8
Feb 27 17:09:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.318833 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set NIC um1 State = UP
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.320819 |0|0|0| D 17 CAL0000: Set Module um1 State = 8
Feb 27 17:09:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.321219 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Module um1 State = UP
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.326328 |0|0|0| D 17 CAL0000: Set NIC um2 State = 8
Feb 27 17:09:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.326740 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set NIC um2 State = UP
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.328654 |0|0|0| D 17 CAL0000: Set Module um2 State = 8
Feb 27 17:09:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.329034 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Module um2 State = UP
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.333034 |0|0|0| D 17 CAL0000: Set NIC pm1 State = 8
Feb 27 17:09:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.333423 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set NIC pm1 State = UP
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.335548 |0|0|0| D 17 CAL0000: Set Module pm1 State = 8
Feb 27 17:09:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.335972 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Module pm1 State = UP
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.341046 |0|0|0| D 17 CAL0000: Set NIC pm2 State = 8
Feb 27 17:09:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.341438 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set NIC pm2 State = UP
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.343572 |0|0|0| D 17 CAL0000: Set Module pm2 State = 8
Feb 27 17:09:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.343985 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Module pm2 State = UP
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.348689 |0|0|0| D 17 CAL0000: Set NIC pm3 State = 8
Feb 27 17:09:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.349092 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set NIC pm3 State = UP
Feb 27 17:09:36 ip-172-30-0-250 ProcessManager[3421]: 36.351302 |0|0|0| D 17 CAL0000: Set Module pm3 State = 8
Feb 27 17:09:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.351670 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Module pm3 State = UP
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.287836 |0|0|0| D 17 CAL0000: startMgrProcessThread launched
Feb 27 17:09:41 ip-172-30-0-250 ProcessMonitor[3230]: 41.287952 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ProcessManager State = ACTIVE
Feb 27 17:09:41 ip-172-30-0-250 ProcessMonitor[3230]: 41.287998 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ProcessManager State = ACTIVE PID = 3421
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.290561 |0|0|0| D 17 CAL0000: processInitComplete Successfully Called
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.293078 |0|0|0| D 17 CAL0000: set ProcMgr IPaddr to 172.30.0.250
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.293114 |0|0|0| D 17 CAL0000: distributeConfigFile called for system file = Columnstore.xml
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.302022 |0|0|0| D 17 CAL0000: Message Thread started ..
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.302065 |0|0|0| D 17 CAL0000: Alarm Message Thread started ..
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.307211 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.308074 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.313882 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.314746 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.321036 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.321842 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.326949 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.327667 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.327842 |0|0|0| D 17 CAL0000: distributeConfigFile called for system file = ProcessConfig.xml
Feb 27 17:09:41 ip-172-30-0-250 ProcessManager[3421]: 41.336922 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:09:42 ip-172-30-0-250 ProcessManager[3421]: 42.310671 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:09:42 ip-172-30-0-250 ProcessManager[3421]: 42.316416 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:09:42 ip-172-30-0-250 ProcessManager[3421]: 42.317130 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:09:42 ip-172-30-0-250 ProcessManager[3421]: 42.322314 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:09:42 ip-172-30-0-250 ProcessManager[3421]: 42.323674 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:09:42 ip-172-30-0-250 ProcessManager[3421]: 42.328810 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:09:42 ip-172-30-0-250 ProcessManager[3421]: 42.329501 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:09:42 ip-172-30-0-250 ProcessManager[3421]: 42.329567 |0|0|0| D 17 CAL0000: configureModule: Process module um1
Feb 27 17:09:42 ip-172-30-0-250 ProcessManager[3421]: 42.329626 |0|0|0| D 17 CAL0000: distributeConfigFile called for um1 file = Columnstore.xml
Feb 27 17:09:42 ip-172-30-0-250 ProcessManager[3421]: 42.337709 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:09:43 ip-172-30-0-250 ProcessManager[3421]: 43.311900 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:09:43 ip-172-30-0-250 ProcessManager[3421]: 43.312062 |0|0|0| D 17 CAL0000: distributeConfigFile called for um1 file = ProcessConfig.xml
Feb 27 17:09:43 ip-172-30-0-250 ProcessManager[3421]: 43.320171 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:09:44 ip-172-30-0-250 ProcessManager[3421]: 44.313627 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:09:44 ip-172-30-0-250 ProcessManager[3421]: 44.317928 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:09:45 ip-172-30-0-250 ProcessManager[3421]: 45.319005 |0|0|0| D 17 CAL0000: configureModule - procmon configure successful
Feb 27 17:09:45 ip-172-30-0-250 ProcessManager[3421]: 45.319091 |0|0|0| D 17 CAL0000: configureModule: Process module um2
Feb 27 17:09:45 ip-172-30-0-250 ProcessManager[3421]: 45.319180 |0|0|0| D 17 CAL0000: distributeConfigFile called for um2 file = Columnstore.xml
Feb 27 17:09:45 ip-172-30-0-250 ProcessManager[3421]: 45.327349 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:09:45 ip-172-30-0-250 ProcessManager[3421]: 45.328277 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:09:45 ip-172-30-0-250 ProcessManager[3421]: 45.328376 |0|0|0| D 17 CAL0000: distributeConfigFile called for um2 file = ProcessConfig.xml
Feb 27 17:09:45 ip-172-30-0-250 ProcessManager[3421]: 45.335917 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:09:46 ip-172-30-0-250 ProcessManager[3421]: 46.330037 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:09:46 ip-172-30-0-250 ProcessManager[3421]: 46.334352 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:09:47 ip-172-30-0-250 ProcessManager[3421]: 47.340304 |0|0|0| D 17 CAL0000: configureModule - procmon configure successful
Feb 27 17:09:47 ip-172-30-0-250 ProcessManager[3421]: 47.340389 |0|0|0| D 17 CAL0000: configureModule: Process module pm2
Feb 27 17:09:47 ip-172-30-0-250 ProcessManager[3421]: 47.340476 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm2 file = Columnstore.xml
Feb 27 17:09:47 ip-172-30-0-250 ProcessManager[3421]: 47.348801 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:09:47 ip-172-30-0-250 ProcessManager[3421]: 47.349753 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:09:47 ip-172-30-0-250 ProcessManager[3421]: 47.349847 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm2 file = ProcessConfig.xml
Feb 27 17:09:47 ip-172-30-0-250 ProcessManager[3421]: 47.357408 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:09:48 ip-172-30-0-250 ProcessManager[3421]: 48.351520 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:09:48 ip-172-30-0-250 ProcessManager[3421]: 48.355803 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:09:49 ip-172-30-0-250 ProcessManager[3421]: 49.356170 |0|0|0| D 17 CAL0000: configureModule - procmon configure successful
Feb 27 17:09:49 ip-172-30-0-250 ProcessManager[3421]: 49.356256 |0|0|0| D 17 CAL0000: configureModule: Process module pm3
Feb 27 17:09:49 ip-172-30-0-250 ProcessManager[3421]: 49.356346 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm3 file = Columnstore.xml
Feb 27 17:09:49 ip-172-30-0-250 ProcessManager[3421]: 49.364564 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:09:49 ip-172-30-0-250 ProcessManager[3421]: 49.365404 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:09:49 ip-172-30-0-250 ProcessManager[3421]: 49.365500 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm3 file = ProcessConfig.xml
Feb 27 17:09:49 ip-172-30-0-250 ProcessManager[3421]: 49.373283 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:09:50 ip-172-30-0-250 ProcessManager[3421]: 50.367079 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:09:50 ip-172-30-0-250 ProcessManager[3421]: 50.371368 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:09:51 ip-172-30-0-250 ProcessManager[3421]: 51.371927 |0|0|0| D 17 CAL0000: configureModule - procmon configure successful
Feb 27 17:09:51 ip-172-30-0-250 ProcessManager[3421]: 51.379153 |0|0|0| D 17 CAL0000: updatePMSconfig Started
Feb 27 17:09:51 ip-172-30-0-250 ProcessManager[3421]: 51.390546 |0|0|0| D 17 CAL0000: updatePMSconfig completed
Feb 27 17:09:51 ip-172-30-0-250 ProcessManager[3421]: 51.393276 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:09:51 ip-172-30-0-250 ProcessManager[3421]: 51.394973 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:09:51 ip-172-30-0-250 ProcessManager[3421]: 51.398531 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:09:51 ip-172-30-0-250 ProcessManager[3421]: 51.400395 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:09:52 ip-172-30-0-250 ProcessManager[3421]: 52.402629 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:09:52 ip-172-30-0-250 ProcessManager[3421]: 52.404488 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:09:52 ip-172-30-0-250 ProcessManager[3421]: 52.408103 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:09:52 ip-172-30-0-250 ProcessManager[3421]: 52.410030 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:09:53 ip-172-30-0-250 ProcessManager[3421]: 53.412300 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:09:53 ip-172-30-0-250 ProcessManager[3421]: 53.414073 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:09:53 ip-172-30-0-250 ProcessManager[3421]: 53.417862 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:09:53 ip-172-30-0-250 ProcessManager[3421]: 53.419790 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:09:54 ip-172-30-0-250 ProcessManager[3421]: 54.422064 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:09:54 ip-172-30-0-250 ProcessManager[3421]: 54.423937 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:09:54 ip-172-30-0-250 ProcessManager[3421]: 54.427734 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:09:54 ip-172-30-0-250 ProcessManager[3421]: 54.429709 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:09:55 ip-172-30-0-250 ProcessManager[3421]: 55.431976 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:09:55 ip-172-30-0-250 ProcessManager[3421]: 55.433913 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:09:55 ip-172-30-0-250 ProcessManager[3421]: 55.437624 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:09:55 ip-172-30-0-250 ProcessManager[3421]: 55.439542 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:09:56 ip-172-30-0-250 ProcessManager[3421]: 56.441660 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:09:56 ip-172-30-0-250 ProcessManager[3421]: 56.443361 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:09:56 ip-172-30-0-250 ProcessManager[3421]: 56.447014 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:09:56 ip-172-30-0-250 ProcessManager[3421]: 56.448919 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:09:57 ip-172-30-0-250 ProcessManager[3421]: 57.451064 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:09:57 ip-172-30-0-250 ProcessManager[3421]: 57.452900 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:09:57 ip-172-30-0-250 ProcessManager[3421]: 57.456528 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:09:57 ip-172-30-0-250 ProcessManager[3421]: 57.458561 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:09:58 ip-172-30-0-250 ProcessManager[3421]: 58.460842 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:09:58 ip-172-30-0-250 ProcessManager[3421]: 58.462619 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:09:58 ip-172-30-0-250 ProcessManager[3421]: 58.466142 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:09:58 ip-172-30-0-250 ProcessManager[3421]: 58.467942 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.332063 |0|0|0| I 17 CAL0000: MSG RECEIVED: Distribute Config File pm2/Columnstore.xml
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.332164 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm2 file = Columnstore.xml
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.340401 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.341214 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.341312 |0|0|0| I 17 CAL0000: Distribute Config File Completed pm2/Columnstore.xml
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.352373 |0|0|0| I 17 CAL0000: MSG RECEIVED: Distribute Config File pm2/ProcessConfig.xml
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.352478 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm2 file = ProcessConfig.xml
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.360725 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.470189 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.471995 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.475775 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:09:59 ip-172-30-0-250 ProcessManager[3421]: 59.477646 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:00 ip-172-30-0-250 ProcessManager[3421]: 00.343045 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:10:00 ip-172-30-0-250 ProcessManager[3421]: 00.343163 |0|0|0| I 17 CAL0000: Distribute Config File Completed pm2/ProcessConfig.xml
Feb 27 17:10:00 ip-172-30-0-250 ProcessManager[3421]: 00.479823 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:00 ip-172-30-0-250 ProcessManager[3421]: 00.481614 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:00 ip-172-30-0-250 ProcessManager[3421]: 00.485154 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:00 ip-172-30-0-250 ProcessManager[3421]: 00.487110 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.438579 |0|0|0| I 17 CAL0000: MSG RECEIVED: Distribute Config File pm3/Columnstore.xml
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.438685 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm3 file = Columnstore.xml
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.446922 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.447729 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.447822 |0|0|0| I 17 CAL0000: Distribute Config File Completed pm3/Columnstore.xml
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.459460 |0|0|0| I 17 CAL0000: MSG RECEIVED: Distribute Config File pm3/ProcessConfig.xml
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.459555 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm3 file = ProcessConfig.xml
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.468231 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.489257 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.491209 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.494871 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:01 ip-172-30-0-250 ProcessManager[3421]: 01.496741 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:02 ip-172-30-0-250 ProcessManager[3421]: 02.449557 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:10:02 ip-172-30-0-250 ProcessManager[3421]: 02.449666 |0|0|0| I 17 CAL0000: Distribute Config File Completed pm3/ProcessConfig.xml
Feb 27 17:10:02 ip-172-30-0-250 ProcessManager[3421]: 02.499133 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:02 ip-172-30-0-250 ProcessManager[3421]: 02.500958 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:02 ip-172-30-0-250 ProcessManager[3421]: 02.504808 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:02 ip-172-30-0-250 ProcessManager[3421]: 02.506821 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:03 ip-172-30-0-250 ProcessManager[3421]: 03.509092 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:03 ip-172-30-0-250 ProcessManager[3421]: 03.510997 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:03 ip-172-30-0-250 ProcessManager[3421]: 03.514605 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:03 ip-172-30-0-250 ProcessManager[3421]: 03.516563 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:04 ip-172-30-0-250 ProcessManager[3421]: 04.518838 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:04 ip-172-30-0-250 ProcessManager[3421]: 04.520729 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:04 ip-172-30-0-250 ProcessManager[3421]: 04.524317 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:04 ip-172-30-0-250 ProcessManager[3421]: 04.526209 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:05 ip-172-30-0-250 ProcessManager[3421]: 05.528391 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:05 ip-172-30-0-250 ProcessManager[3421]: 05.530276 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:05 ip-172-30-0-250 ProcessManager[3421]: 05.534070 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:05 ip-172-30-0-250 ProcessManager[3421]: 05.535999 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:06 ip-172-30-0-250 ProcessManager[3421]: 06.538181 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:06 ip-172-30-0-250 ProcessManager[3421]: 06.539954 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:06 ip-172-30-0-250 ProcessManager[3421]: 06.543492 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:06 ip-172-30-0-250 ProcessManager[3421]: 06.545323 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:07 ip-172-30-0-250 ProcessManager[3421]: 07.547637 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:07 ip-172-30-0-250 ProcessManager[3421]: 07.549574 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:07 ip-172-30-0-250 ProcessManager[3421]: 07.553337 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:07 ip-172-30-0-250 ProcessManager[3421]: 07.555333 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.557549 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.559351 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.563104 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.565020 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.634603 |0|0|0| I 17 CAL0000: MSG RECEIVED: Distribute Config File um1/Columnstore.xml
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.634721 |0|0|0| D 17 CAL0000: distributeConfigFile called for um1 file = Columnstore.xml
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.642812 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.643621 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.643694 |0|0|0| I 17 CAL0000: Distribute Config File Completed um1/Columnstore.xml
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.654897 |0|0|0| I 17 CAL0000: MSG RECEIVED: Distribute Config File um1/ProcessConfig.xml
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.655007 |0|0|0| D 17 CAL0000: distributeConfigFile called for um1 file = ProcessConfig.xml
Feb 27 17:10:08 ip-172-30-0-250 ProcessManager[3421]: 08.663217 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.567267 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.569084 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.572769 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.574796 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.645587 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.645725 |0|0|0| I 17 CAL0000: Distribute Config File Completed um1/ProcessConfig.xml
Feb 27 17:10:09 ip-172-30-0-250 ProcessMonitor[3230]: 09.651852 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um1/ProcessMonitor State = AUTO_OFFLINE
Feb 27 17:10:09 ip-172-30-0-250 ProcessMonitor[3230]: 09.651920 |0|0|0| D 18 CAL0000: statusControl: Set Process um1/ProcessMonitor State = AUTO_OFFLINE PID = 2789
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.818459 |0|0|0| I 17 CAL0000: MSG RECEIVED: Distribute Config File um2/Columnstore.xml
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.818570 |0|0|0| D 17 CAL0000: distributeConfigFile called for um2 file = Columnstore.xml
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.826763 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.827490 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.827581 |0|0|0| I 17 CAL0000: Distribute Config File Completed um2/Columnstore.xml
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.837540 |0|0|0| I 17 CAL0000: MSG RECEIVED: Distribute Config File um2/ProcessConfig.xml
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.837646 |0|0|0| D 17 CAL0000: distributeConfigFile called for um2 file = ProcessConfig.xml
Feb 27 17:10:09 ip-172-30-0-250 ProcessManager[3421]: 09.845837 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:10:10 ip-172-30-0-250 ProcessManager[3421]: 10.577105 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:10 ip-172-30-0-250 ProcessManager[3421]: 10.578856 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:10 ip-172-30-0-250 ProcessManager[3421]: 10.582477 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:10 ip-172-30-0-250 ProcessManager[3421]: 10.584343 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:10 ip-172-30-0-250 ProcessManager[3421]: 10.829340 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:10:10 ip-172-30-0-250 ProcessManager[3421]: 10.829461 |0|0|0| I 17 CAL0000: Distribute Config File Completed um2/ProcessConfig.xml
Feb 27 17:10:10 ip-172-30-0-250 ProcessMonitor[3230]: 10.835160 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um2/ProcessMonitor State = AUTO_OFFLINE
Feb 27 17:10:10 ip-172-30-0-250 ProcessMonitor[3230]: 10.835215 |0|0|0| D 18 CAL0000: statusControl: Set Process um2/ProcessMonitor State = AUTO_OFFLINE PID = 2780
Feb 27 17:10:11 ip-172-30-0-250 ProcessManager[3421]: 11.586523 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:11 ip-172-30-0-250 ProcessManager[3421]: 11.588314 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:11 ip-172-30-0-250 ProcessManager[3421]: 11.591949 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:11 ip-172-30-0-250 ProcessManager[3421]: 11.593975 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:11 ip-172-30-0-250 ProcessMonitor[3230]: 11.875987 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set DBroot 2 State = ACTIVE
Feb 27 17:10:12 ip-172-30-0-250 ProcessManager[3421]: 12.596206 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:12 ip-172-30-0-250 ProcessManager[3421]: 12.598040 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:12 ip-172-30-0-250 ProcessManager[3421]: 12.601769 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:12 ip-172-30-0-250 ProcessManager[3421]: 12.603624 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:13 ip-172-30-0-250 ProcessManager[3421]: 13.605911 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:13 ip-172-30-0-250 ProcessManager[3421]: 13.607715 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:13 ip-172-30-0-250 ProcessManager[3421]: 13.611282 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:13 ip-172-30-0-250 ProcessManager[3421]: 13.613211 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:13 ip-172-30-0-250 ProcessMonitor[3230]: 13.904566 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set DBroot 3 State = ACTIVE
Feb 27 17:10:14 ip-172-30-0-250 ProcessManager[3421]: 14.615397 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:14 ip-172-30-0-250 ProcessManager[3421]: 14.617186 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:14 ip-172-30-0-250 ProcessManager[3421]: 14.620759 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:14 ip-172-30-0-250 ProcessManager[3421]: 14.622611 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:15 ip-172-30-0-250 ProcessManager[3421]: 15.624796 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:15 ip-172-30-0-250 ProcessManager[3421]: 15.626542 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:15 ip-172-30-0-250 ProcessManager[3421]: 15.630243 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:15 ip-172-30-0-250 ProcessManager[3421]: 15.632141 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:16 ip-172-30-0-250 ProcessManager[3421]: 16.634398 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um1
Feb 27 17:10:16 ip-172-30-0-250 ProcessManager[3421]: 16.636258 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:16 ip-172-30-0-250 ProcessManager[3421]: 16.639956 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:16 ip-172-30-0-250 ProcessManager[3421]: 16.641897 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.091977 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um1/ProcessMonitor State = ACTIVE
Feb 27 17:10:17 ip-172-30-0-250 ProcessMonitor[3230]: 17.092044 |0|0|0| D 18 CAL0000: statusControl: Set Process um1/ProcessMonitor State = ACTIVE PID = 2789
Feb 27 17:10:17 ip-172-30-0-250 ProcessManager[3421]: 17.651345 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: um2
Feb 27 17:10:17 ip-172-30-0-250 ProcessManager[3421]: 17.654940 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:17 ip-172-30-0-250 ProcessManager[3421]: 17.656826 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:18 ip-172-30-0-250 ProcessMonitor[3230]: 18.357846 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um2/ProcessMonitor State = ACTIVE
Feb 27 17:10:18 ip-172-30-0-250 ProcessMonitor[3230]: 18.357913 |0|0|0| D 18 CAL0000: statusControl: Set Process um2/ProcessMonitor State = ACTIVE PID = 2780
Feb 27 17:10:18 ip-172-30-0-250 ProcessManager[3421]: 18.674639 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:18 ip-172-30-0-250 ProcessManager[3421]: 18.676729 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:19 ip-172-30-0-250 ProcessManager[3421]: 19.695961 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:19 ip-172-30-0-250 ProcessManager[3421]: 19.697875 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:19 ip-172-30-0-250 ProcessMonitor[3230]: 19.882271 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessMonitor State = AUTO_OFFLINE
Feb 27 17:10:19 ip-172-30-0-250 ProcessMonitor[3230]: 19.882339 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessMonitor State = AUTO_OFFLINE PID = 2735
Feb 27 17:10:20 ip-172-30-0-250 ProcessManager[3421]: 20.715973 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:20 ip-172-30-0-250 ProcessManager[3421]: 20.717841 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:21 ip-172-30-0-250 ProcessManager[3421]: 21.736009 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:21 ip-172-30-0-250 ProcessManager[3421]: 21.738073 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:21 ip-172-30-0-250 ProcessMonitor[3230]: 21.910279 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm3/ProcessMonitor State = AUTO_OFFLINE
Feb 27 17:10:21 ip-172-30-0-250 ProcessMonitor[3230]: 21.910341 |0|0|0| D 18 CAL0000: statusControl: Set Process pm3/ProcessMonitor State = AUTO_OFFLINE PID = 2736
Feb 27 17:10:22 ip-172-30-0-250 ProcessManager[3421]: 22.756229 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:22 ip-172-30-0-250 ProcessManager[3421]: 22.758240 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:23 ip-172-30-0-250 ProcessManager[3421]: 23.776338 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:23 ip-172-30-0-250 ProcessManager[3421]: 23.778279 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:24 ip-172-30-0-250 ProcessManager[3421]: 24.797158 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:24 ip-172-30-0-250 ProcessManager[3421]: 24.799148 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:25 ip-172-30-0-250 ProcessManager[3421]: 25.816673 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:25 ip-172-30-0-250 ProcessManager[3421]: 25.818531 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:26 ip-172-30-0-250 ProcessManager[3421]: 26.836510 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:26 ip-172-30-0-250 ProcessManager[3421]: 26.838523 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:27 ip-172-30-0-250 ProcessMonitor[3230]: 27.332879 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessManager State = HOT_STANDBY
Feb 27 17:10:27 ip-172-30-0-250 ProcessMonitor[3230]: 27.332946 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessManager State = HOT_STANDBY PID = 0
Feb 27 17:10:27 ip-172-30-0-250 ProcessMonitor[3230]: 27.336905 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessManager State = AUTO_OFFLINE
Feb 27 17:10:27 ip-172-30-0-250 ProcessMonitor[3230]: 27.336955 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessManager State = AUTO_OFFLINE PID = 0
Feb 27 17:10:27 ip-172-30-0-250 ProcessMonitor[3230]: 27.376877 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessManager State = HOT_STANDBY
Feb 27 17:10:27 ip-172-30-0-250 ProcessMonitor[3230]: 27.376950 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessManager State = HOT_STANDBY PID = 0
Feb 27 17:10:27 ip-172-30-0-250 ProcessManager[3421]: 27.858240 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm2
Feb 27 17:10:27 ip-172-30-0-250 ProcessManager[3421]: 27.860097 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:28 ip-172-30-0-250 ProcessMonitor[3230]: 28.395958 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessManager State = PID_UPDATE
Feb 27 17:10:28 ip-172-30-0-250 ProcessMonitor[3230]: 28.396027 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessManager State = PID_UPDATE PID = 2900
Feb 27 17:10:28 ip-172-30-0-250 ProcessMonitor[3230]: 28.398441 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessMonitor State = ACTIVE
Feb 27 17:10:28 ip-172-30-0-250 ProcessMonitor[3230]: 28.398486 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessMonitor State = ACTIVE PID = 2735
Feb 27 17:10:28 ip-172-30-0-250 ProcessManager[3421]: 28.885326 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:29 ip-172-30-0-250 ProcessMonitor[3230]: 29.359413 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm3/ProcessManager State = COLD_STANDBY
Feb 27 17:10:29 ip-172-30-0-250 ProcessMonitor[3230]: 29.359485 |0|0|0| D 18 CAL0000: statusControl: Set Process pm3/ProcessManager State = COLD_STANDBY PID = 0
Feb 27 17:10:29 ip-172-30-0-250 ProcessMonitor[3230]: 29.642887 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessManager State = HOT_STANDBY
Feb 27 17:10:29 ip-172-30-0-250 ProcessMonitor[3230]: 29.642957 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessManager State = HOT_STANDBY PID = 2900
Feb 27 17:10:29 ip-172-30-0-250 ProcessManager[3421]: 29.910007 |0|0|0| D 17 CAL0000: Module ProcMon not active yet: pm3
Feb 27 17:10:30 ip-172-30-0-250 ProcessMonitor[3230]: 30.361728 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm3/ProcessMonitor State = ACTIVE
Feb 27 17:10:30 ip-172-30-0-250 ProcessMonitor[3230]: 30.361799 |0|0|0| D 18 CAL0000: statusControl: Set Process pm3/ProcessMonitor State = ACTIVE PID = 2736
Feb 27 17:10:30 ip-172-30-0-250 ProcessManager[3421]: 30.940652 |0|0|0| D 17 CAL0000: startSystemThread launched
Feb 27 17:10:30 ip-172-30-0-250 ProcessManager[3421]: 30.941293 |0|0|0| D 17 CAL0000: Set System State = MAN_INIT
Feb 27 17:10:30 ip-172-30-0-250 ProcessMonitor[3230]: 30.941681 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set System State = MAN_INIT
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.367430 |0|0|0| D 17 CAL0000: Set System State = MAN_INIT
Feb 27 17:10:33 ip-172-30-0-250 ProcessMonitor[3230]: 33.367919 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set System State = MAN_INIT
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.369903 |0|0|0| D 17 CAL0000: getStandbyModule called
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.370066 |0|0|0| D 17 CAL0000: GLUSTER_WHOHAS called:1 2 3
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.371871 |0|0|0| D 17 CAL0000: setStandbyModule called
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.378257 |0|0|0| D 17 CAL0000: distributeConfigFile called for system file = Columnstore.xml
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.388540 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.389478 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.395197 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.395970 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.401723 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.402545 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.408171 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.408976 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.409047 |0|0|0| D 17 CAL0000: Columnstore.xml Standby OAM updated to : pm2:172.30.0.204
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.409071 |0|0|0| D 17 CAL0000: Send Message for new Hot-Standby ProcessManager to module = pm2
Feb 27 17:10:33 ip-172-30-0-250 ProcessManager[3421]: 33.414563 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:10:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.408782 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessManager State = HOT_STANDBY
Feb 27 17:10:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.408850 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessManager State = HOT_STANDBY PID = 0
Feb 27 17:10:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.417894 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessManager State = HOT_STANDBY
Feb 27 17:10:34 ip-172-30-0-250 ProcessMonitor[3230]: 34.417956 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessManager State = HOT_STANDBY PID = 0
Feb 27 17:10:35 ip-172-30-0-250 ProcessMonitor[3230]: 35.426308 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessManager State = PID_UPDATE
Feb 27 17:10:35 ip-172-30-0-250 ProcessMonitor[3230]: 35.426384 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessManager State = PID_UPDATE PID = 2923
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.427806 |0|0|0| D 17 CAL0000: pm2/ProcessManager process is started by request.
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.427874 |0|0|0| D 17 CAL0000: Hot-Standby ProcessManager ACK received from Process-Monitor, return status = 0
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.427976 |0|0|0| D 17 CAL0000: updateWorkerNodeconfig Started
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.545879 |0|0|0| D 17 CAL0000: updateWorkerNodeconfig completed
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.546520 |0|0|0| D 17 CAL0000: updatePMSconfig Started
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.557962 |0|0|0| D 17 CAL0000: updatePMSconfig completed
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.558061 |0|0|0| D 17 CAL0000: distributeConfigFile called for system file = Columnstore.xml
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.567650 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.568449 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.573750 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.574477 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:10:35 ip-172-30-0-250 ProcessManager[3421]: 35.579922 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:10:36 ip-172-30-0-250 ProcessManager[3421]: 36.428137 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:10:36 ip-172-30-0-250 ProcessManager[3421]: 36.434182 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:10:36 ip-172-30-0-250 ProcessManager[3421]: 36.434948 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:10:36 ip-172-30-0-250 ProcessManager[3421]: 36.435230 |0|0|0| D 17 CAL0000: Start Module pm1
Feb 27 17:10:36 ip-172-30-0-250 ProcessManager[3421]: 36.436751 |0|0|0| D 17 CAL0000: Set Module pm1 State = 2
Feb 27 17:10:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.437146 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Module pm1 State = MAN_INIT
Feb 27 17:10:36 ip-172-30-0-250 ProcessManager[3421]: 36.437212 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm1
Feb 27 17:10:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.439620 |0|0|0| I 18 CAL0000: MSG RECEIVED: Start All process request...
Feb 27 17:10:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.440021 |0|0|0| D 18 CAL0000: createDataDirs called
Feb 27 17:10:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.459423 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm2/ProcessManager State = HOT_STANDBY
Feb 27 17:10:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.459485 |0|0|0| D 18 CAL0000: statusControl: Set Process pm2/ProcessManager State = HOT_STANDBY PID = 2923
Feb 27 17:10:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.464210 |0|0|0| D 18 CAL0000: checkDataMount called
Feb 27 17:10:36 ip-172-30-0-250 ProcessMonitor[3230]: 36.468976 |0|0|0| D 18 CAL0000: glusterAssign called : 1
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.441210 |0|0|0| D 17 CAL0000: setPMProcIPs called for um1
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.441712 |0|0|0| D 17 CAL0000: setPMProcIPs for DDLProc: no update needed
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.442156 |0|0|0| D 17 CAL0000: setPMProcIPs for DMLProc: no update needed, exiting function
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.442218 |0|0|0| D 17 CAL0000: distributeConfigFile called for system file = Columnstore.xml
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.452397 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.453236 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.458765 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.459749 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.465547 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.466398 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.471956 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.472740 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.473030 |0|0|0| D 17 CAL0000: Start Module um1
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.474341 |0|0|0| D 17 CAL0000: Set Module um1 State = 2
Feb 27 17:10:41 ip-172-30-0-250 ProcessMonitor[3230]: 41.474721 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Module um1 State = MAN_INIT
Feb 27 17:10:41 ip-172-30-0-250 ProcessManager[3421]: 41.478594 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:10:43 ip-172-30-0-250 ProcessMonitor[3230]: 43.496564 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um1/mysqld State = ACTIVE
Feb 27 17:10:43 ip-172-30-0-250 ProcessMonitor[3230]: 43.496636 |0|0|0| D 18 CAL0000: statusControl: Set Process um1/mysqld State = ACTIVE PID = 3085
Feb 27 17:10:43 ip-172-30-0-250 ProcessMonitor[3230]: 43.506354 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um1/ServerMonitor State = MAN_INIT
Feb 27 17:10:43 ip-172-30-0-250 ProcessMonitor[3230]: 43.506418 |0|0|0| D 18 CAL0000: statusControl: Set Process um1/ServerMonitor State = MAN_INIT PID = 0
Feb 27 17:10:44 ip-172-30-0-250 ProcessMonitor[3230]: 44.516436 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um1/ServerMonitor State = PID_UPDATE
Feb 27 17:10:44 ip-172-30-0-250 ProcessMonitor[3230]: 44.516509 |0|0|0| D 18 CAL0000: statusControl: Set Process um1/ServerMonitor State = PID_UPDATE PID = 3127
Feb 27 17:10:44 ip-172-30-0-250 ProcessManager[3421]: 44.532499 |0|0|0| I 17 CAL0000: MSG RECEIVED: Get DBRM Data Files
Feb 27 17:10:44 ip-172-30-0-250 ProcessManager[3421]: 44.532818 |0|0|0| D 17 CAL0000: I declare that I am ProcMgr, and I am running getDBRMData!
Feb 27 17:10:45 ip-172-30-0-250 ProcessMonitor[3230]: 45.672091 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um1/ServerMonitor State = ACTIVE
Feb 27 17:10:45 ip-172-30-0-250 ProcessMonitor[3230]: 45.672163 |0|0|0| D 18 CAL0000: statusControl: Set Process um1/ServerMonitor State = ACTIVE PID = 3127
Feb 27 17:10:46 ip-172-30-0-250 ProcessManager[3421]: 46.475436 |0|0|0| D 17 CAL0000: Start Module um2
Feb 27 17:10:46 ip-172-30-0-250 ProcessManager[3421]: 46.477021 |0|0|0| D 17 CAL0000: Set Module um2 State = 2
Feb 27 17:10:46 ip-172-30-0-250 ProcessMonitor[3230]: 46.477434 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Module um2 State = MAN_INIT
Feb 27 17:10:46 ip-172-30-0-250 ProcessManager[3421]: 46.482291 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.522590 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um2/mysqld State = ACTIVE
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.522667 |0|0|0| D 18 CAL0000: statusControl: Set Process um2/mysqld State = ACTIVE PID = 3094
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.532133 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um2/ServerMonitor State = MAN_INIT
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.532185 |0|0|0| D 18 CAL0000: statusControl: Set Process um2/ServerMonitor State = MAN_INIT PID = 0
Feb 27 17:10:47 ip-172-30-0-250 IDBFile[3421]: 47.593202 |0|0|0| D 35 CAL0002: Failed to open file: /var/lib/columnstore/data1/systemFiles/dbrm/BRM_saves_current, exception: unable to open Buffered file
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.604827 |0|0|0| E 18 CAL0000: glusterAssign mount failure: dbroot: 1 error: 1
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.604918 |0|0|0| E 18 CAL0000: glusterAssign failed.
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.607492 |0|0|0| E 18 CAL0000: Error assigning gluster dbroot# 1
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.607607 |0|0|0| D 18 CAL0000: unmountExtraDBroots called
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.634508 |0|0|0| D 18 CAL0000: flushInodeCache successful
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.640911 |0|0|0| D 18 CAL0000: glusterUnassign called: 2
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.756645 |0|0|0| E 18 CAL0000: glusterUnassign mount failure: dbroot: 2 error: 32
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.756767 |0|0|0| I 18 CAL0000: Gluster unassign gluster dbroot# 2
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.756854 |0|0|0| D 18 CAL0000: glusterUnassign called: 3
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.761881 |0|0|0| E 18 CAL0000: glusterUnassign mount failure: dbroot: 3 error: 32
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.761973 |0|0|0| I 18 CAL0000: Gluster unassign gluster dbroot# 3
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.762002 |0|0|0| D 18 CAL0000: unmountExtraDBroots finished
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.774275 |0|0|0| D 18 CAL0000: flushInodeCache successful
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.795535 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set DBroot 1 State = ACTIVE
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.798777 |0|0|0| D 18 CAL0000: checkSpecialProcessState on : DBRMControllerNode
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.798827 |0|0|0| D 18 CAL0000: checkSpecialProcessState status return : 2
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.798898 |0|0|0| D 18 CAL0000: STARTING Process: DBRMControllerNode
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.798919 |0|0|0| D 18 CAL0000: Process location: /usr/bin/controllernode
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.802681 |0|0|0| D 18 CAL0000: Dependent process of ProcessManager/pm1 is 4
Feb 27 17:10:47 ip-172-30-0-250 IDBFile[3230]: 47.836383 |0|0|0| D 35 CAL0002: Failed to open file: /var/lib/columnstore/data1/systemFiles/dbrm/BRM_saves_current, exception: unable to open Buffered file
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.836458 |0|0|0| D 18 CAL0000: No DBRM files exist, must be a initial startup
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.837122 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 27 on device DBRM
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.941270 |0|0|0| D 18 CAL0000: Pkill Process just to make sure: controllernode*
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.941412 |0|0|0| D 18 CAL0000: StatusUpdate of Process DBRMControllerNode State = 2 PID = 0
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.942031 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/DBRMControllerNode State = MAN_INIT
Feb 27 17:10:47 ip-172-30-0-250 ProcessMonitor[3230]: 47.942084 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/DBRMControllerNode State = MAN_INIT PID = 0
Feb 27 17:10:48 ip-172-30-0-250 messagequeue[3230]: 48.278890 |0|0|0| W 31 CAL0000: MessageQueueClient::write: error writing 37 bytes to IOSocket: sd: 11 inet: 172.30.0.250 port: 8606. Socket error was InetStreamSocket::write error: Broken pipe – write from InetStreamSocket: sd: 11 inet: 172.30.0.250 port: 8606
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.541951 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um2/ServerMonitor State = PID_UPDATE
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.542024 |0|0|0| D 18 CAL0000: statusControl: Set Process um2/ServerMonitor State = PID_UPDATE PID = 3136
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.955441 |0|0|0| D 18 CAL0000: StatusUpdate of Process DBRMControllerNode State = 21 PID = 5478
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.956195 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/DBRMControllerNode State = PID_UPDATE
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.956246 |0|0|0| D 18 CAL0000: DBRMControllerNode PID is 5478
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.956255 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/DBRMControllerNode State = PID_UPDATE PID = 5478
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.956574 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 21 on device DBRMControllerNode
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.957727 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 13 on device DBRMControllerNode
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.959256 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 25 on device DBRMControllerNode
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.959821 |0|0|0| D 18 CAL0000: checkSpecialProcessState status return : 2
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.959925 |0|0|0| D 18 CAL0000: STARTING Process: ServerMonitor
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.959948 |0|0|0| D 18 CAL0000: Process location: /usr/bin/ServerMonitor
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.961628 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 27 on device DBRM
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.967181 |0|0|0| D 18 CAL0000: Pkill Process just to make sure: ServerMonitor*
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.967275 |0|0|0| D 18 CAL0000: StatusUpdate of Process ServerMonitor State = 2 PID = 0
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.967815 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ServerMonitor State = MAN_INIT
Feb 27 17:10:48 ip-172-30-0-250 ProcessMonitor[3230]: 48.967858 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ServerMonitor State = MAN_INIT PID = 0
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.696611 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process um2/ServerMonitor State = ACTIVE
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.696685 |0|0|0| D 18 CAL0000: statusControl: Set Process um2/ServerMonitor State = ACTIVE PID = 3136
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.797238 |0|0|0| C 18 CAL0000: *****MariaDB ColumnStore Process Restarting: ProcessManager, old PID = 3421
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.797458 |0|0|0| D 18 CAL0000: STOPPING Process: ProcessManager
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.797568 |0|0|0| D 18 CAL0000: Send SET Alarm ID 13 on device ProcessManager
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.797723 |0|0|0| D 18 CAL0000: StatusUpdate of Process ProcessManager State = 1 PID = 0
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.799499 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ProcessManager State = AUTO_OFFLINE
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.799551 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ProcessManager State = AUTO_OFFLINE PID = 0
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.799655 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 25 on device ProcessManager
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.800363 |0|0|0| D 18 CAL0000: Send SET Alarm ID 13 on device ProcessManager
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.804152 |0|0|0| D 18 CAL0000: Pkill Process just to make sure: ProcMgr*
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.804266 |0|0|0| D 18 CAL0000: STARTING Process: ProcessManager
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.804292 |0|0|0| D 18 CAL0000: Process location: /usr/bin/ProcMgr
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.804804 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 27 on device DBRM
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.810016 |0|0|0| D 18 CAL0000: Pkill Process just to make sure: ProcMgr*
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.810109 |0|0|0| D 18 CAL0000: StatusUpdate of Process ProcessManager State = 3 PID = 0
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.810676 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ProcessManager State = AUTO_INIT
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.810746 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ProcessManager State = AUTO_INIT PID = 0
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.975209 |0|0|0| D 18 CAL0000: StatusUpdate of Process ServerMonitor State = 21 PID = 5517
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.975885 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ServerMonitor State = PID_UPDATE
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.975949 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ServerMonitor State = PID_UPDATE PID = 5517
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.975987 |0|0|0| D 18 CAL0000: ServerMonitor PID is 5517
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.976311 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 13 on device ServerMonitor
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.977472 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 21 on device ServerMonitor
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.978954 |0|0|0| D 18 CAL0000: checkSpecialProcessState status return : 2
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.979056 |0|0|0| D 18 CAL0000: STARTING Process: DBRMWorkerNode
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.979092 |0|0|0| D 18 CAL0000: Process location: /usr/bin/workernode
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.979086 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 25 on device ServerMonitor
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.984989 |0|0|0| D 18 CAL0000: getLocalDBRMID Worker Node ID = 1
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.985496 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 27 on device DBRM
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.991163 |0|0|0| D 18 CAL0000: Pkill Process just to make sure: workernode*
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.991278 |0|0|0| D 18 CAL0000: StatusUpdate of Process DBRMWorkerNode State = 2 PID = 0
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.991923 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/DBRMWorkerNode State = MAN_INIT
Feb 27 17:10:49 ip-172-30-0-250 ProcessMonitor[3230]: 49.991973 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/DBRMWorkerNode State = MAN_INIT PID = 0
Feb 27 17:10:50 ip-172-30-0-250 IDBFile[5478]: 50.420422 |0|0|0| D 35 CAL0002: Failed to open file: /var/lib/columnstore/data1/systemFiles/dbrm/tablelocks, exception: unable to open Buffered file
Feb 27 17:10:50 ip-172-30-0-250 controllernode[5478]: 50.420507 |0|0|0| D 29 CAL0000: TableLockServer::load(): could not open the save file/var/lib/columnstore/data1/systemFiles/dbrm/tablelocks
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.421599 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/DBRMControllerNode State = ACTIVE
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.421660 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/DBRMControllerNode State = ACTIVE PID = 5478
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.817488 |0|0|0| D 18 CAL0000: StatusUpdate of Process ProcessManager State = 21 PID = 5542
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.818236 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ProcessManager State = PID_UPDATE
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.818284 |0|0|0| D 18 CAL0000: ProcessManager PID is 5542
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.818293 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ProcessManager State = PID_UPDATE PID = 5542
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.818569 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 21 on device ProcessManager
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.818771 |0|0|0| D 18 CAL0000: Inform Process Mgr that process was restarted: ProcessManager
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.819084 |0|0|0| D 18 CAL0000: Inform Process Mgr that process was restarted: ProcessManager
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.819302 |0|0|0| I 18 CAL0000: MariaDB ColumnStore Process ProcessManager restarted successfully!!
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.819830 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 13 on device ProcessManager
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.820588 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 25 on device ProcessManager
Feb 27 17:10:50 ip-172-30-0-250 ProcessMonitor[3230]: 50.999820 |0|0|0| D 18 CAL0000: StatusUpdate of Process DBRMWorkerNode State = 21 PID = 5554
Feb 27 17:10:51 ip-172-30-0-250 ProcessMonitor[3230]: 51.000512 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/DBRMWorkerNode State = PID_UPDATE
Feb 27 17:10:51 ip-172-30-0-250 ProcessMonitor[3230]: 51.000565 |0|0|0| D 18 CAL0000: DBRMWorkerNode PID is 5554
Feb 27 17:10:51 ip-172-30-0-250 ProcessMonitor[3230]: 51.000574 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/DBRMWorkerNode State = PID_UPDATE PID = 5554
Feb 27 17:10:51 ip-172-30-0-250 ProcessMonitor[3230]: 51.000855 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 21 on device DBRMWorkerNode
Feb 27 17:10:51 ip-172-30-0-250 ProcessMonitor[3230]: 51.001865 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 13 on device DBRMWorkerNode
Feb 27 17:10:51 ip-172-30-0-250 ProcessMonitor[3230]: 51.002654 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 25 on device DBRMWorkerNode
Feb 27 17:10:51 ip-172-30-0-250 ServerMonitor[5517]: 51.035519 |0|0|0| W 09 CAL0000: Total Swap space is set to 0
Feb 27 17:10:51 ip-172-30-0-250 ProcessMonitor[3230]: 51.039989 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ServerMonitor State = ACTIVE
Feb 27 17:10:51 ip-172-30-0-250 ProcessMonitor[3230]: 51.040048 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ServerMonitor State = ACTIVE PID = 5517
Feb 27 17:10:51 ip-172-30-0-250 ServerMonitor[5517]: 51.040449 |0|0|0| I 09 CAL0000: processInitComplete Successfully Called
Feb 27 17:10:51 ip-172-30-0-250 ProcessManager[5542]: 51.848066 |0|0|0| I 17 CAL0000:
Feb 27 17:10:51 ip-172-30-0-250 ProcessManager[5542]: 51.848137 |0|0|0| I 17 CAL0000: *********Process Manager Started*********
Feb 27 17:10:51 ip-172-30-0-250 ProcessManager[5542]: 51.848909 |0|0|0| D 17 CAL0000: Main: DBRootStorageType = DataRedundancy
Feb 27 17:10:51 ip-172-30-0-250 ProcessManager[5542]: 51.849415 |0|0|0| I 17 CAL0000: Running Active
Feb 27 17:10:51 ip-172-30-0-250 ProcessManager[5542]: 51.849442 |0|0|0| D 17 CAL0000: Running Active
Feb 27 17:10:51 ip-172-30-0-250 ProcessManager[5542]: 51.850121 |0|0|0| D 17 CAL0000: Main Ethernet Port = eth0
Feb 27 17:10:51 ip-172-30-0-250 ProcessManager[5542]: 51.850208 |0|0|0| D 17 CAL0000: updateWorkerNodeconfig Started
Feb 27 17:10:51 ip-172-30-0-250 ProcessManager[5542]: 51.966511 |0|0|0| D 17 CAL0000: updateWorkerNodeconfig completed
Feb 27 17:10:51 ip-172-30-0-250 ProcessManager[5542]: 51.967718 |0|0|0| D 17 CAL0000: pingDeviceThread launched
Feb 27 17:10:51 ip-172-30-0-250 ProcessManager[5542]: 51.972415 |0|0|0| D 17 CAL0000: pingDeviceThread: DBRootStorageType = DataRedundancy
Feb 27 17:10:52 ip-172-30-0-250 ProcessMonitor[3230]: 52.134828 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/DBRMWorkerNode State = ACTIVE
Feb 27 17:10:52 ip-172-30-0-250 ProcessMonitor[3230]: 52.134890 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/DBRMWorkerNode State = ACTIVE PID = 5554
Feb 27 17:10:54 ip-172-30-0-250 ProcessMonitor[3230]: 54.003589 |0|0|0| D 18 CAL0000: checkSpecialProcessState status return : 2
Feb 27 17:10:54 ip-172-30-0-250 ProcessMonitor[3230]: 54.003712 |0|0|0| D 18 CAL0000: STARTING Process: PrimProc
Feb 27 17:10:54 ip-172-30-0-250 ProcessMonitor[3230]: 54.003737 |0|0|0| D 18 CAL0000: Process location: /usr/bin/PrimProc
Feb 27 17:10:54 ip-172-30-0-250 ProcessMonitor[3230]: 54.004405 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 27 on device DBRM
Feb 27 17:10:54 ip-172-30-0-250 ProcessMonitor[3230]: 54.010398 |0|0|0| D 18 CAL0000: Pkill Process just to make sure: PrimProc*
Feb 27 17:10:54 ip-172-30-0-250 ProcessMonitor[3230]: 54.010522 |0|0|0| D 18 CAL0000: StatusUpdate of Process PrimProc State = 2 PID = 0
Feb 27 17:10:54 ip-172-30-0-250 ProcessMonitor[3230]: 54.011145 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/PrimProc State = MAN_INIT
Feb 27 17:10:54 ip-172-30-0-250 ProcessMonitor[3230]: 54.011193 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/PrimProc State = MAN_INIT PID = 0
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.018776 |0|0|0| D 18 CAL0000: StatusUpdate of Process PrimProc State = 21 PID = 5698
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.019568 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/PrimProc State = PID_UPDATE
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.019617 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/PrimProc State = PID_UPDATE PID = 5698
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.019636 |0|0|0| D 18 CAL0000: PrimProc PID is 5698
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.019997 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 21 on device PrimProc
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.022241 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 13 on device PrimProc
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.022467 |0|0|0| D 18 CAL0000: checkSpecialProcessState status return : 2
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.022591 |0|0|0| D 18 CAL0000: STARTING Process: WriteEngineServer
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.022623 |0|0|0| D 18 CAL0000: Process location: /usr/bin/WriteEngineServer
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.024057 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 25 on device PrimProc
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.024880 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 27 on device DBRM
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.029353 |0|0|0| D 18 CAL0000: Pkill Process just to make sure: WriteEngineServ*
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.029457 |0|0|0| D 18 CAL0000: StatusUpdate of Process WriteEngineServer State = 2 PID = 0
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.030031 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/WriteEngineServer State = MAN_INIT
Feb 27 17:10:55 ip-172-30-0-250 ProcessMonitor[3230]: 55.030080 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/WriteEngineServer State = MAN_INIT PID = 0
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.037665 |0|0|0| D 18 CAL0000: StatusUpdate of Process WriteEngineServer State = 21 PID = 5752
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.038441 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/WriteEngineServer State = PID_UPDATE
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.038491 |0|0|0| D 18 CAL0000: WriteEngineServer PID is 5752
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.038500 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/WriteEngineServer State = PID_UPDATE PID = 5752
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.038824 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 13 on device WriteEngineServer
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.039163 |0|0|0| I 18 CAL0000: STARTALL: ACK back to ProcMgr, return status = 0
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.040000 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 21 on device WriteEngineServer
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.040788 |0|0|0| D 18 CAL0000: Send CLEAR Alarm ID 25 on device WriteEngineServer
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.380986 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/PrimProc State = ACTIVE
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.381053 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/PrimProc State = ACTIVE PID = 5698
Feb 27 17:10:56 ip-172-30-0-250 ProcessManager[5542]: 56.968122 |0|0|0| D 17 CAL0000: startMgrProcessThread launched
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.968255 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/ProcessManager State = ACTIVE
Feb 27 17:10:56 ip-172-30-0-250 ProcessMonitor[3230]: 56.968299 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/ProcessManager State = ACTIVE PID = 5542
Feb 27 17:10:56 ip-172-30-0-250 ProcessManager[5542]: 56.970885 |0|0|0| D 17 CAL0000: processInitComplete Successfully Called
Feb 27 17:10:56 ip-172-30-0-250 ProcessManager[5542]: 56.973355 |0|0|0| D 17 CAL0000: set ProcMgr IPaddr to 172.30.0.250
Feb 27 17:10:56 ip-172-30-0-250 ProcessManager[5542]: 56.973397 |0|0|0| D 17 CAL0000: distributeConfigFile called for system file = Columnstore.xml
Feb 27 17:10:56 ip-172-30-0-250 ProcessManager[5542]: 56.981746 |0|0|0| D 17 CAL0000: Message Thread started ..
Feb 27 17:10:56 ip-172-30-0-250 ProcessManager[5542]: 56.981804 |0|0|0| D 17 CAL0000: Alarm Message Thread started ..
Feb 27 17:10:56 ip-172-30-0-250 ProcessManager[5542]: 56.987402 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:10:56 ip-172-30-0-250 ProcessManager[5542]: 56.988215 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:10:56 ip-172-30-0-250 ProcessManager[5542]: 56.996981 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:10:56 ip-172-30-0-250 ProcessManager[5542]: 56.997763 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:10:57 ip-172-30-0-250 ProcessManager[5542]: 57.003200 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:10:57 ip-172-30-0-250 ProcessManager[5542]: 57.003998 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:10:57 ip-172-30-0-250 ProcessManager[5542]: 57.009485 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:10:57 ip-172-30-0-250 ProcessManager[5542]: 57.010306 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:10:57 ip-172-30-0-250 ProcessManager[5542]: 57.010405 |0|0|0| D 17 CAL0000: distributeConfigFile called for system file = ProcessConfig.xml
Feb 27 17:10:57 ip-172-30-0-250 ProcessManager[5542]: 57.020369 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:10:57 ip-172-30-0-250 ProcessMonitor[3230]: 57.195912 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/WriteEngineServer State = BUSY_INIT
Feb 27 17:10:57 ip-172-30-0-250 ProcessMonitor[3230]: 57.195975 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/WriteEngineServer State = BUSY_INIT PID = 5752
Feb 27 17:10:57 ip-172-30-0-250 ProcessMonitor[3230]: 57.206817 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set Process pm1/WriteEngineServer State = ACTIVE
Feb 27 17:10:57 ip-172-30-0-250 ProcessMonitor[3230]: 57.206881 |0|0|0| D 18 CAL0000: statusControl: Set Process pm1/WriteEngineServer State = ACTIVE PID = 5752
Feb 27 17:10:57 ip-172-30-0-250 ProcessManager[5542]: 57.990157 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:10:57 ip-172-30-0-250 ProcessManager[5542]: 57.995930 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:10:57 ip-172-30-0-250 ProcessManager[5542]: 57.999473 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:10:58 ip-172-30-0-250 ProcessManager[5542]: 58.005024 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:10:58 ip-172-30-0-250 ProcessManager[5542]: 58.005753 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:10:58 ip-172-30-0-250 ProcessManager[5542]: 58.010928 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:10:58 ip-172-30-0-250 ProcessManager[5542]: 58.011856 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:10:58 ip-172-30-0-250 ProcessManager[5542]: 58.011910 |0|0|0| D 17 CAL0000: configureModule: Process module um1
Feb 27 17:10:58 ip-172-30-0-250 ProcessManager[5542]: 58.011970 |0|0|0| D 17 CAL0000: distributeConfigFile called for um1 file = Columnstore.xml
Feb 27 17:10:58 ip-172-30-0-250 ProcessManager[5542]: 58.019687 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:10:58 ip-172-30-0-250 ProcessManager[5542]: 58.991346 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:10:58 ip-172-30-0-250 ProcessManager[5542]: 58.991503 |0|0|0| D 17 CAL0000: distributeConfigFile called for um1 file = ProcessConfig.xml
Feb 27 17:10:58 ip-172-30-0-250 ProcessManager[5542]: 58.999633 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:10:59 ip-172-30-0-250 ProcessManager[5542]: 59.992990 |0|0|0| D 17 CAL0000: um1 distributeConfigFile success.
Feb 27 17:10:59 ip-172-30-0-250 ProcessManager[5542]: 59.997189 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um1
Feb 27 17:11:00 ip-172-30-0-250 ProcessManager[5542]: 00.996630 |0|0|0| D 17 CAL0000: configureModule - procmon configure successful
Feb 27 17:11:00 ip-172-30-0-250 ProcessManager[5542]: 00.996738 |0|0|0| D 17 CAL0000: configureModule: Process module um2
Feb 27 17:11:00 ip-172-30-0-250 ProcessManager[5542]: 00.996831 |0|0|0| D 17 CAL0000: distributeConfigFile called for um2 file = Columnstore.xml
Feb 27 17:11:01 ip-172-30-0-250 ProcessManager[5542]: 01.005081 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:11:01 ip-172-30-0-250 ProcessManager[5542]: 01.005919 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:11:01 ip-172-30-0-250 ProcessManager[5542]: 01.006008 |0|0|0| D 17 CAL0000: distributeConfigFile called for um2 file = ProcessConfig.xml
Feb 27 17:11:01 ip-172-30-0-250 ProcessManager[5542]: 01.014059 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:11:02 ip-172-30-0-250 ProcessManager[5542]: 02.007797 |0|0|0| D 17 CAL0000: um2 distributeConfigFile success.
Feb 27 17:11:02 ip-172-30-0-250 ProcessManager[5542]: 02.012760 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module um2
Feb 27 17:11:03 ip-172-30-0-250 ProcessManager[5542]: 03.011459 |0|0|0| D 17 CAL0000: configureModule - procmon configure successful
Feb 27 17:11:03 ip-172-30-0-250 ProcessManager[5542]: 03.011541 |0|0|0| D 17 CAL0000: configureModule: Process module pm2
Feb 27 17:11:03 ip-172-30-0-250 ProcessManager[5542]: 03.011633 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm2 file = Columnstore.xml
Feb 27 17:11:03 ip-172-30-0-250 ProcessManager[5542]: 03.020034 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:11:03 ip-172-30-0-250 ProcessManager[5542]: 03.021029 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:11:03 ip-172-30-0-250 ProcessManager[5542]: 03.021124 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm2 file = ProcessConfig.xml
Feb 27 17:11:03 ip-172-30-0-250 ProcessManager[5542]: 03.029236 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:11:04 ip-172-30-0-250 ProcessManager[5542]: 04.022748 |0|0|0| D 17 CAL0000: pm2 distributeConfigFile success.
Feb 27 17:11:04 ip-172-30-0-250 ProcessManager[5542]: 04.027053 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm2
Feb 27 17:11:05 ip-172-30-0-250 ProcessManager[5542]: 05.029364 |0|0|0| D 17 CAL0000: configureModule - procmon configure successful
Feb 27 17:11:05 ip-172-30-0-250 ProcessManager[5542]: 05.029445 |0|0|0| D 17 CAL0000: configureModule: Process module pm3
Feb 27 17:11:05 ip-172-30-0-250 ProcessManager[5542]: 05.029534 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm3 file = Columnstore.xml
Feb 27 17:11:05 ip-172-30-0-250 ProcessManager[5542]: 05.037876 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:11:05 ip-172-30-0-250 ProcessManager[5542]: 05.038733 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:11:05 ip-172-30-0-250 ProcessManager[5542]: 05.038847 |0|0|0| D 17 CAL0000: distributeConfigFile called for pm3 file = ProcessConfig.xml
Feb 27 17:11:05 ip-172-30-0-250 ProcessManager[5542]: 05.047161 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:11:06 ip-172-30-0-250 ProcessManager[5542]: 06.040418 |0|0|0| D 17 CAL0000: pm3 distributeConfigFile success.
Feb 27 17:11:06 ip-172-30-0-250 ProcessManager[5542]: 06.044621 |0|0|0| D 17 CAL0000: sendMsgProcMon: Process module pm3
Feb 27 17:11:07 ip-172-30-0-250 ProcessManager[5542]: 07.045370 |0|0|0| D 17 CAL0000: configureModule - procmon configure successful
Feb 27 17:11:07 ip-172-30-0-250 ProcessManager[5542]: 07.052805 |0|0|0| D 17 CAL0000: updatePMSconfig Started
Feb 27 17:11:07 ip-172-30-0-250 ProcessManager[5542]: 07.064211 |0|0|0| D 17 CAL0000: updatePMSconfig completed
Feb 27 17:11:08 ip-172-30-0-250 ProcessManager[5542]: 08.046427 |0|0|0| D 17 CAL0000: startSystemThread launched
Feb 27 17:11:08 ip-172-30-0-250 ProcessManager[5542]: 08.047045 |0|0|0| D 17 CAL0000: Start already in-progess, exit startSystemThread
Feb 27 17:11:08 ip-172-30-0-250 ProcessManager[5542]: 08.047395 |0|0|0| D 17 CAL0000: Set System State = FAILED
Feb 27 17:11:08 ip-172-30-0-250 ProcessMonitor[3230]: 08.047856 |0|0|0| D 18 CAL0000: statusControl: REQUEST RECEIVED: Set System State = FAILED
Feb 27 17:11:08 ip-172-30-0-250 ProcessManager[5542]: 08.048648 |0|0|0| C 17 CAL0000: startMgrProcessThread Exit with a failure, error returned from startSystemThread
Feb 27 17:11:08 ip-172-30-0-250 ProcessManager[5542]: 08.048711 |0|0|0| D 17 CAL0000: startMgrProcessThread Exit
[centos@ip-172-30-0-250 columnstore]$

It looked like ProcMgr had crashed during this time. I turned on the corefile setting and postConfigure

NOTE IN THE SECOND RUN, IT DIDNT REUSE THE NUMBER OF COPIES OF 3 I ENTERED THE FIRST TIME AROUND. IT SEEMS THAT SHOULD HAVE BEEN THE DEFAULT

----- Setup Data Redundancy Copy Count Configuration -----

Setup the Number of Copies: This is the total number of copies of the data
in the system. At least 2, but not more than the number of PMs(3), are required.

Enter Number of Copies [2-3] (2) > 3

----- Starting MariaDB ColumnStore on local server -----

MariaDB ColumnStore successfully started

MariaDB ColumnStore Database Platform Starting, please wait ....................................... FAILED

IMPORTANT: There was a system startup failed, once issue has been resolved, rerun postConfigure

MariaDB ColumnStore System failed to start, check log files in /var/log/mariadb/columnstore

BUT NO CORE FILE THIS TIME..

tried shutdown and start and getting this error. Not sure why. I have sshkeys between pm1 and the other nodes

[centos@ip-172-30-0-250 ~]$ sudo mcsadmin starts
startsystem Thu Feb 27 17:55:56 2020

startSystem command, 'columnstore' service is down, sending command to
start the 'columnstore' service on all modules

System being started, please wait...ERROR: Connection refused

Invalid Password when running 'columnstore start' on module um1, can retry by providing password as the second argument

        • startSystem Failed
          ERROR: Connection refused

Invalid Password when running 'columnstore start' on module pm2, can retry by providing password as the second argument

        • startSystem Failed

[centos@ip-172-30-0-250 ~]$ sudo mcsadmin getsystemn
getsystemnetworkconfig Thu Feb 27 17:57:36 2020

System Network Configuration

Module Name Module Description NIC ID Host Name IP Address
----------- ------------------------- ------ --------- ---------------
um1 User Module #1 1 um1 172.30.0.128
um2 User Module #2 1 um2 172.30.0.102
pm1 Performance Module #1 1 pm1 172.30.0.250
pm2 Performance Module #2 1 pm2 172.30.0.204
pm3 Performance Module #3 1 pm3 172.30.0.229

[centos@ip-172-30-0-250 ~]$ sudo -i
[root@ip-172-30-0-250 ~]# ssh 172.30.0.128
Last login: Thu Feb 27 17:33:45 2020 from 172.30.0.250
[root@ip-172-30-0-128 ~]#

Generated at Thu Feb 08 02:45:50 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.