service_manager.py 35.2 KB
Newer Older
1 2
from __future__ import annotations

3 4 5 6 7 8 9
import os
import io
import sys
import threading
import signal
import logging
import time
10
from subprocess import PIPE, Popen, TimeoutExpired
11

12
from typing import IO, List, NewType, Optional
13 14 15 16 17 18 19 20

try:
    import psutil
except:
    print("Psutil module needed, please install: sudo pip3 install psutil")
    sys.exit(-1)

from queue import Queue, Empty
21

22
from .misc import Logging, Status, CrashGenError, Dice, Helper, Progress
23
from .db import DbConn, DbTarget
24
import crash_gen.settings 
25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52

class TdeInstance():
    """
    A class to capture the *static* information of a TDengine instance,
    including the location of the various files/directories, and basica
    configuration.
    """

    @classmethod
    def _getBuildPath(cls):
        selfPath = os.path.dirname(os.path.realpath(__file__))
        if ("community" in selfPath):
            projPath = selfPath[:selfPath.find("communit")]
        else:
            projPath = selfPath[:selfPath.find("tests")]

        buildPath = None
        for root, dirs, files in os.walk(projPath):
            if ("taosd" in files):
                rootRealPath = os.path.dirname(os.path.realpath(root))
                if ("packaging" not in rootRealPath):
                    buildPath = root[:len(root) - len("/build/bin")]
                    break
        if buildPath == None:
            raise RuntimeError("Failed to determine buildPath, selfPath={}, projPath={}"
                .format(selfPath, projPath))
        return buildPath

S
Steven Li 已提交
53 54 55 56
    @classmethod
    def prepareGcovEnv(cls, env):
        # Ref: https://gcc.gnu.org/onlinedocs/gcc/Cross-profiling.html
        bPath = cls._getBuildPath() # build PATH
57 58 59 60
        numSegments = len(bPath.split('/')) # "/x/TDengine/build" should yield 3
        # numSegments += 2 # cover "/src" after build
        # numSegments = numSegments - 1 # DEBUG only
        env['GCOV_PREFIX'] = bPath + '/src_s' # Server side source
S
Steven Li 已提交
61
        env['GCOV_PREFIX_STRIP'] = str(numSegments) # Strip every element, plus, ENV needs strings
62
        # VERY VERY important note: GCOV data collection NOT effective upon SIG_KILL
S
Steven Li 已提交
63 64 65
        Logging.info("Preparing GCOV environement to strip {} elements and use path: {}".format(
            numSegments, env['GCOV_PREFIX'] ))

66
    def __init__(self, subdir='test', tInstNum=0, port=6030, fepPort=6030):
67 68 69 70 71
        self._buildDir  = self._getBuildPath()
        self._subdir    = '/' + subdir # TODO: tolerate "/"
        self._port      = port # TODO: support different IP address too
        self._fepPort   = fepPort

72 73 74
        self._tInstNum    = tInstNum
        self._smThread    = ServiceManagerThread()

75 76 77 78 79
    def getDbTarget(self):
        return DbTarget(self.getCfgDir(), self.getHostAddr(), self._port)

    def getPort(self):
        return self._port
80 81

    def __repr__(self):
82 83
        return "[TdeInstance: {}, subdir={}]".format(
            self._buildDir, Helper.getFriendlyPath(self._subdir))
84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 100 101 102 103 104 105 106
    
    def generateCfgFile(self):       
        # print("Logger = {}".format(logger))
        # buildPath = self.getBuildPath()
        # taosdPath = self._buildPath + "/build/bin/taosd"

        cfgDir  = self.getCfgDir()
        cfgFile = cfgDir + "/taos.cfg" # TODO: inquire if this is fixed
        if os.path.exists(cfgFile):
            if os.path.isfile(cfgFile):
                Logging.warning("Config file exists already, skip creation: {}".format(cfgFile))
                return # cfg file already exists, nothing to do
            else:
                raise CrashGenError("Invalid config file: {}".format(cfgFile))
        # Now that the cfg file doesn't exist
        if os.path.exists(cfgDir):
            if not os.path.isdir(cfgDir):
                raise CrashGenError("Invalid config dir: {}".format(cfgDir))
            # else: good path
        else: 
            os.makedirs(cfgDir, exist_ok=True) # like "mkdir -p"
        # Now we have a good cfg dir
        cfgValues = {
107 108 109 110
            'runDir':   self.getRunDir(),
            'ip':       '127.0.0.1', # TODO: change to a network addressable ip
            'port':     self._port,
            'fepPort':  self._fepPort,
111 112 113 114 115 116 117
        }
        cfgTemplate = """
dataDir {runDir}/data
logDir  {runDir}/log

charset UTF-8

118
firstEp {ip}:{fepPort}
119 120 121 122 123 124 125 126 127 128 129 130 131 132 133 134 135 136 137
fqdn {ip}
serverPort {port}

# was all 135 below
dDebugFlag 135
cDebugFlag 135
rpcDebugFlag 135
qDebugFlag 135
# httpDebugFlag 143
# asyncLog 0
# tables 10
maxtablesPerVnode 10
rpcMaxTime 101
# cache 2
keep 36500
# walLevel 2
walLevel 1
#
# maxConnections 100
138
quorum 2
139 140 141 142 143 144 145 146 147 148 149 150 151 152 153 154 155 156 157 158 159 160 161 162 163 164 165 166 167 168 169
"""
        cfgContent = cfgTemplate.format_map(cfgValues)
        f = open(cfgFile, "w")
        f.write(cfgContent)
        f.close()

    def rotateLogs(self):
        logPath = self.getLogDir()
        # ref: https://stackoverflow.com/questions/1995373/deleting-all-files-in-a-directory-with-python/1995397
        if os.path.exists(logPath):
            logPathSaved = logPath + "_" + time.strftime('%Y-%m-%d-%H-%M-%S')
            Logging.info("Saving old log files to: {}".format(logPathSaved))
            os.rename(logPath, logPathSaved)
        # os.mkdir(logPath) # recreate, no need actually, TDengine will auto-create with proper perms


    def getExecFile(self): # .../taosd
        return self._buildDir + "/build/bin/taosd"

    def getRunDir(self): # TODO: rename to "root dir" ?!
        return self._buildDir + self._subdir

    def getCfgDir(self): # path, not file
        return self.getRunDir() + "/cfg"

    def getLogDir(self):
        return self.getRunDir() + "/log"

    def getHostAddr(self):
        return "127.0.0.1"

170
    def getServiceCmdLine(self): # to start the instance
171 172 173 174
        cmdLine = []
        if crash_gen.settings.gConfig.track_memory_leaks:
            Logging.info("Invoking VALGRIND on service...")
            cmdLine = ['valgrind', '--leak-check=yes']
175
        # TODO: move "exec -c" into Popen(), we can both "use shell" and NOT fork so ask to lose kill control
176 177
        cmdLine += ["exec " + self.getExecFile(), '-c', self.getCfgDir()] # used in subproce.Popen()        
        return cmdLine
178 179 180 181 182 183 184 185 186 187 188 189 190 191 192 193 194 195 196 197 198 199 200 201 202 203 204 205 206 207 208 209 210 211 212 213 214
    
    def _getDnodes(self, dbc):
        dbc.query("show dnodes")
        cols = dbc.getQueryResult() #  id,end_point,vnodes,cores,status,role,create_time,offline reason
        return {c[1]:c[4] for c in cols} # {'xxx:6030':'ready', 'xxx:6130':'ready'}

    def createDnode(self, dbt: DbTarget):
        """
        With a connection to the "first" EP, let's create a dnode for someone else who
        wants to join.
        """
        dbc = DbConn.createNative(self.getDbTarget())
        dbc.open()

        if dbt.getEp() in self._getDnodes(dbc):
            Logging.info("Skipping DNode creation for: {}".format(dbt))
            dbc.close()
            return

        sql = "CREATE DNODE \"{}\"".format(dbt.getEp())
        dbc.execute(sql)
        dbc.close()

    def getStatus(self):
        return self._smThread.getStatus()

    def getSmThread(self):
        return self._smThread

    def start(self):
        if not self.getStatus().isStopped():
            raise CrashGenError("Cannot start instance from status: {}".format(self.getStatus()))

        Logging.info("Starting TDengine instance: {}".format(self))
        self.generateCfgFile() # service side generates config file, client does not
        self.rotateLogs()

215
        self._smThread.start(self.getServiceCmdLine(), self.getLogDir()) # May raise exceptions
216 217 218 219 220 221

    def stop(self):
        self._smThread.stop()

    def isFirst(self):
        return self._tInstNum == 0
222 223 224 225 226 227 228 229 230


class TdeSubProcess:
    """
    A class to to represent the actual sub process that is the run-time
    of a TDengine instance. 

    It takes a TdeInstance object as its parameter, with the rationale being
    "a sub process runs an instance".
231 232 233

    We aim to ensure that this object has exactly the same life-cycle as the 
    underlying sub process.
234 235
    """

236 237 238 239
    # RET_ALREADY_STOPPED = -1
    # RET_TIME_OUT = -3
    # RET_SUCCESS = -4

240 241
    def __init__(self, po: Popen):
        self._popen = po # type: Popen
242 243 244
        # if tInst is None:
        #     raise CrashGenError("Empty instance not allowed in TdeSubProcess")
        # self._tInst = tInst # Default create at ServiceManagerThread
245

S
Steven Li 已提交
246
    def __repr__(self):
247 248
        # if self.subProcess is None:
        #     return '[TdeSubProc: Empty]'
S
Steven Li 已提交
249 250
        return '[TdeSubProc: pid = {}]'.format(self.getPid())

251
    def getStdOut(self):
252
        return self._popen.stdout
253 254

    def getStdErr(self):
255
        return self._popen.stderr
256

257 258 259
    # Now it's always running, since we matched the life cycle
    # def isRunning(self):
    #     return self.subProcess is not None
260 261

    def getPid(self):
262
        return self._popen.pid
263

264 265
    @classmethod
    def start(cls, cmdLine):
266 267 268
        ON_POSIX = 'posix' in sys.builtin_module_names

        # Sanity check
269 270
        # if self.subProcess:  # already there
        #     raise RuntimeError("Corrupt process state")
S
Steven Li 已提交
271 272 273 274 275 276 277

        # Prepare environment variables for coverage information
        # Ref: https://stackoverflow.com/questions/2231227/python-subprocess-popen-with-a-modified-environment
        myEnv = os.environ.copy()
        TdeInstance.prepareGcovEnv(myEnv)

        # print(myEnv)
278
        # print("Starting TDengine with env: ", myEnv.items())
S
Steven Li 已提交
279 280
        # print("Starting TDengine via Shell: {}".format(cmdLineStr))

281 282 283 284 285 286
        # useShell = True # Needed to pass environments into it
        popen = Popen(            
            ' '.join(cmdLine), # ' '.join(cmdLine) if useShell else cmdLine,
            shell=True, # Always use shell, since we need to pass ENV vars
            stdout=PIPE,
            stderr=PIPE,
S
Steven Li 已提交
287 288
            close_fds=ON_POSIX,
            env=myEnv
289
            )  # had text=True, which interferred with reading EOF
290
        return cls(popen)
291

292
    STOP_SIGNAL = signal.SIGINT # signal.SIGKILL/SIGINT # What signal to use (in kill) to stop a taosd process?
293
    SIG_KILL_RETCODE = 137 # ref: https://stackoverflow.com/questions/43268156/process-finished-with-exit-code-137-in-pycharm
S
Steven Li 已提交
294

295 296
    @classmethod
    def stop(cls, tsp: TdeSubProcess):
297
        """
298
        Stop a sub process, DO NOT return anything, process all conditions INSIDE
299 300 301 302 303 304 305 306 307 308 309 310 311 312 313 314 315

        Common POSIX signal values (from man -7 signal):
        SIGHUP           1
        SIGINT           2 
        SIGQUIT          3 
        SIGILL           4
        SIGTRAP          5
        SIGABRT          6 
        SIGIOT           6 
        SIGBUS           7 
        SIGEMT           - 
        SIGFPE           8  
        SIGKILL          9  
        SIGUSR1         10 
        SIGSEGV         11
        SIGUSR2         12
        """
316 317 318 319 320
        # self._popen should always be valid.

        # if not self.subProcess:
        #     Logging.error("Sub process already stopped")
        #     return
321

322
        retCode = tsp._popen.poll() # ret -N means killed with signal N, otherwise it's from exit(N)
323
        if retCode:  # valid return code, process ended
324
            # retCode = -retCode # only if valid
325
            Logging.warning("TSP.stop(): process ended itself")
326
            # self.subProcess = None
327
            return
328 329

        # process still alive, let's interrupt it
330
        cls._stopForSure(tsp._popen, cls.STOP_SIGNAL) # success if no exception
331

S
Steven Li 已提交
332
        # sub process should end, then IPC queue should end, causing IO thread to end        
333 334

    @classmethod
335
    def _stopForSure(cls, proc: Popen, sig: int):
336 337 338
        ''' 
        Stop a process and all sub processes with a singal, and SIGKILL if necessary
        '''
339
        def doKillTdService(proc: Popen, sig: int):
340 341 342 343 344 345 346 347 348 349 350
            Logging.info("Killing sub-sub process {} with signal {}".format(proc.pid, sig))
            proc.send_signal(sig)
            try:            
                retCode = proc.wait(20)
                if (- retCode) == signal.SIGSEGV: # Crashed
                    Logging.warning("Process {} CRASHED, please check CORE file!".format(proc.pid))
                elif (- retCode) == sig : 
                    Logging.info("TD service terminated with expected return code {}".format(sig))
                else:
                    Logging.warning("TD service terminated, EXPECTING ret code {}, got {}".format(sig, -retCode))
                return True # terminated successfully
351
            except TimeoutExpired as err:
352 353 354 355 356 357 358 359 360 361 362 363 364 365 366 367 368 369 370 371
                Logging.warning("Failed to kill sub-sub process {} with signal {}".format(proc.pid, sig))
            return False # failed to terminate


        def doKillChild(child: psutil.Process, sig: int):
            Logging.info("Killing sub-sub process {} with signal {}".format(child.pid, sig))
            child.send_signal(sig)
            try:            
                retCode = child.wait(20)
                if (- retCode) == signal.SIGSEGV: # Crashed
                    Logging.warning("Process {} CRASHED, please check CORE file!".format(child.pid))
                elif (- retCode) == sig : 
                    Logging.info("Sub-sub process terminated with expected return code {}".format(sig))
                else:
                    Logging.warning("Process terminated, EXPECTING ret code {}, got {}".format(sig, -retCode))
                return True # terminated successfully
            except psutil.TimeoutExpired as err:
                Logging.warning("Failed to kill sub-sub process {} with signal {}".format(child.pid, sig))
            return False # did not terminate

372
        def doKill(proc: Popen, sig: int):
373 374
            pid = proc.pid
            try:
375
                topSubProc = psutil.Process(pid) # Now that we are doing "exec -c", should not have children any more
376 377 378 379 380 381 382 383 384 385 386 387 388 389 390 391 392 393 394 395 396 397 398 399
                for child in topSubProc.children(recursive=True):  # or parent.children() for recursive=False
                    Logging.warning("Unexpected child to be killed")
                    doKillChild(child, sig)
            except psutil.NoSuchProcess as err:
                Logging.info("Process not found, can't kill, pid = {}".format(pid))
            
            return doKillTdService(proc, sig)
            # TODO: re-examine if we need to kill the top process, which is always the SHELL for now
            # try:
            #     proc.wait(1) # SHELL process here, may throw subprocess.TimeoutExpired exception
            #     # expRetCode = self.SIG_KILL_RETCODE if sig==signal.SIGKILL else (-sig)
            #     # if retCode == expRetCode:
            #     #     Logging.info("Process terminated with expected return code {}".format(retCode))
            #     # else:
            #     #     Logging.warning("Process terminated, EXPECTING ret code {}, got {}".format(expRetCode, retCode))
            #     # return True # success
            # except subprocess.TimeoutExpired as err:
            #     Logging.warning("Failed to kill process {} with signal {}".format(pid, sig))
            # return False # failed to kill

        def softKill(proc, sig):
            return doKill(proc, sig)

        def hardKill(proc):
400
            return doKill(proc, signal.SIGKILL) 
401 402 403 404

        pid = proc.pid
        Logging.info("Terminate running processes under {}, with SIG #{} and wait...".format(pid, sig))
        if softKill(proc, sig):            
405
            return # success
406 407
        if sig != signal.SIGKILL: # really was soft above            
            if hardKill(proc):
408
                return 
409
        raise CrashGenError("Failed to stop process, pid={}".format(pid))
410 411 412 413

class ServiceManager:
    PAUSE_BETWEEN_IPC_CHECK = 1.2  # seconds between checks on STDOUT of sub process

414
    def __init__(self, numDnodes): # >1 when we run a cluster
415 416
        Logging.info("TDengine Service Manager (TSM) created")
        self._numDnodes = numDnodes # >1 means we have a cluster
417
        self._lock = threading.Lock()
418 419 420 421 422 423 424
        # signal.signal(signal.SIGTERM, self.sigIntHandler) # Moved to MainExec
        # signal.signal(signal.SIGINT, self.sigIntHandler)
        # signal.signal(signal.SIGUSR1, self.sigUsrHandler)  # different handler!

        self.inSigHandler = False
        # self._status = MainExec.STATUS_RUNNING # set inside
        # _startTaosService()
425
        self._runCluster = (numDnodes > 1)
426
        self._tInsts : List[TdeInstance] = []
427
        for i in range(0, numDnodes):
428 429 430 431 432 433 434
            ti = self._createTdeInstance(i) # construct tInst
            self._tInsts.append(ti)

        # self.svcMgrThreads : List[ServiceManagerThread] = []
        # for i in range(0, numDnodes):
        #     thread = self._createThread(i) # construct tInst
        #     self.svcMgrThreads.append(thread)
435

436
    def _createTdeInstance(self, dnIndex):
437 438 439 440
        if not self._runCluster: # single instance 
            subdir = 'test'
        else:        # Create all threads in a cluster
            subdir = 'cluster_dnode_{}'.format(dnIndex)
441 442
        fepPort= 6030 # firstEP Port
        port   = fepPort + dnIndex * 100
443 444
        return TdeInstance(subdir, dnIndex, port, fepPort)
        # return ServiceManagerThread(dnIndex, ti)
445 446 447 448 449 450 451 452 453 454 455 456 457 458 459 460 461 462 463 464 465 466 467 468 469 470 471 472 473 474 475 476 477 478 479 480 481 482 483 484 485 486 487 488 489 490 491 492 493 494 495 496 497 498 499 500 501 502 503 504 505 506 507 508

    def _doMenu(self):
        choice = ""
        while True:
            print("\nInterrupting Service Program, Choose an Action: ")
            print("1: Resume")
            print("2: Terminate")
            print("3: Restart")
            # Remember to update the if range below
            # print("Enter Choice: ", end="", flush=True)
            while choice == "":
                choice = input("Enter Choice: ")
                if choice != "":
                    break  # done with reading repeated input
            if choice in ["1", "2", "3"]:
                break  # we are done with whole method
            print("Invalid choice, please try again.")
            choice = ""  # reset
        return choice

    def sigUsrHandler(self, signalNumber, frame):
        print("Interrupting main thread execution upon SIGUSR1")
        if self.inSigHandler:  # already
            print("Ignoring repeated SIG...")
            return  # do nothing if it's already not running
        self.inSigHandler = True

        choice = self._doMenu()
        if choice == "1":            
            self.sigHandlerResume() # TODO: can the sub-process be blocked due to us not reading from queue?
        elif choice == "2":
            self.stopTaosServices()
        elif choice == "3": # Restart
            self.restart()
        else:
            raise RuntimeError("Invalid menu choice: {}".format(choice))

        self.inSigHandler = False

    def sigIntHandler(self, signalNumber, frame):
        print("ServiceManager: INT Signal Handler starting...")
        if self.inSigHandler:
            print("Ignoring repeated SIG_INT...")
            return
        self.inSigHandler = True

        self.stopTaosServices()
        print("ServiceManager: INT Signal Handler returning...")
        self.inSigHandler = False

    def sigHandlerResume(self):
        print("Resuming TDengine service manager (main thread)...\n\n")

    # def _updateThreadStatus(self):
    #     if self.svcMgrThread:  # valid svc mgr thread
    #         if self.svcMgrThread.isStopped():  # done?
    #             self.svcMgrThread.procIpcBatch()  # one last time. TODO: appropriate?
    #             self.svcMgrThread = None  # no more

    def isActive(self):
        """
        Determine if the service/cluster is active at all, i.e. at least
        one thread is not "stopped".
        """
509 510
        for ti in self._tInsts:
            if not ti.getStatus().isStopped():
511 512 513
                return True
        return False

514 515 516 517 518 519 520
    def isRunning(self):
        for ti in self._tInsts:
            if not ti.getStatus().isRunning():
                return False
        return True


521 522 523 524 525 526 527 528 529 530 531 532 533 534 535
    # def isRestarting(self):
    #     """
    #     Determine if the service/cluster is being "restarted", i.e., at least
    #     one thread is in "restarting" status
    #     """
    #     for thread in self.svcMgrThreads:
    #         if thread.isRestarting():
    #             return True
    #     return False

    def isStable(self):
        """
        Determine if the service/cluster is "stable", i.e. all of the
        threads are in "stable" status.
        """
536
        for ti in self._tInsts:
537
            if not ti.getStatus().isStable():
538 539 540 541 542
                return False
        return True

    def _procIpcAll(self):
        while self.isActive():
543 544
            Progress.emit(Progress.SERVICE_HEART_BEAT)
            for ti in self._tInsts: # all thread objects should always be valid
545
            # while self.isRunning() or self.isRestarting() :  # for as long as the svc mgr thread is still here
546 547 548 549 550 551
                status = ti.getStatus()
                if  status.isRunning():
                    th = ti.getSmThread()
                    th.procIpcBatch()  # regular processing,
                    if  status.isStopped():
                        th.procIpcBatch() # one last time?
552 553 554 555
                    # self._updateThreadStatus()
                    
            time.sleep(self.PAUSE_BETWEEN_IPC_CHECK)  # pause, before next round
        # raise CrashGenError("dummy")
S
Steven Li 已提交
556
        Logging.info("Service Manager Thread (with subprocess) ended, main thread exiting...")
557

558 559 560
    def _getFirstInstance(self):
        return self._tInsts[0]

561 562 563 564 565 566 567 568
    def startTaosServices(self):
        with self._lock:
            if self.isActive():
                raise RuntimeError("Cannot start TAOS service(s) when one/some may already be running")

            # Find if there's already a taosd service, and then kill it
            for proc in psutil.process_iter():
                if proc.name() == 'taosd':
S
Steven Li 已提交
569
                    Logging.info("Killing an existing TAOSD process in 2 seconds... press CTRL-C to interrupt")
570 571 572 573 574
                    time.sleep(2.0)
                    proc.kill()
                # print("Process: {}".format(proc.name()))
            
            # self.svcMgrThread = ServiceManagerThread()  # create the object
575 576 577 578 579 580 581
            
            for ti in self._tInsts:
                ti.start()  
                if not ti.isFirst():                                    
                    tFirst = self._getFirstInstance()
                    tFirst.createDnode(ti.getDbTarget())
                ti.getSmThread().procIpcBatch(trimToTarget=10, forceOutput=True)  # for printing 10 lines                                     
582 583 584 585 586 587 588

    def stopTaosServices(self):
        with self._lock:
            if not self.isActive():
                Logging.warning("Cannot stop TAOS service(s), already not active")
                return

589 590
            for ti in self._tInsts:
                ti.stop()
591 592 593 594 595 596 597 598
                
    def run(self):
        self.startTaosServices()
        self._procIpcAll()  # pump/process all the messages, may encounter SIG + restart
        if  self.isActive():  # if sig handler hasn't destroyed it by now
            self.stopTaosServices()  # should have started already

    def restart(self):
599
        if not self.isStable():
600 601 602 603 604 605 606 607 608
            Logging.warning("Cannot restart service/cluster, when not stable")
            return

        # self._isRestarting = True
        if  self.isActive():
            self.stopTaosServices()
        else:
            Logging.warning("Service not active when restart requested")

609
        self.startTaosServices()
610 611 612 613 614 615 616 617 618 619 620 621 622 623 624 625 626
        # self._isRestarting = False

    # def isRunning(self):
    #     return self.svcMgrThread != None

    # def isRestarting(self):
    #     return self._isRestarting

class ServiceManagerThread:
    """
    A class representing a dedicated thread which manages the "sub process"
    of the TDengine service, interacting with its STDOUT/ERR.

    It takes a TdeInstance parameter at creation time, or create a default    
    """
    MAX_QUEUE_SIZE = 10000

627
    def __init__(self):
628 629 630 631
        # Set the sub process
        self._tdeSubProcess = None # type: TdeSubProcess

        # Arrange the TDengine instance
632 633
        # self._tInstNum = tInstNum # instance serial number in cluster, ZERO based
        # self._tInst    = tInst or TdeInstance() # Need an instance
634

635 636
        self._thread  = None # The actual thread, # type: threading.Thread
        self._thread2 = None # watching stderr
637
        self._status = Status(Status.STATUS_STOPPED) # The status of the underlying service, actually.
638 639

    def __repr__(self):
640 641
        return "[SvcMgrThread: status={}, subProc={}]".format(
            self.getStatus(), self._tdeSubProcess)
642 643

    def getStatus(self):
644 645 646
        '''
        Get the status of the process being managed. (misnomer alert!)
        '''
647 648 649 650
        return self._status

    # Start the thread (with sub process), and wait for the sub service
    # to become fully operational
651 652 653 654 655 656 657
    def start(self, cmdLine : str, logDir: str):
        '''
        Request the manager thread to start a new sub process, and manage it.

        :param cmdLine: the command line to invoke
        :param logDir: the logging directory, to hold stdout/stderr files
        '''
658 659 660 661 662 663 664
        if self._thread:
            raise RuntimeError("Unexpected _thread")
        if self._tdeSubProcess:
            raise RuntimeError("TDengine sub process already created/running")

        Logging.info("Attempting to start TAOS service: {}".format(self))

665
        self._status.set(Status.STATUS_STARTING)
666
        self._tdeSubProcess = TdeSubProcess.start(cmdLine) # TODO: verify process is running
667

668
        self._ipcQueue = Queue() # type: Queue
669 670
        self._thread = threading.Thread( # First thread captures server OUTPUT
            target=self.svcOutputReader,
671
            args=(self._tdeSubProcess.getStdOut(), self._ipcQueue, logDir))
672 673
        self._thread.daemon = True  # thread dies with the program
        self._thread.start()
674 675 676 677 678 679
        time.sleep(0.01)
        if not self._thread.is_alive(): # What happened?
            Logging.info("Failed to started process to monitor STDOUT")
            self.stop()
            raise CrashGenError("Failed to start thread to monitor STDOUT")
        Logging.info("Successfully started process to monitor STDOUT")
680 681 682

        self._thread2 = threading.Thread( # 2nd thread captures server ERRORs
            target=self.svcErrorReader,
683
            args=(self._tdeSubProcess.getStdErr(), self._ipcQueue, logDir))
684 685
        self._thread2.daemon = True  # thread dies with the program
        self._thread2.start()
686 687 688 689
        time.sleep(0.01)
        if not self._thread2.is_alive():
            self.stop()
            raise CrashGenError("Failed to start thread to monitor STDERR")
690 691 692 693 694

        # wait for service to start
        for i in range(0, 100):
            time.sleep(1.0)
            # self.procIpcBatch() # don't pump message during start up
S
Steven Li 已提交
695 696
            Progress.emit(Progress.SERVICE_START_NAP)
            # print("_zz_", end="", flush=True)
697
            if self._status.isRunning():
698 699
                Logging.info("[] TDengine service READY to process requests")
                Logging.info("[] TAOS service started: {}".format(self))
700 701
                # self._verifyDnode(self._tInst) # query and ensure dnode is ready
                # Logging.debug("[] TAOS Dnode verified: {}".format(self))
702 703 704 705 706
                return  # now we've started
        # TODO: handle failure-to-start  better?
        self.procIpcBatch(100, True) # display output before cronking out, trim to last 20 msgs, force output
        raise RuntimeError("TDengine service did not start successfully: {}".format(self))

707 708 709 710 711 712 713 714 715
    def _verifyDnode(self, tInst: TdeInstance):
        dbc = DbConn.createNative(tInst.getDbTarget())
        dbc.open()
        dbc.query("show dnodes")
        # dbc.query("DESCRIBE {}.{}".format(dbName, self._stName))
        cols = dbc.getQueryResult() #  id,end_point,vnodes,cores,status,role,create_time,offline reason
        # ret = {row[0]:row[1] for row in stCols if row[3]=='TAG'} # name:type
        isValid = False
        for col in cols:
716
            # print("col = {}".format(col))
717
            ep = col[1].split(':') # 10.1.30.2:6030
718
            print("Found ep={}".format(ep))
719
            if tInst.getPort() == int(ep[1]): # That's us
720
                # print("Valid Dnode matched!")
721 722 723
                isValid = True # now we are valid
                break
        if not isValid:
724 725 726 727
            print("Failed to start dnode, sleep for a while")
            time.sleep(600)
            raise RuntimeError("Failed to start Dnode, expected port not found: {}".
                format(tInst.getPort()))
728 729
        dbc.close()

730 731
    def stop(self):
        # can be called from both main thread or signal handler
S
Steven Li 已提交
732
        Logging.info("Terminating TDengine service running as the sub process...")
733
        if self.getStatus().isStopped():
S
Steven Li 已提交
734
            Logging.info("Service already stopped")
735
            return
736
        if self.getStatus().isStopping():
737
            Logging.info("Service is already being stopped, pid: {}".format(self._tdeSubProcess.getPid()))
738 739 740 741 742 743 744
            return
        # Linux will send Control-C generated SIGINT to the TDengine process
        # already, ref:
        # https://unix.stackexchange.com/questions/176235/fork-and-how-signals-are-delivered-to-processes
        if not self._tdeSubProcess:
            raise RuntimeError("sub process object missing")

745
        self._status.set(Status.STATUS_STOPPING)
746 747 748 749 750 751 752 753 754
        TdeSubProcess.stop(self._tdeSubProcess) # must stop, no matter what
        self._tdeSubProcess = None
        # if not self._tdeSubProcess.stop(): # everything withing
        #     if self._tdeSubProcess.isRunning():  # still running, should now never happen
        #         Logging.error("FAILED to stop sub process, it is still running... pid = {}".format(
        #             self._tdeSubProcess.getPid()))
        #     else:
        #         self._tdeSubProcess = None  # not running any more
        self.join()  # stop the thread, change the status, etc.
755 756

        # Check if it's really stopped
757 758
        outputLines = 10 # for last output
        if  self.getStatus().isStopped():
759
            self.procIpcBatch(outputLines)  # one last time
760 761
            Logging.debug("End of TDengine Service Output: {}".format(self))
            Logging.info("----- TDengine Service (managed by SMT) is now terminated -----\n")
762 763 764 765 766
        else:
            print("WARNING: SMT did not terminate as expected: {}".format(self))

    def join(self):
        # TODO: sanity check
767
        if not self.getStatus().isStopping():
768
            raise RuntimeError(
769
                "SMT.Join(): Unexpected status: {}".format(self._status))
770

771 772 773 774 775 776 777
        if self._thread or self._thread2 :
            if self._thread:
                self._thread.join()
                self._thread = None
            if self._thread2: # STD ERR thread            
                self._thread2.join()
                self._thread2 = None
778 779 780
        else:
            print("Joining empty thread, doing nothing")

781 782
        self._status.set(Status.STATUS_STOPPED)

783 784 785 786 787 788 789 790 791 792 793 794 795 796 797 798 799 800 801 802 803 804 805 806 807 808 809 810 811 812 813 814 815
    def _trimQueue(self, targetSize):
        if targetSize <= 0:
            return  # do nothing
        q = self._ipcQueue
        if (q.qsize() <= targetSize):  # no need to trim
            return

        Logging.debug("Triming IPC queue to target size: {}".format(targetSize))
        itemsToTrim = q.qsize() - targetSize
        for i in range(0, itemsToTrim):
            try:
                q.get_nowait()
            except Empty:
                break  # break out of for loop, no more trimming

    TD_READY_MSG = "TDengine is initialized successfully"

    def procIpcBatch(self, trimToTarget=0, forceOutput=False):
        self._trimQueue(trimToTarget)  # trim if necessary
        # Process all the output generated by the underlying sub process,
        # managed by IO thread
        print("<", end="", flush=True)
        while True:
            try:
                line = self._ipcQueue.get_nowait()  # getting output at fast speed
                self._printProgress("_o")
            except Empty:
                # time.sleep(2.3) # wait only if there's no output
                # no more output
                print(".>", end="", flush=True)
                return  # we are done with THIS BATCH
            else:  # got line, printing out
                if forceOutput:
S
Steven Li 已提交
816
                    Logging.info('[TAOSD] ' + line)
817
                else:
S
Steven Li 已提交
818
                    Logging.debug('[TAOSD] ' + line)
819 820 821 822 823 824 825 826 827 828
        print(">", end="", flush=True)

    _ProgressBars = ["--", "//", "||", "\\\\"]

    def _printProgress(self, msg):  # TODO: assuming 2 chars
        print(msg, end="", flush=True)
        pBar = self._ProgressBars[Dice.throw(4)]
        print(pBar, end="", flush=True)
        print('\b\b\b\b', end="", flush=True)

829 830 831 832 833 834 835 836 837 838 839 840 841
    BinaryLine = NewType('BinaryLine', bytes) # line with binary data, directly from STDOUT, etc.
    TextLine   = NewType('TextLine', str) # properly decoded, suitable for printing, etc.
    x = TextLine('xyz')
    
    @classmethod
    def _decodeBinLine(cls, bLine: BinaryLine) -> Optional[TextLine] :
        try:
            tLine = bLine.decode("utf-8").rstrip()
            return cls.TextLine(tLine)
        except UnicodeError:
            print("\nNon-UTF8 server output: {}\n".format(bLine.decode('cp437')))
            return None

842 843 844 845 846 847 848 849 850 851 852
    def svcOutputReader(self, out: IO, queue, logDir: str):
        '''
        The infinite routine that processes the STDOUT stream for the sub process being managed.

        :param out: the IO stream object used to fetch the data from
        :param queue: the queue where we dump the roughly parsed line-by-line data
        :param logDir: where we should dump a verbatim output file
        '''
        os.makedirs(logDir, exist_ok=True)
        logFile = os.path.join(logDir,'stdout.log')
        fOut = open(logFile, 'wb')
853 854 855
        # Important Reference: https://stackoverflow.com/questions/375427/non-blocking-read-on-a-subprocess-pipe-in-python
        # print("This is the svcOutput Reader...")
        # for line in out :
856 857 858
        out.readline()
        for bLine in iter(out.readline, b''):
            fOut.write(bLine)
859 860
            # print("Finished reading a line: {}".format(line))
            # print("Adding item to queue...")
861 862 863 864 865 866 867 868 869 870 871 872 873 874 875 876 877 878 879 880 881 882 883 884 885 886

            # Moved to above
            # try:
            #     line = line.decode("utf-8").rstrip()
            # except UnicodeError:
            #     print("\nNon-UTF8 server output: {}\n".format(line))
            tLine = self._decodeBinLine(bLine)

            if tLine is not None:
                # This might block, and then causing "out" buffer to block
                queue.put(tLine)
                self._printProgress("_i")

                if self._status.isStarting():  # we are starting, let's see if we have started
                    if tLine.find(self.TD_READY_MSG) != -1:  # found
                        Logging.info("Waiting for the service to become FULLY READY")
                        time.sleep(1.0) # wait for the server to truly start. TODO: remove this
                        Logging.info("Service is now FULLY READY") # TODO: more ID info here?
                        self._status.set(Status.STATUS_RUNNING)

                # Trim the queue if necessary: TODO: try this 1 out of 10 times
                self._trimQueue(self.MAX_QUEUE_SIZE * 9 // 10)  # trim to 90% size

                if self._status.isStopping():  # TODO: use thread status instead
                    # WAITING for stopping sub process to finish its outptu
                    print("_w", end="", flush=True)
887 888 889

            # queue.put(line)
        # meaning sub process must have died
S
Steven Li 已提交
890
        Logging.info("EOF for TDengine STDOUT: {}".format(self))
891 892
        out.close() # Close the stream
        fOut.close() # Close the output file
893

894 895 896 897
    def svcErrorReader(self, err: IO, queue, logDir: str):
        os.makedirs(logDir, exist_ok=True)
        logFile = os.path.join(logDir,'stderr.log')
        fErr = open(logFile, 'wb')
898
        for line in iter(err.readline, b''):
899
            fErr.write(line)
S
Steven Li 已提交
900 901
            Logging.info("TDengine STDERR: {}".format(line))
        Logging.info("EOF for TDengine STDERR: {}".format(self))
902 903
        err.close()
        fErr.close()